2023-05-12T17:20:58.215Z,1683912058.215 [CommandExec](IMPORTANT): got command restart logs 2023-05-12T17:21:14.610Z,1683912074.610 [CommandExec](IMPORTANT): got command run ./Missions/Demo/senddata_direct_and_track_test.xml 2023-05-12T17:21:14.611Z,1683912074.611 [MissionManager](INFO): Loading Mission from file: ./Missions/Demo/senddata_direct_and_track_test.xml 2023-05-12T17:21:14.647Z,1683912074.647 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.AcousticTargetContactLabel = 10.000000 count 2023-05-12T17:21:14.649Z,1683912074.649 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.AcousticTargetDepth = 0.000000 m 2023-05-12T17:21:14.651Z,1683912074.651 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.TrackingUpdatePeriod = 15.000000 s 2023-05-12T17:21:14.653Z,1683912074.653 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.NumberOfPings = 1.000000 count 2023-05-12T17:21:14.655Z,1683912074.655 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.NumberOfFixesLowPass = 2.000000 count 2023-05-12T17:21:14.658Z,1683912074.658 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.NumberOfStartingFixesToIgnore = 2.000000 count 2023-05-12T17:21:14.660Z,1683912074.660 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.BeingInterrogatedTimeout = 1.000000 h 2023-05-12T17:21:14.698Z,1683912074.698 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.AcommsInterval = 1.000000 min 2023-05-12T17:21:14.700Z,1683912074.700 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.modemId1 = 9.000000 enum 2023-05-12T17:21:14.706Z,1683912074.706 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.modemId2 = 5.000000 enum 2023-05-12T17:21:14.709Z,1683912074.709 [MissionManager](INFO): DefineOutput senddata_direct_and_track_test.ModemID = 1.000000 enum 2023-05-12T17:21:14.709Z,1683912074.709 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/AbortDrift.xml 2023-05-12T17:21:14.750Z,1683912074.750 [MissionManager](INFO): DefineArg senddata_direct_and_track_test:AbortDrift.AcousticTimeout = 96.000000 h 2023-05-12T17:21:14.752Z,1683912074.752 [senddata_direct_and_track_test:AbortDrift:B.AbortDrift](DEBUG): Construct AbortDrift. 2023-05-12T17:21:14.758Z,1683912074.758 [senddata_direct_and_track_test:AbortDrift:C.Execute](DEBUG): Construct Execute. 2023-05-12T17:21:14.760Z,1683912074.760 [MissionManager](INFO): RedefineArg senddata_direct_and_track_test:AbortDrift.AcousticTimeout = reader:senddata_direct_and_track_test.BeingInterrogatedTimeout 2023-05-12T17:21:14.792Z,1683912074.792 [MissionManager](DEBUG): The acoustic address of the asset to be tracked. 21 is waveglider Tiny 10 Depth of acoustic target if known and fixed (or nearly fixed). For example, if the acoustic target is a Wave Glider, set it to zero. This will improve 2D projected position estimates in the Earth reference frame. Defaults to NaN. 0 How long to wait between acoustic queries 15 Number of return pings to request with each acoustic query (more than 1 will activate oneway mode) 1 Number of fixes to average to produce smoothed lat/lon/dep output 2 Number of fixes to ignore at the start of mission (as the vehicle just leaves surface, contact's location estimate especailly bearing can be erroneous. Initialized to 8, corresponding to 2 minutes if query interval TrackingUpdatePeriod = 15 s. 2 If the vehicle does not receive an acoustic signal for more than this length of time, it will surface for communications. Set longer than MissionTimeout to effectively disable. 1 1 Modem ID1. 9 Modem ID2. 5 Modem ID. Set to modemId1 or modemId2. Initialized to 1. 1 2023-05-12T17:21:14.803Z,1683912074.803 [CommandExec](IMPORTANT): Running ./Missions/Demo/senddata_direct_and_track_test.xml 2023-05-12T17:21:14.911Z,1683912074.911 [Default] Stopped 2023-05-12T17:21:14.911Z,1683912074.911 [Default](DEBUG): Aggregate::uninitialize Default 2023-05-12T17:21:14.911Z,1683912074.911 [Default:B.GoToSurface] Stopped 2023-05-12T17:21:14.911Z,1683912074.911 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-05-12T17:21:14.911Z,1683912074.911 [Default:CheckIn] Stopped 2023-05-12T17:21:14.911Z,1683912074.911 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-05-12T17:21:14.911Z,1683912074.911 [Default:CheckIn:Read_GPS] Stopped 2023-05-12T17:21:14.911Z,1683912074.911 [MissionManager](IMPORTANT): Started mission senddata_direct_and_track_test 2023-05-12T17:21:14.912Z,1683912074.912 [senddata_direct_and_track_test] Running Loop=1 2023-05-12T17:21:14.912Z,1683912074.912 [senddata_direct_and_track_test](DEBUG): Aggregate::initialize senddata_direct_and_track_test 2023-05-12T17:21:14.912Z,1683912074.912 [senddata_direct_and_track_test:AbortDrift] Running Loop=1 2023-05-12T17:21:14.912Z,1683912074.912 [senddata_direct_and_track_test:AbortDrift](DEBUG): Aggregate::initialize senddata_direct_and_track_test:AbortDrift 2023-05-12T17:21:14.912Z,1683912074.912 [senddata_direct_and_track_test:AbortDrift:A] Running Loop=1 2023-05-12T17:21:14.912Z,1683912074.912 [senddata_direct_and_track_test:DirectData] Running Loop=1 2023-05-12T17:21:14.912Z,1683912074.912 [senddata_direct_and_track_test:DirectData](DEBUG): Aggregate::initialize senddata_direct_and_track_test:DirectData 2023-05-12T17:21:14.912Z,1683912074.912 [senddata_direct_and_track_test:DirectData:A] Running Loop=1 2023-05-12T17:21:14.913Z,1683912074.913 [senddata_direct_and_track_test:DirectData:A](INFO): Queuing up send data for Onboard.Pressure with destination: modem:10:_.pressure 2023-05-12T17:21:14.913Z,1683912074.913 [senddata_direct_and_track_test:DirectData:A] Stopped 2023-05-12T17:21:14.914Z,1683912074.914 [senddata_direct_and_track_test:DirectData:B] Running Loop=1 2023-05-12T17:21:14.914Z,1683912074.914 [senddata_direct_and_track_test:AbortDrift] Running Loop=1 2023-05-12T17:21:14.914Z,1683912074.914 [senddata_direct_and_track_test:AbortDrift:A](INFO): Insert acoustic timeout set to 1.000000 h . 2023-05-12T17:21:14.914Z,1683912074.914 [senddata_direct_and_track_test:AbortDrift:A] Stopped 2023-05-12T17:21:14.915Z,1683912074.915 [senddata_direct_and_track_test:AbortDrift:B.AbortDrift] Running Loop=1 2023-05-12T17:21:14.915Z,1683912074.915 [senddata_direct_and_track_test:AbortDrift:B.AbortDrift](INFO): Initializing AbortDrift. 2023-05-12T17:21:14.950Z,1683912074.950 [senddata_direct_and_track_test:AbortDrift:B.AbortDrift](IMPORTANT): Acoustic timeout set to: 1.00 hours. 2023-05-12T17:21:15.086Z,1683912075.086 [DATMMP](INFO): *** SENDING_FILL_BUFFER *** 2023-05-12T17:21:15.087Z,1683912075.087 [DATMMP](INFO): modem://10: set _.pressure 0.745482 atmosphere 2023-05-12T17:21:15.087Z,1683912075.087 [DATMMP](INFO): #Outgoing data=1 2023-05-12T17:21:15.087Z,1683912075.087 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA 2023-05-12T17:21:15.319Z,1683912075.319 [senddata_direct_and_track_test:DirectData:B](INFO): Queuing up send data for Onboard.Temperature with destination: modem:10:_.temperature 2023-05-12T17:21:15.319Z,1683912075.319 [senddata_direct_and_track_test:DirectData:B] Stopped 2023-05-12T17:21:15.319Z,1683912075.319 [senddata_direct_and_track_test:DirectData:C] Running Loop=1 2023-05-12T17:21:15.320Z,1683912075.320 [senddata_direct_and_track_test:AbortDrift:B.AbortDrift](INFO): Received valid gps time fix: 1683911680.00. Resetting abort timer. 2023-05-12T17:21:15.321Z,1683912075.321 [senddata_direct_and_track_test:AbortDrift:B.AbortDrift](INFO): Received valid acoustic ping at time: 1683912064.00. Resetting abort timer. 2023-05-12T17:21:15.338Z,1683912075.338 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2023-05-12T17:21:15.338Z,1683912075.338 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT 2023-05-12T17:21:15.590Z,1683912075.590 [DATMMP](INFO): *** SENDING_TRANSMIT *** 2023-05-12T17:21:15.591Z,1683912075.591 [DATMMP](INFO): Tx to 10 len 34 xid 28 2023-05-12T17:21:15.591Z,1683912075.591 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2023-05-12T17:21:15.592Z,1683912075.592 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-12T17:21:15.727Z,1683912075.727 [senddata_direct_and_track_test:DirectData:C](INFO): Queuing up send data for Onboard.Humidity with destination: modem:10:_.humidity 2023-05-12T17:21:15.727Z,1683912075.727 [senddata_direct_and_track_test:DirectData:C] Stopped 2023-05-12T17:21:15.727Z,1683912075.727 [senddata_direct_and_track_test:DirectData](INFO): Completed senddata_direct_and_track_test:DirectData 2023-05-12T17:21:15.727Z,1683912075.727 [senddata_direct_and_track_test:DirectData] Stopped 2023-05-12T17:21:15.727Z,1683912075.727 [senddata_direct_and_track_test:DirectData](DEBUG): Aggregate::uninitialize senddata_direct_and_track_test:DirectData 2023-05-12T17:21:15.727Z,1683912075.727 [senddata_direct_and_track_test:DirectData](INFO): Running loop #2 2023-05-12T17:21:15.728Z,1683912075.728 [senddata_direct_and_track_test:DirectData] Running Loop=2 2023-05-12T17:21:15.728Z,1683912075.728 [senddata_direct_and_track_test:DirectData](DEBUG): Aggregate::initialize senddata_direct_and_track_test:DirectData 2023-05-12T17:21:15.728Z,1683912075.728 [senddata_direct_and_track_test:DirectData:A] Running Loop=1 2023-05-12T17:21:15.842Z,1683912075.842 [DATMMP](INFO): GSXN notify for xid: 28 2023-05-12T17:21:15.843Z,1683912075.843 [DATMMP](INFO): cmd_result rx: x1 x7 x0 x1 2023-05-12T17:21:15.843Z,1683912075.843 [DATMMP](DEBUG): Command complete send 2023-05-12T17:21:15.844Z,1683912075.844 [DATMMP](ERROR): 1:COMPLETE 2023-05-12T17:21:15.844Z,1683912075.844 [DATMMP](INFO): Received message type: cmd_result 2023-05-12T17:21:15.844Z,1683912075.844 [DATMMP](INFO): Handled 2023-05-12T17:21:15.844Z,1683912075.844 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:16.094Z,1683912076.094 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:21:16.095Z,1683912076.095 [DATMMP](INFO): timestamp rx: x0 x11 x15 xE x1F0E 2023-05-12T17:21:16.095Z,1683912076.095 [DATMMP](INFO): Received message type: timestamp 2023-05-12T17:21:16.096Z,1683912076.096 [DATMMP](INFO): Handled 2023-05-12T17:21:16.096Z,1683912076.096 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:16.163Z,1683912076.163 [senddata_direct_and_track_test:DirectData:A](INFO): Queuing up send data for Onboard.Pressure with destination: modem:10:_.pressure 2023-05-12T17:21:16.164Z,1683912076.164 [senddata_direct_and_track_test:DirectData:A] Stopped 2023-05-12T17:21:16.164Z,1683912076.164 [senddata_direct_and_track_test:DirectData:B] Running Loop=1 2023-05-12T17:21:16.346Z,1683912076.346 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:16.529Z,1683912076.529 [senddata_direct_and_track_test:DirectData:B](INFO): Queuing up send data for Onboard.Temperature with destination: modem:10:_.temperature 2023-05-12T17:21:16.530Z,1683912076.530 [senddata_direct_and_track_test:DirectData:B] Stopped 2023-05-12T17:21:16.530Z,1683912076.530 [senddata_direct_and_track_test:DirectData:C] Running Loop=1 2023-05-12T17:21:16.599Z,1683912076.599 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:16.850Z,1683912076.850 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:16.929Z,1683912076.929 [senddata_direct_and_track_test:DirectData:C](INFO): Queuing up send data for Onboard.Humidity with destination: modem:10:_.humidity 2023-05-12T17:21:16.929Z,1683912076.929 [senddata_direct_and_track_test:DirectData:C] Stopped 2023-05-12T17:21:16.929Z,1683912076.929 [senddata_direct_and_track_test:DirectData](INFO): Completed senddata_direct_and_track_test:DirectData 2023-05-12T17:21:16.929Z,1683912076.929 [senddata_direct_and_track_test:DirectData] Stopped 2023-05-12T17:21:16.942Z,1683912076.942 [senddata_direct_and_track_test:DirectData](DEBUG): Aggregate::uninitialize senddata_direct_and_track_test:DirectData 2023-05-12T17:21:16.942Z,1683912076.942 [senddata_direct_and_track_test](INFO): Completed senddata_direct_and_track_test 2023-05-12T17:21:16.942Z,1683912076.942 [MissionManager](INFO): senddata_direct_and_track_test is completed. 2023-05-12T17:21:16.942Z,1683912076.942 [MissionManager](INFO): Uninitializing Mission senddata_direct_and_track_test 2023-05-12T17:21:16.942Z,1683912076.942 [senddata_direct_and_track_test] Stopped 2023-05-12T17:21:16.943Z,1683912076.943 [senddata_direct_and_track_test](DEBUG): Aggregate::uninitialize senddata_direct_and_track_test 2023-05-12T17:21:16.943Z,1683912076.943 [senddata_direct_and_track_test:AbortDrift] Stopped 2023-05-12T17:21:16.943Z,1683912076.943 [senddata_direct_and_track_test:AbortDrift](DEBUG): Aggregate::uninitialize senddata_direct_and_track_test:AbortDrift 2023-05-12T17:21:16.943Z,1683912076.943 [senddata_direct_and_track_test:AbortDrift:B.AbortDrift] Stopped 2023-05-12T17:21:16.943Z,1683912076.943 [senddata_direct_and_track_test:AbortDrift:B.AbortDrift](INFO): Uninitializing AbortDrift. 2023-05-12T17:21:17.102Z,1683912077.102 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:17.334Z,1683912077.334 [MissionManager](IMPORTANT): Started mission Default 2023-05-12T17:21:17.334Z,1683912077.334 [Default] Running Loop=1 2023-05-12T17:21:17.335Z,1683912077.335 [Default](DEBUG): Aggregate::initialize Default 2023-05-12T17:21:17.335Z,1683912077.335 [Default:B.GoToSurface] Running Loop=1 2023-05-12T17:21:17.335Z,1683912077.335 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-05-12T17:21:17.335Z,1683912077.335 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-05-12T17:21:17.335Z,1683912077.335 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-05-12T17:21:17.336Z,1683912077.336 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-05-12T17:21:17.336Z,1683912077.336 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-05-12T17:21:17.336Z,1683912077.336 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-05-12T17:21:17.336Z,1683912077.336 [Default:A.Wait] Running Loop=1 2023-05-12T17:21:17.337Z,1683912077.337 [Default:A.Wait](DEBUG): Initialize Wait Component. 2023-05-12T17:21:17.354Z,1683912077.354 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:17.606Z,1683912077.606 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:17.858Z,1683912077.858 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:18.110Z,1683912078.110 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:18.362Z,1683912078.362 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:18.614Z,1683912078.614 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:18.866Z,1683912078.866 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:19.118Z,1683912079.118 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:19.370Z,1683912079.370 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:19.622Z,1683912079.622 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:19.874Z,1683912079.874 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:20.127Z,1683912080.127 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:21:20.153Z,1683912080.153 [DATMMP](INFO): doppler rx: xFFFF 2023-05-12T17:21:20.153Z,1683912080.153 [DATMMP](INFO): Received message type: doppler 2023-05-12T17:21:20.153Z,1683912080.153 [DATMMP](INFO): Handled 2023-05-12T17:21:20.378Z,1683912080.378 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:21:20.379Z,1683912080.379 [DATMMP](INFO): timestamp rx: x1 x11 x15 x12 x1F20 2023-05-12T17:21:20.380Z,1683912080.380 [DATMMP](INFO): Rx dataTimestamp_ set to:1683912091.851968 2023-05-12T17:21:20.380Z,1683912080.380 [DATMMP](INFO): Rx ping set to:1683912080.379321 2023-05-12T17:21:20.380Z,1683912080.380 [DATMMP](INFO): Received message type: timestamp 2023-05-12T17:21:20.380Z,1683912080.380 [DATMMP](INFO): Handled 2023-05-12T17:21:20.380Z,1683912080.380 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:20.630Z,1683912080.630 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:20.883Z,1683912080.883 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:21:20.885Z,1683912080.885 [DATMMP](INFO): remote_header rx: xF x4 x5 x4 x0 xC xA x0 2023-05-12T17:21:20.886Z,1683912080.886 [DATMMP](INFO): DATA Src=10, Dst=12 2023-05-12T17:21:20.886Z,1683912080.886 [DATMMP](INFO): Received message type: remote_header 2023-05-12T17:21:20.887Z,1683912080.887 [DATMMP](INFO): Handled 2023-05-12T17:21:20.887Z,1683912080.887 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:21.134Z,1683912081.134 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:21:21.135Z,1683912081.135 [DATMMP](INFO): nav_stat rx: x0 x1 x1 2023-05-12T17:21:21.135Z,1683912081.135 [DATMMP](INFO): Received message type: nav_stat 2023-05-12T17:21:21.135Z,1683912081.135 [DATMMP](INFO): Handled 2023-05-12T17:21:21.135Z,1683912081.135 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:21.386Z,1683912081.386 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:21:21.388Z,1683912081.388 [DATMMP](INFO): directional_debug rx: x8 x212 x23 xCC x10A0 x1351 x1242 x1693 2023-05-12T17:21:21.389Z,1683912081.389 [DATMMP](INFO): Received message type: directional_debug 2023-05-12T17:21:21.389Z,1683912081.389 [DATMMP](INFO): Handled 2023-05-12T17:21:21.389Z,1683912081.389 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:21.638Z,1683912081.638 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:21:21.640Z,1683912081.640 [DATMMP](INFO): directional rx: x2 x1D4 x5D4 xFDEE x9B7 xFDF2 x0 x3FA x6 xFFEF x27B5966 x-43569E0 2023-05-12T17:21:21.641Z,1683912081.641 [DATMMP](INFO): Received message type: directional 2023-05-12T17:21:21.642Z,1683912081.642 [DATMMP](INFO): Handled 2023-05-12T17:21:21.642Z,1683912081.642 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:21.891Z,1683912081.891 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:21:21.892Z,1683912081.892 [DATMMP](INFO): acstats rx: xA x5 xFFFF x299 x22 x89 xB8 xC5 xD xFFFF xAF x24 xD x0 2023-05-12T17:21:21.893Z,1683912081.893 [DATMMP](INFO): Received message type: acstats 2023-05-12T17:21:21.893Z,1683912081.893 [DATMMP](INFO): Handled 2023-05-12T17:21:21.893Z,1683912081.893 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:22.142Z,1683912082.142 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:21:22.143Z,1683912082.143 [DATMMP](INFO): data_rx rx: xA x0 x0 x4 2023-05-12T17:21:22.143Z,1683912082.143 [DATMMP](INFO): Check CRC 2023-05-12T17:21:22.143Z,1683912082.143 [DATMMP](INFO): data rx parseDataResponse 4 2023-05-12T17:21:22.143Z,1683912082.143 [DATMMP](INFO): Got DATA: len 4 2023-05-12T17:21:22.144Z,1683912082.144 [DATMMP](DEBUG): In parseResponses, got ack so set commsState_ = SENDING_VERIFIED 2023-05-12T17:21:22.144Z,1683912082.144 [DATMMP](INFO): Got ~~ ack, bytes left 2 2023-05-12T17:21:22.144Z,1683912082.144 [DATMMP](INFO): Received command: 2023-05-12T17:21:22.144Z,1683912082.144 [DATMMP](INFO): Received message type: data_rx 2023-05-12T17:21:22.144Z,1683912082.144 [DATMMP](INFO): Handled 2023-05-12T17:21:22.144Z,1683912082.144 [DATMMP](INFO): *** SENDING_VERIFIED *** 2023-05-12T17:21:22.145Z,1683912082.145 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER 2023-05-12T17:21:22.394Z,1683912082.394 [DATMMP](INFO): *** SENDING_FILL_BUFFER *** 2023-05-12T17:21:22.395Z,1683912082.395 [DATMMP](INFO): modem://10: set _.temperature 24.299829 celsius 2023-05-12T17:21:22.395Z,1683912082.395 [DATMMP](INFO): #Outgoing data=1 2023-05-12T17:21:22.395Z,1683912082.395 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA 2023-05-12T17:21:22.646Z,1683912082.646 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2023-05-12T17:21:22.646Z,1683912082.646 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT 2023-05-12T17:21:22.898Z,1683912082.898 [DATMMP](INFO): *** SENDING_TRANSMIT *** 2023-05-12T17:21:22.899Z,1683912082.899 [DATMMP](INFO): Tx to 10 len 35 xid 29 2023-05-12T17:21:22.899Z,1683912082.899 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2023-05-12T17:21:22.899Z,1683912082.899 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-12T17:21:23.150Z,1683912083.150 [DATMMP](INFO): GSXN notify for xid: 29 2023-05-12T17:21:23.151Z,1683912083.151 [DATMMP](INFO): cmd_result rx: x1 x7 x0 x1 2023-05-12T17:21:23.151Z,1683912083.151 [DATMMP](DEBUG): Command complete send 2023-05-12T17:21:23.152Z,1683912083.152 [DATMMP](ERROR): 1:COMPLETE 2023-05-12T17:21:23.152Z,1683912083.152 [DATMMP](INFO): Received message type: cmd_result 2023-05-12T17:21:23.152Z,1683912083.152 [DATMMP](INFO): Handled 2023-05-12T17:21:23.152Z,1683912083.152 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:23.402Z,1683912083.402 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:21:23.403Z,1683912083.403 [DATMMP](INFO): timestamp rx: x0 x11 x15 x16 x3B5 2023-05-12T17:21:23.403Z,1683912083.403 [DATMMP](INFO): Received message type: timestamp 2023-05-12T17:21:23.404Z,1683912083.404 [DATMMP](INFO): Handled 2023-05-12T17:21:23.404Z,1683912083.404 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:23.654Z,1683912083.654 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:23.907Z,1683912083.907 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:24.158Z,1683912084.158 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:24.410Z,1683912084.410 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:24.662Z,1683912084.662 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:24.914Z,1683912084.914 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:25.166Z,1683912085.166 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:25.418Z,1683912085.418 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:25.670Z,1683912085.670 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:25.922Z,1683912085.922 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:26.174Z,1683912086.174 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:26.428Z,1683912086.428 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:26.678Z,1683912086.678 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:26.930Z,1683912086.930 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:27.182Z,1683912087.182 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:27.434Z,1683912087.434 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:21:27.435Z,1683912087.435 [DATMMP](INFO): doppler rx: xFFFF 2023-05-12T17:21:27.435Z,1683912087.435 [DATMMP](INFO): Received message type: doppler 2023-05-12T17:21:27.435Z,1683912087.435 [DATMMP](INFO): Handled 2023-05-12T17:21:27.686Z,1683912087.686 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:21:27.687Z,1683912087.687 [DATMMP](INFO): timestamp rx: x1 x11 x15 x1A x3C7 2023-05-12T17:21:27.688Z,1683912087.688 [DATMMP](INFO): Rx dataTimestamp_ set to:1683912095.464896 2023-05-12T17:21:27.688Z,1683912087.688 [DATMMP](INFO): Rx ping set to:1683912087.687343 2023-05-12T17:21:27.688Z,1683912087.688 [DATMMP](INFO): Received message type: timestamp 2023-05-12T17:21:27.688Z,1683912087.688 [DATMMP](INFO): Handled 2023-05-12T17:21:27.689Z,1683912087.689 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:27.938Z,1683912087.938 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:28.190Z,1683912088.190 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:21:28.191Z,1683912088.191 [DATMMP](INFO): remote_header rx: xF x4 x5 x4 x0 xC xA x0 2023-05-12T17:21:28.192Z,1683912088.192 [DATMMP](INFO): DATA Src=10, Dst=12 2023-05-12T17:21:28.192Z,1683912088.192 [DATMMP](INFO): Received message type: remote_header 2023-05-12T17:21:28.192Z,1683912088.192 [DATMMP](INFO): Handled 2023-05-12T17:21:28.192Z,1683912088.192 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:28.442Z,1683912088.442 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:21:28.443Z,1683912088.443 [DATMMP](INFO): nav_stat rx: x0 x1 x1 2023-05-12T17:21:28.443Z,1683912088.443 [DATMMP](INFO): Received message type: nav_stat 2023-05-12T17:21:28.443Z,1683912088.443 [DATMMP](INFO): Handled 2023-05-12T17:21:28.444Z,1683912088.444 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:28.694Z,1683912088.694 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:21:28.695Z,1683912088.695 [DATMMP](INFO): directional_debug rx: x208 x1E7 x22 x1B6 x1070 x1371 x1242 x1673 2023-05-12T17:21:28.696Z,1683912088.696 [DATMMP](INFO): Received message type: directional_debug 2023-05-12T17:21:28.697Z,1683912088.697 [DATMMP](INFO): Handled 2023-05-12T17:21:28.697Z,1683912088.697 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:28.946Z,1683912088.946 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:21:28.948Z,1683912088.948 [DATMMP](INFO): directional rx: x20E x193 x3D4 xFE19 x7B9 xFE29 x0 x3ED x5 xFFF0 x27B5966 x-43569E0 2023-05-12T17:21:28.949Z,1683912088.949 [DATMMP](INFO): Received message type: directional 2023-05-12T17:21:28.950Z,1683912088.950 [DATMMP](INFO): Handled 2023-05-12T17:21:28.950Z,1683912088.950 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:29.198Z,1683912089.198 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:21:29.200Z,1683912089.200 [DATMMP](INFO): acstats rx: xA x5 xFFFF x2CC x23 x87 xB3 xC0 xD xFFFF xAE x23 xC x0 2023-05-12T17:21:29.200Z,1683912089.200 [DATMMP](INFO): Received message type: acstats 2023-05-12T17:21:29.200Z,1683912089.200 [DATMMP](INFO): Handled 2023-05-12T17:21:29.200Z,1683912089.200 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:29.450Z,1683912089.450 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:21:29.451Z,1683912089.451 [DATMMP](INFO): data_rx rx: xA x0 x0 x4 2023-05-12T17:21:29.451Z,1683912089.451 [DATMMP](INFO): Check CRC 2023-05-12T17:21:29.452Z,1683912089.452 [DATMMP](INFO): data rx parseDataResponse 4 2023-05-12T17:21:29.452Z,1683912089.452 [DATMMP](INFO): Got DATA: len 4 2023-05-12T17:21:29.452Z,1683912089.452 [DATMMP](DEBUG): In parseResponses, got ack so set commsState_ = SENDING_VERIFIED 2023-05-12T17:21:29.452Z,1683912089.452 [DATMMP](INFO): Got ~~ ack, bytes left 2 2023-05-12T17:21:29.452Z,1683912089.452 [DATMMP](INFO): Received command: 2023-05-12T17:21:29.453Z,1683912089.453 [DATMMP](INFO): Received message type: data_rx 2023-05-12T17:21:29.453Z,1683912089.453 [DATMMP](INFO): Handled 2023-05-12T17:21:29.453Z,1683912089.453 [DATMMP](INFO): *** SENDING_VERIFIED *** 2023-05-12T17:21:29.453Z,1683912089.453 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER 2023-05-12T17:21:29.702Z,1683912089.702 [DATMMP](INFO): *** SENDING_FILL_BUFFER *** 2023-05-12T17:21:29.703Z,1683912089.703 [DATMMP](INFO): modem://10: set _.humidity 12.879990 percent 2023-05-12T17:21:29.703Z,1683912089.703 [DATMMP](INFO): #Outgoing data=1 2023-05-12T17:21:29.703Z,1683912089.703 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA 2023-05-12T17:21:29.954Z,1683912089.954 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2023-05-12T17:21:29.954Z,1683912089.954 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT 2023-05-12T17:21:30.206Z,1683912090.206 [DATMMP](INFO): *** SENDING_TRANSMIT *** 2023-05-12T17:21:30.207Z,1683912090.207 [DATMMP](INFO): Tx to 10 len 32 xid 30 2023-05-12T17:21:30.207Z,1683912090.207 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2023-05-12T17:21:30.207Z,1683912090.207 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-12T17:21:30.459Z,1683912090.459 [DATMMP](INFO): GSXN notify for xid: 30 2023-05-12T17:21:30.459Z,1683912090.459 [DATMMP](INFO): cmd_result rx: x1 x7 x0 x1 2023-05-12T17:21:30.460Z,1683912090.460 [DATMMP](DEBUG): Command complete send 2023-05-12T17:21:30.460Z,1683912090.460 [DATMMP](ERROR): 1:COMPLETE 2023-05-12T17:21:30.460Z,1683912090.460 [DATMMP](INFO): Received message type: cmd_result 2023-05-12T17:21:30.460Z,1683912090.460 [DATMMP](INFO): Handled 2023-05-12T17:21:30.460Z,1683912090.460 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:30.663Z,1683912090.663 [Default:A.Wait](INFO): Done Waiting. 2023-05-12T17:21:30.663Z,1683912090.663 [Default:A.Wait] Stopped 2023-05-12T17:21:30.663Z,1683912090.663 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2023-05-12T17:21:30.711Z,1683912090.711 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:21:30.712Z,1683912090.712 [DATMMP](INFO): timestamp rx: x0 x11 x15 x1D xF6D 2023-05-12T17:21:30.712Z,1683912090.712 [DATMMP](INFO): Received message type: timestamp 2023-05-12T17:21:30.713Z,1683912090.713 [DATMMP](INFO): Handled 2023-05-12T17:21:30.718Z,1683912090.718 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:30.964Z,1683912090.964 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:31.104Z,1683912091.104 [Default:CheckIn] Running Loop=1 2023-05-12T17:21:31.105Z,1683912091.105 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-05-12T17:21:31.105Z,1683912091.105 [Default:CheckIn:Read_GPS] Running Loop=1 2023-05-12T17:21:31.214Z,1683912091.214 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:31.474Z,1683912091.474 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:31.726Z,1683912091.726 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:31.978Z,1683912091.978 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:32.230Z,1683912092.230 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:32.483Z,1683912092.483 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:32.734Z,1683912092.734 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:32.986Z,1683912092.986 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:33.238Z,1683912093.238 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:33.742Z,1683912093.742 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:33.997Z,1683912093.997 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:34.247Z,1683912094.247 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:34.498Z,1683912094.498 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:34.750Z,1683912094.750 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:35.002Z,1683912095.002 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:35.254Z,1683912095.254 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:35.767Z,1683912095.767 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:36.018Z,1683912096.018 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:36.270Z,1683912096.270 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:36.522Z,1683912096.522 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:36.774Z,1683912096.774 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:37.026Z,1683912097.026 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:37.279Z,1683912097.279 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:37.782Z,1683912097.782 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:38.286Z,1683912098.286 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:38.538Z,1683912098.538 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:38.790Z,1683912098.790 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:39.042Z,1683912099.042 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:39.295Z,1683912099.295 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:39.546Z,1683912099.546 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:39.798Z,1683912099.798 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:40.050Z,1683912100.050 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:40.302Z,1683912100.302 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:40.302Z,1683912100.302 [DATMMP](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER 2023-05-12T17:21:40.554Z,1683912100.554 [DATMMP](INFO): *** SENDING_FILL_BUFFER *** 2023-05-12T17:21:40.555Z,1683912100.555 [DATMMP](INFO): modem://10: set _.pressure 0.745482 atmosphere 2023-05-12T17:21:40.555Z,1683912100.555 [DATMMP](INFO): #Outgoing data=1 2023-05-12T17:21:40.556Z,1683912100.556 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA 2023-05-12T17:21:40.806Z,1683912100.806 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2023-05-12T17:21:40.806Z,1683912100.806 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT 2023-05-12T17:21:41.058Z,1683912101.058 [DATMMP](INFO): *** SENDING_TRANSMIT *** 2023-05-12T17:21:41.059Z,1683912101.059 [DATMMP](INFO): Tx to 10 len 32 xid 31 2023-05-12T17:21:41.059Z,1683912101.059 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2023-05-12T17:21:41.060Z,1683912101.060 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-12T17:21:41.311Z,1683912101.311 [DATMMP](INFO): GSXN notify for xid: 31 2023-05-12T17:21:41.311Z,1683912101.311 [DATMMP](INFO): cmd_result rx: x1 x7 x0 x1 2023-05-12T17:21:41.312Z,1683912101.312 [DATMMP](DEBUG): Command complete send 2023-05-12T17:21:41.312Z,1683912101.312 [DATMMP](ERROR): 1:COMPLETE 2023-05-12T17:21:41.312Z,1683912101.312 [DATMMP](INFO): Received message type: cmd_result 2023-05-12T17:21:41.312Z,1683912101.312 [DATMMP](INFO): Handled 2023-05-12T17:21:41.312Z,1683912101.312 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:41.562Z,1683912101.562 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:21:41.566Z,1683912101.566 [DATMMP](INFO): timestamp rx: x0 x11 x15 x28 x990 2023-05-12T17:21:41.567Z,1683912101.567 [DATMMP](INFO): Received message type: timestamp 2023-05-12T17:21:41.567Z,1683912101.567 [DATMMP](INFO): Handled 2023-05-12T17:21:41.814Z,1683912101.814 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:42.066Z,1683912102.066 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:42.318Z,1683912102.318 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:42.570Z,1683912102.570 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:42.822Z,1683912102.822 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:43.074Z,1683912103.074 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:43.326Z,1683912103.326 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:43.578Z,1683912103.578 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:43.830Z,1683912103.830 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:44.082Z,1683912104.082 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:44.334Z,1683912104.334 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:44.586Z,1683912104.586 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:44.838Z,1683912104.838 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:45.090Z,1683912105.090 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:45.342Z,1683912105.342 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:45.594Z,1683912105.594 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:21:45.595Z,1683912105.595 [DATMMP](INFO): doppler rx: xFFFF 2023-05-12T17:21:45.595Z,1683912105.595 [DATMMP](INFO): Received message type: doppler 2023-05-12T17:21:45.595Z,1683912105.595 [DATMMP](INFO): Handled 2023-05-12T17:21:45.595Z,1683912105.595 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:45.846Z,1683912105.846 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:21:45.847Z,1683912105.847 [DATMMP](INFO): timestamp rx: x1 x11 x15 x2C x9A2 2023-05-12T17:21:45.848Z,1683912105.848 [DATMMP](INFO): Rx dataTimestamp_ set to:1683912128.086016 2023-05-12T17:21:45.848Z,1683912105.848 [DATMMP](INFO): Rx ping set to:1683912105.847334 2023-05-12T17:21:45.848Z,1683912105.848 [DATMMP](INFO): Received message type: timestamp 2023-05-12T17:21:45.848Z,1683912105.848 [DATMMP](INFO): Handled 2023-05-12T17:21:45.849Z,1683912105.849 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:46.098Z,1683912106.098 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:46.350Z,1683912106.350 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:21:46.352Z,1683912106.352 [DATMMP](INFO): remote_header rx: xF x4 x5 x4 x0 xC xA x0 2023-05-12T17:21:46.352Z,1683912106.352 [DATMMP](INFO): DATA Src=10, Dst=12 2023-05-12T17:21:46.352Z,1683912106.352 [DATMMP](INFO): Received message type: remote_header 2023-05-12T17:21:46.352Z,1683912106.352 [DATMMP](INFO): Handled 2023-05-12T17:21:46.352Z,1683912106.352 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:46.603Z,1683912106.603 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:21:46.603Z,1683912106.603 [DATMMP](INFO): nav_stat rx: x0 x1 x1 2023-05-12T17:21:46.604Z,1683912106.604 [DATMMP](INFO): Received message type: nav_stat 2023-05-12T17:21:46.604Z,1683912106.604 [DATMMP](INFO): Handled 2023-05-12T17:21:46.604Z,1683912106.604 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:46.854Z,1683912106.854 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:21:46.855Z,1683912106.855 [DATMMP](INFO): directional_debug rx: x46 x1F1 x24 xBB x1070 x13C1 x1252 x1673 2023-05-12T17:21:46.856Z,1683912106.856 [DATMMP](INFO): Received message type: directional_debug 2023-05-12T17:21:46.856Z,1683912106.856 [DATMMP](INFO): Handled 2023-05-12T17:21:46.857Z,1683912106.857 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:47.106Z,1683912107.106 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:21:47.108Z,1683912107.108 [DATMMP](INFO): directional rx: x3F x1AC x596 xFE0F x957 xFE16 x0 x3D5 x5 xFFEE x27B5966 x-43569E0 2023-05-12T17:21:47.109Z,1683912107.109 [DATMMP](INFO): Received message type: directional 2023-05-12T17:21:47.110Z,1683912107.110 [DATMMP](INFO): Handled 2023-05-12T17:21:47.110Z,1683912107.110 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:47.358Z,1683912107.358 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:21:47.360Z,1683912107.360 [DATMMP](INFO): acstats rx: xA x5 xFFFF x28B x22 x97 xBD xC9 xD xFFFF xAF x24 xD x0 2023-05-12T17:21:47.360Z,1683912107.360 [DATMMP](INFO): Received message type: acstats 2023-05-12T17:21:47.360Z,1683912107.360 [DATMMP](INFO): Handled 2023-05-12T17:21:47.360Z,1683912107.360 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:47.618Z,1683912107.618 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:21:47.619Z,1683912107.619 [DATMMP](INFO): data_rx rx: xA x0 x0 x4 2023-05-12T17:21:47.619Z,1683912107.619 [DATMMP](INFO): Check CRC 2023-05-12T17:21:47.619Z,1683912107.619 [DATMMP](INFO): data rx parseDataResponse 4 2023-05-12T17:21:47.619Z,1683912107.619 [DATMMP](INFO): Got DATA: len 4 2023-05-12T17:21:47.620Z,1683912107.620 [DATMMP](DEBUG): In parseResponses, got ack so set commsState_ = SENDING_VERIFIED 2023-05-12T17:21:47.620Z,1683912107.620 [DATMMP](INFO): Got ~~ ack, bytes left 2 2023-05-12T17:21:47.620Z,1683912107.620 [DATMMP](INFO): Received command: 2023-05-12T17:21:47.620Z,1683912107.620 [DATMMP](INFO): Received message type: data_rx 2023-05-12T17:21:47.620Z,1683912107.620 [DATMMP](INFO): Handled 2023-05-12T17:21:47.620Z,1683912107.620 [DATMMP](INFO): *** SENDING_VERIFIED *** 2023-05-12T17:21:47.621Z,1683912107.621 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER 2023-05-12T17:21:47.870Z,1683912107.870 [DATMMP](INFO): *** SENDING_FILL_BUFFER *** 2023-05-12T17:21:47.871Z,1683912107.871 [DATMMP](INFO): modem://10: set _.temperature 24.299829 celsius 2023-05-12T17:21:47.872Z,1683912107.872 [DATMMP](INFO): #Outgoing data=2 2023-05-12T17:21:47.872Z,1683912107.872 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA 2023-05-12T17:21:48.122Z,1683912108.122 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2023-05-12T17:21:48.122Z,1683912108.122 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT 2023-05-12T17:21:48.374Z,1683912108.374 [DATMMP](INFO): *** SENDING_TRANSMIT *** 2023-05-12T17:21:48.375Z,1683912108.375 [DATMMP](INFO): Tx to 10 len 34 xid 32 2023-05-12T17:21:48.375Z,1683912108.375 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2023-05-12T17:21:48.375Z,1683912108.375 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-12T17:21:48.626Z,1683912108.626 [DATMMP](INFO): GSXN notify for xid: 32 2023-05-12T17:21:48.628Z,1683912108.628 [DATMMP](INFO): cmd_result rx: x1 x7 x0 x1 2023-05-12T17:21:48.628Z,1683912108.628 [DATMMP](DEBUG): Command complete send 2023-05-12T17:21:48.628Z,1683912108.628 [DATMMP](ERROR): 1:COMPLETE 2023-05-12T17:21:48.628Z,1683912108.628 [DATMMP](INFO): Received message type: cmd_result 2023-05-12T17:21:48.628Z,1683912108.628 [DATMMP](INFO): Handled 2023-05-12T17:21:48.628Z,1683912108.628 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:48.879Z,1683912108.879 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:21:48.880Z,1683912108.880 [DATMMP](INFO): timestamp rx: x0 x11 x15 x2F x1547 2023-05-12T17:21:48.880Z,1683912108.880 [DATMMP](INFO): Received message type: timestamp 2023-05-12T17:21:48.880Z,1683912108.880 [DATMMP](INFO): Handled 2023-05-12T17:21:49.130Z,1683912109.130 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:49.383Z,1683912109.383 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:49.634Z,1683912109.634 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:49.886Z,1683912109.886 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:50.138Z,1683912110.138 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:50.391Z,1683912110.391 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:50.642Z,1683912110.642 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:50.894Z,1683912110.894 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:51.146Z,1683912111.146 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:51.398Z,1683912111.398 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:51.650Z,1683912111.650 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:51.902Z,1683912111.902 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:52.154Z,1683912112.154 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:52.406Z,1683912112.406 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:52.658Z,1683912112.658 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:52.910Z,1683912112.910 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:53.163Z,1683912113.163 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:21:53.163Z,1683912113.163 [DATMMP](INFO): doppler rx: xFFFF 2023-05-12T17:21:53.163Z,1683912113.163 [DATMMP](INFO): Received message type: doppler 2023-05-12T17:21:53.163Z,1683912113.163 [DATMMP](INFO): Handled 2023-05-12T17:21:53.164Z,1683912113.164 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:53.415Z,1683912113.415 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:21:53.416Z,1683912113.416 [DATMMP](INFO): timestamp rx: x1 x11 x15 x33 x1F1E 2023-05-12T17:21:53.416Z,1683912113.416 [DATMMP](INFO): Rx dataTimestamp_ set to:1683912124.831488 2023-05-12T17:21:53.416Z,1683912113.416 [DATMMP](INFO): Rx ping set to:1683912113.415585 2023-05-12T17:21:53.417Z,1683912113.417 [DATMMP](INFO): Received message type: timestamp 2023-05-12T17:21:53.417Z,1683912113.417 [DATMMP](INFO): Handled 2023-05-12T17:21:53.417Z,1683912113.417 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:53.666Z,1683912113.666 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:53.919Z,1683912113.919 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:21:53.920Z,1683912113.920 [DATMMP](INFO): remote_header rx: xF x4 x5 x4 x0 xC xA x0 2023-05-12T17:21:53.921Z,1683912113.921 [DATMMP](INFO): DATA Src=10, Dst=12 2023-05-12T17:21:53.921Z,1683912113.921 [DATMMP](INFO): Received message type: remote_header 2023-05-12T17:21:53.921Z,1683912113.921 [DATMMP](INFO): Handled 2023-05-12T17:21:53.921Z,1683912113.921 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:54.170Z,1683912114.170 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:21:54.171Z,1683912114.171 [DATMMP](INFO): nav_stat rx: x0 x1 x1 2023-05-12T17:21:54.171Z,1683912114.171 [DATMMP](INFO): Received message type: nav_stat 2023-05-12T17:21:54.172Z,1683912114.172 [DATMMP](INFO): Handled 2023-05-12T17:21:54.172Z,1683912114.172 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:54.422Z,1683912114.422 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:21:54.424Z,1683912114.424 [DATMMP](INFO): directional_debug rx: xD0 x192 x23 xAF x1050 x1381 x1232 x16A3 2023-05-12T17:21:54.424Z,1683912114.424 [DATMMP](INFO): Received message type: directional_debug 2023-05-12T17:21:54.425Z,1683912114.425 [DATMMP](INFO): Handled 2023-05-12T17:21:54.425Z,1683912114.425 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:54.675Z,1683912114.675 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:21:54.676Z,1683912114.676 [DATMMP](INFO): directional rx: xC9 x135 x50C xFE6E x8C5 xFE78 x0 x3C5 x5 xFFEE x27B5966 x-43569E0 2023-05-12T17:21:54.678Z,1683912114.678 [DATMMP](INFO): Received message type: directional 2023-05-12T17:21:54.678Z,1683912114.678 [DATMMP](INFO): Handled 2023-05-12T17:21:54.678Z,1683912114.678 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:54.926Z,1683912114.926 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:21:54.928Z,1683912114.928 [DATMMP](INFO): acstats rx: xA x5 xFFFF x29F x23 x86 xB6 xC0 xD xFFFF xAE x25 xC x0 2023-05-12T17:21:54.928Z,1683912114.928 [DATMMP](INFO): Received message type: acstats 2023-05-12T17:21:54.929Z,1683912114.929 [DATMMP](INFO): Handled 2023-05-12T17:21:54.929Z,1683912114.929 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:55.179Z,1683912115.179 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:21:55.179Z,1683912115.179 [DATMMP](INFO): data_rx rx: xA x0 x0 x4 2023-05-12T17:21:55.180Z,1683912115.180 [DATMMP](INFO): Check CRC 2023-05-12T17:21:55.180Z,1683912115.180 [DATMMP](INFO): data rx parseDataResponse 4 2023-05-12T17:21:55.180Z,1683912115.180 [DATMMP](INFO): Got DATA: len 4 2023-05-12T17:21:55.180Z,1683912115.180 [DATMMP](DEBUG): In parseResponses, got ack so set commsState_ = SENDING_VERIFIED 2023-05-12T17:21:55.180Z,1683912115.180 [DATMMP](INFO): Got ~~ ack, bytes left 2 2023-05-12T17:21:55.180Z,1683912115.180 [DATMMP](INFO): Received command: 2023-05-12T17:21:55.181Z,1683912115.181 [DATMMP](INFO): Received message type: data_rx 2023-05-12T17:21:55.181Z,1683912115.181 [DATMMP](INFO): Handled 2023-05-12T17:21:55.181Z,1683912115.181 [DATMMP](INFO): *** SENDING_VERIFIED *** 2023-05-12T17:21:55.181Z,1683912115.181 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER 2023-05-12T17:21:55.430Z,1683912115.430 [DATMMP](INFO): *** SENDING_FILL_BUFFER *** 2023-05-12T17:21:55.431Z,1683912115.431 [DATMMP](INFO): modem://10: set _.humidity 12.879990 percent 2023-05-12T17:21:55.431Z,1683912115.431 [DATMMP](INFO): #Outgoing data=2 2023-05-12T17:21:55.432Z,1683912115.432 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA 2023-05-12T17:21:55.682Z,1683912115.682 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2023-05-12T17:21:55.682Z,1683912115.682 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT 2023-05-12T17:21:55.934Z,1683912115.934 [DATMMP](INFO): *** SENDING_TRANSMIT *** 2023-05-12T17:21:55.935Z,1683912115.935 [DATMMP](INFO): Tx to 10 len 35 xid 33 2023-05-12T17:21:55.935Z,1683912115.935 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2023-05-12T17:21:55.935Z,1683912115.935 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-12T17:21:56.186Z,1683912116.186 [DATMMP](INFO): GSXN notify for xid: 33 2023-05-12T17:21:56.187Z,1683912116.187 [DATMMP](INFO): cmd_result rx: x1 x7 x0 x1 2023-05-12T17:21:56.187Z,1683912116.187 [DATMMP](DEBUG): Command complete send 2023-05-12T17:21:56.188Z,1683912116.188 [DATMMP](ERROR): 1:COMPLETE 2023-05-12T17:21:56.188Z,1683912116.188 [DATMMP](INFO): Received message type: cmd_result 2023-05-12T17:21:56.188Z,1683912116.188 [DATMMP](INFO): Handled 2023-05-12T17:21:56.188Z,1683912116.188 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:56.438Z,1683912116.438 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:21:56.439Z,1683912116.439 [DATMMP](INFO): timestamp rx: x0 x11 x15 x37 x5A7 2023-05-12T17:21:56.439Z,1683912116.439 [DATMMP](INFO): Received message type: timestamp 2023-05-12T17:21:56.440Z,1683912116.440 [DATMMP](INFO): Handled 2023-05-12T17:21:56.440Z,1683912116.440 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:56.690Z,1683912116.690 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:57.202Z,1683912117.202 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:57.454Z,1683912117.454 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:57.706Z,1683912117.706 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:57.958Z,1683912117.958 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:58.210Z,1683912118.210 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:58.462Z,1683912118.462 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:58.715Z,1683912118.715 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:59.218Z,1683912119.218 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:59.471Z,1683912119.471 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:59.722Z,1683912119.722 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:21:59.974Z,1683912119.974 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:22:00.226Z,1683912120.226 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:22:00.478Z,1683912120.478 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:22:00.479Z,1683912120.479 [DATMMP](INFO): doppler rx: xFFFF 2023-05-12T17:22:00.479Z,1683912120.479 [DATMMP](INFO): Received message type: doppler 2023-05-12T17:22:00.479Z,1683912120.479 [DATMMP](INFO): Handled 2023-05-12T17:22:00.479Z,1683912120.479 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:22:00.730Z,1683912120.730 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:22:00.731Z,1683912120.731 [DATMMP](INFO): timestamp rx: x1 x11 x15 x3B x5BA 2023-05-12T17:22:00.732Z,1683912120.732 [DATMMP](INFO): Rx dataTimestamp_ set to:1683912133.331776 2023-05-12T17:22:00.732Z,1683912120.732 [DATMMP](INFO): Rx ping set to:1683912120.731469 2023-05-12T17:22:00.732Z,1683912120.732 [DATMMP](INFO): Received message type: timestamp 2023-05-12T17:22:00.733Z,1683912120.733 [DATMMP](INFO): Handled 2023-05-12T17:22:00.733Z,1683912120.733 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:22:00.982Z,1683912120.982 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:22:01.235Z,1683912121.235 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:22:01.238Z,1683912121.238 [DATMMP](INFO): remote_header rx: xF x4 x5 x4 x0 xC xA x0 2023-05-12T17:22:01.238Z,1683912121.238 [DATMMP](INFO): DATA Src=10, Dst=12 2023-05-12T17:22:01.238Z,1683912121.238 [DATMMP](INFO): Received message type: remote_header 2023-05-12T17:22:01.239Z,1683912121.239 [DATMMP](INFO): Handled 2023-05-12T17:22:01.239Z,1683912121.239 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:22:01.489Z,1683912121.489 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:22:01.490Z,1683912121.490 [DATMMP](INFO): nav_stat rx: x0 x1 x1 2023-05-12T17:22:01.491Z,1683912121.491 [DATMMP](INFO): Received message type: nav_stat 2023-05-12T17:22:01.491Z,1683912121.491 [DATMMP](INFO): Handled 2023-05-12T17:22:01.491Z,1683912121.491 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:22:01.743Z,1683912121.743 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:22:01.744Z,1683912121.744 [DATMMP](INFO): directional_debug rx: x84 x1D9 x22 xB4 x1050 x1331 x1242 x1643 2023-05-12T17:22:01.745Z,1683912121.745 [DATMMP](INFO): Received message type: directional_debug 2023-05-12T17:22:01.745Z,1683912121.745 [DATMMP](INFO): Handled 2023-05-12T17:22:01.745Z,1683912121.745 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:22:01.994Z,1683912121.994 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:22:01.996Z,1683912121.996 [DATMMP](INFO): directional rx: x7D x18D x558 xFE27 x8FB xFE30 x0 x3B4 x5 xFFEE x27B5966 x-43569E0 2023-05-12T17:22:01.997Z,1683912121.997 [DATMMP](INFO): Received message type: directional 2023-05-12T17:22:01.998Z,1683912121.998 [DATMMP](INFO): Handled 2023-05-12T17:22:01.998Z,1683912121.998 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:22:02.246Z,1683912122.246 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:22:02.248Z,1683912122.248 [DATMMP](INFO): acstats rx: xA x5 xFFFF x2CC x22 x84 xB9 xBC xD xFFFF xAF x23 xC x0 2023-05-12T17:22:02.248Z,1683912122.248 [DATMMP](INFO): Received message type: acstats 2023-05-12T17:22:02.248Z,1683912122.248 [DATMMP](INFO): Handled 2023-05-12T17:22:02.248Z,1683912122.248 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:22:02.498Z,1683912122.498 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:22:02.499Z,1683912122.499 [DATMMP](INFO): data_rx rx: xA x0 x0 x4 2023-05-12T17:22:02.499Z,1683912122.499 [DATMMP](INFO): Check CRC 2023-05-12T17:22:02.500Z,1683912122.500 [DATMMP](INFO): data rx parseDataResponse 4 2023-05-12T17:22:02.500Z,1683912122.500 [DATMMP](INFO): Got DATA: len 4 2023-05-12T17:22:02.500Z,1683912122.500 [DATMMP](DEBUG): In parseResponses, got ack so set commsState_ = SENDING_VERIFIED 2023-05-12T17:22:02.500Z,1683912122.500 [DATMMP](INFO): Got ~~ ack, bytes left 2 2023-05-12T17:22:02.500Z,1683912122.500 [DATMMP](INFO): Received command: 2023-05-12T17:22:02.501Z,1683912122.501 [DATMMP](INFO): Received message type: data_rx 2023-05-12T17:22:02.501Z,1683912122.501 [DATMMP](INFO): Handled 2023-05-12T17:22:02.501Z,1683912122.501 [DATMMP](INFO): *** SENDING_VERIFIED *** 2023-05-12T17:22:02.501Z,1683912122.501 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER 2023-05-12T17:22:02.750Z,1683912122.750 [DATMMP](INFO): *** SENDING_FILL_BUFFER *** 2023-05-12T17:22:02.751Z,1683912122.751 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA 2023-05-12T17:22:03.002Z,1683912123.002 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2023-05-12T17:22:03.002Z,1683912123.002 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT 2023-05-12T17:22:03.254Z,1683912123.254 [DATMMP](INFO): *** SENDING_TRANSMIT *** 2023-05-12T17:22:03.254Z,1683912123.254 [DATMMP](INFO): Tx to 10 len 32 xid 34 2023-05-12T17:22:03.255Z,1683912123.255 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2023-05-12T17:22:03.255Z,1683912123.255 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-12T17:22:03.507Z,1683912123.507 [DATMMP](INFO): GSXN notify for xid: 34 2023-05-12T17:22:03.508Z,1683912123.508 [DATMMP](INFO): cmd_result rx: x1 x7 x0 x1 2023-05-12T17:22:03.508Z,1683912123.508 [DATMMP](DEBUG): Command complete send 2023-05-12T17:22:03.508Z,1683912123.508 [DATMMP](ERROR): 1:COMPLETE 2023-05-12T17:22:03.508Z,1683912123.508 [DATMMP](INFO): Received message type: cmd_result 2023-05-12T17:22:03.508Z,1683912123.508 [DATMMP](INFO): Handled 2023-05-12T17:22:03.509Z,1683912123.509 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:22:03.758Z,1683912123.758 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:22:03.759Z,1683912123.759 [DATMMP](INFO): timestamp rx: x0 x11 x16 x2 x115E 2023-05-12T17:22:03.759Z,1683912123.759 [DATMMP](INFO): Received message type: timestamp 2023-05-12T17:22:03.760Z,1683912123.760 [DATMMP](INFO): Handled 2023-05-12T17:22:03.760Z,1683912123.760 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:22:04.010Z,1683912124.010 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:22:04.262Z,1683912124.262 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:22:04.514Z,1683912124.514 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:22:04.766Z,1683912124.766 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:22:05.270Z,1683912125.270 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:22:05.522Z,1683912125.522 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:22:05.774Z,1683912125.774 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:22:06.026Z,1683912126.026 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:22:06.278Z,1683912126.278 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:22:06.530Z,1683912126.530 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:22:06.782Z,1683912126.782 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:22:07.034Z,1683912127.034 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:22:07.286Z,1683912127.286 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:22:07.538Z,1683912127.538 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:22:07.790Z,1683912127.790 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:22:07.791Z,1683912127.791 [DATMMP](INFO): doppler rx: xFFFF 2023-05-12T17:22:07.791Z,1683912127.791 [DATMMP](INFO): Received message type: doppler 2023-05-12T17:22:07.791Z,1683912127.791 [DATMMP](INFO): Handled 2023-05-12T17:22:07.791Z,1683912127.791 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:22:08.042Z,1683912128.042 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:22:08.043Z,1683912128.043 [DATMMP](INFO): timestamp rx: x1 x11 x16 x6 x1172 2023-05-12T17:22:08.044Z,1683912128.044 [DATMMP](INFO): Rx dataTimestamp_ set to:1683912169.643072 2023-05-12T17:22:08.044Z,1683912128.044 [DATMMP](INFO): Rx ping set to:1683912128.043243 2023-05-12T17:22:08.044Z,1683912128.044 [DATMMP](INFO): Received message type: timestamp 2023-05-12T17:22:08.044Z,1683912128.044 [DATMMP](INFO): Handled 2023-05-12T17:22:08.044Z,1683912128.044 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:22:08.309Z,1683912128.309 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:22:08.562Z,1683912128.562 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:22:08.564Z,1683912128.564 [DATMMP](INFO): remote_header rx: xF x4 x5 x4 x0 xC xA x0 2023-05-12T17:22:08.564Z,1683912128.564 [DATMMP](INFO): DATA Src=10, Dst=12 2023-05-12T17:22:08.564Z,1683912128.564 [DATMMP](INFO): Received message type: remote_header 2023-05-12T17:22:08.564Z,1683912128.564 [DATMMP](INFO): Handled 2023-05-12T17:22:08.564Z,1683912128.564 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:22:08.814Z,1683912128.814 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:22:08.815Z,1683912128.815 [DATMMP](INFO): nav_stat rx: x0 x1 x1 2023-05-12T17:22:08.815Z,1683912128.815 [DATMMP](INFO): Received message type: nav_stat 2023-05-12T17:22:08.815Z,1683912128.815 [DATMMP](INFO): Handled 2023-05-12T17:22:08.816Z,1683912128.816 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:22:09.066Z,1683912129.066 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:22:09.068Z,1683912129.068 [DATMMP](INFO): directional_debug rx: x69 x1FD x24 xB5 x1040 x1371 x11F2 x1613 2023-05-12T17:22:09.069Z,1683912129.069 [DATMMP](INFO): Received message type: directional_debug 2023-05-12T17:22:09.069Z,1683912129.069 [DATMMP](INFO): Handled 2023-05-12T17:22:09.069Z,1683912129.069 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:22:09.318Z,1683912129.318 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:22:09.320Z,1683912129.320 [DATMMP](INFO): directional rx: x63 x1B8 x573 xFE03 x903 xFE0D x0 x3A5 x4 xFFED x27B5966 x-43569E0 2023-05-12T17:22:09.321Z,1683912129.321 [DATMMP](INFO): Received message type: directional 2023-05-12T17:22:09.321Z,1683912129.321 [DATMMP](INFO): Handled 2023-05-12T17:22:09.322Z,1683912129.322 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:22:09.570Z,1683912129.570 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:22:09.572Z,1683912129.572 [DATMMP](INFO): acstats rx: xA x5 xFFFF x2CD x22 x91 xB9 xC3 xD xFFFF xAF x25 xD x0 2023-05-12T17:22:09.572Z,1683912129.572 [DATMMP](INFO): Received message type: acstats 2023-05-12T17:22:09.572Z,1683912129.572 [DATMMP](INFO): Handled 2023-05-12T17:22:09.572Z,1683912129.572 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:22:09.822Z,1683912129.822 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:22:09.823Z,1683912129.823 [DATMMP](INFO): data_rx rx: xA x0 x0 x4 2023-05-12T17:22:09.823Z,1683912129.823 [DATMMP](INFO): Check CRC 2023-05-12T17:22:09.823Z,1683912129.823 [DATMMP](INFO): data rx parseDataResponse 4 2023-05-12T17:22:09.824Z,1683912129.824 [DATMMP](INFO): Got DATA: len 4 2023-05-12T17:22:09.824Z,1683912129.824 [DATMMP](DEBUG): In parseResponses, got ack so set commsState_ = SENDING_VERIFIED 2023-05-12T17:22:09.824Z,1683912129.824 [DATMMP](INFO): Got ~~ ack, bytes left 2 2023-05-12T17:22:09.824Z,1683912129.824 [DATMMP](INFO): Received command: 2023-05-12T17:22:09.824Z,1683912129.824 [DATMMP](INFO): Received message type: data_rx 2023-05-12T17:22:09.824Z,1683912129.824 [DATMMP](INFO): Handled 2023-05-12T17:22:09.825Z,1683912129.825 [DATMMP](INFO): *** SENDING_VERIFIED *** 2023-05-12T17:22:09.825Z,1683912129.825 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER 2023-05-12T17:22:19.148Z,1683912139.148 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5. 2023-05-12T17:22:19.167Z,1683912139.167 [BPC1](INFO): Received data from all battery sticks.