2023-05-11T16:30:33.691Z,1683822633.691 [CommandExec](IMPORTANT): got command restart logs 2023-05-11T16:30:34.314Z,1683822634.314 [DataOverHttps](INFO): Sending 1058 bytes from file Logs/20230511T160254/Express0004.lzma 2023-05-11T16:30:43.187Z,1683822643.187 [CommandExec](IMPORTANT): got command failComponent 2023-05-11T16:30:43.188Z,1683822643.188 [CommandExec](IMPORTANT): Failed components: 2023-05-11T16:30:43.188Z,1683822643.188 [CommandExec](IMPORTANT): No failed Components. 2023-05-11T16:30:44.063Z,1683822644.063 [DataOverHttps](INFO): Sending 1058 bytes from file Logs/20230511T160254/Express0004.lzma 2023-05-11T16:30:45.063Z,1683822645.063 [DataOverHttps](INFO): Moved sent file to Logs/20230511T160254/Express0004.lzma.bak 2023-05-11T16:30:45.063Z,1683822645.063 [DataOverHttps](INFO): SBD MOMSN=18290276 2023-05-11T16:30:50.022Z,1683822650.022 [CommandExec](IMPORTANT): got command run ./Missions/Demo/senddata_direct_and_track_test.xml 2023-05-11T16:30:50.022Z,1683822650.022 [MissionManager](INFO): Loading Mission from file: ./Missions/Demo/senddata_direct_and_track_test.xml 2023-05-11T16:30:50.102Z,1683822650.102 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.AcousticTargetContactLabel = 10.000000 count 2023-05-11T16:30:50.105Z,1683822650.105 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.AcousticTargetDepth = 0.000000 m 2023-05-11T16:30:50.113Z,1683822650.113 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.TrackingUpdatePeriod = 15.000000 s 2023-05-11T16:30:50.121Z,1683822650.121 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.NumberOfPings = 1.000000 count 2023-05-11T16:30:50.162Z,1683822650.162 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.NumberOfFixesLowPass = 2.000000 count 2023-05-11T16:30:50.164Z,1683822650.164 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.NumberOfStartingFixesToIgnore = 2.000000 count 2023-05-11T16:30:50.166Z,1683822650.166 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.AcommsInterval = 1.000000 min 2023-05-11T16:30:50.168Z,1683822650.168 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.modemId1 = 9.000000 enum 2023-05-11T16:30:50.170Z,1683822650.170 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.modemId2 = 5.000000 enum 2023-05-11T16:30:50.172Z,1683822650.172 [MissionManager](INFO): DefineOutput senddata_direct_and_track_test.ModemID = 1.000000 enum 2023-05-11T16:30:50.240Z,1683822650.240 [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 1 Modem ID1. 9 Modem ID2. 5 Modem ID. Set to modemId1 or modemId2. Initialized to 1. 1 2023-05-11T16:30:50.242Z,1683822650.242 [CommandExec](IMPORTANT): Running ./Missions/Demo/senddata_direct_and_track_test.xml 2023-05-11T16:30:50.246Z,1683822650.246 [Default] Stopped 2023-05-11T16:30:50.247Z,1683822650.247 [Default](DEBUG): Aggregate::uninitialize Default 2023-05-11T16:30:50.247Z,1683822650.247 [Default:B.GoToSurface] Stopped 2023-05-11T16:30:50.247Z,1683822650.247 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-05-11T16:30:50.247Z,1683822650.247 [Default:CheckIn] Stopped 2023-05-11T16:30:50.247Z,1683822650.247 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-05-11T16:30:50.247Z,1683822650.247 [Default:CheckIn:Read_Iridium] Stopped 2023-05-11T16:30:50.247Z,1683822650.247 [MissionManager](IMPORTANT): Started mission senddata_direct_and_track_test 2023-05-11T16:30:50.247Z,1683822650.247 [senddata_direct_and_track_test] Running Loop=1 2023-05-11T16:30:50.247Z,1683822650.247 [senddata_direct_and_track_test](DEBUG): Aggregate::initialize senddata_direct_and_track_test 2023-05-11T16:30:50.248Z,1683822650.248 [senddata_direct_and_track_test:DirectData] Running Loop=1 2023-05-11T16:30:50.248Z,1683822650.248 [senddata_direct_and_track_test:DirectData](DEBUG): Aggregate::initialize senddata_direct_and_track_test:DirectData 2023-05-11T16:30:50.248Z,1683822650.248 [senddata_direct_and_track_test:DirectData:A] Running Loop=1 2023-05-11T16:30:50.249Z,1683822650.249 [senddata_direct_and_track_test:DirectData:A](INFO): Queuing up send data for Onboard.Pressure with destination: modem:10:_.pressure 2023-05-11T16:30:50.249Z,1683822650.249 [senddata_direct_and_track_test:DirectData:A] Stopped 2023-05-11T16:30:50.249Z,1683822650.249 [senddata_direct_and_track_test:DirectData:B] Running Loop=1 2023-05-11T16:30:50.438Z,1683822650.438 [DATMMP](INFO): *** SENDING_FILL_BUFFER *** 2023-05-11T16:30:50.439Z,1683822650.439 [DATMMP](INFO): modem://10: set _.pressure 0.745180 atmosphere 2023-05-11T16:30:50.439Z,1683822650.439 [DATMMP](INFO): #Outgoing data=1 2023-05-11T16:30:50.439Z,1683822650.439 [DATMMP](ERROR): Waiting for previous to be ack'd 2023-05-11T16:30:50.439Z,1683822650.439 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA 2023-05-11T16:30:50.634Z,1683822650.634 [senddata_direct_and_track_test:DirectData:B](INFO): Queuing up send data for Onboard.Temperature with destination: modem:10:_.temperature 2023-05-11T16:30:50.634Z,1683822650.634 [senddata_direct_and_track_test:DirectData:B] Stopped 2023-05-11T16:30:50.634Z,1683822650.634 [senddata_direct_and_track_test:DirectData:C] Running Loop=1 2023-05-11T16:30:50.690Z,1683822650.690 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2023-05-11T16:30:50.690Z,1683822650.690 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT 2023-05-11T16:30:50.942Z,1683822650.942 [DATMMP](INFO): *** SENDING_TRANSMIT *** 2023-05-11T16:30:50.942Z,1683822650.942 [DATMMP](INFO): Tx to 10 len 34 xid 41 2023-05-11T16:30:50.943Z,1683822650.943 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2023-05-11T16:30:50.943Z,1683822650.943 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-11T16:30:51.043Z,1683822651.043 [senddata_direct_and_track_test:DirectData:C](INFO): Queuing up send data for Onboard.Humidity with destination: modem:10:_.humidity 2023-05-11T16:30:51.044Z,1683822651.044 [senddata_direct_and_track_test:DirectData:C] Stopped 2023-05-11T16:30:51.044Z,1683822651.044 [senddata_direct_and_track_test:DirectData](INFO): Completed senddata_direct_and_track_test:DirectData 2023-05-11T16:30:51.044Z,1683822651.044 [senddata_direct_and_track_test:DirectData] Stopped 2023-05-11T16:30:51.044Z,1683822651.044 [senddata_direct_and_track_test:DirectData](DEBUG): Aggregate::uninitialize senddata_direct_and_track_test:DirectData 2023-05-11T16:30:51.044Z,1683822651.044 [senddata_direct_and_track_test](INFO): Completed senddata_direct_and_track_test 2023-05-11T16:30:51.044Z,1683822651.044 [MissionManager](INFO): senddata_direct_and_track_test is completed. 2023-05-11T16:30:51.044Z,1683822651.044 [MissionManager](INFO): Uninitializing Mission senddata_direct_and_track_test 2023-05-11T16:30:51.044Z,1683822651.044 [senddata_direct_and_track_test] Stopped 2023-05-11T16:30:51.045Z,1683822651.045 [senddata_direct_and_track_test](DEBUG): Aggregate::uninitialize senddata_direct_and_track_test 2023-05-11T16:30:51.194Z,1683822651.194 [DATMMP](INFO): GSXN notify for xid: 41 2023-05-11T16:30:51.195Z,1683822651.195 [DATMMP](INFO): cmd_result rx: x1 x7 x0 x1 2023-05-11T16:30:51.195Z,1683822651.195 [DATMMP](DEBUG): Command complete send 2023-05-11T16:30:51.195Z,1683822651.195 [DATMMP](ERROR): 1:COMPLETE 2023-05-11T16:30:51.196Z,1683822651.196 [DATMMP](INFO): Received message type: cmd_result 2023-05-11T16:30:51.196Z,1683822651.196 [DATMMP](INFO): Handled 2023-05-11T16:30:51.196Z,1683822651.196 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:30:51.446Z,1683822651.446 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:30:51.447Z,1683822651.447 [DATMMP](INFO): timestamp rx: x0 x10 x1E x31 x133A 2023-05-11T16:30:51.447Z,1683822651.447 [DATMMP](INFO): Received message type: timestamp 2023-05-11T16:30:51.447Z,1683822651.447 [DATMMP](INFO): Handled 2023-05-11T16:30:51.447Z,1683822651.447 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:30:51.493Z,1683822651.493 [MissionManager](IMPORTANT): Started mission Default 2023-05-11T16:30:51.493Z,1683822651.493 [Default] Running Loop=1 2023-05-11T16:30:51.494Z,1683822651.494 [Default](DEBUG): Aggregate::initialize Default 2023-05-11T16:30:51.494Z,1683822651.494 [Default:B.GoToSurface] Running Loop=1 2023-05-11T16:30:51.494Z,1683822651.494 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-05-11T16:30:51.494Z,1683822651.494 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-05-11T16:30:51.494Z,1683822651.494 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-05-11T16:30:51.495Z,1683822651.495 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-05-11T16:30:51.495Z,1683822651.495 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-05-11T16:30:51.495Z,1683822651.495 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-05-11T16:30:51.495Z,1683822651.495 [Default:A.Wait] Running Loop=1 2023-05-11T16:30:51.496Z,1683822651.496 [Default:A.Wait](DEBUG): Initialize Wait Component. 2023-05-11T16:30:51.698Z,1683822651.698 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:30:51.950Z,1683822651.950 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:30:52.210Z,1683822652.210 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:30:52.458Z,1683822652.458 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:30:52.710Z,1683822652.710 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:30:52.962Z,1683822652.962 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:30:53.214Z,1683822653.214 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:30:53.466Z,1683822653.466 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:30:53.718Z,1683822653.718 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:30:53.970Z,1683822653.970 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:30:54.223Z,1683822654.223 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:30:54.474Z,1683822654.474 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:30:54.726Z,1683822654.726 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:30:54.978Z,1683822654.978 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:30:55.230Z,1683822655.230 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:30:55.482Z,1683822655.482 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:30:55.734Z,1683822655.734 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:30:55.986Z,1683822655.986 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:30:56.238Z,1683822656.238 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:30:56.490Z,1683822656.490 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:30:56.742Z,1683822656.742 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:30:56.994Z,1683822656.994 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:30:57.246Z,1683822657.246 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:30:57.498Z,1683822657.498 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:30:57.750Z,1683822657.750 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:30:58.002Z,1683822658.002 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:30:58.254Z,1683822658.254 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:30:58.506Z,1683822658.506 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:30:58.758Z,1683822658.758 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:30:59.010Z,1683822659.010 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:30:59.262Z,1683822659.262 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:30:59.514Z,1683822659.514 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:30:59.767Z,1683822659.767 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:00.018Z,1683822660.018 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:00.270Z,1683822660.270 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:00.522Z,1683822660.522 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:00.774Z,1683822660.774 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:01.026Z,1683822661.026 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:01.026Z,1683822661.026 [DATMMP](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER 2023-05-11T16:31:01.278Z,1683822661.278 [DATMMP](INFO): *** SENDING_FILL_BUFFER *** 2023-05-11T16:31:01.279Z,1683822661.279 [DATMMP](INFO): modem://10: set _.temperature 24.259546 celsius 2023-05-11T16:31:01.279Z,1683822661.279 [DATMMP](INFO): #Outgoing data=1 2023-05-11T16:31:01.279Z,1683822661.279 [DATMMP](ERROR): Waiting for previous to be ack'd 2023-05-11T16:31:01.279Z,1683822661.279 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA 2023-05-11T16:31:01.530Z,1683822661.530 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2023-05-11T16:31:01.530Z,1683822661.530 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT 2023-05-11T16:31:01.782Z,1683822661.782 [DATMMP](INFO): *** SENDING_TRANSMIT *** 2023-05-11T16:31:01.782Z,1683822661.782 [DATMMP](INFO): Tx to 10 len 34 xid 42 2023-05-11T16:31:01.783Z,1683822661.783 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2023-05-11T16:31:01.783Z,1683822661.783 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-11T16:31:02.034Z,1683822662.034 [DATMMP](INFO): GSXN notify for xid: 42 2023-05-11T16:31:02.035Z,1683822662.035 [DATMMP](INFO): cmd_result rx: x1 x7 x0 x1 2023-05-11T16:31:02.035Z,1683822662.035 [DATMMP](DEBUG): Command complete send 2023-05-11T16:31:02.035Z,1683822662.035 [DATMMP](ERROR): 1:COMPLETE 2023-05-11T16:31:02.036Z,1683822662.036 [DATMMP](INFO): Received message type: cmd_result 2023-05-11T16:31:02.036Z,1683822662.036 [DATMMP](INFO): Handled 2023-05-11T16:31:02.036Z,1683822662.036 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:02.286Z,1683822662.286 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:31:02.287Z,1683822662.287 [DATMMP](INFO): timestamp rx: x0 x10 x1F x0 xB6A 2023-05-11T16:31:02.287Z,1683822662.287 [DATMMP](INFO): Received message type: timestamp 2023-05-11T16:31:02.287Z,1683822662.287 [DATMMP](INFO): Handled 2023-05-11T16:31:02.287Z,1683822662.287 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:02.538Z,1683822662.538 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:02.790Z,1683822662.790 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:03.042Z,1683822663.042 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:03.265Z,1683822663.265 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20230511T160254/Courier0003.lzma 2023-05-11T16:31:03.294Z,1683822663.294 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:03.546Z,1683822663.546 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:03.798Z,1683822663.798 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:04.050Z,1683822664.050 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:04.278Z,1683822664.278 [DataOverHttps](INFO): Moved sent file to Logs/20230511T160254/Courier0003.lzma.bak 2023-05-11T16:31:04.281Z,1683822664.281 [DataOverHttps](INFO): SBD MOMSN=18290339 2023-05-11T16:31:04.302Z,1683822664.302 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:04.554Z,1683822664.554 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:04.786Z,1683822664.786 [Default:A.Wait](INFO): Done Waiting. 2023-05-11T16:31:04.786Z,1683822664.786 [Default:A.Wait] Stopped 2023-05-11T16:31:04.786Z,1683822664.786 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2023-05-11T16:31:04.806Z,1683822664.806 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:05.058Z,1683822665.058 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:05.186Z,1683822665.186 [Default:CheckIn] Running Loop=1 2023-05-11T16:31:05.186Z,1683822665.186 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-05-11T16:31:05.186Z,1683822665.186 [Default:CheckIn:Read_GPS] Running Loop=1 2023-05-11T16:31:05.310Z,1683822665.310 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:05.574Z,1683822665.574 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:05.814Z,1683822665.814 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:06.066Z,1683822666.066 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:06.318Z,1683822666.318 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:06.570Z,1683822666.570 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:06.801Z,1683822666.801 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-05-11T16:31:06.801Z,1683822666.801 [NAL9602] Data Fault, FailCount= 1 2023-05-11T16:31:06.801Z,1683822666.801 [NAL9602](ERROR): Data Fault 2023-05-11T16:31:06.822Z,1683822666.822 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:06.825Z,1683822666.825 [CBIT](ERROR): Data Fault in component: NAL9602 2023-05-11T16:31:07.074Z,1683822667.074 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:07.198Z,1683822667.198 [NAL9602](INFO): Powering down 2023-05-11T16:31:07.326Z,1683822667.326 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:07.578Z,1683822667.578 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:07.830Z,1683822667.830 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:08.027Z,1683822668.027 [CBIT](INFO): Clearing failed state for component NAL9602 2023-05-11T16:31:08.027Z,1683822668.027 [NAL9602] No Fault, FailCount= 1 2023-05-11T16:31:08.082Z,1683822668.082 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:08.334Z,1683822668.334 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:08.586Z,1683822668.586 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:08.838Z,1683822668.838 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:09.090Z,1683822669.090 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:09.342Z,1683822669.342 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:09.594Z,1683822669.594 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:09.846Z,1683822669.846 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:10.098Z,1683822670.098 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:10.350Z,1683822670.350 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:10.602Z,1683822670.602 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:10.854Z,1683822670.854 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:11.106Z,1683822671.106 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:11.358Z,1683822671.358 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:11.610Z,1683822671.610 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:11.862Z,1683822671.862 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:11.862Z,1683822671.862 [DATMMP](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER 2023-05-11T16:31:12.115Z,1683822672.115 [DATMMP](INFO): *** SENDING_FILL_BUFFER *** 2023-05-11T16:31:12.119Z,1683822672.119 [DATMMP](INFO): modem://10: set _.humidity 12.916615 percent 2023-05-11T16:31:12.120Z,1683822672.120 [DATMMP](INFO): #Outgoing data=2 2023-05-11T16:31:12.121Z,1683822672.121 [DATMMP](ERROR): Waiting for previous to be ack'd 2023-05-11T16:31:12.121Z,1683822672.121 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA 2023-05-11T16:31:12.366Z,1683822672.366 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2023-05-11T16:31:12.366Z,1683822672.366 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT 2023-05-11T16:31:12.618Z,1683822672.618 [DATMMP](INFO): *** SENDING_TRANSMIT *** 2023-05-11T16:31:12.618Z,1683822672.618 [DATMMP](INFO): Tx to 10 len 34 xid 43 2023-05-11T16:31:12.619Z,1683822672.619 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2023-05-11T16:31:12.619Z,1683822672.619 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-11T16:31:12.870Z,1683822672.870 [DATMMP](INFO): GSXN notify for xid: 43 2023-05-11T16:31:12.871Z,1683822672.871 [DATMMP](INFO): cmd_result rx: x1 x7 x0 x1 2023-05-11T16:31:12.871Z,1683822672.871 [DATMMP](DEBUG): Command complete send 2023-05-11T16:31:12.871Z,1683822672.871 [DATMMP](ERROR): 1:COMPLETE 2023-05-11T16:31:12.872Z,1683822672.872 [DATMMP](INFO): Received message type: cmd_result 2023-05-11T16:31:12.872Z,1683822672.872 [DATMMP](INFO): Handled 2023-05-11T16:31:12.872Z,1683822672.872 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:13.122Z,1683822673.122 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:31:13.123Z,1683822673.123 [DATMMP](INFO): timestamp rx: x0 x10 x1F xB x58D 2023-05-11T16:31:13.123Z,1683822673.123 [DATMMP](INFO): Received message type: timestamp 2023-05-11T16:31:13.123Z,1683822673.123 [DATMMP](INFO): Handled 2023-05-11T16:31:13.123Z,1683822673.123 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:13.374Z,1683822673.374 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:13.626Z,1683822673.626 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:13.878Z,1683822673.878 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:14.130Z,1683822674.130 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:14.382Z,1683822674.382 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:14.634Z,1683822674.634 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:14.886Z,1683822674.886 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:15.138Z,1683822675.138 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:15.390Z,1683822675.390 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:15.642Z,1683822675.642 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:15.894Z,1683822675.894 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:16.146Z,1683822676.146 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:16.398Z,1683822676.398 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:16.650Z,1683822676.650 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:17.154Z,1683822677.154 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:17.406Z,1683822677.406 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:17.658Z,1683822677.658 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:17.910Z,1683822677.910 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:18.170Z,1683822678.170 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:18.422Z,1683822678.422 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:18.674Z,1683822678.674 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:19.182Z,1683822679.182 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:19.434Z,1683822679.434 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:19.691Z,1683822679.691 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:19.942Z,1683822679.942 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:20.194Z,1683822680.194 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:20.446Z,1683822680.446 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:20.698Z,1683822680.698 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:20.958Z,1683822680.958 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:21.210Z,1683822681.210 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:21.462Z,1683822681.462 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:21.714Z,1683822681.714 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:21.966Z,1683822681.966 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:22.218Z,1683822682.218 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:22.470Z,1683822682.470 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:22.722Z,1683822682.722 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:22.723Z,1683822682.723 [DATMMP](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER 2023-05-11T16:31:22.974Z,1683822682.974 [DATMMP](INFO): *** SENDING_FILL_BUFFER *** 2023-05-11T16:31:22.974Z,1683822682.974 [DATMMP](ERROR): Waiting for previous to be ack'd 2023-05-11T16:31:22.974Z,1683822682.974 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA 2023-05-11T16:31:23.226Z,1683822683.226 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2023-05-11T16:31:23.226Z,1683822683.226 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT 2023-05-11T16:31:23.478Z,1683822683.478 [DATMMP](INFO): *** SENDING_TRANSMIT *** 2023-05-11T16:31:23.478Z,1683822683.478 [DATMMP](INFO): Tx to 10 len 34 xid 44 2023-05-11T16:31:23.479Z,1683822683.479 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2023-05-11T16:31:23.479Z,1683822683.479 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-11T16:31:23.730Z,1683822683.730 [DATMMP](INFO): GSXN notify for xid: 44 2023-05-11T16:31:23.731Z,1683822683.731 [DATMMP](INFO): cmd_result rx: x1 x7 x0 x1 2023-05-11T16:31:23.731Z,1683822683.731 [DATMMP](DEBUG): Command complete send 2023-05-11T16:31:23.731Z,1683822683.731 [DATMMP](ERROR): 1:COMPLETE 2023-05-11T16:31:23.732Z,1683822683.732 [DATMMP](INFO): Received message type: cmd_result 2023-05-11T16:31:23.732Z,1683822683.732 [DATMMP](INFO): Handled 2023-05-11T16:31:23.732Z,1683822683.732 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:23.982Z,1683822683.982 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:31:23.983Z,1683822683.983 [DATMMP](INFO): timestamp rx: x0 x10 x1F x15 x26C0 2023-05-11T16:31:23.983Z,1683822683.983 [DATMMP](INFO): Received message type: timestamp 2023-05-11T16:31:23.984Z,1683822683.984 [DATMMP](INFO): Handled 2023-05-11T16:31:23.984Z,1683822683.984 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:24.234Z,1683822684.234 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:24.486Z,1683822684.486 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:24.738Z,1683822684.738 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:24.990Z,1683822684.990 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:25.242Z,1683822685.242 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:25.494Z,1683822685.494 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:25.746Z,1683822685.746 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:25.998Z,1683822685.998 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:26.250Z,1683822686.250 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:26.502Z,1683822686.502 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:26.754Z,1683822686.754 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:27.260Z,1683822687.260 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:27.510Z,1683822687.510 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:27.762Z,1683822687.762 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:28.014Z,1683822688.014 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:28.266Z,1683822688.266 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:28.518Z,1683822688.518 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:28.770Z,1683822688.770 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:29.022Z,1683822689.022 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:29.274Z,1683822689.274 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:29.526Z,1683822689.526 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:29.778Z,1683822689.778 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:30.030Z,1683822690.030 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:30.282Z,1683822690.282 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:30.534Z,1683822690.534 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:30.786Z,1683822690.786 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:31.038Z,1683822691.038 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:31.290Z,1683822691.290 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:31.542Z,1683822691.542 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:31.798Z,1683822691.798 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:32.050Z,1683822692.050 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:32.302Z,1683822692.302 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:32.554Z,1683822692.554 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:32.806Z,1683822692.806 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:33.058Z,1683822693.058 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:33.310Z,1683822693.310 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:33.562Z,1683822693.562 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:33.562Z,1683822693.562 [DATMMP](DEBUG): In sendingAckWaiting, hit max timeouts so set commsState_ = SENDING_VERIFIED 2023-05-11T16:31:33.562Z,1683822693.562 [DATMMP](ERROR): Ack receipt timeout failure. 2023-05-11T16:31:33.814Z,1683822693.814 [DATMMP](INFO): *** SENDING_VERIFIED *** 2023-05-11T16:31:33.814Z,1683822693.814 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER 2023-05-11T16:31:34.066Z,1683822694.066 [DATMMP](INFO): *** SENDING_FILL_BUFFER *** 2023-05-11T16:31:34.066Z,1683822694.066 [DATMMP](ERROR): Waiting for previous to be ack'd 2023-05-11T16:31:34.066Z,1683822694.066 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA 2023-05-11T16:31:34.570Z,1683822694.570 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2023-05-11T16:31:34.570Z,1683822694.570 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT 2023-05-11T16:31:34.822Z,1683822694.822 [DATMMP](INFO): *** SENDING_TRANSMIT *** 2023-05-11T16:31:34.823Z,1683822694.823 [DATMMP](INFO): Tx to 10 len 35 xid 45 2023-05-11T16:31:34.823Z,1683822694.823 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2023-05-11T16:31:34.823Z,1683822694.823 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-11T16:31:35.074Z,1683822695.074 [DATMMP](INFO): GSXN notify for xid: 45 2023-05-11T16:31:35.075Z,1683822695.075 [DATMMP](INFO): cmd_result rx: x1 x7 x0 x1 2023-05-11T16:31:35.075Z,1683822695.075 [DATMMP](DEBUG): Command complete send 2023-05-11T16:31:35.075Z,1683822695.075 [DATMMP](ERROR): 1:COMPLETE 2023-05-11T16:31:35.075Z,1683822695.075 [DATMMP](INFO): Received message type: cmd_result 2023-05-11T16:31:35.076Z,1683822695.076 [DATMMP](INFO): Handled 2023-05-11T16:31:35.076Z,1683822695.076 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:35.326Z,1683822695.326 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:31:35.327Z,1683822695.327 [DATMMP](INFO): timestamp rx: x0 x10 x1F x21 xD5B 2023-05-11T16:31:35.327Z,1683822695.327 [DATMMP](INFO): Received message type: timestamp 2023-05-11T16:31:35.327Z,1683822695.327 [DATMMP](INFO): Handled 2023-05-11T16:31:35.327Z,1683822695.327 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:35.578Z,1683822695.578 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:35.830Z,1683822695.830 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:36.082Z,1683822696.082 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:36.334Z,1683822696.334 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:36.586Z,1683822696.586 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:36.838Z,1683822696.838 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:37.090Z,1683822697.090 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:37.342Z,1683822697.342 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:37.518Z,1683822697.518 [NAL9602](INFO): Powering up NAL9602 2023-05-11T16:31:37.594Z,1683822697.594 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:37.846Z,1683822697.846 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:38.098Z,1683822698.098 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:38.350Z,1683822698.350 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:38.602Z,1683822698.602 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:38.854Z,1683822698.854 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:39.106Z,1683822699.106 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:39.358Z,1683822699.358 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:39.610Z,1683822699.610 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:39.862Z,1683822699.862 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:40.114Z,1683822700.114 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:40.374Z,1683822700.374 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:40.618Z,1683822700.618 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:40.870Z,1683822700.870 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:41.122Z,1683822701.122 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:41.374Z,1683822701.374 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:41.626Z,1683822701.626 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:41.878Z,1683822701.878 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:42.130Z,1683822702.130 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:42.382Z,1683822702.382 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:42.634Z,1683822702.634 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:42.886Z,1683822702.886 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:43.138Z,1683822703.138 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:43.390Z,1683822703.390 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:43.642Z,1683822703.642 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:43.894Z,1683822703.894 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:44.146Z,1683822704.146 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:44.398Z,1683822704.398 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:44.650Z,1683822704.650 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:44.902Z,1683822704.902 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:44.902Z,1683822704.902 [DATMMP](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER 2023-05-11T16:31:45.154Z,1683822705.154 [DATMMP](INFO): *** SENDING_FILL_BUFFER *** 2023-05-11T16:31:45.154Z,1683822705.154 [DATMMP](ERROR): Waiting for previous to be ack'd 2023-05-11T16:31:45.154Z,1683822705.154 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA 2023-05-11T16:31:45.406Z,1683822705.406 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2023-05-11T16:31:45.406Z,1683822705.406 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT 2023-05-11T16:31:45.658Z,1683822705.658 [DATMMP](INFO): *** SENDING_TRANSMIT *** 2023-05-11T16:31:45.658Z,1683822705.658 [DATMMP](INFO): Tx to 10 len 35 xid 46 2023-05-11T16:31:45.659Z,1683822705.659 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2023-05-11T16:31:45.662Z,1683822705.662 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-11T16:31:45.910Z,1683822705.910 [DATMMP](INFO): GSXN notify for xid: 46 2023-05-11T16:31:45.911Z,1683822705.911 [DATMMP](INFO): cmd_result rx: x1 x7 x0 x1 2023-05-11T16:31:45.911Z,1683822705.911 [DATMMP](DEBUG): Command complete send 2023-05-11T16:31:45.911Z,1683822705.911 [DATMMP](ERROR): 1:COMPLETE 2023-05-11T16:31:45.911Z,1683822705.911 [DATMMP](INFO): Received message type: cmd_result 2023-05-11T16:31:45.911Z,1683822705.911 [DATMMP](INFO): Handled 2023-05-11T16:31:45.912Z,1683822705.912 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:46.162Z,1683822706.162 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:31:46.163Z,1683822706.163 [DATMMP](INFO): timestamp rx: x0 x10 x1F x2C x77E 2023-05-11T16:31:46.163Z,1683822706.163 [DATMMP](INFO): Received message type: timestamp 2023-05-11T16:31:46.163Z,1683822706.163 [DATMMP](INFO): Handled 2023-05-11T16:31:46.163Z,1683822706.163 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:46.414Z,1683822706.414 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:46.666Z,1683822706.666 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:46.918Z,1683822706.918 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:47.170Z,1683822707.170 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:47.422Z,1683822707.422 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:47.674Z,1683822707.674 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:47.926Z,1683822707.926 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:48.178Z,1683822708.178 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:48.427Z,1683822708.427 [NAL9602](INFO): NAL9602 initialized 2023-05-11T16:31:48.682Z,1683822708.682 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:48.934Z,1683822708.934 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:49.186Z,1683822709.186 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:49.438Z,1683822709.438 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:49.690Z,1683822709.690 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:49.942Z,1683822709.942 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:50.194Z,1683822710.194 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:50.698Z,1683822710.698 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:50.950Z,1683822710.950 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:51.202Z,1683822711.202 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:51.454Z,1683822711.454 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:51.706Z,1683822711.706 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:51.960Z,1683822711.960 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:52.210Z,1683822712.210 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:52.714Z,1683822712.714 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:52.966Z,1683822712.966 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:53.218Z,1683822713.218 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:53.470Z,1683822713.470 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:53.734Z,1683822713.734 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:53.986Z,1683822713.986 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:54.238Z,1683822714.238 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:54.742Z,1683822714.742 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:54.994Z,1683822714.994 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:55.246Z,1683822715.246 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:55.498Z,1683822715.498 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:55.750Z,1683822715.750 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:55.750Z,1683822715.750 [DATMMP](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER 2023-05-11T16:31:56.002Z,1683822716.002 [DATMMP](INFO): *** SENDING_FILL_BUFFER *** 2023-05-11T16:31:56.002Z,1683822716.002 [DATMMP](ERROR): Waiting for previous to be ack'd 2023-05-11T16:31:56.002Z,1683822716.002 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA 2023-05-11T16:31:56.254Z,1683822716.254 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2023-05-11T16:31:56.254Z,1683822716.254 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT 2023-05-11T16:31:56.506Z,1683822716.506 [DATMMP](INFO): *** SENDING_TRANSMIT *** 2023-05-11T16:31:56.506Z,1683822716.506 [DATMMP](INFO): Tx to 10 len 35 xid 47 2023-05-11T16:31:56.507Z,1683822716.507 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2023-05-11T16:31:56.507Z,1683822716.507 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-11T16:31:56.758Z,1683822716.758 [DATMMP](INFO): GSXN notify for xid: 47 2023-05-11T16:31:56.759Z,1683822716.759 [DATMMP](INFO): cmd_result rx: x1 x7 x0 x1 2023-05-11T16:31:56.759Z,1683822716.759 [DATMMP](DEBUG): Command complete send 2023-05-11T16:31:56.759Z,1683822716.759 [DATMMP](ERROR): 1:COMPLETE 2023-05-11T16:31:56.759Z,1683822716.759 [DATMMP](INFO): Received message type: cmd_result 2023-05-11T16:31:56.760Z,1683822716.760 [DATMMP](INFO): Handled 2023-05-11T16:31:56.760Z,1683822716.760 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:57.010Z,1683822717.010 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:31:57.011Z,1683822717.011 [DATMMP](INFO): timestamp rx: x0 x10 x1F x37 x1A1 2023-05-11T16:31:57.011Z,1683822717.011 [DATMMP](INFO): Received message type: timestamp 2023-05-11T16:31:57.011Z,1683822717.011 [DATMMP](INFO): Handled 2023-05-11T16:31:57.012Z,1683822717.012 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:57.262Z,1683822717.262 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:57.514Z,1683822717.514 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:57.766Z,1683822717.766 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:58.018Z,1683822718.018 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:58.270Z,1683822718.270 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:58.522Z,1683822718.522 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:58.774Z,1683822718.774 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:59.026Z,1683822719.026 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:59.278Z,1683822719.278 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:59.530Z,1683822719.530 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:31:59.782Z,1683822719.782 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:00.034Z,1683822720.034 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:00.289Z,1683822720.289 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:00.542Z,1683822720.542 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:00.794Z,1683822720.794 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:01.046Z,1683822721.046 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:01.298Z,1683822721.298 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:01.550Z,1683822721.550 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:01.551Z,1683822721.551 [DATMMP](INFO): doppler rx: x0 2023-05-11T16:32:01.551Z,1683822721.551 [DATMMP](INFO): Received message type: doppler 2023-05-11T16:32:01.551Z,1683822721.551 [DATMMP](INFO): Handled 2023-05-11T16:32:01.551Z,1683822721.551 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:01.802Z,1683822721.802 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:01.803Z,1683822721.803 [DATMMP](INFO): timestamp rx: x1 x10 x1F x3B x1439 2023-05-11T16:32:01.803Z,1683822721.803 [DATMMP](INFO): Rx dataTimestamp_ set to:1683822769.583680 2023-05-11T16:32:01.804Z,1683822721.804 [DATMMP](INFO): Rx ping set to:1683822721.802944 2023-05-11T16:32:01.804Z,1683822721.804 [DATMMP](INFO): Received message type: timestamp 2023-05-11T16:32:01.804Z,1683822721.804 [DATMMP](INFO): Handled 2023-05-11T16:32:01.804Z,1683822721.804 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:02.054Z,1683822722.054 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:02.306Z,1683822722.306 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:02.307Z,1683822722.307 [DATMMP](INFO): remote_header rx: xF x4 x5 x4 x0 x5 xA x0 2023-05-11T16:32:02.308Z,1683822722.308 [DATMMP](INFO): DATA Src=10, Dst=5 2023-05-11T16:32:02.308Z,1683822722.308 [DATMMP](INFO): Received message type: remote_header 2023-05-11T16:32:02.308Z,1683822722.308 [DATMMP](INFO): Handled 2023-05-11T16:32:02.308Z,1683822722.308 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:02.558Z,1683822722.558 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:02.559Z,1683822722.559 [DATMMP](INFO): nav_stat rx: x0 x1 x1 2023-05-11T16:32:02.559Z,1683822722.559 [DATMMP](INFO): Received message type: nav_stat 2023-05-11T16:32:02.559Z,1683822722.559 [DATMMP](INFO): Handled 2023-05-11T16:32:02.559Z,1683822722.559 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:02.810Z,1683822722.810 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:02.811Z,1683822722.811 [DATMMP](INFO): directional_debug rx: x31 x25D x21 x123 x11F0 x12A1 x11F2 x1623 2023-05-11T16:32:02.812Z,1683822722.812 [DATMMP](INFO): Received message type: directional_debug 2023-05-11T16:32:02.812Z,1683822722.812 [DATMMP](INFO): Handled 2023-05-11T16:32:02.812Z,1683822722.812 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:03.062Z,1683822723.062 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:03.064Z,1683822723.064 [DATMMP](INFO): directional rx: x2C x22B x5AB xFDA3 x28F xFDA9 x0 xB10 x5 xFFF0 x27B5966 x-43569E0 2023-05-11T16:32:03.065Z,1683822723.065 [DATMMP](INFO): Received message type: directional 2023-05-11T16:32:03.065Z,1683822723.065 [DATMMP](INFO): Handled 2023-05-11T16:32:03.066Z,1683822723.066 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:03.314Z,1683822723.314 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:03.316Z,1683822723.316 [DATMMP](INFO): acstats rx: xA x5 x0 x2A0 x22 x87 xB6 xB6 xD xFFFF xB1 x23 xD x0 2023-05-11T16:32:03.316Z,1683822723.316 [DATMMP](INFO): Received message type: acstats 2023-05-11T16:32:03.316Z,1683822723.316 [DATMMP](INFO): Handled 2023-05-11T16:32:03.316Z,1683822723.316 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:03.566Z,1683822723.566 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:03.567Z,1683822723.567 [DATMMP](INFO): data_rx rx: xA x0 x0 x4 2023-05-11T16:32:03.567Z,1683822723.567 [DATMMP](INFO): Check CRC 2023-05-11T16:32:03.567Z,1683822723.567 [DATMMP](INFO): Ignoring message sent to address: 5 2023-05-11T16:32:03.567Z,1683822723.567 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:03.818Z,1683822723.818 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:03.819Z,1683822723.819 [DATMMP](INFO): doppler rx: x0 2023-05-11T16:32:03.819Z,1683822723.819 [DATMMP](INFO): Received message type: doppler 2023-05-11T16:32:03.819Z,1683822723.819 [DATMMP](INFO): Handled 2023-05-11T16:32:03.819Z,1683822723.819 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:04.070Z,1683822724.070 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:04.071Z,1683822724.071 [DATMMP](INFO): timestamp rx: x1 x10 x20 x0 x235A 2023-05-11T16:32:04.072Z,1683822724.072 [DATMMP](INFO): Rx dataTimestamp_ set to:1683822744.397312 2023-05-11T16:32:04.072Z,1683822724.072 [DATMMP](INFO): Rx ping set to:1683822724.071066 2023-05-11T16:32:04.072Z,1683822724.072 [DATMMP](INFO): Received message type: timestamp 2023-05-11T16:32:04.072Z,1683822724.072 [DATMMP](INFO): Handled 2023-05-11T16:32:04.072Z,1683822724.072 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:04.323Z,1683822724.323 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:04.324Z,1683822724.324 [DATMMP](INFO): remote_header rx: xF x4 x5 x4 x0 x5 xA x0 2023-05-11T16:32:04.324Z,1683822724.324 [DATMMP](INFO): DATA Src=10, Dst=5 2023-05-11T16:32:04.324Z,1683822724.324 [DATMMP](INFO): Received message type: remote_header 2023-05-11T16:32:04.324Z,1683822724.324 [DATMMP](INFO): Handled 2023-05-11T16:32:04.325Z,1683822724.325 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:04.574Z,1683822724.574 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:04.575Z,1683822724.575 [DATMMP](INFO): nav_stat rx: x0 x1 x1 2023-05-11T16:32:04.575Z,1683822724.575 [DATMMP](INFO): Received message type: nav_stat 2023-05-11T16:32:04.575Z,1683822724.575 [DATMMP](INFO): Handled 2023-05-11T16:32:04.575Z,1683822724.575 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:04.826Z,1683822724.826 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:04.827Z,1683822724.827 [DATMMP](INFO): directional_debug rx: xE01 x276 x22 x115 x1040 x1241 x11A2 x15B3 2023-05-11T16:32:04.828Z,1683822724.828 [DATMMP](INFO): Received message type: directional_debug 2023-05-11T16:32:04.828Z,1683822724.828 [DATMMP](INFO): Handled 2023-05-11T16:32:04.828Z,1683822724.828 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:05.078Z,1683822725.078 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:05.080Z,1683822725.080 [DATMMP](INFO): directional rx: xDFC x249 x5EB xFD8A x2C9 xFD8E x0 xB0E x5 xFFF0 x27B5966 x-43569E0 2023-05-11T16:32:05.081Z,1683822725.081 [DATMMP](INFO): Received message type: directional 2023-05-11T16:32:05.082Z,1683822725.082 [DATMMP](INFO): Handled 2023-05-11T16:32:05.082Z,1683822725.082 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:05.330Z,1683822725.330 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:05.332Z,1683822725.332 [DATMMP](INFO): acstats rx: xA x5 x0 x2D0 x22 x8C xC3 xBB xD xFFFF xAC x25 xD x0 2023-05-11T16:32:05.332Z,1683822725.332 [DATMMP](INFO): Received message type: acstats 2023-05-11T16:32:05.332Z,1683822725.332 [DATMMP](INFO): Handled 2023-05-11T16:32:05.332Z,1683822725.332 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:05.582Z,1683822725.582 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:05.583Z,1683822725.583 [DATMMP](INFO): data_rx rx: xA x0 x0 x4 2023-05-11T16:32:05.583Z,1683822725.583 [DATMMP](INFO): Check CRC 2023-05-11T16:32:05.583Z,1683822725.583 [DATMMP](INFO): Ignoring message sent to address: 5 2023-05-11T16:32:05.583Z,1683822725.583 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:05.834Z,1683822725.834 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:05.835Z,1683822725.835 [DATMMP](INFO): doppler rx: x0 2023-05-11T16:32:05.835Z,1683822725.835 [DATMMP](INFO): Received message type: doppler 2023-05-11T16:32:05.835Z,1683822725.835 [DATMMP](INFO): Handled 2023-05-11T16:32:05.835Z,1683822725.835 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:06.086Z,1683822726.086 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:06.087Z,1683822726.087 [DATMMP](INFO): timestamp rx: x1 x10 x20 x2 xB6D 2023-05-11T16:32:06.088Z,1683822726.088 [DATMMP](INFO): Rx dataTimestamp_ set to:1683822750.591872 2023-05-11T16:32:06.088Z,1683822726.088 [DATMMP](INFO): Rx ping set to:1683822726.086995 2023-05-11T16:32:06.088Z,1683822726.088 [DATMMP](INFO): Received message type: timestamp 2023-05-11T16:32:06.088Z,1683822726.088 [DATMMP](INFO): Handled 2023-05-11T16:32:06.088Z,1683822726.088 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:06.338Z,1683822726.338 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:06.339Z,1683822726.339 [DATMMP](INFO): remote_header rx: xF x4 x5 x4 x0 x5 xA x0 2023-05-11T16:32:06.339Z,1683822726.339 [DATMMP](INFO): DATA Src=10, Dst=5 2023-05-11T16:32:06.340Z,1683822726.340 [DATMMP](INFO): Received message type: remote_header 2023-05-11T16:32:06.340Z,1683822726.340 [DATMMP](INFO): Handled 2023-05-11T16:32:06.340Z,1683822726.340 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:06.590Z,1683822726.590 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:06.591Z,1683822726.591 [DATMMP](INFO): nav_stat rx: x0 x1 x1 2023-05-11T16:32:06.591Z,1683822726.591 [DATMMP](INFO): Received message type: nav_stat 2023-05-11T16:32:06.591Z,1683822726.591 [DATMMP](INFO): Handled 2023-05-11T16:32:06.591Z,1683822726.591 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:06.591Z,1683822726.591 [DATMMP](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER 2023-05-11T16:32:06.842Z,1683822726.842 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:06.843Z,1683822726.843 [DATMMP](INFO): directional_debug rx: xDFF x26E x22 x11E x1070 x1271 x1192 x15E3 2023-05-11T16:32:06.844Z,1683822726.844 [DATMMP](INFO): Received message type: directional_debug 2023-05-11T16:32:06.844Z,1683822726.844 [DATMMP](INFO): Handled 2023-05-11T16:32:06.845Z,1683822726.845 [DATMMP](INFO): *** SENDING_FILL_BUFFER *** 2023-05-11T16:32:06.845Z,1683822726.845 [DATMMP](ERROR): Waiting for previous to be ack'd 2023-05-11T16:32:06.845Z,1683822726.845 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA 2023-05-11T16:32:07.097Z,1683822727.097 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:07.100Z,1683822727.100 [DATMMP](INFO): directional rx: xDFA x240 x5ED xFD92 x2CA xFD96 x0 xB0B x4 xFFF0 x27B5966 x-43569E0 2023-05-11T16:32:07.102Z,1683822727.102 [DATMMP](INFO): Received message type: directional 2023-05-11T16:32:07.102Z,1683822727.102 [DATMMP](INFO): Handled 2023-05-11T16:32:07.102Z,1683822727.102 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2023-05-11T16:32:07.102Z,1683822727.102 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT 2023-05-11T16:32:07.346Z,1683822727.346 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:07.348Z,1683822727.348 [DATMMP](INFO): acstats rx: xA x5 x0 x2D0 x22 x8D xB9 xB3 xD xFFFF xA9 x23 xD x0 2023-05-11T16:32:07.348Z,1683822727.348 [DATMMP](INFO): Received message type: acstats 2023-05-11T16:32:07.348Z,1683822727.348 [DATMMP](INFO): Handled 2023-05-11T16:32:07.348Z,1683822727.348 [DATMMP](INFO): *** SENDING_TRANSMIT *** 2023-05-11T16:32:07.349Z,1683822727.349 [DATMMP](INFO): Tx to 10 len 35 xid 48 2023-05-11T16:32:07.349Z,1683822727.349 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2023-05-11T16:32:07.349Z,1683822727.349 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-11T16:32:07.598Z,1683822727.598 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:07.599Z,1683822727.599 [DATMMP](INFO): data_rx rx: xA x0 x0 x4 2023-05-11T16:32:07.599Z,1683822727.599 [DATMMP](INFO): Check CRC 2023-05-11T16:32:07.599Z,1683822727.599 [DATMMP](INFO): Ignoring message sent to address: 5 2023-05-11T16:32:07.599Z,1683822727.599 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-11T16:32:07.850Z,1683822727.850 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:07.851Z,1683822727.851 [DATMMP](INFO): doppler rx: x0 2023-05-11T16:32:07.851Z,1683822727.851 [DATMMP](INFO): Received message type: doppler 2023-05-11T16:32:07.851Z,1683822727.851 [DATMMP](INFO): Handled 2023-05-11T16:32:08.102Z,1683822728.102 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:08.103Z,1683822728.103 [DATMMP](INFO): timestamp rx: x1 x10 x20 x3 x1A90 2023-05-11T16:32:08.103Z,1683822728.103 [DATMMP](INFO): Rx dataTimestamp_ set to:1683822725.425984 2023-05-11T16:32:08.104Z,1683822728.104 [DATMMP](INFO): Rx ping set to:1683822728.102924 2023-05-11T16:32:08.104Z,1683822728.104 [DATMMP](INFO): Received message type: timestamp 2023-05-11T16:32:08.104Z,1683822728.104 [DATMMP](INFO): Handled 2023-05-11T16:32:08.104Z,1683822728.104 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-11T16:32:08.354Z,1683822728.354 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:08.355Z,1683822728.355 [DATMMP](INFO): remote_header rx: xF x4 x5 x4 x0 x5 xA x0 2023-05-11T16:32:08.356Z,1683822728.356 [DATMMP](INFO): DATA Src=10, Dst=5 2023-05-11T16:32:08.356Z,1683822728.356 [DATMMP](INFO): Received message type: remote_header 2023-05-11T16:32:08.356Z,1683822728.356 [DATMMP](INFO): Handled 2023-05-11T16:32:08.356Z,1683822728.356 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-11T16:32:08.606Z,1683822728.606 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:08.607Z,1683822728.607 [DATMMP](INFO): nav_stat rx: x0 x1 x1 2023-05-11T16:32:08.607Z,1683822728.607 [DATMMP](INFO): Received message type: nav_stat 2023-05-11T16:32:08.607Z,1683822728.607 [DATMMP](INFO): Handled 2023-05-11T16:32:08.607Z,1683822728.607 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-11T16:32:08.858Z,1683822728.858 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:08.859Z,1683822728.859 [DATMMP](INFO): directional_debug rx: xDFD x278 x22 x120 x1040 x1251 x11C2 x15D3 2023-05-11T16:32:08.860Z,1683822728.860 [DATMMP](INFO): Received message type: directional_debug 2023-05-11T16:32:08.860Z,1683822728.860 [DATMMP](INFO): Handled 2023-05-11T16:32:08.860Z,1683822728.860 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-11T16:32:09.110Z,1683822729.110 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:09.112Z,1683822729.112 [DATMMP](INFO): directional rx: xDF8 x24B x5EF xFD88 x2CA xFD8D x0 xB09 x4 xFFF1 x27B5966 x-43569E0 2023-05-11T16:32:09.113Z,1683822729.113 [DATMMP](INFO): Received message type: directional 2023-05-11T16:32:09.113Z,1683822729.113 [DATMMP](INFO): Handled 2023-05-11T16:32:09.113Z,1683822729.113 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-11T16:32:09.362Z,1683822729.362 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:09.364Z,1683822729.364 [DATMMP](INFO): acstats rx: xA x5 x0 x2D0 x22 x8D xBA xC2 xD xFFFF xA9 x25 xD x0 2023-05-11T16:32:09.364Z,1683822729.364 [DATMMP](INFO): Received message type: acstats 2023-05-11T16:32:09.364Z,1683822729.364 [DATMMP](INFO): Handled 2023-05-11T16:32:09.364Z,1683822729.364 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-11T16:32:09.614Z,1683822729.614 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:09.615Z,1683822729.615 [DATMMP](INFO): data_rx rx: xA x0 x0 x4 2023-05-11T16:32:09.615Z,1683822729.615 [DATMMP](INFO): Check CRC 2023-05-11T16:32:09.616Z,1683822729.616 [DATMMP](INFO): Ignoring message sent to address: 5 2023-05-11T16:32:09.616Z,1683822729.616 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-11T16:32:09.866Z,1683822729.866 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:09.867Z,1683822729.867 [DATMMP](INFO): doppler rx: x0 2023-05-11T16:32:09.867Z,1683822729.867 [DATMMP](INFO): Received message type: doppler 2023-05-11T16:32:09.867Z,1683822729.867 [DATMMP](INFO): Handled 2023-05-11T16:32:09.867Z,1683822729.867 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-11T16:32:10.118Z,1683822730.118 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:10.119Z,1683822730.119 [DATMMP](INFO): timestamp rx: x1 x10 x20 x5 x2A3 2023-05-11T16:32:10.120Z,1683822730.120 [DATMMP](INFO): Rx dataTimestamp_ set to:1683822731.620544 2023-05-11T16:32:10.120Z,1683822730.120 [DATMMP](INFO): Rx ping set to:1683822730.119117 2023-05-11T16:32:10.120Z,1683822730.120 [DATMMP](INFO): Received message type: timestamp 2023-05-11T16:32:10.120Z,1683822730.120 [DATMMP](INFO): Handled 2023-05-11T16:32:10.120Z,1683822730.120 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-11T16:32:10.370Z,1683822730.370 [DATMMP](INFO): GSXN notify for xid: 48 2023-05-11T16:32:10.371Z,1683822730.371 [DATMMP](INFO): cmd_result rx: x1 x7 x0 x1 2023-05-11T16:32:10.371Z,1683822730.371 [DATMMP](DEBUG): Command complete send 2023-05-11T16:32:10.371Z,1683822730.371 [DATMMP](ERROR): 1:COMPLETE 2023-05-11T16:32:10.371Z,1683822730.371 [DATMMP](INFO): Received message type: cmd_result 2023-05-11T16:32:10.371Z,1683822730.372 [DATMMP](INFO): Handled 2023-05-11T16:32:10.372Z,1683822730.372 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:10.622Z,1683822730.622 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:10.623Z,1683822730.623 [DATMMP](INFO): remote_header rx: xF x4 x5 x4 x0 x5 xA x0 2023-05-11T16:32:10.623Z,1683822730.623 [DATMMP](INFO): DATA Src=10, Dst=5 2023-05-11T16:32:10.624Z,1683822730.624 [DATMMP](INFO): Received message type: remote_header 2023-05-11T16:32:10.624Z,1683822730.624 [DATMMP](INFO): Handled 2023-05-11T16:32:10.624Z,1683822730.624 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:10.875Z,1683822730.875 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:10.875Z,1683822730.875 [DATMMP](INFO): nav_stat rx: x0 x1 x1 2023-05-11T16:32:10.876Z,1683822730.876 [DATMMP](INFO): Received message type: nav_stat 2023-05-11T16:32:10.876Z,1683822730.876 [DATMMP](INFO): Handled 2023-05-11T16:32:10.876Z,1683822730.876 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:11.126Z,1683822731.126 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:11.127Z,1683822731.127 [DATMMP](INFO): directional_debug rx: x3 x270 x22 x122 x1010 x1211 x11B2 x1593 2023-05-11T16:32:11.128Z,1683822731.128 [DATMMP](INFO): Received message type: directional_debug 2023-05-11T16:32:11.128Z,1683822731.128 [DATMMP](INFO): Handled 2023-05-11T16:32:11.128Z,1683822731.128 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:11.378Z,1683822731.378 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:11.380Z,1683822731.380 [DATMMP](INFO): directional rx: xE0E x242 x5D9 xFD90 x2B4 xFD94 x0 xB07 x4 xFFF1 x27B5966 x-43569E0 2023-05-11T16:32:11.381Z,1683822731.381 [DATMMP](INFO): Received message type: directional 2023-05-11T16:32:11.381Z,1683822731.381 [DATMMP](INFO): Handled 2023-05-11T16:32:11.381Z,1683822731.381 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:11.630Z,1683822731.630 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:11.632Z,1683822731.632 [DATMMP](INFO): acstats rx: xA x5 x0 x2D0 x22 x8D xC9 xBC xD xFFFF xAD x21 xD x0 2023-05-11T16:32:11.632Z,1683822731.632 [DATMMP](INFO): Received message type: acstats 2023-05-11T16:32:11.632Z,1683822731.632 [DATMMP](INFO): Handled 2023-05-11T16:32:11.632Z,1683822731.632 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:11.882Z,1683822731.882 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:11.883Z,1683822731.883 [DATMMP](INFO): timestamp rx: x0 x10 x20 x6 x971 2023-05-11T16:32:11.883Z,1683822731.883 [DATMMP](INFO): Received message type: timestamp 2023-05-11T16:32:11.883Z,1683822731.883 [DATMMP](INFO): Handled 2023-05-11T16:32:11.883Z,1683822731.883 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:12.134Z,1683822732.134 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:12.135Z,1683822732.135 [DATMMP](INFO): data_rx rx: xA x0 x0 x4 2023-05-11T16:32:12.135Z,1683822732.135 [DATMMP](INFO): Check CRC 2023-05-11T16:32:12.135Z,1683822732.135 [DATMMP](INFO): Ignoring message sent to address: 5 2023-05-11T16:32:12.135Z,1683822732.135 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:12.386Z,1683822732.386 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:12.638Z,1683822732.638 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:12.890Z,1683822732.890 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:13.142Z,1683822733.142 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:13.394Z,1683822733.394 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:13.646Z,1683822733.646 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:14.150Z,1683822734.150 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:14.402Z,1683822734.402 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:14.654Z,1683822734.654 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:14.906Z,1683822734.906 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:15.158Z,1683822735.158 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:15.410Z,1683822735.410 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:15.662Z,1683822735.662 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:15.914Z,1683822735.914 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:16.166Z,1683822736.166 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:16.418Z,1683822736.418 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:16.670Z,1683822736.670 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:16.922Z,1683822736.922 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:17.174Z,1683822737.174 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:17.426Z,1683822737.426 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:17.426Z,1683822737.426 [DATMMP](DEBUG): In sendingAckWaiting, hit max timeouts so set commsState_ = SENDING_VERIFIED 2023-05-11T16:32:17.426Z,1683822737.426 [DATMMP](ERROR): Ack receipt timeout failure. 2023-05-11T16:32:17.678Z,1683822737.678 [DATMMP](INFO): *** SENDING_VERIFIED *** 2023-05-11T16:32:17.678Z,1683822737.678 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER 2023-05-11T16:32:18.182Z,1683822738.182 [DATMMP](INFO): *** SENDING_FILL_BUFFER *** 2023-05-11T16:32:18.182Z,1683822738.182 [DATMMP](ERROR): Waiting for previous to be ack'd 2023-05-11T16:32:18.182Z,1683822738.182 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA 2023-05-11T16:32:18.434Z,1683822738.434 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2023-05-11T16:32:18.434Z,1683822738.434 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT 2023-05-11T16:32:18.686Z,1683822738.686 [DATMMP](INFO): *** SENDING_TRANSMIT *** 2023-05-11T16:32:18.686Z,1683822738.686 [DATMMP](INFO): Tx to 10 len 32 xid 49 2023-05-11T16:32:18.687Z,1683822738.687 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2023-05-11T16:32:18.687Z,1683822738.687 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-11T16:32:18.938Z,1683822738.938 [DATMMP](INFO): GSXN notify for xid: 49 2023-05-11T16:32:18.939Z,1683822738.939 [DATMMP](INFO): cmd_result rx: x1 x7 x0 x1 2023-05-11T16:32:18.939Z,1683822738.939 [DATMMP](DEBUG): Command complete send 2023-05-11T16:32:18.939Z,1683822738.939 [DATMMP](ERROR): 1:COMPLETE 2023-05-11T16:32:18.939Z,1683822738.939 [DATMMP](INFO): Received message type: cmd_result 2023-05-11T16:32:18.939Z,1683822738.939 [DATMMP](INFO): Handled 2023-05-11T16:32:18.940Z,1683822738.940 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:19.190Z,1683822739.190 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:19.191Z,1683822739.191 [DATMMP](INFO): timestamp rx: x0 x10 x20 x11 x970 2023-05-11T16:32:19.191Z,1683822739.191 [DATMMP](INFO): Received message type: timestamp 2023-05-11T16:32:19.191Z,1683822739.191 [DATMMP](INFO): Handled 2023-05-11T16:32:19.191Z,1683822739.191 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:19.442Z,1683822739.442 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:19.694Z,1683822739.694 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:19.946Z,1683822739.946 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:20.198Z,1683822740.198 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:20.450Z,1683822740.450 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:20.702Z,1683822740.702 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:20.954Z,1683822740.954 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:21.206Z,1683822741.206 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:21.458Z,1683822741.458 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:21.710Z,1683822741.710 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:22.214Z,1683822742.214 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:22.466Z,1683822742.466 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:22.718Z,1683822742.718 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:22.970Z,1683822742.970 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:23.222Z,1683822743.222 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:23.223Z,1683822743.223 [DATMMP](INFO): doppler rx: x0 2023-05-11T16:32:23.223Z,1683822743.223 [DATMMP](INFO): Received message type: doppler 2023-05-11T16:32:23.223Z,1683822743.223 [DATMMP](INFO): Handled 2023-05-11T16:32:23.223Z,1683822743.223 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:23.474Z,1683822743.474 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:23.475Z,1683822743.475 [DATMMP](INFO): timestamp rx: x1 x10 x20 x15 x69A 2023-05-11T16:32:23.475Z,1683822743.475 [DATMMP](INFO): Rx dataTimestamp_ set to:1683822757.528384 2023-05-11T16:32:23.476Z,1683822743.476 [DATMMP](INFO): Rx ping set to:1683822743.474966 2023-05-11T16:32:23.476Z,1683822743.476 [DATMMP](INFO): Received message type: timestamp 2023-05-11T16:32:23.476Z,1683822743.476 [DATMMP](INFO): Handled 2023-05-11T16:32:23.476Z,1683822743.476 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:23.726Z,1683822743.726 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:23.978Z,1683822743.978 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:23.979Z,1683822743.979 [DATMMP](INFO): remote_header rx: xF x4 x5 x4 x0 x5 xA x0 2023-05-11T16:32:23.980Z,1683822743.980 [DATMMP](INFO): DATA Src=10, Dst=5 2023-05-11T16:32:23.980Z,1683822743.980 [DATMMP](INFO): Received message type: remote_header 2023-05-11T16:32:23.980Z,1683822743.980 [DATMMP](INFO): Handled 2023-05-11T16:32:24.231Z,1683822744.231 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:24.231Z,1683822744.231 [DATMMP](INFO): nav_stat rx: x0 x1 x1 2023-05-11T16:32:24.231Z,1683822744.231 [DATMMP](INFO): Received message type: nav_stat 2023-05-11T16:32:24.232Z,1683822744.232 [DATMMP](INFO): Handled 2023-05-11T16:32:24.232Z,1683822744.232 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:24.482Z,1683822744.482 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:24.483Z,1683822744.483 [DATMMP](INFO): directional_debug rx: x19 x208 x23 xAD x1020 x1221 x11D2 x15F3 2023-05-11T16:32:24.484Z,1683822744.484 [DATMMP](INFO): Received message type: directional_debug 2023-05-11T16:32:24.484Z,1683822744.484 [DATMMP](INFO): Handled 2023-05-11T16:32:24.484Z,1683822744.484 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:24.734Z,1683822744.734 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:24.736Z,1683822744.736 [DATMMP](INFO): directional rx: x12 x1C9 x5C3 xFDF8 x27B xFDFE x0 xADD x4 xFFEF x27B5966 x-43569E0 2023-05-11T16:32:24.737Z,1683822744.737 [DATMMP](INFO): Received message type: directional 2023-05-11T16:32:24.737Z,1683822744.737 [DATMMP](INFO): Handled 2023-05-11T16:32:24.737Z,1683822744.737 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:24.986Z,1683822744.986 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:24.988Z,1683822744.988 [DATMMP](INFO): acstats rx: xA x5 x0 x2CE x22 x8E xCF xC4 xD xFFFF xAD x23 xD x0 2023-05-11T16:32:24.988Z,1683822744.988 [DATMMP](INFO): Received message type: acstats 2023-05-11T16:32:24.988Z,1683822744.988 [DATMMP](INFO): Handled 2023-05-11T16:32:24.988Z,1683822744.988 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:25.238Z,1683822745.238 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:25.239Z,1683822745.239 [DATMMP](INFO): data_rx rx: xA x0 x0 x4 2023-05-11T16:32:25.239Z,1683822745.239 [DATMMP](INFO): Check CRC 2023-05-11T16:32:25.239Z,1683822745.239 [DATMMP](INFO): Ignoring message sent to address: 5 2023-05-11T16:32:25.239Z,1683822745.239 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:25.490Z,1683822745.490 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:25.742Z,1683822745.742 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:25.994Z,1683822745.994 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:26.246Z,1683822746.246 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:26.498Z,1683822746.498 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:26.750Z,1683822746.750 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:27.002Z,1683822747.002 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:27.254Z,1683822747.254 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:27.506Z,1683822747.506 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:27.758Z,1683822747.758 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:28.010Z,1683822748.010 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:28.262Z,1683822748.262 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:28.514Z,1683822748.514 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:28.766Z,1683822748.766 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:28.766Z,1683822748.766 [DATMMP](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER 2023-05-11T16:32:29.018Z,1683822749.018 [DATMMP](INFO): *** SENDING_FILL_BUFFER *** 2023-05-11T16:32:29.018Z,1683822749.018 [DATMMP](ERROR): Waiting for previous to be ack'd 2023-05-11T16:32:29.018Z,1683822749.018 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA 2023-05-11T16:32:29.270Z,1683822749.270 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2023-05-11T16:32:29.270Z,1683822749.270 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT 2023-05-11T16:32:29.522Z,1683822749.522 [DATMMP](INFO): *** SENDING_TRANSMIT *** 2023-05-11T16:32:29.522Z,1683822749.522 [DATMMP](INFO): Tx to 10 len 32 xid 50 2023-05-11T16:32:29.523Z,1683822749.523 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2023-05-11T16:32:29.526Z,1683822749.526 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-11T16:32:29.774Z,1683822749.774 [DATMMP](INFO): GSXN notify for xid: 50 2023-05-11T16:32:29.775Z,1683822749.775 [DATMMP](INFO): cmd_result rx: x1 x7 x0 x1 2023-05-11T16:32:29.775Z,1683822749.775 [DATMMP](DEBUG): Command complete send 2023-05-11T16:32:29.775Z,1683822749.775 [DATMMP](ERROR): 1:COMPLETE 2023-05-11T16:32:29.775Z,1683822749.775 [DATMMP](INFO): Received message type: cmd_result 2023-05-11T16:32:29.775Z,1683822749.775 [DATMMP](INFO): Handled 2023-05-11T16:32:29.776Z,1683822749.776 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:30.026Z,1683822750.026 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:30.027Z,1683822750.027 [DATMMP](INFO): timestamp rx: x0 x10 x20 x1C x1AD 2023-05-11T16:32:30.027Z,1683822750.027 [DATMMP](INFO): Received message type: timestamp 2023-05-11T16:32:30.027Z,1683822750.027 [DATMMP](INFO): Handled 2023-05-11T16:32:30.027Z,1683822750.027 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:30.278Z,1683822750.278 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:30.530Z,1683822750.530 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:30.782Z,1683822750.782 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:31.034Z,1683822751.034 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:31.286Z,1683822751.286 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:31.538Z,1683822751.538 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:31.790Z,1683822751.790 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:32.052Z,1683822752.052 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:32.302Z,1683822752.302 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:32.554Z,1683822752.554 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:32.806Z,1683822752.806 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:33.058Z,1683822753.058 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:33.310Z,1683822753.310 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:33.562Z,1683822753.562 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:33.814Z,1683822753.814 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:34.066Z,1683822754.066 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:34.318Z,1683822754.318 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:34.319Z,1683822754.319 [DATMMP](INFO): doppler rx: x0 2023-05-11T16:32:34.319Z,1683822754.319 [DATMMP](INFO): Received message type: doppler 2023-05-11T16:32:34.319Z,1683822754.319 [DATMMP](INFO): Handled 2023-05-11T16:32:34.319Z,1683822754.319 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:34.570Z,1683822754.570 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:34.571Z,1683822754.571 [DATMMP](INFO): timestamp rx: x1 x10 x20 x20 xA73 2023-05-11T16:32:34.572Z,1683822754.572 [DATMMP](INFO): Rx dataTimestamp_ set to:1683822778.129024 2023-05-11T16:32:34.572Z,1683822754.572 [DATMMP](INFO): Rx ping set to:1683822754.571011 2023-05-11T16:32:34.572Z,1683822754.572 [DATMMP](INFO): Received message type: timestamp 2023-05-11T16:32:34.572Z,1683822754.572 [DATMMP](INFO): Handled 2023-05-11T16:32:34.572Z,1683822754.572 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:34.822Z,1683822754.822 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:35.075Z,1683822755.075 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:35.076Z,1683822755.076 [DATMMP](INFO): remote_header rx: xF x4 x5 x4 x0 x5 xA x0 2023-05-11T16:32:35.076Z,1683822755.076 [DATMMP](INFO): DATA Src=10, Dst=5 2023-05-11T16:32:35.076Z,1683822755.076 [DATMMP](INFO): Received message type: remote_header 2023-05-11T16:32:35.076Z,1683822755.076 [DATMMP](INFO): Handled 2023-05-11T16:32:35.076Z,1683822755.076 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:35.326Z,1683822755.326 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:35.327Z,1683822755.327 [DATMMP](INFO): nav_stat rx: x0 x1 x1 2023-05-11T16:32:35.327Z,1683822755.327 [DATMMP](INFO): Received message type: nav_stat 2023-05-11T16:32:35.327Z,1683822755.327 [DATMMP](INFO): Handled 2023-05-11T16:32:35.327Z,1683822755.327 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:35.578Z,1683822755.578 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:35.579Z,1683822755.579 [DATMMP](INFO): directional_debug rx: xDDD x289 x23 xF7 x1200 x1481 x13C2 x17C3 2023-05-11T16:32:35.580Z,1683822755.580 [DATMMP](INFO): Received message type: directional_debug 2023-05-11T16:32:35.580Z,1683822755.580 [DATMMP](INFO): Handled 2023-05-11T16:32:35.580Z,1683822755.580 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:35.830Z,1683822755.830 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:35.831Z,1683822755.831 [DATMMP](INFO): directional rx: xDD8 x260 x60F xFD77 x293 xFD7C x0 xAB6 x3 xFFEF x27B5966 x-43569E0 2023-05-11T16:32:35.833Z,1683822755.833 [DATMMP](INFO): Received message type: directional 2023-05-11T16:32:35.833Z,1683822755.833 [DATMMP](INFO): Handled 2023-05-11T16:32:35.833Z,1683822755.833 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:36.083Z,1683822756.083 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:36.084Z,1683822756.084 [DATMMP](INFO): acstats rx: xA x5 x0 x2AC x22 x88 xC4 xBD xD xFFFF xA9 x25 xD x0 2023-05-11T16:32:36.085Z,1683822756.085 [DATMMP](INFO): Received message type: acstats 2023-05-11T16:32:36.085Z,1683822756.085 [DATMMP](INFO): Handled 2023-05-11T16:32:36.085Z,1683822756.085 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:36.334Z,1683822756.334 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:36.335Z,1683822756.335 [DATMMP](INFO): data_rx rx: xA x0 x0 x4 2023-05-11T16:32:36.335Z,1683822756.335 [DATMMP](INFO): Check CRC 2023-05-11T16:32:36.335Z,1683822756.335 [DATMMP](INFO): Ignoring message sent to address: 5 2023-05-11T16:32:36.335Z,1683822756.335 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:36.586Z,1683822756.586 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:36.838Z,1683822756.838 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:37.090Z,1683822757.090 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:37.342Z,1683822757.342 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:37.594Z,1683822757.594 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:37.846Z,1683822757.846 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:38.098Z,1683822758.098 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:38.350Z,1683822758.350 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:38.602Z,1683822758.602 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:38.854Z,1683822758.854 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:39.106Z,1683822759.106 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:39.610Z,1683822759.610 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:39.610Z,1683822759.610 [DATMMP](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER 2023-05-11T16:32:39.862Z,1683822759.862 [DATMMP](INFO): *** SENDING_FILL_BUFFER *** 2023-05-11T16:32:39.862Z,1683822759.862 [DATMMP](ERROR): Waiting for previous to be ack'd 2023-05-11T16:32:39.862Z,1683822759.862 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA 2023-05-11T16:32:40.114Z,1683822760.114 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2023-05-11T16:32:40.114Z,1683822760.114 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT 2023-05-11T16:32:40.366Z,1683822760.366 [DATMMP](INFO): *** SENDING_TRANSMIT *** 2023-05-11T16:32:40.366Z,1683822760.366 [DATMMP](INFO): Tx to 10 len 32 xid 51 2023-05-11T16:32:40.367Z,1683822760.367 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2023-05-11T16:32:40.374Z,1683822760.374 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-11T16:32:40.618Z,1683822760.618 [DATMMP](INFO): GSXN notify for xid: 51 2023-05-11T16:32:40.619Z,1683822760.619 [DATMMP](INFO): cmd_result rx: x1 x7 x0 x1 2023-05-11T16:32:40.619Z,1683822760.619 [DATMMP](DEBUG): Command complete send 2023-05-11T16:32:40.619Z,1683822760.619 [DATMMP](ERROR): 1:COMPLETE 2023-05-11T16:32:40.619Z,1683822760.619 [DATMMP](INFO): Received message type: cmd_result 2023-05-11T16:32:40.620Z,1683822760.620 [DATMMP](INFO): Handled 2023-05-11T16:32:40.620Z,1683822760.620 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:40.870Z,1683822760.870 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:40.871Z,1683822760.871 [DATMMP](INFO): timestamp rx: x0 x10 x20 x26 x22D3 2023-05-11T16:32:40.871Z,1683822760.871 [DATMMP](INFO): Received message type: timestamp 2023-05-11T16:32:40.871Z,1683822760.871 [DATMMP](INFO): Handled 2023-05-11T16:32:40.871Z,1683822760.871 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:41.122Z,1683822761.122 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:41.374Z,1683822761.374 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:41.626Z,1683822761.626 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:41.878Z,1683822761.878 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:42.130Z,1683822762.130 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:42.382Z,1683822762.382 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:42.634Z,1683822762.634 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:42.886Z,1683822762.886 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:43.138Z,1683822763.138 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:43.390Z,1683822763.390 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:43.642Z,1683822763.642 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:43.894Z,1683822763.894 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:44.148Z,1683822764.148 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:44.398Z,1683822764.398 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:44.650Z,1683822764.650 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:44.902Z,1683822764.902 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:44.903Z,1683822764.903 [DATMMP](INFO): doppler rx: xFFFF 2023-05-11T16:32:44.903Z,1683822764.903 [DATMMP](INFO): Received message type: doppler 2023-05-11T16:32:44.903Z,1683822764.903 [DATMMP](INFO): Handled 2023-05-11T16:32:44.903Z,1683822764.903 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:45.154Z,1683822765.154 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:45.155Z,1683822765.155 [DATMMP](INFO): timestamp rx: x1 x10 x20 x2A x21F1 2023-05-11T16:32:45.156Z,1683822765.156 [DATMMP](INFO): Rx dataTimestamp_ set to:1683822782.894976 2023-05-11T16:32:45.156Z,1683822765.156 [DATMMP](INFO): Rx ping set to:1683822765.155143 2023-05-11T16:32:45.156Z,1683822765.156 [DATMMP](INFO): Received message type: timestamp 2023-05-11T16:32:45.156Z,1683822765.156 [DATMMP](INFO): Handled 2023-05-11T16:32:45.156Z,1683822765.156 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:45.410Z,1683822765.410 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:45.662Z,1683822765.662 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:45.663Z,1683822765.663 [DATMMP](INFO): remote_header rx: xF x4 x5 x4 x0 x5 xA x0 2023-05-11T16:32:45.663Z,1683822765.663 [DATMMP](INFO): DATA Src=10, Dst=5 2023-05-11T16:32:45.664Z,1683822765.664 [DATMMP](INFO): Received message type: remote_header 2023-05-11T16:32:45.664Z,1683822765.664 [DATMMP](INFO): Handled 2023-05-11T16:32:45.664Z,1683822765.664 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:45.914Z,1683822765.914 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:45.915Z,1683822765.915 [DATMMP](INFO): nav_stat rx: x0 x1 x1 2023-05-11T16:32:45.915Z,1683822765.915 [DATMMP](INFO): Received message type: nav_stat 2023-05-11T16:32:45.915Z,1683822765.915 [DATMMP](INFO): Handled 2023-05-11T16:32:45.915Z,1683822765.915 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:46.166Z,1683822766.166 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:46.167Z,1683822766.167 [DATMMP](INFO): directional_debug rx: xCD8 x2C4 x23 xE8 x11F0 x1451 x1372 x1863 2023-05-11T16:32:46.168Z,1683822766.168 [DATMMP](INFO): Received message type: directional_debug 2023-05-11T16:32:46.168Z,1683822766.168 [DATMMP](INFO): Handled 2023-05-11T16:32:46.168Z,1683822766.168 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:46.418Z,1683822766.418 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:46.420Z,1683822766.420 [DATMMP](INFO): directional rx: xCD0 x2A8 x714 xFD3C x373 xFD38 x0 xAA0 x4 xFFEF x27B5966 x-43569E0 2023-05-11T16:32:46.421Z,1683822766.421 [DATMMP](INFO): Received message type: directional 2023-05-11T16:32:46.422Z,1683822766.422 [DATMMP](INFO): Handled 2023-05-11T16:32:46.422Z,1683822766.422 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:46.670Z,1683822766.670 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:46.672Z,1683822766.672 [DATMMP](INFO): acstats rx: xA x5 xFFFF x2D0 x23 x86 xC5 xCB xD xFFFF xAD x27 xD x0 2023-05-11T16:32:46.672Z,1683822766.672 [DATMMP](INFO): Received message type: acstats 2023-05-11T16:32:46.672Z,1683822766.672 [DATMMP](INFO): Handled 2023-05-11T16:32:46.672Z,1683822766.672 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:46.922Z,1683822766.922 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:46.923Z,1683822766.923 [DATMMP](INFO): data_rx rx: xA x0 x0 x4 2023-05-11T16:32:46.923Z,1683822766.923 [DATMMP](INFO): Check CRC 2023-05-11T16:32:46.923Z,1683822766.923 [DATMMP](INFO): Ignoring message sent to address: 5 2023-05-11T16:32:46.923Z,1683822766.923 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:47.174Z,1683822767.174 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:47.426Z,1683822767.426 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:47.678Z,1683822767.678 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:47.930Z,1683822767.930 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:48.182Z,1683822768.182 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:48.434Z,1683822768.434 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:48.686Z,1683822768.686 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:48.938Z,1683822768.938 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:49.190Z,1683822769.190 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:49.694Z,1683822769.694 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:49.946Z,1683822769.946 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:50.198Z,1683822770.198 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:50.450Z,1683822770.450 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:50.450Z,1683822770.450 [DATMMP](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER 2023-05-11T16:32:50.702Z,1683822770.702 [DATMMP](INFO): *** SENDING_FILL_BUFFER *** 2023-05-11T16:32:50.702Z,1683822770.702 [DATMMP](ERROR): Waiting for previous to be ack'd 2023-05-11T16:32:50.702Z,1683822770.702 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA 2023-05-11T16:32:50.954Z,1683822770.954 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2023-05-11T16:32:50.954Z,1683822770.954 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT 2023-05-11T16:32:51.206Z,1683822771.206 [DATMMP](INFO): *** SENDING_TRANSMIT *** 2023-05-11T16:32:51.206Z,1683822771.206 [DATMMP](INFO): Tx to 10 len 32 xid 52 2023-05-11T16:32:51.207Z,1683822771.207 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2023-05-11T16:32:51.207Z,1683822771.207 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-11T16:32:51.458Z,1683822771.458 [DATMMP](INFO): GSXN notify for xid: 52 2023-05-11T16:32:51.459Z,1683822771.459 [DATMMP](INFO): cmd_result rx: x1 x7 x0 x1 2023-05-11T16:32:51.459Z,1683822771.459 [DATMMP](DEBUG): Command complete send 2023-05-11T16:32:51.459Z,1683822771.459 [DATMMP](ERROR): 1:COMPLETE 2023-05-11T16:32:51.459Z,1683822771.459 [DATMMP](INFO): Received message type: cmd_result 2023-05-11T16:32:51.460Z,1683822771.460 [DATMMP](INFO): Handled 2023-05-11T16:32:51.460Z,1683822771.460 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:51.710Z,1683822771.710 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:51.711Z,1683822771.711 [DATMMP](INFO): timestamp rx: x0 x10 x20 x31 x1D03 2023-05-11T16:32:51.711Z,1683822771.711 [DATMMP](INFO): Received message type: timestamp 2023-05-11T16:32:51.711Z,1683822771.711 [DATMMP](INFO): Handled 2023-05-11T16:32:51.711Z,1683822771.711 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:51.962Z,1683822771.962 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:52.214Z,1683822772.214 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:52.466Z,1683822772.466 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:52.718Z,1683822772.718 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:52.970Z,1683822772.970 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:53.222Z,1683822773.222 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:53.474Z,1683822773.474 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:53.726Z,1683822773.726 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:53.978Z,1683822773.978 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:54.230Z,1683822774.230 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:54.482Z,1683822774.482 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:54.734Z,1683822774.734 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:54.986Z,1683822774.986 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:55.238Z,1683822775.238 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:55.750Z,1683822775.750 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:55.751Z,1683822775.751 [DATMMP](INFO): doppler rx: x0 2023-05-11T16:32:55.751Z,1683822775.751 [DATMMP](INFO): Received message type: doppler 2023-05-11T16:32:55.751Z,1683822775.751 [DATMMP](INFO): Handled 2023-05-11T16:32:55.751Z,1683822775.751 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:56.002Z,1683822776.002 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:56.003Z,1683822776.003 [DATMMP](INFO): timestamp rx: x1 x10 x20 x35 x1C07 2023-05-11T16:32:56.004Z,1683822776.004 [DATMMP](INFO): Rx dataTimestamp_ set to:1683822779.071680 2023-05-11T16:32:56.004Z,1683822776.004 [DATMMP](INFO): Rx ping set to:1683822776.003479 2023-05-11T16:32:56.004Z,1683822776.004 [DATMMP](INFO): Received message type: timestamp 2023-05-11T16:32:56.005Z,1683822776.005 [DATMMP](INFO): Handled 2023-05-11T16:32:56.005Z,1683822776.005 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:56.254Z,1683822776.254 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:56.506Z,1683822776.506 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:56.507Z,1683822776.507 [DATMMP](INFO): remote_header rx: xF x4 x5 x4 x0 x5 xA x0 2023-05-11T16:32:56.507Z,1683822776.507 [DATMMP](INFO): DATA Src=10, Dst=5 2023-05-11T16:32:56.508Z,1683822776.508 [DATMMP](INFO): Received message type: remote_header 2023-05-11T16:32:56.508Z,1683822776.508 [DATMMP](INFO): Handled 2023-05-11T16:32:56.508Z,1683822776.508 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:56.758Z,1683822776.758 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:56.759Z,1683822776.759 [DATMMP](INFO): nav_stat rx: x0 x1 x1 2023-05-11T16:32:56.759Z,1683822776.759 [DATMMP](INFO): Received message type: nav_stat 2023-05-11T16:32:56.759Z,1683822776.759 [DATMMP](INFO): Handled 2023-05-11T16:32:56.759Z,1683822776.759 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:57.010Z,1683822777.010 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:57.011Z,1683822777.011 [DATMMP](INFO): directional_debug rx: xDFA x26F x23 x101 x1250 x1471 x1372 x17B3 2023-05-11T16:32:57.012Z,1683822777.012 [DATMMP](INFO): Received message type: directional_debug 2023-05-11T16:32:57.012Z,1683822777.012 [DATMMP](INFO): Handled 2023-05-11T16:32:57.012Z,1683822777.012 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:57.262Z,1683822777.262 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:57.264Z,1683822777.264 [DATMMP](INFO): directional rx: xDF5 x242 x5F2 xFD91 x249 xFD95 x0 xA87 x4 xFFEF x27B5966 x-43569E0 2023-05-11T16:32:57.265Z,1683822777.265 [DATMMP](INFO): Received message type: directional 2023-05-11T16:32:57.265Z,1683822777.265 [DATMMP](INFO): Handled 2023-05-11T16:32:57.266Z,1683822777.266 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:57.514Z,1683822777.514 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:57.516Z,1683822777.516 [DATMMP](INFO): acstats rx: xA x5 x0 x2AC x22 x85 xB5 xB9 xD xFFFF xA9 x25 xD x0 2023-05-11T16:32:57.516Z,1683822777.516 [DATMMP](INFO): Received message type: acstats 2023-05-11T16:32:57.516Z,1683822777.516 [DATMMP](INFO): Handled 2023-05-11T16:32:57.766Z,1683822777.766 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:32:57.767Z,1683822777.767 [DATMMP](INFO): data_rx rx: xA x0 x0 x4 2023-05-11T16:32:57.767Z,1683822777.767 [DATMMP](INFO): Check CRC 2023-05-11T16:32:57.767Z,1683822777.767 [DATMMP](INFO): Ignoring message sent to address: 5 2023-05-11T16:32:57.767Z,1683822777.767 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:58.018Z,1683822778.018 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:58.270Z,1683822778.270 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:58.522Z,1683822778.522 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:58.774Z,1683822778.774 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:59.026Z,1683822779.026 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:59.278Z,1683822779.278 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:59.530Z,1683822779.530 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:32:59.782Z,1683822779.782 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:33:00.034Z,1683822780.034 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:33:00.286Z,1683822780.286 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:33:00.538Z,1683822780.538 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:33:00.790Z,1683822780.790 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:33:01.042Z,1683822781.042 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:33:01.294Z,1683822781.294 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2023-05-11T16:33:01.294Z,1683822781.294 [DATMMP](DEBUG): In sendingAckWaiting, hit max timeouts so set commsState_ = SENDING_VERIFIED 2023-05-11T16:33:01.294Z,1683822781.294 [DATMMP](ERROR): Ack receipt timeout failure. 2023-05-11T16:33:01.798Z,1683822781.798 [DATMMP](INFO): *** SENDING_VERIFIED *** 2023-05-11T16:33:01.798Z,1683822781.798 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER 2023-05-11T16:34:13.583Z,1683822853.583 [CommandExec](IMPORTANT): got command get DATMMP.localAddressReading 2023-05-11T16:34:13.583Z,1683822853.583 [CommandExec](IMPORTANT): DATMMP.localAddressReading 0 enum 2023-05-11T16:36:05.392Z,1683822965.392 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-11T16:31:05.2Z 2023-05-11T16:36:05.392Z,1683822965.392 [Default:CheckIn:Read_GPS] Stopped 2023-05-11T16:36:05.392Z,1683822965.392 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-05-11T16:36:05.411Z,1683822965.411 [CommandExec](IMPORTANT): got command show variable address 2023-05-11T16:36:05.413Z,1683822965.413 [CommandExec](IMPORTANT): acoustic_contact_address (enum) 2023-05-11T16:36:05.511Z,1683822965.511 [CommandExec](IMPORTANT): Config/workSite.beacon1Address (count) 2023-05-11T16:36:05.511Z,1683822965.511 [CommandExec](IMPORTANT): Config/workSite.beacon2Address (count) 2023-05-11T16:36:05.512Z,1683822965.512 [CommandExec](IMPORTANT): Config/workSite.beacon3Address (count) 2023-05-11T16:36:05.550Z,1683822965.550 [CommandExec](IMPORTANT): AcousticModem_Benthos_ATM900.localAddress (count) 2023-05-11T16:36:05.550Z,1683822965.550 [CommandExec](IMPORTANT): AcousticModem_Benthos_ATM900.sbdAddress (enum) 2023-05-11T16:36:05.551Z,1683822965.551 [CommandExec](IMPORTANT): AcousticModem_Benthos_ATM900.transponderAddress (enum) 2023-05-11T16:36:05.554Z,1683822965.554 [CommandExec](IMPORTANT): DAT.sbdAddress (enum) 2023-05-11T16:36:05.555Z,1683822965.555 [CommandExec](IMPORTANT): DAT.transponderAddress (enum) 2023-05-11T16:36:05.556Z,1683822965.556 [CommandExec](IMPORTANT): DATMMP.sbdAddress (enum) 2023-05-11T16:36:05.556Z,1683822965.556 [CommandExec](IMPORTANT): DATMMP.transponderAddress (enum) 2023-05-11T16:36:05.559Z,1683822965.559 [CommandExec](IMPORTANT): Micromodem.localAddress (enum) 2023-05-11T16:36:05.560Z,1683822965.560 [CommandExec](IMPORTANT): Micromodem.destinationAddress (enum) 2023-05-11T16:36:05.682Z,1683822965.682 [CommandExec](IMPORTANT): DATMMP.acoustic_contact_address (enum) 2023-05-11T16:36:05.707Z,1683822965.707 [CommandExec](IMPORTANT): DATMMP.localAddressReading (enum) 2023-05-11T16:36:13.149Z,1683822973.149 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230511T163033/Courier0000.lzma 2023-05-11T16:36:14.151Z,1683822974.151 [DataOverHttps](INFO): Moved sent file to Logs/20230511T163033/Courier0000.lzma.bak 2023-05-11T16:36:14.151Z,1683822974.151 [DataOverHttps](INFO): SBD MOMSN=18290341 2023-05-11T16:36:17.999Z,1683822977.999 [CommandExec](IMPORTANT): got command get DATMMP.localAddressReading 2023-05-11T16:36:17.999Z,1683822977.999 [CommandExec](IMPORTANT): DATMMP.localAddressReading 0 enum 2023-05-11T16:36:32.518Z,1683822992.518 [DataOverHttps](INFO): Sending 806 bytes from file Logs/20230511T163033/Express0001.lzma 2023-05-11T16:36:33.519Z,1683822993.519 [DataOverHttps](INFO): Moved sent file to Logs/20230511T163033/Express0001.lzma.bak 2023-05-11T16:36:33.519Z,1683822993.519 [DataOverHttps](INFO): SBD MOMSN=18290344 2023-05-11T16:36:52.231Z,1683823012.231 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-05-11T16:37:22.938Z,1683823042.938 [NAL9602](INFO): Not Powering down - fast GPS 2023-05-11T16:45:31.479Z,1683823531.479 [CommandExec](IMPORTANT): got command restart application 2023-05-11T16:45:32.482Z,1683823532.482 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2023-05-11T16:45:32.482Z,1683823532.482 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-11T16:45:32.482Z,1683823532.482 [CommandExec](INFO): Uninitializing the command executive. 2023-05-11T16:45:32.482Z,1683823532.482 [CommandExec](INFO): Uninitializing the command scheduler. 2023-05-11T16:45:32.482Z,1683823532.482 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-05-11T16:45:32.529Z,1683823532.529 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2023-05-11T16:45:32.530Z,1683823532.530 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2023-05-11T16:45:32.530Z,1683823532.530 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-05-11T16:45:32.530Z,1683823532.530 [NavChartDb](INFO): Join timeout helper Thread ID is 9218 2023-05-11T16:45:32.894Z,1683823532.894 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-11T16:45:32.894Z,1683823532.894 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-05-11T16:45:32.909Z,1683823532.909 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2023-05-11T16:45:32.910Z,1683823532.910 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-05-11T16:45:32.910Z,1683823532.910 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 9219 2023-05-11T16:45:33.278Z,1683823533.278 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-11T16:45:33.278Z,1683823533.278 [WetLabsBB2FL](INFO): Powering down 2023-05-11T16:45:33.279Z,1683823533.279 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-05-11T16:45:33.282Z,1683823533.282 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2023-05-11T16:45:33.282Z,1683823533.282 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-05-11T16:45:33.282Z,1683823533.282 [CTD_Seabird](INFO): Join timeout helper Thread ID is 9220 2023-05-11T16:45:34.258Z,1683823534.258 [CTD_Seabird](INFO): Powering down 2023-05-11T16:45:34.270Z,1683823534.270 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-11T16:45:34.270Z,1683823534.270 [CTD_Seabird](INFO): Powering down 2023-05-11T16:45:34.282Z,1683823534.282 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-05-11T16:45:34.298Z,1683823534.298 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2023-05-11T16:45:34.298Z,1683823534.298 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-05-11T16:45:34.298Z,1683823534.298 [Radio_Surface](INFO): Join timeout helper Thread ID is 9221 2023-05-11T16:45:34.490Z,1683823534.490 [Radio_Surface](INFO): Powering down 2023-05-11T16:45:34.491Z,1683823534.491 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-11T16:45:34.491Z,1683823534.491 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-05-11T16:45:34.498Z,1683823534.498 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2023-05-11T16:45:34.498Z,1683823534.498 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-05-11T16:45:34.498Z,1683823534.498 [Onboard](INFO): Join timeout helper Thread ID is 9222 2023-05-11T16:45:34.566Z,1683823534.566 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2023-05-11T16:45:34.978Z,1683823534.978 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-11T16:45:34.978Z,1683823534.978 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-05-11T16:45:34.998Z,1683823534.998 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2023-05-11T16:45:34.999Z,1683823534.999 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-05-11T16:45:34.999Z,1683823534.999 [DataOverHttps](INFO): Join timeout helper Thread ID is 9223 2023-05-11T16:45:36.035Z,1683823536.035 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-11T16:45:36.035Z,1683823536.035 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-05-11T16:45:36.050Z,1683823536.050 [ComponentRegistry](INFO): Shutting down DATMMP ThreadHandler 2023-05-11T16:45:36.050Z,1683823536.050 [DATMMP ThreadHandler](INFO): Thread cancelled. 2023-05-11T16:45:36.051Z,1683823536.051 [DATMMP](INFO): Join timeout helper Thread ID is 9224 2023-05-11T16:45:36.225Z,1683823536.225 [DATMMP](INFO): Stop 2023-05-11T16:45:36.226Z,1683823536.226 [DATMMP](INFO): uninitialize 2023-05-11T16:45:36.226Z,1683823536.226 [DATMMP](INFO): Powering down 2023-05-11T16:45:36.302Z,1683823536.302 [DATMMP ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-11T16:45:36.302Z,1683823536.302 [DATMMP](INFO): uninitialize 2023-05-11T16:45:36.302Z,1683823536.302 [DATMMP](INFO): Powering down 2023-05-11T16:45:36.303Z,1683823536.303 [DATMMP ThreadHandler](INFO): Thread cancelled. 2023-05-11T16:45:36.321Z,1683823536.321 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2023-05-11T16:45:36.321Z,1683823536.321 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-05-11T16:45:36.321Z,1683823536.321 [BackseatComponent](INFO): Join timeout helper Thread ID is 9225 2023-05-11T16:45:36.403Z,1683823536.403 [Default:CheckIn:Read_Iridium] Stopped 2023-05-11T16:45:36.404Z,1683823536.404 [Default:CheckIn:C.Wait] Running Loop=1 2023-05-11T16:45:36.404Z,1683823536.404 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-05-11T16:45:36.438Z,1683823536.438 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-11T16:45:36.438Z,1683823536.438 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-05-11T16:45:36.442Z,1683823536.442 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2023-05-11T16:45:36.442Z,1683823536.442 [logger ThreadHandler](INFO): Thread cancelled. 2023-05-11T16:45:36.443Z,1683823536.443 [logger](INFO): Join timeout helper Thread ID is 9226 2023-05-11T16:45:36.482Z,1683823536.482 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-11T16:45:36.482Z,1683823536.482 [logger ThreadHandler](INFO): Thread cancelled. 2023-05-11T16:45:36.490Z,1683823536.490 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2023-05-11T16:45:36.490Z,1683823536.490 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-05-11T16:45:36.491Z,1683823536.491 [CommandLine](INFO): Join timeout helper Thread ID is 9227 2023-05-11T16:45:36.550Z,1683823536.550 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-11T16:45:36.550Z,1683823536.550 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-05-11T16:45:36.570Z,1683823536.570 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2023-05-11T16:45:36.570Z,1683823536.570 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-05-11T16:45:36.571Z,1683823536.571 [CommandExec](INFO): Join timeout helper Thread ID is 9228 2023-05-11T16:45:36.572Z,1683823536.572 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2023-05-11T16:45:36.572Z,1683823536.572 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-05-11T16:45:36.572Z,1683823536.572 [controlThread](INFO): Join timeout helper Thread ID is 9229 2023-05-11T16:45:36.750Z,1683823536.750 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-11T16:45:36.750Z,1683823536.750 [controlThread](DEBUG): Uninitializing ControlThread 2023-05-11T16:45:36.750Z,1683823536.750 [AHRS_M2](INFO): Powering down 2023-05-11T16:45:36.822Z,1683823536.822 [NAL9602](INFO): Powering down 2023-05-11T16:45:36.825Z,1683823536.825 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2023-05-11T16:45:36.826Z,1683823536.826 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2023-05-11T16:45:36.826Z,1683823536.826 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2023-05-11T16:45:36.827Z,1683823536.827 [MissionManager](INFO): Uninitializing Mission Default 2023-05-11T16:45:36.827Z,1683823536.827 [Default] Stopped 2023-05-11T16:45:36.827Z,1683823536.827 [Default](DEBUG): Aggregate::uninitialize Default 2023-05-11T16:45:36.827Z,1683823536.827 [Default:B.GoToSurface] Stopped 2023-05-11T16:45:36.827Z,1683823536.827 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-05-11T16:45:36.827Z,1683823536.827 [Default:CheckIn] Stopped 2023-05-11T16:45:36.827Z,1683823536.827 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-05-11T16:45:36.827Z,1683823536.827 [Default:CheckIn:C.Wait] Stopped 2023-05-11T16:45:36.827Z,1683823536.827 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-05-11T16:45:36.830Z,1683823536.830 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2023-05-11T16:45:36.830Z,1683823536.830 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2023-05-11T16:45:36.831Z,1683823536.831 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2023-05-11T16:45:36.831Z,1683823536.831 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2023-05-11T16:45:36.831Z,1683823536.831 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2023-05-11T16:45:36.831Z,1683823536.831 [BuoyancyServo](INFO): Powering down 2023-05-11T16:45:36.846Z,1683823536.846 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2023-05-11T16:45:36.846Z,1683823536.846 [ElevatorServo](INFO): Powering down 2023-05-11T16:45:36.847Z,1683823536.847 [MassServo](DEBUG): Uninitialize Mass Servo. 2023-05-11T16:45:36.847Z,1683823536.847 [MassServo](INFO): Powering down 2023-05-11T16:45:36.847Z,1683823536.847 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2023-05-11T16:45:36.848Z,1683823536.848 [RudderServo](INFO): Powering down 2023-05-11T16:45:36.848Z,1683823536.848 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2023-05-11T16:45:36.848Z,1683823536.848 [ThrusterHE](INFO): Powering down 2023-05-11T16:45:36.850Z,1683823536.850 [SBIT](DEBUG): Uninitialize SBIT Component. 2023-05-11T16:45:36.850Z,1683823536.850 [IBIT](DEBUG): Uninitialize IBIT Component. 2023-05-11T16:45:36.850Z,1683823536.850 [CBIT](DEBUG): Uninitialize CBIT Component. 2023-05-11T16:45:36.850Z,1683823536.850 [CBIT](DEBUG): Powering off loads. 2023-05-11T16:45:36.861Z,1683823536.861 [CBIT](DEBUG): Disabling WDT. 2023-05-11T16:45:36.873Z,1683823536.873 [CBIT](DEBUG): Opening all GF detection circuits. 2023-05-11T16:45:36.874Z,1683823536.874 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-05-11T16:45:36.929Z,1683823536.929 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-05-11T16:45:36.932Z,1683823536.932 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-05-11T16:45:36.937Z,1683823536.937 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-05-11T16:45:36.943Z,1683823536.943 [DATMMP ThreadHandler](INFO): Thread cancelled. 2023-05-11T16:45:36.984Z,1683823536.984 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-05-11T16:45:37.022Z,1683823537.022 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-05-11T16:45:37.027Z,1683823537.027 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-05-11T16:45:37.061Z,1683823537.061 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-05-11T16:45:37.123Z,1683823537.123 [logger ThreadHandler](INFO): Thread cancelled.