2025-01-23T03:03:26.393Z,1737601406.393 [CommandExec](IMPORTANT): got command restart logs
2025-01-23T03:03:47.256Z,1737601427.256 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:03:48.256Z,1737601428.256 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:03:57.685Z,1737601437.685 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,030406.00,A,3646.55473,N,12152.28462,W,0.330,0.00,230125,,,A*72
2025-01-23T03:03:57.687Z,1737601437.687 [NAL9602](INFO): GPS fix at 20250123T030406: (36.775912, -121.871410)
2025-01-23T03:03:57.701Z,1737601437.701 [UniversalFixResidualReporter](INFO): Fix residual: 13.1 %DT, over the last 5852.7 m. Residual distance 768.1 m at bearing -5.4 degrees. Fix at (36.7759, -121.8714) with 3751.5 m made good.
2025-01-23T03:03:57.706Z,1737601437.706 [PAM:AbortDrift:B.AbortDrift](INFO): Received valid gps time fix: 1737601408.00. Resetting abort timer.
2025-01-23T03:03:57.709Z,1737601437.709 [PAM:NeedComms:C] Stopped
2025-01-23T03:03:57.709Z,1737601437.709 [PAM:NeedComms:D] Running Loop=1
2025-01-23T03:04:18.376Z,1737601458.376 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:04:19.380Z,1737601459.380 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:04:30.031Z,1737601470.031 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion:A.Wait](INFO): Done Waiting.
2025-01-23T03:04:30.031Z,1737601470.031 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion:A.Wait] Stopped
2025-01-23T03:04:30.031Z,1737601470.031 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion:A.Wait](DEBUG): Uninitialize Wait Component.
2025-01-23T03:04:30.032Z,1737601470.032 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion](INFO): Completed PAM:PAM:Waits:WaitForSurfaceCommsCompletion
2025-01-23T03:04:30.032Z,1737601470.032 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion] Stopped
2025-01-23T03:04:30.032Z,1737601470.032 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion](DEBUG): Aggregate::uninitialize PAM:PAM:Waits:WaitForSurfaceCommsCompletion
2025-01-23T03:04:30.032Z,1737601470.032 [PAM:PAM:Waits:WaitBuoyancyChange] Running Loop=1
2025-01-23T03:04:30.032Z,1737601470.032 [PAM:PAM:Waits:WaitBuoyancyChange](DEBUG): Aggregate::initialize PAM:PAM:Waits:WaitBuoyancyChange
2025-01-23T03:04:30.049Z,1737601470.049 [PAM:PAM:Waits:WaitBuoyancyChange:A] Running Loop=1
2025-01-23T03:04:30.439Z,1737601470.439 [PAM:PAM:Waits:WaitBuoyancyChange] Stopped
2025-01-23T03:04:30.439Z,1737601470.439 [PAM:PAM:Waits:WaitBuoyancyChange](DEBUG): Aggregate::uninitialize PAM:PAM:Waits:WaitBuoyancyChange
2025-01-23T03:04:30.439Z,1737601470.439 [PAM:PAM:Waits:WaitBuoyancyChange:A] Stopped
2025-01-23T03:04:30.440Z,1737601470.440 [PAM:PAM:Waits](INFO): Completed PAM:PAM:Waits
2025-01-23T03:04:30.441Z,1737601470.441 [PAM:PAM:Waits] Stopped
2025-01-23T03:04:30.441Z,1737601470.441 [PAM:PAM:Waits](DEBUG): Aggregate::uninitialize PAM:PAM:Waits
2025-01-23T03:04:30.441Z,1737601470.441 [PAM:PAM:Waits:A.Mass] Stopped
2025-01-23T03:04:30.441Z,1737601470.441 [PAM:PAM:Waits:A.Mass](DEBUG): Uninitialize.
2025-01-23T03:04:30.441Z,1737601470.441 [PAM:PAM:Waits:B.Buoyancy] Stopped
2025-01-23T03:04:30.441Z,1737601470.441 [PAM:PAM:Waits:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-01-23T03:04:30.441Z,1737601470.441 [PAM:PAM:C] Running Loop=1
2025-01-23T03:04:30.854Z,1737601470.854 [PAM:PAM:C] Stopped
2025-01-23T03:04:30.854Z,1737601470.854 [PAM:PAM:ApproachDepthSettleAtDepth] Running Loop=1
2025-01-23T03:04:30.855Z,1737601470.855 [PAM:PAM:ApproachDepthSettleAtDepth](DEBUG): Aggregate::initialize PAM:PAM:ApproachDepthSettleAtDepth
2025-01-23T03:04:30.855Z,1737601470.855 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth] Running Loop=1
2025-01-23T03:04:30.855Z,1737601470.855 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth](DEBUG): Aggregate::initialize PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth
2025-01-23T03:04:30.855Z,1737601470.855 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn] Running Loop=1
2025-01-23T03:04:30.855Z,1737601470.855 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn](DEBUG): Aggregate::initialize PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn
2025-01-23T03:04:30.855Z,1737601470.855 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:A.Mass] Running Loop=1
2025-01-23T03:04:30.855Z,1737601470.855 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:A.Mass](DEBUG): Initialize.
2025-01-23T03:04:30.856Z,1737601470.856 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:B.Buoyancy] Running Loop=1
2025-01-23T03:04:30.856Z,1737601470.856 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:B.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-01-23T03:04:30.856Z,1737601470.856 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:E.Point] Running Loop=1
2025-01-23T03:04:30.856Z,1737601470.856 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:E.Point](DEBUG): Initialize.
2025-01-23T03:04:30.856Z,1737601470.856 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:F.SetSpeed] Running Loop=1
2025-01-23T03:04:30.856Z,1737601470.856 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:F.SetSpeed](DEBUG): Initialize.
2025-01-23T03:04:30.856Z,1737601470.856 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:C] Running Loop=1
2025-01-23T03:04:31.312Z,1737601471.312 [PAM:PAM:ApproachDepthSettleAtDepth] Stopped
2025-01-23T03:04:31.312Z,1737601471.312 [PAM:PAM:ApproachDepthSettleAtDepth](DEBUG): Aggregate::uninitialize PAM:PAM:ApproachDepthSettleAtDepth
2025-01-23T03:04:31.312Z,1737601471.312 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth] Stopped
2025-01-23T03:04:31.312Z,1737601471.312 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth](DEBUG): Aggregate::uninitialize PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth
2025-01-23T03:04:31.313Z,1737601471.313 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn] Stopped
2025-01-23T03:04:31.313Z,1737601471.313 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn](DEBUG): Aggregate::uninitialize PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn
2025-01-23T03:04:31.313Z,1737601471.313 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:A.Mass] Stopped
2025-01-23T03:04:31.313Z,1737601471.313 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:A.Mass](DEBUG): Uninitialize.
2025-01-23T03:04:31.313Z,1737601471.313 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:B.Buoyancy] Stopped
2025-01-23T03:04:31.313Z,1737601471.313 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-01-23T03:04:31.313Z,1737601471.313 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:C] Stopped
2025-01-23T03:04:31.313Z,1737601471.313 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:E.Point] Stopped
2025-01-23T03:04:31.313Z,1737601471.313 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:F.SetSpeed] Stopped
2025-01-23T03:04:31.313Z,1737601471.313 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:F.SetSpeed](DEBUG): Uninitialize.
2025-01-23T03:04:31.313Z,1737601471.313 [PAM:PAM](INFO): Completed PAM:PAM
2025-01-23T03:04:31.313Z,1737601471.313 [PAM:PAM] Stopped
2025-01-23T03:04:31.313Z,1737601471.313 [PAM:PAM](DEBUG): Aggregate::uninitialize PAM:PAM
2025-01-23T03:04:31.314Z,1737601471.314 [PAM:PAM](INFO): Running loop #45
2025-01-23T03:04:31.314Z,1737601471.314 [PAM:PAM] Running Loop=45
2025-01-23T03:04:31.314Z,1737601471.314 [PAM:PAM](DEBUG): Aggregate::initialize PAM:PAM
2025-01-23T03:04:31.314Z,1737601471.314 [PAM:PAM:SettingVariables] Running Loop=1
2025-01-23T03:04:31.314Z,1737601471.314 [PAM:PAM:SettingVariables](DEBUG): Aggregate::initialize PAM:PAM:SettingVariables
2025-01-23T03:04:31.314Z,1737601471.314 [PAM:PAM:SettingVariables:A.Mass] Running Loop=1
2025-01-23T03:04:31.314Z,1737601471.314 [PAM:PAM:SettingVariables:A.Mass](DEBUG): Initialize.
2025-01-23T03:04:31.314Z,1737601471.314 [PAM:PAM:SettingVariables:B.Buoyancy] Running Loop=1
2025-01-23T03:04:31.314Z,1737601471.314 [PAM:PAM:SettingVariables:B.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-01-23T03:04:31.315Z,1737601471.315 [PAM:PAM:SettingVariables:C] Running Loop=1
2025-01-23T03:04:31.665Z,1737601471.665 [PAM:PAM:SettingVariables] Stopped
2025-01-23T03:04:31.665Z,1737601471.665 [PAM:PAM:SettingVariables](DEBUG): Aggregate::uninitialize PAM:PAM:SettingVariables
2025-01-23T03:04:31.666Z,1737601471.666 [PAM:PAM:SettingVariables:A.Mass] Stopped
2025-01-23T03:04:31.666Z,1737601471.666 [PAM:PAM:SettingVariables:A.Mass](DEBUG): Uninitialize.
2025-01-23T03:04:31.666Z,1737601471.666 [PAM:PAM:SettingVariables:B.Buoyancy] Stopped
2025-01-23T03:04:31.666Z,1737601471.666 [PAM:PAM:SettingVariables:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-01-23T03:04:31.666Z,1737601471.666 [PAM:PAM:SettingVariables:C] Stopped
2025-01-23T03:04:31.666Z,1737601471.666 [PAM:PAM:Waits] Running Loop=1
2025-01-23T03:04:31.666Z,1737601471.666 [PAM:PAM:Waits](DEBUG): Aggregate::initialize PAM:PAM:Waits
2025-01-23T03:04:31.666Z,1737601471.666 [PAM:PAM:Waits:A.Mass] Running Loop=1
2025-01-23T03:04:31.666Z,1737601471.666 [PAM:PAM:Waits:A.Mass](DEBUG): Initialize.
2025-01-23T03:04:31.666Z,1737601471.666 [PAM:PAM:Waits:B.Buoyancy] Running Loop=1
2025-01-23T03:04:31.666Z,1737601471.666 [PAM:PAM:Waits:B.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-01-23T03:04:31.666Z,1737601471.666 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion] Running Loop=1
2025-01-23T03:04:31.667Z,1737601471.667 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion](DEBUG): Aggregate::initialize PAM:PAM:Waits:WaitForSurfaceCommsCompletion
2025-01-23T03:04:31.667Z,1737601471.667 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion:A.Wait] Running Loop=1
2025-01-23T03:04:31.667Z,1737601471.667 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion:A.Wait](DEBUG): Initialize Wait Component.
2025-01-23T03:04:49.500Z,1737601489.500 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:04:50.504Z,1737601490.504 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:05:20.632Z,1737601520.632 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:05:21.636Z,1737601521.636 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:05:28.788Z,1737601528.788 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-01-23T03:05:51.764Z,1737601551.764 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:05:52.768Z,1737601552.768 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:06:02.227Z,1737601562.227 [NAL9602](INFO): SBD MO Status=2, MOMSN=47856, MT Status=2, MTMSN=0
2025-01-23T03:06:02.228Z,1737601562.228 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-01-23T03:06:22.888Z,1737601582.888 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:06:23.892Z,1737601583.892 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:06:54.013Z,1737601614.013 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:06:55.018Z,1737601615.018 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:07:02.854Z,1737601622.854 [NAL9602](INFO): SBD MO Status=2, MOMSN=47856, MT Status=2, MTMSN=0
2025-01-23T03:07:02.854Z,1737601622.854 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-01-23T03:07:25.144Z,1737601645.144 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:07:26.148Z,1737601646.148 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:07:43.966Z,1737601663.966 [NAL9602](INFO): SBD MO Status=2, MOMSN=47856, MT Status=2, MTMSN=0
2025-01-23T03:07:43.967Z,1737601663.967 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-01-23T03:07:56.268Z,1737601676.268 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:07:57.272Z,1737601677.272 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:08:12.011Z,1737601692.011 [NAL9602](INFO): SBD MO Status=2, MOMSN=47856, MT Status=2, MTMSN=0
2025-01-23T03:08:12.011Z,1737601692.011 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-01-23T03:08:25.696Z,1737601705.696 [WetLabsUBAT](ERROR): Failed to parse incomplete device message.
2025-01-23T03:08:27.392Z,1737601707.392 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:08:28.396Z,1737601708.396 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:08:37.869Z,1737601717.869 [NAL9602](INFO): SBD MO Status=2, MOMSN=47856, MT Status=2, MTMSN=0
2025-01-23T03:08:37.869Z,1737601717.869 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-01-23T03:08:58.528Z,1737601738.528 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:08:59.532Z,1737601739.532 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:09:01.827Z,1737601741.827 [NAL9602](INFO): SBD MO Status=1, MOMSN=47856, MT Status=0, MTMSN=0
2025-01-23T03:09:01.874Z,1737601741.874 [NAL9602](INFO): Sent 88 bytes from file Logs/20250122T032855/Courier0024.lzma
2025-01-23T03:09:01.874Z,1737601741.874 [NAL9602](INFO): Packets left to send: 0
2025-01-23T03:09:19.726Z,1737601759.726 [NAL9602](INFO): SBD MO Status=1, MOMSN=47857, MT Status=0, MTMSN=0
2025-01-23T03:09:19.776Z,1737601759.776 [NAL9602](INFO): Sent 325 bytes from file Logs/20250123T030326/Courier0000.lzma
2025-01-23T03:09:19.776Z,1737601759.776 [NAL9602](INFO): Packets left to send: 0
2025-01-23T03:09:29.680Z,1737601769.680 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:09:30.684Z,1737601770.684 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:09:32.337Z,1737601772.337 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion:A.Wait](INFO): Done Waiting.
2025-01-23T03:09:32.337Z,1737601772.337 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion:A.Wait] Stopped
2025-01-23T03:09:32.337Z,1737601772.337 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion:A.Wait](DEBUG): Uninitialize Wait Component.
2025-01-23T03:09:32.338Z,1737601772.338 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion](INFO): Completed PAM:PAM:Waits:WaitForSurfaceCommsCompletion
2025-01-23T03:09:32.338Z,1737601772.338 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion] Stopped
2025-01-23T03:09:32.338Z,1737601772.338 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion](DEBUG): Aggregate::uninitialize PAM:PAM:Waits:WaitForSurfaceCommsCompletion
2025-01-23T03:09:32.339Z,1737601772.339 [PAM:PAM:Waits:WaitBuoyancyChange] Running Loop=1
2025-01-23T03:09:32.339Z,1737601772.339 [PAM:PAM:Waits:WaitBuoyancyChange](DEBUG): Aggregate::initialize PAM:PAM:Waits:WaitBuoyancyChange
2025-01-23T03:09:32.339Z,1737601772.339 [PAM:PAM:Waits:WaitBuoyancyChange:A] Running Loop=1
2025-01-23T03:09:32.741Z,1737601772.741 [PAM:PAM:Waits:WaitBuoyancyChange] Stopped
2025-01-23T03:09:32.741Z,1737601772.741 [PAM:PAM:Waits:WaitBuoyancyChange](DEBUG): Aggregate::uninitialize PAM:PAM:Waits:WaitBuoyancyChange
2025-01-23T03:09:32.741Z,1737601772.741 [PAM:PAM:Waits:WaitBuoyancyChange:A] Stopped
2025-01-23T03:09:32.742Z,1737601772.742 [PAM:PAM:Waits](INFO): Completed PAM:PAM:Waits
2025-01-23T03:09:32.742Z,1737601772.742 [PAM:PAM:Waits] Stopped
2025-01-23T03:09:32.742Z,1737601772.742 [PAM:PAM:Waits](DEBUG): Aggregate::uninitialize PAM:PAM:Waits
2025-01-23T03:09:32.742Z,1737601772.742 [PAM:PAM:Waits:A.Mass] Stopped
2025-01-23T03:09:32.742Z,1737601772.742 [PAM:PAM:Waits:A.Mass](DEBUG): Uninitialize.
2025-01-23T03:09:32.742Z,1737601772.742 [PAM:PAM:Waits:B.Buoyancy] Stopped
2025-01-23T03:09:32.742Z,1737601772.742 [PAM:PAM:Waits:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-01-23T03:09:32.742Z,1737601772.742 [PAM:PAM:C] Running Loop=1
2025-01-23T03:09:33.152Z,1737601773.152 [PAM:PAM:C] Stopped
2025-01-23T03:09:33.152Z,1737601773.152 [PAM:PAM:ApproachDepthSettleAtDepth] Running Loop=1
2025-01-23T03:09:33.153Z,1737601773.153 [PAM:PAM:ApproachDepthSettleAtDepth](DEBUG): Aggregate::initialize PAM:PAM:ApproachDepthSettleAtDepth
2025-01-23T03:09:33.153Z,1737601773.153 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth] Running Loop=1
2025-01-23T03:09:33.153Z,1737601773.153 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth](DEBUG): Aggregate::initialize PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth
2025-01-23T03:09:33.153Z,1737601773.153 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn] Running Loop=1
2025-01-23T03:09:33.153Z,1737601773.153 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn](DEBUG): Aggregate::initialize PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn
2025-01-23T03:09:33.153Z,1737601773.153 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:A.Mass] Running Loop=1
2025-01-23T03:09:33.153Z,1737601773.153 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:A.Mass](DEBUG): Initialize.
2025-01-23T03:09:33.153Z,1737601773.153 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:B.Buoyancy] Running Loop=1
2025-01-23T03:09:33.153Z,1737601773.153 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:B.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-01-23T03:09:33.154Z,1737601773.154 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:E.Point] Running Loop=1
2025-01-23T03:09:33.154Z,1737601773.154 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:E.Point](DEBUG): Initialize.
2025-01-23T03:09:33.154Z,1737601773.154 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:F.SetSpeed] Running Loop=1
2025-01-23T03:09:33.154Z,1737601773.154 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:F.SetSpeed](DEBUG): Initialize.
2025-01-23T03:09:33.154Z,1737601773.154 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:C] Running Loop=1
2025-01-23T03:09:33.580Z,1737601773.580 [PAM:PAM:ApproachDepthSettleAtDepth] Stopped
2025-01-23T03:09:33.580Z,1737601773.580 [PAM:PAM:ApproachDepthSettleAtDepth](DEBUG): Aggregate::uninitialize PAM:PAM:ApproachDepthSettleAtDepth
2025-01-23T03:09:33.580Z,1737601773.580 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth] Stopped
2025-01-23T03:09:33.580Z,1737601773.580 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth](DEBUG): Aggregate::uninitialize PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth
2025-01-23T03:09:33.580Z,1737601773.580 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn] Stopped
2025-01-23T03:09:33.580Z,1737601773.580 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn](DEBUG): Aggregate::uninitialize PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn
2025-01-23T03:09:33.580Z,1737601773.580 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:A.Mass] Stopped
2025-01-23T03:09:33.580Z,1737601773.580 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:A.Mass](DEBUG): Uninitialize.
2025-01-23T03:09:33.580Z,1737601773.580 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:B.Buoyancy] Stopped
2025-01-23T03:09:33.580Z,1737601773.580 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-01-23T03:09:33.581Z,1737601773.581 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:C] Stopped
2025-01-23T03:09:33.581Z,1737601773.581 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:E.Point] Stopped
2025-01-23T03:09:33.581Z,1737601773.581 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:F.SetSpeed] Stopped
2025-01-23T03:09:33.581Z,1737601773.581 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:F.SetSpeed](DEBUG): Uninitialize.
2025-01-23T03:09:33.581Z,1737601773.581 [PAM:PAM](INFO): Completed PAM:PAM
2025-01-23T03:09:33.581Z,1737601773.581 [PAM:PAM] Stopped
2025-01-23T03:09:33.581Z,1737601773.581 [PAM:PAM](DEBUG): Aggregate::uninitialize PAM:PAM
2025-01-23T03:09:33.581Z,1737601773.581 [PAM:PAM](INFO): Running loop #46
2025-01-23T03:09:33.581Z,1737601773.581 [PAM:PAM] Running Loop=46
2025-01-23T03:09:33.581Z,1737601773.581 [PAM:PAM](DEBUG): Aggregate::initialize PAM:PAM
2025-01-23T03:09:33.581Z,1737601773.581 [PAM:PAM:SettingVariables] Running Loop=1
2025-01-23T03:09:33.582Z,1737601773.582 [PAM:PAM:SettingVariables](DEBUG): Aggregate::initialize PAM:PAM:SettingVariables
2025-01-23T03:09:33.582Z,1737601773.582 [PAM:PAM:SettingVariables:A.Mass] Running Loop=1
2025-01-23T03:09:33.582Z,1737601773.582 [PAM:PAM:SettingVariables:A.Mass](DEBUG): Initialize.
2025-01-23T03:09:33.582Z,1737601773.582 [PAM:PAM:SettingVariables:B.Buoyancy] Running Loop=1
2025-01-23T03:09:33.582Z,1737601773.582 [PAM:PAM:SettingVariables:B.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-01-23T03:09:33.582Z,1737601773.582 [PAM:PAM:SettingVariables:C] Running Loop=1
2025-01-23T03:09:33.961Z,1737601773.961 [PAM:PAM:SettingVariables] Stopped
2025-01-23T03:09:33.961Z,1737601773.961 [PAM:PAM:SettingVariables](DEBUG): Aggregate::uninitialize PAM:PAM:SettingVariables
2025-01-23T03:09:33.961Z,1737601773.961 [PAM:PAM:SettingVariables:A.Mass] Stopped
2025-01-23T03:09:33.961Z,1737601773.961 [PAM:PAM:SettingVariables:A.Mass](DEBUG): Uninitialize.
2025-01-23T03:09:33.961Z,1737601773.961 [PAM:PAM:SettingVariables:B.Buoyancy] Stopped
2025-01-23T03:09:33.961Z,1737601773.961 [PAM:PAM:SettingVariables:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-01-23T03:09:33.961Z,1737601773.961 [PAM:PAM:SettingVariables:C] Stopped
2025-01-23T03:09:33.961Z,1737601773.961 [PAM:PAM:Waits] Running Loop=1
2025-01-23T03:09:33.961Z,1737601773.961 [PAM:PAM:Waits](DEBUG): Aggregate::initialize PAM:PAM:Waits
2025-01-23T03:09:33.961Z,1737601773.961 [PAM:PAM:Waits:A.Mass] Running Loop=1
2025-01-23T03:09:33.961Z,1737601773.961 [PAM:PAM:Waits:A.Mass](DEBUG): Initialize.
2025-01-23T03:09:33.962Z,1737601773.962 [PAM:PAM:Waits:B.Buoyancy] Running Loop=1
2025-01-23T03:09:33.962Z,1737601773.962 [PAM:PAM:Waits:B.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-01-23T03:09:33.962Z,1737601773.962 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion] Running Loop=1
2025-01-23T03:09:33.962Z,1737601773.962 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion](DEBUG): Aggregate::initialize PAM:PAM:Waits:WaitForSurfaceCommsCompletion
2025-01-23T03:09:33.962Z,1737601773.962 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion:A.Wait] Running Loop=1
2025-01-23T03:09:33.962Z,1737601773.962 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion:A.Wait](DEBUG): Initialize Wait Component.
2025-01-23T03:09:36.775Z,1737601776.775 [NAL9602](INFO): SBD MO Status=1, MOMSN=47858, MT Status=0, MTMSN=0
2025-01-23T03:09:36.832Z,1737601776.832 [NAL9602](INFO): Sent 332 bytes from file Logs/20250122T032855/Express0025.lzma
2025-01-23T03:09:36.832Z,1737601776.832 [NAL9602](INFO): Packets left to send: 6
2025-01-23T03:09:53.843Z,1737601793.843 [NAL9602](INFO): SBD MO Status=1, MOMSN=47859, MT Status=0, MTMSN=0
2025-01-23T03:09:53.922Z,1737601793.922 [NAL9602](INFO): Sent 332 bytes from file Logs/20250122T032855/Express0025.lzma
2025-01-23T03:09:53.922Z,1737601793.922 [NAL9602](INFO): Packets left to send: 5
2025-01-23T03:10:00.804Z,1737601800.804 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:10:01.808Z,1737601801.808 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:10:12.177Z,1737601812.177 [CTD_Seabird](ERROR): Failed to parse device response: 9597.27
2025-01-23T03:10:12.317Z,1737601812.317 [NAL9602](INFO): SBD MO Status=1, MOMSN=47860, MT Status=0, MTMSN=0
2025-01-23T03:10:12.368Z,1737601812.368 [NAL9602](INFO): Sent 332 bytes from file Logs/20250122T032855/Express0025.lzma
2025-01-23T03:10:12.368Z,1737601812.368 [NAL9602](INFO): Packets left to send: 4
2025-01-23T03:10:31.282Z,1737601831.282 [NAL9602](INFO): SBD MO Status=1, MOMSN=47861, MT Status=0, MTMSN=0
2025-01-23T03:10:31.332Z,1737601831.332 [NAL9602](INFO): Sent 332 bytes from file Logs/20250122T032855/Express0025.lzma
2025-01-23T03:10:31.332Z,1737601831.332 [NAL9602](INFO): Packets left to send: 3
2025-01-23T03:10:31.932Z,1737601831.932 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:10:32.937Z,1737601832.937 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:10:52.060Z,1737601852.060 [NAL9602](INFO): SBD MO Status=1, MOMSN=47862, MT Status=0, MTMSN=0
2025-01-23T03:10:52.108Z,1737601852.108 [NAL9602](INFO): Sent 332 bytes from file Logs/20250122T032855/Express0025.lzma
2025-01-23T03:10:52.108Z,1737601852.108 [NAL9602](INFO): Packets left to send: 2
2025-01-23T03:11:03.056Z,1737601863.056 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:11:04.060Z,1737601864.060 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:11:14.123Z,1737601874.123 [NAL9602](INFO): SBD MO Status=1, MOMSN=47863, MT Status=0, MTMSN=0
2025-01-23T03:11:14.172Z,1737601874.172 [NAL9602](INFO): Sent 332 bytes from file Logs/20250122T032855/Express0025.lzma
2025-01-23T03:11:14.172Z,1737601874.172 [NAL9602](INFO): Packets left to send: 1
2025-01-23T03:11:34.192Z,1737601894.192 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:11:35.196Z,1737601895.196 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:11:40.019Z,1737601900.019 [NAL9602](INFO): SBD MO Status=1, MOMSN=47864, MT Status=0, MTMSN=0
2025-01-23T03:11:40.068Z,1737601900.068 [NAL9602](INFO): Sent 137 bytes from file Logs/20250122T032855/Express0025.lzma
2025-01-23T03:11:40.068Z,1737601900.068 [NAL9602](INFO): Packets left to send: 0
2025-01-23T03:11:56.650Z,1737601916.650 [NAL9602](INFO): SBD MO Status=1, MOMSN=47865, MT Status=0, MTMSN=0
2025-01-23T03:11:56.700Z,1737601916.700 [NAL9602](INFO): Sent 332 bytes from file Logs/20250123T030326/Express0001.lzma
2025-01-23T03:11:56.700Z,1737601916.700 [NAL9602](INFO): Packets left to send: 1
2025-01-23T03:12:05.322Z,1737601925.322 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:12:06.324Z,1737601926.324 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:12:13.101Z,1737601933.101 [NAL9602](INFO): SBD MO Status=1, MOMSN=47866, MT Status=0, MTMSN=0
2025-01-23T03:12:13.155Z,1737601933.155 [NAL9602](INFO): Sent 178 bytes from file Logs/20250123T030326/Express0001.lzma
2025-01-23T03:12:13.156Z,1737601933.156 [NAL9602](INFO): Packets left to send: 0
2025-01-23T03:12:21.362Z,1737601941.362 [NAL9602](INFO): SBD MO Status=0, MOMSN=47867, MT Status=0, MTMSN=0
2025-01-23T03:12:36.448Z,1737601956.448 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:12:37.448Z,1737601957.448 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:12:46.862Z,1737601966.862 [NAL9602](INFO): SBD MO Status=2, MOMSN=47868, MT Status=2, MTMSN=0
2025-01-23T03:12:46.862Z,1737601966.862 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-01-23T03:13:04.102Z,1737601984.102 [NAL9602](INFO): SBD MO Status=1, MOMSN=47868, MT Status=0, MTMSN=0
2025-01-23T03:13:04.152Z,1737601984.152 [NAL9602](INFO): Sent 39 bytes from file Logs/20250123T030326/Courier0003.lzma
2025-01-23T03:13:04.152Z,1737601984.152 [NAL9602](INFO): Packets left to send: 0
2025-01-23T03:13:07.570Z,1737601987.570 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:13:08.572Z,1737601988.572 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:13:22.146Z,1737602002.146 [NAL9602](INFO): SBD MO Status=1, MOMSN=47869, MT Status=0, MTMSN=0
2025-01-23T03:13:22.208Z,1737602002.208 [NAL9602](INFO): Sent 232 bytes from file Logs/20250123T030326/Express0004.lzma
2025-01-23T03:13:22.208Z,1737602002.208 [NAL9602](INFO): Packets left to send: 0
2025-01-23T03:13:29.793Z,1737602009.793 [NAL9602](INFO): SBD MO Status=0, MOMSN=47870, MT Status=0, MTMSN=0
2025-01-23T03:13:29.903Z,1737602009.903 [PAM:NeedComms:D] Stopped
2025-01-23T03:13:29.903Z,1737602009.903 [PAM:NeedComms:E] Running Loop=1
2025-01-23T03:13:32.233Z,1737602012.233 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,031340.00,A,3646.49923,N,12152.25114,W,0.428,166.69,230125,,,A*7A
2025-01-23T03:13:32.235Z,1737602012.235 [NAL9602](INFO): GPS fix at 20250123T031340: (36.774987, -121.870852)
2025-01-23T03:13:32.259Z,1737602012.259 [PAM:AbortDrift:B.AbortDrift](INFO): Received valid gps time fix: 1737602048.00. Resetting abort timer.
2025-01-23T03:13:32.260Z,1737602012.260 [PAM:NeedComms:E] Stopped
2025-01-23T03:13:32.261Z,1737602012.261 [PAM:NeedComms](INFO): Completed PAM:NeedComms
2025-01-23T03:13:32.262Z,1737602012.262 [PAM:NeedComms] Stopped
2025-01-23T03:13:32.262Z,1737602012.262 [PAM:NeedComms](DEBUG): Aggregate::uninitialize PAM:NeedComms
2025-01-23T03:13:32.262Z,1737602012.262 [PAM:NeedComms:B.GoToSurface] Stopped
2025-01-23T03:13:32.262Z,1737602012.262 [PAM:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-01-23T03:13:32.666Z,1737602012.666 [PAM:CommsAndOptionallyTransitBackToListeningLocation:CommsAndLevelRun:LevelRunStartComms](INFO): Completed PAM:CommsAndOptionallyTransitBackToListeningLocation:CommsAndLevelRun:LevelRunStartComms
2025-01-23T03:13:32.666Z,1737602012.666 [PAM:CommsAndOptionallyTransitBackToListeningLocation:CommsAndLevelRun:LevelRunStartComms] Stopped
2025-01-23T03:13:32.666Z,1737602012.666 [PAM:CommsAndOptionallyTransitBackToListeningLocation:CommsAndLevelRun:LevelRunStartComms](DEBUG): Aggregate::uninitialize PAM:CommsAndOptionallyTransitBackToListeningLocation:CommsAndLevelRun:LevelRunStartComms
2025-01-23T03:13:32.666Z,1737602012.666 [PAM:CommsAndOptionallyTransitBackToListeningLocation:CommsAndLevelRun:B] Running Loop=1
2025-01-23T03:13:33.056Z,1737602013.056 [PAM:CommsAndOptionallyTransitBackToListeningLocation:CommsAndLevelRun:B](IMPORTANT): Level run back to listening location LatListening = 36.797001 arcdeg , LonListening = -121.847000 arcdeg .
2025-01-23T03:13:33.056Z,1737602013.056 [PAM:CommsAndOptionallyTransitBackToListeningLocation:CommsAndLevelRun:B] Stopped
2025-01-23T03:13:33.056Z,1737602013.056 [PAM:CommsAndOptionallyTransitBackToListeningLocation:CommsAndLevelRun:C] Running Loop=1
2025-01-23T03:13:33.482Z,1737602013.482 [PAM:CommsAndOptionallyTransitBackToListeningLocation:CommsAndLevelRun:C] Stopped
2025-01-23T03:13:33.482Z,1737602013.482 [PAM:CommsAndOptionallyTransitBackToListeningLocation:CommsAndLevelRun:H.Waypoint] Running Loop=1
2025-01-23T03:13:33.482Z,1737602013.482 [PAM:CommsAndOptionallyTransitBackToListeningLocation:CommsAndLevelRun:H.Waypoint](DEBUG): Initialize WaypointComponent.
2025-01-23T03:13:33.483Z,1737602013.483 [PAM:CommsAndOptionallyTransitBackToListeningLocation:CommsAndLevelRun:H.Waypoint](IMPORTANT): Navigating to waypoint: 36.797001,-121.847000
2025-01-23T03:13:38.336Z,1737602018.336 [RudderServo](FAULT): Overload Error
2025-01-23T03:13:38.336Z,1737602018.336 [RudderServo] Hardware Fault, FailCount= 1
2025-01-23T03:13:38.336Z,1737602018.336 [RudderServo](ERROR): Hardware Fault
2025-01-23T03:13:38.480Z,1737602018.480 [CBIT](ERROR): Hardware Fault in component: RudderServo
2025-01-23T03:13:38.638Z,1737602018.638 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-01-23T03:13:38.638Z,1737602018.638 [RudderServo](INFO): Powering down
2025-01-23T03:13:38.692Z,1737602018.692 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:13:39.445Z,1737602019.445 [RudderServo](DEBUG): Initializing EZServoServo.
2025-01-23T03:13:39.565Z,1737602019.565 [RudderServo](DEBUG): Initializing RudderServo.
2025-01-23T03:13:39.597Z,1737602019.597 [CBIT](INFO): Clearing failed state for component RudderServo
2025-01-23T03:13:39.597Z,1737602019.597 [RudderServo] No Fault, FailCount= 1
2025-01-23T03:13:39.696Z,1737602019.696 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:14:04.590Z,1737602044.590 [NAL9602](INFO): Not Powering down - fast GPS
2025-01-23T03:14:09.816Z,1737602049.816 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:14:10.820Z,1737602050.820 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:14:32.007Z,1737602072.007 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 19.98 mm (1 active estimators).
2025-01-23T03:14:34.438Z,1737602074.438 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion:A.Wait](INFO): Done Waiting.
2025-01-23T03:14:34.438Z,1737602074.438 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion:A.Wait] Stopped
2025-01-23T03:14:34.438Z,1737602074.438 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion:A.Wait](DEBUG): Uninitialize Wait Component.
2025-01-23T03:14:34.438Z,1737602074.438 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion](INFO): Completed PAM:PAM:Waits:WaitForSurfaceCommsCompletion
2025-01-23T03:14:34.438Z,1737602074.438 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion] Stopped
2025-01-23T03:14:34.438Z,1737602074.438 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion](DEBUG): Aggregate::uninitialize PAM:PAM:Waits:WaitForSurfaceCommsCompletion
2025-01-23T03:14:34.439Z,1737602074.439 [PAM:PAM:Waits:WaitBuoyancyChange] Running Loop=1
2025-01-23T03:14:34.439Z,1737602074.439 [PAM:PAM:Waits:WaitBuoyancyChange](DEBUG): Aggregate::initialize PAM:PAM:Waits:WaitBuoyancyChange
2025-01-23T03:14:34.439Z,1737602074.439 [PAM:PAM:Waits:WaitBuoyancyChange:A] Running Loop=1
2025-01-23T03:14:34.827Z,1737602074.827 [PAM:PAM:Waits:WaitBuoyancyChange] Stopped
2025-01-23T03:14:34.827Z,1737602074.827 [PAM:PAM:Waits:WaitBuoyancyChange](DEBUG): Aggregate::uninitialize PAM:PAM:Waits:WaitBuoyancyChange
2025-01-23T03:14:34.827Z,1737602074.827 [PAM:PAM:Waits:WaitBuoyancyChange:A] Stopped
2025-01-23T03:14:34.828Z,1737602074.828 [PAM:PAM:Waits](INFO): Completed PAM:PAM:Waits
2025-01-23T03:14:34.828Z,1737602074.828 [PAM:PAM:Waits] Stopped
2025-01-23T03:14:34.828Z,1737602074.828 [PAM:PAM:Waits](DEBUG): Aggregate::uninitialize PAM:PAM:Waits
2025-01-23T03:14:34.828Z,1737602074.828 [PAM:PAM:Waits:A.Mass] Stopped
2025-01-23T03:14:34.828Z,1737602074.828 [PAM:PAM:Waits:A.Mass](DEBUG): Uninitialize.
2025-01-23T03:14:34.828Z,1737602074.828 [PAM:PAM:Waits:B.Buoyancy] Stopped
2025-01-23T03:14:34.828Z,1737602074.828 [PAM:PAM:Waits:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-01-23T03:14:34.828Z,1737602074.828 [PAM:PAM:C] Running Loop=1
2025-01-23T03:14:35.285Z,1737602075.285 [PAM:PAM:C] Stopped
2025-01-23T03:14:35.285Z,1737602075.285 [PAM:PAM:ApproachDepthSettleAtDepth] Running Loop=1
2025-01-23T03:14:35.286Z,1737602075.286 [PAM:PAM:ApproachDepthSettleAtDepth](DEBUG): Aggregate::initialize PAM:PAM:ApproachDepthSettleAtDepth
2025-01-23T03:14:35.286Z,1737602075.286 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth] Running Loop=1
2025-01-23T03:14:35.286Z,1737602075.286 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth](DEBUG): Aggregate::initialize PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth
2025-01-23T03:14:35.286Z,1737602075.286 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn] Running Loop=1
2025-01-23T03:14:35.287Z,1737602075.287 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn](DEBUG): Aggregate::initialize PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn
2025-01-23T03:14:35.287Z,1737602075.287 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:A.Mass] Running Loop=1
2025-01-23T03:14:35.287Z,1737602075.287 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:A.Mass](DEBUG): Initialize.
2025-01-23T03:14:35.296Z,1737602075.296 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:B.Buoyancy] Running Loop=1
2025-01-23T03:14:35.296Z,1737602075.296 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:B.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-01-23T03:14:35.296Z,1737602075.296 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:E.Point] Running Loop=1
2025-01-23T03:14:35.296Z,1737602075.296 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:E.Point](DEBUG): Initialize.
2025-01-23T03:14:35.296Z,1737602075.296 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:F.SetSpeed] Running Loop=1
2025-01-23T03:14:35.296Z,1737602075.296 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:F.SetSpeed](DEBUG): Initialize.
2025-01-23T03:14:35.297Z,1737602075.297 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:C] Running Loop=1
2025-01-23T03:14:35.666Z,1737602075.666 [PAM:PAM:ApproachDepthSettleAtDepth] Stopped
2025-01-23T03:14:35.666Z,1737602075.666 [PAM:PAM:ApproachDepthSettleAtDepth](DEBUG): Aggregate::uninitialize PAM:PAM:ApproachDepthSettleAtDepth
2025-01-23T03:14:35.666Z,1737602075.666 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth] Stopped
2025-01-23T03:14:35.666Z,1737602075.666 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth](DEBUG): Aggregate::uninitialize PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth
2025-01-23T03:14:35.666Z,1737602075.666 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn] Stopped
2025-01-23T03:14:35.667Z,1737602075.667 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn](DEBUG): Aggregate::uninitialize PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn
2025-01-23T03:14:35.667Z,1737602075.667 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:A.Mass] Stopped
2025-01-23T03:14:35.667Z,1737602075.667 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:A.Mass](DEBUG): Uninitialize.
2025-01-23T03:14:35.667Z,1737602075.667 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:B.Buoyancy] Stopped
2025-01-23T03:14:35.667Z,1737602075.667 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-01-23T03:14:35.667Z,1737602075.667 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:C] Stopped
2025-01-23T03:14:35.667Z,1737602075.667 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:E.Point] Stopped
2025-01-23T03:14:35.667Z,1737602075.667 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:F.SetSpeed] Stopped
2025-01-23T03:14:35.667Z,1737602075.667 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:F.SetSpeed](DEBUG): Uninitialize.
2025-01-23T03:14:35.671Z,1737602075.671 [PAM:PAM](INFO): Completed PAM:PAM
2025-01-23T03:14:35.672Z,1737602075.672 [PAM:PAM] Stopped
2025-01-23T03:14:35.672Z,1737602075.672 [PAM:PAM](DEBUG): Aggregate::uninitialize PAM:PAM
2025-01-23T03:14:35.672Z,1737602075.672 [PAM:PAM](INFO): Running loop #47
2025-01-23T03:14:35.672Z,1737602075.672 [PAM:PAM] Running Loop=47
2025-01-23T03:14:35.672Z,1737602075.672 [PAM:PAM](DEBUG): Aggregate::initialize PAM:PAM
2025-01-23T03:14:35.672Z,1737602075.672 [PAM:PAM:SettingVariables] Running Loop=1
2025-01-23T03:14:35.672Z,1737602075.672 [PAM:PAM:SettingVariables](DEBUG): Aggregate::initialize PAM:PAM:SettingVariables
2025-01-23T03:14:35.672Z,1737602075.672 [PAM:PAM:SettingVariables:A.Mass] Running Loop=1
2025-01-23T03:14:35.672Z,1737602075.672 [PAM:PAM:SettingVariables:A.Mass](DEBUG): Initialize.
2025-01-23T03:14:35.673Z,1737602075.673 [PAM:PAM:SettingVariables:B.Buoyancy] Running Loop=1
2025-01-23T03:14:35.673Z,1737602075.673 [PAM:PAM:SettingVariables:B.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-01-23T03:14:35.673Z,1737602075.673 [PAM:PAM:SettingVariables:C] Running Loop=1
2025-01-23T03:14:36.072Z,1737602076.072 [PAM:PAM:SettingVariables] Stopped
2025-01-23T03:14:36.073Z,1737602076.073 [PAM:PAM:SettingVariables](DEBUG): Aggregate::uninitialize PAM:PAM:SettingVariables
2025-01-23T03:14:36.073Z,1737602076.073 [PAM:PAM:SettingVariables:A.Mass] Stopped
2025-01-23T03:14:36.073Z,1737602076.073 [PAM:PAM:SettingVariables:A.Mass](DEBUG): Uninitialize.
2025-01-23T03:14:36.073Z,1737602076.073 [PAM:PAM:SettingVariables:B.Buoyancy] Stopped
2025-01-23T03:14:36.073Z,1737602076.073 [PAM:PAM:SettingVariables:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-01-23T03:14:36.073Z,1737602076.073 [PAM:PAM:SettingVariables:C] Stopped
2025-01-23T03:14:36.073Z,1737602076.073 [PAM:PAM:Waits] Running Loop=1
2025-01-23T03:14:36.073Z,1737602076.073 [PAM:PAM:Waits](DEBUG): Aggregate::initialize PAM:PAM:Waits
2025-01-23T03:14:36.073Z,1737602076.073 [PAM:PAM:Waits:A.Mass] Running Loop=1
2025-01-23T03:14:36.073Z,1737602076.073 [PAM:PAM:Waits:A.Mass](DEBUG): Initialize.
2025-01-23T03:14:36.073Z,1737602076.073 [PAM:PAM:Waits:B.Buoyancy] Running Loop=1
2025-01-23T03:14:36.073Z,1737602076.073 [PAM:PAM:Waits:B.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-01-23T03:14:36.074Z,1737602076.074 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion] Running Loop=1
2025-01-23T03:14:36.074Z,1737602076.074 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion](DEBUG): Aggregate::initialize PAM:PAM:Waits:WaitForSurfaceCommsCompletion
2025-01-23T03:14:36.074Z,1737602076.074 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion:A.Wait] Running Loop=1
2025-01-23T03:14:36.074Z,1737602076.074 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion:A.Wait](DEBUG): Initialize Wait Component.
2025-01-23T03:14:40.952Z,1737602080.952 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:14:41.956Z,1737602081.956 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:14:53.148Z,1737602093.148 [WetLabsUBAT](ERROR): Failed to parse incomplete device message.
2025-01-23T03:15:12.076Z,1737602112.076 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:15:13.080Z,1737602113.080 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:15:27.005Z,1737602127.005 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-01-23T03:15:32.592Z,1737602132.592 [Radio_Surface](INFO): Powering down
2025-01-23T03:15:43.216Z,1737602143.216 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:15:44.220Z,1737602144.220 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-01-23T03:15:44.221Z,1737602144.221 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:16:14.340Z,1737602174.340 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:16:15.346Z,1737602175.346 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:16:45.464Z,1737602205.464 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:16:46.468Z,1737602206.468 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:17:03.316Z,1737602223.316 [WetLabsUBAT](ERROR): Failed to parse incomplete device message.
2025-01-23T03:17:16.588Z,1737602236.588 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:17:17.592Z,1737602237.592 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:17:47.716Z,1737602267.716 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:17:48.720Z,1737602268.720 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:18:18.856Z,1737602298.856 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:18:19.860Z,1737602299.860 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:18:43.996Z,1737602323.996 [WetLabsUBAT](ERROR): Failed to parse incomplete device message.
2025-01-23T03:18:49.980Z,1737602329.980 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:18:50.984Z,1737602330.984 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:19:21.113Z,1737602361.113 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:19:22.116Z,1737602362.116 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:19:36.733Z,1737602376.733 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion:A.Wait](INFO): Done Waiting.
2025-01-23T03:19:36.733Z,1737602376.733 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion:A.Wait] Stopped
2025-01-23T03:19:36.733Z,1737602376.733 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion:A.Wait](DEBUG): Uninitialize Wait Component.
2025-01-23T03:19:36.734Z,1737602376.734 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion](INFO): Completed PAM:PAM:Waits:WaitForSurfaceCommsCompletion
2025-01-23T03:19:36.734Z,1737602376.734 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion] Stopped
2025-01-23T03:19:36.734Z,1737602376.734 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion](DEBUG): Aggregate::uninitialize PAM:PAM:Waits:WaitForSurfaceCommsCompletion
2025-01-23T03:19:36.734Z,1737602376.734 [PAM:PAM:Waits:WaitBuoyancyChange] Running Loop=1
2025-01-23T03:19:36.734Z,1737602376.734 [PAM:PAM:Waits:WaitBuoyancyChange](DEBUG): Aggregate::initialize PAM:PAM:Waits:WaitBuoyancyChange
2025-01-23T03:19:36.735Z,1737602376.735 [PAM:PAM:Waits:WaitBuoyancyChange:A] Running Loop=1
2025-01-23T03:19:37.142Z,1737602377.142 [PAM:PAM:Waits:WaitBuoyancyChange] Stopped
2025-01-23T03:19:37.142Z,1737602377.142 [PAM:PAM:Waits:WaitBuoyancyChange](DEBUG): Aggregate::uninitialize PAM:PAM:Waits:WaitBuoyancyChange
2025-01-23T03:19:37.142Z,1737602377.142 [PAM:PAM:Waits:WaitBuoyancyChange:A] Stopped
2025-01-23T03:19:37.143Z,1737602377.143 [PAM:PAM:Waits](INFO): Completed PAM:PAM:Waits
2025-01-23T03:19:37.143Z,1737602377.143 [PAM:PAM:Waits] Stopped
2025-01-23T03:19:37.143Z,1737602377.143 [PAM:PAM:Waits](DEBUG): Aggregate::uninitialize PAM:PAM:Waits
2025-01-23T03:19:37.143Z,1737602377.143 [PAM:PAM:Waits:A.Mass] Stopped
2025-01-23T03:19:37.143Z,1737602377.143 [PAM:PAM:Waits:A.Mass](DEBUG): Uninitialize.
2025-01-23T03:19:37.143Z,1737602377.143 [PAM:PAM:Waits:B.Buoyancy] Stopped
2025-01-23T03:19:37.144Z,1737602377.144 [PAM:PAM:Waits:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-01-23T03:19:37.144Z,1737602377.144 [PAM:PAM:C] Running Loop=1
2025-01-23T03:19:37.553Z,1737602377.553 [PAM:PAM:C] Stopped
2025-01-23T03:19:37.554Z,1737602377.554 [PAM:PAM:ApproachDepthSettleAtDepth] Running Loop=1
2025-01-23T03:19:37.554Z,1737602377.554 [PAM:PAM:ApproachDepthSettleAtDepth](DEBUG): Aggregate::initialize PAM:PAM:ApproachDepthSettleAtDepth
2025-01-23T03:19:37.554Z,1737602377.554 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth] Running Loop=1
2025-01-23T03:19:37.554Z,1737602377.554 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth](DEBUG): Aggregate::initialize PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth
2025-01-23T03:19:37.554Z,1737602377.554 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn] Running Loop=1
2025-01-23T03:19:37.554Z,1737602377.554 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn](DEBUG): Aggregate::initialize PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn
2025-01-23T03:19:37.555Z,1737602377.555 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:A.Mass] Running Loop=1
2025-01-23T03:19:37.555Z,1737602377.555 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:A.Mass](DEBUG): Initialize.
2025-01-23T03:19:37.555Z,1737602377.555 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:B.Buoyancy] Running Loop=1
2025-01-23T03:19:37.555Z,1737602377.555 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:B.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-01-23T03:19:37.556Z,1737602377.556 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:E.Point] Running Loop=1
2025-01-23T03:19:37.556Z,1737602377.556 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:E.Point](DEBUG): Initialize.
2025-01-23T03:19:37.556Z,1737602377.556 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:F.SetSpeed] Running Loop=1
2025-01-23T03:19:37.556Z,1737602377.556 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:F.SetSpeed](DEBUG): Initialize.
2025-01-23T03:19:37.557Z,1737602377.557 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:C] Running Loop=1
2025-01-23T03:19:37.946Z,1737602377.946 [PAM:PAM:ApproachDepthSettleAtDepth] Stopped
2025-01-23T03:19:37.946Z,1737602377.946 [PAM:PAM:ApproachDepthSettleAtDepth](DEBUG): Aggregate::uninitialize PAM:PAM:ApproachDepthSettleAtDepth
2025-01-23T03:19:37.946Z,1737602377.946 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth] Stopped
2025-01-23T03:19:37.946Z,1737602377.946 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth](DEBUG): Aggregate::uninitialize PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth
2025-01-23T03:19:37.946Z,1737602377.946 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn] Stopped
2025-01-23T03:19:37.946Z,1737602377.946 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn](DEBUG): Aggregate::uninitialize PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn
2025-01-23T03:19:37.946Z,1737602377.946 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:A.Mass] Stopped
2025-01-23T03:19:37.947Z,1737602377.947 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:A.Mass](DEBUG): Uninitialize.
2025-01-23T03:19:37.947Z,1737602377.947 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:B.Buoyancy] Stopped
2025-01-23T03:19:37.947Z,1737602377.947 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-01-23T03:19:37.947Z,1737602377.947 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:C] Stopped
2025-01-23T03:19:37.947Z,1737602377.947 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:E.Point] Stopped
2025-01-23T03:19:37.947Z,1737602377.947 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:F.SetSpeed] Stopped
2025-01-23T03:19:37.947Z,1737602377.947 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:F.SetSpeed](DEBUG): Uninitialize.
2025-01-23T03:19:37.947Z,1737602377.947 [PAM:PAM](INFO): Completed PAM:PAM
2025-01-23T03:19:37.947Z,1737602377.947 [PAM:PAM] Stopped
2025-01-23T03:19:37.963Z,1737602377.963 [PAM:PAM](DEBUG): Aggregate::uninitialize PAM:PAM
2025-01-23T03:19:37.964Z,1737602377.964 [PAM:PAM](INFO): Running loop #48
2025-01-23T03:19:37.964Z,1737602377.964 [PAM:PAM] Running Loop=48
2025-01-23T03:19:37.964Z,1737602377.964 [PAM:PAM](DEBUG): Aggregate::initialize PAM:PAM
2025-01-23T03:19:37.964Z,1737602377.964 [PAM:PAM:SettingVariables] Running Loop=1
2025-01-23T03:19:37.964Z,1737602377.964 [PAM:PAM:SettingVariables](DEBUG): Aggregate::initialize PAM:PAM:SettingVariables
2025-01-23T03:19:37.964Z,1737602377.964 [PAM:PAM:SettingVariables:A.Mass] Running Loop=1
2025-01-23T03:19:37.964Z,1737602377.964 [PAM:PAM:SettingVariables:A.Mass](DEBUG): Initialize.
2025-01-23T03:19:37.964Z,1737602377.964 [PAM:PAM:SettingVariables:B.Buoyancy] Running Loop=1
2025-01-23T03:19:37.964Z,1737602377.964 [PAM:PAM:SettingVariables:B.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-01-23T03:19:37.965Z,1737602377.965 [PAM:PAM:SettingVariables:C] Running Loop=1
2025-01-23T03:19:38.389Z,1737602378.389 [PAM:PAM:SettingVariables] Stopped
2025-01-23T03:19:38.390Z,1737602378.390 [PAM:PAM:SettingVariables](DEBUG): Aggregate::uninitialize PAM:PAM:SettingVariables
2025-01-23T03:19:38.390Z,1737602378.390 [PAM:PAM:SettingVariables:A.Mass] Stopped
2025-01-23T03:19:38.390Z,1737602378.390 [PAM:PAM:SettingVariables:A.Mass](DEBUG): Uninitialize.
2025-01-23T03:19:38.390Z,1737602378.390 [PAM:PAM:SettingVariables:B.Buoyancy] Stopped
2025-01-23T03:19:38.390Z,1737602378.390 [PAM:PAM:SettingVariables:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-01-23T03:19:38.390Z,1737602378.390 [PAM:PAM:SettingVariables:C] Stopped
2025-01-23T03:19:38.390Z,1737602378.390 [PAM:PAM:Waits] Running Loop=1
2025-01-23T03:19:38.390Z,1737602378.390 [PAM:PAM:Waits](DEBUG): Aggregate::initialize PAM:PAM:Waits
2025-01-23T03:19:38.390Z,1737602378.390 [PAM:PAM:Waits:A.Mass] Running Loop=1
2025-01-23T03:19:38.390Z,1737602378.390 [PAM:PAM:Waits:A.Mass](DEBUG): Initialize.
2025-01-23T03:19:38.390Z,1737602378.390 [PAM:PAM:Waits:B.Buoyancy] Running Loop=1
2025-01-23T03:19:38.390Z,1737602378.390 [PAM:PAM:Waits:B.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-01-23T03:19:38.391Z,1737602378.391 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion] Running Loop=1
2025-01-23T03:19:38.391Z,1737602378.391 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion](DEBUG): Aggregate::initialize PAM:PAM:Waits:WaitForSurfaceCommsCompletion
2025-01-23T03:19:38.391Z,1737602378.391 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion:A.Wait] Running Loop=1
2025-01-23T03:19:38.391Z,1737602378.391 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion:A.Wait](DEBUG): Initialize Wait Component.
2025-01-23T03:19:52.245Z,1737602392.245 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:19:53.248Z,1737602393.248 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:20:08.404Z,1737602408.404 [WetLabsUBAT](ERROR): Failed to parse incomplete device message.
2025-01-23T03:20:23.372Z,1737602423.372 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:20:24.376Z,1737602424.376 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:20:54.496Z,1737602454.496 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:20:55.500Z,1737602455.500 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:21:00.276Z,1737602460.276 [WetLabsUBAT](ERROR): Failed to parse incomplete device message.
2025-01-23T03:21:25.628Z,1737602485.628 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:21:26.632Z,1737602486.632 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:21:46.418Z,1737602506.418 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,25012219225548,35.0, +12.1, 9
2025-01-23T03:21:56.752Z,1737602516.752 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:21:57.756Z,1737602517.756 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:22:16.330Z,1737602536.330 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for water velocity. Device response is::WS, -5, +883A
2025-01-23T03:22:27.876Z,1737602547.876 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:22:28.880Z,1737602548.880 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:22:59.018Z,1737602579.018 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:23:00.020Z,1737602580.020 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:23:30.152Z,1737602610.152 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:23:31.152Z,1737602611.152 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:23:32.828Z,1737602612.828 [WetLabsUBAT](ERROR): Failed to parse incomplete device message.
2025-01-23T03:23:53.160Z,1737602633.160 [WetLabsUBAT](ERROR): Failed to parse incomplete device message.
2025-01-23T03:24:01.280Z,1737602641.280 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:24:02.286Z,1737602642.286 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:24:32.408Z,1737602672.408 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:24:33.412Z,1737602673.412 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:24:39.012Z,1737602679.012 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion:A.Wait](INFO): Done Waiting.
2025-01-23T03:24:39.012Z,1737602679.012 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion:A.Wait] Stopped
2025-01-23T03:24:39.012Z,1737602679.012 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion:A.Wait](DEBUG): Uninitialize Wait Component.
2025-01-23T03:24:39.013Z,1737602679.013 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion](INFO): Completed PAM:PAM:Waits:WaitForSurfaceCommsCompletion
2025-01-23T03:24:39.013Z,1737602679.013 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion] Stopped
2025-01-23T03:24:39.013Z,1737602679.013 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion](DEBUG): Aggregate::uninitialize PAM:PAM:Waits:WaitForSurfaceCommsCompletion
2025-01-23T03:24:39.016Z,1737602679.016 [PAM:PAM:Waits:WaitBuoyancyChange] Running Loop=1
2025-01-23T03:24:39.016Z,1737602679.016 [PAM:PAM:Waits:WaitBuoyancyChange](DEBUG): Aggregate::initialize PAM:PAM:Waits:WaitBuoyancyChange
2025-01-23T03:24:39.016Z,1737602679.016 [PAM:PAM:Waits:WaitBuoyancyChange:A] Running Loop=1
2025-01-23T03:24:39.396Z,1737602679.396 [PAM:PAM:Waits:WaitBuoyancyChange] Stopped
2025-01-23T03:24:39.397Z,1737602679.397 [PAM:PAM:Waits:WaitBuoyancyChange](DEBUG): Aggregate::uninitialize PAM:PAM:Waits:WaitBuoyancyChange
2025-01-23T03:24:39.397Z,1737602679.397 [PAM:PAM:Waits:WaitBuoyancyChange:A] Stopped
2025-01-23T03:24:39.397Z,1737602679.397 [PAM:PAM:Waits](INFO): Completed PAM:PAM:Waits
2025-01-23T03:24:39.397Z,1737602679.397 [PAM:PAM:Waits] Stopped
2025-01-23T03:24:39.397Z,1737602679.397 [PAM:PAM:Waits](DEBUG): Aggregate::uninitialize PAM:PAM:Waits
2025-01-23T03:24:39.398Z,1737602679.398 [PAM:PAM:Waits:A.Mass] Stopped
2025-01-23T03:24:39.398Z,1737602679.398 [PAM:PAM:Waits:A.Mass](DEBUG): Uninitialize.
2025-01-23T03:24:39.398Z,1737602679.398 [PAM:PAM:Waits:B.Buoyancy] Stopped
2025-01-23T03:24:39.398Z,1737602679.398 [PAM:PAM:Waits:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-01-23T03:24:39.398Z,1737602679.398 [PAM:PAM:C] Running Loop=1
2025-01-23T03:24:39.821Z,1737602679.821 [PAM:PAM:C] Stopped
2025-01-23T03:24:39.821Z,1737602679.821 [PAM:PAM:ApproachDepthSettleAtDepth] Running Loop=1
2025-01-23T03:24:39.821Z,1737602679.821 [PAM:PAM:ApproachDepthSettleAtDepth](DEBUG): Aggregate::initialize PAM:PAM:ApproachDepthSettleAtDepth
2025-01-23T03:24:39.822Z,1737602679.822 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth] Running Loop=1
2025-01-23T03:24:39.822Z,1737602679.822 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth](DEBUG): Aggregate::initialize PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth
2025-01-23T03:24:39.822Z,1737602679.822 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn] Running Loop=1
2025-01-23T03:24:39.822Z,1737602679.822 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn](DEBUG): Aggregate::initialize PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn
2025-01-23T03:24:39.822Z,1737602679.822 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:A.Mass] Running Loop=1
2025-01-23T03:24:39.822Z,1737602679.822 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:A.Mass](DEBUG): Initialize.
2025-01-23T03:24:39.822Z,1737602679.822 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:B.Buoyancy] Running Loop=1
2025-01-23T03:24:39.823Z,1737602679.823 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:B.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-01-23T03:24:39.823Z,1737602679.823 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:E.Point] Running Loop=1
2025-01-23T03:24:39.823Z,1737602679.823 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:E.Point](DEBUG): Initialize.
2025-01-23T03:24:39.823Z,1737602679.823 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:F.SetSpeed] Running Loop=1
2025-01-23T03:24:39.824Z,1737602679.824 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:F.SetSpeed](DEBUG): Initialize.
2025-01-23T03:24:39.824Z,1737602679.824 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:C] Running Loop=1
2025-01-23T03:24:40.215Z,1737602680.215 [PAM:PAM:ApproachDepthSettleAtDepth] Stopped
2025-01-23T03:24:40.220Z,1737602680.220 [PAM:PAM:ApproachDepthSettleAtDepth](DEBUG): Aggregate::uninitialize PAM:PAM:ApproachDepthSettleAtDepth
2025-01-23T03:24:40.220Z,1737602680.220 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth] Stopped
2025-01-23T03:24:40.220Z,1737602680.220 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth](DEBUG): Aggregate::uninitialize PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth
2025-01-23T03:24:40.220Z,1737602680.220 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn] Stopped
2025-01-23T03:24:40.220Z,1737602680.220 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn](DEBUG): Aggregate::uninitialize PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn
2025-01-23T03:24:40.220Z,1737602680.220 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:A.Mass] Stopped
2025-01-23T03:24:40.220Z,1737602680.220 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:A.Mass](DEBUG): Uninitialize.
2025-01-23T03:24:40.220Z,1737602680.220 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:B.Buoyancy] Stopped
2025-01-23T03:24:40.220Z,1737602680.220 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-01-23T03:24:40.220Z,1737602680.220 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:C] Stopped
2025-01-23T03:24:40.220Z,1737602680.220 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:E.Point] Stopped
2025-01-23T03:24:40.220Z,1737602680.220 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:F.SetSpeed] Stopped
2025-01-23T03:24:40.220Z,1737602680.220 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:F.SetSpeed](DEBUG): Uninitialize.
2025-01-23T03:24:40.221Z,1737602680.221 [PAM:PAM](INFO): Completed PAM:PAM
2025-01-23T03:24:40.221Z,1737602680.221 [PAM:PAM] Stopped
2025-01-23T03:24:40.221Z,1737602680.221 [PAM:PAM](DEBUG): Aggregate::uninitialize PAM:PAM
2025-01-23T03:24:40.221Z,1737602680.221 [PAM:PAM](INFO): Running loop #49
2025-01-23T03:24:40.221Z,1737602680.221 [PAM:PAM] Running Loop=49
2025-01-23T03:24:40.221Z,1737602680.221 [PAM:PAM](DEBUG): Aggregate::initialize PAM:PAM
2025-01-23T03:24:40.221Z,1737602680.221 [PAM:PAM:SettingVariables] Running Loop=1
2025-01-23T03:24:40.221Z,1737602680.221 [PAM:PAM:SettingVariables](DEBUG): Aggregate::initialize PAM:PAM:SettingVariables
2025-01-23T03:24:40.221Z,1737602680.221 [PAM:PAM:SettingVariables:A.Mass] Running Loop=1
2025-01-23T03:24:40.221Z,1737602680.221 [PAM:PAM:SettingVariables:A.Mass](DEBUG): Initialize.
2025-01-23T03:24:40.222Z,1737602680.222 [PAM:PAM:SettingVariables:B.Buoyancy] Running Loop=1
2025-01-23T03:24:40.222Z,1737602680.222 [PAM:PAM:SettingVariables:B.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-01-23T03:24:40.222Z,1737602680.222 [PAM:PAM:SettingVariables:C] Running Loop=1
2025-01-23T03:24:40.638Z,1737602680.638 [PAM:PAM:SettingVariables] Stopped
2025-01-23T03:24:40.638Z,1737602680.638 [PAM:PAM:SettingVariables](DEBUG): Aggregate::uninitialize PAM:PAM:SettingVariables
2025-01-23T03:24:40.638Z,1737602680.638 [PAM:PAM:SettingVariables:A.Mass] Stopped
2025-01-23T03:24:40.638Z,1737602680.638 [PAM:PAM:SettingVariables:A.Mass](DEBUG): Uninitialize.
2025-01-23T03:24:40.638Z,1737602680.638 [PAM:PAM:SettingVariables:B.Buoyancy] Stopped
2025-01-23T03:24:40.638Z,1737602680.638 [PAM:PAM:SettingVariables:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-01-23T03:24:40.638Z,1737602680.638 [PAM:PAM:SettingVariables:C] Stopped
2025-01-23T03:24:40.638Z,1737602680.638 [PAM:PAM:Waits] Running Loop=1
2025-01-23T03:24:40.638Z,1737602680.638 [PAM:PAM:Waits](DEBUG): Aggregate::initialize PAM:PAM:Waits
2025-01-23T03:24:40.638Z,1737602680.638 [PAM:PAM:Waits:A.Mass] Running Loop=1
2025-01-23T03:24:40.638Z,1737602680.638 [PAM:PAM:Waits:A.Mass](DEBUG): Initialize.
2025-01-23T03:24:40.639Z,1737602680.639 [PAM:PAM:Waits:B.Buoyancy] Running Loop=1
2025-01-23T03:24:40.639Z,1737602680.639 [PAM:PAM:Waits:B.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-01-23T03:24:40.639Z,1737602680.639 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion] Running Loop=1
2025-01-23T03:24:40.639Z,1737602680.639 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion](DEBUG): Aggregate::initialize PAM:PAM:Waits:WaitForSurfaceCommsCompletion
2025-01-23T03:24:40.639Z,1737602680.639 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion:A.Wait] Running Loop=1
2025-01-23T03:24:40.639Z,1737602680.639 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion:A.Wait](DEBUG): Initialize Wait Component.
2025-01-23T03:25:03.536Z,1737602703.536 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:25:04.540Z,1737602704.540 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:25:05.360Z,1737602705.360 [WetLabsUBAT](ERROR): Failed to parse incomplete device message.
2025-01-23T03:25:27.053Z,1737602727.053 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-01-23T03:25:34.660Z,1737602734.660 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:25:35.664Z,1737602735.664 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:26:05.788Z,1737602765.788 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:26:06.792Z,1737602766.792 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:26:36.924Z,1737602796.924 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:26:37.928Z,1737602797.928 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:27:08.068Z,1737602828.068 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:27:09.072Z,1737602829.072 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:27:39.212Z,1737602859.212 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:27:40.216Z,1737602860.216 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:28:10.336Z,1737602890.336 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:28:11.340Z,1737602891.340 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:28:41.465Z,1737602921.465 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:28:42.469Z,1737602922.469 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:29:12.605Z,1737602952.605 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:29:13.608Z,1737602953.608 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:29:41.250Z,1737602981.250 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion:A.Wait](INFO): Done Waiting.
2025-01-23T03:29:41.250Z,1737602981.250 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion:A.Wait] Stopped
2025-01-23T03:29:41.251Z,1737602981.251 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion:A.Wait](DEBUG): Uninitialize Wait Component.
2025-01-23T03:29:41.251Z,1737602981.251 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion](INFO): Completed PAM:PAM:Waits:WaitForSurfaceCommsCompletion
2025-01-23T03:29:41.251Z,1737602981.251 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion] Stopped
2025-01-23T03:29:41.251Z,1737602981.251 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion](DEBUG): Aggregate::uninitialize PAM:PAM:Waits:WaitForSurfaceCommsCompletion
2025-01-23T03:29:41.255Z,1737602981.255 [PAM:PAM:Waits:WaitBuoyancyChange] Running Loop=1
2025-01-23T03:29:41.256Z,1737602981.256 [PAM:PAM:Waits:WaitBuoyancyChange](DEBUG): Aggregate::initialize PAM:PAM:Waits:WaitBuoyancyChange
2025-01-23T03:29:41.256Z,1737602981.256 [PAM:PAM:Waits:WaitBuoyancyChange:A] Running Loop=1
2025-01-23T03:29:41.643Z,1737602981.643 [PAM:PAM:Waits:WaitBuoyancyChange] Stopped
2025-01-23T03:29:41.643Z,1737602981.643 [PAM:PAM:Waits:WaitBuoyancyChange](DEBUG): Aggregate::uninitialize PAM:PAM:Waits:WaitBuoyancyChange
2025-01-23T03:29:41.643Z,1737602981.643 [PAM:PAM:Waits:WaitBuoyancyChange:A] Stopped
2025-01-23T03:29:41.644Z,1737602981.644 [PAM:PAM:Waits](INFO): Completed PAM:PAM:Waits
2025-01-23T03:29:41.644Z,1737602981.644 [PAM:PAM:Waits] Stopped
2025-01-23T03:29:41.644Z,1737602981.644 [PAM:PAM:Waits](DEBUG): Aggregate::uninitialize PAM:PAM:Waits
2025-01-23T03:29:41.644Z,1737602981.644 [PAM:PAM:Waits:A.Mass] Stopped
2025-01-23T03:29:41.644Z,1737602981.644 [PAM:PAM:Waits:A.Mass](DEBUG): Uninitialize.
2025-01-23T03:29:41.644Z,1737602981.644 [PAM:PAM:Waits:B.Buoyancy] Stopped
2025-01-23T03:29:41.644Z,1737602981.644 [PAM:PAM:Waits:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-01-23T03:29:41.644Z,1737602981.644 [PAM:PAM:C] Running Loop=1
2025-01-23T03:29:42.029Z,1737602982.029 [PAM:PAM:C] Stopped
2025-01-23T03:29:42.030Z,1737602982.030 [PAM:PAM:ApproachDepthSettleAtDepth] Running Loop=1
2025-01-23T03:29:42.030Z,1737602982.030 [PAM:PAM:ApproachDepthSettleAtDepth](DEBUG): Aggregate::initialize PAM:PAM:ApproachDepthSettleAtDepth
2025-01-23T03:29:42.030Z,1737602982.030 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth] Running Loop=1
2025-01-23T03:29:42.030Z,1737602982.030 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth](DEBUG): Aggregate::initialize PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth
2025-01-23T03:29:42.031Z,1737602982.031 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn] Running Loop=1
2025-01-23T03:29:42.031Z,1737602982.031 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn](DEBUG): Aggregate::initialize PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn
2025-01-23T03:29:42.031Z,1737602982.031 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:A.Mass] Running Loop=1
2025-01-23T03:29:42.031Z,1737602982.031 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:A.Mass](DEBUG): Initialize.
2025-01-23T03:29:42.031Z,1737602982.031 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:B.Buoyancy] Running Loop=1
2025-01-23T03:29:42.031Z,1737602982.031 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:B.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-01-23T03:29:42.031Z,1737602982.031 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:E.Point] Running Loop=1
2025-01-23T03:29:42.035Z,1737602982.035 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:E.Point](DEBUG): Initialize.
2025-01-23T03:29:42.035Z,1737602982.035 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:F.SetSpeed] Running Loop=1
2025-01-23T03:29:42.036Z,1737602982.036 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:F.SetSpeed](DEBUG): Initialize.
2025-01-23T03:29:42.036Z,1737602982.036 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:C] Running Loop=1
2025-01-23T03:29:42.462Z,1737602982.462 [PAM:PAM:ApproachDepthSettleAtDepth] Stopped
2025-01-23T03:29:42.462Z,1737602982.462 [PAM:PAM:ApproachDepthSettleAtDepth](DEBUG): Aggregate::uninitialize PAM:PAM:ApproachDepthSettleAtDepth
2025-01-23T03:29:42.462Z,1737602982.462 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth] Stopped
2025-01-23T03:29:42.462Z,1737602982.462 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth](DEBUG): Aggregate::uninitialize PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth
2025-01-23T03:29:42.462Z,1737602982.462 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn] Stopped
2025-01-23T03:29:42.462Z,1737602982.462 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn](DEBUG): Aggregate::uninitialize PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn
2025-01-23T03:29:42.462Z,1737602982.462 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:A.Mass] Stopped
2025-01-23T03:29:42.462Z,1737602982.462 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:A.Mass](DEBUG): Uninitialize.
2025-01-23T03:29:42.463Z,1737602982.463 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:B.Buoyancy] Stopped
2025-01-23T03:29:42.463Z,1737602982.463 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-01-23T03:29:42.463Z,1737602982.463 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:C] Stopped
2025-01-23T03:29:42.465Z,1737602982.465 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:E.Point] Stopped
2025-01-23T03:29:42.465Z,1737602982.465 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:F.SetSpeed] Stopped
2025-01-23T03:29:42.465Z,1737602982.465 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:F.SetSpeed](DEBUG): Uninitialize.
2025-01-23T03:29:42.465Z,1737602982.465 [PAM:PAM](INFO): Completed PAM:PAM
2025-01-23T03:29:42.465Z,1737602982.465 [PAM:PAM] Stopped
2025-01-23T03:29:42.465Z,1737602982.465 [PAM:PAM](DEBUG): Aggregate::uninitialize PAM:PAM
2025-01-23T03:29:42.465Z,1737602982.465 [PAM:PAM](INFO): Running loop #50
2025-01-23T03:29:42.465Z,1737602982.465 [PAM:PAM] Running Loop=50
2025-01-23T03:29:42.465Z,1737602982.465 [PAM:PAM](DEBUG): Aggregate::initialize PAM:PAM
2025-01-23T03:29:42.465Z,1737602982.465 [PAM:PAM:SettingVariables] Running Loop=1
2025-01-23T03:29:42.466Z,1737602982.466 [PAM:PAM:SettingVariables](DEBUG): Aggregate::initialize PAM:PAM:SettingVariables
2025-01-23T03:29:42.466Z,1737602982.466 [PAM:PAM:SettingVariables:A.Mass] Running Loop=1
2025-01-23T03:29:42.466Z,1737602982.466 [PAM:PAM:SettingVariables:A.Mass](DEBUG): Initialize.
2025-01-23T03:29:42.466Z,1737602982.466 [PAM:PAM:SettingVariables:B.Buoyancy] Running Loop=1
2025-01-23T03:29:42.466Z,1737602982.466 [PAM:PAM:SettingVariables:B.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-01-23T03:29:42.466Z,1737602982.466 [PAM:PAM:SettingVariables:C] Running Loop=1
2025-01-23T03:29:42.846Z,1737602982.846 [PAM:PAM:SettingVariables] Stopped
2025-01-23T03:29:42.846Z,1737602982.846 [PAM:PAM:SettingVariables](DEBUG): Aggregate::uninitialize PAM:PAM:SettingVariables
2025-01-23T03:29:42.846Z,1737602982.846 [PAM:PAM:SettingVariables:A.Mass] Stopped
2025-01-23T03:29:42.847Z,1737602982.847 [PAM:PAM:SettingVariables:A.Mass](DEBUG): Uninitialize.
2025-01-23T03:29:42.847Z,1737602982.847 [PAM:PAM:SettingVariables:B.Buoyancy] Stopped
2025-01-23T03:29:42.847Z,1737602982.847 [PAM:PAM:SettingVariables:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-01-23T03:29:42.847Z,1737602982.847 [PAM:PAM:SettingVariables:C] Stopped
2025-01-23T03:29:42.847Z,1737602982.847 [PAM:PAM:Waits] Running Loop=1
2025-01-23T03:29:42.848Z,1737602982.848 [PAM:PAM:Waits](DEBUG): Aggregate::initialize PAM:PAM:Waits
2025-01-23T03:29:42.848Z,1737602982.848 [PAM:PAM:Waits:A.Mass] Running Loop=1
2025-01-23T03:29:42.848Z,1737602982.848 [PAM:PAM:Waits:A.Mass](DEBUG): Initialize.
2025-01-23T03:29:42.848Z,1737602982.848 [PAM:PAM:Waits:B.Buoyancy] Running Loop=1
2025-01-23T03:29:42.848Z,1737602982.848 [PAM:PAM:Waits:B.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-01-23T03:29:42.848Z,1737602982.848 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion] Running Loop=1
2025-01-23T03:29:42.848Z,1737602982.848 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion](DEBUG): Aggregate::initialize PAM:PAM:Waits:WaitForSurfaceCommsCompletion
2025-01-23T03:29:42.848Z,1737602982.848 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion:A.Wait] Running Loop=1
2025-01-23T03:29:42.848Z,1737602982.848 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion:A.Wait](DEBUG): Initialize Wait Component.
2025-01-23T03:29:43.733Z,1737602983.733 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:29:44.736Z,1737602984.736 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:30:14.868Z,1737603014.868 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:30:15.874Z,1737603015.874 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:30:41.020Z,1737603041.020 [WetLabsUBAT](ERROR): Failed to parse incomplete device message.
2025-01-23T03:30:45.994Z,1737603045.994 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:30:46.996Z,1737603046.996 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:31:17.124Z,1737603077.124 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:31:18.128Z,1737603078.128 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:31:48.270Z,1737603108.270 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:31:49.272Z,1737603109.272 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:32:19.392Z,1737603139.392 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:32:20.396Z,1737603140.396 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:32:50.533Z,1737603170.533 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:32:51.536Z,1737603171.536 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:33:21.656Z,1737603201.656 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:33:22.660Z,1737603202.660 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:33:26.768Z,1737603206.768 [WetLabsUBAT](ERROR): Failed to parse incomplete device message.
2025-01-23T03:33:52.780Z,1737603232.780 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:33:53.784Z,1737603233.784 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:34:23.930Z,1737603263.930 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:34:24.932Z,1737603264.932 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:34:43.545Z,1737603283.545 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion:A.Wait](INFO): Done Waiting.
2025-01-23T03:34:43.545Z,1737603283.545 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion:A.Wait] Stopped
2025-01-23T03:34:43.545Z,1737603283.545 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion:A.Wait](DEBUG): Uninitialize Wait Component.
2025-01-23T03:34:43.545Z,1737603283.545 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion](INFO): Completed PAM:PAM:Waits:WaitForSurfaceCommsCompletion
2025-01-23T03:34:43.545Z,1737603283.545 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion] Stopped
2025-01-23T03:34:43.546Z,1737603283.546 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion](DEBUG): Aggregate::uninitialize PAM:PAM:Waits:WaitForSurfaceCommsCompletion
2025-01-23T03:34:43.546Z,1737603283.546 [PAM:PAM:Waits:WaitBuoyancyChange] Running Loop=1
2025-01-23T03:34:43.546Z,1737603283.546 [PAM:PAM:Waits:WaitBuoyancyChange](DEBUG): Aggregate::initialize PAM:PAM:Waits:WaitBuoyancyChange
2025-01-23T03:34:43.546Z,1737603283.546 [PAM:PAM:Waits:WaitBuoyancyChange:A] Running Loop=1
2025-01-23T03:34:43.952Z,1737603283.952 [PAM:PAM:Waits:WaitBuoyancyChange] Stopped
2025-01-23T03:34:43.952Z,1737603283.952 [PAM:PAM:Waits:WaitBuoyancyChange](DEBUG): Aggregate::uninitialize PAM:PAM:Waits:WaitBuoyancyChange
2025-01-23T03:34:43.952Z,1737603283.952 [PAM:PAM:Waits:WaitBuoyancyChange:A] Stopped
2025-01-23T03:34:43.953Z,1737603283.953 [PAM:PAM:Waits](INFO): Completed PAM:PAM:Waits
2025-01-23T03:34:43.953Z,1737603283.953 [PAM:PAM:Waits] Stopped
2025-01-23T03:34:43.953Z,1737603283.953 [PAM:PAM:Waits](DEBUG): Aggregate::uninitialize PAM:PAM:Waits
2025-01-23T03:34:43.953Z,1737603283.953 [PAM:PAM:Waits:A.Mass] Stopped
2025-01-23T03:34:43.953Z,1737603283.953 [PAM:PAM:Waits:A.Mass](DEBUG): Uninitialize.
2025-01-23T03:34:43.953Z,1737603283.953 [PAM:PAM:Waits:B.Buoyancy] Stopped
2025-01-23T03:34:43.953Z,1737603283.953 [PAM:PAM:Waits:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-01-23T03:34:43.953Z,1737603283.953 [PAM:PAM:C] Running Loop=1
2025-01-23T03:34:44.389Z,1737603284.389 [PAM:PAM:C] Stopped
2025-01-23T03:34:44.389Z,1737603284.389 [PAM:PAM:ApproachDepthSettleAtDepth] Running Loop=1
2025-01-23T03:34:44.389Z,1737603284.389 [PAM:PAM:ApproachDepthSettleAtDepth](DEBUG): Aggregate::initialize PAM:PAM:ApproachDepthSettleAtDepth
2025-01-23T03:34:44.389Z,1737603284.389 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth] Running Loop=1
2025-01-23T03:34:44.389Z,1737603284.389 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth](DEBUG): Aggregate::initialize PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth
2025-01-23T03:34:44.389Z,1737603284.389 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn] Running Loop=1
2025-01-23T03:34:44.389Z,1737603284.389 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn](DEBUG): Aggregate::initialize PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn
2025-01-23T03:34:44.390Z,1737603284.390 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:A.Mass] Running Loop=1
2025-01-23T03:34:44.390Z,1737603284.390 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:A.Mass](DEBUG): Initialize.
2025-01-23T03:34:44.390Z,1737603284.390 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:B.Buoyancy] Running Loop=1
2025-01-23T03:34:44.390Z,1737603284.390 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:B.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-01-23T03:34:44.390Z,1737603284.390 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:E.Point] Running Loop=1
2025-01-23T03:34:44.390Z,1737603284.390 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:E.Point](DEBUG): Initialize.
2025-01-23T03:34:44.390Z,1737603284.390 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:F.SetSpeed] Running Loop=1
2025-01-23T03:34:44.390Z,1737603284.390 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:F.SetSpeed](DEBUG): Initialize.
2025-01-23T03:34:44.390Z,1737603284.390 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:C] Running Loop=1
2025-01-23T03:34:44.755Z,1737603284.755 [PAM:PAM:ApproachDepthSettleAtDepth] Stopped
2025-01-23T03:34:44.755Z,1737603284.755 [PAM:PAM:ApproachDepthSettleAtDepth](DEBUG): Aggregate::uninitialize PAM:PAM:ApproachDepthSettleAtDepth
2025-01-23T03:34:44.755Z,1737603284.755 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth] Stopped
2025-01-23T03:34:44.755Z,1737603284.755 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth](DEBUG): Aggregate::uninitialize PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth
2025-01-23T03:34:44.755Z,1737603284.755 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn] Stopped
2025-01-23T03:34:44.755Z,1737603284.755 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn](DEBUG): Aggregate::uninitialize PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn
2025-01-23T03:34:44.756Z,1737603284.756 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:A.Mass] Stopped
2025-01-23T03:34:44.756Z,1737603284.756 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:A.Mass](DEBUG): Uninitialize.
2025-01-23T03:34:44.756Z,1737603284.756 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:B.Buoyancy] Stopped
2025-01-23T03:34:44.756Z,1737603284.756 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-01-23T03:34:44.756Z,1737603284.756 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:C] Stopped
2025-01-23T03:34:44.756Z,1737603284.756 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:E.Point] Stopped
2025-01-23T03:34:44.756Z,1737603284.756 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:F.SetSpeed] Stopped
2025-01-23T03:34:44.756Z,1737603284.756 [PAM:PAM:ApproachDepthSettleAtDepth:ApproachDepth:ApproachDepthPropellerOn:F.SetSpeed](DEBUG): Uninitialize.
2025-01-23T03:34:44.756Z,1737603284.756 [PAM:PAM](INFO): Completed PAM:PAM
2025-01-23T03:34:44.756Z,1737603284.756 [PAM:PAM] Stopped
2025-01-23T03:34:44.756Z,1737603284.756 [PAM:PAM](DEBUG): Aggregate::uninitialize PAM:PAM
2025-01-23T03:34:44.756Z,1737603284.756 [PAM:PAM](INFO): Running loop #51
2025-01-23T03:34:44.757Z,1737603284.757 [PAM:PAM] Running Loop=51
2025-01-23T03:34:44.757Z,1737603284.757 [PAM:PAM](DEBUG): Aggregate::initialize PAM:PAM
2025-01-23T03:34:44.757Z,1737603284.757 [PAM:PAM:SettingVariables] Running Loop=1
2025-01-23T03:34:44.757Z,1737603284.757 [PAM:PAM:SettingVariables](DEBUG): Aggregate::initialize PAM:PAM:SettingVariables
2025-01-23T03:34:44.757Z,1737603284.757 [PAM:PAM:SettingVariables:A.Mass] Running Loop=1
2025-01-23T03:34:44.757Z,1737603284.757 [PAM:PAM:SettingVariables:A.Mass](DEBUG): Initialize.
2025-01-23T03:34:44.757Z,1737603284.757 [PAM:PAM:SettingVariables:B.Buoyancy] Running Loop=1
2025-01-23T03:34:44.757Z,1737603284.757 [PAM:PAM:SettingVariables:B.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-01-23T03:34:44.757Z,1737603284.757 [PAM:PAM:SettingVariables:C] Running Loop=1
2025-01-23T03:34:45.168Z,1737603285.168 [PAM:PAM:SettingVariables] Stopped
2025-01-23T03:34:45.169Z,1737603285.169 [PAM:PAM:SettingVariables](DEBUG): Aggregate::uninitialize PAM:PAM:SettingVariables
2025-01-23T03:34:45.169Z,1737603285.169 [PAM:PAM:SettingVariables:A.Mass] Stopped
2025-01-23T03:34:45.169Z,1737603285.169 [PAM:PAM:SettingVariables:A.Mass](DEBUG): Uninitialize.
2025-01-23T03:34:45.169Z,1737603285.169 [PAM:PAM:SettingVariables:B.Buoyancy] Stopped
2025-01-23T03:34:45.169Z,1737603285.169 [PAM:PAM:SettingVariables:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-01-23T03:34:45.169Z,1737603285.169 [PAM:PAM:SettingVariables:C] Stopped
2025-01-23T03:34:45.169Z,1737603285.169 [PAM:PAM:Waits] Running Loop=1
2025-01-23T03:34:45.170Z,1737603285.170 [PAM:PAM:Waits](DEBUG): Aggregate::initialize PAM:PAM:Waits
2025-01-23T03:34:45.170Z,1737603285.170 [PAM:PAM:Waits:A.Mass] Running Loop=1
2025-01-23T03:34:45.170Z,1737603285.170 [PAM:PAM:Waits:A.Mass](DEBUG): Initialize.
2025-01-23T03:34:45.170Z,1737603285.170 [PAM:PAM:Waits:B.Buoyancy] Running Loop=1
2025-01-23T03:34:45.170Z,1737603285.170 [PAM:PAM:Waits:B.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-01-23T03:34:45.170Z,1737603285.170 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion] Running Loop=1
2025-01-23T03:34:45.171Z,1737603285.171 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion](DEBUG): Aggregate::initialize PAM:PAM:Waits:WaitForSurfaceCommsCompletion
2025-01-23T03:34:45.171Z,1737603285.171 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion:A.Wait] Running Loop=1
2025-01-23T03:34:45.171Z,1737603285.171 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion:A.Wait](DEBUG): Initialize Wait Component.
2025-01-23T03:34:55.056Z,1737603295.056 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:34:56.060Z,1737603296.060 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:35:26.192Z,1737603326.192 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:35:27.196Z,1737603327.196 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:35:27.215Z,1737603327.215 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-01-23T03:35:51.871Z,1737603351.871 [PAM](INFO): Timed out from 2025-01-22T03:35:51.7Z
2025-01-23T03:35:51.871Z,1737603351.871 [MissionManager](INFO): PAM is completed.
2025-01-23T03:35:51.871Z,1737603351.871 [MissionManager](INFO): Uninitializing Mission PAM
2025-01-23T03:35:51.871Z,1737603351.871 [PAM] Stopped
2025-01-23T03:35:51.871Z,1737603351.871 [PAM](DEBUG): Aggregate::uninitialize PAM
2025-01-23T03:35:51.872Z,1737603351.872 [PAM:Science] Stopped
2025-01-23T03:35:51.872Z,1737603351.872 [PAM:Science](DEBUG): Aggregate::uninitialize PAM:Science
2025-01-23T03:35:51.872Z,1737603351.872 [PAM:Science:B] Stopped
2025-01-23T03:35:51.872Z,1737603351.872 [PAM:Science:C] Stopped
2025-01-23T03:35:51.872Z,1737603351.872 [PAM:Science:E] Stopped
2025-01-23T03:35:51.872Z,1737603351.872 [PAM:Science:F] Stopped
2025-01-23T03:35:51.872Z,1737603351.872 [PAM:Science:OceanCurrent] Stopped
2025-01-23T03:35:51.872Z,1737603351.872 [PAM:Science:OceanCurrent](DEBUG): Aggregate::uninitialize PAM:Science:OceanCurrent
2025-01-23T03:35:51.872Z,1737603351.872 [PAM:Science:OceanCurrent:A.] Stopped
2025-01-23T03:35:51.872Z,1737603351.872 [PAM:StandardEnvelopes] Stopped
2025-01-23T03:35:51.872Z,1737603351.872 [PAM:StandardEnvelopes](DEBUG): Aggregate::uninitialize PAM:StandardEnvelopes
2025-01-23T03:35:51.872Z,1737603351.872 [PAM:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2025-01-23T03:35:51.872Z,1737603351.872 [PAM:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2025-01-23T03:35:51.872Z,1737603351.872 [PAM:StandardEnvelopes:B.DepthEnvelope] Stopped
2025-01-23T03:35:51.873Z,1737603351.873 [PAM:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2025-01-23T03:35:51.873Z,1737603351.873 [PAM:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2025-01-23T03:35:51.873Z,1737603351.873 [PAM:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2025-01-23T03:35:51.873Z,1737603351.873 [PAM:AbortDrift] Stopped
2025-01-23T03:35:51.873Z,1737603351.873 [PAM:AbortDrift](DEBUG): Aggregate::uninitialize PAM:AbortDrift
2025-01-23T03:35:51.873Z,1737603351.873 [PAM:AbortDrift:B.AbortDrift] Stopped
2025-01-23T03:35:51.873Z,1737603351.873 [PAM:AbortDrift:B.AbortDrift](INFO): Uninitializing AbortDrift.
2025-01-23T03:35:51.873Z,1737603351.873 [PAM:BackseatDriver] Stopped
2025-01-23T03:35:51.873Z,1737603351.873 [PAM:BackseatDriver](DEBUG): Aggregate::uninitialize PAM:BackseatDriver
2025-01-23T03:35:51.873Z,1737603351.873 [PAM:BackseatDriver:A.BackseatDriver] Stopped
2025-01-23T03:35:51.873Z,1737603351.873 [PAM:K] Stopped
2025-01-23T03:35:51.873Z,1737603351.873 [PAM:L] Stopped
2025-01-23T03:35:51.873Z,1737603351.873 [PAM:M] Stopped
2025-01-23T03:35:51.873Z,1737603351.873 [PAM:N] Stopped
2025-01-23T03:35:51.874Z,1737603351.874 [PAM:CommsAndOptionallyTransitBackToListeningLocation] Stopped
2025-01-23T03:35:51.874Z,1737603351.874 [PAM:CommsAndOptionallyTransitBackToListeningLocation](DEBUG): Aggregate::uninitialize PAM:CommsAndOptionallyTransitBackToListeningLocation
2025-01-23T03:35:51.874Z,1737603351.874 [PAM:CommsAndOptionallyTransitBackToListeningLocation:CommsAndLevelRun] Stopped
2025-01-23T03:35:51.874Z,1737603351.874 [PAM:CommsAndOptionallyTransitBackToListeningLocation:CommsAndLevelRun](DEBUG): Aggregate::uninitialize PAM:CommsAndOptionallyTransitBackToListeningLocation:CommsAndLevelRun
2025-01-23T03:35:51.874Z,1737603351.874 [PAM:CommsAndOptionallyTransitBackToListeningLocation:CommsAndLevelRun:D.SetSpeed] Stopped
2025-01-23T03:35:51.874Z,1737603351.874 [PAM:CommsAndOptionallyTransitBackToListeningLocation:CommsAndLevelRun:D.SetSpeed](DEBUG): Uninitialize.
2025-01-23T03:35:51.874Z,1737603351.874 [PAM:CommsAndOptionallyTransitBackToListeningLocation:CommsAndLevelRun:E.Mass] Stopped
2025-01-23T03:35:51.874Z,1737603351.874 [PAM:CommsAndOptionallyTransitBackToListeningLocation:CommsAndLevelRun:E.Mass](DEBUG): Uninitialize.
2025-01-23T03:35:51.874Z,1737603351.874 [PAM:CommsAndOptionallyTransitBackToListeningLocation:CommsAndLevelRun:F.Buoyancy] Stopped
2025-01-23T03:35:51.874Z,1737603351.874 [PAM:CommsAndOptionallyTransitBackToListeningLocation:CommsAndLevelRun:F.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-01-23T03:35:51.874Z,1737603351.874 [PAM:CommsAndOptionallyTransitBackToListeningLocation:CommsAndLevelRun:G.Pitch] Stopped
2025-01-23T03:35:51.874Z,1737603351.874 [PAM:CommsAndOptionallyTransitBackToListeningLocation:CommsAndLevelRun:H.Waypoint] Stopped
2025-01-23T03:35:51.874Z,1737603351.874 [PAM:CommsAndOptionallyTransitBackToListeningLocation:CommsAndLevelRun:H.Waypoint](DEBUG): Uninitialize WaypointComponent.
2025-01-23T03:35:51.875Z,1737603351.875 [PAM:PAM] Stopped
2025-01-23T03:35:51.875Z,1737603351.875 [PAM:PAM](DEBUG): Aggregate::uninitialize PAM:PAM
2025-01-23T03:35:51.875Z,1737603351.875 [PAM:PAM:Waits] Stopped
2025-01-23T03:35:51.875Z,1737603351.875 [PAM:PAM:Waits](DEBUG): Aggregate::uninitialize PAM:PAM:Waits
2025-01-23T03:35:51.875Z,1737603351.875 [PAM:PAM:Waits:A.Mass] Stopped
2025-01-23T03:35:51.875Z,1737603351.875 [PAM:PAM:Waits:A.Mass](DEBUG): Uninitialize.
2025-01-23T03:35:51.875Z,1737603351.875 [PAM:PAM:Waits:B.Buoyancy] Stopped
2025-01-23T03:35:51.875Z,1737603351.875 [PAM:PAM:Waits:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-01-23T03:35:51.875Z,1737603351.875 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion] Stopped
2025-01-23T03:35:51.876Z,1737603351.876 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion](DEBUG): Aggregate::uninitialize PAM:PAM:Waits:WaitForSurfaceCommsCompletion
2025-01-23T03:35:51.876Z,1737603351.876 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion:A.Wait] Stopped
2025-01-23T03:35:51.876Z,1737603351.876 [PAM:PAM:Waits:WaitForSurfaceCommsCompletion:A.Wait](DEBUG): Uninitialize Wait Component.
2025-01-23T03:35:52.312Z,1737603352.312 [WetLabsUBAT](INFO): Powering down
2025-01-23T03:35:52.441Z,1737603352.441 [WetLabsBB2FL](INFO): Powering down
2025-01-23T03:35:52.521Z,1737603352.521 [MissionManager](IMPORTANT): Started mission DefaultWithUndock
2025-01-23T03:35:52.522Z,1737603352.522 [DefaultWithUndock] Running Loop=1
2025-01-23T03:35:52.522Z,1737603352.522 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock
2025-01-23T03:35:52.522Z,1737603352.522 [DefaultWithUndock:A.Wait] Running Loop=1
2025-01-23T03:35:52.522Z,1737603352.522 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component.
2025-01-23T03:35:57.324Z,1737603357.324 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:35:58.328Z,1737603358.328 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:36:22.805Z,1737603382.805 [DefaultWithUndock:A.Wait](INFO): Done Waiting.
2025-01-23T03:36:22.805Z,1737603382.805 [DefaultWithUndock:A.Wait] Stopped
2025-01-23T03:36:22.805Z,1737603382.805 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component.
2025-01-23T03:36:22.805Z,1737603382.805 [DefaultWithUndock:LeaveDock] Running Loop=1
2025-01-23T03:36:22.805Z,1737603382.805 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::initialize DefaultWithUndock:LeaveDock
2025-01-23T03:36:22.806Z,1737603382.806 [DefaultWithUndock:LeaveDock:A.] Running Loop=1
2025-01-23T03:36:22.806Z,1737603382.806 [DefaultWithUndock:LeaveDock:A.](INFO): Initializing TrackAcousticContact.
2025-01-23T03:36:22.806Z,1737603382.806 [DefaultWithUndock:LeaveDock:B.Undock] Running Loop=1
2025-01-23T03:36:22.806Z,1737603382.806 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize.
2025-01-23T03:36:22.806Z,1737603382.806 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values.
2025-01-23T03:36:23.216Z,1737603383.216 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize.
2025-01-23T03:36:23.216Z,1737603383.216 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values.
2025-01-23T03:36:23.217Z,1737603383.217 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached from dock at depth 40.262131 m.
2025-01-23T03:36:23.355Z,1737603383.355 [DockingStepper](INFO): Resume
2025-01-23T03:36:23.644Z,1737603383.644 [DockingStepper](INFO): Resuming
2025-01-23T03:36:24.021Z,1737603384.021 [DockingStepper](INFO): Start
2025-01-23T03:36:24.022Z,1737603384.022 [DockingStepper](DEBUG): Initializing EZServoServo.
2025-01-23T03:36:24.141Z,1737603384.141 [DockingStepper](DEBUG): Initializing DockingStepper.
2025-01-23T03:36:24.644Z,1737603384.644 [DockingStepper](INFO): Starting
2025-01-23T03:36:24.852Z,1737603384.852 [DockingStepper](INFO): Starting
2025-01-23T03:36:25.580Z,1737603385.580 [DockingStepper](INFO): Starting
2025-01-23T03:36:25.636Z,1737603385.636 [DockingStepper](INFO): Init string: /1z16352aE13561N3m50
2025-01-23T03:36:25.848Z,1737603385.848 [DockingStepper](INFO): Starting
2025-01-23T03:36:26.140Z,1737603386.140 [DockingStepper](INFO): Starting
2025-01-23T03:36:26.567Z,1737603386.567 [DockingStepper](INFO): Starting
2025-01-23T03:36:27.065Z,1737603387.065 [DockingStepper](INFO): Starting
2025-01-23T03:36:27.373Z,1737603387.373 [DockingStepper](INFO): Starting
2025-01-23T03:36:27.400Z,1737603387.400 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@
2025-01-23T03:36:27.764Z,1737603387.764 [DockingStepper](INFO): Starting
2025-01-23T03:36:28.184Z,1737603388.184 [DockingStepper](INFO): Changing to mode: 3
2025-01-23T03:36:28.184Z,1737603388.184 [DockingStepper](INFO): Detach mode.
2025-01-23T03:36:28.248Z,1737603388.248 [DockingStepper](INFO): Commanding negative:54500
2025-01-23T03:36:28.456Z,1737603388.456 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:36:29.460Z,1737603389.460 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:36:32.976Z,1737603392.976 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2025-01-23T03:36:33.107Z,1737603393.107 [DAT](DEBUG): Acoustic response timeout
2025-01-23T03:36:33.107Z,1737603393.107 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-01-23T03:36:33.360Z,1737603393.360 [DAT](INFO): DAT read:
2025-01-23T03:36:33.361Z,1737603393.361 [DAT](INFO): DAT read: Tx time:03:36:35.3621
2025-01-23T03:36:33.361Z,1737603393.361 [DAT](INFO): Ping request sent.
2025-01-23T03:36:33.361Z,1737603393.361 [DAT](INFO): transmitted an acoustic signal
2025-01-23T03:36:33.361Z,1737603393.361 [DAT](INFO): publishing transmit ping time
2025-01-23T03:36:33.362Z,1737603393.362 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000731
2025-01-23T03:36:33.613Z,1737603393.613 [DAT](INFO): DAT read: TxSync time:03:36:35.3613
2025-01-23T03:36:33.614Z,1737603393.614 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.252151
2025-01-23T03:36:33.865Z,1737603393.865 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.503446
2025-01-23T03:36:34.117Z,1737603394.117 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.755164
2025-01-23T03:36:34.186Z,1737603394.186 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detected possible detachment. Starting timer.
2025-01-23T03:36:34.369Z,1737603394.369 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.007688
2025-01-23T03:36:34.620Z,1737603394.620 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.259003
2025-01-23T03:36:34.872Z,1737603394.872 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.511006
2025-01-23T03:36:35.125Z,1737603395.125 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.763187
2025-01-23T03:36:35.376Z,1737603395.376 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.015125
2025-01-23T03:36:35.628Z,1737603395.628 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.267002
2025-01-23T03:36:35.881Z,1737603395.881 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.519294
2025-01-23T03:36:36.133Z,1737603396.133 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.771421
2025-01-23T03:36:36.384Z,1737603396.384 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022969
2025-01-23T03:36:36.637Z,1737603396.637 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.275534
2025-01-23T03:36:36.888Z,1737603396.888 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.527061
2025-01-23T03:36:37.140Z,1737603397.140 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.779024
2025-01-23T03:36:37.393Z,1737603397.393 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.031227
2025-01-23T03:36:37.644Z,1737603397.644 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.283060
2025-01-23T03:36:37.900Z,1737603397.900 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.538153
2025-01-23T03:36:38.153Z,1737603398.153 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.791290
2025-01-23T03:36:38.405Z,1737603398.405 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.043167
2025-01-23T03:36:38.657Z,1737603398.657 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.295148
2025-01-23T03:36:38.909Z,1737603398.909 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.547194
2025-01-23T03:36:39.160Z,1737603399.160 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.799078
2025-01-23T03:36:39.412Z,1737603399.412 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.050961
2025-01-23T03:36:39.664Z,1737603399.664 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.303020
2025-01-23T03:36:39.917Z,1737603399.917 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.555158
2025-01-23T03:36:40.168Z,1737603400.168 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.806918
2025-01-23T03:36:40.420Z,1737603400.420 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.058970
2025-01-23T03:36:40.673Z,1737603400.673 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.311197
2025-01-23T03:36:40.924Z,1737603400.924 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.563081
2025-01-23T03:36:41.176Z,1737603401.176 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.815021
2025-01-23T03:36:41.428Z,1737603401.428 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.066992
2025-01-23T03:36:41.680Z,1737603401.680 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.318996
2025-01-23T03:36:41.933Z,1737603401.933 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.571297
2025-01-23T03:36:42.184Z,1737603402.184 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.823086
2025-01-23T03:36:42.436Z,1737603402.436 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.075003
2025-01-23T03:36:42.689Z,1737603402.689 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.327300
2025-01-23T03:36:42.940Z,1737603402.940 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.579062
2025-01-23T03:36:43.081Z,1737603403.081 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2025-01-23T03:36:43.192Z,1737603403.192 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.830988
2025-01-23T03:36:43.444Z,1737603403.444 [DAT](INFO): Reached modem response timeout
2025-01-23T03:36:43.445Z,1737603403.445 [DAT](DEBUG): Acoustic response timeout
2025-01-23T03:36:43.445Z,1737603403.445 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-01-23T03:36:43.696Z,1737603403.696 [DAT](INFO): Reached modem response timeout
2025-01-23T03:36:43.696Z,1737603403.696 [DAT](DEBUG): Acoustic response timeout
2025-01-23T03:36:43.697Z,1737603403.697 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-01-23T03:36:43.949Z,1737603403.949 [DAT](INFO): Reached modem response timeout
2025-01-23T03:36:43.949Z,1737603403.949 [DAT](DEBUG): Acoustic response timeout
2025-01-23T03:36:43.949Z,1737603403.949 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-01-23T03:36:44.202Z,1737603404.202 [DAT](INFO): DAT read: Response Not Received
2025-01-23T03:36:44.202Z,1737603404.202 [DAT](INFO): response not received
2025-01-23T03:36:44.204Z,1737603404.204 [DAT](INFO): DAT read: user:21>
2025-01-23T03:36:44.206Z,1737603404.206 [DAT](INFO): DAT read: Tx time:03:36:46.3122
2025-01-23T03:36:44.206Z,1737603404.206 [DAT](INFO): Ping request sent.
2025-01-23T03:36:44.206Z,1737603404.206 [DAT](INFO): transmitted an acoustic signal
2025-01-23T03:36:44.206Z,1737603404.206 [DAT](INFO): publishing transmit ping time
2025-01-23T03:36:44.207Z,1737603404.207 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000733
2025-01-23T03:36:44.453Z,1737603404.453 [DAT](INFO): DAT read: TxSync time:03:36:46.3114
2025-01-23T03:36:44.453Z,1737603404.453 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.247004
2025-01-23T03:36:44.717Z,1737603404.717 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.510477
2025-01-23T03:36:44.969Z,1737603404.969 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.762256
2025-01-23T03:36:45.220Z,1737603405.220 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.014125
2025-01-23T03:36:45.472Z,1737603405.472 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.266023
2025-01-23T03:36:45.724Z,1737603405.724 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.518110
2025-01-23T03:36:45.977Z,1737603405.977 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.770223
2025-01-23T03:36:46.230Z,1737603406.230 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.023697
2025-01-23T03:36:46.480Z,1737603406.480 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.274100
2025-01-23T03:36:46.745Z,1737603406.745 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.538602
2025-01-23T03:36:46.997Z,1737603406.997 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.790253
2025-01-23T03:36:47.249Z,1737603407.249 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.042249
2025-01-23T03:36:47.501Z,1737603407.501 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.294233
2025-01-23T03:36:47.752Z,1737603407.752 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.546051
2025-01-23T03:36:48.004Z,1737603408.004 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.798193
2025-01-23T03:36:48.258Z,1737603408.258 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.051867
2025-01-23T03:36:48.508Z,1737603408.508 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.302134
2025-01-23T03:36:48.761Z,1737603408.761 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.554372
2025-01-23T03:36:49.015Z,1737603409.015 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.808358
2025-01-23T03:36:49.264Z,1737603409.264 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.058069
2025-01-23T03:36:49.521Z,1737603409.521 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.314478
2025-01-23T03:36:49.772Z,1737603409.772 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.566121
2025-01-23T03:36:50.024Z,1737603410.024 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.818074
2025-01-23T03:36:50.281Z,1737603410.281 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.073873
2025-01-23T03:36:50.528Z,1737603410.528 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.322198
2025-01-23T03:36:50.780Z,1737603410.780 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.574176
2025-01-23T03:36:51.033Z,1737603411.033 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.826310
2025-01-23T03:36:51.284Z,1737603411.284 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.078088
2025-01-23T03:36:51.536Z,1737603411.536 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.330143
2025-01-23T03:36:51.789Z,1737603411.789 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.582374
2025-01-23T03:36:52.041Z,1737603412.041 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.834295
2025-01-23T03:36:52.292Z,1737603412.292 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.085996
2025-01-23T03:36:52.545Z,1737603412.545 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.338289
2025-01-23T03:36:52.797Z,1737603412.797 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.590379
2025-01-23T03:36:53.048Z,1737603413.048 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.842072
2025-01-23T03:36:53.175Z,1737603413.175 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2025-01-23T03:36:53.301Z,1737603413.301 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.094401
2025-01-23T03:36:53.552Z,1737603413.552 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.346127
2025-01-23T03:36:53.804Z,1737603413.804 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.598083
2025-01-23T03:36:54.057Z,1737603414.057 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.850463
2025-01-23T03:36:54.308Z,1737603414.308 [DAT](INFO): Reached modem response timeout
2025-01-23T03:36:54.308Z,1737603414.308 [DAT](DEBUG): Acoustic response timeout
2025-01-23T03:36:54.309Z,1737603414.309 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-01-23T03:36:54.416Z,1737603414.416 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached at range: nan m. Transitioning docking module to standby.
2025-01-23T03:36:54.560Z,1737603414.560 [DAT](INFO): Reached modem response timeout
2025-01-23T03:36:54.560Z,1737603414.560 [DAT](DEBUG): Acoustic response timeout
2025-01-23T03:36:54.560Z,1737603414.560 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-01-23T03:36:54.803Z,1737603414.803 [DockingStepper](INFO): Changing to mode: 1
2025-01-23T03:36:54.803Z,1737603414.803 [DockingStepper](INFO): Standby mode.
2025-01-23T03:36:54.812Z,1737603414.812 [DAT](INFO): Reached modem response timeout
2025-01-23T03:36:54.813Z,1737603414.813 [DAT](DEBUG): Acoustic response timeout
2025-01-23T03:36:54.813Z,1737603414.813 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-01-23T03:36:54.863Z,1737603414.863 [DockingStepper](INFO): Commanding positive:54476
2025-01-23T03:36:55.064Z,1737603415.064 [DAT](INFO): DAT read: Response Not Received
2025-01-23T03:36:55.065Z,1737603415.065 [DAT](INFO): response not received
2025-01-23T03:36:55.065Z,1737603415.065 [DAT](INFO): DAT read: user:22>
2025-01-23T03:36:55.065Z,1737603415.065 [DAT](ERROR): No response from remote modem.
2025-01-23T03:36:55.066Z,1737603415.066 [DAT](DEBUG): Acoustic response timeout
2025-01-23T03:36:55.066Z,1737603415.066 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-01-23T03:36:55.316Z,1737603415.316 [DAT](INFO): DAT read: Tx time:03:36:57.2622
2025-01-23T03:36:55.317Z,1737603415.317 [DAT](INFO): Ping request sent.
2025-01-23T03:36:55.317Z,1737603415.317 [DAT](INFO): transmitted an acoustic signal
2025-01-23T03:36:55.317Z,1737603415.317 [DAT](INFO): DAT read: TxSync time:03:36:57.2614
2025-01-23T03:36:55.318Z,1737603415.318 [DAT](INFO): publishing transmit ping time
2025-01-23T03:36:55.318Z,1737603415.318 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.001520
2025-01-23T03:36:55.569Z,1737603415.569 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251687
2025-01-23T03:36:55.820Z,1737603415.820 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.503483
2025-01-23T03:36:56.076Z,1737603416.076 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.758401
2025-01-23T03:36:56.329Z,1737603416.329 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.011731
2025-01-23T03:36:56.580Z,1737603416.580 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.263514
2025-01-23T03:36:56.832Z,1737603416.832 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.515545
2025-01-23T03:36:57.084Z,1737603417.084 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.767572
2025-01-23T03:36:57.336Z,1737603417.336 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.019564
2025-01-23T03:36:57.588Z,1737603417.588 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.271440
2025-01-23T03:36:57.840Z,1737603417.840 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.523448
2025-01-23T03:36:58.093Z,1737603418.093 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.776498
2025-01-23T03:36:58.344Z,1737603418.344 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.027338
2025-01-23T03:36:58.596Z,1737603418.596 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.279465
2025-01-23T03:36:58.848Z,1737603418.848 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.531448
2025-01-23T03:36:59.100Z,1737603419.100 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.783375
2025-01-23T03:36:59.352Z,1737603419.352 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.035475
2025-01-23T03:36:59.580Z,1737603419.580 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:36:59.604Z,1737603419.604 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.287460
2025-01-23T03:36:59.856Z,1737603419.856 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.539383
2025-01-23T03:37:00.109Z,1737603420.109 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.791631
2025-01-23T03:37:00.360Z,1737603420.360 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.043507
2025-01-23T03:37:00.584Z,1737603420.584 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:37:00.614Z,1737603420.614 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.297333
2025-01-23T03:37:00.855Z,1737603420.855 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Docking module at standby.
2025-01-23T03:37:00.866Z,1737603420.866 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.549443
2025-01-23T03:37:01.116Z,1737603421.116 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.799528
2025-01-23T03:37:01.264Z,1737603421.264 [DefaultWithUndock:LeaveDock:B.Undock](IMPORTANT): Undocking sequence complete.
2025-01-23T03:37:01.264Z,1737603421.264 [DefaultWithUndock:LeaveDock:B.Undock] Stopped
2025-01-23T03:37:01.265Z,1737603421.265 [DefaultWithUndock:LeaveDock](INFO): Completed DefaultWithUndock:LeaveDock
2025-01-23T03:37:01.265Z,1737603421.265 [DefaultWithUndock:LeaveDock] Stopped
2025-01-23T03:37:01.265Z,1737603421.265 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::uninitialize DefaultWithUndock:LeaveDock
2025-01-23T03:37:01.265Z,1737603421.265 [DefaultWithUndock:LeaveDock:A.] Stopped
2025-01-23T03:37:01.265Z,1737603421.265 [DefaultWithUndock:LeaveDock:A.](DEBUG): Uninitializing TrackAcousticContact.
2025-01-23T03:37:01.265Z,1737603421.265 [DefaultWithUndock:SurfaceDefault] Running Loop=1
2025-01-23T03:37:01.265Z,1737603421.265 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault
2025-01-23T03:37:01.265Z,1737603421.265 [DefaultWithUndock:SurfaceDefault:A.GoToSurface] Running Loop=1
2025-01-23T03:37:01.265Z,1737603421.265 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-01-23T03:37:01.266Z,1737603421.266 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-01-23T03:37:01.266Z,1737603421.266 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-01-23T03:37:01.266Z,1737603421.266 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-01-23T03:37:01.266Z,1737603421.266 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-01-23T03:37:01.267Z,1737603421.267 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-01-23T03:37:01.267Z,1737603421.267 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-01-23T03:37:01.368Z,1737603421.368 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.051443
2025-01-23T03:37:01.621Z,1737603421.621 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.303731
2025-01-23T03:37:01.686Z,1737603421.686 [DockingStepper](INFO): Pause
2025-01-23T03:37:01.872Z,1737603421.872 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.555461
2025-01-23T03:37:02.062Z,1737603422.062 [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-01-23T03:37:02.124Z,1737603422.124 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.807344
2025-01-23T03:37:02.268Z,1737603422.268 [Radio_Surface](INFO): Powering up
2025-01-23T03:37:02.377Z,1737603422.377 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.059763
2025-01-23T03:37:02.628Z,1737603422.628 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.311511
2025-01-23T03:37:02.883Z,1737603422.883 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.565691
2025-01-23T03:37:03.133Z,1737603423.133 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.815722
2025-01-23T03:37:03.385Z,1737603423.385 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.067580
2025-01-23T03:37:03.636Z,1737603423.636 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.319481
2025-01-23T03:37:03.890Z,1737603423.890 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.572639
2025-01-23T03:37:04.141Z,1737603424.141 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.823626
2025-01-23T03:37:04.392Z,1737603424.392 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.075438
2025-01-23T03:37:04.645Z,1737603424.645 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.328102
2025-01-23T03:37:04.896Z,1737603424.896 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.579535
2025-01-23T03:37:05.149Z,1737603425.149 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.831827
2025-01-23T03:37:05.400Z,1737603425.400 [DAT](INFO): Reached modem response timeout
2025-01-23T03:37:06.157Z,1737603426.157 [DAT](INFO): DAT read: Response Not Received
2025-01-23T03:37:06.158Z,1737603426.158 [DAT](INFO): response not received
2025-01-23T03:37:06.158Z,1737603426.158 [DAT](INFO): DAT read: user:23>
2025-01-23T03:37:06.159Z,1737603426.159 [DAT](INFO): DAT read: Tx time:03:37:08.2123
2025-01-23T03:37:06.159Z,1737603426.159 [DAT](INFO): Ping request sent.
2025-01-23T03:37:06.159Z,1737603426.159 [DAT](INFO): transmitted an acoustic signal
2025-01-23T03:37:06.159Z,1737603426.159 [DAT](INFO): publishing transmit ping time
2025-01-23T03:37:06.160Z,1737603426.160 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000835
2025-01-23T03:37:06.409Z,1737603426.409 [DAT](INFO): DAT read: TxSync time:03:37:08.2115
2025-01-23T03:37:06.409Z,1737603426.409 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250101
2025-01-23T03:37:06.660Z,1737603426.660 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.501135
2025-01-23T03:37:06.913Z,1737603426.913 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.753548
2025-01-23T03:37:07.165Z,1737603427.165 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.005344
2025-01-23T03:37:07.416Z,1737603427.416 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.257174
2025-01-23T03:37:07.520Z,1737603427.520 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.10
2025-01-23T03:37:07.520Z,1737603427.520 [Radio_Surface](INFO): Found local IP address 10.89.10.2 for eth0
2025-01-23T03:37:07.668Z,1737603427.668 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.509278
2025-01-23T03:37:07.921Z,1737603427.921 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.761426
2025-01-23T03:37:08.172Z,1737603428.172 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.013265
2025-01-23T03:37:08.425Z,1737603428.425 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.265550
2025-01-23T03:37:08.676Z,1737603428.676 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.517214
2025-01-23T03:37:08.928Z,1737603428.928 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.769230
2025-01-23T03:37:09.185Z,1737603429.185 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.026105
2025-01-23T03:37:09.436Z,1737603429.436 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.277238
2025-01-23T03:37:09.688Z,1737603429.688 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.529122
2025-01-23T03:37:09.940Z,1737603429.940 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.781274
2025-01-23T03:37:10.192Z,1737603430.192 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.033182
2025-01-23T03:37:10.449Z,1737603430.449 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.289297
2025-01-23T03:37:10.696Z,1737603430.696 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.537167
2025-01-23T03:37:10.948Z,1737603430.948 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.789235
2025-01-23T03:37:11.200Z,1737603431.200 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.041239
2025-01-23T03:37:11.452Z,1737603431.452 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.293188
2025-01-23T03:37:11.704Z,1737603431.704 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.545216
2025-01-23T03:37:11.956Z,1737603431.956 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.797083
2025-01-23T03:37:12.210Z,1737603432.210 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.050685
2025-01-23T03:37:12.461Z,1737603432.461 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.301373
2025-01-23T03:37:12.712Z,1737603432.712 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.553189
2025-01-23T03:37:12.965Z,1737603432.965 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.805604
2025-01-23T03:37:13.216Z,1737603433.216 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.057178
2025-01-23T03:37:13.469Z,1737603433.469 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.309377
2025-01-23T03:37:13.720Z,1737603433.720 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.561243
2025-01-23T03:37:13.972Z,1737603433.972 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.813185
2025-01-23T03:37:14.227Z,1737603434.227 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.067014
2025-01-23T03:37:14.477Z,1737603434.477 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.317512
2025-01-23T03:37:14.729Z,1737603434.729 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.569344
2025-01-23T03:37:14.980Z,1737603434.980 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.821150
2025-01-23T03:37:15.233Z,1737603435.233 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.073901
2025-01-23T03:37:15.484Z,1737603435.484 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.325243
2025-01-23T03:37:15.736Z,1737603435.736 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.577180
2025-01-23T03:37:15.989Z,1737603435.989 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.829847
2025-01-23T03:37:16.997Z,1737603436.997 [DAT](INFO): DAT read: Response Not Received
2025-01-23T03:37:16.997Z,1737603436.997 [DAT](INFO): response not received
2025-01-23T03:37:16.997Z,1737603436.997 [DAT](INFO): DAT read: user:24>
2025-01-23T03:37:16.998Z,1737603436.998 [DAT](INFO): DAT read: Tx time:03:37:19.1623
2025-01-23T03:37:16.998Z,1737603436.998 [DAT](INFO): Ping request sent.
2025-01-23T03:37:16.998Z,1737603436.998 [DAT](INFO): transmitted an acoustic signal
2025-01-23T03:37:16.998Z,1737603436.998 [DAT](INFO): publishing transmit ping time
2025-01-23T03:37:16.999Z,1737603436.999 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000714
2025-01-23T03:37:17.248Z,1737603437.248 [DAT](INFO): DAT read: TxSync time:03:37:19.1616
2025-01-23T03:37:17.249Z,1737603437.249 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250838
2025-01-23T03:37:17.500Z,1737603437.500 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502090
2025-01-23T03:37:17.752Z,1737603437.752 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754110
2025-01-23T03:37:18.004Z,1737603438.004 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006071
2025-01-23T03:37:18.259Z,1737603438.259 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.260438
2025-01-23T03:37:18.509Z,1737603438.509 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510179
2025-01-23T03:37:18.760Z,1737603438.760 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762030
2025-01-23T03:37:19.014Z,1737603439.014 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.015251
2025-01-23T03:37:19.264Z,1737603439.264 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266069
2025-01-23T03:37:19.516Z,1737603439.516 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.517971
2025-01-23T03:37:19.768Z,1737603439.768 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770147
2025-01-23T03:37:20.021Z,1737603440.021 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022170
2025-01-23T03:37:20.275Z,1737603440.275 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.276174
2025-01-23T03:37:20.525Z,1737603440.525 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.526251
2025-01-23T03:37:20.776Z,1737603440.776 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.778102
2025-01-23T03:37:21.028Z,1737603441.028 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.030071
2025-01-23T03:37:21.284Z,1737603441.284 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.285203
2025-01-23T03:37:21.532Z,1737603441.532 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.534097
2025-01-23T03:37:21.785Z,1737603441.785 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.786311
2025-01-23T03:37:22.036Z,1737603442.036 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.038001
2025-01-23T03:37:22.290Z,1737603442.290 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.291245
2025-01-23T03:37:22.540Z,1737603442.540 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.542027
2025-01-23T03:37:22.793Z,1737603442.793 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.794348
2025-01-23T03:37:23.045Z,1737603443.045 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.046487
2025-01-23T03:37:23.296Z,1737603443.296 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.297953
2025-01-23T03:37:23.549Z,1737603443.549 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.550261
2025-01-23T03:37:23.800Z,1737603443.800 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.802148
2025-01-23T03:37:24.052Z,1737603444.052 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.054012
2025-01-23T03:37:24.305Z,1737603444.305 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.306398
2025-01-23T03:37:24.556Z,1737603444.556 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.558086
2025-01-23T03:37:24.809Z,1737603444.809 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.810309
2025-01-23T03:37:25.061Z,1737603445.061 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.062584
2025-01-23T03:37:25.312Z,1737603445.312 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.314032
2025-01-23T03:37:25.564Z,1737603445.564 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.566129
2025-01-23T03:37:25.817Z,1737603445.817 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.818261
2025-01-23T03:37:26.068Z,1737603446.068 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.070146
2025-01-23T03:37:26.321Z,1737603446.321 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.322728
2025-01-23T03:37:26.572Z,1737603446.572 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.574029
2025-01-23T03:37:26.825Z,1737603446.825 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.826198
2025-01-23T03:37:28.085Z,1737603448.085 [DAT](INFO): DAT read: Response Not Received
2025-01-23T03:37:28.086Z,1737603448.086 [DAT](INFO): response not received
2025-01-23T03:37:28.088Z,1737603448.088 [DAT](INFO): DAT read: user:25>
2025-01-23T03:37:28.091Z,1737603448.091 [DAT](INFO): DAT read: Tx time:03:37:30.1124
2025-01-23T03:37:28.091Z,1737603448.091 [DAT](INFO): Ping request sent.
2025-01-23T03:37:28.092Z,1737603448.092 [DAT](INFO): transmitted an acoustic signal
2025-01-23T03:37:28.092Z,1737603448.092 [DAT](INFO): publishing transmit ping time
2025-01-23T03:37:28.092Z,1737603448.092 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000887
2025-01-23T03:37:28.337Z,1737603448.337 [DAT](INFO): DAT read: TxSync time:03:37:30.1116
2025-01-23T03:37:28.337Z,1737603448.337 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.245822
2025-01-23T03:37:28.588Z,1737603448.588 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.496810
2025-01-23T03:37:28.840Z,1737603448.840 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.748776
2025-01-23T03:37:29.092Z,1737603449.092 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.000744
2025-01-23T03:37:29.345Z,1737603449.345 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.252767
2025-01-23T03:37:29.597Z,1737603449.597 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.504907
2025-01-23T03:37:29.849Z,1737603449.849 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.756953
2025-01-23T03:37:30.100Z,1737603450.100 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.008773
2025-01-23T03:37:30.353Z,1737603450.353 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.261104
2025-01-23T03:37:30.604Z,1737603450.604 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.512866
2025-01-23T03:37:30.711Z,1737603450.711 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:37:30.857Z,1737603450.857 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.765171
2025-01-23T03:37:31.109Z,1737603451.109 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.017051
2025-01-23T03:37:31.361Z,1737603451.361 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.269355
2025-01-23T03:37:31.612Z,1737603451.612 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.520846
2025-01-23T03:37:31.712Z,1737603451.712 [DataOverHttps](INFO): Radio surface powered ON.
2025-01-23T03:37:31.712Z,1737603451.712 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:37:31.864Z,1737603451.864 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.772787
2025-01-23T03:37:32.116Z,1737603452.116 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.024783
2025-01-23T03:37:32.368Z,1737603452.368 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.276857
2025-01-23T03:37:32.620Z,1737603452.620 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.528882
2025-01-23T03:37:32.872Z,1737603452.872 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.780819
2025-01-23T03:37:33.125Z,1737603453.125 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.033290
2025-01-23T03:37:33.377Z,1737603453.377 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.285035
2025-01-23T03:37:33.628Z,1737603453.628 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.536794
2025-01-23T03:37:33.880Z,1737603453.880 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.788784
2025-01-23T03:37:34.132Z,1737603454.132 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.040825
2025-01-23T03:37:34.385Z,1737603454.385 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.292912
2025-01-23T03:37:34.637Z,1737603454.637 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.545409
2025-01-23T03:37:34.889Z,1737603454.889 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.797377
2025-01-23T03:37:35.140Z,1737603455.140 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.048831
2025-01-23T03:37:35.393Z,1737603455.393 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.301853
2025-01-23T03:37:35.645Z,1737603455.645 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.553367
2025-01-23T03:37:35.896Z,1737603455.896 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.804858
2025-01-23T03:37:36.148Z,1737603456.148 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.056762
2025-01-23T03:37:36.400Z,1737603456.400 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.308879
2025-01-23T03:37:36.653Z,1737603456.653 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.561458
2025-01-23T03:37:36.905Z,1737603456.905 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.813487
2025-01-23T03:37:37.157Z,1737603457.157 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.064984
2025-01-23T03:37:37.408Z,1737603457.408 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.316825
2025-01-23T03:37:37.661Z,1737603457.661 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.569426
2025-01-23T03:37:37.912Z,1737603457.912 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.820880
2025-01-23T03:37:38.921Z,1737603458.921 [DAT](INFO): DAT read: Response Not Received
2025-01-23T03:37:38.921Z,1737603458.921 [DAT](INFO): response not received
2025-01-23T03:37:38.922Z,1737603458.922 [DAT](INFO): DAT read: user:26>
2025-01-23T03:37:38.923Z,1737603458.923 [DAT](INFO): DAT read: Tx time:03:37:41.0625
2025-01-23T03:37:38.923Z,1737603458.923 [DAT](INFO): Ping request sent.
2025-01-23T03:37:38.924Z,1737603458.924 [DAT](INFO): transmitted an acoustic signal
2025-01-23T03:37:38.924Z,1737603458.924 [DAT](INFO): publishing transmit ping time
2025-01-23T03:37:38.925Z,1737603458.925 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.001126
2025-01-23T03:37:39.172Z,1737603459.172 [DAT](INFO): DAT read: TxSync time:03:37:41.0617
2025-01-23T03:37:39.173Z,1737603459.173 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250048
2025-01-23T03:37:39.424Z,1737603459.424 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.501181
2025-01-23T03:37:39.677Z,1737603459.677 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.753328
2025-01-23T03:37:39.932Z,1737603459.932 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.008428
2025-01-23T03:37:40.181Z,1737603460.181 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.257463
2025-01-23T03:37:40.432Z,1737603460.432 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.509247
2025-01-23T03:37:40.686Z,1737603460.686 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762410
2025-01-23T03:37:40.937Z,1737603460.937 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.013612
2025-01-23T03:37:41.188Z,1737603461.188 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.265211
2025-01-23T03:37:41.440Z,1737603461.440 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.517158
2025-01-23T03:37:41.693Z,1737603461.693 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.769642
2025-01-23T03:37:41.944Z,1737603461.944 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.021227
2025-01-23T03:37:42.196Z,1737603462.196 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.273185
2025-01-23T03:37:42.449Z,1737603462.449 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.525425
2025-01-23T03:37:42.700Z,1737603462.700 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.777236
2025-01-23T03:37:42.953Z,1737603462.953 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.029714
2025-01-23T03:37:43.204Z,1737603463.204 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.281172
2025-01-23T03:37:43.456Z,1737603463.456 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.533134
2025-01-23T03:37:43.710Z,1737603463.710 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.786788
2025-01-23T03:37:43.961Z,1737603463.961 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.037391
2025-01-23T03:37:44.212Z,1737603464.212 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.289228
2025-01-23T03:37:44.464Z,1737603464.464 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.541147
2025-01-23T03:37:44.716Z,1737603464.716 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.793133
2025-01-23T03:37:44.969Z,1737603464.969 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.045773
2025-01-23T03:37:45.220Z,1737603465.220 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.297172
2025-01-23T03:37:45.472Z,1737603465.472 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.549284
2025-01-23T03:37:45.724Z,1737603465.724 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.801181
2025-01-23T03:37:45.976Z,1737603465.976 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.053227
2025-01-23T03:37:46.231Z,1737603466.231 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.307108
2025-01-23T03:37:46.481Z,1737603466.481 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.557796
2025-01-23T03:37:46.732Z,1737603466.732 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.809202
2025-01-23T03:37:46.986Z,1737603466.986 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.062373
2025-01-23T03:37:47.237Z,1737603467.237 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.313735
2025-01-23T03:37:47.489Z,1737603467.489 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.565568
2025-01-23T03:37:47.741Z,1737603467.741 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.817383
2025-01-23T03:37:47.995Z,1737603467.995 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.071686
2025-01-23T03:37:48.248Z,1737603468.248 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.323626
2025-01-23T03:37:48.496Z,1737603468.496 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.573119
2025-01-23T03:37:48.748Z,1737603468.748 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.825181
2025-01-23T03:37:50.010Z,1737603470.010 [DAT](INFO): DAT read: Response Not Received
2025-01-23T03:37:50.010Z,1737603470.010 [DAT](INFO): response not received
2025-01-23T03:37:50.010Z,1737603470.010 [DAT](INFO): DAT read: user:27>
2025-01-23T03:37:50.011Z,1737603470.011 [DAT](INFO): DAT read: Tx time:03:37:52.0125
2025-01-23T03:37:50.012Z,1737603470.012 [DAT](INFO): Ping request sent.
2025-01-23T03:37:50.012Z,1737603470.012 [DAT](INFO): transmitted an acoustic signal
2025-01-23T03:37:50.012Z,1737603470.012 [DAT](INFO): publishing transmit ping time
2025-01-23T03:37:50.013Z,1737603470.013 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.001017
2025-01-23T03:37:50.263Z,1737603470.263 [DAT](INFO): DAT read: TxSync time:03:37:52.0118
2025-01-23T03:37:50.266Z,1737603470.266 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.253475
2025-01-23T03:37:50.512Z,1737603470.512 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.500286
2025-01-23T03:37:50.764Z,1737603470.764 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.752328
2025-01-23T03:37:51.017Z,1737603471.017 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.004941
2025-01-23T03:37:51.268Z,1737603471.268 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.256301
2025-01-23T03:37:51.521Z,1737603471.521 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.508673
2025-01-23T03:37:51.775Z,1737603471.775 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762450
2025-01-23T03:37:52.025Z,1737603472.025 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.012494
2025-01-23T03:37:52.280Z,1737603472.280 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.267125
2025-01-23T03:37:52.528Z,1737603472.528 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.516353
2025-01-23T03:37:52.780Z,1737603472.780 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.768260
2025-01-23T03:37:53.032Z,1737603473.032 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.020408
2025-01-23T03:37:53.284Z,1737603473.284 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.272349
2025-01-23T03:37:53.536Z,1737603473.536 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.524308
2025-01-23T03:37:53.788Z,1737603473.788 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.776291
2025-01-23T03:37:54.041Z,1737603474.041 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.029017
2025-01-23T03:37:54.298Z,1737603474.298 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.285462
2025-01-23T03:37:54.549Z,1737603474.549 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.536951
2025-01-23T03:37:54.800Z,1737603474.800 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.788377
2025-01-23T03:37:55.052Z,1737603475.052 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.040402
2025-01-23T03:37:55.304Z,1737603475.304 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.292323
2025-01-23T03:37:55.557Z,1737603475.557 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.544787
2025-01-23T03:37:55.808Z,1737603475.808 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.796233
2025-01-23T03:37:56.064Z,1737603476.064 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.051661
2025-01-23T03:37:56.313Z,1737603476.313 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.300479
2025-01-23T03:37:56.565Z,1737603476.565 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.552458
2025-01-23T03:37:56.816Z,1737603476.816 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.804259
2025-01-23T03:37:57.069Z,1737603477.069 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.056507
2025-01-23T03:37:57.321Z,1737603477.321 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.308681
2025-01-23T03:37:57.573Z,1737603477.573 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.560632
2025-01-23T03:37:57.824Z,1737603477.824 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.812405
2025-01-23T03:37:58.077Z,1737603478.077 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.064986
2025-01-23T03:37:58.329Z,1737603478.329 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.316680
2025-01-23T03:37:58.581Z,1737603478.581 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.568441
2025-01-23T03:37:58.832Z,1737603478.832 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.820320
2025-01-23T03:37:59.084Z,1737603479.084 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.072318
2025-01-23T03:37:59.336Z,1737603479.336 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.324355
2025-01-23T03:37:59.589Z,1737603479.589 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.576836
2025-01-23T03:37:59.841Z,1737603479.841 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.828562
2025-01-23T03:38:00.852Z,1737603480.852 [DAT](INFO): DAT read: Response Not Received
2025-01-23T03:38:00.853Z,1737603480.853 [DAT](INFO): response not received
2025-01-23T03:38:00.853Z,1737603480.853 [DAT](ERROR): No response from remote modem.
2025-01-23T03:38:01.836Z,1737603481.836 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:38:02.840Z,1737603482.840 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:38:24.229Z,1737603504.229 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=1
2025-01-23T03:38:24.229Z,1737603504.229 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2025-01-23T03:38:24.230Z,1737603504.230 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2025-01-23T03:38:32.960Z,1737603512.960 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:38:33.976Z,1737603513.976 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:38:53.368Z,1737603533.368 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=47871, MT Status=1, MTMSN=2691
2025-01-23T03:38:53.368Z,1737603533.368 [NAL9602](INFO): Data available in MT queue
2025-01-23T03:38:54.044Z,1737603534.044 [NAL9602](INFO): Received command: load Transport/keepstation.tl;set keepstation.NeedCommsTime 20 min;set keepstation.Latitude 36.797 degree;set keepstation.Longitude -121.847 degree;set keepstation.Speed 1 m/s;run
2025-01-23T03:38:54.162Z,1737603534.162 [CommandExec](IMPORTANT): got command load ./Missions/Transport/keepstation.tl
2025-01-23T03:38:54.163Z,1737603534.163 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/keepstation.tl
2025-01-23T03:38:54.163Z,1737603534.163 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/keepstation.tl
2025-01-23T03:38:54.184Z,1737603534.184 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/keepstation.tl
2025-01-23T03:38:54.976Z,1737603534.976 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,033903.00,A,3646.99084,N,12151.69419,W,0.097,16.76,230125,,,A*47
2025-01-23T03:38:54.978Z,1737603534.978 [NAL9602](INFO): GPS fix at 20250123T033903: (36.783181, -121.861570)
2025-01-23T03:38:55.024Z,1737603535.024 [UniversalFixResidualReporter](INFO): Fix residual: 4.1 %DT, over the last 1265.4 m. Residual distance 52.2 m at bearing -46.7 degrees. Fix at (36.7832, -121.8616) with 1230.2 m made good.
2025-01-23T03:38:55.043Z,1737603535.043 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/keepstation.tl translated into:
4
45
NaN
NaN
30
15
4
0.75
200
45
2
2025-01-23T03:38:55.058Z,1737603535.058 [MissionManager](INFO): DefineArg keepstation.MissionTimeout = 4.000000 h
2025-01-23T03:38:55.076Z,1737603535.076 [MissionManager](INFO): DefineArg keepstation.NeedCommsTime = 45.000000 min
2025-01-23T03:38:55.078Z,1737603535.078 [MissionManager](INFO): DefineArg keepstation.Latitude = nan arcdeg
2025-01-23T03:38:55.084Z,1737603535.084 [MissionManager](INFO): DefineArg keepstation.Longitude = nan arcdeg
2025-01-23T03:38:55.086Z,1737603535.086 [MissionManager](INFO): DefineArg keepstation.Depth = 30.000000 m
2025-01-23T03:38:55.093Z,1737603535.093 [MissionManager](INFO): DefineArg keepstation.ApproachDepth = 15.000000 m
2025-01-23T03:38:55.095Z,1737603535.095 [MissionManager](INFO): DefineArg keepstation.DepthDeadband = 4.000000 m
2025-01-23T03:38:55.105Z,1737603535.105 [MissionManager](INFO): DefineArg keepstation.Speed = 0.750000 m/s
2025-01-23T03:38:55.107Z,1737603535.107 [MissionManager](INFO): DefineArg keepstation.Radius = 200.000000 m
2025-01-23T03:38:55.113Z,1737603535.113 [MissionManager](INFO): DefineArg keepstation.MaxDepth = 45.000000 m
2025-01-23T03:38:55.120Z,1737603535.120 [MissionManager](INFO): DefineArg keepstation.MinOffshore = 2.000000 km
2025-01-23T03:38:55.120Z,1737603535.120 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2025-01-23T03:38:55.120Z,1737603535.120 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl
2025-01-23T03:38:55.122Z,1737603535.122 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl
2025-01-23T03:38:55.465Z,1737603535.465 [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-01-23T03:38:55.484Z,1737603535.484 [MissionManager](INFO): DefineArg keepstation:NeedComms.DiveInterval = 1.000000 h
2025-01-23T03:38:55.488Z,1737603535.488 [MissionManager](INFO): DefineArg keepstation:NeedComms.WaitForPitchUp = 10.000000 min
2025-01-23T03:38:55.490Z,1737603535.490 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacePitch = 20.000000 arcdeg
2025-01-23T03:38:55.493Z,1737603535.493 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceDepthRate = nan m/s
2025-01-23T03:38:55.496Z,1737603535.496 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceSpeed = 1.000000 m/s
2025-01-23T03:38:55.498Z,1737603535.498 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacingTimeout = 1000.000000 s
2025-01-23T03:38:55.500Z,1737603535.500 [MissionManager](INFO): DefineArg keepstation:NeedComms.GPSTimeout = 7.000000 min
2025-01-23T03:38:55.502Z,1737603535.502 [MissionManager](INFO): DefineArg keepstation:NeedComms.CommsTimeout = 30.000000 min
2025-01-23T03:38:55.508Z,1737603535.508 [keepstation:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-01-23T03:38:55.524Z,1737603535.524 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2025-01-23T03:38:55.524Z,1737603535.524 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl
2025-01-23T03:38:55.525Z,1737603535.525 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl
2025-01-23T03:38:55.646Z,1737603535.646 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into:
5
0
200
2000
2025-01-23T03:38:55.650Z,1737603535.650 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinAltitude = 5.000000 m
2025-01-23T03:38:55.652Z,1737603535.652 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepthIgnore = 0.000000 m
2025-01-23T03:38:55.654Z,1737603535.654 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepth = 200.000000 m
2025-01-23T03:38:55.656Z,1737603535.656 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinOffshore = 2000.000000 m
2025-01-23T03:38:55.657Z,1737603535.657 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2025-01-23T03:38:55.663Z,1737603535.663 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2025-01-23T03:38:55.669Z,1737603535.669 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2025-01-23T03:38:55.676Z,1737603535.676 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2025-01-23T03:38:55.677Z,1737603535.677 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2025-01-23T03:38:55.678Z,1737603535.678 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2025-01-23T03:38:55.725Z,1737603535.725 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into:
2025-01-23T03:38:55.737Z,1737603535.737 [MissionManager](INFO): DefineArg keepstation:BackseatDriver.EnableBackseat = 0 bool
2025-01-23T03:38:55.739Z,1737603535.739 [keepstation:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2025-01-23T03:38:55.741Z,1737603535.741 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl
2025-01-23T03:38:55.742Z,1737603535.742 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl
2025-01-23T03:38:55.743Z,1737603535.743 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl
2025-01-23T03:38:55.971Z,1737603535.971 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into:
99999
2025-01-23T03:38:55.975Z,1737603535.975 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleAll = 0 bool
2025-01-23T03:38:55.977Z,1737603535.977 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad1 = 0 bool
2025-01-23T03:38:55.978Z,1737603535.978 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad2 = 0 bool
2025-01-23T03:38:55.980Z,1737603535.980 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad3 = 0 bool
2025-01-23T03:38:55.983Z,1737603535.983 [MissionManager](INFO): DefineArg keepstation:PowerOnly.EnabledPowerOnly = 0 bool
2025-01-23T03:38:55.989Z,1737603535.989 [keepstation:PowerOnly:E.Wait](DEBUG): Construct Wait.
2025-01-23T03:38:55.991Z,1737603535.991 [keepstation:H.Pitch](DEBUG): Construct.
2025-01-23T03:38:55.997Z,1737603535.997 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Construct Buoyancy.
2025-01-23T03:38:55.000Z,1737603536.000 [keepstation:TransitToStation:B.Pitch](DEBUG): Construct.
2025-01-23T03:38:56.024Z,1737603536.024 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Construct.
2025-01-23T03:38:56.026Z,1737603536.026 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2025-01-23T03:38:56.042Z,1737603536.042 [keepstation:KeepStation:B.Pitch](DEBUG): Construct.
2025-01-23T03:38:56.050Z,1737603536.050 [keepstation:KeepStation:C.KeepStation](DEBUG): Construct KeepStation.
2025-01-23T03:38:56.055Z,1737603536.055 [keepstation:KeepStation:D.Wait](DEBUG): Construct Wait.
2025-01-23T03:38:56.078Z,1737603536.078 [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 keepstation {
"""
Vehicle transits to desired waypoint (or stays put if none is commanded)
and stays within the specified radius.
"""
arguments {
MissionTimeout = 4 hour
"""
Maximum duration of mission
"""
NeedCommsTime = 45 minute
"""
How often to surface for communications
"""
Latitude = NaN degree
"""
Latitude of waypoint to hold. If NaN, hold the latitude at start of
mission.
"""
Longitude = NaN degree
"""
Longitude of waypoint to hold. If NaN, hold the longitude at start of
mission.
"""
Depth = 30 meter
"""
Depth held during drift mode
"""
ApproachDepth = 15 meter
"""
Depth for initial approach to station.
"""
DepthDeadband = 4 meter
"""
How much vertical drift from the specified depth is allowed in drift
mode
"""
Speed = 0.75 meter_per_second
"""
Vehicle transit speed.
"""
Radius = 200 meter
"""
Radius of circle around waypoint to hold.
"""
MaxDepth = 45 meter
"""
Maximum allowable depth during the mission.
"""
MinOffshore = 2 kilometer
"""
Minimum distance from the shoreline to maintain
"""
}
timeout duration=MissionTimeout
insert id="NeedComms" Insert/NeedComms.tl
assign in sequence NeedComms:DiveInterval = NeedCommsTime
insert Insert/StandardEnvelopes.tl
assign in sequence StandardEnvelopes:MaxDepth = MaxDepth
assign in sequence StandardEnvelopes:MinOffshore = MinOffshore
insert Insert/BackseatDriver.tl
insert Insert/PowerOnly.tl
behavior Guidance:Pitch {
run in parallel
set massPosition = Control:VerticalControl.massDefault
}
call id="StartingMission" refId="NeedComms"
aggregate TransitToStation {
"""
Need a separate aggregate for transit if we want to specify a
different depth for the approach.
"""
run in sequence
behavior Guidance:Buoyancy {
run in parallel
set position = Control:VerticalControl.buoyancyNeutral
}
behavior Guidance:Pitch {
run in parallel
set depth = ApproachDepth
}
behavior Guidance:SetSpeed {
run in parallel
set speed = Speed
}
behavior Guidance:Waypoint id="Wpt1" {
run in sequence
set latitude = Latitude
set longitude = Longitude
}
}
call id="OnStation" refId="NeedComms"
aggregate KeepStation {
run in sequence
assign in parallel Control:VerticalControl.depthDeadband = DepthDeadband
behavior Guidance:Pitch {
run in parallel
set depth = Depth
}
behavior Guidance:KeepStation {
run in parallel
set latitude = Latitude
set longitude = Longitude
set radius = Radius
set speed = Speed
}
behavior Guidance:Wait {
"""
Due to the way the KeepStation behavior is currently
implemented, we must run it in parallel and use a Wait to keep
from completing the mission before the timeout expires. However,
this new version of the mission uses a top-level timeout so that
the entire mission will not run for longer then MissionTimeout.
"""
run in sequence
set duration = MissionTimeout
}
}
}
2025-01-23T03:38:56.079Z,1737603536.079 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/keepstation.tl id=keepstation
2025-01-23T03:38:56.307Z,1737603536.307 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2025-01-23T03:38:56.307Z,1737603536.307 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1
2025-01-23T03:39:04.096Z,1737603544.096 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:39:05.100Z,1737603545.100 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:39:06.845Z,1737603546.845 [CommandExec](IMPORTANT): got command set keepstation.NeedCommsTime 20 minute
2025-01-23T03:39:06.845Z,1737603546.845 [CommandExec](IMPORTANT): got command set keepstation.Latitude 36.797 degree
2025-01-23T03:39:06.846Z,1737603546.846 [CommandExec](IMPORTANT): got command set keepstation.Longitude -121.847 degree
2025-01-23T03:39:06.847Z,1737603546.847 [CommandExec](IMPORTANT): got command set keepstation.Speed 1 meter_per_second
2025-01-23T03:39:06.847Z,1737603546.847 [CommandExec](IMPORTANT): got command run
2025-01-23T03:39:06.870Z,1737603546.870 [CommandExec](IMPORTANT): Running
2025-01-23T03:39:07.314Z,1737603547.314 [DefaultWithUndock] Stopped
2025-01-23T03:39:07.314Z,1737603547.314 [DefaultWithUndock](DEBUG): Aggregate::uninitialize DefaultWithUndock
2025-01-23T03:39:07.314Z,1737603547.314 [DefaultWithUndock:SurfaceDefault] Stopped
2025-01-23T03:39:07.314Z,1737603547.314 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault
2025-01-23T03:39:07.314Z,1737603547.314 [DefaultWithUndock:SurfaceDefault:A.GoToSurface] Stopped
2025-01-23T03:39:07.314Z,1737603547.314 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-01-23T03:39:07.315Z,1737603547.315 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped
2025-01-23T03:39:07.315Z,1737603547.315 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn
2025-01-23T03:39:07.315Z,1737603547.315 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped
2025-01-23T03:39:07.315Z,1737603547.315 [MissionManager](IMPORTANT): Started mission keepstation
2025-01-23T03:39:07.315Z,1737603547.315 [keepstation] Running Loop=1
2025-01-23T03:39:07.315Z,1737603547.315 [keepstation](DEBUG): Aggregate::initialize keepstation
2025-01-23T03:39:07.316Z,1737603547.316 [keepstation:StandardEnvelopes] Running Loop=1
2025-01-23T03:39:07.316Z,1737603547.316 [keepstation:StandardEnvelopes](DEBUG): Aggregate::initialize keepstation:StandardEnvelopes
2025-01-23T03:39:07.316Z,1737603547.316 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-01-23T03:39:07.316Z,1737603547.316 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2025-01-23T03:39:07.316Z,1737603547.316 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-01-23T03:39:07.316Z,1737603547.316 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2025-01-23T03:39:07.316Z,1737603547.316 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-01-23T03:39:07.316Z,1737603547.316 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2025-01-23T03:39:07.316Z,1737603547.316 [keepstation:BackseatDriver] Running Loop=1
2025-01-23T03:39:07.316Z,1737603547.316 [keepstation:BackseatDriver](DEBUG): Aggregate::initialize keepstation:BackseatDriver
2025-01-23T03:39:07.317Z,1737603547.317 [keepstation:BackseatDriver:A.BackseatDriver] Running Loop=1
2025-01-23T03:39:07.317Z,1737603547.317 [keepstation:PowerOnly] Running Loop=1
2025-01-23T03:39:07.317Z,1737603547.317 [keepstation:PowerOnly](DEBUG): Aggregate::initialize keepstation:PowerOnly
2025-01-23T03:39:07.317Z,1737603547.317 [keepstation:PowerOnly:A] Running Loop=1
2025-01-23T03:39:07.317Z,1737603547.317 [keepstation:PowerOnly:B] Running Loop=1
2025-01-23T03:39:07.317Z,1737603547.317 [keepstation:PowerOnly:C] Running Loop=1
2025-01-23T03:39:07.317Z,1737603547.317 [keepstation:PowerOnly:D] Running Loop=1
2025-01-23T03:39:07.317Z,1737603547.317 [keepstation:PowerOnly:E.Wait] Running Loop=1
2025-01-23T03:39:07.317Z,1737603547.317 [keepstation:PowerOnly:E.Wait](DEBUG): Initialize Wait Component.
2025-01-23T03:39:07.317Z,1737603547.317 [keepstation:H.Pitch] Running Loop=1
2025-01-23T03:39:07.317Z,1737603547.317 [keepstation:H.Pitch](DEBUG): Initialize.
2025-01-23T03:39:07.318Z,1737603547.318 [keepstation:B] Running Loop=1
2025-01-23T03:39:07.318Z,1737603547.318 [keepstation:H.Pitch] Running Loop=1
2025-01-23T03:39:07.318Z,1737603547.318 [keepstation:PowerOnly] Running Loop=1
2025-01-23T03:39:07.319Z,1737603547.319 [keepstation:PowerOnly:D] Stopped
2025-01-23T03:39:07.319Z,1737603547.319 [keepstation:PowerOnly:C] Stopped
2025-01-23T03:39:07.319Z,1737603547.319 [keepstation:PowerOnly:B] Stopped
2025-01-23T03:39:07.319Z,1737603547.319 [keepstation:PowerOnly:A] Stopped
2025-01-23T03:39:07.319Z,1737603547.319 [keepstation:BackseatDriver] Running Loop=1
2025-01-23T03:39:07.320Z,1737603547.320 [keepstation:StandardEnvelopes] Running Loop=1
2025-01-23T03:39:07.320Z,1737603547.320 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-01-23T03:39:07.320Z,1737603547.320 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-01-23T03:39:07.321Z,1737603547.321 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-01-23T03:39:07.321Z,1737603547.321 [keepstation:B] Stopped
2025-01-23T03:39:07.321Z,1737603547.321 [keepstation:D] Running Loop=1
2025-01-23T03:39:07.669Z,1737603547.669 [keepstation:D] Stopped
2025-01-23T03:39:07.669Z,1737603547.669 [keepstation:E] Running Loop=1
2025-01-23T03:39:08.075Z,1737603548.075 [keepstation:E] Stopped
2025-01-23T03:39:08.075Z,1737603548.075 [keepstation:StartingMission] Running Loop=1
2025-01-23T03:39:08.076Z,1737603548.076 [keepstation:StartingMission](DEBUG): Aggregate::initialize keepstation:StartingMission
2025-01-23T03:39:08.498Z,1737603548.498 [keepstation:NeedComms] Running Loop=1
2025-01-23T03:39:08.499Z,1737603548.499 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-01-23T03:39:08.499Z,1737603548.499 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-01-23T03:39:08.499Z,1737603548.499 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-01-23T03:39:08.499Z,1737603548.499 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-01-23T03:39:08.500Z,1737603548.500 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-01-23T03:39:08.500Z,1737603548.500 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-01-23T03:39:08.500Z,1737603548.500 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-01-23T03:39:08.501Z,1737603548.501 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-01-23T03:39:08.501Z,1737603548.501 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-01-23T03:39:08.501Z,1737603548.501 [keepstation:NeedComms:A] Running Loop=1
2025-01-23T03:39:08.503Z,1737603548.503 [keepstation:NeedComms:A](INFO): last time_fix was: 1737603543.000000 second since 1970/01/01T00:00:00Z
2025-01-23T03:39:08.503Z,1737603548.503 [keepstation:NeedComms:A] Stopped
2025-01-23T03:39:08.878Z,1737603548.878 [keepstation:NeedComms:C] Running Loop=1
2025-01-23T03:39:09.279Z,1737603549.279 [keepstation:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-01-23T03:39:17.347Z,1737603557.347 [NAL9602](INFO): SBD MO Status=1, MOMSN=47872, MT Status=0, MTMSN=0
2025-01-23T03:39:17.400Z,1737603557.400 [NAL9602](INFO): Sent 83 bytes from file Logs/20250123T030326/Courier0006.lzma
2025-01-23T03:39:17.401Z,1737603557.401 [NAL9602](INFO): Packets left to send: 0
2025-01-23T03:39:18.562Z,1737603558.562 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,033927.00,A,3646.98998,N,12151.69462,W,0.272,134.92,230125,,,D*7F
2025-01-23T03:39:18.565Z,1737603558.565 [NAL9602](INFO): GPS fix at 20250123T033927: (36.783166, -121.861577)
2025-01-23T03:39:18.622Z,1737603558.622 [keepstation:NeedComms:C] Stopped
2025-01-23T03:39:18.622Z,1737603558.622 [keepstation:NeedComms:D] Running Loop=1
2025-01-23T03:39:18.996Z,1737603558.996 [keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-01-23T03:39:35.236Z,1737603575.236 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:39:36.241Z,1737603576.241 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:39:45.392Z,1737603585.392 [NAL9602](INFO): SBD MO Status=1, MOMSN=47873, MT Status=0, MTMSN=0
2025-01-23T03:39:45.440Z,1737603585.440 [NAL9602](INFO): Sent 39 bytes from file Logs/20250123T030326/Courier0009.lzma
2025-01-23T03:39:45.440Z,1737603585.440 [NAL9602](INFO): Packets left to send: 0
2025-01-23T03:39:55.322Z,1737603595.322 [NAL9602](INFO): SBD MO Status=1, MOMSN=47874, MT Status=0, MTMSN=0
2025-01-23T03:39:55.372Z,1737603595.372 [NAL9602](INFO): Sent 64 bytes from file Logs/20250123T030326/Courier0012.lzma
2025-01-23T03:39:55.372Z,1737603595.372 [NAL9602](INFO): Packets left to send: 0
2025-01-23T03:40:06.360Z,1737603606.360 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:40:07.364Z,1737603607.364 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:40:19.501Z,1737603619.501 [NAL9602](INFO): SBD MO Status=1, MOMSN=47875, MT Status=0, MTMSN=0
2025-01-23T03:40:19.553Z,1737603619.553 [NAL9602](INFO): Sent 332 bytes from file Logs/20250123T030326/Express0007.lzma
2025-01-23T03:40:19.553Z,1737603619.553 [NAL9602](INFO): Packets left to send: 1
2025-01-23T03:40:36.300Z,1737603636.300 [NAL9602](INFO): SBD MO Status=1, MOMSN=47876, MT Status=0, MTMSN=0
2025-01-23T03:40:36.352Z,1737603636.352 [NAL9602](INFO): Sent 319 bytes from file Logs/20250123T030326/Express0007.lzma
2025-01-23T03:40:36.352Z,1737603636.352 [NAL9602](INFO): Packets left to send: 0
2025-01-23T03:40:37.484Z,1737603637.484 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:40:38.488Z,1737603638.488 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:40:47.267Z,1737603647.267 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002794
2025-01-23T03:40:53.324Z,1737603653.324 [NAL9602](INFO): SBD MO Status=1, MOMSN=47877, MT Status=0, MTMSN=0
2025-01-23T03:40:53.384Z,1737603653.384 [NAL9602](INFO): Sent 230 bytes from file Logs/20250123T030326/Express0010.lzma
2025-01-23T03:40:53.384Z,1737603653.384 [NAL9602](INFO): Packets left to send: 0
2025-01-23T03:41:24.029Z,1737603684.029 [NAL9602](INFO): Not Powering down - fast GPS
2025-01-23T03:41:47.461Z,1737603707.461 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.196220
2025-01-23T03:42:17.381Z,1737603737.381 [NAL9602](INFO): SBD MO Status=2, MOMSN=47878, MT Status=2, MTMSN=0
2025-01-23T03:42:17.381Z,1737603737.381 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-01-23T03:42:18.601Z,1737603738.601 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:42:19.604Z,1737603739.604 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:42:44.987Z,1737603764.987 [NAL9602](INFO): SBD MO Status=2, MOMSN=47878, MT Status=2, MTMSN=0
2025-01-23T03:42:44.987Z,1737603764.987 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-01-23T03:43:06.281Z,1737603786.281 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.008559
2025-01-23T03:43:12.515Z,1737603792.515 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD, +40.48, , -9.61, 114.36, 0.00
2025-01-23T03:43:19.015Z,1737603799.015 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20250123T030326/Express0013.lzma
2025-01-23T03:43:20.018Z,1737603800.018 [DataOverHttps](INFO): Moved sent file to Logs/20250123T030326/Express0013.lzma.bak
2025-01-23T03:43:20.018Z,1737603800.018 [DataOverHttps](INFO): SBD MOMSN=23924972
2025-01-23T03:43:25.039Z,1737603805.039 [keepstation:NeedComms:D] Stopped
2025-01-23T03:43:25.039Z,1737603805.039 [keepstation:NeedComms:E] Running Loop=1
2025-01-23T03:43:25.461Z,1737603805.461 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-01-23T03:43:27.026Z,1737603807.026 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,034335.00,A,3646.96477,N,12151.68611,W,0.428,155.51,230125,,,D*75
2025-01-23T03:43:27.028Z,1737603807.028 [NAL9602](INFO): GPS fix at 20250123T034335: (36.782746, -121.861435)
2025-01-23T03:43:27.074Z,1737603807.074 [keepstation:NeedComms:E] Stopped
2025-01-23T03:43:27.075Z,1737603807.075 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-01-23T03:43:27.075Z,1737603807.075 [keepstation:NeedComms] Stopped
2025-01-23T03:43:27.075Z,1737603807.075 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-01-23T03:43:27.079Z,1737603807.079 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-01-23T03:43:27.080Z,1737603807.080 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-01-23T03:43:27.481Z,1737603807.481 [keepstation:StartingMission](INFO): Completed keepstation:StartingMission
2025-01-23T03:43:27.481Z,1737603807.481 [keepstation:StartingMission] Stopped
2025-01-23T03:43:27.481Z,1737603807.481 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission
2025-01-23T03:43:27.481Z,1737603807.481 [keepstation:TransitToStation] Running Loop=1
2025-01-23T03:43:27.481Z,1737603807.481 [keepstation:TransitToStation](DEBUG): Aggregate::initialize keepstation:TransitToStation
2025-01-23T03:43:27.481Z,1737603807.481 [keepstation:TransitToStation:A.Buoyancy] Running Loop=1
2025-01-23T03:43:27.481Z,1737603807.481 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-01-23T03:43:27.481Z,1737603807.481 [keepstation:TransitToStation:B.Pitch] Running Loop=1
2025-01-23T03:43:27.481Z,1737603807.481 [keepstation:TransitToStation:B.Pitch](DEBUG): Initialize.
2025-01-23T03:43:27.482Z,1737603807.482 [keepstation:TransitToStation:C.SetSpeed] Running Loop=1
2025-01-23T03:43:27.482Z,1737603807.482 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Initialize.
2025-01-23T03:43:27.482Z,1737603807.482 [keepstation:TransitToStation:Wpt1.Waypoint] Running Loop=1
2025-01-23T03:43:27.482Z,1737603807.482 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2025-01-23T03:43:27.483Z,1737603807.483 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.797001,-121.847000
2025-01-23T03:43:27.858Z,1737603807.858 [keepstation:TransitToStation:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.797001,-121.847000
2025-01-23T03:43:27.858Z,1737603807.858 [keepstation:TransitToStation:C.SetSpeed] Running Loop=1
2025-01-23T03:43:27.859Z,1737603807.859 [keepstation:TransitToStation:B.Pitch] Running Loop=1
2025-01-23T03:43:27.859Z,1737603807.859 [keepstation:TransitToStation:A.Buoyancy] Running Loop=1
2025-01-23T03:43:46.608Z,1737603826.608 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,25012219445548,35.0, +12.7, 0.0,1499.5,A, 0.00,1270.04, 883.16,1136.12, 972.44
2025-01-23T03:43:50.230Z,1737603830.230 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:44:00.360Z,1737603840.360 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:44:09.560Z,1737603849.560 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:44:21.880Z,1737603861.880 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:44:31.116Z,1737603871.116 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:44:40.448Z,1737603880.448 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:44:49.520Z,1737603889.520 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:44:58.964Z,1737603898.964 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:45:07.135Z,1737603907.135 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position 20.38 mm (3 active estimators).
2025-01-23T03:45:30.052Z,1737603930.052 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:45:58.165Z,1737603958.165 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.203819
2025-01-23T03:45:59.168Z,1737603959.168 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:46:07.508Z,1737603967.508 [Radio_Surface](INFO): Powering down
2025-01-23T03:46:29.297Z,1737603989.297 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:46:30.300Z,1737603990.300 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-01-23T03:46:30.300Z,1737603990.300 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:47:00.420Z,1737604020.420 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:47:01.424Z,1737604021.424 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:47:31.544Z,1737604051.544 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:47:32.548Z,1737604052.548 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:47:40.316Z,1737604060.316 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,25012219484948,35.0 0.0,1499.1, 0
2025-01-23T03:48:02.668Z,1737604082.668 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:48:03.672Z,1737604083.672 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:48:29.141Z,1737604109.141 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-01-23T03:48:33.792Z,1737604113.792 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:48:34.796Z,1737604114.796 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:48:59.843Z,1737604139.843 [NAL9602](INFO): Not Powering down - fast GPS
2025-01-23T03:49:04.916Z,1737604144.916 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:49:05.920Z,1737604145.920 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:49:36.040Z,1737604176.040 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:49:37.044Z,1737604177.044 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:50:07.164Z,1737604207.164 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:50:08.168Z,1737604208.168 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:50:38.288Z,1737604238.288 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:50:39.292Z,1737604239.292 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:51:09.412Z,1737604269.412 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:51:10.416Z,1737604270.416 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:51:40.540Z,1737604300.540 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:51:41.546Z,1737604301.546 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:52:11.664Z,1737604331.664 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:52:12.668Z,1737604332.668 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:52:42.788Z,1737604362.788 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:52:43.792Z,1737604363.792 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:53:13.920Z,1737604393.920 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:53:14.924Z,1737604394.924 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:53:45.044Z,1737604425.044 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:53:46.049Z,1737604426.049 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:54:16.168Z,1737604456.168 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:54:17.173Z,1737604457.173 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:54:47.305Z,1737604487.305 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:54:48.308Z,1737604488.308 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:55:18.428Z,1737604518.428 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:55:19.433Z,1737604519.433 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:55:49.560Z,1737604549.560 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:55:50.564Z,1737604550.564 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:56:20.684Z,1737604580.684 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:56:21.689Z,1737604581.689 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:56:51.810Z,1737604611.810 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:56:52.812Z,1737604612.812 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:57:22.940Z,1737604642.940 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:57:23.944Z,1737604643.944 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:57:54.068Z,1737604674.068 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:57:55.074Z,1737604675.074 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:58:25.196Z,1737604705.196 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:58:26.200Z,1737604706.200 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:58:56.320Z,1737604736.320 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:58:57.324Z,1737604737.324 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:59:27.448Z,1737604767.448 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:59:28.452Z,1737604768.452 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T03:59:58.572Z,1737604798.572 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T03:59:59.576Z,1737604799.576 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T04:00:29.696Z,1737604829.696 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T04:00:30.700Z,1737604830.700 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T04:01:00.838Z,1737604860.838 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T04:01:01.841Z,1737604861.841 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T04:01:31.976Z,1737604891.976 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T04:01:32.980Z,1737604892.980 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T04:02:03.100Z,1737604923.100 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T04:02:04.120Z,1737604924.120 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T04:02:34.241Z,1737604954.241 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T04:02:35.244Z,1737604955.244 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T04:03:05.364Z,1737604985.364 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T04:03:06.368Z,1737604986.368 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T04:03:36.488Z,1737605016.488 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T04:03:37.494Z,1737605017.494 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T04:03:58.012Z,1737605038.012 [keepstation:NeedComms] Running Loop=1
2025-01-23T04:03:58.012Z,1737605038.012 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-01-23T04:03:58.012Z,1737605038.012 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-01-23T04:03:58.012Z,1737605038.012 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-01-23T04:03:58.012Z,1737605038.012 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-01-23T04:03:58.013Z,1737605038.013 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-01-23T04:03:58.014Z,1737605038.014 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-01-23T04:03:58.019Z,1737605038.019 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-01-23T04:03:58.019Z,1737605038.019 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-01-23T04:03:58.020Z,1737605038.020 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-01-23T04:03:58.020Z,1737605038.020 [keepstation:NeedComms:A] Running Loop=1
2025-01-23T04:03:58.022Z,1737605038.022 [keepstation:NeedComms:A](INFO): last time_fix was: 1737603815.000000 second since 1970/01/01T00:00:00Z
2025-01-23T04:03:58.022Z,1737605038.022 [keepstation:NeedComms:A] Stopped
2025-01-23T04:04:07.620Z,1737605047.620 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T04:04:08.624Z,1737605048.624 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T04:04:38.744Z,1737605078.744 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T04:04:39.748Z,1737605079.748 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T04:04:49.576Z,1737605089.576 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](IMPORTANT): Altitude envelope ACTIVE
2025-01-23T04:04:55.950Z,1737605095.950 [keepstation:NeedComms:C] Running Loop=1
2025-01-23T04:04:56.440Z,1737605096.440 [Radio_Surface](INFO): Powering up
2025-01-23T04:05:01.692Z,1737605101.692 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.10
2025-01-23T04:05:01.692Z,1737605101.692 [Radio_Surface](INFO): Found local IP address 10.89.10.2 for eth0
2025-01-23T04:05:09.880Z,1737605109.880 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T04:05:10.884Z,1737605110.884 [DataOverHttps](INFO): Radio surface powered ON.
2025-01-23T04:05:10.884Z,1737605110.884 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T04:05:28.711Z,1737605128.711 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-01-23T04:05:28.783Z,1737605128.783 [NAL9602](ERROR): received:
+CSQ:0
OK878, 2, 0, 0, 0
OK
2025-01-23T04:05:41.016Z,1737605141.016 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T04:05:42.020Z,1737605142.020 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T04:05:55.788Z,1737605155.788 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=47878, MT Status=1, MTMSN=2692
2025-01-23T04:05:55.788Z,1737605155.788 [NAL9602](INFO): Data available in MT queue
2025-01-23T04:05:56.304Z,1737605156.304 [NAL9602](INFO): Received command: stop
2025-01-23T04:05:56.368Z,1737605156.368 [CommandExec](IMPORTANT): got command stop
2025-01-23T04:05:56.368Z,1737605156.368 [CommandExec](IMPORTANT): Scheduling is paused
2025-01-23T04:05:56.369Z,1737605156.369 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2025-01-23T04:05:56.564Z,1737605156.564 [MissionManager](INFO): MissionManager is completed.
2025-01-23T04:05:56.564Z,1737605156.564 [MissionManager](INFO): Uninitializing Mission keepstation
2025-01-23T04:05:56.564Z,1737605156.564 [keepstation] Stopped
2025-01-23T04:05:56.564Z,1737605156.564 [keepstation](DEBUG): Aggregate::uninitialize keepstation
2025-01-23T04:05:56.564Z,1737605156.564 [keepstation:NeedComms] Stopped
2025-01-23T04:05:56.564Z,1737605156.564 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-01-23T04:05:56.564Z,1737605156.564 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-01-23T04:05:56.564Z,1737605156.564 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-01-23T04:05:56.564Z,1737605156.564 [keepstation:NeedComms:C] Stopped
2025-01-23T04:05:56.564Z,1737605156.564 [keepstation:StandardEnvelopes] Stopped
2025-01-23T04:05:56.565Z,1737605156.565 [keepstation:StandardEnvelopes](DEBUG): Aggregate::uninitialize keepstation:StandardEnvelopes
2025-01-23T04:05:56.565Z,1737605156.565 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2025-01-23T04:05:56.565Z,1737605156.565 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2025-01-23T04:05:56.565Z,1737605156.565 [keepstation:StandardEnvelopes:B.DepthEnvelope] Stopped
2025-01-23T04:05:56.565Z,1737605156.565 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2025-01-23T04:05:56.565Z,1737605156.565 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2025-01-23T04:05:56.565Z,1737605156.565 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2025-01-23T04:05:56.565Z,1737605156.565 [keepstation:BackseatDriver] Stopped
2025-01-23T04:05:56.565Z,1737605156.565 [keepstation:BackseatDriver](DEBUG): Aggregate::uninitialize keepstation:BackseatDriver
2025-01-23T04:05:56.565Z,1737605156.565 [keepstation:BackseatDriver:A.BackseatDriver] Stopped
2025-01-23T04:05:56.565Z,1737605156.565 [keepstation:PowerOnly] Stopped
2025-01-23T04:05:56.565Z,1737605156.565 [keepstation:PowerOnly](DEBUG): Aggregate::uninitialize keepstation:PowerOnly
2025-01-23T04:05:56.565Z,1737605156.565 [keepstation:PowerOnly:E.Wait] Stopped
2025-01-23T04:05:56.566Z,1737605156.566 [keepstation:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component.
2025-01-23T04:05:56.566Z,1737605156.566 [keepstation:H.Pitch] Stopped
2025-01-23T04:05:56.566Z,1737605156.566 [keepstation:TransitToStation] Stopped
2025-01-23T04:05:56.566Z,1737605156.566 [keepstation:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:TransitToStation
2025-01-23T04:05:56.566Z,1737605156.566 [keepstation:TransitToStation:A.Buoyancy] Stopped
2025-01-23T04:05:56.566Z,1737605156.566 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-01-23T04:05:56.566Z,1737605156.566 [keepstation:TransitToStation:B.Pitch] Stopped
2025-01-23T04:05:56.566Z,1737605156.566 [keepstation:TransitToStation:C.SetSpeed] Stopped
2025-01-23T04:05:56.566Z,1737605156.566 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Uninitialize.
2025-01-23T04:05:56.566Z,1737605156.566 [keepstation:TransitToStation:Wpt1.Waypoint] Stopped
2025-01-23T04:05:56.566Z,1737605156.566 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2025-01-23T04:05:57.038Z,1737605157.038 [MissionManager](IMPORTANT): Started mission DefaultWithUndock
2025-01-23T04:05:57.039Z,1737605157.039 [DefaultWithUndock] Running Loop=1
2025-01-23T04:05:57.039Z,1737605157.039 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock
2025-01-23T04:05:57.039Z,1737605157.039 [DefaultWithUndock:A.Wait] Running Loop=1
2025-01-23T04:05:57.039Z,1737605157.039 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component.
2025-01-23T04:05:57.354Z,1737605157.354 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,040605.00,A,3647.35570,N,12151.18615,W,0.486,285.61,230125,,,D*74
2025-01-23T04:05:57.364Z,1737605157.364 [NAL9602](INFO): GPS fix at 20250123T040605: (36.789262, -121.853103)
2025-01-23T04:05:57.377Z,1737605157.377 [UniversalFixResidualReporter](INFO): Fix residual: 12.4 %DT, over the last 1222.6 m. Residual distance 152.1 m at bearing -43.2 degrees. Fix at (36.7893, -121.8531) with 1037.1 m made good.
2025-01-23T04:06:12.140Z,1737605172.140 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T04:06:13.144Z,1737605173.144 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T04:06:27.250Z,1737605187.250 [DefaultWithUndock:A.Wait](INFO): Done Waiting.
2025-01-23T04:06:27.250Z,1737605187.250 [DefaultWithUndock:A.Wait] Stopped
2025-01-23T04:06:27.250Z,1737605187.250 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component.
2025-01-23T04:06:27.251Z,1737605187.251 [DefaultWithUndock:LeaveDock] Running Loop=1
2025-01-23T04:06:27.251Z,1737605187.251 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::initialize DefaultWithUndock:LeaveDock
2025-01-23T04:06:27.251Z,1737605187.251 [DefaultWithUndock:LeaveDock:A.] Running Loop=1
2025-01-23T04:06:27.251Z,1737605187.251 [DefaultWithUndock:LeaveDock:A.](INFO): Initializing TrackAcousticContact.
2025-01-23T04:06:27.251Z,1737605187.251 [DefaultWithUndock:LeaveDock:B.Undock] Running Loop=1
2025-01-23T04:06:27.251Z,1737605187.251 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize.
2025-01-23T04:06:27.251Z,1737605187.251 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values.
2025-01-23T04:06:27.696Z,1737605187.696 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize.
2025-01-23T04:06:27.696Z,1737605187.696 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values.
2025-01-23T04:06:27.696Z,1737605187.696 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached from dock at depth 0.012113 m.
2025-01-23T04:06:27.697Z,1737605187.697 [DefaultWithUndock:LeaveDock:A.] Stopped
2025-01-23T04:06:27.697Z,1737605187.697 [DefaultWithUndock:LeaveDock:A.](DEBUG): Uninitializing TrackAcousticContact.
2025-01-23T04:06:27.706Z,1737605187.706 [DockingStepper](INFO): Resume
2025-01-23T04:06:28.056Z,1737605188.056 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detected possible detachment. Starting timer.
2025-01-23T04:06:28.062Z,1737605188.062 [DockingStepper](INFO): Resuming
2025-01-23T04:06:28.460Z,1737605188.460 [DockingStepper](INFO): Start
2025-01-23T04:06:28.460Z,1737605188.460 [DockingStepper](DEBUG): Initializing EZServoServo.
2025-01-23T04:06:28.580Z,1737605188.580 [DockingStepper](DEBUG): Initializing DockingStepper.
2025-01-23T04:06:28.871Z,1737605188.871 [DockingStepper](INFO): Starting
2025-01-23T04:06:29.252Z,1737605189.252 [NAL9602](INFO): Not Powering down - fast GPS
2025-01-23T04:06:29.426Z,1737605189.426 [DockingStepper](INFO): Starting
2025-01-23T04:06:29.732Z,1737605189.732 [DockingStepper](INFO): Starting
2025-01-23T04:06:29.784Z,1737605189.784 [DockingStepper](INFO): Init string: /1z16352aE13561N3m50
2025-01-23T04:06:30.113Z,1737605190.113 [DockingStepper](INFO): Starting
2025-01-23T04:06:30.513Z,1737605190.513 [DockingStepper](INFO): Starting
2025-01-23T04:06:30.929Z,1737605190.929 [DockingStepper](INFO): Starting
2025-01-23T04:06:31.317Z,1737605191.317 [DockingStepper](INFO): Starting
2025-01-23T04:06:31.345Z,1737605191.345 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@
2025-01-23T04:06:31.737Z,1737605191.737 [DockingStepper](INFO): Starting
2025-01-23T04:06:32.154Z,1737605192.154 [DockingStepper](INFO): Changing to mode: 1
2025-01-23T04:06:32.154Z,1737605192.154 [DockingStepper](INFO): Standby mode.
2025-01-23T04:06:43.264Z,1737605203.264 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T04:06:44.268Z,1737605204.268 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T04:06:48.259Z,1737605208.259 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached at range: nan m. Transitioning docking module to standby.
2025-01-23T04:06:48.681Z,1737605208.681 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Docking module at standby.
2025-01-23T04:06:49.209Z,1737605209.209 [DefaultWithUndock:LeaveDock:B.Undock](IMPORTANT): Undocking sequence complete.
2025-01-23T04:06:49.209Z,1737605209.209 [DefaultWithUndock:LeaveDock:B.Undock] Stopped
2025-01-23T04:06:49.209Z,1737605209.209 [DefaultWithUndock:LeaveDock](INFO): Completed DefaultWithUndock:LeaveDock
2025-01-23T04:06:49.209Z,1737605209.209 [DefaultWithUndock:LeaveDock] Stopped
2025-01-23T04:06:49.210Z,1737605209.210 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::uninitialize DefaultWithUndock:LeaveDock
2025-01-23T04:06:49.210Z,1737605209.210 [DefaultWithUndock:SurfaceDefault] Running Loop=1
2025-01-23T04:06:49.210Z,1737605209.210 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault
2025-01-23T04:06:49.210Z,1737605209.210 [DefaultWithUndock:SurfaceDefault:A.GoToSurface] Running Loop=1
2025-01-23T04:06:49.210Z,1737605209.210 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-01-23T04:06:49.210Z,1737605209.210 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-01-23T04:06:49.210Z,1737605209.210 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-01-23T04:06:49.211Z,1737605209.211 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-01-23T04:06:49.211Z,1737605209.211 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-01-23T04:06:49.216Z,1737605209.216 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-01-23T04:06:49.216Z,1737605209.216 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-01-23T04:06:49.549Z,1737605209.549 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=1
2025-01-23T04:06:49.549Z,1737605209.549 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2025-01-23T04:06:49.549Z,1737605209.549 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2025-01-23T04:06:49.688Z,1737605209.688 [DockingStepper](INFO): Pause
2025-01-23T04:06:51.477Z,1737605211.477 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,040700.00,A,3647.35222,N,12151.18455,W,0.292,147.09,230125,,,D*76
2025-01-23T04:06:51.480Z,1737605211.480 [NAL9602](INFO): GPS fix at 20250123T040700: (36.789204, -121.853076)
2025-01-23T04:06:51.491Z,1737605211.491 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2025-01-23T04:06:51.492Z,1737605211.492 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1
2025-01-23T04:07:14.388Z,1737605234.388 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T04:07:15.392Z,1737605235.392 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T04:07:21.022Z,1737605241.022 [NAL9602](INFO): SBD MO Status=2, MOMSN=47879, MT Status=2, MTMSN=0
2025-01-23T04:07:21.022Z,1737605241.022 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-01-23T04:07:45.516Z,1737605265.516 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T04:07:46.520Z,1737605266.520 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T04:07:58.818Z,1737605278.818 [NAL9602](INFO): SBD MO Status=2, MOMSN=47879, MT Status=2, MTMSN=0
2025-01-23T04:07:58.818Z,1737605278.818 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-01-23T04:08:16.657Z,1737605296.657 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T04:08:17.523Z,1737605297.523 [NAL9602](INFO): SBD MO Status=1, MOMSN=47879, MT Status=0, MTMSN=0
2025-01-23T04:08:17.576Z,1737605297.576 [NAL9602](INFO): Sent 84 bytes from file Logs/20250123T030326/Courier0015.lzma
2025-01-23T04:08:17.576Z,1737605297.576 [NAL9602](INFO): Packets left to send: 0
2025-01-23T04:08:17.660Z,1737605297.660 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T04:08:34.652Z,1737605314.652 [NAL9602](INFO): SBD MO Status=1, MOMSN=47880, MT Status=0, MTMSN=0
2025-01-23T04:08:34.708Z,1737605314.708 [NAL9602](INFO): Sent 332 bytes from file Logs/20250123T030326/Express0016.lzma
2025-01-23T04:08:34.708Z,1737605314.708 [NAL9602](INFO): Packets left to send: 1
2025-01-23T04:08:45.460Z,1737605325.460 [NAL9602](INFO): SBD MO Status=1, MOMSN=47881, MT Status=0, MTMSN=0
2025-01-23T04:08:45.516Z,1737605325.516 [NAL9602](INFO): Sent 196 bytes from file Logs/20250123T030326/Express0016.lzma
2025-01-23T04:08:45.516Z,1737605325.516 [NAL9602](INFO): Packets left to send: 0
2025-01-23T04:08:46.868Z,1737605326.868 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002922
2025-01-23T04:08:53.229Z,1737605333.229 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped
2025-01-23T04:08:53.229Z,1737605333.229 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1
2025-01-23T04:08:53.229Z,1737605333.229 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-01-23T04:09:03.286Z,1737605343.286 [NAL9602](INFO): SBD MO Status=0, MOMSN=47882, MT Status=0, MTMSN=0
2025-01-23T04:09:14.700Z,1737605354.700 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T04:09:15.020Z,1737605355.020 [RDI_Pathfinder](ERROR): only read 1 of 4 data items for bottom velocity. Device response is::BS,-32768
2025-01-23T04:09:23.760Z,1737605363.760 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T04:09:32.400Z,1737605372.400 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T04:09:33.997Z,1737605373.997 [NAL9602](INFO): Not Powering down - fast GPS
2025-01-23T04:09:43.092Z,1737605383.092 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T04:09:51.848Z,1737605391.848 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T04:10:00.452Z,1737605400.452 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T04:10:09.128Z,1737605409.128 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T04:10:14.967Z,1737605414.967 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2025-01-23T04:10:15.026Z,1737605415.026 [BPC1](INFO): Received data from all battery sticks.
2025-01-23T04:10:23.464Z,1737605423.464 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T04:10:37.732Z,1737605437.732 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T04:10:46.832Z,1737605446.832 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T04:10:48.955Z,1737605448.955 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for bottom velocity. Device response is::BS,-32768,32768,V
2025-01-23T04:10:56.748Z,1737605456.748 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T04:11:10.576Z,1737605470.576 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T04:11:20.221Z,1737605480.221 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T04:11:36.904Z,1737605496.904 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T04:11:46.592Z,1737605506.592 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T04:11:57.428Z,1737605517.428 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T04:12:06.128Z,1737605526.128 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T04:12:24.228Z,1737605544.228 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T04:12:55.380Z,1737605575.380 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T04:13:12.248Z,1737605592.248 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T04:13:42.369Z,1737605622.369 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-23T04:13:43.372Z,1737605623.372 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T04:13:53.629Z,1737605633.629 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting.
2025-01-23T04:13:53.629Z,1737605633.629 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped
2025-01-23T04:13:53.629Z,1737605633.629 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-01-23T04:13:53.630Z,1737605633.630 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1
2025-01-23T04:13:54.025Z,1737605634.025 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped
2025-01-23T04:13:54.025Z,1737605634.025 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1
2025-01-23T04:13:54.430Z,1737605634.430 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 7.949771 min
2025-01-23T04:13:54.430Z,1737605634.430 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped
2025-01-23T04:13:54.430Z,1737605634.430 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn
2025-01-23T04:13:54.430Z,1737605634.430 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped
2025-01-23T04:13:54.430Z,1737605634.430 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn
2025-01-23T04:13:54.430Z,1737605634.430 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #2
2025-01-23T04:13:54.431Z,1737605634.431 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=2
2025-01-23T04:13:54.431Z,1737605634.431 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2025-01-23T04:13:54.431Z,1737605634.431 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2025-01-23T04:13:56.442Z,1737605636.442 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,041405.00,A,3647.30623,N,12151.17663,W,0.758,204.43,230125,,,D*70
2025-01-23T04:13:56.445Z,1737605636.445 [NAL9602](INFO): GPS fix at 20250123T041405: (36.788437, -121.852944)
2025-01-23T04:13:56.458Z,1737605636.458 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2025-01-23T04:13:56.458Z,1737605636.458 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1
2025-01-23T04:14:11.532Z,1737605651.532 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.287487
2025-01-23T04:14:40.873Z,1737605680.873 [NAL9602](INFO): SBD MO Status=2, MOMSN=47883, MT Status=2, MTMSN=0
2025-01-23T04:14:40.873Z,1737605680.873 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-01-23T04:14:44.520Z,1737605684.520 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T04:14:59.054Z,1737605699.054 [NAL9602](INFO): SBD MO Status=0, MOMSN=47883, MT Status=0, MTMSN=0
2025-01-23T04:14:59.054Z,1737605699.054 [NAL9602](INFO): No messages in MT queue
2025-01-23T04:15:01.200Z,1737605701.200 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003436
2025-01-23T04:15:10.676Z,1737605710.676 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20250123T030326/Courier0018.lzma
2025-01-23T04:15:11.677Z,1737605711.677 [DataOverHttps](INFO): Moved sent file to Logs/20250123T030326/Courier0018.lzma.bak
2025-01-23T04:15:11.678Z,1737605711.678 [DataOverHttps](INFO): SBD MOMSN=23925016
2025-01-23T04:15:25.006Z,1737605725.006 [NAL9602](INFO): SBD MO Status=2, MOMSN=47884, MT Status=2, MTMSN=0
2025-01-23T04:15:25.006Z,1737605725.006 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-01-23T04:15:44.076Z,1737605744.076 [DataOverHttps](INFO): Sending 179 bytes from file Logs/20250123T030326/Express0019.lzma
2025-01-23T04:15:45.078Z,1737605745.078 [DataOverHttps](INFO): Moved sent file to Logs/20250123T030326/Express0019.lzma.bak
2025-01-23T04:15:45.078Z,1737605745.078 [DataOverHttps](INFO): SBD MOMSN=23925019
2025-01-23T04:15:55.421Z,1737605755.421 [NAL9602](INFO): Not Powering down - fast GPS
2025-01-23T04:15:55.880Z,1737605755.880 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped
2025-01-23T04:15:55.880Z,1737605755.880 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1
2025-01-23T04:15:55.880Z,1737605755.880 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-01-23T04:16:32.756Z,1737605792.756 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T04:16:41.352Z,1737605801.352 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T04:16:50.188Z,1737605810.188 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T04:16:59.896Z,1737605819.896 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T04:17:12.040Z,1737605832.040 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T04:17:20.912Z,1737605840.912 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T04:17:29.784Z,1737605849.784 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T04:17:38.680Z,1737605858.680 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T04:17:46.361Z,1737605866.361 [DataOverHttps](IMPORTANT): SBD MTMSN=20250123T041754
2025-01-23T04:17:54.587Z,1737605874.587 [DataOverHttps](INFO): Received command: restart sys
2025-01-23T04:17:54.685Z,1737605874.685 [CommandExec](IMPORTANT): got command restart system
2025-01-23T04:17:56.592Z,1737605876.592 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-01-23T04:17:56.872Z,1737605876.872 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2025-01-23T04:17:56.872Z,1737605876.872 [CommandExec](INFO): Uninitializing the command executive.
2025-01-23T04:17:56.872Z,1737605876.872 [CommandExec](INFO): Uninitializing the command scheduler.
2025-01-23T04:17:56.872Z,1737605876.872 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-01-23T04:17:56.932Z,1737605876.932 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2025-01-23T04:17:56.932Z,1737605876.932 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2025-01-23T04:17:56.932Z,1737605876.932 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-01-23T04:17:56.933Z,1737605876.933 [NavChartDb](INFO): Join timeout helper Thread ID is 7341
2025-01-23T04:17:57.240Z,1737605877.240 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2025-01-23T04:17:57.240Z,1737605877.240 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-01-23T04:17:57.252Z,1737605877.252 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler
2025-01-23T04:17:57.252Z,1737605877.252 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2025-01-23T04:17:57.252Z,1737605877.252 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 7342
2025-01-23T04:17:57.453Z,1737605877.453 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread.
2025-01-23T04:17:57.453Z,1737605877.453 [WetLabsUBAT](INFO): Powering down
2025-01-23T04:17:57.454Z,1737605877.454 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2025-01-23T04:17:57.471Z,1737605877.471 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2025-01-23T04:17:57.472Z,1737605877.472 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-01-23T04:17:57.472Z,1737605877.472 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 7343
2025-01-23T04:17:57.844Z,1737605877.844 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2025-01-23T04:17:57.844Z,1737605877.844 [WetLabsBB2FL](INFO): Powering down
2025-01-23T04:17:5