2025-08-22T17:26:33.583Z,1755883593.583 [DAT](INFO): Rx dataTimestamp_ set to:1755883593.582803 2025-08-22T17:26:34.091Z,1755883594.091 [DAT](INFO): DAT read: 17:26:34.1337 LVL= 26960, 22977, 23314, 26051, AGC= 58, IDX= 439,-0.18, 1.984,-2.575,-2.297,-3.101, PHS=-1.096, 0.571, 0.760, RAW= 264.7, -2.7, CAL= 262.7, -7.8, ROT= 247.3, 7.8 2025-08-22T17:26:34.092Z,1755883594.092 [DAT](INFO): got valid direction response: 17:26:34.1337 LVL= 26960, 22977, 23314, 26051, AGC= 58, IDX= 439,-0.18, 1.984,-2.575,-2.297,-3.101, PHS=-1.096, 0.571, 0.760, RAW= 264.7, -2.7, CAL= 262.7, -7.8, ROT= 247.3, 7.8 2025-08-22T17:26:34.092Z,1755883594.092 [DAT](INFO): DAT read: OK 2025-08-22T17:26:34.092Z,1755883594.092 [DAT](INFO): unknown deviceResponse_: OK 2025-08-22T17:26:34.093Z,1755883594.093 [DAT](INFO): #Rx 6: Read direction message, but no range. 2025-08-22T17:26:34.094Z,1755883594.094 [DAT](INFO): direction in FSK: [-0.382336,-0.914003,-0.135716] 2025-08-22T17:26:39.384Z,1755883599.384 [CommandExec](IMPORTANT): got command set OnDock.DockedTime 24 hour 2025-08-22T17:26:39.385Z,1755883599.385 [CommandExec](IMPORTANT): got command run 2025-08-22T17:26:39.425Z,1755883599.425 [CommandExec](IMPORTANT): Running 2025-08-22T17:26:39.439Z,1755883599.439 [DefaultWithUndock] Stopped 2025-08-22T17:26:39.439Z,1755883599.439 [DefaultWithUndock](DEBUG): Aggregate::uninitialize DefaultWithUndock 2025-08-22T17:26:39.439Z,1755883599.439 [DefaultWithUndock:A.Wait] Stopped 2025-08-22T17:26:39.439Z,1755883599.439 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component. 2025-08-22T17:26:39.440Z,1755883599.440 [MissionManager](IMPORTANT): Started mission OnDock 2025-08-22T17:26:39.440Z,1755883599.440 [OnDock] Running Loop=1 2025-08-22T17:26:39.440Z,1755883599.440 [OnDock](DEBUG): Aggregate::initialize OnDock 2025-08-22T17:26:39.440Z,1755883599.440 [OnDock:StandardEnvelopes] Running Loop=1 2025-08-22T17:26:39.440Z,1755883599.440 [OnDock:StandardEnvelopes](DEBUG): Aggregate::initialize OnDock:StandardEnvelopes 2025-08-22T17:26:39.440Z,1755883599.440 [OnDock:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2025-08-22T17:26:39.440Z,1755883599.440 [OnDock:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2025-08-22T17:26:39.440Z,1755883599.440 [OnDock:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2025-08-22T17:26:39.440Z,1755883599.440 [OnDock:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2025-08-22T17:26:39.441Z,1755883599.441 [OnDock:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2025-08-22T17:26:39.441Z,1755883599.441 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2025-08-22T17:26:39.441Z,1755883599.441 [OnDock:BackseatDriver] Running Loop=1 2025-08-22T17:26:39.441Z,1755883599.441 [OnDock:BackseatDriver](DEBUG): Aggregate::initialize OnDock:BackseatDriver 2025-08-22T17:26:39.441Z,1755883599.441 [OnDock:BackseatDriver:A.BackseatDriver] Running Loop=1 2025-08-22T17:26:39.441Z,1755883599.441 [OnDock:PowerOnly] Running Loop=1 2025-08-22T17:26:39.441Z,1755883599.441 [OnDock:PowerOnly](DEBUG): Aggregate::initialize OnDock:PowerOnly 2025-08-22T17:26:39.442Z,1755883599.442 [OnDock:PowerOnly:A] Running Loop=1 2025-08-22T17:26:39.442Z,1755883599.442 [OnDock:PowerOnly:B] Running Loop=1 2025-08-22T17:26:39.442Z,1755883599.442 [OnDock:PowerOnly:C] Running Loop=1 2025-08-22T17:26:39.442Z,1755883599.442 [OnDock:PowerOnly:D] Running Loop=1 2025-08-22T17:26:39.442Z,1755883599.442 [OnDock:PowerOnly:E.Wait] Running Loop=1 2025-08-22T17:26:39.442Z,1755883599.442 [OnDock:PowerOnly:E.Wait](DEBUG): Initialize Wait Component. 2025-08-22T17:26:39.442Z,1755883599.442 [OnDock:dataRead] Running Loop=1 2025-08-22T17:26:39.442Z,1755883599.442 [OnDock:dataRead](DEBUG): Aggregate::initialize OnDock:dataRead 2025-08-22T17:26:39.442Z,1755883599.442 [OnDock:dataRead:A] Running Loop=1 2025-08-22T17:26:39.442Z,1755883599.442 [OnDock:science] Running Loop=1 2025-08-22T17:26:39.442Z,1755883599.442 [OnDock:science](DEBUG): Aggregate::initialize OnDock:science 2025-08-22T17:26:39.443Z,1755883599.443 [OnDock:science:Science] Running Loop=1 2025-08-22T17:26:39.443Z,1755883599.443 [OnDock:science:Science](DEBUG): Aggregate::initialize OnDock:science:Science 2025-08-22T17:26:39.443Z,1755883599.443 [OnDock:science:Science:A] Running Loop=1 2025-08-22T17:26:39.443Z,1755883599.443 [OnDock:science:Science:B] Running Loop=1 2025-08-22T17:26:39.443Z,1755883599.443 [OnDock:science:Science:C] Running Loop=1 2025-08-22T17:26:39.443Z,1755883599.443 [OnDock:science:Science:D] Running Loop=1 2025-08-22T17:26:39.443Z,1755883599.443 [OnDock:science:Science:E] Running Loop=1 2025-08-22T17:26:39.443Z,1755883599.443 [OnDock:science:Science:F] Running Loop=1 2025-08-22T17:26:39.443Z,1755883599.443 [OnDock:science:Science:Read_Oil] Running Loop=1 2025-08-22T17:26:39.443Z,1755883599.443 [OnDock:science:Science:PeakDetectChl] Running Loop=1 2025-08-22T17:26:39.443Z,1755883599.443 [OnDock:science:Science:PeakDetectChl](DEBUG): Aggregate::initialize OnDock:science:Science:PeakDetectChl 2025-08-22T17:26:39.443Z,1755883599.443 [OnDock:science:Science:PeakDetectChl:A] Running Loop=1 2025-08-22T17:26:39.443Z,1755883599.443 [OnDock:science:Science:EdgeDetectChl] Running Loop=1 2025-08-22T17:26:39.444Z,1755883599.444 [OnDock:science:Science:EdgeDetectChl](DEBUG): Aggregate::initialize OnDock:science:Science:EdgeDetectChl 2025-08-22T17:26:39.444Z,1755883599.444 [OnDock:science:Science:EdgeDetectChl:A] Running Loop=1 2025-08-22T17:26:39.444Z,1755883599.444 [OnDock:science:Science:HighestChlPeakReport] Running Loop=1 2025-08-22T17:26:39.444Z,1755883599.444 [OnDock:science:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize OnDock:science:Science:HighestChlPeakReport 2025-08-22T17:26:39.445Z,1755883599.445 [OnDock:science:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1 2025-08-22T17:26:39.445Z,1755883599.445 [OnDock:science:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2025-08-22T17:26:39.445Z,1755883599.445 [OnDock:science:Science:PeakDetectNO3] Running Loop=1 2025-08-22T17:26:39.449Z,1755883599.449 [OnDock:science:Science:PeakDetectNO3](DEBUG): Aggregate::initialize OnDock:science:Science:PeakDetectNO3 2025-08-22T17:26:39.450Z,1755883599.450 [OnDock:science:Science:PeakDetectNO3:A] Running Loop=1 2025-08-22T17:26:39.450Z,1755883599.450 [OnDock:science:Science:PeakDetectOil] Running Loop=1 2025-08-22T17:26:39.450Z,1755883599.450 [OnDock:science:Science:PeakDetectOil](DEBUG): Aggregate::initialize OnDock:science:Science:PeakDetectOil 2025-08-22T17:26:39.450Z,1755883599.450 [OnDock:science:Science:PeakDetectOil:A] Running Loop=1 2025-08-22T17:26:39.450Z,1755883599.450 [OnDock:science:Science:HighestOilPeakReport] Running Loop=1 2025-08-22T17:26:39.450Z,1755883599.450 [OnDock:science:Science:HighestOilPeakReport](DEBUG): Aggregate::initialize OnDock:science:Science:HighestOilPeakReport 2025-08-22T17:26:39.450Z,1755883599.450 [OnDock:science:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Running Loop=1 2025-08-22T17:26:39.450Z,1755883599.450 [OnDock:science:Science:HighestOilPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2025-08-22T17:26:39.451Z,1755883599.451 [OnDock:science:Science:PeakDetectFDOM] Running Loop=1 2025-08-22T17:26:39.451Z,1755883599.451 [OnDock:science:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize OnDock:science:Science:PeakDetectFDOM 2025-08-22T17:26:39.451Z,1755883599.451 [OnDock:science:Science:PeakDetectFDOM:A] Running Loop=1 2025-08-22T17:26:39.451Z,1755883599.451 [OnDock:science:Science:PeakDetectSalinity] Running Loop=1 2025-08-22T17:26:39.451Z,1755883599.451 [OnDock:science:Science:PeakDetectSalinity](DEBUG): Aggregate::initialize OnDock:science:Science:PeakDetectSalinity 2025-08-22T17:26:39.451Z,1755883599.451 [OnDock:science:Science:PeakDetectSalinity:A] Running Loop=1 2025-08-22T17:26:39.451Z,1755883599.451 [OnDock:science:Science:HighestSaltPeakReport] Running Loop=1 2025-08-22T17:26:39.451Z,1755883599.451 [OnDock:science:Science:HighestSaltPeakReport](DEBUG): Aggregate::initialize OnDock:science:Science:HighestSaltPeakReport 2025-08-22T17:26:39.452Z,1755883599.452 [OnDock:science:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Running Loop=1 2025-08-22T17:26:39.452Z,1755883599.452 [OnDock:science:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2025-08-22T17:26:39.452Z,1755883599.452 [OnDock:science:Science:PeakDetectPlanktivoreLMavgROI] Running Loop=1 2025-08-22T17:26:39.452Z,1755883599.452 [OnDock:science:Science:PeakDetectPlanktivoreLMavgROI](DEBUG): Aggregate::initialize OnDock:science:Science:PeakDetectPlanktivoreLMavgROI 2025-08-22T17:26:39.452Z,1755883599.452 [OnDock:science:Science:PeakDetectPlanktivoreLMavgROI:A] Running Loop=1 2025-08-22T17:26:39.452Z,1755883599.452 [OnDock:science:Science:EdgeDetectPlanktivoreLMavgROI] Running Loop=1 2025-08-22T17:26:39.452Z,1755883599.452 [OnDock:science:Science:EdgeDetectPlanktivoreLMavgROI](DEBUG): Aggregate::initialize OnDock:science:Science:EdgeDetectPlanktivoreLMavgROI 2025-08-22T17:26:39.453Z,1755883599.453 [OnDock:science:Science:EdgeDetectPlanktivoreLMavgROI:A] Running Loop=1 2025-08-22T17:26:39.453Z,1755883599.453 [OnDock:science:Science:PeakDetectPlanktivoreHMavgROI] Running Loop=1 2025-08-22T17:26:39.453Z,1755883599.453 [OnDock:science:Science:PeakDetectPlanktivoreHMavgROI](DEBUG): Aggregate::initialize OnDock:science:Science:PeakDetectPlanktivoreHMavgROI 2025-08-22T17:26:39.453Z,1755883599.453 [OnDock:science:Science:PeakDetectPlanktivoreHMavgROI:A] Running Loop=1 2025-08-22T17:26:39.453Z,1755883599.453 [OnDock:science:Science:EdgeDetectPlanktivoreHMavgROI] Running Loop=1 2025-08-22T17:26:39.453Z,1755883599.453 [OnDock:science:Science:EdgeDetectPlanktivoreHMavgROI](DEBUG): Aggregate::initialize OnDock:science:Science:EdgeDetectPlanktivoreHMavgROI 2025-08-22T17:26:39.453Z,1755883599.453 [OnDock:science:Science:EdgeDetectPlanktivoreHMavgROI:A] Running Loop=1 2025-08-22T17:26:39.457Z,1755883599.457 [OnDock:science:Science:PeakDetectPlanktivoreDiatoms] Running Loop=1 2025-08-22T17:26:39.458Z,1755883599.458 [OnDock:science:Science:PeakDetectPlanktivoreDiatoms](DEBUG): Aggregate::initialize OnDock:science:Science:PeakDetectPlanktivoreDiatoms 2025-08-22T17:26:39.458Z,1755883599.458 [OnDock:science:Science:PeakDetectPlanktivoreDiatoms:A] Running Loop=1 2025-08-22T17:26:39.458Z,1755883599.458 [OnDock:science:Science:EdgeDetectPlanktivoreDiatoms] Running Loop=1 2025-08-22T17:26:39.458Z,1755883599.458 [OnDock:science:Science:EdgeDetectPlanktivoreDiatoms](DEBUG): Aggregate::initialize OnDock:science:Science:EdgeDetectPlanktivoreDiatoms 2025-08-22T17:26:39.458Z,1755883599.458 [OnDock:science:Science:EdgeDetectPlanktivoreDiatoms:A] Running Loop=1 2025-08-22T17:26:39.458Z,1755883599.458 [OnDock:science:Science:PeakDetectPlanktivoreDinoflagellates] Running Loop=1 2025-08-22T17:26:39.458Z,1755883599.458 [OnDock:science:Science:PeakDetectPlanktivoreDinoflagellates](DEBUG): Aggregate::initialize OnDock:science:Science:PeakDetectPlanktivoreDinoflagellates 2025-08-22T17:26:39.458Z,1755883599.458 [OnDock:science:Science:PeakDetectPlanktivoreDinoflagellates:A] Running Loop=1 2025-08-22T17:26:39.458Z,1755883599.458 [OnDock:science:Science:EdgeDetectPlanktivoreDinoflagellates] Running Loop=1 2025-08-22T17:26:39.458Z,1755883599.458 [OnDock:science:Science:EdgeDetectPlanktivoreDinoflagellates](DEBUG): Aggregate::initialize OnDock:science:Science:EdgeDetectPlanktivoreDinoflagellates 2025-08-22T17:26:39.459Z,1755883599.459 [OnDock:science:Science:EdgeDetectPlanktivoreDinoflagellates:A] Running Loop=1 2025-08-22T17:26:39.459Z,1755883599.459 [OnDock:science:Science:ChlThreshTrigger] Running Loop=1 2025-08-22T17:26:39.459Z,1755883599.459 [OnDock:science:Science:ChlThreshTrigger](DEBUG): Aggregate::initialize OnDock:science:Science:ChlThreshTrigger 2025-08-22T17:26:39.459Z,1755883599.459 [OnDock:science:Science:ChlThreshTrigger:A.ValueDetect] Running Loop=1 2025-08-22T17:26:39.459Z,1755883599.459 [OnDock:science:Science:ChlThreshTrigger:A.ValueDetect](INFO): Initialize. 2025-08-22T17:26:39.459Z,1755883599.459 [OnDock:science:Science:OceanCurrent] Running Loop=1 2025-08-22T17:26:39.459Z,1755883599.459 [OnDock:science:Science:OceanCurrent](DEBUG): Aggregate::initialize OnDock:science:Science:OceanCurrent 2025-08-22T17:26:39.460Z,1755883599.460 [OnDock:science:Science:OceanCurrent:A.] Running Loop=1 2025-08-22T17:26:39.460Z,1755883599.460 [OnDock:science:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator. 2025-08-22T17:26:39.460Z,1755883599.460 [OnDock:J.] Running Loop=1 2025-08-22T17:26:39.460Z,1755883599.460 [OnDock:J.](INFO): Initializing TrackAcousticContact. 2025-08-22T17:26:39.460Z,1755883599.460 [OnDock:PeriodicCheck] Running Loop=1 2025-08-22T17:26:39.460Z,1755883599.460 [OnDock:PeriodicCheck](DEBUG): Aggregate::initialize OnDock:PeriodicCheck 2025-08-22T17:26:39.460Z,1755883599.460 [OnDock:PeriodicCheck:CheckIP] Running Loop=1 2025-08-22T17:26:39.461Z,1755883599.461 [OnDock:PeriodicCheck:CheckIP](DEBUG): Aggregate::initialize OnDock:PeriodicCheck:CheckIP 2025-08-22T17:26:39.461Z,1755883599.461 [OnDock:PeriodicCheck:CheckIP:A.Execute] Running Loop=1 2025-08-22T17:26:39.461Z,1755883599.461 [OnDock:B] Running Loop=1 2025-08-22T17:26:39.466Z,1755883599.466 [OnDock:PeriodicCheck] Running Loop=1 2025-08-22T17:26:39.466Z,1755883599.466 [OnDock:PeriodicCheck:CheckIP:A.Execute](INFO): Executing command ! ifconfig 2025-08-22T17:26:39.466Z,1755883599.466 [OnDock:PeriodicCheck:CheckIP:A.Execute] Stopped 2025-08-22T17:26:39.466Z,1755883599.466 [OnDock:PeriodicCheck:CheckIP:B.Wait] Running Loop=1 2025-08-22T17:26:39.466Z,1755883599.466 [OnDock:PeriodicCheck:CheckIP:B.Wait](DEBUG): Initialize Wait Component. 2025-08-22T17:26:39.466Z,1755883599.466 [OnDock:J.] Running Loop=1 2025-08-22T17:26:39.467Z,1755883599.467 [OnDock:J.](ERROR): Failed to read sound speed 2025-08-22T17:26:39.467Z,1755883599.467 [OnDock:science] Stopped 2025-08-22T17:26:39.467Z,1755883599.467 [OnDock:science](DEBUG): Aggregate::uninitialize OnDock:science 2025-08-22T17:26:39.467Z,1755883599.467 [OnDock:science:Science] Stopped 2025-08-22T17:26:39.467Z,1755883599.467 [OnDock:science:Science](DEBUG): Aggregate::uninitialize OnDock:science:Science 2025-08-22T17:26:39.467Z,1755883599.467 [OnDock:science:Science:A] Stopped 2025-08-22T17:26:39.467Z,1755883599.467 [OnDock:science:Science:B] Stopped 2025-08-22T17:26:39.467Z,1755883599.467 [OnDock:science:Science:C] Stopped 2025-08-22T17:26:39.467Z,1755883599.467 [OnDock:science:Science:D] Stopped 2025-08-22T17:26:39.467Z,1755883599.467 [OnDock:science:Science:E] Stopped 2025-08-22T17:26:39.467Z,1755883599.467 [OnDock:science:Science:F] Stopped 2025-08-22T17:26:39.467Z,1755883599.467 [OnDock:science:Science:Read_Oil] Stopped 2025-08-22T17:26:39.468Z,1755883599.468 [OnDock:science:Science:OceanCurrent] Stopped 2025-08-22T17:26:39.468Z,1755883599.468 [OnDock:science:Science:OceanCurrent](DEBUG): Aggregate::uninitialize OnDock:science:Science:OceanCurrent 2025-08-22T17:26:39.468Z,1755883599.468 [OnDock:science:Science:OceanCurrent:A.] Stopped 2025-08-22T17:26:39.468Z,1755883599.468 [OnDock:science:Science:PeakDetectChl] Stopped 2025-08-22T17:26:39.468Z,1755883599.468 [OnDock:science:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize OnDock:science:Science:PeakDetectChl 2025-08-22T17:26:39.468Z,1755883599.468 [OnDock:science:Science:PeakDetectChl:A] Stopped 2025-08-22T17:26:39.468Z,1755883599.468 [OnDock:science:Science:EdgeDetectChl] Stopped 2025-08-22T17:26:39.468Z,1755883599.468 [OnDock:science:Science:EdgeDetectChl](DEBUG): Aggregate::uninitialize OnDock:science:Science:EdgeDetectChl 2025-08-22T17:26:39.468Z,1755883599.468 [OnDock:science:Science:EdgeDetectChl:A] Stopped 2025-08-22T17:26:39.468Z,1755883599.468 [OnDock:science:Science:HighestChlPeakReport] Stopped 2025-08-22T17:26:39.468Z,1755883599.468 [OnDock:science:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize OnDock:science:Science:HighestChlPeakReport 2025-08-22T17:26:39.468Z,1755883599.468 [OnDock:science:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped 2025-08-22T17:26:39.468Z,1755883599.468 [OnDock:science:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2025-08-22T17:26:39.469Z,1755883599.469 [OnDock:science:Science:PeakDetectNO3] Stopped 2025-08-22T17:26:39.469Z,1755883599.469 [OnDock:science:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize OnDock:science:Science:PeakDetectNO3 2025-08-22T17:26:39.469Z,1755883599.469 [OnDock:science:Science:PeakDetectNO3:A] Stopped 2025-08-22T17:26:39.469Z,1755883599.469 [OnDock:science:Science:PeakDetectOil] Stopped 2025-08-22T17:26:39.469Z,1755883599.469 [OnDock:science:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize OnDock:science:Science:PeakDetectOil 2025-08-22T17:26:39.469Z,1755883599.469 [OnDock:science:Science:PeakDetectOil:A] Stopped 2025-08-22T17:26:39.469Z,1755883599.469 [OnDock:science:Science:HighestOilPeakReport] Stopped 2025-08-22T17:26:39.469Z,1755883599.469 [OnDock:science:Science:HighestOilPeakReport](DEBUG): Aggregate::uninitialize OnDock:science:Science:HighestOilPeakReport 2025-08-22T17:26:39.469Z,1755883599.469 [OnDock:science:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Stopped 2025-08-22T17:26:39.474Z,1755883599.474 [OnDock:science:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2025-08-22T17:26:39.474Z,1755883599.474 [OnDock:science:Science:PeakDetectFDOM] Stopped 2025-08-22T17:26:39.474Z,1755883599.474 [OnDock:science:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize OnDock:science:Science:PeakDetectFDOM 2025-08-22T17:26:39.474Z,1755883599.474 [OnDock:science:Science:PeakDetectFDOM:A] Stopped 2025-08-22T17:26:39.474Z,1755883599.474 [OnDock:science:Science:PeakDetectSalinity] Stopped 2025-08-22T17:26:39.474Z,1755883599.474 [OnDock:science:Science:PeakDetectSalinity](DEBUG): Aggregate::uninitialize OnDock:science:Science:PeakDetectSalinity 2025-08-22T17:26:39.474Z,1755883599.474 [OnDock:science:Science:PeakDetectSalinity:A] Stopped 2025-08-22T17:26:39.474Z,1755883599.474 [OnDock:science:Science:HighestSaltPeakReport] Stopped 2025-08-22T17:26:39.474Z,1755883599.474 [OnDock:science:Science:HighestSaltPeakReport](DEBUG): Aggregate::uninitialize OnDock:science:Science:HighestSaltPeakReport 2025-08-22T17:26:39.474Z,1755883599.474 [OnDock:science:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Stopped 2025-08-22T17:26:39.474Z,1755883599.474 [OnDock:science:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2025-08-22T17:26:39.475Z,1755883599.475 [OnDock:science:Science:PeakDetectPlanktivoreLMavgROI] Stopped 2025-08-22T17:26:39.475Z,1755883599.475 [OnDock:science:Science:PeakDetectPlanktivoreLMavgROI](DEBUG): Aggregate::uninitialize OnDock:science:Science:PeakDetectPlanktivoreLMavgROI 2025-08-22T17:26:39.475Z,1755883599.475 [OnDock:science:Science:PeakDetectPlanktivoreLMavgROI:A] Stopped 2025-08-22T17:26:39.475Z,1755883599.475 [OnDock:science:Science:EdgeDetectPlanktivoreLMavgROI] Stopped 2025-08-22T17:26:39.475Z,1755883599.475 [OnDock:science:Science:EdgeDetectPlanktivoreLMavgROI](DEBUG): Aggregate::uninitialize OnDock:science:Science:EdgeDetectPlanktivoreLMavgROI 2025-08-22T17:26:39.475Z,1755883599.475 [OnDock:science:Science:EdgeDetectPlanktivoreLMavgROI:A] Stopped 2025-08-22T17:26:39.475Z,1755883599.475 [OnDock:science:Science:PeakDetectPlanktivoreHMavgROI] Stopped 2025-08-22T17:26:39.475Z,1755883599.475 [OnDock:science:Science:PeakDetectPlanktivoreHMavgROI](DEBUG): Aggregate::uninitialize OnDock:science:Science:PeakDetectPlanktivoreHMavgROI 2025-08-22T17:26:39.475Z,1755883599.475 [OnDock:science:Science:PeakDetectPlanktivoreHMavgROI:A] Stopped 2025-08-22T17:26:39.475Z,1755883599.475 [OnDock:science:Science:EdgeDetectPlanktivoreHMavgROI] Stopped 2025-08-22T17:26:39.475Z,1755883599.475 [OnDock:science:Science:EdgeDetectPlanktivoreHMavgROI](DEBUG): Aggregate::uninitialize OnDock:science:Science:EdgeDetectPlanktivoreHMavgROI 2025-08-22T17:26:39.475Z,1755883599.475 [OnDock:science:Science:EdgeDetectPlanktivoreHMavgROI:A] Stopped 2025-08-22T17:26:39.475Z,1755883599.475 [OnDock:science:Science:PeakDetectPlanktivoreDiatoms] Stopped 2025-08-22T17:26:39.476Z,1755883599.476 [OnDock:science:Science:PeakDetectPlanktivoreDiatoms](DEBUG): Aggregate::uninitialize OnDock:science:Science:PeakDetectPlanktivoreDiatoms 2025-08-22T17:26:39.476Z,1755883599.476 [OnDock:science:Science:PeakDetectPlanktivoreDiatoms:A] Stopped 2025-08-22T17:26:39.476Z,1755883599.476 [OnDock:science:Science:EdgeDetectPlanktivoreDiatoms] Stopped 2025-08-22T17:26:39.476Z,1755883599.476 [OnDock:science:Science:EdgeDetectPlanktivoreDiatoms](DEBUG): Aggregate::uninitialize OnDock:science:Science:EdgeDetectPlanktivoreDiatoms 2025-08-22T17:26:39.476Z,1755883599.476 [OnDock:science:Science:EdgeDetectPlanktivoreDiatoms:A] Stopped 2025-08-22T17:26:39.476Z,1755883599.476 [OnDock:science:Science:PeakDetectPlanktivoreDinoflagellates] Stopped 2025-08-22T17:26:39.476Z,1755883599.476 [OnDock:science:Science:PeakDetectPlanktivoreDinoflagellates](DEBUG): Aggregate::uninitialize OnDock:science:Science:PeakDetectPlanktivoreDinoflagellates 2025-08-22T17:26:39.476Z,1755883599.476 [OnDock:science:Science:PeakDetectPlanktivoreDinoflagellates:A] Stopped 2025-08-22T17:26:39.476Z,1755883599.476 [OnDock:science:Science:EdgeDetectPlanktivoreDinoflagellates] Stopped 2025-08-22T17:26:39.476Z,1755883599.476 [OnDock:science:Science:EdgeDetectPlanktivoreDinoflagellates](DEBUG): Aggregate::uninitialize OnDock:science:Science:EdgeDetectPlanktivoreDinoflagellates 2025-08-22T17:26:39.476Z,1755883599.476 [OnDock:science:Science:EdgeDetectPlanktivoreDinoflagellates:A] Stopped 2025-08-22T17:26:39.476Z,1755883599.476 [OnDock:science:Science:ChlThreshTrigger] Stopped 2025-08-22T17:26:39.476Z,1755883599.476 [OnDock:science:Science:ChlThreshTrigger](DEBUG): Aggregate::uninitialize OnDock:science:Science:ChlThreshTrigger 2025-08-22T17:26:39.477Z,1755883599.477 [OnDock:science:Science:ChlThreshTrigger:A.ValueDetect] Stopped 2025-08-22T17:26:39.477Z,1755883599.477 [OnDock:science:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Uninitialize. 2025-08-22T17:26:39.477Z,1755883599.477 [OnDock:dataRead] Running Loop=1 2025-08-22T17:26:39.482Z,1755883599.482 [OnDock:dataRead:A](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-08-22T17:26:39.482Z,1755883599.482 [OnDock:PowerOnly] Running Loop=1 2025-08-22T17:26:39.483Z,1755883599.483 [OnDock:PowerOnly:D] Stopped 2025-08-22T17:26:39.483Z,1755883599.483 [OnDock:PowerOnly:C] Stopped 2025-08-22T17:26:39.483Z,1755883599.483 [OnDock:PowerOnly:B] Stopped 2025-08-22T17:26:39.483Z,1755883599.483 [OnDock:PowerOnly:A] Stopped 2025-08-22T17:26:39.483Z,1755883599.483 [OnDock:BackseatDriver] Running Loop=1 2025-08-22T17:26:39.484Z,1755883599.484 [OnDock:B] Stopped 2025-08-22T17:26:39.484Z,1755883599.484 [OnDock:C] Running Loop=1 2025-08-22T17:26:39.484Z,1755883599.484 [OnDock:StandardEnvelopes] Running Loop=1 2025-08-22T17:26:39.484Z,1755883599.484 [OnDock:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2025-08-22T17:26:39.485Z,1755883599.485 [OnDock:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2025-08-22T17:26:39.485Z,1755883599.485 [OnDock:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2025-08-22T17:26:39.543Z,1755883599.543 [CommandExec](IMPORTANT): got command ! ifconfig 2025-08-22T17:26:39.813Z,1755883599.813 [CommandExec](IMPORTANT): eth0 Link encap:Ethernet HWaddr 50:2D:F4:07:45:37 UP BROADCAST RUNNING MULTICAST MTU:1500 Metric:1 RX packets:3325 errors:0 dropped:0 overruns:0 frame:0 TX packets:4082 errors:0 dropped:0 overruns:0 carrier:0 collisions:7 txqueuelen:1000 RX bytes:1358737 (1.2 Mb) TX bytes:745211 (727.7 Kb) Interrupt:29 lo Link encap:Local Loopback inet addr:127.0.0.1 Mask:255.0.0.0 UP LOOPBACK RUNNING MULTICAST MTU:16436 Metric:1 RX packets:601 errors:0 dropped:0 overruns:0 frame:0 TX packets:601 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:0 RX bytes:49702 (48.5 Kb) TX bytes:49702 (48.5 Kb) 2025-08-22T17:26:40.102Z,1755883600.102 [OnDock:C] Stopped 2025-08-22T17:26:40.102Z,1755883600.102 [OnDock:D] Running Loop=1 2025-08-22T17:26:40.511Z,1755883600.511 [OnDock:D] Stopped 2025-08-22T17:26:40.511Z,1755883600.511 [OnDock:E] Running Loop=1 2025-08-22T17:26:40.892Z,1755883600.892 [OnDock:E] Stopped 2025-08-22T17:26:40.893Z,1755883600.893 [OnDock:K] Running Loop=1 2025-08-22T17:26:41.288Z,1755883601.288 [OnDock:K](IMPORTANT): Restarting logs 2025-08-22T17:26:41.288Z,1755883601.288 [OnDock:K] Stopped 2025-08-22T17:26:41.288Z,1755883601.288 [OnDock:L.Execute] Running Loop=1 2025-08-22T17:26:41.687Z,1755883601.687 [OnDock:L.Execute](INFO): Executing command restart logs 2025-08-22T17:26:41.688Z,1755883601.688 [OnDock:L.Execute] Stopped 2025-08-22T17:26:41.688Z,1755883601.688 [OnDock:N.Docked] Running Loop=1 2025-08-22T17:26:41.688Z,1755883601.688 [OnDock:N.Docked](DEBUG): Initialize. 2025-08-22T17:26:41.688Z,1755883601.688 [OnDock:N.Docked](DEBUG): Initializing internal variables to default values. 2025-08-22T17:26:41.695Z,1755883601.695 [CommandExec](IMPORTANT): got command restart logs 2025-08-22T17:26:42.092Z,1755883602.092 [OnDock:N.Docked](FAULT): Off dock, range to dock 15.800000 exceeded threshold. 2025-08-22T17:26:42.092Z,1755883602.092 [OnDock:N.Docked](IMPORTANT): State switched to DETACHED. Waiting for state timeout to act... 2025-08-22T17:26:42.150Z,1755883602.150 [DAT](INFO): Requesting device enable set for address 50. 2025-08-22T17:26:42.402Z,1755883602.402 [DAT](INFO): DAT read: user:688> 2025-08-22T17:26:42.403Z,1755883602.403 [DAT](INFO): DAT read: Tx time:17:26:43.4322 2025-08-22T17:26:42.404Z,1755883602.404 [DAT](INFO): Ping request sent. 2025-08-22T17:26:42.404Z,1755883602.404 [DAT](INFO): transmitted an acoustic signal 2025-08-22T17:26:42.404Z,1755883602.404 [DAT](INFO): publishing transmit ping time 2025-08-22T17:26:42.404Z,1755883602.404 [DAT](INFO): Requesting device enable set for address 50. 2025-08-22T17:26:42.591Z,1755883602.591 [CTD_Seabird](ERROR): Failed to parse device response: 2025-08-22T17:26:42.655Z,1755883602.655 [DAT](INFO): Requesting device enable set for address 50. 2025-08-22T17:26:42.967Z,1755883602.967 [DockingStepper](DEBUG): Initializing EZServoServo. 2025-08-22T17:26:43.086Z,1755883603.086 [DockingStepper](DEBUG): Initializing DockingStepper. 2025-08-22T17:26:44.923Z,1755883604.923 [DAT](INFO): DAT read: Rx Time:17:26:45.3342 2025-08-22T17:26:44.923Z,1755883604.923 [DAT](INFO): Rx dataTimestamp_ set to:1755883604.922873 2025-08-22T17:26:45.431Z,1755883605.431 [DAT](INFO): DAT read: 17:26:45.3342 LVL= 32752, 24545, 28466, 32755, AGC= 59, IDX= 445, 0.05, 0.798, 2.617, 2.432, 2.009, PHS=-1.109, 0.652, 0.379, RAW= 278.3, 1.0, CAL= 276.9, -4.5, ROT= 233.1, 4.5 2025-08-22T17:26:45.432Z,1755883605.432 [DAT](INFO): got valid direction response: 17:26:45.3342 LVL= 32752, 24545, 28466, 32755, AGC= 59, IDX= 445, 0.05, 0.798, 2.617, 2.432, 2.009, PHS=-1.109, 0.652, 0.379, RAW= 278.3, 1.0, CAL= 276.9, -4.5, ROT= 233.1, 4.5 2025-08-22T17:26:45.432Z,1755883605.432 [DAT](INFO): DAT read: OK 2025-08-22T17:26:45.433Z,1755883605.433 [DAT](INFO): unknown deviceResponse_: OK 2025-08-22T17:26:45.433Z,1755883605.433 [DAT](INFO): DAT read: user:689> 2025-08-22T17:26:45.434Z,1755883605.434 [DAT](INFO): DAT read: Tx time:17:26:46.4322 2025-08-22T17:26:45.434Z,1755883605.434 [DAT](INFO): Ping request sent. 2025-08-22T17:26:45.434Z,1755883605.434 [DAT](INFO): transmitted an acoustic signal 2025-08-22T17:26:45.435Z,1755883605.435 [DAT](INFO): #Rx 7: Read direction message, but no range. 2025-08-22T17:26:45.436Z,1755883605.436 [DAT](INFO): direction in FSK: [-0.598569,-0.797220,-0.078459] 2025-08-22T17:26:45.436Z,1755883605.436 [DAT](INFO): publishing transmit ping time 2025-08-22T17:26:45.439Z,1755883605.439 [DAT](INFO): publishing transmit ping time 2025-08-22T17:26:46.627Z,1755883606.627 [DockingStepper](INFO): Changing to mode: 1 2025-08-22T17:26:47.695Z,1755883607.695 [DAT](INFO): DAT read: Rx Time:17:26:48.3343 2025-08-22T17:26:47.695Z,1755883607.695 [DAT](INFO): Rx dataTimestamp_ set to:1755883607.694736 2025-08-22T17:26:48.455Z,1755883608.455 [DAT](INFO): DAT read: 17:26:48.3343 LVL= 28944, 18161, 25906, 29091, AGC= 54, IDX= 446,-0.12, 2.278,-2.227,-2.491,-2.865, PHS=-1.038, 0.683, 0.331, RAW= 281.2, 0.3, CAL= 280.1, -5.1, ROT= 229.9, 5.1 2025-08-22T17:26:48.456Z,1755883608.456 [DAT](INFO): got valid direction response: 17:26:48.3343 LVL= 28944, 18161, 25906, 29091, AGC= 54, IDX= 446,-0.12, 2.278,-2.227,-2.491,-2.865, PHS=-1.038, 0.683, 0.331, RAW= 281.2, 0.3, CAL= 280.1, -5.1, ROT= 229.9, 5.1 2025-08-22T17:26:48.456Z,1755883608.456 [DAT](INFO): DAT read: OK 2025-08-22T17:26:48.457Z,1755883608.457 [DAT](INFO): unknown deviceResponse_: OK 2025-08-22T17:26:48.457Z,1755883608.457 [DAT](INFO): DAT read: user:690> 2025-08-22T17:26:48.458Z,1755883608.458 [DAT](INFO): DAT read: Tx time:17:26:49.4322 2025-08-22T17:26:48.458Z,1755883608.458 [DAT](INFO): Ping request sent. 2025-08-22T17:26:48.458Z,1755883608.458 [DAT](INFO): transmitted an acoustic signal 2025-08-22T17:26:48.459Z,1755883608.459 [DAT](INFO): #Rx 8: Read direction message, but no range. 2025-08-22T17:26:48.460Z,1755883608.460 [DAT](INFO): direction in FSK: [-0.641574,-0.761893,-0.088894] 2025-08-22T17:26:48.460Z,1755883608.460 [DAT](INFO): publishing transmit ping time 2025-08-22T17:26:48.463Z,1755883608.463 [DAT](INFO): publishing transmit ping time 2025-08-22T17:26:50.719Z,1755883610.719 [DAT](INFO): DAT read: Rx Time:17:26:51.3343 2025-08-22T17:26:50.719Z,1755883610.719 [DAT](INFO): Rx dataTimestamp_ set to:1755883610.718718 2025-08-22T17:26:51.227Z,1755883611.227 [DAT](INFO): DAT read: 17:26:51.3343 LVL= 26304, 18545, 23538, 28595, AGC= 53, IDX= 447, 0.03,-2.161,-0.375,-0.700,-1.059, PHS=-1.001, 0.728, 0.314, RAW= 283.3, -0.5, CAL= 282.3, -5.9, ROT= 227.7, 5.9 2025-08-22T17:26:51.228Z,1755883611.228 [DAT](INFO): got valid direction response: 17:26:51.3343 LVL= 26304, 18545, 23538, 28595, AGC= 53, IDX= 447, 0.03,-2.161,-0.375,-0.700,-1.059, PHS=-1.001, 0.728, 0.314, RAW= 283.3, -0.5, CAL= 282.3, -5.9, ROT= 227.7, 5.9 2025-08-22T17:26:51.229Z,1755883611.229 [DAT](INFO): DAT read: OK 2025-08-22T17:26:51.229Z,1755883611.229 [DAT](INFO): unknown deviceResponse_: OK 2025-08-22T17:26:51.230Z,1755883611.230 [DAT](INFO): #Rx 9: Read direction message, but no range. 2025-08-22T17:26:51.231Z,1755883611.231 [DAT](INFO): direction in FSK: [-0.669447,-0.735713,-0.102793] 2025-08-22T17:26:57.467Z,1755883617.467 [OnDock:StandardEnvelopes:A.AltitudeEnvelope](IMPORTANT): Altitude envelope ACTIVE. Altitude:0.100000m. At depth:74.180115m. 2025-08-22T17:27:12.435Z,1755883632.435 [OnDock:N.Docked](IMPORTANT): VEHICLE DETACHED FROM DOCK. 2025-08-22T17:27:12.436Z,1755883632.436 [OnDock:N.Docked] Stopped 2025-08-22T17:27:12.437Z,1755883632.437 [OnDock:Detach] Running Loop=1 2025-08-22T17:27:12.437Z,1755883632.437 [OnDock:Detach](DEBUG): Aggregate::initialize OnDock:Detach 2025-08-22T17:27:12.470Z,1755883632.470 [OnDock:Detach:A] Running Loop=1 2025-08-22T17:27:12.535Z,1755883632.535 [DockingStepper](INFO): Changing to mode: 2 2025-08-22T17:27:12.822Z,1755883632.822 [OnDock:Detach:A](IMPORTANT): Mission ending, breaking off. Range: 15.697304 m . 2025-08-22T17:27:12.822Z,1755883632.822 [OnDock:Detach:A] Stopped 2025-08-22T17:27:12.822Z,1755883632.822 [OnDock:Detach:B.Undock] Running Loop=1 2025-08-22T17:27:12.822Z,1755883632.822 [OnDock:Detach:B.Undock](DEBUG): Initialize. 2025-08-22T17:27:12.822Z,1755883632.822 [OnDock:Detach:B.Undock](DEBUG): Initializing internal variables to default values. 2025-08-22T17:27:12.907Z,1755883632.907 [DAT](INFO): DAT read: user:691> 2025-08-22T17:27:12.908Z,1755883632.908 [DAT](INFO): DAT read: Tx time:17:27:13.9320 2025-08-22T17:27:12.908Z,1755883632.908 [DAT](INFO): Ping request sent. 2025-08-22T17:27:12.908Z,1755883632.908 [DAT](INFO): transmitted an acoustic signal 2025-08-22T17:27:12.909Z,1755883632.909 [DAT](INFO): publishing transmit ping time 2025-08-22T17:27:13.203Z,1755883633.203 [OnDock:Detach:B.Undock](DEBUG): Initialize. 2025-08-22T17:27:13.203Z,1755883633.203 [OnDock:Detach:B.Undock](DEBUG): Initializing internal variables to default values. 2025-08-22T17:27:13.204Z,1755883633.204 [OnDock:Detach:B.Undock](INFO): Detaching from dock at depth 74.201286 m. 2025-08-22T17:27:13.787Z,1755883633.787 [DockingStepper](INFO): Changing to mode: 3 2025-08-22T17:27:15.427Z,1755883635.427 [DAT](INFO): DAT read: Rx Time:17:27:15.8352 2025-08-22T17:27:15.427Z,1755883635.427 [DAT](INFO): Rx dataTimestamp_ set to:1755883635.426692 2025-08-22T17:27:15.935Z,1755883635.935 [DAT](INFO): DAT read: 17:27:15.8352 LVL= 26160, 21841, 23474, 31667, AGC= 57, IDX= 458,-0.30,-1.279, 0.609, 0.038,-0.160, PHS=-1.016, 0.814, 0.155, RAW= 290.8, 0.6, CAL= 289.6, -4.3, ROT= 220.4, 4.3 2025-08-22T17:27:15.936Z,1755883635.936 [DAT](INFO): got valid direction response: 17:27:15.8352 LVL= 26160, 21841, 23474, 31667, AGC= 57, IDX= 458,-0.30,-1.279, 0.609, 0.038,-0.160, PHS=-1.016, 0.814, 0.155, RAW= 290.8, 0.6, CAL= 289.6, -4.3, ROT= 220.4, 4.3 2025-08-22T17:27:15.936Z,1755883635.936 [DAT](INFO): DAT read: OK 2025-08-22T17:27:15.936Z,1755883635.936 [DAT](INFO): unknown deviceResponse_: OK 2025-08-22T17:27:15.937Z,1755883635.937 [DAT](INFO): DAT read: user:692> 2025-08-22T17:27:15.938Z,1755883635.938 [DAT](INFO): DAT read: Tx time:17:27:16.9320 2025-08-22T17:27:15.938Z,1755883635.938 [DAT](INFO): Ping request sent. 2025-08-22T17:27:15.938Z,1755883635.938 [DAT](INFO): transmitted an acoustic signal 2025-08-22T17:27:15.938Z,1755883635.938 [DAT](INFO): #Rx 10: Read direction message, but no range. 2025-08-22T17:27:15.939Z,1755883635.939 [DAT](INFO): direction in FSK: [-0.759395,-0.646296,-0.074979] 2025-08-22T17:27:15.939Z,1755883635.939 [DAT](INFO): publishing transmit ping time 2025-08-22T17:27:15.942Z,1755883635.942 [DAT](INFO): publishing transmit ping time 2025-08-22T17:27:18.210Z,1755883638.210 [DAT](INFO): DAT read: Rx Time:17:27:18.8353 2025-08-22T17:27:18.210Z,1755883638.210 [DAT](INFO): Rx dataTimestamp_ set to:1755883638.209858 2025-08-22T17:27:18.443Z,1755883638.443 [OnDock:Detach:B.Undock](INFO): Engaging thruster for 10.0 seconds (max) to disengage. 2025-08-22T17:27:18.971Z,1755883638.971 [DAT](INFO): DAT read: 17:27:18.8353 LVL= 26192, 18225, 23330, 29427, AGC= 56, IDX= 443,-0.31,-0.967, 0.906, 0.351, 0.180, PHS=-1.045, 0.771, 0.128, RAW= 290.5, 1.9, CAL= 289.0, -3.0, ROT= 221.0, 3.0 2025-08-22T17:27:18.972Z,1755883638.972 [DAT](INFO): got valid direction response: 17:27:18.8353 LVL= 26192, 18225, 23330, 29427, AGC= 56, IDX= 443,-0.31,-0.967, 0.906, 0.351, 0.180, PHS=-1.045, 0.771, 0.128, RAW= 290.5, 1.9, CAL= 289.0, -3.0, ROT= 221.0, 3.0 2025-08-22T17:27:18.972Z,1755883638.972 [DAT](INFO): DAT read: OK 2025-08-22T17:27:18.972Z,1755883638.972 [DAT](INFO): unknown deviceResponse_: OK 2025-08-22T17:27:18.973Z,1755883638.973 [DAT](INFO): DAT read: user:693> 2025-08-22T17:27:18.974Z,1755883638.974 [DAT](INFO): DAT read: Tx time:17:27:19.9322 2025-08-22T17:27:18.974Z,1755883638.974 [DAT](INFO): Ping request sent. 2025-08-22T17:27:18.974Z,1755883638.974 [DAT](INFO): transmitted an acoustic signal 2025-08-22T17:27:18.974Z,1755883638.974 [DAT](INFO): #Rx 11: Read direction message, but no range. 2025-08-22T17:27:18.975Z,1755883638.975 [DAT](INFO): direction in FSK: [-0.753675,-0.655160,-0.052336] 2025-08-22T17:27:18.976Z,1755883638.976 [DAT](INFO): publishing transmit ping time 2025-08-22T17:27:18.979Z,1755883638.979 [DAT](INFO): publishing transmit ping time 2025-08-22T17:27:21.235Z,1755883641.235 [DAT](INFO): DAT read: Rx Time:17:27:21.8354 2025-08-22T17:27:21.235Z,1755883641.235 [DAT](INFO): Rx dataTimestamp_ set to:1755883641.235132 2025-08-22T17:27:28.553Z,1755883648.553 [OnDock:Detach:B.Undock](INFO): Thruster off. 2025-08-22T17:28:27.129Z,1755883707.129 [OnDock:Detach:B.Undock](INFO): Detected possible detachment. Starting timer. 2025-08-22T17:28:39.653Z,1755883719.653 [OnDock:J.](INFO): *** querying acoustic contact *** 2025-08-22T17:28:39.724Z,1755883719.724 [DAT](DEBUG): Acoustic response timeout 2025-08-22T17:28:39.725Z,1755883719.725 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-22T17:28:39.976Z,1755883719.976 [DAT](INFO): DAT read: 17:27:21.8354 LVL= 26720, 19313, 23074, 31075, AGC= 55, IDX= 443, 0.00, 1.262,-3694> 2025-08-22T17:28:39.977Z,1755883719.977 [DAT](INFO): unknown deviceResponse_: 17:27:21.8354 LVL= 26720, 19313, 23074, 31075, AGC= 55, IDX= 443, 0.00, 1.262,-3694> 2025-08-22T17:28:39.977Z,1755883719.977 [DAT](INFO): DAT read: Tx time:17:28:40.9825 2025-08-22T17:28:39.978Z,1755883719.978 [DAT](INFO): Ping request sent. 2025-08-22T17:28:39.978Z,1755883719.978 [DAT](INFO): transmitted an acoustic signal 2025-08-22T17:28:39.978Z,1755883719.978 [DAT](INFO): publishing transmit ping time 2025-08-22T17:28:39.978Z,1755883719.978 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000753 2025-08-22T17:28:40.226Z,1755883720.226 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.248786 2025-08-22T17:28:40.479Z,1755883720.479 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.500931 2025-08-22T17:28:40.733Z,1755883720.733 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754771 2025-08-22T17:28:40.982Z,1755883720.982 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.004760 2025-08-22T17:28:41.234Z,1755883721.234 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.256807 2025-08-22T17:28:41.487Z,1755883721.487 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.508853 2025-08-22T17:28:41.739Z,1755883721.739 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.761132 2025-08-22T17:28:41.991Z,1755883721.991 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.013383 2025-08-22T17:28:42.243Z,1755883722.243 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.264848 2025-08-22T17:28:42.495Z,1755883722.495 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.516917 2025-08-22T17:28:42.749Z,1755883722.749 [DAT](INFO): DAT read: Rx Time:17:28:43.4158 2025-08-22T17:28:42.751Z,1755883722.751 [DAT](INFO): Rx dataTimestamp_ set to:1755883722.749791 2025-08-22T17:28:42.752Z,1755883722.752 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.774427 2025-08-22T17:28:42.999Z,1755883722.999 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.020890 2025-08-22T17:28:43.250Z,1755883723.250 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.272799 2025-08-22T17:28:43.509Z,1755883723.509 [DAT](INFO): DAT read: 17:28:43.4158 LVL= 30048, 18561, 26610, 32147, AGC= 55, IDX= 231,-0.48, 0.285, 2.223, 1.457, 1.330, PHS=-0.943, 0.938, 0.083, RAW= 297.0, -1.0, CAL= 295.8, -5.4, ROT= 214.2, 5.4 2025-08-22T17:28:43.510Z,1755883723.510 [DAT](INFO): got valid direction response: 17:28:43.4158 LVL= 30048, 18561, 26610, 32147, AGC= 55, IDX= 231,-0.48, 0.285, 2.223, 1.457, 1.330, PHS=-0.943, 0.938, 0.083, RAW= 297.0, -1.0, CAL= 295.8, -5.4, ROT= 214.2, 5.4 2025-08-22T17:28:43.511Z,1755883723.511 [DAT](INFO): DAT read: Bearing 152.7, -38.5 (Local) 2025-08-22T17:28:43.512Z,1755883723.512 [DAT](INFO): Local bearing/azimuth received: Bearing 152.7, -38.5 (Local) 2025-08-22T17:28:43.513Z,1755883723.513 [DAT](INFO): DAT read: Range 11 to 50 : 21.8 m (Round-trip 29.1 ms) speed 0.0 m/s 2025-08-22T17:28:43.514Z,1755883723.514 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-22T17:28:43.515Z,1755883723.515 [DAT](INFO): direction in FSK: [-0.823410,-0.559589,-0.094108] 2025-08-22T17:28:43.515Z,1755883723.515 [DAT](INFO): publishing direction and range info 2025-08-22T17:28:47.334Z,1755883727.334 [OnDock:Detach:B.Undock](INFO): Detached at range: 21.80 m. Transitioning docking module to standby. 2025-08-22T17:28:47.810Z,1755883727.810 [DockingStepper](INFO): Changing to mode: 1 2025-08-22T17:28:53.387Z,1755883733.387 [OnDock:Detach:B.Undock](INFO): Docking module at standby. 2025-08-22T17:28:53.791Z,1755883733.791 [OnDock:Detach:B.Undock](IMPORTANT): Undocking sequence complete. 2025-08-22T17:28:53.791Z,1755883733.791 [OnDock:Detach:B.Undock] Stopped 2025-08-22T17:28:53.792Z,1755883733.792 [OnDock:Detach](INFO): Completed OnDock:Detach 2025-08-22T17:28:53.793Z,1755883733.793 [OnDock:Detach] Stopped 2025-08-22T17:28:53.793Z,1755883733.793 [OnDock:Detach](DEBUG): Aggregate::uninitialize OnDock:Detach 2025-08-22T17:28:53.799Z,1755883733.799 [OnDock](INFO): Completed OnDock 2025-08-22T17:28:53.799Z,1755883733.799 [MissionManager](INFO): OnDock is completed. 2025-08-22T17:28:53.799Z,1755883733.799 [MissionManager](INFO): Uninitializing Mission OnDock 2025-08-22T17:28:53.799Z,1755883733.799 [OnDock] Stopped 2025-08-22T17:28:53.799Z,1755883733.799 [OnDock](DEBUG): Aggregate::uninitialize OnDock 2025-08-22T17:28:53.799Z,1755883733.799 [OnDock:StandardEnvelopes] Stopped 2025-08-22T17:28:53.799Z,1755883733.799 [OnDock:StandardEnvelopes](DEBUG): Aggregate::uninitialize OnDock:StandardEnvelopes 2025-08-22T17:28:53.799Z,1755883733.799 [OnDock:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2025-08-22T17:28:53.799Z,1755883733.799 [OnDock:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2025-08-22T17:28:53.800Z,1755883733.800 [OnDock:StandardEnvelopes:B.DepthEnvelope] Stopped 2025-08-22T17:28:53.800Z,1755883733.800 [OnDock:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2025-08-22T17:28:53.800Z,1755883733.800 [OnDock:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2025-08-22T17:28:53.800Z,1755883733.800 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2025-08-22T17:28:53.800Z,1755883733.800 [OnDock:BackseatDriver] Stopped 2025-08-22T17:28:53.800Z,1755883733.800 [OnDock:BackseatDriver](DEBUG): Aggregate::uninitialize OnDock:BackseatDriver 2025-08-22T17:28:53.800Z,1755883733.800 [OnDock:BackseatDriver:A.BackseatDriver] Stopped 2025-08-22T17:28:53.800Z,1755883733.800 [OnDock:PowerOnly] Stopped 2025-08-22T17:28:53.800Z,1755883733.800 [OnDock:PowerOnly](DEBUG): Aggregate::uninitialize OnDock:PowerOnly 2025-08-22T17:28:53.801Z,1755883733.801 [OnDock:PowerOnly:E.Wait] Stopped 2025-08-22T17:28:53.801Z,1755883733.801 [OnDock:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2025-08-22T17:28:53.801Z,1755883733.801 [OnDock:dataRead] Stopped 2025-08-22T17:28:53.801Z,1755883733.801 [OnDock:dataRead](DEBUG): Aggregate::uninitialize OnDock:dataRead 2025-08-22T17:28:53.801Z,1755883733.801 [OnDock:dataRead:A] Stopped 2025-08-22T17:28:53.801Z,1755883733.801 [OnDock:J.] Stopped 2025-08-22T17:28:53.801Z,1755883733.801 [OnDock:J.](DEBUG): Uninitializing TrackAcousticContact. 2025-08-22T17:28:53.801Z,1755883733.801 [OnDock:PeriodicCheck] Stopped 2025-08-22T17:28:53.801Z,1755883733.801 [OnDock:PeriodicCheck](DEBUG): Aggregate::uninitialize OnDock:PeriodicCheck 2025-08-22T17:28:53.801Z,1755883733.801 [OnDock:PeriodicCheck:CheckIP] Stopped 2025-08-22T17:28:53.802Z,1755883733.802 [OnDock:PeriodicCheck:CheckIP](DEBUG): Aggregate::uninitialize OnDock:PeriodicCheck:CheckIP 2025-08-22T17:28:53.802Z,1755883733.802 [OnDock:PeriodicCheck:CheckIP:B.Wait] Stopped 2025-08-22T17:28:53.802Z,1755883733.802 [OnDock:PeriodicCheck:CheckIP:B.Wait](DEBUG): Uninitialize Wait Component. 2025-08-22T17:28:54.231Z,1755883734.231 [MissionManager](IMPORTANT): Started mission DefaultWithUndock 2025-08-22T17:28:54.231Z,1755883734.231 [DefaultWithUndock] Running Loop=1 2025-08-22T17:28:54.231Z,1755883734.231 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock 2025-08-22T17:28:54.231Z,1755883734.231 [DefaultWithUndock:A.Wait] Running Loop=1 2025-08-22T17:28:54.232Z,1755883734.232 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component. 2025-08-22T17:29:05.898Z,1755883745.898 [BPC1](ERROR): Battery stick #17 (s/n: 00C9) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-08-22T17:29:05.899Z,1755883745.899 [BPC1](ERROR): Battery stick #26 (s/n: 0112) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-08-22T17:29:05.899Z,1755883745.899 [BPC1](ERROR): Battery stick #27 (s/n: 00AF) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-08-22T17:29:05.900Z,1755883745.900 [BPC1](ERROR): Battery stick #44 (s/n: 00E4) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-08-22T17:29:05.901Z,1755883745.901 [BPC1](ERROR): Battery stick #58 (s/n: 00F3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-08-22T17:29:05.901Z,1755883745.901 [BPC1](INFO): Calculating totals. Valid battery stick count: 29. Valid reserve battery stick count: 3. 2025-08-22T17:29:05.904Z,1755883745.904 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle. 2025-08-22T17:29:24.523Z,1755883764.523 [DefaultWithUndock:A.Wait](INFO): Done Waiting. 2025-08-22T17:29:24.523Z,1755883764.523 [DefaultWithUndock:A.Wait] Stopped 2025-08-22T17:29:24.523Z,1755883764.523 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component. 2025-08-22T17:29:24.523Z,1755883764.523 [DefaultWithUndock:LeaveDock] Running Loop=1 2025-08-22T17:29:24.524Z,1755883764.524 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::initialize DefaultWithUndock:LeaveDock 2025-08-22T17:29:24.524Z,1755883764.524 [DefaultWithUndock:LeaveDock:A.] Running Loop=1 2025-08-22T17:29:24.524Z,1755883764.524 [DefaultWithUndock:LeaveDock:A.](INFO): Initializing TrackAcousticContact. 2025-08-22T17:29:24.524Z,1755883764.524 [DefaultWithUndock:LeaveDock:B.Undock] Running Loop=1 2025-08-22T17:29:24.524Z,1755883764.524 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize. 2025-08-22T17:29:24.524Z,1755883764.524 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values. 2025-08-22T17:29:24.906Z,1755883764.907 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize. 2025-08-22T17:29:24.907Z,1755883764.907 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values. 2025-08-22T17:29:24.907Z,1755883764.907 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached from dock at depth 73.606377 m. 2025-08-22T17:29:24.909Z,1755883764.909 [DefaultWithUndock:LeaveDock:A.](ERROR): Failed to read sound speed 2025-08-22T17:29:25.740Z,1755883765.740 [DockingStepper](DEBUG): Initializing EZServoServo. 2025-08-22T17:29:25.858Z,1755883765.858 [DockingStepper](DEBUG): Initializing DockingStepper. 2025-08-22T17:29:28.271Z,1755883768.271 [CTD_Seabird](ERROR): Failed to parse device response: 2025-08-22T17:29:29.395Z,1755883769.395 [DockingStepper](INFO): Changing to mode: 3 2025-08-22T17:29:34.635Z,1755883774.635 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2025-08-22T17:29:34.707Z,1755883774.707 [DAT](DEBUG): Acoustic response timeout 2025-08-22T17:29:34.707Z,1755883774.707 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-22T17:29:34.958Z,1755883774.958 [DAT](INFO): DAT read: user:695> 2025-08-22T17:29:34.959Z,1755883774.959 [DAT](INFO): DAT read: Tx time:17:29:35.9822 2025-08-22T17:29:34.959Z,1755883774.959 [DAT](INFO): Ping request sent. 2025-08-22T17:29:34.959Z,1755883774.959 [DAT](INFO): transmitted an acoustic signal 2025-08-22T17:29:34.960Z,1755883774.960 [DAT](INFO): publishing transmit ping time 2025-08-22T17:29:34.960Z,1755883774.960 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000735 2025-08-22T17:29:35.211Z,1755883775.211 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250999 2025-08-22T17:29:35.408Z,1755883775.408 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detected possible detachment. Starting timer. 2025-08-22T17:29:35.464Z,1755883775.464 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.504653 2025-08-22T17:29:35.714Z,1755883775.714 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754965 2025-08-22T17:29:35.971Z,1755883775.971 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.011083 2025-08-22T17:29:36.223Z,1755883776.223 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.263019 2025-08-22T17:29:36.475Z,1755883776.475 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.515182 2025-08-22T17:29:36.727Z,1755883776.727 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.767256 2025-08-22T17:29:36.978Z,1755883776.978 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.018933 2025-08-22T17:29:37.231Z,1755883777.231 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.271326 2025-08-22T17:29:37.483Z,1755883777.483 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.523467 2025-08-22T17:29:37.735Z,1755883777.735 [DAT](INFO): DAT read: Rx Time:17:29:38.4184 2025-08-22T17:29:37.735Z,1755883777.735 [DAT](INFO): Rx dataTimestamp_ set to:1755883777.734738 2025-08-22T17:29:37.736Z,1755883777.736 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.776051 2025-08-22T17:29:37.986Z,1755883777.986 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.026957 2025-08-22T17:29:38.239Z,1755883778.239 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.279096 2025-08-22T17:29:38.496Z,1755883778.496 [DAT](INFO): DAT read: 17:29:38.4184 LVL= 31904, 19057, 21266, 32755, AGC= 52, IDX= 469, 0.33,-0.241, 1.750, 1.175, 1.029, PHS=-1.168, 0.766, 0.102, RAW= 289.8, 3.6, CAL= 288.1, -1.3, ROT= 221.9, 1.3 2025-08-22T17:29:38.497Z,1755883778.497 [DAT](INFO): got valid direction response: 17:29:38.4184 LVL= 31904, 19057, 21266, 32755, AGC= 52, IDX= 469, 0.33,-0.241, 1.750, 1.175, 1.029, PHS=-1.168, 0.766, 0.102, RAW= 289.8, 3.6, CAL= 288.1, -1.3, ROT= 221.9, 1.3 2025-08-22T17:29:38.498Z,1755883778.498 [DAT](INFO): DAT read: Bearing 132.4, -47.5 (Local) 2025-08-22T17:29:38.498Z,1755883778.498 [DAT](INFO): Local bearing/azimuth received: Bearing 132.4, -47.5 (Local) 2025-08-22T17:29:38.500Z,1755883778.500 [DAT](INFO): DAT read: Range 11 to 50 : 24.0 m (Round-trip 32.1 ms) speed -0.1 m/s 2025-08-22T17:29:38.500Z,1755883778.500 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-22T17:29:38.501Z,1755883778.501 [DAT](INFO): direction in FSK: [-0.744120,-0.667661,-0.022687] 2025-08-22T17:29:38.501Z,1755883778.501 [DAT](INFO): publishing direction and range info 2025-08-22T17:29:44.744Z,1755883784.744 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2025-08-22T17:29:44.807Z,1755883784.807 [DAT](INFO): ****** received valid address query ****** 2025-08-22T17:29:44.807Z,1755883784.807 [DAT](INFO): ****** received valid ping request ****** 2025-08-22T17:29:44.807Z,1755883784.807 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-22T17:29:45.058Z,1755883785.058 [DAT](INFO): DAT read: user:696> 2025-08-22T17:29:45.059Z,1755883785.059 [DAT](INFO): DAT read: Tx time:17:29:46.0837 2025-08-22T17:29:45.059Z,1755883785.059 [DAT](INFO): Ping request sent. 2025-08-22T17:29:45.060Z,1755883785.060 [DAT](INFO): transmitted an acoustic signal 2025-08-22T17:29:45.060Z,1755883785.060 [DAT](INFO): publishing transmit ping time 2025-08-22T17:29:45.060Z,1755883785.060 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000728 2025-08-22T17:29:45.310Z,1755883785.310 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250831 2025-08-22T17:29:45.563Z,1755883785.563 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502979 2025-08-22T17:29:45.815Z,1755883785.815 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754896 2025-08-22T17:29:46.066Z,1755883786.066 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006822 2025-08-22T17:29:46.327Z,1755883786.327 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.266910 2025-08-22T17:29:46.579Z,1755883786.579 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.518901 2025-08-22T17:29:46.831Z,1755883786.831 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.771312 2025-08-22T17:29:47.082Z,1755883787.082 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.022862 2025-08-22T17:29:47.335Z,1755883787.335 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.274891 2025-08-22T17:29:47.587Z,1755883787.587 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.527251 2025-08-22T17:29:47.839Z,1755883787.839 [DAT](INFO): DAT read: Rx Time:17:29:48.5207 2025-08-22T17:29:47.839Z,1755883787.839 [DAT](INFO): Rx dataTimestamp_ set to:1755883787.838980 2025-08-22T17:29:47.840Z,1755883787.840 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.780175 2025-08-22T17:29:48.091Z,1755883788.091 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.030893 2025-08-22T17:29:48.344Z,1755883788.344 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.284750 2025-08-22T17:29:48.600Z,1755883788.600 [DAT](INFO): DAT read: 17:29:48.5207 LVL= 31680, 18513, 24290, 32755, AGC= 54, IDX= 430,-0.41,-0.913, 1.032, 0.453, 0.299, PHS=-1.110, 0.778, 0.110, RAW= 290.4, 2.7, CAL= 288.9, -2.2, ROT= 221.1, 2.2 2025-08-22T17:29:48.601Z,1755883788.601 [DAT](INFO): got valid direction response: 17:29:48.5207 LVL= 31680, 18513, 24290, 32755, AGC= 54, IDX= 430,-0.41,-0.913, 1.032, 0.453, 0.299, PHS=-1.110, 0.778, 0.110, RAW= 290.4, 2.7, CAL= 288.9, -2.2, ROT= 221.1, 2.2 2025-08-22T17:29:48.602Z,1755883788.602 [DAT](INFO): DAT read: Bearing 137.1, -41.2 (Local) 2025-08-22T17:29:48.602Z,1755883788.602 [DAT](INFO): Local bearing/azimuth received: Bearing 137.1, -41.2 (Local) 2025-08-22T17:29:48.603Z,1755883788.603 [DAT](INFO): DAT read: Range 11 to 50 : 24.6 m (Round-trip 32.9 ms) speed -0.1 m/s 2025-08-22T17:29:48.604Z,1755883788.604 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-22T17:29:48.605Z,1755883788.605 [DAT](INFO): direction in FSK: [-0.753008,-0.656891,-0.038388] 2025-08-22T17:29:48.605Z,1755883788.605 [DAT](INFO): publishing direction and range info 2025-08-22T17:29:54.805Z,1755883794.805 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2025-08-22T17:29:54.898Z,1755883794.898 [DAT](INFO): ****** received valid address query ****** 2025-08-22T17:29:54.899Z,1755883794.899 [DAT](INFO): ****** received valid ping request ****** 2025-08-22T17:29:54.899Z,1755883794.899 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-22T17:29:55.150Z,1755883795.150 [DAT](INFO): DAT read: user:697> 2025-08-22T17:29:55.151Z,1755883795.151 [DAT](INFO): DAT read: Tx time:17:29:56.1821 2025-08-22T17:29:55.152Z,1755883795.152 [DAT](INFO): Ping request sent. 2025-08-22T17:29:55.152Z,1755883795.152 [DAT](INFO): transmitted an acoustic signal 2025-08-22T17:29:55.152Z,1755883795.152 [DAT](INFO): publishing transmit ping time 2025-08-22T17:29:55.153Z,1755883795.153 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.001012 2025-08-22T17:29:55.403Z,1755883795.403 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250428 2025-08-22T17:29:55.610Z,1755883795.610 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached at range: 24.60 m. Transitioning docking module to standby. 2025-08-22T17:29:55.655Z,1755883795.655 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.503267 2025-08-22T17:29:55.906Z,1755883795.906 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754408 2025-08-22T17:29:56.034Z,1755883796.034 [DockingStepper](INFO): Changing to mode: 1 2025-08-22T17:29:56.159Z,1755883796.159 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006665 2025-08-22T17:29:56.410Z,1755883796.410 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258424 2025-08-22T17:29:56.662Z,1755883796.662 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510371 2025-08-22T17:29:56.917Z,1755883796.917 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.764363 2025-08-22T17:29:57.167Z,1755883797.167 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014802 2025-08-22T17:29:57.419Z,1755883797.419 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266442 2025-08-22T17:29:57.672Z,1755883797.672 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.519932 2025-08-22T17:29:57.923Z,1755883797.923 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770541 2025-08-22T17:29:58.175Z,1755883798.175 [DAT](INFO): DAT read: Rx Time:17:29:58.6200 2025-08-22T17:29:58.176Z,1755883798.176 [DAT](INFO): Rx dataTimestamp_ set to:1755883798.175400 2025-08-22T17:29:58.177Z,1755883798.177 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.024425 2025-08-22T17:29:58.426Z,1755883798.426 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274396 2025-08-22T17:29:58.684Z,1755883798.684 [DAT](INFO): DAT read: 17:29:58.6200 LVL= 32752, 20161, 24050, 32755, AGC= 52, IDX= 438, 0.48, 2.680,-1.647,-2.270,-2.441, PHS=-1.060, 0.839, 0.127, RAW= 291.8, 1.1, CAL= 290.4, -3.7, ROT= 219.6, 3.7 2025-08-22T17:29:58.685Z,1755883798.685 [DAT](INFO): got valid direction response: 17:29:58.6200 LVL= 32752, 20161, 24050, 32755, AGC= 52, IDX= 438, 0.48, 2.680,-1.647,-2.270,-2.441, PHS=-1.060, 0.839, 0.127, RAW= 291.8, 1.1, CAL= 290.4, -3.7, ROT= 219.6, 3.7 2025-08-22T17:29:58.686Z,1755883798.686 [DAT](INFO): DAT read: Bearing 141.6, -39.6 (Local) 2025-08-22T17:29:58.686Z,1755883798.686 [DAT](INFO): Local bearing/azimuth received: Bearing 141.6, -39.6 (Local) 2025-08-22T17:29:58.687Z,1755883798.687 [DAT](INFO): DAT read: Range 11 to 50 : 25.2 m (Round-trip 33.7 ms) speed 0.0 m/s 2025-08-22T17:29:58.688Z,1755883798.688 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-22T17:29:58.689Z,1755883798.689 [DAT](INFO): direction in FSK: [-0.768907,-0.636095,-0.064532] 2025-08-22T17:29:58.689Z,1755883798.689 [DAT](INFO): publishing direction and range info 2025-08-22T17:30:02.094Z,1755883802.094 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Docking module at standby. 2025-08-22T17:30:02.494Z,1755883802.494 [DefaultWithUndock:LeaveDock:B.Undock](IMPORTANT): Undocking sequence complete. 2025-08-22T17:30:02.495Z,1755883802.495 [DefaultWithUndock:LeaveDock:B.Undock] Stopped 2025-08-22T17:30:02.495Z,1755883802.495 [DefaultWithUndock:LeaveDock](INFO): Completed DefaultWithUndock:LeaveDock 2025-08-22T17:30:02.495Z,1755883802.495 [DefaultWithUndock:LeaveDock] Stopped 2025-08-22T17:30:02.495Z,1755883802.495 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::uninitialize DefaultWithUndock:LeaveDock 2025-08-22T17:30:02.495Z,1755883802.495 [DefaultWithUndock:LeaveDock:A.] Stopped 2025-08-22T17:30:02.495Z,1755883802.495 [DefaultWithUndock:LeaveDock:A.](DEBUG): Uninitializing TrackAcousticContact. 2025-08-22T17:30:02.496Z,1755883802.496 [DefaultWithUndock:SurfaceDefault] Running Loop=1 2025-08-22T17:30:02.496Z,1755883802.496 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault 2025-08-22T17:30:02.496Z,1755883802.496 [DefaultWithUndock:SurfaceDefault:A.GoToSurface] Running Loop=1 2025-08-22T17:30:02.496Z,1755883802.496 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-08-22T17:30:02.496Z,1755883802.496 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-08-22T17:30:02.497Z,1755883802.497 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-08-22T17:30:02.497Z,1755883802.497 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-08-22T17:30:02.497Z,1755883802.497 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-08-22T17:30:02.498Z,1755883802.498 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-08-22T17:30:02.498Z,1755883802.498 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-08-22T17:30:02.878Z,1755883802.878 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.29 2025-08-22T17:30:03.299Z,1755883803.299 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.29 2025-08-22T17:30:03.502Z,1755883803.502 [Radio_Surface](INFO): Powering up 2025-08-22T17:30:03.692Z,1755883803.692 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.29 2025-08-22T17:30:04.114Z,1755883804.114 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.29 2025-08-22T17:30:04.493Z,1755883804.493 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.29 2025-08-22T17:30:04.896Z,1755883804.896 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.29 2025-08-22T17:30:05.322Z,1755883805.322 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.29 2025-08-22T17:30:05.711Z,1755883805.711 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.29 2025-08-22T17:30:06.149Z,1755883806.149 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.27 2025-08-22T17:30:06.515Z,1755883806.515 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.27 2025-08-22T17:30:06.928Z,1755883806.928 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.27 2025-08-22T17:30:07.351Z,1755883807.351 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.25 2025-08-22T17:30:07.726Z,1755883807.726 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.25 2025-08-22T17:30:08.164Z,1755883808.164 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.23 2025-08-22T17:30:08.548Z,1755883808.548 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.23 2025-08-22T17:30:08.754Z,1755883808.754 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.11 2025-08-22T17:30:08.754Z,1755883808.754 [Radio_Surface](INFO): No local IP found 2025-08-22T17:30:08.942Z,1755883808.942 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.23 2025-08-22T17:30:09.379Z,1755883809.379 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.23 2025-08-22T17:30:09.746Z,1755883809.746 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.23 2025-08-22T17:30:10.174Z,1755883810.174 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.24 2025-08-22T17:30:10.551Z,1755883810.551 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.24 2025-08-22T17:30:10.954Z,1755883810.954 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.24 2025-08-22T17:30:11.389Z,1755883811.389 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.24 2025-08-22T17:30:11.777Z,1755883811.777 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.24 2025-08-22T17:30:12.194Z,1755883812.194 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.26 2025-08-22T17:30:12.577Z,1755883812.577 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.26 2025-08-22T17:30:12.983Z,1755883812.983 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.26 2025-08-22T17:30:13.406Z,1755883813.406 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.26 2025-08-22T17:30:13.798Z,1755883813.798 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.26 2025-08-22T17:30:14.223Z,1755883814.223 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.29 2025-08-22T17:30:14.596Z,1755883814.596 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.29 2025-08-22T17:30:15.001Z,1755883815.001 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.29 2025-08-22T17:30:15.430Z,1755883815.430 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.31 2025-08-22T17:30:15.802Z,1755883815.802 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.31 2025-08-22T17:30:16.234Z,1755883816.234 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.32 2025-08-22T17:30:16.617Z,1755883816.617 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.32 2025-08-22T17:30:17.031Z,1755883817.031 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.32 2025-08-22T17:30:17.452Z,1755883817.452 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.34 2025-08-22T17:30:17.823Z,1755883817.823 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.34 2025-08-22T17:30:18.260Z,1755883818.260 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.35 2025-08-22T17:30:18.630Z,1755883818.630 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.35 2025-08-22T17:30:19.043Z,1755883819.043 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.35 2025-08-22T17:30:19.457Z,1755883819.457 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.38 2025-08-22T17:30:19.848Z,1755883819.848 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.38 2025-08-22T17:30:20.289Z,1755883820.289 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.39 2025-08-22T17:30:20.654Z,1755883820.654 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.39 2025-08-22T17:30:21.055Z,1755883821.055 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.39 2025-08-22T17:30:21.482Z,1755883821.483 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.40 2025-08-22T17:30:21.862Z,1755883821.862 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.40 2025-08-22T17:30:22.317Z,1755883822.317 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.38 2025-08-22T17:30:22.672Z,1755883822.672 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.38 2025-08-22T17:30:23.118Z,1755883823.118 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.38 2025-08-22T17:30:23.501Z,1755883823.501 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.37 2025-08-22T17:30:23.882Z,1755883823.882 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.37 2025-08-22T17:30:24.346Z,1755883824.346 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.34 2025-08-22T17:30:24.696Z,1755883824.696 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.34 2025-08-22T17:30:25.094Z,1755883825.094 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.34 2025-08-22T17:30:25.536Z,1755883825.536 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.31 2025-08-22T17:30:25.907Z,1755883825.907 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.31 2025-08-22T17:30:26.373Z,1755883826.373 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.28 2025-08-22T17:30:26.713Z,1755883826.713 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.28 2025-08-22T17:30:27.113Z,1755883827.113 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.28 2025-08-22T17:30:27.538Z,1755883827.538 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.23 2025-08-22T17:30:27.931Z,1755883827.931 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.23 2025-08-22T17:30:28.375Z,1755883828.375 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.23 2025-08-22T17:30:28.772Z,1755883828.772 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.18 2025-08-22T17:30:29.136Z,1755883829.136 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.18 2025-08-22T17:30:29.563Z,1755883829.563 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.13 2025-08-22T17:30:29.943Z,1755883829.943 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.13 2025-08-22T17:30:30.406Z,1755883830.406 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.13 2025-08-22T17:30:30.769Z,1755883830.769 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.09 2025-08-22T17:30:31.164Z,1755883831.164 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.09 2025-08-22T17:30:31.587Z,1755883831.587 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.06 2025-08-22T17:30:31.962Z,1755883831.962 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.06 2025-08-22T17:30:32.433Z,1755883832.433 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.06 2025-08-22T17:30:32.803Z,1755883832.803 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.02 2025-08-22T17:30:33.182Z,1755883833.182 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.02 2025-08-22T17:30:33.605Z,1755883833.605 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 0.99 2025-08-22T17:30:34.002Z,1755883834.002 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 0.99 2025-08-22T17:30:34.398Z,1755883834.398 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 0.99 2025-08-22T17:30:34.816Z,1755883834.816 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 0.98 2025-08-22T17:30:35.204Z,1755883835.204 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 0.98 2025-08-22T17:30:35.627Z,1755883835.627 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 0.97 2025-08-22T17:30:36.002Z,1755883836.002 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 0.97 2025-08-22T17:30:36.411Z,1755883836.411 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 0.97 2025-08-22T17:30:36.837Z,1755883836.837 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 0.98 2025-08-22T17:30:37.214Z,1755883837.214 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 0.98 2025-08-22T17:30:37.652Z,1755883837.652 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 0.98 2025-08-22T17:30:38.031Z,1755883838.031 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 0.98 2025-08-22T17:30:38.433Z,1755883838.433 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 0.98 2025-08-22T17:30:38.867Z,1755883838.867 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 0.98 2025-08-22T17:30:39.235Z,1755883839.235 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 0.98 2025-08-22T17:30:39.662Z,1755883839.662 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.01 2025-08-22T17:30:40.063Z,1755883840.063 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.01 2025-08-22T17:30:40.446Z,1755883840.446 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.01 2025-08-22T17:30:40.875Z,1755883840.875 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.03 2025-08-22T17:30:41.254Z,1755883841.254 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.03 2025-08-22T17:30:41.700Z,1755883841.700 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.08 2025-08-22T17:30:42.066Z,1755883842.066 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.08 2025-08-22T17:30:42.477Z,1755883842.477 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.08 2025-08-22T17:30:42.902Z,1755883842.902 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.17 2025-08-22T17:30:43.274Z,1755883843.274 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.17 2025-08-22T17:30:43.698Z,1755883843.698 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.23 2025-08-22T17:30:44.093Z,1755883844.093 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.23 2025-08-22T17:30:44.486Z,1755883844.486 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.23 2025-08-22T17:30:44.909Z,1755883844.909 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.24 2025-08-22T17:30:45.296Z,1755883845.296 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.24 2025-08-22T17:30:45.723Z,1755883845.723 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.32 2025-08-22T17:30:46.107Z,1755883846.107 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.32 2025-08-22T17:30:46.506Z,1755883846.506 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.32 2025-08-22T17:30:46.940Z,1755883846.940 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.29 2025-08-22T17:30:47.329Z,1755883847.329 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.29 2025-08-22T17:30:47.751Z,1755883847.751 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.37 2025-08-22T17:30:48.140Z,1755883848.140 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.37 2025-08-22T17:30:48.531Z,1755883848.531 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](ERROR): Altitude too low: 1.37 2025-08-22T17:31:18.428Z,1755883878.428 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD, +61.92,3 -8.15, 7.1 2025-08-22T17:31:23.272Z,1755883883.272 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for beam range. Device response is::RA, 0.00, 119.13, 79.00 2025-08-22T17:32:30.402Z,1755883950.402 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-22T17:32:31.406Z,1755883951.406 [DataOverHttps](INFO): Radio surface powered ON. 2025-08-22T17:32:31.406Z,1755883951.406 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T17:33:01.528Z,1755883981.528 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-22T17:33:02.530Z,1755883982.530 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T17:33:32.180Z,1755884012.180 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=1 2025-08-22T17:33:32.180Z,1755884012.180 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-08-22T17:33:32.180Z,1755884012.180 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2025-08-22T17:33:32.650Z,1755884012.650 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-22T17:33:33.654Z,1755884013.654 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T17:33:49.508Z,1755884029.508 [NAL9602](INFO): SBD MO Status=0, MOMSN=981, MT Status=0, MTMSN=0 2025-08-22T17:33:49.508Z,1755884029.508 [NAL9602](INFO): No messages in MT queue 2025-08-22T17:33:50.725Z,1755884030.725 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173350.00,A,3646.71716,N,12151.65034,W,2.138,285.61,220825,,,A*73 2025-08-22T17:33:50.727Z,1755884030.727 [NAL9602](INFO): GPS fix at 20250822T173350: (36.778619, -121.860839) 2025-08-22T17:33:50.742Z,1755884030.742 [UniversalFixResidualReporter](INFO): Fix residual: 1.7 %DT, over the last 1640.2 m. Residual distance 27.1 m at bearing -17.8 degrees. Fix at (36.7786, -121.8608) with 109.5 m made good. 2025-08-22T17:33:50.743Z,1755884030.743 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2025-08-22T17:33:50.743Z,1755884030.743 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2025-08-22T17:34:03.774Z,1755884043.774 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-22T17:34:04.778Z,1755884044.778 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T17:34:11.138Z,1755884051.138 [NAL9602](INFO): SBD MO Status=1, MOMSN=982, MT Status=0, MTMSN=0 2025-08-22T17:34:11.186Z,1755884051.186 [NAL9602](INFO): Sent 73 bytes from file Logs/20250822T160302/Courier0018.lzma 2025-08-22T17:34:11.186Z,1755884051.186 [NAL9602](INFO): Packets left to send: 0 2025-08-22T17:34:31.872Z,1755884071.872 [NAL9602](INFO): SBD MO Status=1, MOMSN=983, MT Status=0, MTMSN=0 2025-08-22T17:34:31.925Z,1755884071.925 [NAL9602](INFO): Sent 151 bytes from file Logs/20250822T163240/Courier0000.lzma 2025-08-22T17:34:31.925Z,1755884071.925 [NAL9602](INFO): Packets left to send: 0 2025-08-22T17:34:34.918Z,1755884074.918 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-22T17:34:35.922Z,1755884075.922 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T17:34:43.654Z,1755884083.654 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003322 2025-08-22T17:34:47.396Z,1755884087.396 [NAL9602](INFO): SBD MO Status=2, MOMSN=984, MT Status=2, MTMSN=0 2025-08-22T17:34:47.396Z,1755884087.396 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-22T17:34:57.409Z,1755884097.409 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250822T163240/Courier0006.lzma 2025-08-22T17:34:58.412Z,1755884098.412 [DataOverHttps](INFO): Moved sent file to Logs/20250822T163240/Courier0006.lzma.bak 2025-08-22T17:34:58.412Z,1755884098.412 [DataOverHttps](INFO): SBD MOMSN=25882196 2025-08-22T17:35:14.673Z,1755884114.673 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250822T163240/Courier0009.lzma 2025-08-22T17:35:15.675Z,1755884115.675 [DataOverHttps](INFO): Moved sent file to Logs/20250822T163240/Courier0009.lzma.bak 2025-08-22T17:35:15.676Z,1755884115.676 [DataOverHttps](INFO): SBD MOMSN=25882202 2025-08-22T17:35:18.097Z,1755884118.097 [NAL9602](INFO): Not Powering down - fast GPS 2025-08-22T17:35:32.121Z,1755884132.121 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250822T163240/Courier0012.lzma 2025-08-22T17:35:33.123Z,1755884133.123 [DataOverHttps](INFO): Moved sent file to Logs/20250822T163240/Courier0012.lzma.bak 2025-08-22T17:35:33.124Z,1755884133.124 [DataOverHttps](INFO): SBD MOMSN=25882204 2025-08-22T17:35:49.565Z,1755884149.565 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250822T163240/Courier0015.lzma 2025-08-22T17:35:50.567Z,1755884150.567 [DataOverHttps](INFO): Moved sent file to Logs/20250822T163240/Courier0015.lzma.bak 2025-08-22T17:35:50.568Z,1755884150.568 [DataOverHttps](INFO): SBD MOMSN=25882208 2025-08-22T17:36:09.065Z,1755884169.065 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250822T163240/Courier0018.lzma 2025-08-22T17:36:10.067Z,1755884170.067 [DataOverHttps](INFO): Moved sent file to Logs/20250822T163240/Courier0018.lzma.bak 2025-08-22T17:36:10.068Z,1755884170.068 [DataOverHttps](INFO): SBD MOMSN=25882210 2025-08-22T17:36:27.405Z,1755884187.405 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250822T163240/Courier0021.lzma 2025-08-22T17:36:28.410Z,1755884188.410 [DataOverHttps](INFO): Moved sent file to Logs/20250822T163240/Courier0021.lzma.bak 2025-08-22T17:36:28.410Z,1755884188.410 [DataOverHttps](INFO): SBD MOMSN=25882212 2025-08-22T17:36:44.841Z,1755884204.841 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250822T163240/Courier0024.lzma 2025-08-22T17:36:45.860Z,1755884205.860 [DataOverHttps](INFO): Moved sent file to Logs/20250822T163240/Courier0024.lzma.bak 2025-08-22T17:36:45.860Z,1755884205.860 [DataOverHttps](INFO): SBD MOMSN=25882214 2025-08-22T17:37:02.530Z,1755884222.530 [DataOverHttps](INFO): Sending 367 bytes from file Logs/20250822T172641/Courier0000.lzma 2025-08-22T17:37:03.531Z,1755884223.531 [DataOverHttps](INFO): Moved sent file to Logs/20250822T172641/Courier0000.lzma.bak 2025-08-22T17:37:03.531Z,1755884223.531 [DataOverHttps](INFO): SBD MOMSN=25882216 2025-08-22T17:37:19.899Z,1755884239.899 [DataOverHttps](INFO): Sending 191 bytes from file Logs/20250822T160302/Express0019.lzma 2025-08-22T17:37:20.899Z,1755884240.899 [DataOverHttps](INFO): Moved sent file to Logs/20250822T160302/Express0019.lzma.bak 2025-08-22T17:37:20.900Z,1755884240.900 [DataOverHttps](INFO): SBD MOMSN=25882220 2025-08-22T17:37:37.864Z,1755884257.864 [DataOverHttps](INFO): Sending 656 bytes from file Logs/20250822T163240/Express0001.lzma 2025-08-22T17:37:38.863Z,1755884258.863 [DataOverHttps](INFO): Moved sent file to Logs/20250822T163240/Express0001.lzma.bak 2025-08-22T17:37:38.864Z,1755884258.864 [DataOverHttps](INFO): SBD MOMSN=25882223 2025-08-22T17:37:55.291Z,1755884275.291 [DataOverHttps](INFO): Sending 79 bytes from file Logs/20250822T163240/Express0004.lzma 2025-08-22T17:37:56.291Z,1755884276.291 [DataOverHttps](INFO): Moved sent file to Logs/20250822T163240/Express0004.lzma.bak 2025-08-22T17:37:56.292Z,1755884276.292 [DataOverHttps](INFO): SBD MOMSN=25882230 2025-08-22T17:38:13.640Z,1755884293.640 [DataOverHttps](INFO): Sending 497 bytes from file Logs/20250822T163240/Express0007.lzma 2025-08-22T17:38:14.639Z,1755884294.639 [DataOverHttps](INFO): Moved sent file to Logs/20250822T163240/Express0007.lzma.bak 2025-08-22T17:38:14.640Z,1755884294.640 [DataOverHttps](INFO): SBD MOMSN=25882234 2025-08-22T17:38:31.634Z,1755884311.634 [DataOverHttps](INFO): Sending 127 bytes from file Logs/20250822T163240/Express0010.lzma 2025-08-22T17:38:32.636Z,1755884312.636 [DataOverHttps](INFO): Moved sent file to Logs/20250822T163240/Express0010.lzma.bak 2025-08-22T17:38:32.636Z,1755884312.636 [DataOverHttps](INFO): SBD MOMSN=25882246 2025-08-22T17:38:48.810Z,1755884328.810 [DataOverHttps](INFO): Sending 355 bytes from file Logs/20250822T163240/Express0013.lzma 2025-08-22T17:38:49.811Z,1755884329.811 [DataOverHttps](INFO): Moved sent file to Logs/20250822T163240/Express0013.lzma.bak 2025-08-22T17:38:49.812Z,1755884329.812 [DataOverHttps](INFO): SBD MOMSN=25882252 2025-08-22T17:39:06.970Z,1755884346.970 [DataOverHttps](INFO): Sending 148 bytes from file Logs/20250822T163240/Express0016.lzma 2025-08-22T17:39:07.975Z,1755884347.975 [DataOverHttps](INFO): Moved sent file to Logs/20250822T163240/Express0016.lzma.bak 2025-08-22T17:39:07.976Z,1755884347.976 [DataOverHttps](INFO): SBD MOMSN=25882256 2025-08-22T17:39:24.284Z,1755884364.284 [DataOverHttps](INFO): Sending 478 bytes from file Logs/20250822T163240/Express0019.lzma 2025-08-22T17:39:25.283Z,1755884365.283 [DataOverHttps](INFO): Moved sent file to Logs/20250822T163240/Express0019.lzma.bak 2025-08-22T17:39:25.284Z,1755884365.284 [DataOverHttps](INFO): SBD MOMSN=25882258 2025-08-22T17:39:42.407Z,1755884382.407 [DataOverHttps](INFO): Sending 536 bytes from file Logs/20250822T163240/Express0022.lzma 2025-08-22T17:39:43.407Z,1755884383.407 [DataOverHttps](INFO): Moved sent file to Logs/20250822T163240/Express0022.lzma.bak 2025-08-22T17:39:43.408Z,1755884383.408 [DataOverHttps](INFO): SBD MOMSN=25882266 2025-08-22T17:39:59.786Z,1755884399.786 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20250822T163240/Express0025.lzma 2025-08-22T17:40:00.787Z,1755884400.787 [DataOverHttps](INFO): Moved sent file to Logs/20250822T163240/Express0025.lzma.bak 2025-08-22T17:40:00.788Z,1755884400.788 [DataOverHttps](INFO): SBD MOMSN=25882282 2025-08-22T17:40:17.297Z,1755884417.297 [DataOverHttps](INFO): Sending 1373 bytes from file Logs/20250822T172641/Express0001.lzma 2025-08-22T17:40:18.295Z,1755884418.295 [DataOverHttps](INFO): Moved sent file to Logs/20250822T172641/Express0001.lzma.bak 2025-08-22T17:40:18.296Z,1755884418.296 [DataOverHttps](INFO): SBD MOMSN=25882286 2025-08-22T17:40:19.585Z,1755884419.585 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2025-08-22T17:40:19.585Z,1755884419.585 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2025-08-22T17:40:19.585Z,1755884419.585 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-08-22T17:40:28.894Z,1755884428.894 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T17:40:37.570Z,1755884437.570 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T17:40:46.186Z,1755884446.186 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T17:40:54.854Z,1755884454.854 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T17:41:03.471Z,1755884463.471 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T17:41:12.158Z,1755884472.158 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T17:41:20.698Z,1755884480.698 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T17:41:29.330Z,1755884489.330 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T17:41:38.202Z,1755884498.202 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T17:41:46.854Z,1755884506.854 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T17:41:55.426Z,1755884515.426 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T17:42:04.174Z,1755884524.174 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T17:42:11.808Z,1755884531.808 [DataOverHttps](IMPORTANT): SBD MTMSN=20250822T174210 2025-08-22T17:42:19.490Z,1755884539.490 [DataOverHttps](INFO): Received command: schedule clear;schedule resume;restart logs