[Dec 19 22:31:38] Asterisk 16.4.0 built by root @ localhost on a x86_64 running Linux on 2019-08-16 05:48:06 UTC
[Dec 19 22:32:09] DEBUG[2032] chan_iax2.c: ip callno count decremented to 1 for fc00:1234:c::1
[Dec 19 22:32:09] DEBUG[2036] chan_iax2.c: ip callno count incremented to 2 for fc00:1234:c::1
[Dec 19 22:32:09] DEBUG[2037] chan_iax2.c: schedule decrement of callno used for fc00:1234:c::1 in 60 seconds
[Dec 19 22:32:09] DEBUG[2037] chan_iax2.c: Peer 000c: got pong, lastms 1, historicms 1, maxms 30000
[Dec 19 22:32:19] DEBUG[2027] res_pjsip_registrar.c: Woke up at 1576794739  Interval: 30
[Dec 19 22:32:19] DEBUG[2027] res_pjsip_registrar.c: Expiring 0 contacts
[Dec 19 22:32:22] DEBUG[2016] res_pjsip/pjsip_distributor.c: Could not find matching transaction for Request msg REGISTER/cseq=20 (rdata0x55b81e112e08)
[Dec 19 22:32:22] DEBUG[2016] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-00000053 to use for Request msg REGISTER/cseq=20 (rdata0x55b81e112e08)
[Dec 19 22:32:22] DEBUG[2014] threadpool.c: Increasing threadpool pjsip/pool's size by 5
[Dec 19 22:32:22] DEBUG[11470] netsock2.c: Splitting '172.16.66.101' into...
[Dec 19 22:32:22] DEBUG[11470] netsock2.c: ...host '172.16.66.101' and port ''.
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_endpoint_identifier_ip.c: No identify sections to match against
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_endpoint_identifier_user.c: Attempting identify by From username '20000' domain '172.16.66.1'
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_endpoint_identifier_user.c: Identified by From username '20000' domain '172.16.66.1'
[Dec 19 22:32:22] DEBUG[11470] netsock2.c: Splitting '172.16.66.1' into...
[Dec 19 22:32:22] DEBUG[11470] netsock2.c: ...host '172.16.66.1' and port ''.
[Dec 19 22:32:22] DEBUG[11470] netsock2.c: Splitting '172.16.66.101' into...
[Dec 19 22:32:22] DEBUG[11470] netsock2.c: ...host '172.16.66.101' and port ''.
[Dec 19 22:32:22] DEBUG[2016] res_pjsip/pjsip_distributor.c: Could not find matching transaction for Request msg REGISTER/cseq=21 (rdata0x55b81e112e08)
[Dec 19 22:32:22] DEBUG[2016] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-00000053 to use for Request msg REGISTER/cseq=21 (rdata0x55b81e112e08)
[Dec 19 22:32:22] DEBUG[11470] netsock2.c: Splitting '172.16.66.101' into...
[Dec 19 22:32:22] DEBUG[11470] netsock2.c: ...host '172.16.66.101' and port ''.
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_endpoint_identifier_ip.c: No identify sections to match against
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_endpoint_identifier_user.c: Attempting identify by From username '20000' domain '172.16.66.1'
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_endpoint_identifier_user.c: Identified by From username '20000' domain '172.16.66.1'
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_authenticator_digest.c: Calculated nonce 1576794742/3b792ac5eb21f8443f95e040c1442850. Actual nonce is 1576794742/3b792ac5eb21f8443f95e040c1442850
[Dec 19 22:32:22] DEBUG[11470] netsock2.c: Splitting '172.16.66.1' into...
[Dec 19 22:32:22] DEBUG[11470] netsock2.c: ...host '172.16.66.1' and port ''.
[Dec 19 22:32:22] DEBUG[11470] netsock2.c: Splitting '172.16.66.101' into...
[Dec 19 22:32:22] DEBUG[11470] netsock2.c: ...host '172.16.66.101' and port ''.
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_registrar.c: Matched id '20000' to aor '20000'
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_registrar.c: Matched aor '20000' by To username
[Dec 19 22:32:22] DEBUG[11470] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0)
[Dec 19 22:32:22] DEBUG[11470] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[Dec 19 22:32:22] VERBOSE[11470] res_pjsip_registrar.c: Added contact 'sip:20000@172.16.66.101:50600' to AOR '20000' with expiration of 3600 seconds
[Dec 19 22:32:22] DEBUG[1990] threadpool.c: Increasing threadpool sorcery/pool's size by 1
[Dec 19 22:32:22] DEBUG[11472] res_pjsip/pjsip_options.c: Starting scheduled callback on AOR '20000' for qualifying as there is now a contact on it
[Dec 19 22:32:22] DEBUG[11470] res_pjsip/pjsip_options.c: Qualifying all contacts on AOR '20000'
[Dec 19 22:32:22] DEBUG[11470] res_pjsip/pjsip_options.c: Qualifying contact '20000;@4e1e7e83e8388f68dd24f30d1210da28' on AOR '20000'
[Dec 19 22:32:22] DEBUG[11470] res_pjsip.c: 0x7f3690007b90: Wrapper created
[Dec 19 22:32:22] DEBUG[11470] res_pjsip.c: 0x7f3690007b90: Set timer to 3000 msec
[Dec 19 22:32:22] DEBUG[11470] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target '172.16.66.101'
[Dec 19 22:32:22] DEBUG[11470] res_pjsip/pjsip_resolver.c: Transport type for target '172.16.66.101' is 'UDP'
[Dec 19 22:32:22] DEBUG[11470] res_pjsip/pjsip_resolver.c: Target '172.16.66.101' is an IP address, skipping resolution
[Dec 19 22:32:22] DEBUG[11470] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 172.16.66.1:5060 (this may be re-written again later)
[Dec 19 22:32:22] DEBUG[2016] res_pjsip/pjsip_distributor.c: No dialog serializer for Response msg 200/OPTIONS/cseq=37020 (rdata0x55b81e112e08).  Using request transaction as basis.
[Dec 19 22:32:22] DEBUG[2016] res_pjsip/pjsip_distributor.c: Found transaction tsx0x7f3654022b18 for Response msg 200/OPTIONS/cseq=37020 (rdata0x55b81e112e08).
[Dec 19 22:32:22] DEBUG[2016] res_pjsip/pjsip_distributor.c: Found serializer pjsip/options/20000-00000079 on transaction tsx0x7f3654022b18
[Dec 19 22:32:22] DEBUG[11470] res_pjsip.c: 0x7f3690007b90: PJSIP tsx response received
[Dec 19 22:32:22] DEBUG[11470] res_pjsip.c: 0x7f3690007b90: Cancelling timer
[Dec 19 22:32:22] DEBUG[11470] res_pjsip.c: 0x7f3690007b90: Timer cancelled
[Dec 19 22:32:22] DEBUG[11470] res_pjsip.c: 0x7f3690007b90: Callbacks executed
[Dec 19 22:32:22] DEBUG[11470] res_pjsip.c: 0x7f3690007b90: wrapper destroyed
[Dec 19 22:32:22] DEBUG[11470] res_pjsip/pjsip_options.c: Endpoint state compositor '20000' is online as AOR '20000' is available
[Dec 19 22:32:22] DEBUG[11470] pbx.c: Added extension '20000' priority 1 to registered (0x7f369809b1c0)
[Dec 19 22:32:22] VERBOSE[11470] res_pjsip/pjsip_configuration.c: Endpoint 20000 is now Reachable
[Dec 19 22:32:22] VERBOSE[11470] res_pjsip/pjsip_options.c: Contact 20000/sip:20000@172.16.66.101:50600 is now Reachable.  RTT: 48.909 msec
[Dec 19 22:32:22] DEBUG[11470] res_pjsip/pjsip_options.c: AOR '20000' now has 1 available contacts
[Dec 19 22:32:22] DEBUG[1999] devicestate.c: No provider found, checking channel drivers for PJSIP - 20000
[Dec 19 22:32:22] DEBUG[1999] devicestate.c: Changing state for PJSIP/20000 - state 1 (Not in use)
[Dec 19 22:32:22] DEBUG[2062] app_queue.c: Device 'PJSIP/20000' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
[Dec 19 22:32:22] DEBUG[11470] res_pjsip/pjsip_options.c: Qualifying all contacts on AOR '20000'
[Dec 19 22:32:22] DEBUG[11470] res_pjsip/pjsip_options.c: Qualifying contact '20000;@4e1e7e83e8388f68dd24f30d1210da28' on AOR '20000'
[Dec 19 22:32:22] DEBUG[11470] res_pjsip.c: 0x7f3690019c10: Wrapper created
[Dec 19 22:32:22] DEBUG[11470] res_pjsip.c: 0x7f3690019c10: Set timer to 3000 msec
[Dec 19 22:32:22] DEBUG[11470] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target '172.16.66.101'
[Dec 19 22:32:22] DEBUG[11470] res_pjsip/pjsip_resolver.c: Transport type for target '172.16.66.101' is 'UDP'
[Dec 19 22:32:22] DEBUG[11470] res_pjsip/pjsip_resolver.c: Target '172.16.66.101' is an IP address, skipping resolution
[Dec 19 22:32:22] DEBUG[11470] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 172.16.66.1:5060 (this may be re-written again later)
[Dec 19 22:32:22] DEBUG[11481] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0)
[Dec 19 22:32:22] DEBUG[11481] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[Dec 19 22:32:22] DEBUG[11481] config.c: extract double from [3.000000] in [-inf, inf] gives [3.000000](0)
[Dec 19 22:32:22] DEBUG[11481] config.c: extract uint from [50600] in [0, 4294967295] gives [50600](0)
[Dec 19 22:32:22] DEBUG[11481] config.c: extract uint from [30] in [0, 86400] gives [30](0)
[Dec 19 22:32:22] DEBUG[11470] res_pjsip/pjsip_options.c: Qualifying all contacts on AOR '20000'
[Dec 19 22:32:22] DEBUG[11470] res_pjsip/pjsip_options.c: Qualifying contact '20000;@4e1e7e83e8388f68dd24f30d1210da28' on AOR '20000'
[Dec 19 22:32:22] DEBUG[11470] res_pjsip.c: 0x7f36900171a0: Wrapper created
[Dec 19 22:32:22] DEBUG[11470] res_pjsip.c: 0x7f36900171a0: Set timer to 3000 msec
[Dec 19 22:32:22] DEBUG[11470] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target '172.16.66.101'
[Dec 19 22:32:22] DEBUG[11470] res_pjsip/pjsip_resolver.c: Transport type for target '172.16.66.101' is 'UDP'
[Dec 19 22:32:22] DEBUG[11470] res_pjsip/pjsip_resolver.c: Target '172.16.66.101' is an IP address, skipping resolution
[Dec 19 22:32:22] DEBUG[11470] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 172.16.66.1:5060 (this may be re-written again later)
[Dec 19 22:32:22] DEBUG[11483] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0)
[Dec 19 22:32:22] DEBUG[11483] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[Dec 19 22:32:22] DEBUG[11483] config.c: extract double from [3.000000] in [-inf, inf] gives [3.000000](0)
[Dec 19 22:32:22] DEBUG[11483] config.c: extract uint from [50600] in [0, 4294967295] gives [50600](0)
[Dec 19 22:32:22] DEBUG[11483] config.c: extract uint from [30] in [0, 86400] gives [30](0)
[Dec 19 22:32:22] DEBUG[2016] res_pjsip/pjsip_distributor.c: No dialog serializer for Response msg 200/OPTIONS/cseq=44688 (rdata0x55b81e112e08).  Using request transaction as basis.
[Dec 19 22:32:22] DEBUG[2016] res_pjsip/pjsip_distributor.c: Found transaction tsx0x7f3678014d38 for Response msg 200/OPTIONS/cseq=44688 (rdata0x55b81e112e08).
[Dec 19 22:32:22] DEBUG[2016] res_pjsip/pjsip_distributor.c: Found serializer pjsip/options/20000-00000079 on transaction tsx0x7f3678014d38
[Dec 19 22:32:22] DEBUG[11470] res_pjsip.c: 0x7f3690019c10: PJSIP tsx response received
[Dec 19 22:32:22] DEBUG[11470] res_pjsip.c: 0x7f3690019c10: Cancelling timer
[Dec 19 22:32:22] DEBUG[11470] res_pjsip.c: 0x7f3690019c10: Timer cancelled
[Dec 19 22:32:22] DEBUG[11470] res_pjsip.c: 0x7f3690019c10: Callbacks executed
[Dec 19 22:32:22] DEBUG[11470] res_pjsip.c: 0x7f3690019c10: wrapper destroyed
[Dec 19 22:32:22] DEBUG[11470] res_pjsip/pjsip_options.c: Contact 20000/sip:20000@172.16.66.101:50600 status didn't change: Reachable, RTT: 26.498 msec
[Dec 19 22:32:22] DEBUG[11470] res_pjsip/pjsip_options.c: AOR '20000' now has 1 available contacts
[Dec 19 22:32:22] DEBUG[2016] res_pjsip/pjsip_distributor.c: No dialog serializer for Response msg 200/OPTIONS/cseq=16468 (rdata0x55b81e112e08).  Using request transaction as basis.
[Dec 19 22:32:22] DEBUG[2016] res_pjsip/pjsip_distributor.c: Found transaction tsx0x7f3650006518 for Response msg 200/OPTIONS/cseq=16468 (rdata0x55b81e112e08).
[Dec 19 22:32:22] DEBUG[2016] res_pjsip/pjsip_distributor.c: Found serializer pjsip/options/20000-00000079 on transaction tsx0x7f3650006518
[Dec 19 22:32:22] DEBUG[11470] res_pjsip.c: 0x7f36900171a0: PJSIP tsx response received
[Dec 19 22:32:22] DEBUG[11470] res_pjsip.c: 0x7f36900171a0: Cancelling timer
[Dec 19 22:32:22] DEBUG[11470] res_pjsip.c: 0x7f36900171a0: Timer cancelled
[Dec 19 22:32:22] DEBUG[11470] res_pjsip.c: 0x7f36900171a0: Callbacks executed
[Dec 19 22:32:22] DEBUG[11470] res_pjsip.c: 0x7f36900171a0: wrapper destroyed
[Dec 19 22:32:22] DEBUG[11470] res_pjsip/pjsip_options.c: Contact 20000/sip:20000@172.16.66.101:50600 status didn't change: Reachable, RTT: 34.546 msec
[Dec 19 22:32:22] DEBUG[11470] res_pjsip/pjsip_options.c: AOR '20000' now has 1 available contacts
[Dec 19 22:32:22] DEBUG[2056] pbx_dundi.c: Got canonical message 1 (0), 32 bytes data
[Dec 19 22:32:22] DEBUG[2056] pbx_dundi.c: Answering query for '20000@dundi'!
[Dec 19 22:32:22] DEBUG[11484] pbx_dundi.c: Whee, looking up '20000@dundi' for '11:11:11:00:0c:01'
[Dec 19 22:32:22] DEBUG[11484] pbx_variables.c: Result of 'SECRET' is 'MNaYA50s32UvNiIxuyTwWA=='
[Dec 19 22:32:22] DEBUG[11484] pbx_variables.c: Result of 'NUMBER' is '20000'
[Dec 19 22:32:22] DEBUG[2043] chan_iax2.c: ip callno count incremented to 3 for fc00:1234:c::1
[Dec 19 22:32:22] DEBUG[2043] chan_iax2.c: No channel, so populating IAXVARs to the pvt, as an intermediate step.
[Dec 19 22:32:22] VERBOSE[2035] chan_iax2.c: Accepting AUTHENTICATED call from [fc00:1234:c::1]:4569:
       > requested format = alaw,
       > requested prefs = (alaw|speex),
       > actual format = speex,
       > host prefs = (speex),
       > priority = mine
[Dec 19 22:32:22] DEBUG[2035] stasis.c: Creating topic. name: channel:1576794742.51, detail: 
[Dec 19 22:32:22] DEBUG[2035] stasis.c: Topic 'channel:1576794742.51': 0x7f36900199c0 created
[Dec 19 22:32:22] DEBUG[2035] stasis.c: Creating topic. name: cache:132/channel:1576794742.51, detail: 
[Dec 19 22:32:22] DEBUG[2035] stasis.c: Topic 'cache:132/channel:1576794742.51': 0x7f3690019a80 created
[Dec 19 22:32:22] DEBUG[2035] channel.c: Channel 0x7f3690023c90 'IAX2/audio-8052' allocated
[Dec 19 22:32:22] DEBUG[2035] chan_iax2.c: Loading up the channel with IAXVARs
[Dec 19 22:32:22] DEBUG[11485][C-00000012] pbx.c: Launching 'NoOp'
[Dec 19 22:32:22] VERBOSE[11485][C-00000012] pbx.c: Executing [20000@internal:1] NoOp("IAX2/audio-8052", "internal context") in new stack
[Dec 19 22:32:22] DEBUG[11485][C-00000012] pbx.c: Launching 'NoOp'
[Dec 19 22:32:22] VERBOSE[11485][C-00000012] pbx.c: Executing [20000@internal:2] NoOp("IAX2/audio-8052", "Hit the PJSIP context from registered") in new stack
[Dec 19 22:32:22] DEBUG[11485][C-00000012] pbx.c: Launching 'Dial'
[Dec 19 22:32:22] VERBOSE[11485][C-00000012] pbx.c: Executing [20000@internal:3] Dial("IAX2/audio-8052", "PJSIP/20000,60") in new stack
[Dec 19 22:32:22] DEBUG[11470] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0)
[Dec 19 22:32:22] DEBUG[11470] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[Dec 19 22:32:22] DEBUG[11470] config.c: extract double from [3.000000] in [-inf, inf] gives [3.000000](0)
[Dec 19 22:32:22] DEBUG[11470] config.c: extract uint from [50600] in [0, 4294967295] gives [50600](0)
[Dec 19 22:32:22] DEBUG[11470] config.c: extract uint from [30] in [0, 86400] gives [30](0)
[Dec 19 22:32:22] DEBUG[11485][C-00000012] stasis.c: Creating topic. name: channel:1576794742.52, detail: 
[Dec 19 22:32:22] DEBUG[11485][C-00000012] stasis.c: Topic 'channel:1576794742.52': 0x7f36bc008e20 created
[Dec 19 22:32:22] DEBUG[11485][C-00000012] stasis.c: Creating topic. name: cache:133/channel:1576794742.52, detail: 
[Dec 19 22:32:22] DEBUG[11485][C-00000012] stasis.c: Topic 'cache:133/channel:1576794742.52': 0x7f36bc008280 created
[Dec 19 22:32:22] DEBUG[11485][C-00000012] channel.c: Channel 0x7f36bc0ddf50 'PJSIP/20000-00000011' allocated
[Dec 19 22:32:22] DEBUG[11485][C-00000012] rtp_engine.c: Can't find native functions for channel 'IAX2/audio-8052'
[Dec 19 22:32:22] VERBOSE[11485][C-00000012] app_dial.c: Called PJSIP/20000
[Dec 19 22:32:22] DEBUG[11485][C-00000012] channel.c: Channel PJSIP/20000-00000011 setting read format path: speex -> speex
[Dec 19 22:32:22] DEBUG[11485][C-00000012] channel.c: Channel IAX2/audio-8052 setting write format path: speex -> speex
[Dec 19 22:32:22] DEBUG[11470] netsock2.c: Splitting '172.16.66.1' into...
[Dec 19 22:32:22] DEBUG[11470] netsock2.c: ...host '172.16.66.1' and port ''.
[Dec 19 22:32:22] DEBUG[11485][C-00000012] channel.c: Channel IAX2/audio-8052 setting read format path: speex -> speex
[Dec 19 22:32:22] DEBUG[11485][C-00000012] channel.c: Channel PJSIP/20000-00000011 setting write format path: speex -> speex
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_sdp_rtp.c: Transport transport-udp bound to 172.16.66.1: Using it for RTP media.
[Dec 19 22:32:22] DEBUG[11470] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x7f369002a0e0'
[Dec 19 22:32:22] DEBUG[11485][C-00000012] chan_iax2.c: Callno 8052: Config blocked sending control frame 22.
[Dec 19 22:32:22] DEBUG[11470] res_rtp_asterisk.c: Allocated port 15228 for RTP instance '0x7f369002a0e0'
[Dec 19 22:32:22] DEBUG[11470] res_rtp_asterisk.c: Creating ICE session 172.16.66.1:15228 (15228) for RTP instance '0x7f369002a0e0'
[Dec 19 22:32:22] DEBUG[11470] netsock2.c: Splitting '192.168.122.1' into...
[Dec 19 22:32:22] DEBUG[11470] netsock2.c: ...host '192.168.122.1' and port ''.
[Dec 19 22:32:22] DEBUG[11470] netsock2.c: Splitting '192.168.122.1' into...
[Dec 19 22:32:22] DEBUG[11470] netsock2.c: ...host '192.168.122.1' and port ''.
[Dec 19 22:32:22] DEBUG[11470] netsock2.c: Splitting '172.16.66.1' into...
[Dec 19 22:32:22] DEBUG[11470] netsock2.c: ...host '172.16.66.1' and port ''.
[Dec 19 22:32:22] DEBUG[11470] netsock2.c: Splitting '172.16.66.1' into...
[Dec 19 22:32:22] DEBUG[11470] netsock2.c: ...host '172.16.66.1' and port ''.
[Dec 19 22:32:22] DEBUG[11470] rtp_engine.c: RTP instance '0x7f369002a0e0' is setup and ready to go
[Dec 19 22:32:22] VERBOSE[11470] netsock2.c: Using SIP RTP Audio TOS bits 184
[Dec 19 22:32:22] DEBUG[11470] acl.c: Attached to given IP address
[Dec 19 22:32:22] DEBUG[11470] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x7f369002a0e0'
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: Method is INVITE
[Dec 19 22:32:22] DEBUG[11485][C-00000012] channel.c: IAX2/audio-8052: Dropping redundant connected line update "" <20000>.
[Dec 19 22:32:22] DEBUG[11470] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target '172.16.66.101'
[Dec 19 22:32:22] DEBUG[11470] res_pjsip/pjsip_resolver.c: Transport type for target '172.16.66.101' is 'UDP'
[Dec 19 22:32:22] DEBUG[11470] res_pjsip/pjsip_resolver.c: Target '172.16.66.101' is an IP address, skipping resolution
[Dec 19 22:32:22] DEBUG[11470] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 172.16.66.1:5060 (this may be re-written again later)
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: The state change pertains to the endpoint '20000(PJSIP/20000-00000011)'
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f365400cf48)
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: There is no transaction involved in this state change
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: The current inv state is CALLING
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: Source of transaction state change is TX_MSG
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: The state change pertains to the endpoint '20000(PJSIP/20000-00000011)'
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f365400cf48)
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7f365400cf48
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: The current transaction state is Calling
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: The transaction state change event is TX_MSG
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: The current inv state is CALLING
[Dec 19 22:32:22] DEBUG[2016] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7f3654016418 for Response msg 100/INVITE/cseq=20774 (rdata0x55b81e112e08)
[Dec 19 22:32:22] DEBUG[2016] res_pjsip/pjsip_distributor.c: Found serializer pjsip/outsess/20000-000000d8 associated with dialog dlg0x7f3654016418
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: The state change pertains to the endpoint '20000(PJSIP/20000-00000011)'
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f365400cf48)
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7f365400cf48
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: The current transaction state is Proceeding
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: The transaction state change event is RX_MSG
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: The current inv state is CALLING
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: Received response
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: Response is 100 Trying
[Dec 19 22:32:22] DEBUG[2016] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7f3654016418 for Response msg 180/INVITE/cseq=20774 (rdata0x55b81e112e08)
[Dec 19 22:32:22] DEBUG[2016] res_pjsip/pjsip_distributor.c: Found serializer pjsip/outsess/20000-000000d8 associated with dialog dlg0x7f3654016418
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: The state change pertains to the endpoint '20000(PJSIP/20000-00000011)'
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f365400cf48)
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: There is no transaction involved in this state change
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: The current inv state is EARLY
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: Source of transaction state change is RX_MSG
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: Received response
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: Response is 180 Ringing
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: The state change pertains to the endpoint '20000(PJSIP/20000-00000011)'
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f365400cf48)
[Dec 19 22:32:22] DEBUG[1999] devicestate.c: No provider found, checking channel drivers for PJSIP - 20000
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7f365400cf48
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: The current transaction state is Proceeding
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: The transaction state change event is RX_MSG
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: The current inv state is EARLY
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: Received response
[Dec 19 22:32:22] DEBUG[1999] devicestate.c: Changing state for PJSIP/20000 - state 6 (Ringing)
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: Response is 180 Ringing
[Dec 19 22:32:22] DEBUG[2062] app_queue.c: Device 'PJSIP/20000' changed to state '6' (Ringing) but we don't care because they're not a member of any queue.
[Dec 19 22:32:22] VERBOSE[11485][C-00000012] app_dial.c: PJSIP/20000-00000011 is ringing
[Dec 19 22:32:22] VERBOSE[11485][C-00000012] app_dial.c: PJSIP/20000-00000011 is ringing
[Dec 19 22:32:22] DEBUG[2016] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7f3654016418 for Response msg 200/INVITE/cseq=20774 (rdata0x55b81e112e08)
[Dec 19 22:32:22] DEBUG[2016] res_pjsip/pjsip_distributor.c: Found serializer pjsip/outsess/20000-000000d8 associated with dialog dlg0x7f3654016418
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: The state change pertains to the endpoint '20000(PJSIP/20000-00000011)'
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f365400cf48)
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: There is no transaction involved in this state change
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: The current inv state is CONNECTING
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: Source of transaction state change is RX_MSG
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: Received response
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: Response is 200 Ok
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: Applying negotiated SDP media stream 'audio' using audio SDP handler
[Dec 19 22:32:22] DEBUG[11470] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x7f369002a0e0'
[Dec 19 22:32:22] DEBUG[11470] netsock2.c: Splitting '172.16.66.101' into...
[Dec 19 22:32:22] DEBUG[11470] netsock2.c: ...host '172.16.66.101' and port ''.
[Dec 19 22:32:22] VERBOSE[11485][C-00000012] app_dial.c: PJSIP/20000-00000011 answered IAX2/audio-8052
[Dec 19 22:32:22] DEBUG[1999] devicestate.c: No provider found, checking channel drivers for PJSIP - 20000
[Dec 19 22:32:22] DEBUG[11470] acl.c: For destination '172.16.66.101', our source address is '172.16.66.1'.
[Dec 19 22:32:22] DEBUG[1999] devicestate.c: Changing state for PJSIP/20000 - state 2 (In use)
[Dec 19 22:32:22] DEBUG[11470] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7f369002a0e0'
[Dec 19 22:32:22] VERBOSE[11470] res_rtp_asterisk.c: 0x7f369002b060 -- Strict RTP learning after remote address set to: 172.16.66.101:60600
[Dec 19 22:32:22] DEBUG[11470] rtp_engine.c: Setting tx payload type 8 based on m type on 0x7f36d115ced0
[Dec 19 22:32:22] DEBUG[11470] rtp_engine.c: Copying tx payload mapping 8 (0x55b81ddf97b8) from 0x7f36d115ced0 to 0x7f369002a2b8
[Dec 19 22:32:22] DEBUG[11470] rtp_engine.c: Copying tx payload mapping 101 (0x7f369002f878) from 0x7f36d115ced0 to 0x7f369002a2b8
[Dec 19 22:32:22] DEBUG[11485][C-00000012] channel.c: Channel PJSIP/20000-00000011 setting read format path: speex -> speex
[Dec 19 22:32:22] DEBUG[11485][C-00000012] channel.c: Channel IAX2/audio-8052 setting write format path: speex -> speex
[Dec 19 22:32:22] DEBUG[11470] codec_speex.c: Default quality (vbr): 4
[Dec 19 22:32:22] DEBUG[11470] channel.c: Channel PJSIP/20000-00000011 setting read format path: alaw -> speex
[Dec 19 22:32:22] DEBUG[2062] app_queue.c: Device 'PJSIP/20000' changed to state '2' (In use) but we don't care because they're not a member of any queue.
[Dec 19 22:32:22] DEBUG[11470] channel.c: Channel PJSIP/20000-00000011 setting write format path: speex -> alaw
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: Applied negotiated SDP media stream 'audio' using audio SDP handler
[Dec 19 22:32:22] DEBUG[11485][C-00000012] channel.c: Channel IAX2/audio-8052 setting read format path: speex -> slin
[Dec 19 22:32:22] DEBUG[11485][C-00000012] channel.c: Channel PJSIP/20000-00000011 setting write format path: slin -> alaw
[Dec 19 22:32:22] DEBUG[11470] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target '172.16.66.101'
[Dec 19 22:32:22] DEBUG[11470] res_pjsip/pjsip_resolver.c: Transport type for target '172.16.66.101' is 'UDP'
[Dec 19 22:32:22] DEBUG[11485][C-00000012] chan_iax2.c: Answering IAX2 call
[Dec 19 22:32:22] DEBUG[11470] res_pjsip/pjsip_resolver.c: Target '172.16.66.101' is an IP address, skipping resolution
[Dec 19 22:32:22] DEBUG[1999] devicestate.c: No provider found, checking channel drivers for IAX2 - audio
[Dec 19 22:32:22] DEBUG[1999] chan_iax2.c: Checking device state for device audio
[Dec 19 22:32:22] DEBUG[1999] chan_iax2.c: Found peer. What's device state of audio? addr=0.0.0.0:4569, defaddr=(null) maxms=0, lastms=0
[Dec 19 22:32:22] DEBUG[1999] devicestate.c: Changing state for IAX2/audio - state 2 (In use)
[Dec 19 22:32:22] DEBUG[11485][C-00000012] stasis.c: Creating topic. name: bridge:8afb86a1-929d-4023-92ac-dc03d6b7a5cd, detail: 
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: Function session_inv_on_state_changed called on event TX_MSG
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: The state change pertains to the endpoint '20000(PJSIP/20000-00000011)'
[Dec 19 22:32:22] DEBUG[11485][C-00000012] stasis.c: Topic 'bridge:8afb86a1-929d-4023-92ac-dc03d6b7a5cd': 0x7f36bc00d8d0 created
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f365400cf48)
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: There is no transaction involved in this state change
[Dec 19 22:32:22] DEBUG[11485][C-00000012] stasis.c: Creating topic. name: cache:134/bridge:8afb86a1-929d-4023-92ac-dc03d6b7a5cd, detail: 
[Dec 19 22:32:22] DEBUG[2062] app_queue.c: Device 'IAX2/audio' changed to state '2' (In use) but we don't care because they're not a member of any queue.
[Dec 19 22:32:22] DEBUG[11485][C-00000012] stasis.c: Topic 'cache:134/bridge:8afb86a1-929d-4023-92ac-dc03d6b7a5cd': 0x7f36bc0817d0 created
[Dec 19 22:32:22] DEBUG[11485][C-00000012] bridge_native_rtp.c: Bridge '8afb86a1-929d-4023-92ac-dc03d6b7a5cd' can not use native RTP bridge as two channels are required
[Dec 19 22:32:22] DEBUG[11485][C-00000012] bridge.c: Bridge technology native_rtp is not compatible with properties of existing bridge.
[Dec 19 22:32:22] DEBUG[11485][C-00000012] dahdi/bridge_native_dahdi.c: Bridge 8afb86a1-929d-4023-92ac-dc03d6b7a5cd: Cannot use native DAHDI.  Must have two channels.
[Dec 19 22:32:22] DEBUG[11485][C-00000012] bridge.c: Bridge technology native_dahdi is not compatible with properties of existing bridge.
[Dec 19 22:32:22] DEBUG[11485][C-00000012] bridge.c: Bridge technology holding_bridge does not have any capabilities we want.
[Dec 19 22:32:22] DEBUG[11485][C-00000012] bridge.c: Bridge technology softmix has less preference than simple_bridge (10 <= 50). Skipping.
[Dec 19 22:32:22] DEBUG[11485][C-00000012] bridge.c: Chose bridge technology simple_bridge
[Dec 19 22:32:22] DEBUG[11485][C-00000012] bridge.c: Bridge 8afb86a1-929d-4023-92ac-dc03d6b7a5cd: calling simple_bridge technology constructor
[Dec 19 22:32:22] DEBUG[11485][C-00000012] bridge.c: Bridge 8afb86a1-929d-4023-92ac-dc03d6b7a5cd: calling simple_bridge technology start
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: The current inv state is CONFIRMED
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: The state change pertains to the endpoint '20000(PJSIP/20000-00000011)'
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: The inv session does NOT have an invite_tsx
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x7f365400cf48
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: The current transaction state is Terminated
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: The transaction state change event is RX_MSG
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: The current inv state is CONFIRMED
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: Received response
[Dec 19 22:32:22] DEBUG[11470] res_pjsip_session.c: Response is 200 Ok
[Dec 19 22:32:22] DEBUG[11486][C-00000012] bridge_channel.c: Bridge 8afb86a1-929d-4023-92ac-dc03d6b7a5cd: 0x7f36bc00d040(PJSIP/20000-00000011) is joining
[Dec 19 22:32:22] DEBUG[11486][C-00000012] bridge_channel.c: Bridge 8afb86a1-929d-4023-92ac-dc03d6b7a5cd: pushing 0x7f36bc00d040(PJSIP/20000-00000011)
[Dec 19 22:32:22] VERBOSE[11486][C-00000012] bridge_channel.c: Channel PJSIP/20000-00000011 joined 'simple_bridge' basic-bridge <8afb86a1-929d-4023-92ac-dc03d6b7a5cd>
[Dec 19 22:32:22] DEBUG[11486][C-00000012] bridge_native_rtp.c: Bridge '8afb86a1-929d-4023-92ac-dc03d6b7a5cd' can not use native RTP bridge as two channels are required
[Dec 19 22:32:22] DEBUG[11486][C-00000012] bridge.c: Bridge technology native_rtp is not compatible with properties of existing bridge.
[Dec 19 22:32:22] DEBUG[11486][C-00000012] dahdi/bridge_native_dahdi.c: Bridge 8afb86a1-929d-4023-92ac-dc03d6b7a5cd: Cannot use native DAHDI.  Must have two channels.
[Dec 19 22:32:22] DEBUG[11486][C-00000012] bridge.c: Bridge technology native_dahdi is not compatible with properties of existing bridge.
[Dec 19 22:32:22] DEBUG[11486][C-00000012] bridge.c: Bridge technology holding_bridge does not have any capabilities we want.
[Dec 19 22:32:22] DEBUG[11486][C-00000012] bridge.c: Bridge technology softmix does not have any capabilities we want.
[Dec 19 22:32:22] DEBUG[11486][C-00000012] bridge.c: Chose bridge technology simple_bridge
[Dec 19 22:32:22] DEBUG[11486][C-00000012] bridge.c: Bridge 8afb86a1-929d-4023-92ac-dc03d6b7a5cd is already using the new technology.
[Dec 19 22:32:22] DEBUG[11486][C-00000012] bridge.c: Bridge 8afb86a1-929d-4023-92ac-dc03d6b7a5cd: 0x7f36bc00d040(PJSIP/20000-00000011) is joining simple_bridge technology
[Dec 19 22:32:22] DEBUG[11485][C-00000012] bridge_channel.c: Bridge 8afb86a1-929d-4023-92ac-dc03d6b7a5cd: 0x7f36bc00d220(IAX2/audio-8052) is joining
[Dec 19 22:32:22] DEBUG[11486][C-00000012] channel.c: Dropping duplicate answer!
[Dec 19 22:32:22] DEBUG[11485][C-00000012] bridge_channel.c: Bridge 8afb86a1-929d-4023-92ac-dc03d6b7a5cd: pushing 0x7f36bc00d220(IAX2/audio-8052)
[Dec 19 22:32:22] VERBOSE[11485][C-00000012] bridge_channel.c: Channel IAX2/audio-8052 joined 'simple_bridge' basic-bridge <8afb86a1-929d-4023-92ac-dc03d6b7a5cd>
[Dec 19 22:32:22] DEBUG[11485][C-00000012] bridge_native_rtp.c: Bridge '8afb86a1-929d-4023-92ac-dc03d6b7a5cd'.  Checking compatability for channels 'PJSIP/20000-00000011' and 'IAX2/audio-8052'
[Dec 19 22:32:22] DEBUG[2007] cdr.c: Finalized CDR for PJSIP/20000-00000011 - start 1576794742.573356 answer 1576794742.612264 end 1576794742.614507 dispo ANSWERED
[Dec 19 22:32:22] DEBUG[11485][C-00000012] bridge_native_rtp.c: Bridge '8afb86a1-929d-4023-92ac-dc03d6b7a5cd' can not use native RTP bridge as could not get details
[Dec 19 22:32:22] DEBUG[11485][C-00000012] bridge.c: Bridge technology native_rtp is not compatible with properties of existing bridge.
[Dec 19 22:32:22] DEBUG[11485][C-00000012] dahdi/bridge_native_dahdi.c: Channel 'PJSIP/20000-00000011' is not DAHDI.
[Dec 19 22:32:22] DEBUG[11485][C-00000012] dahdi/bridge_native_dahdi.c: Bridge 8afb86a1-929d-4023-92ac-dc03d6b7a5cd: Cannot use native DAHDI.  Channel 'PJSIP/20000-00000011' not compatible.
[Dec 19 22:32:22] DEBUG[11485][C-00000012] bridge.c: Bridge technology native_dahdi is not compatible with properties of existing bridge.
[Dec 19 22:32:22] DEBUG[11485][C-00000012] bridge.c: Bridge technology holding_bridge does not have any capabilities we want.
[Dec 19 22:32:22] DEBUG[11485][C-00000012] bridge.c: Bridge technology softmix does not have any capabilities we want.
[Dec 19 22:32:22] DEBUG[11485][C-00000012] bridge.c: Chose bridge technology simple_bridge
[Dec 19 22:32:22] DEBUG[11485][C-00000012] bridge.c: Bridge 8afb86a1-929d-4023-92ac-dc03d6b7a5cd is already using the new technology.
[Dec 19 22:32:22] DEBUG[11485][C-00000012] bridge.c: Bridge 8afb86a1-929d-4023-92ac-dc03d6b7a5cd: 0x7f36bc00d220(IAX2/audio-8052) is joining simple_bridge technology
[Dec 19 22:32:22] DEBUG[11485][C-00000012] channel.c: Channel PJSIP/20000-00000011 setting read format path: alaw -> slin
[Dec 19 22:32:22] DEBUG[11485][C-00000012] codec_speex.c: Default quality (vbr): 4
[Dec 19 22:32:22] DEBUG[11485][C-00000012] channel.c: Channel IAX2/audio-8052 setting write format path: slin -> speex
[Dec 19 22:32:22] DEBUG[11485][C-00000012] chan_iax2.c: Callno 8052: Blocked sending control frame 26.
[Dec 19 22:32:22] VERBOSE[11486][C-00000012] res_rtp_asterisk.c: 0x7f369002b060 -- Strict RTP switching to RTP target address 172.16.66.101:60600 as source
[Dec 19 22:32:22] DEBUG[11485][C-00000012] chan_iax2.c: Created trunk peer for '[fc00:1234:c::1]:4569'
[Dec 19 22:32:22] DEBUG[11485][C-00000012] chan_iax2.c: Expanded trunk '[fc00:1234:c::1]:4569' to 6400 bytes
[Dec 19 22:32:22] DEBUG[2037] chan_iax2.c: Ooh, voice format changed to 'speex'
[Dec 19 22:32:22] VERBOSE[2041] chan_iax2.c: V[Dec 19 22:32:22] DEBUG[11486][C-00000012] res_rtp_asterisk.c: Ooh, format changed from none to alaw
[Dec 19 22:32:22] VERBOSE[2038] chan_iax2.c: G[Dec 19 22:32:22] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:22] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:22] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:22] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:22] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:22] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:22] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:22] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:22] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:22] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:22] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:22] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:23] VERBOSE[2036] chan_iax2.c: 
[Dec 19 22:32:23] VERBOSE[2036] chan_iax2.c: G[Dec 19 22:32:23] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:23] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:23] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:23] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:23] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:23] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:23] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:23] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:23] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:23] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:23] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:23] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:23] VERBOSE[2038] chan_iax2.c: G[Dec 19 22:32:23] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:23] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:23] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:23] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:23] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:23] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:23] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:23] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:23] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:23] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:23] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:23] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:23] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:23] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:23] VERBOSE[2043] chan_iax2.c: G[Dec 19 22:32:23] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:23] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:23] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:23] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:23] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:23] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:23] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:23] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:23] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:23] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:23] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:23] VERBOSE[2034] chan_iax2.c: G[Dec 19 22:32:23] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:23] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:23] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:23] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:23] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:23] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:23] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:23] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:23] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:24] VERBOSE[2034] chan_iax2.c: 
[Dec 19 22:32:24] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:24] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:24] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:24] VERBOSE[2042] chan_iax2.c: G[Dec 19 22:32:24] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:24] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:24] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:24] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:24] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:24] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:24] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:24] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:24] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:24] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:24] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:24] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:24] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:24] VERBOSE[2040] chan_iax2.c: G[Dec 19 22:32:24] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:24] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:24] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:24] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:24] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:24] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:24] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:24] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:24] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:24] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:24] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:24] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:24] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:24] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:24] VERBOSE[2043] chan_iax2.c: G[Dec 19 22:32:24] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:24] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:24] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:24] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:24] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:24] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:24] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:24] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:24] DEBUG[11486][C-00000012] res_rtp_asterisk.c: Got RTCP report of 104 bytes from 172.16.66.101:60601
[Dec 19 22:32:24] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:24] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:24] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:24] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:24] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:24] VERBOSE[2037] chan_iax2.c: G[Dec 19 22:32:24] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:24] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:24] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:25] VERBOSE[2040] chan_iax2.c: 
[Dec 19 22:32:25] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:25] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:25] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:25] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:25] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:25] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:25] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:25] VERBOSE[2042] chan_iax2.c: G[Dec 19 22:32:25] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:25] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:25] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:25] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:25] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:25] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:25] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:25] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:25] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:25] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:25] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:25] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:25] VERBOSE[2034] chan_iax2.c: G[Dec 19 22:32:25] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:25] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:25] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:25] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:25] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:25] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:25] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:25] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:25] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:25] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:25] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:25] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:25] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:25] VERBOSE[2043] chan_iax2.c: G[Dec 19 22:32:25] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:25] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:25] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:25] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:25] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:25] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:25] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:25] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:25] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:25] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:25] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:25] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:25] VERBOSE[2040] chan_iax2.c: G[Dec 19 22:32:25] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:25] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:26] VERBOSE[2038] chan_iax2.c: 
[Dec 19 22:32:26] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:26] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:26] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:26] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:26] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:26] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:26] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:26] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:26] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:26] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:26] VERBOSE[2043] chan_iax2.c: G[Dec 19 22:32:26] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:26] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:26] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:26] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:26] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:26] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:26] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:26] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:26] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:26] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:26] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:26] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:26] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:26] VERBOSE[2037] chan_iax2.c: G[Dec 19 22:32:26] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:26] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:26] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:26] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:26] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:26] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:26] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:26] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:26] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:26] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:26] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:26] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:26] VERBOSE[2035] chan_iax2.c: G[Dec 19 22:32:26] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:26] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:26] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:26] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:26] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:26] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:26] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:26] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:26] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:26] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:26] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:26] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:27] VERBOSE[2037] chan_iax2.c: 
[Dec 19 22:32:27] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:27] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:27] VERBOSE[2041] chan_iax2.c: L[Dec 19 22:32:27] VERBOSE[2043] chan_iax2.c: L[Dec 19 22:32:27] VERBOSE[2038] chan_iax2.c: L[Dec 19 22:32:27] VERBOSE[2035] chan_iax2.c: L[Dec 19 22:32:27] VERBOSE[2034] chan_iax2.c: L[Dec 19 22:32:27] VERBOSE[2039] chan_iax2.c: L[Dec 19 22:32:27] VERBOSE[2040] chan_iax2.c: L[Dec 19 22:32:27] VERBOSE[2036] chan_iax2.c: L[Dec 19 22:32:27] VERBOSE[2037] chan_iax2.c: L[Dec 19 22:32:27] VERBOSE[2042] chan_iax2.c: L[Dec 19 22:32:27] VERBOSE[11486][C-00000012] res_rtp_asterisk.c: 0x7f369002b060 -- Strict RTP learning complete - Locking on source address 172.16.66.101:60600
[Dec 19 22:32:27] DEBUG[11486][C-00000012] res_rtp_asterisk.c: Got RTCP report of 104 bytes from 172.16.66.101:60601
[Dec 19 22:32:29] VERBOSE[2043] chan_iax2.c: V[Dec 19 22:32:29] VERBOSE[2038] chan_iax2.c: L[Dec 19 22:32:29] DEBUG[11486][C-00000012] res_rtp_asterisk.c: Difference is 14944, ms is 1888
[Dec 19 22:32:29] VERBOSE[2034] chan_iax2.c: l[Dec 19 22:32:29] VERBOSE[2040] chan_iax2.c: V[Dec 19 22:32:29] VERBOSE[2036] chan_iax2.c: L[Dec 19 22:32:29] DEBUG[11486][C-00000012] res_rtp_asterisk.c: Difference is 1104, ms is 158
[Dec 19 22:32:29] VERBOSE[2042] chan_iax2.c: l[Dec 19 22:32:29] VERBOSE[2043] chan_iax2.c: V[Dec 19 22:32:29] VERBOSE[2038] chan_iax2.c: L[Dec 19 22:32:29] DEBUG[11486][C-00000012] res_rtp_asterisk.c: Difference is 1048, ms is 151
[Dec 19 22:32:29] DEBUG[11611] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0)
[Dec 19 22:32:29] DEBUG[11611] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[Dec 19 22:32:29] DEBUG[11611] config.c: extract double from [3.000000] in [-inf, inf] gives [3.000000](0)
[Dec 19 22:32:29] DEBUG[11611] config.c: extract uint from [50600] in [0, 4294967295] gives [50600](0)
[Dec 19 22:32:29] DEBUG[11611] config.c: extract uint from [30] in [0, 86400] gives [30](0)
[Dec 19 22:32:29] VERBOSE[2034] chan_iax2.c: l[Dec 19 22:32:29] VERBOSE[2040] chan_iax2.c: V[Dec 19 22:32:29] DEBUG[11486][C-00000012] res_rtp_asterisk.c: Difference is 1040, ms is 150
[Dec 19 22:32:29] VERBOSE[2042] chan_iax2.c: G[Dec 19 22:32:29] VERBOSE[2036] chan_iax2.c: 
[Dec 19 22:32:29] VERBOSE[2036] chan_iax2.c: 
[Dec 19 22:32:29] VERBOSE[2036] chan_iax2.c: 
[Dec 19 22:32:29] VERBOSE[2036] chan_iax2.c: 
[Dec 19 22:32:29] VERBOSE[2036] chan_iax2.c: 
[Dec 19 22:32:29] VERBOSE[2036] chan_iax2.c: 
[Dec 19 22:32:29] VERBOSE[2036] chan_iax2.c: 
[Dec 19 22:32:29] VERBOSE[2037] chan_iax2.c: V[Dec 19 22:32:29] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:29] VERBOSE[2038] chan_iax2.c: G[Dec 19 22:32:29] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:29] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:29] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:29] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:29] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:29] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:29] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:29] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:29] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:29] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:29] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:29] VERBOSE[2039] chan_iax2.c: G[Dec 19 22:32:29] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:29] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:29] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:29] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:29] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:29] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:29] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:29] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:29] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:29] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:29] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:29] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:29] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:29] VERBOSE[2038] chan_iax2.c: G[Dec 19 22:32:29] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:29] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:29] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:29] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:29] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:29] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:30] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:30] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:30] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:30] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:30] VERBOSE[2035] chan_iax2.c: 
[Dec 19 22:32:30] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:30] VERBOSE[2039] chan_iax2.c: G[Dec 19 22:32:30] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:30] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:30] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:30] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:30] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:30] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:30] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:30] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:30] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:30] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:30] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:30] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:30] VERBOSE[2041] chan_iax2.c: G[Dec 19 22:32:30] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:30] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:30] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:30] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:30] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:30] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:30] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:30] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:30] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:30] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:30] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:30] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:30] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:30] VERBOSE[2036] chan_iax2.c: G[Dec 19 22:32:30] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:30] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:30] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:30] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:30] DEBUG[11486][C-00000012] res_rtp_asterisk.c: Got RTCP report of 104 bytes from 172.16.66.101:60601
[Dec 19 22:32:30] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:30] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:30] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:30] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:30] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:30] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:30] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:30] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:30] VERBOSE[2038] chan_iax2.c: G[Dec 19 22:32:30] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:30] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:30] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:30] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:31] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:31] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:31] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:31] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:31] VERBOSE[2042] chan_iax2.c: 
[Dec 19 22:32:31] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:31] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:31] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:31] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:31] VERBOSE[2036] chan_iax2.c: G[Dec 19 22:32:31] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:31] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:31] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:31] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:31] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:31] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:31] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:31] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:31] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:31] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:31] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:31] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:31] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:31] VERBOSE[2034] chan_iax2.c: G[Dec 19 22:32:31] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:31] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:31] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:31] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:31] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:31] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:31] DEBUG[11695] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0)
[Dec 19 22:32:31] DEBUG[11695] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[Dec 19 22:32:31] DEBUG[11695] config.c: extract double from [3.000000] in [-inf, inf] gives [3.000000](0)
[Dec 19 22:32:31] DEBUG[11695] config.c: extract uint from [50600] in [0, 4294967295] gives [50600](0)
[Dec 19 22:32:31] DEBUG[11695] config.c: extract uint from [30] in [0, 86400] gives [30](0)
[Dec 19 22:32:31] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:31] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:31] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:31] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:31] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:31] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:31] VERBOSE[2042] chan_iax2.c: G[Dec 19 22:32:31] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:31] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:31] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:31] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:31] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:31] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:31] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:31] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:31] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:31] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:31] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:31] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:31] VERBOSE[2034] chan_iax2.c: G[Dec 19 22:32:31] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:32] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:32] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:32] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:32] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:32] VERBOSE[2039] chan_iax2.c: 
[Dec 19 22:32:32] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:32] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:32] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:32] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:32] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:32] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:32] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:32] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:32] VERBOSE[2043] chan_iax2.c: G[Dec 19 22:32:32] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:32] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:32] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:32] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:32] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:32] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:32] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:32] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:32] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:32] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:32] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:32] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:32] VERBOSE[2040] chan_iax2.c: G[Dec 19 22:32:32] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:32] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:32] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:32] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:32] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:32] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:32] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:32] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:32] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:32] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:32] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:32] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:32] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:32] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:32] DEBUG[11486][C-00000012] res_rtp_asterisk.c: Got RTCP report of 84 bytes from 172.16.66.101:60601
[Dec 19 22:32:32] VERBOSE[2040] chan_iax2.c: G[Dec 19 22:32:32] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:32] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:32] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:32] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:32] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:32] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:32] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:32] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:32] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:33] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:33] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:33] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:33] VERBOSE[2043] chan_iax2.c: G[Dec 19 22:32:33] VERBOSE[2035] chan_iax2.c: 
[Dec 19 22:32:33] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:33] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:33] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:33] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:33] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:33] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:33] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:33] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:33] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:33] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:33] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:33] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:33] VERBOSE[2040] chan_iax2.c: G[Dec 19 22:32:33] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:33] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:33] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:33] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:33] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:33] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:33] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:33] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:33] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:33] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:33] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:33] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:33] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:33] VERBOSE[2039] chan_iax2.c: G[Dec 19 22:32:33] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:33] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:33] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:33] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:33] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:33] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:33] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:33] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:33] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:33] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:33] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:33] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:33] VERBOSE[2041] chan_iax2.c: G[Dec 19 22:32:33] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:33] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:33] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:33] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:33] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:33] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:34] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:34] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:34] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:34] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:34] VERBOSE[2043] chan_iax2.c: 
[Dec 19 22:32:34] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:34] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:34] VERBOSE[2039] chan_iax2.c: G[Dec 19 22:32:34] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:34] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:34] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:34] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:34] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:34] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:34] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:34] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:34] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:34] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:34] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:34] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:34] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:34] VERBOSE[2038] chan_iax2.c: G[Dec 19 22:32:34] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:34] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:34] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:34] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:34] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:34] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:34] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:34] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:34] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:34] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:34] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:34] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:34] VERBOSE[2037] chan_iax2.c: G[Dec 19 22:32:34] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:34] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:34] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:34] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:34] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:34] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:34] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:34] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:34] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:34] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:34] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:34] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:34] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:34] VERBOSE[2039] chan_iax2.c: G[Dec 19 22:32:34] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:34] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:35] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:35] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:35] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:35] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:35] VERBOSE[2041] chan_iax2.c: 
[Dec 19 22:32:35] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:35] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:35] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:35] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:35] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:35] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:35] VERBOSE[2042] chan_iax2.c: G[Dec 19 22:32:35] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:35] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:35] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:35] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:35] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:35] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:35] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:35] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:35] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:35] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:35] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:35] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:35] VERBOSE[2040] chan_iax2.c: G[Dec 19 22:32:35] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:35] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:35] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:35] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:35] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:35] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:35] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:35] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:35] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:35] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:35] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:35] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:35] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:35] VERBOSE[2035] chan_iax2.c: G[Dec 19 22:32:35] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:35] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:35] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:35] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:35] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:35] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:35] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:35] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:35] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:35] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:35] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:35] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:36] VERBOSE[2037] chan_iax2.c: G[Dec 19 22:32:36] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:36] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:36] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:36] VERBOSE[2036] chan_iax2.c: 
[Dec 19 22:32:36] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:36] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:36] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:36] DEBUG[11486][C-00000012] res_rtp_asterisk.c: Got RTCP report of 84 bytes from 172.16.66.101:60601
[Dec 19 22:32:36] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:36] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:36] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:36] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:36] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:36] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:36] VERBOSE[2035] chan_iax2.c: G[Dec 19 22:32:36] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:36] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:36] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:36] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:36] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:36] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:36] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:36] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:36] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:36] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:36] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:36] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:36] VERBOSE[2037] chan_iax2.c: G[Dec 19 22:32:36] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:36] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:36] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:36] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:36] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:36] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:36] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:36] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:36] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:36] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:36] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:36] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:36] VERBOSE[2035] chan_iax2.c: G[Dec 19 22:32:36] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:36] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:36] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:36] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:36] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:36] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:36] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:36] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:36] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:36] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:37] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:37] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:37] VERBOSE[2041] chan_iax2.c: G[Dec 19 22:32:37] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:37] VERBOSE[2038] chan_iax2.c: 
[Dec 19 22:32:37] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:37] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:37] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:37] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:37] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:37] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:37] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:37] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:37] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:37] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:37] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:37] VERBOSE[2035] chan_iax2.c: G[Dec 19 22:32:37] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:37] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:37] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:37] VERBOSE[2043] chan_iax2.c: v[Dec 19 22:32:37] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:37] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:37] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:37] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:37] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:37] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:37] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:37] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:37] VERBOSE[2041] chan_iax2.c: G[Dec 19 22:32:37] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:37] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:37] VERBOSE[2034] chan_iax2.c: v[Dec 19 22:32:37] VERBOSE[2040] chan_iax2.c: v[Dec 19 22:32:37] VERBOSE[2041] chan_iax2.c: v[Dec 19 22:32:37] VERBOSE[2042] chan_iax2.c: v[Dec 19 22:32:37] VERBOSE[2038] chan_iax2.c: v[Dec 19 22:32:37] VERBOSE[2035] chan_iax2.c: v[Dec 19 22:32:37] VERBOSE[2039] chan_iax2.c: v[Dec 19 22:32:37] VERBOSE[2036] chan_iax2.c: v[Dec 19 22:32:37] VERBOSE[2037] chan_iax2.c: v[Dec 19 22:32:37] VERBOSE[2042] chan_iax2.c: L[Dec 19 22:32:37] VERBOSE[2043] chan_iax2.c: L[Dec 19 22:32:37] VERBOSE[2038] chan_iax2.c: L[Dec 19 22:32:37] VERBOSE[2035] chan_iax2.c: L[Dec 19 22:32:37] VERBOSE[2034] chan_iax2.c: L[Dec 19 22:32:37] VERBOSE[2039] chan_iax2.c: L[Dec 19 22:32:37] VERBOSE[2040] chan_iax2.c: L[Dec 19 22:32:37] VERBOSE[2036] chan_iax2.c: L[Dec 19 22:32:37] VERBOSE[2041] chan_iax2.c: L[Dec 19 22:32:37] VERBOSE[2037] chan_iax2.c: L[Dec 19 22:32:38] DEBUG[2042] chan_iax2.c: Immediately destroying 8052, having received hangup
[Dec 19 22:32:38] DEBUG[11485][C-00000012] bridge_channel.c: Setting 0x7f36bc00d220(IAX2/audio-8052) state from:0 to:1
[Dec 19 22:32:38] DEBUG[11485][C-00000012] bridge_channel.c: Bridge 8afb86a1-929d-4023-92ac-dc03d6b7a5cd: pulling 0x7f36bc00d220(IAX2/audio-8052)
[Dec 19 22:32:38] VERBOSE[11485][C-00000012] bridge_channel.c: Channel IAX2/audio-8052 left 'simple_bridge' basic-bridge <8afb86a1-929d-4023-92ac-dc03d6b7a5cd>
[Dec 19 22:32:38] DEBUG[11485][C-00000012] bridge_channel.c: Bridge 8afb86a1-929d-4023-92ac-dc03d6b7a5cd: 0x7f36bc00d220(IAX2/audio-8052) is leaving simple_bridge technology
[Dec 19 22:32:38] DEBUG[11485][C-00000012] bridge.c: Bridge 8afb86a1-929d-4023-92ac-dc03d6b7a5cd: dissolving bridge with cause 16(Normal Clearing)
[Dec 19 22:32:38] DEBUG[11485][C-00000012] bridge_channel.c: Setting 0x7f36bc00d040(PJSIP/20000-00000011) state from:0 to:2
[Dec 19 22:32:38] DEBUG[11485][C-00000012] bridge.c: Bridge 8afb86a1-929d-4023-92ac-dc03d6b7a5cd: queueing action type:13 sub:1001
[Dec 19 22:32:38] DEBUG[11485][C-00000012] bridge.c: Bridge 8afb86a1-929d-4023-92ac-dc03d6b7a5cd is dissolved, not performing smart bridge operation.
[Dec 19 22:32:38] DEBUG[11486][C-00000012] bridge_channel.c: Bridge 8afb86a1-929d-4023-92ac-dc03d6b7a5cd: pulling 0x7f36bc00d040(PJSIP/20000-00000011)
[Dec 19 22:32:38] DEBUG[2007] cdr.c: Finalized CDR for IAX2/audio-8052 - start 1576794742.571153 answer 1576794742.613100 end 1576794758.885879 dispo ANSWERED
[Dec 19 22:32:38] DEBUG[11485][C-00000012] bridge_channel.c: Bridge is returning 0x7f36bc00d220(IAX2/audio-8052) to write format speex
[Dec 19 22:32:38] VERBOSE[11486][C-00000012] bridge_channel.c: Channel PJSIP/20000-00000011 left 'simple_bridge' basic-bridge <8afb86a1-929d-4023-92ac-dc03d6b7a5cd>
[Dec 19 22:32:38] DEBUG[11486][C-00000012] bridge_channel.c: Bridge 8afb86a1-929d-4023-92ac-dc03d6b7a5cd: 0x7f36bc00d040(PJSIP/20000-00000011) is leaving simple_bridge technology
[Dec 19 22:32:38] DEBUG[11485][C-00000012] channel.c: Channel IAX2/audio-8052 setting write format path: speex -> speex
[Dec 19 22:32:38] DEBUG[11486][C-00000012] bridge.c: Bridge 8afb86a1-929d-4023-92ac-dc03d6b7a5cd is dissolved, not performing smart bridge operation.
[Dec 19 22:32:38] DEBUG[11486][C-00000012] bridge_channel.c: Bridge is returning 0x7f36bc00d040(PJSIP/20000-00000011) to read format speex
[Dec 19 22:32:38] DEBUG[11485][C-00000012] app_dial.c: Exiting with DIALSTATUS=ANSWER.
[Dec 19 22:32:38] DEBUG[11486][C-00000012] codec_speex.c: Default quality (vbr): 4
[Dec 19 22:32:38] DEBUG[11485][C-00000012] pbx.c: Spawn extension (internal,20000,3) exited non-zero on 'IAX2/audio-8052'
[Dec 19 22:32:38] DEBUG[11486][C-00000012] channel.c: Channel PJSIP/20000-00000011 setting read format path: alaw -> speex
[Dec 19 22:32:38] VERBOSE[11485][C-00000012] pbx.c: Spawn extension (internal, 20000, 3) exited non-zero on 'IAX2/audio-8052'
[Dec 19 22:32:38] DEBUG[11485][C-00000012] channel.c: Soft-Hanging (0x10) up channel 'IAX2/audio-8052'
[Dec 19 22:32:38] DEBUG[11486][C-00000012] bridge.c: Bridge 8afb86a1-929d-4023-92ac-dc03d6b7a5cd: actually destroying basic bridge, nobody wants it anymore
[Dec 19 22:32:38] DEBUG[11486][C-00000012] bridge.c: Bridge 8afb86a1-929d-4023-92ac-dc03d6b7a5cd: calling basic bridge destructor
[Dec 19 22:32:38] DEBUG[11485][C-00000012] channel.c: Channel 0x7f3690023c90 'IAX2/audio-8052' hanging up.  Refs: 2
[Dec 19 22:32:38] DEBUG[11486][C-00000012] bridge.c: Bridge 8afb86a1-929d-4023-92ac-dc03d6b7a5cd: calling simple_bridge technology stop
[Dec 19 22:32:38] DEBUG[11486][C-00000012] bridge.c: Bridge 8afb86a1-929d-4023-92ac-dc03d6b7a5cd: calling simple_bridge technology destructor
[Dec 19 22:32:38] DEBUG[11485][C-00000012] chan_iax2.c: We're hanging up IAX2/audio-8052 now...
[Dec 19 22:32:38] DEBUG[11486][C-00000012] stasis.c: Destroying topic. name: cache:134/bridge:8afb86a1-929d-4023-92ac-dc03d6b7a5cd, detail: 
[Dec 19 22:32:38] DEBUG[11486][C-00000012] stasis.c: Topic 'cache:134/bridge:8afb86a1-929d-4023-92ac-dc03d6b7a5cd': 0x7f36bc0817d0 destroyed
[Dec 19 22:32:38] DEBUG[11486][C-00000012] stasis.c: Destroying topic. name: bridge:8afb86a1-929d-4023-92ac-dc03d6b7a5cd, detail: 
[Dec 19 22:32:38] DEBUG[11486][C-00000012] stasis.c: Topic 'bridge:8afb86a1-929d-4023-92ac-dc03d6b7a5cd': 0x7f36bc00d8d0 destroyed
[Dec 19 22:32:38] DEBUG[11485][C-00000012] chan_iax2.c: Really destroying IAX2/audio-8052 now...
[Dec 19 22:32:38] DEBUG[11486][C-00000012] channel.c: Channel 0x7f36bc0ddf50 'PJSIP/20000-00000011' hanging up.  Refs: 2
[Dec 19 22:32:38] DEBUG[11485][C-00000012] chan_iax2.c: schedule decrement of callno used for fc00:1234:c::1 in 60 seconds
[Dec 19 22:32:38] VERBOSE[11485][C-00000012] chan_iax2.c: Hungup 'IAX2/audio-8052'
[Dec 19 22:32:38] DEBUG[11485][C-00000012] channel.c: Channel 0x7f3690023c90 'IAX2/audio-8052' destroying
[Dec 19 22:32:38] DEBUG[11486][C-00000012] chan_pjsip.c: AST hangup cause 16 (no match found in PJSIP)
[Dec 19 22:32:38] DEBUG[11485][C-00000012] stasis.c: Destroying topic. name: cache:132/channel:1576794742.51, detail: 
[Dec 19 22:32:38] DEBUG[11485][C-00000012] stasis.c: Topic 'cache:132/channel:1576794742.51': 0x7f3690019a80 destroyed
[Dec 19 22:32:38] DEBUG[2007] stasis.c: Creating topic. name: channel:1576794758.53, detail: 
[Dec 19 22:32:38] DEBUG[11485][C-00000012] stasis.c: Destroying topic. name: channel:1576794742.51, detail: 
[Dec 19 22:32:38] DEBUG[11485][C-00000012] stasis.c: Topic 'channel:1576794742.51': 0x7f36900199c0 destroyed
[Dec 19 22:32:38] DEBUG[2007] stasis.c: Topic 'channel:1576794758.53': 0x7f3674012f80 created
[Dec 19 22:32:38] DEBUG[2007] stasis.c: Creating topic. name: cache:135/channel:1576794758.53, detail: 
[Dec 19 22:32:38] DEBUG[2007] stasis.c: Topic 'cache:135/channel:1576794758.53': 0x7f36740012e0 created
[Dec 19 22:32:38] DEBUG[11470] rtp_engine.c: Destroyed RTP instance '0x7f369002a0e0'
[Dec 19 22:32:38] DEBUG[2007] stasis.c: Destroying topic. name: cache:135/channel:1576794758.53, detail: 
[Dec 19 22:32:38] DEBUG[2007] stasis.c: Topic 'cache:135/channel:1576794758.53': 0x7f36740012e0 destroyed
[Dec 19 22:32:38] DEBUG[2007] stasis.c: Destroying topic. name: channel:1576794758.53, detail: 
[Dec 19 22:32:38] DEBUG[2007] stasis.c: Topic 'channel:1576794758.53': 0x7f3674012f80 destroyed
[Dec 19 22:32:38] DEBUG[11470] res_pjsip_session.c: Method is BYE
[Dec 19 22:32:38] DEBUG[1987] threadpool.c: Increasing threadpool stasis/pool's size by 1
[Dec 19 22:32:38] DEBUG[11470] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target '172.16.66.101'
[Dec 19 22:32:38] DEBUG[11470] res_pjsip/pjsip_resolver.c: Transport type for target '172.16.66.101' is 'UDP'
[Dec 19 22:32:38] DEBUG[11470] res_pjsip/pjsip_resolver.c: Target '172.16.66.101' is an IP address, skipping resolution
[Dec 19 22:32:38] DEBUG[1999] devicestate.c: No provider found, checking channel drivers for IAX2 - audio
[Dec 19 22:32:38] DEBUG[1999] chan_iax2.c: Checking device state for device audio
[Dec 19 22:32:38] DEBUG[1999] chan_iax2.c: Found peer. What's device state of audio? addr=0.0.0.0:4569, defaddr=(null) maxms=0, lastms=0
[Dec 19 22:32:38] DEBUG[1999] devicestate.c: Changing state for IAX2/audio - state 0 (Unknown)
[Dec 19 22:32:38] DEBUG[11470] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[Dec 19 22:32:38] DEBUG[11470] res_pjsip_session.c: The state change pertains to the endpoint '20000(PJSIP/20000-00000011)'
[Dec 19 22:32:38] DEBUG[11470] res_pjsip_session.c: The inv session does NOT have an invite_tsx
[Dec 19 22:32:38] DEBUG[11470] res_pjsip_session.c: The UAC BYE transaction involved in this state change is 0x7f3650006518
[Dec 19 22:32:38] DEBUG[11470] res_pjsip_session.c: The current transaction state is Calling
[Dec 19 22:32:38] DEBUG[11470] res_pjsip_session.c: The transaction state change event is TX_MSG
[Dec 19 22:32:38] DEBUG[11470] res_pjsip_session.c: The current inv state is CONFIRMED
[Dec 19 22:32:38] DEBUG[11470] channel.c: Channel 0x7f36bc0ddf50 'PJSIP/20000-00000011' destroying
[Dec 19 22:32:38] DEBUG[2062] app_queue.c: Device 'IAX2/audio' changed to state '0' (Unknown) but we don't care because they're not a member of any queue.
[Dec 19 22:32:38] DEBUG[2007] cdr.c: CDR for PJSIP/20000-00000011 is dialed and has no Party B; discarding
[Dec 19 22:32:38] DEBUG[11470] stasis.c: Destroying topic. name: cache:133/channel:1576794742.52, detail: 
[Dec 19 22:32:38] DEBUG[11470] stasis.c: Topic 'cache:133/channel:1576794742.52': 0x7f36bc008280 destroyed
[Dec 19 22:32:38] DEBUG[11470] stasis.c: Destroying topic. name: channel:1576794742.52, detail: 
[Dec 19 22:32:38] DEBUG[11470] stasis.c: Topic 'channel:1576794742.52': 0x7f36bc008e20 destroyed
[Dec 19 22:32:38] DEBUG[1999] devicestate.c: No provider found, checking channel drivers for PJSIP - 20000
[Dec 19 22:32:38] DEBUG[1999] devicestate.c: Changing state for PJSIP/20000 - state 1 (Not in use)
[Dec 19 22:32:38] DEBUG[2062] app_queue.c: Device 'PJSIP/20000' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
[Dec 19 22:32:38] DEBUG[2016] res_pjsip/pjsip_distributor.c: Searching for serializer associated with dialog dlg0x7f3654016418 for Response msg 200/BYE/cseq=20775 (rdata0x55b81e112e08)
[Dec 19 22:32:38] DEBUG[2016] res_pjsip/pjsip_distributor.c: Found serializer pjsip/outsess/20000-000000d8 associated with dialog dlg0x7f3654016418
[Dec 19 22:32:38] DEBUG[11470] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE
[Dec 19 22:32:38] DEBUG[11470] res_pjsip_session.c: The state change pertains to the endpoint '20000()'
[Dec 19 22:32:38] DEBUG[11470] res_pjsip_session.c: The inv session does NOT have an invite_tsx
[Dec 19 22:32:38] DEBUG[11470] res_pjsip_session.c: There is no transaction involved in this state change
[Dec 19 22:32:38] DEBUG[11470] res_pjsip_session.c: The current inv state is DISCONNCTD
[Dec 19 22:32:38] DEBUG[11470] res_pjsip_session.c: Source of transaction state change is RX_MSG
[Dec 19 22:32:38] DEBUG[11470] res_pjsip_session.c: Received response
[Dec 19 22:32:38] DEBUG[11470] res_pjsip_session.c: Response is 200 Ok
[Dec 19 22:32:38] DEBUG[11470] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[Dec 19 22:32:38] DEBUG[11470] res_pjsip_session.c: The state change pertains to the endpoint '20000()'
[Dec 19 22:32:38] DEBUG[11470] res_pjsip_session.c: The inv session does NOT have an invite_tsx
[Dec 19 22:32:38] DEBUG[11470] res_pjsip_session.c: The UAC BYE transaction involved in this state change is 0x7f3650006518
[Dec 19 22:32:38] DEBUG[11470] res_pjsip_session.c: The current transaction state is Completed
[Dec 19 22:32:38] DEBUG[11470] res_pjsip_session.c: The transaction state change event is RX_MSG
[Dec 19 22:32:38] DEBUG[11470] res_pjsip_session.c: The current inv state is DISCONNCTD
[Dec 19 22:32:38] DEBUG[11470] res_pjsip_session.c: Received response
[Dec 19 22:32:38] DEBUG[11470] res_pjsip_session.c: Response is 200 Ok
[Dec 19 22:32:38] DEBUG[11470] res_pjsip_session.c: BYE received final response code 200
[Dec 19 22:32:38] DEBUG[2016] res_pjsip/pjsip_distributor.c: Could not find matching transaction for Request msg REGISTER/cseq=22 (rdata0x55b81e112e08)
[Dec 19 22:32:38] DEBUG[2016] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-00000053 to use for Request msg REGISTER/cseq=22 (rdata0x55b81e112e08)
[Dec 19 22:32:38] DEBUG[11470] netsock2.c: Splitting '172.16.66.101' into...
[Dec 19 22:32:38] DEBUG[11470] netsock2.c: ...host '172.16.66.101' and port ''.
[Dec 19 22:32:38] DEBUG[11470] res_pjsip_endpoint_identifier_ip.c: No identify sections to match against
[Dec 19 22:32:38] DEBUG[11470] res_pjsip_endpoint_identifier_user.c: Attempting identify by From username '20000' domain '172.16.66.1'
[Dec 19 22:32:38] DEBUG[11470] res_pjsip_endpoint_identifier_user.c: Identified by From username '20000' domain '172.16.66.1'
[Dec 19 22:32:38] DEBUG[11470] res_pjsip_authenticator_digest.c: Calculated nonce 1576794742/3b792ac5eb21f8443f95e040c1442850. Actual nonce is 1576794742/3b792ac5eb21f8443f95e040c1442850
[Dec 19 22:32:38] DEBUG[11470] netsock2.c: Splitting '172.16.66.1' into...
[Dec 19 22:32:38] DEBUG[11470] netsock2.c: ...host '172.16.66.1' and port ''.
[Dec 19 22:32:38] DEBUG[11470] netsock2.c: Splitting '172.16.66.101' into...
[Dec 19 22:32:38] DEBUG[11470] netsock2.c: ...host '172.16.66.101' and port ''.
[Dec 19 22:32:38] DEBUG[11470] res_pjsip_registrar.c: Matched id '20000' to aor '20000'
[Dec 19 22:32:38] DEBUG[11470] res_pjsip_registrar.c: Matched aor '20000' by To username
[Dec 19 22:32:38] DEBUG[11470] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0)
[Dec 19 22:32:38] DEBUG[11470] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[Dec 19 22:32:38] DEBUG[11470] config.c: extract double from [3.000000] in [-inf, inf] gives [3.000000](0)
[Dec 19 22:32:38] DEBUG[11470] config.c: extract uint from [50600] in [0, 4294967295] gives [50600](0)
[Dec 19 22:32:38] DEBUG[11470] config.c: extract uint from [30] in [0, 86400] gives [30](0)
[Dec 19 22:32:38] VERBOSE[11470] res_pjsip_registrar.c: Removed contact 'sip:20000@172.16.66.101:50600' from AOR '20000' due to request
[Dec 19 22:32:38] VERBOSE[11472] res_pjsip/pjsip_options.c: Contact 20000/sip:20000@172.16.66.101:50600 has been deleted
[Dec 19 22:32:38] VERBOSE[11472] res_pjsip/pjsip_configuration.c: Endpoint 20000 is now Unreachable
[Dec 19 22:32:38] DEBUG[11472] res_pjsip/pjsip_options.c: AOR '20000' now has 0 available contacts
[Dec 19 22:32:38] DEBUG[11472] res_pjsip/pjsip_options.c: Terminating scheduled callback on AOR '20000' as there are no contacts to qualify
[Dec 19 22:32:38] DEBUG[1999] devicestate.c: No provider found, checking channel drivers for PJSIP - 20000
[Dec 19 22:32:38] DEBUG[1999] devicestate.c: Changing state for PJSIP/20000 - state 5 (Unavailable)
[Dec 19 22:32:38] DEBUG[2062] app_queue.c: Device 'PJSIP/20000' changed to state '5' (Unavailable) but we don't care because they're not a member of any queue.
[Dec 19 22:32:38] DEBUG[11470] res_pjsip/pjsip_options.c: Qualifying all contacts on AOR '20000'
[Dec 19 22:32:43] DEBUG[2044] chan_iax2.c: Dropping unused iax2 trunk peer '[fc00:1234:c::1]:4569'
[Dec 19 22:32:43] DEBUG[2016] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[Dec 19 22:32:43] DEBUG[2016] res_pjsip_session.c: The state change pertains to the endpoint '20000()'
[Dec 19 22:32:43] DEBUG[2016] res_pjsip_session.c: The inv session does NOT have an invite_tsx
[Dec 19 22:32:43] DEBUG[2016] res_pjsip_session.c: The UAC BYE transaction involved in this state change is 0x7f3650006518
[Dec 19 22:32:43] DEBUG[2016] res_pjsip_session.c: The current transaction state is Terminated
[Dec 19 22:32:43] DEBUG[2016] res_pjsip_session.c: The transaction state change event is TIMER
[Dec 19 22:32:43] DEBUG[2016] res_pjsip_session.c: The current inv state is DISCONNCTD
[Dec 19 22:32:43] DEBUG[11470] res_pjsip_session.c: Destroying SIP session with endpoint 20000
[Dec 19 22:32:49] DEBUG[2027] res_pjsip_registrar.c: Woke up at 1576794769  Interval: 30
[Dec 19 22:32:49] DEBUG[2027] res_pjsip_registrar.c: Expiring 0 contacts