2025-07-05T14:44:38.288Z,1751726678.288 [OnDock:L.Execute](INFO): Executing command restart logs
2025-07-05T14:44:38.289Z,1751726678.289 [OnDock:L.Execute] Stopped
2025-07-05T14:44:38.289Z,1751726678.289 [OnDock:N.Docked] Running Loop=1
2025-07-05T14:44:38.289Z,1751726678.289 [OnDock:N.Docked](DEBUG): Initialize.
2025-07-05T14:44:38.289Z,1751726678.289 [OnDock:N.Docked](DEBUG): Initializing internal variables to default values.
2025-07-05T14:44:38.345Z,1751726678.345 [CommandExec](IMPORTANT): got command restart logs
2025-07-05T14:44:38.758Z,1751726678.758 [DAT](INFO): Requesting device enable set for address 50.
2025-07-05T14:44:38.901Z,1751726678.901 [DockingStepper](INFO): Resume
2025-07-05T14:44:39.009Z,1751726679.009 [DAT](INFO): DAT read: user:354>
2025-07-05T14:44:39.010Z,1751726679.010 [DAT](INFO): DAT read: Tx time:14:44:39.1191
2025-07-05T14:44:39.010Z,1751726679.010 [DAT](INFO): Ping request sent.
2025-07-05T14:44:39.010Z,1751726679.010 [DAT](INFO): transmitted an acoustic signal
2025-07-05T14:44:39.010Z,1751726679.010 [DAT](INFO): publishing transmit ping time
2025-07-05T14:44:39.011Z,1751726679.011 [DAT](INFO): Requesting device enable set for address 50.
2025-07-05T14:44:39.145Z,1751726679.145 [DockingStepper](INFO): Resuming
2025-07-05T14:44:39.261Z,1751726679.261 [DAT](INFO): Requesting device enable set for address 50.
2025-07-05T14:44:39.730Z,1751726679.730 [DockingStepper](INFO): Start
2025-07-05T14:44:39.730Z,1751726679.730 [DockingStepper](DEBUG): Initializing EZServoServo.
2025-07-05T14:44:39.745Z,1751726679.745 [CTD_Seabird](ERROR): Failed to parse device response:
2025-07-05T14:44:39.845Z,1751726679.845 [DockingStepper](DEBUG): Initializing DockingStepper.
2025-07-05T14:44:39.941Z,1751726679.941 [DockingStepper](INFO): Starting
2025-07-05T14:44:40.429Z,1751726680.429 [DockingStepper](INFO): Starting
2025-07-05T14:44:40.791Z,1751726680.791 [DockingStepper](INFO): Starting
2025-07-05T14:44:41.140Z,1751726681.140 [DockingStepper](INFO): Starting
2025-07-05T14:44:41.189Z,1751726681.189 [DockingStepper](INFO): Init string: /1z16353aE13561N3m50
2025-07-05T14:44:41.277Z,1751726681.277 [DAT](INFO): DAT read: Rx Time:14:44:40.9880
2025-07-05T14:44:41.277Z,1751726681.277 [DAT](INFO): Rx dataTimestamp_ set to:1751726681.277199
2025-07-05T14:44:41.578Z,1751726681.578 [DockingStepper](INFO): Starting
2025-07-05T14:44:41.781Z,1751726681.781 [DAT](INFO): DAT read: OK
2025-07-05T14:44:41.781Z,1751726681.781 [DAT](INFO): unknown deviceResponse_: OK
2025-07-05T14:44:41.781Z,1751726681.781 [DAT](INFO): DAT read: user:355>
2025-07-05T14:44:41.782Z,1751726681.782 [DAT](INFO): DAT read: Tx time:14:44:42.0691
2025-07-05T14:44:41.782Z,1751726681.782 [DAT](INFO): Ping request sent.
2025-07-05T14:44:41.783Z,1751726681.783 [DAT](INFO): transmitted an acoustic signal
2025-07-05T14:44:41.783Z,1751726681.783 [DAT](INFO): publishing transmit ping time
2025-07-05T14:44:42.017Z,1751726682.017 [DockingStepper](INFO): Starting
2025-07-05T14:44:42.359Z,1751726682.359 [DockingStepper](INFO): Starting
2025-07-05T14:44:42.925Z,1751726682.925 [DockingStepper](INFO): Starting
2025-07-05T14:44:42.949Z,1751726682.949 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@
2025-07-05T14:44:43.185Z,1751726683.185 [DockingStepper](INFO): Starting
2025-07-05T14:44:43.589Z,1751726683.589 [DockingStepper](INFO): Changing to mode: 1
2025-07-05T14:44:43.589Z,1751726683.589 [DockingStepper](INFO): Standby mode.
2025-07-05T14:44:44.301Z,1751726684.301 [DAT](INFO): DAT read: Rx Time:14:44:43.9382
2025-07-05T14:44:44.301Z,1751726684.301 [DAT](INFO): Rx dataTimestamp_ set to:1751726684.301180
2025-07-05T14:44:44.809Z,1751726684.809 [DAT](INFO): DAT read: 14:44:43.9382 LVL= 11504, 14881, 8882, 9923, AGC= 36, IDX= 444, 0.43,-2.318,-1.445,-1.068,-0.899, PHS=-1.331,-0.499,-0.172, RAW= 254.1, 34.4, CAL= 267.6, 40.9, ROT= 242.4, -40.9
2025-07-05T14:44:44.810Z,1751726684.810 [DAT](INFO): got valid direction response:
14:44:43.9382 LVL= 11504, 14881, 8882, 9923, AGC= 36, IDX= 444, 0.43,-2.318,-1.445,-1.068,-0.899, PHS=-1.331,-0.499,-0.172, RAW= 254.1, 34.4, CAL= 267.6, 40.9, ROT= 242.4, -40.9
2025-07-05T14:44:44.811Z,1751726684.811 [DAT](INFO): DAT read: OK
2025-07-05T14:44:44.811Z,1751726684.811 [DAT](INFO): unknown deviceResponse_: OK
2025-07-05T14:44:44.811Z,1751726684.811 [DAT](INFO): DAT read: user:356>
2025-07-05T14:44:44.812Z,1751726684.812 [DAT](INFO): DAT read: Tx time:14:44:45.0191
2025-07-05T14:44:44.813Z,1751726684.813 [DAT](INFO): Ping request sent.
2025-07-05T14:44:44.813Z,1751726684.813 [DAT](INFO): transmitted an acoustic signal
2025-07-05T14:44:44.813Z,1751726684.813 [DAT](INFO): #Rx 2: Read direction message, but no range.
2025-07-05T14:44:44.814Z,1751726684.814 [DAT](INFO): direction in FSK: [-0.350184,-0.669840,0.654741]
2025-07-05T14:44:44.814Z,1751726684.814 [DAT](INFO): publishing transmit ping time
2025-07-05T14:44:44.817Z,1751726684.817 [DAT](INFO): publishing transmit ping time
2025-07-05T14:44:47.325Z,1751726687.325 [DAT](INFO): DAT read: Rx Time:14:44:46.8883
2025-07-05T14:44:47.325Z,1751726687.325 [DAT](INFO): Rx dataTimestamp_ set to:1751726687.325146
2025-07-05T14:44:47.830Z,1751726687.830 [DAT](INFO): DAT read: OK
2025-07-05T14:44:47.831Z,1751726687.831 [DAT](INFO): unknown deviceResponse_: OK
2025-07-05T14:45:07.844Z,1751726707.844 [DataOverHttps](INFO): Received command: set OnDock.DockedTime 40 h
2025-07-05T14:45:08.849Z,1751726708.849 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004016
2025-07-05T14:45:12.343Z,1751726712.343 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-07-05T14:45:13.965Z,1751726713.965 [CommandExec](IMPORTANT): got command set OnDock.DockedTime 40 hour
2025-07-05T14:45:18.294Z,1751726718.294 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-07-05T14:45:19.615Z,1751726719.615 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20250705T070651/Courier0049.lzma
2025-07-05T14:45:20.618Z,1751726720.618 [DataOverHttps](INFO): Moved sent file to Logs/20250705T070651/Courier0049.lzma.bak
2025-07-05T14:45:20.618Z,1751726720.618 [DataOverHttps](INFO): SBD MOMSN=25361120
2025-07-05T14:45:22.522Z,1751726722.522 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-07-05T14:45:31.892Z,1751726731.892 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-07-05T14:45:40.180Z,1751726740.180 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250705T135857/Courier0000.lzma
2025-07-05T14:45:41.629Z,1751726741.629 [DataOverHttps](INFO): Moved sent file to Logs/20250705T135857/Courier0000.lzma.bak
2025-07-05T14:45:41.629Z,1751726741.629 [DataOverHttps](INFO): SBD MOMSN=25361123
2025-07-05T14:45:43.156Z,1751726743.156 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-07-05T14:45:52.552Z,1751726752.552 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-07-05T14:45:59.903Z,1751726759.903 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250705T135857/Courier0003.lzma
2025-07-05T14:46:00.906Z,1751726760.906 [DataOverHttps](INFO): Moved sent file to Logs/20250705T135857/Courier0003.lzma.bak
2025-07-05T14:46:00.906Z,1751726760.906 [DataOverHttps](INFO): SBD MOMSN=25361126
2025-07-05T14:46:02.870Z,1751726762.870 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-07-05T14:46:12.063Z,1751726772.063 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-07-05T14:46:12.072Z,1751726772.072 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-07-05T14:46:20.367Z,1751726780.367 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250705T135857/Courier0006.lzma
2025-07-05T14:46:21.912Z,1751726781.912 [DataOverHttps](INFO): Moved sent file to Logs/20250705T135857/Courier0006.lzma.bak
2025-07-05T14:46:21.912Z,1751726781.912 [DataOverHttps](INFO): SBD MOMSN=25361128
2025-07-05T14:46:23.210Z,1751726783.210 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-07-05T14:46:31.681Z,1751726791.681 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-07-05T14:46:36.030Z,1751726796.030 [OnDock:J.](INFO): *** querying acoustic contact ***
2025-07-05T14:46:36.229Z,1751726796.229 [DAT](DEBUG): Acoustic response timeout
2025-07-05T14:46:36.229Z,1751726796.229 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-07-05T14:46:36.481Z,1751726796.481 [DAT](INFO): DAT read: user:357>
2025-07-05T14:46:36.482Z,1751726796.482 [DAT](INFO): DAT read: Tx time:14:46:36.5714
2025-07-05T14:46:36.482Z,1751726796.482 [DAT](INFO): Ping request sent.
2025-07-05T14:46:36.482Z,1751726796.482 [DAT](INFO): transmitted an acoustic signal
2025-07-05T14:46:36.483Z,1751726796.483 [DAT](INFO): DAT read: TxSync time:14:46:36.5707
2025-07-05T14:46:36.483Z,1751726796.483 [DAT](INFO): publishing transmit ping time
2025-07-05T14:46:36.484Z,1751726796.484 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.001569
2025-07-05T14:46:36.733Z,1751726796.733 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250819
2025-07-05T14:46:36.985Z,1751726796.985 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502824
2025-07-05T14:46:37.237Z,1751726797.237 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754740
2025-07-05T14:46:37.489Z,1751726797.489 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006796
2025-07-05T14:46:37.741Z,1751726797.741 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258985
2025-07-05T14:46:37.993Z,1751726797.993 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510797
2025-07-05T14:46:38.245Z,1751726798.245 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.763168
2025-07-05T14:46:38.498Z,1751726798.498 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.015318
2025-07-05T14:46:38.749Z,1751726798.749 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266766
2025-07-05T14:46:39.001Z,1751726799.001 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518719
2025-07-05T14:46:39.100Z,1751726799.100 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20250705T135857/Courier0009.lzma
2025-07-05T14:46:39.253Z,1751726799.253 [DAT](INFO): DAT read: Rx Time:14:46:38.9768
2025-07-05T14:46:39.253Z,1751726799.253 [DAT](INFO): Rx dataTimestamp_ set to:1751726799.253208
2025-07-05T14:46:39.254Z,1751726799.254 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.772001
2025-07-05T14:46:39.505Z,1751726799.505 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022720
2025-07-05T14:46:39.758Z,1751726799.758 [DAT](INFO): DAT read: Range 10 to 50 : 0.9 m (Round-trip 1.2 ms) speed 0.0 m/s
2025-07-05T14:46:39.758Z,1751726799.758 [DAT](ERROR): #Rx 1: Read range message, but no direction.
2025-07-05T14:46:40.102Z,1751726800.102 [DataOverHttps](INFO): Moved sent file to Logs/20250705T135857/Courier0009.lzma.bak
2025-07-05T14:46:40.103Z,1751726800.103 [DataOverHttps](INFO): SBD MOMSN=25361130
2025-07-05T14:46:42.114Z,1751726802.114 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-07-05T14:46:51.356Z,1751726811.356 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-07-05T14:46:56.718Z,1751726816.718 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD,7.22, +2979.2-35.92, 7.37, 0.00
2025-07-05T14:47:01.458Z,1751726821.458 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250705T135857/Courier0012.lzma
2025-07-05T14:47:02.466Z,1751726822.466 [DataOverHttps](INFO): Moved sent file to Logs/20250705T135857/Courier0012.lzma.bak
2025-07-05T14:47:02.466Z,1751726822.466 [DataOverHttps](INFO): SBD MOMSN=25361132
2025-07-05T14:47:04.457Z,1751726824.457 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-07-05T14:47:13.902Z,1751726833.902 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-07-05T14:47:21.271Z,1751726841.271 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250705T135857/Courier0015.lzma
2025-07-05T14:47:22.274Z,1751726842.274 [DataOverHttps](INFO): Moved sent file to Logs/20250705T135857/Courier0015.lzma.bak
2025-07-05T14:47:22.274Z,1751726842.274 [DataOverHttps](INFO): SBD MOMSN=25361134
2025-07-05T14:47:24.301Z,1751726844.301 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-07-05T14:47:33.715Z,1751726853.715 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-07-05T14:47:41.903Z,1751726861.903 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-07-05T14:47:42.003Z,1751726862.003 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250705T135857/Courier0018.lzma
2025-07-05T14:47:43.006Z,1751726863.006 [DataOverHttps](INFO): Moved sent file to Logs/20250705T135857/Courier0018.lzma.bak
2025-07-05T14:47:43.006Z,1751726863.006 [DataOverHttps](INFO): SBD MOMSN=25361139
2025-07-05T14:47:46.906Z,1751726866.906 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-07-05T14:47:56.268Z,1751726876.268 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-07-05T14:48:03.490Z,1751726883.490 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250705T144438/Courier0000.lzma
2025-07-05T14:48:04.490Z,1751726884.490 [DataOverHttps](INFO): Moved sent file to Logs/20250705T144438/Courier0000.lzma.bak
2025-07-05T14:48:04.490Z,1751726884.490 [DataOverHttps](IMPORTANT): SBD MOMSN=25361145, MTMSN=20250705T144809
2025-07-05T14:48:07.300Z,1751726887.300 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-07-05T14:48:14.512Z,1751726894.512 [DataOverHttps](INFO): Received command: stop; schedule clear
2025-07-05T14:48:14.570Z,1751726894.570 [CommandExec](IMPORTANT): got command stop
2025-07-05T14:48:14.571Z,1751726894.571 [CommandExec](IMPORTANT): Scheduling is paused for 1 commands
2025-07-05T14:48:14.571Z,1751726894.571 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2025-07-05T14:48:14.571Z,1751726894.571 [CommandExec](IMPORTANT): got command schedule clear
2025-07-05T14:48:14.571Z,1751726894.571 [CommandExec](IMPORTANT): Cleared 1 scheduled commands.
2025-07-05T14:48:14.897Z,1751726894.897 [MissionManager](INFO): MissionManager is completed.
2025-07-05T14:48:14.897Z,1751726894.897 [MissionManager](INFO): Uninitializing Mission OnDock
2025-07-05T14:48:14.897Z,1751726894.897 [OnDock] Stopped
2025-07-05T14:48:14.897Z,1751726894.897 [OnDock](DEBUG): Aggregate::uninitialize OnDock
2025-07-05T14:48:14.897Z,1751726894.897 [OnDock:StandardEnvelopes] Stopped
2025-07-05T14:48:14.898Z,1751726894.898 [OnDock:StandardEnvelopes](DEBUG): Aggregate::uninitialize OnDock:StandardEnvelopes
2025-07-05T14:48:14.898Z,1751726894.898 [OnDock:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2025-07-05T14:48:14.898Z,1751726894.898 [OnDock:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2025-07-05T14:48:14.898Z,1751726894.898 [OnDock:StandardEnvelopes:B.DepthEnvelope] Stopped
2025-07-05T14:48:14.898Z,1751726894.898 [OnDock:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2025-07-05T14:48:14.898Z,1751726894.898 [OnDock:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2025-07-05T14:48:14.898Z,1751726894.898 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2025-07-05T14:48:14.898Z,1751726894.898 [OnDock:BackseatDriver] Stopped
2025-07-05T14:48:14.898Z,1751726894.898 [OnDock:BackseatDriver](DEBUG): Aggregate::uninitialize OnDock:BackseatDriver
2025-07-05T14:48:14.898Z,1751726894.898 [OnDock:BackseatDriver:A.BackseatDriver] Stopped
2025-07-05T14:48:14.898Z,1751726894.898 [OnDock:PowerOnly] Stopped
2025-07-05T14:48:14.898Z,1751726894.898 [OnDock:PowerOnly](DEBUG): Aggregate::uninitialize OnDock:PowerOnly
2025-07-05T14:48:14.898Z,1751726894.898 [OnDock:PowerOnly:E.Wait] Stopped
2025-07-05T14:48:14.899Z,1751726894.899 [OnDock:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component.
2025-07-05T14:48:14.899Z,1751726894.899 [OnDock:dataRead] Stopped
2025-07-05T14:48:14.899Z,1751726894.899 [OnDock:dataRead](DEBUG): Aggregate::uninitialize OnDock:dataRead
2025-07-05T14:48:14.899Z,1751726894.899 [OnDock:dataRead:A] Stopped
2025-07-05T14:48:14.899Z,1751726894.899 [OnDock:J.] Stopped
2025-07-05T14:48:14.899Z,1751726894.899 [OnDock:J.](DEBUG): Uninitializing TrackAcousticContact.
2025-07-05T14:48:14.899Z,1751726894.899 [OnDock:PeriodicCheck] Stopped
2025-07-05T14:48:14.899Z,1751726894.899 [OnDock:PeriodicCheck](DEBUG): Aggregate::uninitialize OnDock:PeriodicCheck
2025-07-05T14:48:14.899Z,1751726894.899 [OnDock:PeriodicCheck:CheckIP] Stopped
2025-07-05T14:48:14.899Z,1751726894.899 [OnDock:PeriodicCheck:CheckIP](DEBUG): Aggregate::uninitialize OnDock:PeriodicCheck:CheckIP
2025-07-05T14:48:14.899Z,1751726894.899 [OnDock:PeriodicCheck:CheckIP:B.Wait] Stopped
2025-07-05T14:48:14.899Z,1751726894.899 [OnDock:PeriodicCheck:CheckIP:B.Wait](DEBUG): Uninitialize Wait Component.
2025-07-05T14:48:14.900Z,1751726894.900 [OnDock:N.Docked] Stopped
2025-07-05T14:48:15.187Z,1751726895.187 [MissionManager](IMPORTANT): Started mission DefaultWithUndock
2025-07-05T14:48:15.187Z,1751726895.187 [DefaultWithUndock] Running Loop=1
2025-07-05T14:48:15.187Z,1751726895.187 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock
2025-07-05T14:48:15.188Z,1751726895.188 [DefaultWithUndock:A.Wait] Running Loop=1
2025-07-05T14:48:15.188Z,1751726895.188 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component.
2025-07-05T14:48:15.194Z,1751726895.194 [DockingStepper](INFO): Pause
2025-07-05T14:48:15.321Z,1751726895.321 [DAT](INFO): DAT read: user:358>
2025-07-05T14:48:15.322Z,1751726895.322 [DAT](INFO): DAT read: Tx time:14:48:15.4214
2025-07-05T14:48:15.322Z,1751726895.322 [DAT](INFO): Ping request sent.
2025-07-05T14:48:15.322Z,1751726895.322 [DAT](INFO): transmitted an acoustic signal
2025-07-05T14:48:15.322Z,1751726895.322 [DAT](INFO): publishing transmit ping time
2025-07-05T14:48:16.516Z,1751726896.516 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-07-05T14:48:17.589Z,1751726897.589 [DAT](INFO): DAT read: Rx Time:14:48:17.2944
2025-07-05T14:48:17.590Z,1751726897.590 [DAT](INFO): Rx dataTimestamp_ set to:1751726897.589352
2025-07-05T14:48:18.100Z,1751726898.100 [DAT](INFO): DAT read: 14:48:17.2944 LVL= 8592, 10449, 8178, 10035, AGC= 38, IDX= 114, 0.43,-0.046, 0.667, 1.587, 1.556, PHS=-1.514,-0.842, 0.028, RAW= 235.8, 31.6, CAL= 246.9, 39.7, ROT= 263.1, -39.7
2025-07-05T14:48:18.101Z,1751726898.101 [DAT](INFO): got valid direction response:
14:48:17.2944 LVL= 8592, 10449, 8178, 10035, AGC= 38, IDX= 114, 0.43,-0.046, 0.667, 1.587, 1.556, PHS=-1.514,-0.842, 0.028, RAW= 235.8, 31.6, CAL= 246.9, 39.7, ROT= 263.1, -39.7
2025-07-05T14:48:18.101Z,1751726898.101 [DAT](INFO): DAT read: OK
2025-07-05T14:48:18.102Z,1751726898.102 [DAT](INFO): unknown deviceResponse_: OK
2025-07-05T14:48:18.102Z,1751726898.102 [DAT](INFO): DAT read: user:359>
2025-07-05T14:48:18.103Z,1751726898.103 [DAT](INFO): DAT read: Tx time:14:48:18.3714
2025-07-05T14:48:18.103Z,1751726898.103 [DAT](INFO): Ping request sent.
2025-07-05T14:48:18.103Z,1751726898.103 [DAT](INFO): transmitted an acoustic signal
2025-07-05T14:48:18.103Z,1751726898.103 [DAT](INFO): #Rx 2: Read direction message, but no range.
2025-07-05T14:48:18.105Z,1751726898.105 [DAT](INFO): direction in FSK: [-0.092433,-0.763827,0.638768]
2025-07-05T14:48:18.106Z,1751726898.106 [DAT](INFO): publishing transmit ping time
2025-07-05T14:48:18.108Z,1751726898.108 [DAT](INFO): publishing transmit ping time
2025-07-05T14:48:20.613Z,1751726900.613 [DAT](INFO): DAT read: Rx Time:14:48:20.2444
2025-07-05T14:48:20.613Z,1751726900.613 [DAT](INFO): Rx dataTimestamp_ set to:1751726900.613112
2025-07-05T14:48:21.121Z,1751726901.121 [DAT](INFO): DAT read: 14:48:20.2444 LVL= 12112, 15441, 10194, 11635, AGC= 37, IDX= 435, 0.06,-0.862,-0.036, 0.519, 0.663, PHS=-1.438,-0.653,-0.147, RAW= 247.1, 35.1, CAL= 260.3, 41.7, ROT= 249.7, -41.7
2025-07-05T14:48:21.123Z,1751726901.123 [DAT](INFO): got valid direction response:
14:48:20.2444 LVL= 12112, 15441, 10194, 11635, AGC= 37, IDX= 435, 0.06,-0.862,-0.036, 0.519, 0.663, PHS=-1.438,-0.653,-0.147, RAW= 247.1, 35.1, CAL= 260.3, 41.7, ROT= 249.7, -41.7
2025-07-05T14:48:21.123Z,1751726901.123 [DAT](INFO): DAT read: OK
2025-07-05T14:48:21.123Z,1751726901.123 [DAT](INFO): unknown deviceResponse_: OK
2025-07-05T14:48:21.124Z,1751726901.124 [DAT](INFO): DAT read: user:360>
2025-07-05T14:48:21.124Z,1751726901.124 [DAT](INFO): DAT read: Tx time:14:48:21.3214
2025-07-05T14:48:21.125Z,1751726901.125 [DAT](INFO): Ping request sent.
2025-07-05T14:48:21.125Z,1751726901.125 [DAT](INFO): transmitted an acoustic signal
2025-07-05T14:48:21.125Z,1751726901.125 [DAT](INFO): #Rx 3: Read direction message, but no range.
2025-07-05T14:48:21.126Z,1751726901.126 [DAT](INFO): direction in FSK: [-0.259035,-0.700264,0.665230]
2025-07-05T14:48:21.126Z,1751726901.126 [DAT](INFO): publishing transmit ping time
2025-07-05T14:48:21.129Z,1751726901.129 [DAT](INFO): publishing transmit ping time
2025-07-05T14:48:23.637Z,1751726903.637 [DAT](INFO): DAT read: Rx Time:14:48:23.1945
2025-07-05T14:48:23.637Z,1751726903.637 [DAT](INFO): Rx dataTimestamp_ set to:1751726903.637170
2025-07-05T14:48:24.145Z,1751726904.145 [DAT](INFO): DAT read: 14:48:23.1945 LVL= 11344, 15889, 10578, 12211, AGC= 37, IDX= 435, 0.04, 0.160, 0.388, 2.141, 1.936, PHS=-1.688,-1.501, 0.202, RAW= 215.1, 30.4, CAL= 212.8, 41.3, ROT= 297.2, -41.3
2025-07-05T14:48:24.146Z,1751726904.146 [DAT](INFO): got valid direction response:
14:48:23.1945 LVL= 11344, 15889, 10578, 12211, AGC= 37, IDX= 435, 0.04, 0.160, 0.388, 2.141, 1.936, PHS=-1.688,-1.501, 0.202, RAW= 215.1, 30.4, CAL= 212.8, 41.3, ROT= 297.2, -41.3
2025-07-05T14:48:24.146Z,1751726904.146 [DAT](INFO): DAT read: OK
2025-07-05T14:48:24.147Z,1751726904.147 [DAT](INFO): unknown deviceResponse_: OK
2025-07-05T14:48:24.147Z,1751726904.147 [DAT](INFO): #Rx 4: Read direction message, but no range.
2025-07-05T14:48:24.164Z,1751726904.164 [DAT](INFO): direction in FSK: [0.343401,-0.668187,0.660002]
2025-07-05T14:48:45.496Z,1751726925.496 [DefaultWithUndock:A.Wait](INFO): Done Waiting.
2025-07-05T14:48:45.496Z,1751726925.496 [DefaultWithUndock:A.Wait] Stopped
2025-07-05T14:48:45.496Z,1751726925.496 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component.
2025-07-05T14:48:45.496Z,1751726925.496 [DefaultWithUndock:LeaveDock] Running Loop=1
2025-07-05T14:48:45.497Z,1751726925.497 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::initialize DefaultWithUndock:LeaveDock
2025-07-05T14:48:45.497Z,1751726925.497 [DefaultWithUndock:LeaveDock:A.] Running Loop=1
2025-07-05T14:48:45.497Z,1751726925.497 [DefaultWithUndock:LeaveDock:A.](INFO): Initializing TrackAcousticContact.
2025-07-05T14:48:45.497Z,1751726925.497 [DefaultWithUndock:LeaveDock:B.Undock] Running Loop=1
2025-07-05T14:48:45.497Z,1751726925.497 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize.
2025-07-05T14:48:45.497Z,1751726925.497 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values.
2025-07-05T14:48:45.892Z,1751726925.892 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize.
2025-07-05T14:48:45.892Z,1751726925.892 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values.
2025-07-05T14:48:45.893Z,1751726925.893 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detaching from dock at depth 63.244621 m.
2025-07-05T14:48:45.894Z,1751726925.894 [DefaultWithUndock:LeaveDock:A.] Running Loop=1
2025-07-05T14:48:45.894Z,1751726925.894 [DefaultWithUndock:LeaveDock:A.](ERROR): Failed to read sound speed
2025-07-05T14:48:45.937Z,1751726925.937 [DockingStepper](INFO): Resume
2025-07-05T14:48:46.365Z,1751726926.365 [DockingStepper](INFO): Resuming
2025-07-05T14:48:46.637Z,1751726926.637 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-05T14:48:46.728Z,1751726926.728 [DockingStepper](INFO): Start
2025-07-05T14:48:46.728Z,1751726926.728 [DockingStepper](DEBUG): Initializing EZServoServo.
2025-07-05T14:48:46.849Z,1751726926.849 [DockingStepper](DEBUG): Initializing DockingStepper.
2025-07-05T14:48:47.137Z,1751726927.137 [DockingStepper](INFO): Starting
2025-07-05T14:48:47.561Z,1751726927.561 [DockingStepper](INFO): Starting
2025-07-05T14:48:47.640Z,1751726927.640 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-07-05T14:48:47.937Z,1751726927.937 [DockingStepper](INFO): Starting
2025-07-05T14:48:47.984Z,1751726927.984 [DockingStepper](INFO): Init string: /1z16352aE13561N3m50
2025-07-05T14:48:48.381Z,1751726928.381 [DockingStepper](INFO): Starting
2025-07-05T14:48:48.749Z,1751726928.749 [DockingStepper](INFO): Starting
2025-07-05T14:48:48.794Z,1751726928.794 [CTD_Seabird](ERROR): Failed to parse device response:
2025-07-05T14:48:49.137Z,1751726929.137 [DockingStepper](INFO): Starting
2025-07-05T14:48:49.650Z,1751726929.650 [DockingStepper](INFO): Starting
2025-07-05T14:48:49.677Z,1751726929.677 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@
2025-07-05T14:48:49.977Z,1751726929.977 [DockingStepper](INFO): Starting
2025-07-05T14:48:50.441Z,1751726930.441 [DockingStepper](INFO): Changing to mode: 3
2025-07-05T14:48:50.441Z,1751726930.441 [DockingStepper](INFO): Detach mode.
2025-07-05T14:48:50.504Z,1751726930.504 [DockingStepper](INFO): Commanding negative:54500
2025-07-05T14:48:55.623Z,1751726935.623 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2025-07-05T14:48:55.677Z,1751726935.677 [DAT](DEBUG): Acoustic response timeout
2025-07-05T14:48:55.677Z,1751726935.677 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-07-05T14:48:55.929Z,1751726935.929 [DAT](INFO): DAT read: user:361>
2025-07-05T14:48:55.930Z,1751726935.930 [DAT](INFO): DAT read: Tx time:14:48:56.0228
2025-07-05T14:48:55.930Z,1751726935.930 [DAT](INFO): Ping request sent.
2025-07-05T14:48:55.930Z,1751726935.930 [DAT](INFO): transmitted an acoustic signal
2025-07-05T14:48:55.931Z,1751726935.931 [DAT](INFO): DAT read: TxSync time:14:48:56.0220
2025-07-05T14:48:55.931Z,1751726935.931 [DAT](INFO): publishing transmit ping time
2025-07-05T14:48:55.932Z,1751726935.932 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.001574
2025-07-05T14:48:56.181Z,1751726936.181 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250870
2025-07-05T14:48:56.433Z,1751726936.433 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502870
2025-07-05T14:48:56.460Z,1751726936.460 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock
2025-07-05T14:48:56.460Z,1751726936.460 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Engaging thruster for 10.0 seconds (max) to disengage.
2025-07-05T14:48:56.685Z,1751726936.685 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754870
2025-07-05T14:48:56.788Z,1751726936.788 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock
2025-07-05T14:48:56.937Z,1751726936.937 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006843
2025-07-05T14:48:57.185Z,1751726937.185 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock
2025-07-05T14:48:57.189Z,1751726937.189 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258898
2025-07-05T14:48:57.441Z,1751726937.441 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.511084
2025-07-05T14:48:57.614Z,1751726937.614 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock
2025-07-05T14:48:57.693Z,1751726937.693 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762926
2025-07-05T14:48:57.945Z,1751726937.945 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014823
2025-07-05T14:48:58.009Z,1751726938.009 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock
2025-07-05T14:48:58.197Z,1751726938.197 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266757
2025-07-05T14:48:58.449Z,1751726938.449 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518856
2025-07-05T14:48:58.533Z,1751726938.533 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock
2025-07-05T14:48:58.701Z,1751726938.701 [DAT](INFO): DAT read: Rx Time:14:48:58.4284
2025-07-05T14:48:58.701Z,1751726938.701 [DAT](INFO): Rx dataTimestamp_ set to:1751726938.701066
2025-07-05T14:48:58.702Z,1751726938.702 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.771938
2025-07-05T14:48:58.800Z,1751726938.800 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock
2025-07-05T14:48:58.953Z,1751726938.953 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022810
2025-07-05T14:48:59.208Z,1751726939.208 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.277169
2025-07-05T14:48:59.261Z,1751726939.261 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock
2025-07-05T14:48:59.466Z,1751726939.466 [DAT](INFO): DAT read: 14:48:58.4284 LVL= 17648, 26161, 21938, 23107, AGC= 41, IDX= 251,-0.09,-2.801, 2.992, 2.704,-2.101, PHS=-0.613,-1.144,-1.481, RAW= 67.3, 56.5, CAL= 65.9, 61.2, ROT= 84.1, -61.2
2025-07-05T14:48:59.467Z,1751726939.467 [DAT](INFO): got valid direction response:
14:48:58.4284 LVL= 17648, 26161, 21938, 23107, AGC= 41, IDX= 251,-0.09,-2.801, 2.992, 2.704,-2.101, PHS=-0.613,-1.144,-1.481, RAW= 67.3, 56.5, CAL= 65.9, 61.2, ROT= 84.1, -61.2
2025-07-05T14:48:59.468Z,1751726939.468 [DAT](INFO): DAT read: Bearing 84.1, -61.2 (Local)
2025-07-05T14:48:59.469Z,1751726939.469 [DAT](INFO): Local bearing/azimuth received:
Bearing 84.1, -61.2 (Local)
2025-07-05T14:48:59.470Z,1751726939.470 [DAT](INFO): DAT read: Range 10 to 50 : 1.0 m (Round-trip 1.4 ms) speed 0.0 m/s
2025-07-05T14:48:59.471Z,1751726939.471 [DAT](INFO): #Rx 1: Read range and direction messages.
2025-07-05T14:48:59.472Z,1751726939.472 [DAT](INFO): direction in FSK: [0.049521,0.479202,0.876307]
2025-07-05T14:48:59.472Z,1751726939.472 [DAT](INFO): publishing direction and range info
2025-07-05T14:49:05.705Z,1751726945.705 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2025-07-05T14:49:05.758Z,1751726945.758 [DAT](INFO): ****** received valid address query ******
2025-07-05T14:49:05.758Z,1751726945.758 [DAT](INFO): ****** received valid ping request ******
2025-07-05T14:49:05.758Z,1751726945.758 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-07-05T14:49:06.009Z,1751726946.009 [DAT](INFO): DAT read: user:362>
2025-07-05T14:49:06.010Z,1751726946.010 [DAT](INFO): DAT read: Tx time:14:49:06.1229
2025-07-05T14:49:06.010Z,1751726946.010 [DAT](INFO): Ping request sent.
2025-07-05T14:49:06.010Z,1751726946.010 [DAT](INFO): transmitted an acoustic signal
2025-07-05T14:49:06.010Z,1751726946.010 [DAT](INFO): publishing transmit ping time
2025-07-05T14:49:06.011Z,1751726946.011 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000743
2025-07-05T14:49:06.261Z,1751726946.261 [DAT](INFO): DAT read: TxSync time:14:49:06.1221
2025-07-05T14:49:06.262Z,1751726946.262 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251704
2025-07-05T14:49:06.516Z,1751726946.516 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.505135
2025-07-05T14:49:06.518Z,1751726946.518 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Thruster off.
2025-07-05T14:49:06.765Z,1751726946.765 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754820
2025-07-05T14:49:07.018Z,1751726947.018 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.007913
2025-07-05T14:49:07.269Z,1751726947.269 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258723
2025-07-05T14:49:07.524Z,1751726947.524 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.513130
2025-07-05T14:49:07.773Z,1751726947.773 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.763098
2025-07-05T14:49:08.026Z,1751726948.026 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.016309
2025-07-05T14:49:08.277Z,1751726948.277 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266773
2025-07-05T14:49:08.532Z,1751726948.532 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.521624
2025-07-05T14:49:08.781Z,1751726948.781 [DAT](INFO): DAT read: Rx Time:14:49:08.5289
2025-07-05T14:49:08.781Z,1751726948.781 [DAT](INFO): Rx dataTimestamp_ set to:1751726948.781198
2025-07-05T14:49:08.782Z,1751726948.782 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.771993
2025-07-05T14:49:09.033Z,1751726949.033 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022785
2025-07-05T14:49:09.296Z,1751726949.296 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.286100
2025-07-05T14:49:09.562Z,1751726949.562 [DAT](INFO): DAT read: 14:49:08.5289 LVL= 16496, 20385, 20962, 24803, AGC= 39, IDX= 446,-0.14, 1.342,-0.512, 0.386, 1.573, PHS=-0.143,-2.038,-1.190, RAW= 116.5, 35.9, CAL= 125.8, 46.5, ROT= 24.2, -46.5
2025-07-05T14:49:09.579Z,1751726949.579 [DAT](INFO): got valid direction response:
14:49:08.5289 LVL= 16496, 20385, 20962, 24803, AGC= 39, IDX= 446,-0.14, 1.342,-0.512, 0.386, 1.573, PHS=-0.143,-2.038,-1.190, RAW= 116.5, 35.9, CAL= 125.8, 46.5, ROT= 24.2, -46.5
2025-07-05T14:49:09.602Z,1751726949.602 [DAT](INFO): DAT read: Bearing 24.2, -46.5 (Local)
2025-07-05T14:49:09.608Z,1751726949.608 [DAT](INFO): Local bearing/azimuth received:
Bearing 24.2, -46.5 (Local)
2025-07-05T14:49:09.624Z,1751726949.624 [DAT](INFO): DAT read: Range 10 to 50 : 1.3 m (Round-trip 1.8 ms) speed -0.2 m/s
2025-07-05T14:49:09.625Z,1751726949.625 [DAT](INFO): #Rx 1: Read range and direction messages.
2025-07-05T14:49:09.626Z,1751726949.626 [DAT](INFO): direction in FSK: [0.627862,0.282172,0.725374]
2025-07-05T14:49:09.626Z,1751726949.626 [DAT](INFO): publishing direction and range info
2025-07-05T14:49:15.765Z,1751726955.765 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.251472
2025-07-05T14:49:15.801Z,1751726955.801 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2025-07-05T14:49:15.842Z,1751726955.842 [DAT](INFO): ****** received valid address query ******
2025-07-05T14:49:15.842Z,1751726955.842 [DAT](INFO): ****** received valid ping request ******
2025-07-05T14:49:15.843Z,1751726955.843 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-07-05T14:49:16.093Z,1751726956.093 [DAT](INFO): DAT read: user:363>
2025-07-05T14:49:16.093Z,1751726956.093 [DAT](INFO): DAT read: Tx time:14:49:16.2230
2025-07-05T14:49:16.094Z,1751726956.094 [DAT](INFO): Ping request sent.
2025-07-05T14:49:16.094Z,1751726956.094 [DAT](INFO): transmitted an acoustic signal
2025-07-05T14:49:16.094Z,1751726956.094 [DAT](INFO): publishing transmit ping time
2025-07-05T14:49:16.095Z,1751726956.095 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000734
2025-07-05T14:49:16.347Z,1751726956.347 [DAT](INFO): DAT read: TxSync time:14:49:16.2222
2025-07-05T14:49:16.350Z,1751726956.350 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.255805
2025-07-05T14:49:16.597Z,1751726956.597 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502970
2025-07-05T14:49:16.768Z,1751726956.768 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-07-05T14:49:16.849Z,1751726956.849 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754835
2025-07-05T14:49:17.101Z,1751726957.101 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.007015
2025-07-05T14:49:17.353Z,1751726957.353 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258923
2025-07-05T14:49:17.605Z,1751726957.605 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510882
2025-07-05T14:49:17.866Z,1751726957.866 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.771754
2025-07-05T14:49:18.117Z,1751726958.117 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.022882
2025-07-05T14:49:18.369Z,1751726958.369 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.274841
2025-07-05T14:49:18.621Z,1751726958.621 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.526982
2025-07-05T14:49:18.874Z,1751726958.874 [DAT](INFO): DAT read: Rx Time:14:49:18.6297
2025-07-05T14:49:18.874Z,1751726958.874 [DAT](INFO): Rx dataTimestamp_ set to:1751726958.873854
2025-07-05T14:49:18.875Z,1751726958.875 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.780782
2025-07-05T14:49:19.125Z,1751726959.125 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.030820
2025-07-05T14:49:19.377Z,1751726959.377 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.282834
2025-07-05T14:49:19.630Z,1751726959.630 [DAT](INFO): DAT read: Range 10 to 50 : 1.9 m (Round-trip 2.6 ms) speed 0.0 m/s
2025-07-05T14:49:19.630Z,1751726959.630 [DAT](ERROR): #Rx 1: Read range message, but no direction.
2025-07-05T14:49:21.892Z,1751726961.892 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 0.00, 87.96, 69.96, 72.34,
2025-07-05T14:49:25.912Z,1751726965.912 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2025-07-05T14:49:25.929Z,1751726965.929 [DAT](INFO): ****** received valid address query ******
2025-07-05T14:49:25.929Z,1751726965.929 [DAT](INFO): ****** received valid ping request ******
2025-07-05T14:49:25.929Z,1751726965.929 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-07-05T14:49:26.181Z,1751726966.181 [DAT](INFO): DAT read: user:364>
2025-07-05T14:49:26.182Z,1751726966.182 [DAT](INFO): DAT read: Tx time:14:49:26.2731
2025-07-05T14:49:26.182Z,1751726966.182 [DAT](INFO): Ping request sent.
2025-07-05T14:49:26.182Z,1751726966.182 [DAT](INFO): transmitted an acoustic signal
2025-07-05T14:49:26.183Z,1751726966.183 [DAT](INFO): DAT read: TxSync time:14:49:26.2723
2025-07-05T14:49:26.183Z,1751726966.183 [DAT](INFO): publishing transmit ping time
2025-07-05T14:49:26.184Z,1751726966.184 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.001660
2025-07-05T14:49:26.433Z,1751726966.433 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250575
2025-07-05T14:49:26.685Z,1751726966.685 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502628
2025-07-05T14:49:26.937Z,1751726966.937 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754895
2025-07-05T14:49:27.190Z,1751726967.190 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.007466
2025-07-05T14:49:27.441Z,1751726967.441 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258515
2025-07-05T14:49:27.693Z,1751726967.693 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510796
2025-07-05T14:49:27.945Z,1751726967.945 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762560
2025-07-05T14:49:28.197Z,1751726968.197 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014486
2025-07-05T14:49:28.453Z,1751726968.453 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.270566
2025-07-05T14:49:28.705Z,1751726968.705 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.522671
2025-07-05T14:49:28.957Z,1751726968.957 [DAT](INFO): DAT read: Rx Time:14:49:28.6811
2025-07-05T14:49:28.957Z,1751726968.957 [DAT](INFO): Rx dataTimestamp_ set to:1751726968.957028
2025-07-05T14:49:28.958Z,1751726968.958 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.775600
2025-07-05T14:49:29.209Z,1751726969.209 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.027101
2025-07-05T14:49:29.468Z,1751726969.468 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.285010
2025-07-05T14:49:29.718Z,1751726969.718 [DAT](INFO): DAT read: 14:49:28.6811 LVL= 15168, 22801, 18482, 24051, AGC= 34, IDX= 452,-0.22, 2.136, 0.101, 0.323, 1.134, PHS= 1.090,-0.986,-0.814, RAW= 94.3, 7.2, CAL= 102.8, 6.7, ROT= 47.2, -6.7
2025-07-05T14:49:29.719Z,1751726969.719 [DAT](INFO): got valid direction response:
14:49:28.6811 LVL= 15168, 22801, 18482, 24051, AGC= 34, IDX= 452,-0.22, 2.136, 0.101, 0.323, 1.134, PHS= 1.090,-0.986,-0.814, RAW= 94.3, 7.2, CAL= 102.8, 6.7, ROT= 47.2, -6.7
2025-07-05T14:49:29.720Z,1751726969.720 [DAT](INFO): DAT read: Bearing 47.2, -6.7 (Local)
2025-07-05T14:49:29.721Z,1751726969.721 [DAT](INFO): Local bearing/azimuth received:
Bearing 47.2, -6.7 (Local)
2025-07-05T14:49:29.722Z,1751726969.722 [DAT](INFO): DAT read: Range 10 to 50 : 2.9 m (Round-trip 3.9 ms) speed 0.0 m/s
2025-07-05T14:49:29.723Z,1751726969.723 [DAT](INFO): #Rx 1: Read range and direction messages.
2025-07-05T14:49:29.724Z,1751726969.724 [DAT](INFO): direction in FSK: [0.674801,0.728719,0.116671]
2025-07-05T14:49:29.724Z,1751726969.724 [DAT](INFO): publishing direction and range info
2025-07-05T14:49:36.079Z,1751726976.079 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2025-07-05T14:49:36.265Z,1751726976.265 [DAT](DEBUG): Acoustic response timeout
2025-07-05T14:49:36.265Z,1751726976.265 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-07-05T14:49:36.517Z,1751726976.517 [DAT](INFO): DAT read: user:365>
2025-07-05T14:49:36.517Z,1751726976.517 [DAT](INFO): DAT read: Tx time:14:49:36.6232
2025-07-05T14:49:36.518Z,1751726976.518 [DAT](INFO): Ping request sent.
2025-07-05T14:49:36.518Z,1751726976.518 [DAT](INFO): transmitted an acoustic signal
2025-07-05T14:49:36.518Z,1751726976.518 [DAT](INFO): publishing transmit ping time
2025-07-05T14:49:36.519Z,1751726976.519 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000740
2025-07-05T14:49:36.770Z,1751726976.770 [DAT](INFO): DAT read: TxSync time:14:49:36.6224
2025-07-05T14:49:36.771Z,1751726976.771 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.252660
2025-07-05T14:49:37.021Z,1751726977.021 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502947
2025-07-05T14:49:37.275Z,1751726977.275 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.756786
2025-07-05T14:49:37.525Z,1751726977.525 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006924
2025-07-05T14:49:37.777Z,1751726977.777 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258974
2025-07-05T14:49:38.037Z,1751726978.037 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.513735
2025-07-05T14:49:38.281Z,1751726978.281 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762898
2025-07-05T14:49:38.533Z,1751726978.533 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.015104
2025-07-05T14:49:38.785Z,1751726978.785 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266977
2025-07-05T14:49:39.037Z,1751726979.037 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518950
2025-07-05T14:49:39.290Z,1751726979.290 [DAT](INFO): DAT read: Rx Time:14:49:39.0318
2025-07-05T14:49:39.290Z,1751726979.290 [DAT](INFO): Rx dataTimestamp_ set to:1751726979.289766
2025-07-05T14:49:39.291Z,1751726979.291 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.773008
2025-07-05T14:49:39.542Z,1751726979.542 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.023752
2025-07-05T14:49:39.795Z,1751726979.795 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.276931
2025-07-05T14:49:40.063Z,1751726980.063 [DAT](INFO): DAT read: 14:49:39.0318 LVL= 18784, 28593, 25074, 32595, AGC= 39, IDX= 441, 0.43,-1.854, 2.408, 2.646,-2.623, PHS= 0.856,-1.205,-1.018, RAW= 94.7, 13.7, CAL= 103.0, 15.6, ROT= 47.0, -15.6
2025-07-05T14:49:40.064Z,1751726980.064 [DAT](INFO): got valid direction response:
14:49:39.0318 LVL= 18784, 28593, 25074, 32595, AGC= 39, IDX= 441, 0.43,-1.854, 2.408, 2.646,-2.623, PHS= 0.856,-1.205,-1.018, RAW= 94.7, 13.7, CAL= 103.0, 15.6, ROT= 47.0, -15.6
2025-07-05T14:49:40.065Z,1751726980.065 [DAT](INFO): DAT read: Bearing 47.0, -15.6 (Local)
2025-07-05T14:49:40.065Z,1751726980.065 [DAT](INFO): Local bearing/azimuth received:
Bearing 47.0, -15.6 (Local)
2025-07-05T14:49:40.066Z,1751726980.066 [DAT](INFO): DAT read: Range 10 to 50 : 3.3 m (Round-trip 4.4 ms) speed -0.1 m/s
2025-07-05T14:49:40.067Z,1751726980.067 [DAT](INFO): #Rx 1: Read range and direction messages.
2025-07-05T14:49:40.080Z,1751726980.080 [DAT](INFO): direction in FSK: [0.656875,0.704413,0.268920]
2025-07-05T14:49:40.081Z,1751726980.081 [DAT](INFO): publishing direction and range info
2025-07-05T14:49:46.160Z,1751726986.160 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2025-07-05T14:49:46.349Z,1751726986.349 [DAT](INFO): ****** received valid address query ******
2025-07-05T14:49:46.349Z,1751726986.349 [DAT](INFO): ****** received valid ping request ******
2025-07-05T14:49:46.349Z,1751726986.349 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-07-05T14:49:46.601Z,1751726986.601 [DAT](INFO): DAT read: user:366>
2025-07-05T14:49:46.601Z,1751726986.601 [DAT](INFO): DAT read: Tx time:14:49:46.7233
2025-07-05T14:49:46.602Z,1751726986.602 [DAT](INFO): Ping request sent.
2025-07-05T14:49:46.602Z,1751726986.602 [DAT](INFO): transmitted an acoustic signal
2025-07-05T14:49:46.602Z,1751726986.602 [DAT](INFO): publishing transmit ping time
2025-07-05T14:49:46.603Z,1751726986.603 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000724
2025-07-05T14:49:46.853Z,1751726986.853 [DAT](INFO): DAT read: TxSync time:14:49:46.7225
2025-07-05T14:49:46.854Z,1751726986.854 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251931
2025-07-05T14:49:46.896Z,1751726986.896 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-05T14:49:47.108Z,1751726987.108 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.505324
2025-07-05T14:49:47.357Z,1751726987.357 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.755326
2025-07-05T14:49:47.609Z,1751726987.609 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006976
2025-07-05T14:49:47.861Z,1751726987.861 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258872
2025-07-05T14:49:47.900Z,1751726987.900 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-07-05T14:49:48.116Z,1751726988.116 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.513248
2025-07-05T14:49:48.365Z,1751726988.365 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762939
2025-07-05T14:49:48.617Z,1751726988.617 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014815
2025-07-05T14:49:48.869Z,1751726988.869 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266849
2025-07-05T14:49:49.124Z,1751726989.124 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.521564
2025-07-05T14:49:49.374Z,1751726989.374 [DAT](INFO): DAT read: Rx Time:14:49:49.1329
2025-07-05T14:49:49.375Z,1751726989.375 [DAT](INFO): Rx dataTimestamp_ set to:1751726989.374587
2025-07-05T14:49:49.376Z,1751726989.376 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.773753
2025-07-05T14:49:49.625Z,1751726989.625 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022927
2025-07-05T14:49:49.877Z,1751726989.877 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274943
2025-07-05T14:49:50.158Z,1751726990.158 [DAT](INFO): DAT read: 14:49:49.1329 LVL= 14672, 21665, 17282, 19603, AGC= 41, IDX= 436,-0.03, 2.146, 0.160, 0.533, 1.615, PHS= 0.619,-1.408,-1.085, RAW= 98.5, 19.4, CAL= 106.0, 22.4, ROT= 44.0, -22.4
2025-07-05T14:49:50.174Z,1751726990.174 [DAT](INFO): got valid direction response:
14:49:49.1329 LVL= 14672, 21665, 17282, 19603, AGC= 41, IDX= 436,-0.03, 2.146, 0.160, 0.533, 1.615, PHS= 0.619,-1.408,-1.085, RAW= 98.5, 19.4, CAL= 106.0, 22.4, ROT= 44.0, -22.4
2025-07-05T14:49:50.182Z,1751726990.182 [DAT](INFO): DAT read: Bearing 44.0, -22.4 (Local)
2025-07-05T14:49:50.183Z,1751726990.183 [DAT](INFO): Local bearing/azimuth received:
Bearing 44.0, -22.4 (Local)
2025-07-05T14:49:50.214Z,1751726990.214 [DAT](INFO): DAT read: Range 10 to 50 : 4.0 m (Round-trip 5.4 ms) speed 0.0 m/s
2025-07-05T14:49:50.215Z,1751726990.215 [DAT](INFO): #Rx 1: Read range and direction messages.
2025-07-05T14:49:50.215Z,1751726990.215 [DAT](INFO): direction in FSK: [0.665063,0.642244,0.381070]
2025-07-05T14:49:50.220Z,1751726990.220 [DAT](INFO): publishing direction and range info
2025-07-05T14:49:56.177Z,1751726996.177 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2025-07-05T14:49:56.193Z,1751726996.193 [DAT](INFO): ****** received valid address query ******
2025-07-05T14:49:56.193Z,1751726996.193 [DAT](INFO): ****** received valid ping request ******
2025-07-05T14:49:56.193Z,1751726996.193 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-07-05T14:49:56.445Z,1751726996.445 [DAT](INFO): DAT read: user:367>
2025-07-05T14:49:56.445Z,1751726996.445 [DAT](INFO): DAT read: Tx time:14:49:56.5733
2025-07-05T14:49:56.446Z,1751726996.446 [DAT](INFO): Ping request sent.
2025-07-05T14:49:56.446Z,1751726996.446 [DAT](INFO): transmitted an acoustic signal
2025-07-05T14:49:56.446Z,1751726996.446 [DAT](INFO): publishing transmit ping time
2025-07-05T14:49:56.447Z,1751726996.447 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000740
2025-07-05T14:49:56.698Z,1751726996.698 [DAT](INFO): DAT read: TxSync time:14:49:56.5725
2025-07-05T14:49:56.699Z,1751726996.699 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.252748
2025-07-05T14:49:56.949Z,1751726996.949 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502804
2025-07-05T14:49:57.201Z,1751726997.201 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754867
2025-07-05T14:49:57.453Z,1751726997.453 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006968
2025-07-05T14:49:57.705Z,1751726997.705 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258868
2025-07-05T14:49:57.957Z,1751726997.957 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.511095
2025-07-05T14:49:58.209Z,1751726998.209 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762995
2025-07-05T14:49:58.463Z,1751726998.463 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.016941
2025-07-05T14:49:58.713Z,1751726998.713 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.267150
2025-07-05T14:49:58.965Z,1751726998.965 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518917
2025-07-05T14:49:59.217Z,1751726999.217 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770845
2025-07-05T14:49:59.469Z,1751726999.469 [DAT](INFO): DAT read: Rx Time:14:49:58.9850
2025-07-05T14:49:59.470Z,1751726999.470 [DAT](INFO): Rx dataTimestamp_ set to:1751726999.469360
2025-07-05T14:49:59.470Z,1751726999.470 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.024288
2025-07-05T14:49:59.721Z,1751726999.721 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274957
2025-07-05T14:49:59.979Z,1751726999.979 [DAT](INFO): DAT read: 14:49:58.9850 LVL= 17888, 32753, 22322, 30595, AGC= 52, IDX= 455, 0.18, 1.329,-0.792, 0.543, 0.818, PHS= 0.599,-1.563,-0.278, RAW= 126.2, 13.1, CAL= 132.5, 8.6, ROT= 17.5, -8.6
2025-07-05T14:49:59.980Z,1751726999.980 [DAT](INFO): got valid direction response:
14:49:58.9850 LVL= 17888, 32753, 22322, 30595, AGC= 52, IDX= 455, 0.18, 1.329,-0.792, 0.543, 0.818, PHS= 0.599,-1.563,-0.278, RAW= 126.2, 13.1, CAL= 132.5, 8.6, ROT= 17.5, -8.6
2025-07-05T14:49:59.981Z,1751726999.981 [DAT](INFO): DAT read: Bearing 17.5, -8.6 (Local)
2025-07-05T14:49:59.981Z,1751726999.981 [DAT](INFO): Local bearing/azimuth received:
Bearing 17.5, -8.6 (Local)
2025-07-05T14:49:59.982Z,1751726999.982 [DAT](INFO): DAT read: Range 10 to 50 : 5.6 m (Round-trip 7.5 ms) speed 0.0 m/s
2025-07-05T14:49:59.983Z,1751726999.983 [DAT](INFO): #Rx 1: Read range and direction messages.
2025-07-05T14:49:59.984Z,1751726999.984 [DAT](INFO): direction in FSK: [0.942994,0.297325,0.149535]
2025-07-05T14:49:59.985Z,1751726999.985 [DAT](INFO): publishing direction and range info
2025-07-05T14:50:06.269Z,1751727006.269 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2025-07-05T14:50:06.277Z,1751727006.277 [DAT](INFO): ****** received valid address query ******
2025-07-05T14:50:06.277Z,1751727006.277 [DAT](INFO): ****** received valid ping request ******
2025-07-05T14:50:06.278Z,1751727006.278 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-07-05T14:50:06.529Z,1751727006.529 [DAT](INFO): DAT read: user:368>
2025-07-05T14:50:06.530Z,1751727006.530 [DAT](INFO): DAT read: Tx time:14:50:06.6234
2025-07-05T14:50:06.530Z,1751727006.530 [DAT](INFO): Ping request sent.
2025-07-05T14:50:06.530Z,1751727006.530 [DAT](INFO): transmitted an acoustic signal
2025-07-05T14:50:06.531Z,1751727006.531 [DAT](INFO): DAT read: TxSync time:14:50:06.6226
2025-07-05T14:50:06.531Z,1751727006.531 [DAT](INFO): publishing transmit ping time
2025-07-05T14:50:06.532Z,1751727006.532 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.001719
2025-07-05T14:50:06.782Z,1751727006.782 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.252301
2025-07-05T14:50:07.033Z,1751727007.033 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502643
2025-07-05T14:50:07.285Z,1751727007.285 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754712
2025-07-05T14:50:07.550Z,1751727007.550 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.019726
2025-07-05T14:50:07.802Z,1751727007.802 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.271647
2025-07-05T14:50:08.054Z,1751727008.054 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.523071
2025-07-05T14:50:08.305Z,1751727008.305 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.774697
2025-07-05T14:50:08.557Z,1751727008.557 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.026635
2025-07-05T14:50:08.811Z,1751727008.811 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.280470
2025-07-05T14:50:09.061Z,1751727009.061 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.530632
2025-07-05T14:50:09.313Z,1751727009.313 [DAT](INFO): DAT read: Rx Time:14:50:09.0357
2025-07-05T14:50:09.313Z,1751727009.313 [DAT](INFO): Rx dataTimestamp_ set to:1751727009.313072
2025-07-05T14:50:09.314Z,1751727009.314 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.783762
2025-07-05T14:50:09.565Z,1751727009.565 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.035058
2025-07-05T14:50:09.819Z,1751727009.819 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.288609
2025-07-05T14:50:10.074Z,1751727010.074 [DAT](INFO): DAT read: 14:50:09.0357 LVL= 20272, 24433, 20834, 27635, AGC= 53, IDX= 447,-0.18,-0.984,-3.110,-2.395,-1.508, PHS= 0.612,-1.556,-0.890, RAW= 107.4, 18.6, CAL= 114.8, 17.8, ROT= 35.2, -17.8
2025-07-05T14:50:10.076Z,1751727010.076 [DAT](INFO): got valid direction response:
14:50:09.0357 LVL= 20272, 24433, 20834, 27635, AGC= 53, IDX= 447,-0.18,-0.984,-3.110,-2.395,-1.508, PHS= 0.612,-1.556,-0.890, RAW= 107.4, 18.6, CAL= 114.8, 17.8, ROT= 35.2, -17.8
2025-07-05T14:50:10.077Z,1751727010.077 [DAT](INFO): DAT read: Bearing 35.2, -17.8 (Local)
2025-07-05T14:50:10.077Z,1751727010.077 [DAT](INFO): Local bearing/azimuth received:
Bearing 35.2, -17.8 (Local)
2025-07-05T14:50:10.078Z,1751727010.078 [DAT](INFO): DAT read: Range 10 to 50 : 6.0 m (Round-trip 8.1 ms) speed -0.2 m/s
2025-07-05T14:50:10.079Z,1751727010.079 [DAT](INFO): #Rx 1: Read range and direction messages.
2025-07-05T14:50:10.080Z,1751727010.080 [DAT](INFO): direction in FSK: [0.778028,0.548838,0.305695]
2025-07-05T14:50:10.080Z,1751727010.080 [DAT](INFO): publishing direction and range info
2025-07-05T14:50:16.372Z,1751727016.372 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2025-07-05T14:50:16.621Z,1751727016.621 [DAT](DEBUG): Acoustic response timeout
2025-07-05T14:50:16.621Z,1751727016.621 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-07-05T14:50:16.876Z,1751727016.876 [DAT](INFO): DAT read: user:369>
2025-07-05T14:50:16.877Z,1751727016.877 [DAT](INFO): DAT read: Tx time:14:50:16.9735
2025-07-05T14:50:16.877Z,1751727016.877 [DAT](INFO): Ping request sent.
2025-07-05T14:50:16.877Z,1751727016.877 [DAT](INFO): transmitted an acoustic signal
2025-07-05T14:50:16.878Z,1751727016.878 [DAT](INFO): DAT read: TxSync time:14:50:16.9727
2025-07-05T14:50:16.878Z,1751727016.878 [DAT](INFO): publishing transmit ping time
2025-07-05T14:50:16.879Z,1751727016.879 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.001556
2025-07-05T14:50:17.125Z,1751727017.125 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.247848
2025-07-05T14:50:17.377Z,1751727017.377 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.499759
2025-07-05T14:50:17.629Z,1751727017.629 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.752155
2025-07-05T14:50:17.881Z,1751727017.881 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.003821
2025-07-05T14:50:18.024Z,1751727018.024 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-05T14:50:18.133Z,1751727018.133 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.255752
2025-07-05T14:50:18.385Z,1751727018.385 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.507894
2025-07-05T14:50:18.637Z,1751727018.637 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.759853
2025-07-05T14:50:18.890Z,1751727018.890 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.012384
2025-07-05T14:50:19.028Z,1751727019.028 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-07-05T14:50:19.141Z,1751727019.141 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.263786
2025-07-05T14:50:19.393Z,1751727019.393 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.515844
2025-07-05T14:50:19.645Z,1751727019.645 [DAT](INFO): DAT read: Rx Time:14:50:19.3882
2025-07-05T14:50:19.646Z,1751727019.646 [DAT](INFO): Rx dataTimestamp_ set to:1751727019.645651
2025-07-05T14:50:19.647Z,1751727019.647 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.769758
2025-07-05T14:50:19.897Z,1751727019.897 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.019848
2025-07-05T14:50:20.152Z,1751727020.152 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274368
2025-07-05T14:50:20.406Z,1751727020.406 [DAT](INFO): DAT read: 14:50:19.3882 LVL= 15424, 28097, 23618, 27267, AGC= 46, IDX= 456,-0.41,-0.557,-2.377,-1.933,-0.935, PHS= 0.466,-1.395,-1.002, RAW= 101.6, 21.9, CAL= 108.3, 26.3, ROT= 41.7, -26.3
2025-07-05T14:50:20.407Z,1751727020.407 [DAT](INFO): got valid direction response:
14:50:19.3882 LVL= 15424, 28097, 23618, 27267, AGC= 46, IDX= 456,-0.41,-0.557,-2.377,-1.933,-0.935, PHS= 0.466,-1.395,-1.002, RAW= 101.6, 21.9, CAL= 108.3, 26.3, ROT= 41.7, -26.3
2025-07-05T14:50:20.408Z,1751727020.408 [DAT](INFO): DAT read: Bearing 41.7, -26.3 (Local)
2025-07-05T14:50:20.409Z,1751727020.409 [DAT](INFO): Local bearing/azimuth received:
Bearing 41.7, -26.3 (Local)
2025-07-05T14:50:20.410Z,1751727020.410 [DAT](INFO): DAT read: Range 10 to 50 : 7.8 m (Round-trip 10.5 ms) speed 0.0 m/s
2025-07-05T14:50:20.410Z,1751727020.410 [DAT](INFO): #Rx 1: Read range and direction messages.
2025-07-05T14:50:20.411Z,1751727020.411 [DAT](INFO): direction in FSK: [0.669351,0.596370,0.443071]
2025-07-05T14:50:20.412Z,1751727020.412 [DAT](INFO): publishing direction and range info
2025-07-05T14:50:20.470Z,1751727020.470 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detected possible detachment. Starting timer.
2025-07-05T14:50:26.470Z,1751727026.470 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2025-07-05T14:50:26.707Z,1751727026.707 [DAT](INFO): ****** received valid address query ******
2025-07-05T14:50:26.707Z,1751727026.707 [DAT](INFO): ****** received valid ping request ******
2025-07-05T14:50:26.708Z,1751727026.708 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-07-05T14:50:26.957Z,1751727026.957 [DAT](INFO): DAT read: user:370>
2025-07-05T14:50:26.958Z,1751727026.958 [DAT](INFO): DAT read: Tx time:14:50:27.0736
2025-07-05T14:50:26.958Z,1751727026.958 [DAT](INFO): Ping request sent.
2025-07-05T14:50:26.958Z,1751727026.958 [DAT](INFO): transmitted an acoustic signal
2025-07-05T14:50:26.958Z,1751727026.958 [DAT](INFO): publishing transmit ping time
2025-07-05T14:50:26.959Z,1751727026.959 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000732
2025-07-05T14:50:27.209Z,1751727027.209 [DAT](INFO): DAT read: TxSync time:14:50:27.0728
2025-07-05T14:50:27.210Z,1751727027.210 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251758
2025-07-05T14:50:27.461Z,1751727027.461 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502782
2025-07-05T14:50:27.716Z,1751727027.716 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.757277
2025-07-05T14:50:27.965Z,1751727027.965 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006932
2025-07-05T14:50:28.217Z,1751727028.217 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258863
2025-07-05T14:50:28.469Z,1751727028.469 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510867
2025-07-05T14:50:28.724Z,1751727028.724 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.765326
2025-07-05T14:50:28.973Z,1751727028.973 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.015192
2025-07-05T14:50:29.225Z,1751727029.225 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266868
2025-07-05T14:50:29.477Z,1751727029.477 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518930
2025-07-05T14:50:29.732Z,1751727029.732 [DAT](INFO): DAT read: Rx Time:14:50:29.4906
2025-07-05T14:50:29.734Z,1751727029.734 [DAT](INFO): Rx dataTimestamp_ set to:1751727029.732926
2025-07-05T14:50:29.735Z,1751727029.735 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.776919
2025-07-05T14:50:29.981Z,1751727029.981 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022848
2025-07-05T14:50:30.233Z,1751727030.233 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274816
2025-07-05T14:50:30.496Z,1751727030.496 [DAT](INFO): DAT read: 14:50:29.4906 LVL= 14000, 23841, 20866, 23475, AGC= 51, IDX= 463,-0.16, 3.075, 1.003, 1.628, 2.431, PHS= 0.732,-1.381,-0.805, RAW= 105.3, 15.2, CAL= 113.6, 14.4, ROT= 36.4, -14.4
2025-07-05T14:50:30.498Z,1751727030.498 [DAT](INFO): got valid direction response:
14:50:29.4906 LVL= 14000, 23841, 20866, 23475, AGC= 51, IDX= 463,-0.16, 3.075, 1.003, 1.628, 2.431, PHS= 0.732,-1.381,-0.805, RAW= 105.3, 15.2, CAL= 113.6, 14.4, ROT= 36.4, -14.4
2025-07-05T14:50:30.498Z,1751727030.498 [DAT](INFO): DAT read: Bearing 36.4, -14.4 (Local)
2025-07-05T14:50:30.499Z,1751727030.499 [DAT](INFO): Local bearing/azimuth received:
Bearing 36.4, -14.4 (Local)
2025-07-05T14:50:30.504Z,1751727030.504 [DAT](INFO): DAT read: Range 10 to 50 : 9.5 m (Round-trip 12.7 ms) speed -0.2 m/s
2025-07-05T14:50:30.505Z,1751727030.505 [DAT](INFO): #Rx 1: Read range and direction messages.
2025-07-05T14:50:30.506Z,1751727030.506 [DAT](INFO): direction in FSK: [0.779607,0.574776,0.248690]
2025-07-05T14:50:30.506Z,1751727030.506 [DAT](INFO): publishing direction and range info
2025-07-05T14:50:36.576Z,1751727036.576 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2025-07-05T14:50:36.797Z,1751727036.797 [DAT](INFO): ****** received valid address query ******
2025-07-05T14:50:36.797Z,1751727036.797 [DAT](INFO): ****** received valid ping request ******
2025-07-05T14:50:36.797Z,1751727036.797 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-07-05T14:50:37.049Z,1751727037.049 [DAT](INFO): DAT read: user:371>
2025-07-05T14:50:37.049Z,1751727037.049 [DAT](INFO): DAT read: Tx time:14:50:37.1737
2025-07-05T14:50:37.050Z,1751727037.050 [DAT](INFO): Ping request sent.
2025-07-05T14:50:37.050Z,1751727037.050 [DAT](INFO): transmitted an acoustic signal
2025-07-05T14:50:37.050Z,1751727037.050 [DAT](INFO): publishing transmit ping time
2025-07-05T14:50:37.051Z,1751727037.051 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000734
2025-07-05T14:50:37.301Z,1751727037.301 [DAT](INFO): DAT read: TxSync time:14:50:37.1729
2025-07-05T14:50:37.302Z,1751727037.302 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.252292
2025-07-05T14:50:37.553Z,1751727037.553 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.503032
2025-07-05T14:50:37.805Z,1751727037.805 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754947
2025-07-05T14:50:38.057Z,1751727038.057 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006976
2025-07-05T14:50:38.309Z,1751727038.309 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.259312
2025-07-05T14:50:38.561Z,1751727038.561 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510912
2025-07-05T14:50:38.813Z,1751727038.813 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762983
2025-07-05T14:50:39.065Z,1751727039.065 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.015103
2025-07-05T14:50:39.317Z,1751727039.317 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266909
2025-07-05T14:50:39.569Z,1751727039.569 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518994
2025-07-05T14:50:39.834Z,1751727039.834 [DAT](INFO): DAT read: Rx Time:14:50:39.5923
2025-07-05T14:50:39.834Z,1751727039.834 [DAT](INFO): Rx dataTimestamp_ set to:1751727039.834203
2025-07-05T14:50:39.835Z,1751727039.835 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.785523
2025-07-05T14:50:40.085Z,1751727040.085 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.034972
2025-07-05T14:50:40.337Z,1751727040.337 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.286993
2025-07-05T14:50:40.594Z,1751727040.594 [DAT](INFO): DAT read: 14:50:39.5923 LVL= 13776, 23505, 21426, 24227, AGC= 53, IDX= 448,-0.10,-0.278,-2.335,-1.738,-0.865, PHS= 0.675,-1.422,-0.876, RAW= 104.5, 16.9, CAL= 112.6, 16.6, ROT= 37.4, -16.6
2025-07-05T14:50:40.595Z,1751727040.595 [DAT](INFO): got valid direction response:
14:50:39.5923 LVL= 13776, 23505, 21426, 24227, AGC= 53, IDX= 448,-0.10,-0.278,-2.335,-1.738,-0.865, PHS= 0.675,-1.422,-0.876, RAW= 104.5, 16.9, CAL= 112.6, 16.6, ROT= 37.4, -16.6
2025-07-05T14:50:40.596Z,1751727040.596 [DAT](INFO): DAT read: Bearing 37.4, -16.6 (Local)
2025-07-05T14:50:40.597Z,1751727040.597 [DAT](INFO): Local bearing/azimuth received:
Bearing 37.4, -16.6 (Local)
2025-07-05T14:50:40.598Z,1751727040.598 [DAT](INFO): DAT read: Range 10 to 50 : 10.8 m (Round-trip 14.4 ms) speed -0.3 m/s
2025-07-05T14:50:40.599Z,1751727040.599 [DAT](INFO): #Rx 1: Read range and direction messages.
2025-07-05T14:50:40.604Z,1751727040.604 [DAT](INFO): direction in FSK: [0.761305,0.582062,0.285688]
2025-07-05T14:50:40.604Z,1751727040.604 [DAT](INFO): publishing direction and range info
2025-07-05T14:50:40.684Z,1751727040.684 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached at range: 10.80 m. Transitioning docking module to standby.
2025-07-05T14:50:41.093Z,1751727041.093 [DockingStepper](INFO): Changing to mode: 1
2025-07-05T14:50:41.093Z,1751727041.093 [DockingStepper](INFO): Standby mode.
2025-07-05T14:50:41.156Z,1751727041.156 [DockingStepper](INFO): Commanding positive:54309
2025-07-05T14:50:46.685Z,1751727046.685 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2025-07-05T14:50:46.909Z,1751727046.909 [DAT](INFO): ****** received valid address query ******
2025-07-05T14:50:46.909Z,1751727046.909 [DAT](INFO): ****** received valid ping request ******
2025-07-05T14:50:46.909Z,1751727046.909 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-07-05T14:50:47.109Z,1751727047.109 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Docking module at standby.
2025-07-05T14:50:47.161Z,1751727047.161 [DAT](INFO): DAT read: user:372>
2025-07-05T14:50:47.162Z,1751727047.162 [DAT](INFO): DAT read: Tx time:14:50:47.2738
2025-07-05T14:50:47.162Z,1751727047.162 [DAT](INFO): Ping request sent.
2025-07-05T14:50:47.163Z,1751727047.163 [DAT](INFO): transmitted an acoustic signal
2025-07-05T14:50:47.163Z,1751727047.163 [DAT](INFO): publishing transmit ping time
2025-07-05T14:50:47.164Z,1751727047.164 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.001046
2025-07-05T14:50:47.413Z,1751727047.413 [DAT](INFO): DAT read: TxSync time:14:50:47.2730
2025-07-05T14:50:47.414Z,1751727047.414 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251232
2025-07-05T14:50:47.490Z,1751727047.490 [DefaultWithUndock:LeaveDock:B.Undock](IMPORTANT): Undocking sequence complete.
2025-07-05T14:50:47.491Z,1751727047.491 [DefaultWithUndock:LeaveDock:B.Undock] Stopped
2025-07-05T14:50:47.491Z,1751727047.491 [DefaultWithUndock:LeaveDock](INFO): Completed DefaultWithUndock:LeaveDock
2025-07-05T14:50:47.491Z,1751727047.491 [DefaultWithUndock:LeaveDock] Stopped
2025-07-05T14:50:47.491Z,1751727047.491 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::uninitialize DefaultWithUndock:LeaveDock
2025-07-05T14:50:47.491Z,1751727047.491 [DefaultWithUndock:LeaveDock:A.] Stopped
2025-07-05T14:50:47.491Z,1751727047.491 [DefaultWithUndock:LeaveDock:A.](DEBUG): Uninitializing TrackAcousticContact.
2025-07-05T14:50:47.492Z,1751727047.492 [DefaultWithUndock:SurfaceDefault] Running Loop=1
2025-07-05T14:50:47.492Z,1751727047.492 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault
2025-07-05T14:50:47.492Z,1751727047.492 [DefaultWithUndock:SurfaceDefault:A.GoToSurface] Running Loop=1
2025-07-05T14:50:47.492Z,1751727047.492 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-07-05T14:50:47.493Z,1751727047.493 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-07-05T14:50:47.493Z,1751727047.493 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-07-05T14:50:47.493Z,1751727047.493 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-07-05T14:50:47.493Z,1751727047.493 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-07-05T14:50:47.494Z,1751727047.494 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-07-05T14:50:47.494Z,1751727047.494 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-07-05T14:50:47.665Z,1751727047.665 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502515
2025-07-05T14:50:47.918Z,1751727047.918 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.755059
2025-07-05T14:50:47.925Z,1751727047.925 [DockingStepper](INFO): Pause
2025-07-05T14:50:48.169Z,1751727048.169 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006223
2025-07-05T14:50:48.307Z,1751727048.307 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -22.00 mm (2 active estimators).
2025-07-05T14:50:48.412Z,1751727048.412 [Radio_Surface](INFO): Powering up
2025-07-05T14:50:48.421Z,1751727048.421 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258148
2025-07-05T14:50:48.677Z,1751727048.677 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.514301
2025-07-05T14:50:48.929Z,1751727048.929 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.766151
2025-07-05T14:50:49.152Z,1751727049.152 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-05T14:50:49.181Z,1751727049.181 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.018233
2025-07-05T14:50:49.433Z,1751727049.433 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.270206
2025-07-05T14:50:49.685Z,1751727049.685 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.522278
2025-07-05T14:50:49.937Z,1751727049.937 [DAT](INFO): DAT read: Rx Time:14:50:49.6954
2025-07-05T14:50:49.937Z,1751727049.937 [DAT](INFO): Rx dataTimestamp_ set to:1751727049.937157
2025-07-05T14:50:49.938Z,1751727049.938 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.775389
2025-07-05T14:50:50.156Z,1751727050.156 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-07-05T14:50:50.189Z,1751727050.189 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.026589
2025-07-05T14:50:50.441Z,1751727050.441 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.278333
2025-07-05T14:50:50.700Z,1751727050.700 [DAT](INFO): DAT read: 14:50:49.6954 LVL= 18352, 22177, 23026, 25587, AGC= 48, IDX= 460, 0.28, 0.757,-1.397,-0.638, 0.091, PHS= 0.754,-1.441,-0.732, RAW= 108.5, 14.5, CAL= 116.7, 12.8, ROT= 33.3, -12.8
2025-07-05T14:50:50.701Z,1751727050.701 [DAT](INFO): got valid direction response:
14:50:49.6954 LVL= 18352, 22177, 23026, 25587, AGC= 48, IDX= 460, 0.28, 0.757,-1.397,-0.638, 0.091, PHS= 0.754,-1.441,-0.732, RAW= 108.5, 14.5, CAL= 116.7, 12.8, ROT= 33.3, -12.8
2025-07-05T14:50:50.703Z,1751727050.703 [DAT](INFO): DAT read: Bearing 33.3, -12.8 (Local)
2025-07-05T14:50:50.703Z,1751727050.703 [DAT](INFO): Local bearing/azimuth received:
Bearing 33.3, -12.8 (Local)
2025-07-05T14:50:50.705Z,1751727050.705 [DAT](INFO): DAT read: Range 10 to 50 : 12.9 m (Round-trip 17.3 ms) speed -0.1 m/s
2025-07-05T14:50:50.706Z,1751727050.706 [DAT](INFO): #Rx 1: Read range and direction messages.
2025-07-05T14:50:50.708Z,1751727050.708 [DAT](INFO): direction in FSK: [0.815037,0.535379,0.221548]
2025-07-05T14:50:50.709Z,1751727050.709 [DAT](INFO): publishing direction and range info
2025-07-05T14:50:53.665Z,1751727053.665 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.10
2025-07-05T14:50:53.666Z,1751727053.666 [Radio_Surface](INFO): Found local IP address 134.89.93.61 for eth0
2025-07-05T14:50:53.666Z,1751727053.666 [Radio_Surface](IMPORTANT): Local IP not on subnet 10.89.10, restarting DHCP
2025-07-05T14:50:53.707Z,1751727053.707 [CommandExec](IMPORTANT): got command ! /mnt/mmc/LRAUV/Tools/dhcp.sh
2025-07-05T14:50:54.013Z,1751727054.013 [CommandExec](IMPORTANT): /mnt/mmc/started/2025-07-05UTC145053_sh
2025-07-05T14:51:20.280Z,1751727080.280 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-05T14:51:21.284Z,1751727081.284 [DataOverHttps](INFO): Radio surface powered ON.
2025-07-05T14:51:21.284Z,1751727081.284 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-07-05T14:52:35.853Z,1751727155.853 [DAT](INFO): DAT read: user:373>Rx Time:14:52:35.6140
2025-07-05T14:52:35.854Z,1751727155.854 [DAT](INFO): Rx dataTimestamp_ set to:1751727155.853465
2025-07-05T14:52:36.609Z,1751727156.609 [DAT](INFO): DAT read: $Error in header
2025-07-05T14:52:36.609Z,1751727156.609 [DAT](INFO): Received a bad header
2025-07-05T14:52:37.502Z,1751727157.502 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=1
2025-07-05T14:52:37.502Z,1751727157.502 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2025-07-05T14:52:37.502Z,1751727157.502 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2025-07-05T14:52:38.297Z,1751727158.297 [NAL9602](INFO): Powering up
2025-07-05T14:52:38.310Z,1751727158.310 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. Device response is::WD, -2.78+61.09, -1.71, 20.00, 0.00
2025-07-05T14:52:49.184Z,1751727169.184 [NAL9602](INFO): NAL9602 initialized
2025-07-05T14:53:02.128Z,1751727182.128 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for bottom velocity. Device response is::BS,-3278,-32768,V
2025-07-05T14:53:12.225Z,1751727192.225 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for bottom velocity. Device response is::BS,-32768,32768,V
2025-07-05T14:53:21.101Z,1751727201.101 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-07-05T14:53:21.180Z,1751727201.180 [NAL9602](ERROR): received:
+CSQ:0
OK155, 0, 0, 0, 0
OK
2025-07-05T14:53:22.012Z,1751727202.012 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2025-07-05T14:53:22.013Z,1751727202.013 [RDI_Pathfinder] Communications Fault, FailCount= 1
2025-07-05T14:53:22.013Z,1751727202.013 [RDI_Pathfinder](ERROR): Communications Fault
2025-07-05T14:53:22.085Z,1751727202.085 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2025-07-05T14:53:22.314Z,1751727202.314 [RDI_Pathfinder](INFO): Powering down
2025-07-05T14:53:23.176Z,1751727203.176 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2025-07-05T14:53:23.176Z,1751727203.176 [RDI_Pathfinder] No Fault, FailCount= 1
2025-07-05T14:53:23.535Z,1751727203.535 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2025-07-05T14:53:50.889Z,1751727230.889 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-05T14:53:51.892Z,1751727231.892 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-07-05T14:53:52.205Z,1751727232.205 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-07-05T14:53:58.270Z,1751727238.270 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,145403.00,A,3654.39408,N,12206.91885,W,0.350,0.38,050725,,,A*7B
2025-07-05T14:53:58.272Z,1751727238.272 [NAL9602](INFO): GPS fix at 20250705T145403: (36.906568, -122.115314)
2025-07-05T14:53:58.285Z,1751727238.285 [UniversalFixResidualReporter](INFO): Fix residual: 2.4 %DT, over the last 1428.3 m. Residual distance 33.9 m at bearing -76.1 degrees. Fix at (36.9066, -122.1153) with 628.0 m made good.
2025-07-05T14:53:58.285Z,1751727238.285 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2025-07-05T14:53:58.286Z,1751727238.286 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1
2025-07-05T14:54:22.028Z,1751727262.028 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-05T14:54:23.032Z,1751727263.032 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-07-05T14:54:28.973Z,1751727268.973 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response.
2025-07-05T14:54:29.056Z,1751727269.056 [NAL9602](ERROR): received:
+CSQ:0
OK155, 0, 0, 0, 0
OK
2025-07-05T14:54:43.784Z,1751727283.784 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003305
2025-07-05T14:54:52.604Z,1751727292.604 [DataOverHttps](INFO): Sending 284 bytes from file Logs/20250705T144438/Courier0003.lzma
2025-07-05T14:55:03.646Z,1751727303.646 [DataOverHttps](INFO): Moved sent file to Logs/20250705T144438/Courier0003.lzma.bak
2025-07-05T14:55:03.646Z,1751727303.646 [DataOverHttps](INFO): SBD MOMSN=25361284
2025-07-05T14:55:14.125Z,1751727314.125 [NAL9602](INFO): Powering down
2025-07-05T14:55:32.683Z,1751727332.683 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250705T144438/Courier0006.lzma
2025-07-05T14:55:43.726Z,1751727343.726 [DataOverHttps](INFO): Moved sent file to Logs/20250705T144438/Courier0006.lzma.bak
2025-07-05T14:55:43.726Z,1751727343.726 [DataOverHttps](INFO): SBD MOMSN=25361287
2025-07-05T14:56:16.148Z,1751727376.148 [DataOverHttps](INFO): Sending 191 bytes from file Logs/20250705T070651/Express0050.lzma
2025-07-05T14:56:27.190Z,1751727387.190 [DataOverHttps](INFO): Moved sent file to Logs/20250705T070651/Express0050.lzma.bak
2025-07-05T14:56:27.190Z,1751727387.190 [DataOverHttps](INFO): SBD MOMSN=25361289
2025-07-05T14:56:55.549Z,1751727415.549 [DataOverHttps](INFO): Sending 660 bytes from file Logs/20250705T135857/Express0001.lzma
2025-07-05T14:57:06.602Z,1751727426.602 [DataOverHttps](INFO): Moved sent file to Logs/20250705T135857/Express0001.lzma.bak
2025-07-05T14:57:06.602Z,1751727426.602 [DataOverHttps](INFO): SBD MOMSN=25361292
2025-07-05T14:57:34.900Z,1751727454.900 [DataOverHttps](INFO): Sending 439 bytes from file Logs/20250705T135857/Express0004.lzma
2025-07-05T14:57:45.926Z,1751727465.926 [DataOverHttps](INFO): Moved sent file to Logs/20250705T135857/Express0004.lzma.bak
2025-07-05T14:57:45.926Z,1751727465.926 [DataOverHttps](INFO): SBD MOMSN=25361297
2025-07-05T14:58:16.879Z,1751727496.879 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20250705T135857/Express0007.lzma
2025-07-05T14:58:27.918Z,1751727507.918 [DataOverHttps](INFO): Moved sent file to Logs/20250705T135857/Express0007.lzma.bak
2025-07-05T14:58:27.918Z,1751727507.918 [DataOverHttps](INFO): SBD MOMSN=25361309
2025-07-05T14:58:56.444Z,1751727536.444 [DataOverHttps](INFO): Sending 257 bytes from file Logs/20250705T135857/Express0010.lzma
2025-07-05T14:59:07.489Z,1751727547.489 [DataOverHttps](INFO): Moved sent file to Logs/20250705T135857/Express0010.lzma.bak
2025-07-05T14:59:07.490Z,1751727547.490 [DataOverHttps](INFO): SBD MOMSN=25361312
2025-07-05T14:59:34.197Z,1751727574.197 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-07-05T14:59:35.853Z,1751727575.853 [DataOverHttps](INFO): Sending 366 bytes from file Logs/20250705T135857/Express0013.lzma
2025-07-05T14:59:46.511Z,1751727586.511 [DataOverHttps](INFO): Moved sent file to Logs/20250705T135857/Express0013.lzma.bak
2025-07-05T14:59:46.511Z,1751727586.511 [DataOverHttps](INFO): SBD MOMSN=25361316
2025-07-05T15:00:18.641Z,1751727618.641 [DataOverHttps](INFO): Sending 305 bytes from file Logs/20250705T135857/Express0016.lzma
2025-07-05T15:00:29.682Z,1751727629.682 [DataOverHttps](INFO): Moved sent file to Logs/20250705T135857/Express0016.lzma.bak
2025-07-05T15:00:29.682Z,1751727629.682 [DataOverHttps](INFO): SBD MOMSN=25361321
2025-07-05T15:00:42.039Z,1751727642.039 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2025-07-05T15:01:00.778Z,1751727660.778 [DataOverHttps](INFO): Sending 464 bytes from file Logs/20250705T135857/Express0019.lzma
2025-07-05T15:01:11.818Z,1751727671.818 [DataOverHttps](INFO): Moved sent file to Logs/20250705T135857/Express0019.lzma.bak
2025-07-05T15:01:11.818Z,1751727671.818 [DataOverHttps](INFO): SBD MOMSN=25361331
2025-07-05T15:01:42.368Z,1751727702.368 [DataOverHttps](INFO): Sending 371 bytes from file Logs/20250705T144438/Express0001.lzma
2025-07-05T15:01:53.410Z,1751727713.410 [DataOverHttps](INFO): Moved sent file to Logs/20250705T144438/Express0001.lzma.bak
2025-07-05T15:01:53.410Z,1751727713.410 [DataOverHttps](INFO): SBD MOMSN=25361336
2025-07-05T15:02:24.366Z,1751727744.366 [DataOverHttps](INFO): Sending 879 bytes from file Logs/20250705T144438/Express0004.lzma
2025-07-05T15:02:35.408Z,1751727755.408 [DataOverHttps](INFO): Moved sent file to Logs/20250705T144438/Express0004.lzma.bak
2025-07-05T15:02:35.408Z,1751727755.408 [DataOverHttps](IMPORTANT): SBD MOMSN=25361340, MTMSN=20250705T150240
2025-07-05T15:02:44.824Z,1751727764.824 [DataOverHttps](INFO): Received command: sched "load Engineering/lineCaptureHoming.tl;set lineCaptureHoming.StayOnDock 1 bool;set lineCaptureHoming.DockingAltitude 7.8 m;set lineCaptureHoming.AltitudeDepthLimit 68 m;set lineCaptureHoming.MaxDepth 70 m" 3u73w 1 2
2025-07-05T15:02:44.934Z,1751727764.934 [CommandExec](IMPORTANT): got command schedule "load Engineering/lineCaptureHoming.tl;set lineCaptureHoming.StayOnDock 1 bool;set lineCaptureHoming.DockingAltitude 7.8 m;set lineCaptureHoming.AltitudeDepthLimit 68 m;set lineCaptureHoming.MaxDepth 70 m" 3u73w 1 2.000000
2025-07-05T15:02:44.934Z,1751727764.934 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=3u73w
2025-07-05T15:02:44.935Z,1751727764.935 [CommandExec](IMPORTANT): Scheduled #9 (#1 of 2 with id='3u73w'): "load Engineering/lineCaptureHoming.tl;set lineCaptureHoming.StayOnDock 1 bool;set lineCaptureHoming.DockingAltitude 7.8 m;set lineCaptureHoming.AltitudeDepthLimit 68 m;set lineCaptureHoming.MaxDepth 70 m", AFTER MISSION
2025-07-05T15:02:44.940Z,1751727764.940 [CommandExec](FAULT): Scheduling was paused by a command at 2025-07-05T14:48:14Z
2025-07-05T15:02:55.865Z,1751727775.865 [DataOverHttps](IMPORTANT): SBD MTMSN=20250705T150300
2025-07-05T15:03:03.587Z,1751727783.587 [DataOverHttps](INFO): Sending 70 bytes from file Logs/20250705T144438/Express0007.lzma
2025-07-05T15:03:03.590Z,1751727783.590 [DataOverHttps](INFO): Received command: sched "set lineCaptureHoming.CorrectForSoundSpeed 1 bool;run" 3u73w 2 2
2025-07-05T15:03:03.669Z,1751727783.669 [CommandExec](IMPORTANT): got command schedule "set lineCaptureHoming.CorrectForSoundSpeed 1 bool;run" 3u73w 2 2.000000
2025-07-05T15:03:03.669Z,1751727783.669 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=3u73w
2025-07-05T15:03:03.670Z,1751727783.670 [CommandExec](IMPORTANT): Scheduled #10 (#2 of 2 with id='3u73w'): "set lineCaptureHoming.CorrectForSoundSpeed 1 bool;run", AFTER MISSION
2025-07-05T15:03:03.671Z,1751727783.671 [CommandExec](FAULT): Scheduling was paused by a command at 2025-07-05T14:48:14Z
2025-07-05T15:03:14.630Z,1751727794.630 [DataOverHttps](INFO): Moved sent file to Logs/20250705T144438/Express0007.lzma.bak
2025-07-05T15:03:14.630Z,1751727794.630 [DataOverHttps](IMPORTANT): SBD MOMSN=25361496, MTMSN=20250705T150319
2025-07-05T15:03:22.564Z,1751727802.564 [DataOverHttps](INFO): Received command: sched "load Engineering/OnDock.tl;set OnDock.DockedTime 40 h;set OnDock.RangeTimeout 40 h;run"
2025-07-05T15:03:22.594Z,1751727802.594 [CommandExec](IMPORTANT): got command schedule "load Engineering/OnDock.tl;set OnDock.DockedTime 40 h;set OnDock.RangeTimeout 40 h;run"
2025-07-05T15:03:22.595Z,1751727802.595 [CommandExec](IMPORTANT): Scheduled #11: "load Engineering/OnDock.tl;set OnDock.DockedTime 40 h;set OnDock.RangeTimeout 40 h;run", AFTER MISSION
2025-07-05T15:03:22.595Z,1751727802.595 [CommandExec](FAULT): Scheduling was paused by a command at 2025-07-05T14:48:14Z
2025-07-05T15:03:41.424Z,1751727821.424 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250705T144438/Courier0009.lzma
2025-07-05T15:03:52.462Z,1751727832.462 [DataOverHttps](INFO): Moved sent file to Logs/20250705T144438/Courier0009.lzma.bak
2025-07-05T15:03:52.462Z,1751727832.462 [DataOverHttps](IMPORTANT): SBD MOMSN=25361507, MTMSN=20250705T150357
2025-07-05T15:04:00.177Z,1751727840.177 [DataOverHttps](INFO): Received command: sched 20250707T0430 "load Engineering/undock.tl;set undock.TransitLat 36.91 degree;set undock.TransitLon -122.11 degree;run"
2025-07-05T15:04:00.272Z,1751727840.272 [CommandExec](IMPORTANT): got command schedule 20250707T043000 "load Engineering/undock.tl;set undock.TransitLat 36.91 degree;set undock.TransitLon -122.11 degree;run"
2025-07-05T15:04:00.273Z,1751727840.273 [CommandExec](IMPORTANT): Scheduled #12: "load Engineering/undock.tl;set undock.TransitLat 36.91 degree;set undock.TransitLon -122.11 degree;run", AT TIME: 2025-07-07T04:30:00Z
2025-07-05T15:04:00.273Z,1751727840.273 [CommandExec](FAULT): Scheduling was paused by a command at 2025-07-05T14:48:14Z
2025-07-05T15:04:13.566Z,1751727853.566 [BPC1](INFO): Calculating totals. Valid battery stick count: 44. Valid reserve battery stick count: 4.
2025-07-05T15:04:13.579Z,1751727853.579 [BPC1](FAULT): Failed to receive data from 3 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 8, 43, 46.
2025-07-05T15:04:21.525Z,1751727861.525 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250705T144438/Courier0012.lzma
2025-07-05T15:04:32.566Z,1751727872.566 [DataOverHttps](INFO): Moved sent file to Logs/20250705T144438/Courier0012.lzma.bak
2025-07-05T15:04:32.566Z,1751727872.566 [DataOverHttps](INFO): SBD MOMSN=25361511
2025-07-05T15:04:59.095Z,1751727899.095 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250705T144438/Courier0015.lzma
2025-07-05T15:05:10.139Z,1751727910.139 [DataOverHttps](INFO): Moved sent file to Logs/20250705T144438/Courier0015.lzma.bak
2025-07-05T15:05:10.139Z,1751727910.139 [DataOverHttps](IMPORTANT): SBD MOMSN=25361514, MTMSN=20250705T150514
2025-07-05T15:05:17.744Z,1751727917.744 [DataOverHttps](INFO): Received command: sched "load Science/sci2.tl;set sci2.MissionTimeout 18 h;set sci2.NeedCommsTime 90 min;set sci2.Lat1 37.02 degree;set sci2.Lon1 -122.27 degree;set sci2.Lat2 36.91 degree;set sci2.Lon2 -122.11 degree;set sci2.Speed 1.1 m/s" 3u78q 1 2
2025-07-05T15:05:17.758Z,1751727917.758 [CommandExec](IMPORTANT): got command schedule "load Science/sci2.tl;set sci2.MissionTimeout 18 h;set sci2.NeedCommsTime 90 min;set sci2.Lat1 37.02 degree;set sci2.Lon1 -122.27 degree;set sci2.Lat2 36.91 degree;set sci2.Lon2 -122.11 degree;set sci2.Speed 1.1 m/s" 3u78q 1 2.000000
2025-07-05T15:05:17.759Z,1751727917.759 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=3u78q
2025-07-05T15:05:17.772Z,1751727917.772 [CommandExec](IMPORTANT): Scheduled #13 (#1 of 2 with id='3u78q'): "load Science/sci2.tl;set sci2.MissionTimeout 18 h;set sci2.NeedCommsTime 90 min;set sci2.Lat1 37.02 degree;set sci2.Lon1 -122.27 degree;set sci2.Lat2 36.91 degree;set sci2.Lon2 -122.11 degree;set sci2.Speed 1.1 m/s", AFTER MISSION
2025-07-05T15:05:17.772Z,1751727917.772 [CommandExec](FAULT): Scheduling was paused by a command at 2025-07-05T14:48:14Z
2025-07-05T15:05:23.076Z,1751727923.076 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 0.00, 0.00, 626.79, 716.80
2025-07-05T15:05:28.802Z,1751727928.802 [DataOverHttps](IMPORTANT): SBD MTMSN=20250705T150533
2025-07-05T15:05:36.504Z,1751727936.504 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250705T144438/Courier0018.lzma
2025-07-05T15:05:36.507Z,1751727936.507 [DataOverHttps](INFO): Received command: sched "set sci2.YoYoMaxDepth 50 m;set sci2:NeedComms.CommsTimeout 10 min;run" 3u78q 2 2
2025-07-05T15:05:36.546Z,1751727936.546 [CommandExec](IMPORTANT): got command schedule "set sci2.YoYoMaxDepth 50 m;set sci2:NeedComms.CommsTimeout 10 min;run" 3u78q 2 2.000000
2025-07-05T15:05:36.546Z,1751727936.546 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=3u78q
2025-07-05T15:05:36.547Z,1751727936.547 [CommandExec](IMPORTANT): Scheduled #14 (#2 of 2 with id='3u78q'): "set sci2.YoYoMaxDepth 50 m;set sci2:NeedComms.CommsTimeout 10 min;run", AFTER MISSION
2025-07-05T15:05:36.548Z,1751727936.548 [CommandExec](FAULT): Scheduling was paused by a command at 2025-07-05T14:48:14Z
2025-07-05T15:05:46.549Z,1751727946.549 [DataOverHttps](INFO): Moved sent file to Logs/20250705T144438/Courier0018.lzma.bak
2025-07-05T15:05:46.549Z,1751727946.549 [DataOverHttps](INFO): SBD MOMSN=25361517
2025-07-05T15:06:05.330Z,1751727965.330 [DataOverHttps](IMPORTANT): SBD MTMSN=20250705T150610
2025-07-05T15:06:16.480Z,1751727976.480 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20250705T144438/Courier0021.lzma
2025-07-05T15:06:16.482Z,1751727976.482 [DataOverHttps](INFO): Received command: sched 20250707T0430 "ubat on; restart logs"
2025-07-05T15:06:16.499Z,1751727976.499 [CommandExec](IMPORTANT): got command schedule 20250707T043000 "ubat on; restart logs"
2025-07-05T15:06:16.500Z,1751727976.500 [CommandExec](IMPORTANT): Scheduled #15: "ubat on; restart logs", AT TIME: 2025-07-07T04:30:00Z
2025-07-05T15:06:16.500Z,1751727976.500 [CommandExec](FAULT): Scheduling was paused by a command at 2025-07-05T14:48:14Z
2025-07-05T15:06:27.522Z,1751727987.522 [DataOverHttps](INFO): Moved sent file to Logs/20250705T144438/Courier0021.lzma.bak
2025-07-05T15:06:27.522Z,1751727987.522 [DataOverHttps](INFO): SBD MOMSN=25361521
2025-07-05T15:06:57.003Z,1751728017.003 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250705T144438/Courier0024.lzma
2025-07-05T15:07:08.046Z,1751728028.046 [DataOverHttps](INFO): Moved sent file to Logs/20250705T144438/Courier0024.lzma.bak
2025-07-05T15:07:08.046Z,1751728028.046 [DataOverHttps](IMPORTANT): SBD MOMSN=25361526, MTMSN=20250705T150712
2025-07-05T15:07:16.905Z,1751728036.905 [DataOverHttps](INFO): Received command: sched 20250707T1230 "ubat off; set sci2.Speed 0.8 m/s; restart logs;"
2025-07-05T15:07:16.967Z,1751728036.967 [CommandExec](IMPORTANT): got command schedule 20250707T123000 "ubat off; set sci2.Speed 0.8 m/s; restart logs;"
2025-07-05T15:07:16.968Z,1751728036.968 [CommandExec](IMPORTANT): Scheduled #16: "ubat off; set sci2.Speed 0.8 m/s; restart logs;", AT TIME: 2025-07-07T12:30:00Z
2025-07-05T15:07:16.969Z,1751728036.969 [CommandExec](FAULT): Scheduling was paused by a command at 2025-07-05T14:48:14Z
2025-07-05T15:07:35.659Z,1751728055.659 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250705T144438/Courier0027.lzma
2025-07-05T15:07:46.830Z,1751728066.830 [DataOverHttps](INFO): Moved sent file to Logs/20250705T144438/Courier0027.lzma.bak
2025-07-05T15:07:46.831Z,1751728066.831 [DataOverHttps](INFO): SBD MOMSN=25361529
2025-07-05T15:08:09.905Z,1751728089.905 [DataOverHttps](IMPORTANT): SBD MTMSN=20250705T150814
2025-07-05T15:08:21.403Z,1751728101.403 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250705T144438/Courier0030.lzma
2025-07-05T15:08:21.406Z,1751728101.406 [DataOverHttps](INFO): Received command: schedule resume
2025-07-05T15:08:21.494Z,1751728101.494 [CommandExec](IMPORTANT): got command schedule resume
2025-07-05T15:08:21.494Z,1751728101.494 [CommandExec](IMPORTANT): Scheduling is resumed
2025-07-05T15:08:22.212Z,1751728102.212 [CommandExec](IMPORTANT): got command load ./Missions/Engineering/lineCaptureHoming.tl
2025-07-05T15:08:22.214Z,1751728102.214 [MissionManager](INFO): Loading Mission from file: ./Missions/Engineering/lineCaptureHoming.tl
2025-07-05T15:08:22.214Z,1751728102.214 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Engineering/lineCaptureHoming.tl
2025-07-05T15:08:22.222Z,1751728102.222 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Engineering/lineCaptureHoming.tl
2025-07-05T15:08:30.445Z,1751728110.445 [MissionManager](DEBUG): TethyslAPI: ./Missions/Engineering/lineCaptureHoming.tl translated into:
3
180
3
1
5
4
1.5
1.5
60
2
2
5
1
120
40
5
NaN
10
NaN
4
205
2
20
0.05
NaN
NaN
NaN
60
-1
1
1
0
0
0
No longer in Terminal. Resuming normal ranging with
pings.
5
Entering USBL mode with
pings at
(mode
).
5
Resuming normal ranging with
pings
(mode
).
5
Entering Midcourse tracking update period
sec at
(mode
).
5
0
Entering Terminal tracking update period
sec at
(mode
).
Restarting DHCP...
! /mnt/mmc/LRAUV/Tools/dhcp.sh
Restarting logs
restart logs
Timed out trying to reach the target depth. Stopping mission at current depth of
stop
1
-99
Mode
0
Waiting on dock for
Retrying incomplete docking attempt
1
Breaking off. Range:
.
Driving to post-undock waypoint
,
No post-undock waypoint, drifting for
minutes
0
0
0
2025-07-05T15:08:30.459Z,1751728110.459 [MissionManager](INFO): DefineArg lineCaptureHoming.MissionTimeout = 3.000000 h
2025-07-05T15:08:30.462Z,1751728110.462 [MissionManager](INFO): DefineArg lineCaptureHoming.NeedCommsTime = 180.000000 min
2025-07-05T15:08:30.464Z,1751728110.464 [MissionManager](INFO): DefineArg lineCaptureHoming.MicromodemCommsInterval = 3.000000 h
2025-07-05T15:08:30.466Z,1751728110.466 [MissionManager](INFO): DefineArg lineCaptureHoming.Repeat = 1.000000 count
2025-07-05T15:08:30.469Z,1751728110.469 [MissionManager](INFO): DefineArg lineCaptureHoming.WaitOnDockTimeout = 5.000000 min
2025-07-05T15:08:30.471Z,1751728110.471 [MissionManager](INFO): DefineArg lineCaptureHoming.StayOnDock = 0 bool
2025-07-05T15:08:30.473Z,1751728110.473 [MissionManager](INFO): DefineArg lineCaptureHoming.TrySlide = 0 bool
2025-07-05T15:08:30.475Z,1751728110.475 [MissionManager](INFO): DefineArg lineCaptureHoming.RetryIncompleteDock = 0 bool
2025-07-05T15:08:30.477Z,1751728110.477 [MissionManager](INFO): DefineArg lineCaptureHoming.SlideRetries = 4.000000 count
2025-07-05T15:08:30.479Z,1751728110.479 [MissionManager](INFO): DefineArg lineCaptureHoming.SlideSinkDuration = 1.500000 min
2025-07-05T15:08:30.481Z,1751728110.481 [MissionManager](INFO): DefineArg lineCaptureHoming.SlideCloseDuration = 1.500000 min
2025-07-05T15:08:30.484Z,1751728110.484 [MissionManager](INFO): DefineArg lineCaptureHoming.TransponderCode = 50 count
2025-07-05T15:08:30.486Z,1751728110.486 [MissionManager](INFO): DefineArg lineCaptureHoming.TrackingUpdatePeriodMidcourse = 60.000000 s
2025-07-05T15:08:30.489Z,1751728110.489 [MissionManager](INFO): DefineArg lineCaptureHoming.TrackingUpdatePeriodTerminal = 2.000000 s
2025-07-05T15:08:30.491Z,1751728110.491 [MissionManager](INFO): DefineArg lineCaptureHoming.TrackingUpdatePeriodDocked = 2.000000 min
2025-07-05T15:08:30.525Z,1751728110.525 [MissionManager](INFO): DefineArg lineCaptureHoming.TrackingNumFixesLowPassFilter = 5.000000 count
2025-07-05T15:08:30.527Z,1751728110.527 [MissionManager](INFO): DefineArg lineCaptureHoming.NumberOfPings = 1.000000 count
2025-07-05T15:08:30.538Z,1751728110.538 [MissionManager](INFO): DefineArg lineCaptureHoming.NumPingsUSBL = 120.000000 count
2025-07-05T15:08:30.544Z,1751728110.544 [MissionManager](INFO): DefineArg lineCaptureHoming.RangeUSBL = 40.000000 m
2025-07-05T15:08:30.547Z,1751728110.547 [MissionManager](INFO): DefineArg lineCaptureHoming.TrackingNumFixesLowPassFilterUSBL = 5.000000 count
2025-07-05T15:08:30.565Z,1751728110.565 [MissionManager](INFO): DefineArg lineCaptureHoming.DockingAltitude = nan m
2025-07-05T15:08:30.567Z,1751728110.567 [MissionManager](INFO): DefineArg lineCaptureHoming.InvalidAltitudeTimeout = 10.000000 min
2025-07-05T15:08:30.574Z,1751728110.574 [MissionManager](INFO): DefineArg lineCaptureHoming.AltitudeDepthLimit = nan m
2025-07-05T15:08:30.584Z,1751728110.584 [MissionManager](INFO): DefineArg lineCaptureHoming.MinAltitude = 4.000000 m
2025-07-05T15:08:30.587Z,1751728110.587 [MissionManager](INFO): DefineArg lineCaptureHoming.MaxDepth = 205.000000 m
2025-07-05T15:08:30.593Z,1751728110.593 [MissionManager](INFO): DefineArg lineCaptureHoming.MinOffshore = 2.000000 km
2025-07-05T15:08:30.595Z,1751728110.595 [MissionManager](INFO): DefineArg lineCaptureHoming.ApproachDepthTimeout = 20.000000 min
2025-07-05T15:08:30.606Z,1751728110.606 [MissionManager](INFO): DefineArg lineCaptureHoming.ApproachDepthRate = 0.050000 m/s
2025-07-05T15:08:30.612Z,1751728110.612 [MissionManager](INFO): DefineArg lineCaptureHoming.Lat1 = nan arcdeg
2025-07-05T15:08:30.614Z,1751728110.614 [MissionManager](INFO): DefineArg lineCaptureHoming.Lon1 = nan arcdeg
2025-07-05T15:08:30.617Z,1751728110.617 [MissionManager](INFO): DefineArg lineCaptureHoming.CaptureRadius = nan m
2025-07-05T15:08:30.619Z,1751728110.619 [MissionManager](INFO): DefineArg lineCaptureHoming.CorrectForSoundSpeed = 0 bool
2025-07-05T15:08:30.631Z,1751728110.631 [MissionManager](INFO): DefineArg lineCaptureHoming.MidcourseDepth = 50.000000 m
2025-07-05T15:08:30.634Z,1751728110.634 [MissionManager](INFO): DefineArg lineCaptureHoming.MidcourseSpeed = 0.800000 m/s
2025-07-05T15:08:30.640Z,1751728110.640 [MissionManager](INFO): DefineArg lineCaptureHoming.TrackingPeriod = 60.000000 s
2025-07-05T15:08:30.643Z,1751728110.643 [MissionManager](INFO): DefineOutput lineCaptureHoming.Mode = -1.000000 count
2025-07-05T15:08:30.649Z,1751728110.649 [MissionManager](INFO): DefineOutput lineCaptureHoming.NumPings = 1.000000 count
2025-07-05T15:08:30.656Z,1751728110.656 [MissionManager](INFO): DefineOutput lineCaptureHoming.LowPassFilterWinSize = 1.000000 count
2025-07-05T15:08:30.658Z,1751728110.658 [MissionManager](INFO): DefineOutput lineCaptureHoming.LowPassFilterWinOverlap = 1 bool
2025-07-05T15:08:30.704Z,1751728110.704 [MissionManager](INFO): DefineOutput lineCaptureHoming.EndDockingSequence = 0 bool
2025-07-05T15:08:30.707Z,1751728110.707 [MissionManager](INFO): DefineOutput lineCaptureHoming.LastDockSuccessful = 0 bool
2025-07-05T15:08:30.717Z,1751728110.717 [MissionManager](INFO): DefineOutput lineCaptureHoming.DockSuccessfulCount = 0.000000 count
2025-07-05T15:08:30.719Z,1751728110.719 [MissionManager](INFO): DefineOutput lineCaptureHoming.RepeatArg = 1.000000 count
2025-07-05T15:08:30.732Z,1751728110.732 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/Science.tl
2025-07-05T15:08:30.732Z,1751728110.732 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/Science.tl
2025-07-05T15:08:30.757Z,1751728110.757 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/Science.tl
2025-07-05T15:08:32.446Z,1751728112.446 [DataOverHttps](INFO): Moved sent file to Logs/20250705T144438/Courier0030.lzma.bak
2025-07-05T15:08:32.446Z,1751728112.446 [DataOverHttps](INFO): SBD MOMSN=25361532
2025-07-05T15:08:35.467Z,1751728115.467 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/Science.tl translated into:
NaN
3
100
80
20
5
NaN
NaN
2.0
NaN
NaN
0
NaN
NaN
5
0
0
0
0
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
PeakDetectVsDepth
EdgeDetectVsDepth
GoDown, ChlPeakThresh, PeakChl, PeakChlDepth, EdgeChl, EdgeChlDepth =
,
,
,
,
,
PeakDetectVsDepth
PeakDetectVsDepth
PeakDetectVsDepth
PeakDetectVsDepth
PeakDetectVsDepth
_.planktivore_LM_AvgRois
EdgeDetectVsDepth
_.planktivore_LM_AvgRois
GoDown, PlanktivoreLMavgROIPeakThresh, PeakPlanktivoreLMavgROI, PeakPlanktivoreLMavgROIDepth, EdgePlanktivoreLMavgROI, EdgePlanktivoreLMavgROIDepth =
,
,
,
,
,
PeakDetectVsDepth
_.planktivore_HM_AvgRois
EdgeDetectVsDepth
_.planktivore_HM_AvgRois
GoDown, PlanktivoreHMavgROIPeakThresh, PeakPlanktivoreHMavgROI, PeakPlanktivoreHMavgROIDepth, EdgePlanktivoreHMavgROI, EdgePlanktivoreHMavgROIDepth =
,
,
,
,
,
PeakDetectVsDepth
_.planktivore_diatoms
EdgeDetectVsDepth
_.planktivore_diatoms
GoDown, PlanktivoreDiatomsPeakThresh, PeakPlanktivoreDiatoms, PeakPlanktivoreDiatomsDepth, EdgePlanktivoreDiatoms, EdgePlanktivoreDiatomsDepth =
,
,
,
,
,
PeakDetectVsDepth
_.planktivore_dinoflagellates
EdgeDetectVsDepth
_.planktivore_dinoflagellates
GoDown, PlanktivoreDinoflagellatesPeakThresh, PeakPlanktivoreDinoflagellates, PeakPlanktivoreDinoflagellatesDepth, EdgePlanktivoreDinoflagellates, EdgePlanktivoreDinoflagellatesDepth =
,
,
,
,
,
Triggered. PeakChl values on
consecutive yo-yo profiles fall in [
] (NaN bound is ignored).
2025-07-05T15:08:35.498Z,1751728115.498 [MissionManager](INFO): DefineArg lineCaptureHoming:Science.PeakDetectChlActive = 0 bool
2025-07-05T15:08:35.556Z,1751728115.556 [MissionManager](INFO): DefineArg lineCaptureHoming:Science.EdgeDetectChlActive = 0 bool
2025-07-05T15:08:35.558Z,1751728115.558 [MissionManager](INFO): DefineArg lineCaptureHoming:Science.TimeWindowPeakReport = nan min
2025-07-05T15:08:35.572Z,1751728115.572 [MissionManager](INFO): DefineArg lineCaptureHoming:Science.HighestChlPeakReportActive = 0 bool
2025-07-05T15:08:35.574Z,1751728115.574 [MissionManager](INFO): DefineArg lineCaptureHoming:Science.HighestSaltPeakReportActive = 0 bool
2025-07-05T15:08:35.580Z,1751728115.580 [MissionManager](INFO): DefineArg lineCaptureHoming:Science.HighestOilPeakReportActive = 0 bool
2025-07-05T15:08:35.582Z,1751728115.582 [MissionManager](INFO): DefineArg lineCaptureHoming:Science.PatchTracking = 0 bool
2025-07-05T15:08:35.608Z,1751728115.608 [MissionManager](INFO): DefineArg lineCaptureHoming:Science.FilterWidthHorizontal = 3.000000 count
2025-07-05T15:08:35.611Z,1751728115.611 [MissionManager](INFO): DefineArg lineCaptureHoming:Science.NumProfilesSlidingwindow = 100.000000 count
2025-07-05T15:08:35.613Z,1751728115.613 [MissionManager](INFO): DefineArg lineCaptureHoming:Science.OffPeakFractionHorizontal = 80.000000 %
2025-07-05T15:08:35.615Z,1751728115.615 [MissionManager](INFO): DefineArg lineCaptureHoming:Science.PeakDetectNO3Active = 0 bool
2025-07-05T15:08:35.633Z,1751728115.633 [MissionManager](INFO): DefineArg lineCaptureHoming:Science.PeakDetectOilActive = 0 bool
2025-07-05T15:08:35.635Z,1751728115.635 [MissionManager](INFO): DefineArg lineCaptureHoming:Science.PeakDetectFDOMActive = 0 bool
2025-07-05T15:08:35.642Z,1751728115.642 [MissionManager](INFO): DefineArg lineCaptureHoming:Science.PeakDetectSalinityActive = 0 bool
2025-07-05T15:08:35.645Z,1751728115.645 [MissionManager](INFO): DefineArg lineCaptureHoming:Science.PeakDetectPlanktivoreLMavgROIActive = 0 bool
2025-07-05T15:08:35.647Z,1751728115.647 [MissionManager](INFO): DefineArg lineCaptureHoming:Science.EdgeDetectPlanktivoreLMavgROIActive = 0 bool
2025-07-05T15:08:35.669Z,1751728115.669 [MissionManager](INFO): DefineArg lineCaptureHoming:Science.PeakDetectPlanktivoreHMavgROIActive = 0 bool
2025-07-05T15:08:35.671Z,1751728115.671 [MissionManager](INFO): DefineArg lineCaptureHoming:Science.EdgeDetectPlanktivoreHMavgROIActive = 0 bool
2025-07-05T15:08:35.677Z,1751728115.677 [MissionManager](INFO): DefineArg lineCaptureHoming:Science.PeakDetectPlanktivoreDiatomsActive = 0 bool
2025-07-05T15:08:35.679Z,1751728115.679 [MissionManager](INFO): DefineArg lineCaptureHoming:Science.EdgeDetectPlanktivoreDiatomsActive = 0 bool
2025-07-05T15:08:35.686Z,1751728115.686 [MissionManager](INFO): DefineArg lineCaptureHoming:Science.PeakDetectPlanktivoreDinoflagellatesActive = 0 bool
2025-07-05T15:08:35.687Z,1751728115.687 [MissionManager](INFO): DefineArg lineCaptureHoming:Science.EdgeDetectPlanktivoreDinoflagellatesActive = 0 bool
2025-07-05T15:08:35.694Z,1751728115.694 [MissionManager](INFO): DefineArg lineCaptureHoming:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2025-07-05T15:08:35.697Z,1751728115.697 [MissionManager](INFO): DefineArg lineCaptureHoming:Science.EnabledAanderaaO2 = 0 bool
2025-07-05T15:08:35.699Z,1751728115.699 [MissionManager](INFO): DefineArg lineCaptureHoming:Science.EnabledNeilBrown = 0 bool
2025-07-05T15:08:35.717Z,1751728115.717 [MissionManager](INFO): DefineArg lineCaptureHoming:Science.EnabledRBRTridente = 0 bool
2025-07-05T15:08:35.719Z,1751728115.719 [MissionManager](INFO): DefineArg lineCaptureHoming:Science.EnabledSeabird = 1 bool
2025-07-05T15:08:35.726Z,1751728115.726 [MissionManager](INFO): DefineArg lineCaptureHoming:Science.EnabledWetLabsBB2FL = 1 bool
2025-07-05T15:08:35.741Z,1751728115.741 [MissionManager](INFO): DefineArg lineCaptureHoming:Science.EnabledWetLabsSeaOWL_UV_A = 0 bool
2025-07-05T15:08:35.748Z,1751728115.748 [MissionManager](INFO): DefineArg lineCaptureHoming:Science.EnabledWetLabsUBAT = 1 bool
2025-07-05T15:08:35.750Z,1751728115.750 [MissionManager](INFO): DefineArg lineCaptureHoming:Science.LowPassWindowLength = 20.000000 count
2025-07-05T15:08:35.757Z,1751728115.757 [MissionManager](INFO): DefineArg lineCaptureHoming:Science.MedianFilterLen = 5.000000 count
2025-07-05T15:08:35.759Z,1751728115.759 [MissionManager](INFO): DefineArg lineCaptureHoming:Science.ShallowBound = nan m
2025-07-05T15:08:35.765Z,1751728115.765 [MissionManager](INFO): DefineArg lineCaptureHoming:Science.DeepBound = nan m
2025-07-05T15:08:35.767Z,1751728115.767 [MissionManager](INFO): DefineArg lineCaptureHoming:Science.DepChangeThreshForAttitudeFlip = 2.000000 m
2025-07-05T15:08:35.774Z,1751728115.774 [MissionManager](INFO): DefineArg lineCaptureHoming:Science.OffPeakFractionVerticalAscent = nan %
2025-07-05T15:08:35.784Z,1751728115.784 [MissionManager](INFO): DefineArg lineCaptureHoming:Science.OffPeakFractionVerticalDescent = nan %
2025-07-05T15:08:35.787Z,1751728115.787 [MissionManager](INFO): DefineArg lineCaptureHoming:Science.ChlPeakThresh = 0.000000 ug/l
2025-07-05T15:08:35.793Z,1751728115.793 [MissionManager](INFO): DefineArg lineCaptureHoming:Science.ChlThreshTriggerActive = 0 bool
2025-07-05T15:08:35.795Z,1751728115.795 [MissionManager](INFO): DefineArg lineCaptureHoming:Science.ChlLowerThresh = nan ug/l
2025-07-05T15:08:35.813Z,1751728115.813 [MissionManager](INFO): DefineArg lineCaptureHoming:Science.ChlUpperThresh = nan ug/l
2025-07-05T15:08:35.815Z,1751728115.815 [MissionManager](INFO): DefineArg lineCaptureHoming:Science.NumProfilesThresh = 5.000000 count
2025-07-05T15:08:35.830Z,1751728115.830 [MissionManager](INFO): DefineArg lineCaptureHoming:Science.PlanktivoreLMavgROIPeakThresh = 0.000000 count/s
2025-07-05T15:08:35.835Z,1751728115.835 [MissionManager](INFO): DefineArg lineCaptureHoming:Science.PlanktivoreHMavgROIPeakThresh = 0.000000 count/s
2025-07-05T15:08:35.850Z,1751728115.850 [MissionManager](INFO): DefineArg lineCaptureHoming:Science.PlanktivoreDiatomsPeakThresh = 0.000000 count/ml
2025-07-05T15:08:35.856Z,1751728115.856 [MissionManager](INFO): DefineArg lineCaptureHoming:Science.PlanktivoreDinoflagellatesPeakThresh = 0.000000 count/ml
2025-07-05T15:08:35.858Z,1751728115.858 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.GoDown = 1 bool
2025-07-05T15:08:35.861Z,1751728115.861 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PeakChl = nan ug/l
2025-07-05T15:08:35.863Z,1751728115.863 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PeakChlDepth = nan m
2025-07-05T15:08:35.874Z,1751728115.874 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PeakChlTemperature = nan degC
2025-07-05T15:08:35.877Z,1751728115.877 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PeakChlLatitude = nan arcdeg
2025-07-05T15:08:35.886Z,1751728115.886 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PeakChlLongitude = nan arcdeg
2025-07-05T15:08:35.896Z,1751728115.896 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.EdgeChl = nan ug/l
2025-07-05T15:08:35.898Z,1751728115.898 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.EdgeChlDepth = nan m
2025-07-05T15:08:35.901Z,1751728115.901 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.EdgeChlTemperature = nan degC
2025-07-05T15:08:35.903Z,1751728115.903 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.EdgeChlLatitude = nan arcdeg
2025-07-05T15:08:35.950Z,1751728115.950 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.EdgeChlLongitude = nan arcdeg
2025-07-05T15:08:35.961Z,1751728115.961 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PeakNO3 = nan umol/l
2025-07-05T15:08:35.963Z,1751728115.963 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PeakNO3Depth = nan m
2025-07-05T15:08:35.982Z,1751728115.982 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PeakNO3Latitude = nan arcdeg
2025-07-05T15:08:35.996Z,1751728115.996 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PeakNO3Longitude = nan arcdeg
2025-07-05T15:08:35.998Z,1751728115.998 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PeakOil = nan kg/m3
2025-07-05T15:08:36.005Z,1751728116.005 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PeakOilDepth = nan m
2025-07-05T15:08:36.007Z,1751728116.007 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PeakOilLatitude = nan arcdeg
2025-07-05T15:08:36.014Z,1751728116.014 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PeakOilLongitude = nan arcdeg
2025-07-05T15:08:36.032Z,1751728116.033 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PeakFDOM = nan ppb
2025-07-05T15:08:36.035Z,1751728116.035 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PeakFDOMDepth = nan m
2025-07-05T15:08:36.041Z,1751728116.041 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PeakFDOMTemperature = nan degC
2025-07-05T15:08:36.052Z,1751728116.052 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PeakFDOMLatitude = nan arcdeg
2025-07-05T15:08:36.054Z,1751728116.054 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PeakFDOMLongitude = nan arcdeg
2025-07-05T15:08:36.061Z,1751728116.061 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PeakSalt = nan psu
2025-07-05T15:08:36.063Z,1751728116.063 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PeakSaltDepth = nan m
2025-07-05T15:08:36.070Z,1751728116.070 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PeakSaltTemperature = nan degC
2025-07-05T15:08:36.076Z,1751728116.076 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PeakSaltLatitude = nan arcdeg
2025-07-05T15:08:36.079Z,1751728116.079 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PeakSaltLongitude = nan arcdeg
2025-07-05T15:08:36.085Z,1751728116.085 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PeakPlanktivoreLMavgROI = nan count/s
2025-07-05T15:08:36.092Z,1751728116.092 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PeakPlanktivoreLMavgROIDepth = nan m
2025-07-05T15:08:36.094Z,1751728116.094 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PeakPlanktivoreLMavgROITemperature = nan degC
2025-07-05T15:08:36.101Z,1751728116.101 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PeakPlanktivoreLMavgROILatitude = nan arcdeg
2025-07-05T15:08:36.103Z,1751728116.103 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PeakPlanktivoreLMavgROILongitude = nan arcdeg
2025-07-05T15:08:36.114Z,1751728116.114 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.EdgePlanktivoreLMavgROI = nan count/s
2025-07-05T15:08:36.120Z,1751728116.120 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.EdgePlanktivoreLMavgROIDepth = nan m
2025-07-05T15:08:36.123Z,1751728116.123 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.EdgePlanktivoreLMavgROITemperature = nan degC
2025-07-05T15:08:36.129Z,1751728116.129 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.EdgePlanktivoreLMavgROILatitude = nan arcdeg
2025-07-05T15:08:36.145Z,1751728116.145 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.EdgePlanktivoreLMavgROILongitude = nan arcdeg
2025-07-05T15:08:36.147Z,1751728116.147 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PeakPlanktivoreHMavgROI = nan count/s
2025-07-05T15:08:36.155Z,1751728116.155 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PeakPlanktivoreHMavgROIDepth = nan m
2025-07-05T15:08:36.161Z,1751728116.161 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PeakPlanktivoreHMavgROITemperature = nan degC
2025-07-05T15:08:36.168Z,1751728116.168 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PeakPlanktivoreHMavgROILatitude = nan arcdeg
2025-07-05T15:08:36.171Z,1751728116.171 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PeakPlanktivoreHMavgROILongitude = nan arcdeg
2025-07-05T15:08:36.177Z,1751728116.177 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.EdgePlanktivoreHMavgROI = nan count/s
2025-07-05T15:08:36.179Z,1751728116.179 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.EdgePlanktivoreHMavgROIDepth = nan m
2025-07-05T15:08:36.189Z,1751728116.189 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.EdgePlanktivoreHMavgROITemperature = nan degC
2025-07-05T15:08:36.191Z,1751728116.191 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.EdgePlanktivoreHMavgROILatitude = nan arcdeg
2025-07-05T15:08:36.194Z,1751728116.194 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.EdgePlanktivoreHMavgROILongitude = nan arcdeg
2025-07-05T15:08:36.204Z,1751728116.204 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PeakPlanktivoreDiatoms = nan count/ml
2025-07-05T15:08:36.207Z,1751728116.207 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PeakPlanktivoreDiatomsDepth = nan m
2025-07-05T15:08:36.213Z,1751728116.213 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PeakPlanktivoreDiatomsTemperature = nan degC
2025-07-05T15:08:36.220Z,1751728116.220 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PeakPlanktivoreDiatomsLatitude = nan arcdeg
2025-07-05T15:08:36.222Z,1751728116.222 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PeakPlanktivoreDiatomsLongitude = nan arcdeg
2025-07-05T15:08:36.225Z,1751728116.225 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.EdgePlanktivoreDiatoms = nan count/ml
2025-07-05T15:08:36.227Z,1751728116.227 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.EdgePlanktivoreDiatomsDepth = nan m
2025-07-05T15:08:36.242Z,1751728116.242 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.EdgePlanktivoreDiatomsTemperature = nan degC
2025-07-05T15:08:36.248Z,1751728116.248 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.EdgePlanktivoreDiatomsLatitude = nan arcdeg
2025-07-05T15:08:36.250Z,1751728116.250 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.EdgePlanktivoreDiatomsLongitude = nan arcdeg
2025-07-05T15:08:36.255Z,1751728116.255 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PeakPlanktivoreDinoflagellates = nan count/ml
2025-07-05T15:08:36.270Z,1751728116.270 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PeakPlanktivoreDinoflagellatesDepth = nan m
2025-07-05T15:08:36.278Z,1751728116.278 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PeakPlanktivoreDinoflagellatesTemperature = nan degC
2025-07-05T15:08:36.282Z,1751728116.282 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PeakPlanktivoreDinoflagellatesLatitude = nan arcdeg
2025-07-05T15:08:36.284Z,1751728116.284 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PeakPlanktivoreDinoflagellatesLongitude = nan arcdeg
2025-07-05T15:08:36.287Z,1751728116.287 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.EdgePlanktivoreDinoflagellates = nan count/ml
2025-07-05T15:08:36.293Z,1751728116.293 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.EdgePlanktivoreDinoflagellatesDepth = nan m
2025-07-05T15:08:36.295Z,1751728116.295 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.EdgePlanktivoreDinoflagellatesTemperature = nan degC
2025-07-05T15:08:36.302Z,1751728116.302 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.EdgePlanktivoreDinoflagellatesLatitude = nan arcdeg
2025-07-05T15:08:36.305Z,1751728116.305 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.EdgePlanktivoreDinoflagellatesLongitude = nan arcdeg
2025-07-05T15:08:36.307Z,1751728116.307 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PatchChl = nan ug/l
2025-07-05T15:08:36.354Z,1751728116.354 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PatchChlDepth = nan m
2025-07-05T15:08:36.361Z,1751728116.361 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PatchChlLatitude = nan arcdeg
2025-07-05T15:08:36.380Z,1751728116.380 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PatchChlLongitude = nan arcdeg
2025-07-05T15:08:36.382Z,1751728116.382 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PatchChlDistance = nan m
2025-07-05T15:08:36.389Z,1751728116.389 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PatchSalt = nan psu
2025-07-05T15:08:36.404Z,1751728116.404 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PatchSaltDepth = nan m
2025-07-05T15:08:36.406Z,1751728116.406 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PatchSaltLatitude = nan arcdeg
2025-07-05T15:08:36.413Z,1751728116.413 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PatchSaltLongitude = nan arcdeg
2025-07-05T15:08:36.415Z,1751728116.415 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PatchSaltDistance = nan m
2025-07-05T15:08:36.434Z,1751728116.434 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PatchOil = nan psu
2025-07-05T15:08:36.444Z,1751728116.444 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PatchOilDepth = nan m
2025-07-05T15:08:36.447Z,1751728116.447 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PatchOilLatitude = nan arcdeg
2025-07-05T15:08:36.461Z,1751728116.461 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PatchOilLongitude = nan arcdeg
2025-07-05T15:08:36.463Z,1751728116.463 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.PatchOilDistance = nan m
2025-07-05T15:08:36.469Z,1751728116.469 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.TriggeredOnChl = 0 bool
2025-07-05T15:08:36.476Z,1751728116.476 [MissionManager](INFO): DefineOutput lineCaptureHoming:Science.ChlTriggering = nan ug/l
2025-07-05T15:08:36.519Z,1751728116.519 [lineCaptureHoming:Science:PeakDetectChl:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2025-07-05T15:08:36.543Z,1751728116.543 [lineCaptureHoming:Science:EdgeDetectChl:B.EdgeDetectVsDepth](DEBUG): Construct EdgeDetectVsDepth.
2025-07-05T15:08:36.606Z,1751728116.606 [lineCaptureHoming:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2025-07-05T15:08:36.643Z,1751728116.643 [lineCaptureHoming:Science:PeakDetectNO3:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2025-07-05T15:08:36.693Z,1751728116.693 [lineCaptureHoming:Science:PeakDetectOil:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2025-07-05T15:08:36.711Z,1751728116.711 [lineCaptureHoming:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2025-07-05T15:08:36.797Z,1751728116.797 [lineCaptureHoming:Science:PeakDetectFDOM:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2025-07-05T15:08:36.838Z,1751728116.838 [lineCaptureHoming:Science:PeakDetectSalinity:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2025-07-05T15:08:36.922Z,1751728116.922 [lineCaptureHoming:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2025-07-05T15:08:36.958Z,1751728116.958 [lineCaptureHoming:Science:PeakDetectPlanktivoreLMavgROI:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2025-07-05T15:08:36.996Z,1751728116.996 [lineCaptureHoming:Science:EdgeDetectPlanktivoreLMavgROI:B.EdgeDetectVsDepth](DEBUG): Construct EdgeDetectVsDepth.
2025-07-05T15:08:37.062Z,1751728117.062 [lineCaptureHoming:Science:PeakDetectPlanktivoreHMavgROI:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2025-07-05T15:08:37.115Z,1751728117.115 [lineCaptureHoming:Science:EdgeDetectPlanktivoreHMavgROI:B.EdgeDetectVsDepth](DEBUG): Construct EdgeDetectVsDepth.
2025-07-05T15:08:37.260Z,1751728117.260 [lineCaptureHoming:Science:PeakDetectPlanktivoreDiatoms:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2025-07-05T15:08:37.290Z,1751728117.290 [lineCaptureHoming:Science:EdgeDetectPlanktivoreDiatoms:B.EdgeDetectVsDepth](DEBUG): Construct EdgeDetectVsDepth.
2025-07-05T15:08:37.357Z,1751728117.357 [lineCaptureHoming:Science:PeakDetectPlanktivoreDinoflagellates:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2025-07-05T15:08:37.398Z,1751728117.398 [lineCaptureHoming:Science:EdgeDetectPlanktivoreDinoflagellates:B.EdgeDetectVsDepth](DEBUG): Construct EdgeDetectVsDepth.
2025-07-05T15:08:37.463Z,1751728117.463 [lineCaptureHoming:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Construct ValueDetect.
2025-07-05T15:08:37.479Z,1751728117.479 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2025-07-05T15:08:37.479Z,1751728117.479 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl
2025-07-05T15:08:37.485Z,1751728117.485 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl
2025-07-05T15:08:37.693Z,1751728117.693 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into:
5
1.5
200
2000
2025-07-05T15:08:37.708Z,1751728117.708 [MissionManager](INFO): DefineArg lineCaptureHoming:StandardEnvelopes.MinAltitude = 5.000000 m
2025-07-05T15:08:37.710Z,1751728117.710 [MissionManager](INFO): DefineArg lineCaptureHoming:StandardEnvelopes.MaxDepthIgnore = 1.500000 m
2025-07-05T15:08:37.717Z,1751728117.717 [MissionManager](INFO): DefineArg lineCaptureHoming:StandardEnvelopes.MaxDepth = 200.000000 m
2025-07-05T15:08:37.719Z,1751728117.719 [MissionManager](INFO): DefineArg lineCaptureHoming:StandardEnvelopes.MinOffshore = 2000.000000 m
2025-07-05T15:08:37.724Z,1751728117.724 [lineCaptureHoming:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2025-07-05T15:08:37.784Z,1751728117.784 [lineCaptureHoming:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2025-07-05T15:08:37.794Z,1751728117.795 [lineCaptureHoming:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2025-07-05T15:08:37.818Z,1751728117.818 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2025-07-05T15:08:37.818Z,1751728117.818 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl
2025-07-05T15:08:37.819Z,1751728117.819 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl
2025-07-05T15:08:38.253Z,1751728118.253 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/NeedComms.tl translated into:
1
10
20
NaN
1
1000
7
30
0
last time_fix was:
first GPS update timeout
Comms timed out after
minutes
second GPS update timeout
2025-07-05T15:08:38.261Z,1751728118.261 [MissionManager](INFO): DefineArg lineCaptureHoming:NeedComms.DiveInterval = 1.000000 h
2025-07-05T15:08:38.263Z,1751728118.263 [MissionManager](INFO): DefineArg lineCaptureHoming:NeedComms.WaitForPitchUp = 10.000000 min
2025-07-05T15:08:38.270Z,1751728118.270 [MissionManager](INFO): DefineArg lineCaptureHoming:NeedComms.SurfacePitch = 20.000000 arcdeg
2025-07-05T15:08:38.292Z,1751728118.292 [MissionManager](INFO): DefineArg lineCaptureHoming:NeedComms.SurfaceDepthRate = nan m/s
2025-07-05T15:08:38.294Z,1751728118.294 [MissionManager](INFO): DefineArg lineCaptureHoming:NeedComms.SurfaceSpeed = 1.000000 m/s
2025-07-05T15:08:38.301Z,1751728118.301 [MissionManager](INFO): DefineArg lineCaptureHoming:NeedComms.SurfacingTimeout = 1000.000000 s
2025-07-05T15:08:38.303Z,1751728118.303 [MissionManager](INFO): DefineArg lineCaptureHoming:NeedComms.GPSTimeout = 7.000000 min
2025-07-05T15:08:38.309Z,1751728118.309 [MissionManager](INFO): DefineArg lineCaptureHoming:NeedComms.CommsTimeout = 30.000000 min
2025-07-05T15:08:38.319Z,1751728118.319 [lineCaptureHoming:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-07-05T15:08:38.437Z,1751728118.437 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/MicromodemComms.tl
2025-07-05T15:08:38.437Z,1751728118.437 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/MicromodemComms.tl
2025-07-05T15:08:38.438Z,1751728118.438 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/MicromodemComms.tl
2025-07-05T15:08:38.555Z,1751728118.555 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/MicromodemComms.tl translated into:
10
5
2025-07-05T15:08:38.571Z,1751728118.571 [MissionManager](INFO): DefineArg lineCaptureHoming:MicromodemComms.Timeout = 10.000000 min
2025-07-05T15:08:38.577Z,1751728118.577 [MissionManager](INFO): DefineArg lineCaptureHoming:MicromodemComms.CommsInterval = 5.000000 min
2025-07-05T15:08:38.586Z,1751728118.586 [MissionManager](INFO): RedefineArg lineCaptureHoming:MicromodemComms.CommsInterval = reader:lineCaptureHoming.MicromodemCommsInterval
2025-07-05T15:08:38.598Z,1751728118.598 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/LineCapture.tl
2025-07-05T15:08:38.598Z,1751728118.598 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/LineCapture.tl
2025-07-05T15:08:38.660Z,1751728118.660 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/LineCapture.tl
2025-07-05T15:08:40.817Z,1751728120.817 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/LineCapture.tl translated into:
30
0.0
1
0.01
0.001
2
8
1
NaN
NaN
NaN
0.0
0
NaN
-1
0
1
2
3
1
5
0
0
Timed out while trying to detach from dock at current depth of
.
Transitioning to midcourse guidance.
Navigating to dock waypoint (
,
)
Transitioning to terminal guidance at range
Terminating homing sequence: timed out while navigating to the dock waypoint.
Executing search pattern.
Timed out while executing search pattern around dock waypoint.
Homing sequence timed out prior to docking.
Terminal guidance.
Transitioning to midcourse guidance. Received last range of
ago.
Docked. Homing sequence complete.
3
GuidanceMode
2025-07-05T15:08:40.924Z,1751728120.924 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture.DockLat = 36.905698 arcdeg
2025-07-05T15:08:40.927Z,1751728120.927 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture.DockLon = -122.116120 arcdeg
2025-07-05T15:08:40.937Z,1751728120.937 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture.DetachTimeout = 30.000000 min
2025-07-05T15:08:40.939Z,1751728120.939 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture.MidcourseDepth = 50.000000 m
2025-07-05T15:08:40.950Z,1751728120.950 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture.MidcourseSpeed = 0.800000 m/s
2025-07-05T15:08:40.961Z,1751728120.961 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture.MidcourseTimeout = 6.000000 h
2025-07-05T15:08:40.963Z,1751728120.963 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture.TerminalRange = 650.000000 m
2025-07-05T15:08:40.974Z,1751728120.974 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture.AcousticTimeout = 5.000000 min
2025-07-05T15:08:41.001Z,1751728121.001 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture.losRateIIRFilterDecay = 0.000000 n/a
2025-07-05T15:08:41.003Z,1751728121.003 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture.SearchTimeout = 5.000000 min
2025-07-05T15:08:41.026Z,1751728121.026 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture.CircleRadius = 1000.000000 m
2025-07-05T15:08:41.040Z,1751728121.040 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture.CircleMaxError = 1.000000 m
2025-07-05T15:08:41.042Z,1751728121.042 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture.CircleTurnToPort = 0 bool
2025-07-05T15:08:41.053Z,1751728121.053 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture.DepthDeadband = 0.010000 m
2025-07-05T15:08:41.055Z,1751728121.055 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture.RudderDeadband = 0.001000 arcdeg
2025-07-05T15:08:41.065Z,1751728121.065 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture.MinAltitude = 2.000000 m
2025-07-05T15:08:41.067Z,1751728121.067 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture.MaxDepth = 8.000000 m
2025-07-05T15:08:41.096Z,1751728121.096 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture.MinOffshore = 1.000000 m
2025-07-05T15:08:41.098Z,1751728121.098 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture.DockingAltitude = nan m
2025-07-05T15:08:41.107Z,1751728121.107 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture.InvalidAltitudeTimeout = nan min
2025-07-05T15:08:41.130Z,1751728121.130 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture.AltitudeDepthLimit = nan m
2025-07-05T15:08:41.140Z,1751728121.140 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture.AltitudeIIRFilterDecay = 0.000000 n/a
2025-07-05T15:08:41.143Z,1751728121.143 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture.ElapsedRange = 0.000000 min
2025-07-05T15:08:41.153Z,1751728121.153 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture.Range = nan m
2025-07-05T15:08:41.155Z,1751728121.155 [MissionManager](INFO): DefineOutput lineCaptureHoming:LineCapture.DoneTimeout = -1.000000 count
2025-07-05T15:08:41.166Z,1751728121.166 [MissionManager](INFO): DefineOutput lineCaptureHoming:LineCapture.Done = 0.000000 count
2025-07-05T15:08:41.196Z,1751728121.196 [MissionManager](INFO): DefineOutput lineCaptureHoming:LineCapture.DetachFromDock = 1.000000 count
2025-07-05T15:08:41.199Z,1751728121.199 [MissionManager](INFO): DefineOutput lineCaptureHoming:LineCapture.Midcourse = 2.000000 count
2025-07-05T15:08:41.213Z,1751728121.213 [MissionManager](INFO): DefineOutput lineCaptureHoming:LineCapture.Terminal = 3.000000 count
2025-07-05T15:08:41.215Z,1751728121.215 [MissionManager](INFO): DefineOutput lineCaptureHoming:LineCapture.GuidanceMode = 1.000000 count
2025-07-05T15:08:41.235Z,1751728121.235 [MissionManager](INFO): DefineOutput lineCaptureHoming:LineCapture.DockingDepth = 1.000000 m
2025-07-05T15:08:41.254Z,1751728121.254 [MissionManager](INFO): DefineOutput lineCaptureHoming:LineCapture.LineCapRollout = 5.000000 count
2025-07-05T15:08:41.270Z,1751728121.270 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2025-07-05T15:08:41.271Z,1751728121.271 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl
2025-07-05T15:08:41.288Z,1751728121.288 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl
2025-07-05T15:08:41.525Z,1751728121.525 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into:
5
1.5
200
2000
2025-07-05T15:08:41.538Z,1751728121.538 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture:StandardEnvelopes.MinAltitude = 5.000000 m
2025-07-05T15:08:41.540Z,1751728121.540 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture:StandardEnvelopes.MaxDepthIgnore = 1.500000 m
2025-07-05T15:08:41.542Z,1751728121.542 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture:StandardEnvelopes.MaxDepth = 200.000000 m
2025-07-05T15:08:41.549Z,1751728121.549 [MissionManager](INFO): DefineArg lineCaptureHoming:LineCapture:StandardEnvelopes.MinOffshore = 2000.000000 m
2025-07-05T15:08:41.550Z,1751728121.550 [lineCaptureHoming:LineCapture:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2025-07-05T15:08:41.559Z,1751728121.559 [lineCaptureHoming:LineCapture:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2025-07-05T15:08:41.614Z,1751728121.614 [lineCaptureHoming:LineCapture:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2025-07-05T15:08:41.701Z,1751728121.701 [lineCaptureHoming:LineCapture:HoldMass:B.Mass](DEBUG): Construct.
2025-07-05T15:08:41.713Z,1751728121.713 [lineCaptureHoming:LineCapture:HoldBuoyancy:A.Buoyancy](DEBUG): Construct Buoyancy.
2025-07-05T15:08:41.727Z,1751728121.727 [lineCaptureHoming:LineCapture:HomingSequence:DetachFromDock:guide:A.Undock](DEBUG): Construct.
2025-07-05T15:08:41.727Z,1751728121.727 [lineCaptureHoming:LineCapture:HomingSequence:DetachFromDock:guide:A.Undock](DEBUG): Initializing internal variables to default values.
2025-07-05T15:08:41.781Z,1751728121.781 [lineCaptureHoming:LineCapture:HomingSequence:MidcourseGuidance:guide:C.SetSpeed](DEBUG): Construct.
2025-07-05T15:08:41.788Z,1751728121.788 [lineCaptureHoming:LineCapture:HomingSequence:MidcourseGuidance:guide:D.Pitch](DEBUG): Construct.
2025-07-05T15:08:41.803Z,1751728121.803 [lineCaptureHoming:LineCapture:HomingSequence:MidcourseGuidance:guide:E.Waypoint](DEBUG): Construct Waypoint.
2025-07-05T15:08:41.831Z,1751728121.831 [lineCaptureHoming:LineCapture:HomingSequence:MidcourseGuidance:guide:SearchPattern:A.Circle](DEBUG): Construct.
2025-07-05T15:08:41.850Z,1751728121.850 [lineCaptureHoming:LineCapture:HomingSequence:MidcourseGuidance:guide:SearchPattern:B.Wait](DEBUG): Construct Wait.
2025-07-05T15:08:41.873Z,1751728121.873 [lineCaptureHoming:LineCapture:HomingSequence:TerminalGuidance:guide:altitudeControl:A.AltitudeServo](DEBUG): Construct.
2025-07-05T15:08:41.888Z,1751728121.888 [lineCaptureHoming:LineCapture:HomingSequence:TerminalGuidance:guide:depthControl:A.Pitch](DEBUG): Construct.
2025-07-05T15:08:41.908Z,1751728121.908 [lineCaptureHoming:LineCapture:HomingSequence:TerminalGuidance:guide:G.LineCapture](DEBUG): Construct.
2025-07-05T15:08:41.933Z,1751728121.933 [lineCaptureHoming:LineCapture:HomingSequence:TerminalGuidance:guide:G.LineCapture](DEBUG): Initializing internal variables to default values.
2025-07-05T15:08:41.933Z,1751728121.933 [lineCaptureHoming:LineCapture:HomingSequence:TerminalGuidance:guide:G.LineCapture](INFO): Transitioning guidance mode to: UNINITIALIZED
2025-07-05T15:08:41.942Z,1751728121.942 [lineCaptureHoming:LineCapture:L.Wait](DEBUG): Construct Wait.
2025-07-05T15:08:42.211Z,1751728122.211 [lineCaptureHoming:restartDHCP:B.Execute](DEBUG): Construct Execute.
2025-07-05T15:08:42.218Z,1751728122.218 [lineCaptureHoming:BA.Execute](DEBUG): Construct Execute.
2025-07-05T15:08:42.237Z,1751728122.237 [lineCaptureHoming:InitialDive:A.SetSpeed](DEBUG): Construct.
2025-07-05T15:08:42.239Z,1751728122.239 [lineCaptureHoming:InitialDive:B.Mass](DEBUG): Construct.
2025-07-05T15:08:42.246Z,1751728122.246 [lineCaptureHoming:InitialDive:C.Pitch](DEBUG): Construct.
2025-07-05T15:08:42.264Z,1751728122.264 [lineCaptureHoming:InitialDive:C.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute.
2025-07-05T15:08:42.291Z,1751728122.291 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/SetNavAcoustic.tl
2025-07-05T15:08:42.291Z,1751728122.291 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/SetNavAcoustic.tl
2025-07-05T15:08:42.296Z,1751728122.296 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/SetNavAcoustic.tl
2025-07-05T15:08:42.545Z,1751728122.545 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/SetNavAcoustic.tl translated into:
Reseting nav to dock location at range
2025-07-05T15:08:42.553Z,1751728122.553 [MissionManager](INFO): DefineArg lineCaptureHoming:Lap:Docked:SetNavAcoustic.DockLat = 36.905698 arcdeg
2025-07-05T15:08:42.560Z,1751728122.560 [MissionManager](INFO): DefineArg lineCaptureHoming:Lap:Docked:SetNavAcoustic.DockLon = -122.116120 arcdeg
2025-07-05T15:08:42.562Z,1751728122.562 [MissionManager](INFO): DefineArg lineCaptureHoming:Lap:Docked:SetNavAcoustic.DockRange = 0.000000 m
2025-07-05T15:08:42.593Z,1751728122.593 [MissionManager](INFO): DefineArg lineCaptureHoming:Lap:Docked:SetNavAcoustic.RangeTimeout = 5.000000 min
2025-07-05T15:08:42.603Z,1751728122.603 [lineCaptureHoming:Lap:Docked:SetNavAcoustic:UpdateNav:B.SetNav](DEBUG): Construct SetNav.
2025-07-05T15:08:42.617Z,1751728122.617 [lineCaptureHoming:Lap:Docked:Docked.Docked](DEBUG): Construct.
2025-07-05T15:08:42.617Z,1751728122.617 [lineCaptureHoming:Lap:Docked:Docked.Docked](DEBUG): Initializing internal variables to default values.
2025-07-05T15:08:42.671Z,1751728122.671 [lineCaptureHoming:Lap:Docked:Detach:Undock.Undock](DEBUG): Construct.
2025-07-05T15:08:42.671Z,1751728122.671 [lineCaptureHoming:Lap:Docked:Detach:Undock.Undock](DEBUG): Initializing internal variables to default values.
2025-07-05T15:08:42.700Z,1751728122.700 [lineCaptureHoming:Lap:Docked:Wpt1:B.Pitch](DEBUG): Construct.
2025-07-05T15:08:42.708Z,1751728122.708 [lineCaptureHoming:Lap:Docked:Wpt1:C.SetSpeed](DEBUG): Construct.
2025-07-05T15:08:42.711Z,1751728122.711 [lineCaptureHoming:Lap:Docked:Wpt1:D.Waypoint](DEBUG): Construct Waypoint.
2025-07-05T15:08:42.747Z,1751728122.747 [lineCaptureHoming:Lap:Docked:Drift:B.Buoyancy](DEBUG): Construct Buoyancy.
2025-07-05T15:08:42.766Z,1751728122.766 [lineCaptureHoming:Lap:Docked:Drift:C.Pitch](DEBUG): Construct.
2025-07-05T15:08:42.775Z,1751728122.775 [lineCaptureHoming:Lap:Docked:Drift:D.Point](DEBUG): Construct.
2025-07-05T15:08:42.874Z,1751728122.874 [lineCaptureHoming:Lap:Docked:Drift:E.SetSpeed](DEBUG): Construct.
2025-07-05T15:08:42.917Z,1751728122.917 [lineCaptureHoming:Lap:Docked:Drift:F.Wait](DEBUG): Construct Wait.
2025-07-05T15:08:43.113Z,1751728123.113 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission lineCaptureHoming {
"""
Home to transponder while maintaining altitude or depth. Altitude gets
priority if non nan. The mission sequence: (*) Start (1) Midcourse
guidance: nav to target WP while querying transponder (2) Terminal
guidance: home to target using USBL tracking data (4) Dock: final
approach using fixed heading.
"""
# Define mission arguments
arguments {
MissionTimeout = 3 hour
"""
Maximum length of mission
"""
NeedCommsTime = 180 minute
"""
How often to surface for commumications
"""
MicromodemCommsInterval = 3 hour
"""
How often to trigger Umodem communications
"""
Repeat = 1 count
"""
Number of times the vehicle should try to cycle through the homing
sequence.
"""
WaitOnDockTimeout = 5 minute
"""
Time duration to wait on the dock after a successful line capture.
"""
StayOnDock = false
"""
If true, mission terminates after WaitOnDockTimeout following a successful
capture, without executing the undock sequence.
"""
TrySlide = false
"""
If true, once docked, try to slide down the docking rod until charging and
comms are acheived
"""
RetryIncompleteDock = false
"""
If true, restart docking sequence in event of a successful capture but
unsuccessful slide
"""
SlideRetries = 4 count
"""
If using slide mode, max number of times to try the slide sequence before
giving up
"""
SlideSinkDuration = 1.5 minute
"""
Time duration to slide down by sinking during each slide mode iteration
"""
SlideCloseDuration = 1.5 minute
"""
Time duration to close arm to check for power/comms during each slide mode
iteration
"""
TransponderCode = Dock:Dock.transponderCode
"""
Transponder Address.
"""
TrackingUpdatePeriodMidcourse = 60 second
"""
How long to wait between acoustic queries.
"""
TrackingUpdatePeriodTerminal = 2 second
"""
How long to wait between acoustic queries.
"""
TrackingUpdatePeriodDocked = 2 minute
"""
How long to wait between acoustic queries.
"""
TrackingNumFixesLowPassFilter = 5 count
"""
Median filter bin size. Filters the contact estimated lat/lon position.
Prefer odd number, set to 1 to disable filter.
"""
NumberOfPings = 1 count
"""
Number of pings requested each time.
"""
NumPingsUSBL = 120 count
"""
Number of pings requested while in USBL mode range.
"""
RangeUSBL = 40 meter
"""
USBL mode range.
"""
TrackingNumFixesLowPassFilterUSBL = 5 count
"""
Median filter bin size. Filters the contact estimated lat/lon position.
Prefer odd number, set to 1 to disable filter.
"""
DockingAltitude = NaN meter
"""
Docking altitude. Ignored when NaN and used over depth when a valid
altitude is set.
"""
InvalidAltitudeTimeout = 10 minute
"""
The mission will use the last good depth command if no valid altitude
readings are received within this specified time duration.
"""
AltitudeDepthLimit = NaN meter
"""
Vehicle will not be commanded deeper than this regardless of altitude
command.
"""
MinAltitude = 4 meter
"""
Minimum height above the sea floor for the entire mission.
"""
MaxDepth = 205 meter
"""
Maximum depth for the entire mission.
"""
MinOffshore = 2 kilometer
"""
Minimum offshore distance for the entire mission.
"""
ApproachDepthTimeout = 20 minute
"""
Maximum wait time for the vehicle to reach the target depth on initial
dive.
"""
ApproachDepthRate = 0.05 meter_per_second
"""
Descent depth rate (positive depth is down). Used only as a smoothing
parameter.
"""
Lat1 = NaN degree
"""
Latitude of rollout waypoint. If nan, waypoint is skipped.
"""
Lon1 = NaN degree
"""
Longitude of rollout waypoint. If nan, waypoint is skipped.
"""
CaptureRadius = NaN meter
"""
How close to call the waypiont behavior satisfied. Nan uses default
value.
"""
CorrectForSoundSpeed = false
"""
Whether or not to apply sound speed correction to acoustic ranges
"""
# Mission Variables (don't change)
MidcourseDepth = Dock:LineCapture.midcourseDepth
"""
Desired vehicle depth when navigating to dock WP (during midcourse
phase).
"""
MidcourseSpeed = Dock:LineCapture.midcourseSpeed
"""
Speed during initial dive and waypoint.
"""
TrackingPeriod = 60 second
"""
Mission variable (don't change). The mission sets this variable to
update the how long to wait between acoustic queries.
"""
}
output {
Mode = -1 count
"""
Mission variable (don't change). The mission sets this variable to
reflect the termination status of the LineCapture insert.
"""
NumPings = 1 count
"""
Mission variable (don't change). The mission sets this variable to
update the requested ping number and enter usbl mode.
"""
LowPassFilterWinSize = 1 count
"""
Mission variable (don't change). The mission sets this variable to
update the requested ping number and enter usbl mode.
"""
LowPassFilterWinOverlap = true
"""
Mission variable (don't change). The mission sets this variable to
update the requested ping number and enter usbl mode.
"""
EndDockingSequence = StayOnDock
"""
Mission variable (don't change). The mission sets this variable to
determine whether or not to restart the docking sequence upon
completion of the Docked behavior.
"""
LastDockSuccessful = false
"""
Mission variable (don't change). The mission sets this variable based
on the last run of the Docked behavior
"""
DockSuccessfulCount = 0 count
"""
Mission variable (don't change). The mission sets this variable to
track the number of complete docking attempts
"""
RepeatArg = Repeat
"""
Mission variable (don't change). Actual number of Lap repetitions
"""
}
# Mission timeout
timeout duration=MissionTimeout
insert Insert/Science.tl {
"""
Most missions will run the science sensors. If you don't place this
aggregate above NeedComms, science instruments get turned off on the
last upcast and while floating on the surface.
"""
}
# Safety nets and surfacing behaviors
insert Insert/StandardEnvelopes.tl
assign in sequence StandardEnvelopes:MinAltitude = MinAltitude
assign in sequence StandardEnvelopes:MaxDepth = MaxDepth
assign in sequence StandardEnvelopes:MinOffshore = MinOffshore
insert id="NeedComms" Insert/NeedComms.tl
assign in sequence NeedComms:DiveInterval = NeedCommsTime
assign in sequence NeedComms:WaitForPitchUp = 0 minute
# Micromodem comms
insert id="MicromodemComms" Insert/MicromodemComms.tl {
redefineArg CommsInterval = MicromodemCommsInterval
}
readData {
while (
Mode >= 0 count
and Sensor:DataOverHttps.connectionStatus == true
)
Universal:platform_communications
}
# Add LineCapture directives.
insert Insert/LineCapture.tl
assign in sequence LineCapture:MaxDepth = MaxDepth
assign in sequence LineCapture:MinOffshore = MinOffshore
assign in sequence LineCapture:MinAltitude = MinAltitude
assign in sequence LineCapture:DockingAltitude = DockingAltitude
assign in sequence LineCapture:InvalidAltitudeTimeout = InvalidAltitudeTimeout
assign in sequence LineCapture:AltitudeDepthLimit = AltitudeDepthLimit
# Add track acoustic contact directive.
assign in sequence NumPings = NumberOfPings
behavior Estimation:TrackAcousticContact id="TrackAC" {
run in parallel
set contactLabelSetting = TransponderCode
set numberOfSamplesSetting = NumPings
set updatePeriodSetting = TrackingPeriod
set numFixesLowPassSetting = LowPassFilterWinSize
set lowPassOverlapSetting = LowPassFilterWinOverlap
set useSoundSpeedCorrectionSetting = CorrectForSoundSpeed
}
# Add USBL mode directives.
aggregate UpdateNumPings_NotTerminal {
run while (
not ( LineCapture:GuidanceMode == LineCapture:Terminal )
and not ( NumPings == NumberOfPings )
)
syslog info "No longer in Terminal. Resuming normal ranging with " + NumberOfPings~count
+ "pings."
assign in sequence NumPings = NumberOfPings
assign in sequence LowPassFilterWinSize = TrackingNumFixesLowPassFilter
assign in sequence LowPassFilterWinOverlap = true
}
aggregate UpdateNumPings_Terminal {
run while (
LineCapture:GuidanceMode == LineCapture:Terminal
)
aggregate UpdateNumPingsUSBL {
run when (
not ( NumPings == NumPingsUSBL )
and (
( LineCapture:Range < RangeUSBL ) # don't enter USBL mode during rollout
and not ( Dock:LineCapture.guidanceMode == 5 count )
)
)
syslog info "Entering USBL mode with " + NumPingsUSBL~count + "pings at "
+ LineCapture:Range~meter + "(mode " + Dock:LineCapture.guidanceMode~count + ")."
assign in sequence NumPings = NumPingsUSBL
assign in sequence LowPassFilterWinSize = TrackingNumFixesLowPassFilterUSBL
assign in sequence LowPassFilterWinOverlap = true
}
aggregate UpdateNumPingsATR {
run when (
not ( NumPings == NumberOfPings )
and (
( LineCapture:Range >= RangeUSBL ) # do ATR mode during rollout
or Dock:LineCapture.guidanceMode == 5 count
)
)
syslog info "Resuming normal ranging with " + NumberOfPings~count + "pings "
+ LineCapture:Range~meter + "(mode " + Dock:LineCapture.guidanceMode~count + ")."
assign in sequence NumPings = NumberOfPings
assign in sequence LowPassFilterWinSize = TrackingNumFixesLowPassFilter
assign in sequence LowPassFilterWinOverlap = true
}
}
aggregate UpdatePingUpdateRate_Midcourse {
run when (
not ( TrackingPeriod == TrackingUpdatePeriodMidcourse )
and (
( LineCapture:Range > LineCapture:TerminalRange ) # do slow ping mode during rollout
or Dock:LineCapture.guidanceMode == 5 count
)
)
syslog info "Entering Midcourse tracking update period " + TrackingUpdatePeriodMidcourse~second
+ "sec at" + LineCapture:Range~meter + "(mode " + Dock:LineCapture.guidanceMode~count + ")."
assign in sequence TrackingPeriod = TrackingUpdatePeriodMidcourse
assign in sequence LowPassFilterWinSize = TrackingNumFixesLowPassFilter
assign in sequence LowPassFilterWinOverlap = true
}
aggregate UpdatePingUpdateRate_Terminal {
run when (
not ( TrackingPeriod == TrackingUpdatePeriodTerminal )
and (
( LineCapture:Range <= LineCapture:TerminalRange )
and not ( Dock:LineCapture.guidanceMode == 5 count )
and not ( LineCapture:GuidanceMode == 0 count )
) # don't enter fast ping mode during rollout or on dock
)
syslog info "Entering Terminal tracking update period " + TrackingUpdatePeriodTerminal~second
+ "sec at" + LineCapture:Range~meter + "(mode " + Dock:LineCapture.guidanceMode~count + ")."
assign in sequence TrackingPeriod = TrackingUpdatePeriodTerminal
assign in sequence LowPassFilterWinSize = TrackingNumFixesLowPassFilter
assign in sequence LowPassFilterWinOverlap = true
}
aggregate restartDHCP {
run when ( called )
syslog info "Restarting DHCP..."
behavior Guidance:Execute {
run in sequence
set command = "! /mnt/mmc/LRAUV/Tools/dhcp.sh"
}
}
# Start mission sequence.
call id="StartingMission" priorityHere=false refId="NeedComms"
syslog important "Restarting logs"
behavior Guidance:Execute {
run in sequence
set command = "restart logs"
}
aggregate InitialDive {
run in sequence
behavior Guidance:SetSpeed {
run in parallel
set speed = MidcourseSpeed
}
behavior Guidance:Mass {
run in parallel
set position = Control:VerticalControl.massDefault
}
behavior Guidance:Pitch {
run in sequence
timeout duration=ApproachDepthTimeout {
syslog important "Timed out trying to reach the target depth. Stopping mission at current depth of "
+ Universal:depth~meter
behavior Guidance:Execute {
run in sequence
set command = "stop"
}
}
set depth = MidcourseDepth
set depthRate = ApproachDepthRate
}
}
# I'd like to avoid a recursive call of Lap, so "retry" is implemented by incrementing the
# required repetition count (which has just been decremented by the lap attempt).
# But that repeat check is evaluated at the beginning of the aggregate, not the end.
# In order to be able to retry an incomplete docking attempt on the last lap
# we need to make it back up to the top of the Lap aggregate somehow.
# Solve this by quietly setting the repeat count to one more than actually desired.
# The DockSuccessfulCount check will break the Lap aggregate before it runs the extra
# lap, but starting the Lap aggregate will allow us to check the repeat on each run.
aggregate RepeatHack {
run in sequence
break if ( not RetryIncompleteDock )
assign in sequence RepeatArg = Repeat + 1 count
}
aggregate Lap {
run in sequence repeat=RepeatArg
break if (
( StayOnDock
and LastDockSuccessful
)
or (
RetryIncompleteDock
# Can't trust RepeatArg with retry, it's been incremented to allow re-evaluation of the count
and ( DockSuccessfulCount == Repeat )
)
)
assign in sequence Mode = -99 count
# Execute the LineCapture sequence.
call priorityHere=false refId="LineCapture"
assign in sequence Mode = LineCapture:GuidanceMode
syslog info "Mode" + Mode~count
call priorityHere=false refId="restartDHCP"
aggregate Docked {
run in sequence
break if ( Mode < 0 count )
syslog info "Waiting on dock for " + WaitOnDockTimeout~minute
# Add SetNav directives.
insert Insert/SetNavAcoustic.tl
assign in sequence TrackingPeriod = TrackingUpdatePeriodDocked
assign in sequence EndDockingSequence = StayOnDock
behavior Dock:Docked id="Docked" {
run in sequence
timeout duration=WaitOnDockTimeout
set slideSinkTime = SlideSinkDuration
set slideCloseTime = SlideCloseDuration
set slideRetries = SlideRetries
set slideAllowed = TrySlide
outputArg LastDockSuccessful = dockSuccessful
}
# If retrying incomplete attempts require total success to skip rollout, not just StayOnDock
aggregate setCompletion {
run in sequence
break if ( not RetryIncompleteDock )
assign in sequence EndDockingSequence = ( LastDockSuccessful
and StayOnDock
)
assign in sequence DockSuccessfulCount = DockSuccessfulCount + LastDockSuccessful
aggregate setRetry {
run in sequence
break if ( LastDockSuccessful )
syslog important "Retrying incomplete docking attempt"
# This lap failed and doesn't "count", so repeat one additional time
assign in sequence RepeatArg = RepeatArg + 1 count
}
}
aggregate Detach {
run in sequence
break if ( EndDockingSequence )
syslog important "Breaking off. Range: " + Universal:acoustic_contact_range~meter + "."
behavior Dock:Undock id="Undock" {
run in sequence
}
call priorityHere=false refId="restartDHCP"
}
aggregate Wpt1 {
run in sequence
break if (
isNaN ( Lat1 )
or isNaN ( Lon1 )
or ( EndDockingSequence )
)
syslog important "Driving to post-undock waypoint " + Lat1~degree + "," + Lon1~degree
behavior Guidance:Pitch {
run in parallel
set depth = MidcourseDepth
}
behavior Guidance:SetSpeed {
run in parallel
set speed = MidcourseSpeed
}
behavior Guidance:Waypoint {
run in sequence
set latitude = Lat1
set longitude = Lon1
set captureRadius = CaptureRadius
}
}
aggregate Drift {
"""
Drift before next docking attempt
"""
run in sequence
break if (
not isNaN ( Lat1 )
and not isNaN ( Lon1 )
or ( EndDockingSequence )
)
syslog important "No post-undock waypoint, drifting for "
+ Dock:LineCapture.rolloutTimeout~minute + " minutes"
behavior Guidance:Buoyancy {
run in parallel
set position = Control:VerticalControl.buoyancyNeutral
}
behavior Guidance:Pitch {
run in parallel
set massPosition = Control:VerticalControl.massDefault
set elevatorAngle = 0 degree
}
behavior Guidance:Point {
run in parallel
set rudderAngle = 0 degree
}
behavior Guidance:SetSpeed {
run in parallel
set speed = 0 meter_per_second
}
behavior Guidance:Wait {
run in sequence
set duration = Dock:LineCapture.rolloutTimeout
}
}
}
}
}
2025-07-05T15:08:43.113Z,1751728123.113 [CommandExec](IMPORTANT): Loaded ./Missions/Engineering/lineCaptureHoming.tl id=lineCaptureHoming
2025-07-05T15:09:07.779Z,1751728147.779 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250705T144438/Courier0033.lzma
2025-07-05T15:09:18.822Z,1751728158.822 [DataOverHttps](INFO): Moved sent file to Logs/20250705T144438/Courier0033.lzma.bak
2025-07-05T15:09:18.822Z,1751728158.822 [DataOverHttps](INFO): SBD MOMSN=25361541
2025-07-05T15:09:20.181Z,1751728160.181 [CommandExec](IMPORTANT): got command set lineCaptureHoming.StayOnDock 1 bool
2025-07-05T15:09:20.182Z,1751728160.182 [CommandExec](IMPORTANT): got command set lineCaptureHoming.DockingAltitude 7.8 meter
2025-07-05T15:09:20.183Z,1751728160.183 [CommandExec](IMPORTANT): got command set lineCaptureHoming.AltitudeDepthLimit 68 meter
2025-07-05T15:09:20.186Z,1751728160.186 [CommandExec](IMPORTANT): got command set lineCaptureHoming.MaxDepth 70 meter
2025-07-05T15:09:36.342Z,1751728176.342 [CommandExec](IMPORTANT): got command set lineCaptureHoming.CorrectForSoundSpeed 1 bool
2025-07-05T15:09:36.343Z,1751728176.343 [CommandExec](IMPORTANT): got command run
2025-07-05T15:09:36.345Z,1751728176.345 [CommandExec](IMPORTANT): Running
2025-07-05T15:09:36.642Z,1751728176.642 [DefaultWithUndock] Stopped
2025-07-05T15:09:36.642Z,1751728176.642 [DefaultWithUndock](DEBUG): Aggregate::uninitialize DefaultWithUndock
2025-07-05T15:09:36.642Z,1751728176.642 [DefaultWithUndock:SurfaceDefault] Stopped
2025-07-05T15:09:36.642Z,1751728176.642 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault
2025-07-05T15:09:36.642Z,1751728176.642 [DefaultWithUndock:SurfaceDefault:A.GoToSurface] Stopped
2025-07-05T15:09:36.642Z,1751728176.642 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-07-05T15:09:36.642Z,1751728176.642 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped
2025-07-05T15:09:36.642Z,1751728176.642 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn
2025-07-05T15:09:36.642Z,1751728176.642 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped
2025-07-05T15:09:36.642Z,1751728176.642 [MissionManager](IMPORTANT): Started mission lineCaptureHoming
2025-07-05T15:09:36.643Z,1751728176.643 [lineCaptureHoming] Running Loop=1
2025-07-05T15:09:36.643Z,1751728176.643 [lineCaptureHoming](DEBUG): Aggregate::initialize lineCaptureHoming
2025-07-05T15:09:36.643Z,1751728176.643 [lineCaptureHoming:Science] Running Loop=1
2025-07-05T15:09:36.643Z,1751728176.643 [lineCaptureHoming:Science](DEBUG): Aggregate::initialize lineCaptureHoming:Science
2025-07-05T15:09:36.643Z,1751728176.643 [lineCaptureHoming:Science:A] Running Loop=1
2025-07-05T15:09:36.643Z,1751728176.643 [lineCaptureHoming:Science:B] Running Loop=1
2025-07-05T15:09:36.643Z,1751728176.643 [lineCaptureHoming:Science:C] Running Loop=1
2025-07-05T15:09:36.643Z,1751728176.643 [lineCaptureHoming:Science:D] Running Loop=1
2025-07-05T15:09:36.643Z,1751728176.643 [lineCaptureHoming:Science:E] Running Loop=1
2025-07-05T15:09:36.643Z,1751728176.643 [lineCaptureHoming:Science:F] Running Loop=1
2025-07-05T15:09:36.660Z,1751728176.660 [lineCaptureHoming:Science:Read_Oil] Running Loop=1
2025-07-05T15:09:36.660Z,1751728176.660 [lineCaptureHoming:Science:PeakDetectChl] Running Loop=1
2025-07-05T15:09:36.660Z,1751728176.660 [lineCaptureHoming:Science:PeakDetectChl](DEBUG): Aggregate::initialize lineCaptureHoming:Science:PeakDetectChl
2025-07-05T15:09:36.661Z,1751728176.661 [lineCaptureHoming:Science:PeakDetectChl:A] Running Loop=1
2025-07-05T15:09:36.661Z,1751728176.661 [lineCaptureHoming:Science:EdgeDetectChl] Running Loop=1
2025-07-05T15:09:36.661Z,1751728176.661 [lineCaptureHoming:Science:EdgeDetectChl](DEBUG): Aggregate::initialize lineCaptureHoming:Science:EdgeDetectChl
2025-07-05T15:09:36.661Z,1751728176.661 [lineCaptureHoming:Science:EdgeDetectChl:A] Running Loop=1
2025-07-05T15:09:36.661Z,1751728176.661 [lineCaptureHoming:Science:HighestChlPeakReport] Running Loop=1
2025-07-05T15:09:36.661Z,1751728176.661 [lineCaptureHoming:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize lineCaptureHoming:Science:HighestChlPeakReport
2025-07-05T15:09:36.661Z,1751728176.661 [lineCaptureHoming:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2025-07-05T15:09:36.661Z,1751728176.661 [lineCaptureHoming:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2025-07-05T15:09:36.662Z,1751728176.662 [lineCaptureHoming:Science:PeakDetectNO3] Running Loop=1
2025-07-05T15:09:36.662Z,1751728176.662 [lineCaptureHoming:Science:PeakDetectNO3](DEBUG): Aggregate::initialize lineCaptureHoming:Science:PeakDetectNO3
2025-07-05T15:09:36.662Z,1751728176.662 [lineCaptureHoming:Science:PeakDetectNO3:A] Running Loop=1
2025-07-05T15:09:36.662Z,1751728176.662 [lineCaptureHoming:Science:PeakDetectOil] Running Loop=1
2025-07-05T15:09:36.662Z,1751728176.662 [lineCaptureHoming:Science:PeakDetectOil](DEBUG): Aggregate::initialize lineCaptureHoming:Science:PeakDetectOil
2025-07-05T15:09:36.662Z,1751728176.662 [lineCaptureHoming:Science:PeakDetectOil:A] Running Loop=1
2025-07-05T15:09:36.662Z,1751728176.662 [lineCaptureHoming:Science:HighestOilPeakReport] Running Loop=1
2025-07-05T15:09:36.662Z,1751728176.662 [lineCaptureHoming:Science:HighestOilPeakReport](DEBUG): Aggregate::initialize lineCaptureHoming:Science:HighestOilPeakReport
2025-07-05T15:09:36.663Z,1751728176.663 [lineCaptureHoming:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Running Loop=1
2025-07-05T15:09:36.663Z,1751728176.663 [lineCaptureHoming:Science:HighestOilPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2025-07-05T15:09:36.663Z,1751728176.663 [lineCaptureHoming:Science:PeakDetectFDOM] Running Loop=1
2025-07-05T15:09:36.663Z,1751728176.663 [lineCaptureHoming:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize lineCaptureHoming:Science:PeakDetectFDOM
2025-07-05T15:09:36.663Z,1751728176.663 [lineCaptureHoming:Science:PeakDetectFDOM:A] Running Loop=1
2025-07-05T15:09:36.663Z,1751728176.663 [lineCaptureHoming:Science:PeakDetectSalinity] Running Loop=1
2025-07-05T15:09:36.663Z,1751728176.663 [lineCaptureHoming:Science:PeakDetectSalinity](DEBUG): Aggregate::initialize lineCaptureHoming:Science:PeakDetectSalinity
2025-07-05T15:09:36.670Z,1751728176.670 [lineCaptureHoming:Science:PeakDetectSalinity:A] Running Loop=1
2025-07-05T15:09:36.670Z,1751728176.670 [lineCaptureHoming:Science:HighestSaltPeakReport] Running Loop=1
2025-07-05T15:09:36.671Z,1751728176.671 [lineCaptureHoming:Science:HighestSaltPeakReport](DEBUG): Aggregate::initialize lineCaptureHoming:Science:HighestSaltPeakReport
2025-07-05T15:09:36.671Z,1751728176.671 [lineCaptureHoming:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Running Loop=1
2025-07-05T15:09:36.671Z,1751728176.671 [lineCaptureHoming:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2025-07-05T15:09:36.671Z,1751728176.671 [lineCaptureHoming:Science:PeakDetectPlanktivoreLMavgROI] Running Loop=1
2025-07-05T15:09:36.671Z,1751728176.671 [lineCaptureHoming:Science:PeakDetectPlanktivoreLMavgROI](DEBUG): Aggregate::initialize lineCaptureHoming:Science:PeakDetectPlanktivoreLMavgROI
2025-07-05T15:09:36.676Z,1751728176.676 [lineCaptureHoming:Science:PeakDetectPlanktivoreLMavgROI:A] Running Loop=1
2025-07-05T15:09:36.676Z,1751728176.676 [lineCaptureHoming:Science:EdgeDetectPlanktivoreLMavgROI] Running Loop=1
2025-07-05T15:09:36.676Z,1751728176.676 [lineCaptureHoming:Science:EdgeDetectPlanktivoreLMavgROI](DEBUG): Aggregate::initialize lineCaptureHoming:Science:EdgeDetectPlanktivoreLMavgROI
2025-07-05T15:09:36.676Z,1751728176.676 [lineCaptureHoming:Science:EdgeDetectPlanktivoreLMavgROI:A] Running Loop=1
2025-07-05T15:09:36.676Z,1751728176.676 [lineCaptureHoming:Science:PeakDetectPlanktivoreHMavgROI] Running Loop=1
2025-07-05T15:09:36.676Z,1751728176.676 [lineCaptureHoming:Science:PeakDetectPlanktivoreHMavgROI](DEBUG): Aggregate::initialize lineCaptureHoming:Science:PeakDetectPlanktivoreHMavgROI
2025-07-05T15:09:36.676Z,1751728176.676 [lineCaptureHoming:Science:PeakDetectPlanktivoreHMavgROI:A] Running Loop=1
2025-07-05T15:09:36.677Z,1751728176.677 [lineCaptureHoming:Science:EdgeDetectPlanktivoreHMavgROI] Running Loop=1
2025-07-05T15:09:36.677Z,1751728176.677 [lineCaptureHoming:Science:EdgeDetectPlanktivoreHMavgROI](DEBUG): Aggregate::initialize lineCaptureHoming:Science:EdgeDetectPlanktivoreHMavgROI
2025-07-05T15:09:36.677Z,1751728176.677 [lineCaptureHoming:Science:EdgeDetectPlanktivoreHMavgROI:A] Running Loop=1
2025-07-05T15:09:36.677Z,1751728176.677 [lineCaptureHoming:Science:PeakDetectPlanktivoreDiatoms] Running Loop=1
2025-07-05T15:09:36.677Z,1751728176.677 [lineCaptureHoming:Science:PeakDetectPlanktivoreDiatoms](DEBUG): Aggregate::initialize lineCaptureHoming:Science:PeakDetectPlanktivoreDiatoms
2025-07-05T15:09:36.677Z,1751728176.677 [lineCaptureHoming:Science:PeakDetectPlanktivoreDiatoms:A] Running Loop=1
2025-07-05T15:09:36.677Z,1751728176.677 [lineCaptureHoming:Science:EdgeDetectPlanktivoreDiatoms] Running Loop=1
2025-07-05T15:09:36.677Z,1751728176.677 [lineCaptureHoming:Science:EdgeDetectPlanktivoreDiatoms](DEBUG): Aggregate::initialize lineCaptureHoming:Science:EdgeDetectPlanktivoreDiatoms
2025-07-05T15:09:36.677Z,1751728176.677 [lineCaptureHoming:Science:EdgeDetectPlanktivoreDiatoms:A] Running Loop=1
2025-07-05T15:09:36.677Z,1751728176.677 [lineCaptureHoming:Science:PeakDetectPlanktivoreDinoflagellates] Running Loop=1
2025-07-05T15:09:36.678Z,1751728176.678 [lineCaptureHoming:Science:PeakDetectPlanktivoreDinoflagellates](DEBUG): Aggregate::initialize lineCaptureHoming:Science:PeakDetectPlanktivoreDinoflagellates
2025-07-05T15:09:36.678Z,1751728176.678 [lineCaptureHoming:Science:PeakDetectPlanktivoreDinoflagellates:A] Running Loop=1
2025-07-05T15:09:36.678Z,1751728176.678 [lineCaptureHoming:Science:EdgeDetectPlanktivoreDinoflagellates] Running Loop=1
2025-07-05T15:09:36.678Z,1751728176.678 [lineCaptureHoming:Science:EdgeDetectPlanktivoreDinoflagellates](DEBUG): Aggregate::initialize lineCaptureHoming:Science:EdgeDetectPlanktivoreDinoflagellates
2025-07-05T15:09:36.678Z,1751728176.678 [lineCaptureHoming:Science:EdgeDetectPlanktivoreDinoflagellates:A] Running Loop=1
2025-07-05T15:09:36.678Z,1751728176.678 [lineCaptureHoming:Science:ChlThreshTrigger] Running Loop=1
2025-07-05T15:09:36.678Z,1751728176.678 [lineCaptureHoming:Science:ChlThreshTrigger](DEBUG): Aggregate::initialize lineCaptureHoming:Science:ChlThreshTrigger
2025-07-05T15:09:36.678Z,1751728176.678 [lineCaptureHoming:Science:ChlThreshTrigger:A.ValueDetect] Running Loop=1
2025-07-05T15:09:36.678Z,1751728176.678 [lineCaptureHoming:Science:ChlThreshTrigger:A.ValueDetect](INFO): Initialize.
2025-07-05T15:09:36.679Z,1751728176.679 [lineCaptureHoming:Science:OceanCurrent] Running Loop=1
2025-07-05T15:09:36.679Z,1751728176.679 [lineCaptureHoming:Science:OceanCurrent](DEBUG): Aggregate::initialize lineCaptureHoming:Science:OceanCurrent
2025-07-05T15:09:36.679Z,1751728176.679 [lineCaptureHoming:Science:OceanCurrent:A.] Running Loop=1
2025-07-05T15:09:36.679Z,1751728176.679 [lineCaptureHoming:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator.
2025-07-05T15:09:36.679Z,1751728176.679 [lineCaptureHoming:StandardEnvelopes] Running Loop=1
2025-07-05T15:09:36.679Z,1751728176.679 [lineCaptureHoming:StandardEnvelopes](DEBUG): Aggregate::initialize lineCaptureHoming:StandardEnvelopes
2025-07-05T15:09:36.679Z,1751728176.679 [lineCaptureHoming:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-07-05T15:09:36.679Z,1751728176.679 [lineCaptureHoming:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2025-07-05T15:09:36.684Z,1751728176.684 [lineCaptureHoming:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-07-05T15:09:36.684Z,1751728176.684 [lineCaptureHoming:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2025-07-05T15:09:36.684Z,1751728176.684 [lineCaptureHoming:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-07-05T15:09:36.684Z,1751728176.684 [lineCaptureHoming:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2025-07-05T15:09:36.684Z,1751728176.684 [lineCaptureHoming:MicromodemComms] Running Loop=1
2025-07-05T15:09:36.684Z,1751728176.684 [lineCaptureHoming:MicromodemComms](DEBUG): Aggregate::initialize lineCaptureHoming:MicromodemComms
2025-07-05T15:09:36.685Z,1751728176.685 [lineCaptureHoming:MicromodemComms:CheckIn] Running Loop=1
2025-07-05T15:09:36.685Z,1751728176.685 [lineCaptureHoming:MicromodemComms:CheckIn](DEBUG): Aggregate::initialize lineCaptureHoming:MicromodemComms:CheckIn
2025-07-05T15:09:36.685Z,1751728176.685 [lineCaptureHoming:MicromodemComms:CheckIn:A] Running Loop=1
2025-07-05T15:09:36.685Z,1751728176.685 [lineCaptureHoming:J] Running Loop=1
2025-07-05T15:09:36.685Z,1751728176.685 [lineCaptureHoming:TrackAC.] Running Loop=1
2025-07-05T15:09:36.685Z,1751728176.685 [lineCaptureHoming:TrackAC.](INFO): Initializing TrackAcousticContact.
2025-07-05T15:09:36.685Z,1751728176.685 [lineCaptureHoming:UpdateNumPings_NotTerminal] Running Loop=1
2025-07-05T15:09:36.685Z,1751728176.685 [lineCaptureHoming:UpdateNumPings_NotTerminal](DEBUG): Aggregate::initialize lineCaptureHoming:UpdateNumPings_NotTerminal
2025-07-05T15:09:36.685Z,1751728176.685 [lineCaptureHoming:UpdateNumPings_NotTerminal:A] Running Loop=1
2025-07-05T15:09:36.686Z,1751728176.686 [lineCaptureHoming:UpdateNumPings_Terminal] Running Loop=1
2025-07-05T15:09:36.686Z,1751728176.686 [lineCaptureHoming:UpdateNumPings_Terminal](DEBUG): Aggregate::initialize lineCaptureHoming:UpdateNumPings_Terminal
2025-07-05T15:09:36.686Z,1751728176.686 [lineCaptureHoming:C] Running Loop=1
2025-07-05T15:09:36.692Z,1751728176.692 [lineCaptureHoming:UpdateNumPings_Terminal] Stopped
2025-07-05T15:09:36.692Z,1751728176.692 [lineCaptureHoming:UpdateNumPings_Terminal](DEBUG): Aggregate::uninitialize lineCaptureHoming:UpdateNumPings_Terminal
2025-07-05T15:09:36.692Z,1751728176.692 [lineCaptureHoming:UpdateNumPings_NotTerminal] Stopped
2025-07-05T15:09:36.692Z,1751728176.692 [lineCaptureHoming:UpdateNumPings_NotTerminal](DEBUG): Aggregate::uninitialize lineCaptureHoming:UpdateNumPings_NotTerminal
2025-07-05T15:09:36.692Z,1751728176.692 [lineCaptureHoming:UpdateNumPings_NotTerminal:A] Stopped
2025-07-05T15:09:36.692Z,1751728176.692 [lineCaptureHoming:TrackAC.] Running Loop=1
2025-07-05T15:09:36.693Z,1751728176.693 [lineCaptureHoming:TrackAC.](ERROR): Failed to read sound speed
2025-07-05T15:09:36.693Z,1751728176.693 [lineCaptureHoming:J] Stopped
2025-07-05T15:09:36.693Z,1751728176.693 [lineCaptureHoming:MicromodemComms] Running Loop=1
2025-07-05T15:09:36.694Z,1751728176.694 [lineCaptureHoming:MicromodemComms:CheckIn:A](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-07-05T15:09:36.694Z,1751728176.694 [lineCaptureHoming:C] Stopped
2025-07-05T15:09:36.695Z,1751728176.695 [lineCaptureHoming:D] Running Loop=1
2025-07-05T15:09:36.695Z,1751728176.695 [lineCaptureHoming:StandardEnvelopes] Running Loop=1
2025-07-05T15:09:36.695Z,1751728176.695 [lineCaptureHoming:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-07-05T15:09:36.695Z,1751728176.695 [lineCaptureHoming:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-07-05T15:09:36.695Z,1751728176.695 [lineCaptureHoming:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-07-05T15:09:36.700Z,1751728176.700 [lineCaptureHoming:Science] Running Loop=1
2025-07-05T15:09:36.701Z,1751728176.701 [lineCaptureHoming:Science:ChlThreshTrigger] Stopped
2025-07-05T15:09:36.702Z,1751728176.702 [lineCaptureHoming:Science:ChlThreshTrigger](DEBUG): Aggregate::uninitialize lineCaptureHoming:Science:ChlThreshTrigger
2025-07-05T15:09:36.702Z,1751728176.702 [lineCaptureHoming:Science:ChlThreshTrigger:A.ValueDetect] Stopped
2025-07-05T15:09:36.702Z,1751728176.702 [lineCaptureHoming:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Uninitialize.
2025-07-05T15:09:36.702Z,1751728176.702 [lineCaptureHoming:Science:EdgeDetectPlanktivoreDinoflagellates] Stopped
2025-07-05T15:09:36.702Z,1751728176.702 [lineCaptureHoming:Science:EdgeDetectPlanktivoreDinoflagellates](DEBUG): Aggregate::uninitialize lineCaptureHoming:Science:EdgeDetectPlanktivoreDinoflagellates
2025-07-05T15:09:36.702Z,1751728176.702 [lineCaptureHoming:Science:EdgeDetectPlanktivoreDinoflagellates:A] Stopped
2025-07-05T15:09:36.702Z,1751728176.702 [lineCaptureHoming:Science:PeakDetectPlanktivoreDinoflagellates] Stopped
2025-07-05T15:09:36.702Z,1751728176.702 [lineCaptureHoming:Science:PeakDetectPlanktivoreDinoflagellates](DEBUG): Aggregate::uninitialize lineCaptureHoming:Science:PeakDetectPlanktivoreDinoflagellates
2025-07-05T15:09:36.702Z,1751728176.702 [lineCaptureHoming:Science:PeakDetectPlanktivoreDinoflagellates:A] Stopped
2025-07-05T15:09:36.702Z,1751728176.702 [lineCaptureHoming:Science:EdgeDetectPlanktivoreDiatoms] Stopped
2025-07-05T15:09:36.702Z,1751728176.702 [lineCaptureHoming:Science:EdgeDetectPlanktivoreDiatoms](DEBUG): Aggregate::uninitialize lineCaptureHoming:Science:EdgeDetectPlanktivoreDiatoms
2025-07-05T15:09:36.703Z,1751728176.703 [lineCaptureHoming:Science:EdgeDetectPlanktivoreDiatoms:A] Stopped
2025-07-05T15:09:36.703Z,1751728176.703 [lineCaptureHoming:Science:PeakDetectPlanktivoreDiatoms] Stopped
2025-07-05T15:09:36.703Z,1751728176.703 [lineCaptureHoming:Science:PeakDetectPlanktivoreDiatoms](DEBUG): Aggregate::uninitialize lineCaptureHoming:Science:PeakDetectPlanktivoreDiatoms
2025-07-05T15:09:36.703Z,1751728176.703 [lineCaptureHoming:Science:PeakDetectPlanktivoreDiatoms:A] Stopped
2025-07-05T15:09:36.703Z,1751728176.703 [lineCaptureHoming:Science:EdgeDetectPlanktivoreHMavgROI] Stopped
2025-07-05T15:09:36.703Z,1751728176.703 [lineCaptureHoming:Science:EdgeDetectPlanktivoreHMavgROI](DEBUG): Aggregate::uninitialize lineCaptureHoming:Science:EdgeDetectPlanktivoreHMavgROI
2025-07-05T15:09:36.703Z,1751728176.703 [lineCaptureHoming:Science:EdgeDetectPlanktivoreHMavgROI:A] Stopped
2025-07-05T15:09:36.703Z,1751728176.703 [lineCaptureHoming:Science:PeakDetectPlanktivoreHMavgROI] Stopped
2025-07-05T15:09:36.703Z,1751728176.703 [lineCaptureHoming:Science:PeakDetectPlanktivoreHMavgROI](DEBUG): Aggregate::uninitialize lineCaptureHoming:Science:PeakDetectPlanktivoreHMavgROI
2025-07-05T15:09:36.703Z,1751728176.703 [lineCaptureHoming:Science:PeakDetectPlanktivoreHMavgROI:A] Stopped
2025-07-05T15:09:36.703Z,1751728176.703 [lineCaptureHoming:Science:EdgeDetectPlanktivoreLMavgROI] Stopped
2025-07-05T15:09:36.703Z,1751728176.703 [lineCaptureHoming:Science:EdgeDetectPlanktivoreLMavgROI](DEBUG): Aggregate::uninitialize lineCaptureHoming:Science:EdgeDetectPlanktivoreLMavgROI
2025-07-05T15:09:36.703Z,1751728176.703 [lineCaptureHoming:Science:EdgeDetectPlanktivoreLMavgROI:A] Stopped
2025-07-05T15:09:36.704Z,1751728176.704 [lineCaptureHoming:Science:PeakDetectPlanktivoreLMavgROI] Stopped
2025-07-05T15:09:36.709Z,1751728176.709 [lineCaptureHoming:Science:PeakDetectPlanktivoreLMavgROI](DEBUG): Aggregate::uninitialize lineCaptureHoming:Science:PeakDetectPlanktivoreLMavgROI
2025-07-05T15:09:36.709Z,1751728176.709 [lineCaptureHoming:Science:PeakDetectPlanktivoreLMavgROI:A] Stopped
2025-07-05T15:09:36.709Z,1751728176.709 [lineCaptureHoming:Science:HighestSaltPeakReport] Stopped
2025-07-05T15:09:36.709Z,1751728176.709 [lineCaptureHoming:Science:HighestSaltPeakReport](DEBUG): Aggregate::uninitialize lineCaptureHoming:Science:HighestSaltPeakReport
2025-07-05T15:09:36.709Z,1751728176.709 [lineCaptureHoming:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Stopped
2025-07-05T15:09:36.709Z,1751728176.709 [lineCaptureHoming:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2025-07-05T15:09:36.709Z,1751728176.709 [lineCaptureHoming:Science:PeakDetectSalinity] Stopped
2025-07-05T15:09:36.709Z,1751728176.709 [lineCaptureHoming:Science:PeakDetectSalinity](DEBUG): Aggregate::uninitialize lineCaptureHoming:Science:PeakDetectSalinity
2025-07-05T15:09:36.709Z,1751728176.709 [lineCaptureHoming:Science:PeakDetectSalinity:A] Stopped
2025-07-05T15:09:36.709Z,1751728176.709 [lineCaptureHoming:Science:PeakDetectFDOM] Stopped
2025-07-05T15:09:36.709Z,1751728176.709 [lineCaptureHoming:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize lineCaptureHoming:Science:PeakDetectFDOM
2025-07-05T15:09:36.710Z,1751728176.710 [lineCaptureHoming:Science:PeakDetectFDOM:A] Stopped
2025-07-05T15:09:36.710Z,1751728176.710 [lineCaptureHoming:Science:HighestOilPeakReport] Stopped
2025-07-05T15:09:36.710Z,1751728176.710 [lineCaptureHoming:Science:HighestOilPeakReport](DEBUG): Aggregate::uninitialize lineCaptureHoming:Science:HighestOilPeakReport
2025-07-05T15:09:36.710Z,1751728176.710 [lineCaptureHoming:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Stopped
2025-07-05T15:09:36.710Z,1751728176.710 [lineCaptureHoming:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2025-07-05T15:09:36.710Z,1751728176.710 [lineCaptureHoming:Science:PeakDetectOil] Stopped
2025-07-05T15:09:36.710Z,1751728176.710 [lineCaptureHoming:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize lineCaptureHoming:Science:PeakDetectOil
2025-07-05T15:09:36.710Z,1751728176.710 [lineCaptureHoming:Science:PeakDetectOil:A] Stopped
2025-07-05T15:09:36.710Z,1751728176.710 [lineCaptureHoming:Science:PeakDetectNO3] Stopped
2025-07-05T15:09:36.710Z,1751728176.710 [lineCaptureHoming:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize lineCaptureHoming:Science:PeakDetectNO3
2025-07-05T15:09:36.710Z,1751728176.710 [lineCaptureHoming:Science:PeakDetectNO3:A] Stopped
2025-07-05T15:09:36.710Z,1751728176.710 [lineCaptureHoming:Science:HighestChlPeakReport] Stopped
2025-07-05T15:09:36.710Z,1751728176.710 [lineCaptureHoming:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize lineCaptureHoming:Science:HighestChlPeakReport
2025-07-05T15:09:36.711Z,1751728176.711 [lineCaptureHoming:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2025-07-05T15:09:36.711Z,1751728176.711 [lineCaptureHoming:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2025-07-05T15:09:36.711Z,1751728176.711 [lineCaptureHoming:Science:EdgeDetectChl] Stopped
2025-07-05T15:09:36.711Z,1751728176.711 [lineCaptureHoming:Science:EdgeDetectChl](DEBUG): Aggregate::uninitialize lineCaptureHoming:Science:EdgeDetectChl
2025-07-05T15:09:36.711Z,1751728176.711 [lineCaptureHoming:Science:EdgeDetectChl:A] Stopped
2025-07-05T15:09:36.711Z,1751728176.711 [lineCaptureHoming:Science:PeakDetectChl] Stopped
2025-07-05T15:09:36.711Z,1751728176.711 [lineCaptureHoming:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize lineCaptureHoming:Science:PeakDetectChl
2025-07-05T15:09:36.711Z,1751728176.711 [lineCaptureHoming:Science:PeakDetectChl:A] Stopped
2025-07-05T15:09:36.711Z,1751728176.711 [lineCaptureHoming:Science:OceanCurrent:A.] Running Loop=1
2025-07-05T15:09:36.713Z,1751728176.713 [lineCaptureHoming:Science:Read_Oil] Stopped
2025-07-05T15:09:36.713Z,1751728176.713 [lineCaptureHoming:Science:F] Running Loop=1
2025-07-05T15:09:36.713Z,1751728176.713 [lineCaptureHoming:Science:F](DEBUG): Initialize ReadDataComponent to sense WetLabsUBAT.average_bioluminescence
2025-07-05T15:09:36.713Z,1751728176.713 [lineCaptureHoming:Science:E] Running Loop=1
2025-07-05T15:09:36.714Z,1751728176.714 [lineCaptureHoming:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2025-07-05T15:09:36.714Z,1751728176.714 [lineCaptureHoming:Science:D] Stopped
2025-07-05T15:09:36.714Z,1751728176.714 [lineCaptureHoming:Science:C] Running Loop=1
2025-07-05T15:09:36.714Z,1751728176.714 [lineCaptureHoming:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2025-07-05T15:09:36.715Z,1751728176.715 [lineCaptureHoming:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2025-07-05T15:09:36.715Z,1751728176.715 [lineCaptureHoming:Science:B] Running Loop=1
2025-07-05T15:09:36.715Z,1751728176.715 [lineCaptureHoming:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2025-07-05T15:09:36.720Z,1751728176.720 [lineCaptureHoming:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2025-07-05T15:09:36.720Z,1751728176.720 [lineCaptureHoming:Science:A] Stopped
2025-07-05T15:09:37.097Z,1751728177.097 [lineCaptureHoming:MicromodemComms] Stopped
2025-07-05T15:09:37.097Z,1751728177.097 [lineCaptureHoming:MicromodemComms](DEBUG): Aggregate::uninitialize lineCaptureHoming:MicromodemComms
2025-07-05T15:09:37.097Z,1751728177.097 [lineCaptureHoming:MicromodemComms:CheckIn] Stopped
2025-07-05T15:09:37.097Z,1751728177.097 [lineCaptureHoming:MicromodemComms:CheckIn](DEBUG): Aggregate::uninitialize lineCaptureHoming:MicromodemComms:CheckIn
2025-07-05T15:09:37.097Z,1751728177.097 [lineCaptureHoming:MicromodemComms:CheckIn:A] Stopped
2025-07-05T15:09:37.098Z,1751728177.098 [lineCaptureHoming:D] Stopped
2025-07-05T15:09:37.098Z,1751728177.098 [lineCaptureHoming:E] Running Loop=1
2025-07-05T15:09:37.465Z,1751728177.465 [lineCaptureHoming:E] Stopped
2025-07-05T15:09:37.465Z,1751728177.465 [lineCaptureHoming:G] Running Loop=1
2025-07-05T15:09:37.481Z,1751728177.481 [WetLabsBB2FL](INFO): Powering up
2025-07-05T15:09:37.868Z,1751728177.868 [lineCaptureHoming:G] Stopped
2025-07-05T15:09:37.868Z,1751728177.868 [lineCaptureHoming:H] Running Loop=1
2025-07-05T15:09:38.259Z,1751728178.259 [lineCaptureHoming:H] Stopped
2025-07-05T15:09:38.259Z,1751728178.259 [lineCaptureHoming:L] Running Loop=1
2025-07-05T15:09:38.667Z,1751728178.667 [lineCaptureHoming:L] Stopped
2025-07-05T15:09:38.668Z,1751728178.668 [lineCaptureHoming:M] Running Loop=1
2025-07-05T15:09:39.090Z,1751728179.090 [lineCaptureHoming:M] Stopped
2025-07-05T15:09:39.090Z,1751728179.090 [lineCaptureHoming:N] Running Loop=1
2025-07-05T15:09:39.478Z,1751728179.478 [lineCaptureHoming:N] Stopped
2025-07-05T15:09:39.479Z,1751728179.479 [lineCaptureHoming:O] Running Loop=1
2025-07-05T15:09:39.870Z,1751728179.870 [lineCaptureHoming:O] Stopped
2025-07-05T15:09:39.871Z,1751728179.871 [lineCaptureHoming:P] Running Loop=1
2025-07-05T15:09:40.113Z,1751728180.113 [CTD_Seabird](ERROR): Failed to parse device response:
2025-07-05T15:09:40.282Z,1751728180.282 [lineCaptureHoming:P] Stopped
2025-07-05T15:09:40.282Z,1751728180.282 [lineCaptureHoming:Q] Running Loop=1
2025-07-05T15:09:40.681Z,1751728180.681 [lineCaptureHoming:Q] Stopped
2025-07-05T15:09:40.681Z,1751728180.681 [lineCaptureHoming:U] Running Loop=1
2025-07-05T15:09:41.117Z,1751728181.117 [lineCaptureHoming:U] Stopped
2025-07-05T15:09:41.118Z,1751728181.118 [lineCaptureHoming:StartingMission] Running Loop=1
2025-07-05T15:09:41.118Z,1751728181.118 [lineCaptureHoming:StartingMission](DEBUG): Aggregate::initialize lineCaptureHoming:StartingMission
2025-07-05T15:09:41.489Z,1751728181.489 [lineCaptureHoming:NeedComms] Running Loop=1
2025-07-05T15:09:41.489Z,1751728181.489 [lineCaptureHoming:NeedComms](DEBUG): Aggregate::initialize lineCaptureHoming:NeedComms
2025-07-05T15:09:41.489Z,1751728181.489 [lineCaptureHoming:NeedComms:B.GoToSurface] Running Loop=1
2025-07-05T15:09:41.490Z,1751728181.490 [lineCaptureHoming:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-07-05T15:09:41.490Z,1751728181.490 [lineCaptureHoming:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-07-05T15:09:41.490Z,1751728181.490 [lineCaptureHoming:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-07-05T15:09:41.491Z,1751728181.491 [lineCaptureHoming:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-07-05T15:09:41.491Z,1751728181.491 [lineCaptureHoming:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-07-05T15:09:41.491Z,1751728181.491 [lineCaptureHoming:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-07-05T15:09:41.508Z,1751728181.508 [lineCaptureHoming:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-07-05T15:09:41.508Z,1751728181.508 [lineCaptureHoming:NeedComms:A] Running Loop=1
2025-07-05T15:09:41.510Z,1751728181.510 [lineCaptureHoming:NeedComms:A](INFO): last time_fix was: 1751727243.000000 second since 1970/01/01T00:00:00Z
2025-07-05T15:09:41.510Z,1751728181.510 [lineCaptureHoming:NeedComms:A] Stopped
2025-07-05T15:09:41.893Z,1751728181.893 [lineCaptureHoming:NeedComms:C] Running Loop=1
2025-07-05T15:09:42.319Z,1751728182.319 [lineCaptureHoming:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-07-05T15:09:42.686Z,1751728182.686 [NAL9602](INFO): Powering up
2025-07-05T15:09:53.594Z,1751728193.594 [NAL9602](INFO): NAL9602 initialized
2025-07-05T15:09:54.526Z,1751728194.526 [DataOverHttps](INFO): Sending 365 bytes from file Logs/20250705T144438/Express0010.lzma
2025-07-05T15:09:54.815Z,1751728194.815 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,150613.00,A,3654.40544,N,12206.90641,W,0.622,202.27,050725,,,A*72
2025-07-05T15:09:54.818Z,1751728194.818 [NAL9602](INFO): GPS fix at 20250705T150613: (36.906757, -122.115107)
2025-07-05T15:09:54.866Z,1751728194.866 [lineCaptureHoming:NeedComms:C] Stopped
2025-07-05T15:09:54.867Z,1751728194.867 [lineCaptureHoming:NeedComms:D] Running Loop=1
2025-07-05T15:09:55.267Z,1751728195.267 [lineCaptureHoming:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-07-05T15:10:05.567Z,1751728205.567 [DataOverHttps](INFO): Moved sent file to Logs/20250705T144438/Express0010.lzma.bak
2025-07-05T15:10:05.567Z,1751728205.567 [DataOverHttps](INFO): SBD MOMSN=25361543
2025-07-05T15:10:13.045Z,1751728213.045 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 0.00, 699.65, 675.10, 603.75
2025-07-05T15:10:23.174Z,1751728223.174 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,25070507113276,35.0, +13.3, 0.0,1501
2025-07-05T15:10:32.945Z,1751728232.945 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,25070507114276,35.0, +0.0,1501.4, 0
2025-07-05T15:10:36.999Z,1751728236.999 [lineCaptureHoming:TrackAC.](INFO): *** querying acoustic contact ***
2025-07-05T15:10:37.237Z,1751728237.237 [DAT](DEBUG): Acoustic response timeout
2025-07-05T15:10:37.237Z,1751728237.237 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-07-05T15:10:37.489Z,1751728237.489 [DAT](INFO): DAT read:
2025-07-05T15:10:37.489Z,1751728237.489 [DAT](INFO): DAT read: Tx time:15:10:37.6346
2025-07-05T15:10:37.490Z,1751728237.490 [DAT](INFO): Ping request sent.
2025-07-05T15:10:37.490Z,1751728237.490 [DAT](INFO): transmitted an acoustic signal
2025-07-05T15:10:37.490Z,1751728237.490 [DAT](INFO): publishing transmit ping time
2025-07-05T15:10:37.491Z,1751728237.491 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000742
2025-07-05T15:10:37.741Z,1751728237.741 [DAT](INFO): DAT read: TxSync time:15:10:37.6338
2025-07-05T15:10:37.742Z,1751728237.742 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251923
2025-07-05T15:10:37.993Z,1751728237.993 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502971
2025-07-05T15:10:38.007Z,1751728238.007 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250705T144438/Courier0036.lzma
2025-07-05T15:10:38.248Z,1751728238.248 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.758347
2025-07-05T15:10:38.501Z,1751728238.501 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.011068
2025-07-05T15:10:38.753Z,1751728238.753 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.262998
2025-07-05T15:10:39.005Z,1751728239.005 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.515123
2025-07-05T15:10:39.257Z,1751728239.257 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.767097
2025-07-05T15:10:39.509Z,1751728239.509 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.018940
2025-07-05T15:10:39.761Z,1751728239.761 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.271105
2025-07-05T15:10:40.013Z,1751728240.013 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.522921
2025-07-05T15:10:40.265Z,1751728240.265 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.775029
2025-07-05T15:10:40.517Z,1751728240.517 [DAT](INFO): DAT read: Rx Time:15:10:40.2182
2025-07-05T15:10:40.518Z,1751728240.518 [DAT](INFO): Rx dataTimestamp_ set to:1751728240.517613
2025-07-05T15:10:40.519Z,1751728240.519 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.029421
2025-07-05T15:10:40.771Z,1751728240.771 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.281198
2025-07-05T15:10:41.026Z,1751728241.026 [DAT](INFO): DAT read: Range 10 to 50 : 134.5 m (Round-trip 179.4 ms) speed -0.2 m/s
2025-07-05T15:10:41.027Z,1751728241.027 [DAT](ERROR): #Rx 1: Read range message, but no direction.
2025-07-05T15:10:42.968Z,1751728242.968 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 0.00, 622.52, 0.00, 699.27
2025-07-05T15:10:49.050Z,1751728249.050 [DataOverHttps](INFO): Moved sent file to Logs/20250705T144438/Courier0036.lzma.bak
2025-07-05T15:10:49.050Z,1751728249.050 [DataOverHttps](INFO): SBD MOMSN=25361550
2025-07-05T15:10:56.701Z,1751728256.701 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-07-05T15:11:03.166Z,1751728263.166 [RDI_Pathfinder](ERROR): only read 1 of 4 data items for water velocity. Device response is::WS, -35,A
2025-07-05T15:11:17.940Z,1751728277.940 [DataOverHttps](INFO): Sending 291 bytes from file Logs/20250705T144438/Express0013.lzma
2025-07-05T15:11:27.398Z,1751728287.398 [NAL9602](INFO): Powering down
2025-07-05T15:11:28.983Z,1751728288.983 [DataOverHttps](INFO): Moved sent file to Logs/20250705T144438/Express0013.lzma.bak
2025-07-05T15:11:28.983Z,1751728288.983 [DataOverHttps](INFO): SBD MOMSN=25361553
2025-07-05T15:11:33.069Z,1751728293.069 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for bottom velocity. Device response is::BS, +167, +13, +151 +13, +151,A
2025-07-05T15:11:37.165Z,1751728297.165 [lineCaptureHoming:TrackAC.](INFO): *** querying acoustic contact ***
2025-07-05T15:11:37.249Z,1751728297.249 [DAT](DEBUG): Acoustic response timeout
2025-07-05T15:11:37.249Z,1751728297.249 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-07-05T15:11:37.501Z,1751728297.501 [DAT](INFO): DAT read: user:374>
2025-07-05T15:11:37.501Z,1751728297.501 [DAT](INFO): DAT read: Tx time:15:11:37.6351
2025-07-05T15:11:37.502Z,1751728297.502 [DAT](INFO): Ping request sent.
2025-07-05T15:11:37.502Z,1751728297.502 [DAT](INFO): transmitted an acoustic signal
2025-07-05T15:11:37.502Z,1751728297.502 [DAT](INFO): publishing transmit ping time
2025-07-05T15:11:37.503Z,1751728297.503 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000742
2025-07-05T15:11:37.753Z,1751728297.753 [DAT](INFO): DAT read: TxSync time:15:11:37.6343
2025-07-05T15:11:37.754Z,1751728297.754 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251922
2025-07-05T15:11:38.005Z,1751728298.005 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502919
2025-07-05T15:11:38.257Z,1751728298.257 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754985
2025-07-05T15:11:38.509Z,1751728298.509 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006901
2025-07-05T15:11:38.761Z,1751728298.761 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.259032
2025-07-05T15:11:39.013Z,1751728299.013 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510969
2025-07-05T15:11:39.265Z,1751728299.265 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762859
2025-07-05T15:11:39.517Z,1751728299.517 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014889
2025-07-05T15:11:39.769Z,1751728299.769 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266977
2025-07-05T15:11:40.021Z,1751728300.021 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518917
2025-07-05T15:11:40.273Z,1751728300.273 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.771183
2025-07-05T15:11:40.525Z,1751728300.525 [DAT](INFO): DAT read: Rx Time:15:11:40.2214
2025-07-05T15:11:40.525Z,1751728300.525 [DAT](INFO): Rx dataTimestamp_ set to:1751728300.525122
2025-07-05T15:11:40.526Z,1751728300.526 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.024077
2025-07-05T15:11:40.777Z,1751728300.777 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274907
2025-07-05T15:11:41.030Z,1751728301.030 [DAT](INFO): DAT read: Range 10 to 50 : 136.6 m (Round-trip 182.2 ms) speed 0.1 m/s
2025-07-05T15:11:41.030Z,1751728301.030 [DAT](ERROR): #Rx 1: Read range message, but no direction.
2025-07-05T15:11:57.992Z,1751728317.992 [DataOverHttps](INFO): Sending 277 bytes from file Logs/20250705T144438/Express0016.lzma
2025-07-05T15:12:09.038Z,1751728329.038 [DataOverHttps](INFO): Moved sent file to Logs/20250705T144438/Express0016.lzma.bak
2025-07-05T15:12:09.038Z,1751728329.038 [DataOverHttps](INFO): SBD MOMSN=25361560
2025-07-05T15:12:37.364Z,1751728357.364 [lineCaptureHoming:TrackAC.](INFO): *** querying acoustic contact ***
2025-07-05T15:12:37.593Z,1751728357.593 [DAT](DEBUG): Acoustic response timeout
2025-07-05T15:12:37.593Z,1751728357.593 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-07-05T15:12:37.850Z,1751728357.850 [DAT](INFO): DAT read: user:375>
2025-07-05T15:12:37.851Z,1751728357.851 [DAT](INFO): DAT read: Tx time:15:12:37.9856
2025-07-05T15:12:37.851Z,1751728357.851 [DAT](INFO): Ping request sent.
2025-07-05T15:12:37.851Z,1751728357.851 [DAT](INFO): transmitted an acoustic signal
2025-07-05T15:12:37.851Z,1751728357.851 [DAT](INFO): publishing transmit ping time
2025-07-05T15:12:37.852Z,1751728357.852 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000868
2025-07-05T15:12:38.097Z,1751728358.097 [DAT](INFO): DAT read: TxSync time:15:12:37.9848
2025-07-05T15:12:38.098Z,1751728358.098 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.246639
2025-07-05T15:12:38.349Z,1751728358.349 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.497639
2025-07-05T15:12:38.601Z,1751728358.601 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.749504
2025-07-05T15:12:38.857Z,1751728358.857 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.004173
2025-07-05T15:12:39.105Z,1751728359.105 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.253698
2025-07-05T15:12:39.357Z,1751728359.357 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.505666
2025-07-05T15:12:39.609Z,1751728359.609 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.757649
2025-07-05T15:12:39.861Z,1751728359.861 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.009565
2025-07-05T15:12:40.113Z,1751728360.113 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.261542
2025-07-05T15:12:40.365Z,1751728360.365 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.513663
2025-07-05T15:12:40.617Z,1751728360.617 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.765650
2025-07-05T15:12:40.887Z,1751728360.887 [DAT](INFO): DAT read: Rx Time:15:12:40.5744
2025-07-05T15:12:40.890Z,1751728360.890 [DAT](INFO): Rx dataTimestamp_ set to:1751728360.888492
2025-07-05T15:12:40.891Z,1751728360.891 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.039500
2025-07-05T15:12:41.121Z,1751728361.121 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.269668
2025-07-05T15:12:41.378Z,1751728361.378 [DAT](INFO): DAT read: 15:12:40.5744 LVL= 4976, 8625, 6498, 7251, AGC= 72, IDX= 465,-0.08, 1.828, 2.123,-3.002, 2.457, PHS=-0.541,-0.286, 0.821, RAW= 220.1, 0.1, CAL= 220.1, -5.3, ROT= 289.9, 5.3
2025-07-05T15:12:41.380Z,1751728361.380 [DAT](INFO): got valid direction response:
15:12:40.5744 LVL= 4976, 8625, 6498, 7251, AGC= 72, IDX= 465,-0.08, 1.828, 2.123,-3.002, 2.457, PHS=-0.541,-0.286, 0.821, RAW= 220.1, 0.1, CAL= 220.1, -5.3, ROT= 289.9, 5.3
2025-07-05T15:12:41.381Z,1751728361.381 [DAT](INFO): DAT read: Bearing 289.9, 5.3 (Local)
2025-07-05T15:12:41.382Z,1751728361.382 [DAT](INFO): Local bearing/azimuth received:
Bearing 289.9, 5.3 (Local)
2025-07-05T15:12:41.383Z,1751728361.383 [DAT](INFO): DAT read: Range 10 to 50 : 138.4 m (Round-trip 184.6 ms) speed -0.4 m/s
2025-07-05T15:12:41.384Z,1751728361.384 [DAT](INFO): #Rx 1: Read range and direction messages.
2025-07-05T15:12:41.385Z,1751728361.385 [DAT](INFO): direction in FSK: [0.338924,-0.936268,-0.092371]
2025-07-05T15:12:41.385Z,1751728361.385 [DAT](INFO): publishing direction and range info
2025-07-05T15:12:43.384Z,1751728363.384 [DataOverHttps](INFO): Sending 291 bytes from file Logs/20250705T144438/Express0019.lzma
2025-07-05T15:12:54.430Z,1751728374.430 [DataOverHttps](INFO): Moved sent file to Logs/20250705T144438/Express0019.lzma.bak
2025-07-05T15:12:54.430Z,1751728374.430 [DataOverHttps](INFO): SBD MOMSN=25361566
2025-07-05T15:13:26.066Z,1751728406.066 [DataOverHttps](INFO): Sending 493 bytes from file Logs/20250705T144438/Express0022.lzma
2025-07-05T15:13:37.126Z,1751728417.126 [DataOverHttps](INFO): Moved sent file to Logs/20250705T144438/Express0022.lzma.bak
2025-07-05T15:13:37.126Z,1751728417.126 [DataOverHttps](INFO): SBD MOMSN=25361593
2025-07-05T15:13:37.545Z,1751728417.545 [lineCaptureHoming:TrackAC.](INFO): *** querying acoustic contact ***
2025-07-05T15:13:37.633Z,1751728417.633 [DAT](DEBUG): Acoustic response timeout
2025-07-05T15:13:37.633Z,1751728417.633 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-07-05T15:13:37.885Z,1751728417.885 [DAT](INFO): DAT read: user:376>
2025-07-05T15:13:37.886Z,1751728417.886 [DAT](INFO): DAT read: Tx time:15:13:37.9861
2025-07-05T15:13:37.886Z,1751728417.886 [DAT](INFO): Ping request sent.
2025-07-05T15:13:37.886Z,1751728417.886 [DAT](INFO): transmitted an acoustic signal
2025-07-05T15:13:37.887Z,1751728417.887 [DAT](INFO): DAT read: TxSync time:15:13:37.9853
2025-07-05T15:13:37.887Z,1751728417.887 [DAT](INFO): publishing transmit ping time
2025-07-05T15:13:37.888Z,1751728417.888 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.001544
2025-07-05T15:13:38.137Z,1751728418.137 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250632
2025-07-05T15:13:38.389Z,1751728418.389 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502841
2025-07-05T15:13:38.642Z,1751728418.642 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.755523
2025-07-05T15:13:38.895Z,1751728418.895 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.008996
2025-07-05T15:13:39.145Z,1751728419.145 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258607
2025-07-05T15:13:39.397Z,1751728419.397 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510901
2025-07-05T15:13:39.650Z,1751728419.650 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.763531
2025-07-05T15:13:39.901Z,1751728419.901 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014877
2025-07-05T15:13:40.153Z,1751728420.153 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266763
2025-07-05T15:13:40.405Z,1751728420.405 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518790
2025-07-05T15:13:40.657Z,1751728420.657 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.771128
2025-07-05T15:13:40.914Z,1751728420.914 [DAT](INFO): DAT read: Rx Time:15:13:40.5755
2025-07-05T15:13:40.914Z,1751728420.914 [DAT](INFO): Rx dataTimestamp_ set to:1751728420.914145
2025-07-05T15:13:40.915Z,1751728420.915 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.028923
2025-07-05T15:13:41.165Z,1751728421.165 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.278822
2025-07-05T15:13:41.418Z,1751728421.418 [DAT](INFO): DAT read: Range 10 to 50 : 138.9 m (Round-trip 185.3 ms) speed 0.2 m/s
2025-07-05T15:13:41.419Z,1751728421.419 [DAT](ERROR): #Rx 1: Read range message, but no direction.
2025-07-05T15:14:08.037Z,1751728448.037 [DataOverHttps](INFO): Sending 307 bytes from file Logs/20250705T144438/Express0025.lzma
2025-07-05T15:14:19.094Z,1751728459.094 [DataOverHttps](INFO): Moved sent file to Logs/20250705T144438/Express0025.lzma.bak
2025-07-05T15:14:19.094Z,1751728459.094 [DataOverHttps](INFO): SBD MOMSN=25361720
2025-07-05T15:14:37.923Z,1751728477.923 [lineCaptureHoming:TrackAC.](INFO): *** querying acoustic contact ***
2025-07-05T15:14:37.957Z,1751728477.957 [DAT](DEBUG): Acoustic response timeout
2025-07-05T15:14:37.957Z,1751728477.957 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-07-05T15:14:38.209Z,1751728478.209 [DAT](INFO): DAT read: user:377>
2025-07-05T15:14:38.210Z,1751728478.210 [DAT](INFO): DAT read: Tx time:15:14:38.3366
2025-07-05T15:14:38.210Z,1751728478.210 [DAT](INFO): Ping request sent.
2025-07-05T15:14:38.210Z,1751728478.210 [DAT](INFO): transmitted an acoustic signal
2025-07-05T15:14:38.210Z,1751728478.210 [DAT](INFO): publishing transmit ping time
2025-07-05T15:14:38.211Z,1751728478.211 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000743
2025-07-05T15:14:38.461Z,1751728478.461 [DAT](INFO): DAT read: TxSync time:15:14:38.3358
2025-07-05T15:14:38.462Z,1751728478.462 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251668
2025-07-05T15:14:38.713Z,1751728478.713 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502692
2025-07-05T15:14:38.965Z,1751728478.965 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754724
2025-07-05T15:14:39.217Z,1751728479.217 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006782
2025-07-05T15:14:39.469Z,1751728479.469 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258826
2025-07-05T15:14:39.721Z,1751728479.721 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510666
2025-07-05T15:14:39.973Z,1751728479.973 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762862
2025-07-05T15:14:40.225Z,1751728480.225 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014792
2025-07-05T15:14:40.477Z,1751728480.477 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266728
2025-07-05T15:14:40.729Z,1751728480.729 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518775
2025-07-05T15:14:40.981Z,1751728480.981 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770809
2025-07-05T15:14:41.233Z,1751728481.233 [DAT](INFO): DAT read: Rx Time:15:14:40.9266
2025-07-05T15:14:41.233Z,1751728481.233 [DAT](INFO): Rx dataTimestamp_ set to:1751728481.233186
2025-07-05T15:14:41.234Z,1751728481.234 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.023983
2025-07-05T15:14:41.485Z,1751728481.485 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274754
2025-07-05T15:14:41.743Z,1751728481.743 [DAT](INFO): DAT read: 15:14:40.9266 LVL= 32752, 32753, 17378, 32755, AGC= 65, IDX= 443, 0.45,-0.446,-0.277, 1.153, 0.133, PHS=-0.491,-0.363, 1.017, RAW= 214.4, -2.3, CAL= 213.0, -9.0, ROT= 297.0, 9.0
2025-07-05T15:14:41.744Z,1751728481.744 [DAT](INFO): got valid direction response:
15:14:40.9266 LVL= 32752, 32753, 17378, 32755, AGC= 65, IDX= 443, 0.45,-0.446,-0.277, 1.153, 0.133, PHS=-0.491,-0.363, 1.017, RAW= 214.4, -2.3, CAL= 213.0, -9.0, ROT= 297.0, 9.0
2025-07-05T15:14:41.745Z,1751728481.745 [DAT](INFO): DAT read: Bearing 297.0, 9.0 (Local)
2025-07-05T15:14:41.745Z,1751728481.745 [DAT](INFO): Local bearing/azimuth received:
Bearing 297.0, 9.0 (Local)
2025-07-05T15:14:41.747Z,1751728481.747 [DAT](INFO): DAT read: Range 10 to 50 : 139.4 m (Round-trip 185.9 ms) speed 0.1 m/s
2025-07-05T15:14:41.747Z,1751728481.747 [DAT](INFO): #Rx 1: Read range and direction messages.
2025-07-05T15:14:41.748Z,1751728481.748 [DAT](INFO): direction in FSK: [0.448401,-0.880037,-0.156434]
2025-07-05T15:14:41.749Z,1751728481.749 [DAT](INFO): publishing direction and range info
2025-07-05T15:14:49.972Z,1751728489.972 [DataOverHttps](INFO): Sending 234 bytes from file Logs/20250705T144438/Express0028.lzma
2025-07-05T15:15:01.014Z,1751728501.014 [DataOverHttps](INFO): Moved sent file to Logs/20250705T144438/Express0028.lzma.bak
2025-07-05T15:15:01.014Z,1751728501.014 [DataOverHttps](INFO): SBD MOMSN=25361730
2025-07-05T15:15:31.373Z,1751728531.373 [DataOverHttps](INFO): Sending 274 bytes from file Logs/20250705T144438/Express0031.lzma
2025-07-05T15:15:38.156Z,1751728538.156 [lineCaptureHoming:TrackAC.](INFO): *** querying acoustic contact ***
2025-07-05T15:15:38.201Z,1751728538.201 [DAT](DEBUG): Acoustic response timeout
2025-07-05T15:15:38.201Z,1751728538.201 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-07-05T15:15:38.453Z,1751728538.453 [DAT](INFO): DAT read: user:378>
2025-07-05T15:15:38.454Z,1751728538.454 [DAT](INFO): DAT read: Tx time:15:15:38.5871
2025-07-05T15:15:38.454Z,1751728538.454 [DAT](INFO): Ping request sent.
2025-07-05T15:15:38.454Z,1751728538.454 [DAT](INFO): transmitted an acoustic signal
2025-07-05T15:15:38.454Z,1751728538.454 [DAT](INFO): publishing transmit ping time
2025-07-05T15:15:38.455Z,1751728538.455 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000746
2025-07-05T15:15:38.705Z,1751728538.705 [DAT](INFO): DAT read: TxSync time:15:15:38.5863
2025-07-05T15:15:38.706Z,1751728538.706 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251688
2025-07-05T15:15:38.957Z,1751728538.957 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502886
2025-07-05T15:15:39.209Z,1751728539.209 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754851
2025-07-05T15:15:39.462Z,1751728539.462 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.007747
2025-07-05T15:15:39.717Z,1751728539.717 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.262710
2025-07-05T15:15:39.969Z,1751728539.969 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.514843
2025-07-05T15:15:40.221Z,1751728540.221 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.766754
2025-07-05T15:15:40.473Z,1751728540.473 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.018792
2025-07-05T15:15:40.725Z,1751728540.725 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.270845
2025-07-05T15:15:40.977Z,1751728540.977 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.522853
2025-07-05T15:15:41.229Z,1751728541.229 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.775333
2025-07-05T15:15:41.481Z,1751728541.481 [DAT](INFO): DAT read: Rx Time:15:15:41.1797
2025-07-05T15:15:41.481Z,1751728541.481 [DAT](INFO): Rx dataTimestamp_ set to:1751728541.481120
2025-07-05T15:15:41.482Z,1751728541.482 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.027981
2025-07-05T15:15:41.735Z,1751728541.735 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.280915
2025-07-05T15:15:41.990Z,1751728541.990 [DAT](INFO): DAT read: Range 10 to 50 : 141.3 m (Round-trip 188.4 ms) speed 0.0 m/s
2025-07-05T15:15:41.990Z,1751728541.990 [DAT](ERROR): #Rx 1: Read range message, but no direction.
2025-07-05T15:15:42.414Z,1751728542.414 [DataOverHttps](INFO): Moved sent file to Logs/20250705T144438/Express0031.lzma.bak
2025-07-05T15:15:42.414Z,1751728542.414 [DataOverHttps](INFO): SBD MOMSN=25361737
2025-07-05T15:16:17.084Z,1751728577.084 [DataOverHttps](INFO): Sending 205 bytes from file Logs/20250705T144438/Express0034.lzma
2025-07-05T15:16:28.135Z,1751728588.135 [DataOverHttps](INFO): Moved sent file to Logs/20250705T144438/Express0034.lzma.bak
2025-07-05T15:16:28.135Z,1751728588.135 [DataOverHttps](INFO): SBD MOMSN=25361743
2025-07-05T15:16:38.431Z,1751728598.431 [lineCaptureHoming:TrackAC.](INFO): *** querying acoustic contact ***
2025-07-05T15:16:38.532Z,1751728598.532 [DAT](DEBUG): Acoustic response timeout
2025-07-05T15:16:38.533Z,1751728598.533 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-07-05T15:16:38.785Z,1751728598.785 [DAT](INFO): DAT read: user:379>
2025-07-05T15:16:38.786Z,1751728598.786 [DAT](INFO): DAT read: Tx time:15:16:38.9375
2025-07-05T15:16:38.786Z,1751728598.786 [DAT](INFO): Ping request sent.
2025-07-05T15:16:38.786Z,1751728598.786 [DAT](INFO): transmitted an acoustic signal
2025-07-05T15:16:38.786Z,1751728598.786 [DAT](INFO): publishing transmit ping time
2025-07-05T15:16:38.787Z,1751728598.787 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000752
2025-07-05T15:16:39.037Z,1751728599.037 [DAT](INFO): DAT read: TxSync time:15:16:38.9368
2025-07-05T15:16:39.038Z,1751728599.038 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251804
2025-07-05T15:16:39.289Z,1751728599.289 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502894
2025-07-05T15:16:39.541Z,1751728599.541 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754848
2025-07-05T15:16:39.793Z,1751728599.793 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006860
2025-07-05T15:16:40.045Z,1751728600.045 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258920
2025-07-05T15:16:40.297Z,1751728600.297 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510851
2025-07-05T15:16:40.549Z,1751728600.549 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762895
2025-07-05T15:16:40.801Z,1751728600.801 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014791
2025-07-05T15:16:41.053Z,1751728601.053 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266821
2025-07-05T15:16:41.243Z,1751728601.243 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-07-05T15:16:41.305Z,1751728601.305 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518994
2025-07-05T15:16:41.557Z,1751728601.557 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770797
2025-07-05T15:16:41.809Z,1751728601.809 [DAT](INFO): DAT read: Rx Time:15:16:41.5302
2025-07-05T15:16:41.809Z,1751728601.809 [DAT](INFO): Rx dataTimestamp_ set to:1751728601.809084
2025-07-05T15:16:41.810Z,1751728601.810 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.023986
2025-07-05T15:16:42.061Z,1751728602.061 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274971
2025-07-05T15:16:42.329Z,1751728602.329 [DAT](INFO): DAT read: 15:16:41.5303 LVL= 11632, 17905, 10306, 13859, AGC= 66, IDX= 439, 0.15,-0.447,-0.319, 1.431, 0.013, PHS=-0.373,-0.285, 1.415, RAW= 212.5, -8.7, CAL= 208.8, -15.2, ROT= 301.2, 15.2
2025-07-05T15:16:42.330Z,1751728602.330 [DAT](INFO): got valid direction response:
15:16:41.5303 LVL= 11632, 17905, 10306, 13859, AGC= 66, IDX= 439, 0.15,-0.447,-0.319, 1.431, 0.013, PHS=-0.373,-0.285, 1.415, RAW= 212.5, -8.7, CAL= 208.8, -15.2, ROT= 301.2, 15.2
2025-07-05T15:16:42.331Z,1751728602.331 [DAT](INFO): DAT read: Bearing 301.2, 15.2 (Local)
2025-07-05T15:16:42.331Z,1751728602.331 [DAT](INFO): Local bearing/azimuth received:
Bearing 301.2, 15.2 (Local)
2025-07-05T15:16:42.333Z,1751728602.333 [DAT](INFO): DAT read: Range 10 to 50 : 141.3 m (Round-trip 188.5 ms) speed 0.2 m/s
2025-07-05T15:16:42.334Z,1751728602.334 [DAT](INFO): #Rx 1: Read range and direction messages.
2025-07-05T15:16:42.335Z,1751728602.335 [DAT](INFO): direction in FSK: [0.499905,-0.825441,-0.262189]
2025-07-05T15:16:42.335Z,1751728602.335 [DAT](INFO): publishing direction and range info
2025-07-05T15:16:47.304Z,1751728607.304 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-07-05T15:16:58.930Z,1751728618.930 [DataOverHttps](INFO): Sending 499 bytes from file Logs/20250705T144438/Express0037.lzma
2025-07-05T15:17:09.970Z,1751728629.970 [DataOverHttps](INFO): Moved sent file to Logs/20250705T144438/Express0037.lzma.bak
2025-07-05T15:17:09.971Z,1751728629.971 [DataOverHttps](INFO): SBD MOMSN=25361749
2025-07-05T15:17:12.775Z,1751728632.775 [lineCaptureHoming:NeedComms:D] Stopped
2025-07-05T15:17:12.775Z,1751728632.775 [lineCaptureHoming:NeedComms:E] Running Loop=1
2025-07-05T15:17:13.261Z,1751728633.261 [lineCaptureHoming:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-07-05T15:17:13.557Z,1751728633.557 [NAL9602](INFO): Powering up
2025-07-05T15:17:24.471Z,1751728644.471 [NAL9602](INFO): NAL9602 initialized
2025-07-05T15:17:38.663Z,1751728658.663 [lineCaptureHoming:TrackAC.](INFO): *** querying acoustic contact ***
2025-07-05T15:17:38.851Z,1751728658.851 [DAT](DEBUG): Acoustic response timeout
2025-07-05T15:17:38.852Z,1751728658.852 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-07-05T15:17:39.101Z,1751728659.101 [DAT](INFO): DAT read: user:380>
2025-07-05T15:17:39.102Z,1751728659.102 [DAT](INFO): DAT read: Tx time:15:17:39.2381
2025-07-05T15:17:39.102Z,1751728659.102 [DAT](INFO): Ping request sent.
2025-07-05T15:17:39.102Z,1751728659.102 [DAT](INFO): transmitted an acoustic signal
2025-07-05T15:17:39.102Z,1751728659.102 [DAT](INFO): publishing transmit ping time
2025-07-05T15:17:39.103Z,1751728659.103 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000747
2025-07-05T15:17:39.353Z,1751728659.353 [DAT](INFO): DAT read: TxSync time:15:17:39.2374
2025-07-05T15:17:39.354Z,1751728659.354 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.252131
2025-07-05T15:17:39.605Z,1751728659.605 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502895
2025-07-05T15:17:39.857Z,1751728659.857 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754767
2025-07-05T15:17:40.109Z,1751728660.109 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006814
2025-07-05T15:17:40.361Z,1751728660.361 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258924
2025-07-05T15:17:40.613Z,1751728660.613 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510828
2025-07-05T15:17:40.869Z,1751728660.869 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.765640
2025-07-05T15:17:41.117Z,1751728661.117 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014782
2025-07-05T15:17:41.369Z,1751728661.369 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266795
2025-07-05T15:17:41.621Z,1751728661.621 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.519167
2025-07-05T15:17:41.873Z,1751728661.873 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770816
2025-07-05T15:17:42.125Z,1751728662.125 [DAT](INFO): DAT read: Rx Time:15:17:41.8326
2025-07-05T15:17:42.125Z,1751728662.125 [DAT](INFO): Rx dataTimestamp_ set to:1751728662.125016
2025-07-05T15:17:42.126Z,1751728662.126 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.023834
2025-07-05T15:17:42.377Z,1751728662.377 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274745
2025-07-05T15:17:42.630Z,1751728662.630 [DAT](INFO): DAT read: Range 10 to 50 : 142.7 m (Round-trip 190.3 ms) speed 0.0 m/s
2025-07-05T15:17:42.631Z,1751728662.631 [DAT](ERROR): #Rx 1: Read range message, but no direction.
2025-07-05T15:17:54.296Z,1751728674.296 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-07-05T15:17:56.113Z,1751728676.113 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-07-05T15:17:56.192Z,1751728676.192 [NAL9602](ERROR): received:
+CSQ:0
OK155, 0, 0, 0, 0
OK
2025-07-05T15:18:13.376Z,1751728693.376 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-07-05T15:18:23.978Z,1751728703.978 [NAL9602](INFO): SBD MO Status=1, MOMSN=49161, MT Status=0, MTMSN=0
2025-07-05T15:18:23.979Z,1751728703.979 [NAL9602](INFO): No messages in MT queue
2025-07-05T15:18:25.192Z,1751728705.192 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,151829.00,A,3654.41029,N,12206.89660,W,0.816,109.69,050725,,,A*7B
2025-07-05T15:18:25.195Z,1751728705.195 [NAL9602](INFO): GPS fix at 20250705T151829: (36.906838, -122.114943)
2025-07-05T15:18:25.234Z,1751728705.234 [lineCaptureHoming:NeedComms:E] Stopped
2025-07-05T15:18:25.235Z,1751728705.235 [lineCaptureHoming:NeedComms](INFO): Completed lineCaptureHoming:NeedComms
2025-07-05T15:18:25.235Z,1751728705.235 [lineCaptureHoming:NeedComms] Stopped
2025-07-05T15:18:25.235Z,1751728705.235 [lineCaptureHoming:NeedComms](DEBUG): Aggregate::uninitialize lineCaptureHoming:NeedComms
2025-07-05T15:18:25.235Z,1751728705.235 [lineCaptureHoming:NeedComms:B.GoToSurface] Stopped
2025-07-05T15:18:25.235Z,1751728705.235 [lineCaptureHoming:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-07-05T15:18:25.665Z,1751728705.665 [lineCaptureHoming:StartingMission](INFO): Completed lineCaptureHoming:StartingMission
2025-07-05T15:18:25.665Z,1751728705.665 [lineCaptureHoming:StartingMission] Stopped
2025-07-05T15:18:25.665Z,1751728705.665 [lineCaptureHoming:StartingMission](DEBUG): Aggregate::uninitialize lineCaptureHoming:StartingMission
2025-07-05T15:18:25.665Z,1751728705.665 [lineCaptureHoming:Z] Running Loop=1
2025-07-05T15:18:26.011Z,1751728706.011 [lineCaptureHoming:Z](IMPORTANT): Restarting logs
2025-07-05T15:18:26.011Z,1751728706.011 [lineCaptureHoming:Z] Stopped
2025-07-05T15:18:26.011Z,1751728706.011 [lineCaptureHoming:BA.Execute] Running Loop=1
2025-07-05T15:18:26.407Z,1751728706.407 [CTD_Seabird](ERROR): Failed to parse device response: 0,3.92762,8327.76