[Mar 14 15:57:37] DEBUG[44] res_pjsip.c: 0x7f3abc0013f8: wrapper destroyed
[Mar 14 15:57:37] DEBUG[49] res_pjsip/pjsip_configuration.c: Contact 242/sip:424242242@206.126.62.175:5060 status didn't change: Reachable, RTT: 111.226 msec
[Mar 14 15:57:39] DEBUG[63] manager.c: Running action 'Ping'
[Mar 14 15:57:45] DEBUG[43] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[Mar 14 15:57:45] DEBUG[43] res_pjsip_session.c: inv_session 0x7f3ac403a198 has no ast session
[Mar 14 15:57:45] DEBUG[43] res_pjsip_session.c: The inv session does NOT have an invite_tsx
[Mar 14 15:57:45] DEBUG[43] res_pjsip_session.c: The UAS CANCEL transaction involved in this state change is 0x7f3ac000b498
[Mar 14 15:57:45] DEBUG[43] res_pjsip_session.c: The current transaction state is Terminated
[Mar 14 15:57:45] DEBUG[43] res_pjsip_session.c: The transaction state change event is TIMER
[Mar 14 15:57:45] DEBUG[43] res_pjsip_session.c: The current inv state is DISCONNCTD
[Mar 14 15:57:51] DEBUG[43] res_pjsip/pjsip_distributor.c: Could not find matching transaction for Request msg INVITE/cseq=31 (rdata0x2a91848)
[Mar 14 15:57:51] DEBUG[43] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-00000027 to use for Request msg INVITE/cseq=31 (rdata0x2a91848)
[Mar 14 15:57:51] DEBUG[44] res_pjsip_endpoint_identifier_ip.c: No identify sections to match against
[Mar 14 15:57:51] DEBUG[44] res_pjsip_endpoint_identifier_user.c: Attempting identify by From username '242' domain 'sip.primevox.net'
[Mar 14 15:57:51] DEBUG[44] res_pjsip_endpoint_identifier_user.c: Identified by From username '242' domain 'sip.primevox.net'
[Mar 14 15:57:51] DEBUG[44] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-00000027 to use for Request msg INVITE/cseq=31 (rdata0x25294f8)
[Mar 14 15:57:51] DEBUG[44] res_pjsip_session.c: Negotiating incoming SDP media stream 'audio' using audio SDP handler
[Mar 14 15:57:51] DEBUG[44] netsock2.c: Splitting '206.126.62.175' into...
[Mar 14 15:57:51] DEBUG[44] netsock2.c: ...host '206.126.62.175' and port ''.
[Mar 14 15:57:51] DEBUG[44] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x7f3ac400d758'
[Mar 14 15:57:51] 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 15:57:51] DEBUG[44] res_rtp_asterisk.c: Allocated port 11796 for RTP instance '0x7f3ac400d758'
[Mar 14 15:57:51] DEBUG[44] res_rtp_asterisk.c: Creating ICE session [::]:11796 (11796) for RTP instance '0x7f3ac400d758'
[Mar 14 15:57:51] DEBUG[44] netsock2.c: Splitting 'fe80::42:acff:fe11:2' into...
[Mar 14 15:57:51] DEBUG[44] netsock2.c: ...host 'fe80::42:acff:fe11:2' and port ''.
[Mar 14 15:57:51] DEBUG[44] netsock2.c: Splitting 'fe80::42:acff:fe11:2' into...
[Mar 14 15:57:51] DEBUG[44] netsock2.c: ...host 'fe80::42:acff:fe11:2' and port ''.
[Mar 14 15:57:51] DEBUG[44] netsock2.c: Splitting '172.17.0.2' into...
[Mar 14 15:57:51] DEBUG[44] netsock2.c: ...host '172.17.0.2' and port ''.
[Mar 14 15:57:51] DEBUG[44] netsock2.c: Splitting '172.17.0.2' into...
[Mar 14 15:57:51] DEBUG[44] netsock2.c: ...host '172.17.0.2' and port ''.
[Mar 14 15:57:51] DEBUG[44] rtp_engine.c: RTP instance '0x7f3ac400d758' is setup and ready to go
[Mar 14 15:57:51] DEBUG[44] netsock2.c: Splitting 'd24d6d931b44' into...
[Mar 14 15:57:51] DEBUG[44] netsock2.c: ...host 'd24d6d931b44' and port ''.
[Mar 14 15:57:51] DEBUG[44] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x7f3ac400d758'
[Mar 14 15:57:51] DEBUG[44] netsock2.c: Splitting 'fe80::42:acff:fe11:2' into...
[Mar 14 15:57:51] DEBUG[44] netsock2.c: ...host 'fe80::42:acff:fe11:2' and port ''.
[Mar 14 15:57:51] DEBUG[44] netsock2.c: Splitting 'fe80::42:acff:fe11:2' into...
[Mar 14 15:57:51] DEBUG[44] netsock2.c: ...host 'fe80::42:acff:fe11:2' and port ''.
[Mar 14 15:57:51] DEBUG[44] netsock2.c: Splitting '172.17.0.2' into...
[Mar 14 15:57:51] DEBUG[44] netsock2.c: ...host '172.17.0.2' and port ''.
[Mar 14 15:57:51] DEBUG[44] netsock2.c: Splitting '172.17.0.2' into...
[Mar 14 15:57:51] DEBUG[44] netsock2.c: ...host '172.17.0.2' and port ''.
[Mar 14 15:57:51] DEBUG[44] rtp_engine.c: Setting tx payload type 0 based on m type on 0x7f3add5763b0
[Mar 14 15:57:51] DEBUG[44] rtp_engine.c: Setting tx payload type 8 based on m type on 0x7f3add5763b0
[Mar 14 15:57:51] DEBUG[44] rtp_engine.c: Setting tx payload type 101 based on m type on 0x7f3add5763b0
[Mar 14 15:57:51] DEBUG[44] rtp_engine.c: Crossover copying tx to rx payload mapping 0 (0x7f3ac403a390) from 0x7f3add5763b0 to 0x7f3add5763b0
[Mar 14 15:57:51] DEBUG[44] rtp_engine.c: Crossover copying tx to rx payload mapping 8 (0x7f3ac400daa0) from 0x7f3add5763b0 to 0x7f3add5763b0
[Mar 14 15:57:51] DEBUG[44] rtp_engine.c: Crossover copying tx to rx payload mapping 101 (0x2340b30) from 0x7f3add5763b0 to 0x7f3add5763b0
[Mar 14 15:57:51] DEBUG[44] rtp_engine.c: Copying rx payload mapping 0 (0x7f3ac403a390) from 0x7f3add5763b0 to 0x7f3ac400d920
[Mar 14 15:57:51] DEBUG[44] rtp_engine.c: Copying rx payload mapping 8 (0x7f3ac400daa0) from 0x7f3add5763b0 to 0x7f3ac400d920
[Mar 14 15:57:51] DEBUG[44] rtp_engine.c: Copying rx payload mapping 101 (0x2340b30) from 0x7f3add5763b0 to 0x7f3ac400d920
[Mar 14 15:57:51] DEBUG[44] rtp_engine.c: Copying tx payload mapping 0 (0x7f3ac403a390) from 0x7f3add5763b0 to 0x7f3ac400d920
[Mar 14 15:57:51] DEBUG[44] rtp_engine.c: Copying tx payload mapping 8 (0x7f3ac400daa0) from 0x7f3add5763b0 to 0x7f3ac400d920
[Mar 14 15:57:51] DEBUG[44] rtp_engine.c: Copying tx payload mapping 101 (0x2340b30) from 0x7f3add5763b0 to 0x7f3ac400d920
[Mar 14 15:57:51] DEBUG[44] res_pjsip_session.c: Media stream 'audio' handled by audio
[Mar 14 15:57:51] DEBUG[44] res_pjsip_t38.c: Not creating outgoing SDP stream: T.38 not enabled
[Mar 14 15:57:51] DEBUG[44] res_pjsip_session.c: Method is INVITE, Response is 100 Trying
[Mar 14 15:57:51] DEBUG[44] netsock2.c: Splitting '206.126.62.175' into...
[Mar 14 15:57:51] DEBUG[44] netsock2.c: ...host '206.126.62.175' and port ''.
[Mar 14 15:57:51] DEBUG[44] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE
[Mar 14 15:57:51] DEBUG[44] res_pjsip_session.c: The state change pertains to the endpoint '242()'
[Mar 14 15:57:51] DEBUG[44] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f3ac400f368)
[Mar 14 15:57:51] DEBUG[44] res_pjsip_session.c: There is no transaction involved in this state change
[Mar 14 15:57:51] DEBUG[44] res_pjsip_session.c: The current inv state is INCOMING
[Mar 14 15:57:51] DEBUG[44] res_pjsip_session.c: Source of transaction state change is TX_MSG
[Mar 14 15:57:51] DEBUG[44] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[Mar 14 15:57:51] DEBUG[44] res_pjsip_session.c: The state change pertains to the endpoint '242()'
[Mar 14 15:57:51] DEBUG[44] res_pjsip_session.c: The inv session still has an invite_tsx (0x7f3ac400f368)
[Mar 14 15:57:51] DEBUG[44] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x7f3ac400f368
[Mar 14 15:57:51] DEBUG[44] res_pjsip_session.c: The current transaction state is Proceeding
[Mar 14 15:57:51] DEBUG[44] res_pjsip_session.c: The transaction state change event is TX_MSG
[Mar 14 15:57:51] DEBUG[44] res_pjsip_session.c: The current inv state is INCOMING
[Mar 14 15:57:51] DEBUG[44] res_pjsip_session.c: Method is INVITE
[Mar 14 15:57:51] DEBUG[16] threadpool.c: Increasing threadpool stasis-core's size by 1
[Mar 14 15:57:51] DEBUG[63] manager.c: Examining AMI event:
Event: Newchannel
Privilege: call,all
Channel: PJSIP/242-00000001
ChannelState: 4
ChannelStateDesc: Ring
CallerIDNum: 242
CallerIDName: PBX 242
ConnectedLineNum: <unknown>
ConnectedLineName: <unknown>
Language: en
AccountCode: 
Context: from-internal
Exten: *43
Priority: 1
Uniqueid: 1489507071.3
Linkedid: 1489507071.3


[Mar 14 15:57:51] DEBUG[44] chan_pjsip.c: Started PBX on new PJSIP channel PJSIP/242-00000001
[Mar 14 15:57:51] DEBUG[113][C-00000002] pbx.c: Launching 'NoOp'
[Mar 14 15:57:51] DEBUG[113][C-00000002] pbx.c: Launching 'Echo'
[Mar 14 15:57:51] DEBUG[63] manager.c: Examining AMI event:
Event: Newexten
Privilege: call,all
Channel: PJSIP/242-00000001
ChannelState: 4
ChannelStateDesc: Ring
CallerIDNum: 242
CallerIDName: PBX 242
ConnectedLineNum: <unknown>
ConnectedLineName: <unknown>
Language: en
AccountCode: 
Context: from-internal
Exten: *43
Priority: 1
Uniqueid: 1489507071.3
Linkedid: 1489507071.3
Extension: *43
Application: NoOp
AppData: 


[Mar 14 15:57:51] DEBUG[63] manager.c: Examining AMI event:
Event: Newexten
Privilege: call,all
Channel: PJSIP/242-00000001
ChannelState: 4
ChannelStateDesc: Ring
CallerIDNum: 242
CallerIDName: PBX 242
ConnectedLineNum: <unknown>
ConnectedLineName: <unknown>
Language: en
AccountCode: 
Context: from-internal
Exten: *43
Priority: 2
Uniqueid: 1489507071.3
Linkedid: 1489507071.3
Extension: *43
Application: Echo
AppData: 


[Mar 14 15:57:51] DEBUG[44] config.c: extract uint from [3] in [0, 4294967295] gives [3](0)
[Mar 14 15:57:51] DEBUG[44] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[Mar 14 15:57:51] DEBUG[44] config.c: extract uint from [2] in [0, 4294967295] gives [2](0)
[Mar 14 15:57:51] DEBUG[44] res_pjsip.c: 0x7f3ac40322e8: Wrapper created
[Mar 14 15:57:51] DEBUG[44] res_pjsip.c: 0x7f3ac40322e8: Set timer to 20000 msec
[Mar 14 15:57:51] DEBUG[44] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target '206.126.62.175'
[Mar 14 15:57:51] DEBUG[44] res_pjsip/pjsip_resolver.c: Transport type for target '206.126.62.175' is 'UDP'
[Mar 14 15:57:51] DEBUG[44] res_pjsip/pjsip_resolver.c: Target '206.126.62.175' is an IP address, skipping resolution
[Mar 14 15:57:51] DEBUG[44] res_pjsip/pjsip_message_ip_updater.c: Re-wrote Contact URI host/port to 172.17.0.2:5060
[Mar 14 15:57:51] DEBUG[44] netsock2.c: Splitting '206.126.62.175' into...
[Mar 14 15:57:51] DEBUG[44] netsock2.c: ...host '206.126.62.175' and port ''.
[Mar 14 15:57:51] DEBUG[41] threadpool.c: Increasing threadpool SIP's size by 5
[Mar 14 15:57:51] DEBUG[44] config.c: extract uint from [3] in [0, 4294967295] gives [3](0)
[Mar 14 15:57:51] DEBUG[44] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[Mar 14 15:57:51] DEBUG[44] config.c: extract uint from [2] in [0, 4294967295] gives [2](0)
[Mar 14 15:57:51] DEBUG[45] config.c: extract uint from [3] in [0, 4294967295] gives [3](0)
[Mar 14 15:57:51] DEBUG[45] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[Mar 14 15:57:51] DEBUG[45] config.c: extract uint from [2] in [0, 4294967295] gives [2](0)
[Mar 14 15:57:51] DEBUG[44] res_pjsip.c: 0x7f3ac400ff48: Wrapper created
[Mar 14 15:57:51] DEBUG[44] res_pjsip.c: 0x7f3ac400ff48: Set timer to 20000 msec
[Mar 14 15:57:51] DEBUG[45] res_pjsip.c: 0x7f3ac8004318: Wrapper created
[Mar 14 15:57:51] DEBUG[45] res_pjsip.c: 0x7f3ac8004318: Set timer to 20000 msec
[Mar 14 15:57:51] DEBUG[44] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target '206.126.62.175'
[Mar 14 15:57:51] DEBUG[44] res_pjsip/pjsip_resolver.c: Transport type for target '206.126.62.175' is 'UDP'
[Mar 14 15:57:51] DEBUG[45] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target '206.126.62.175'
[Mar 14 15:57:51] DEBUG[44] res_pjsip/pjsip_resolver.c: Target '206.126.62.175' is an IP address, skipping resolution
[Mar 14 15:57:51] DEBUG[45] res_pjsip/pjsip_resolver.c: Transport type for target '206.126.62.175' is 'UDP'
[Mar 14 15:57:51] DEBUG[45] res_pjsip/pjsip_resolver.c: Target '206.126.62.175' is an IP address, skipping resolution
[Mar 14 15:57:51] DEBUG[46] config.c: extract uint from [3] in [0, 4294967295] gives [3](0)
[Mar 14 15:57:51] DEBUG[44] res_pjsip/pjsip_message_ip_updater.c: Re-wrote Contact URI host/port to 172.17.0.2:5060
[Mar 14 15:57:51] DEBUG[46] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[Mar 14 15:57:51] DEBUG[46] config.c: extract uint from [2] in [0, 4294967295] gives [2](0)
[Mar 14 15:57:51] DEBUG[44] netsock2.c: Splitting '206.126.62.175' into...
[Mar 14 15:57:51] DEBUG[45] res_pjsip/pjsip_message_ip_updater.c: Re-wrote Contact URI host/port to 172.17.0.2:5060
[Mar 14 15:57:51] DEBUG[44] netsock2.c: ...host '206.126.62.175' and port ''.
[Mar 14 15:57:51] DEBUG[46] res_pjsip.c: 0x7f3abc001868: Wrapper created
[Mar 14 15:57:51] DEBUG[45] netsock2.c: Splitting '206.126.62.175' into...
[Mar 14 15:57:51] DEBUG[46] res_pjsip.c: 0x7f3abc001868: Set timer to 20000 msec
[Mar 14 15:57:51] DEBUG[45] netsock2.c: ...host '206.126.62.175' and port ''.
[Mar 14 15:57:51] DEBUG[46] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target '206.126.62.175'
[Mar 14 15:57:51] DEBUG[114] config.c: extract uint from [3] in [0, 4294967295] gives [3](0)
[Mar 14 15:57:51] DEBUG[46] res_pjsip/pjsip_resolver.c: Transport type for target '206.126.62.175' is 'UDP'
[Mar 14 15:57:51] DEBUG[114] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[Mar 14 15:57:51] DEBUG[46] res_pjsip/pjsip_resolver.c: Target '206.126.62.175' is an IP address, skipping resolution
[Mar 14 15:57:51] DEBUG[114] config.c: extract uint from [2] in [0, 4294967295] gives [2](0)
[Mar 14 15:57:51] DEBUG[114] res_pjsip.c: 0x7f3ab4008288: Wrapper created
[Mar 14 15:57:51] DEBUG[114] res_pjsip.c: 0x7f3ab4008288: Set timer to 20000 msec
[Mar 14 15:57:51] DEBUG[46] res_pjsip/pjsip_message_ip_updater.c: Re-wrote Contact URI host/port to 172.17.0.2:5060
[Mar 14 15:57:51] DEBUG[46] netsock2.c: Splitting '206.126.62.175' into...
[Mar 14 15:57:51] DEBUG[46] netsock2.c: ...host '206.126.62.175' and port ''.
[Mar 14 15:57:51] DEBUG[114] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target '206.126.62.175'
[Mar 14 15:57:51] DEBUG[114] res_pjsip/pjsip_resolver.c: Transport type for target '206.126.62.175' is 'UDP'
[Mar 14 15:57:51] DEBUG[114] res_pjsip/pjsip_resolver.c: Target '206.126.62.175' is an IP address, skipping resolution
[Mar 14 15:57:51] DEBUG[114] res_pjsip/pjsip_message_ip_updater.c: Re-wrote Contact URI host/port to 172.17.0.2:5060
[Mar 14 15:57:51] DEBUG[114] netsock2.c: Splitting '206.126.62.175' into...
[Mar 14 15:57:51] DEBUG[114] netsock2.c: ...host '206.126.62.175' and port ''.
[Mar 14 15:57:51] DEBUG[44] config.c: extract uint from [3] in [0, 4294967295] gives [3](0)
[Mar 14 15:57:51] DEBUG[44] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[Mar 14 15:57:51] DEBUG[44] config.c: extract uint from [2] in [0, 4294967295] gives [2](0)
[Mar 14 15:57:51] DEBUG[44] res_pjsip.c: 0x7f3ac4003588: Wrapper created
[Mar 14 15:57:51] DEBUG[44] res_pjsip.c: 0x7f3ac4003588: Set timer to 20000 msec
[Mar 14 15:57:51] DEBUG[44] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target '206.126.62.175'
[Mar 14 15:57:51] DEBUG[44] res_pjsip/pjsip_resolver.c: Transport type for target '206.126.62.175' is 'UDP'
[Mar 14 15:57:51] DEBUG[44] res_pjsip/pjsip_resolver.c: Target '206.126.62.175' is an IP address, skipping resolution
[Mar 14 15:57:51] DEBUG[44] res_pjsip/pjsip_message_ip_updater.c: Re-wrote Contact URI host/port to 172.17.0.2:5060
[Mar 14 15:57:51] DEBUG[44] netsock2.c: Splitting '206.126.62.175' into...
[Mar 14 15:57:51] DEBUG[44] netsock2.c: ...host '206.126.62.175' and port ''.
[Mar 14 15:57:52] DEBUG[43] res_pjsip/pjsip_distributor.c: No dialog serializer for response Response msg 200/OPTIONS/cseq=50530 (rdata0x2a91848). Using request transaction as basis
[Mar 14 15:57:52] DEBUG[43] res_pjsip/pjsip_distributor.c: Found serializer pjsip/default-0000000e on transaction tsx0x7f3ac00104f8
[Mar 14 15:57:52] DEBUG[44] res_pjsip.c: 0x7f3ac40322e8: PJSIP tsx response received
[Mar 14 15:57:52] DEBUG[44] res_pjsip.c: 0x7f3ac40322e8: Cancelling timer
[Mar 14 15:57:52] DEBUG[44] res_pjsip.c: 0x7f3ac40322e8: Timer cancelled
[Mar 14 15:57:52] DEBUG[44] config.c: extract uint from [3] in [0, 4294967295] gives [3](0)
[Mar 14 15:57:52] DEBUG[44] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[Mar 14 15:57:52] DEBUG[44] config.c: extract uint from [2] in [0, 4294967295] gives [2](0)
[Mar 14 15:57:52] DEBUG[44] res_pjsip.c: 0x7f3ac40322e8: Callbacks executed
[Mar 14 15:57:52] DEBUG[44] res_pjsip.c: 0x7f3ac40322e8: wrapper destroyed
[Mar 14 15:57:52] DEBUG[49] res_pjsip/pjsip_configuration.c: Contact 100/sip:424242100@206.126.62.175:5060 status didn't change: Reachable, RTT: 57.313 msec
[Mar 14 15:57:52] DEBUG[43] res_pjsip/pjsip_distributor.c: No dialog serializer for response Response msg 200/OPTIONS/cseq=61060 (rdata0x2a91848). Using request transaction as basis
[Mar 14 15:57:52] DEBUG[43] res_pjsip/pjsip_distributor.c: Found serializer pjsip/default-00000012 on transaction tsx0x7f3abc0062d8
[Mar 14 15:57:52] DEBUG[43] res_pjsip/pjsip_distributor.c: No dialog serializer for response Response msg 200/OPTIONS/cseq=14297 (rdata0x2a91848). Using request transaction as basis
[Mar 14 15:57:52] DEBUG[43] res_pjsip/pjsip_distributor.c: Found serializer pjsip/default-0000000f on transaction tsx0x7f3abc000c38
[Mar 14 15:57:52] DEBUG[44] res_pjsip.c: 0x7f3ab4008288: PJSIP tsx response received
[Mar 14 15:57:52] DEBUG[44] res_pjsip.c: 0x7f3ab4008288: Cancelling timer
[Mar 14 15:57:52] DEBUG[44] res_pjsip.c: 0x7f3ab4008288: Timer cancelled
[Mar 14 15:57:52] DEBUG[44] config.c: extract uint from [3] in [0, 4294967295] gives [3](0)
[Mar 14 15:57:52] DEBUG[45] res_pjsip.c: 0x7f3ac400ff48: PJSIP tsx response received
[Mar 14 15:57:52] DEBUG[44] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[Mar 14 15:57:52] DEBUG[45] res_pjsip.c: 0x7f3ac400ff48: Cancelling timer
[Mar 14 15:57:52] DEBUG[44] config.c: extract uint from [2] in [0, 4294967295] gives [2](0)
[Mar 14 15:57:52] DEBUG[45] res_pjsip.c: 0x7f3ac400ff48: Timer cancelled
[Mar 14 15:57:52] DEBUG[44] res_pjsip.c: 0x7f3ab4008288: Callbacks executed
[Mar 14 15:57:52] DEBUG[45] config.c: extract uint from [3] in [0, 4294967295] gives [3](0)
[Mar 14 15:57:52] DEBUG[45] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[Mar 14 15:57:52] DEBUG[44] res_pjsip.c: 0x7f3ab4008288: wrapper destroyed
[Mar 14 15:57:52] DEBUG[45] config.c: extract uint from [2] in [0, 4294967295] gives [2](0)
[Mar 14 15:57:52] DEBUG[45] res_pjsip.c: 0x7f3ac400ff48: Callbacks executed
[Mar 14 15:57:52] DEBUG[45] res_pjsip.c: 0x7f3ac400ff48: wrapper destroyed
[Mar 14 15:57:52] DEBUG[49] res_pjsip/pjsip_configuration.c: Contact 145/sip:424242145@206.126.62.175:5060 status didn't change: Reachable, RTT: 60.868 msec
[Mar 14 15:57:52] DEBUG[49] res_pjsip/pjsip_configuration.c: Contact 142/sip:424242142@206.126.62.175:5060 status didn't change: Reachable, RTT: 61.057 msec
[Mar 14 15:57:52] DEBUG[43] res_pjsip/pjsip_distributor.c: No dialog serializer for response Response msg 200/OPTIONS/cseq=56795 (rdata0x2a91848). Using request transaction as basis
[Mar 14 15:57:52] DEBUG[43] res_pjsip/pjsip_distributor.c: Found serializer pjsip/default-00000010 on transaction tsx0x7f3ac0000c38
[Mar 14 15:57:52] DEBUG[44] res_pjsip.c: 0x7f3ac8004318: PJSIP tsx response received
[Mar 14 15:57:52] DEBUG[44] res_pjsip.c: 0x7f3ac8004318: Cancelling timer
[Mar 14 15:57:52] DEBUG[44] res_pjsip.c: 0x7f3ac8004318: Timer cancelled
[Mar 14 15:57:52] DEBUG[44] config.c: extract uint from [3] in [0, 4294967295] gives [3](0)
[Mar 14 15:57:52] DEBUG[44] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[Mar 14 15:57:52] DEBUG[44] config.c: extract uint from [2] in [0, 4294967295] gives [2](0)
[Mar 14 15:57:52] DEBUG[44] res_pjsip.c: 0x7f3ac8004318: Callbacks executed
[Mar 14 15:57:52] DEBUG[44] res_pjsip.c: 0x7f3ac8004318: wrapper destroyed
[Mar 14 15:57:52] DEBUG[49] res_pjsip/pjsip_configuration.c: Contact 143/sip:424242143@206.126.62.175:5060 status didn't change: Reachable, RTT: 66.716 msec
[Mar 14 15:57:52] DEBUG[43] res_pjsip/pjsip_distributor.c: No dialog serializer for response Response msg 200/OPTIONS/cseq=54058 (rdata0x2a91848). Using request transaction as basis
[Mar 14 15:57:52] DEBUG[43] res_pjsip/pjsip_distributor.c: Found serializer pjsip/default-00000011 on transaction tsx0x7f3abc009d88
[Mar 14 15:57:52] DEBUG[44] res_pjsip.c: 0x7f3abc001868: PJSIP tsx response received
[Mar 14 15:57:52] DEBUG[44] res_pjsip.c: 0x7f3abc001868: Cancelling timer
[Mar 14 15:57:52] DEBUG[44] res_pjsip.c: 0x7f3abc001868: Timer cancelled
[Mar 14 15:57:52] DEBUG[44] config.c: extract uint from [3] in [0, 4294967295] gives [3](0)
[Mar 14 15:57:52] DEBUG[44] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[Mar 14 15:57:52] DEBUG[44] config.c: extract uint from [2] in [0, 4294967295] gives [2](0)
[Mar 14 15:57:52] DEBUG[44] res_pjsip.c: 0x7f3abc001868: Callbacks executed
[Mar 14 15:57:52] DEBUG[44] res_pjsip.c: 0x7f3abc001868: wrapper destroyed
[Mar 14 15:57:52] DEBUG[49] res_pjsip/pjsip_configuration.c: Contact 144/sip:424242144@206.126.62.175:5060 status didn't change: Reachable, RTT: 72.906 msec
[Mar 14 15:57:52] DEBUG[43] res_pjsip/pjsip_distributor.c: No dialog serializer for response Response msg 200/OPTIONS/cseq=54395 (rdata0x2a91848). Using request transaction as basis
[Mar 14 15:57:52] DEBUG[43] res_pjsip/pjsip_distributor.c: Found serializer pjsip/default-00000013 on transaction tsx0x7f3ac0006278
[Mar 14 15:57:52] DEBUG[44] res_pjsip.c: 0x7f3ac4003588: PJSIP tsx response received
[Mar 14 15:57:52] DEBUG[44] res_pjsip.c: 0x7f3ac4003588: Cancelling timer
[Mar 14 15:57:52] DEBUG[44] res_pjsip.c: 0x7f3ac4003588: Timer cancelled
[Mar 14 15:57:52] DEBUG[44] config.c: extract uint from [3] in [0, 4294967295] gives [3](0)
[Mar 14 15:57:52] DEBUG[44] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[Mar 14 15:57:52] DEBUG[44] config.c: extract uint from [2] in [0, 4294967295] gives [2](0)
[Mar 14 15:57:52] DEBUG[44] res_pjsip.c: 0x7f3ac4003588: Callbacks executed
[Mar 14 15:57:52] DEBUG[44] res_pjsip.c: 0x7f3ac4003588: wrapper destroyed
[Mar 14 15:57:52] DEBUG[49] res_pjsip/pjsip_configuration.c: Contact 242/sip:424242242@206.126.62.175:5060 status didn't change: Reachable, RTT: 106.658 msec
[Mar 14 15:57:52] DEBUG[63] manager.c: Running action 'ExtensionStateList'
[Mar 14 15:57:52] DEBUG[63] manager.c: Running action 'PJSIPShowRegistrationInboundContactStatuses'
[Mar 14 15:57:52] DEBUG[63] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0)
[Mar 14 15:57:52] DEBUG[63] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[Mar 14 15:57:52] DEBUG[63] config.c: extract double from [20.000000] in [-inf, inf] gives [20.000000](0)
[Mar 14 15:57:52] DEBUG[63] config.c: extract uint from [15] in [0, 86400] gives [15](0)
[Mar 14 15:57:52] DEBUG[63] config.c: extract uint from [5060] in [0, 4294967295] gives [5060](0)
[Mar 14 15:57:52] DEBUG[63] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0)
[Mar 14 15:57:52] DEBUG[63] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[Mar 14 15:57:52] DEBUG[63] config.c: extract double from [20.000000] in [-inf, inf] gives [20.000000](0)
[Mar 14 15:57:52] DEBUG[63] config.c: extract uint from [15] in [0, 86400] gives [15](0)
[Mar 14 15:57:52] DEBUG[63] config.c: extract uint from [5060] in [0, 4294967295] gives [5060](0)
[Mar 14 15:57:52] DEBUG[63] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0)
[Mar 14 15:57:52] DEBUG[63] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[Mar 14 15:57:52] DEBUG[63] config.c: extract double from [20.000000] in [-inf, inf] gives [20.000000](0)
[Mar 14 15:57:52] DEBUG[63] config.c: extract uint from [15] in [0, 86400] gives [15](0)
[Mar 14 15:57:52] DEBUG[63] config.c: extract uint from [5060] in [0, 4294967295] gives [5060](0)
[Mar 14 15:57:52] DEBUG[63] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0)
[Mar 14 15:57:52] DEBUG[63] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[Mar 14 15:57:52] DEBUG[63] config.c: extract double from [20.000000] in [-inf, inf] gives [20.000000](0)
[Mar 14 15:57:52] DEBUG[63] config.c: extract uint from [15] in [0, 86400] gives [15](0)
[Mar 14 15:57:52] DEBUG[63] config.c: extract uint from [5060] in [0, 4294967295] gives [5060](0)
[Mar 14 15:57:52] DEBUG[63] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0)
[Mar 14 15:57:52] DEBUG[63] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[Mar 14 15:57:52] DEBUG[63] config.c: extract double from [20.000000] in [-inf, inf] gives [20.000000](0)
[Mar 14 15:57:52] DEBUG[63] config.c: extract uint from [15] in [0, 86400] gives [15](0)
[Mar 14 15:57:52] DEBUG[63] config.c: extract uint from [5060] in [0, 4294967295] gives [5060](0)
[Mar 14 15:57:52] DEBUG[63] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0)
[Mar 14 15:57:52] DEBUG[63] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[Mar 14 15:57:52] DEBUG[63] config.c: extract double from [20.000000] in [-inf, inf] gives [20.000000](0)
[Mar 14 15:57:52] DEBUG[63] config.c: extract uint from [15] in [0, 86400] gives [15](0)
[Mar 14 15:57:52] DEBUG[63] config.c: extract uint from [5060] in [0, 4294967295] gives [5060](0)
[Mar 14 15:57:52] DEBUG[63] manager.c: Running action 'Ping'