2025-11-11T01:10:01.759Z,1762823401.759 [CommandExec](IMPORTANT): got command set marl_lab.SendObservationDataInterval 1 minute 2025-11-11T01:10:01.762Z,1762823401.762 [CommandExec](IMPORTANT): got command restart logs 2025-11-11T01:10:06.993Z,1762823406.993 [CommandExec](IMPORTANT): got command run 2025-11-11T01:10:06.994Z,1762823406.994 [CommandExec](IMPORTANT): Running 2025-11-11T01:10:07.177Z,1762823407.177 [Default] Stopped 2025-11-11T01:10:07.177Z,1762823407.177 [Default](DEBUG): Aggregate::uninitialize Default 2025-11-11T01:10:07.177Z,1762823407.177 [Default:B.GoToSurface] Stopped 2025-11-11T01:10:07.177Z,1762823407.177 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-11-11T01:10:07.177Z,1762823407.177 [Default:CheckIn] Stopped 2025-11-11T01:10:07.177Z,1762823407.177 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-11-11T01:10:07.177Z,1762823407.177 [Default:CheckIn:C.Wait] Stopped 2025-11-11T01:10:07.178Z,1762823407.178 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-11-11T01:10:07.178Z,1762823407.178 [MissionManager](IMPORTANT): Started mission marl_lab 2025-11-11T01:10:07.178Z,1762823407.178 [marl_lab] Running Loop=1 2025-11-11T01:10:07.178Z,1762823407.178 [marl_lab](DEBUG): Aggregate::initialize marl_lab 2025-11-11T01:10:07.178Z,1762823407.178 [marl_lab:AbortDrift] Running Loop=1 2025-11-11T01:10:07.178Z,1762823407.178 [marl_lab:AbortDrift](DEBUG): Aggregate::initialize marl_lab:AbortDrift 2025-11-11T01:10:07.178Z,1762823407.178 [marl_lab:AbortDrift:A] Running Loop=1 2025-11-11T01:10:07.179Z,1762823407.179 [marl_lab:B.] Running Loop=1 2025-11-11T01:10:07.179Z,1762823407.179 [marl_lab:B.](INFO): Initializing TrackAcousticContact. 2025-11-11T01:10:07.179Z,1762823407.179 [marl_lab:SendObservationData] Running Loop=1 2025-11-11T01:10:07.179Z,1762823407.179 [marl_lab:SendObservationData](DEBUG): Aggregate::initialize marl_lab:SendObservationData 2025-11-11T01:10:07.179Z,1762823407.179 [marl_lab:SendObservationData:D.SendDirect] Running Loop=1 2025-11-11T01:10:07.186Z,1762823407.186 [marl_lab:SendObservationData:A] Running Loop=1 2025-11-11T01:10:07.186Z,1762823407.186 [marl_lab:BackseatDriver] Running Loop=1 2025-11-11T01:10:07.186Z,1762823407.186 [marl_lab:BackseatDriver](DEBUG): Aggregate::initialize marl_lab:BackseatDriver 2025-11-11T01:10:07.186Z,1762823407.186 [marl_lab:BackseatDriver:A.BackseatDriver] Running Loop=1 2025-11-11T01:10:07.186Z,1762823407.186 [marl_lab:PowerOnly] Running Loop=1 2025-11-11T01:10:07.186Z,1762823407.186 [marl_lab:PowerOnly](DEBUG): Aggregate::initialize marl_lab:PowerOnly 2025-11-11T01:10:07.186Z,1762823407.186 [marl_lab:PowerOnly:A] Running Loop=1 2025-11-11T01:10:07.186Z,1762823407.186 [marl_lab:PowerOnly:B] Running Loop=1 2025-11-11T01:10:07.186Z,1762823407.186 [marl_lab:PowerOnly:C] Running Loop=1 2025-11-11T01:10:07.186Z,1762823407.187 [marl_lab:PowerOnly:D] Running Loop=1 2025-11-11T01:10:07.187Z,1762823407.187 [marl_lab:PowerOnly:E.Wait] Running Loop=1 2025-11-11T01:10:07.187Z,1762823407.187 [marl_lab:PowerOnly:E.Wait](DEBUG): Initialize Wait Component. 2025-11-11T01:10:07.187Z,1762823407.187 [marl_lab:F] Running Loop=1 2025-11-11T01:10:07.187Z,1762823407.187 [marl_lab:PowerOnly] Running Loop=1 2025-11-11T01:10:07.192Z,1762823407.192 [marl_lab:PowerOnly:D] Stopped 2025-11-11T01:10:07.192Z,1762823407.192 [marl_lab:PowerOnly:C] Stopped 2025-11-11T01:10:07.192Z,1762823407.192 [marl_lab:PowerOnly:B] Stopped 2025-11-11T01:10:07.192Z,1762823407.192 [marl_lab:PowerOnly:A] Stopped 2025-11-11T01:10:07.192Z,1762823407.192 [marl_lab:F] Stopped 2025-11-11T01:10:07.192Z,1762823407.192 [marl_lab:H.Wait] Running Loop=1 2025-11-11T01:10:07.192Z,1762823407.192 [marl_lab:H.Wait](DEBUG): Initialize Wait Component. 2025-11-11T01:10:07.193Z,1762823407.193 [marl_lab:BackseatDriver] Running Loop=1 2025-11-11T01:10:07.193Z,1762823407.193 [marl_lab:BackseatDriver:A.BackseatDriver](INFO): Initializing backseat 2025-11-11T01:10:07.226Z,1762823407.226 [marl_lab:SendObservationData] Stopped 2025-11-11T01:10:07.226Z,1762823407.226 [marl_lab:SendObservationData](DEBUG): Aggregate::uninitialize marl_lab:SendObservationData 2025-11-11T01:10:07.226Z,1762823407.226 [marl_lab:SendObservationData:A] Stopped 2025-11-11T01:10:07.226Z,1762823407.226 [marl_lab:SendObservationData:D.SendDirect] Stopped 2025-11-11T01:10:07.226Z,1762823407.226 [marl_lab:B.] Running Loop=1 2025-11-11T01:10:07.226Z,1762823407.226 [marl_lab:AbortDrift] Running Loop=1 2025-11-11T01:10:07.227Z,1762823407.227 [marl_lab:AbortDrift:A](INFO): Insert acoustic timeout set to 1.000000 h . 2025-11-11T01:10:07.227Z,1762823407.227 [marl_lab:AbortDrift:A] Stopped 2025-11-11T01:10:07.227Z,1762823407.227 [marl_lab:AbortDrift:B.AbortDrift] Running Loop=1 2025-11-11T01:10:07.227Z,1762823407.227 [marl_lab:AbortDrift:B.AbortDrift](INFO): Initializing AbortDrift. 2025-11-11T01:10:07.228Z,1762823407.228 [marl_lab:AbortDrift:B.AbortDrift](IMPORTANT): Acoustic timeout set to: 1.00 hours. 2025-11-11T01:10:07.552Z,1762823407.552 [BackseatComponent](INFO): Powering up 2025-11-11T01:10:07.552Z,1762823407.552 [BackseatComponent](INFO): Subscribing to LCM channels. 2025-11-11T01:10:10.197Z,1762823410.197 [CTD_Seabird](ERROR): Failed to parse device response: 2025-11-11T01:10:12.206Z,1762823412.206 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287123 psu 2025-11-11T01:10:12.208Z,1762823412.208 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:10:13.210Z,1762823413.210 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287070 psu 2025-11-11T01:10:13.211Z,1762823413.211 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:10:14.214Z,1762823414.214 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287075 psu 2025-11-11T01:10:14.215Z,1762823414.215 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:10:15.218Z,1762823415.218 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287072 psu 2025-11-11T01:10:15.219Z,1762823415.219 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:10:15.634Z,1762823415.634 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-11-11T01:10:15.634Z,1762823415.634 [NAL9602] Data Fault, FailCount= 4 2025-11-11T01:10:15.634Z,1762823415.634 [NAL9602](ERROR): Data Fault 2025-11-11T01:10:15.684Z,1762823415.684 [CBIT](ERROR): Data Fault in component: NAL9602 2025-11-11T01:10:16.033Z,1762823416.033 [NAL9602](INFO): Powering down 2025-11-11T01:10:16.222Z,1762823416.222 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287070 psu 2025-11-11T01:10:16.223Z,1762823416.223 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:10:16.876Z,1762823416.876 [CBIT](INFO): Clearing failed state for component NAL9602 2025-11-11T01:10:16.876Z,1762823416.876 [NAL9602] No Fault, FailCount= 4 2025-11-11T01:10:17.226Z,1762823417.226 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287074 psu 2025-11-11T01:10:17.227Z,1762823417.227 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:10:18.230Z,1762823418.230 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287071 psu 2025-11-11T01:10:18.231Z,1762823418.231 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:10:19.234Z,1762823419.234 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287074 psu 2025-11-11T01:10:19.235Z,1762823419.235 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:10:20.238Z,1762823420.238 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287068 psu 2025-11-11T01:10:20.239Z,1762823420.239 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:10:21.242Z,1762823421.242 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287068 psu 2025-11-11T01:10:21.243Z,1762823421.243 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:10:22.246Z,1762823422.246 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287071 psu 2025-11-11T01:10:22.247Z,1762823422.247 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:10:23.250Z,1762823423.250 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287074 psu 2025-11-11T01:10:23.251Z,1762823423.251 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:10:24.254Z,1762823424.254 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287070 psu 2025-11-11T01:10:24.255Z,1762823424.255 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:10:25.258Z,1762823425.258 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287070 psu 2025-11-11T01:10:25.259Z,1762823425.259 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:10:26.262Z,1762823426.262 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287068 psu 2025-11-11T01:10:26.263Z,1762823426.263 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:10:27.266Z,1762823427.266 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287069 psu 2025-11-11T01:10:27.267Z,1762823427.267 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:10:28.270Z,1762823428.270 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287069 psu 2025-11-11T01:10:28.271Z,1762823428.271 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:10:29.274Z,1762823429.274 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287015 psu 2025-11-11T01:10:29.275Z,1762823429.275 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:10:30.278Z,1762823430.278 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287019 psu 2025-11-11T01:10:30.279Z,1762823430.279 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:10:31.282Z,1762823431.282 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287021 psu 2025-11-11T01:10:31.283Z,1762823431.283 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:10:32.286Z,1762823432.286 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287022 psu 2025-11-11T01:10:32.287Z,1762823432.287 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:10:33.290Z,1762823433.290 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287018 psu 2025-11-11T01:10:33.291Z,1762823433.291 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:10:34.294Z,1762823434.294 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287022 psu 2025-11-11T01:10:34.295Z,1762823434.295 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:10:35.298Z,1762823435.298 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287019 psu 2025-11-11T01:10:35.299Z,1762823435.299 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:10:36.302Z,1762823436.302 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287019 psu 2025-11-11T01:10:36.303Z,1762823436.303 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:10:37.306Z,1762823437.306 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287019 psu 2025-11-11T01:10:37.307Z,1762823437.307 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:10:38.310Z,1762823438.310 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287021 psu 2025-11-11T01:10:38.311Z,1762823438.311 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:10:39.316Z,1762823439.316 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287023 psu 2025-11-11T01:10:39.321Z,1762823439.321 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:10:40.319Z,1762823440.319 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287020 psu 2025-11-11T01:10:40.321Z,1762823440.321 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:10:41.322Z,1762823441.322 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287074 psu 2025-11-11T01:10:41.323Z,1762823441.323 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:10:42.326Z,1762823442.326 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287072 psu 2025-11-11T01:10:42.327Z,1762823442.327 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:10:43.330Z,1762823443.330 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287072 psu 2025-11-11T01:10:43.331Z,1762823443.331 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:10:44.334Z,1762823444.334 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287019 psu 2025-11-11T01:10:44.335Z,1762823444.335 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:10:45.338Z,1762823445.338 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287021 psu 2025-11-11T01:10:45.340Z,1762823445.340 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:10:46.346Z,1762823446.346 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287022 psu 2025-11-11T01:10:46.347Z,1762823446.347 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:10:46.361Z,1762823446.361 [NAL9602](INFO): Powering up NAL9602 2025-11-11T01:10:47.350Z,1762823447.350 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287019 psu 2025-11-11T01:10:47.351Z,1762823447.351 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:10:48.354Z,1762823448.354 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287074 psu 2025-11-11T01:10:48.355Z,1762823448.355 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:10:48.404Z,1762823448.404 [marl_lab:SendObservationData] Running Loop=1 2025-11-11T01:10:48.404Z,1762823448.404 [marl_lab:SendObservationData](DEBUG): Aggregate::initialize marl_lab:SendObservationData 2025-11-11T01:10:48.404Z,1762823448.404 [marl_lab:SendObservationData:D.SendDirect] Running Loop=1 2025-11-11T01:10:48.405Z,1762823448.405 [marl_lab:SendObservationData:A] Running Loop=1 2025-11-11T01:10:48.405Z,1762823448.405 [marl_lab:SendObservationData] Running Loop=1 2025-11-11T01:10:48.405Z,1762823448.405 [marl_lab:SendObservationData:D.SendDirect] Running Loop=1 2025-11-11T01:10:48.406Z,1762823448.406 [marl_lab:SendObservationData:D.SendDirect](DEBUG): SendData::Push: Added data for modem://10:_.others_observations, buffer was empty 2025-11-11T01:10:48.406Z,1762823448.406 [marl_lab:SendObservationData:A](ERROR): data element is not active. 2025-11-11T01:10:48.407Z,1762823448.407 [marl_lab:SendObservationData:A](INFO): Got test_good : n/a str and temp var is NaN n/a str 2025-11-11T01:10:48.407Z,1762823448.407 [marl_lab:SendObservationData:A] Stopped 2025-11-11T01:10:48.407Z,1762823448.407 [marl_lab:SendObservationData:B] Running Loop=1 2025-11-11T01:10:48.634Z,1762823448.634 [DAT](INFO): modem://10: set _.others_observations string "NaN" 2025-11-11T01:10:48.634Z,1762823448.634 [DAT](INFO): #Outgoing data=1 2025-11-11T01:10:48.634Z,1762823448.634 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT 2025-11-11T01:10:48.785Z,1762823448.785 [marl_lab:SendObservationData:D.SendDirect](DEBUG): SendData::Push: Added data for modem://10:_.others_observations, buffer was empty 2025-11-11T01:10:48.785Z,1762823448.785 [marl_lab:SendObservationData:B] Stopped 2025-11-11T01:10:48.785Z,1762823448.785 [marl_lab:SendObservationData:C] Running Loop=1 2025-11-11T01:10:48.885Z,1762823448.885 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2025-11-11T01:10:49.189Z,1762823449.189 [marl_lab:SendObservationData:D.SendDirect](DEBUG): SendData::Push: Added data for modem://10:_.others_observations, buffer was not empty 2025-11-11T01:10:49.189Z,1762823449.189 [marl_lab:SendObservationData:C] Stopped 2025-11-11T01:10:49.189Z,1762823449.189 [marl_lab:SendObservationData:E] Running Loop=1 2025-11-11T01:10:49.358Z,1762823449.358 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287072 psu 2025-11-11T01:10:49.359Z,1762823449.359 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:10:49.596Z,1762823449.596 [marl_lab:SendObservationData] Stopped 2025-11-11T01:10:49.596Z,1762823449.596 [marl_lab:SendObservationData](DEBUG): Aggregate::uninitialize marl_lab:SendObservationData 2025-11-11T01:10:49.596Z,1762823449.596 [marl_lab:SendObservationData:D.SendDirect] Stopped 2025-11-11T01:10:49.596Z,1762823449.596 [marl_lab:SendObservationData:E] Stopped 2025-11-11T01:10:50.362Z,1762823450.362 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287074 psu 2025-11-11T01:10:50.363Z,1762823450.363 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:10:51.366Z,1762823451.366 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287072 psu 2025-11-11T01:10:51.367Z,1762823451.367 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:10:52.161Z,1762823452.161 [DAT](INFO): DAT read: Forwarding Delay UpTx time:01:10:51.2332 2025-11-11T01:10:52.161Z,1762823452.161 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING 2025-11-11T01:10:52.370Z,1762823452.370 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287072 psu 2025-11-11T01:10:52.371Z,1762823452.371 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:10:53.374Z,1762823453.374 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287017 psu 2025-11-11T01:10:53.375Z,1762823453.375 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:10:54.378Z,1762823454.378 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287018 psu 2025-11-11T01:10:54.379Z,1762823454.379 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:10:55.382Z,1762823455.382 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287023 psu 2025-11-11T01:10:55.383Z,1762823455.383 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:10:56.386Z,1762823456.386 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287017 psu 2025-11-11T01:10:56.387Z,1762823456.387 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:10:57.239Z,1762823457.239 [NAL9602](INFO): NAL9602 initialized 2025-11-11T01:10:57.390Z,1762823457.390 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287020 psu 2025-11-11T01:10:57.391Z,1762823457.391 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:10:58.394Z,1762823458.394 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287017 psu 2025-11-11T01:10:58.395Z,1762823458.395 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:10:58.461Z,1762823458.461 [DAT](INFO): DAT read: Rx Time:01:10:57.0034 2025-11-11T01:10:58.461Z,1762823458.461 [DAT](INFO): Rx dataTimestamp_ set to:1762823458.461167 2025-11-11T01:10:59.217Z,1762823459.217 [DAT](INFO): DAT read: 2025-11-11T01:10:59.218Z,1762823459.218 [DAT](INFO): DAT read: DATA(0002):~~ 2025-11-11T01:10:59.218Z,1762823459.218 [DAT](INFO): Got DATA 2 2025-11-11T01:10:59.219Z,1762823459.219 [DAT](INFO): DAT read: Source:010 Destination:001 2025-11-11T01:10:59.219Z,1762823459.219 [DAT](INFO): Got Src/Dest after DATA 2025-11-11T01:10:59.219Z,1762823459.219 [DAT](INFO): DATA Src=10, Dst=1 2025-11-11T01:10:59.221Z,1762823459.221 [DAT](INFO): DAT read: CRC:Pass MPD:03.4 PSNR:20.4 AGC:18 SPD:+0.0 CCERR:013 2025-11-11T01:10:59.221Z,1762823459.221 [DAT](INFO): Got CRC:Pass 2025-11-11T01:10:59.221Z,1762823459.221 [DAT](INFO): Got CRC:Pass 2025-11-11T01:10:59.221Z,1762823459.221 [DAT](INFO): Incoming data is intended for us 2025-11-11T01:10:59.222Z,1762823459.222 [DAT](DEBUG): parseResponses: Got ACK. Before transition: sendDataBuffer_ isEmpty=0, outgoingCommsBuffer_ size=0, outgoingCommsNow_=NOT NULL 2025-11-11T01:10:59.222Z,1762823459.222 [DAT](DEBUG): In parseResponses, got ack so set commsState_ = SENDING_VERIFIED 2025-11-11T01:10:59.222Z,1762823459.222 [DAT](INFO): Got ack 2025-11-11T01:10:59.222Z,1762823459.222 [DAT](INFO): DAT read: 2025-11-11T01:10:59.222Z,1762823459.222 [DAT](INFO): DAT read: 2025-11-11T01:10:59.223Z,1762823459.223 [DAT](DEBUG): sendingVerified: ACK received. sendDataBuffer_ isEmpty=0, outgoingCommsBuffer_ size=0 2025-11-11T01:10:59.223Z,1762823459.223 [DAT](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER 2025-11-11T01:10:59.399Z,1762823459.399 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287016 psu 2025-11-11T01:10:59.401Z,1762823459.401 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:10:59.469Z,1762823459.469 [DAT](INFO): modem://10: set _.others_observations string "NaN" 2025-11-11T01:10:59.469Z,1762823459.469 [DAT](INFO): #Outgoing data=1 2025-11-11T01:10:59.470Z,1762823459.470 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT 2025-11-11T01:10:59.721Z,1762823459.721 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2025-11-11T01:11:00.402Z,1762823460.402 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287020 psu 2025-11-11T01:11:00.403Z,1762823460.403 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:11:01.406Z,1762823461.406 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287021 psu 2025-11-11T01:11:01.407Z,1762823461.407 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:11:02.410Z,1762823462.410 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287021 psu 2025-11-11T01:11:02.411Z,1762823462.411 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:11:03.001Z,1762823463.001 [DAT](INFO): DAT read: Forwarding Delay UpTx time:01:11:02.0833 2025-11-11T01:11:03.001Z,1762823463.001 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING 2025-11-11T01:11:03.414Z,1762823463.414 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287021 psu 2025-11-11T01:11:03.415Z,1762823463.415 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:11:04.419Z,1762823464.419 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287017 psu 2025-11-11T01:11:04.420Z,1762823464.420 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:11:05.422Z,1762823465.422 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287019 psu 2025-11-11T01:11:05.423Z,1762823465.423 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:11:06.426Z,1762823466.426 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287019 psu 2025-11-11T01:11:06.427Z,1762823466.427 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:11:07.430Z,1762823467.430 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287018 psu 2025-11-11T01:11:07.431Z,1762823467.431 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:11:08.434Z,1762823468.434 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287019 psu 2025-11-11T01:11:08.435Z,1762823468.435 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:11:09.305Z,1762823469.305 [DAT](INFO): DAT read: Rx Time:01:11:07.8535 2025-11-11T01:11:09.306Z,1762823469.306 [DAT](INFO): Rx dataTimestamp_ set to:1762823469.305240 2025-11-11T01:11:09.438Z,1762823469.438 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287017 psu 2025-11-11T01:11:09.439Z,1762823469.439 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:11:10.061Z,1762823470.061 [DAT](INFO): DAT read: 2025-11-11T01:11:10.061Z,1762823470.061 [DAT](INFO): DAT read: DATA(0002):~~ 2025-11-11T01:11:10.061Z,1762823470.061 [DAT](INFO): Got DATA 2 2025-11-11T01:11:10.062Z,1762823470.062 [DAT](INFO): DAT read: Source:010 Destination:001 2025-11-11T01:11:10.062Z,1762823470.062 [DAT](INFO): Got Src/Dest after DATA 2025-11-11T01:11:10.063Z,1762823470.063 [DAT](INFO): DATA Src=10, Dst=1 2025-11-11T01:11:10.064Z,1762823470.064 [DAT](INFO): DAT read: CRC:Pass MPD:03.4 PSNR:20.1 AGC:19 SPD:+0.0 CCERR:013 2025-11-11T01:11:10.064Z,1762823470.064 [DAT](INFO): Got CRC:Pass 2025-11-11T01:11:10.064Z,1762823470.064 [DAT](INFO): Got CRC:Pass 2025-11-11T01:11:10.064Z,1762823470.064 [DAT](INFO): Incoming data is intended for us 2025-11-11T01:11:10.065Z,1762823470.065 [DAT](DEBUG): parseResponses: Got ACK. Before transition: sendDataBuffer_ isEmpty=0, outgoingCommsBuffer_ size=0, outgoingCommsNow_=NOT NULL 2025-11-11T01:11:10.065Z,1762823470.065 [DAT](DEBUG): In parseResponses, got ack so set commsState_ = SENDING_VERIFIED 2025-11-11T01:11:10.065Z,1762823470.065 [DAT](INFO): Got ack 2025-11-11T01:11:10.065Z,1762823470.065 [DAT](INFO): DAT read: 2025-11-11T01:11:10.066Z,1762823470.066 [DAT](INFO): DAT read: 2025-11-11T01:11:10.066Z,1762823470.066 [DAT](DEBUG): sendingVerified: ACK received. sendDataBuffer_ isEmpty=0, outgoingCommsBuffer_ size=0 2025-11-11T01:11:10.066Z,1762823470.066 [DAT](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER 2025-11-11T01:11:10.313Z,1762823470.313 [DAT](INFO): modem://10: set _.others_observations string "NaN" 2025-11-11T01:11:10.313Z,1762823470.313 [DAT](INFO): #Outgoing data=1 2025-11-11T01:11:10.313Z,1762823470.313 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT 2025-11-11T01:11:10.442Z,1762823470.442 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287015 psu 2025-11-11T01:11:10.443Z,1762823470.443 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:11:10.565Z,1762823470.565 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2025-11-11T01:11:11.446Z,1762823471.446 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287020 psu 2025-11-11T01:11:11.447Z,1762823471.447 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:11:12.450Z,1762823472.450 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287072 psu 2025-11-11T01:11:12.451Z,1762823472.451 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:11:13.465Z,1762823473.465 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287070 psu 2025-11-11T01:11:13.466Z,1762823473.466 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:11:13.857Z,1762823473.857 [DAT](INFO): DAT read: Forwarding Delay UpTx time:01:11:12.9333 2025-11-11T01:11:13.858Z,1762823473.858 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING 2025-11-11T01:11:14.466Z,1762823474.466 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287017 psu 2025-11-11T01:11:14.467Z,1762823474.467 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:11:15.474Z,1762823475.474 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287016 psu 2025-11-11T01:11:15.475Z,1762823475.475 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:11:16.478Z,1762823476.478 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287018 psu 2025-11-11T01:11:16.479Z,1762823476.479 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:11:17.486Z,1762823477.486 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287012 psu 2025-11-11T01:11:17.487Z,1762823477.487 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:11:18.494Z,1762823478.494 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287013 psu 2025-11-11T01:11:18.495Z,1762823478.495 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:11:19.502Z,1762823479.502 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287014 psu 2025-11-11T01:11:19.503Z,1762823479.503 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:11:20.161Z,1762823480.161 [DAT](INFO): DAT read: Rx Time:01:11:18.7037 2025-11-11T01:11:20.161Z,1762823480.161 [DAT](INFO): Rx dataTimestamp_ set to:1762823480.161148 2025-11-11T01:11:20.510Z,1762823480.510 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287019 psu 2025-11-11T01:11:20.511Z,1762823480.511 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:11:20.917Z,1762823480.917 [DAT](INFO): DAT read: 2025-11-11T01:11:20.917Z,1762823480.917 [DAT](INFO): DAT read: DATA(0002):~~ 2025-11-11T01:11:20.918Z,1762823480.918 [DAT](INFO): Got DATA 2 2025-11-11T01:11:20.918Z,1762823480.918 [DAT](INFO): DAT read: Source:010 Destination:001 2025-11-11T01:11:20.918Z,1762823480.918 [DAT](INFO): Got Src/Dest after DATA 2025-11-11T01:11:20.919Z,1762823480.919 [DAT](INFO): DATA Src=10, Dst=1 2025-11-11T01:11:20.920Z,1762823480.920 [DAT](INFO): DAT read: CRC:Pass MPD:03.4 PSNR:19.8 AGC:16 SPD:+0.0 CCERR:013 2025-11-11T01:11:20.920Z,1762823480.920 [DAT](INFO): Got CRC:Pass 2025-11-11T01:11:20.920Z,1762823480.920 [DAT](INFO): Got CRC:Pass 2025-11-11T01:11:20.920Z,1762823480.920 [DAT](INFO): Incoming data is intended for us 2025-11-11T01:11:20.921Z,1762823480.921 [DAT](DEBUG): parseResponses: Got ACK. Before transition: sendDataBuffer_ isEmpty=1, outgoingCommsBuffer_ size=0, outgoingCommsNow_=NOT NULL 2025-11-11T01:11:20.921Z,1762823480.921 [DAT](DEBUG): In parseResponses, got ack so set commsState_ = SENDING_VERIFIED 2025-11-11T01:11:20.921Z,1762823480.921 [DAT](INFO): Got ack 2025-11-11T01:11:20.921Z,1762823480.921 [DAT](INFO): DAT read: 2025-11-11T01:11:20.922Z,1762823480.922 [DAT](INFO): DAT read: 2025-11-11T01:11:20.922Z,1762823480.922 [DAT](DEBUG): sendingVerified: ACK received. sendDataBuffer_ isEmpty=1, outgoingCommsBuffer_ size=0 2025-11-11T01:11:20.922Z,1762823480.922 [DAT](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER 2025-11-11T01:11:21.518Z,1762823481.518 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287014 psu 2025-11-11T01:11:21.519Z,1762823481.519 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:11:22.526Z,1762823482.526 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287018 psu 2025-11-11T01:11:22.527Z,1762823482.527 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:11:23.526Z,1762823483.526 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287022 psu 2025-11-11T01:11:23.527Z,1762823483.527 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:11:24.530Z,1762823484.530 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287018 psu 2025-11-11T01:11:24.531Z,1762823484.531 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:11:25.534Z,1762823485.534 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287024 psu 2025-11-11T01:11:25.535Z,1762823485.535 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:11:26.538Z,1762823486.538 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287018 psu 2025-11-11T01:11:26.539Z,1762823486.539 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:11:27.543Z,1762823487.543 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287019 psu 2025-11-11T01:11:27.544Z,1762823487.544 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:11:28.546Z,1762823488.546 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287023 psu 2025-11-11T01:11:28.547Z,1762823488.547 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:11:29.550Z,1762823489.550 [CTD_Seabird](ERROR): Salinity reading out of range: 0.287020 psu 2025-11-11T01:11:29.551Z,1762823489.551 [CTD_Seabird](INFO): some bad data, not updating bins 2025-11-11T01:11:29.913Z,1762823489.913 [CommandExec](IMPORTANT): got command stop 2025-11-11T01:11:29.913Z,1762823489.913 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop 2025-11-11T01:11:30.032Z,1762823490.032 [MissionManager](INFO): MissionManager is completed. 2025-11-11T01:11:30.033Z,1762823490.033 [MissionManager](INFO): Uninitializing Mission marl_lab 2025-11-11T01:11:30.033Z,1762823490.033 [marl_lab] Stopped 2025-11-11T01:11:30.033Z,1762823490.033 [marl_lab](DEBUG): Aggregate::uninitialize marl_lab 2025-11-11T01:11:30.033Z,1762823490.033 [marl_lab:AbortDrift] Stopped 2025-11-11T01:11:30.033Z,1762823490.033 [marl_lab:AbortDrift](DEBUG): Aggregate::uninitialize marl_lab:AbortDrift 2025-11-11T01:11:30.033Z,1762823490.033 [marl_lab:AbortDrift:B.AbortDrift] Stopped 2025-11-11T01:11:30.033Z,1762823490.033 [marl_lab:AbortDrift:B.AbortDrift](INFO): Uninitializing AbortDrift. 2025-11-11T01:11:30.033Z,1762823490.033 [marl_lab:B.] Stopped 2025-11-11T01:11:30.033Z,1762823490.033 [marl_lab:B.](DEBUG): Uninitializing TrackAcousticContact. 2025-11-11T01:11:30.033Z,1762823490.033 [marl_lab:BackseatDriver] Stopped 2025-11-11T01:11:30.033Z,1762823490.033 [marl_lab:BackseatDriver](DEBUG): Aggregate::uninitialize marl_lab:BackseatDriver 2025-11-11T01:11:30.033Z,1762823490.033 [marl_lab:BackseatDriver:A.BackseatDriver] Stopped 2025-11-11T01:11:30.034Z,1762823490.034 [marl_lab:PowerOnly] Stopped 2025-11-11T01:11:30.034Z,1762823490.034 [marl_lab:PowerOnly](DEBUG): Aggregate::uninitialize marl_lab:PowerOnly 2025-11-11T01:11:30.034Z,1762823490.034 [marl_lab:PowerOnly:E.Wait] Stopped 2025-11-11T01:11:30.034Z,1762823490.034 [marl_lab:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2025-11-11T01:11:30.034Z,1762823490.034 [marl_lab:H.Wait] Stopped 2025-11-11T01:11:30.034Z,1762823490.034 [marl_lab:H.Wait](DEBUG): Uninitialize Wait Component. 2025-11-11T01:11:30.301Z,1762823490.301 [BackseatComponent](INFO): Sent LCM shutdown request. 2025-11-11T01:11:30.301Z,1762823490.301 [BackseatComponent](INFO): Shutdown requested. Waiting (30.00 sec to power down.). 2025-11-11T01:11:30.453Z,1762823490.453 [MissionManager](IMPORTANT): Started mission Default 2025-11-11T01:11:30.453Z,1762823490.453 [Default] Running Loop=1 2025-11-11T01:11:30.453Z,1762823490.453 [Default](DEBUG): Aggregate::initialize Default 2025-11-11T01:11:30.453Z,1762823490.453 [Default:B.GoToSurface] Running Loop=1 2025-11-11T01:11:30.454Z,1762823490.454 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-11-11T01:11:30.454Z,1762823490.454 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-11-11T01:11:30.454Z,1762823490.454 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-11-11T01:11:30.454Z,1762823490.454 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-11-11T01:11:30.455Z,1762823490.455 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-11-11T01:11:30.455Z,1762823490.455 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-11-11T01:11:30.455Z,1762823490.455 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-11-11T01:11:30.456Z,1762823490.456 [Default:A.Wait] Running Loop=1 2025-11-11T01:11:30.456Z,1762823490.456 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-11-11T01:11:43.766Z,1762823503.766 [Default:A.Wait](INFO): Done Waiting. 2025-11-11T01:11:43.766Z,1762823503.766 [Default:A.Wait] Stopped 2025-11-11T01:11:43.767Z,1762823503.767 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-11-11T01:11:44.177Z,1762823504.177 [Default:CheckIn] Running Loop=1 2025-11-11T01:11:44.177Z,1762823504.177 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-11-11T01:11:44.177Z,1762823504.177 [Default:CheckIn:Read_GPS] Running Loop=1 2025-11-11T01:12:00.504Z,1762823520.504 [BackseatComponent](INFO): Powering down and starting shutdown timer (30.00 sec). 2025-11-11T01:12:00.505Z,1762823520.505 [BackseatComponent](INFO): Unsubscribing from LCM channels. 2025-11-11T01:12:00.505Z,1762823520.505 [LcmPublisher](INFO): Deactivating messaging. 2025-11-11T01:12:08.139Z,1762823528.139 [CommandExec](IMPORTANT): got command load ./Missions/marl_lab.tl 2025-11-11T01:12:08.141Z,1762823528.141 [MissionManager](INFO): Loading Mission from file: ./Missions/marl_lab.tl 2025-11-11T01:12:08.142Z,1762823528.142 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/marl_lab.tl 2025-11-11T01:12:08.148Z,1762823528.148 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/marl_lab.tl 2025-11-11T01:12:08.627Z,1762823528.627 [MissionManager](DEBUG): TethyslAPI: ./Missions/marl_lab.tl translated into: 1 8 10 NaN 20 10 1 1 NaN 0 0 Got test_good : and temp var is modem _.others_observations none_str SendData: sent send_observations : to nearby vehicles. Elapsed time Upadating ContactLabel 2025-11-11T01:12:08.632Z,1762823528.632 [MissionManager](INFO): DefineArg marl_lab.MissionTimeout = 1.000000 h 2025-11-11T01:12:08.634Z,1762823528.634 [MissionManager](INFO): DefineArg marl_lab.ContactLabel = 8.000000 count 2025-11-11T01:12:08.636Z,1762823528.636 [MissionManager](INFO): DefineArg marl_lab.SendDataLabel = 10.000000 count 2025-11-11T01:12:08.638Z,1762823528.638 [MissionManager](INFO): DefineArg marl_lab.ContactDepth = nan m 2025-11-11T01:12:08.641Z,1762823528.641 [MissionManager](INFO): DefineArg marl_lab.SendObservationDataInterval = 20.000000 s 2025-11-11T01:12:08.643Z,1762823528.643 [MissionManager](INFO): DefineArg marl_lab.TrackingUpdatePeriod = 10.000000 min 2025-11-11T01:12:08.693Z,1762823528.693 [MissionManager](INFO): DefineArg marl_lab.NumberOfPings = 1.000000 count 2025-11-11T01:12:08.695Z,1762823528.695 [MissionManager](INFO): DefineArg marl_lab.AcousticContactTimeout = 1.000000 h 2025-11-11T01:12:08.707Z,1762823528.707 [MissionManager](INFO): DefineArg marl_lab.MyTempVar = NaN 2025-11-11T01:12:08.714Z,1762823528.714 [MissionManager](INFO): DefineOutput marl_lab.TimeLastSendData = 0.000000 s 2025-11-11T01:12:08.721Z,1762823528.721 [MissionManager](INFO): DefineOutput marl_lab.ElapsedSinceLastSendData = 0.000000 s 2025-11-11T01:12:08.722Z,1762823528.722 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/AbortDrift.tl 2025-11-11T01:12:08.722Z,1762823528.722 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/AbortDrift.tl 2025-11-11T01:12:08.723Z,1762823528.723 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/AbortDrift.tl 2025-11-11T01:12:08.838Z,1762823528.838 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/AbortDrift.tl translated into: 96 Insert acoustic timeout set to . stop 2025-11-11T01:12:08.846Z,1762823528.846 [MissionManager](INFO): DefineArg marl_lab:AbortDrift.AcousticTimeout = 96.000000 h 2025-11-11T01:12:08.848Z,1762823528.848 [marl_lab:AbortDrift:B.AbortDrift](DEBUG): Construct AbortDrift. 2025-11-11T01:12:08.850Z,1762823528.850 [marl_lab:AbortDrift:C.Execute](DEBUG): Construct Execute. 2025-11-11T01:12:08.876Z,1762823528.876 [MissionManager](INFO): RedefineArg marl_lab:AbortDrift.AcousticTimeout = reader:marl_lab.AcousticContactTimeout 2025-11-11T01:12:08.927Z,1762823528.927 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl 2025-11-11T01:12:08.927Z,1762823528.927 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl 2025-11-11T01:12:08.929Z,1762823528.929 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl 2025-11-11T01:12:08.947Z,1762823528.947 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into: 2025-11-11T01:12:08.952Z,1762823528.952 [MissionManager](INFO): DefineArg marl_lab:BackseatDriver.EnableBackseat = 0 bool 2025-11-11T01:12:08.986Z,1762823528.986 [marl_lab:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver. 2025-11-11T01:12:08.990Z,1762823528.990 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl 2025-11-11T01:12:08.990Z,1762823528.990 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl 2025-11-11T01:12:08.991Z,1762823528.991 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl 2025-11-11T01:12:09.101Z,1762823529.101 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into: 99999 2025-11-11T01:12:09.104Z,1762823529.104 [MissionManager](INFO): DefineArg marl_lab:PowerOnly.SampleAll = 0 bool 2025-11-11T01:12:09.106Z,1762823529.106 [MissionManager](INFO): DefineArg marl_lab:PowerOnly.SampleLoad1 = 0 bool 2025-11-11T01:12:09.108Z,1762823529.108 [MissionManager](INFO): DefineArg marl_lab:PowerOnly.SampleLoad2 = 0 bool 2025-11-11T01:12:09.110Z,1762823529.110 [MissionManager](INFO): DefineArg marl_lab:PowerOnly.SampleLoad3 = 0 bool 2025-11-11T01:12:09.153Z,1762823529.153 [MissionManager](INFO): DefineArg marl_lab:PowerOnly.EnabledPowerOnly = 0 bool 2025-11-11T01:12:09.163Z,1762823529.163 [marl_lab:PowerOnly:E.Wait](DEBUG): Construct Wait. 2025-11-11T01:12:09.177Z,1762823529.177 [marl_lab:H.Wait](DEBUG): Construct Wait. 2025-11-11T01:12:09.184Z,1762823529.184 [MissionManager](DEBUG): mission marl_lab { """ Lab test mission for marl3 acoustic communications. Tests acoustic tracking and send observation data without driving. """ arguments { MissionTimeout = 1 hour """ Maximum duration of mission """ ContactLabel = 8 count """ The acoustic address of the asset to be tracked. tethys = 6 brizo = 11 daphne = 8 galene = 9 makai = 5 pontus = 10 triton = 12 """ SendDataLabel = 10 count """ The acoustic address of the asset to send observation data to. 10 is pontus """ ContactDepth = NaN meter """ The asset's depth to be tracked """ SendObservationDataInterval = 20 second """ How often to send back some a-comms data """ TrackingUpdatePeriod = 10 minute """ How long to wait between acoustic queries """ NumberOfPings = 1 count """ Number of return pings to request with each acoustic query (more than 1 will activate oneway mode) """ AcousticContactTimeout = 1 hour """ If the vehicle does not receive an acoustic signal for more than this length of time, it will surface for communications with shore. """ MyTempVar = NaN none_str """ A custom variable from the backseat driver interface. """ } output { TimeLastSendData = 0 second ElapsedSinceLastSendData = 0 second """ Time elapsed since last Send Data fix. """ } timeout duration=MissionTimeout insert Insert/AbortDrift.tl { redefineArg AcousticTimeout = AcousticContactTimeout } behavior Estimation:TrackAcousticContact { run in parallel set contactDepthSetting = ContactDepth set contactLabelSetting = ContactLabel set numberOfSamplesSetting = NumberOfPings set updatePeriodSetting = TrackingUpdatePeriod } aggregate SendObservationData { run while ( elapsed ( MyTempVar ) >= SendObservationDataInterval ) syslog info "Got test_good : " + customUri "_.send_observations"~none_str + " and temp var is " + MyTempVar~none_str assign in sequence ElapsedSinceLastSendData = elapsed ( MyTempVar ) assign in sequence MyTempVar = customUri "_.send_observations" behavior Sensor:SendDirect { run in sequence set destType = "modem" set destId = SendDataLabel set destName = "_.others_observations" set value = MyTempVar set unit = "none_str" } syslog info "SendData: sent send_observations : " + MyTempVar~none_str + " to nearby vehicles. Elapsed time " + ElapsedSinceLastSendData~minute } aggregate UpdateContactLabel { run when ( elapsed ( customUri "_.contactLabelSetting" ) < ( elapsed ( ContactLabel ) ) ) assign in sequence ContactLabel = abs ( customUri "_.contactLabelSetting" ) syslog info "Upadating ContactLabel " + customUri "_.contactLabelSetting"~count } insert Insert/BackseatDriver.tl assign in sequence BackseatDriver:EnableBackseat = true insert Insert/PowerOnly.tl behavior Guidance:Wait { run in sequence set duration = MissionTimeout } } 2025-11-11T01:12:09.185Z,1762823529.185 [CommandExec](IMPORTANT): Loaded ./Missions/marl_lab.tl id=marl_lab 2025-11-11T01:12:19.583Z,1762823539.583 [CommandExec](IMPORTANT): got command set marl_lab.SendObservationDataInterval 1 minute 2025-11-11T01:12:27.389Z,1762823547.389 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-11-11T01:12:27.389Z,1762823547.389 [DropWeight] Hardware Fault, FailCount= 1 2025-11-11T01:12:27.389Z,1762823547.389 [DropWeight](ERROR): Hardware Fault 2025-11-11T01:12:27.425Z,1762823547.425 [CommandExec](FAULT): Scheduling is paused 2025-11-11T01:12:27.425Z,1762823547.425 [CBIT](INFO): Critical error at 20251111T011227 2025-11-11T01:12:27.425Z,1762823547.425 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2025-11-11T01:12:27.428Z,1762823547.428 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-11-11T01:12:27.428Z,1762823547.428 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-11-11T01:12:27.836Z,1762823547.836 [CBIT](INFO): Critical error at 20251111T011227 2025-11-11T01:12:38.709Z,1762823558.709 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15 2025-11-11T01:12:38.709Z,1762823558.709 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-11-11T01:12:38.719Z,1762823558.719 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-11-11T01:12:39.125Z,1762823559.125 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-11-11T01:12:39.125Z,1762823559.125 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15 2025-11-11T01:13:17.976Z,1762823597.976 [CommandExec](IMPORTANT): got command run 2025-11-11T01:13:17.978Z,1762823597.978 [CommandExec](IMPORTANT): Running 2025-11-11T01:13:18.297Z,1762823598.297 [Default] Stopped 2025-11-11T01:13:18.298Z,1762823598.298 [Default](DEBUG): Aggregate::uninitialize Default 2025-11-11T01:13:18.298Z,1762823598.298 [Default:B.GoToSurface] Stopped 2025-11-11T01:13:18.298Z,1762823598.298 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-11-11T01:13:18.298Z,1762823598.298 [Default:CheckIn] Stopped 2025-11-11T01:13:18.298Z,1762823598.298 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-11-11T01:13:18.298Z,1762823598.298 [Default:CheckIn:Read_GPS] Stopped 2025-11-11T01:13:18.298Z,1762823598.298 [MissionManager](IMPORTANT): Started mission marl_lab 2025-11-11T01:13:18.298Z,1762823598.298 [marl_lab] Running Loop=1 2025-11-11T01:13:18.298Z,1762823598.298 [marl_lab](DEBUG): Aggregate::initialize marl_lab 2025-11-11T01:13:18.299Z,1762823598.299 [marl_lab:AbortDrift] Running Loop=1 2025-11-11T01:13:18.299Z,1762823598.299 [marl_lab:AbortDrift](DEBUG): Aggregate::initialize marl_lab:AbortDrift 2025-11-11T01:13:18.299Z,1762823598.299 [marl_lab:AbortDrift:A] Running Loop=1 2025-11-11T01:13:18.299Z,1762823598.299 [marl_lab:B.] Running Loop=1 2025-11-11T01:13:18.299Z,1762823598.299 [marl_lab:B.](INFO): Initializing TrackAcousticContact. 2025-11-11T01:13:18.299Z,1762823598.299 [marl_lab:SendObservationData] Running Loop=1 2025-11-11T01:13:18.299Z,1762823598.299 [marl_lab:SendObservationData](DEBUG): Aggregate::initialize marl_lab:SendObservationData 2025-11-11T01:13:18.299Z,1762823598.299 [marl_lab:SendObservationData:A] Running Loop=1 2025-11-11T01:13:18.299Z,1762823598.299 [marl_lab:BackseatDriver] Running Loop=1 2025-11-11T01:13:18.300Z,1762823598.300 [marl_lab:BackseatDriver](DEBUG): Aggregate::initialize marl_lab:BackseatDriver 2025-11-11T01:13:18.300Z,1762823598.300 [marl_lab:BackseatDriver:A.BackseatDriver] Running Loop=1 2025-11-11T01:13:18.300Z,1762823598.300 [marl_lab:PowerOnly] Running Loop=1 2025-11-11T01:13:18.300Z,1762823598.300 [marl_lab:PowerOnly](DEBUG): Aggregate::initialize marl_lab:PowerOnly 2025-11-11T01:13:18.300Z,1762823598.300 [marl_lab:PowerOnly:A] Running Loop=1 2025-11-11T01:13:18.300Z,1762823598.300 [marl_lab:PowerOnly:B] Running Loop=1 2025-11-11T01:13:18.300Z,1762823598.300 [marl_lab:PowerOnly:C] Running Loop=1 2025-11-11T01:13:18.300Z,1762823598.300 [marl_lab:PowerOnly:D] Running Loop=1 2025-11-11T01:13:18.300Z,1762823598.300 [marl_lab:PowerOnly:E.Wait] Running Loop=1 2025-11-11T01:13:18.301Z,1762823598.301 [marl_lab:PowerOnly:E.Wait](DEBUG): Initialize Wait Component. 2025-11-11T01:13:18.301Z,1762823598.301 [marl_lab:F] Running Loop=1 2025-11-11T01:13:18.301Z,1762823598.301 [marl_lab:PowerOnly] Running Loop=1 2025-11-11T01:13:18.301Z,1762823598.301 [marl_lab:PowerOnly:D] Stopped 2025-11-11T01:13:18.302Z,1762823598.302 [marl_lab:PowerOnly:C] Stopped 2025-11-11T01:13:18.302Z,1762823598.302 [marl_lab:PowerOnly:B] Stopped 2025-11-11T01:13:18.302Z,1762823598.302 [marl_lab:PowerOnly:A] Stopped 2025-11-11T01:13:18.302Z,1762823598.302 [marl_lab:F] Stopped 2025-11-11T01:13:18.302Z,1762823598.302 [marl_lab:H.Wait] Running Loop=1 2025-11-11T01:13:18.302Z,1762823598.302 [marl_lab:H.Wait](DEBUG): Initialize Wait Component. 2025-11-11T01:13:18.302Z,1762823598.302 [marl_lab:BackseatDriver] Running Loop=1 2025-11-11T01:13:18.302Z,1762823598.302 [marl_lab:BackseatDriver:A.BackseatDriver](INFO): Initializing backseat 2025-11-11T01:13:18.318Z,1762823598.318 [marl_lab:SendObservationData] Running Loop=1 2025-11-11T01:13:18.318Z,1762823598.318 [marl_lab:SendObservationData:A](ERROR): data element is not active. 2025-11-11T01:13:18.319Z,1762823598.319 [marl_lab:SendObservationData:A](INFO): Got test_good : n/a str and temp var is NaN n/a str 2025-11-11T01:13:18.319Z,1762823598.319 [marl_lab:SendObservationData:A] Stopped 2025-11-11T01:13:18.319Z,1762823598.319 [marl_lab:SendObservationData:B] Running Loop=1 2025-11-11T01:13:18.319Z,1762823598.319 [marl_lab:B.] Running Loop=1 2025-11-11T01:13:18.319Z,1762823598.319 [marl_lab:AbortDrift] Running Loop=1 2025-11-11T01:13:18.336Z,1762823598.336 [marl_lab:AbortDrift:A](INFO): Insert acoustic timeout set to 1.000000 h . 2025-11-11T01:13:18.336Z,1762823598.336 [marl_lab:AbortDrift:A] Stopped 2025-11-11T01:13:18.336Z,1762823598.336 [marl_lab:AbortDrift:B.AbortDrift] Running Loop=1 2025-11-11T01:13:18.336Z,1762823598.336 [marl_lab:AbortDrift:B.AbortDrift](INFO): Initializing AbortDrift. 2025-11-11T01:13:18.337Z,1762823598.337 [marl_lab:AbortDrift:B.AbortDrift](IMPORTANT): Acoustic timeout set to: 1.00 hours. 2025-11-11T01:13:18.337Z,1762823598.337 [MissionManager](INFO): MissionManager is completed. 2025-11-11T01:13:18.337Z,1762823598.337 [MissionManager](INFO): Uninitializing Mission marl_lab 2025-11-11T01:13:18.337Z,1762823598.337 [marl_lab] Stopped 2025-11-11T01:13:18.337Z,1762823598.337 [marl_lab](DEBUG): Aggregate::uninitialize marl_lab 2025-11-11T01:13:18.337Z,1762823598.337 [marl_lab:AbortDrift] Stopped 2025-11-11T01:13:18.337Z,1762823598.337 [marl_lab:AbortDrift](DEBUG): Aggregate::uninitialize marl_lab:AbortDrift 2025-11-11T01:13:18.338Z,1762823598.338 [marl_lab:AbortDrift:B.AbortDrift] Stopped 2025-11-11T01:13:18.338Z,1762823598.338 [marl_lab:AbortDrift:B.AbortDrift](INFO): Uninitializing AbortDrift. 2025-11-11T01:13:18.338Z,1762823598.338 [marl_lab:B.] Stopped 2025-11-11T01:13:18.338Z,1762823598.338 [marl_lab:B.](DEBUG): Uninitializing TrackAcousticContact. 2025-11-11T01:13:18.338Z,1762823598.338 [marl_lab:SendObservationData] Stopped 2025-11-11T01:13:18.338Z,1762823598.338 [marl_lab:SendObservationData](DEBUG): Aggregate::uninitialize marl_lab:SendObservationData 2025-11-11T01:13:18.338Z,1762823598.338 [marl_lab:SendObservationData:B] Stopped 2025-11-11T01:13:18.338Z,1762823598.338 [marl_lab:BackseatDriver] Stopped 2025-11-11T01:13:18.338Z,1762823598.338 [marl_lab:BackseatDriver](DEBUG): Aggregate::uninitialize marl_lab:BackseatDriver 2025-11-11T01:13:18.338Z,1762823598.338 [marl_lab:BackseatDriver:A.BackseatDriver] Stopped 2025-11-11T01:13:18.338Z,1762823598.338 [marl_lab:PowerOnly] Stopped 2025-11-11T01:13:18.339Z,1762823598.339 [marl_lab:PowerOnly](DEBUG): Aggregate::uninitialize marl_lab:PowerOnly 2025-11-11T01:13:18.339Z,1762823598.339 [marl_lab:PowerOnly:E.Wait] Stopped 2025-11-11T01:13:18.339Z,1762823598.339 [marl_lab:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2025-11-11T01:13:18.339Z,1762823598.339 [marl_lab:H.Wait] Stopped 2025-11-11T01:13:18.339Z,1762823598.339 [marl_lab:H.Wait](DEBUG): Uninitialize Wait Component. 2025-11-11T01:13:18.713Z,1762823598.713 [MissionManager](IMPORTANT): Started mission Default 2025-11-11T01:13:18.714Z,1762823598.714 [Default] Running Loop=1 2025-11-11T01:13:18.714Z,1762823598.714 [Default](DEBUG): Aggregate::initialize Default 2025-11-11T01:13:18.714Z,1762823598.714 [Default:B.GoToSurface] Running Loop=1 2025-11-11T01:13:18.714Z,1762823598.714 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-11-11T01:13:18.714Z,1762823598.714 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-11-11T01:13:18.714Z,1762823598.714 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-11-11T01:13:18.715Z,1762823598.715 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-11-11T01:13:18.715Z,1762823598.715 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-11-11T01:13:18.715Z,1762823598.715 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-11-11T01:13:18.716Z,1762823598.716 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-11-11T01:13:18.716Z,1762823598.716 [Default:A.Wait] Running Loop=1 2025-11-11T01:13:18.716Z,1762823598.716 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-11-11T01:13:32.063Z,1762823612.063 [Default:A.Wait](INFO): Done Waiting. 2025-11-11T01:13:32.063Z,1762823612.063 [Default:A.Wait] Stopped 2025-11-11T01:13:32.063Z,1762823612.063 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-11-11T01:13:32.453Z,1762823612.453 [Default:CheckIn] Running Loop=1 2025-11-11T01:13:32.453Z,1762823612.453 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-11-11T01:13:32.453Z,1762823612.453 [Default:CheckIn:Read_GPS] Running Loop=1 2025-11-11T01:15:39.731Z,1762823739.731 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16 2025-11-11T01:15:39.731Z,1762823739.731 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-11-11T01:15:39.742Z,1762823739.742 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-11-11T01:15:40.138Z,1762823740.138 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-11-11T01:15:40.138Z,1762823740.138 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16 2025-11-11T01:17:36.763Z,1762823856.763 [CommandExec](IMPORTANT): got command load ./Missions/marl_lab.tl 2025-11-11T01:17:36.763Z,1762823856.763 [MissionManager](INFO): Loading Mission from file: ./Missions/marl_lab.tl 2025-11-11T01:17:36.763Z,1762823856.763 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/marl_lab.tl 2025-11-11T01:17:36.765Z,1762823856.765 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/marl_lab.tl 2025-11-11T01:17:37.332Z,1762823857.332 [MissionManager](DEBUG): TethyslAPI: ./Missions/marl_lab.tl translated into: 1 8 10 NaN 20 10 1 1 NaN 0 0 Got test_good : and temp var is modem _.others_observations none_str SendData: sent send_observations : to nearby vehicles. Elapsed time Upadating ContactLabel 2025-11-11T01:17:37.337Z,1762823857.337 [MissionManager](INFO): DefineArg marl_lab.MissionTimeout = 1.000000 h 2025-11-11T01:17:37.339Z,1762823857.339 [MissionManager](INFO): DefineArg marl_lab.ContactLabel = 8.000000 count 2025-11-11T01:17:37.342Z,1762823857.342 [MissionManager](INFO): DefineArg marl_lab.SendDataLabel = 10.000000 count 2025-11-11T01:17:37.344Z,1762823857.344 [MissionManager](INFO): DefineArg marl_lab.ContactDepth = nan m 2025-11-11T01:17:37.346Z,1762823857.346 [MissionManager](INFO): DefineArg marl_lab.SendObservationDataInterval = 20.000000 s 2025-11-11T01:17:37.348Z,1762823857.348 [MissionManager](INFO): DefineArg marl_lab.TrackingUpdatePeriod = 10.000000 min 2025-11-11T01:17:37.351Z,1762823857.351 [MissionManager](INFO): DefineArg marl_lab.NumberOfPings = 1.000000 count 2025-11-11T01:17:37.353Z,1762823857.353 [MissionManager](INFO): DefineArg marl_lab.AcousticContactTimeout = 1.000000 h 2025-11-11T01:17:37.355Z,1762823857.355 [MissionManager](INFO): DefineArg marl_lab.MyTempVar = NaN 2025-11-11T01:17:37.357Z,1762823857.357 [MissionManager](INFO): DefineOutput marl_lab.TimeLastSendData = 0.000000 s 2025-11-11T01:17:37.359Z,1762823857.359 [MissionManager](INFO): DefineOutput marl_lab.ElapsedSinceLastSendData = 0.000000 s 2025-11-11T01:17:37.361Z,1762823857.361 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/AbortDrift.tl 2025-11-11T01:17:37.361Z,1762823857.361 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/AbortDrift.tl 2025-11-11T01:17:37.362Z,1762823857.362 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/AbortDrift.tl 2025-11-11T01:17:37.526Z,1762823857.526 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/AbortDrift.tl translated into: 96 Insert acoustic timeout set to . stop 2025-11-11T01:17:37.537Z,1762823857.537 [MissionManager](INFO): DefineArg marl_lab:AbortDrift.AcousticTimeout = 96.000000 h 2025-11-11T01:17:37.539Z,1762823857.539 [marl_lab:AbortDrift:B.AbortDrift](DEBUG): Construct AbortDrift. 2025-11-11T01:17:37.541Z,1762823857.541 [marl_lab:AbortDrift:C.Execute](DEBUG): Construct Execute. 2025-11-11T01:17:37.543Z,1762823857.543 [MissionManager](INFO): RedefineArg marl_lab:AbortDrift.AcousticTimeout = reader:marl_lab.AcousticContactTimeout 2025-11-11T01:17:37.573Z,1762823857.573 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl 2025-11-11T01:17:37.573Z,1762823857.573 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl 2025-11-11T01:17:37.574Z,1762823857.574 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl 2025-11-11T01:17:37.634Z,1762823857.634 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into: 2025-11-11T01:17:37.639Z,1762823857.639 [MissionManager](INFO): DefineArg marl_lab:BackseatDriver.EnableBackseat = 0 bool 2025-11-11T01:17:37.642Z,1762823857.642 [marl_lab:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver. 2025-11-11T01:17:37.645Z,1762823857.645 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl 2025-11-11T01:17:37.645Z,1762823857.645 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl 2025-11-11T01:17:37.646Z,1762823857.646 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl 2025-11-11T01:17:37.915Z,1762823857.915 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into: 99999 2025-11-11T01:17:37.925Z,1762823857.925 [MissionManager](INFO): DefineArg marl_lab:PowerOnly.SampleAll = 0 bool 2025-11-11T01:17:37.927Z,1762823857.927 [MissionManager](INFO): DefineArg marl_lab:PowerOnly.SampleLoad1 = 0 bool 2025-11-11T01:17:37.937Z,1762823857.937 [MissionManager](INFO): DefineArg marl_lab:PowerOnly.SampleLoad2 = 0 bool 2025-11-11T01:17:37.939Z,1762823857.939 [MissionManager](INFO): DefineArg marl_lab:PowerOnly.SampleLoad3 = 0 bool 2025-11-11T01:17:37.945Z,1762823857.945 [MissionManager](INFO): DefineArg marl_lab:PowerOnly.EnabledPowerOnly = 0 bool 2025-11-11T01:17:38.005Z,1762823858.005 [marl_lab:PowerOnly:E.Wait](DEBUG): Construct Wait. 2025-11-11T01:17:38.006Z,1762823858.006 [marl_lab:H.Wait](DEBUG): Construct Wait. 2025-11-11T01:17:38.036Z,1762823858.036 [MissionManager](DEBUG): mission marl_lab { """ Lab test mission for marl3 acoustic communications. Tests acoustic tracking and send observation data without driving. """ arguments { MissionTimeout = 1 hour """ Maximum duration of mission """ ContactLabel = 8 count """ The acoustic address of the asset to be tracked. tethys = 6 brizo = 11 daphne = 8 galene = 9 makai = 5 pontus = 10 triton = 12 """ SendDataLabel = 10 count """ The acoustic address of the asset to send observation data to. 10 is pontus """ ContactDepth = NaN meter """ The asset's depth to be tracked """ SendObservationDataInterval = 20 second """ How often to send back some a-comms data """ TrackingUpdatePeriod = 10 minute """ How long to wait between acoustic queries """ NumberOfPings = 1 count """ Number of return pings to request with each acoustic query (more than 1 will activate oneway mode) """ AcousticContactTimeout = 1 hour """ If the vehicle does not receive an acoustic signal for more than this length of time, it will surface for communications with shore. """ MyTempVar = NaN none_str """ A custom variable from the backseat driver interface. """ } output { TimeLastSendData = 0 second ElapsedSinceLastSendData = 0 second """ Time elapsed since last Send Data fix. """ } timeout duration=MissionTimeout insert Insert/AbortDrift.tl { redefineArg AcousticTimeout = AcousticContactTimeout } behavior Estimation:TrackAcousticContact { run in parallel set contactDepthSetting = ContactDepth set contactLabelSetting = ContactLabel set numberOfSamplesSetting = NumberOfPings set updatePeriodSetting = TrackingUpdatePeriod } aggregate SendObservationData { run when ( elapsed ( MyTempVar ) >= SendObservationDataInterval ) syslog info "Got test_good : " + customUri "_.send_observations"~none_str + " and temp var is " + MyTempVar~none_str assign in sequence ElapsedSinceLastSendData = elapsed ( MyTempVar ) assign in sequence MyTempVar = customUri "_.send_observations" behavior Sensor:SendDirect { run in sequence set destType = "modem" set destId = SendDataLabel set destName = "_.others_observations" set value = MyTempVar set unit = "none_str" } syslog info "SendData: sent send_observations : " + MyTempVar~none_str + " to nearby vehicles. Elapsed time " + ElapsedSinceLastSendData~minute } aggregate UpdateContactLabel { run when ( elapsed ( customUri "_.contactLabelSetting" ) < ( elapsed ( ContactLabel ) ) ) assign in sequence ContactLabel = abs ( customUri "_.contactLabelSetting" ) syslog info "Upadating ContactLabel " + customUri "_.contactLabelSetting"~count } insert Insert/BackseatDriver.tl assign in sequence BackseatDriver:EnableBackseat = true insert Insert/PowerOnly.tl behavior Guidance:Wait { run in sequence set duration = MissionTimeout } } 2025-11-11T01:17:38.037Z,1762823858.037 [CommandExec](IMPORTANT): Loaded ./Missions/marl_lab.tl id=marl_lab 2025-11-11T01:17:49.291Z,1762823869.291 [CommandExec](IMPORTANT): got command set marl_lab.SendObservationDataInterval 60 second