2019-04-03 09:10:38:398 flexisip-debug-nta: timer J fired, terminate 200 response 2019-04-03 09:10:38:398 flexisip-debug-incoming_reclaim_all((nil), (nil), 0x7ffd48758120) 2019-04-03 09:10:38:398 flexisip-debug-incoming_reclaim: 0x1e8f730 2019-04-03 09:10:38:398 flexisip-debug-nta_incoming_timer: 0/0 resent, 0/0 tout, 1/2 term, 1/2 free 2019-04-03 09:10:38:398 flexisip-debug-nta: timer set next to 369 ms 2019-04-03 09:10:38:767 flexisip-debug-nta: timer J fired, terminate 200 response 2019-04-03 09:10:38:767 flexisip-debug-incoming_reclaim_all((nil), (nil), 0x7ffd48758120) 2019-04-03 09:10:38:767 flexisip-debug-incoming_reclaim: 0x1e914e0 2019-04-03 09:10:38:767 flexisip-debug-nta_incoming_timer: 0/0 resent, 0/0 tout, 1/1 term, 1/1 free 2019-04-03 09:10:38:768 flexisip-debug-nta: timer not set 2019-04-03 09:10:45:994 flexisip-debug-tport_wakeup_pri(0x1e80b20): events IN 2019-04-03 09:10:45:994 flexisip-debug-tport_recv_event(0x1e80b20) 2019-04-03 09:10:45:994 flexisip-debug-tport_recv_iovec(0x1e80b20) msg 0x1ea35d0 from (udp/[fd31:aeb1:48df::2]:5061) has 2571 bytes, veclen = 1 2019-04-03 09:10:45:994 flexisip-debug-tport_deliver(0x1e80b20): msg 0x1ea35d0 (2571 bytes) from udp/[2001:1234:5678:abcd::2]:5061 next=(nil) 2019-04-03 09:10:45:994 flexisip-debug-nta: received INVITE sip:brian_h8@[fd31:aeb1:48df::2]:5061;pn-silent=1;fs-conn-id=2135b2587618077;CtRtdc7b0b21aa83e9ed=udp:pbx-mobile.example.com SIP/2.0 (CSeq 2100) 2019-04-03 09:10:45:994 flexisip-debug-nta: canonizing sip:brian_h8@[fd31:aeb1:48df::2]:5061 with contact 2019-04-03 09:10:45:994 flexisip-debug-nta: INVITE (2100) to message callback 2019-04-03 09:10:45:994 flexisip-debug-New SipEvent 0x1ea1c28 - msg 0x1ea35d0 2019-04-03 09:10:45:994 flexisip-debug-Receiving new Request SIP message INVITE from sip:[redacted]@[2001:1234:5678:abcd::2] : INVITE sip:brian_h8@[fd31:aeb1:48df::2]:5061;pn-silent=1;fs-conn-id=2135b2587618077;CtRtdc7b0b21aa83e9ed=udp:pbx-mobile.example.com SIP/2.0 Via: SIP/2.0/UDP [2001:1234:5678:abcd::2]:5060;rport=5060;branch=z9hG4bKPjd8400683-7856-4f2d-96a2-53788b609f00 From: "[redacted]" ;tag=1dc4710c-d855-4a3b-b622-1fce9764c602 To: Contact: Call-ID: 8442bc63-82ce-4d10-85d1-19b8caeeea49 CSeq: 2100 INVITE Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REGISTER, REFER, MESSAGE Supported: 100rel, timer, replaces, norefersub Session-Expires: 1800 Min-SE: 90 Max-Forwards: 70 User-Agent: Asterisk PBX 13.25.0 Content-Type: application/sdp Content-Length: 1662 v=0 o=- 993639818 993639818 IN IP6 2001:1234:5678:abcd::2 s=Asterisk c=IN IP6 2001:1234:5678:abcd::2 t=0 0 m=audio 11148 RTP/AVP 0 3 101 a=ice-ufrag:344ec7d03858769543b8ab106babebf0 a=ice-pwd:109c115707365b2408ae773218b6eafb a=candidate:H67c355d6 1 UDP 2130706431 fd31:aeb1:48df::2 11148 typ host a=candidate:Hc1e19c54 1 UDP 2130706431 2607:abcd:9876:5432::247 11148 typ host a=candidate:Hf5a91683 1 UDP 2130706431 2001:1234:5678:abcd::2 11148 typ host a=candidate:Hdccea0f2 1 UDP 2130706431 2001:123:ab:123::2 11148 typ host a=candidate:Hcbb5ed22 1 UDP 2130706431 fe80::21f:c6ff:fec4:926a 11148 typ host a=candidate:Ha4b16f7 1 UDP 2130706431 10.75.22.247 11148 typ host a=candidate:Ha4b1605 1 UDP 2130706431 10.75.22.5 11148 typ host a=candidate:Ha4b1608 1 UDP 2130706431 10.75.22.8 11148 typ host a=candidate:Ha4b1609 1 UDP 2130706431 10.75.22.9 11148 typ host a=candidate:H67c355d6 2 UDP 2130706430 fd31:aeb1:48df::2 11149 typ host a=candidate:Hc1e19c54 2 UDP 2130706430 2607:abcd:9876:5432::247 11149 typ host a=candidate:Hf5a91683 2 UDP 2130706430 2001:1234:5678:abcd::2 11149 typ host a=candidate:Hdccea0f2 2 UDP 2130706430 2001:123:ab:123::2 11149 typ host a=candidate:Hcbb5ed22 2 UDP 2130706430 fe80::21f:c6ff:fec4:926a 11149 typ host a=candidate:Ha4b16f7 2 UDP 2130706430 10.75.22.247 11149 typ host a=candidate:Ha4b1605 2 UDP 2130706430 10.75.22.5 11149 typ host a=candidate:Ha4b1608 2 UDP 2130706430 10.75.22.8 11149 typ host a=candidate:Ha4b1609 2 UDP 2130706430 10.75.22.9 11149 typ host a=rtpmap:0 PCMU/8000 a=rtpmap:3 GSM/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=maxptime:150 a=sendrecv 2019-04-03 09:10:45:994 flexisip-debug-Skipping onRequest() on module DoSProtection 2019-04-03 09:10:45:994 flexisip-debug-Invoking onRequest() on module SanityChecker 2019-04-03 09:10:45:995 flexisip-debug-Skipping onRequest() on module GarbageIn 2019-04-03 09:10:45:995 flexisip-debug-Invoking onRequest() on module NatHelper 2019-04-03 09:10:45:995 flexisip-debug-Record route added. 2019-04-03 09:10:45:995 flexisip-debug-Skipping onRequest() on module Authentication 2019-04-03 09:10:45:995 flexisip-debug-Skipping onRequest() on module Redirect 2019-04-03 09:10:45:995 flexisip-debug-Skipping onRequest() on module GatewayAdapter 2019-04-03 09:10:45:995 flexisip-debug-Skipping onRequest() on module Presence 2019-04-03 09:10:45:995 flexisip-debug-Invoking onRequest() on module Registrar 2019-04-03 09:10:45:996 flexisip-debug-Skipping onRequest() on module StatisticsCollector 2019-04-03 09:10:45:996 flexisip-debug-Invoking onRequest() on module ContactRouteInserter 2019-04-03 09:10:45:996 flexisip-debug-Found a contact route parameter 2019-04-03 09:10:45:997 flexisip-debug-Request url changed to sip:brian_h8@pbx-mobile.example.com;pn-silent=1;fs-conn-id=2135b2587618077;doroute 2019-04-03 09:10:45:997 flexisip-debug-Invoking onRequest() on module Router 2019-04-03 09:10:45:997 flexisip-debug-Fetch for url sip:brian_h8@pbx-mobile.example.com;pn-silent=1;fs-conn-id=2135b2587618077;doroute. 2019-04-03 09:10:45:998 flexisip-debug-New IncomingTransaction 0x1e98858 2019-04-03 09:10:45:998 flexisip-debug-nta: timer set to 200 ms 2019-04-03 09:10:45:998 flexisip-debug-nta_incoming_create: created incoming transaction 0x1e9b260 2019-04-03 09:10:45:999 flexisip-debug-Replying Request SIP message: 100 Trying 2019-04-03 09:10:45:999 flexisip-debug-tport_tsend(0x1e80b20) tpn = UDP/[2001:1234:5678:abcd::2]:5060 2019-04-03 09:10:45:999 flexisip-debug-tport_resolve addrinfo = [2001:1234:5678:abcd::2]:5060 2019-04-03 09:10:46:000 flexisip-debug-tport_by_addrinfo(0x1e80b20): not found by name UDP/[2001:1234:5678:abcd::2]:5060 2019-04-03 09:10:46:000 flexisip-debug-tport_vsend returned 547 2019-04-03 09:10:46:000 flexisip-debug-nta: sent 100 Trying for INVITE (2100) 2019-04-03 09:10:46:000 flexisip-debug-Suspend SipEvent 0x1ea1c28 2019-04-03 09:10:46:000 flexisip-debug-Step: 1 Found contact sip:brian_h8@pbx-mobile.example.com;pn-silent=1;fs-conn-id=2135b2587618077;doroute -> sip:brian_h8@[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:50458;app-id=755770037818;pn-type=firebase;pn-tok==[redacted];pn-silent=1;fs-conn-id=2135b2587618077 usedAsRoute:0 2019-04-03 09:10:46:001 flexisip-debug-Step: 1 Returning collected records 1 2019-04-03 09:10:46:001 flexisip-debug-New SipEvent 0x1e8f608 with state SUSPENDED 2019-04-03 09:10:46:002 flexisip-debug-New MsgSip 0x1e9aae8 copied from MsgSip 0x1ea1be8 2019-04-03 09:10:46:002 flexisip-debug-New ForkCallContext 0x1e8f4e8 2019-04-03 09:10:46:002 flexisip-debug-New SipEvent 0x1e9c708 with state SUSPENDED 2019-04-03 09:10:46:002 flexisip-debug-New MsgSip 0x1e8f158 copied from MsgSip 0x1ea1be8 2019-04-03 09:10:46:002 flexisip-debug-New OutgoingTransaction 0x1e8e938 2019-04-03 09:10:46:002 flexisip-debug-ForkContext [0x1e8f4e8] new fork branch [0x1e9c258] 2019-04-03 09:10:46:002 flexisip-debug-Fork to sip:brian_h8@[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:50458;app-id=755770037818;pn-type=firebase;pn-tok==[redacted];pn-silent=1;fs-conn-id=2135b2587618077 2019-04-03 09:10:46:002 flexisip-debug-Started forking branches with priority [0x1e8f4e8]: 1.000000 2019-04-03 09:10:46:002 flexisip-debug-Inject Request SIP message: INVITE sip:brian_h8@[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:50458;app-id=755770037818;pn-type=firebase;pn-tok==[redacted];pn-silent=1;fs-conn-id=2135b2587618077 SIP/2.0 Via: SIP/2.0/UDP [2001:1234:5678:abcd::2]:5060;rport=5060;branch=z9hG4bKPjd8400683-7856-4f2d-96a2-53788b609f00 Route: Record-Route: Max-Forwards: 70 From: "[redacted]" ;tag=1dc4710c-d855-4a3b-b622-1fce9764c602 To: Call-ID: 8442bc63-82ce-4d10-85d1-19b8caeeea49 CSeq: 2100 INVITE Contact: User-Agent: Asterisk PBX 13.25.0 Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REGISTER, REFER, MESSAGE Supported: 100rel, timer, replaces, norefersub Session-Expires: 1800 Min-SE: 90 Content-Type: application/sdp Content-Length: 1662 v=0 o=- 993639818 993639818 IN IP6 2001:1234:5678:abcd::2 s=Asterisk c=IN IP6 2001:1234:5678:abcd::2 t=0 0 m=audio 11148 RTP/AVP 0 3 101 a=ice-ufrag:344ec7d03858769543b8ab106babebf0 a=ice-pwd:109c115707365b2408ae773218b6eafb a=candidate:H67c355d6 1 UDP 2130706431 fd31:aeb1:48df::2 11148 typ host a=candidate:Hc1e19c54 1 UDP 2130706431 2607:abcd:9876:5432::247 11148 typ host a=candidate:Hf5a91683 1 UDP 2130706431 2001:1234:5678:abcd::2 11148 typ host a=candidate:Hdccea0f2 1 UDP 2130706431 2001:123:ab:123::2 11148 typ host a=candidate:Hcbb5ed22 1 UDP 2130706431 fe80::21f:c6ff:fec4:926a 11148 typ host a=candidate:Ha4b16f7 1 UDP 2130706431 10.75.22.247 11148 typ host a=candidate:Ha4b1605 1 UDP 2130706431 10.75.22.5 11148 typ host a=candidate:Ha4b1608 1 UDP 2130706431 10.75.22.8 11148 typ host a=candidate:Ha4b1609 1 UDP 2130706431 10.75.22.9 11148 typ host a=candidate:H67c355d6 2 UDP 2130706430 fd31:aeb1:48df::2 11149 typ host a=candidate:Hc1e19c54 2 UDP 2130706430 2607:abcd:9876:5432::247 11149 typ host a=candidate:Hf5a91683 2 UDP 2130706430 2001:1234:5678:abcd::2 11149 typ host a=candidate:Hdccea0f2 2 UDP 2130706430 2001:123:ab:123::2 11149 typ host a=candidate:Hcbb5ed22 2 UDP 2130706430 fe80::21f:c6ff:fec4:926a 11149 typ host a=candidate:Ha4b16f7 2 UDP 2130706430 10.75.22.247 11149 typ host a=candidate:Ha4b1605 2 UDP 2130706430 10.75.22.5 11149 typ host a=candidate:Ha4b1608 2 UDP 2130706430 10.75.22.8 11149 typ host a=candidate:Ha4b1609 2 UDP 2130706430 10.75.22.9 11149 typ host a=rtpmap:0 PCMU/8000 a=rtpmap:3 GSM/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=maxptime:150 a=sendrecv 2019-04-03 09:10:46:004 flexisip-debug-Restart SipEvent 0x1e9c708 2019-04-03 09:10:46:004 flexisip-debug-Injecting request event after Router 2019-04-03 09:10:46:004 flexisip-debug-Invoking onRequest() on module PushNotification 2019-04-03 09:10:46:004 flexisip-debug-Creating Firebase push notif request 2019-04-03 09:10:46:004 flexisip-debug-Push notification https post body is {"to":"[redacted]", "priority":"high", "uuid":"", "send-time":"2019-04-03 13:10:46"} 2019-04-03 09:10:46:004 flexisip-debug-PNR 0x1e90738 https post header is POST /fcm/send HTTP/1.1 Host:fcm.googleapis.com Content-Type:application/json Authorization:key=[redacted] Content-Length:273 2019-04-03 09:10:46:005 flexisip-debug-Creating a push notif context PNR 0x1e90738 to send in 5s 2019-04-03 09:10:46:005 flexisip-debug-Skipping onRequest() on module LoadBalancer 2019-04-03 09:10:46:005 flexisip-debug-Skipping onRequest() on module MediaRelay 2019-04-03 09:10:46:005 flexisip-debug-Skipping onRequest() on module Transcoder 2019-04-03 09:10:46:005 flexisip-debug-Invoking onRequest() on module Forward 2019-04-03 09:10:46:005 flexisip-debug-Removing top route sip:[2001:1234:5678:abcd::2]:5061;lr 2019-04-03 09:10:46:005 flexisip-debug-tport: not found from primary 0x1e80b20, trying another one...*/2001:1234:5678:abcd:d4a3:bc17:899c:9cbc:50458 2019-04-03 09:10:46:005 flexisip-debug-tport: not found from primary 0x1e815e0, trying another one...*/2001:1234:5678:abcd:d4a3:bc17:899c:9cbc:50458 2019-04-03 09:10:46:005 flexisip-debug-tport: not found from primary 0x1e820a0, trying another one...*/2001:1234:5678:abcd:d4a3:bc17:899c:9cbc:50458 2019-04-03 09:10:46:005 flexisip-debug-tport: not found from primary 0x1e82b60, trying another one...*/2001:1234:5678:abcd:d4a3:bc17:899c:9cbc:50458 2019-04-03 09:10:46:005 flexisip-debug-tport: not found from primary 0x1e86f90, trying another one...*/2001:1234:5678:abcd:d4a3:bc17:899c:9cbc:50458 2019-04-03 09:10:46:005 flexisip-debug-Skipping addition of record route identical to top one 2019-04-03 09:10:46:006 flexisip-debug-Removed push params from contact 2019-04-03 09:10:46:006 flexisip-debug-Sending Request SIP message to sip:brian_h8@[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:50458;pn-silent=1 INVITE sip:brian_h8@[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:50458;pn-silent=1 SIP/2.0 Via: SIP/2.0/UDP [2001:1234:5678:abcd::2]:5060;rport=5060;branch=z9hG4bKPjd8400683-7856-4f2d-96a2-53788b609f00 Record-Route: Max-Forwards: 69 From: "[redacted]" ;tag=1dc4710c-d855-4a3b-b622-1fce9764c602 To: Call-ID: 8442bc63-82ce-4d10-85d1-19b8caeeea49 CSeq: 2100 INVITE Contact: User-Agent: Asterisk PBX 13.25.0 Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REGISTER, REFER, MESSAGE Supported: 100rel, timer, replaces, norefersub Session-Expires: 1800 Min-SE: 90 Content-Type: application/sdp Content-Length: 1662 v=0 o=- 993639818 993639818 IN IP6 2001:1234:5678:abcd::2 s=Asterisk c=IN IP6 2001:1234:5678:abcd::2 t=0 0 m=audio 11148 RTP/AVP 0 3 101 a=ice-ufrag:344ec7d03858769543b8ab106babebf0 a=ice-pwd:109c115707365b2408ae773218b6eafb a=candidate:H67c355d6 1 UDP 2130706431 fd31:aeb1:48df::2 11148 typ host a=candidate:Hc1e19c54 1 UDP 2130706431 2607:abcd:9876:5432::247 11148 typ host a=candidate:Hf5a91683 1 UDP 2130706431 2001:1234:5678:abcd::2 11148 typ host a=candidate:Hdccea0f2 1 UDP 2130706431 2001:123:ab:123::2 11148 typ host a=candidate:Hcbb5ed22 1 UDP 2130706431 fe80::21f:c6ff:fec4:926a 11148 typ host a=candidate:Ha4b16f7 1 UDP 2130706431 10.75.22.247 11148 typ host a=candidate:Ha4b1605 1 UDP 2130706431 10.75.22.5 11148 typ host a=candidate:Ha4b1608 1 UDP 2130706431 10.75.22.8 11148 typ host a=candidate:Ha4b1609 1 UDP 2130706431 10.75.22.9 11148 typ host a=candidate:H67c355d6 2 UDP 2130706430 fd31:aeb1:48df::2 11149 typ host a=candidate:Hc1e19c54 2 UDP 2130706430 2607:abcd:9876:5432::247 11149 typ host a=candidate:Hf5a91683 2 UDP 2130706430 2001:1234:5678:abcd::2 11149 typ host a=candidate:Hdccea0f2 2 UDP 2130706430 2001:123:ab:123::2 11149 typ host a=candidate:Hcbb5ed22 2 UDP 2130706430 fe80::21f:c6ff:fec4:926a 11149 typ host a=candidate:Ha4b16f7 2 UDP 2130706430 10.75.22.247 11149 typ host a=candidate:Ha4b1605 2 UDP 2130706430 10.75.22.5 11149 typ host a=candidate:Ha4b1608 2 UDP 2130706430 10.75.22.8 11149 typ host a=candidate:Ha4b1609 2 UDP 2130706430 10.75.22.9 11149 typ host a=rtpmap:0 PCMU/8000 a=rtpmap:3 GSM/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=maxptime:150 a=sendrecv 2019-04-03 09:10:46:006 flexisip-debug-Message is sent through an outgoing transaction. 2019-04-03 09:10:46:006 flexisip-debug-nta: selecting scheme sip 2019-04-03 09:10:46:006 flexisip-debug-tport: not found from primary 0x1e80b20, trying another one...udp/[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:50458 2019-04-03 09:10:46:007 flexisip-debug-tport: not found from primary 0x1e815e0, trying another one...udp/[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:50458 2019-04-03 09:10:46:007 flexisip-debug-tport: not found from primary 0x1e820a0, trying another one...udp/[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:50458 2019-04-03 09:10:46:007 flexisip-debug-tport: not found from primary 0x1e82b60, trying another one...udp/[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:50458 2019-04-03 09:10:46:007 flexisip-debug-tport: not found from primary 0x1e86f90, trying another one...udp/[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:50458 2019-04-03 09:10:46:007 flexisip-debug-tport_tsend(0x1e80b20) tpn = udp/[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:50458 2019-04-03 09:10:46:007 flexisip-debug-tport_resolve addrinfo = [2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:50458 2019-04-03 09:10:46:008 flexisip-debug-tport_by_addrinfo(0x1e80b20): not found by name udp/[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:50458 2019-04-03 09:10:46:008 flexisip-debug-tport: not found from primary 0x1e80e40, trying another one...tcp/[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:50458 2019-04-03 09:10:46:008 flexisip-debug-tport: not found from primary 0x1e81b40, trying another one...tcp/[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:50458 2019-04-03 09:10:46:008 flexisip-debug-tport: not found from primary 0x1e82600, trying another one...tcp/[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:50458 2019-04-03 09:10:46:009 flexisip-debug-tport: not found from primary 0x1e830c0, trying another one...tcp/[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:50458 2019-04-03 09:10:46:009 flexisip-debug-tport: not found from primary 0x1e874e0, trying another one...tcp/[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:50458 2019-04-03 09:10:46:012 flexisip-debug-nta: INVITE (2100) too large for UDP, trying TCP 2019-04-03 09:10:46:012 flexisip-debug-tport_tsend(0x1e80e40) tpn = tcp/[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:50458 2019-04-03 09:10:46:012 flexisip-debug-tport_resolve addrinfo = [2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:50458 2019-04-03 09:10:46:013 flexisip-debug-tport_by_addrinfo(0x1e80e40): not found by name tcp/[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:50458 2019-04-03 09:10:46:013 flexisip-debug-tport_alloc_secondary(0x1e80e40): new secondary tport 0x1e9e200 2019-04-03 09:10:46:014 flexisip-debug-tport_base_connect(0x1e9e200): connecting to tcp/[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:50458 2019-04-03 09:10:46:014 flexisip-debug-tport(0x1e9e200): set timer at 1799999 ms because keepalive 2019-04-03 09:10:46:014 flexisip-debug-tport_queue(0x1e9e200): queueing 0x1e8d870 for tcp/[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:50458 2019-04-03 09:10:46:014 flexisip-debug-nta: sent INVITE (2100) to tcp/[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:50458 2019-04-03 09:10:46:014 flexisip-debug-tport_pend(0x1e9e200): pending 0x1e8d870 for tcp/[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:50458 (already 0) 2019-04-03 09:10:46:015 flexisip-debug-Terminate SipEvent 0x1e9c708 2019-04-03 09:10:46:194 flexisip-debug-nta: timer set next to 4800 ms 2019-04-03 09:10:46:382 flexisip-debug-tport_connected(0x1e9e200): events CONNECTED ERR 2019-04-03 09:10:46:382 flexisip-debug-tport_release(0x1e9e200): 0x1e8d870 by 0x1e9d080 with (nil) 2019-04-03 09:10:46:382 flexisip-debug-nta: INVITE (2100): retrying with UDP after Connection refused (111) with tcp/[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:50458 2019-04-03 09:10:46:382 flexisip-debug-tport: not found from primary 0x1e80b20, trying another one...udp/[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:50458 2019-04-03 09:10:46:382 flexisip-debug-tport: not found from primary 0x1e815e0, trying another one...udp/[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:50458 2019-04-03 09:10:46:382 flexisip-debug-tport: not found from primary 0x1e820a0, trying another one...udp/[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:50458 2019-04-03 09:10:46:382 flexisip-debug-tport: not found from primary 0x1e82b60, trying another one...udp/[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:50458 2019-04-03 09:10:46:382 flexisip-debug-tport: not found from primary 0x1e86f90, trying another one...udp/[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:50458 2019-04-03 09:10:46:382 flexisip-debug-nta: INVITE (2100) TCP refused, trying UDP 2019-04-03 09:10:46:382 flexisip-debug-tport(0x1e9e200): reset timer 2019-04-03 09:10:46:382 flexisip-debug-tport_tsend(0x1e80b20) tpn = udp/[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:50458 2019-04-03 09:10:46:382 flexisip-debug-tport_resolve addrinfo = [2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:50458 2019-04-03 09:10:46:382 flexisip-debug-tport_by_addrinfo(0x1e80b20): not found by name udp/[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:50458 2019-04-03 09:10:46:383 flexisip-debug-tport_vsend returned 2659 2019-04-03 09:10:46:383 flexisip-debug-nta: sent INVITE (2100) to udp/[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:50458 2019-04-03 09:10:46:383 flexisip-debug-tport_pend(0x1e80b20): pending 0x1e8d870 for udp/[fd31:aeb1:48df::2]:5061 (already 0) 2019-04-03 09:10:46:383 flexisip-debug-nta: timer shortened to 500 ms 2019-04-03 09:10:46:383 flexisip-debug-tport_close(0x1e9e200): tcp/[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:50458 2019-04-03 09:10:46:385 flexisip-debug-tport_wakeup_pri(0x1e80b20): events ERR 2019-04-03 09:10:46:385 flexisip-debug-tport_udp_error: icmp(6) message was truncated (at 832) 2019-04-03 09:10:46:385 flexisip-debug-tport_udp_error: Connection refused (111) [icmp6 type=1 code=4] 2019-04-03 09:10:46:385 flexisip-debug- reported by [2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:0 2019-04-03 09:10:46:385 flexisip-debug-tport_release(0x1e80b20): 0x1e8d870 by 0x1e9d080 with (nil) 2019-04-03 09:10:46:385 flexisip-debug-nta: INVITE (2100): Connection refused (111) with udp/[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:50458 2019-04-03 09:10:46:385 flexisip-debug-OutgoingTransaction callback 0x1e8e938 2019-04-03 09:10:46:385 flexisip-debug-New SipEvent 0x1ea1c28 - 0x1e9f3d0 2019-04-03 09:10:46:385 flexisip-debug-Receiving new Response SIP message: 503 SIP/2.0 503 Service Unavailable Via: SIP/2.0/UDP [fd31:aeb1:48df::2]:5061;rport;branch=z9hG4bK.00045v32cXD9eZ8pggF00rNZQH Via: SIP/2.0/UDP [2001:1234:5678:abcd::2]:5060;rport=5060;branch=z9hG4bKPjd8400683-7856-4f2d-96a2-53788b609f00 Record-Route: From: "[redacted]" ;tag=1dc4710c-d855-4a3b-b622-1fce9764c602 To: ;tag=7ec1SXUUrBSKa Call-ID: 8442bc63-82ce-4d10-85d1-19b8caeeea49 CSeq: 2100 INVITE Content-Length: 0 2019-04-03 09:10:46:385 flexisip-debug-Skipping onResponse() on module DoSProtection 2019-04-03 09:10:46:385 flexisip-debug-Invoking onResponse() on module SanityChecker 2019-04-03 09:10:46:385 flexisip-debug-Skipping onResponse() on module GarbageIn 2019-04-03 09:10:46:385 flexisip-debug-Invoking onResponse() on module NatHelper 2019-04-03 09:10:46:385 flexisip-debug-Skipping onResponse() on module Authentication 2019-04-03 09:10:46:385 flexisip-debug-Skipping onResponse() on module Redirect 2019-04-03 09:10:46:385 flexisip-debug-Skipping onResponse() on module GatewayAdapter 2019-04-03 09:10:46:385 flexisip-debug-Skipping onResponse() on module Presence 2019-04-03 09:10:46:385 flexisip-debug-Invoking onResponse() on module Registrar 2019-04-03 09:10:46:385 flexisip-debug-Skipping onResponse() on module StatisticsCollector 2019-04-03 09:10:46:385 flexisip-debug-Invoking onResponse() on module ContactRouteInserter 2019-04-03 09:10:46:385 flexisip-debug-Invoking onResponse() on module Router 2019-04-03 09:10:46:385 flexisip-debug-New SipEvent 0x1e9a328 with state STARTED 2019-04-03 09:10:46:385 flexisip-debug-New MsgSip 0x1ea1d28 copied from MsgSip 0x1e9f298 2019-04-03 09:10:46:385 flexisip-debug-Suspend SipEvent 0x1e9a328 2019-04-03 09:10:46:385 flexisip-debug-Inject Response SIP message: SIP/2.0 503 Service Unavailable Via: SIP/2.0/UDP [fd31:aeb1:48df::2]:5061;rport;branch=z9hG4bK.00045v32cXD9eZ8pggF00rNZQH Via: SIP/2.0/UDP [2001:1234:5678:abcd::2]:5060;rport=5060;branch=z9hG4bKPjd8400683-7856-4f2d-96a2-53788b609f00 Record-Route: From: "[redacted]" ;tag=1dc4710c-d855-4a3b-b622-1fce9764c602 To: ;tag=7ec1SXUUrBSKa Call-ID: 8442bc63-82ce-4d10-85d1-19b8caeeea49 CSeq: 2100 INVITE Content-Length: 0 2019-04-03 09:10:46:385 flexisip-debug-Restart SipEvent 0x1e9a328 2019-04-03 09:10:46:385 flexisip-debug-Injecting response event after Router 2019-04-03 09:10:46:385 flexisip-debug-Invoking onResponse() on module PushNotification 2019-04-03 09:10:46:385 flexisip-debug-Skipping onResponse() on module LoadBalancer 2019-04-03 09:10:46:385 flexisip-debug-Skipping onResponse() on module MediaRelay 2019-04-03 09:10:46:386 flexisip-debug-Skipping onResponse() on module Transcoder 2019-04-03 09:10:46:386 flexisip-debug-Invoking onResponse() on module Forward 2019-04-03 09:10:46:386 flexisip-debug-Sending response: (via popped) SIP/2.0 503 Service Unavailable Via: SIP/2.0/UDP [2001:1234:5678:abcd::2]:5060;rport=5060;branch=z9hG4bKPjd8400683-7856-4f2d-96a2-53788b609f00 Record-Route: From: "[redacted]" ;tag=1dc4710c-d855-4a3b-b622-1fce9764c602 To: ;tag=7ec1SXUUrBSKa Call-ID: 8442bc63-82ce-4d10-85d1-19b8caeeea49 CSeq: 2100 INVITE Content-Length: 0 2019-04-03 09:10:46:386 flexisip-debug-Response is sent through an incoming transaction. 2019-04-03 09:10:46:386 flexisip-debug-tport_tsend(0x1e80b20) tpn = UDP/[2001:1234:5678:abcd::2]:5060 2019-04-03 09:10:46:386 flexisip-debug-tport_resolve addrinfo = [2001:1234:5678:abcd::2]:5060 2019-04-03 09:10:46:386 flexisip-debug-tport_by_addrinfo(0x1e80b20): not found by name UDP/[2001:1234:5678:abcd::2]:5060 2019-04-03 09:10:46:389 flexisip-debug-tport_vsend returned 533 2019-04-03 09:10:46:389 flexisip-debug-nta: sent 503 Service Unavailable for INVITE (2100) 2019-04-03 09:10:46:390 flexisip-debug-nta_incoming_destroy: 0x1e9b260 2019-04-03 09:10:46:390 flexisip-debug-Terminate SipEvent 0x1e9a328 2019-04-03 09:10:46:390 flexisip-debug-Terminate SipEvent 0x1ea1c28 2019-04-03 09:10:46:390 flexisip-debug-Delete OutgoingTransaction 0x1e8e938 2019-04-03 09:10:46:390 flexisip-debug-Delete IncomingTransaction 0x1e98858 2019-04-03 09:10:46:390 flexisip-debug-tport_wakeup_pri(0x1e80b20): events IN 2019-04-03 09:10:46:391 flexisip-debug-tport_recv_event(0x1e80b20) 2019-04-03 09:10:46:391 flexisip-debug-tport_recv_iovec(0x1e80b20) msg 0x1ea1550 from (udp/[fd31:aeb1:48df::2]:5061) has 638 bytes, veclen = 1 2019-04-03 09:10:46:391 flexisip-debug-tport_deliver(0x1e80b20): msg 0x1ea1550 (638 bytes) from udp/[2001:1234:5678:abcd::2]:5061 next=(nil) 2019-04-03 09:10:46:391 flexisip-debug-nta: received ACK sip:brian_h8@[fd31:aeb1:48df::2]:5061;pn-silent=1;fs-conn-id=2135b2587618077;CtRtdc7b0b21aa83e9ed=udp:pbx-mobile.example.com SIP/2.0 (CSeq 2100) 2019-04-03 09:10:46:391 flexisip-debug-nta: ACK (2100) is going to INVITE (2100) 2019-04-03 09:10:46:883 flexisip-debug-nta: timer set next to 4509 ms 2019-04-03 09:10:51:005 flexisip-debug-PNR 0x1e90738: timeout 2019-04-03 09:10:51:005 flexisip-debug-Call is already established or canceled, so push notification is not sent but cleared. 2019-04-03 09:10:51:005 flexisip-debug-PNR 0x1e90738: PushNotificationContext clear 2019-04-03 09:10:51:005 flexisip-debug-Push notification to 8442bc63-82ce-4d10-85d1-19b8caeeea49:"":755770037818 cleared. 2019-04-03 09:10:51:005 flexisip-debug-Destroy ForkCallContext 0x1e8f4e8 2019-04-03 09:10:51:392 flexisip-debug-nta: timer I fired, terminate 503 response 2019-04-03 09:10:51:392 flexisip-debug-incoming_reclaim_all((nil), (nil), 0x7ffd48758120) 2019-04-03 09:10:51:392 flexisip-debug-incoming_reclaim: 0x1e9b260 2019-04-03 09:10:51:392 flexisip-debug-nta_incoming_timer: 0/0 resent, 0/0 tout, 1/1 term, 1/1 free 2019-04-03 09:10:51:392 flexisip-debug-nta: timer set next to 26994 ms 2019-04-03 09:10:57:078 flexisip-debug-tport_wakeup_pri(0x1e820a0): events IN 2019-04-03 09:10:57:078 flexisip-debug-tport_recv_event(0x1e820a0) 2019-04-03 09:10:57:078 flexisip-debug-tport_recv_iovec(0x1e820a0) msg 0x1ea35d0 from (udp/[2001:1234:5678:abcd::2]:5061) has 957 bytes, veclen = 1 2019-04-03 09:10:57:078 flexisip-debug-tport_deliver(0x1e820a0): msg 0x1ea35d0 (957 bytes) from udp/[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:5061 next=(nil) 2019-04-03 09:10:57:078 flexisip-debug-nta: received REGISTER sip:pbx-mobile.example.com SIP/2.0 (CSeq 20) 2019-04-03 09:10:57:078 flexisip-debug-nta: REGISTER (20) to message callback 2019-04-03 09:10:57:078 flexisip-debug-New SipEvent 0x1e8c2f8 - msg 0x1ea35d0 2019-04-03 09:10:57:078 flexisip-debug-Receiving new Request SIP message REGISTER from sip:brian_h8@pbx-mobile.example.com : REGISTER sip:pbx-mobile.example.com SIP/2.0 Via: SIP/2.0/UDP [2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:51741;branch=z9hG4bK.bWF5pGXJT;rport=51741 From: ;tag=QvPEEwndn To: sip:brian_h8@pbx-mobile.example.com CSeq: 20 REGISTER Call-ID: 4UXAHpFwo8 Max-Forwards: 70 Supported: replaces, outbound, gruu Accept: application/sdp, text/plain, application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="";+org.linphone.specs="lime" Expires: 172800 User-Agent: LinphoneAndroid/4.1-debug (FRD-L04) LinphoneSDK/4.1-312-gbaecc61-debug (master) (belle-sip/1.6.3) 2019-04-03 09:10:57:078 flexisip-debug-Skipping onRequest() on module DoSProtection 2019-04-03 09:10:57:078 flexisip-debug-Invoking onRequest() on module SanityChecker 2019-04-03 09:10:57:078 flexisip-debug-Skipping onRequest() on module GarbageIn 2019-04-03 09:10:57:078 flexisip-debug-Invoking onRequest() on module NatHelper 2019-04-03 09:10:57:078 flexisip-debug-Skipping onRequest() on module Authentication 2019-04-03 09:10:57:078 flexisip-debug-Skipping onRequest() on module Redirect 2019-04-03 09:10:57:078 flexisip-debug-Skipping onRequest() on module GatewayAdapter 2019-04-03 09:10:57:078 flexisip-debug-Skipping onRequest() on module Presence 2019-04-03 09:10:57:078 flexisip-debug-Invoking onRequest() on module Registrar 2019-04-03 09:10:57:078 flexisip-debug-Path added to: sip:[2001:1234:5678:abcd::2]:5061;lr 2019-04-03 09:10:57:078 flexisip-debug-New IncomingTransaction 0x1e9c708 2019-04-03 09:10:57:078 flexisip-debug-nta_incoming_create: created incoming transaction 0x1e98840 2019-04-03 09:10:57:078 flexisip-debug-Replying Request SIP message: 100 Trying 2019-04-03 09:10:57:079 flexisip-debug-tport_tsend(0x1e820a0) tpn = UDP/[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:51741 2019-04-03 09:10:57:079 flexisip-debug-tport_resolve addrinfo = [2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:51741 2019-04-03 09:10:57:079 flexisip-debug-tport_by_addrinfo(0x1e820a0): not found by name UDP/[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:51741 2019-04-03 09:10:57:079 flexisip-debug-tport_vsend returned 339 2019-04-03 09:10:57:079 flexisip-debug-nta: sent 100 Trying for REGISTER (20) 2019-04-03 09:10:57:079 flexisip-debug-New OutgoingTransaction 0x1e9cc28 2019-04-03 09:10:57:079 flexisip-debug-Contacts :;+sip.instance="";+org.linphone.specs="lime" 2019-04-03 09:10:57:079 flexisip-debug-Removed instance and push params: ;+sip.instance="";+org.linphone.specs="lime" 2019-04-03 09:10:57:079 flexisip-debug-Removed paths 2019-04-03 09:10:57:079 flexisip-debug-Skipping onRequest() on module StatisticsCollector 2019-04-03 09:10:57:079 flexisip-debug-Invoking onRequest() on module ContactRouteInserter 2019-04-03 09:10:57:079 flexisip-debug-Masquerading contact 2019-04-03 09:10:57:079 flexisip-debug-Rewriting contact with param [CtRtdc7b0b21aa83e9ed=udp:pbx-mobile.example.com] 2019-04-03 09:10:57:079 flexisip-debug-Contact has been rewritten to sip:brian_h8@[fd31:aeb1:48df::2]:5061;pn-silent=1;fs-conn-id=2135b2587618077;CtRtdc7b0b21aa83e9ed=udp:pbx-mobile.example.com 2019-04-03 09:10:57:079 flexisip-debug-Skipping onRequest() on module Router 2019-04-03 09:10:57:079 flexisip-debug-Invoking onRequest() on module PushNotification 2019-04-03 09:10:57:079 flexisip-debug-Skipping onRequest() on module LoadBalancer 2019-04-03 09:10:57:079 flexisip-debug-Skipping onRequest() on module MediaRelay 2019-04-03 09:10:57:079 flexisip-debug-Skipping onRequest() on module Transcoder 2019-04-03 09:10:57:079 flexisip-debug-Invoking onRequest() on module Forward 2019-04-03 09:10:57:079 flexisip-debug-tport: not found from primary 0x1e83620, trying another one...*/10.75.22.8:5060 2019-04-03 09:10:57:079 flexisip-debug-tport: not found from primary 0x1e84100, trying another one...*/10.75.22.8:5060 2019-04-03 09:10:57:079 flexisip-debug-tport: not found from primary 0x1e84be0, trying another one...*/10.75.22.8:5060 2019-04-03 09:10:57:079 flexisip-debug-tport: not found from primary 0x1e856c0, trying another one...*/10.75.22.8:5060 2019-04-03 09:10:57:079 flexisip-debug-tport: not found from primary 0x1e864b0, trying another one...*/10.75.22.8:5060 2019-04-03 09:10:57:079 flexisip-debug-Path added to: sip:10.75.22.247:5061;fs-proxy-id=dc7b0b21aa83e9ed;lr 2019-04-03 09:10:57:079 flexisip-debug-Sending Request SIP message to sip:10.75.22.8:5060 REGISTER sip:pbx-mobile.example.com SIP/2.0 Via: SIP/2.0/UDP [2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:51741;branch=z9hG4bK.bWF5pGXJT;rport=51741 From: ;tag=QvPEEwndn To: sip:brian_h8@pbx-mobile.example.com CSeq: 20 REGISTER Call-ID: 4UXAHpFwo8 Max-Forwards: 69 Supported: replaces, outbound, gruu Accept: application/sdp, text/plain, application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="";+org.linphone.specs="lime" Expires: 172800 User-Agent: LinphoneAndroid/4.1-debug (FRD-L04) LinphoneSDK/4.1-312-gbaecc61-debug (master) (belle-sip/1.6.3) Path: 2019-04-03 09:10:57:079 flexisip-debug-Message is sent through an outgoing transaction. 2019-04-03 09:10:57:081 flexisip-debug-nta: selecting scheme sip 2019-04-03 09:10:57:082 flexisip-debug-tport: not found from primary 0x1e83620, trying another one...udp/10.75.22.8:5060 2019-04-03 09:10:57:082 flexisip-debug-tport: not found from primary 0x1e84100, trying another one...udp/10.75.22.8:5060 2019-04-03 09:10:57:083 flexisip-debug-tport: not found from primary 0x1e84be0, trying another one...udp/10.75.22.8:5060 2019-04-03 09:10:57:083 flexisip-debug-tport: not found from primary 0x1e856c0, trying another one...udp/10.75.22.8:5060 2019-04-03 09:10:57:083 flexisip-debug-tport: not found from primary 0x1e864b0, trying another one...udp/10.75.22.8:5060 2019-04-03 09:10:57:083 flexisip-debug-tport_tsend(0x1e83620) tpn = udp/10.75.22.8:5060 2019-04-03 09:10:57:084 flexisip-debug-tport_resolve addrinfo = 10.75.22.8:5060 2019-04-03 09:10:57:084 flexisip-debug-tport_by_addrinfo(0x1e83620): not found by name udp/10.75.22.8:5060 2019-04-03 09:10:57:085 flexisip-debug-tport_vsend returned 960 2019-04-03 09:10:57:085 flexisip-debug-nta: sent REGISTER (20) to udp/10.75.22.8:5060 2019-04-03 09:10:57:085 flexisip-debug-tport_pend(0x1e83620): pending 0x1ea35d0 for udp/10.75.22.247:5061 (already 0) 2019-04-03 09:10:57:085 flexisip-debug-nta: timer shortened to 500 ms 2019-04-03 09:10:57:085 flexisip-debug-Terminate SipEvent 0x1e8c2f8 2019-04-03 09:10:57:085 flexisip-debug-tport_wakeup_pri(0x1e83620): events IN 2019-04-03 09:10:57:085 flexisip-debug-tport_recv_event(0x1e83620) 2019-04-03 09:10:57:085 flexisip-debug-tport_recv_iovec(0x1e83620) msg 0x1ea31a0 from (udp/10.75.22.247:5061) has 627 bytes, veclen = 1 2019-04-03 09:10:57:085 flexisip-debug-tport_deliver(0x1e83620): msg 0x1ea31a0 (627 bytes) from udp/10.75.22.8:5061 next=(nil) 2019-04-03 09:10:57:085 flexisip-debug-nta: received 401 Unauthorized for REGISTER (20) 2019-04-03 09:10:57:085 flexisip-debug-nta: 401 Unauthorized is going to a transaction 2019-04-03 09:10:57:085 flexisip-debug-nta_outgoing: RTT is 2.032 ms 2019-04-03 09:10:57:086 flexisip-debug-tport_release(0x1e83620): 0x1ea35d0 by 0x1e99d30 with 0x1ea31a0 2019-04-03 09:10:57:086 flexisip-debug-OutgoingTransaction callback 0x1e9cc28 2019-04-03 09:10:57:086 flexisip-debug-New SipEvent 0x1ea2168 - 0x1ea31a0 2019-04-03 09:10:57:086 flexisip-debug-Receiving new Response SIP message: 401 SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 10.75.22.247:5061;rport=5061;received=10.75.22.247;branch=z9hG4bK.0950re74QrF112X4UyD6j64cSF Via: SIP/2.0/UDP [2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:51741;rport=51741;branch=z9hG4bK.bWF5pGXJT Call-ID: 4UXAHpFwo8 From: ;tag=QvPEEwndn To: ;tag=z9hG4bK.0950re74QrF112X4UyD6j64cSF CSeq: 20 REGISTER WWW-Authenticate: [redacted] Server: Asterisk PBX 13.25.0 Content-Length: 0 2019-04-03 09:10:57:086 flexisip-debug-Skipping onResponse() on module DoSProtection 2019-04-03 09:10:57:086 flexisip-debug-Invoking onResponse() on module SanityChecker 2019-04-03 09:10:57:087 flexisip-debug-Skipping onResponse() on module GarbageIn 2019-04-03 09:10:57:087 flexisip-debug-Invoking onResponse() on module NatHelper 2019-04-03 09:10:57:087 flexisip-debug-Skipping onResponse() on module Authentication 2019-04-03 09:10:57:087 flexisip-debug-Skipping onResponse() on module Redirect 2019-04-03 09:10:57:087 flexisip-debug-Skipping onResponse() on module GatewayAdapter 2019-04-03 09:10:57:087 flexisip-debug-Skipping onResponse() on module Presence 2019-04-03 09:10:57:087 flexisip-debug-Invoking onResponse() on module Registrar 2019-04-03 09:10:57:087 flexisip-debug-Skipping onResponse() on module StatisticsCollector 2019-04-03 09:10:57:087 flexisip-debug-Invoking onResponse() on module ContactRouteInserter 2019-04-03 09:10:57:087 flexisip-debug-Invoking onResponse() on module Router 2019-04-03 09:10:57:087 flexisip-debug-Invoking onResponse() on module PushNotification 2019-04-03 09:10:57:087 flexisip-debug-Skipping onResponse() on module LoadBalancer 2019-04-03 09:10:57:088 flexisip-debug-Skipping onResponse() on module MediaRelay 2019-04-03 09:10:57:088 flexisip-debug-Skipping onResponse() on module Transcoder 2019-04-03 09:10:57:088 flexisip-debug-Invoking onResponse() on module Forward 2019-04-03 09:10:57:088 flexisip-debug-Sending response: (via popped) SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP [2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:51741;rport=51741;branch=z9hG4bK.bWF5pGXJT Call-ID: 4UXAHpFwo8 From: ;tag=QvPEEwndn To: ;tag=z9hG4bK.0950re74QrF112X4UyD6j64cSF CSeq: 20 REGISTER WWW-Authenticate: [redacted] Server: Asterisk PBX 13.25.0 Content-Length: 0 2019-04-03 09:10:57:090 flexisip-debug-Response is sent through an incoming transaction. 2019-04-03 09:10:57:091 flexisip-debug-tport_tsend(0x1e820a0) tpn = UDP/[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:51741 2019-04-03 09:10:57:092 flexisip-debug-tport_resolve addrinfo = [2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:51741 2019-04-03 09:10:57:092 flexisip-debug-tport_by_addrinfo(0x1e820a0): not found by name UDP/[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:51741 2019-04-03 09:10:57:092 flexisip-debug-tport_vsend returned 519 2019-04-03 09:10:57:092 flexisip-debug-nta: sent 401 Unauthorized for REGISTER (20) 2019-04-03 09:10:57:094 flexisip-debug-nta_incoming_destroy: 0x1e98840 2019-04-03 09:10:57:094 flexisip-debug-Terminate SipEvent 0x1ea2168 2019-04-03 09:10:57:094 flexisip-debug-Delete IncomingTransaction 0x1e9c708 2019-04-03 09:10:57:094 flexisip-debug-Delete OutgoingTransaction 0x1e9cc28 2019-04-03 09:10:57:194 flexisip-debug-tport_wakeup_pri(0x1e820a0): events IN 2019-04-03 09:10:57:194 flexisip-debug-tport_recv_event(0x1e820a0) 2019-04-03 09:10:57:194 flexisip-debug-tport_recv_iovec(0x1e820a0) msg 0x1e8e9e0 from (udp/[2001:1234:5678:abcd::2]:5061) has 1248 bytes, veclen = 1 2019-04-03 09:10:57:194 flexisip-debug-tport_deliver(0x1e820a0): msg 0x1e8e9e0 (1248 bytes) from udp/[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:5061 next=(nil) 2019-04-03 09:10:57:194 flexisip-debug-nta: received REGISTER sip:pbx-mobile.example.com SIP/2.0 (CSeq 21) 2019-04-03 09:10:57:195 flexisip-debug-nta: REGISTER (21) to message callback 2019-04-03 09:10:57:195 flexisip-debug-New SipEvent 0x1e9cbe8 - msg 0x1e8e9e0 2019-04-03 09:10:57:195 flexisip-debug-Receiving new Request SIP message REGISTER from sip:brian_h8@pbx-mobile.example.com : REGISTER sip:pbx-mobile.example.com SIP/2.0 Via: SIP/2.0/UDP [2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:51741;branch=z9hG4bK.-QCNetQ8q;rport=51741 From: ;tag=QvPEEwndn To: sip:brian_h8@pbx-mobile.example.com CSeq: 21 REGISTER Call-ID: 4UXAHpFwo8 Max-Forwards: 70 Supported: replaces, outbound, gruu Accept: application/sdp, text/plain, application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="";+org.linphone.specs="lime" Expires: 172800 User-Agent: LinphoneAndroid/4.1-debug (FRD-L04) LinphoneSDK/4.1-312-gbaecc61-debug (master) (belle-sip/1.6.3) Authorization: [redacted] 2019-04-03 09:10:57:195 flexisip-debug-Skipping onRequest() on module DoSProtection 2019-04-03 09:10:57:195 flexisip-debug-Invoking onRequest() on module SanityChecker 2019-04-03 09:10:57:195 flexisip-debug-Skipping onRequest() on module GarbageIn 2019-04-03 09:10:57:195 flexisip-debug-Invoking onRequest() on module NatHelper 2019-04-03 09:10:57:195 flexisip-debug-Skipping onRequest() on module Authentication 2019-04-03 09:10:57:195 flexisip-debug-Skipping onRequest() on module Redirect 2019-04-03 09:10:57:195 flexisip-debug-Skipping onRequest() on module GatewayAdapter 2019-04-03 09:10:57:195 flexisip-debug-Skipping onRequest() on module Presence 2019-04-03 09:10:57:195 flexisip-debug-Invoking onRequest() on module Registrar 2019-04-03 09:10:57:195 flexisip-debug-Path added to: sip:[2001:1234:5678:abcd::2]:5061;lr 2019-04-03 09:10:57:195 flexisip-debug-New IncomingTransaction 0x1e9b278 2019-04-03 09:10:57:195 flexisip-debug-nta_incoming_create: created incoming transaction 0x1e9b300 2019-04-03 09:10:57:195 flexisip-debug-Replying Request SIP message: 100 Trying 2019-04-03 09:10:57:195 flexisip-debug-tport_tsend(0x1e820a0) tpn = UDP/[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:51741 2019-04-03 09:10:57:195 flexisip-debug-tport_resolve addrinfo = [2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:51741 2019-04-03 09:10:57:195 flexisip-debug-tport_by_addrinfo(0x1e820a0): not found by name UDP/[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:51741 2019-04-03 09:10:57:195 flexisip-debug-tport_vsend returned 339 2019-04-03 09:10:57:195 flexisip-debug-nta: sent 100 Trying for REGISTER (21) 2019-04-03 09:10:57:195 flexisip-debug-New OutgoingTransaction 0x1e999a8 2019-04-03 09:10:57:195 flexisip-debug-Contacts :;+sip.instance="";+org.linphone.specs="lime" 2019-04-03 09:10:57:195 flexisip-debug-Removed instance and push params: ;+sip.instance="";+org.linphone.specs="lime" 2019-04-03 09:10:57:195 flexisip-debug-Removed paths 2019-04-03 09:10:57:195 flexisip-debug-Skipping onRequest() on module StatisticsCollector 2019-04-03 09:10:57:195 flexisip-debug-Invoking onRequest() on module ContactRouteInserter 2019-04-03 09:10:57:195 flexisip-debug-Masquerading contact 2019-04-03 09:10:57:197 flexisip-debug-Rewriting contact with param [CtRtdc7b0b21aa83e9ed=udp:pbx-mobile.example.com] 2019-04-03 09:10:57:197 flexisip-debug-Contact has been rewritten to sip:brian_h8@[fd31:aeb1:48df::2]:5061;pn-silent=1;fs-conn-id=2135b2587618077;CtRtdc7b0b21aa83e9ed=udp:pbx-mobile.example.com 2019-04-03 09:10:57:197 flexisip-debug-Skipping onRequest() on module Router 2019-04-03 09:10:57:197 flexisip-debug-Invoking onRequest() on module PushNotification 2019-04-03 09:10:57:197 flexisip-debug-Skipping onRequest() on module LoadBalancer 2019-04-03 09:10:57:197 flexisip-debug-Skipping onRequest() on module MediaRelay 2019-04-03 09:10:57:197 flexisip-debug-Skipping onRequest() on module Transcoder 2019-04-03 09:10:57:197 flexisip-debug-Invoking onRequest() on module Forward 2019-04-03 09:10:57:198 flexisip-debug-tport: not found from primary 0x1e83620, trying another one...*/10.75.22.8:5060 2019-04-03 09:10:57:198 flexisip-debug-tport: not found from primary 0x1e84100, trying another one...*/10.75.22.8:5060 2019-04-03 09:10:57:198 flexisip-debug-tport: not found from primary 0x1e84be0, trying another one...*/10.75.22.8:5060 2019-04-03 09:10:57:198 flexisip-debug-tport: not found from primary 0x1e856c0, trying another one...*/10.75.22.8:5060 2019-04-03 09:10:57:198 flexisip-debug-tport: not found from primary 0x1e864b0, trying another one...*/10.75.22.8:5060 2019-04-03 09:10:57:198 flexisip-debug-Path added to: sip:10.75.22.247:5061;fs-proxy-id=dc7b0b21aa83e9ed;lr 2019-04-03 09:10:57:200 flexisip-debug-Sending Request SIP message to sip:10.75.22.8:5060 REGISTER sip:pbx-mobile.example.com SIP/2.0 Via: SIP/2.0/UDP [2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:51741;branch=z9hG4bK.-QCNetQ8q;rport=51741 From: ;tag=QvPEEwndn To: sip:brian_h8@pbx-mobile.example.com CSeq: 21 REGISTER Call-ID: 4UXAHpFwo8 Max-Forwards: 69 Supported: replaces, outbound, gruu Accept: application/sdp, text/plain, application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="";+org.linphone.specs="lime" Expires: 172800 User-Agent: LinphoneAndroid/4.1-debug (FRD-L04) LinphoneSDK/4.1-312-gbaecc61-debug (master) (belle-sip/1.6.3) Authorization: [redacted] Path: 2019-04-03 09:10:57:200 flexisip-debug-Message is sent through an outgoing transaction. 2019-04-03 09:10:57:200 flexisip-debug-nta: selecting scheme sip 2019-04-03 09:10:57:201 flexisip-debug-tport: not found from primary 0x1e83620, trying another one...udp/10.75.22.8:5060 2019-04-03 09:10:57:201 flexisip-debug-tport: not found from primary 0x1e84100, trying another one...udp/10.75.22.8:5060 2019-04-03 09:10:57:201 flexisip-debug-tport: not found from primary 0x1e84be0, trying another one...udp/10.75.22.8:5060 2019-04-03 09:10:57:201 flexisip-debug-tport: not found from primary 0x1e856c0, trying another one...udp/10.75.22.8:5060 2019-04-03 09:10:57:201 flexisip-debug-tport: not found from primary 0x1e864b0, trying another one...udp/10.75.22.8:5060 2019-04-03 09:10:57:201 flexisip-debug-tport_tsend(0x1e83620) tpn = udp/10.75.22.8:5060 2019-04-03 09:10:57:201 flexisip-debug-tport_resolve addrinfo = 10.75.22.8:5060 2019-04-03 09:10:57:201 flexisip-debug-tport_by_addrinfo(0x1e83620): not found by name udp/10.75.22.8:5060 2019-04-03 09:10:57:205 flexisip-debug-tport_vsend returned 1249 2019-04-03 09:10:57:205 flexisip-debug-nta: sent REGISTER (21) to udp/10.75.22.8:5060 2019-04-03 09:10:57:205 flexisip-debug-tport_pend(0x1e83620): pending 0x1e8e9e0 for udp/10.75.22.247:5061 (already 0) 2019-04-03 09:10:57:205 flexisip-debug-Terminate SipEvent 0x1e9cbe8 2019-04-03 09:10:57:206 flexisip-debug-tport_wakeup_pri(0x1e83620): events IN 2019-04-03 09:10:57:206 flexisip-debug-tport_recv_event(0x1e83620) 2019-04-03 09:10:57:206 flexisip-debug-tport_recv_iovec(0x1e83620) msg 0x1e92580 from (udp/10.75.22.247:5061) has 681 bytes, veclen = 1 2019-04-03 09:10:57:206 flexisip-debug-tport_deliver(0x1e83620): msg 0x1e92580 (681 bytes) from udp/10.75.22.8:5061 next=(nil) 2019-04-03 09:10:57:207 flexisip-debug-nta: received 200 OK for REGISTER (21) 2019-04-03 09:10:57:207 flexisip-debug-nta: 200 OK is going to a transaction 2019-04-03 09:10:57:207 flexisip-debug-nta_outgoing: RTT is 6.029 ms 2019-04-03 09:10:57:207 flexisip-debug-tport_release(0x1e83620): 0x1e8e9e0 by 0x1ea0cf0 with 0x1e92580 2019-04-03 09:10:57:207 flexisip-debug-OutgoingTransaction callback 0x1e999a8 2019-04-03 09:10:57:207 flexisip-debug-New SipEvent 0x1e933d8 - 0x1e92580 2019-04-03 09:10:57:207 flexisip-debug-Receiving new Response SIP message: 200 SIP/2.0 200 OK Via: SIP/2.0/UDP 10.75.22.247:5061;rport=5061;received=10.75.22.247;branch=z9hG4bK.tBjDypQQ4B72mt4FNmag74v5Qe Via: SIP/2.0/UDP [2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:51741;rport=51741;branch=z9hG4bK.-QCNetQ8q Call-ID: 4UXAHpFwo8 From: ;tag=QvPEEwndn To: ;tag=z9hG4bK.tBjDypQQ4B72mt4FNmag74v5Qe CSeq: 21 REGISTER Date: Wed, 03 Apr 2019 13:10:57 GMT Contact: ;expires=172799 Expires: 172800 Server: Asterisk PBX 13.25.0 Content-Length: 0 2019-04-03 09:10:57:207 flexisip-debug-Skipping onResponse() on module DoSProtection 2019-04-03 09:10:57:207 flexisip-debug-Invoking onResponse() on module SanityChecker 2019-04-03 09:10:57:208 flexisip-debug-Skipping onResponse() on module GarbageIn 2019-04-03 09:10:57:208 flexisip-debug-Invoking onResponse() on module NatHelper 2019-04-03 09:10:57:208 flexisip-debug-Skipping onResponse() on module Authentication 2019-04-03 09:10:57:208 flexisip-debug-Skipping onResponse() on module Redirect 2019-04-03 09:10:57:208 flexisip-debug-Skipping onResponse() on module GatewayAdapter 2019-04-03 09:10:57:209 flexisip-debug-Skipping onResponse() on module Presence 2019-04-03 09:10:57:209 flexisip-debug-Invoking onResponse() on module Registrar 2019-04-03 09:10:57:209 flexisip-debug-Suspend SipEvent 0x1e933d8 2019-04-03 09:10:57:209 flexisip-debug-Updating binding 2019-04-03 09:10:57:210 flexisip-debug-AOR brian_h8@pbx-mobile.example.com found 2019-04-03 09:10:57:212 flexisip-debug-Trying to insert new contact sip:brian_h8@[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:51741;app-id=755770037818;pn-type=firebase;pn-tok==[redacted];pn-silent=1;fs-conn-id=2135b2587618077 path="sip:[2001:1234:5678:abcd::2]:5061;lr" user-agent="" alias=no uid="" expire=86400 s (Thu Apr 4 09:10:57 2019) 2019-04-03 09:10:57:212 flexisip-debug-Cleaning older line '""' for contact brian_h8:[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:50458 2019-04-03 09:10:57:212 flexisip-debug-tport: not found from primary 0x1e80b20, trying another one...*/2001:1234:5678:abcd:d4a3:bc17:899c:9cbc:50458 2019-04-03 09:10:57:212 flexisip-debug-tport: not found from primary 0x1e815e0, trying another one...*/2001:1234:5678:abcd:d4a3:bc17:899c:9cbc:50458 2019-04-03 09:10:57:212 flexisip-debug-tport: not found from primary 0x1e820a0, trying another one...*/2001:1234:5678:abcd:d4a3:bc17:899c:9cbc:50458 2019-04-03 09:10:57:212 flexisip-debug-tport: not found from primary 0x1e82b60, trying another one...*/2001:1234:5678:abcd:d4a3:bc17:899c:9cbc:50458 2019-04-03 09:10:57:212 flexisip-debug-tport: not found from primary 0x1e86f90, trying another one...*/2001:1234:5678:abcd:d4a3:bc17:899c:9cbc:50458 2019-04-03 09:10:57:212 flexisip-debug-Removing old tport for sip uri sip:brian_h8@[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:50458;app-id=755770037818;pn-type=firebase;pn-tok==[redacted];pn-silent=1;fs-conn-id=2135b2587618077 2019-04-03 09:10:57:212 flexisip-debug-Record contains 1 contacts sip:brian_h8@[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:51741;app-id=755770037818;pn-type=firebase;pn-tok==[redacted];pn-silent=1;fs-conn-id=2135b2587618077 path="sip:[2001:1234:5678:abcd::2]:5061;lr" user-agent="" alias=no uid="" expire=86400 s (Thu Apr 4 09:10:57 2019) ========================== 2019-04-03 09:10:57:212 flexisip-debug-Publish topic = brian_h8@pbx-mobile.example.com, uid = "" 2019-04-03 09:10:57:213 flexisip-debug-Notify topic = brian_h8@pbx-mobile.example.com, uid = "" 2019-04-03 09:10:57:213 flexisip-debug-Step: 1 Found contact sip:brian_h8@pbx-mobile.example.com -> sip:brian_h8@[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:51741;app-id=755770037818;pn-type=firebase;pn-tok==[redacted];pn-silent=1;fs-conn-id=2135b2587618077 usedAsRoute:0 2019-04-03 09:10:57:213 flexisip-debug-Step: 1 Returning collected records 1 2019-04-03 09:10:57:213 flexisip-debug-Inject Response SIP message: SIP/2.0 200 OK Via: SIP/2.0/UDP 10.75.22.247:5061;rport=5061;received=10.75.22.247;branch=z9hG4bK.tBjDypQQ4B72mt4FNmag74v5Qe Via: SIP/2.0/UDP [2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:51741;rport=51741;branch=z9hG4bK.-QCNetQ8q Call-ID: 4UXAHpFwo8 From: ;tag=QvPEEwndn To: ;tag=z9hG4bK.tBjDypQQ4B72mt4FNmag74v5Qe CSeq: 21 REGISTER Date: Wed, 03 Apr 2019 13:10:57 GMT Contact: ;expires=172799 Expires: 172800 Server: Asterisk PBX 13.25.0 Content-Length: 0 Contact: ;+sip.instance="";+org.linphone.specs="lime";pub-gruu Path: 2019-04-03 09:10:57:214 flexisip-debug-Restart SipEvent 0x1e933d8 2019-04-03 09:10:57:214 flexisip-debug-Injecting response event after Registrar 2019-04-03 09:10:57:214 flexisip-debug-Skipping onResponse() on module StatisticsCollector 2019-04-03 09:10:57:215 flexisip-debug-Invoking onResponse() on module ContactRouteInserter 2019-04-03 09:10:57:215 flexisip-debug-Invoking onResponse() on module Router 2019-04-03 09:10:57:215 flexisip-debug-Invoking onResponse() on module PushNotification 2019-04-03 09:10:57:215 flexisip-debug-Skipping onResponse() on module LoadBalancer 2019-04-03 09:10:57:215 flexisip-debug-Skipping onResponse() on module MediaRelay 2019-04-03 09:10:57:216 flexisip-debug-Skipping onResponse() on module Transcoder 2019-04-03 09:10:57:216 flexisip-debug-Invoking onResponse() on module Forward 2019-04-03 09:10:57:216 flexisip-debug-Sending response: (via popped) SIP/2.0 200 OK Via: SIP/2.0/UDP [2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:51741;rport=51741;branch=z9hG4bK.-QCNetQ8q Call-ID: 4UXAHpFwo8 From: ;tag=QvPEEwndn To: ;tag=z9hG4bK.tBjDypQQ4B72mt4FNmag74v5Qe CSeq: 21 REGISTER Date: Wed, 03 Apr 2019 13:10:57 GMT Contact: ;expires=172799 Expires: 172800 Server: Asterisk PBX 13.25.0 Content-Length: 0 Contact: ;+sip.instance="";+org.linphone.specs="lime";pub-gruu Path: 2019-04-03 09:10:57:217 flexisip-debug-Response is sent through an incoming transaction. 2019-04-03 09:10:57:217 flexisip-debug-tport_tsend(0x1e820a0) tpn = UDP/[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:51741 2019-04-03 09:10:57:218 flexisip-debug-tport_resolve addrinfo = [2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:51741 2019-04-03 09:10:57:218 flexisip-debug-tport_by_addrinfo(0x1e820a0): not found by name UDP/[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:51741 2019-04-03 09:10:57:218 flexisip-debug-tport_vsend returned 1024 2019-04-03 09:10:57:218 flexisip-debug-nta: sent 200 OK for REGISTER (21) 2019-04-03 09:10:57:219 flexisip-debug-nta_incoming_destroy: 0x1e9b300 2019-04-03 09:10:57:219 flexisip-debug-Terminate SipEvent 0x1e933d8 2019-04-03 09:10:57:219 flexisip-debug-Delete IncomingTransaction 0x1e9b278 2019-04-03 09:10:57:219 flexisip-debug-Delete OutgoingTransaction 0x1e999a8 2019-04-03 09:10:57:305 flexisip-debug-tport_wakeup_pri(0x1e80b20): events IN 2019-04-03 09:10:57:305 flexisip-debug-tport_recv_event(0x1e80b20) 2019-04-03 09:10:57:305 flexisip-debug-tport_recv_iovec(0x1e80b20) msg 0x1e8f700 from (udp/[fd31:aeb1:48df::2]:5061) has 876 bytes, veclen = 1 2019-04-03 09:10:57:305 flexisip-debug-tport_deliver(0x1e80b20): msg 0x1e8f700 (876 bytes) from udp/[2001:1234:5678:abcd::2]:5061 next=(nil) 2019-04-03 09:10:57:305 flexisip-debug-nta: received NOTIFY sip:brian_h8@[fd31:aeb1:48df::2]:5061;pn-silent=1;fs-conn-id=2135b2587618077;CtRtdc7b0b21aa83e9ed=udp:pbx-mobile.example.com SIP/2.0 (CSeq 10750) 2019-04-03 09:10:57:305 flexisip-debug-nta: canonizing sip:brian_h8@[fd31:aeb1:48df::2]:5061 with contact 2019-04-03 09:10:57:305 flexisip-debug-nta: NOTIFY (10750) to message callback 2019-04-03 09:10:57:305 flexisip-debug-New SipEvent 0x1e8fd68 - msg 0x1e8f700 2019-04-03 09:10:57:305 flexisip-debug-Receiving new Request SIP message NOTIFY from sip:[redacted]@[2001:1234:5678:abcd::2] : NOTIFY sip:brian_h8@[fd31:aeb1:48df::2]:5061;pn-silent=1;fs-conn-id=2135b2587618077;CtRtdc7b0b21aa83e9ed=udp:pbx-mobile.example.com SIP/2.0 Via: SIP/2.0/UDP [2001:1234:5678:abcd::2]:5060;rport=5060;branch=z9hG4bKPj64741ad4-6bd7-4e72-94c2-f94233413bcb From: ;tag=b6448f7a-6bf9-4c64-a1ea-76f73266a2b4 To: Contact: Call-ID: 8a1e04ec-7255-416c-b0bd-d4aba5a67a65 CSeq: 10750 NOTIFY Subscription-State: terminated Event: message-summary Allow-Events: presence, dialog, message-summary, refer Max-Forwards: 70 User-Agent: Asterisk PBX 13.25.0 Content-Type: application/simple-message-summary Content-Length: 48 Messages-Waiting: no Voice-Message: 0/0 (0/0) 2019-04-03 09:10:57:305 flexisip-debug-Skipping onRequest() on module DoSProtection 2019-04-03 09:10:57:305 flexisip-debug-Invoking onRequest() on module SanityChecker 2019-04-03 09:10:57:305 flexisip-debug-Skipping onRequest() on module GarbageIn 2019-04-03 09:10:57:305 flexisip-debug-Invoking onRequest() on module NatHelper 2019-04-03 09:10:57:305 flexisip-debug-Skipping onRequest() on module Authentication 2019-04-03 09:10:57:305 flexisip-debug-Skipping onRequest() on module Redirect 2019-04-03 09:10:57:305 flexisip-debug-Skipping onRequest() on module GatewayAdapter 2019-04-03 09:10:57:305 flexisip-debug-Skipping onRequest() on module Presence 2019-04-03 09:10:57:305 flexisip-debug-Invoking onRequest() on module Registrar 2019-04-03 09:10:57:305 flexisip-debug-Skipping onRequest() on module StatisticsCollector 2019-04-03 09:10:57:305 flexisip-debug-Invoking onRequest() on module ContactRouteInserter 2019-04-03 09:10:57:305 flexisip-debug-Found a contact route parameter 2019-04-03 09:10:57:305 flexisip-debug-Request url changed to sip:brian_h8@pbx-mobile.example.com;pn-silent=1;fs-conn-id=2135b2587618077;doroute 2019-04-03 09:10:57:306 flexisip-debug-Invoking onRequest() on module Router 2019-04-03 09:10:57:306 flexisip-debug-Fetch for url sip:brian_h8@pbx-mobile.example.com;pn-silent=1;fs-conn-id=2135b2587618077;doroute. 2019-04-03 09:10:57:306 flexisip-debug-New IncomingTransaction 0x1e93d38 2019-04-03 09:10:57:306 flexisip-debug-nta_incoming_create: created incoming transaction 0x1e93e30 2019-04-03 09:10:57:306 flexisip-debug-Replying Request SIP message: 100 Trying 2019-04-03 09:10:57:306 flexisip-debug-tport_tsend(0x1e80b20) tpn = UDP/[2001:1234:5678:abcd::2]:5060 2019-04-03 09:10:57:306 flexisip-debug-tport_resolve addrinfo = [2001:1234:5678:abcd::2]:5060 2019-04-03 09:10:57:306 flexisip-debug-tport_by_addrinfo(0x1e80b20): not found by name UDP/[2001:1234:5678:abcd::2]:5060 2019-04-03 09:10:57:306 flexisip-debug-tport_vsend returned 480 2019-04-03 09:10:57:306 flexisip-debug-nta: sent 100 Trying for NOTIFY (10750) 2019-04-03 09:10:57:306 flexisip-debug-Suspend SipEvent 0x1e8fd68 2019-04-03 09:10:57:306 flexisip-debug-Step: 1 Found contact sip:brian_h8@pbx-mobile.example.com;pn-silent=1;fs-conn-id=2135b2587618077;doroute -> sip:brian_h8@[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:51741;app-id=755770037818;pn-type=firebase;pn-tok==[redacted];pn-silent=1;fs-conn-id=2135b2587618077 usedAsRoute:0 2019-04-03 09:10:57:306 flexisip-debug-Step: 1 Returning collected records 1 2019-04-03 09:10:57:306 flexisip-debug-New SipEvent 0x1e97b98 with state SUSPENDED 2019-04-03 09:10:57:306 flexisip-debug-New MsgSip 0x1e94c78 copied from MsgSip 0x1ea26f8 2019-04-03 09:10:57:306 flexisip-debug-New ForkBasicContext 0x1e97a98 2019-04-03 09:10:57:306 flexisip-debug-New SipEvent 0x1ea4b88 with state SUSPENDED 2019-04-03 09:10:57:306 flexisip-debug-New MsgSip 0x1ea4c58 copied from MsgSip 0x1ea26f8 2019-04-03 09:10:57:306 flexisip-debug-New OutgoingTransaction 0x1ea5eb8 2019-04-03 09:10:57:306 flexisip-debug-ForkContext [0x1e97a98] new fork branch [0x1ea61f8] 2019-04-03 09:10:57:306 flexisip-debug-Fork to sip:brian_h8@[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:51741;app-id=755770037818;pn-type=firebase;pn-tok==[redacted];pn-silent=1;fs-conn-id=2135b2587618077 2019-04-03 09:10:57:306 flexisip-debug-Started forking branches with priority [0x1e97a98]: 1.000000 2019-04-03 09:10:57:306 flexisip-debug-Inject Request SIP message: NOTIFY sip:brian_h8@[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:51741;app-id=755770037818;pn-type=firebase;pn-tok==[redacted];pn-silent=1;fs-conn-id=2135b2587618077 SIP/2.0 Via: SIP/2.0/UDP [2001:1234:5678:abcd::2]:5060;rport=5060;branch=z9hG4bKPj64741ad4-6bd7-4e72-94c2-f94233413bcb Route: Max-Forwards: 70 From: ;tag=b6448f7a-6bf9-4c64-a1ea-76f73266a2b4 To: Call-ID: 8a1e04ec-7255-416c-b0bd-d4aba5a67a65 CSeq: 10750 NOTIFY Contact: User-Agent: Asterisk PBX 13.25.0 Event: message-summary Allow-Events: presence, dialog, message-summary, refer Subscription-State: terminated Content-Type: application/simple-message-summary Content-Length: 48 Messages-Waiting: no Voice-Message: 0/0 (0/0) 2019-04-03 09:10:57:306 flexisip-debug-Restart SipEvent 0x1ea4b88 2019-04-03 09:10:57:306 flexisip-debug-Injecting request event after Router 2019-04-03 09:10:57:306 flexisip-debug-Invoking onRequest() on module PushNotification 2019-04-03 09:10:57:306 flexisip-debug-Skipping onRequest() on module LoadBalancer 2019-04-03 09:10:57:306 flexisip-debug-Skipping onRequest() on module MediaRelay 2019-04-03 09:10:57:306 flexisip-debug-Skipping onRequest() on module Transcoder 2019-04-03 09:10:57:306 flexisip-debug-Invoking onRequest() on module Forward 2019-04-03 09:10:57:306 flexisip-debug-Removing top route sip:[2001:1234:5678:abcd::2]:5061;lr 2019-04-03 09:10:57:310 flexisip-debug-tport: not found from primary 0x1e80b20, trying another one...*/2001:1234:5678:abcd:d4a3:bc17:899c:9cbc:51741 2019-04-03 09:10:57:311 flexisip-debug-tport: not found from primary 0x1e815e0, trying another one...*/2001:1234:5678:abcd:d4a3:bc17:899c:9cbc:51741 2019-04-03 09:10:57:311 flexisip-debug-tport: not found from primary 0x1e820a0, trying another one...*/2001:1234:5678:abcd:d4a3:bc17:899c:9cbc:51741 2019-04-03 09:10:57:311 flexisip-debug-tport: not found from primary 0x1e82b60, trying another one...*/2001:1234:5678:abcd:d4a3:bc17:899c:9cbc:51741 2019-04-03 09:10:57:311 flexisip-debug-tport: not found from primary 0x1e86f90, trying another one...*/2001:1234:5678:abcd:d4a3:bc17:899c:9cbc:51741 2019-04-03 09:10:57:311 flexisip-debug-Removed push params from contact 2019-04-03 09:10:57:311 flexisip-debug-Sending Request SIP message to sip:brian_h8@[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:51741;pn-silent=1 NOTIFY sip:brian_h8@[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:51741;pn-silent=1 SIP/2.0 Via: SIP/2.0/UDP [2001:1234:5678:abcd::2]:5060;rport=5060;branch=z9hG4bKPj64741ad4-6bd7-4e72-94c2-f94233413bcb Max-Forwards: 69 From: ;tag=b6448f7a-6bf9-4c64-a1ea-76f73266a2b4 To: Call-ID: 8a1e04ec-7255-416c-b0bd-d4aba5a67a65 CSeq: 10750 NOTIFY Contact: User-Agent: Asterisk PBX 13.25.0 Event: message-summary Allow-Events: presence, dialog, message-summary, refer Subscription-State: terminated Content-Type: application/simple-message-summary Content-Length: 48 Messages-Waiting: no Voice-Message: 0/0 (0/0) 2019-04-03 09:10:57:311 flexisip-debug-Message is sent through an outgoing transaction. 2019-04-03 09:10:57:311 flexisip-debug-nta: selecting scheme sip 2019-04-03 09:10:57:311 flexisip-debug-tport: not found from primary 0x1e80b20, trying another one...udp/[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:51741 2019-04-03 09:10:57:311 flexisip-debug-tport: not found from primary 0x1e815e0, trying another one...udp/[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:51741 2019-04-03 09:10:57:311 flexisip-debug-tport: not found from primary 0x1e820a0, trying another one...udp/[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:51741 2019-04-03 09:10:57:311 flexisip-debug-tport: not found from primary 0x1e82b60, trying another one...udp/[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:51741 2019-04-03 09:10:57:311 flexisip-debug-tport: not found from primary 0x1e86f90, trying another one...udp/[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:51741 2019-04-03 09:10:57:312 flexisip-debug-tport_tsend(0x1e80b20) tpn = udp/[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:51741 2019-04-03 09:10:57:312 flexisip-debug-tport_resolve addrinfo = [2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:51741 2019-04-03 09:10:57:312 flexisip-debug-tport_by_addrinfo(0x1e80b20): not found by name udp/[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:51741 2019-04-03 09:10:57:313 flexisip-debug-tport_vsend returned 913 2019-04-03 09:10:57:313 flexisip-debug-nta: sent NOTIFY (10750) to udp/[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:51741 2019-04-03 09:10:57:313 flexisip-debug-tport_pend(0x1e80b20): pending 0x1ea4c80 for udp/[fd31:aeb1:48df::2]:5061 (already 0) 2019-04-03 09:10:57:313 flexisip-debug-Terminate SipEvent 0x1ea4b88 2019-04-03 09:10:57:357 flexisip-debug-tport_wakeup_pri(0x1e80b20): events IN 2019-04-03 09:10:57:357 flexisip-debug-tport_recv_event(0x1e80b20) 2019-04-03 09:10:57:357 flexisip-debug-tport_recv_iovec(0x1e80b20) msg 0x1ea7f40 from (udp/[fd31:aeb1:48df::2]:5061) has 663 bytes, veclen = 1 2019-04-03 09:10:57:357 flexisip-debug-tport_deliver(0x1e80b20): msg 0x1ea7f40 (663 bytes) from udp/[2001:1234:5678:abcd:d4a3:bc17:899c:9cbc]:5061 next=(nil) 2019-04-03 09:10:57:357 flexisip-debug-nta: received 200 Ok for NOTIFY (10750) 2019-04-03 09:10:57:357 flexisip-debug-nta: 200 Ok is going to a transaction 2019-04-03 09:10:57:357 flexisip-debug-nta_outgoing: RTT is 45.835 ms 2019-04-03 09:10:57:358 flexisip-debug-tport_release(0x1e80b20): 0x1ea4c80 by 0x1ea7340 with 0x1ea7f40 2019-04-03 09:10:57:358 flexisip-debug-OutgoingTransaction callback 0x1ea5eb8 2019-04-03 09:10:57:358 flexisip-debug-New SipEvent 0x1e94af8 - 0x1ea7f40 2019-04-03 09:10:57:358 flexisip-debug-Receiving new Response SIP message: 200 SIP/2.0 200 Ok Via: SIP/2.0/UDP [fd31:aeb1:48df::2]:5061;rport;branch=z9hG4bK.FB0r8rXaaar6K3U6F7D5Fyeyya Via: SIP/2.0/UDP [2001:1234:5678:abcd::2]:5060;rport=5060;branch=z9hG4bKPj64741ad4-6bd7-4e72-94c2-f94233413bcb From: ;tag=b6448f7a-6bf9-4c64-a1ea-76f73266a2b4 To: ;tag=qyXjh7V Call-ID: 8a1e04ec-7255-416c-b0bd-d4aba5a67a65 CSeq: 10750 NOTIFY User-Agent: LinphoneAndroid/4.1-debug (FRD-L04) LinphoneSDK/4.1-312-gbaecc61-debug (master) (belle-sip/1.6.3) Supported: replaces, outbound, gruu 2019-04-03 09:10:57:359 flexisip-debug-Skipping onResponse() on module DoSProtection 2019-04-03 09:10:57:359 flexisip-debug-Invoking onResponse() on module SanityChecker 2019-04-03 09:10:57:359 flexisip-debug-Skipping onResponse() on module GarbageIn 2019-04-03 09:10:57:359 flexisip-debug-Invoking onResponse() on module NatHelper 2019-04-03 09:10:57:359 flexisip-debug-Skipping onResponse() on module Authentication 2019-04-03 09:10:57:359 flexisip-debug-Skipping onResponse() on module Redirect 2019-04-03 09:10:57:359 flexisip-debug-Skipping onResponse() on module GatewayAdapter 2019-04-03 09:10:57:359 flexisip-debug-Skipping onResponse() on module Presence 2019-04-03 09:10:57:359 flexisip-debug-Invoking onResponse() on module Registrar 2019-04-03 09:10:57:359 flexisip-debug-Skipping onResponse() on module StatisticsCollector 2019-04-03 09:10:57:359 flexisip-debug-Invoking onResponse() on module ContactRouteInserter 2019-04-03 09:10:57:359 flexisip-debug-Invoking onResponse() on module Router 2019-04-03 09:10:57:359 flexisip-debug-New SipEvent 0x1ea85e8 with state STARTED 2019-04-03 09:10:57:359 flexisip-debug-New MsgSip 0x1e94b88 copied from MsgSip 0x1e94928 2019-04-03 09:10:57:359 flexisip-debug-Suspend SipEvent 0x1ea85e8 2019-04-03 09:10:57:360 flexisip-debug-Inject Response SIP message: SIP/2.0 200 Ok Via: SIP/2.0/UDP [fd31:aeb1:48df::2]:5061;rport;branch=z9hG4bK.FB0r8rXaaar6K3U6F7D5Fyeyya Via: SIP/2.0/UDP [2001:1234:5678:abcd::2]:5060;rport=5060;branch=z9hG4bKPj64741ad4-6bd7-4e72-94c2-f94233413bcb From: ;tag=b6448f7a-6bf9-4c64-a1ea-76f73266a2b4 To: ;tag=qyXjh7V Call-ID: 8a1e04ec-7255-416c-b0bd-d4aba5a67a65 CSeq: 10750 NOTIFY User-Agent: LinphoneAndroid/4.1-debug (FRD-L04) LinphoneSDK/4.1-312-gbaecc61-debug (master) (belle-sip/1.6.3) Supported: replaces, outbound, gruu 2019-04-03 09:10:57:360 flexisip-debug-Restart SipEvent 0x1ea85e8 2019-04-03 09:10:57:360 flexisip-debug-Injecting response event after Router 2019-04-03 09:10:57:360 flexisip-debug-Invoking onResponse() on module PushNotification 2019-04-03 09:10:57:360 flexisip-debug-Skipping onResponse() on module LoadBalancer 2019-04-03 09:10:57:360 flexisip-debug-Skipping onResponse() on module MediaRelay 2019-04-03 09:10:57:360 flexisip-debug-Skipping onResponse() on module Transcoder 2019-04-03 09:10:57:360 flexisip-debug-Invoking onResponse() on module Forward 2019-04-03 09:10:57:360 flexisip-debug-Automatically adding content-length because going to a stream-based transport 2019-04-03 09:10:57:360 flexisip-debug-Sending response: (via popped) SIP/2.0 200 Ok Via: SIP/2.0/UDP [2001:1234:5678:abcd::2]:5060;rport=5060;branch=z9hG4bKPj64741ad4-6bd7-4e72-94c2-f94233413bcb From: ;tag=b6448f7a-6bf9-4c64-a1ea-76f73266a2b4 To: ;tag=qyXjh7V Call-ID: 8a1e04ec-7255-416c-b0bd-d4aba5a67a65 CSeq: 10750 NOTIFY User-Agent: LinphoneAndroid/4.1-debug (FRD-L04) LinphoneSDK/4.1-312-gbaecc61-debug (master) (belle-sip/1.6.3) Supported: replaces, outbound, gruu Content-Length: 0 2019-04-03 09:10:57:361 flexisip-debug-Response is sent through an incoming transaction. 2019-04-03 09:10:57:361 flexisip-debug-tport_tsend(0x1e80b20) tpn = UDP/[2001:1234:5678:abcd::2]:5060 2019-04-03 09:10:57:361 flexisip-debug-tport_resolve addrinfo = [2001:1234:5678:abcd::2]:5060 2019-04-03 09:10:57:362 flexisip-debug-tport_by_addrinfo(0x1e80b20): not found by name UDP/[2001:1234:5678:abcd::2]:5060 2019-04-03 09:10:57:364 flexisip-debug-tport_vsend returned 591 2019-04-03 09:10:57:364 flexisip-debug-nta: sent 200 Ok for NOTIFY (10750) 2019-04-03 09:10:57:364 flexisip-debug-nta_incoming_destroy: 0x1e93e30 2019-04-03 09:10:57:364 flexisip-debug-Terminate SipEvent 0x1ea85e8 2019-04-03 09:10:57:364 flexisip-debug-Terminate SipEvent 0x1e94af8 2019-04-03 09:10:57:364 flexisip-debug-Delete OutgoingTransaction 0x1ea5eb8 2019-04-03 09:10:57:364 flexisip-debug-Delete IncomingTransaction 0x1e93d38 2019-04-03 09:10:57:364 flexisip-debug-Destroy ForkBasicContext 0x1e97a98 2019-04-03 09:10:57:578 flexisip-debug-nta: timer set next to 4507 ms 2019-04-03 09:11:02:085 flexisip-debug-nta: timer K fired, terminate REGISTER (20) 2019-04-03 09:11:02:085 flexisip-debug-outgoing_reclaim_all((nil), (nil), 0x7ffd48758220) 2019-04-03 09:11:02:085 flexisip-debug-nta_outgoing_timer: 0/0 resent, 0/0 tout, 1/4 term, 1/4 free 2019-04-03 09:11:02:085 flexisip-debug-nta: timer set next to 121 ms 2019-04-03 09:11:02:206 flexisip-debug-nta: timer K fired, terminate REGISTER (21) 2019-04-03 09:11:02:206 flexisip-debug-outgoing_reclaim_all((nil), (nil), 0x7ffd48758220) 2019-04-03 09:11:02:206 flexisip-debug-nta_outgoing_timer: 0/0 resent, 0/0 tout, 1/3 term, 1/3 free 2019-04-03 09:11:02:206 flexisip-debug-nta: timer set next to 151 ms 2019-04-03 09:11:02:357 flexisip-debug-nta: timer K fired, terminate NOTIFY (10750) 2019-04-03 09:11:02:357 flexisip-debug-outgoing_reclaim_all((nil), (nil), 0x7ffd48758220) 2019-04-03 09:11:02:357 flexisip-debug-nta_outgoing_timer: 0/0 resent, 0/0 tout, 1/2 term, 1/2 free 2019-04-03 09:11:02:357 flexisip-debug-nta: timer set next to 16028 ms