· 6 years ago · Mar 18, 2020, 01:56 PM
1===================== System Information =====================
2Log file created: 2020-03-18 08:52:34
3Logging started: 2020-03-18 08:44:05
4
5Bria 5 5.6.2 build 99262 label 5600a
6
7Processor:Intel(R) Core(TM) i5-7300U CPU @ 2.60GHz
8Speed: 2600.000000
9Number of cores: 4, Class: Very High (can do HD video)
10Operating System: Windows 10 Professional Edition 64-bit, Version: 10.0.16299
11.NET Version: .NET 4.7.1 (461308)
12Video card: Fast
13GPU Model: Intel(R) HD Graphics 620, Driver Version: 23.20.16.4973
14
15===================== Logs =====================
16Time | Level | Message | Thread ID | Function | Location
172020-03-18 08:43:52.320 | DEBUG | OnStartup: Application Started | 52372 | model::ApplicationModel::writeLogMessage | applicationmodel.cxx:856
182020-03-18 08:43:52.428 | DEBUG | OnStartup: Culture String en-US | 52372 | model::ApplicationModel::writeLogMessage | applicationmodel.cxx:856
192020-03-18 08:43:52.446 | DEBUG | Brand Guid: 3DA0DF4D-8163-4592-B8BB-B497D8FDDCD0 | 52372 | model::ApplicationModel::writeLogMessage | applicationmodel.cxx:856
202020-03-18 08:43:52.450 | DEBUG | value of IMClient.imClsid =3da0df4d-8163-4592-b8bb-b497d8fddcd0 | 52372 | model::ApplicationModel::writeLogMessage | applicationmodel.cxx:856
212020-03-18 08:43:55.609 | DEBUG | AutoUpdateServiceImpl::shouldAttemptAutoInstall - Checking if there's an update file we've already downloaded waiting for us to update to. | 52372 | cpcjam::AutoUpdateServiceImpl::shouldAttemptAutoInstall | autoupdateserviceimpl.cxx:1217
222020-03-18 08:43:55.612 | DEBUG | SocketServer::run - feature:socket_api:listen_host127.0.0.1 | 51804 | cpcjam::api::SocketServer::run | socketserver.cxx:56
232020-03-18 08:43:55.612 | DEBUG | SocketServer::run - feature:socket_api:listen_port9002 | 51804 | cpcjam::api::SocketServer::run | socketserver.cxx:57
242020-03-18 08:43:55.612 | DEBUG | SocketServer::run - feature:socket_api:api_resourcecounterpath/socketapi/ | 51804 | cpcjam::api::SocketServer::run | socketserver.cxx:58
252020-03-18 08:43:55.612 | DEBUG | SocketServer::run - feature:socket_api:api_versionv1 | 51804 | cpcjam::api::SocketServer::run | socketserver.cxx:59
262020-03-18 08:43:55.616 | DEBUG | Initializing. | 52372 | model::ApplicationModel::onInitializationStatus | applicationmodel.cxx:271
272020-03-18 08:43:56.822 | DEBUG | Starting, with login: 1 | 52372 | model::ApplicationModel::start | applicationmodel.cxx:125
282020-03-18 08:43:56.823 | DEBUG | Found license keys in file. | 52372 | model::ApplicationModel::readLicenseKeys | applicationmodel.cxx:700
292020-03-18 08:44:06.287 | ERROR | 99262 Entered AudioInterface::queryDeviceList() | 46608 | CPCAPI2 | AudioInterface.cpp:75
302020-03-18 08:44:07.032 | WARNING | 99262 PhoneInterface::registerInterface: current thread is not sdk, module LdapManagerInterface thread 52372 | 46608 | CPCAPI2 | PhoneInterface.cpp:1097
312020-03-18 08:44:07.615 | ERROR | 99262 peer certificate validation failure: self signed certificate | 46608 | CPCAPI2 | tlsopensslbase.cpp:324
322020-03-18 08:44:16.751 | WARNING | 99262 WARNING ; (13:44:16:750 | 365) AUDIO DEVICE: -1; 24720; AudioDeviceWindowsCore::~AudioDeviceWindowsCore() the Avrt DLL module is now unloaded | 46608 | CPCAPI2 | MediaStackLog.cpp:53
332020-03-18 08:48:14.337 | ERROR | 99262 connect(): already connected | 46608 | CPCAPI2 | XmppAccountImpl.cpp:949
342020-03-18 08:48:14.337 | ERROR | 99262 Firing XmppAccountImpl error: Already connected | 46608 | CPCAPI2 | XmppAccountImpl.cpp:174
352020-03-18 08:48:58.041 | DEBUG | 99262 APICALL ; (13:48:58: 40 | 9999) VOICE: 1 99; 50716; GetSpeakerVolume() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
362020-03-18 08:48:58.062 | INFO | 99262 STATEINFO ; (13:48:58: 61 |10021) AUDIO DEVICE: 1 99; 50716; output: volume=122 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
372020-03-18 08:48:58.062 | INFO | 99262 STATEINFO ; (13:48:58: 62 | 0) AUDIO DEVICE: 1 99; 50716; output: maxVolume=255 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
382020-03-18 08:48:58.062 | INFO | 99262 STATEINFO ; (13:48:58: 62 | 0) VOICE: 1 99; 50716; GetSpeakerVolume() => volume=122 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
392020-03-18 08:48:58.062 | DEBUG | 99262 APICALL ; (13:48:58: 62 | 22) VOICE: 1 99; 50716; GetMicVolume() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
402020-03-18 08:48:58.063 | INFO | 99262 STATEINFO ; (13:48:58: 63 | 1) VOICE: 1 99; 50716; GetMicVolume() => volume=249 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
412020-03-18 08:48:59.131 | DEBUG | 99262 100 ticks of process() for account interface | 46608 | CPCAPI2 | SipAccountInterface.cpp:541
422020-03-18 08:49:06.837 | INFO | 99262 Send to TU: TU: DialogUsageManager size=0 DumTimeout::RegistrationRetry: duration=20 seq=1 | 46608 | CPCAPI2 | TuSelector.cxx:70
432020-03-18 08:49:06.837 | INFO | 99262 Transitioning state from 5 to 1 | 46608 | CPCAPI2 | ClientRegistration.cxx:1032
442020-03-18 08:49:06.838 | DEBUG | 99262 SEND: SipReq: REGISTER sterlingcomputers.com tid=916e3f761f0a3145 cseq=2 REGISTER contact=4015 / 2 from(tu) | 46608 | CPCAPI2 | DialogUsageManager.cxx:1035
452020-03-18 08:49:06.838 | DEBUG | 99262 DialogId::DialogId: 99262NWFiMTliNjJhYjk5YWE1YjliMGMxNzIxMzE3YjM0NWM-7efcb36b- | 46608 | CPCAPI2 | DialogId.cxx:50
462020-03-18 08:49:06.838 | DEBUG | 99262 Using outbound proxy: sip:sterlingcomputers.com;lr -> SipReq: REGISTER sterlingcomputers.com tid=916e3f761f0a3145 cseq=2 REGISTER contact=4015 / 2 from(tu) | 46608 | CPCAPI2 | DialogUsageManager.cxx:1170
472020-03-18 08:49:06.838 | DEBUG | 99262 Sending to outbound proxy uri | 46608 | CPCAPI2 | DialogUsageManager.cxx:1204
482020-03-18 08:49:06.841 | INFO | 99262 AccountRegState transition from WaitingToRegister to Registering for mHandle=512 (4015@sterlingcomputers.com) | 46608 | CPCAPI2 | SipAccountImpl.cpp:5277
492020-03-18 08:49:06.841 | INFO | 99262 CPOptionsPingManager::onAccountStatusChanged disabled account 512 | 46608 | CPCAPI2 | CPOptionsPingManager.cpp:57
502020-03-18 08:49:06.841 | DEBUG | 99262 Triggering onAccountStatusChanged callback with status: Status_Registering (4) reason: Reason_None (0) | 46608 | CPCAPI2 | SipAccountImpl.cpp:5287
512020-03-18 08:49:06.841 | DEBUG | 99262 Adding timer: Timer F tid=916e3f761f0a3145 ms=32000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
522020-03-18 08:49:06.841 | DEBUG | 99262 DnsResult::lookup(): 0C326A20 URI: sip:sterlingcomputers.com;lr | 46608 | CPCAPI2 | dnsResult.cxx:260
532020-03-18 08:49:06.843 | DEBUG | 99262 sterlingcomputers.com lookup failed: DNS server returned answer with no data | 46608 | CPCAPI2 | DnsStub.cxx:69
542020-03-18 08:49:06.847 | DEBUG | 99262 _sips._tcp.sterlingcomputers.com lookup failed: Domain name not found | 46608 | CPCAPI2 | DnsStub.cxx:63
552020-03-18 08:49:06.847 | DEBUG | 99262 _sip._tcp.sterlingcomputers.com lookup failed: Domain name not found | 46608 | CPCAPI2 | DnsStub.cxx:63
562020-03-18 08:49:06.847 | DEBUG | 99262 _sip._udp.sterlingcomputers.com (SRV) --> p=10 w=0 vps.sterlingcomputers.com:5060_sip._udp.sterlingcomputers.com (SRV) --> p=20 w=0 vps2.sterlingcomputers.com:5060 | 46608 | CPCAPI2 | DnsStub.cxx:63
572020-03-18 08:49:06.848 | DEBUG | 99262 vps.sterlingcomputers.com(A)--> 18.223.218.156 | 46608 | CPCAPI2 | DnsStub.cxx:45
582020-03-18 08:49:06.850 | DEBUG | 99262 Adding timer: Timer E1 tid=916e3f761f0a3145 ms=500 | 46608 | CPCAPI2 | TimerQueue.cxx:50
592020-03-18 08:49:06.850 | DEBUG | 99262 TransportSelector::transmit: msg: 2FB3E8B8 | 46608 | CPCAPI2 | TransportSelector.cxx:902
602020-03-18 08:49:06.850 | DEBUG | 99262 TransportSelector::findTransportByVia: target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] source: [ V4 0.0.0.0:0 UNKNOWN_TRANSPORT target domain=unspecified mFlowKey=0 ] via: SIP/2.0/ ;branch=z9hG4bK-524287-1---916e3f761f0a3145;rport | 46608 | CPCAPI2 | TransportSelector.cxx:597
612020-03-18 08:49:06.851 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] | 46608 | CPCAPI2 | TransportSelector.cxx:1448
622020-03-18 08:49:06.856 | DEBUG | 99262 Looked up source for destination: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] -> [ V4 192.168.1.124:0 UDP target domain=sterlingcomputers.com mFlowKey=0 ] sent-by= sent-port=0 | 46608 | CPCAPI2 | TransportSelector.cxx:845
632020-03-18 08:49:06.856 | INFO | 99262 Transmitting to [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] tlsDomain= via [ V4 192.168.1.124:62809 UDP target domain=sterlingcomputers.com mFlowKey=0 ]
64
65REGISTER sip:sterlingcomputers.com SIP/2.0
66Via: SIP/2.0/UDP 192.168.1.124:62809;branch=z9hG4bK-524287-1---916e3f761f0a3145;rport
67Max-Forwards: 70
68Contact: <sip:4015@192.168.1.124:62809;rinstance=b7bf87550f6fe3aa>
69To: "Jayne Hettinger"<sip:4015@sterlingcomputers.com>
70From: "Jayne Hettinger"<sip:4015@sterlingcomputers.com>;tag=7efcb36b
71Call-ID: 99262NWFiMTliNjJhYjk5YWE1YjliMGMxNzIxMzE3YjM0NWM
72CSeq: 2 REGISTER
73Expires: 3600
74Allow: OPTIONS, SUBSCRIBE, NOTIFY, INVITE, ACK, CANCEL, BYE, REFER, INFO
75User-Agent: Bria 5 release 5.6.2 stamp 99262
76Content-Length: 0
77
78sigcomp id= | 46608 | CPCAPI2 | TransportSelector.cxx:1269
792020-03-18 08:49:06.931 | DEBUG | Conversation is enabled | 52372 | model::MessageConversationModel::setIsEnabled | messageconversationmodel.cxx:1447
802020-03-18 08:49:06.936 | DEBUG | Totol voice mail count: 0 | 52372 | model::VoicemailModel::updateAccounts | voicemailmodel.cxx:166
812020-03-18 08:49:06.938 | DEBUG | DeviceControlService::setAudioChannel(1) | 52372 | cpcjam::DeviceControlServiceImpl::setAudioChannel | devicecontrolserviceimpl.cxx:392
822020-03-18 08:49:06.938 | DEBUG | Publishing my presence... | 52372 | cpcjam::XmppAccountServiceImpl::publishPresence | accountserviceimpl.cxx:1098
832020-03-18 08:49:06.938 | DEBUG | Custom message: Available | 52372 | cpcjam::XmppAccountServiceImpl::publishPresence | accountserviceimpl.cxx:1100
842020-03-18 08:49:06.940 | DEBUG | 99262 OUT: <presence from="4015@sterlingcomputers.com/54176898702507633505933218" xmlns="jabber:client"><priority>0</priority><status>Available</status><c xmlns="http://jabber.org/protocol/caps" hash="sha-1" node="http://counterpath.com/briavoip" ver="ceroIVHtGBW7tpjvJbQNwsCbhhE="/></presence> | 46608 | CPCAPI2 | XmppStackLog.cpp:47
852020-03-18 08:49:06.991 | DEBUG | 99262 IN: <presence xml:lang="en" to="4015@sterlingcomputers.com/54176898702507633505933218" from="4015@sterlingcomputers.com/54176898702507633505933218"><c xmlns="http://jabber.org/protocol/caps" hash="sha-1" node="http://counterpath.com/briavoip" ver="ceroIVHtGBW7tpjvJbQNwsCbhhE="/><x xmlns="vcard-temp:x:update"/><priority>0</priority><status>Available</status></presence> | 46608 | CPCAPI2 | XmppStackLog.cpp:32
862020-03-18 08:49:06.992 | DEBUG | 99262 CPCAPI2 >> handleSelfPresence status:0 Note: Available | 46608 | CPCAPI2 | XmppRosterImpl.cpp:476
872020-03-18 08:49:06.992 | DEBUG | 99262 XmppVCardManagerImpl::handlePresence(): 0C333A74 duplicate VcardUpdate hash () for jid: 4015@sterlingcomputers.com/54176898702507633505933218 | 46608 | CPCAPI2 | XmppVCardManagerImpl.cpp:313
882020-03-18 08:49:07.026 | INFO | Self presence udpated | 52372 | model::SelfPresenceModel::updatePresenceStatus | selfpresencemodel.cxx:819
892020-03-18 08:49:07.351 | DEBUG | 99262 Adding timer: Timer E1 tid=916e3f761f0a3145 ms=1000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
902020-03-18 08:49:07.351 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] transport key: 1 | 46608 | CPCAPI2 | TransportSelector.cxx:1432
912020-03-18 08:49:07.810 | DEBUG | SocketServer::onMessage - GET /status
92User-Agent: Jabra Direct
93Transaction-ID: 240
94Content-Type: application/xml
95Content-Length: 87
96
97<?xml version="1.0" encoding="utf-8" ?>
98<status>
99 <type>callOptions</type>
100</status> | 51804 | cpcjam::api::SocketServer::onMessage | socketserver.cxx:151
1012020-03-18 08:49:07.913 | DEBUG | [148567392] Api incoming request: GET /status
102User-Agent: Jabra Direct
103Transaction-ID: 240
104Content-Type: application/xml
105Content-Length: 87
106
107<?xml version="1.0" encoding="utf-8" ?>
108<status>
109 <type>callOptions</type>
110</status> | 52372 | cpcjam::ApiServiceImplConcrete::process | apiserviceimplconcrete.cxx:570
1112020-03-18 08:49:08.132 | DEBUG | 99262 APICALL ; (13:49: 8:131 |10070) VOICE: 1 99; 50716; GetSpeakerVolume() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
1122020-03-18 08:49:08.150 | INFO | 99262 STATEINFO ; (13:49: 8:148 |10086) AUDIO DEVICE: 1 99; 50716; output: volume=122 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
1132020-03-18 08:49:08.150 | INFO | 99262 STATEINFO ; (13:49: 8:149 | 1) AUDIO DEVICE: 1 99; 50716; output: maxVolume=255 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
1142020-03-18 08:49:08.151 | INFO | 99262 STATEINFO ; (13:49: 8:149 | 0) VOICE: 1 99; 50716; GetSpeakerVolume() => volume=122 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
1152020-03-18 08:49:08.151 | DEBUG | 99262 APICALL ; (13:49: 8:149 | 18) VOICE: 1 99; 50716; GetMicVolume() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
1162020-03-18 08:49:08.152 | INFO | 99262 STATEINFO ; (13:49: 8:150 | 1) VOICE: 1 99; 50716; GetMicVolume() => volume=249 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
1172020-03-18 08:49:08.352 | DEBUG | 99262 Adding timer: Timer E1 tid=916e3f761f0a3145 ms=2000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
1182020-03-18 08:49:08.352 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] transport key: 1 | 46608 | CPCAPI2 | TransportSelector.cxx:1432
1192020-03-18 08:49:10.208 | DEBUG | 99262 100 ticks of process() for account interface | 46608 | CPCAPI2 | SipAccountInterface.cpp:541
1202020-03-18 08:49:10.353 | DEBUG | 99262 Adding timer: Timer E1 tid=916e3f761f0a3145 ms=4000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
1212020-03-18 08:49:10.353 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] transport key: 1 | 46608 | CPCAPI2 | TransportSelector.cxx:1432
1222020-03-18 08:49:14.353 | DEBUG | 99262 Adding timer: Timer E1 tid=916e3f761f0a3145 ms=4000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
1232020-03-18 08:49:14.353 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] transport key: 1 | 46608 | CPCAPI2 | TransportSelector.cxx:1432
1242020-03-18 08:49:18.162 | DEBUG | 99262 APICALL ; (13:49:18:160 |10011) VOICE: 1 99; 50716; GetSpeakerVolume() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
1252020-03-18 08:49:18.168 | INFO | 99262 STATEINFO ; (13:49:18:168 |10017) AUDIO DEVICE: 1 99; 50716; output: volume=122 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
1262020-03-18 08:49:18.168 | INFO | 99262 STATEINFO ; (13:49:18:168 | 0) AUDIO DEVICE: 1 99; 50716; output: maxVolume=255 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
1272020-03-18 08:49:18.168 | INFO | 99262 STATEINFO ; (13:49:18:168 | 0) VOICE: 1 99; 50716; GetSpeakerVolume() => volume=122 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
1282020-03-18 08:49:18.169 | DEBUG | 99262 APICALL ; (13:49:18:169 | 8) VOICE: 1 99; 50716; GetMicVolume() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
1292020-03-18 08:49:18.169 | INFO | 99262 STATEINFO ; (13:49:18:169 | 1) VOICE: 1 99; 50716; GetMicVolume() => volume=249 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
1302020-03-18 08:49:18.353 | DEBUG | 99262 Adding timer: Timer E1 tid=916e3f761f0a3145 ms=4000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
1312020-03-18 08:49:18.353 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] transport key: 1 | 46608 | CPCAPI2 | TransportSelector.cxx:1432
1322020-03-18 08:49:19.696 | INFO | 99262 NetworkChangeManager_Polling - sendNetworkChangeEvent (1 interfaces) | 46608 | CPCAPI2 | NetworkChangeManager_Polling.cpp:74
1332020-03-18 08:49:19.708 | DEBUG | 99262 Current network interfaces: 192.168.1.124 | 46608 | CPCAPI2 | SipAccountInterface.cpp:558
1342020-03-18 08:49:19.708 | INFO | 99262 handleNetworkChangeEvent - unrestricted network | 46608 | CPCAPI2 | SipAccountInterface.cpp:779
1352020-03-18 08:49:19.708 | DEBUG | 99262 handleNetworkChangeEvent - refreshing on unrestricted network, transport=1 | 46608 | CPCAPI2 | SipAccountInterface.cpp:782
1362020-03-18 08:49:19.709 | DEBUG | 99262 AresDns::reInit(): : ares 0C378F50 channel 0C311A90 group 00000000 TID: 50716 | 46608 | CPCAPI2 | AresDns.cxx:678
1372020-03-18 08:49:19.709 | INFO | 99262 AresDns::init(): : ares 0C378F50 channel 0C311A90 group 00000000 TID: 50716 | 46608 | CPCAPI2 | AresDns.cxx:370
1382020-03-18 08:49:19.709 | INFO | 99262 AresDns::internalInit(): destroying channel 0C311A90 with server count 1 : ares 0C378F50 channel 0C311A90 group 00000000 | 46608 | CPCAPI2 | AresDns.cxx:473
1392020-03-18 08:49:19.709 | DEBUG | 99262 AresDns::destroyPollItems(): TID: 50716 Owner TID: 50716 | 46608 | CPCAPI2 | AresDns.cxx:810
1402020-03-18 08:49:19.709 | DEBUG | 99262 AresDns::destroyChannel(): destroy channel: 0C311A90 with server count 1 : ares 0C378F50 channel 0C311A90 group 00000000 TID: 50716 | 46608 | CPCAPI2 | AresDns.cxx:843
1412020-03-18 08:49:19.714 | DEBUG | 99262 AresDns::internalInit(): channel init at 0C3327C8 with 1 name servers found : ares 0C378F50 channel 0C3327C8 group 00000000 | 46608 | CPCAPI2 | AresDns.cxx:615
1422020-03-18 08:49:19.714 | DEBUG | 99262 AresDns::internalInit(): name server: 192.168.1.1 : ares 0C378F50 channel 0C3327C8 group 00000000 | 46608 | CPCAPI2 | AresDns.cxx:627
1432020-03-18 08:49:19.714 | INFO | 99262 refreshRegOnNetworkChangeImpl() mHandle=512 mEnabled=1 | 46608 | CPCAPI2 | SipAccountImpl.cpp:1608
1442020-03-18 08:49:19.714 | INFO | 99262 Stop registration retry timer mHandle=512 | 46608 | CPCAPI2 | SipAccountImpl.cpp:2672
1452020-03-18 08:49:19.714 | DEBUG | 99262 closeTransportConnections 512 | 46608 | CPCAPI2 | SipAccountImpl.cpp:2529
1462020-03-18 08:49:19.714 | DEBUG | 99262 Stopping existing registration - currently not registered | 46608 | CPCAPI2 | SipAccountImpl.cpp:1664
1472020-03-18 08:49:19.714 | INFO | 99262 DialogUsageManager::stopRegistration() have 1 dialogs | 46608 | CPCAPI2 | DialogUsageManager.cxx:562
1482020-03-18 08:49:19.714 | DEBUG | 99262 DialogUsageManager::stopRegistration() DialgSetId:99262NWFiMTliNjJhYjk5YWE1YjliMGMxNzIxMzE3YjM0NWM-7efcb36b | 46608 | CPCAPI2 | DialogUsageManager.cxx:566
1492020-03-18 08:49:19.714 | INFO | 99262 DialogUsageManager::stopRegistration() DialgSetId:99262NWFiMTliNjJhYjk5YWE1YjliMGMxNzIxMzE3YjM0NWM-7efcb36b is a registration dialog | 46608 | CPCAPI2 | DialogUsageManager.cxx:575
1502020-03-18 08:49:19.714 | DEBUG | 99262 SipAccountImpl::updateTransportStatus(): Current IP Version is V4 | 46608 | CPCAPI2 | SipAccountImpl.cpp:2379
1512020-03-18 08:49:19.714 | DEBUG | 99262 SipAccountImpl::sendStunQueryIfRequired(): mHandle=512 mEnabled=1 | 46608 | CPCAPI2 | SipAccountImpl.cpp:2028
1522020-03-18 08:49:19.715 | DEBUG | 99262 SipAccountImpl::sendRegisterOrProbeIfRequired(): mHandle=512 mEnabled=1 | 46608 | CPCAPI2 | SipAccountImpl.cpp:2003
1532020-03-18 08:49:19.715 | DEBUG | 99262 SipAccountImpl::autoIpVersion(): Active network transport: 1 SIP account setting list size: 1 | 46608 | CPCAPI2 | SipAccountImpl.cpp:1806
1542020-03-18 08:49:19.715 | DEBUG | 99262 SipAccountImpl::autoIpVersion(): IP version set to: V4 in SIP account settings not set to auto for: 1 | 46608 | CPCAPI2 | SipAccountImpl.cpp:1813
1552020-03-18 08:49:19.715 | INFO | 99262 SipAccountImpl::removeRestriction | 46608 | CPCAPI2 | SipAccountImpl.cpp:4953
1562020-03-18 08:49:19.715 | INFO | 99262 SipAccountImpl::removeRestriction mHandle=512 tried to remove account restriction NetworkRestriction but it doesn't exist | 46608 | CPCAPI2 | SipAccountImpl.cpp:4957
1572020-03-18 08:49:19.715 | DEBUG | 99262 handleNetworkChangeEvent transport = 1 | 46608 | CPCAPI2 | XmppAccountInterface.cpp:538
1582020-03-18 08:49:19.715 | DEBUG | 99262 handleNetworkChangeEvent - unrestricted network | 46608 | CPCAPI2 | XmppAccountInterface.cpp:554
1592020-03-18 08:49:19.715 | DEBUG | 99262 XmppAccountImpl::removeRestriction mHandle=512 restriction=NetworkRestriction | 46608 | CPCAPI2 | XmppAccountImpl.cpp:1378
1602020-03-18 08:49:19.720 | DEBUG | 99262 target: [ V4 66.172.239.5:5222 TCP target domain=presence.sterlingcomputers.com mFlowKey=0 ], preferred interface: 192.168.1.124, old interface: 192.168.1.124 | 46608 | CPCAPI2 | XmppAccountImpl.cpp:1403
1612020-03-18 08:49:19.720 | INFO | 99262 Network interface switch is not required | 46608 | CPCAPI2 | XmppAccountImpl.cpp:1413
1622020-03-18 08:49:19.720 | ERROR | 99262 connect(): already connected | 46608 | CPCAPI2 | XmppAccountImpl.cpp:949
1632020-03-18 08:49:19.720 | ERROR | 99262 Firing XmppAccountImpl error: Already connected | 46608 | CPCAPI2 | XmppAccountImpl.cpp:174
1642020-03-18 08:49:19.720 | DEBUG | 99262 Settings:usePingKeepAlive=0, keepAliveTime=120 | 46608 | CPCAPI2 | XmppAccountImpl.cpp:913
1652020-03-18 08:49:19.721 | DEBUG | 99262 ClientRegistration::~ClientRegistration | 46608 | CPCAPI2 | ClientRegistration.cxx:73
1662020-03-18 08:49:19.721 | DEBUG | 99262 ********** DialogSet::~DialogSet: 99262NWFiMTliNjJhYjk5YWE1YjliMGMxNzIxMzE3YjM0NWM-7efcb36b************* | 46608 | CPCAPI2 | DialogSet.cxx:124
1672020-03-18 08:49:20.214 | DEBUG | 99262 SipAccountImpl::sendRegisterOrProbeIfRequiredImpl(): mHandle=512 mEnabled=1 | 46608 | CPCAPI2 | SipAccountImpl.cpp:1858
1682020-03-18 08:49:20.214 | DEBUG | 99262 BaseCreator::makeInitialRequest:
169
1700C6C8060 | 46608 | CPCAPI2 | BaseCreator.cxx:149
1712020-03-18 08:49:20.214 | INFO | 99262 You really should consider setting an instance id in the UserProfile (see UserProfile::setInstanceId()). This is really easy, and makes this class much less likely to clash with another endpoint registering at the same AOR. | 46608 | CPCAPI2 | ClientRegistration.cxx:781
1722020-03-18 08:49:20.214 | DEBUG | 99262 RegistrationCreator::RegistrationCreator: 0C6C8060 | 46608 | CPCAPI2 | RegistrationCreator.cxx:32
1732020-03-18 08:49:20.215 | DEBUG | 99262 ************* Created DialogSet(UAC) -- 99262Njk5ZGY2MmY2YjI3NDYwMzFhMDMxYmJiNjA4NGU4Y2Q-9230f359************* | 46608 | CPCAPI2 | DialogSet.cxx:52
1742020-03-18 08:49:20.215 | DEBUG | 99262 DialogUsageManager::makeUacDialogSet(): Dialog list size: 1 | 46608 | CPCAPI2 | DialogUsageManager.cxx:556
1752020-03-18 08:49:20.215 | INFO | 99262 Detected only 2 network changes in specified interval; setting new registration expiry to (bigval) 3600 | 46608 | CPCAPI2 | SipAccountImpl.cpp:1702
1762020-03-18 08:49:20.215 | DEBUG | 99262 SipAccountImpl::autoIpVersion(): Active network transport: 1 SIP account setting list size: 1 | 46608 | CPCAPI2 | SipAccountImpl.cpp:1806
1772020-03-18 08:49:20.215 | DEBUG | 99262 SipAccountImpl::autoIpVersion(): IP version set to: V4 in SIP account settings not set to auto for: 1 | 46608 | CPCAPI2 | SipAccountImpl.cpp:1813
1782020-03-18 08:49:20.215 | DEBUG | 99262 SEND: SipReq: REGISTER sterlingcomputers.com tid=aa44dd63138dae34 cseq=1 REGISTER contact=4015 / 1 from(tu) | 46608 | CPCAPI2 | DialogUsageManager.cxx:1035
1792020-03-18 08:49:20.215 | DEBUG | 99262 DialogId::DialogId: 99262Njk5ZGY2MmY2YjI3NDYwMzFhMDMxYmJiNjA4NGU4Y2Q-9230f359- | 46608 | CPCAPI2 | DialogId.cxx:50
1802020-03-18 08:49:20.215 | DEBUG | 99262 Using outbound proxy: sip:sterlingcomputers.com;lr -> SipReq: REGISTER sterlingcomputers.com tid=aa44dd63138dae34 cseq=1 REGISTER contact=4015 / 1 from(tu) | 46608 | CPCAPI2 | DialogUsageManager.cxx:1170
1812020-03-18 08:49:20.215 | DEBUG | 99262 Sending to outbound proxy uri | 46608 | CPCAPI2 | DialogUsageManager.cxx:1204
1822020-03-18 08:49:20.216 | DEBUG | 99262 Adding timer: Timer F tid=aa44dd63138dae34 ms=32000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
1832020-03-18 08:49:20.216 | DEBUG | 99262 DnsResult::lookup(): 0C325D78 URI: sip:sterlingcomputers.com;lr | 46608 | CPCAPI2 | dnsResult.cxx:260
1842020-03-18 08:49:20.217 | INFO | 99262 sterlingcomputers.com not cached. Doing external dns lookup | 46608 | CPCAPI2 | DnsStub.cxx:611
1852020-03-18 08:49:20.364 | INFO | 99262 AresDns::resip_AresDns_aresCallback | 46608 | CPCAPI2 | AresDns.cxx:1093
1862020-03-18 08:49:20.364 | DEBUG | 99262 sterlingcomputers.com lookup failed: DNS server returned answer with no data | 46608 | CPCAPI2 | DnsStub.cxx:69
1872020-03-18 08:49:20.367 | INFO | 99262 _sips._tcp.sterlingcomputers.com not cached. Doing external dns lookup | 46608 | CPCAPI2 | DnsStub.cxx:611
1882020-03-18 08:49:20.370 | INFO | 99262 _sip._tcp.sterlingcomputers.com not cached. Doing external dns lookup | 46608 | CPCAPI2 | DnsStub.cxx:611
1892020-03-18 08:49:20.373 | INFO | 99262 _sip._udp.sterlingcomputers.com not cached. Doing external dns lookup | 46608 | CPCAPI2 | DnsStub.cxx:611
1902020-03-18 08:49:20.405 | INFO | 99262 AresDns::resip_AresDns_aresCallback | 46608 | CPCAPI2 | AresDns.cxx:1093
1912020-03-18 08:49:20.405 | DEBUG | 99262 _sips._tcp.sterlingcomputers.com lookup failed: Domain name not found | 46608 | CPCAPI2 | DnsStub.cxx:63
1922020-03-18 08:49:20.405 | INFO | 99262 AresDns::resip_AresDns_aresCallback | 46608 | CPCAPI2 | AresDns.cxx:1093
1932020-03-18 08:49:20.405 | DEBUG | 99262 _sip._tcp.sterlingcomputers.com lookup failed: Domain name not found | 46608 | CPCAPI2 | DnsStub.cxx:63
1942020-03-18 08:49:20.405 | INFO | 99262 AresDns::resip_AresDns_aresCallback | 46608 | CPCAPI2 | AresDns.cxx:1093
1952020-03-18 08:49:20.407 | DEBUG | 99262 _sip._udp.sterlingcomputers.com (SRV) --> p=20 w=0 vps2.sterlingcomputers.com:5060_sip._udp.sterlingcomputers.com (SRV) --> p=10 w=0 vps.sterlingcomputers.com:5060 | 46608 | CPCAPI2 | DnsStub.cxx:63
1962020-03-18 08:49:20.408 | INFO | 99262 vps.sterlingcomputers.com not cached. Doing external dns lookup | 46608 | CPCAPI2 | DnsStub.cxx:611
1972020-03-18 08:49:20.413 | INFO | 99262 AresDns::resip_AresDns_aresCallback | 46608 | CPCAPI2 | AresDns.cxx:1093
1982020-03-18 08:49:20.413 | DEBUG | 99262 vps.sterlingcomputers.com(A)--> 18.223.218.156 | 46608 | CPCAPI2 | DnsStub.cxx:45
1992020-03-18 08:49:20.415 | DEBUG | 99262 Adding timer: Timer E1 tid=aa44dd63138dae34 ms=500 | 46608 | CPCAPI2 | TimerQueue.cxx:50
2002020-03-18 08:49:20.415 | DEBUG | 99262 TransportSelector::transmit: msg: 0C6C9F98 | 46608 | CPCAPI2 | TransportSelector.cxx:902
2012020-03-18 08:49:20.415 | DEBUG | 99262 TransportSelector::findTransportByVia: target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] source: [ V4 0.0.0.0:0 UNKNOWN_TRANSPORT target domain=unspecified mFlowKey=0 ] via: SIP/2.0/ ;branch=z9hG4bK-524287-1---aa44dd63138dae34;rport | 46608 | CPCAPI2 | TransportSelector.cxx:597
2022020-03-18 08:49:20.415 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] | 46608 | CPCAPI2 | TransportSelector.cxx:1448
2032020-03-18 08:49:20.426 | DEBUG | 99262 Looked up source for destination: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] -> [ V4 192.168.1.124:0 UDP target domain=sterlingcomputers.com mFlowKey=0 ] sent-by= sent-port=0 | 46608 | CPCAPI2 | TransportSelector.cxx:845
2042020-03-18 08:49:20.427 | INFO | 99262 Transmitting to [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] tlsDomain= via [ V4 192.168.1.124:62809 UDP target domain=sterlingcomputers.com mFlowKey=0 ]
205
206REGISTER sip:sterlingcomputers.com SIP/2.0
207Via: SIP/2.0/UDP 192.168.1.124:62809;branch=z9hG4bK-524287-1---aa44dd63138dae34;rport
208Max-Forwards: 70
209Contact: <sip:4015@192.168.1.124:62809;rinstance=b7bf87550f6fe3aa>
210To: "Jayne Hettinger"<sip:4015@sterlingcomputers.com>
211From: "Jayne Hettinger"<sip:4015@sterlingcomputers.com>;tag=9230f359
212Call-ID: 99262Njk5ZGY2MmY2YjI3NDYwMzFhMDMxYmJiNjA4NGU4Y2Q
213CSeq: 1 REGISTER
214Expires: 3600
215Allow: OPTIONS, SUBSCRIBE, NOTIFY, INVITE, ACK, CANCEL, BYE, REFER, INFO
216User-Agent: Bria 5 release 5.6.2 stamp 99262
217Content-Length: 0
218
219sigcomp id= | 46608 | CPCAPI2 | TransportSelector.cxx:1269
2202020-03-18 08:49:20.914 | DEBUG | 99262 Adding timer: Timer E1 tid=aa44dd63138dae34 ms=1000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
2212020-03-18 08:49:20.914 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] transport key: 1 | 46608 | CPCAPI2 | TransportSelector.cxx:1432
2222020-03-18 08:49:21.351 | DEBUG | 99262 100 ticks of process() for account interface | 46608 | CPCAPI2 | SipAccountInterface.cpp:541
2232020-03-18 08:49:21.916 | DEBUG | 99262 Adding timer: Timer E1 tid=aa44dd63138dae34 ms=2000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
2242020-03-18 08:49:21.916 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] transport key: 1 | 46608 | CPCAPI2 | TransportSelector.cxx:1432
2252020-03-18 08:49:22.353 | DEBUG | 99262 Adding timer: Timer E1 tid=916e3f761f0a3145 ms=4000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
2262020-03-18 08:49:22.353 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] transport key: 1 | 46608 | CPCAPI2 | TransportSelector.cxx:1432
2272020-03-18 08:49:23.915 | DEBUG | 99262 Adding timer: Timer E1 tid=aa44dd63138dae34 ms=4000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
2282020-03-18 08:49:23.915 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] transport key: 1 | 46608 | CPCAPI2 | TransportSelector.cxx:1432
2292020-03-18 08:49:26.354 | DEBUG | 99262 Adding timer: Timer E1 tid=916e3f761f0a3145 ms=4000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
2302020-03-18 08:49:26.354 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] transport key: 1 | 46608 | CPCAPI2 | TransportSelector.cxx:1432
2312020-03-18 08:49:27.917 | DEBUG | 99262 Adding timer: Timer E1 tid=aa44dd63138dae34 ms=4000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
2322020-03-18 08:49:27.917 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] transport key: 1 | 46608 | CPCAPI2 | TransportSelector.cxx:1432
2332020-03-18 08:49:28.247 | DEBUG | 99262 APICALL ; (13:49:28:246 |10077) VOICE: 1 99; 50716; GetSpeakerVolume() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
2342020-03-18 08:49:28.261 | INFO | 99262 STATEINFO ; (13:49:28:260 |10091) AUDIO DEVICE: 1 99; 50716; output: volume=122 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
2352020-03-18 08:49:28.261 | INFO | 99262 STATEINFO ; (13:49:28:260 | 0) AUDIO DEVICE: 1 99; 50716; output: maxVolume=255 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
2362020-03-18 08:49:28.261 | INFO | 99262 STATEINFO ; (13:49:28:260 | 0) VOICE: 1 99; 50716; GetSpeakerVolume() => volume=122 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
2372020-03-18 08:49:28.261 | DEBUG | 99262 APICALL ; (13:49:28:260 | 14) VOICE: 1 99; 50716; GetMicVolume() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
2382020-03-18 08:49:28.262 | INFO | 99262 STATEINFO ; (13:49:28:261 | 1) VOICE: 1 99; 50716; GetMicVolume() => volume=249 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
2392020-03-18 08:49:30.355 | DEBUG | 99262 Adding timer: Timer E1 tid=916e3f761f0a3145 ms=4000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
2402020-03-18 08:49:30.355 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] transport key: 1 | 46608 | CPCAPI2 | TransportSelector.cxx:1432
2412020-03-18 08:49:31.917 | DEBUG | 99262 Adding timer: Timer E1 tid=aa44dd63138dae34 ms=4000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
2422020-03-18 08:49:31.917 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] transport key: 1 | 46608 | CPCAPI2 | TransportSelector.cxx:1432
2432020-03-18 08:49:32.286 | DEBUG | 99262 100 ticks of process() for account interface | 46608 | CPCAPI2 | SipAccountInterface.cpp:541
2442020-03-18 08:49:34.355 | DEBUG | 99262 Adding timer: Timer E1 tid=916e3f761f0a3145 ms=4000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
2452020-03-18 08:49:34.355 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] transport key: 1 | 46608 | CPCAPI2 | TransportSelector.cxx:1432
2462020-03-18 08:49:35.917 | DEBUG | 99262 Adding timer: Timer E1 tid=aa44dd63138dae34 ms=4000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
2472020-03-18 08:49:35.917 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] transport key: 1 | 46608 | CPCAPI2 | TransportSelector.cxx:1432
2482020-03-18 08:49:38.310 | DEBUG | 99262 APICALL ; (13:49:38:309 |10049) VOICE: 1 99; 50716; GetSpeakerVolume() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
2492020-03-18 08:49:38.327 | INFO | 99262 STATEINFO ; (13:49:38:326 |10065) AUDIO DEVICE: 1 99; 50716; output: volume=122 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
2502020-03-18 08:49:38.327 | INFO | 99262 STATEINFO ; (13:49:38:326 | 0) AUDIO DEVICE: 1 99; 50716; output: maxVolume=255 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
2512020-03-18 08:49:38.327 | INFO | 99262 STATEINFO ; (13:49:38:326 | 0) VOICE: 1 99; 50716; GetSpeakerVolume() => volume=122 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
2522020-03-18 08:49:38.327 | DEBUG | 99262 APICALL ; (13:49:38:326 | 17) VOICE: 1 99; 50716; GetMicVolume() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
2532020-03-18 08:49:38.328 | INFO | 99262 STATEINFO ; (13:49:38:327 | 1) VOICE: 1 99; 50716; GetMicVolume() => volume=249 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
2542020-03-18 08:49:38.357 | DEBUG | 99262 Adding timer: Timer E1 tid=916e3f761f0a3145 ms=4000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
2552020-03-18 08:49:38.357 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] transport key: 1 | 46608 | CPCAPI2 | TransportSelector.cxx:1432
2562020-03-18 08:49:38.843 | DEBUG | 99262 Helper::makeResponse(SipReq: REGISTER sterlingcomputers.com tid=916e3f761f0a3145 cseq=2 REGISTER contact=4015@192.168.1.124:62809 / 2 from(tu) code=408 reason= | 46608 | CPCAPI2 | Helper.cxx:375
2572020-03-18 08:49:38.843 | DEBUG | 99262 Remove vip sterlingcomputers.com(1) | 46608 | CPCAPI2 | dnsResult.cxx:182
2582020-03-18 08:49:38.843 | INFO | 99262 Send to TU: TU: DialogUsageManager size=0 SipResp: 408 tid=916e3f761f0a3145 cseq=2 REGISTER / 2 from(wire) | 46608 | CPCAPI2 | TuSelector.cxx:70
2592020-03-18 08:49:38.844 | INFO | 99262 Got: SipResp: 408 tid=916e3f761f0a3145 cseq=2 REGISTER / 2 from(wire) | 46608 | CPCAPI2 | DialogUsageManager.cxx:1686
2602020-03-18 08:49:38.844 | INFO | 99262 Throwing away stray response:
261
262SipResp: 408 tid=916e3f761f0a3145 cseq=2 REGISTER / 2 from(wire) | 46608 | CPCAPI2 | DialogUsageManager.cxx:2254
2632020-03-18 08:49:39.917 | DEBUG | 99262 Adding timer: Timer E1 tid=aa44dd63138dae34 ms=4000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
2642020-03-18 08:49:39.917 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] transport key: 1 | 46608 | CPCAPI2 | TransportSelector.cxx:1432
2652020-03-18 08:49:43.224 | DEBUG | 99262 100 ticks of process() for account interface | 46608 | CPCAPI2 | SipAccountInterface.cpp:541
2662020-03-18 08:49:43.917 | DEBUG | 99262 Adding timer: Timer E1 tid=aa44dd63138dae34 ms=4000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
2672020-03-18 08:49:43.917 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] transport key: 1 | 46608 | CPCAPI2 | TransportSelector.cxx:1432
2682020-03-18 08:49:47.916 | DEBUG | 99262 Adding timer: Timer E1 tid=aa44dd63138dae34 ms=4000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
2692020-03-18 08:49:47.916 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] transport key: 1 | 46608 | CPCAPI2 | TransportSelector.cxx:1432
2702020-03-18 08:49:48.366 | DEBUG | 99262 APICALL ; (13:49:48:365 |10040) VOICE: 1 99; 50716; GetSpeakerVolume() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
2712020-03-18 08:49:48.373 | INFO | 99262 STATEINFO ; (13:49:48:372 |10046) AUDIO DEVICE: 1 99; 50716; output: volume=122 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
2722020-03-18 08:49:48.373 | INFO | 99262 STATEINFO ; (13:49:48:372 | 0) AUDIO DEVICE: 1 99; 50716; output: maxVolume=255 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
2732020-03-18 08:49:48.374 | INFO | 99262 STATEINFO ; (13:49:48:373 | 1) VOICE: 1 99; 50716; GetSpeakerVolume() => volume=122 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
2742020-03-18 08:49:48.374 | DEBUG | 99262 APICALL ; (13:49:48:373 | 8) VOICE: 1 99; 50716; GetMicVolume() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
2752020-03-18 08:49:48.374 | INFO | 99262 STATEINFO ; (13:49:48:373 | 0) VOICE: 1 99; 50716; GetMicVolume() => volume=249 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
2762020-03-18 08:49:51.917 | DEBUG | 99262 Adding timer: Timer E1 tid=aa44dd63138dae34 ms=4000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
2772020-03-18 08:49:51.917 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] transport key: 1 | 46608 | CPCAPI2 | TransportSelector.cxx:1432
2782020-03-18 08:49:52.217 | DEBUG | 99262 Helper::makeResponse(SipReq: REGISTER sterlingcomputers.com tid=aa44dd63138dae34 cseq=1 REGISTER contact=4015@192.168.1.124:62809 / 1 from(tu) code=408 reason= | 46608 | CPCAPI2 | Helper.cxx:375
2792020-03-18 08:49:52.217 | DEBUG | 99262 Remove vip sterlingcomputers.com(1) | 46608 | CPCAPI2 | dnsResult.cxx:182
2802020-03-18 08:49:52.217 | INFO | 99262 Send to TU: TU: DialogUsageManager size=0 SipResp: 408 tid=aa44dd63138dae34 cseq=1 REGISTER / 1 from(wire) | 46608 | CPCAPI2 | TuSelector.cxx:70
2812020-03-18 08:49:52.217 | INFO | 99262 Got: SipResp: 408 tid=aa44dd63138dae34 cseq=1 REGISTER / 1 from(wire) | 46608 | CPCAPI2 | DialogUsageManager.cxx:1686
2822020-03-18 08:49:52.217 | DEBUG | 99262 DialogUsageManager::processResponse: SipResp: 408 tid=aa44dd63138dae34 cseq=1 REGISTER / 1 from(wire) | 46608 | CPCAPI2 | DialogUsageManager.cxx:2249
2832020-03-18 08:49:52.217 | DEBUG | 99262 DialogId::DialogId: 99262Njk5ZGY2MmY2YjI3NDYwMzFhMDMxYmJiNjA4NGU4Y2Q-9230f359-c441962b | 46608 | CPCAPI2 | DialogId.cxx:50
2842020-03-18 08:49:52.217 | INFO | 99262 SipAccountImpl::onRequestRetry() mHandle=512 handling internally generated message: SipResp: 408 tid=aa44dd63138dae34 cseq=1 REGISTER / 1 from(wire) | 46608 | CPCAPI2 | SipAccountImpl.cpp:3470
2852020-03-18 08:49:52.217 | INFO | 99262 Retry registration due to internally-generated 408/503 from stack in 20 seconds | 46608 | CPCAPI2 | SipAccountImpl.cpp:3566
2862020-03-18 08:49:52.217 | INFO | 99262 AccountRegState transition from Registering to WaitingToRegister for mHandle=512 (4015@sterlingcomputers.com) | 46608 | CPCAPI2 | SipAccountImpl.cpp:3568
2872020-03-18 08:49:52.217 | INFO | 99262 SipAccountImpl::onRequestRetry() mHandle=512 handling internally generated 408 triggered due to dns lookup: SIP/2.0 408 Request Timeout
288Via: SIP/2.0/UDP 192.168.1.124:62809;branch=z9hG4bK-524287-1---aa44dd63138dae34;rport
289To: "Jayne Hettinger"<sip:4015@sterlingcomputers.com>;tag=c441962b
290From: "Jayne Hettinger"<sip:4015@sterlingcomputers.com>;tag=9230f359
291Call-ID: 99262Njk5ZGY2MmY2YjI3NDYwMzFhMDMxYmJiNjA4NGU4Y2Q
292CSeq: 1 REGISTER
293X-Alternatives-Available: true
294Content-Length: 0
295
296 | 46608 | CPCAPI2 | SipAccountImpl.cpp:3581
2972020-03-18 08:49:52.217 | INFO | 99262 CPOptionsPingManager::onAccountStatusChanged disabled account 512 | 46608 | CPCAPI2 | CPOptionsPingManager.cpp:57
2982020-03-18 08:49:52.217 | DEBUG | 99262 Triggering onAccountStatusChanged callback with status: Status_WaitingToRegister (6) reason: Reason_Dns_Lookup (8) | 46608 | CPCAPI2 | SipAccountImpl.cpp:3623
2992020-03-18 08:49:52.217 | DEBUG | 99262 Application requested delayed retry on 408 or internal 503: 20 | 46608 | CPCAPI2 | ClientRegistration.cxx:698
3002020-03-18 08:49:52.217 | INFO | 99262 Transitioning state from 1 to 5 | 46608 | CPCAPI2 | ClientRegistration.cxx:703
3012020-03-18 08:49:52.217 | DEBUG | 99262 Adding application timer: DumTimeout::RegistrationRetry: duration=20 seq=1 ms=20000 | 46608 | CPCAPI2 | TimerQueue.cxx:124
3022020-03-18 08:49:52.318 | DEBUG | Conversation is enabled | 52372 | model::MessageConversationModel::setIsEnabled | messageconversationmodel.cxx:1447
3032020-03-18 08:49:52.320 | DEBUG | Totol voice mail count: 0 | 52372 | model::VoicemailModel::updateAccounts | voicemailmodel.cxx:166
3042020-03-18 08:49:52.321 | DEBUG | DeviceControlService::setAudioChannel(1) | 52372 | cpcjam::DeviceControlServiceImpl::setAudioChannel | devicecontrolserviceimpl.cxx:392
3052020-03-18 08:49:52.321 | DEBUG | Publishing my presence... | 52372 | cpcjam::XmppAccountServiceImpl::publishPresence | accountserviceimpl.cxx:1098
3062020-03-18 08:49:52.321 | DEBUG | Custom message: Available | 52372 | cpcjam::XmppAccountServiceImpl::publishPresence | accountserviceimpl.cxx:1100
3072020-03-18 08:49:52.322 | DEBUG | 99262 OUT: <presence from="4015@sterlingcomputers.com/54176898702507633505933218" xmlns="jabber:client"><priority>0</priority><status>Available</status><c xmlns="http://jabber.org/protocol/caps" hash="sha-1" node="http://counterpath.com/briavoip" ver="ceroIVHtGBW7tpjvJbQNwsCbhhE="/></presence> | 46608 | CPCAPI2 | XmppStackLog.cpp:47
3082020-03-18 08:49:52.390 | INFO | Self presence udpated | 52372 | model::SelfPresenceModel::updatePresenceStatus | selfpresencemodel.cxx:819
3092020-03-18 08:49:52.863 | DEBUG | 99262 IN: <presence xml:lang="en" to="4015@sterlingcomputers.com/54176898702507633505933218" from="4015@sterlingcomputers.com/54176898702507633505933218"><c xmlns="http://jabber.org/protocol/caps" hash="sha-1" node="http://counterpath.com/briavoip" ver="ceroIVHtGBW7tpjvJbQNwsCbhhE="/><x xmlns="vcard-temp:x:update"/><priority>0</priority><status>Available</status></presence> | 46608 | CPCAPI2 | XmppStackLog.cpp:32
3102020-03-18 08:49:52.864 | DEBUG | 99262 CPCAPI2 >> handleSelfPresence status:0 Note: Available | 46608 | CPCAPI2 | XmppRosterImpl.cpp:476
3112020-03-18 08:49:52.864 | DEBUG | 99262 XmppVCardManagerImpl::handlePresence(): 0C333A74 duplicate VcardUpdate hash () for jid: 4015@sterlingcomputers.com/54176898702507633505933218 | 46608 | CPCAPI2 | XmppVCardManagerImpl.cpp:313
3122020-03-18 08:49:53.526 | DEBUG | SocketServer::onMessage - GET /status
313User-Agent: Jabra Direct
314Transaction-ID: 241
315Content-Type: application/xml
316Content-Length: 87
317
318<?xml version="1.0" encoding="utf-8" ?>
319<status>
320 <type>callOptions</type>
321</status> | 51804 | cpcjam::api::SocketServer::onMessage | socketserver.cxx:151
3222020-03-18 08:49:53.631 | DEBUG | [148567392] Api incoming request: GET /status
323User-Agent: Jabra Direct
324Transaction-ID: 241
325Content-Type: application/xml
326Content-Length: 87
327
328<?xml version="1.0" encoding="utf-8" ?>
329<status>
330 <type>callOptions</type>
331</status> | 52372 | cpcjam::ApiServiceImplConcrete::process | apiserviceimplconcrete.cxx:570
3322020-03-18 08:49:54.291 | DEBUG | 99262 100 ticks of process() for account interface | 46608 | CPCAPI2 | SipAccountInterface.cpp:541
3332020-03-18 08:49:58.445 | DEBUG | 99262 APICALL ; (13:49:58:445 |10071) VOICE: 1 99; 50716; GetSpeakerVolume() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
3342020-03-18 08:49:58.456 | INFO | 99262 STATEINFO ; (13:49:58:455 |10081) AUDIO DEVICE: 1 99; 50716; output: volume=122 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
3352020-03-18 08:49:58.456 | INFO | 99262 STATEINFO ; (13:49:58:456 | 1) AUDIO DEVICE: 1 99; 50716; output: maxVolume=255 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
3362020-03-18 08:49:58.456 | INFO | 99262 STATEINFO ; (13:49:58:456 | 0) VOICE: 1 99; 50716; GetSpeakerVolume() => volume=122 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
3372020-03-18 08:49:58.456 | DEBUG | 99262 APICALL ; (13:49:58:456 | 11) VOICE: 1 99; 50716; GetMicVolume() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
3382020-03-18 08:49:58.456 | INFO | 99262 STATEINFO ; (13:49:58:456 | 0) VOICE: 1 99; 50716; GetMicVolume() => volume=249 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
3392020-03-18 08:50:05.226 | DEBUG | 99262 100 ticks of process() for account interface | 46608 | CPCAPI2 | SipAccountInterface.cpp:541
3402020-03-18 08:50:08.512 | DEBUG | 99262 APICALL ; (13:50: 8:506 |10050) VOICE: 1 99; 50716; GetSpeakerVolume() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
3412020-03-18 08:50:08.513 | INFO | 99262 STATEINFO ; (13:50: 8:512 |10056) AUDIO DEVICE: 1 99; 50716; output: volume=122 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
3422020-03-18 08:50:08.513 | INFO | 99262 STATEINFO ; (13:50: 8:512 | 0) AUDIO DEVICE: 1 99; 50716; output: maxVolume=255 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
3432020-03-18 08:50:08.513 | INFO | 99262 STATEINFO ; (13:50: 8:512 | 0) VOICE: 1 99; 50716; GetSpeakerVolume() => volume=122 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
3442020-03-18 08:50:08.513 | DEBUG | 99262 APICALL ; (13:50: 8:512 | 6) VOICE: 1 99; 50716; GetMicVolume() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
3452020-03-18 08:50:08.514 | INFO | 99262 STATEINFO ; (13:50: 8:513 | 1) VOICE: 1 99; 50716; GetMicVolume() => volume=249 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
3462020-03-18 08:50:12.219 | INFO | 99262 Send to TU: TU: DialogUsageManager size=0 DumTimeout::RegistrationRetry: duration=20 seq=1 | 46608 | CPCAPI2 | TuSelector.cxx:70
3472020-03-18 08:50:12.219 | INFO | 99262 Transitioning state from 5 to 1 | 46608 | CPCAPI2 | ClientRegistration.cxx:1032
3482020-03-18 08:50:12.219 | DEBUG | 99262 SEND: SipReq: REGISTER sterlingcomputers.com tid=0e249d4dd2d7f362 cseq=2 REGISTER contact=4015 / 2 from(tu) | 46608 | CPCAPI2 | DialogUsageManager.cxx:1035
3492020-03-18 08:50:12.219 | DEBUG | 99262 DialogId::DialogId: 99262Njk5ZGY2MmY2YjI3NDYwMzFhMDMxYmJiNjA4NGU4Y2Q-9230f359- | 46608 | CPCAPI2 | DialogId.cxx:50
3502020-03-18 08:50:12.219 | DEBUG | 99262 Using outbound proxy: sip:sterlingcomputers.com;lr -> SipReq: REGISTER sterlingcomputers.com tid=0e249d4dd2d7f362 cseq=2 REGISTER contact=4015 / 2 from(tu) | 46608 | CPCAPI2 | DialogUsageManager.cxx:1170
3512020-03-18 08:50:12.219 | DEBUG | 99262 Sending to outbound proxy uri | 46608 | CPCAPI2 | DialogUsageManager.cxx:1204
3522020-03-18 08:50:12.221 | INFO | 99262 AccountRegState transition from WaitingToRegister to Registering for mHandle=512 (4015@sterlingcomputers.com) | 46608 | CPCAPI2 | SipAccountImpl.cpp:5277
3532020-03-18 08:50:12.221 | INFO | 99262 CPOptionsPingManager::onAccountStatusChanged disabled account 512 | 46608 | CPCAPI2 | CPOptionsPingManager.cpp:57
3542020-03-18 08:50:12.221 | DEBUG | 99262 Triggering onAccountStatusChanged callback with status: Status_Registering (4) reason: Reason_None (0) | 46608 | CPCAPI2 | SipAccountImpl.cpp:5287
3552020-03-18 08:50:12.221 | DEBUG | 99262 Adding timer: Timer F tid=0e249d4dd2d7f362 ms=32000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
3562020-03-18 08:50:12.221 | DEBUG | 99262 DnsResult::lookup(): 0C325358 URI: sip:sterlingcomputers.com;lr | 46608 | CPCAPI2 | dnsResult.cxx:260
3572020-03-18 08:50:12.223 | DEBUG | 99262 sterlingcomputers.com lookup failed: DNS server returned answer with no data | 46608 | CPCAPI2 | DnsStub.cxx:69
3582020-03-18 08:50:12.228 | DEBUG | Conversation is enabled | 52372 | model::MessageConversationModel::setIsEnabled | messageconversationmodel.cxx:1447
3592020-03-18 08:50:12.228 | DEBUG | 99262 _sips._tcp.sterlingcomputers.com lookup failed: Domain name not found | 46608 | CPCAPI2 | DnsStub.cxx:63
3602020-03-18 08:50:12.228 | DEBUG | 99262 _sip._tcp.sterlingcomputers.com lookup failed: Domain name not found | 46608 | CPCAPI2 | DnsStub.cxx:63
3612020-03-18 08:50:12.228 | DEBUG | 99262 _sip._udp.sterlingcomputers.com (SRV) --> p=20 w=0 vps2.sterlingcomputers.com:5060_sip._udp.sterlingcomputers.com (SRV) --> p=10 w=0 vps.sterlingcomputers.com:5060 | 46608 | CPCAPI2 | DnsStub.cxx:63
3622020-03-18 08:50:12.228 | DEBUG | 99262 vps.sterlingcomputers.com(A)--> 18.223.218.156 | 46608 | CPCAPI2 | DnsStub.cxx:45
3632020-03-18 08:50:12.230 | INFO | 99262 vps2.sterlingcomputers.com not cached. Doing external dns lookup | 46608 | CPCAPI2 | DnsStub.cxx:611
3642020-03-18 08:50:12.231 | DEBUG | Totol voice mail count: 0 | 52372 | model::VoicemailModel::updateAccounts | voicemailmodel.cxx:166
3652020-03-18 08:50:12.233 | DEBUG | DeviceControlService::setAudioChannel(1) | 52372 | cpcjam::DeviceControlServiceImpl::setAudioChannel | devicecontrolserviceimpl.cxx:392
3662020-03-18 08:50:12.233 | DEBUG | Publishing my presence... | 52372 | cpcjam::XmppAccountServiceImpl::publishPresence | accountserviceimpl.cxx:1098
3672020-03-18 08:50:12.233 | DEBUG | Custom message: Available | 52372 | cpcjam::XmppAccountServiceImpl::publishPresence | accountserviceimpl.cxx:1100
3682020-03-18 08:50:12.236 | DEBUG | 99262 OUT: <presence from="4015@sterlingcomputers.com/54176898702507633505933218" xmlns="jabber:client"><priority>0</priority><status>Available</status><c xmlns="http://jabber.org/protocol/caps" hash="sha-1" node="http://counterpath.com/briavoip" ver="ceroIVHtGBW7tpjvJbQNwsCbhhE="/></presence> | 46608 | CPCAPI2 | XmppStackLog.cpp:47
3692020-03-18 08:50:12.307 | INFO | Self presence udpated | 52372 | model::SelfPresenceModel::updatePresenceStatus | selfpresencemodel.cxx:819
3702020-03-18 08:50:12.674 | DEBUG | 99262 IN: <presence xml:lang="en" to="4015@sterlingcomputers.com/54176898702507633505933218" from="4015@sterlingcomputers.com/54176898702507633505933218"><c xmlns="http://jabber.org/protocol/caps" hash="sha-1" node="http://counterpath.com/briavoip" ver="ceroIVHtGBW7tpjvJbQNwsCbhhE="/><x xmlns="vcard-temp:x:update"/><priority>0</priority><status>Available</status></presence> | 46608 | CPCAPI2 | XmppStackLog.cpp:32
3712020-03-18 08:50:12.675 | DEBUG | 99262 CPCAPI2 >> handleSelfPresence status:0 Note: Available | 46608 | CPCAPI2 | XmppRosterImpl.cpp:476
3722020-03-18 08:50:12.675 | DEBUG | 99262 XmppVCardManagerImpl::handlePresence(): 0C333A74 duplicate VcardUpdate hash () for jid: 4015@sterlingcomputers.com/54176898702507633505933218 | 46608 | CPCAPI2 | XmppVCardManagerImpl.cpp:313
3732020-03-18 08:50:12.805 | INFO | 99262 AresDns::resip_AresDns_aresCallback | 46608 | CPCAPI2 | AresDns.cxx:1093
3742020-03-18 08:50:12.805 | DEBUG | 99262 AresDns::hostFileLookup(): failed for vps2.sterlingcomputers.com : ares 0C378F50 channel 0C3327C8 group 00000000 | 46608 | CPCAPI2 | AresDns.cxx:890
3752020-03-18 08:50:12.805 | DEBUG | 99262 vps2.sterlingcomputers.com lookup failed: Domain name not found | 46608 | CPCAPI2 | DnsStub.cxx:45
3762020-03-18 08:50:12.806 | DEBUG | 99262 Adding timer: Timer E1 tid=0e249d4dd2d7f362 ms=500 | 46608 | CPCAPI2 | TimerQueue.cxx:50
3772020-03-18 08:50:12.807 | DEBUG | 99262 TransportSelector::transmit: msg: 2FBAC040 | 46608 | CPCAPI2 | TransportSelector.cxx:902
3782020-03-18 08:50:12.807 | DEBUG | 99262 TransportSelector::findTransportByVia: target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] source: [ V4 0.0.0.0:0 UNKNOWN_TRANSPORT target domain=unspecified mFlowKey=0 ] via: SIP/2.0/ ;branch=z9hG4bK-524287-1---0e249d4dd2d7f362;rport | 46608 | CPCAPI2 | TransportSelector.cxx:597
3792020-03-18 08:50:12.807 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] | 46608 | CPCAPI2 | TransportSelector.cxx:1448
3802020-03-18 08:50:12.816 | DEBUG | 99262 Looked up source for destination: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] -> [ V4 192.168.1.124:0 UDP target domain=sterlingcomputers.com mFlowKey=0 ] sent-by= sent-port=0 | 46608 | CPCAPI2 | TransportSelector.cxx:845
3812020-03-18 08:50:12.816 | INFO | 99262 Transmitting to [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] tlsDomain= via [ V4 192.168.1.124:62809 UDP target domain=sterlingcomputers.com mFlowKey=0 ]
382
383REGISTER sip:sterlingcomputers.com SIP/2.0
384Via: SIP/2.0/UDP 192.168.1.124:62809;branch=z9hG4bK-524287-1---0e249d4dd2d7f362;rport
385Max-Forwards: 70
386Contact: <sip:4015@192.168.1.124:62809;rinstance=b7bf87550f6fe3aa>
387To: "Jayne Hettinger"<sip:4015@sterlingcomputers.com>
388From: "Jayne Hettinger"<sip:4015@sterlingcomputers.com>;tag=9230f359
389Call-ID: 99262Njk5ZGY2MmY2YjI3NDYwMzFhMDMxYmJiNjA4NGU4Y2Q
390CSeq: 2 REGISTER
391Expires: 3600
392Allow: OPTIONS, SUBSCRIBE, NOTIFY, INVITE, ACK, CANCEL, BYE, REFER, INFO
393User-Agent: Bria 5 release 5.6.2 stamp 99262
394Content-Length: 0
395
396sigcomp id= | 46608 | CPCAPI2 | TransportSelector.cxx:1269
3972020-03-18 08:50:13.179 | DEBUG | SocketServer::onMessage - GET /status
398User-Agent: Jabra Direct
399Transaction-ID: 242
400Content-Type: application/xml
401Content-Length: 87
402
403<?xml version="1.0" encoding="utf-8" ?>
404<status>
405 <type>callOptions</type>
406</status> | 51804 | cpcjam::api::SocketServer::onMessage | socketserver.cxx:151
4072020-03-18 08:50:13.292 | DEBUG | [148567392] Api incoming request: GET /status
408User-Agent: Jabra Direct
409Transaction-ID: 242
410Content-Type: application/xml
411Content-Length: 87
412
413<?xml version="1.0" encoding="utf-8" ?>
414<status>
415 <type>callOptions</type>
416</status> | 52372 | cpcjam::ApiServiceImplConcrete::process | apiserviceimplconcrete.cxx:570
4172020-03-18 08:50:13.307 | DEBUG | 99262 Adding timer: Timer E1 tid=0e249d4dd2d7f362 ms=1000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
4182020-03-18 08:50:13.307 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] transport key: 1 | 46608 | CPCAPI2 | TransportSelector.cxx:1432
4192020-03-18 08:50:14.307 | DEBUG | 99262 Adding timer: Timer E1 tid=0e249d4dd2d7f362 ms=2000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
4202020-03-18 08:50:14.307 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] transport key: 1 | 46608 | CPCAPI2 | TransportSelector.cxx:1432
4212020-03-18 08:50:16.240 | DEBUG | 99262 100 ticks of process() for account interface | 46608 | CPCAPI2 | SipAccountInterface.cpp:541
4222020-03-18 08:50:16.309 | DEBUG | 99262 Adding timer: Timer E1 tid=0e249d4dd2d7f362 ms=4000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
4232020-03-18 08:50:16.309 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] transport key: 1 | 46608 | CPCAPI2 | TransportSelector.cxx:1432
4242020-03-18 08:50:18.540 | DEBUG | 99262 APICALL ; (13:50:18:539 |10027) VOICE: 1 99; 50716; GetSpeakerVolume() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
4252020-03-18 08:50:18.546 | INFO | 99262 STATEINFO ; (13:50:18:545 |10032) AUDIO DEVICE: 1 99; 50716; output: volume=122 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
4262020-03-18 08:50:18.546 | INFO | 99262 STATEINFO ; (13:50:18:545 | 0) AUDIO DEVICE: 1 99; 50716; output: maxVolume=255 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
4272020-03-18 08:50:18.546 | INFO | 99262 STATEINFO ; (13:50:18:545 | 0) VOICE: 1 99; 50716; GetSpeakerVolume() => volume=122 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
4282020-03-18 08:50:18.546 | DEBUG | 99262 APICALL ; (13:50:18:545 | 6) VOICE: 1 99; 50716; GetMicVolume() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
4292020-03-18 08:50:18.546 | INFO | 99262 STATEINFO ; (13:50:18:546 | 1) VOICE: 1 99; 50716; GetMicVolume() => volume=249 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
4302020-03-18 08:50:20.309 | DEBUG | 99262 Adding timer: Timer E1 tid=0e249d4dd2d7f362 ms=4000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
4312020-03-18 08:50:20.309 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] transport key: 1 | 46608 | CPCAPI2 | TransportSelector.cxx:1432
4322020-03-18 08:50:24.309 | DEBUG | 99262 Adding timer: Timer E1 tid=0e249d4dd2d7f362 ms=4000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
4332020-03-18 08:50:24.309 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] transport key: 1 | 46608 | CPCAPI2 | TransportSelector.cxx:1432
4342020-03-18 08:50:27.194 | DEBUG | 99262 100 ticks of process() for account interface | 46608 | CPCAPI2 | SipAccountInterface.cpp:541
4352020-03-18 08:50:28.309 | DEBUG | 99262 Adding timer: Timer E1 tid=0e249d4dd2d7f362 ms=4000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
4362020-03-18 08:50:28.309 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] transport key: 1 | 46608 | CPCAPI2 | TransportSelector.cxx:1432
4372020-03-18 08:50:28.617 | DEBUG | 99262 APICALL ; (13:50:28:616 |10072) VOICE: 1 99; 50716; GetSpeakerVolume() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
4382020-03-18 08:50:28.633 | INFO | 99262 STATEINFO ; (13:50:28:632 |10087) AUDIO DEVICE: 1 99; 50716; output: volume=122 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
4392020-03-18 08:50:28.633 | INFO | 99262 STATEINFO ; (13:50:28:632 | 0) AUDIO DEVICE: 1 99; 50716; output: maxVolume=255 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
4402020-03-18 08:50:28.633 | INFO | 99262 STATEINFO ; (13:50:28:632 | 0) VOICE: 1 99; 50716; GetSpeakerVolume() => volume=122 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
4412020-03-18 08:50:28.633 | DEBUG | 99262 APICALL ; (13:50:28:632 | 16) VOICE: 1 99; 50716; GetMicVolume() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
4422020-03-18 08:50:28.634 | INFO | 99262 STATEINFO ; (13:50:28:633 | 1) VOICE: 1 99; 50716; GetMicVolume() => volume=249 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
4432020-03-18 08:50:32.310 | DEBUG | 99262 Adding timer: Timer E1 tid=0e249d4dd2d7f362 ms=4000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
4442020-03-18 08:50:32.310 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] transport key: 1 | 46608 | CPCAPI2 | TransportSelector.cxx:1432
4452020-03-18 08:50:36.310 | DEBUG | 99262 Adding timer: Timer E1 tid=0e249d4dd2d7f362 ms=4000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
4462020-03-18 08:50:36.310 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] transport key: 1 | 46608 | CPCAPI2 | TransportSelector.cxx:1432
4472020-03-18 08:50:38.132 | DEBUG | 99262 100 ticks of process() for account interface | 46608 | CPCAPI2 | SipAccountInterface.cpp:541
4482020-03-18 08:50:38.678 | DEBUG | 99262 APICALL ; (13:50:38:677 |10045) VOICE: 1 99; 50716; GetSpeakerVolume() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
4492020-03-18 08:50:38.694 | INFO | 99262 STATEINFO ; (13:50:38:693 |10060) AUDIO DEVICE: 1 99; 50716; output: volume=122 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
4502020-03-18 08:50:38.694 | INFO | 99262 STATEINFO ; (13:50:38:693 | 0) AUDIO DEVICE: 1 99; 50716; output: maxVolume=255 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
4512020-03-18 08:50:38.694 | INFO | 99262 STATEINFO ; (13:50:38:693 | 0) VOICE: 1 99; 50716; GetSpeakerVolume() => volume=122 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
4522020-03-18 08:50:38.694 | DEBUG | 99262 APICALL ; (13:50:38:693 | 16) VOICE: 1 99; 50716; GetMicVolume() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
4532020-03-18 08:50:38.695 | INFO | 99262 STATEINFO ; (13:50:38:694 | 1) VOICE: 1 99; 50716; GetMicVolume() => volume=249 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
4542020-03-18 08:50:40.311 | DEBUG | 99262 Adding timer: Timer E1 tid=0e249d4dd2d7f362 ms=4000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
4552020-03-18 08:50:40.311 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] transport key: 1 | 46608 | CPCAPI2 | TransportSelector.cxx:1432
4562020-03-18 08:50:44.221 | DEBUG | 99262 Helper::makeResponse(SipReq: REGISTER sterlingcomputers.com tid=0e249d4dd2d7f362 cseq=2 REGISTER contact=4015@192.168.1.124:62809 / 2 from(tu) code=408 reason= | 46608 | CPCAPI2 | Helper.cxx:375
4572020-03-18 08:50:44.221 | DEBUG | 99262 Remove vip sterlingcomputers.com(1) | 46608 | CPCAPI2 | dnsResult.cxx:182
4582020-03-18 08:50:44.222 | INFO | 99262 Send to TU: TU: DialogUsageManager size=0 SipResp: 408 tid=0e249d4dd2d7f362 cseq=2 REGISTER / 2 from(wire) | 46608 | CPCAPI2 | TuSelector.cxx:70
4592020-03-18 08:50:44.222 | INFO | 99262 Got: SipResp: 408 tid=0e249d4dd2d7f362 cseq=2 REGISTER / 2 from(wire) | 46608 | CPCAPI2 | DialogUsageManager.cxx:1686
4602020-03-18 08:50:44.222 | DEBUG | 99262 DialogUsageManager::processResponse: SipResp: 408 tid=0e249d4dd2d7f362 cseq=2 REGISTER / 2 from(wire) | 46608 | CPCAPI2 | DialogUsageManager.cxx:2249
4612020-03-18 08:50:44.222 | DEBUG | 99262 DialogId::DialogId: 99262Njk5ZGY2MmY2YjI3NDYwMzFhMDMxYmJiNjA4NGU4Y2Q-9230f359-6879b674 | 46608 | CPCAPI2 | DialogId.cxx:50
4622020-03-18 08:50:44.223 | INFO | 99262 SipAccountImpl::onRequestRetry() mHandle=512 handling internally generated message: SipResp: 408 tid=0e249d4dd2d7f362 cseq=2 REGISTER / 2 from(wire) | 46608 | CPCAPI2 | SipAccountImpl.cpp:3470
4632020-03-18 08:50:44.223 | DEBUG | 99262 Failure Re-Registration Interval: 20 Initial Attempts Pending: 1 | 46608 | CPCAPI2 | SipAccountImpl.cpp:5230
4642020-03-18 08:50:44.223 | INFO | 99262 Retry registration due to internally-generated 408/503 from stack in 20 seconds | 46608 | CPCAPI2 | SipAccountImpl.cpp:3566
4652020-03-18 08:50:44.223 | INFO | 99262 AccountRegState transition from Registering to WaitingToRegister for mHandle=512 (4015@sterlingcomputers.com) | 46608 | CPCAPI2 | SipAccountImpl.cpp:3568
4662020-03-18 08:50:44.223 | INFO | 99262 CPOptionsPingManager::onAccountStatusChanged disabled account 512 | 46608 | CPCAPI2 | CPOptionsPingManager.cpp:57
4672020-03-18 08:50:44.223 | DEBUG | 99262 Triggering onAccountStatusChanged callback with status: Status_WaitingToRegister (6) reason: Reason_Local_Timeout (5) | 46608 | CPCAPI2 | SipAccountImpl.cpp:3623
4682020-03-18 08:50:44.223 | DEBUG | 99262 Application requested delayed retry on 408 or internal 503: 20 | 46608 | CPCAPI2 | ClientRegistration.cxx:698
4692020-03-18 08:50:44.223 | INFO | 99262 Transitioning state from 1 to 5 | 46608 | CPCAPI2 | ClientRegistration.cxx:703
4702020-03-18 08:50:44.224 | DEBUG | 99262 Adding application timer: DumTimeout::RegistrationRetry: duration=20 seq=2 ms=20000 | 46608 | CPCAPI2 | TimerQueue.cxx:124
4712020-03-18 08:50:44.255 | DEBUG | Conversation is enabled | 52372 | model::MessageConversationModel::setIsEnabled | messageconversationmodel.cxx:1447
4722020-03-18 08:50:44.260 | DEBUG | Totol voice mail count: 0 | 52372 | model::VoicemailModel::updateAccounts | voicemailmodel.cxx:166
4732020-03-18 08:50:44.261 | DEBUG | DeviceControlService::setAudioChannel(1) | 52372 | cpcjam::DeviceControlServiceImpl::setAudioChannel | devicecontrolserviceimpl.cxx:392
4742020-03-18 08:50:44.261 | DEBUG | Publishing my presence... | 52372 | cpcjam::XmppAccountServiceImpl::publishPresence | accountserviceimpl.cxx:1098
4752020-03-18 08:50:44.262 | DEBUG | Custom message: Available | 52372 | cpcjam::XmppAccountServiceImpl::publishPresence | accountserviceimpl.cxx:1100
4762020-03-18 08:50:44.263 | DEBUG | 99262 OUT: <presence from="4015@sterlingcomputers.com/54176898702507633505933218" xmlns="jabber:client"><priority>0</priority><status>Available</status><c xmlns="http://jabber.org/protocol/caps" hash="sha-1" node="http://counterpath.com/briavoip" ver="ceroIVHtGBW7tpjvJbQNwsCbhhE="/></presence> | 46608 | CPCAPI2 | XmppStackLog.cpp:47
4772020-03-18 08:50:44.359 | INFO | Self presence udpated | 52372 | model::SelfPresenceModel::updatePresenceStatus | selfpresencemodel.cxx:819
4782020-03-18 08:50:45.303 | DEBUG | 99262 IN: <presence xml:lang="en" to="4015@sterlingcomputers.com/54176898702507633505933218" from="4015@sterlingcomputers.com/54176898702507633505933218"><c xmlns="http://jabber.org/protocol/caps" hash="sha-1" node="http://counterpath.com/briavoip" ver="ceroIVHtGBW7tpjvJbQNwsCbhhE="/><x xmlns="vcard-temp:x:update"/><priority>0</priority><status>Available</status></presence> | 46608 | CPCAPI2 | XmppStackLog.cpp:32
4792020-03-18 08:50:45.304 | DEBUG | 99262 CPCAPI2 >> handleSelfPresence status:0 Note: Available | 46608 | CPCAPI2 | XmppRosterImpl.cpp:476
4802020-03-18 08:50:45.304 | DEBUG | 99262 XmppVCardManagerImpl::handlePresence(): 0C333A74 duplicate VcardUpdate hash () for jid: 4015@sterlingcomputers.com/54176898702507633505933218 | 46608 | CPCAPI2 | XmppVCardManagerImpl.cpp:313
4812020-03-18 08:50:45.998 | DEBUG | SocketServer::onMessage - GET /status
482User-Agent: Jabra Direct
483Transaction-ID: 243
484Content-Type: application/xml
485Content-Length: 87
486
487<?xml version="1.0" encoding="utf-8" ?>
488<status>
489 <type>callOptions</type>
490</status> | 51804 | cpcjam::api::SocketServer::onMessage | socketserver.cxx:151
4912020-03-18 08:50:46.100 | DEBUG | [148567392] Api incoming request: GET /status
492User-Agent: Jabra Direct
493Transaction-ID: 243
494Content-Type: application/xml
495Content-Length: 87
496
497<?xml version="1.0" encoding="utf-8" ?>
498<status>
499 <type>callOptions</type>
500</status> | 52372 | cpcjam::ApiServiceImplConcrete::process | apiserviceimplconcrete.cxx:570
5012020-03-18 08:50:48.726 | DEBUG | 99262 APICALL ; (13:50:48:725 |10032) VOICE: 1 99; 50716; GetSpeakerVolume() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
5022020-03-18 08:50:48.736 | INFO | 99262 STATEINFO ; (13:50:48:735 |10041) AUDIO DEVICE: 1 99; 50716; output: volume=122 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
5032020-03-18 08:50:48.736 | INFO | 99262 STATEINFO ; (13:50:48:735 | 0) AUDIO DEVICE: 1 99; 50716; output: maxVolume=255 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
5042020-03-18 08:50:48.736 | INFO | 99262 STATEINFO ; (13:50:48:735 | 0) VOICE: 1 99; 50716; GetSpeakerVolume() => volume=122 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
5052020-03-18 08:50:48.736 | DEBUG | 99262 APICALL ; (13:50:48:735 | 10) VOICE: 1 99; 50716; GetMicVolume() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
5062020-03-18 08:50:48.737 | INFO | 99262 STATEINFO ; (13:50:48:736 | 1) VOICE: 1 99; 50716; GetMicVolume() => volume=249 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
5072020-03-18 08:50:49.165 | DEBUG | 99262 100 ticks of process() for account interface | 46608 | CPCAPI2 | SipAccountInterface.cpp:541
5082020-03-18 08:50:58.805 | DEBUG | 99262 APICALL ; (13:50:58:803 |10068) VOICE: 1 99; 50716; GetSpeakerVolume() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
5092020-03-18 08:50:58.822 | INFO | 99262 STATEINFO ; (13:50:58:821 |10085) AUDIO DEVICE: 1 99; 50716; output: volume=122 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
5102020-03-18 08:50:58.822 | INFO | 99262 STATEINFO ; (13:50:58:822 | 0) AUDIO DEVICE: 1 99; 50716; output: maxVolume=255 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
5112020-03-18 08:50:58.823 | INFO | 99262 STATEINFO ; (13:50:58:822 | 0) VOICE: 1 99; 50716; GetSpeakerVolume() => volume=122 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
5122020-03-18 08:50:58.823 | DEBUG | 99262 APICALL ; (13:50:58:822 | 18) VOICE: 1 99; 50716; GetMicVolume() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
5132020-03-18 08:50:58.824 | INFO | 99262 STATEINFO ; (13:50:58:823 | 1) VOICE: 1 99; 50716; GetMicVolume() => volume=249 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
5142020-03-18 08:51:00.116 | DEBUG | 99262 100 ticks of process() for account interface | 46608 | CPCAPI2 | SipAccountInterface.cpp:541
5152020-03-18 08:51:04.224 | INFO | 99262 Send to TU: TU: DialogUsageManager size=0 DumTimeout::RegistrationRetry: duration=20 seq=2 | 46608 | CPCAPI2 | TuSelector.cxx:70
5162020-03-18 08:51:04.224 | INFO | 99262 Transitioning state from 5 to 1 | 46608 | CPCAPI2 | ClientRegistration.cxx:1032
5172020-03-18 08:51:04.224 | DEBUG | 99262 SEND: SipReq: REGISTER sterlingcomputers.com tid=6f1f743926c02349 cseq=3 REGISTER contact=4015 / 3 from(tu) | 46608 | CPCAPI2 | DialogUsageManager.cxx:1035
5182020-03-18 08:51:04.224 | DEBUG | 99262 DialogId::DialogId: 99262Njk5ZGY2MmY2YjI3NDYwMzFhMDMxYmJiNjA4NGU4Y2Q-9230f359- | 46608 | CPCAPI2 | DialogId.cxx:50
5192020-03-18 08:51:04.224 | DEBUG | 99262 Using outbound proxy: sip:sterlingcomputers.com;lr -> SipReq: REGISTER sterlingcomputers.com tid=6f1f743926c02349 cseq=3 REGISTER contact=4015 / 3 from(tu) | 46608 | CPCAPI2 | DialogUsageManager.cxx:1170
5202020-03-18 08:51:04.224 | DEBUG | 99262 Sending to outbound proxy uri | 46608 | CPCAPI2 | DialogUsageManager.cxx:1204
5212020-03-18 08:51:04.225 | INFO | 99262 AccountRegState transition from WaitingToRegister to Registering for mHandle=512 (4015@sterlingcomputers.com) | 46608 | CPCAPI2 | SipAccountImpl.cpp:5277
5222020-03-18 08:51:04.225 | INFO | 99262 CPOptionsPingManager::onAccountStatusChanged disabled account 512 | 46608 | CPCAPI2 | CPOptionsPingManager.cpp:57
5232020-03-18 08:51:04.225 | DEBUG | 99262 Triggering onAccountStatusChanged callback with status: Status_Registering (4) reason: Reason_None (0) | 46608 | CPCAPI2 | SipAccountImpl.cpp:5287
5242020-03-18 08:51:04.225 | DEBUG | 99262 Adding timer: Timer F tid=6f1f743926c02349 ms=32000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
5252020-03-18 08:51:04.225 | DEBUG | 99262 DnsResult::lookup(): 0C327950 URI: sip:sterlingcomputers.com;lr | 46608 | CPCAPI2 | dnsResult.cxx:260
5262020-03-18 08:51:04.225 | DEBUG | 99262 sterlingcomputers.com lookup failed: DNS server returned answer with no data | 46608 | CPCAPI2 | DnsStub.cxx:69
5272020-03-18 08:51:04.226 | DEBUG | 99262 _sips._tcp.sterlingcomputers.com lookup failed: Domain name not found | 46608 | CPCAPI2 | DnsStub.cxx:63
5282020-03-18 08:51:04.226 | DEBUG | 99262 _sip._tcp.sterlingcomputers.com lookup failed: Domain name not found | 46608 | CPCAPI2 | DnsStub.cxx:63
5292020-03-18 08:51:04.226 | DEBUG | 99262 _sip._udp.sterlingcomputers.com (SRV) --> p=20 w=0 vps2.sterlingcomputers.com:5060_sip._udp.sterlingcomputers.com (SRV) --> p=10 w=0 vps.sterlingcomputers.com:5060 | 46608 | CPCAPI2 | DnsStub.cxx:63
5302020-03-18 08:51:04.227 | DEBUG | 99262 vps.sterlingcomputers.com(A)--> 18.223.218.156 | 46608 | CPCAPI2 | DnsStub.cxx:45
5312020-03-18 08:51:04.227 | DEBUG | 99262 vps2.sterlingcomputers.com lookup failed: Domain name not found | 46608 | CPCAPI2 | DnsStub.cxx:45
5322020-03-18 08:51:04.227 | DEBUG | 99262 Adding timer: Timer E1 tid=6f1f743926c02349 ms=500 | 46608 | CPCAPI2 | TimerQueue.cxx:50
5332020-03-18 08:51:04.227 | DEBUG | 99262 TransportSelector::transmit: msg: 2F9CA3F0 | 46608 | CPCAPI2 | TransportSelector.cxx:902
5342020-03-18 08:51:04.227 | DEBUG | 99262 TransportSelector::findTransportByVia: target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] source: [ V4 0.0.0.0:0 UNKNOWN_TRANSPORT target domain=unspecified mFlowKey=0 ] via: SIP/2.0/ ;branch=z9hG4bK-524287-1---6f1f743926c02349;rport | 46608 | CPCAPI2 | TransportSelector.cxx:597
5352020-03-18 08:51:04.227 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] | 46608 | CPCAPI2 | TransportSelector.cxx:1448
5362020-03-18 08:51:04.230 | DEBUG | 99262 Looked up source for destination: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] -> [ V4 192.168.1.124:0 UDP target domain=sterlingcomputers.com mFlowKey=0 ] sent-by= sent-port=0 | 46608 | CPCAPI2 | TransportSelector.cxx:845
5372020-03-18 08:51:04.230 | INFO | 99262 Transmitting to [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] tlsDomain= via [ V4 192.168.1.124:62809 UDP target domain=sterlingcomputers.com mFlowKey=0 ]
538
539REGISTER sip:sterlingcomputers.com SIP/2.0
540Via: SIP/2.0/UDP 192.168.1.124:62809;branch=z9hG4bK-524287-1---6f1f743926c02349;rport
541Max-Forwards: 70
542Contact: <sip:4015@192.168.1.124:62809;rinstance=b7bf87550f6fe3aa>
543To: "Jayne Hettinger"<sip:4015@sterlingcomputers.com>
544From: "Jayne Hettinger"<sip:4015@sterlingcomputers.com>;tag=9230f359
545Call-ID: 99262Njk5ZGY2MmY2YjI3NDYwMzFhMDMxYmJiNjA4NGU4Y2Q
546CSeq: 3 REGISTER
547Expires: 3600
548Allow: OPTIONS, SUBSCRIBE, NOTIFY, INVITE, ACK, CANCEL, BYE, REFER, INFO
549User-Agent: Bria 5 release 5.6.2 stamp 99262
550Content-Length: 0
551
552sigcomp id= | 46608 | CPCAPI2 | TransportSelector.cxx:1269
5532020-03-18 08:51:04.275 | DEBUG | Conversation is enabled | 52372 | model::MessageConversationModel::setIsEnabled | messageconversationmodel.cxx:1447
5542020-03-18 08:51:04.277 | DEBUG | Totol voice mail count: 0 | 52372 | model::VoicemailModel::updateAccounts | voicemailmodel.cxx:166
5552020-03-18 08:51:04.277 | DEBUG | DeviceControlService::setAudioChannel(1) | 52372 | cpcjam::DeviceControlServiceImpl::setAudioChannel | devicecontrolserviceimpl.cxx:392
5562020-03-18 08:51:04.277 | DEBUG | Publishing my presence... | 52372 | cpcjam::XmppAccountServiceImpl::publishPresence | accountserviceimpl.cxx:1098
5572020-03-18 08:51:04.277 | DEBUG | Custom message: Available | 52372 | cpcjam::XmppAccountServiceImpl::publishPresence | accountserviceimpl.cxx:1100
5582020-03-18 08:51:04.278 | DEBUG | 99262 OUT: <presence from="4015@sterlingcomputers.com/54176898702507633505933218" xmlns="jabber:client"><priority>0</priority><status>Available</status><c xmlns="http://jabber.org/protocol/caps" hash="sha-1" node="http://counterpath.com/briavoip" ver="ceroIVHtGBW7tpjvJbQNwsCbhhE="/></presence> | 46608 | CPCAPI2 | XmppStackLog.cpp:47
5592020-03-18 08:51:04.337 | INFO | Self presence udpated | 52372 | model::SelfPresenceModel::updatePresenceStatus | selfpresencemodel.cxx:819
5602020-03-18 08:51:04.728 | DEBUG | 99262 Adding timer: Timer E1 tid=6f1f743926c02349 ms=1000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
5612020-03-18 08:51:04.728 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] transport key: 1 | 46608 | CPCAPI2 | TransportSelector.cxx:1432
5622020-03-18 08:51:05.333 | DEBUG | 99262 IN: <presence xml:lang="en" to="4015@sterlingcomputers.com/54176898702507633505933218" from="4015@sterlingcomputers.com/54176898702507633505933218"><c xmlns="http://jabber.org/protocol/caps" hash="sha-1" node="http://counterpath.com/briavoip" ver="ceroIVHtGBW7tpjvJbQNwsCbhhE="/><x xmlns="vcard-temp:x:update"/><priority>0</priority><status>Available</status></presence> | 46608 | CPCAPI2 | XmppStackLog.cpp:32
5632020-03-18 08:51:05.333 | DEBUG | 99262 CPCAPI2 >> handleSelfPresence status:0 Note: Available | 46608 | CPCAPI2 | XmppRosterImpl.cpp:476
5642020-03-18 08:51:05.333 | DEBUG | 99262 XmppVCardManagerImpl::handlePresence(): 0C333A74 duplicate VcardUpdate hash () for jid: 4015@sterlingcomputers.com/54176898702507633505933218 | 46608 | CPCAPI2 | XmppVCardManagerImpl.cpp:313
5652020-03-18 08:51:05.649 | DEBUG | SocketServer::onMessage - GET /status
566User-Agent: Jabra Direct
567Transaction-ID: 244
568Content-Type: application/xml
569Content-Length: 87
570
571<?xml version="1.0" encoding="utf-8" ?>
572<status>
573 <type>callOptions</type>
574</status> | 51804 | cpcjam::api::SocketServer::onMessage | socketserver.cxx:151
5752020-03-18 08:51:05.729 | DEBUG | 99262 Adding timer: Timer E1 tid=6f1f743926c02349 ms=2000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
5762020-03-18 08:51:05.729 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] transport key: 1 | 46608 | CPCAPI2 | TransportSelector.cxx:1432
5772020-03-18 08:51:05.755 | DEBUG | [148567392] Api incoming request: GET /status
578User-Agent: Jabra Direct
579Transaction-ID: 244
580Content-Type: application/xml
581Content-Length: 87
582
583<?xml version="1.0" encoding="utf-8" ?>
584<status>
585 <type>callOptions</type>
586</status> | 52372 | cpcjam::ApiServiceImplConcrete::process | apiserviceimplconcrete.cxx:570
5872020-03-18 08:51:07.729 | DEBUG | 99262 Adding timer: Timer E1 tid=6f1f743926c02349 ms=4000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
5882020-03-18 08:51:07.729 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] transport key: 1 | 46608 | CPCAPI2 | TransportSelector.cxx:1432
5892020-03-18 08:51:08.821 | DEBUG | 99262 APICALL ; (13:51: 8:819 | 9998) VOICE: 1 99; 50716; GetSpeakerVolume() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
5902020-03-18 08:51:08.826 | INFO | 99262 STATEINFO ; (13:51: 8:825 |10003) AUDIO DEVICE: 1 99; 50716; output: volume=122 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
5912020-03-18 08:51:08.826 | INFO | 99262 STATEINFO ; (13:51: 8:825 | 0) AUDIO DEVICE: 1 99; 50716; output: maxVolume=255 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
5922020-03-18 08:51:08.826 | INFO | 99262 STATEINFO ; (13:51: 8:825 | 0) VOICE: 1 99; 50716; GetSpeakerVolume() => volume=122 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
5932020-03-18 08:51:08.826 | DEBUG | 99262 APICALL ; (13:51: 8:825 | 6) VOICE: 1 99; 50716; GetMicVolume() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
5942020-03-18 08:51:08.826 | INFO | 99262 STATEINFO ; (13:51: 8:825 | 0) VOICE: 1 99; 50716; GetMicVolume() => volume=249 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
5952020-03-18 08:51:11.116 | DEBUG | 99262 100 ticks of process() for account interface | 46608 | CPCAPI2 | SipAccountInterface.cpp:541
5962020-03-18 08:51:11.729 | DEBUG | 99262 Adding timer: Timer E1 tid=6f1f743926c02349 ms=4000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
5972020-03-18 08:51:11.729 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] transport key: 1 | 46608 | CPCAPI2 | TransportSelector.cxx:1432
5982020-03-18 08:51:15.730 | DEBUG | 99262 Adding timer: Timer E1 tid=6f1f743926c02349 ms=4000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
5992020-03-18 08:51:15.730 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] transport key: 1 | 46608 | CPCAPI2 | TransportSelector.cxx:1432
6002020-03-18 08:51:18.882 | DEBUG | 99262 APICALL ; (13:51:18:881 |10055) VOICE: 1 99; 50716; GetSpeakerVolume() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
6012020-03-18 08:51:18.893 | INFO | 99262 STATEINFO ; (13:51:18:893 |10067) AUDIO DEVICE: 1 99; 50716; output: volume=122 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
6022020-03-18 08:51:18.893 | INFO | 99262 STATEINFO ; (13:51:18:893 | 0) AUDIO DEVICE: 1 99; 50716; output: maxVolume=255 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
6032020-03-18 08:51:18.893 | INFO | 99262 STATEINFO ; (13:51:18:893 | 0) VOICE: 1 99; 50716; GetSpeakerVolume() => volume=122 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
6042020-03-18 08:51:18.893 | DEBUG | 99262 APICALL ; (13:51:18:893 | 12) VOICE: 1 99; 50716; GetMicVolume() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
6052020-03-18 08:51:18.894 | INFO | 99262 STATEINFO ; (13:51:18:894 | 1) VOICE: 1 99; 50716; GetMicVolume() => volume=249 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
6062020-03-18 08:51:19.721 | DEBUG | 99262 Settings:usePingKeepAlive=0, keepAliveTime=120 | 46608 | CPCAPI2 | XmppAccountImpl.cpp:913
6072020-03-18 08:51:19.730 | DEBUG | 99262 Adding timer: Timer E1 tid=6f1f743926c02349 ms=4000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
6082020-03-18 08:51:19.730 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] transport key: 1 | 46608 | CPCAPI2 | TransportSelector.cxx:1432
6092020-03-18 08:51:22.071 | DEBUG | 99262 100 ticks of process() for account interface | 46608 | CPCAPI2 | SipAccountInterface.cpp:541
6102020-03-18 08:51:23.731 | DEBUG | 99262 Adding timer: Timer E1 tid=6f1f743926c02349 ms=4000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
6112020-03-18 08:51:23.731 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] transport key: 1 | 46608 | CPCAPI2 | TransportSelector.cxx:1432
6122020-03-18 08:51:27.731 | DEBUG | 99262 Adding timer: Timer E1 tid=6f1f743926c02349 ms=4000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
6132020-03-18 08:51:27.731 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] transport key: 1 | 46608 | CPCAPI2 | TransportSelector.cxx:1432
6142020-03-18 08:51:28.963 | DEBUG | 99262 APICALL ; (13:51:28:963 |10070) VOICE: 1 99; 50716; GetSpeakerVolume() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
6152020-03-18 08:51:28.966 | INFO | 99262 STATEINFO ; (13:51:28:966 |10072) AUDIO DEVICE: 1 99; 50716; output: volume=122 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
6162020-03-18 08:51:28.966 | INFO | 99262 STATEINFO ; (13:51:28:966 | 0) AUDIO DEVICE: 1 99; 50716; output: maxVolume=255 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
6172020-03-18 08:51:28.966 | INFO | 99262 STATEINFO ; (13:51:28:966 | 0) VOICE: 1 99; 50716; GetSpeakerVolume() => volume=122 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
6182020-03-18 08:51:28.966 | DEBUG | 99262 APICALL ; (13:51:28:966 | 3) VOICE: 1 99; 50716; GetMicVolume() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
6192020-03-18 08:51:28.967 | INFO | 99262 STATEINFO ; (13:51:28:966 | 0) VOICE: 1 99; 50716; GetMicVolume() => volume=249 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
6202020-03-18 08:51:31.730 | DEBUG | 99262 Adding timer: Timer E1 tid=6f1f743926c02349 ms=4000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
6212020-03-18 08:51:31.730 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] transport key: 1 | 46608 | CPCAPI2 | TransportSelector.cxx:1432
6222020-03-18 08:51:33.008 | DEBUG | 99262 100 ticks of process() for account interface | 46608 | CPCAPI2 | SipAccountInterface.cpp:541
6232020-03-18 08:51:35.731 | DEBUG | 99262 Adding timer: Timer E1 tid=6f1f743926c02349 ms=4000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
6242020-03-18 08:51:35.731 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] transport key: 1 | 46608 | CPCAPI2 | TransportSelector.cxx:1432
6252020-03-18 08:51:36.225 | DEBUG | 99262 Helper::makeResponse(SipReq: REGISTER sterlingcomputers.com tid=6f1f743926c02349 cseq=3 REGISTER contact=4015@192.168.1.124:62809 / 3 from(tu) code=408 reason= | 46608 | CPCAPI2 | Helper.cxx:375
6262020-03-18 08:51:36.225 | DEBUG | 99262 Remove vip sterlingcomputers.com(1) | 46608 | CPCAPI2 | dnsResult.cxx:182
6272020-03-18 08:51:36.226 | INFO | 99262 Send to TU: TU: DialogUsageManager size=0 SipResp: 408 tid=6f1f743926c02349 cseq=3 REGISTER / 3 from(wire) | 46608 | CPCAPI2 | TuSelector.cxx:70
6282020-03-18 08:51:36.226 | INFO | 99262 Got: SipResp: 408 tid=6f1f743926c02349 cseq=3 REGISTER / 3 from(wire) | 46608 | CPCAPI2 | DialogUsageManager.cxx:1686
6292020-03-18 08:51:36.226 | DEBUG | 99262 DialogUsageManager::processResponse: SipResp: 408 tid=6f1f743926c02349 cseq=3 REGISTER / 3 from(wire) | 46608 | CPCAPI2 | DialogUsageManager.cxx:2249
6302020-03-18 08:51:36.226 | DEBUG | 99262 DialogId::DialogId: 99262Njk5ZGY2MmY2YjI3NDYwMzFhMDMxYmJiNjA4NGU4Y2Q-9230f359-dc580c30 | 46608 | CPCAPI2 | DialogId.cxx:50
6312020-03-18 08:51:36.226 | INFO | 99262 SipAccountImpl::onRequestRetry() mHandle=512 handling internally generated message: SipResp: 408 tid=6f1f743926c02349 cseq=3 REGISTER / 3 from(wire) | 46608 | CPCAPI2 | SipAccountImpl.cpp:3470
6322020-03-18 08:51:36.226 | DEBUG | 99262 Failure Re-Registration Interval: 20 Initial Attempts Pending: 0 | 46608 | CPCAPI2 | SipAccountImpl.cpp:5230
6332020-03-18 08:51:36.226 | INFO | 99262 Retry registration due to internally-generated 408/503 from stack in 20 seconds | 46608 | CPCAPI2 | SipAccountImpl.cpp:3566
6342020-03-18 08:51:36.226 | INFO | 99262 AccountRegState transition from Registering to WaitingToRegister for mHandle=512 (4015@sterlingcomputers.com) | 46608 | CPCAPI2 | SipAccountImpl.cpp:3568
6352020-03-18 08:51:36.226 | INFO | 99262 CPOptionsPingManager::onAccountStatusChanged disabled account 512 | 46608 | CPCAPI2 | CPOptionsPingManager.cpp:57
6362020-03-18 08:51:36.226 | DEBUG | 99262 Triggering onAccountStatusChanged callback with status: Status_WaitingToRegister (6) reason: Reason_Local_Timeout (5) | 46608 | CPCAPI2 | SipAccountImpl.cpp:3623
6372020-03-18 08:51:36.226 | DEBUG | 99262 Application requested delayed retry on 408 or internal 503: 20 | 46608 | CPCAPI2 | ClientRegistration.cxx:698
6382020-03-18 08:51:36.226 | INFO | 99262 Transitioning state from 1 to 5 | 46608 | CPCAPI2 | ClientRegistration.cxx:703
6392020-03-18 08:51:36.226 | DEBUG | 99262 Adding application timer: DumTimeout::RegistrationRetry: duration=20 seq=3 ms=20000 | 46608 | CPCAPI2 | TimerQueue.cxx:124
6402020-03-18 08:51:36.287 | DEBUG | Conversation is enabled | 52372 | model::MessageConversationModel::setIsEnabled | messageconversationmodel.cxx:1447
6412020-03-18 08:51:36.289 | DEBUG | Totol voice mail count: 0 | 52372 | model::VoicemailModel::updateAccounts | voicemailmodel.cxx:166
6422020-03-18 08:51:36.290 | DEBUG | DeviceControlService::setAudioChannel(1) | 52372 | cpcjam::DeviceControlServiceImpl::setAudioChannel | devicecontrolserviceimpl.cxx:392
6432020-03-18 08:51:36.290 | DEBUG | Publishing my presence... | 52372 | cpcjam::XmppAccountServiceImpl::publishPresence | accountserviceimpl.cxx:1098
6442020-03-18 08:51:36.290 | DEBUG | Custom message: Available | 52372 | cpcjam::XmppAccountServiceImpl::publishPresence | accountserviceimpl.cxx:1100
6452020-03-18 08:51:36.291 | DEBUG | 99262 OUT: <presence from="4015@sterlingcomputers.com/54176898702507633505933218" xmlns="jabber:client"><priority>0</priority><status>Available</status><c xmlns="http://jabber.org/protocol/caps" hash="sha-1" node="http://counterpath.com/briavoip" ver="ceroIVHtGBW7tpjvJbQNwsCbhhE="/></presence> | 46608 | CPCAPI2 | XmppStackLog.cpp:47
6462020-03-18 08:51:36.354 | INFO | Self presence udpated | 52372 | model::SelfPresenceModel::updatePresenceStatus | selfpresencemodel.cxx:819
6472020-03-18 08:51:36.566 | DEBUG | TestWindow open: 0 | 52372 | model::TestWindowModel::setIsOpen | testwindowmodel.cxx:75
6482020-03-18 08:51:36.566 | DEBUG | TestDevicesModel closed | 52372 | model::TestDevicesModel::onClose | testdevicesmodel.cxx:346
6492020-03-18 08:51:36.566 | DEBUG | DeviceControlService::setAudioChannel(0) | 52372 | cpcjam::DeviceControlServiceImpl::setAudioChannel | devicecontrolserviceimpl.cxx:392
6502020-03-18 08:51:36.567 | WARNING | 99262 WARNING ; (13:51:36:565 | 7600) 24720; (rtp_rtcp_impl.cc:461): Failed to send RTCP BYE | 46608 | CPCAPI2 | MediaStackLog.cpp:53
6512020-03-18 08:51:36.578 | DEBUG | 99262 APICALL ; (13:51:36:575 | 7609) AUDIO DEVICE: 5 99; 25932; AudioDeviceWindowsCore::DoCaptureThread() exit - captured 7036320 samples | 46608 | CPCAPI2 | MediaStackLog.cpp:81
6522020-03-18 08:51:36.582 | INFO | 99262 (13:51:36:582 | 16) 24720; (neteq_impl.cc:126): Deleting NetEqImpl object. | 46608 | CPCAPI2 | MediaStackLog.cpp:70
6532020-03-18 08:51:36.583 | ERROR | 99262 ERROR ; (13:51:36:583 | 1) VOICE: 5 99; 24720; StopSend() failed to locate channel (error=8002) | 46608 | CPCAPI2 | MediaStackLog.cpp:58
6542020-03-18 08:51:36.584 | ERROR | 99262 ERROR ; (13:51:36:583 | 0) VOICE: 5 99; 24720; StopPlayout() failed to locate channel (error=8002) | 46608 | CPCAPI2 | MediaStackLog.cpp:58
6552020-03-18 08:51:36.584 | ERROR | 99262 ERROR ; (13:51:36:583 | 0) VOICE: 5 99; 24720; DeleteChannel() failed to locate channel (error=8002) | 46608 | CPCAPI2 | MediaStackLog.cpp:58
6562020-03-18 08:51:36.584 | INFO | 99262 PlaySoundDevice::shutdown() | 46608 | CPCAPI2 | PlaySoundDevice.cpp:88
6572020-03-18 08:51:36.584 | DEBUG | 99262 APICALL ; (13:51:36:583 | 8) VOICE: -1; 24720; VoiceEngineImpl self deleting (voiceEngine=0x0C31E728) | 46608 | CPCAPI2 | MediaStackLog.cpp:81
6582020-03-18 08:51:36.598 | WARNING | 99262 WARNING ; (13:51:36:598 | 15) AUDIO DEVICE: 5 99; 24720; AudioDeviceWindowsCore::~AudioDeviceWindowsCore() the Avrt DLL module is now unloaded | 46608 | CPCAPI2 | MediaStackLog.cpp:53
6592020-03-18 08:51:37.168 | DEBUG | 99262 IN: <presence xml:lang="en" to="4015@sterlingcomputers.com/54176898702507633505933218" from="4015@sterlingcomputers.com/54176898702507633505933218"><c xmlns="http://jabber.org/protocol/caps" hash="sha-1" node="http://counterpath.com/briavoip" ver="ceroIVHtGBW7tpjvJbQNwsCbhhE="/><x xmlns="vcard-temp:x:update"/><priority>0</priority><status>Available</status></presence> | 46608 | CPCAPI2 | XmppStackLog.cpp:32
6602020-03-18 08:51:37.170 | DEBUG | 99262 CPCAPI2 >> handleSelfPresence status:0 Note: Available | 46608 | CPCAPI2 | XmppRosterImpl.cpp:476
6612020-03-18 08:51:37.170 | DEBUG | 99262 XmppVCardManagerImpl::handlePresence(): 0C333A74 duplicate VcardUpdate hash () for jid: 4015@sterlingcomputers.com/54176898702507633505933218 | 46608 | CPCAPI2 | XmppVCardManagerImpl.cpp:313
6622020-03-18 08:51:37.467 | DEBUG | SocketServer::onMessage - GET /status
663User-Agent: Jabra Direct
664Transaction-ID: 245
665Content-Type: application/xml
666Content-Length: 87
667
668<?xml version="1.0" encoding="utf-8" ?>
669<status>
670 <type>callOptions</type>
671</status> | 51804 | cpcjam::api::SocketServer::onMessage | socketserver.cxx:151
6722020-03-18 08:51:37.626 | DEBUG | [148567392] Api incoming request: GET /status
673User-Agent: Jabra Direct
674Transaction-ID: 245
675Content-Type: application/xml
676Content-Length: 87
677
678<?xml version="1.0" encoding="utf-8" ?>
679<status>
680 <type>callOptions</type>
681</status> | 52372 | cpcjam::ApiServiceImplConcrete::process | apiserviceimplconcrete.cxx:570
6822020-03-18 08:51:39.040 | DEBUG | 99262 APICALL ; (13:51:39: 39 | 2457) VOICE: 1 99; 50716; GetSpeakerVolume() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
6832020-03-18 08:51:39.046 | INFO | 99262 STATEINFO ; (13:51:39: 46 | 2449) AUDIO DEVICE: 1 99; 50716; output: volume=122 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
6842020-03-18 08:51:39.046 | INFO | 99262 STATEINFO ; (13:51:39: 46 | 0) AUDIO DEVICE: 1 99; 50716; output: maxVolume=255 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
6852020-03-18 08:51:39.046 | INFO | 99262 STATEINFO ; (13:51:39: 46 | 0) VOICE: 1 99; 50716; GetSpeakerVolume() => volume=122 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
6862020-03-18 08:51:39.046 | DEBUG | 99262 APICALL ; (13:51:39: 46 | 7) VOICE: 1 99; 50716; GetMicVolume() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
6872020-03-18 08:51:39.047 | INFO | 99262 STATEINFO ; (13:51:39: 46 | 0) VOICE: 1 99; 50716; GetMicVolume() => volume=249 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
6882020-03-18 08:51:44.224 | DEBUG | 99262 100 ticks of process() for account interface | 46608 | CPCAPI2 | SipAccountInterface.cpp:541
6892020-03-18 08:51:49.053 | DEBUG | 99262 APICALL ; (13:51:49: 52 |10005) VOICE: 1 99; 50716; GetSpeakerVolume() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
6902020-03-18 08:51:49.067 | INFO | 99262 STATEINFO ; (13:51:49: 66 |10019) AUDIO DEVICE: 1 99; 50716; output: volume=122 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
6912020-03-18 08:51:49.067 | INFO | 99262 STATEINFO ; (13:51:49: 66 | 0) AUDIO DEVICE: 1 99; 50716; output: maxVolume=255 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
6922020-03-18 08:51:49.067 | INFO | 99262 STATEINFO ; (13:51:49: 66 | 0) VOICE: 1 99; 50716; GetSpeakerVolume() => volume=122 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
6932020-03-18 08:51:49.067 | DEBUG | 99262 APICALL ; (13:51:49: 66 | 14) VOICE: 1 99; 50716; GetMicVolume() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
6942020-03-18 08:51:49.068 | INFO | 99262 STATEINFO ; (13:51:49: 67 | 1) VOICE: 1 99; 50716; GetMicVolume() => volume=249 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
6952020-03-18 08:51:55.621 | DEBUG | 99262 100 ticks of process() for account interface | 46608 | CPCAPI2 | SipAccountInterface.cpp:541
6962020-03-18 08:51:56.227 | INFO | 99262 Send to TU: TU: DialogUsageManager size=0 DumTimeout::RegistrationRetry: duration=20 seq=3 | 46608 | CPCAPI2 | TuSelector.cxx:70
6972020-03-18 08:51:56.227 | INFO | 99262 Transitioning state from 5 to 1 | 46608 | CPCAPI2 | ClientRegistration.cxx:1032
6982020-03-18 08:51:56.227 | DEBUG | 99262 SEND: SipReq: REGISTER sterlingcomputers.com tid=173f1820020d036d cseq=4 REGISTER contact=4015 / 4 from(tu) | 46608 | CPCAPI2 | DialogUsageManager.cxx:1035
6992020-03-18 08:51:56.228 | DEBUG | 99262 DialogId::DialogId: 99262Njk5ZGY2MmY2YjI3NDYwMzFhMDMxYmJiNjA4NGU4Y2Q-9230f359- | 46608 | CPCAPI2 | DialogId.cxx:50
7002020-03-18 08:51:56.228 | DEBUG | 99262 Using outbound proxy: sip:sterlingcomputers.com;lr -> SipReq: REGISTER sterlingcomputers.com tid=173f1820020d036d cseq=4 REGISTER contact=4015 / 4 from(tu) | 46608 | CPCAPI2 | DialogUsageManager.cxx:1170
7012020-03-18 08:51:56.228 | DEBUG | 99262 Sending to outbound proxy uri | 46608 | CPCAPI2 | DialogUsageManager.cxx:1204
7022020-03-18 08:51:56.230 | INFO | 99262 AccountRegState transition from WaitingToRegister to Registering for mHandle=512 (4015@sterlingcomputers.com) | 46608 | CPCAPI2 | SipAccountImpl.cpp:5277
7032020-03-18 08:51:56.230 | INFO | 99262 CPOptionsPingManager::onAccountStatusChanged disabled account 512 | 46608 | CPCAPI2 | CPOptionsPingManager.cpp:57
7042020-03-18 08:51:56.230 | DEBUG | 99262 Triggering onAccountStatusChanged callback with status: Status_Registering (4) reason: Reason_None (0) | 46608 | CPCAPI2 | SipAccountImpl.cpp:5287
7052020-03-18 08:51:56.230 | DEBUG | 99262 Adding timer: Timer F tid=173f1820020d036d ms=32000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
7062020-03-18 08:51:56.230 | DEBUG | 99262 DnsResult::lookup(): 0C326000 URI: sip:sterlingcomputers.com;lr | 46608 | CPCAPI2 | dnsResult.cxx:260
7072020-03-18 08:51:56.232 | DEBUG | 99262 sterlingcomputers.com lookup failed: DNS server returned answer with no data | 46608 | CPCAPI2 | DnsStub.cxx:69
7082020-03-18 08:51:56.234 | DEBUG | 99262 _sips._tcp.sterlingcomputers.com lookup failed: Domain name not found | 46608 | CPCAPI2 | DnsStub.cxx:63
7092020-03-18 08:51:56.234 | DEBUG | 99262 _sip._tcp.sterlingcomputers.com lookup failed: Domain name not found | 46608 | CPCAPI2 | DnsStub.cxx:63
7102020-03-18 08:51:56.234 | DEBUG | 99262 _sip._udp.sterlingcomputers.com (SRV) --> p=20 w=0 vps2.sterlingcomputers.com:5060_sip._udp.sterlingcomputers.com (SRV) --> p=10 w=0 vps.sterlingcomputers.com:5060 | 46608 | CPCAPI2 | DnsStub.cxx:63
7112020-03-18 08:51:56.236 | INFO | 99262 vps.sterlingcomputers.com not cached. Doing external dns lookup | 46608 | CPCAPI2 | DnsStub.cxx:611
7122020-03-18 08:51:56.321 | DEBUG | Conversation is enabled | 52372 | model::MessageConversationModel::setIsEnabled | messageconversationmodel.cxx:1447
7132020-03-18 08:51:56.325 | DEBUG | Totol voice mail count: 0 | 52372 | model::VoicemailModel::updateAccounts | voicemailmodel.cxx:166
7142020-03-18 08:51:56.326 | DEBUG | DeviceControlService::setAudioChannel(0) | 52372 | cpcjam::DeviceControlServiceImpl::setAudioChannel | devicecontrolserviceimpl.cxx:392
7152020-03-18 08:51:56.327 | DEBUG | Publishing my presence... | 52372 | cpcjam::XmppAccountServiceImpl::publishPresence | accountserviceimpl.cxx:1098
7162020-03-18 08:51:56.327 | DEBUG | Custom message: Available | 52372 | cpcjam::XmppAccountServiceImpl::publishPresence | accountserviceimpl.cxx:1100
7172020-03-18 08:51:56.329 | DEBUG | 99262 OUT: <presence from="4015@sterlingcomputers.com/54176898702507633505933218" xmlns="jabber:client"><priority>0</priority><status>Available</status><c xmlns="http://jabber.org/protocol/caps" hash="sha-1" node="http://counterpath.com/briavoip" ver="ceroIVHtGBW7tpjvJbQNwsCbhhE="/></presence> | 46608 | CPCAPI2 | XmppStackLog.cpp:47
7182020-03-18 08:51:56.393 | INFO | 99262 AresDns::resip_AresDns_aresCallback | 46608 | CPCAPI2 | AresDns.cxx:1093
7192020-03-18 08:51:56.393 | DEBUG | 99262 vps.sterlingcomputers.com(A)--> 18.223.218.156 | 46608 | CPCAPI2 | DnsStub.cxx:45
7202020-03-18 08:51:56.393 | DEBUG | 99262 Adding timer: Timer E1 tid=173f1820020d036d ms=500 | 46608 | CPCAPI2 | TimerQueue.cxx:50
7212020-03-18 08:51:56.393 | DEBUG | 99262 TransportSelector::transmit: msg: 2FB3F8C0 | 46608 | CPCAPI2 | TransportSelector.cxx:902
7222020-03-18 08:51:56.393 | DEBUG | 99262 TransportSelector::findTransportByVia: target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] source: [ V4 0.0.0.0:0 UNKNOWN_TRANSPORT target domain=unspecified mFlowKey=0 ] via: SIP/2.0/ ;branch=z9hG4bK-524287-1---173f1820020d036d;rport | 46608 | CPCAPI2 | TransportSelector.cxx:597
7232020-03-18 08:51:56.393 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] | 46608 | CPCAPI2 | TransportSelector.cxx:1448
7242020-03-18 08:51:56.396 | DEBUG | 99262 Looked up source for destination: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] -> [ V4 192.168.1.124:0 UDP target domain=sterlingcomputers.com mFlowKey=0 ] sent-by= sent-port=0 | 46608 | CPCAPI2 | TransportSelector.cxx:845
7252020-03-18 08:51:56.397 | INFO | 99262 Transmitting to [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] tlsDomain= via [ V4 192.168.1.124:62809 UDP target domain=sterlingcomputers.com mFlowKey=0 ]
726
727REGISTER sip:sterlingcomputers.com SIP/2.0
728Via: SIP/2.0/UDP 192.168.1.124:62809;branch=z9hG4bK-524287-1---173f1820020d036d;rport
729Max-Forwards: 70
730Contact: <sip:4015@192.168.1.124:62809;rinstance=b7bf87550f6fe3aa>
731To: "Jayne Hettinger"<sip:4015@sterlingcomputers.com>
732From: "Jayne Hettinger"<sip:4015@sterlingcomputers.com>;tag=9230f359
733Call-ID: 99262Njk5ZGY2MmY2YjI3NDYwMzFhMDMxYmJiNjA4NGU4Y2Q
734CSeq: 4 REGISTER
735Expires: 3600
736Allow: OPTIONS, SUBSCRIBE, NOTIFY, INVITE, ACK, CANCEL, BYE, REFER, INFO
737User-Agent: Bria 5 release 5.6.2 stamp 99262
738Content-Length: 0
739
740sigcomp id= | 46608 | CPCAPI2 | TransportSelector.cxx:1269
7412020-03-18 08:51:56.408 | INFO | Self presence udpated | 52372 | model::SelfPresenceModel::updatePresenceStatus | selfpresencemodel.cxx:819
7422020-03-18 08:51:56.422 | DEBUG | 99262 IN: <presence xml:lang="en" to="4015@sterlingcomputers.com/54176898702507633505933218" from="4015@sterlingcomputers.com/54176898702507633505933218"><c xmlns="http://jabber.org/protocol/caps" hash="sha-1" node="http://counterpath.com/briavoip" ver="ceroIVHtGBW7tpjvJbQNwsCbhhE="/><x xmlns="vcard-temp:x:update"/><priority>0</priority><status>Available</status></presence> | 46608 | CPCAPI2 | XmppStackLog.cpp:32
7432020-03-18 08:51:56.422 | DEBUG | 99262 CPCAPI2 >> handleSelfPresence status:0 Note: Available | 46608 | CPCAPI2 | XmppRosterImpl.cpp:476
7442020-03-18 08:51:56.422 | DEBUG | 99262 XmppVCardManagerImpl::handlePresence(): 0C333A74 duplicate VcardUpdate hash () for jid: 4015@sterlingcomputers.com/54176898702507633505933218 | 46608 | CPCAPI2 | XmppVCardManagerImpl.cpp:313
7452020-03-18 08:51:56.894 | DEBUG | 99262 Adding timer: Timer E1 tid=173f1820020d036d ms=1000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
7462020-03-18 08:51:56.894 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] transport key: 1 | 46608 | CPCAPI2 | TransportSelector.cxx:1432
7472020-03-18 08:51:57.512 | DEBUG | SocketServer::onMessage - GET /status
748User-Agent: Jabra Direct
749Transaction-ID: 246
750Content-Type: application/xml
751Content-Length: 87
752
753<?xml version="1.0" encoding="utf-8" ?>
754<status>
755 <type>callOptions</type>
756</status> | 51804 | cpcjam::api::SocketServer::onMessage | socketserver.cxx:151
7572020-03-18 08:51:57.618 | DEBUG | [148567392] Api incoming request: GET /status
758User-Agent: Jabra Direct
759Transaction-ID: 246
760Content-Type: application/xml
761Content-Length: 87
762
763<?xml version="1.0" encoding="utf-8" ?>
764<status>
765 <type>callOptions</type>
766</status> | 52372 | cpcjam::ApiServiceImplConcrete::process | apiserviceimplconcrete.cxx:570
7672020-03-18 08:51:57.894 | DEBUG | 99262 Adding timer: Timer E1 tid=173f1820020d036d ms=2000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
7682020-03-18 08:51:57.894 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] transport key: 1 | 46608 | CPCAPI2 | TransportSelector.cxx:1432
7692020-03-18 08:51:59.102 | DEBUG | 99262 APICALL ; (13:51:59:101 |10035) VOICE: 1 99; 50716; GetSpeakerVolume() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
7702020-03-18 08:51:59.116 | INFO | 99262 STATEINFO ; (13:51:59:115 |10048) AUDIO DEVICE: 1 99; 50716; output: volume=122 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
7712020-03-18 08:51:59.116 | INFO | 99262 STATEINFO ; (13:51:59:116 | 1) AUDIO DEVICE: 1 99; 50716; output: maxVolume=255 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
7722020-03-18 08:51:59.116 | INFO | 99262 STATEINFO ; (13:51:59:116 | 0) VOICE: 1 99; 50716; GetSpeakerVolume() => volume=122 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
7732020-03-18 08:51:59.116 | DEBUG | 99262 APICALL ; (13:51:59:116 | 15) VOICE: 1 99; 50716; GetMicVolume() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
7742020-03-18 08:51:59.117 | INFO | 99262 STATEINFO ; (13:51:59:116 | 0) VOICE: 1 99; 50716; GetMicVolume() => volume=249 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
7752020-03-18 08:51:59.894 | DEBUG | 99262 Adding timer: Timer E1 tid=173f1820020d036d ms=4000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
7762020-03-18 08:51:59.894 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] transport key: 1 | 46608 | CPCAPI2 | TransportSelector.cxx:1432
7772020-03-18 08:52:03.896 | DEBUG | 99262 Adding timer: Timer E1 tid=173f1820020d036d ms=4000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
7782020-03-18 08:52:03.896 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] transport key: 1 | 46608 | CPCAPI2 | TransportSelector.cxx:1432
7792020-03-18 08:52:07.006 | DEBUG | 99262 100 ticks of process() for account interface | 46608 | CPCAPI2 | SipAccountInterface.cpp:541
7802020-03-18 08:52:07.896 | DEBUG | 99262 Adding timer: Timer E1 tid=173f1820020d036d ms=4000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
7812020-03-18 08:52:07.896 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] transport key: 1 | 46608 | CPCAPI2 | TransportSelector.cxx:1432
7822020-03-18 08:52:09.164 | DEBUG | 99262 APICALL ; (13:52: 9:163 |10048) VOICE: 1 99; 50716; GetSpeakerVolume() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
7832020-03-18 08:52:09.178 | INFO | 99262 STATEINFO ; (13:52: 9:177 |10062) AUDIO DEVICE: 1 99; 50716; output: volume=122 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
7842020-03-18 08:52:09.178 | INFO | 99262 STATEINFO ; (13:52: 9:177 | 0) AUDIO DEVICE: 1 99; 50716; output: maxVolume=255 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
7852020-03-18 08:52:09.178 | INFO | 99262 STATEINFO ; (13:52: 9:177 | 0) VOICE: 1 99; 50716; GetSpeakerVolume() => volume=122 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
7862020-03-18 08:52:09.178 | DEBUG | 99262 APICALL ; (13:52: 9:177 | 14) VOICE: 1 99; 50716; GetMicVolume() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
7872020-03-18 08:52:09.179 | INFO | 99262 STATEINFO ; (13:52: 9:178 | 1) VOICE: 1 99; 50716; GetMicVolume() => volume=249 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
7882020-03-18 08:52:11.897 | DEBUG | 99262 Adding timer: Timer E1 tid=173f1820020d036d ms=4000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
7892020-03-18 08:52:11.898 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] transport key: 1 | 46608 | CPCAPI2 | TransportSelector.cxx:1432
7902020-03-18 08:52:15.898 | DEBUG | 99262 Adding timer: Timer E1 tid=173f1820020d036d ms=4000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
7912020-03-18 08:52:15.898 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] transport key: 1 | 46608 | CPCAPI2 | TransportSelector.cxx:1432
7922020-03-18 08:52:18.306 | DEBUG | 99262 100 ticks of process() for account interface | 46608 | CPCAPI2 | SipAccountInterface.cpp:541
7932020-03-18 08:52:19.214 | DEBUG | 99262 APICALL ; (13:52:19:213 |10035) VOICE: 1 99; 50716; GetSpeakerVolume() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
7942020-03-18 08:52:19.232 | INFO | 99262 STATEINFO ; (13:52:19:231 |10052) AUDIO DEVICE: 1 99; 50716; output: volume=122 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
7952020-03-18 08:52:19.232 | INFO | 99262 STATEINFO ; (13:52:19:232 | 1) AUDIO DEVICE: 1 99; 50716; output: maxVolume=255 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
7962020-03-18 08:52:19.232 | INFO | 99262 STATEINFO ; (13:52:19:232 | 0) VOICE: 1 99; 50716; GetSpeakerVolume() => volume=122 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
7972020-03-18 08:52:19.232 | DEBUG | 99262 APICALL ; (13:52:19:232 | 19) VOICE: 1 99; 50716; GetMicVolume() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
7982020-03-18 08:52:19.233 | INFO | 99262 STATEINFO ; (13:52:19:233 | 1) VOICE: 1 99; 50716; GetMicVolume() => volume=249 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
7992020-03-18 08:52:19.899 | DEBUG | 99262 Adding timer: Timer E1 tid=173f1820020d036d ms=4000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
8002020-03-18 08:52:19.899 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] transport key: 1 | 46608 | CPCAPI2 | TransportSelector.cxx:1432
8012020-03-18 08:52:23.899 | DEBUG | 99262 Adding timer: Timer E1 tid=173f1820020d036d ms=4000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
8022020-03-18 08:52:23.899 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] transport key: 1 | 46608 | CPCAPI2 | TransportSelector.cxx:1432
8032020-03-18 08:52:27.900 | DEBUG | 99262 Adding timer: Timer E1 tid=173f1820020d036d ms=4000 | 46608 | CPCAPI2 | TimerQueue.cxx:50
8042020-03-18 08:52:27.900 | DEBUG | 99262 TransportSelector::findTransportByDest(): target: [ V4 18.223.218.156:5060 UDP target domain=sterlingcomputers.com mFlowKey=0 ] transport key: 1 | 46608 | CPCAPI2 | TransportSelector.cxx:1432
8052020-03-18 08:52:28.055 | DEBUG | TestWindow open: 1 | 52372 | model::TestWindowModel::setIsOpen | testwindowmodel.cxx:75
8062020-03-18 08:52:28.055 | DEBUG | TestDevicesModel opened | 52372 | model::TestDevicesModel::onOpen | testdevicesmodel.cxx:327
8072020-03-18 08:52:28.055 | DEBUG | DeviceControlService::setAudioChannel(1) | 52372 | cpcjam::DeviceControlServiceImpl::setAudioChannel | devicecontrolserviceimpl.cxx:392
8082020-03-18 08:52:28.056 | DEBUG | 99262 APICALL ; (13:52:28: 56 | 8824) VOICE: 1 99; 50716; GetNumOfPlayoutDevices(devices=?) | 46608 | CPCAPI2 | MediaStackLog.cpp:81
8092020-03-18 08:52:28.059 | INFO | 99262 STATEINFO ; (13:52:28: 58 | 8825) AUDIO DEVICE: 1 99; 50716; output: #playout devices=3 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
8102020-03-18 08:52:28.059 | INFO | 99262 STATEINFO ; (13:52:28: 58 | 0) VOICE: 1 99; 50716; Output: devices=3 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
8112020-03-18 08:52:28.059 | DEBUG | 99262 APICALL ; (13:52:28: 58 | 2) VOICE: 1 99; 50716; GetNumOfRecordingDevices(devices=?) | 46608 | CPCAPI2 | MediaStackLog.cpp:81
8122020-03-18 08:52:28.060 | INFO | 99262 STATEINFO ; (13:52:28: 60 | 2) AUDIO DEVICE: 1 99; 50716; output: #recording devices=2 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
8132020-03-18 08:52:28.060 | INFO | 99262 STATEINFO ; (13:52:28: 60 | 0) VOICE: 1 99; 50716; Output: devices=2 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
8142020-03-18 08:52:28.060 | DEBUG | 99262 APICALL ; (13:52:28: 60 | 2) VOICE: 1 99; 50716; GetPlayoutDeviceName(index=0) | 46608 | CPCAPI2 | MediaStackLog.cpp:81
8152020-03-18 08:52:28.062 | INFO | 99262 STATEINFO ; (13:52:28: 62 | 2) AUDIO DEVICE: 1 99; 50716; output: name=E2241 (Intel(R) Display Audio) | 46608 | CPCAPI2 | MediaStackLog.cpp:70
8162020-03-18 08:52:28.062 | INFO | 99262 STATEINFO ; (13:52:28: 62 | 0) AUDIO DEVICE: 1 99; 50716; output: guid={0.0.0.00000000}.{151307e6-c32e-4cd9-bd44-a762ac176e76} | 46608 | CPCAPI2 | MediaStackLog.cpp:70
8172020-03-18 08:52:28.062 | INFO | 99262 STATEINFO ; (13:52:28: 62 | 0) VOICE: 1 99; 50716; Output: strNameUTF8=E2241 (Intel(R) Display Audio) | 46608 | CPCAPI2 | MediaStackLog.cpp:70
8182020-03-18 08:52:28.062 | INFO | 99262 STATEINFO ; (13:52:28: 62 | 0) VOICE: 1 99; 50716; Output: strGuidUTF8={0.0.0.00000000}.{151307e6-c32e-4cd9-bd44-a762ac176e76} | 46608 | CPCAPI2 | MediaStackLog.cpp:70
8192020-03-18 08:52:28.066 | DEBUG | 99262 APICALL ; (13:52:28: 66 | 6) VOICE: 1 99; 50716; GetPlayoutDeviceHid(index=0) | 46608 | CPCAPI2 | MediaStackLog.cpp:81
8202020-03-18 08:52:28.069 | INFO | 99262 STATEINFO ; (13:52:28: 68 | 6) AUDIO DEVICE: 1 99; 50716; output: hid=HDAUDIO\FUNC_01&VEN_8086&DEV_280B&SUBSYS_80860101&REV_1000\4&39ef364d&0&0201 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
8212020-03-18 08:52:28.069 | INFO | 99262 STATEINFO ; (13:52:28: 68 | 0) VOICE: 1 99; 50716; Output: strHidUTF8=HDAUDIO\FUNC_01&VEN_8086&DEV_280B&SUBSYS_80860101&REV_1000\4&39ef364d&0&0201 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
8222020-03-18 08:52:28.069 | DEBUG | 99262 APICALL ; (13:52:28: 68 | 2) VOICE: 1 99; 50716; GetPlayoutDeviceName(index=1) | 46608 | CPCAPI2 | MediaStackLog.cpp:81
8232020-03-18 08:52:28.070 | INFO | 99262 STATEINFO ; (13:52:28: 70 | 2) AUDIO DEVICE: 1 99; 50716; output: name=Speakers / Headphones (Realtek Audio) | 46608 | CPCAPI2 | MediaStackLog.cpp:70
8242020-03-18 08:52:28.070 | INFO | 99262 STATEINFO ; (13:52:28: 70 | 0) AUDIO DEVICE: 1 99; 50716; output: guid={0.0.0.00000000}.{4e344549-b6c4-476d-ae88-973592317baf} | 46608 | CPCAPI2 | MediaStackLog.cpp:70
8252020-03-18 08:52:28.070 | INFO | 99262 STATEINFO ; (13:52:28: 70 | 0) VOICE: 1 99; 50716; Output: strNameUTF8=Speakers / Headphones (Realtek Audio) | 46608 | CPCAPI2 | MediaStackLog.cpp:70
8262020-03-18 08:52:28.070 | INFO | 99262 STATEINFO ; (13:52:28: 70 | 0) VOICE: 1 99; 50716; Output: strGuidUTF8={0.0.0.00000000}.{4e344549-b6c4-476d-ae88-973592317baf} | 46608 | CPCAPI2 | MediaStackLog.cpp:70
8272020-03-18 08:52:28.074 | DEBUG | 99262 APICALL ; (13:52:28: 74 | 6) VOICE: 1 99; 50716; GetPlayoutDeviceHid(index=1) | 46608 | CPCAPI2 | MediaStackLog.cpp:81
8282020-03-18 08:52:28.079 | INFO | 99262 STATEINFO ; (13:52:28: 79 | 9) AUDIO DEVICE: 1 99; 50716; output: hid=HDAUDIO\FUNC_01&VEN_10EC&DEV_0256&SUBSYS_102807A0&REV_1000\4&39ef364d&0&0001 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
8292020-03-18 08:52:28.079 | INFO | 99262 STATEINFO ; (13:52:28: 79 | 0) VOICE: 1 99; 50716; Output: strHidUTF8=HDAUDIO\FUNC_01&VEN_10EC&DEV_0256&SUBSYS_102807A0&REV_1000\4&39ef364d&0&0001 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
8302020-03-18 08:52:28.079 | DEBUG | 99262 APICALL ; (13:52:28: 79 | 5) VOICE: 1 99; 50716; GetPlayoutDeviceName(index=2) | 46608 | CPCAPI2 | MediaStackLog.cpp:81
8312020-03-18 08:52:28.081 | INFO | 99262 STATEINFO ; (13:52:28: 81 | 2) AUDIO DEVICE: 1 99; 50716; output: name=Speakers (Jabra LINK 360) | 46608 | CPCAPI2 | MediaStackLog.cpp:70
8322020-03-18 08:52:28.081 | INFO | 99262 STATEINFO ; (13:52:28: 81 | 0) AUDIO DEVICE: 1 99; 50716; output: guid={0.0.0.00000000}.{566286f1-2004-4a43-a057-d09dc6c525b6} | 46608 | CPCAPI2 | MediaStackLog.cpp:70
8332020-03-18 08:52:28.081 | INFO | 99262 STATEINFO ; (13:52:28: 81 | 0) VOICE: 1 99; 50716; Output: strNameUTF8=Speakers (Jabra LINK 360) | 46608 | CPCAPI2 | MediaStackLog.cpp:70
8342020-03-18 08:52:28.082 | INFO | 99262 STATEINFO ; (13:52:28: 81 | 0) VOICE: 1 99; 50716; Output: strGuidUTF8={0.0.0.00000000}.{566286f1-2004-4a43-a057-d09dc6c525b6} | 46608 | CPCAPI2 | MediaStackLog.cpp:70
8352020-03-18 08:52:28.088 | DEBUG | 99262 APICALL ; (13:52:28: 88 | 9) VOICE: 1 99; 50716; GetPlayoutDeviceHid(index=2) | 46608 | CPCAPI2 | MediaStackLog.cpp:81
8362020-03-18 08:52:28.093 | INFO | 99262 STATEINFO ; (13:52:28: 92 | 11) AUDIO DEVICE: 1 99; 50716; output: hid=USB\VID_0B0E&PID_A346&MI_00\6&1cdf878e&0&0000 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
8372020-03-18 08:52:28.093 | INFO | 99262 STATEINFO ; (13:52:28: 93 | 1) VOICE: 1 99; 50716; Output: strHidUTF8=USB\VID_0B0E&PID_A346&MI_00\6&1cdf878e&0&0000 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
8382020-03-18 08:52:28.093 | DEBUG | 99262 APICALL ; (13:52:28: 93 | 5) VOICE: 1 99; 50716; GetRecordingDeviceName(index=0) | 46608 | CPCAPI2 | MediaStackLog.cpp:81
8392020-03-18 08:52:28.094 | INFO | 99262 STATEINFO ; (13:52:28: 94 | 1) AUDIO DEVICE: 1 99; 50716; output: name=Microphone Array (Realtek Audio) | 46608 | CPCAPI2 | MediaStackLog.cpp:70
8402020-03-18 08:52:28.094 | INFO | 99262 STATEINFO ; (13:52:28: 94 | 0) AUDIO DEVICE: 1 99; 50716; output: guid={0.0.1.00000000}.{124e07ab-df0b-4e94-9b2d-7123bf56b987} | 46608 | CPCAPI2 | MediaStackLog.cpp:70
8412020-03-18 08:52:28.094 | INFO | 99262 STATEINFO ; (13:52:28: 94 | 0) VOICE: 1 99; 50716; Output: strNameUTF8=Microphone Array (Realtek Audio) | 46608 | CPCAPI2 | MediaStackLog.cpp:70
8422020-03-18 08:52:28.094 | INFO | 99262 STATEINFO ; (13:52:28: 94 | 0) VOICE: 1 99; 50716; Output: strGuidUTF8={0.0.1.00000000}.{124e07ab-df0b-4e94-9b2d-7123bf56b987} | 46608 | CPCAPI2 | MediaStackLog.cpp:70
8432020-03-18 08:52:28.103 | DEBUG | 99262 APICALL ; (13:52:28:102 | 9) VOICE: 1 99; 50716; GetRecordingDeviceHid(index=0) | 46608 | CPCAPI2 | MediaStackLog.cpp:81
8442020-03-18 08:52:28.107 | INFO | 99262 STATEINFO ; (13:52:28:106 | 12) AUDIO DEVICE: 1 99; 50716; output: hid=HDAUDIO\FUNC_01&VEN_8086&DEV_280B&SUBSYS_80860101&REV_1000\4&39ef364d&0&0201 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
8452020-03-18 08:52:28.107 | INFO | 99262 STATEINFO ; (13:52:28:106 | 0) VOICE: 1 99; 50716; Output: strHidUTF8=HDAUDIO\FUNC_01&VEN_8086&DEV_280B&SUBSYS_80860101&REV_1000\4&39ef364d&0&0201 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
8462020-03-18 08:52:28.107 | DEBUG | 99262 APICALL ; (13:52:28:106 | 4) VOICE: 1 99; 50716; GetRecordingDeviceName(index=1) | 46608 | CPCAPI2 | MediaStackLog.cpp:81
8472020-03-18 08:52:28.110 | INFO | 99262 STATEINFO ; (13:52:28:109 | 3) AUDIO DEVICE: 1 99; 50716; output: name=Microphone (Jabra LINK 360) | 46608 | CPCAPI2 | MediaStackLog.cpp:70
8482020-03-18 08:52:28.110 | INFO | 99262 STATEINFO ; (13:52:28:109 | 0) AUDIO DEVICE: 1 99; 50716; output: guid={0.0.1.00000000}.{d386df00-a74c-4e4c-847d-976ebcaf94d5} | 46608 | CPCAPI2 | MediaStackLog.cpp:70
8492020-03-18 08:52:28.110 | INFO | 99262 STATEINFO ; (13:52:28:109 | 0) VOICE: 1 99; 50716; Output: strNameUTF8=Microphone (Jabra LINK 360) | 46608 | CPCAPI2 | MediaStackLog.cpp:70
8502020-03-18 08:52:28.110 | INFO | 99262 STATEINFO ; (13:52:28:109 | 0) VOICE: 1 99; 50716; Output: strGuidUTF8={0.0.1.00000000}.{d386df00-a74c-4e4c-847d-976ebcaf94d5} | 46608 | CPCAPI2 | MediaStackLog.cpp:70
8512020-03-18 08:52:28.130 | DEBUG | 99262 APICALL ; (13:52:28:130 | 24) VOICE: 1 99; 50716; GetRecordingDeviceHid(index=1) | 46608 | CPCAPI2 | MediaStackLog.cpp:81
8522020-03-18 08:52:28.132 | INFO | 99262 STATEINFO ; (13:52:28:131 | 22) AUDIO DEVICE: 1 99; 50716; output: hid=HDAUDIO\FUNC_01&VEN_10EC&DEV_0256&SUBSYS_102807A0&REV_1000\4&39ef364d&0&0001 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
8532020-03-18 08:52:28.132 | INFO | 99262 STATEINFO ; (13:52:28:131 | 0) VOICE: 1 99; 50716; Output: strHidUTF8=HDAUDIO\FUNC_01&VEN_10EC&DEV_0256&SUBSYS_102807A0&REV_1000\4&39ef364d&0&0001 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
8542020-03-18 08:52:28.132 | INFO | 99262 PlaySoundDevice::init(0 1) | 46608 | CPCAPI2 | PlaySoundDevice.cpp:49
8552020-03-18 08:52:28.133 | DEBUG | 99262 APICALL ; (13:52:28:133 | 3) VOICE: 6 99; 52096; SetAudioDeviceLayer(audioLayer=0) | 46608 | CPCAPI2 | MediaStackLog.cpp:81
8562020-03-18 08:52:28.133 | DEBUG | 99262 APICALL ; (13:52:28:133 | 0) VOICE: 6 99; 52096; SetAudioType(audioType=1) | 46608 | CPCAPI2 | MediaStackLog.cpp:81
8572020-03-18 08:52:28.146 | DEBUG | 99262 APICALL ; (13:52:28:145 | 12) AUDIO DEVICE: -1; 52096; AudioDeviceWindowsCore::InitRecording() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
8582020-03-18 08:52:28.148 | DEBUG | 99262 APICALL ; (13:52:28:148 | 3) AUDIO DEVICE: -1; 52096; InitMicrophone(): "Microphone Array (Realtek Audio)" | 46608 | CPCAPI2 | MediaStackLog.cpp:81
8592020-03-18 08:52:28.230 | DEBUG | 99262 Helper::makeResponse(SipReq: REGISTER sterlingcomputers.com tid=173f1820020d036d cseq=4 REGISTER contact=4015@192.168.1.124:62809 / 4 from(tu) code=408 reason= | 46608 | CPCAPI2 | Helper.cxx:375
8602020-03-18 08:52:28.230 | DEBUG | 99262 Remove vip sterlingcomputers.com(1) | 46608 | CPCAPI2 | dnsResult.cxx:182
8612020-03-18 08:52:28.230 | INFO | 99262 Send to TU: TU: DialogUsageManager size=0 SipResp: 408 tid=173f1820020d036d cseq=4 REGISTER / 4 from(wire) | 46608 | CPCAPI2 | TuSelector.cxx:70
8622020-03-18 08:52:28.230 | INFO | 99262 Got: SipResp: 408 tid=173f1820020d036d cseq=4 REGISTER / 4 from(wire) | 46608 | CPCAPI2 | DialogUsageManager.cxx:1686
8632020-03-18 08:52:28.230 | DEBUG | 99262 DialogUsageManager::processResponse: SipResp: 408 tid=173f1820020d036d cseq=4 REGISTER / 4 from(wire) | 46608 | CPCAPI2 | DialogUsageManager.cxx:2249
8642020-03-18 08:52:28.230 | DEBUG | 99262 DialogId::DialogId: 99262Njk5ZGY2MmY2YjI3NDYwMzFhMDMxYmJiNjA4NGU4Y2Q-9230f359-37538711 | 46608 | CPCAPI2 | DialogId.cxx:50
8652020-03-18 08:52:28.230 | INFO | 99262 SipAccountImpl::onRequestRetry() mHandle=512 handling internally generated message: SipResp: 408 tid=173f1820020d036d cseq=4 REGISTER / 4 from(wire) | 46608 | CPCAPI2 | SipAccountImpl.cpp:3470
8662020-03-18 08:52:28.230 | INFO | 99262 Retry registration due to internally-generated 408/503 from stack in 20 seconds | 46608 | CPCAPI2 | SipAccountImpl.cpp:3566
8672020-03-18 08:52:28.230 | INFO | 99262 AccountRegState transition from Registering to WaitingToRegister for mHandle=512 (4015@sterlingcomputers.com) | 46608 | CPCAPI2 | SipAccountImpl.cpp:3568
8682020-03-18 08:52:28.230 | INFO | 99262 SipAccountImpl::onRequestRetry() mHandle=512 handling internally generated 408 triggered due to dns lookup: SIP/2.0 408 Request Timeout
869Via: SIP/2.0/UDP 192.168.1.124:62809;branch=z9hG4bK-524287-1---173f1820020d036d;rport
870To: "Jayne Hettinger"<sip:4015@sterlingcomputers.com>;tag=37538711
871From: "Jayne Hettinger"<sip:4015@sterlingcomputers.com>;tag=9230f359
872Call-ID: 99262Njk5ZGY2MmY2YjI3NDYwMzFhMDMxYmJiNjA4NGU4Y2Q
873CSeq: 4 REGISTER
874X-Alternatives-Available: true
875Content-Length: 0
876
877 | 46608 | CPCAPI2 | SipAccountImpl.cpp:3581
8782020-03-18 08:52:28.230 | INFO | 99262 CPOptionsPingManager::onAccountStatusChanged disabled account 512 | 46608 | CPCAPI2 | CPOptionsPingManager.cpp:57
8792020-03-18 08:52:28.230 | DEBUG | 99262 Triggering onAccountStatusChanged callback with status: Status_WaitingToRegister (6) reason: Reason_Dns_Lookup (8) | 46608 | CPCAPI2 | SipAccountImpl.cpp:3623
8802020-03-18 08:52:28.230 | DEBUG | 99262 Application requested delayed retry on 408 or internal 503: 20 | 46608 | CPCAPI2 | ClientRegistration.cxx:698
8812020-03-18 08:52:28.230 | INFO | 99262 Transitioning state from 1 to 5 | 46608 | CPCAPI2 | ClientRegistration.cxx:703
8822020-03-18 08:52:28.230 | DEBUG | 99262 Adding application timer: DumTimeout::RegistrationRetry: duration=20 seq=4 ms=20000 | 46608 | CPCAPI2 | TimerQueue.cxx:124
8832020-03-18 08:52:28.334 | DEBUG | Conversation is enabled | 52372 | model::MessageConversationModel::setIsEnabled | messageconversationmodel.cxx:1447
8842020-03-18 08:52:28.336 | DEBUG | Totol voice mail count: 0 | 52372 | model::VoicemailModel::updateAccounts | voicemailmodel.cxx:166
8852020-03-18 08:52:28.337 | DEBUG | DeviceControlService::setAudioChannel(1) | 52372 | cpcjam::DeviceControlServiceImpl::setAudioChannel | devicecontrolserviceimpl.cxx:392
8862020-03-18 08:52:28.337 | DEBUG | Publishing my presence... | 52372 | cpcjam::XmppAccountServiceImpl::publishPresence | accountserviceimpl.cxx:1098
8872020-03-18 08:52:28.337 | DEBUG | Custom message: Available | 52372 | cpcjam::XmppAccountServiceImpl::publishPresence | accountserviceimpl.cxx:1100
8882020-03-18 08:52:28.337 | DEBUG | 99262 OUT: <presence from="4015@sterlingcomputers.com/54176898702507633505933218" xmlns="jabber:client"><priority>0</priority><status>Available</status><c xmlns="http://jabber.org/protocol/caps" hash="sha-1" node="http://counterpath.com/briavoip" ver="ceroIVHtGBW7tpjvJbQNwsCbhhE="/></presence> | 46608 | CPCAPI2 | XmppStackLog.cpp:47
8892020-03-18 08:52:28.391 | INFO | Self presence udpated | 52372 | model::SelfPresenceModel::updatePresenceStatus | selfpresencemodel.cxx:819
8902020-03-18 08:52:28.490 | DEBUG | 99262 APICALL ; (13:52:28:490 | 342) AUDIO DEVICE: -1; 52096; AudioDeviceWindowsCore::InitRecording() - return 0 | 46608 | CPCAPI2 | MediaStackLog.cpp:81
8912020-03-18 08:52:28.501 | DEBUG | 99262 APICALL ; (13:52:28:500 | 10) AUDIO DEVICE: -1; 52096; InitMicrophone(): "Microphone Array (Realtek Audio)" | 46608 | CPCAPI2 | MediaStackLog.cpp:81
8922020-03-18 08:52:28.502 | DEBUG | 99262 APICALL ; (13:52:28:501 | 1) AUDIO DEVICE: -1; 52096; AudioDeviceWindowsCore::InitRecording() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
8932020-03-18 08:52:28.504 | DEBUG | 99262 APICALL ; (13:52:28:503 | 3) AUDIO DEVICE: -1; 52096; InitMicrophone(): "Microphone (Jabra LINK 360)" | 46608 | CPCAPI2 | MediaStackLog.cpp:81
8942020-03-18 08:52:28.514 | DEBUG | 99262 APICALL ; (13:52:28:513 | 10) AUDIO DEVICE: -1; 52096; AudioDeviceWindowsCore::InitRecording() - return 0 | 46608 | CPCAPI2 | MediaStackLog.cpp:81
8952020-03-18 08:52:28.518 | DEBUG | 99262 APICALL ; (13:52:28:517 | 4) AUDIO DEVICE: -1; 52096; InitMicrophone(): "Microphone (Jabra LINK 360)" | 46608 | CPCAPI2 | MediaStackLog.cpp:81
8962020-03-18 08:52:28.520 | DEBUG | 99262 APICALL ; (13:52:28:520 | 3) AUDIO DEVICE: -1; 52096; AudioDeviceWindowsCore::InitPlayout() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
8972020-03-18 08:52:28.523 | DEBUG | 99262 APICALL ; (13:52:28:522 | 2) AUDIO DEVICE: -1; 52096; InitSpeaker(): "E2241 (Intel(R) Display Audio)" | 46608 | CPCAPI2 | MediaStackLog.cpp:81
8982020-03-18 08:52:28.536 | DEBUG | 99262 APICALL ; (13:52:28:535 | 13) AUDIO DEVICE: -1; 52096; AudioDeviceWindowsCore::InitPlayout() - return 0 | 46608 | CPCAPI2 | MediaStackLog.cpp:81
8992020-03-18 08:52:28.540 | DEBUG | 99262 APICALL ; (13:52:28:540 | 5) AUDIO DEVICE: -1; 52096; InitSpeaker(): "E2241 (Intel(R) Display Audio)" | 46608 | CPCAPI2 | MediaStackLog.cpp:81
9002020-03-18 08:52:28.542 | DEBUG | 99262 APICALL ; (13:52:28:541 | 1) AUDIO DEVICE: -1; 52096; AudioDeviceWindowsCore::InitPlayout() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
9012020-03-18 08:52:28.545 | DEBUG | 99262 APICALL ; (13:52:28:544 | 3) AUDIO DEVICE: -1; 52096; InitSpeaker(): "Speakers / Headphones (Realtek Audio)" | 46608 | CPCAPI2 | MediaStackLog.cpp:81
9022020-03-18 08:52:28.855 | DEBUG | 99262 APICALL ; (13:52:28:854 | 309) AUDIO DEVICE: -1; 52096; AudioDeviceWindowsCore::InitPlayout() - return 0 | 46608 | CPCAPI2 | MediaStackLog.cpp:81
9032020-03-18 08:52:28.870 | DEBUG | 99262 APICALL ; (13:52:28:869 | 16) AUDIO DEVICE: -1; 52096; InitSpeaker(): "Speakers / Headphones (Realtek Audio)" | 46608 | CPCAPI2 | MediaStackLog.cpp:81
9042020-03-18 08:52:28.871 | DEBUG | 99262 APICALL ; (13:52:28:870 | 1) AUDIO DEVICE: -1; 52096; AudioDeviceWindowsCore::InitPlayout() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
9052020-03-18 08:52:28.874 | DEBUG | 99262 APICALL ; (13:52:28:874 | 4) AUDIO DEVICE: -1; 52096; InitSpeaker(): "Speakers (Jabra LINK 360)" | 46608 | CPCAPI2 | MediaStackLog.cpp:81
9062020-03-18 08:52:28.889 | DEBUG | 99262 APICALL ; (13:52:28:889 | 15) AUDIO DEVICE: -1; 52096; AudioDeviceWindowsCore::InitPlayout() - return 0 | 46608 | CPCAPI2 | MediaStackLog.cpp:81
9072020-03-18 08:52:28.895 | DEBUG | 99262 APICALL ; (13:52:28:894 | 5) AUDIO DEVICE: -1; 52096; InitSpeaker(): "Speakers (Jabra LINK 360)" | 46608 | CPCAPI2 | MediaStackLog.cpp:81
9082020-03-18 08:52:28.895 | WARNING | 99262 WARNING ; (13:52:28:894 | 764) AUDIO DEVICE: -1; 52096; AudioDeviceWindowsCore::~AudioDeviceWindowsCore() the Avrt DLL module is now unloaded | 46608 | CPCAPI2 | MediaStackLog.cpp:53
9092020-03-18 08:52:28.895 | INFO | 99262 STATEINFO ; (13:52:28:894 | 0) AUDIO DEVICE: -1; 52096; *** Windows Core Audio is supported *** | 46608 | CPCAPI2 | MediaStackLog.cpp:70
9102020-03-18 08:52:28.906 | DEBUG | 99262 APICALL ; (13:52:28:905 | 11) AUDIO DEVICE: 6 99; 52096; InitSpeaker(): "Speakers (Jabra LINK 360)" | 46608 | CPCAPI2 | MediaStackLog.cpp:81
9112020-03-18 08:52:28.909 | DEBUG | 99262 APICALL ; (13:52:28:908 | 3) AUDIO DEVICE: 6 99; 52096; InitMicrophone(): "Microphone (Jabra LINK 360)" | 46608 | CPCAPI2 | MediaStackLog.cpp:81
9122020-03-18 08:52:28.909 | INFO | 99262 STATEINFO ; (13:52:28:908 | 14) AUDIO DEVICE: 6 99; 52096; output: available=1 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
9132020-03-18 08:52:28.909 | INFO | 99262 STATEINFO ; (13:52:28:908 | 0) AUDIO DEVICE: 6 99; 52096; output: available=1 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
9142020-03-18 08:52:28.909 | DEBUG | 99262 APICALL ; (13:52:28:908 | 0) VOICE: 6 99; 52096; SetRecordingDevice(index=1, recordingChannel=2) | 46608 | CPCAPI2 | MediaStackLog.cpp:81
9152020-03-18 08:52:28.911 | DEBUG | 99262 APICALL ; (13:52:28:911 | 3) AUDIO DEVICE: 6 99; 52096; InitMicrophone(): "Microphone (Jabra LINK 360)" | 46608 | CPCAPI2 | MediaStackLog.cpp:81
9162020-03-18 08:52:28.911 | INFO | 99262 STATEINFO ; (13:52:28:911 | 3) AUDIO DEVICE: 6 99; 52096; output: available=1 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
9172020-03-18 08:52:28.912 | DEBUG | 99262 APICALL ; (13:52:28:911 | 0) AUDIO DEVICE: 6 99; 52096; AudioDeviceWindowsCore::InitRecording() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
9182020-03-18 08:52:28.913 | DEBUG | 99262 APICALL ; (13:52:28:913 | 2) AUDIO DEVICE: 6 99; 52096; InitMicrophone(): "Microphone (Jabra LINK 360)" | 46608 | CPCAPI2 | MediaStackLog.cpp:81
9192020-03-18 08:52:28.921 | DEBUG | 99262 APICALL ; (13:52:28:920 | 7) AUDIO DEVICE: 6 99; 52096; AudioDeviceWindowsCore::InitRecording() - return 0 | 46608 | CPCAPI2 | MediaStackLog.cpp:81
9202020-03-18 08:52:28.939 | INFO | 99262 (13:52:28:938 | 26) 66464; (agc_manager_direct.cc:344): [agc] Initial GetMicVolume()=0 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
9212020-03-18 08:52:28.939 | INFO | 99262 (13:52:28:938 | 0) 66464; (agc_manager_direct.cc:349): [agc] Initial volume too low, raising to 12 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
9222020-03-18 08:52:29.259 | DEBUG | 99262 APICALL ; (13:52:29:257 | 337) VOICE: 1 99; 50716; GetSpeakerVolume() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
9232020-03-18 08:52:29.279 | INFO | 99262 STATEINFO ; (13:52:29:277 | 340) AUDIO DEVICE: 1 99; 50716; output: volume=122 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
9242020-03-18 08:52:29.279 | INFO | 99262 STATEINFO ; (13:52:29:278 | 1) AUDIO DEVICE: 1 99; 50716; output: maxVolume=255 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
9252020-03-18 08:52:29.280 | INFO | 99262 STATEINFO ; (13:52:29:279 | 0) VOICE: 1 99; 50716; GetSpeakerVolume() => volume=122 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
9262020-03-18 08:52:29.280 | DEBUG | 99262 APICALL ; (13:52:29:279 | 22) VOICE: 1 99; 50716; GetMicVolume() | 46608 | CPCAPI2 | MediaStackLog.cpp:81
9272020-03-18 08:52:29.282 | INFO | 99262 STATEINFO ; (13:52:29:281 | 2) VOICE: 1 99; 50716; GetMicVolume() => volume=249 | 46608 | CPCAPI2 | MediaStackLog.cpp:70
9282020-03-18 08:52:29.282 | DEBUG | 99262 IN: <presence xml:lang="en" to="4015@sterlingcomputers.com/54176898702507633505933218" from="4015@sterlingcomputers.com/54176898702507633505933218"><c xmlns="http://jabber.org/protocol/caps" hash="sha-1" node="http://counterpath.com/briavoip" ver="ceroIVHtGBW7tpjvJbQNwsCbhhE="/><x xmlns="vcard-temp:x:update"/><priority>0</priority><status>Available</status></presence> | 46608 | CPCAPI2 | XmppStackLog.cpp:32
9292020-03-18 08:52:29.285 | DEBUG | 99262 CPCAPI2 >> handleSelfPresence status:0 Note: Available | 46608 | CPCAPI2 | XmppRosterImpl.cpp:476
9302020-03-18 08:52:29.285 | DEBUG | 99262 XmppVCardManagerImpl::handlePresence(): 0C333A74 duplicate VcardUpdate hash () for jid: 4015@sterlingcomputers.com/54176898702507633505933218 | 46608 | CPCAPI2 | XmppVCardManagerImpl.cpp:313
9312020-03-18 08:52:29.806 | DEBUG | 99262 100 ticks of process() for account interface | 46608 | CPCAPI2 | SipAccountInterface.cpp:541
9322020-03-18 08:52:29.808 | DEBUG | SocketServer::onMessage - GET /status
933User-Agent: Jabra Direct
934Transaction-ID: 247
935Content-Type: application/xml
936Content-Length: 87
937
938<?xml version="1.0" encoding="utf-8" ?>
939<status>
940 <type>callOptions</type>
941</status> | 51804 | cpcjam::api::SocketServer::onMessage | socketserver.cxx:151
9422020-03-18 08:52:29.914 | DEBUG | [148567392] Api incoming request: GET /status
943User-Agent: Jabra Direct
944Transaction-ID: 247
945Content-Type: application/xml
946Content-Length: 87
947
948<?xml version="1.0" encoding="utf-8" ?>
949<status>
950 <type>callOptions</type>
951</status> | 52372 | cpcjam::ApiServiceImplConcrete::process | apiserviceimplconcrete.cxx:570