2023-05-12T17:24:12.959Z,1683912252.959 [CommandExec](IMPORTANT): got command restart logs 2023-05-12T17:25:03.601Z,1683912303.601 [CommandExec](IMPORTANT): got command run ./Missions/Demo/senddata_direct_and_track_test.xml 2023-05-12T17:25:03.610Z,1683912303.610 [MissionManager](INFO): Loading Mission from file: ./Missions/Demo/senddata_direct_and_track_test.xml 2023-05-12T17:25:03.683Z,1683912303.683 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.AcousticTargetContactLabel = 10.000000 count 2023-05-12T17:25:03.685Z,1683912303.685 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.AcousticTargetDepth = 0.000000 m 2023-05-12T17:25:03.688Z,1683912303.688 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.TrackingUpdatePeriod = 15.000000 s 2023-05-12T17:25:03.690Z,1683912303.690 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.NumberOfPings = 1.000000 count 2023-05-12T17:25:03.692Z,1683912303.692 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.NumberOfFixesLowPass = 2.000000 count 2023-05-12T17:25:03.694Z,1683912303.694 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.NumberOfStartingFixesToIgnore = 2.000000 count 2023-05-12T17:25:03.696Z,1683912303.696 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.BeingInterrogatedTimeout = 1.000000 h 2023-05-12T17:25:03.705Z,1683912303.705 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.AcommsInterval = 1.000000 min 2023-05-12T17:25:03.708Z,1683912303.708 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.modemId1 = 9.000000 enum 2023-05-12T17:25:03.711Z,1683912303.711 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.modemId2 = 5.000000 enum 2023-05-12T17:25:03.713Z,1683912303.713 [MissionManager](INFO): DefineOutput senddata_direct_and_track_test.ModemID = 1.000000 enum 2023-05-12T17:25:03.714Z,1683912303.714 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/AbortDrift.xml 2023-05-12T17:25:03.729Z,1683912303.729 [MissionManager](INFO): DefineArg senddata_direct_and_track_test:AbortDrift.AcousticTimeout = 96.000000 h 2023-05-12T17:25:03.755Z,1683912303.755 [senddata_direct_and_track_test:AbortDrift:B.AbortDrift](DEBUG): Construct AbortDrift. 2023-05-12T17:25:03.757Z,1683912303.757 [senddata_direct_and_track_test:AbortDrift:C.Execute](DEBUG): Construct Execute. 2023-05-12T17:25:03.763Z,1683912303.763 [MissionManager](INFO): RedefineArg senddata_direct_and_track_test:AbortDrift.AcousticTimeout = reader:senddata_direct_and_track_test.BeingInterrogatedTimeout 2023-05-12T17:25:03.780Z,1683912303.780 [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:25:03.787Z,1683912303.787 [CommandExec](IMPORTANT): Running ./Missions/Demo/senddata_direct_and_track_test.xml 2023-05-12T17:25:03.986Z,1683912303.986 [Default] Stopped 2023-05-12T17:25:03.986Z,1683912303.986 [Default](DEBUG): Aggregate::uninitialize Default 2023-05-12T17:25:03.986Z,1683912303.986 [Default:B.GoToSurface] Stopped 2023-05-12T17:25:03.987Z,1683912303.987 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-05-12T17:25:03.987Z,1683912303.987 [Default:CheckIn] Stopped 2023-05-12T17:25:03.987Z,1683912303.987 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-05-12T17:25:03.987Z,1683912303.987 [Default:CheckIn:Read_GPS] Stopped 2023-05-12T17:25:03.987Z,1683912303.987 [MissionManager](IMPORTANT): Started mission senddata_direct_and_track_test 2023-05-12T17:25:03.987Z,1683912303.987 [senddata_direct_and_track_test] Running Loop=1 2023-05-12T17:25:03.987Z,1683912303.987 [senddata_direct_and_track_test](DEBUG): Aggregate::initialize senddata_direct_and_track_test 2023-05-12T17:25:03.987Z,1683912303.987 [senddata_direct_and_track_test:AbortDrift] Running Loop=1 2023-05-12T17:25:03.987Z,1683912303.987 [senddata_direct_and_track_test:AbortDrift](DEBUG): Aggregate::initialize senddata_direct_and_track_test:AbortDrift 2023-05-12T17:25:03.988Z,1683912303.988 [senddata_direct_and_track_test:AbortDrift:A] Running Loop=1 2023-05-12T17:25:03.988Z,1683912303.988 [senddata_direct_and_track_test:DirectData] Running Loop=1 2023-05-12T17:25:03.988Z,1683912303.988 [senddata_direct_and_track_test:DirectData](DEBUG): Aggregate::initialize senddata_direct_and_track_test:DirectData 2023-05-12T17:25:03.988Z,1683912303.988 [senddata_direct_and_track_test:DirectData:A] Running Loop=1 2023-05-12T17:25:03.989Z,1683912303.989 [senddata_direct_and_track_test:DirectData:A](INFO): Queuing up send data for Onboard.Pressure with destination: modem:10:_.pressure 2023-05-12T17:25:03.989Z,1683912303.989 [senddata_direct_and_track_test:DirectData:A] Stopped 2023-05-12T17:25:03.989Z,1683912303.989 [senddata_direct_and_track_test:DirectData:B] Running Loop=1 2023-05-12T17:25:03.989Z,1683912303.989 [senddata_direct_and_track_test:AbortDrift] Running Loop=1 2023-05-12T17:25:03.999Z,1683912303.999 [senddata_direct_and_track_test:AbortDrift:A](INFO): Insert acoustic timeout set to 1.000000 h . 2023-05-12T17:25:03.999Z,1683912303.999 [senddata_direct_and_track_test:AbortDrift:A] Stopped 2023-05-12T17:25:03.999Z,1683912303.999 [senddata_direct_and_track_test:AbortDrift:B.AbortDrift] Running Loop=1 2023-05-12T17:25:03.999Z,1683912303.999 [senddata_direct_and_track_test:AbortDrift:B.AbortDrift](INFO): Initializing AbortDrift. 2023-05-12T17:25:03.999Z,1683912303.999 [senddata_direct_and_track_test:AbortDrift:B.AbortDrift](IMPORTANT): Acoustic timeout set to: 1.00 hours. 2023-05-12T17:25:04.026Z,1683912304.026 [DATMMP](INFO): *** SENDING_FILL_BUFFER *** 2023-05-12T17:25:04.027Z,1683912304.027 [DATMMP](INFO): modem://10: set _.pressure 0.743017 atmosphere 2023-05-12T17:25:04.027Z,1683912304.027 [DATMMP](INFO): #Outgoing data=1 2023-05-12T17:25:04.028Z,1683912304.028 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA 2023-05-12T17:25:04.278Z,1683912304.278 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2023-05-12T17:25:04.278Z,1683912304.278 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT 2023-05-12T17:25:04.420Z,1683912304.420 [senddata_direct_and_track_test:DirectData:B](INFO): Queuing up send data for Onboard.Temperature with destination: modem:10:_.temperature 2023-05-12T17:25:04.420Z,1683912304.420 [senddata_direct_and_track_test:DirectData:B] Stopped 2023-05-12T17:25:04.420Z,1683912304.420 [senddata_direct_and_track_test:DirectData:C] Running Loop=1 2023-05-12T17:25:04.421Z,1683912304.421 [senddata_direct_and_track_test:AbortDrift:B.AbortDrift](INFO): Received valid gps time fix: 1683911680.00. Resetting abort timer. 2023-05-12T17:25:04.426Z,1683912304.426 [senddata_direct_and_track_test:AbortDrift:B.AbortDrift](INFO): Received valid acoustic ping at time: 1683912192.00. Resetting abort timer. 2023-05-12T17:25:04.530Z,1683912304.530 [DATMMP](INFO): *** SENDING_TRANSMIT *** 2023-05-12T17:25:04.531Z,1683912304.531 [DATMMP](INFO): Tx to 10 len 34 xid 41 2023-05-12T17:25:04.531Z,1683912304.531 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2023-05-12T17:25:04.531Z,1683912304.531 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-12T17:25:04.782Z,1683912304.782 [DATMMP](INFO): GSXN notify for xid: 41 2023-05-12T17:25:04.783Z,1683912304.783 [DATMMP](INFO): cmd_result rx: x1 x7 x0 x1 2023-05-12T17:25:04.783Z,1683912304.783 [DATMMP](DEBUG): Command complete send 2023-05-12T17:25:04.783Z,1683912304.783 [DATMMP](ERROR): 1:COMPLETE 2023-05-12T17:25:04.784Z,1683912304.784 [DATMMP](INFO): Received message type: cmd_result 2023-05-12T17:25:04.784Z,1683912304.784 [DATMMP](INFO): Handled 2023-05-12T17:25:04.784Z,1683912304.784 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:04.844Z,1683912304.844 [senddata_direct_and_track_test:DirectData:C](INFO): Queuing up send data for Onboard.Humidity with destination: modem:10:_.humidity 2023-05-12T17:25:04.844Z,1683912304.844 [senddata_direct_and_track_test:DirectData:C] Stopped 2023-05-12T17:25:04.845Z,1683912304.845 [senddata_direct_and_track_test:DirectData](INFO): Completed senddata_direct_and_track_test:DirectData 2023-05-12T17:25:04.845Z,1683912304.845 [senddata_direct_and_track_test:DirectData] Stopped 2023-05-12T17:25:04.845Z,1683912304.845 [senddata_direct_and_track_test:DirectData](DEBUG): Aggregate::uninitialize senddata_direct_and_track_test:DirectData 2023-05-12T17:25:04.845Z,1683912304.845 [senddata_direct_and_track_test:DirectData](INFO): Running loop #2 2023-05-12T17:25:04.845Z,1683912304.845 [senddata_direct_and_track_test:DirectData] Running Loop=2 2023-05-12T17:25:04.845Z,1683912304.845 [senddata_direct_and_track_test:DirectData](DEBUG): Aggregate::initialize senddata_direct_and_track_test:DirectData 2023-05-12T17:25:04.845Z,1683912304.845 [senddata_direct_and_track_test:DirectData:A] Running Loop=1 2023-05-12T17:25:05.034Z,1683912305.034 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:05.035Z,1683912305.035 [DATMMP](INFO): timestamp rx: x0 x11 x19 x3 x1D06 2023-05-12T17:25:05.035Z,1683912305.035 [DATMMP](INFO): Received message type: timestamp 2023-05-12T17:25:05.035Z,1683912305.035 [DATMMP](INFO): Handled 2023-05-12T17:25:05.036Z,1683912305.036 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:05.208Z,1683912305.208 [senddata_direct_and_track_test:DirectData:A](INFO): Queuing up send data for Onboard.Pressure with destination: modem:10:_.pressure 2023-05-12T17:25:05.208Z,1683912305.208 [senddata_direct_and_track_test:DirectData:A] Stopped 2023-05-12T17:25:05.208Z,1683912305.208 [senddata_direct_and_track_test:DirectData:B] Running Loop=1 2023-05-12T17:25:05.286Z,1683912305.286 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:05.538Z,1683912305.538 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:05.619Z,1683912305.619 [senddata_direct_and_track_test:DirectData:B](INFO): Queuing up send data for Onboard.Temperature with destination: modem:10:_.temperature 2023-05-12T17:25:05.620Z,1683912305.620 [senddata_direct_and_track_test:DirectData:B] Stopped 2023-05-12T17:25:05.620Z,1683912305.620 [senddata_direct_and_track_test:DirectData:C] Running Loop=1 2023-05-12T17:25:05.790Z,1683912305.790 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:06.006Z,1683912306.006 [senddata_direct_and_track_test:DirectData:C](INFO): Queuing up send data for Onboard.Humidity with destination: modem:10:_.humidity 2023-05-12T17:25:06.006Z,1683912306.006 [senddata_direct_and_track_test:DirectData:C] Stopped 2023-05-12T17:25:06.007Z,1683912306.007 [senddata_direct_and_track_test:DirectData](INFO): Completed senddata_direct_and_track_test:DirectData 2023-05-12T17:25:06.007Z,1683912306.007 [senddata_direct_and_track_test:DirectData] Stopped 2023-05-12T17:25:06.007Z,1683912306.007 [senddata_direct_and_track_test:DirectData](DEBUG): Aggregate::uninitialize senddata_direct_and_track_test:DirectData 2023-05-12T17:25:06.007Z,1683912306.007 [senddata_direct_and_track_test](INFO): Completed senddata_direct_and_track_test 2023-05-12T17:25:06.007Z,1683912306.007 [MissionManager](INFO): senddata_direct_and_track_test is completed. 2023-05-12T17:25:06.008Z,1683912306.008 [MissionManager](INFO): Uninitializing Mission senddata_direct_and_track_test 2023-05-12T17:25:06.008Z,1683912306.008 [senddata_direct_and_track_test] Stopped 2023-05-12T17:25:06.008Z,1683912306.008 [senddata_direct_and_track_test](DEBUG): Aggregate::uninitialize senddata_direct_and_track_test 2023-05-12T17:25:06.008Z,1683912306.008 [senddata_direct_and_track_test:AbortDrift] Stopped 2023-05-12T17:25:06.008Z,1683912306.008 [senddata_direct_and_track_test:AbortDrift](DEBUG): Aggregate::uninitialize senddata_direct_and_track_test:AbortDrift 2023-05-12T17:25:06.008Z,1683912306.008 [senddata_direct_and_track_test:AbortDrift:B.AbortDrift] Stopped 2023-05-12T17:25:06.008Z,1683912306.008 [senddata_direct_and_track_test:AbortDrift:B.AbortDrift](INFO): Uninitializing AbortDrift. 2023-05-12T17:25:06.051Z,1683912306.051 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:06.302Z,1683912306.302 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:06.420Z,1683912306.420 [MissionManager](IMPORTANT): Started mission Default 2023-05-12T17:25:06.420Z,1683912306.420 [Default] Running Loop=1 2023-05-12T17:25:06.420Z,1683912306.420 [Default](DEBUG): Aggregate::initialize Default 2023-05-12T17:25:06.420Z,1683912306.420 [Default:B.GoToSurface] Running Loop=1 2023-05-12T17:25:06.421Z,1683912306.421 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-05-12T17:25:06.421Z,1683912306.421 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-05-12T17:25:06.421Z,1683912306.421 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-05-12T17:25:06.422Z,1683912306.422 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-05-12T17:25:06.422Z,1683912306.422 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-05-12T17:25:06.422Z,1683912306.422 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-05-12T17:25:06.423Z,1683912306.423 [Default:A.Wait] Running Loop=1 2023-05-12T17:25:06.423Z,1683912306.423 [Default:A.Wait](DEBUG): Initialize Wait Component. 2023-05-12T17:25:06.554Z,1683912306.554 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:07.058Z,1683912307.058 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:07.310Z,1683912307.310 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:07.562Z,1683912307.562 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:07.814Z,1683912307.814 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:08.066Z,1683912308.066 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:08.318Z,1683912308.318 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:08.570Z,1683912308.570 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:08.822Z,1683912308.822 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:09.074Z,1683912309.074 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:09.075Z,1683912309.075 [DATMMP](INFO): doppler rx: xFFFF 2023-05-12T17:25:09.075Z,1683912309.075 [DATMMP](INFO): Received message type: doppler 2023-05-12T17:25:09.075Z,1683912309.075 [DATMMP](INFO): Handled 2023-05-12T17:25:09.075Z,1683912309.075 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:09.326Z,1683912309.326 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:09.327Z,1683912309.327 [DATMMP](INFO): timestamp rx: x1 x11 x19 x7 x1D21 2023-05-12T17:25:09.328Z,1683912309.328 [DATMMP](INFO): Rx dataTimestamp_ set to:1683912315.862208 2023-05-12T17:25:09.328Z,1683912309.328 [DATMMP](INFO): Rx ping set to:1683912309.327259 2023-05-12T17:25:09.328Z,1683912309.328 [DATMMP](INFO): Received message type: timestamp 2023-05-12T17:25:09.328Z,1683912309.328 [DATMMP](INFO): Handled 2023-05-12T17:25:09.329Z,1683912309.329 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:09.578Z,1683912309.578 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:09.831Z,1683912309.831 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:09.833Z,1683912309.833 [DATMMP](INFO): remote_header rx: xF x4 x5 x4 x0 xC xA x0 2023-05-12T17:25:09.833Z,1683912309.833 [DATMMP](INFO): DATA Src=10, Dst=12 2023-05-12T17:25:09.834Z,1683912309.834 [DATMMP](INFO): Received message type: remote_header 2023-05-12T17:25:09.834Z,1683912309.834 [DATMMP](INFO): Handled 2023-05-12T17:25:09.834Z,1683912309.834 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:10.082Z,1683912310.082 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:10.083Z,1683912310.083 [DATMMP](INFO): nav_stat rx: x0 x1 x1 2023-05-12T17:25:10.083Z,1683912310.083 [DATMMP](INFO): Received message type: nav_stat 2023-05-12T17:25:10.083Z,1683912310.083 [DATMMP](INFO): Handled 2023-05-12T17:25:10.084Z,1683912310.084 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:10.335Z,1683912310.335 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:10.336Z,1683912310.336 [DATMMP](INFO): directional_debug rx: xEB x19E x23 xAE x1070 x13C1 x1232 x1683 2023-05-12T17:25:10.337Z,1683912310.337 [DATMMP](INFO): Received message type: directional_debug 2023-05-12T17:25:10.337Z,1683912310.337 [DATMMP](INFO): Handled 2023-05-12T17:25:10.337Z,1683912310.337 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:10.586Z,1683912310.586 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:10.588Z,1683912310.588 [DATMMP](INFO): directional rx: xE5 x141 x4F1 xFE62 x722 xFE6E x0 x23D x4 xFFEF x27B5966 x-43569E0 2023-05-12T17:25:10.590Z,1683912310.590 [DATMMP](INFO): Received message type: directional 2023-05-12T17:25:10.590Z,1683912310.590 [DATMMP](INFO): Handled 2023-05-12T17:25:10.590Z,1683912310.590 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:10.838Z,1683912310.838 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:10.840Z,1683912310.840 [DATMMP](INFO): acstats rx: xA x5 xFFFF x2CE x23 x87 xB7 xBD xD xFFFF xAE x23 xC x0 2023-05-12T17:25:10.840Z,1683912310.840 [DATMMP](INFO): Received message type: acstats 2023-05-12T17:25:10.840Z,1683912310.840 [DATMMP](INFO): Handled 2023-05-12T17:25:10.841Z,1683912310.841 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:11.090Z,1683912311.090 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:11.091Z,1683912311.091 [DATMMP](INFO): data_rx rx: xA x0 x0 x4 2023-05-12T17:25:11.091Z,1683912311.091 [DATMMP](INFO): Check CRC 2023-05-12T17:25:11.091Z,1683912311.091 [DATMMP](INFO): data rx parseDataResponse 4 2023-05-12T17:25:11.092Z,1683912311.092 [DATMMP](INFO): Got DATA: len 4 2023-05-12T17:25:11.092Z,1683912311.092 [DATMMP](DEBUG): In parseResponses, got ack so set commsState_ = SENDING_VERIFIED 2023-05-12T17:25:11.092Z,1683912311.092 [DATMMP](INFO): Got ~~ ack, bytes left 2 2023-05-12T17:25:11.092Z,1683912311.092 [DATMMP](INFO): Received command: 2023-05-12T17:25:11.092Z,1683912311.092 [DATMMP](INFO): Received message type: data_rx 2023-05-12T17:25:11.092Z,1683912311.092 [DATMMP](INFO): Handled 2023-05-12T17:25:11.092Z,1683912311.092 [DATMMP](INFO): *** SENDING_VERIFIED *** 2023-05-12T17:25:11.093Z,1683912311.093 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER 2023-05-12T17:25:11.342Z,1683912311.342 [DATMMP](INFO): *** SENDING_FILL_BUFFER *** 2023-05-12T17:25:11.343Z,1683912311.343 [DATMMP](INFO): modem://10: set _.temperature 24.340143 celsius 2023-05-12T17:25:11.343Z,1683912311.343 [DATMMP](INFO): #Outgoing data=1 2023-05-12T17:25:11.343Z,1683912311.343 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA 2023-05-12T17:25:11.594Z,1683912311.594 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2023-05-12T17:25:11.594Z,1683912311.594 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT 2023-05-12T17:25:11.846Z,1683912311.846 [DATMMP](INFO): *** SENDING_TRANSMIT *** 2023-05-12T17:25:11.847Z,1683912311.847 [DATMMP](INFO): Tx to 10 len 35 xid 42 2023-05-12T17:25:11.847Z,1683912311.847 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2023-05-12T17:25:11.847Z,1683912311.847 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-12T17:25:12.098Z,1683912312.098 [DATMMP](INFO): GSXN notify for xid: 42 2023-05-12T17:25:12.099Z,1683912312.099 [DATMMP](INFO): cmd_result rx: x1 x7 x0 x1 2023-05-12T17:25:12.099Z,1683912312.099 [DATMMP](DEBUG): Command complete send 2023-05-12T17:25:12.100Z,1683912312.100 [DATMMP](ERROR): 1:COMPLETE 2023-05-12T17:25:12.100Z,1683912312.100 [DATMMP](INFO): Received message type: cmd_result 2023-05-12T17:25:12.100Z,1683912312.100 [DATMMP](INFO): Handled 2023-05-12T17:25:12.100Z,1683912312.100 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:12.350Z,1683912312.350 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:12.351Z,1683912312.351 [DATMMP](INFO): timestamp rx: x0 x11 x19 xB x1AD 2023-05-12T17:25:12.351Z,1683912312.351 [DATMMP](INFO): Received message type: timestamp 2023-05-12T17:25:12.352Z,1683912312.352 [DATMMP](INFO): Handled 2023-05-12T17:25:12.352Z,1683912312.352 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:12.602Z,1683912312.602 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:12.858Z,1683912312.858 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:13.112Z,1683912313.112 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:13.362Z,1683912313.362 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:13.614Z,1683912313.614 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:13.866Z,1683912313.866 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:14.118Z,1683912314.118 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:14.370Z,1683912314.370 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:14.622Z,1683912314.622 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:14.878Z,1683912314.878 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:15.130Z,1683912315.130 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:15.382Z,1683912315.382 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:15.634Z,1683912315.634 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:15.886Z,1683912315.886 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:16.138Z,1683912316.138 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:16.390Z,1683912316.390 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:16.642Z,1683912316.642 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:16.643Z,1683912316.643 [DATMMP](INFO): doppler rx: xFFFF 2023-05-12T17:25:16.643Z,1683912316.643 [DATMMP](INFO): Received message type: doppler 2023-05-12T17:25:16.643Z,1683912316.643 [DATMMP](INFO): Handled 2023-05-12T17:25:16.643Z,1683912316.643 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:16.894Z,1683912316.894 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:16.895Z,1683912316.895 [DATMMP](INFO): timestamp rx: x1 x11 x19 xF x3BE 2023-05-12T17:25:16.896Z,1683912316.896 [DATMMP](INFO): Rx dataTimestamp_ set to:1683912324.372736 2023-05-12T17:25:16.896Z,1683912316.896 [DATMMP](INFO): Rx ping set to:1683912316.895474 2023-05-12T17:25:16.896Z,1683912316.896 [DATMMP](INFO): Received message type: timestamp 2023-05-12T17:25:16.897Z,1683912316.897 [DATMMP](INFO): Handled 2023-05-12T17:25:16.897Z,1683912316.897 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:17.147Z,1683912317.147 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:17.148Z,1683912317.148 [DATMMP](INFO): remote_header rx: xF x4 x5 x4 x0 xC xA x0 2023-05-12T17:25:17.148Z,1683912317.148 [DATMMP](INFO): DATA Src=10, Dst=12 2023-05-12T17:25:17.148Z,1683912317.148 [DATMMP](INFO): Received message type: remote_header 2023-05-12T17:25:17.148Z,1683912317.148 [DATMMP](INFO): Handled 2023-05-12T17:25:17.149Z,1683912317.149 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:17.398Z,1683912317.398 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:17.399Z,1683912317.399 [DATMMP](INFO): nav_stat rx: x0 x1 x1 2023-05-12T17:25:17.399Z,1683912317.399 [DATMMP](INFO): Received message type: nav_stat 2023-05-12T17:25:17.399Z,1683912317.399 [DATMMP](INFO): Handled 2023-05-12T17:25:17.399Z,1683912317.399 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:17.650Z,1683912317.650 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:17.651Z,1683912317.651 [DATMMP](INFO): directional_debug rx: xCD x1A5 x22 xB7 x1040 x1351 x11F2 x1633 2023-05-12T17:25:17.652Z,1683912317.652 [DATMMP](INFO): Received message type: directional_debug 2023-05-12T17:25:17.652Z,1683912317.652 [DATMMP](INFO): Handled 2023-05-12T17:25:17.653Z,1683912317.653 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:17.903Z,1683912317.903 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:17.905Z,1683912317.905 [DATMMP](INFO): directional rx: xC7 x14B x50F xFE5B x72F xFE67 x0 x22F x5 xFFEC x27B5966 x-43569E0 2023-05-12T17:25:17.907Z,1683912317.907 [DATMMP](INFO): Received message type: directional 2023-05-12T17:25:17.907Z,1683912317.907 [DATMMP](INFO): Handled 2023-05-12T17:25:17.907Z,1683912317.907 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:18.154Z,1683912318.154 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:18.156Z,1683912318.156 [DATMMP](INFO): acstats rx: xA x5 xFFFF x2A6 x22 x83 xC1 xB4 xD xFFFF xAF x23 xC x0 2023-05-12T17:25:18.156Z,1683912318.156 [DATMMP](INFO): Received message type: acstats 2023-05-12T17:25:18.157Z,1683912318.157 [DATMMP](INFO): Handled 2023-05-12T17:25:18.157Z,1683912318.157 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:18.406Z,1683912318.406 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:18.407Z,1683912318.407 [DATMMP](INFO): data_rx rx: xA x0 x0 x4 2023-05-12T17:25:18.407Z,1683912318.407 [DATMMP](INFO): Check CRC 2023-05-12T17:25:18.407Z,1683912318.407 [DATMMP](INFO): data rx parseDataResponse 4 2023-05-12T17:25:18.407Z,1683912318.407 [DATMMP](INFO): Got DATA: len 4 2023-05-12T17:25:18.408Z,1683912318.408 [DATMMP](DEBUG): In parseResponses, got ack so set commsState_ = SENDING_VERIFIED 2023-05-12T17:25:18.408Z,1683912318.408 [DATMMP](INFO): Got ~~ ack, bytes left 2 2023-05-12T17:25:18.408Z,1683912318.408 [DATMMP](INFO): Received command: 2023-05-12T17:25:18.408Z,1683912318.408 [DATMMP](INFO): Received message type: data_rx 2023-05-12T17:25:18.408Z,1683912318.408 [DATMMP](INFO): Handled 2023-05-12T17:25:18.408Z,1683912318.408 [DATMMP](INFO): *** SENDING_VERIFIED *** 2023-05-12T17:25:18.409Z,1683912318.409 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER 2023-05-12T17:25:18.658Z,1683912318.658 [DATMMP](INFO): *** SENDING_FILL_BUFFER *** 2023-05-12T17:25:18.659Z,1683912318.659 [DATMMP](INFO): modem://10: set _.humidity 12.879990 percent 2023-05-12T17:25:18.659Z,1683912318.659 [DATMMP](INFO): #Outgoing data=1 2023-05-12T17:25:18.659Z,1683912318.659 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA 2023-05-12T17:25:18.911Z,1683912318.911 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2023-05-12T17:25:18.911Z,1683912318.911 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT 2023-05-12T17:25:19.162Z,1683912319.162 [DATMMP](INFO): *** SENDING_TRANSMIT *** 2023-05-12T17:25:19.163Z,1683912319.163 [DATMMP](INFO): Tx to 10 len 32 xid 43 2023-05-12T17:25:19.163Z,1683912319.163 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2023-05-12T17:25:19.163Z,1683912319.163 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-12T17:25:19.414Z,1683912319.414 [DATMMP](INFO): GSXN notify for xid: 43 2023-05-12T17:25:19.415Z,1683912319.415 [DATMMP](INFO): cmd_result rx: x1 x7 x0 x1 2023-05-12T17:25:19.415Z,1683912319.415 [DATMMP](DEBUG): Command complete send 2023-05-12T17:25:19.415Z,1683912319.415 [DATMMP](ERROR): 1:COMPLETE 2023-05-12T17:25:19.416Z,1683912319.416 [DATMMP](INFO): Received message type: cmd_result 2023-05-12T17:25:19.416Z,1683912319.416 [DATMMP](INFO): Handled 2023-05-12T17:25:19.416Z,1683912319.416 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:19.666Z,1683912319.666 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:19.667Z,1683912319.667 [DATMMP](INFO): timestamp rx: x0 x11 x19 x12 xD64 2023-05-12T17:25:19.667Z,1683912319.667 [DATMMP](INFO): Received message type: timestamp 2023-05-12T17:25:19.667Z,1683912319.667 [DATMMP](INFO): Handled 2023-05-12T17:25:19.668Z,1683912319.668 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:19.748Z,1683912319.748 [Default:A.Wait](INFO): Done Waiting. 2023-05-12T17:25:19.749Z,1683912319.749 [Default:A.Wait] Stopped 2023-05-12T17:25:19.749Z,1683912319.749 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2023-05-12T17:25:19.918Z,1683912319.918 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:20.160Z,1683912320.160 [Default:CheckIn] Running Loop=1 2023-05-12T17:25:20.160Z,1683912320.160 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-05-12T17:25:20.160Z,1683912320.160 [Default:CheckIn:Read_GPS] Running Loop=1 2023-05-12T17:25:20.170Z,1683912320.170 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:20.422Z,1683912320.422 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:20.674Z,1683912320.674 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:20.926Z,1683912320.926 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:21.178Z,1683912321.178 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:21.430Z,1683912321.430 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:21.682Z,1683912321.682 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:21.934Z,1683912321.934 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:22.186Z,1683912322.186 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:22.438Z,1683912322.438 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:22.690Z,1683912322.690 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:22.942Z,1683912322.942 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:23.194Z,1683912323.194 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:23.446Z,1683912323.446 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:23.698Z,1683912323.698 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:23.950Z,1683912323.950 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:23.951Z,1683912323.951 [DATMMP](INFO): doppler rx: x0 2023-05-12T17:25:23.951Z,1683912323.951 [DATMMP](INFO): Received message type: doppler 2023-05-12T17:25:23.951Z,1683912323.951 [DATMMP](INFO): Handled 2023-05-12T17:25:23.951Z,1683912323.951 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:24.202Z,1683912324.202 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:24.203Z,1683912324.203 [DATMMP](INFO): timestamp rx: x1 x11 x19 x16 xF75 2023-05-12T17:25:24.204Z,1683912324.204 [DATMMP](INFO): Rx dataTimestamp_ set to:1683912360.673792 2023-05-12T17:25:24.204Z,1683912324.204 [DATMMP](INFO): Rx ping set to:1683912324.203524 2023-05-12T17:25:24.204Z,1683912324.204 [DATMMP](INFO): Received message type: timestamp 2023-05-12T17:25:24.205Z,1683912324.205 [DATMMP](INFO): Handled 2023-05-12T17:25:24.205Z,1683912324.205 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:24.454Z,1683912324.454 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:24.455Z,1683912324.455 [DATMMP](INFO): remote_header rx: xF x4 x5 x4 x0 xC xA x0 2023-05-12T17:25:24.456Z,1683912324.456 [DATMMP](INFO): DATA Src=10, Dst=12 2023-05-12T17:25:24.456Z,1683912324.456 [DATMMP](INFO): Received message type: remote_header 2023-05-12T17:25:24.456Z,1683912324.456 [DATMMP](INFO): Handled 2023-05-12T17:25:24.456Z,1683912324.456 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:24.706Z,1683912324.706 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:24.707Z,1683912324.707 [DATMMP](INFO): nav_stat rx: x0 x1 x1 2023-05-12T17:25:24.707Z,1683912324.707 [DATMMP](INFO): Received message type: nav_stat 2023-05-12T17:25:24.707Z,1683912324.707 [DATMMP](INFO): Handled 2023-05-12T17:25:24.707Z,1683912324.707 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:24.958Z,1683912324.958 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:24.959Z,1683912324.959 [DATMMP](INFO): directional_debug rx: x63 x1EE x24 xB7 x1070 x1351 x1222 x1673 2023-05-12T17:25:24.960Z,1683912324.960 [DATMMP](INFO): Received message type: directional_debug 2023-05-12T17:25:24.960Z,1683912324.960 [DATMMP](INFO): Handled 2023-05-12T17:25:24.961Z,1683912324.961 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:25.211Z,1683912325.211 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:25.212Z,1683912325.212 [DATMMP](INFO): directional rx: x5D x1A7 x579 xFE12 x785 xFE1B x0 x220 x4 xFFED x27B5966 x-43569E0 2023-05-12T17:25:25.214Z,1683912325.214 [DATMMP](INFO): Received message type: directional 2023-05-12T17:25:25.214Z,1683912325.214 [DATMMP](INFO): Handled 2023-05-12T17:25:25.214Z,1683912325.214 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:25.464Z,1683912325.464 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:25.465Z,1683912325.465 [DATMMP](INFO): acstats rx: xA x5 x0 x2D0 x22 x93 xB6 xB8 xD xFFFF xAD x22 xD x0 2023-05-12T17:25:25.466Z,1683912325.466 [DATMMP](INFO): Received message type: acstats 2023-05-12T17:25:25.466Z,1683912325.466 [DATMMP](INFO): Handled 2023-05-12T17:25:25.467Z,1683912325.467 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:25.714Z,1683912325.714 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:25.715Z,1683912325.715 [DATMMP](INFO): data_rx rx: xA x0 x0 x4 2023-05-12T17:25:25.715Z,1683912325.715 [DATMMP](INFO): Check CRC 2023-05-12T17:25:25.716Z,1683912325.716 [DATMMP](INFO): data rx parseDataResponse 4 2023-05-12T17:25:25.716Z,1683912325.716 [DATMMP](INFO): Got DATA: len 4 2023-05-12T17:25:25.716Z,1683912325.716 [DATMMP](DEBUG): In parseResponses, got ack so set commsState_ = SENDING_VERIFIED 2023-05-12T17:25:25.716Z,1683912325.716 [DATMMP](INFO): Got ~~ ack, bytes left 2 2023-05-12T17:25:25.716Z,1683912325.716 [DATMMP](INFO): Received command: 2023-05-12T17:25:25.716Z,1683912325.716 [DATMMP](INFO): Received message type: data_rx 2023-05-12T17:25:25.716Z,1683912325.716 [DATMMP](INFO): Handled 2023-05-12T17:25:25.717Z,1683912325.717 [DATMMP](INFO): *** SENDING_VERIFIED *** 2023-05-12T17:25:25.717Z,1683912325.717 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER 2023-05-12T17:25:25.966Z,1683912325.966 [DATMMP](INFO): *** SENDING_FILL_BUFFER *** 2023-05-12T17:25:25.967Z,1683912325.967 [DATMMP](INFO): modem://10: set _.pressure 0.743017 atmosphere 2023-05-12T17:25:25.967Z,1683912325.967 [DATMMP](INFO): #Outgoing data=1 2023-05-12T17:25:25.967Z,1683912325.967 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA 2023-05-12T17:25:26.218Z,1683912326.218 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2023-05-12T17:25:26.218Z,1683912326.218 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT 2023-05-12T17:25:26.470Z,1683912326.470 [DATMMP](INFO): *** SENDING_TRANSMIT *** 2023-05-12T17:25:26.471Z,1683912326.471 [DATMMP](INFO): Tx to 10 len 34 xid 44 2023-05-12T17:25:26.471Z,1683912326.471 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2023-05-12T17:25:26.471Z,1683912326.471 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-12T17:25:26.722Z,1683912326.722 [DATMMP](INFO): GSXN notify for xid: 44 2023-05-12T17:25:26.723Z,1683912326.723 [DATMMP](INFO): cmd_result rx: x1 x7 x0 x1 2023-05-12T17:25:26.723Z,1683912326.723 [DATMMP](DEBUG): Command complete send 2023-05-12T17:25:26.724Z,1683912326.724 [DATMMP](ERROR): 1:COMPLETE 2023-05-12T17:25:26.724Z,1683912326.724 [DATMMP](INFO): Received message type: cmd_result 2023-05-12T17:25:26.724Z,1683912326.724 [DATMMP](INFO): Handled 2023-05-12T17:25:26.724Z,1683912326.724 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:26.974Z,1683912326.974 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:26.975Z,1683912326.975 [DATMMP](INFO): timestamp rx: x0 x11 x19 x19 x191C 2023-05-12T17:25:26.975Z,1683912326.975 [DATMMP](INFO): Received message type: timestamp 2023-05-12T17:25:26.975Z,1683912326.975 [DATMMP](INFO): Handled 2023-05-12T17:25:26.976Z,1683912326.976 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:27.226Z,1683912327.226 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:27.478Z,1683912327.478 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:27.730Z,1683912327.730 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:27.982Z,1683912327.982 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:28.234Z,1683912328.234 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:28.486Z,1683912328.486 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:28.738Z,1683912328.738 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:28.990Z,1683912328.990 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:29.242Z,1683912329.242 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:29.494Z,1683912329.494 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:29.746Z,1683912329.746 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:29.998Z,1683912329.998 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:30.502Z,1683912330.502 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:30.754Z,1683912330.754 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:31.006Z,1683912331.006 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:31.258Z,1683912331.258 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:31.259Z,1683912331.259 [DATMMP](INFO): doppler rx: xFFFF 2023-05-12T17:25:31.259Z,1683912331.259 [DATMMP](INFO): Received message type: doppler 2023-05-12T17:25:31.259Z,1683912331.259 [DATMMP](INFO): Handled 2023-05-12T17:25:31.260Z,1683912331.260 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:31.510Z,1683912331.510 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:31.511Z,1683912331.511 [DATMMP](INFO): timestamp rx: x1 x11 x19 x1D x24F0 2023-05-12T17:25:31.512Z,1683912331.512 [DATMMP](INFO): Rx dataTimestamp_ set to:1683912357.360448 2023-05-12T17:25:31.512Z,1683912331.512 [DATMMP](INFO): Rx ping set to:1683912331.511361 2023-05-12T17:25:31.512Z,1683912331.512 [DATMMP](INFO): Received message type: timestamp 2023-05-12T17:25:31.512Z,1683912331.512 [DATMMP](INFO): Handled 2023-05-12T17:25:31.513Z,1683912331.513 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:31.762Z,1683912331.762 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:32.017Z,1683912332.017 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:32.020Z,1683912332.020 [DATMMP](INFO): remote_header rx: xF x4 x5 x4 x0 xC xA x0 2023-05-12T17:25:32.021Z,1683912332.021 [DATMMP](INFO): DATA Src=10, Dst=12 2023-05-12T17:25:32.021Z,1683912332.021 [DATMMP](INFO): Received message type: remote_header 2023-05-12T17:25:32.021Z,1683912332.021 [DATMMP](INFO): Handled 2023-05-12T17:25:32.022Z,1683912332.022 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:32.270Z,1683912332.270 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:32.271Z,1683912332.271 [DATMMP](INFO): nav_stat rx: x0 x1 x1 2023-05-12T17:25:32.271Z,1683912332.271 [DATMMP](INFO): Received message type: nav_stat 2023-05-12T17:25:32.271Z,1683912332.271 [DATMMP](INFO): Handled 2023-05-12T17:25:32.272Z,1683912332.272 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:32.522Z,1683912332.522 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:32.524Z,1683912332.524 [DATMMP](INFO): directional_debug rx: x219 x1E9 x23 x1A5 x1070 x1361 x1212 x16D3 2023-05-12T17:25:32.524Z,1683912332.524 [DATMMP](INFO): Received message type: directional_debug 2023-05-12T17:25:32.525Z,1683912332.525 [DATMMP](INFO): Handled 2023-05-12T17:25:32.525Z,1683912332.525 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:32.774Z,1683912332.774 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:32.776Z,1683912332.776 [DATMMP](INFO): directional rx: x221 x196 x3C3 xFE17 x5CB xFE29 x0 x20E x3 xFFED x27B5966 x-43569E0 2023-05-12T17:25:32.778Z,1683912332.778 [DATMMP](INFO): Received message type: directional 2023-05-12T17:25:32.778Z,1683912332.778 [DATMMP](INFO): Handled 2023-05-12T17:25:32.778Z,1683912332.778 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:33.026Z,1683912333.026 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:33.033Z,1683912333.033 [DATMMP](INFO): acstats rx: xA x5 xFFFF x2CA x22 x89 xBD xB4 xD xFFFF xAD x23 xC x0 2023-05-12T17:25:33.033Z,1683912333.033 [DATMMP](INFO): Received message type: acstats 2023-05-12T17:25:33.033Z,1683912333.033 [DATMMP](INFO): Handled 2023-05-12T17:25:33.034Z,1683912333.034 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:33.278Z,1683912333.278 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:33.279Z,1683912333.279 [DATMMP](INFO): data_rx rx: xA x0 x0 x4 2023-05-12T17:25:33.279Z,1683912333.279 [DATMMP](INFO): Check CRC 2023-05-12T17:25:33.279Z,1683912333.279 [DATMMP](INFO): data rx parseDataResponse 4 2023-05-12T17:25:33.280Z,1683912333.280 [DATMMP](INFO): Got DATA: len 4 2023-05-12T17:25:33.280Z,1683912333.280 [DATMMP](DEBUG): In parseResponses, got ack so set commsState_ = SENDING_VERIFIED 2023-05-12T17:25:33.280Z,1683912333.280 [DATMMP](INFO): Got ~~ ack, bytes left 2 2023-05-12T17:25:33.280Z,1683912333.280 [DATMMP](INFO): Received command: 2023-05-12T17:25:33.280Z,1683912333.280 [DATMMP](INFO): Received message type: data_rx 2023-05-12T17:25:33.280Z,1683912333.280 [DATMMP](INFO): Handled 2023-05-12T17:25:33.280Z,1683912333.280 [DATMMP](INFO): *** SENDING_VERIFIED *** 2023-05-12T17:25:33.281Z,1683912333.281 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER 2023-05-12T17:25:33.530Z,1683912333.530 [DATMMP](INFO): *** SENDING_FILL_BUFFER *** 2023-05-12T17:25:33.531Z,1683912333.531 [DATMMP](INFO): modem://10: set _.temperature 24.340143 celsius 2023-05-12T17:25:33.531Z,1683912333.531 [DATMMP](INFO): #Outgoing data=1 2023-05-12T17:25:33.531Z,1683912333.531 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA 2023-05-12T17:25:33.782Z,1683912333.782 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2023-05-12T17:25:33.782Z,1683912333.782 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT 2023-05-12T17:25:34.034Z,1683912334.034 [DATMMP](INFO): *** SENDING_TRANSMIT *** 2023-05-12T17:25:34.035Z,1683912334.035 [DATMMP](INFO): Tx to 10 len 35 xid 45 2023-05-12T17:25:34.035Z,1683912334.035 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2023-05-12T17:25:34.035Z,1683912334.035 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-12T17:25:34.286Z,1683912334.286 [DATMMP](INFO): GSXN notify for xid: 45 2023-05-12T17:25:34.287Z,1683912334.287 [DATMMP](INFO): cmd_result rx: x1 x7 x0 x1 2023-05-12T17:25:34.287Z,1683912334.287 [DATMMP](DEBUG): Command complete send 2023-05-12T17:25:34.288Z,1683912334.288 [DATMMP](ERROR): 1:COMPLETE 2023-05-12T17:25:34.288Z,1683912334.288 [DATMMP](INFO): Received message type: cmd_result 2023-05-12T17:25:34.288Z,1683912334.288 [DATMMP](INFO): Handled 2023-05-12T17:25:34.538Z,1683912334.538 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:34.539Z,1683912334.539 [DATMMP](INFO): timestamp rx: x0 x11 x19 x21 x97B 2023-05-12T17:25:34.539Z,1683912334.539 [DATMMP](INFO): Received message type: timestamp 2023-05-12T17:25:34.539Z,1683912334.539 [DATMMP](INFO): Handled 2023-05-12T17:25:34.540Z,1683912334.540 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:34.790Z,1683912334.790 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:35.042Z,1683912335.042 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:35.294Z,1683912335.294 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:35.546Z,1683912335.546 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:35.798Z,1683912335.798 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:36.050Z,1683912336.050 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:36.554Z,1683912336.554 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:36.806Z,1683912336.806 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:37.058Z,1683912337.058 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:37.310Z,1683912337.310 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:37.562Z,1683912337.562 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:37.814Z,1683912337.814 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:38.066Z,1683912338.066 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:38.318Z,1683912338.318 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:38.570Z,1683912338.570 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:38.571Z,1683912338.571 [DATMMP](INFO): doppler rx: xFFFF 2023-05-12T17:25:38.571Z,1683912338.571 [DATMMP](INFO): Received message type: doppler 2023-05-12T17:25:38.571Z,1683912338.571 [DATMMP](INFO): Handled 2023-05-12T17:25:38.571Z,1683912338.571 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:38.822Z,1683912338.822 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:38.823Z,1683912338.823 [DATMMP](INFO): timestamp rx: x1 x11 x19 x25 x998 2023-05-12T17:25:38.824Z,1683912338.824 [DATMMP](INFO): Rx dataTimestamp_ set to:1683912361.032192 2023-05-12T17:25:38.824Z,1683912338.824 [DATMMP](INFO): Rx ping set to:1683912338.823270 2023-05-12T17:25:38.824Z,1683912338.824 [DATMMP](INFO): Received message type: timestamp 2023-05-12T17:25:38.824Z,1683912338.824 [DATMMP](INFO): Handled 2023-05-12T17:25:38.824Z,1683912338.824 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:39.074Z,1683912339.074 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:39.134Z,1683912339.134 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-05-12T17:25:39.134Z,1683912339.134 [NAL9602] Data Fault, FailCount= 1 2023-05-12T17:25:39.134Z,1683912339.134 [NAL9602](ERROR): Data Fault 2023-05-12T17:25:39.184Z,1683912339.184 [CBIT](ERROR): Data Fault in component: NAL9602 2023-05-12T17:25:39.327Z,1683912339.327 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:39.330Z,1683912339.330 [DATMMP](INFO): remote_header rx: xF x4 x5 x4 x0 xC xA x0 2023-05-12T17:25:39.330Z,1683912339.330 [DATMMP](INFO): DATA Src=10, Dst=12 2023-05-12T17:25:39.331Z,1683912339.331 [DATMMP](INFO): Received message type: remote_header 2023-05-12T17:25:39.331Z,1683912339.331 [DATMMP](INFO): Handled 2023-05-12T17:25:39.331Z,1683912339.331 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:39.532Z,1683912339.532 [NAL9602](INFO): Powering down 2023-05-12T17:25:39.578Z,1683912339.578 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:39.579Z,1683912339.579 [DATMMP](INFO): nav_stat rx: x0 x1 x1 2023-05-12T17:25:39.579Z,1683912339.579 [DATMMP](INFO): Received message type: nav_stat 2023-05-12T17:25:39.579Z,1683912339.579 [DATMMP](INFO): Handled 2023-05-12T17:25:39.580Z,1683912339.580 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:39.830Z,1683912339.830 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:39.832Z,1683912339.832 [DATMMP](INFO): directional_debug rx: x20D x1F6 x22 x1BE x1010 x1321 x11D2 x1643 2023-05-12T17:25:39.832Z,1683912339.832 [DATMMP](INFO): Received message type: directional_debug 2023-05-12T17:25:39.833Z,1683912339.833 [DATMMP](INFO): Handled 2023-05-12T17:25:39.833Z,1683912339.833 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:40.082Z,1683912340.082 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:40.084Z,1683912340.084 [DATMMP](INFO): directional rx: x213 x1A6 x3CF xFE0A x5C5 xFE1C x0 x1FC x3 xFFEE x27B5966 x-43569E0 2023-05-12T17:25:40.085Z,1683912340.085 [DATMMP](INFO): Received message type: directional 2023-05-12T17:25:40.086Z,1683912340.086 [DATMMP](INFO): Handled 2023-05-12T17:25:40.086Z,1683912340.086 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:40.334Z,1683912340.334 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:40.336Z,1683912340.336 [DATMMP](INFO): acstats rx: xA x5 xFFFF x2CB x23 x87 xC2 xC1 xD xFFFF xAF x23 xC x0 2023-05-12T17:25:40.336Z,1683912340.336 [DATMMP](INFO): Received message type: acstats 2023-05-12T17:25:40.336Z,1683912340.336 [DATMMP](INFO): Handled 2023-05-12T17:25:40.336Z,1683912340.336 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:40.403Z,1683912340.403 [CBIT](INFO): Clearing failed state for component NAL9602 2023-05-12T17:25:40.403Z,1683912340.403 [NAL9602] No Fault, FailCount= 1 2023-05-12T17:25:40.586Z,1683912340.586 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:40.587Z,1683912340.587 [DATMMP](INFO): data_rx rx: xA x0 x0 x4 2023-05-12T17:25:40.587Z,1683912340.587 [DATMMP](INFO): Check CRC 2023-05-12T17:25:40.588Z,1683912340.588 [DATMMP](INFO): data rx parseDataResponse 4 2023-05-12T17:25:40.588Z,1683912340.588 [DATMMP](INFO): Got DATA: len 4 2023-05-12T17:25:40.588Z,1683912340.588 [DATMMP](DEBUG): In parseResponses, got ack so set commsState_ = SENDING_VERIFIED 2023-05-12T17:25:40.588Z,1683912340.588 [DATMMP](INFO): Got ~~ ack, bytes left 2 2023-05-12T17:25:40.588Z,1683912340.588 [DATMMP](INFO): Received command: 2023-05-12T17:25:40.588Z,1683912340.588 [DATMMP](INFO): Received message type: data_rx 2023-05-12T17:25:40.588Z,1683912340.588 [DATMMP](INFO): Handled 2023-05-12T17:25:40.589Z,1683912340.589 [DATMMP](INFO): *** SENDING_VERIFIED *** 2023-05-12T17:25:40.589Z,1683912340.589 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER 2023-05-12T17:25:40.838Z,1683912340.838 [DATMMP](INFO): *** SENDING_FILL_BUFFER *** 2023-05-12T17:25:40.839Z,1683912340.839 [DATMMP](INFO): modem://10: set _.humidity 12.879990 percent 2023-05-12T17:25:40.839Z,1683912340.839 [DATMMP](INFO): #Outgoing data=1 2023-05-12T17:25:40.839Z,1683912340.839 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA 2023-05-12T17:25:41.090Z,1683912341.090 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2023-05-12T17:25:41.090Z,1683912341.090 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT 2023-05-12T17:25:41.342Z,1683912341.342 [DATMMP](INFO): *** SENDING_TRANSMIT *** 2023-05-12T17:25:41.342Z,1683912341.342 [DATMMP](INFO): Tx to 10 len 32 xid 46 2023-05-12T17:25:41.343Z,1683912341.343 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2023-05-12T17:25:41.343Z,1683912341.343 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-12T17:25:41.594Z,1683912341.594 [DATMMP](INFO): GSXN notify for xid: 46 2023-05-12T17:25:41.595Z,1683912341.595 [DATMMP](INFO): cmd_result rx: x1 x7 x0 x1 2023-05-12T17:25:41.595Z,1683912341.595 [DATMMP](DEBUG): Command complete send 2023-05-12T17:25:41.595Z,1683912341.595 [DATMMP](ERROR): 1:COMPLETE 2023-05-12T17:25:41.596Z,1683912341.596 [DATMMP](INFO): Received message type: cmd_result 2023-05-12T17:25:41.596Z,1683912341.596 [DATMMP](INFO): Handled 2023-05-12T17:25:41.596Z,1683912341.596 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:41.846Z,1683912341.846 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:41.847Z,1683912341.847 [DATMMP](INFO): timestamp rx: x0 x11 x19 x28 x1532 2023-05-12T17:25:41.847Z,1683912341.847 [DATMMP](INFO): Received message type: timestamp 2023-05-12T17:25:41.847Z,1683912341.847 [DATMMP](INFO): Handled 2023-05-12T17:25:41.848Z,1683912341.848 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:42.098Z,1683912342.098 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:42.350Z,1683912342.350 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:42.602Z,1683912342.602 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:42.854Z,1683912342.854 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:43.106Z,1683912343.106 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:43.358Z,1683912343.358 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:43.610Z,1683912343.610 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:43.862Z,1683912343.862 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:44.114Z,1683912344.114 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:44.370Z,1683912344.370 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:44.622Z,1683912344.622 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:44.874Z,1683912344.874 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:45.126Z,1683912345.126 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:45.380Z,1683912345.380 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:45.630Z,1683912345.630 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:45.882Z,1683912345.882 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:46.134Z,1683912346.134 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:46.390Z,1683912346.390 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:46.642Z,1683912346.642 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:46.894Z,1683912346.894 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:47.146Z,1683912347.146 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:47.398Z,1683912347.398 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:47.650Z,1683912347.650 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:47.902Z,1683912347.902 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:48.154Z,1683912348.154 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:48.410Z,1683912348.410 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:48.662Z,1683912348.662 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:48.914Z,1683912348.914 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:49.166Z,1683912349.166 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:49.418Z,1683912349.418 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:49.670Z,1683912349.670 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:49.922Z,1683912349.922 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:50.174Z,1683912350.174 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:50.430Z,1683912350.430 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:50.682Z,1683912350.682 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:50.934Z,1683912350.934 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:51.186Z,1683912351.186 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:51.438Z,1683912351.438 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:51.438Z,1683912351.438 [DATMMP](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER 2023-05-12T17:25:51.690Z,1683912351.690 [DATMMP](INFO): *** SENDING_FILL_BUFFER *** 2023-05-12T17:25:51.690Z,1683912351.690 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA 2023-05-12T17:25:51.942Z,1683912351.942 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2023-05-12T17:25:51.942Z,1683912351.942 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT 2023-05-12T17:25:52.194Z,1683912352.194 [DATMMP](INFO): *** SENDING_TRANSMIT *** 2023-05-12T17:25:52.195Z,1683912352.195 [DATMMP](INFO): Tx to 10 len 32 xid 47 2023-05-12T17:25:52.195Z,1683912352.195 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2023-05-12T17:25:52.195Z,1683912352.195 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-12T17:25:52.450Z,1683912352.450 [DATMMP](INFO): GSXN notify for xid: 47 2023-05-12T17:25:52.451Z,1683912352.451 [DATMMP](INFO): cmd_result rx: x1 x7 x0 x1 2023-05-12T17:25:52.451Z,1683912352.451 [DATMMP](DEBUG): Command complete send 2023-05-12T17:25:52.452Z,1683912352.452 [DATMMP](ERROR): 1:COMPLETE 2023-05-12T17:25:52.452Z,1683912352.452 [DATMMP](INFO): Received message type: cmd_result 2023-05-12T17:25:52.452Z,1683912352.452 [DATMMP](INFO): Handled 2023-05-12T17:25:52.452Z,1683912352.452 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:52.702Z,1683912352.702 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:52.703Z,1683912352.703 [DATMMP](INFO): timestamp rx: x0 x11 x19 x33 xF56 2023-05-12T17:25:52.703Z,1683912352.703 [DATMMP](INFO): Received message type: timestamp 2023-05-12T17:25:52.703Z,1683912352.703 [DATMMP](INFO): Handled 2023-05-12T17:25:52.704Z,1683912352.704 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:52.954Z,1683912352.954 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:53.206Z,1683912353.206 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:53.458Z,1683912353.458 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:53.711Z,1683912353.711 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:53.962Z,1683912353.962 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:54.214Z,1683912354.214 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:54.466Z,1683912354.466 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:54.718Z,1683912354.718 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:54.970Z,1683912354.970 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:55.222Z,1683912355.222 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:55.474Z,1683912355.474 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:55.726Z,1683912355.726 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:55.978Z,1683912355.978 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:56.230Z,1683912356.230 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:56.490Z,1683912356.490 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:56.742Z,1683912356.742 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:56.743Z,1683912356.743 [DATMMP](INFO): doppler rx: x0 2023-05-12T17:25:56.743Z,1683912356.743 [DATMMP](INFO): Received message type: doppler 2023-05-12T17:25:56.743Z,1683912356.743 [DATMMP](INFO): Handled 2023-05-12T17:25:56.743Z,1683912356.743 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:56.994Z,1683912356.994 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:56.995Z,1683912356.995 [DATMMP](INFO): timestamp rx: x1 x11 x19 x37 xF73 2023-05-12T17:25:56.996Z,1683912356.996 [DATMMP](INFO): Rx dataTimestamp_ set to:1683912393.653312 2023-05-12T17:25:56.996Z,1683912356.996 [DATMMP](INFO): Rx ping set to:1683912356.995297 2023-05-12T17:25:56.996Z,1683912356.996 [DATMMP](INFO): Received message type: timestamp 2023-05-12T17:25:56.996Z,1683912356.996 [DATMMP](INFO): Handled 2023-05-12T17:25:56.996Z,1683912356.996 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:57.250Z,1683912357.250 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:57.502Z,1683912357.502 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:57.504Z,1683912357.504 [DATMMP](INFO): remote_header rx: xF x4 x5 x4 x0 xC xA x0 2023-05-12T17:25:57.504Z,1683912357.504 [DATMMP](INFO): DATA Src=10, Dst=12 2023-05-12T17:25:57.504Z,1683912357.504 [DATMMP](INFO): Received message type: remote_header 2023-05-12T17:25:57.504Z,1683912357.504 [DATMMP](INFO): Handled 2023-05-12T17:25:57.504Z,1683912357.504 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:57.754Z,1683912357.754 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:57.755Z,1683912357.755 [DATMMP](INFO): nav_stat rx: x0 x1 x1 2023-05-12T17:25:57.755Z,1683912357.755 [DATMMP](INFO): Received message type: nav_stat 2023-05-12T17:25:57.755Z,1683912357.755 [DATMMP](INFO): Handled 2023-05-12T17:25:57.755Z,1683912357.755 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:58.006Z,1683912358.006 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:58.008Z,1683912358.008 [DATMMP](INFO): directional_debug rx: x59 x1EA x24 xBD x1080 x1371 x1242 x1683 2023-05-12T17:25:58.008Z,1683912358.008 [DATMMP](INFO): Received message type: directional_debug 2023-05-12T17:25:58.009Z,1683912358.009 [DATMMP](INFO): Handled 2023-05-12T17:25:58.009Z,1683912358.009 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:58.258Z,1683912358.258 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:58.260Z,1683912358.260 [DATMMP](INFO): directional rx: x52 x1A3 x583 xFE16 x737 xFE1F x0 x1C5 x2 xFFEE x27B5966 x-43569E0 2023-05-12T17:25:58.262Z,1683912358.262 [DATMMP](INFO): Received message type: directional 2023-05-12T17:25:58.262Z,1683912358.262 [DATMMP](INFO): Handled 2023-05-12T17:25:58.262Z,1683912358.262 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:58.510Z,1683912358.510 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:58.512Z,1683912358.512 [DATMMP](INFO): acstats rx: xA x5 x0 x2CC x22 x98 xCC xC1 xD xFFFF xAF x26 xD x0 2023-05-12T17:25:58.512Z,1683912358.512 [DATMMP](INFO): Received message type: acstats 2023-05-12T17:25:58.512Z,1683912358.512 [DATMMP](INFO): Handled 2023-05-12T17:25:58.512Z,1683912358.512 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:25:58.762Z,1683912358.762 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:25:58.763Z,1683912358.763 [DATMMP](INFO): data_rx rx: xA x0 x0 x4 2023-05-12T17:25:58.763Z,1683912358.763 [DATMMP](INFO): Check CRC 2023-05-12T17:25:58.763Z,1683912358.763 [DATMMP](INFO): data rx parseDataResponse 4 2023-05-12T17:25:58.763Z,1683912358.763 [DATMMP](INFO): Got DATA: len 4 2023-05-12T17:25:58.764Z,1683912358.764 [DATMMP](DEBUG): In parseResponses, got ack so set commsState_ = SENDING_VERIFIED 2023-05-12T17:25:58.764Z,1683912358.764 [DATMMP](INFO): Got ~~ ack, bytes left 2 2023-05-12T17:25:58.764Z,1683912358.764 [DATMMP](INFO): Received command: 2023-05-12T17:25:58.764Z,1683912358.764 [DATMMP](INFO): Received message type: data_rx 2023-05-12T17:25:58.764Z,1683912358.764 [DATMMP](INFO): Handled 2023-05-12T17:25:58.764Z,1683912358.764 [DATMMP](INFO): *** SENDING_VERIFIED *** 2023-05-12T17:25:58.765Z,1683912358.765 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER