Report0HighestSequence: 4322
Report0SequenceNumberCycles: 0
Report0IAJitter: 38
Report0LSR: 0
Report0DLSR: 0.0000


[Mar 14 16:13:30] DEBUG[58] res_pjsip_registrar_expire.c: Woke up at 1489508010  Interval: 30
[Mar 14 16:13:30] DEBUG[58] res_pjsip_registrar_expire.c: Expiring 0 contacts
[Mar 14 16:13:30] DEBUG[57] res_pjsip_sdp_rtp.c: It has been 0 seconds since RTP was last sent on instance 0x7f3ac403e8f8. Not sending keepalive
[Mar 14 16:13:31] DEBUG[44] config.c: extract uint from [3] in [0, 4294967295] gives [3](0)
[Mar 14 16:13:31] DEBUG[44] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[Mar 14 16:13:31] DEBUG[44] config.c: extract uint from [2] in [0, 4294967295] gives [2](0)
[Mar 14 16:13:31] DEBUG[44] res_pjsip.c: 0x7f3ac40555e8: Wrapper created
[Mar 14 16:13:31] DEBUG[44] res_pjsip.c: 0x7f3ac40555e8: Set timer to 20000 msec
[Mar 14 16:13:31] DEBUG[44] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target '206.126.62.175'
[Mar 14 16:13:31] DEBUG[44] res_pjsip/pjsip_resolver.c: Transport type for target '206.126.62.175' is 'UDP'
[Mar 14 16:13:31] DEBUG[44] res_pjsip/pjsip_resolver.c: Target '206.126.62.175' is an IP address, skipping resolution
[Mar 14 16:13:31] DEBUG[44] res_pjsip/pjsip_message_ip_updater.c: Re-wrote Contact URI host/port to 172.17.0.2:5060
[Mar 14 16:13:31] DEBUG[44] netsock2.c: Splitting '206.126.62.175' into...
[Mar 14 16:13:31] DEBUG[44] netsock2.c: ...host '206.126.62.175' and port ''.
[Mar 14 16:13:31] DEBUG[43] res_pjsip/pjsip_distributor.c: No dialog serializer for response Response msg 200/OPTIONS/cseq=20466 (rdata0x25294f8). Using request transaction as basis
[Mar 14 16:13:31] DEBUG[43] res_pjsip/pjsip_distributor.c: Found serializer pjsip/default-00000013 on transaction tsx0x7f3ac40228a8
[Mar 14 16:13:31] DEBUG[44] res_pjsip.c: 0x7f3ac40555e8: PJSIP tsx response received
[Mar 14 16:13:31] DEBUG[44] res_pjsip.c: 0x7f3ac40555e8: Cancelling timer
[Mar 14 16:13:31] DEBUG[44] res_pjsip.c: 0x7f3ac40555e8: Timer cancelled
[Mar 14 16:13:31] DEBUG[44] config.c: extract uint from [3] in [0, 4294967295] gives [3](0)
[Mar 14 16:13:31] DEBUG[44] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[Mar 14 16:13:31] DEBUG[44] config.c: extract uint from [2] in [0, 4294967295] gives [2](0)
[Mar 14 16:13:31] DEBUG[44] res_pjsip.c: 0x7f3ac40555e8: Callbacks executed
[Mar 14 16:13:31] DEBUG[44] res_pjsip.c: 0x7f3ac40555e8: wrapper destroyed
[Mar 14 16:13:31] DEBUG[49] res_pjsip/pjsip_configuration.c: Contact 142/sip:424242142@206.126.62.175:5060 status didn't change: Reachable, RTT: 24.359 msec
[Mar 14 16:13:31] DEBUG[57] res_pjsip_sdp_rtp.c: It has been 1 seconds since RTP was last sent on instance 0x7f3ac403e8f8. Sending keepalive
[Mar 14 16:13:32] DEBUG[63] manager.c: Running action 'Ping'
[Mar 14 16:13:32] DEBUG[57] res_pjsip_sdp_rtp.c: It has been 1 seconds since RTP was last sent on instance 0x7f3ac403e8f8. Sending keepalive
[Mar 14 16:13:33] DEBUG[57] res_pjsip_sdp_rtp.c: It has been 1 seconds since RTP was last sent on instance 0x7f3ac403e8f8. Sending keepalive
[Mar 14 16:13:34] DEBUG[43] res_pjsip/pjsip_distributor.c: Could not find matching transaction for Request msg INVITE/cseq=51 (rdata0x25294f8)
[Mar 14 16:13:34] DEBUG[43] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-0000003d to use for Request msg INVITE/cseq=51 (rdata0x25294f8)
[Mar 14 16:13:34] DEBUG[44] res_pjsip_endpoint_identifier_ip.c: No identify sections to match against
[Mar 14 16:13:34] DEBUG[44] res_pjsip_endpoint_identifier_user.c: Attempting identify by From username '242' domain 'sip.primevox.net'
[Mar 14 16:13:34] DEBUG[44] res_pjsip_endpoint_identifier_user.c: Identified by From username '242' domain 'sip.primevox.net'
[Mar 14 16:13:34] DEBUG[44] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-0000003d to use for Request msg INVITE/cseq=51 (rdata0x25206d8)
[Mar 14 16:13:34] DEBUG[44] res_pjsip_session.c: Negotiating incoming SDP media stream 'audio' using audio SDP handler
[Mar 14 16:13:34] DEBUG[44] netsock2.c: Splitting '206.126.62.175' into...
[Mar 14 16:13:34] DEBUG[44] netsock2.c: ...host '206.126.62.175' and port ''.
[Mar 14 16:13:34] DEBUG[44] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x7f3ac406d848'
[Mar 14 16:13:34] DEBUG[63] manager.c: Examining AMI event:
Event: VarSet
Privilege: dialplan,all
Channel: none
Uniqueid: none
Variable: SIPDOMAIN
Value: 206.126.62.178


[Mar 14 16:13:34] DEBUG[44] res_rtp_asterisk.c: Allocated port 16608 for RTP instance '0x7f3ac406d848'
[Mar 14 16:13:34] DEBUG[44] res_rtp_asterisk.c: Creating ICE session [::]:16608 (16608) for RTP instance '0x7f3ac406d848'
[Mar 14 16:13:34] DEBUG[44] netsock2.c: Splitting 'fe80::42:acff:fe11:2' into...
[Mar 14 16:13:34] DEBUG[44] netsock2.c: ...host 'fe80::42:acff:fe11:2' and port ''.
[Mar 14 16:13:34] DEBUG[44] netsock2.c: Splitting 'fe80::42:acff:fe11:2' into...
[Mar 14 16:13:34] DEBUG[44] netsock2.c: ...host 'fe80::42:acff:fe11:2' and port ''.
[Mar 14 16:13:34] DEBUG[44] netsock2.c: Splitting '172.17.0.2' into...
[Mar 14 16:13:34] DEBUG[44] netsock2.c: ...host '172.17.0.2' and port ''.
[Mar 14 16:13:34] DEBUG[44] netsock2.c: Splitting '172.17.0.2' into...
[Mar 14 16:13:34] DEBUG[44] netsock2.c: ...host '172.17.0.2' and port ''.
[Mar 14 16:13:34] DEBUG[44] rtp_engine.c: RTP instance '0x7f3ac406d848' is setup and ready to go
[Mar 14 16:13:34] DEBUG[44] netsock2.c: Splitting 'd24d6d931b44' into...
[Mar 14 16:13:34] DEBUG[44] netsock2.c: ...host 'd24d6d931b44' and port ''.
[Mar 14 16:13:34] DEBUG[44] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x7f3ac406d848'
[Mar 14 16:13:34] DEBUG[44] netsock2.c: Splitting 'fe80::42:acff:fe11:2' into...
[Mar 14 16:13:34] DEBUG[44] netsock2.c: ...host 'fe80::42:acff:fe11:2' and port ''.
[Mar 14 16:13:34] DEBUG[44] netsock2.c: Splitting 'fe80::42:acff:fe11:2' into...
[Mar 14 16:13:34] DEBUG[44] netsock2.c: ...host 'fe80::42:acff:fe11:2' and port ''.
[Mar 14 16:13:34] DEBUG[44] netsock2.c: Splitting '172.17.0.2' into...
[Mar 14 16:13:34] DEBUG[44] netsock2.c: ...host '172.17.0.2' and port ''.
[Mar 14 16:13:34] DEBUG[44] netsock2.c: Splitting '172.17.0.2' into...
[Mar 14 16:13:34] DEBUG[44] netsock2.c: ...host '172.17.0.2' and port ''.
[Mar 14 16:13:34] DEBUG[44] rtp_engine.c: Setting tx payload type 0 based on m type on 0x7f3add5763b0
[Mar 14 16:13:34] DEBUG[44] rtp_engine.c: Setting tx payload type 8 based on m type on 0x7f3add5763b0
[Mar 14 16:13:34] DEBUG[44] rtp_engine.c: Setting tx payload type 101 based on m type on 0x7f3add5763b0
[Mar 14 16:13:34] DEBUG[44] rtp_engine.c: Crossover copying tx to rx payload mapping 0 (0x7f3ac4070900) from 0x7f3add5763b0 to 0x7f3add5763b0
[Mar 14 16:13:34] DEBUG[44] rtp_engine.c: Crossover copying tx to rx payload mapping 8 (0x7f3ac40126b0) from 0x7f3add5763b0 to 0x7f3add5763b0
[Mar 14 16:13:34] DEBUG[44] rtp_engine.c: Crossover copying tx to rx payload mapping 101 (0x2340b30) from 0x7f3add5763b0 to 0x7f3add5763b0
[Mar 14 16:13:34] DEBUG[44] rtp_engine.c: Copying rx payload mapping 0 (0x7f3ac4070900) from 0x7f3add5763b0 to 0x7f3ac406da10
[Mar 14 16:13:34] DEBUG[44] rtp_engine.c: Copying rx payload mapping 8 (0x7f3ac40126b0) from 0x7f3add5763b0 to 0x7f3ac406da10
[Mar 14 16:13:34] DEBUG[44] rtp_engine.c: Copying rx payload mapping 101 (0x2340b30) from 0x7f3add5763b0 to 0x7f3ac406da10
[Mar 14 16:13:34] DEBUG[44] rtp_engine.c: Copying tx payload mapping 0 (0x7f3ac4070900) from 0x7f3add5763b0 to 0x7f3ac406da10
[Mar 14 16:13:34] DEBUG[44] rtp_engine.c: Copying tx payload mapping 8 (0x7f3ac40126b0) from 0x7f3add5763b0 to 0x7f3ac406da10
[Mar 14 16:13:34] DEBUG[44] rtp_engine.c: Copying tx payload mapping 101 (0x2340b30) from 0x7f3add5763b0 to 0x7f3ac406da10
[Mar 14 16:13:34] DEBUG[44] res_pjsip_session.c: Media stream 'audio' handled by audio
[Mar 14 16:13:34] DEBUG[44] res_pjsip_t38.c: Not creating outgoing SDP stream: T.38 not enabled
[Mar 14 16:13:34] DEBUG[44] res_pjsip_session.c: Method is INVITE, Response is 100 Trying
[Mar 14 16:13:34] DEBUG[44] netsock2.c: Splitting '206.126.62.175' into...
[Mar 14 16:13:34] DEBUG[44] netsock2.c: ...host '206.126.62.175' and port ''.
[Mar 14 16:13:34] DEBUG[44] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE
[Mar 14 16:13:34] DEBUG[44] res_pjsip_session.c: The state change pertains to the endpoint '242()'
[Mar 14 16:13:34] DEBUG[44] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f3ac4018278)
[Mar 14 16:13:34] DEBUG[44] res_pjsip_session.c: There is no transaction involved in this state change
[Mar 14 16:13:34] DEBUG[44] res_pjsip_session.c: The current inv state is INCOMING
[Mar 14 16:13:34] DEBUG[44] res_pjsip_session.c: Source of transaction state change is TX_MSG
[Mar 14 16:13:34] DEBUG[44] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[Mar 14 16:13:34] DEBUG[44] res_pjsip_session.c: The state change pertains to the endpoint '242()'
[Mar 14 16:13:34] DEBUG[44] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f3ac4018278)
[Mar 14 16:13:34] DEBUG[44] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x7f3ac4018278
[Mar 14 16:13:34] DEBUG[44] res_pjsip_session.c: The current transaction state is Proceeding
[Mar 14 16:13:34] DEBUG[44] res_pjsip_session.c: The transaction state change event is TX_MSG
[Mar 14 16:13:34] DEBUG[44] res_pjsip_session.c: The current inv state is INCOMING
[Mar 14 16:13:34] DEBUG[44] res_pjsip_session.c: Method is INVITE
[Mar 14 16:13:34] DEBUG[16] threadpool.c: Increasing threadpool stasis-core's size by 1
[Mar 14 16:13:34] DEBUG[63] manager.c: Examining AMI event:
Event: Newchannel
Privilege: call,all
Channel: PJSIP/242-0000000f
ChannelState: 4
ChannelStateDesc: Ring
CallerIDNum: 242
CallerIDName: PBX 242
ConnectedLineNum: <unknown>
ConnectedLineName: <unknown>
Language: en
AccountCode: 
Context: from-internal
Exten: *43
Priority: 1
Uniqueid: 1489508014.33
Linkedid: 1489508014.33


[Mar 14 16:13:34] DEBUG[44] chan_pjsip.c: Started PBX on new PJSIP channel PJSIP/242-0000000f
[Mar 14 16:13:34] DEBUG[271][C-0000000a] pbx.c: Launching 'NoOp'
[Mar 14 16:13:34] DEBUG[271][C-0000000a] pbx.c: Launching 'Answer'
[Mar 14 16:13:34] DEBUG[63] manager.c: Examining AMI event:
Event: Newexten
Privilege: call,all
Channel: PJSIP/242-0000000f
ChannelState: 4
ChannelStateDesc: Ring
CallerIDNum: 242
CallerIDName: PBX 242
ConnectedLineNum: <unknown>
ConnectedLineName: <unknown>
Language: en
AccountCode: 
Context: from-internal
Exten: *43
Priority: 1
Uniqueid: 1489508014.33
Linkedid: 1489508014.33
Extension: *43
Application: NoOp
AppData: 


[Mar 14 16:13:34] DEBUG[27] devicestate.c: No provider found, checking channel drivers for PJSIP - 242
[Mar 14 16:13:34] DEBUG[63] manager.c: Examining AMI event:
Event: Newexten
Privilege: call,all
Channel: PJSIP/242-0000000f
ChannelState: 4
ChannelStateDesc: Ring
CallerIDNum: 242
CallerIDName: PBX 242
ConnectedLineNum: <unknown>
ConnectedLineName: <unknown>
Language: en
AccountCode: 
Context: from-internal
Exten: *43
Priority: 2
Uniqueid: 1489508014.33
Linkedid: 1489508014.33
Extension: *43
Application: Answer
AppData: 


[Mar 14 16:13:34] DEBUG[27] devicestate.c: Changing state for PJSIP/242 - state 2 (In use)
[Mar 14 16:13:34] DEBUG[44] res_pjsip_session.c: Applying negotiated SDP media stream 'audio' using audio SDP handler
[Mar 14 16:13:34] DEBUG[44] netsock2.c: Splitting '206.126.62.175' into...
[Mar 14 16:13:34] DEBUG[44] netsock2.c: ...host '206.126.62.175' and port ''.
[Mar 14 16:13:34] DEBUG[63] manager.c: Examining AMI event:
Event: Newstate
Privilege: call,all
Channel: PJSIP/242-0000000f
ChannelState: 6
ChannelStateDesc: Up
CallerIDNum: 242
CallerIDName: PBX 242
ConnectedLineNum: <unknown>
ConnectedLineName: <unknown>
Language: en
AccountCode: 
Context: from-internal
Exten: *43
Priority: 2
Uniqueid: 1489508014.33
Linkedid: 1489508014.33


[Mar 14 16:13:34] DEBUG[44] acl.c: For destination '206.126.62.175', our source address is '172.17.0.2'.
[Mar 14 16:13:34] DEBUG[44] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7f3ac406d848'
[Mar 14 16:13:34] DEBUG[44] rtp_engine.c: Setting tx payload type 0 based on m type on 0x7f3add575df0
[Mar 14 16:13:34] DEBUG[44] rtp_engine.c: Setting tx payload type 8 based on m type on 0x7f3add575df0
[Mar 14 16:13:34] DEBUG[74] app_queue.c: Device 'PJSIP/242' changed to state '2' (In use) but we don't care because they're not a member of any queue.
[Mar 14 16:13:34] DEBUG[44] rtp_engine.c: Setting tx payload type 101 based on m type on 0x7f3add575df0
[Mar 14 16:13:34] DEBUG[44] rtp_engine.c: Copying tx payload mapping 0 (0x7f3ac403bf90) from 0x7f3add575df0 to 0x7f3ac406da10
[Mar 14 16:13:34] DEBUG[44] rtp_engine.c: Copying tx payload mapping 8 (0x7f3ac4046150) from 0x7f3add575df0 to 0x7f3ac406da10
[Mar 14 16:13:34] DEBUG[44] rtp_engine.c: Copying tx payload mapping 101 (0x2340b30) from 0x7f3add575df0 to 0x7f3ac406da10
[Mar 14 16:13:34] DEBUG[44] res_pjsip_session.c: Applied negotiated SDP media stream 'audio' using audio SDP handler
[Mar 14 16:13:34] DEBUG[31] app_queue.c: Extension '242@from-internal' changed to state '2' (In use) but we don't care because they're not a member of any queue.
[Mar 14 16:13:34] DEBUG[44] res_pjsip_session.c: Method is INVITE, Response is 200 OK
[Mar 14 16:13:34] DEBUG[63] manager.c: Examining AMI event:
Event: ExtensionStatus
Privilege: call,all
Exten: 242
Context: from-internal
Hint: PJSIP/242
Status: 1
StatusText: InUse


[Mar 14 16:13:34] DEBUG[44] res_pjsip/pjsip_message_ip_updater.c: Re-wrote Contact URI host/port to 172.17.0.2:5060
[Mar 14 16:13:34] DEBUG[44] netsock2.c: Splitting '206.126.62.175' into...
[Mar 14 16:13:34] DEBUG[44] netsock2.c: ...host '206.126.62.175' and port ''.
[Mar 14 16:13:34] DEBUG[44] netsock2.c: Splitting '172.17.0.2' into...
[Mar 14 16:13:34] DEBUG[44] netsock2.c: ...host '172.17.0.2' and port ''.
[Mar 14 16:13:34] DEBUG[63] manager.c: Examining AMI event:
Event: DeviceStateChange
Privilege: call,all
Device: PJSIP/242
State: INUSE


[Mar 14 16:13:34] DEBUG[44] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE
[Mar 14 16:13:34] DEBUG[44] res_pjsip_session.c: The state change pertains to the endpoint '242(PJSIP/242-0000000f)'
[Mar 14 16:13:34] DEBUG[44] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f3ac4018278)
[Mar 14 16:13:34] DEBUG[44] res_pjsip_session.c: There is no transaction involved in this state change
[Mar 14 16:13:34] DEBUG[44] res_pjsip_session.c: The current inv state is CONNECTING
[Mar 14 16:13:34] DEBUG[44] res_pjsip_session.c: Source of transaction state change is TX_MSG
[Mar 14 16:13:34] DEBUG[44] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[Mar 14 16:13:34] DEBUG[44] res_pjsip_session.c: The state change pertains to the endpoint '242(PJSIP/242-0000000f)'
[Mar 14 16:13:34] DEBUG[44] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f3ac4018278)
[Mar 14 16:13:34] DEBUG[44] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x7f3ac4018278
[Mar 14 16:13:34] DEBUG[44] res_pjsip_session.c: The current transaction state is Completed
[Mar 14 16:13:34] DEBUG[44] res_pjsip_session.c: The transaction state change event is TX_MSG
[Mar 14 16:13:34] DEBUG[44] res_pjsip_session.c: The current inv state is CONNECTING
[Mar 14 16:13:34] DEBUG[43] res_pjsip/pjsip_distributor.c: Searching for serializer on dialog dlg0x7f3ac000b498 for Request msg ACK/cseq=51 (rdata0x25294f8)
[Mar 14 16:13:34] DEBUG[43] res_pjsip/pjsip_distributor.c: Found serializer pjsip/distributor-0000003d on dialog dlg0x7f3ac000b498
[Mar 14 16:13:34] DEBUG[44] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[Mar 14 16:13:34] DEBUG[44] res_pjsip_session.c: The state change pertains to the endpoint '242(PJSIP/242-0000000f)'
[Mar 14 16:13:34] DEBUG[44] res_pjsip_session.c: The inv session does NOT have an invite_tsx
[Mar 14 16:13:34] DEBUG[44] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x7f3ac4018278
[Mar 14 16:13:34] DEBUG[44] res_pjsip_session.c: The current transaction state is Terminated
[Mar 14 16:13:34] DEBUG[44] res_pjsip_session.c: The transaction state change event is USER
[Mar 14 16:13:34] DEBUG[44] res_pjsip_session.c: The current inv state is CONNECTING
[Mar 14 16:13:34] DEBUG[44] res_pjsip_session.c: Function session_inv_on_state_changed called on event RX_MSG
[Mar 14 16:13:34] DEBUG[44] res_pjsip_session.c: The state change pertains to the endpoint '242(PJSIP/242-0000000f)'
[Mar 14 16:13:34] DEBUG[44] res_pjsip_session.c: The inv session does NOT have an invite_tsx
[Mar 14 16:13:34] DEBUG[44] res_pjsip_session.c: There is no transaction involved in this state change
[Mar 14 16:13:34] DEBUG[44] res_pjsip_session.c: The current inv state is CONFIRMED
[Mar 14 16:13:34] DEBUG[44] res_pjsip_session.c: Received request
[Mar 14 16:13:34] DEBUG[44] res_pjsip_session.c: Method is ACK
[Mar 14 16:13:34] DEBUG[63] manager.c: Running action 'ExtensionStateList'
[Mar 14 16:13:34] DEBUG[63] manager.c: Running action 'PJSIPShowRegistrationInboundContactStatuses'
[Mar 14 16:13:34] DEBUG[63] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0)
[Mar 14 16:13:34] DEBUG[63] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[Mar 14 16:13:34] DEBUG[63] config.c: extract double from [20.000000] in [-inf, inf] gives [20.000000](0)
[Mar 14 16:13:34] DEBUG[63] config.c: extract uint from [15] in [0, 86400] gives [15](0)
[Mar 14 16:13:34] DEBUG[63] config.c: extract uint from [5060] in [0, 4294967295] gives [5060](0)
[Mar 14 16:13:34] DEBUG[63] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0)
[Mar 14 16:13:34] DEBUG[63] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[Mar 14 16:13:34] DEBUG[63] config.c: extract double from [20.000000] in [-inf, inf] gives [20.000000](0)
[Mar 14 16:13:34] DEBUG[63] config.c: extract uint from [15] in [0, 86400] gives [15](0)
[Mar 14 16:13:34] DEBUG[63] config.c: extract uint from [5060] in [0, 4294967295] gives [5060](0)
[Mar 14 16:13:34] DEBUG[63] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0)
[Mar 14 16:13:34] DEBUG[63] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[Mar 14 16:13:34] DEBUG[63] config.c: extract double from [20.000000] in [-inf, inf] gives [20.000000](0)
[Mar 14 16:13:34] DEBUG[63] config.c: extract uint from [15] in [0, 86400] gives [15](0)
[Mar 14 16:13:34] DEBUG[63] config.c: extract uint from [5060] in [0, 4294967295] gives [5060](0)
[Mar 14 16:13:34] DEBUG[63] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0)
[Mar 14 16:13:34] DEBUG[63] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[Mar 14 16:13:34] DEBUG[63] config.c: extract double from [20.000000] in [-inf, inf] gives [20.000000](0)
[Mar 14 16:13:34] DEBUG[63] config.c: extract uint from [15] in [0, 86400] gives [15](0)
[Mar 14 16:13:34] DEBUG[63] config.c: extract uint from [5060] in [0, 4294967295] gives [5060](0)
[Mar 14 16:13:34] DEBUG[63] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0)
[Mar 14 16:13:34] DEBUG[63] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[Mar 14 16:13:34] DEBUG[63] config.c: extract double from [20.000000] in [-inf, inf] gives [20.000000](0)
[Mar 14 16:13:34] DEBUG[63] config.c: extract uint from [15] in [0, 86400] gives [15](0)
[Mar 14 16:13:34] DEBUG[63] config.c: extract uint from [5060] in [0, 4294967295] gives [5060](0)
[Mar 14 16:13:34] DEBUG[57] res_pjsip_sdp_rtp.c: It has been 1 seconds since RTP was last sent on instance 0x7f3ac403e8f8. Sending keepalive
[Mar 14 16:13:34] DEBUG[57] res_pjsip_sdp_rtp.c: It has been 1489508014 seconds since RTP was last sent on instance 0x7f3ac406d848. Sending keepalive
[Mar 14 16:13:34] DEBUG[271][C-0000000a] channel.c: Didn't receive a media frame from PJSIP/242-0000000f within 500 ms of answering. Continuing anyway
[Mar 14 16:13:34] DEBUG[271][C-0000000a] pbx.c: Launching 'Echo'
[Mar 14 16:13:34] DEBUG[63] manager.c: Examining AMI event:
Event: Newexten
Privilege: call,all
Channel: PJSIP/242-0000000f
ChannelState: 6
ChannelStateDesc: Up
CallerIDNum: 242
CallerIDName: PBX 242
ConnectedLineNum: <unknown>
ConnectedLineName: <unknown>
Language: en
AccountCode: 
Context: from-internal
Exten: *43
Priority: 3
Uniqueid: 1489508014.33
Linkedid: 1489508014.33
Extension: *43
Application: Echo
AppData: 


[Mar 14 16:13:34] DEBUG[271][C-0000000a] res_rtp_asterisk.c: 0x7f3ac405a3e0 -- Probation learning mode pass with source address 206.126.62.175:12198
[Mar 14 16:13:34] DEBUG[271][C-0000000a] res_rtp_asterisk.c: Ooh, format changed from none to ulaw
[Mar 14 16:13:35] DEBUG[63] manager.c: Examining AMI event:
Event: RTCPSent
Privilege: reporting,all
To: 206.126.62.175:10767
From: 172.17.0.2:19793
SSRC: 0x6e236021
PT: 200(SR)
ReportCount: 1
SentNTP: 1489508015.691723
SentRTP: 2454768800
SentPackets: 819
SentOctets: 127383
Report0SourceSSRC: 0xbf8885a9
Report0FractionLost: 0
Report0CumulativeLost: 0
Report0HighestSequence: 4322
Report0SequenceNumberCycles: 0
Report0IAJitter: 38
Report0LSR: 0
Report0DLSR: 0.0000


[Mar 14 16:13:35] DEBUG[57] res_pjsip_sdp_rtp.c: It has been 0 seconds since RTP was last sent on instance 0x7f3ac403e8f8. Not sending keepalive
[Mar 14 16:13:35] DEBUG[57] res_pjsip_sdp_rtp.c: It has been 0 seconds since RTP was last sent on instance 0x7f3ac406d848. Not sending keepalive
[Mar 14 16:13:36] DEBUG[57] res_pjsip_sdp_rtp.c: It has been 1 seconds since RTP was last sent on instance 0x7f3ac403e8f8. Sending keepalive
[Mar 14 16:13:36] DEBUG[57] res_pjsip_sdp_rtp.c: It has been 0 seconds since RTP was last sent on instance 0x7f3ac406d848. Not sending keepalive
[Mar 14 16:13:36] DEBUG[43] res_pjsip/pjsip_distributor.c: Searching for serializer on dialog dlg0x7f3ac000b498 for Request msg ACK/cseq=52 (rdata0x25294f8)
[Mar 14 16:13:36] DEBUG[43] res_pjsip/pjsip_distributor.c: Found serializer pjsip/distributor-0000003d on dialog dlg0x7f3ac000b498
[Mar 14 16:13:36] DEBUG[43] res_pjsip/pjsip_distributor.c: Searching for serializer on dialog dlg0x7f3ac000b498 for Request msg INVITE/cseq=53 (rdata0x25294f8)
[Mar 14 16:13:36] DEBUG[43] res_pjsip/pjsip_distributor.c: Found serializer pjsip/distributor-0000003d on dialog dlg0x7f3ac000b498
[Mar 14 16:13:36] DEBUG[44] res_pjsip_session.c: Negotiating incoming SDP media stream 'audio' using audio SDP handler
[Mar 14 16:13:36] DEBUG[44] netsock2.c: Splitting '206.126.62.175' into...
[Mar 14 16:13:36] DEBUG[44] netsock2.c: ...host '206.126.62.175' and port ''.
[Mar 14 16:13:36] DEBUG[44] rtp_engine.c: Setting tx payload type 0 based on m type on 0x7f3add576080
[Mar 14 16:13:36] DEBUG[44] rtp_engine.c: Setting tx payload type 8 based on m type on 0x7f3add576080
[Mar 14 16:13:36] DEBUG[44] rtp_engine.c: Setting tx payload type 101 based on m type on 0x7f3add576080
[Mar 14 16:13:36] DEBUG[44] rtp_engine.c: Crossover copying tx to rx payload mapping 0 (0x7f3ac4067a00) from 0x7f3add576080 to 0x7f3add576080
[Mar 14 16:13:36] DEBUG[44] rtp_engine.c: Crossover copying tx to rx payload mapping 8 (0x7f3ac4013510) from 0x7f3add576080 to 0x7f3add576080
[Mar 14 16:13:36] DEBUG[44] rtp_engine.c: Crossover copying tx to rx payload mapping 101 (0x2340b30) from 0x7f3add576080 to 0x7f3add576080
[Mar 14 16:13:36] DEBUG[44] rtp_engine.c: Copying rx payload mapping 0 (0x7f3ac4067a00) from 0x7f3add576080 to 0x7f3ac406da10
[Mar 14 16:13:36] DEBUG[44] rtp_engine.c: Copying rx payload mapping 8 (0x7f3ac4013510) from 0x7f3add576080 to 0x7f3ac406da10
[Mar 14 16:13:36] DEBUG[44] rtp_engine.c: Copying rx payload mapping 101 (0x2340b30) from 0x7f3add576080 to 0x7f3ac406da10
[Mar 14 16:13:36] DEBUG[44] rtp_engine.c: Copying tx payload mapping 0 (0x7f3ac4067a00) from 0x7f3add576080 to 0x7f3ac406da10
[Mar 14 16:13:36] DEBUG[44] rtp_engine.c: Copying tx payload mapping 8 (0x7f3ac4013510) from 0x7f3add576080 to 0x7f3ac406da10
[Mar 14 16:13:36] DEBUG[44] rtp_engine.c: Copying tx payload mapping 101 (0x2340b30) from 0x7f3add576080 to 0x7f3ac406da10
[Mar 14 16:13:36] DEBUG[44] res_pjsip_session.c: Media stream 'audio' handled by audio
[Mar 14 16:13:36] DEBUG[44] res_pjsip_session.c: Negotiating incoming SDP media stream 'video' using video SDP handler
[Mar 14 16:13:36] DEBUG[44] netsock2.c: Splitting '206.126.62.175' into...
[Mar 14 16:13:36] DEBUG[44] netsock2.c: ...host '206.126.62.175' and port ''.
[Mar 14 16:13:36] DEBUG[44] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x7f3ac40722e8'
[Mar 14 16:13:36] DEBUG[44] res_rtp_asterisk.c: Allocated port 11964 for RTP instance '0x7f3ac40722e8'
[Mar 14 16:13:36] DEBUG[44] res_rtp_asterisk.c: Creating ICE session [::]:11964 (11964) for RTP instance '0x7f3ac40722e8'
[Mar 14 16:13:36] DEBUG[44] netsock2.c: Splitting 'fe80::42:acff:fe11:2' into...
[Mar 14 16:13:36] DEBUG[44] netsock2.c: ...host 'fe80::42:acff:fe11:2' and port ''.
[Mar 14 16:13:36] DEBUG[44] netsock2.c: Splitting 'fe80::42:acff:fe11:2' into...
[Mar 14 16:13:36] DEBUG[44] netsock2.c: ...host 'fe80::42:acff:fe11:2' and port ''.
[Mar 14 16:13:36] DEBUG[44] netsock2.c: Splitting '172.17.0.2' into...
[Mar 14 16:13:36] DEBUG[44] netsock2.c: ...host '172.17.0.2' and port ''.
[Mar 14 16:13:36] DEBUG[44] netsock2.c: Splitting '172.17.0.2' into...
[Mar 14 16:13:36] DEBUG[44] netsock2.c: ...host '172.17.0.2' and port ''.
[Mar 14 16:13:36] DEBUG[44] rtp_engine.c: RTP instance '0x7f3ac40722e8' is setup and ready to go
[Mar 14 16:13:36] DEBUG[44] netsock2.c: Splitting 'd24d6d931b44' into...
[Mar 14 16:13:36] DEBUG[44] netsock2.c: ...host 'd24d6d931b44' and port ''.
[Mar 14 16:13:36] DEBUG[44] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x7f3ac40722e8'
[Mar 14 16:13:36] DEBUG[44] netsock2.c: Splitting 'fe80::42:acff:fe11:2' into...
[Mar 14 16:13:36] DEBUG[44] netsock2.c: ...host 'fe80::42:acff:fe11:2' and port ''.
[Mar 14 16:13:36] DEBUG[44] netsock2.c: Splitting 'fe80::42:acff:fe11:2' into...
[Mar 14 16:13:36] DEBUG[44] netsock2.c: ...host 'fe80::42:acff:fe11:2' and port ''.
[Mar 14 16:13:36] DEBUG[44] netsock2.c: Splitting '172.17.0.2' into...
[Mar 14 16:13:36] DEBUG[44] netsock2.c: ...host '172.17.0.2' and port ''.
[Mar 14 16:13:36] DEBUG[44] netsock2.c: Splitting '172.17.0.2' into...
[Mar 14 16:13:36] DEBUG[44] netsock2.c: ...host '172.17.0.2' and port ''.
[Mar 14 16:13:36] DEBUG[44] rtp_engine.c: Setting tx payload type 105 based on m type on 0x7f3add576080
[Mar 14 16:13:36] DEBUG[44] rtp_engine.c: Crossover copying tx to rx payload mapping 105 (0x7f3ac40126b0) from 0x7f3add576080 to 0x7f3add576080
[Mar 14 16:13:36] DEBUG[44] rtp_engine.c: Copying rx payload mapping 105 (0x7f3ac40126b0) from 0x7f3add576080 to 0x7f3ac40724b0
[Mar 14 16:13:36] DEBUG[44] rtp_engine.c: Copying tx payload mapping 105 (0x7f3ac40126b0) from 0x7f3add576080 to 0x7f3ac40724b0
[Mar 14 16:13:36] DEBUG[44] res_pjsip_session.c: Media stream 'video' handled by video
[Mar 14 16:13:36] DEBUG[44] res_pjsip_t38.c: Not creating outgoing SDP stream: T.38 not enabled
[Mar 14 16:13:36] DEBUG[44] res_pjsip_session.c: Applying negotiated SDP media stream 'audio' using audio SDP handler
[Mar 14 16:13:36] DEBUG[44] netsock2.c: Splitting '206.126.62.175' into...
[Mar 14 16:13:36] DEBUG[44] netsock2.c: ...host '206.126.62.175' and port ''.
[Mar 14 16:13:36] DEBUG[44] acl.c: For destination '206.126.62.175', our source address is '172.17.0.2'.
[Mar 14 16:13:36] DEBUG[44] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7f3ac406d848'
[Mar 14 16:13:36] DEBUG[44] rtp_engine.c: Setting tx payload type 0 based on m type on 0x7f3add575b70
[Mar 14 16:13:36] DEBUG[44] rtp_engine.c: Setting tx payload type 8 based on m type on 0x7f3add575b70
[Mar 14 16:13:36] DEBUG[44] rtp_engine.c: Setting tx payload type 101 based on m type on 0x7f3add575b70
[Mar 14 16:13:36] DEBUG[44] rtp_engine.c: Copying tx payload mapping 0 (0x7f3ac406a940) from 0x7f3add575b70 to 0x7f3ac406da10
[Mar 14 16:13:36] DEBUG[44] rtp_engine.c: Copying tx payload mapping 8 (0x7f3ac4070900) from 0x7f3add575b70 to 0x7f3ac406da10
[Mar 14 16:13:36] DEBUG[44] rtp_engine.c: Copying tx payload mapping 101 (0x2340b30) from 0x7f3add575b70 to 0x7f3ac406da10
[Mar 14 16:13:36] DEBUG[44] res_pjsip_session.c: Applied negotiated SDP media stream 'audio' using audio SDP handler
[Mar 14 16:13:36] DEBUG[44] res_pjsip_session.c: Applying negotiated SDP media stream 'video' using video SDP handler
[Mar 14 16:13:36] DEBUG[44] netsock2.c: Splitting '206.126.62.175' into...
[Mar 14 16:13:36] DEBUG[44] netsock2.c: ...host '206.126.62.175' and port ''.
[Mar 14 16:13:36] DEBUG[44] acl.c: For destination '206.126.62.175', our source address is '172.17.0.2'.
[Mar 14 16:13:36] DEBUG[44] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7f3ac40722e8'
[Mar 14 16:13:36] DEBUG[44] rtp_engine.c: Setting tx payload type 105 based on m type on 0x7f3add575b70
[Mar 14 16:13:36] DEBUG[44] rtp_engine.c: Copying tx payload mapping 105 (0x7f3ac406f5f0) from 0x7f3add575b70 to 0x7f3ac40724b0
[Mar 14 16:13:36] DEBUG[44] res_pjsip_session.c: Applied negotiated SDP media stream 'video' using video SDP handler
[Mar 14 16:13:36] DEBUG[44] res_pjsip/pjsip_message_ip_updater.c: Re-wrote Contact URI host/port to 172.17.0.2:5060
[Mar 14 16:13:36] DEBUG[44] netsock2.c: Splitting '206.126.62.175' into...
[Mar 14 16:13:36] DEBUG[44] netsock2.c: ...host '206.126.62.175' and port ''.
[Mar 14 16:13:36] DEBUG[44] netsock2.c: Splitting '172.17.0.2' into...
[Mar 14 16:13:36] DEBUG[44] netsock2.c: ...host '172.17.0.2' and port ''.
[Mar 14 16:13:36] DEBUG[44] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[Mar 14 16:13:36] DEBUG[44] res_pjsip_session.c: The state change pertains to the endpoint '242(PJSIP/242-0000000f)'
[Mar 14 16:13:36] DEBUG[44] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f3ac40228a8)
[Mar 14 16:13:36] DEBUG[44] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x7f3ac40228a8
[Mar 14 16:13:36] DEBUG[44] res_pjsip_session.c: The current transaction state is Completed
[Mar 14 16:13:36] DEBUG[44] res_pjsip_session.c: The transaction state change event is TX_MSG
[Mar 14 16:13:36] DEBUG[44] res_pjsip_session.c: The current inv state is CONFIRMED
[Mar 14 16:13:36] DEBUG[44] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[Mar 14 16:13:36] DEBUG[44] res_pjsip_session.c: The state change pertains to the endpoint '242(PJSIP/242-0000000f)'
[Mar 14 16:13:36] DEBUG[44] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f3ac40228a8)
[Mar 14 16:13:36] DEBUG[44] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x7f3ac40228a8
[Mar 14 16:13:36] DEBUG[44] res_pjsip_session.c: The current transaction state is Completed
[Mar 14 16:13:36] DEBUG[44] res_pjsip_session.c: The transaction state change event is RX_MSG
[Mar 14 16:13:36] DEBUG[44] res_pjsip_session.c: The current inv state is CONFIRMED
[Mar 14 16:13:36] DEBUG[44] res_pjsip_session.c: Received request
[Mar 14 16:13:36] DEBUG[44] res_pjsip_session.c: Method is INVITE
[Mar 14 16:13:37] DEBUG[271][C-0000000a] res_rtp_asterisk.c: 0x7f3ac405a3e0 -- Probation learning mode pass with source address 206.126.62.175:12198
[Mar 14 16:13:37] DEBUG[43] res_pjsip/pjsip_distributor.c: Searching for serializer on dialog dlg0x7f3ac000b498 for Request msg ACK/cseq=53 (rdata0x25294f8)
[Mar 14 16:13:37] DEBUG[43] res_pjsip/pjsip_distributor.c: Found serializer pjsip/distributor-0000003d on dialog dlg0x7f3ac000b498
[Mar 14 16:13:37] DEBUG[44] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[Mar 14 16:13:37] DEBUG[44] res_pjsip_session.c: The state change pertains to the endpoint '242(PJSIP/242-0000000f)'
[Mar 14 16:13:37] DEBUG[44] res_pjsip_session.c: The inv session does NOT have an invite_tsx
[Mar 14 16:13:37] DEBUG[44] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x7f3ac40228a8
[Mar 14 16:13:37] DEBUG[44] res_pjsip_session.c: The current transaction state is Terminated
[Mar 14 16:13:37] DEBUG[44] res_pjsip_session.c: The transaction state change event is USER
[Mar 14 16:13:37] DEBUG[44] res_pjsip_session.c: The current inv state is CONFIRMED
[Mar 14 16:13:37] DEBUG[44] config.c: extract uint from [3] in [0, 4294967295] gives [3](0)
[Mar 14 16:13:37] DEBUG[44] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[Mar 14 16:13:37] DEBUG[44] config.c: extract uint from [2] in [0, 4294967295] gives [2](0)
[Mar 14 16:13:37] DEBUG[44] res_pjsip.c: 0x7f3ac40677b8: Wrapper created
[Mar 14 16:13:37] DEBUG[44] res_pjsip.c: 0x7f3ac40677b8: Set timer to 20000 msec
[Mar 14 16:13:37] DEBUG[44] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target '206.126.62.175'
[Mar 14 16:13:37] DEBUG[44] res_pjsip/pjsip_resolver.c: Transport type for target '206.126.62.175' is 'UDP'
[Mar 14 16:13:37] DEBUG[44] res_pjsip/pjsip_resolver.c: Target '206.126.62.175' is an IP address, skipping resolution
[Mar 14 16:13:37] DEBUG[44] res_pjsip/pjsip_message_ip_updater.c: Re-wrote Contact URI host/port to 172.17.0.2:5060
[Mar 14 16:13:37] DEBUG[44] netsock2.c: Splitting '206.126.62.175' into...
[Mar 14 16:13:37] DEBUG[44] netsock2.c: ...host '206.126.62.175' and port ''.
[Mar 14 16:13:37] DEBUG[43] res_pjsip/pjsip_distributor.c: No dialog serializer for response Response msg 200/OPTIONS/cseq=63195 (rdata0x25294f8). Using request transaction as basis
[Mar 14 16:13:37] DEBUG[43] res_pjsip/pjsip_distributor.c: Found serializer pjsip/default-0000000c on transaction tsx0x7f3ac40228a8
[Mar 14 16:13:37] DEBUG[44] res_pjsip.c: 0x7f3ac40677b8: PJSIP tsx response received
[Mar 14 16:13:37] DEBUG[44] res_pjsip.c: 0x7f3ac40677b8: Cancelling timer
[Mar 14 16:13:37] DEBUG[44] res_pjsip.c: 0x7f3ac40677b8: Timer cancelled
[Mar 14 16:13:37] DEBUG[44] config.c: extract uint from [3] in [0, 4294967295] gives [3](0)
[Mar 14 16:13:37] DEBUG[44] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[Mar 14 16:13:37] DEBUG[44] config.c: extract uint from [2] in [0, 4294967295] gives [2](0)
[Mar 14 16:13:37] DEBUG[44] res_pjsip.c: 0x7f3ac40677b8: Callbacks executed
[Mar 14 16:13:37] DEBUG[44] res_pjsip.c: 0x7f3ac40677b8: wrapper destroyed
[Mar 14 16:13:37] DEBUG[49] res_pjsip/pjsip_configuration.c: Contact 145/sip:424242145@206.126.62.175:5060 status didn't change: Reachable, RTT: 27.601 msec
[Mar 14 16:13:37] DEBUG[57] res_pjsip_sdp_rtp.c: It has been 0 seconds since RTP was last sent on instance 0x7f3ac406d848. Not sending keepalive
[Mar 14 16:13:37] DEBUG[271][C-0000000a] res_rtp_asterisk.c: Difference is 5192, ms is 669
[Mar 14 16:13:37] DEBUG[57] res_pjsip_sdp_rtp.c: It has been 1 seconds since RTP was last sent on instance 0x7f3ac403e8f8. Sending keepalive
[Mar 14 16:13:38] DEBUG[57] res_pjsip_sdp_rtp.c: It has been 0 seconds since RTP was last sent on instance 0x7f3ac406d848. Not sending keepalive
[Mar 14 16:13:38] DEBUG[57] res_pjsip_sdp_rtp.c: It has been 1 seconds since RTP was last sent on instance 0x7f3ac403e8f8. Sending keepalive
[Mar 14 16:13:39] DEBUG[43] res_pjsip/pjsip_distributor.c: Searching for serializer on dialog dlg0x7f3ac40390b8 for Request msg SUBSCRIBE/cseq=20159 (rdata0x25294f8)
[Mar 14 16:13:39] DEBUG[43] res_pjsip/pjsip_distributor.c: Found serializer pjsip/distributor-00000043 on dialog dlg0x7f3ac40390b8
[Mar 14 16:13:39] DEBUG[44] res_pjsip/pjsip_message_ip_updater.c: Re-wrote Contact URI host/port to 172.17.0.2:5060
[Mar 14 16:13:39] DEBUG[44] netsock2.c: Splitting '206.126.62.175' into...
[Mar 14 16:13:39] DEBUG[44] netsock2.c: ...host '206.126.62.175' and port ''.
[Mar 14 16:13:39] DEBUG[44] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target '206.126.62.175'
[Mar 14 16:13:39] DEBUG[44] res_pjsip/pjsip_resolver.c: Transport type for target '206.126.62.175' is 'UDP'
[Mar 14 16:13:39] DEBUG[44] res_pjsip/pjsip_resolver.c: Target '206.126.62.175' is an IP address, skipping resolution
[Mar 14 16:13:39] DEBUG[44] res_pjsip/pjsip_message_ip_updater.c: Re-wrote Contact URI host/port to 172.17.0.2:5060
[Mar 14 16:13:39] DEBUG[44] netsock2.c: Splitting '206.126.62.175' into...
[Mar 14 16:13:39] DEBUG[44] netsock2.c: ...host '206.126.62.175' and port ''.
[Mar 14 16:13:39] DEBUG[44] res_pjsip_pubsub.c: on_evsub_state called with state ACTIVE
[Mar 14 16:13:39] DEBUG[43] res_pjsip/pjsip_distributor.c: Searching for serializer on dialog dlg0x7f3ac40390b8 for Response msg 200/NOTIFY/cseq=17103 (rdata0x25294f8)
[Mar 14 16:13:39] DEBUG[43] res_pjsip/pjsip_distributor.c: Found serializer pjsip/distributor-00000043 on dialog dlg0x7f3ac40390b8
[Mar 14 16:13:39] DEBUG[43] res_pjsip/pjsip_distributor.c: Searching for serializer on dialog dlg0x7f3ab8006db8 for Request msg SUBSCRIBE/cseq=20169 (rdata0x25294f8)
[Mar 14 16:13:39] DEBUG[43] res_pjsip/pjsip_distributor.c: Found serializer pjsip/distributor-00000040 on dialog dlg0x7f3ab8006db8
[Mar 14 16:13:39] DEBUG[44] res_pjsip/pjsip_message_ip_updater.c: Re-wrote Contact URI host/port to 172.17.0.2:5060
[Mar 14 16:13:39] DEBUG[44] netsock2.c: Splitting '206.126.62.175' into...
[Mar 14 16:13:39] DEBUG[44] netsock2.c: ...host '206.126.62.175' and port ''.
[Mar 14 16:13:39] DEBUG[44] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target '206.126.62.175'
[Mar 14 16:13:39] DEBUG[44] res_pjsip/pjsip_resolver.c: Transport type for target '206.126.62.175' is 'UDP'
[Mar 14 16:13:39] DEBUG[44] res_pjsip/pjsip_resolver.c: Target '206.126.62.175' is an IP address, skipping resolution
[Mar 14 16:13:39] DEBUG[44] res_pjsip/pjsip_message_ip_updater.c: Re-wrote Contact URI host/port to 172.17.0.2:5060
[Mar 14 16:13:39] DEBUG[44] netsock2.c: Splitting '206.126.62.175' into...
[Mar 14 16:13:39] DEBUG[44] netsock2.c: ...host '206.126.62.175' and port ''.
[Mar 14 16:13:39] DEBUG[44] res_pjsip_pubsub.c: on_evsub_state called with state ACTIVE
[Mar 14 16:13:39] DEBUG[43] res_pjsip/pjsip_distributor.c: Searching for serializer on dialog dlg0x7f3ab8006db8 for Response msg 200/NOTIFY/cseq=22245 (rdata0x25294f8)
[Mar 14 16:13:39] DEBUG[43] res_pjsip/pjsip_distributor.c: Found serializer pjsip/distributor-00000040 on dialog dlg0x7f3ab8006db8
[Mar 14 16:13:39] DEBUG[43] res_pjsip/pjsip_distributor.c: Searching for serializer on dialog dlg0x7f3ac4034998 for Request msg SUBSCRIBE/cseq=20179 (rdata0x25294f8)
[Mar 14 16:13:39] DEBUG[43] res_pjsip/pjsip_distributor.c: Found serializer pjsip/distributor-00000037 on dialog dlg0x7f3ac4034998
[Mar 14 16:13:39] DEBUG[44] res_pjsip/pjsip_message_ip_updater.c: Re-wrote Contact URI host/port to 172.17.0.2:5060
[Mar 14 16:13:39] DEBUG[44] netsock2.c: Splitting '206.126.62.175' into...
[Mar 14 16:13:39] DEBUG[44] netsock2.c: ...host '206.126.62.175' and port ''.
[Mar 14 16:13:39] DEBUG[44] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target '206.126.62.175'
[Mar 14 16:13:39] DEBUG[44] res_pjsip/pjsip_resolver.c: Transport type for target '206.126.62.175' is 'UDP'
[Mar 14 16:13:39] DEBUG[44] res_pjsip/pjsip_resolver.c: Target '206.126.62.175' is an IP address, skipping resolution
[Mar 14 16:13:39] DEBUG[44] res_pjsip/pjsip_message_ip_updater.c: Re-wrote Contact URI host/port to 172.17.0.2:5060
[Mar 14 16:13:39] DEBUG[44] netsock2.c: Splitting '206.126.62.175' into...
[Mar 14 16:13:39] DEBUG[44] netsock2.c: ...host '206.126.62.175' and port ''.
[Mar 14 16:13:39] DEBUG[44] res_pjsip_pubsub.c: on_evsub_state called with state ACTIVE
[Mar 14 16:13:39] DEBUG[43] res_pjsip/pjsip_distributor.c: Searching for serializer on dialog dlg0x7f3ac4034998 for Response msg 200/NOTIFY/cseq=25864 (rdata0x25294f8)
[Mar 14 16:13:39] DEBUG[43] res_pjsip/pjsip_distributor.c: Found serializer pjsip/distributor-00000037 on dialog dlg0x7f3ac4034998
[Mar 14 16:13:39] DEBUG[57] res_pjsip_sdp_rtp.c: It has been 0 seconds since RTP was last sent on instance 0x7f3ac406d848. Not sending keepalive
[Mar 14 16:13:39] DEBUG[43] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[Mar 14 16:13:39] DEBUG[43] res_pjsip_session.c: The state change pertains to the endpoint '242()'
[Mar 14 16:13:39] DEBUG[43] res_pjsip_session.c: The inv session does NOT have an invite_tsx
[Mar 14 16:13:39] DEBUG[43] res_pjsip_session.c: The UAS BYE transaction involved in this state change is 0x7f3ac40230b8
[Mar 14 16:13:39] DEBUG[43] res_pjsip_session.c: The current transaction state is Terminated
[Mar 14 16:13:39] DEBUG[43] res_pjsip_session.c: The transaction state change event is TIMER
[Mar 14 16:13:39] DEBUG[43] res_pjsip_session.c: The current inv state is DISCONNCTD
[Mar 14 16:13:39] DEBUG[44] res_pjsip_session.c: Destroying SIP session with endpoint 242
[Mar 14 16:13:39] DEBUG[44] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7f3ac403e8f8'
[Mar 14 16:13:39] DEBUG[44] rtp_engine.c: Destroyed RTP instance '0x7f3ac403e8f8'
[Mar 14 16:13:39] DEBUG[44] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7f3ac406c0b8'
[Mar 14 16:13:39] DEBUG[44] rtp_engine.c: Destroyed RTP instance '0x7f3ac406c0b8'
[Mar 14 16:13:39] DEBUG[63] manager.c: Examining AMI event:
Event: RTCPSent
Privilege: reporting,all
Channel: PJSIP/242-0000000f
ChannelState: 6
ChannelStateDesc: Up
CallerIDNum: 242
CallerIDName: PBX 242
ConnectedLineNum: <unknown>
ConnectedLineName: <unknown>
Language: en
AccountCode: 
Context: from-internal
Exten: *43
Priority: 3
Uniqueid: 1489508014.33
Linkedid: 1489508014.33
To: 206.126.62.175:12199
From: 172.17.0.2:16609
SSRC: 0x7ac61f81
PT: 200(SR)
ReportCount: 1
SentNTP: 1489508019.991672
SentRTP: 2455028880
SentPackets: 219
SentOctets: 34881
Report0SourceSSRC: 0xcd9df59b
Report0FractionLost: 0
Report0CumulativeLost: 0
Report0HighestSequence: 2696
Report0SequenceNumberCycles: 0
Report0IAJitter: 25
Report0LSR: 0
Report0DLSR: 0.0000


[Mar 14 16:13:40] DEBUG[57] res_pjsip_sdp_rtp.c: It has been 0 seconds since RTP was last sent on instance 0x7f3ac406d848. Not sending keepalive
[Mar 14 16:13:40] DEBUG[249] threadpool.c: Worker thread idle timeout reached. Dying.
[Mar 14 16:13:40] DEBUG[15] threadpool.c: Destroying worker thread 71
[Mar 14 16:13:40] DEBUG[255] threadpool.c: Worker thread idle timeout reached. Dying.
[Mar 14 16:13:40] DEBUG[257] threadpool.c: Worker thread idle timeout reached. Dying.
[Mar 14 16:13:40] DEBUG[256] threadpool.c: Worker thread idle timeout reached. Dying.
[Mar 14 16:13:40] DEBUG[41] threadpool.c: Destroying worker thread 79
[Mar 14 16:13:40] DEBUG[254] threadpool.c: Worker thread idle timeout reached. Dying.
[Mar 14 16:13:40] DEBUG[41] threadpool.c: Destroying worker thread 78
[Mar 14 16:13:40] DEBUG[41] threadpool.c: Destroying worker thread 77
[Mar 14 16:13:40] DEBUG[41] threadpool.c: Destroying worker thread 76
[Mar 14 16:13:40] DEBUG[253] threadpool.c: Worker thread idle timeout reached. Dying.
[Mar 14 16:13:40] DEBUG[41] threadpool.c: Destroying worker thread 75
[Mar 14 16:13:41] DEBUG[44] config.c: extract uint from [3] in [0, 4294967295] gives [3](0)
[Mar 14 16:13:41] DEBUG[44] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[Mar 14 16:13:41] DEBUG[44] config.c: extract uint from [2] in [0, 4294967295] gives [2](0)
[Mar 14 16:13:41] DEBUG[44] res_pjsip.c: 0x7f3ac406cee8: Wrapper created
[Mar 14 16:13:41] DEBUG[44] res_pjsip.c: 0x7f3ac406cee8: Set timer to 20000 msec
[Mar 14 16:13:41] DEBUG[44] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target '206.126.62.175'
[Mar 14 16:13:41] DEBUG[44] res_pjsip/pjsip_resolver.c: Transport type for target '206.126.62.175' is 'UDP'
[Mar 14 16:13:41] DEBUG[44] res_pjsip/pjsip_resolver.c: Target '206.126.62.175' is an IP address, skipping resolution
[Mar 14 16:13:41] DEBUG[44] res_pjsip/pjsip_message_ip_updater.c: Re-wrote Contact URI host/port to 172.17.0.2:5060
[Mar 14 16:13:41] DEBUG[44] netsock2.c: Splitting '206.126.62.175' into...
[Mar 14 16:13:41] DEBUG[44] netsock2.c: ...host '206.126.62.175' and port ''.
[Mar 14 16:13:41] DEBUG[43] res_pjsip/pjsip_distributor.c: No dialog serializer for response Response msg 200/OPTIONS/cseq=9330 (rdata0x25294f8). Using request transaction as basis
[Mar 14 16:13:41] DEBUG[43] res_pjsip/pjsip_distributor.c: Found serializer pjsip/default-0000000d on transaction tsx0x7f3ac0006278
[Mar 14 16:13:41] DEBUG[44] res_pjsip.c: 0x7f3ac406cee8: PJSIP tsx response received
[Mar 14 16:13:41] DEBUG[44] res_pjsip.c: 0x7f3ac406cee8: Cancelling timer
[Mar 14 16:13:41] DEBUG[44] res_pjsip.c: 0x7f3ac406cee8: Timer cancelled
[Mar 14 16:13:41] DEBUG[44] config.c: extract uint from [3] in [0, 4294967295] gives [3](0)
[Mar 14 16:13:41] DEBUG[44] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[Mar 14 16:13:41] DEBUG[44] config.c: extract uint from [2] in [0, 4294967295] gives [2](0)
[Mar 14 16:13:41] DEBUG[44] res_pjsip.c: 0x7f3ac406cee8: Callbacks executed
[Mar 14 16:13:41] DEBUG[44] res_pjsip.c: 0x7f3ac406cee8: wrapper destroyed
[Mar 14 16:13:41] DEBUG[49] res_pjsip/pjsip_configuration.c: Contact 143/sip:424242143@206.126.62.175:5060 status didn't change: Reachable, RTT: 31.589 msec
[Mar 14 16:13:41] DEBUG[57] res_pjsip_sdp_rtp.c: It has been 0 seconds since RTP was last sent on instance 0x7f3ac406d848. Not sending keepalive
[Mar 14 16:13:42] DEBUG[57] res_pjsip_sdp_rtp.c: It has been 0 seconds since RTP was last sent on instance 0x7f3ac406d848. Not sending keepalive
[Mar 14 16:13:43] DEBUG[57] res_pjsip_sdp_rtp.c: It has been 0 seconds since RTP was last sent on instance 0x7f3ac406d848. Not sending keepalive
[Mar 14 16:13:44] DEBUG[44] config.c: extract uint from [3] in [0, 4294967295] gives [3](0)
[Mar 14 16:13:44] DEBUG[44] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[Mar 14 16:13:44] DEBUG[44] config.c: extract uint from [2] in [0, 4294967295] gives [2](0)
[Mar 14 16:13:44] DEBUG[44] res_pjsip.c: 0x7f3ac4029e18: Wrapper created
[Mar 14 16:13:44] DEBUG[44] res_pjsip.c: 0x7f3ac4029e18: Set timer to 20000 msec
[Mar 14 16:13:44] DEBUG[44] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target '206.126.62.175'
[Mar 14 16:13:44] DEBUG[44] res_pjsip/pjsip_resolver.c: Transport type for target '206.126.62.175' is 'UDP'
[Mar 14 16:13:44] DEBUG[44] res_pjsip/pjsip_resolver.c: Target '206.126.62.175' is an IP address, skipping resolution
[Mar 14 16:13:44] DEBUG[44] res_pjsip/pjsip_message_ip_updater.c: Re-wrote Contact URI host/port to 172.17.0.2:5060
[Mar 14 16:13:44] DEBUG[44] netsock2.c: Splitting '206.126.62.175' into...
[Mar 14 16:13:44] DEBUG[44] netsock2.c: ...host '206.126.62.175' and port ''.
[Mar 14 16:13:44] DEBUG[43] res_pjsip/pjsip_distributor.c: No dialog serializer for response Response msg 200/OPTIONS/cseq=31361 (rdata0x25294f8). Using request transaction as basis
[Mar 14 16:13:44] DEBUG[43] res_pjsip/pjsip_distributor.c: Found serializer pjsip/default-0000000e on transaction tsx0x7f3ac40228a8
[Mar 14 16:13:44] DEBUG[44] res_pjsip.c: 0x7f3ac4029e18: PJSIP tsx response received
[Mar 14 16:13:44] DEBUG[44] res_pjsip.c: 0x7f3ac4029e18: Cancelling timer
[Mar 14 16:13:44] DEBUG[44] res_pjsip.c: 0x7f3ac4029e18: Timer cancelled
[Mar 14 16:13:44] DEBUG[44] config.c: extract uint from [3] in [0, 4294967295] gives [3](0)
[Mar 14 16:13:44] DEBUG[44] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[Mar 14 16:13:44] DEBUG[44] config.c: extract uint from [2] in [0, 4294967295] gives [2](0)
[Mar 14 16:13:44] DEBUG[44] res_pjsip.c: 0x7f3ac4029e18: Callbacks executed
[Mar 14 16:13:44] DEBUG[44] res_pjsip.c: 0x7f3ac4029e18: wrapper destroyed
[Mar 14 16:13:44] DEBUG[49] res_pjsip/pjsip_configuration.c: Contact 100/sip:424242100@206.126.62.175:5060 status didn't change: Reachable, RTT: 25.141 msec
[Mar 14 16:13:44] DEBUG[57] res_pjsip_sdp_rtp.c: It has been 0 seconds since RTP was last sent on instance 0x7f3ac406d848. Not sending keepalive