2023-05-12T17:22:48.839Z,1683912168.839 [CommandExec](IMPORTANT): got command restart logs 2023-05-12T17:23:18.293Z,1683912198.293 [CommandExec](IMPORTANT): got command run ./Missions/Demo/senddata_direct_and_track_test.xml 2023-05-12T17:23:18.293Z,1683912198.293 [MissionManager](INFO): Loading Mission from file: ./Missions/Demo/senddata_direct_and_track_test.xml 2023-05-12T17:23:18.347Z,1683912198.347 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.AcousticTargetContactLabel = 10.000000 count 2023-05-12T17:23:18.349Z,1683912198.349 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.AcousticTargetDepth = 0.000000 m 2023-05-12T17:23:18.351Z,1683912198.351 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.TrackingUpdatePeriod = 15.000000 s 2023-05-12T17:23:18.354Z,1683912198.354 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.NumberOfPings = 1.000000 count 2023-05-12T17:23:18.356Z,1683912198.356 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.NumberOfFixesLowPass = 2.000000 count 2023-05-12T17:23:18.359Z,1683912198.359 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.NumberOfStartingFixesToIgnore = 2.000000 count 2023-05-12T17:23:18.361Z,1683912198.361 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.BeingInterrogatedTimeout = 1.000000 h 2023-05-12T17:23:18.364Z,1683912198.364 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.AcommsInterval = 1.000000 min 2023-05-12T17:23:18.407Z,1683912198.407 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.modemId1 = 9.000000 enum 2023-05-12T17:23:18.409Z,1683912198.409 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.modemId2 = 5.000000 enum 2023-05-12T17:23:18.411Z,1683912198.411 [MissionManager](INFO): DefineOutput senddata_direct_and_track_test.ModemID = 1.000000 enum 2023-05-12T17:23:18.412Z,1683912198.412 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/AbortDrift.xml 2023-05-12T17:23:18.448Z,1683912198.448 [MissionManager](INFO): DefineArg senddata_direct_and_track_test:AbortDrift.AcousticTimeout = 96.000000 h 2023-05-12T17:23:18.450Z,1683912198.450 [senddata_direct_and_track_test:AbortDrift:B.AbortDrift](DEBUG): Construct AbortDrift. 2023-05-12T17:23:18.452Z,1683912198.452 [senddata_direct_and_track_test:AbortDrift:C.Execute](DEBUG): Construct Execute. 2023-05-12T17:23:18.454Z,1683912198.454 [MissionManager](INFO): RedefineArg senddata_direct_and_track_test:AbortDrift.AcousticTimeout = reader:senddata_direct_and_track_test.BeingInterrogatedTimeout 2023-05-12T17:23:18.463Z,1683912198.463 [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:23:18.465Z,1683912198.465 [CommandExec](IMPORTANT): Running ./Missions/Demo/senddata_direct_and_track_test.xml 2023-05-12T17:23:18.566Z,1683912198.566 [Default] Stopped 2023-05-12T17:23:18.567Z,1683912198.567 [Default](DEBUG): Aggregate::uninitialize Default 2023-05-12T17:23:18.567Z,1683912198.567 [Default:B.GoToSurface] Stopped 2023-05-12T17:23:18.567Z,1683912198.567 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-05-12T17:23:18.567Z,1683912198.567 [Default:CheckIn] Stopped 2023-05-12T17:23:18.567Z,1683912198.567 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-05-12T17:23:18.567Z,1683912198.567 [Default:CheckIn:Read_GPS] Stopped 2023-05-12T17:23:18.567Z,1683912198.567 [MissionManager](IMPORTANT): Started mission senddata_direct_and_track_test 2023-05-12T17:23:18.567Z,1683912198.567 [senddata_direct_and_track_test] Running Loop=1 2023-05-12T17:23:18.567Z,1683912198.567 [senddata_direct_and_track_test](DEBUG): Aggregate::initialize senddata_direct_and_track_test 2023-05-12T17:23:18.568Z,1683912198.568 [senddata_direct_and_track_test:AbortDrift] Running Loop=1 2023-05-12T17:23:18.568Z,1683912198.568 [senddata_direct_and_track_test:AbortDrift](DEBUG): Aggregate::initialize senddata_direct_and_track_test:AbortDrift 2023-05-12T17:23:18.568Z,1683912198.568 [senddata_direct_and_track_test:AbortDrift:A] Running Loop=1 2023-05-12T17:23:18.568Z,1683912198.568 [senddata_direct_and_track_test:DirectData] Running Loop=1 2023-05-12T17:23:18.568Z,1683912198.568 [senddata_direct_and_track_test:DirectData](DEBUG): Aggregate::initialize senddata_direct_and_track_test:DirectData 2023-05-12T17:23:18.568Z,1683912198.568 [senddata_direct_and_track_test:DirectData:A] Running Loop=1 2023-05-12T17:23:18.569Z,1683912198.569 [senddata_direct_and_track_test:DirectData:A](INFO): Queuing up send data for Onboard.Pressure with destination: modem:10:_.pressure 2023-05-12T17:23:18.569Z,1683912198.569 [senddata_direct_and_track_test:DirectData:A] Stopped 2023-05-12T17:23:18.569Z,1683912198.569 [senddata_direct_and_track_test:DirectData:B] Running Loop=1 2023-05-12T17:23:18.569Z,1683912198.569 [senddata_direct_and_track_test:AbortDrift] Running Loop=1 2023-05-12T17:23:18.574Z,1683912198.574 [senddata_direct_and_track_test:AbortDrift:A](INFO): Insert acoustic timeout set to 1.000000 h . 2023-05-12T17:23:18.575Z,1683912198.575 [senddata_direct_and_track_test:AbortDrift:A] Stopped 2023-05-12T17:23:18.575Z,1683912198.575 [senddata_direct_and_track_test:AbortDrift:B.AbortDrift] Running Loop=1 2023-05-12T17:23:18.575Z,1683912198.575 [senddata_direct_and_track_test:AbortDrift:B.AbortDrift](INFO): Initializing AbortDrift. 2023-05-12T17:23:18.575Z,1683912198.575 [senddata_direct_and_track_test:AbortDrift:B.AbortDrift](IMPORTANT): Acoustic timeout set to: 1.00 hours. 2023-05-12T17:23:18.630Z,1683912198.630 [DATMMP](INFO): *** SENDING_FILL_BUFFER *** 2023-05-12T17:23:18.631Z,1683912198.631 [DATMMP](INFO): modem://10: set _.pressure 0.742117 atmosphere 2023-05-12T17:23:18.631Z,1683912198.631 [DATMMP](INFO): #Outgoing data=1 2023-05-12T17:23:18.632Z,1683912198.632 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA 2023-05-12T17:23:18.882Z,1683912198.882 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2023-05-12T17:23:18.882Z,1683912198.882 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT 2023-05-12T17:23:18.965Z,1683912198.965 [senddata_direct_and_track_test:DirectData:B](INFO): Queuing up send data for Onboard.Temperature with destination: modem:10:_.temperature 2023-05-12T17:23:18.966Z,1683912198.966 [senddata_direct_and_track_test:DirectData:B] Stopped 2023-05-12T17:23:18.966Z,1683912198.966 [senddata_direct_and_track_test:DirectData:C] Running Loop=1 2023-05-12T17:23:18.967Z,1683912198.967 [senddata_direct_and_track_test:AbortDrift:B.AbortDrift](INFO): Received valid gps time fix: 1683911680.00. Resetting abort timer. 2023-05-12T17:23:18.967Z,1683912198.967 [senddata_direct_and_track_test:AbortDrift:B.AbortDrift](INFO): Received valid acoustic ping at time: 1683912192.00. Resetting abort timer. 2023-05-12T17:23:19.134Z,1683912199.134 [DATMMP](INFO): *** SENDING_TRANSMIT *** 2023-05-12T17:23:19.135Z,1683912199.135 [DATMMP](INFO): Tx to 10 len 34 xid 35 2023-05-12T17:23:19.136Z,1683912199.136 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2023-05-12T17:23:19.136Z,1683912199.136 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-12T17:23:19.356Z,1683912199.356 [senddata_direct_and_track_test:DirectData:C](INFO): Queuing up send data for Onboard.Humidity with destination: modem:10:_.humidity 2023-05-12T17:23:19.356Z,1683912199.356 [senddata_direct_and_track_test:DirectData:C] Stopped 2023-05-12T17:23:19.356Z,1683912199.356 [senddata_direct_and_track_test:DirectData](INFO): Completed senddata_direct_and_track_test:DirectData 2023-05-12T17:23:19.356Z,1683912199.356 [senddata_direct_and_track_test:DirectData] Stopped 2023-05-12T17:23:19.356Z,1683912199.356 [senddata_direct_and_track_test:DirectData](DEBUG): Aggregate::uninitialize senddata_direct_and_track_test:DirectData 2023-05-12T17:23:19.357Z,1683912199.357 [senddata_direct_and_track_test:DirectData](INFO): Running loop #2 2023-05-12T17:23:19.357Z,1683912199.357 [senddata_direct_and_track_test:DirectData] Running Loop=2 2023-05-12T17:23:19.357Z,1683912199.357 [senddata_direct_and_track_test:DirectData](DEBUG): Aggregate::initialize senddata_direct_and_track_test:DirectData 2023-05-12T17:23:19.357Z,1683912199.357 [senddata_direct_and_track_test:DirectData:A] Running Loop=1 2023-05-12T17:23:19.387Z,1683912199.387 [DATMMP](INFO): GSXN notify for xid: 35 2023-05-12T17:23:19.387Z,1683912199.387 [DATMMP](INFO): cmd_result rx: x1 x7 x0 x1 2023-05-12T17:23:19.388Z,1683912199.388 [DATMMP](DEBUG): Command complete send 2023-05-12T17:23:19.388Z,1683912199.388 [DATMMP](ERROR): 1:COMPLETE 2023-05-12T17:23:19.388Z,1683912199.388 [DATMMP](INFO): Received message type: cmd_result 2023-05-12T17:23:19.388Z,1683912199.388 [DATMMP](INFO): Handled 2023-05-12T17:23:19.388Z,1683912199.388 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:19.638Z,1683912199.638 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:23:19.639Z,1683912199.639 [DATMMP](INFO): timestamp rx: x0 x11 x17 x12 xD6F 2023-05-12T17:23:19.639Z,1683912199.639 [DATMMP](INFO): Received message type: timestamp 2023-05-12T17:23:19.639Z,1683912199.639 [DATMMP](INFO): Handled 2023-05-12T17:23:19.640Z,1683912199.640 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:19.766Z,1683912199.766 [senddata_direct_and_track_test:DirectData:A](INFO): Queuing up send data for Onboard.Pressure with destination: modem:10:_.pressure 2023-05-12T17:23:19.766Z,1683912199.766 [senddata_direct_and_track_test:DirectData:A] Stopped 2023-05-12T17:23:19.767Z,1683912199.767 [senddata_direct_and_track_test:DirectData:B] Running Loop=1 2023-05-12T17:23:19.890Z,1683912199.890 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:20.146Z,1683912200.146 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:20.179Z,1683912200.179 [senddata_direct_and_track_test:DirectData:B](INFO): Queuing up send data for Onboard.Temperature with destination: modem:10:_.temperature 2023-05-12T17:23:20.180Z,1683912200.180 [senddata_direct_and_track_test:DirectData:B] Stopped 2023-05-12T17:23:20.180Z,1683912200.180 [senddata_direct_and_track_test:DirectData:C] Running Loop=1 2023-05-12T17:23:20.398Z,1683912200.398 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:20.561Z,1683912200.561 [senddata_direct_and_track_test:DirectData:C](INFO): Queuing up send data for Onboard.Humidity with destination: modem:10:_.humidity 2023-05-12T17:23:20.562Z,1683912200.562 [senddata_direct_and_track_test:DirectData:C] Stopped 2023-05-12T17:23:20.562Z,1683912200.562 [senddata_direct_and_track_test:DirectData](INFO): Completed senddata_direct_and_track_test:DirectData 2023-05-12T17:23:20.562Z,1683912200.562 [senddata_direct_and_track_test:DirectData] Stopped 2023-05-12T17:23:20.562Z,1683912200.562 [senddata_direct_and_track_test:DirectData](DEBUG): Aggregate::uninitialize senddata_direct_and_track_test:DirectData 2023-05-12T17:23:20.562Z,1683912200.562 [senddata_direct_and_track_test](INFO): Completed senddata_direct_and_track_test 2023-05-12T17:23:20.563Z,1683912200.563 [MissionManager](INFO): senddata_direct_and_track_test is completed. 2023-05-12T17:23:20.563Z,1683912200.563 [MissionManager](INFO): Uninitializing Mission senddata_direct_and_track_test 2023-05-12T17:23:20.563Z,1683912200.563 [senddata_direct_and_track_test] Stopped 2023-05-12T17:23:20.563Z,1683912200.563 [senddata_direct_and_track_test](DEBUG): Aggregate::uninitialize senddata_direct_and_track_test 2023-05-12T17:23:20.563Z,1683912200.563 [senddata_direct_and_track_test:AbortDrift] Stopped 2023-05-12T17:23:20.563Z,1683912200.563 [senddata_direct_and_track_test:AbortDrift](DEBUG): Aggregate::uninitialize senddata_direct_and_track_test:AbortDrift 2023-05-12T17:23:20.563Z,1683912200.563 [senddata_direct_and_track_test:AbortDrift:B.AbortDrift] Stopped 2023-05-12T17:23:20.563Z,1683912200.563 [senddata_direct_and_track_test:AbortDrift:B.AbortDrift](INFO): Uninitializing AbortDrift. 2023-05-12T17:23:20.650Z,1683912200.650 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:20.902Z,1683912200.902 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:20.978Z,1683912200.978 [MissionManager](IMPORTANT): Started mission Default 2023-05-12T17:23:20.978Z,1683912200.978 [Default] Running Loop=1 2023-05-12T17:23:20.978Z,1683912200.978 [Default](DEBUG): Aggregate::initialize Default 2023-05-12T17:23:20.978Z,1683912200.978 [Default:B.GoToSurface] Running Loop=1 2023-05-12T17:23:20.978Z,1683912200.978 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-05-12T17:23:20.979Z,1683912200.979 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-05-12T17:23:20.979Z,1683912200.979 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-05-12T17:23:20.979Z,1683912200.979 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-05-12T17:23:20.979Z,1683912200.979 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-05-12T17:23:20.980Z,1683912200.980 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-05-12T17:23:20.980Z,1683912200.980 [Default:A.Wait] Running Loop=1 2023-05-12T17:23:20.980Z,1683912200.980 [Default:A.Wait](DEBUG): Initialize Wait Component. 2023-05-12T17:23:21.154Z,1683912201.154 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:21.406Z,1683912201.406 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:21.658Z,1683912201.658 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:21.910Z,1683912201.910 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:22.162Z,1683912202.162 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:22.414Z,1683912202.414 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:22.666Z,1683912202.666 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:22.918Z,1683912202.918 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:23.170Z,1683912203.170 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:23.422Z,1683912203.422 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:23.674Z,1683912203.674 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:23:23.675Z,1683912203.675 [DATMMP](INFO): doppler rx: xFFFF 2023-05-12T17:23:23.675Z,1683912203.675 [DATMMP](INFO): Received message type: doppler 2023-05-12T17:23:23.675Z,1683912203.675 [DATMMP](INFO): Handled 2023-05-12T17:23:23.675Z,1683912203.675 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:23.926Z,1683912203.926 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:23:23.927Z,1683912203.927 [DATMMP](INFO): timestamp rx: x1 x11 x17 x16 xD86 2023-05-12T17:23:23.928Z,1683912203.928 [DATMMP](INFO): Rx dataTimestamp_ set to:1683912235.847872 2023-05-12T17:23:23.928Z,1683912203.928 [DATMMP](INFO): Rx ping set to:1683912203.927316 2023-05-12T17:23:23.928Z,1683912203.928 [DATMMP](INFO): Received message type: timestamp 2023-05-12T17:23:23.928Z,1683912203.928 [DATMMP](INFO): Handled 2023-05-12T17:23:23.929Z,1683912203.929 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:24.178Z,1683912204.178 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:24.431Z,1683912204.431 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:23:24.433Z,1683912204.433 [DATMMP](INFO): remote_header rx: xF x4 x5 x4 x0 xC xA x0 2023-05-12T17:23:24.436Z,1683912204.436 [DATMMP](INFO): DATA Src=10, Dst=12 2023-05-12T17:23:24.436Z,1683912204.436 [DATMMP](INFO): Received message type: remote_header 2023-05-12T17:23:24.436Z,1683912204.436 [DATMMP](INFO): Handled 2023-05-12T17:23:24.436Z,1683912204.436 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:24.683Z,1683912204.683 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:23:24.683Z,1683912204.683 [DATMMP](INFO): nav_stat rx: x0 x1 x1 2023-05-12T17:23:24.683Z,1683912204.683 [DATMMP](INFO): Received message type: nav_stat 2023-05-12T17:23:24.684Z,1683912204.684 [DATMMP](INFO): Handled 2023-05-12T17:23:24.684Z,1683912204.684 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:24.934Z,1683912204.934 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:23:24.935Z,1683912204.935 [DATMMP](INFO): directional_debug rx: xBE x1A5 x23 xB7 x1020 x1351 x11F2 x1653 2023-05-12T17:23:24.936Z,1683912204.936 [DATMMP](INFO): Received message type: directional_debug 2023-05-12T17:23:24.936Z,1683912204.936 [DATMMP](INFO): Handled 2023-05-12T17:23:24.937Z,1683912204.937 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:25.186Z,1683912205.186 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:23:25.188Z,1683912205.188 [DATMMP](INFO): directional rx: xB7 x14C x51E xFE5B x829 xFE65 x0 x319 x6 xFFEE x27B5966 x-43569E0 2023-05-12T17:23:25.189Z,1683912205.189 [DATMMP](INFO): Received message type: directional 2023-05-12T17:23:25.190Z,1683912205.190 [DATMMP](INFO): Handled 2023-05-12T17:23:25.190Z,1683912205.190 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:25.438Z,1683912205.438 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:23:25.440Z,1683912205.440 [DATMMP](INFO): acstats rx: xA x5 xFFFF x2C2 x23 x83 xD3 xC8 xD xFFFF xAF x25 xD x0 2023-05-12T17:23:25.440Z,1683912205.440 [DATMMP](INFO): Received message type: acstats 2023-05-12T17:23:25.440Z,1683912205.440 [DATMMP](INFO): Handled 2023-05-12T17:23:25.440Z,1683912205.440 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:25.690Z,1683912205.690 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:23:25.691Z,1683912205.691 [DATMMP](INFO): data_rx rx: xA x0 x0 x4 2023-05-12T17:23:25.691Z,1683912205.691 [DATMMP](INFO): Check CRC 2023-05-12T17:23:25.691Z,1683912205.691 [DATMMP](INFO): data rx parseDataResponse 4 2023-05-12T17:23:25.691Z,1683912205.691 [DATMMP](INFO): Got DATA: len 4 2023-05-12T17:23:25.692Z,1683912205.692 [DATMMP](DEBUG): In parseResponses, got ack so set commsState_ = SENDING_VERIFIED 2023-05-12T17:23:25.692Z,1683912205.692 [DATMMP](INFO): Got ~~ ack, bytes left 2 2023-05-12T17:23:25.692Z,1683912205.692 [DATMMP](INFO): Received command: 2023-05-12T17:23:25.692Z,1683912205.692 [DATMMP](INFO): Received message type: data_rx 2023-05-12T17:23:25.692Z,1683912205.692 [DATMMP](INFO): Handled 2023-05-12T17:23:25.692Z,1683912205.692 [DATMMP](INFO): *** SENDING_VERIFIED *** 2023-05-12T17:23:25.693Z,1683912205.693 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER 2023-05-12T17:23:25.944Z,1683912205.944 [DATMMP](INFO): *** SENDING_FILL_BUFFER *** 2023-05-12T17:23:25.947Z,1683912205.947 [DATMMP](INFO): modem://10: set _.temperature 24.340143 celsius 2023-05-12T17:23:25.947Z,1683912205.947 [DATMMP](INFO): #Outgoing data=1 2023-05-12T17:23:25.947Z,1683912205.947 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA 2023-05-12T17:23:26.195Z,1683912206.195 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2023-05-12T17:23:26.195Z,1683912206.195 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT 2023-05-12T17:23:26.446Z,1683912206.446 [DATMMP](INFO): *** SENDING_TRANSMIT *** 2023-05-12T17:23:26.446Z,1683912206.446 [DATMMP](INFO): Tx to 10 len 35 xid 36 2023-05-12T17:23:26.447Z,1683912206.447 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2023-05-12T17:23:26.447Z,1683912206.447 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-12T17:23:26.698Z,1683912206.698 [DATMMP](INFO): GSXN notify for xid: 36 2023-05-12T17:23:26.699Z,1683912206.699 [DATMMP](INFO): cmd_result rx: x1 x7 x0 x1 2023-05-12T17:23:26.699Z,1683912206.699 [DATMMP](DEBUG): Command complete send 2023-05-12T17:23:26.700Z,1683912206.700 [DATMMP](ERROR): 1:COMPLETE 2023-05-12T17:23:26.700Z,1683912206.700 [DATMMP](INFO): Received message type: cmd_result 2023-05-12T17:23:26.700Z,1683912206.700 [DATMMP](INFO): Handled 2023-05-12T17:23:26.700Z,1683912206.700 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:26.950Z,1683912206.950 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:23:26.951Z,1683912206.951 [DATMMP](INFO): timestamp rx: x0 x11 x17 x19 x1927 2023-05-12T17:23:26.952Z,1683912206.952 [DATMMP](INFO): Received message type: timestamp 2023-05-12T17:23:26.952Z,1683912206.952 [DATMMP](INFO): Handled 2023-05-12T17:23:26.952Z,1683912206.952 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:27.202Z,1683912207.202 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:27.454Z,1683912207.454 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:27.706Z,1683912207.706 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:27.958Z,1683912207.958 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:28.210Z,1683912208.210 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:28.462Z,1683912208.462 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:28.714Z,1683912208.714 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:28.966Z,1683912208.966 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:29.218Z,1683912209.218 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:29.470Z,1683912209.470 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:29.722Z,1683912209.722 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:29.974Z,1683912209.974 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:30.226Z,1683912210.226 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:30.478Z,1683912210.478 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:30.730Z,1683912210.730 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:30.982Z,1683912210.982 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:31.234Z,1683912211.234 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:31.486Z,1683912211.486 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:23:31.487Z,1683912211.487 [DATMMP](INFO): doppler rx: xFFFF 2023-05-12T17:23:31.487Z,1683912211.487 [DATMMP](INFO): Received message type: doppler 2023-05-12T17:23:31.487Z,1683912211.487 [DATMMP](INFO): Handled 2023-05-12T17:23:31.487Z,1683912211.487 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:31.738Z,1683912211.738 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:23:31.739Z,1683912211.739 [DATMMP](INFO): timestamp rx: x1 x11 x17 x1D x24F6 2023-05-12T17:23:31.740Z,1683912211.740 [DATMMP](INFO): Rx dataTimestamp_ set to:1683912237.421888 2023-05-12T17:23:31.740Z,1683912211.740 [DATMMP](INFO): Rx ping set to:1683912211.739197 2023-05-12T17:23:31.740Z,1683912211.740 [DATMMP](INFO): Received message type: timestamp 2023-05-12T17:23:31.740Z,1683912211.740 [DATMMP](INFO): Handled 2023-05-12T17:23:31.740Z,1683912211.740 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:31.990Z,1683912211.990 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:23:31.991Z,1683912211.991 [DATMMP](INFO): remote_header rx: xF x4 x5 x4 x0 xC xA x0 2023-05-12T17:23:31.992Z,1683912211.992 [DATMMP](INFO): DATA Src=10, Dst=12 2023-05-12T17:23:31.992Z,1683912211.992 [DATMMP](INFO): Received message type: remote_header 2023-05-12T17:23:31.992Z,1683912211.992 [DATMMP](INFO): Handled 2023-05-12T17:23:31.992Z,1683912211.992 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:32.242Z,1683912212.242 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:23:32.243Z,1683912212.243 [DATMMP](INFO): nav_stat rx: x0 x1 x1 2023-05-12T17:23:32.243Z,1683912212.243 [DATMMP](INFO): Received message type: nav_stat 2023-05-12T17:23:32.243Z,1683912212.243 [DATMMP](INFO): Handled 2023-05-12T17:23:32.244Z,1683912212.244 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:32.494Z,1683912212.494 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:23:32.496Z,1683912212.496 [DATMMP](INFO): directional_debug rx: xE0D x212 x22 xCA x1040 x1371 x1272 x16B3 2023-05-12T17:23:32.496Z,1683912212.496 [DATMMP](INFO): Received message type: directional_debug 2023-05-12T17:23:32.497Z,1683912212.497 [DATMMP](INFO): Handled 2023-05-12T17:23:32.497Z,1683912212.497 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:32.746Z,1683912212.746 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:23:32.748Z,1683912212.748 [DATMMP](INFO): directional rx: xE07 x1D5 x5DF xFDEE x8D0 xFDF5 x0 x30B x5 xFFEC x27B5966 x-43569E0 2023-05-12T17:23:32.749Z,1683912212.749 [DATMMP](INFO): Received message type: directional 2023-05-12T17:23:32.750Z,1683912212.750 [DATMMP](INFO): Handled 2023-05-12T17:23:32.750Z,1683912212.750 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:32.998Z,1683912212.998 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:23:32.000Z,1683912213.000 [DATMMP](INFO): acstats rx: xA x5 xFFFF x2CC x22 x87 xBD xC8 xD xFFFF xAE x20 xC x0 2023-05-12T17:23:33.000Z,1683912213.000 [DATMMP](INFO): Received message type: acstats 2023-05-12T17:23:33.000Z,1683912213.000 [DATMMP](INFO): Handled 2023-05-12T17:23:33.000Z,1683912213.000 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:33.250Z,1683912213.250 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:23:33.251Z,1683912213.251 [DATMMP](INFO): data_rx rx: xA x0 x0 x4 2023-05-12T17:23:33.251Z,1683912213.251 [DATMMP](INFO): Check CRC 2023-05-12T17:23:33.251Z,1683912213.251 [DATMMP](INFO): data rx parseDataResponse 4 2023-05-12T17:23:33.252Z,1683912213.252 [DATMMP](INFO): Got DATA: len 4 2023-05-12T17:23:33.252Z,1683912213.252 [DATMMP](DEBUG): In parseResponses, got ack so set commsState_ = SENDING_VERIFIED 2023-05-12T17:23:33.252Z,1683912213.252 [DATMMP](INFO): Got ~~ ack, bytes left 2 2023-05-12T17:23:33.252Z,1683912213.252 [DATMMP](INFO): Received command: 2023-05-12T17:23:33.252Z,1683912213.252 [DATMMP](INFO): Received message type: data_rx 2023-05-12T17:23:33.252Z,1683912213.252 [DATMMP](INFO): Handled 2023-05-12T17:23:33.253Z,1683912213.253 [DATMMP](INFO): *** SENDING_VERIFIED *** 2023-05-12T17:23:33.253Z,1683912213.253 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER 2023-05-12T17:23:33.502Z,1683912213.502 [DATMMP](INFO): *** SENDING_FILL_BUFFER *** 2023-05-12T17:23:33.503Z,1683912213.503 [DATMMP](INFO): modem://10: set _.humidity 12.879990 percent 2023-05-12T17:23:33.503Z,1683912213.503 [DATMMP](INFO): #Outgoing data=1 2023-05-12T17:23:33.503Z,1683912213.503 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA 2023-05-12T17:23:33.754Z,1683912213.754 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2023-05-12T17:23:33.754Z,1683912213.754 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT 2023-05-12T17:23:34.006Z,1683912214.006 [DATMMP](INFO): *** SENDING_TRANSMIT *** 2023-05-12T17:23:34.007Z,1683912214.007 [DATMMP](INFO): Tx to 10 len 32 xid 37 2023-05-12T17:23:34.007Z,1683912214.007 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2023-05-12T17:23:34.007Z,1683912214.007 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-12T17:23:34.258Z,1683912214.258 [DATMMP](INFO): GSXN notify for xid: 37 2023-05-12T17:23:34.259Z,1683912214.259 [DATMMP](INFO): cmd_result rx: x1 x7 x0 x1 2023-05-12T17:23:34.259Z,1683912214.259 [DATMMP](DEBUG): Command complete send 2023-05-12T17:23:34.259Z,1683912214.260 [DATMMP](ERROR): 1:COMPLETE 2023-05-12T17:23:34.260Z,1683912214.260 [DATMMP](INFO): Received message type: cmd_result 2023-05-12T17:23:34.260Z,1683912214.260 [DATMMP](INFO): Handled 2023-05-12T17:23:34.260Z,1683912214.260 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:34.336Z,1683912214.336 [Default:A.Wait](INFO): Done Waiting. 2023-05-12T17:23:34.336Z,1683912214.336 [Default:A.Wait] Stopped 2023-05-12T17:23:34.336Z,1683912214.336 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2023-05-12T17:23:34.510Z,1683912214.510 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:23:34.511Z,1683912214.511 [DATMMP](INFO): timestamp rx: x0 x11 x17 x21 x792 2023-05-12T17:23:34.511Z,1683912214.511 [DATMMP](INFO): Received message type: timestamp 2023-05-12T17:23:34.512Z,1683912214.512 [DATMMP](INFO): Handled 2023-05-12T17:23:34.512Z,1683912214.512 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:34.714Z,1683912214.714 [Default:CheckIn] Running Loop=1 2023-05-12T17:23:34.714Z,1683912214.714 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-05-12T17:23:34.714Z,1683912214.714 [Default:CheckIn:Read_GPS] Running Loop=1 2023-05-12T17:23:34.762Z,1683912214.762 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:35.014Z,1683912215.014 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:35.266Z,1683912215.266 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:35.518Z,1683912215.518 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:35.770Z,1683912215.770 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:36.022Z,1683912216.022 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:36.274Z,1683912216.274 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:36.526Z,1683912216.526 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:36.778Z,1683912216.778 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:37.030Z,1683912217.030 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:37.282Z,1683912217.282 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:37.534Z,1683912217.534 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:37.786Z,1683912217.786 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:38.038Z,1683912218.038 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:38.290Z,1683912218.290 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:38.542Z,1683912218.542 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:38.794Z,1683912218.794 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:23:38.795Z,1683912218.795 [DATMMP](INFO): doppler rx: xFFFF 2023-05-12T17:23:38.795Z,1683912218.795 [DATMMP](INFO): Received message type: doppler 2023-05-12T17:23:38.795Z,1683912218.795 [DATMMP](INFO): Handled 2023-05-12T17:23:38.795Z,1683912218.795 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:39.047Z,1683912219.047 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:23:39.047Z,1683912219.047 [DATMMP](INFO): timestamp rx: x1 x11 x17 x25 x99D 2023-05-12T17:23:39.048Z,1683912219.048 [DATMMP](INFO): Rx dataTimestamp_ set to:1683912241.034816 2023-05-12T17:23:39.048Z,1683912219.048 [DATMMP](INFO): Rx ping set to:1683912219.047532 2023-05-12T17:23:39.048Z,1683912219.048 [DATMMP](INFO): Received message type: timestamp 2023-05-12T17:23:39.049Z,1683912219.049 [DATMMP](INFO): Handled 2023-05-12T17:23:39.049Z,1683912219.049 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:39.299Z,1683912219.299 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:23:39.300Z,1683912219.300 [DATMMP](INFO): remote_header rx: xF x4 x5 x4 x0 xC xA x0 2023-05-12T17:23:39.300Z,1683912219.300 [DATMMP](INFO): DATA Src=10, Dst=12 2023-05-12T17:23:39.300Z,1683912219.300 [DATMMP](INFO): Received message type: remote_header 2023-05-12T17:23:39.300Z,1683912219.300 [DATMMP](INFO): Handled 2023-05-12T17:23:39.300Z,1683912219.300 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:39.550Z,1683912219.550 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:23:39.551Z,1683912219.551 [DATMMP](INFO): nav_stat rx: x0 x1 x1 2023-05-12T17:23:39.551Z,1683912219.551 [DATMMP](INFO): Received message type: nav_stat 2023-05-12T17:23:39.551Z,1683912219.551 [DATMMP](INFO): Handled 2023-05-12T17:23:39.802Z,1683912219.802 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:23:39.804Z,1683912219.804 [DATMMP](INFO): directional_debug rx: x20C x1E4 x24 x1B6 x1050 x1391 x11F2 x16A3 2023-05-12T17:23:39.804Z,1683912219.804 [DATMMP](INFO): Received message type: directional_debug 2023-05-12T17:23:39.805Z,1683912219.805 [DATMMP](INFO): Handled 2023-05-12T17:23:39.805Z,1683912219.805 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:40.054Z,1683912220.054 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:23:40.056Z,1683912220.056 [DATMMP](INFO): directional rx: x212 x18F x3D0 xFE1C x6C0 xFE31 x0 x2F8 x5 xFFEB x27B5966 x-43569E0 2023-05-12T17:23:40.057Z,1683912220.057 [DATMMP](INFO): Received message type: directional 2023-05-12T17:23:40.058Z,1683912220.058 [DATMMP](INFO): Handled 2023-05-12T17:23:40.058Z,1683912220.058 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:40.306Z,1683912220.306 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:23:40.308Z,1683912220.308 [DATMMP](INFO): acstats rx: xA x5 xFFFF x2CC x23 x91 xC0 xC9 xD xFFFF xAF x22 xC x0 2023-05-12T17:23:40.308Z,1683912220.308 [DATMMP](INFO): Received message type: acstats 2023-05-12T17:23:40.308Z,1683912220.308 [DATMMP](INFO): Handled 2023-05-12T17:23:40.308Z,1683912220.308 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:40.558Z,1683912220.558 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:23:40.559Z,1683912220.559 [DATMMP](INFO): data_rx rx: xA x0 x0 x4 2023-05-12T17:23:40.559Z,1683912220.559 [DATMMP](INFO): Check CRC 2023-05-12T17:23:40.560Z,1683912220.560 [DATMMP](INFO): data rx parseDataResponse 4 2023-05-12T17:23:40.560Z,1683912220.560 [DATMMP](INFO): Got DATA: len 4 2023-05-12T17:23:40.560Z,1683912220.560 [DATMMP](DEBUG): In parseResponses, got ack so set commsState_ = SENDING_VERIFIED 2023-05-12T17:23:40.560Z,1683912220.560 [DATMMP](INFO): Got ~~ ack, bytes left 2 2023-05-12T17:23:40.560Z,1683912220.560 [DATMMP](INFO): Received command: 2023-05-12T17:23:40.560Z,1683912220.560 [DATMMP](INFO): Received message type: data_rx 2023-05-12T17:23:40.561Z,1683912220.561 [DATMMP](INFO): Handled 2023-05-12T17:23:40.561Z,1683912220.561 [DATMMP](INFO): *** SENDING_VERIFIED *** 2023-05-12T17:23:40.561Z,1683912220.561 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER 2023-05-12T17:23:40.810Z,1683912220.810 [DATMMP](INFO): *** SENDING_FILL_BUFFER *** 2023-05-12T17:23:40.811Z,1683912220.811 [DATMMP](INFO): modem://10: set _.pressure 0.742117 atmosphere 2023-05-12T17:23:40.811Z,1683912220.811 [DATMMP](INFO): #Outgoing data=1 2023-05-12T17:23:40.811Z,1683912220.811 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA 2023-05-12T17:23:41.062Z,1683912221.062 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2023-05-12T17:23:41.062Z,1683912221.062 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT 2023-05-12T17:23:41.315Z,1683912221.315 [DATMMP](INFO): *** SENDING_TRANSMIT *** 2023-05-12T17:23:41.315Z,1683912221.315 [DATMMP](INFO): Tx to 10 len 34 xid 38 2023-05-12T17:23:41.316Z,1683912221.316 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2023-05-12T17:23:41.316Z,1683912221.316 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-12T17:23:41.566Z,1683912221.566 [DATMMP](INFO): GSXN notify for xid: 38 2023-05-12T17:23:41.567Z,1683912221.567 [DATMMP](INFO): cmd_result rx: x1 x7 x0 x1 2023-05-12T17:23:41.567Z,1683912221.567 [DATMMP](DEBUG): Command complete send 2023-05-12T17:23:41.568Z,1683912221.568 [DATMMP](ERROR): 1:COMPLETE 2023-05-12T17:23:41.568Z,1683912221.568 [DATMMP](INFO): Received message type: cmd_result 2023-05-12T17:23:41.568Z,1683912221.568 [DATMMP](INFO): Handled 2023-05-12T17:23:41.568Z,1683912221.568 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:41.818Z,1683912221.818 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:23:41.819Z,1683912221.819 [DATMMP](INFO): timestamp rx: x0 x11 x17 x28 x1349 2023-05-12T17:23:41.819Z,1683912221.819 [DATMMP](INFO): Received message type: timestamp 2023-05-12T17:23:41.820Z,1683912221.820 [DATMMP](INFO): Handled 2023-05-12T17:23:41.820Z,1683912221.820 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:42.070Z,1683912222.070 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:42.322Z,1683912222.322 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:42.574Z,1683912222.574 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:42.826Z,1683912222.826 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:43.078Z,1683912223.078 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:43.330Z,1683912223.330 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:43.834Z,1683912223.834 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:44.086Z,1683912224.086 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:44.338Z,1683912224.338 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:44.590Z,1683912224.590 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:44.842Z,1683912224.842 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:45.094Z,1683912225.094 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:45.346Z,1683912225.346 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:45.598Z,1683912225.598 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:45.850Z,1683912225.850 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:46.102Z,1683912226.102 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:23:46.103Z,1683912226.103 [DATMMP](INFO): doppler rx: xFFFF 2023-05-12T17:23:46.103Z,1683912226.103 [DATMMP](INFO): Received message type: doppler 2023-05-12T17:23:46.103Z,1683912226.103 [DATMMP](INFO): Handled 2023-05-12T17:23:46.103Z,1683912226.103 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:46.354Z,1683912226.354 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:23:46.355Z,1683912226.355 [DATMMP](INFO): timestamp rx: x1 x11 x17 x2C x1555 2023-05-12T17:23:46.356Z,1683912226.356 [DATMMP](INFO): Rx dataTimestamp_ set to:1683912277.346112 2023-05-12T17:23:46.356Z,1683912226.356 [DATMMP](INFO): Rx ping set to:1683912226.355188 2023-05-12T17:23:46.356Z,1683912226.356 [DATMMP](INFO): Received message type: timestamp 2023-05-12T17:23:46.356Z,1683912226.356 [DATMMP](INFO): Handled 2023-05-12T17:23:46.356Z,1683912226.356 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:46.606Z,1683912226.606 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:23:46.608Z,1683912226.608 [DATMMP](INFO): remote_header rx: xF x4 x5 x4 x0 xC xA x0 2023-05-12T17:23:46.608Z,1683912226.608 [DATMMP](INFO): DATA Src=10, Dst=12 2023-05-12T17:23:46.608Z,1683912226.608 [DATMMP](INFO): Received message type: remote_header 2023-05-12T17:23:46.608Z,1683912226.608 [DATMMP](INFO): Handled 2023-05-12T17:23:46.608Z,1683912226.608 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:46.858Z,1683912226.858 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:23:46.859Z,1683912226.859 [DATMMP](INFO): nav_stat rx: x0 x1 x1 2023-05-12T17:23:46.859Z,1683912226.859 [DATMMP](INFO): Received message type: nav_stat 2023-05-12T17:23:46.859Z,1683912226.859 [DATMMP](INFO): Handled 2023-05-12T17:23:46.859Z,1683912226.859 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:47.110Z,1683912227.110 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:23:47.112Z,1683912227.112 [DATMMP](INFO): directional_debug rx: xE8 x18A x23 xB3 x1010 x1341 x1212 x1633 2023-05-12T17:23:47.113Z,1683912227.113 [DATMMP](INFO): Received message type: directional_debug 2023-05-12T17:23:47.113Z,1683912227.113 [DATMMP](INFO): Handled 2023-05-12T17:23:47.113Z,1683912227.113 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:47.363Z,1683912227.363 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:23:47.364Z,1683912227.364 [DATMMP](INFO): directional rx: xE2 x12C x4F4 xFE76 x7CD xFE83 x0 x2E6 x5 xFFEC x27B5966 x-43569E0 2023-05-12T17:23:47.366Z,1683912227.366 [DATMMP](INFO): Received message type: directional 2023-05-12T17:23:47.366Z,1683912227.366 [DATMMP](INFO): Handled 2023-05-12T17:23:47.366Z,1683912227.366 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:47.614Z,1683912227.614 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:23:47.616Z,1683912227.616 [DATMMP](INFO): acstats rx: xA x5 xFFFF x2A1 x23 x87 xB7 xBB xD xFFFF xAF x23 xC x0 2023-05-12T17:23:47.616Z,1683912227.616 [DATMMP](INFO): Received message type: acstats 2023-05-12T17:23:47.616Z,1683912227.616 [DATMMP](INFO): Handled 2023-05-12T17:23:47.616Z,1683912227.616 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:47.866Z,1683912227.866 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:23:47.867Z,1683912227.867 [DATMMP](INFO): data_rx rx: xA x0 x0 x4 2023-05-12T17:23:47.867Z,1683912227.867 [DATMMP](INFO): Check CRC 2023-05-12T17:23:47.867Z,1683912227.867 [DATMMP](INFO): data rx parseDataResponse 4 2023-05-12T17:23:47.868Z,1683912227.868 [DATMMP](INFO): Got DATA: len 4 2023-05-12T17:23:47.868Z,1683912227.868 [DATMMP](DEBUG): In parseResponses, got ack so set commsState_ = SENDING_VERIFIED 2023-05-12T17:23:47.868Z,1683912227.868 [DATMMP](INFO): Got ~~ ack, bytes left 2 2023-05-12T17:23:47.868Z,1683912227.868 [DATMMP](INFO): Received command: 2023-05-12T17:23:47.868Z,1683912227.868 [DATMMP](INFO): Received message type: data_rx 2023-05-12T17:23:47.868Z,1683912227.868 [DATMMP](INFO): Handled 2023-05-12T17:23:47.869Z,1683912227.869 [DATMMP](INFO): *** SENDING_VERIFIED *** 2023-05-12T17:23:47.869Z,1683912227.869 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER 2023-05-12T17:23:48.118Z,1683912228.118 [DATMMP](INFO): *** SENDING_FILL_BUFFER *** 2023-05-12T17:23:48.119Z,1683912228.119 [DATMMP](INFO): modem://10: set _.temperature 24.340143 celsius 2023-05-12T17:23:48.119Z,1683912228.119 [DATMMP](INFO): #Outgoing data=1 2023-05-12T17:23:48.119Z,1683912228.119 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA 2023-05-12T17:23:48.370Z,1683912228.370 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2023-05-12T17:23:48.370Z,1683912228.370 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT 2023-05-12T17:23:48.622Z,1683912228.622 [DATMMP](INFO): *** SENDING_TRANSMIT *** 2023-05-12T17:23:48.623Z,1683912228.623 [DATMMP](INFO): Tx to 10 len 35 xid 39 2023-05-12T17:23:48.623Z,1683912228.623 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2023-05-12T17:23:48.623Z,1683912228.623 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-12T17:23:48.875Z,1683912228.875 [DATMMP](INFO): GSXN notify for xid: 39 2023-05-12T17:23:48.875Z,1683912228.875 [DATMMP](INFO): cmd_result rx: x1 x7 x0 x1 2023-05-12T17:23:48.876Z,1683912228.876 [DATMMP](DEBUG): Command complete send 2023-05-12T17:23:48.876Z,1683912228.876 [DATMMP](ERROR): 1:COMPLETE 2023-05-12T17:23:48.876Z,1683912228.876 [DATMMP](INFO): Received message type: cmd_result 2023-05-12T17:23:48.876Z,1683912228.876 [DATMMP](INFO): Handled 2023-05-12T17:23:48.876Z,1683912228.876 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:49.126Z,1683912229.126 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:23:49.127Z,1683912229.127 [DATMMP](INFO): timestamp rx: x0 x11 x17 x2F x20F7 2023-05-12T17:23:49.127Z,1683912229.127 [DATMMP](INFO): Received message type: timestamp 2023-05-12T17:23:49.128Z,1683912229.128 [DATMMP](INFO): Handled 2023-05-12T17:23:49.128Z,1683912229.128 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:49.378Z,1683912229.378 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:49.630Z,1683912229.630 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:49.882Z,1683912229.882 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:50.134Z,1683912230.134 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:50.386Z,1683912230.386 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:50.638Z,1683912230.638 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:50.890Z,1683912230.890 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:51.142Z,1683912231.142 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:51.394Z,1683912231.394 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:51.654Z,1683912231.654 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:51.906Z,1683912231.906 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:52.158Z,1683912232.158 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:52.410Z,1683912232.410 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:52.662Z,1683912232.662 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:52.914Z,1683912232.914 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:53.167Z,1683912233.167 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:23:53.167Z,1683912233.167 [DATMMP](INFO): doppler rx: xFFFF 2023-05-12T17:23:53.167Z,1683912233.167 [DATMMP](INFO): Received message type: doppler 2023-05-12T17:23:53.168Z,1683912233.168 [DATMMP](INFO): Handled 2023-05-12T17:23:53.168Z,1683912233.168 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:53.418Z,1683912233.418 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:23:53.419Z,1683912233.419 [DATMMP](INFO): timestamp rx: x1 x11 x17 x33 x210D 2023-05-12T17:23:53.420Z,1683912233.420 [DATMMP](INFO): Rx dataTimestamp_ set to:1683912249.657408 2023-05-12T17:23:53.420Z,1683912233.420 [DATMMP](INFO): Rx ping set to:1683912233.419286 2023-05-12T17:23:53.420Z,1683912233.420 [DATMMP](INFO): Received message type: timestamp 2023-05-12T17:23:53.420Z,1683912233.420 [DATMMP](INFO): Handled 2023-05-12T17:23:53.420Z,1683912233.420 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:53.670Z,1683912233.670 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:53.923Z,1683912233.923 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:23:53.925Z,1683912233.925 [DATMMP](INFO): remote_header rx: xF x4 x5 x4 x0 xC xA x0 2023-05-12T17:23:53.926Z,1683912233.926 [DATMMP](INFO): DATA Src=10, Dst=12 2023-05-12T17:23:53.926Z,1683912233.926 [DATMMP](INFO): Received message type: remote_header 2023-05-12T17:23:53.926Z,1683912233.926 [DATMMP](INFO): Handled 2023-05-12T17:23:53.926Z,1683912233.926 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:54.174Z,1683912234.174 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:23:54.175Z,1683912234.175 [DATMMP](INFO): nav_stat rx: x0 x1 x1 2023-05-12T17:23:54.175Z,1683912234.175 [DATMMP](INFO): Received message type: nav_stat 2023-05-12T17:23:54.175Z,1683912234.175 [DATMMP](INFO): Handled 2023-05-12T17:23:54.175Z,1683912234.175 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:54.427Z,1683912234.427 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:23:54.428Z,1683912234.428 [DATMMP](INFO): directional_debug rx: xA2 x1CE x22 xB3 x1030 x1341 x1212 x1643 2023-05-12T17:23:54.429Z,1683912234.429 [DATMMP](INFO): Received message type: directional_debug 2023-05-12T17:23:54.429Z,1683912234.429 [DATMMP](INFO): Handled 2023-05-12T17:23:54.429Z,1683912234.429 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:54.678Z,1683912234.678 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:23:54.680Z,1683912234.680 [DATMMP](INFO): directional rx: x9C x17E x53A xFE32 x7FF xFE3D x0 x2D5 x4 xFFEE x27B5966 x-43569E0 2023-05-12T17:23:54.682Z,1683912234.682 [DATMMP](INFO): Received message type: directional 2023-05-12T17:23:54.682Z,1683912234.682 [DATMMP](INFO): Handled 2023-05-12T17:23:54.682Z,1683912234.682 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:54.930Z,1683912234.930 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:23:54.932Z,1683912234.932 [DATMMP](INFO): acstats rx: xA x5 xFFFF x2C6 x22 x84 xB4 xC4 xD xFFFF xAF x25 xD x0 2023-05-12T17:23:54.932Z,1683912234.932 [DATMMP](INFO): Received message type: acstats 2023-05-12T17:23:54.932Z,1683912234.932 [DATMMP](INFO): Handled 2023-05-12T17:23:54.933Z,1683912234.933 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:55.182Z,1683912235.182 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:23:55.183Z,1683912235.183 [DATMMP](INFO): data_rx rx: xA x0 x0 x4 2023-05-12T17:23:55.183Z,1683912235.183 [DATMMP](INFO): Check CRC 2023-05-12T17:23:55.183Z,1683912235.183 [DATMMP](INFO): data rx parseDataResponse 4 2023-05-12T17:23:55.184Z,1683912235.184 [DATMMP](INFO): Got DATA: len 4 2023-05-12T17:23:55.184Z,1683912235.184 [DATMMP](DEBUG): In parseResponses, got ack so set commsState_ = SENDING_VERIFIED 2023-05-12T17:23:55.184Z,1683912235.184 [DATMMP](INFO): Got ~~ ack, bytes left 2 2023-05-12T17:23:55.184Z,1683912235.184 [DATMMP](INFO): Received command: 2023-05-12T17:23:55.184Z,1683912235.184 [DATMMP](INFO): Received message type: data_rx 2023-05-12T17:23:55.184Z,1683912235.184 [DATMMP](INFO): Handled 2023-05-12T17:23:55.185Z,1683912235.185 [DATMMP](INFO): *** SENDING_VERIFIED *** 2023-05-12T17:23:55.185Z,1683912235.185 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER 2023-05-12T17:23:55.434Z,1683912235.434 [DATMMP](INFO): *** SENDING_FILL_BUFFER *** 2023-05-12T17:23:55.435Z,1683912235.435 [DATMMP](INFO): modem://10: set _.humidity 12.879990 percent 2023-05-12T17:23:55.435Z,1683912235.435 [DATMMP](INFO): #Outgoing data=1 2023-05-12T17:23:55.435Z,1683912235.435 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA 2023-05-12T17:23:55.686Z,1683912235.686 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2023-05-12T17:23:55.686Z,1683912235.686 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT 2023-05-12T17:23:55.938Z,1683912235.938 [DATMMP](INFO): *** SENDING_TRANSMIT *** 2023-05-12T17:23:55.938Z,1683912235.938 [DATMMP](INFO): Tx to 10 len 32 xid 40 2023-05-12T17:23:55.939Z,1683912235.939 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2023-05-12T17:23:55.939Z,1683912235.939 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-12T17:23:56.190Z,1683912236.190 [DATMMP](INFO): GSXN notify for xid: 40 2023-05-12T17:23:56.191Z,1683912236.191 [DATMMP](INFO): cmd_result rx: x1 x7 x0 x1 2023-05-12T17:23:56.191Z,1683912236.191 [DATMMP](DEBUG): Command complete send 2023-05-12T17:23:56.192Z,1683912236.192 [DATMMP](ERROR): 1:COMPLETE 2023-05-12T17:23:56.192Z,1683912236.192 [DATMMP](INFO): Received message type: cmd_result 2023-05-12T17:23:56.192Z,1683912236.192 [DATMMP](INFO): Handled 2023-05-12T17:23:56.192Z,1683912236.192 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:56.442Z,1683912236.442 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:23:56.443Z,1683912236.443 [DATMMP](INFO): timestamp rx: x0 x11 x17 x37 x59C 2023-05-12T17:23:56.443Z,1683912236.443 [DATMMP](INFO): Received message type: timestamp 2023-05-12T17:23:56.444Z,1683912236.444 [DATMMP](INFO): Handled 2023-05-12T17:23:56.444Z,1683912236.444 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:56.694Z,1683912236.694 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:56.946Z,1683912236.946 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:57.198Z,1683912237.198 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:57.450Z,1683912237.450 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:57.702Z,1683912237.702 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:57.954Z,1683912237.954 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:58.206Z,1683912238.206 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:58.458Z,1683912238.458 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:58.711Z,1683912238.711 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:58.962Z,1683912238.962 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:59.214Z,1683912239.214 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:59.466Z,1683912239.466 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:59.718Z,1683912239.718 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:23:59.970Z,1683912239.970 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:24:00.222Z,1683912240.222 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:24:00.474Z,1683912240.474 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:24:00.475Z,1683912240.475 [DATMMP](INFO): doppler rx: xFFFF 2023-05-12T17:24:00.475Z,1683912240.475 [DATMMP](INFO): Received message type: doppler 2023-05-12T17:24:00.475Z,1683912240.475 [DATMMP](INFO): Handled 2023-05-12T17:24:00.475Z,1683912240.475 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:24:00.726Z,1683912240.726 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:24:00.727Z,1683912240.727 [DATMMP](INFO): timestamp rx: x1 x11 x17 x3B x5B5 2023-05-12T17:24:00.728Z,1683912240.728 [DATMMP](INFO): Rx dataTimestamp_ set to:1683912253.280576 2023-05-12T17:24:00.728Z,1683912240.728 [DATMMP](INFO): Rx ping set to:1683912240.727326 2023-05-12T17:24:00.728Z,1683912240.728 [DATMMP](INFO): Received message type: timestamp 2023-05-12T17:24:00.728Z,1683912240.728 [DATMMP](INFO): Handled 2023-05-12T17:24:00.729Z,1683912240.729 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:24:00.978Z,1683912240.978 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:24:01.231Z,1683912241.231 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:24:01.233Z,1683912241.233 [DATMMP](INFO): remote_header rx: xF x4 x5 x4 x0 xC xA x0 2023-05-12T17:24:01.234Z,1683912241.234 [DATMMP](INFO): DATA Src=10, Dst=12 2023-05-12T17:24:01.234Z,1683912241.234 [DATMMP](INFO): Received message type: remote_header 2023-05-12T17:24:01.234Z,1683912241.234 [DATMMP](INFO): Handled 2023-05-12T17:24:01.235Z,1683912241.235 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:24:01.482Z,1683912241.482 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:24:01.483Z,1683912241.483 [DATMMP](INFO): nav_stat rx: x0 x1 x1 2023-05-12T17:24:01.483Z,1683912241.483 [DATMMP](INFO): Received message type: nav_stat 2023-05-12T17:24:01.483Z,1683912241.483 [DATMMP](INFO): Handled 2023-05-12T17:24:01.483Z,1683912241.483 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:24:01.734Z,1683912241.734 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:24:01.735Z,1683912241.735 [DATMMP](INFO): directional_debug rx: x66 x1E0 x24 xBA x1060 x1311 x11F2 x1673 2023-05-12T17:24:01.736Z,1683912241.736 [DATMMP](INFO): Received message type: directional_debug 2023-05-12T17:24:01.737Z,1683912241.737 [DATMMP](INFO): Handled 2023-05-12T17:24:01.737Z,1683912241.737 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:24:01.986Z,1683912241.986 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:24:01.988Z,1683912241.988 [DATMMP](INFO): directional rx: x5F x197 x576 xFE20 x828 xFE29 x0 x2C4 x4 xFFEE x27B5966 x-43569E0 2023-05-12T17:24:01.989Z,1683912241.989 [DATMMP](INFO): Received message type: directional 2023-05-12T17:24:01.990Z,1683912241.990 [DATMMP](INFO): Handled 2023-05-12T17:24:01.990Z,1683912241.990 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:24:02.238Z,1683912242.238 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:24:02.240Z,1683912242.240 [DATMMP](INFO): acstats rx: xA x5 xFFFF x2CB x22 x91 xC2 xC1 xD xFFFF xAE x25 xD x0 2023-05-12T17:24:02.240Z,1683912242.240 [DATMMP](INFO): Received message type: acstats 2023-05-12T17:24:02.240Z,1683912242.240 [DATMMP](INFO): Handled 2023-05-12T17:24:02.240Z,1683912242.240 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-12T17:24:02.490Z,1683912242.490 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T17:24:02.491Z,1683912242.491 [DATMMP](INFO): data_rx rx: xA x0 x0 x4 2023-05-12T17:24:02.491Z,1683912242.491 [DATMMP](INFO): Check CRC 2023-05-12T17:24:02.491Z,1683912242.491 [DATMMP](INFO): data rx parseDataResponse 4 2023-05-12T17:24:02.492Z,1683912242.492 [DATMMP](INFO): Got DATA: len 4 2023-05-12T17:24:02.492Z,1683912242.492 [DATMMP](DEBUG): In parseResponses, got ack so set commsState_ = SENDING_VERIFIED 2023-05-12T17:24:02.492Z,1683912242.492 [DATMMP](INFO): Got ~~ ack, bytes left 2 2023-05-12T17:24:02.492Z,1683912242.492 [DATMMP](INFO): Received command: 2023-05-12T17:24:02.492Z,1683912242.492 [DATMMP](INFO): Received message type: data_rx 2023-05-12T17:24:02.492Z,1683912242.492 [DATMMP](INFO): Handled 2023-05-12T17:24:02.493Z,1683912242.493 [DATMMP](INFO): *** SENDING_VERIFIED *** 2023-05-12T17:24:02.493Z,1683912242.493 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER