2023-05-11T16:02:59.196Z,1683820979.196 [CommandExec](IMPORTANT): got command restart logs 2023-05-11T16:03:42.841Z,1683821022.841 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-05-11T16:03:42.841Z,1683821022.841 [Default:CheckIn:C.Wait] Stopped 2023-05-11T16:03:42.841Z,1683821022.841 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-05-11T16:03:42.842Z,1683821022.842 [Default:CheckIn:D] Running Loop=1 2023-05-11T16:03:43.250Z,1683821023.250 [Default:CheckIn:D] Stopped 2023-05-11T16:03:43.250Z,1683821023.250 [Default:CheckIn:E] Running Loop=1 2023-05-11T16:03:43.680Z,1683821023.680 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 8.100979 min 2023-05-11T16:03:43.681Z,1683821023.681 [Default:CheckIn:E] Stopped 2023-05-11T16:03:43.681Z,1683821023.681 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-05-11T16:03:43.681Z,1683821023.681 [Default:CheckIn] Stopped 2023-05-11T16:03:43.681Z,1683821023.681 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-05-11T16:03:43.681Z,1683821023.681 [Default:CheckIn](INFO): Running loop #2 2023-05-11T16:03:43.681Z,1683821023.681 [Default:CheckIn] Running Loop=2 2023-05-11T16:03:43.681Z,1683821023.681 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-05-11T16:03:43.681Z,1683821023.681 [Default:CheckIn:Read_GPS] Running Loop=1 2023-05-11T16:03:45.255Z,1683821025.255 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-05-11T16:03:45.255Z,1683821025.255 [NAL9602] Data Fault, FailCount= 1 2023-05-11T16:03:45.255Z,1683821025.255 [NAL9602](ERROR): Data Fault 2023-05-11T16:03:45.325Z,1683821025.325 [CBIT](ERROR): Data Fault in component: NAL9602 2023-05-11T16:03:45.660Z,1683821025.660 [NAL9602](INFO): Powering down 2023-05-11T16:03:46.506Z,1683821026.506 [CBIT](INFO): Clearing failed state for component NAL9602 2023-05-11T16:03:46.506Z,1683821026.506 [NAL9602] No Fault, FailCount= 1 2023-05-11T16:03:51.081Z,1683821031.081 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:03:51.083Z,1683821031.083 [DATMMP](INFO): doppler rx: x0 2023-05-11T16:03:51.083Z,1683821031.083 [DATMMP](INFO): Received message type: doppler 2023-05-11T16:03:51.083Z,1683821031.083 [DATMMP](INFO): Handled 2023-05-11T16:03:51.334Z,1683821031.334 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:03:51.337Z,1683821031.337 [DATMMP](INFO): timestamp rx: x1 x10 x3 x31 x274 2023-05-11T16:03:51.338Z,1683821031.338 [DATMMP](INFO): Rx dataTimestamp_ set to:1683821035.139264 2023-05-11T16:03:51.339Z,1683821031.339 [DATMMP](INFO): Rx ping set to:1683821031.336796 2023-05-11T16:03:51.340Z,1683821031.340 [DATMMP](INFO): Received message type: timestamp 2023-05-11T16:03:51.340Z,1683821031.340 [DATMMP](INFO): Handled 2023-05-11T16:03:51.584Z,1683821031.584 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:03:51.585Z,1683821031.585 [DATMMP](INFO): remote_header rx: xF x4 x5 x22 x0 xA x5 x0 2023-05-11T16:03:51.586Z,1683821031.586 [DATMMP](INFO): DATA Src=5, Dst=10 2023-05-11T16:03:51.586Z,1683821031.586 [DATMMP](INFO): Received message type: remote_header 2023-05-11T16:03:51.586Z,1683821031.586 [DATMMP](INFO): Handled 2023-05-11T16:03:52.088Z,1683821032.088 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:03:52.089Z,1683821032.089 [DATMMP](INFO): nav_stat rx: x0 x1 x1 2023-05-11T16:03:52.089Z,1683821032.089 [DATMMP](INFO): Received message type: nav_stat 2023-05-11T16:03:52.089Z,1683821032.089 [DATMMP](INFO): Handled 2023-05-11T16:03:52.340Z,1683821032.340 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:03:52.341Z,1683821032.341 [DATMMP](INFO): directional_debug rx: x1A1 x100 x1F x1C9 x3270 x3FF1 x67F2 x7AA3 2023-05-11T16:03:52.342Z,1683821032.342 [DATMMP](INFO): Received message type: directional_debug 2023-05-11T16:03:52.343Z,1683821032.343 [DATMMP](INFO): Handled 2023-05-11T16:03:52.592Z,1683821032.592 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:03:52.594Z,1683821032.594 [DATMMP](INFO): directional rx: x1C5 xEF x43B xFF00 xAF3 xFEFF x0 x6BA x5 xFFFF x27B5966 x-43569E0 2023-05-11T16:03:52.595Z,1683821032.595 [DATMMP](INFO): Received message type: directional 2023-05-11T16:03:52.596Z,1683821032.596 [DATMMP](INFO): Handled 2023-05-11T16:03:52.844Z,1683821032.844 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:03:52.846Z,1683821032.846 [DATMMP](INFO): acstats rx: x5 x5 x0 x2D0 x23 x7D xCD xBE xD xFFFF xAB x20 xC x0 2023-05-11T16:03:52.846Z,1683821032.846 [DATMMP](INFO): Received message type: acstats 2023-05-11T16:03:52.846Z,1683821032.846 [DATMMP](INFO): Handled 2023-05-11T16:03:53.096Z,1683821033.096 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:03:53.097Z,1683821033.097 [DATMMP](INFO): data_rx rx: x5 x0 x0 x22 2023-05-11T16:03:53.097Z,1683821033.097 [DATMMP](INFO): Check CRC 2023-05-11T16:03:53.097Z,1683821033.097 [DATMMP](INFO): data rx parseDataResponse 34 2023-05-11T16:03:53.098Z,1683821033.098 [DATMMP](INFO): Got DATA: len 34 2023-05-11T16:03:53.098Z,1683821033.098 [DATMMP](INFO): Received command: set _.pressure 0.742939 atmosphere 2023-05-11T16:03:53.098Z,1683821033.098 [DATMMP](INFO): #Outgoing data=1 2023-05-11T16:03:53.098Z,1683821033.098 [DATMMP](INFO): Sending ack 2023-05-11T16:03:53.098Z,1683821033.098 [DATMMP](INFO): Received message type: data_rx 2023-05-11T16:03:53.099Z,1683821033.099 [DATMMP](INFO): Handled 2023-05-11T16:03:53.099Z,1683821033.099 [DATMMP](INFO): *** SENDING_FILL_BUFFER *** 2023-05-11T16:03:53.101Z,1683821033.101 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA 2023-05-11T16:03:53.349Z,1683821033.349 [DATMMP](INFO): GSXN notify for xid: 15 2023-05-11T16:03:53.352Z,1683821033.352 [DATMMP](INFO): depth rx: x1 x1 x4 x0 2023-05-11T16:03:53.353Z,1683821033.353 [DATMMP](INFO): Received message type: depth 2023-05-11T16:03:53.353Z,1683821033.353 [DATMMP](INFO): Match for : depth 2023-05-11T16:03:53.353Z,1683821033.353 [DATMMP](INFO): ACK 2023-05-11T16:03:53.354Z,1683821033.354 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2023-05-11T16:03:53.600Z,1683821033.600 [DATMMP](INFO): GSXN notify for xid: 16 2023-05-11T16:03:53.601Z,1683821033.601 [DATMMP](INFO): lat rx: x2318600 x6 x1 x0 2023-05-11T16:03:53.601Z,1683821033.601 [DATMMP](INFO): Received message type: lat 2023-05-11T16:03:53.601Z,1683821033.601 [DATMMP](INFO): Match for : lat 2023-05-11T16:03:53.601Z,1683821033.601 [DATMMP](INFO): ACK 2023-05-11T16:03:53.602Z,1683821033.602 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2023-05-11T16:03:53.852Z,1683821033.852 [DATMMP](INFO): GSXN notify for xid: 17 2023-05-11T16:03:53.936Z,1683821033.936 [DATMMP](INFO): lon rx: x0 x6 x1 x0 2023-05-11T16:03:53.936Z,1683821033.936 [DATMMP](INFO): Received message type: lon 2023-05-11T16:03:53.936Z,1683821033.936 [DATMMP](INFO): Match for : lon 2023-05-11T16:03:53.936Z,1683821033.936 [DATMMP](INFO): ACK 2023-05-11T16:03:53.937Z,1683821033.937 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2023-05-11T16:03:53.937Z,1683821033.937 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT 2023-05-11T16:03:54.104Z,1683821034.104 [DATMMP](INFO): *** SENDING_TRANSMIT *** 2023-05-11T16:03:54.105Z,1683821034.105 [DATMMP](INFO): Tx to 5 len 4 xid 18 2023-05-11T16:03:54.106Z,1683821034.106 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2023-05-11T16:03:54.106Z,1683821034.106 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-11T16:03:54.342Z,1683821034.342 [CommandExec](IMPORTANT): got command set _.pressure 0.742939 atmosphere 2023-05-11T16:03:54.356Z,1683821034.356 [DATMMP](INFO): GSXN notify for xid: 18 2023-05-11T16:03:54.357Z,1683821034.357 [DATMMP](INFO): cmd_result rx: x1 x7 x0 x1 2023-05-11T16:03:54.357Z,1683821034.357 [DATMMP](DEBUG): In rxCmdResult, sent ack so set commsState_ = SENDING_VERIFIED 2023-05-11T16:03:54.357Z,1683821034.357 [DATMMP](DEBUG): Command complete send 2023-05-11T16:03:54.358Z,1683821034.358 [DATMMP](ERROR): 1:COMPLETE 2023-05-11T16:03:54.358Z,1683821034.358 [DATMMP](INFO): Received message type: cmd_result 2023-05-11T16:03:54.358Z,1683821034.358 [DATMMP](INFO): Handled 2023-05-11T16:03:54.358Z,1683821034.358 [DATMMP](INFO): *** SENDING_VERIFIED *** 2023-05-11T16:03:54.358Z,1683821034.358 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER 2023-05-11T16:03:54.608Z,1683821034.608 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:03:54.609Z,1683821034.609 [DATMMP](INFO): timestamp rx: x0 x10 x3 x34 x1C14 2023-05-11T16:03:54.609Z,1683821034.609 [DATMMP](INFO): Received message type: timestamp 2023-05-11T16:03:54.609Z,1683821034.609 [DATMMP](INFO): Handled 2023-05-11T16:04:01.916Z,1683821041.916 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:01.917Z,1683821041.917 [DATMMP](INFO): doppler rx: x0 2023-05-11T16:04:01.917Z,1683821041.917 [DATMMP](INFO): Received message type: doppler 2023-05-11T16:04:01.917Z,1683821041.917 [DATMMP](INFO): Handled 2023-05-11T16:04:02.168Z,1683821042.168 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:02.169Z,1683821042.169 [DATMMP](INFO): timestamp rx: x1 x10 x3 x3B x23A8 2023-05-11T16:04:02.170Z,1683821042.170 [DATMMP](INFO): Rx dataTimestamp_ set to:1683821064.147456 2023-05-11T16:04:02.170Z,1683821042.170 [DATMMP](INFO): Rx ping set to:1683821042.168987 2023-05-11T16:04:02.170Z,1683821042.170 [DATMMP](INFO): Received message type: timestamp 2023-05-11T16:04:02.170Z,1683821042.170 [DATMMP](INFO): Handled 2023-05-11T16:04:02.672Z,1683821042.672 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:02.673Z,1683821042.673 [DATMMP](INFO): remote_header rx: xF x4 x5 x22 x0 xA x5 x0 2023-05-11T16:04:02.674Z,1683821042.674 [DATMMP](INFO): DATA Src=5, Dst=10 2023-05-11T16:04:02.674Z,1683821042.674 [DATMMP](INFO): Received message type: remote_header 2023-05-11T16:04:02.674Z,1683821042.674 [DATMMP](INFO): Handled 2023-05-11T16:04:02.924Z,1683821042.924 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:02.925Z,1683821042.925 [DATMMP](INFO): nav_stat rx: x0 x1 x1 2023-05-11T16:04:02.925Z,1683821042.925 [DATMMP](INFO): Received message type: nav_stat 2023-05-11T16:04:02.925Z,1683821042.925 [DATMMP](INFO): Handled 2023-05-11T16:04:03.176Z,1683821043.176 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:03.177Z,1683821043.177 [DATMMP](INFO): directional_debug rx: x191 x10F x21 x1AA x2BC0 x4841 x7632 x6CD3 2023-05-11T16:04:03.178Z,1683821043.178 [DATMMP](INFO): Received message type: directional_debug 2023-05-11T16:04:03.178Z,1683821043.178 [DATMMP](INFO): Handled 2023-05-11T16:04:03.436Z,1683821043.436 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:03.438Z,1683821043.438 [DATMMP](INFO): directional rx: x1B3 xFA x44B xFEF1 xB03 xFEF0 x0 x6BA x5 x0 x2318600 x0 2023-05-11T16:04:03.439Z,1683821043.439 [DATMMP](INFO): Received message type: directional 2023-05-11T16:04:03.440Z,1683821043.440 [DATMMP](INFO): Handled 2023-05-11T16:04:03.688Z,1683821043.688 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:03.690Z,1683821043.690 [DATMMP](INFO): acstats rx: x5 x5 x0 x2D0 x23 x80 xB5 xC2 xD xFFFF xAB x23 xC x0 2023-05-11T16:04:03.690Z,1683821043.690 [DATMMP](INFO): Received message type: acstats 2023-05-11T16:04:03.690Z,1683821043.690 [DATMMP](INFO): Handled 2023-05-11T16:04:03.940Z,1683821043.940 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:03.941Z,1683821043.941 [DATMMP](INFO): data_rx rx: x5 x0 x0 x22 2023-05-11T16:04:03.941Z,1683821043.941 [DATMMP](INFO): Check CRC 2023-05-11T16:04:03.941Z,1683821043.941 [DATMMP](INFO): data rx parseDataResponse 34 2023-05-11T16:04:03.941Z,1683821043.941 [DATMMP](INFO): Got DATA: len 34 2023-05-11T16:04:03.942Z,1683821043.942 [DATMMP](INFO): Received command: set _.pressure 0.742939 atmosphere 2023-05-11T16:04:03.942Z,1683821043.942 [DATMMP](INFO): #Outgoing data=1 2023-05-11T16:04:03.942Z,1683821043.942 [DATMMP](INFO): Sending ack 2023-05-11T16:04:03.942Z,1683821043.942 [DATMMP](INFO): Received message type: data_rx 2023-05-11T16:04:03.942Z,1683821043.942 [DATMMP](INFO): Handled 2023-05-11T16:04:03.942Z,1683821043.942 [DATMMP](INFO): *** SENDING_FILL_BUFFER *** 2023-05-11T16:04:03.943Z,1683821043.943 [DATMMP](ERROR): Waiting for previous to be ack'd 2023-05-11T16:04:03.943Z,1683821043.943 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA 2023-05-11T16:04:04.192Z,1683821044.192 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2023-05-11T16:04:04.192Z,1683821044.192 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT 2023-05-11T16:04:04.444Z,1683821044.444 [DATMMP](INFO): *** SENDING_TRANSMIT *** 2023-05-11T16:04:04.444Z,1683821044.444 [DATMMP](INFO): Tx to 5 len 4 xid 19 2023-05-11T16:04:04.445Z,1683821044.445 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2023-05-11T16:04:04.445Z,1683821044.445 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-11T16:04:04.696Z,1683821044.696 [DATMMP](INFO): GSXN notify for xid: 19 2023-05-11T16:04:04.697Z,1683821044.697 [DATMMP](INFO): cmd_result rx: x1 x7 x0 x1 2023-05-11T16:04:04.697Z,1683821044.697 [DATMMP](DEBUG): In rxCmdResult, sent ack so set commsState_ = SENDING_VERIFIED 2023-05-11T16:04:04.697Z,1683821044.697 [DATMMP](DEBUG): Command complete send 2023-05-11T16:04:04.698Z,1683821044.698 [DATMMP](ERROR): 1:COMPLETE 2023-05-11T16:04:04.698Z,1683821044.698 [DATMMP](INFO): Received message type: cmd_result 2023-05-11T16:04:04.698Z,1683821044.698 [DATMMP](INFO): Handled 2023-05-11T16:04:04.698Z,1683821044.698 [DATMMP](INFO): *** SENDING_VERIFIED *** 2023-05-11T16:04:04.698Z,1683821044.698 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER 2023-05-11T16:04:04.948Z,1683821044.948 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:04.949Z,1683821044.949 [DATMMP](INFO): timestamp rx: x0 x10 x4 x3 xBC 2023-05-11T16:04:04.949Z,1683821044.949 [DATMMP](INFO): Received message type: timestamp 2023-05-11T16:04:04.949Z,1683821044.949 [DATMMP](INFO): Handled 2023-05-11T16:04:05.186Z,1683821045.186 [CommandExec](IMPORTANT): got command set _.pressure 0.742939 atmosphere 2023-05-11T16:04:12.760Z,1683821052.760 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:12.761Z,1683821052.761 [DATMMP](INFO): doppler rx: x0 2023-05-11T16:04:12.761Z,1683821052.761 [DATMMP](INFO): Received message type: doppler 2023-05-11T16:04:12.761Z,1683821052.761 [DATMMP](INFO): Handled 2023-05-11T16:04:13.012Z,1683821053.012 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:13.013Z,1683821053.013 [DATMMP](INFO): timestamp rx: x1 x10 x4 xA x1DCB 2023-05-11T16:04:13.014Z,1683821053.014 [DATMMP](INFO): Rx dataTimestamp_ set to:1683821060.505856 2023-05-11T16:04:13.014Z,1683821053.014 [DATMMP](INFO): Rx ping set to:1683821053.012993 2023-05-11T16:04:13.014Z,1683821053.014 [DATMMP](INFO): Received message type: timestamp 2023-05-11T16:04:13.014Z,1683821053.014 [DATMMP](INFO): Handled 2023-05-11T16:04:13.516Z,1683821053.516 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:13.517Z,1683821053.517 [DATMMP](INFO): remote_header rx: xF x4 x5 x22 x0 xA x5 x0 2023-05-11T16:04:13.518Z,1683821053.518 [DATMMP](INFO): DATA Src=5, Dst=10 2023-05-11T16:04:13.518Z,1683821053.518 [DATMMP](INFO): Received message type: remote_header 2023-05-11T16:04:13.518Z,1683821053.518 [DATMMP](INFO): Handled 2023-05-11T16:04:13.770Z,1683821053.770 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:13.772Z,1683821053.772 [DATMMP](INFO): nav_stat rx: x0 x1 x1 2023-05-11T16:04:13.772Z,1683821053.772 [DATMMP](INFO): Received message type: nav_stat 2023-05-11T16:04:13.773Z,1683821053.773 [DATMMP](INFO): Handled 2023-05-11T16:04:14.020Z,1683821054.020 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:14.021Z,1683821054.021 [DATMMP](INFO): directional_debug rx: x196 x134 x20 x1CD x2B20 x44A1 x6C92 x6473 2023-05-11T16:04:14.022Z,1683821054.022 [DATMMP](INFO): Received message type: directional_debug 2023-05-11T16:04:14.022Z,1683821054.022 [DATMMP](INFO): Handled 2023-05-11T16:04:14.272Z,1683821054.272 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:14.274Z,1683821054.274 [DATMMP](INFO): directional rx: x1B5 x116 x446 xFECC xB01 xFECC x0 x6BC x2 x0 x2318600 x0 2023-05-11T16:04:14.275Z,1683821054.275 [DATMMP](INFO): Received message type: directional 2023-05-11T16:04:14.275Z,1683821054.276 [DATMMP](INFO): Handled 2023-05-11T16:04:14.524Z,1683821054.524 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:14.526Z,1683821054.526 [DATMMP](INFO): acstats rx: x5 x5 x0 x2D0 x23 x80 xBB xB4 xD xFFFF xAD x21 xC x0 2023-05-11T16:04:14.526Z,1683821054.526 [DATMMP](INFO): Received message type: acstats 2023-05-11T16:04:14.526Z,1683821054.526 [DATMMP](INFO): Handled 2023-05-11T16:04:14.777Z,1683821054.777 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:14.778Z,1683821054.778 [DATMMP](INFO): data_rx rx: x5 x0 x0 x22 2023-05-11T16:04:14.778Z,1683821054.778 [DATMMP](INFO): Check CRC 2023-05-11T16:04:14.778Z,1683821054.778 [DATMMP](INFO): data rx parseDataResponse 34 2023-05-11T16:04:14.778Z,1683821054.778 [DATMMP](INFO): Got DATA: len 34 2023-05-11T16:04:14.778Z,1683821054.778 [DATMMP](INFO): Received command: set _.pressure 0.742939 atmosphere 2023-05-11T16:04:14.779Z,1683821054.779 [DATMMP](INFO): #Outgoing data=1 2023-05-11T16:04:14.779Z,1683821054.779 [DATMMP](INFO): Sending ack 2023-05-11T16:04:14.779Z,1683821054.779 [DATMMP](INFO): Received message type: data_rx 2023-05-11T16:04:14.780Z,1683821054.780 [DATMMP](INFO): Handled 2023-05-11T16:04:14.780Z,1683821054.780 [DATMMP](INFO): *** SENDING_FILL_BUFFER *** 2023-05-11T16:04:14.780Z,1683821054.780 [DATMMP](ERROR): Waiting for previous to be ack'd 2023-05-11T16:04:14.782Z,1683821054.782 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA 2023-05-11T16:04:15.028Z,1683821055.028 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2023-05-11T16:04:15.028Z,1683821055.028 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT 2023-05-11T16:04:15.281Z,1683821055.281 [DATMMP](INFO): *** SENDING_TRANSMIT *** 2023-05-11T16:04:15.281Z,1683821055.281 [DATMMP](INFO): Tx to 5 len 4 xid 20 2023-05-11T16:04:15.282Z,1683821055.282 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2023-05-11T16:04:15.282Z,1683821055.282 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-11T16:04:15.532Z,1683821055.532 [DATMMP](INFO): GSXN notify for xid: 20 2023-05-11T16:04:15.533Z,1683821055.533 [DATMMP](INFO): cmd_result rx: x1 x7 x0 x1 2023-05-11T16:04:15.533Z,1683821055.533 [DATMMP](DEBUG): In rxCmdResult, sent ack so set commsState_ = SENDING_VERIFIED 2023-05-11T16:04:15.533Z,1683821055.533 [DATMMP](DEBUG): Command complete send 2023-05-11T16:04:15.533Z,1683821055.533 [DATMMP](ERROR): 1:COMPLETE 2023-05-11T16:04:15.533Z,1683821055.533 [DATMMP](INFO): Received message type: cmd_result 2023-05-11T16:04:15.534Z,1683821055.534 [DATMMP](INFO): Handled 2023-05-11T16:04:15.534Z,1683821055.534 [DATMMP](INFO): *** SENDING_VERIFIED *** 2023-05-11T16:04:15.534Z,1683821055.534 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER 2023-05-11T16:04:15.784Z,1683821055.784 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:15.785Z,1683821055.785 [DATMMP](INFO): timestamp rx: x0 x10 x4 xD x21EF 2023-05-11T16:04:15.785Z,1683821055.785 [DATMMP](INFO): Received message type: timestamp 2023-05-11T16:04:15.785Z,1683821055.785 [DATMMP](INFO): Handled 2023-05-11T16:04:15.910Z,1683821055.910 [CommandExec](IMPORTANT): got command set _.pressure 0.742939 atmosphere 2023-05-11T16:04:15.964Z,1683821055.964 [NAL9602](INFO): Powering up NAL9602 2023-05-11T16:04:23.608Z,1683821063.608 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:23.609Z,1683821063.609 [DATMMP](INFO): doppler rx: x0 2023-05-11T16:04:23.609Z,1683821063.609 [DATMMP](INFO): Received message type: doppler 2023-05-11T16:04:23.609Z,1683821063.609 [DATMMP](INFO): Handled 2023-05-11T16:04:23.860Z,1683821063.860 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:23.861Z,1683821063.861 [DATMMP](INFO): timestamp rx: x1 x10 x4 x15 x17EF 2023-05-11T16:04:23.862Z,1683821063.862 [DATMMP](INFO): Rx dataTimestamp_ set to:1683821120.874496 2023-05-11T16:04:23.862Z,1683821063.862 [DATMMP](INFO): Rx ping set to:1683821063.861028 2023-05-11T16:04:23.862Z,1683821063.862 [DATMMP](INFO): Received message type: timestamp 2023-05-11T16:04:23.862Z,1683821063.862 [DATMMP](INFO): Handled 2023-05-11T16:04:24.364Z,1683821064.364 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:24.365Z,1683821064.365 [DATMMP](INFO): remote_header rx: xF x4 x5 x22 x0 xA x5 x0 2023-05-11T16:04:24.366Z,1683821064.366 [DATMMP](INFO): DATA Src=5, Dst=10 2023-05-11T16:04:24.366Z,1683821064.366 [DATMMP](INFO): Received message type: remote_header 2023-05-11T16:04:24.366Z,1683821064.366 [DATMMP](INFO): Handled 2023-05-11T16:04:24.619Z,1683821064.619 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:24.621Z,1683821064.621 [DATMMP](INFO): nav_stat rx: x0 x1 x1 2023-05-11T16:04:24.621Z,1683821064.621 [DATMMP](INFO): Received message type: nav_stat 2023-05-11T16:04:24.621Z,1683821064.621 [DATMMP](INFO): Handled 2023-05-11T16:04:24.868Z,1683821064.868 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:24.869Z,1683821064.869 [DATMMP](INFO): directional_debug rx: x1A1 x121 x21 x1AD x2C30 x48B1 x75D2 x6FF3 2023-05-11T16:04:24.870Z,1683821064.870 [DATMMP](INFO): Received message type: directional_debug 2023-05-11T16:04:24.871Z,1683821064.871 [DATMMP](INFO): Handled 2023-05-11T16:04:25.120Z,1683821065.120 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:25.122Z,1683821065.122 [DATMMP](INFO): directional rx: x1C2 x108 x43B xFEDF xAF5 xFEDD x0 x6BB x3 x1 x2318600 x0 2023-05-11T16:04:25.123Z,1683821065.123 [DATMMP](INFO): Received message type: directional 2023-05-11T16:04:25.123Z,1683821065.123 [DATMMP](INFO): Handled 2023-05-11T16:04:25.372Z,1683821065.372 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:25.374Z,1683821065.374 [DATMMP](INFO): acstats rx: x5 x5 x0 x2D0 x22 x7F xAA xB4 xD xFFFF xAD x21 xC x0 2023-05-11T16:04:25.374Z,1683821065.374 [DATMMP](INFO): Received message type: acstats 2023-05-11T16:04:25.374Z,1683821065.374 [DATMMP](INFO): Handled 2023-05-11T16:04:25.624Z,1683821065.624 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:25.625Z,1683821065.625 [DATMMP](INFO): data_rx rx: x5 x0 x0 x22 2023-05-11T16:04:25.625Z,1683821065.625 [DATMMP](INFO): Check CRC 2023-05-11T16:04:25.625Z,1683821065.625 [DATMMP](INFO): data rx parseDataResponse 34 2023-05-11T16:04:25.626Z,1683821065.626 [DATMMP](INFO): Got DATA: len 34 2023-05-11T16:04:25.626Z,1683821065.626 [DATMMP](INFO): Received command: set _.pressure 0.742939 atmosphere 2023-05-11T16:04:25.626Z,1683821065.626 [DATMMP](INFO): #Outgoing data=1 2023-05-11T16:04:25.626Z,1683821065.626 [DATMMP](INFO): Sending ack 2023-05-11T16:04:25.626Z,1683821065.626 [DATMMP](INFO): Received message type: data_rx 2023-05-11T16:04:25.626Z,1683821065.626 [DATMMP](INFO): Handled 2023-05-11T16:04:25.627Z,1683821065.627 [DATMMP](INFO): *** SENDING_FILL_BUFFER *** 2023-05-11T16:04:25.627Z,1683821065.627 [DATMMP](ERROR): Waiting for previous to be ack'd 2023-05-11T16:04:25.628Z,1683821065.628 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA 2023-05-11T16:04:25.876Z,1683821065.876 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2023-05-11T16:04:25.876Z,1683821065.876 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT 2023-05-11T16:04:26.128Z,1683821066.128 [DATMMP](INFO): *** SENDING_TRANSMIT *** 2023-05-11T16:04:26.128Z,1683821066.128 [DATMMP](INFO): Tx to 5 len 4 xid 21 2023-05-11T16:04:26.129Z,1683821066.129 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2023-05-11T16:04:26.129Z,1683821066.129 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-11T16:04:26.380Z,1683821066.380 [DATMMP](INFO): GSXN notify for xid: 21 2023-05-11T16:04:26.381Z,1683821066.381 [DATMMP](INFO): cmd_result rx: x1 x7 x0 x1 2023-05-11T16:04:26.381Z,1683821066.381 [DATMMP](DEBUG): In rxCmdResult, sent ack so set commsState_ = SENDING_VERIFIED 2023-05-11T16:04:26.381Z,1683821066.381 [DATMMP](DEBUG): Command complete send 2023-05-11T16:04:26.381Z,1683821066.381 [DATMMP](ERROR): 1:COMPLETE 2023-05-11T16:04:26.382Z,1683821066.382 [DATMMP](INFO): Received message type: cmd_result 2023-05-11T16:04:26.382Z,1683821066.382 [DATMMP](INFO): Handled 2023-05-11T16:04:26.382Z,1683821066.382 [DATMMP](INFO): *** SENDING_VERIFIED *** 2023-05-11T16:04:26.382Z,1683821066.382 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER 2023-05-11T16:04:26.632Z,1683821066.632 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:26.633Z,1683821066.633 [DATMMP](INFO): timestamp rx: x0 x10 x4 x18 x1C13 2023-05-11T16:04:26.633Z,1683821066.633 [DATMMP](INFO): Received message type: timestamp 2023-05-11T16:04:26.634Z,1683821066.634 [DATMMP](INFO): Handled 2023-05-11T16:04:26.743Z,1683821066.743 [CommandExec](IMPORTANT): got command set _.pressure 0.742939 atmosphere 2023-05-11T16:04:26.881Z,1683821066.881 [NAL9602](INFO): NAL9602 initialized 2023-05-11T16:04:34.696Z,1683821074.696 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:34.697Z,1683821074.697 [DATMMP](INFO): doppler rx: x0 2023-05-11T16:04:34.697Z,1683821074.697 [DATMMP](INFO): Received message type: doppler 2023-05-11T16:04:34.697Z,1683821074.697 [DATMMP](INFO): Handled 2023-05-11T16:04:34.948Z,1683821074.948 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:34.949Z,1683821074.949 [DATMMP](INFO): timestamp rx: x1 x10 x4 x20 x1BD7 2023-05-11T16:04:34.950Z,1683821074.950 [DATMMP](INFO): Rx dataTimestamp_ set to:1683821077.628736 2023-05-11T16:04:34.950Z,1683821074.950 [DATMMP](INFO): Rx ping set to:1683821074.948983 2023-05-11T16:04:34.950Z,1683821074.950 [DATMMP](INFO): Received message type: timestamp 2023-05-11T16:04:34.950Z,1683821074.950 [DATMMP](INFO): Handled 2023-05-11T16:04:35.452Z,1683821075.452 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:35.453Z,1683821075.453 [DATMMP](INFO): remote_header rx: xF x4 x5 x23 x0 xA x5 x0 2023-05-11T16:04:35.454Z,1683821075.454 [DATMMP](INFO): DATA Src=5, Dst=10 2023-05-11T16:04:35.454Z,1683821075.454 [DATMMP](INFO): Received message type: remote_header 2023-05-11T16:04:35.454Z,1683821075.454 [DATMMP](INFO): Handled 2023-05-11T16:04:35.956Z,1683821075.956 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:35.957Z,1683821075.957 [DATMMP](INFO): nav_stat rx: x0 x1 x1 2023-05-11T16:04:35.957Z,1683821075.957 [DATMMP](INFO): Received message type: nav_stat 2023-05-11T16:04:35.957Z,1683821075.957 [DATMMP](INFO): Handled 2023-05-11T16:04:36.208Z,1683821076.208 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:36.210Z,1683821076.210 [DATMMP](INFO): directional_debug rx: x19B x11E x21 x1AE x2B90 x4941 x75F2 x7193 2023-05-11T16:04:36.211Z,1683821076.211 [DATMMP](INFO): Received message type: directional_debug 2023-05-11T16:04:36.211Z,1683821076.211 [DATMMP](INFO): Handled 2023-05-11T16:04:36.460Z,1683821076.460 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:36.462Z,1683821076.462 [DATMMP](INFO): directional rx: x1BD x106 x441 xFEE2 xAF9 xFEE0 x0 x6B9 x2 x1 x2318600 x0 2023-05-11T16:04:36.464Z,1683821076.464 [DATMMP](INFO): Received message type: directional 2023-05-11T16:04:36.464Z,1683821076.464 [DATMMP](INFO): Handled 2023-05-11T16:04:36.712Z,1683821076.712 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:36.714Z,1683821076.714 [DATMMP](INFO): acstats rx: x5 x5 x0 x2D0 x22 x7D xB4 xB7 xD xFFFF xA3 x20 xC x0 2023-05-11T16:04:36.714Z,1683821076.714 [DATMMP](INFO): Received message type: acstats 2023-05-11T16:04:36.714Z,1683821076.714 [DATMMP](INFO): Handled 2023-05-11T16:04:36.970Z,1683821076.970 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:36.971Z,1683821076.971 [DATMMP](INFO): data_rx rx: x5 x0 x0 x23 2023-05-11T16:04:36.972Z,1683821076.972 [DATMMP](INFO): Check CRC 2023-05-11T16:04:36.972Z,1683821076.972 [DATMMP](INFO): data rx parseDataResponse 35 2023-05-11T16:04:36.972Z,1683821076.972 [DATMMP](INFO): Got DATA: len 35 2023-05-11T16:04:36.972Z,1683821076.972 [DATMMP](INFO): Received command: set _.temperature 23.816370 celsius 2023-05-11T16:04:36.972Z,1683821076.972 [DATMMP](INFO): #Outgoing data=1 2023-05-11T16:04:36.973Z,1683821076.973 [DATMMP](INFO): Sending ack 2023-05-11T16:04:36.973Z,1683821076.973 [DATMMP](INFO): Received message type: data_rx 2023-05-11T16:04:36.973Z,1683821076.973 [DATMMP](INFO): Handled 2023-05-11T16:04:36.973Z,1683821076.973 [DATMMP](INFO): *** SENDING_FILL_BUFFER *** 2023-05-11T16:04:36.973Z,1683821076.973 [DATMMP](ERROR): Waiting for previous to be ack'd 2023-05-11T16:04:36.973Z,1683821076.973 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA 2023-05-11T16:04:37.224Z,1683821077.224 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2023-05-11T16:04:37.224Z,1683821077.224 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT 2023-05-11T16:04:37.476Z,1683821077.476 [DATMMP](INFO): *** SENDING_TRANSMIT *** 2023-05-11T16:04:37.476Z,1683821077.476 [DATMMP](INFO): Tx to 5 len 4 xid 22 2023-05-11T16:04:37.477Z,1683821077.477 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2023-05-11T16:04:37.477Z,1683821077.477 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-11T16:04:37.728Z,1683821077.728 [DATMMP](INFO): GSXN notify for xid: 22 2023-05-11T16:04:37.729Z,1683821077.729 [DATMMP](INFO): cmd_result rx: x1 x7 x0 x1 2023-05-11T16:04:37.729Z,1683821077.729 [DATMMP](DEBUG): In rxCmdResult, sent ack so set commsState_ = SENDING_VERIFIED 2023-05-11T16:04:37.729Z,1683821077.729 [DATMMP](DEBUG): Command complete send 2023-05-11T16:04:37.729Z,1683821077.729 [DATMMP](ERROR): 1:COMPLETE 2023-05-11T16:04:37.730Z,1683821077.730 [DATMMP](INFO): Received message type: cmd_result 2023-05-11T16:04:37.730Z,1683821077.730 [DATMMP](INFO): Handled 2023-05-11T16:04:37.730Z,1683821077.730 [DATMMP](INFO): *** SENDING_VERIFIED *** 2023-05-11T16:04:37.730Z,1683821077.730 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER 2023-05-11T16:04:37.980Z,1683821077.980 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:37.981Z,1683821077.981 [DATMMP](INFO): timestamp rx: x0 x10 x4 x24 x2AF 2023-05-11T16:04:37.981Z,1683821077.981 [DATMMP](INFO): Received message type: timestamp 2023-05-11T16:04:37.981Z,1683821077.981 [DATMMP](INFO): Handled 2023-05-11T16:04:38.426Z,1683821078.426 [CommandExec](IMPORTANT): got command set _.temperature 23.816370 celsius 2023-05-11T16:04:45.812Z,1683821085.812 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:45.813Z,1683821085.813 [DATMMP](INFO): doppler rx: x0 2023-05-11T16:04:45.813Z,1683821085.813 [DATMMP](INFO): Received message type: doppler 2023-05-11T16:04:45.813Z,1683821085.813 [DATMMP](INFO): Handled 2023-05-11T16:04:46.060Z,1683821086.060 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:46.061Z,1683821086.061 [DATMMP](INFO): timestamp rx: x1 x10 x4 x2B x1FBE 2023-05-11T16:04:46.062Z,1683821086.062 [DATMMP](INFO): Rx dataTimestamp_ set to:1683821098.372736 2023-05-11T16:04:46.062Z,1683821086.062 [DATMMP](INFO): Rx ping set to:1683821086.061076 2023-05-11T16:04:46.062Z,1683821086.062 [DATMMP](INFO): Received message type: timestamp 2023-05-11T16:04:46.062Z,1683821086.062 [DATMMP](INFO): Handled 2023-05-11T16:04:46.564Z,1683821086.564 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:46.565Z,1683821086.565 [DATMMP](INFO): remote_header rx: xF x4 x5 x23 x0 xA x5 x0 2023-05-11T16:04:46.566Z,1683821086.566 [DATMMP](INFO): DATA Src=5, Dst=10 2023-05-11T16:04:46.566Z,1683821086.566 [DATMMP](INFO): Received message type: remote_header 2023-05-11T16:04:46.566Z,1683821086.566 [DATMMP](INFO): Handled 2023-05-11T16:04:47.068Z,1683821087.068 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:47.069Z,1683821087.069 [DATMMP](INFO): nav_stat rx: x0 x1 x1 2023-05-11T16:04:47.069Z,1683821087.069 [DATMMP](INFO): Received message type: nav_stat 2023-05-11T16:04:47.069Z,1683821087.069 [DATMMP](INFO): Handled 2023-05-11T16:04:47.322Z,1683821087.322 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:47.325Z,1683821087.325 [DATMMP](INFO): directional_debug rx: x193 x111 x20 x1AC x2B40 x4991 x7622 x71A3 2023-05-11T16:04:47.329Z,1683821087.329 [DATMMP](INFO): Received message type: directional_debug 2023-05-11T16:04:47.329Z,1683821087.329 [DATMMP](INFO): Handled 2023-05-11T16:04:47.572Z,1683821087.572 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:47.574Z,1683821087.574 [DATMMP](INFO): directional rx: x1B4 xFC x449 xFEEF xB00 xFEEE x0 x6B8 x3 xFFFF x2318600 x0 2023-05-11T16:04:47.576Z,1683821087.576 [DATMMP](INFO): Received message type: directional 2023-05-11T16:04:47.576Z,1683821087.576 [DATMMP](INFO): Handled 2023-05-11T16:04:47.825Z,1683821087.825 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:47.827Z,1683821087.827 [DATMMP](INFO): acstats rx: x5 x5 x0 x2D0 x23 x7E xC1 xBD xD xFFFF xA2 x23 xB x0 2023-05-11T16:04:47.827Z,1683821087.827 [DATMMP](INFO): Received message type: acstats 2023-05-11T16:04:47.827Z,1683821087.827 [DATMMP](INFO): Handled 2023-05-11T16:04:48.076Z,1683821088.076 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:48.077Z,1683821088.077 [DATMMP](INFO): data_rx rx: x5 x0 x0 x23 2023-05-11T16:04:48.077Z,1683821088.077 [DATMMP](INFO): Check CRC 2023-05-11T16:04:48.077Z,1683821088.077 [DATMMP](INFO): data rx parseDataResponse 35 2023-05-11T16:04:48.077Z,1683821088.077 [DATMMP](INFO): Got DATA: len 35 2023-05-11T16:04:48.078Z,1683821088.078 [DATMMP](INFO): Received command: set _.temperature 23.816370 celsius 2023-05-11T16:04:48.078Z,1683821088.078 [DATMMP](INFO): #Outgoing data=1 2023-05-11T16:04:48.078Z,1683821088.078 [DATMMP](INFO): Sending ack 2023-05-11T16:04:48.078Z,1683821088.078 [DATMMP](INFO): Received message type: data_rx 2023-05-11T16:04:48.078Z,1683821088.078 [DATMMP](INFO): Handled 2023-05-11T16:04:48.078Z,1683821088.078 [DATMMP](INFO): *** SENDING_FILL_BUFFER *** 2023-05-11T16:04:48.079Z,1683821088.079 [DATMMP](ERROR): Waiting for previous to be ack'd 2023-05-11T16:04:48.079Z,1683821088.079 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA 2023-05-11T16:04:48.328Z,1683821088.328 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2023-05-11T16:04:48.328Z,1683821088.328 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT 2023-05-11T16:04:48.580Z,1683821088.580 [DATMMP](INFO): *** SENDING_TRANSMIT *** 2023-05-11T16:04:48.580Z,1683821088.580 [DATMMP](INFO): Tx to 5 len 4 xid 23 2023-05-11T16:04:48.581Z,1683821088.581 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2023-05-11T16:04:48.581Z,1683821088.581 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-11T16:04:48.832Z,1683821088.832 [DATMMP](INFO): GSXN notify for xid: 23 2023-05-11T16:04:48.833Z,1683821088.833 [DATMMP](INFO): cmd_result rx: x1 x7 x0 x1 2023-05-11T16:04:48.833Z,1683821088.833 [DATMMP](DEBUG): In rxCmdResult, sent ack so set commsState_ = SENDING_VERIFIED 2023-05-11T16:04:48.833Z,1683821088.833 [DATMMP](DEBUG): Command complete send 2023-05-11T16:04:48.833Z,1683821088.833 [DATMMP](ERROR): 1:COMPLETE 2023-05-11T16:04:48.834Z,1683821088.834 [DATMMP](INFO): Received message type: cmd_result 2023-05-11T16:04:48.834Z,1683821088.834 [DATMMP](INFO): Handled 2023-05-11T16:04:48.834Z,1683821088.834 [DATMMP](INFO): *** SENDING_VERIFIED *** 2023-05-11T16:04:48.834Z,1683821088.834 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER 2023-05-11T16:04:49.084Z,1683821089.084 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:49.085Z,1683821089.085 [DATMMP](INFO): timestamp rx: x0 x10 x4 x2F x696 2023-05-11T16:04:49.085Z,1683821089.085 [DATMMP](INFO): Received message type: timestamp 2023-05-11T16:04:49.085Z,1683821089.085 [DATMMP](INFO): Handled 2023-05-11T16:04:49.409Z,1683821089.409 [CommandExec](IMPORTANT): got command set _.temperature 23.816370 celsius 2023-05-11T16:04:56.648Z,1683821096.648 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:56.649Z,1683821096.649 [DATMMP](INFO): doppler rx: x0 2023-05-11T16:04:56.649Z,1683821096.649 [DATMMP](INFO): Received message type: doppler 2023-05-11T16:04:56.649Z,1683821096.649 [DATMMP](INFO): Handled 2023-05-11T16:04:56.900Z,1683821096.900 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:56.901Z,1683821096.901 [DATMMP](INFO): timestamp rx: x1 x10 x4 x36 x19E1 2023-05-11T16:04:56.902Z,1683821096.902 [DATMMP](INFO): Rx dataTimestamp_ set to:1683821094.731136 2023-05-11T16:04:56.902Z,1683821096.902 [DATMMP](INFO): Rx ping set to:1683821096.901080 2023-05-11T16:04:56.902Z,1683821096.902 [DATMMP](INFO): Received message type: timestamp 2023-05-11T16:04:56.902Z,1683821096.902 [DATMMP](INFO): Handled 2023-05-11T16:04:57.404Z,1683821097.404 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:57.405Z,1683821097.405 [DATMMP](INFO): remote_header rx: xF x4 x5 x23 x0 xA x5 x0 2023-05-11T16:04:57.406Z,1683821097.406 [DATMMP](INFO): DATA Src=5, Dst=10 2023-05-11T16:04:57.406Z,1683821097.406 [DATMMP](INFO): Received message type: remote_header 2023-05-11T16:04:57.406Z,1683821097.406 [DATMMP](INFO): Handled 2023-05-11T16:04:57.908Z,1683821097.908 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:57.909Z,1683821097.909 [DATMMP](INFO): nav_stat rx: x0 x1 x1 2023-05-11T16:04:57.909Z,1683821097.909 [DATMMP](INFO): Received message type: nav_stat 2023-05-11T16:04:57.909Z,1683821097.909 [DATMMP](INFO): Handled 2023-05-11T16:04:58.160Z,1683821098.160 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:58.161Z,1683821098.161 [DATMMP](INFO): directional_debug rx: x19E x10B x1F x1EA x31F0 x49C1 x7672 x71B3 2023-05-11T16:04:58.162Z,1683821098.162 [DATMMP](INFO): Received message type: directional_debug 2023-05-11T16:04:58.162Z,1683821098.162 [DATMMP](INFO): Handled 2023-05-11T16:04:58.412Z,1683821098.412 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:58.414Z,1683821098.414 [DATMMP](INFO): directional rx: x1C1 xF7 x43E xFEF5 xAF7 xFEF5 x0 x6BA x3 xFFFF x2318600 x0 2023-05-11T16:04:58.416Z,1683821098.416 [DATMMP](INFO): Received message type: directional 2023-05-11T16:04:58.416Z,1683821098.416 [DATMMP](INFO): Handled 2023-05-11T16:04:58.664Z,1683821098.664 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:58.666Z,1683821098.666 [DATMMP](INFO): acstats rx: x5 x5 x0 x2D0 x23 x80 xB4 xBB xD xFFFF xA9 x20 xC x0 2023-05-11T16:04:58.666Z,1683821098.666 [DATMMP](INFO): Received message type: acstats 2023-05-11T16:04:58.666Z,1683821098.666 [DATMMP](INFO): Handled 2023-05-11T16:04:58.916Z,1683821098.916 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:58.917Z,1683821098.917 [DATMMP](INFO): data_rx rx: x5 x0 x0 x23 2023-05-11T16:04:58.917Z,1683821098.917 [DATMMP](INFO): Check CRC 2023-05-11T16:04:58.917Z,1683821098.917 [DATMMP](INFO): data rx parseDataResponse 35 2023-05-11T16:04:58.917Z,1683821098.917 [DATMMP](INFO): Got DATA: len 35 2023-05-11T16:04:58.918Z,1683821098.918 [DATMMP](INFO): Received command: set _.temperature 23.816370 celsius 2023-05-11T16:04:58.918Z,1683821098.918 [DATMMP](INFO): #Outgoing data=1 2023-05-11T16:04:58.918Z,1683821098.918 [DATMMP](INFO): Sending ack 2023-05-11T16:04:58.918Z,1683821098.918 [DATMMP](INFO): Received message type: data_rx 2023-05-11T16:04:58.918Z,1683821098.918 [DATMMP](INFO): Handled 2023-05-11T16:04:58.918Z,1683821098.918 [DATMMP](INFO): *** SENDING_FILL_BUFFER *** 2023-05-11T16:04:58.919Z,1683821098.919 [DATMMP](ERROR): Waiting for previous to be ack'd 2023-05-11T16:04:58.919Z,1683821098.919 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA 2023-05-11T16:04:59.168Z,1683821099.168 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2023-05-11T16:04:59.168Z,1683821099.168 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT 2023-05-11T16:04:59.420Z,1683821099.420 [DATMMP](INFO): *** SENDING_TRANSMIT *** 2023-05-11T16:04:59.420Z,1683821099.420 [DATMMP](INFO): Tx to 5 len 4 xid 24 2023-05-11T16:04:59.421Z,1683821099.421 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2023-05-11T16:04:59.421Z,1683821099.421 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-11T16:04:59.672Z,1683821099.672 [DATMMP](INFO): GSXN notify for xid: 24 2023-05-11T16:04:59.673Z,1683821099.673 [DATMMP](INFO): cmd_result rx: x1 x7 x0 x1 2023-05-11T16:04:59.673Z,1683821099.673 [DATMMP](DEBUG): In rxCmdResult, sent ack so set commsState_ = SENDING_VERIFIED 2023-05-11T16:04:59.673Z,1683821099.673 [DATMMP](DEBUG): Command complete send 2023-05-11T16:04:59.673Z,1683821099.673 [DATMMP](ERROR): 1:COMPLETE 2023-05-11T16:04:59.674Z,1683821099.674 [DATMMP](INFO): Received message type: cmd_result 2023-05-11T16:04:59.674Z,1683821099.674 [DATMMP](INFO): Handled 2023-05-11T16:04:59.674Z,1683821099.674 [DATMMP](INFO): *** SENDING_VERIFIED *** 2023-05-11T16:04:59.674Z,1683821099.674 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER 2023-05-11T16:04:59.924Z,1683821099.924 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:04:59.925Z,1683821099.925 [DATMMP](INFO): timestamp rx: x0 x10 x4 x3A xBA 2023-05-11T16:04:59.925Z,1683821099.925 [DATMMP](INFO): Received message type: timestamp 2023-05-11T16:04:59.925Z,1683821099.925 [DATMMP](INFO): Handled 2023-05-11T16:05:00.018Z,1683821100.018 [CommandExec](IMPORTANT): got command set _.temperature 23.816370 celsius 2023-05-11T16:05:07.484Z,1683821107.484 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:05:07.486Z,1683821107.486 [DATMMP](INFO): doppler rx: x0 2023-05-11T16:05:07.487Z,1683821107.487 [DATMMP](INFO): Received message type: doppler 2023-05-11T16:05:07.487Z,1683821107.487 [DATMMP](INFO): Handled 2023-05-11T16:05:07.736Z,1683821107.736 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:05:07.737Z,1683821107.737 [DATMMP](INFO): timestamp rx: x1 x10 x5 x5 x1211 2023-05-11T16:05:07.738Z,1683821107.738 [DATMMP](INFO): Rx dataTimestamp_ set to:1683821150.174080 2023-05-11T16:05:07.738Z,1683821107.738 [DATMMP](INFO): Rx ping set to:1683821107.737079 2023-05-11T16:05:07.738Z,1683821107.738 [DATMMP](INFO): Received message type: timestamp 2023-05-11T16:05:07.738Z,1683821107.738 [DATMMP](INFO): Handled 2023-05-11T16:05:07.988Z,1683821107.988 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:05:07.990Z,1683821107.990 [DATMMP](INFO): remote_header rx: xF x4 x5 x23 x0 xA x5 x0 2023-05-11T16:05:07.990Z,1683821107.990 [DATMMP](INFO): DATA Src=5, Dst=10 2023-05-11T16:05:07.990Z,1683821107.990 [DATMMP](INFO): Received message type: remote_header 2023-05-11T16:05:07.990Z,1683821107.990 [DATMMP](INFO): Handled 2023-05-11T16:05:08.493Z,1683821108.493 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:05:08.495Z,1683821108.495 [DATMMP](INFO): nav_stat rx: x0 x1 x1 2023-05-11T16:05:08.496Z,1683821108.496 [DATMMP](INFO): Received message type: nav_stat 2023-05-11T16:05:08.496Z,1683821108.496 [DATMMP](INFO): Handled 2023-05-11T16:05:08.744Z,1683821108.744 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:05:08.745Z,1683821108.745 [DATMMP](INFO): directional_debug rx: x19E x10F x1F x1F7 x31D0 x4951 x7782 x71B3 2023-05-11T16:05:08.746Z,1683821108.746 [DATMMP](INFO): Received message type: directional_debug 2023-05-11T16:05:08.746Z,1683821108.746 [DATMMP](INFO): Handled 2023-05-11T16:05:08.996Z,1683821108.996 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:05:08.998Z,1683821108.998 [DATMMP](INFO): directional rx: x1C0 xFB x43E xFEF1 xAF8 xFEEF x0 x6BB x3 x1 x2318600 x0 2023-05-11T16:05:08.000Z,1683821109.000 [DATMMP](INFO): Received message type: directional 2023-05-11T16:05:08.000Z,1683821109.000 [DATMMP](INFO): Handled 2023-05-11T16:05:09.248Z,1683821109.248 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:05:09.250Z,1683821109.250 [DATMMP](INFO): acstats rx: x5 x5 x0 x2D0 x22 x7F xB6 xB7 xD xFFFF xAA x1F xC x0 2023-05-11T16:05:09.250Z,1683821109.250 [DATMMP](INFO): Received message type: acstats 2023-05-11T16:05:09.250Z,1683821109.250 [DATMMP](INFO): Handled 2023-05-11T16:05:09.502Z,1683821109.502 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:05:09.503Z,1683821109.503 [DATMMP](INFO): data_rx rx: x5 x0 x0 x23 2023-05-11T16:05:09.503Z,1683821109.503 [DATMMP](INFO): Check CRC 2023-05-11T16:05:09.503Z,1683821109.503 [DATMMP](INFO): data rx parseDataResponse 35 2023-05-11T16:05:09.503Z,1683821109.503 [DATMMP](INFO): Got DATA: len 35 2023-05-11T16:05:09.503Z,1683821109.503 [DATMMP](INFO): Received command: set _.temperature 23.816370 celsius 2023-05-11T16:05:09.504Z,1683821109.504 [DATMMP](INFO): #Outgoing data=1 2023-05-11T16:05:09.504Z,1683821109.504 [DATMMP](INFO): Sending ack 2023-05-11T16:05:09.504Z,1683821109.504 [DATMMP](INFO): Received message type: data_rx 2023-05-11T16:05:09.504Z,1683821109.504 [DATMMP](INFO): Handled 2023-05-11T16:05:09.504Z,1683821109.504 [DATMMP](INFO): *** SENDING_FILL_BUFFER *** 2023-05-11T16:05:09.505Z,1683821109.505 [DATMMP](ERROR): Waiting for previous to be ack'd 2023-05-11T16:05:09.505Z,1683821109.505 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA 2023-05-11T16:05:09.752Z,1683821109.752 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2023-05-11T16:05:09.752Z,1683821109.752 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT 2023-05-11T16:05:10.004Z,1683821110.004 [DATMMP](INFO): *** SENDING_TRANSMIT *** 2023-05-11T16:05:10.004Z,1683821110.004 [DATMMP](INFO): Tx to 5 len 4 xid 25 2023-05-11T16:05:10.005Z,1683821110.005 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2023-05-11T16:05:10.005Z,1683821110.005 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-11T16:05:10.256Z,1683821110.256 [DATMMP](INFO): GSXN notify for xid: 25 2023-05-11T16:05:10.257Z,1683821110.257 [DATMMP](INFO): cmd_result rx: x1 x7 x0 x1 2023-05-11T16:05:10.257Z,1683821110.257 [DATMMP](DEBUG): In rxCmdResult, sent ack so set commsState_ = SENDING_VERIFIED 2023-05-11T16:05:10.257Z,1683821110.257 [DATMMP](DEBUG): Command complete send 2023-05-11T16:05:10.257Z,1683821110.257 [DATMMP](ERROR): 1:COMPLETE 2023-05-11T16:05:10.258Z,1683821110.258 [DATMMP](INFO): Received message type: cmd_result 2023-05-11T16:05:10.258Z,1683821110.258 [DATMMP](INFO): Handled 2023-05-11T16:05:10.258Z,1683821110.258 [DATMMP](INFO): *** SENDING_VERIFIED *** 2023-05-11T16:05:10.258Z,1683821110.258 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER 2023-05-11T16:05:10.508Z,1683821110.508 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:05:10.509Z,1683821110.509 [DATMMP](INFO): timestamp rx: x0 x10 x5 x8 x182A 2023-05-11T16:05:10.509Z,1683821110.509 [DATMMP](INFO): Received message type: timestamp 2023-05-11T16:05:10.509Z,1683821110.509 [DATMMP](INFO): Handled 2023-05-11T16:05:10.564Z,1683821110.564 [CommandExec](IMPORTANT): got command set _.temperature 23.816370 celsius 2023-05-11T16:05:18.572Z,1683821118.572 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:05:18.573Z,1683821118.573 [DATMMP](INFO): doppler rx: x0 2023-05-11T16:05:18.573Z,1683821118.573 [DATMMP](INFO): Received message type: doppler 2023-05-11T16:05:18.573Z,1683821118.573 [DATMMP](INFO): Handled 2023-05-11T16:05:18.824Z,1683821118.824 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:05:18.825Z,1683821118.825 [DATMMP](INFO): timestamp rx: x1 x10 x5 x10 x17ED 2023-05-11T16:05:18.826Z,1683821118.826 [DATMMP](INFO): Rx dataTimestamp_ set to:1683821175.854016 2023-05-11T16:05:18.826Z,1683821118.826 [DATMMP](INFO): Rx ping set to:1683821118.825160 2023-05-11T16:05:18.826Z,1683821118.826 [DATMMP](INFO): Received message type: timestamp 2023-05-11T16:05:18.826Z,1683821118.826 [DATMMP](INFO): Handled 2023-05-11T16:05:19.330Z,1683821119.330 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:05:19.334Z,1683821119.334 [DATMMP](INFO): remote_header rx: xF x4 x5 x20 x0 xA x5 x0 2023-05-11T16:05:19.335Z,1683821119.335 [DATMMP](INFO): DATA Src=5, Dst=10 2023-05-11T16:05:19.336Z,1683821119.336 [DATMMP](INFO): Received message type: remote_header 2023-05-11T16:05:19.336Z,1683821119.336 [DATMMP](INFO): Handled 2023-05-11T16:05:19.832Z,1683821119.832 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:05:19.833Z,1683821119.833 [DATMMP](INFO): nav_stat rx: x0 x1 x1 2023-05-11T16:05:19.833Z,1683821119.833 [DATMMP](INFO): Received message type: nav_stat 2023-05-11T16:05:19.833Z,1683821119.833 [DATMMP](INFO): Handled 2023-05-11T16:05:20.084Z,1683821120.084 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:05:20.085Z,1683821120.085 [DATMMP](INFO): directional_debug rx: x193 xE9 x1F x198 x3230 x4531 x6CF2 x6563 2023-05-11T16:05:20.086Z,1683821120.086 [DATMMP](INFO): Received message type: directional_debug 2023-05-11T16:05:20.086Z,1683821120.086 [DATMMP](INFO): Handled 2023-05-11T16:05:20.336Z,1683821120.336 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:05:20.338Z,1683821120.338 [DATMMP](INFO): directional rx: x1B8 xDD x449 xFF17 xB02 xFF14 x0 x6BA x3 x3 x2318600 x0 2023-05-11T16:05:20.340Z,1683821120.340 [DATMMP](INFO): Received message type: directional 2023-05-11T16:05:20.340Z,1683821120.340 [DATMMP](INFO): Handled 2023-05-11T16:05:20.593Z,1683821120.593 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:05:20.595Z,1683821120.595 [DATMMP](INFO): acstats rx: x5 x5 x0 x2D0 x23 x7D xAA xB0 xD xFFFF xA5 x23 xC x0 2023-05-11T16:05:20.595Z,1683821120.595 [DATMMP](INFO): Received message type: acstats 2023-05-11T16:05:20.595Z,1683821120.595 [DATMMP](INFO): Handled 2023-05-11T16:05:20.840Z,1683821120.840 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:05:20.841Z,1683821120.841 [DATMMP](INFO): data_rx rx: x5 x0 x0 x20 2023-05-11T16:05:20.841Z,1683821120.841 [DATMMP](INFO): Check CRC 2023-05-11T16:05:20.841Z,1683821120.841 [DATMMP](INFO): data rx parseDataResponse 32 2023-05-11T16:05:20.841Z,1683821120.841 [DATMMP](INFO): Got DATA: len 32 2023-05-11T16:05:20.842Z,1683821120.842 [DATMMP](INFO): Received command: set _.humidity 12.971553 percent 2023-05-11T16:05:20.842Z,1683821120.842 [DATMMP](INFO): #Outgoing data=1 2023-05-11T16:05:20.842Z,1683821120.842 [DATMMP](INFO): Sending ack 2023-05-11T16:05:20.842Z,1683821120.842 [DATMMP](INFO): Received message type: data_rx 2023-05-11T16:05:20.842Z,1683821120.842 [DATMMP](INFO): Handled 2023-05-11T16:05:20.842Z,1683821120.842 [DATMMP](INFO): *** SENDING_FILL_BUFFER *** 2023-05-11T16:05:20.843Z,1683821120.843 [DATMMP](ERROR): Waiting for previous to be ack'd 2023-05-11T16:05:20.843Z,1683821120.843 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA 2023-05-11T16:05:21.092Z,1683821121.092 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2023-05-11T16:05:21.092Z,1683821121.092 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT 2023-05-11T16:05:21.346Z,1683821121.346 [DATMMP](INFO): *** SENDING_TRANSMIT *** 2023-05-11T16:05:21.348Z,1683821121.348 [DATMMP](INFO): Tx to 5 len 4 xid 26 2023-05-11T16:05:21.351Z,1683821121.351 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2023-05-11T16:05:21.352Z,1683821121.352 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-11T16:05:21.596Z,1683821121.596 [DATMMP](INFO): GSXN notify for xid: 26 2023-05-11T16:05:21.597Z,1683821121.597 [DATMMP](INFO): cmd_result rx: x1 x7 x0 x1 2023-05-11T16:05:21.597Z,1683821121.597 [DATMMP](DEBUG): In rxCmdResult, sent ack so set commsState_ = SENDING_VERIFIED 2023-05-11T16:05:21.597Z,1683821121.597 [DATMMP](DEBUG): Command complete send 2023-05-11T16:05:21.597Z,1683821121.597 [DATMMP](ERROR): 1:COMPLETE 2023-05-11T16:05:21.598Z,1683821121.598 [DATMMP](INFO): Received message type: cmd_result 2023-05-11T16:05:21.598Z,1683821121.598 [DATMMP](INFO): Handled 2023-05-11T16:05:21.598Z,1683821121.598 [DATMMP](INFO): *** SENDING_VERIFIED *** 2023-05-11T16:05:21.598Z,1683821121.598 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER 2023-05-11T16:05:21.698Z,1683821121.698 [CommandExec](IMPORTANT): got command set _.humidity 12.971553 percent 2023-05-11T16:05:21.848Z,1683821121.848 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:05:21.849Z,1683821121.849 [DATMMP](INFO): timestamp rx: x0 x10 x5 x13 x23E2 2023-05-11T16:05:21.849Z,1683821121.849 [DATMMP](INFO): Received message type: timestamp 2023-05-11T16:05:21.849Z,1683821121.849 [DATMMP](INFO): Handled 2023-05-11T16:05:29.660Z,1683821129.660 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:05:29.661Z,1683821129.661 [DATMMP](INFO): doppler rx: x0 2023-05-11T16:05:29.661Z,1683821129.661 [DATMMP](INFO): Received message type: doppler 2023-05-11T16:05:29.661Z,1683821129.661 [DATMMP](INFO): Handled 2023-05-11T16:05:29.912Z,1683821129.912 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:05:29.913Z,1683821129.913 [DATMMP](INFO): timestamp rx: x1 x10 x5 x1B x19E0 2023-05-11T16:05:29.914Z,1683821129.914 [DATMMP](INFO): Rx dataTimestamp_ set to:1683821127.720896 2023-05-11T16:05:29.914Z,1683821129.914 [DATMMP](INFO): Rx ping set to:1683821129.913183 2023-05-11T16:05:29.914Z,1683821129.914 [DATMMP](INFO): Received message type: timestamp 2023-05-11T16:05:29.914Z,1683821129.914 [DATMMP](INFO): Handled 2023-05-11T16:05:30.416Z,1683821130.416 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:05:30.417Z,1683821130.417 [DATMMP](INFO): remote_header rx: xF x4 x5 x20 x0 xA x5 x0 2023-05-11T16:05:30.418Z,1683821130.418 [DATMMP](INFO): DATA Src=5, Dst=10 2023-05-11T16:05:30.418Z,1683821130.418 [DATMMP](INFO): Received message type: remote_header 2023-05-11T16:05:30.418Z,1683821130.418 [DATMMP](INFO): Handled 2023-05-11T16:05:30.669Z,1683821130.669 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:05:30.672Z,1683821130.672 [DATMMP](INFO): nav_stat rx: x0 x1 x1 2023-05-11T16:05:30.672Z,1683821130.672 [DATMMP](INFO): Received message type: nav_stat 2023-05-11T16:05:30.673Z,1683821130.673 [DATMMP](INFO): Handled 2023-05-11T16:05:30.920Z,1683821130.920 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:05:30.921Z,1683821130.921 [DATMMP](INFO): directional_debug rx: x191 x10A x21 x168 x2C20 x44D1 x6C32 x6433 2023-05-11T16:05:30.922Z,1683821130.922 [DATMMP](INFO): Received message type: directional_debug 2023-05-11T16:05:30.923Z,1683821130.923 [DATMMP](INFO): Handled 2023-05-11T16:05:31.172Z,1683821131.172 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:05:31.174Z,1683821131.174 [DATMMP](INFO): directional rx: x1B3 xF7 x44B xFEF6 xB05 xFEF4 x0 x6BA x2 x2 x2318600 x0 2023-05-11T16:05:31.176Z,1683821131.176 [DATMMP](INFO): Received message type: directional 2023-05-11T16:05:31.176Z,1683821131.176 [DATMMP](INFO): Handled 2023-05-11T16:05:31.424Z,1683821131.424 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:05:31.426Z,1683821131.426 [DATMMP](INFO): acstats rx: x5 x5 x0 x2D0 x23 x7D xAB xBC xD xFFFF xA5 x22 xC x0 2023-05-11T16:05:31.426Z,1683821131.426 [DATMMP](INFO): Received message type: acstats 2023-05-11T16:05:31.426Z,1683821131.426 [DATMMP](INFO): Handled 2023-05-11T16:05:31.676Z,1683821131.676 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:05:31.677Z,1683821131.677 [DATMMP](INFO): data_rx rx: x5 x0 x0 x20 2023-05-11T16:05:31.677Z,1683821131.677 [DATMMP](INFO): Check CRC 2023-05-11T16:05:31.677Z,1683821131.677 [DATMMP](INFO): data rx parseDataResponse 32 2023-05-11T16:05:31.677Z,1683821131.677 [DATMMP](INFO): Got DATA: len 32 2023-05-11T16:05:31.678Z,1683821131.678 [DATMMP](INFO): Received command: set _.humidity 12.971553 percent 2023-05-11T16:05:31.678Z,1683821131.678 [DATMMP](INFO): #Outgoing data=1 2023-05-11T16:05:31.678Z,1683821131.678 [DATMMP](INFO): Sending ack 2023-05-11T16:05:31.678Z,1683821131.678 [DATMMP](INFO): Received message type: data_rx 2023-05-11T16:05:31.678Z,1683821131.678 [DATMMP](INFO): Handled 2023-05-11T16:05:31.678Z,1683821131.678 [DATMMP](INFO): *** SENDING_FILL_BUFFER *** 2023-05-11T16:05:31.679Z,1683821131.679 [DATMMP](ERROR): Waiting for previous to be ack'd 2023-05-11T16:05:31.679Z,1683821131.679 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA 2023-05-11T16:05:32.192Z,1683821132.192 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2023-05-11T16:05:32.192Z,1683821132.192 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT 2023-05-11T16:05:32.439Z,1683821132.439 [CommandExec](IMPORTANT): got command set _.humidity 12.971553 percent 2023-05-11T16:05:32.444Z,1683821132.444 [DATMMP](INFO): *** SENDING_TRANSMIT *** 2023-05-11T16:05:32.444Z,1683821132.444 [DATMMP](INFO): Tx to 5 len 4 xid 27 2023-05-11T16:05:32.445Z,1683821132.445 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2023-05-11T16:05:32.445Z,1683821132.445 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-11T16:05:32.696Z,1683821132.696 [DATMMP](INFO): GSXN notify for xid: 27 2023-05-11T16:05:32.697Z,1683821132.697 [DATMMP](INFO): cmd_result rx: x1 x7 x0 x1 2023-05-11T16:05:32.697Z,1683821132.697 [DATMMP](DEBUG): In rxCmdResult, sent ack so set commsState_ = SENDING_VERIFIED 2023-05-11T16:05:32.697Z,1683821132.697 [DATMMP](DEBUG): Command complete send 2023-05-11T16:05:32.697Z,1683821132.697 [DATMMP](ERROR): 1:COMPLETE 2023-05-11T16:05:32.698Z,1683821132.698 [DATMMP](INFO): Received message type: cmd_result 2023-05-11T16:05:32.698Z,1683821132.698 [DATMMP](INFO): Handled 2023-05-11T16:05:32.698Z,1683821132.698 [DATMMP](INFO): *** SENDING_VERIFIED *** 2023-05-11T16:05:32.698Z,1683821132.698 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER 2023-05-11T16:05:32.948Z,1683821132.948 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:05:32.949Z,1683821132.949 [DATMMP](INFO): timestamp rx: x0 x10 x5 x1F xB9 2023-05-11T16:05:32.949Z,1683821132.949 [DATMMP](INFO): Received message type: timestamp 2023-05-11T16:05:32.949Z,1683821132.949 [DATMMP](INFO): Handled 2023-05-11T16:05:40.508Z,1683821140.508 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:05:40.509Z,1683821140.509 [DATMMP](INFO): doppler rx: x0 2023-05-11T16:05:40.509Z,1683821140.509 [DATMMP](INFO): Received message type: doppler 2023-05-11T16:05:40.509Z,1683821140.509 [DATMMP](INFO): Handled 2023-05-11T16:05:40.760Z,1683821140.760 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:05:40.761Z,1683821140.761 [DATMMP](INFO): timestamp rx: x1 x10 x5 x26 x1404 2023-05-11T16:05:40.762Z,1683821140.762 [DATMMP](INFO): Rx dataTimestamp_ set to:1683821188.040960 2023-05-11T16:05:40.762Z,1683821140.762 [DATMMP](INFO): Rx ping set to:1683821140.761098 2023-05-11T16:05:40.762Z,1683821140.762 [DATMMP](INFO): Received message type: timestamp 2023-05-11T16:05:40.762Z,1683821140.762 [DATMMP](INFO): Handled 2023-05-11T16:05:41.264Z,1683821141.264 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:05:41.265Z,1683821141.265 [DATMMP](INFO): remote_header rx: xF x4 x5 x20 x0 xA x5 x0 2023-05-11T16:05:41.266Z,1683821141.266 [DATMMP](INFO): DATA Src=5, Dst=10 2023-05-11T16:05:41.266Z,1683821141.266 [DATMMP](INFO): Received message type: remote_header 2023-05-11T16:05:41.266Z,1683821141.266 [DATMMP](INFO): Handled 2023-05-11T16:05:41.517Z,1683821141.517 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:05:41.519Z,1683821141.519 [DATMMP](INFO): nav_stat rx: x0 x1 x1 2023-05-11T16:05:41.520Z,1683821141.520 [DATMMP](INFO): Received message type: nav_stat 2023-05-11T16:05:41.520Z,1683821141.520 [DATMMP](INFO): Handled 2023-05-11T16:05:41.768Z,1683821141.768 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:05:41.769Z,1683821141.769 [DATMMP](INFO): directional_debug rx: x191 x10C x21 x1A4 x2BF0 x4841 x74F2 x6FB3 2023-05-11T16:05:41.771Z,1683821141.771 [DATMMP](INFO): Received message type: directional_debug 2023-05-11T16:05:41.771Z,1683821141.771 [DATMMP](INFO): Handled 2023-05-11T16:05:42.020Z,1683821142.020 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:05:42.022Z,1683821142.022 [DATMMP](INFO): directional rx: x1B3 xF8 x44B xFEF4 xB04 xFEF2 x0 x6B9 x2 x1 x2318600 x0 2023-05-11T16:05:42.024Z,1683821142.024 [DATMMP](INFO): Received message type: directional 2023-05-11T16:05:42.024Z,1683821142.024 [DATMMP](INFO): Handled 2023-05-11T16:05:42.272Z,1683821142.272 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:05:42.274Z,1683821142.274 [DATMMP](INFO): acstats rx: x5 x5 x0 x2D0 x23 x7E xC2 xC0 xD xFFFF xA7 x23 xC x0 2023-05-11T16:05:42.274Z,1683821142.274 [DATMMP](INFO): Received message type: acstats 2023-05-11T16:05:42.274Z,1683821142.274 [DATMMP](INFO): Handled 2023-05-11T16:05:42.524Z,1683821142.524 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:05:42.525Z,1683821142.525 [DATMMP](INFO): data_rx rx: x5 x0 x0 x20 2023-05-11T16:05:42.525Z,1683821142.525 [DATMMP](INFO): Check CRC 2023-05-11T16:05:42.525Z,1683821142.525 [DATMMP](INFO): data rx parseDataResponse 32 2023-05-11T16:05:42.525Z,1683821142.525 [DATMMP](INFO): Got DATA: len 32 2023-05-11T16:05:42.526Z,1683821142.526 [DATMMP](INFO): Received command: set _.humidity 12.971553 percent 2023-05-11T16:05:42.526Z,1683821142.526 [DATMMP](INFO): #Outgoing data=1 2023-05-11T16:05:42.526Z,1683821142.526 [DATMMP](INFO): Sending ack 2023-05-11T16:05:42.526Z,1683821142.526 [DATMMP](INFO): Received message type: data_rx 2023-05-11T16:05:42.526Z,1683821142.526 [DATMMP](INFO): Handled 2023-05-11T16:05:42.527Z,1683821142.527 [DATMMP](INFO): *** SENDING_FILL_BUFFER *** 2023-05-11T16:05:42.527Z,1683821142.527 [DATMMP](ERROR): Waiting for previous to be ack'd 2023-05-11T16:05:42.527Z,1683821142.527 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA 2023-05-11T16:05:42.776Z,1683821142.776 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2023-05-11T16:05:42.776Z,1683821142.776 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT 2023-05-11T16:05:43.028Z,1683821143.028 [DATMMP](INFO): *** SENDING_TRANSMIT *** 2023-05-11T16:05:43.028Z,1683821143.028 [DATMMP](INFO): Tx to 5 len 4 xid 28 2023-05-11T16:05:43.029Z,1683821143.029 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2023-05-11T16:05:43.029Z,1683821143.029 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-11T16:05:43.281Z,1683821143.281 [DATMMP](INFO): GSXN notify for xid: 28 2023-05-11T16:05:43.282Z,1683821143.282 [DATMMP](INFO): cmd_result rx: x1 x7 x0 x1 2023-05-11T16:05:43.282Z,1683821143.282 [DATMMP](DEBUG): In rxCmdResult, sent ack so set commsState_ = SENDING_VERIFIED 2023-05-11T16:05:43.282Z,1683821143.282 [DATMMP](DEBUG): Command complete send 2023-05-11T16:05:43.282Z,1683821143.282 [DATMMP](ERROR): 1:COMPLETE 2023-05-11T16:05:43.282Z,1683821143.282 [DATMMP](INFO): Received message type: cmd_result 2023-05-11T16:05:43.282Z,1683821143.282 [DATMMP](INFO): Handled 2023-05-11T16:05:43.283Z,1683821143.283 [DATMMP](INFO): *** SENDING_VERIFIED *** 2023-05-11T16:05:43.283Z,1683821143.283 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER 2023-05-11T16:05:43.279Z,1683821143.279 [CommandExec](IMPORTANT): got command set _.humidity 12.971553 percent 2023-05-11T16:05:43.532Z,1683821143.532 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:05:43.533Z,1683821143.533 [DATMMP](INFO): timestamp rx: x0 x10 x5 x29 x1829 2023-05-11T16:05:43.533Z,1683821143.533 [DATMMP](INFO): Received message type: timestamp 2023-05-11T16:05:43.533Z,1683821143.533 [DATMMP](INFO): Handled 2023-05-11T16:05:51.346Z,1683821151.346 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:05:51.350Z,1683821151.350 [DATMMP](INFO): doppler rx: x0 2023-05-11T16:05:51.352Z,1683821151.352 [DATMMP](INFO): Received message type: doppler 2023-05-11T16:05:51.352Z,1683821151.352 [DATMMP](INFO): Handled 2023-05-11T16:05:51.596Z,1683821151.596 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:05:51.597Z,1683821151.597 [DATMMP](INFO): timestamp rx: x1 x10 x5 x31 xE27 2023-05-11T16:05:51.598Z,1683821151.598 [DATMMP](INFO): Rx dataTimestamp_ set to:1683821184.399360 2023-05-11T16:05:51.598Z,1683821151.598 [DATMMP](INFO): Rx ping set to:1683821151.597125 2023-05-11T16:05:51.598Z,1683821151.598 [DATMMP](INFO): Received message type: timestamp 2023-05-11T16:05:51.598Z,1683821151.598 [DATMMP](INFO): Handled 2023-05-11T16:05:52.100Z,1683821152.100 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:05:52.101Z,1683821152.101 [DATMMP](INFO): remote_header rx: xF x4 x5 x20 x0 xA x5 x0 2023-05-11T16:05:52.102Z,1683821152.102 [DATMMP](INFO): DATA Src=5, Dst=10 2023-05-11T16:05:52.102Z,1683821152.102 [DATMMP](INFO): Received message type: remote_header 2023-05-11T16:05:52.102Z,1683821152.102 [DATMMP](INFO): Handled 2023-05-11T16:05:52.353Z,1683821152.353 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:05:52.355Z,1683821152.355 [DATMMP](INFO): nav_stat rx: x0 x1 x1 2023-05-11T16:05:52.356Z,1683821152.356 [DATMMP](INFO): Received message type: nav_stat 2023-05-11T16:05:52.356Z,1683821152.356 [DATMMP](INFO): Handled 2023-05-11T16:05:52.604Z,1683821152.604 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:05:52.605Z,1683821152.605 [DATMMP](INFO): directional_debug rx: x1AB x107 x20 x1C6 x31E0 x3FF1 x6762 x6333 2023-05-11T16:05:52.607Z,1683821152.607 [DATMMP](INFO): Received message type: directional_debug 2023-05-11T16:05:52.607Z,1683821152.607 [DATMMP](INFO): Handled 2023-05-11T16:05:52.856Z,1683821152.856 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:05:52.858Z,1683821152.858 [DATMMP](INFO): directional rx: x1D0 xF4 x431 xFEF9 xAEA xFEFA x0 x6B8 x0 xFFFF x2318600 x0 2023-05-11T16:05:52.861Z,1683821152.861 [DATMMP](INFO): Received message type: directional 2023-05-11T16:05:52.861Z,1683821152.861 [DATMMP](INFO): Handled 2023-05-11T16:05:53.108Z,1683821153.108 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:05:53.110Z,1683821153.110 [DATMMP](INFO): acstats rx: x5 x5 x0 x2D0 x23 x80 xBB xBB xD xFFFF xA7 x23 xC x0 2023-05-11T16:05:53.110Z,1683821153.110 [DATMMP](INFO): Received message type: acstats 2023-05-11T16:05:53.110Z,1683821153.110 [DATMMP](INFO): Handled 2023-05-11T16:05:53.362Z,1683821153.362 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:05:53.364Z,1683821153.364 [DATMMP](INFO): data_rx rx: x5 x0 x0 x20 2023-05-11T16:05:53.364Z,1683821153.364 [DATMMP](INFO): Check CRC 2023-05-11T16:05:53.364Z,1683821153.364 [DATMMP](INFO): data rx parseDataResponse 32 2023-05-11T16:05:53.365Z,1683821153.365 [DATMMP](INFO): Got DATA: len 32 2023-05-11T16:05:53.365Z,1683821153.365 [DATMMP](INFO): Received command: set _.humidity 12.971553 percent 2023-05-11T16:05:53.365Z,1683821153.365 [DATMMP](INFO): #Outgoing data=1 2023-05-11T16:05:53.365Z,1683821153.365 [DATMMP](INFO): Sending ack 2023-05-11T16:05:53.366Z,1683821153.366 [DATMMP](INFO): Received message type: data_rx 2023-05-11T16:05:53.366Z,1683821153.366 [DATMMP](INFO): Handled 2023-05-11T16:05:53.366Z,1683821153.366 [DATMMP](INFO): *** SENDING_FILL_BUFFER *** 2023-05-11T16:05:53.366Z,1683821153.366 [DATMMP](ERROR): Waiting for previous to be ack'd 2023-05-11T16:05:53.366Z,1683821153.366 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA 2023-05-11T16:05:53.612Z,1683821153.612 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2023-05-11T16:05:53.612Z,1683821153.612 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT 2023-05-11T16:05:53.864Z,1683821153.864 [DATMMP](INFO): *** SENDING_TRANSMIT *** 2023-05-11T16:05:53.864Z,1683821153.864 [DATMMP](INFO): Tx to 5 len 4 xid 29 2023-05-11T16:05:53.865Z,1683821153.865 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2023-05-11T16:05:53.865Z,1683821153.865 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-11T16:05:54.117Z,1683821154.117 [DATMMP](INFO): GSXN notify for xid: 29 2023-05-11T16:05:54.117Z,1683821154.117 [DATMMP](INFO): cmd_result rx: x1 x7 x0 x1 2023-05-11T16:05:54.118Z,1683821154.118 [DATMMP](DEBUG): In rxCmdResult, sent ack so set commsState_ = SENDING_VERIFIED 2023-05-11T16:05:54.118Z,1683821154.118 [DATMMP](DEBUG): Command complete send 2023-05-11T16:05:54.118Z,1683821154.118 [DATMMP](ERROR): 1:COMPLETE 2023-05-11T16:05:54.118Z,1683821154.118 [DATMMP](INFO): Received message type: cmd_result 2023-05-11T16:05:54.118Z,1683821154.118 [DATMMP](INFO): Handled 2023-05-11T16:05:54.119Z,1683821154.119 [DATMMP](INFO): *** SENDING_VERIFIED *** 2023-05-11T16:05:54.119Z,1683821154.119 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER 2023-05-11T16:05:54.368Z,1683821154.368 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-11T16:05:54.369Z,1683821154.369 [DATMMP](INFO): timestamp rx: x0 x10 x5 x34 x124C 2023-05-11T16:05:54.369Z,1683821154.369 [DATMMP](INFO): Received message type: timestamp 2023-05-11T16:05:54.369Z,1683821154.369 [DATMMP](INFO): Handled 2023-05-11T16