2025-04-28T20:22:01.996Z,1745871721.996 [CommandExec](IMPORTANT): got command restart logs 2025-04-28T20:23:15.264Z,1745871795.264 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-28T20:23:18.910Z,1745871798.910 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202318.00,A,3647.75560,N,12151.08529,W,0.855,209.04,280425,,,A*7F 2025-04-28T20:23:18.912Z,1745871798.912 [NAL9602](INFO): GPS fix at 20250428T202318: (36.795927, -121.851422) 2025-04-28T20:23:18.924Z,1745871798.924 [sci2_noyo_optim:NeedComms:C] Stopped 2025-04-28T20:23:18.925Z,1745871798.925 [sci2_noyo_optim:NeedComms:D] Running Loop=1 2025-04-28T20:23:19.341Z,1745871799.341 [sci2_noyo_optim:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-04-28T20:23:26.162Z,1745871806.162 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250428T185024/Courier0040.lzma 2025-04-28T20:23:27.165Z,1745871807.165 [DataOverHttps](INFO): Moved sent file to Logs/20250428T185024/Courier0040.lzma.bak 2025-04-28T20:23:27.165Z,1745871807.165 [DataOverHttps](INFO): SBD MOMSN=24740721 2025-04-28T20:23:44.708Z,1745871824.708 [DataOverHttps](INFO): Sending 270 bytes from file Logs/20250428T202201/Courier0000.lzma 2025-04-28T20:23:45.709Z,1745871825.709 [DataOverHttps](INFO): Moved sent file to Logs/20250428T202201/Courier0000.lzma.bak 2025-04-28T20:23:45.709Z,1745871825.709 [DataOverHttps](INFO): SBD MOMSN=24740724 2025-04-28T20:23:51.668Z,1745871831.668 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-28T20:24:05.388Z,1745871845.388 [DataOverHttps](INFO): Sending 845 bytes from file Logs/20250428T185024/Express0041.lzma 2025-04-28T20:24:06.389Z,1745871846.389 [DataOverHttps](INFO): Moved sent file to Logs/20250428T185024/Express0041.lzma.bak 2025-04-28T20:24:06.389Z,1745871846.389 [DataOverHttps](INFO): SBD MOMSN=24740734 2025-04-28T20:24:22.688Z,1745871862.688 [DataOverHttps](INFO): Sending 685 bytes from file Logs/20250428T202201/Express0001.lzma 2025-04-28T20:24:23.688Z,1745871863.688 [DataOverHttps](INFO): Moved sent file to Logs/20250428T202201/Express0001.lzma.bak 2025-04-28T20:24:23.689Z,1745871863.689 [DataOverHttps](INFO): SBD MOMSN=24740767 2025-04-28T20:24:24.851Z,1745871864.851 [sci2_noyo_optim:NeedComms:D] Stopped 2025-04-28T20:24:24.852Z,1745871864.852 [sci2_noyo_optim:NeedComms:E] Running Loop=1 2025-04-28T20:24:25.232Z,1745871865.232 [sci2_noyo_optim:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-04-28T20:24:26.838Z,1745871866.838 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202426.00,A,3647.75060,N,12151.09135,W,0.350,189.43,280425,,,A*7E 2025-04-28T20:24:26.840Z,1745871866.840 [NAL9602](INFO): GPS fix at 20250428T202426: (36.795843, -121.851523) 2025-04-28T20:24:26.861Z,1745871866.861 [sci2_noyo_optim:NeedComms:E] Stopped 2025-04-28T20:24:26.862Z,1745871866.862 [sci2_noyo_optim:NeedComms](INFO): Completed sci2_noyo_optim:NeedComms 2025-04-28T20:24:26.862Z,1745871866.862 [sci2_noyo_optim:NeedComms] Stopped 2025-04-28T20:24:26.862Z,1745871866.862 [sci2_noyo_optim:NeedComms](DEBUG): Aggregate::uninitialize sci2_noyo_optim:NeedComms 2025-04-28T20:24:26.862Z,1745871866.862 [sci2_noyo_optim:NeedComms:B.GoToSurface] Stopped 2025-04-28T20:24:26.862Z,1745871866.862 [sci2_noyo_optim:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-04-28T20:24:27.305Z,1745871867.305 [sci2_noyo_optim:SurfaceComms:E](INFO): Completed sci2_noyo_optim:SurfaceComms:E 2025-04-28T20:24:27.305Z,1745871867.305 [sci2_noyo_optim:SurfaceComms:E] Stopped 2025-04-28T20:24:27.305Z,1745871867.305 [sci2_noyo_optim:SurfaceComms:E](DEBUG): Aggregate::uninitialize sci2_noyo_optim:SurfaceComms:E 2025-04-28T20:24:27.305Z,1745871867.305 [sci2_noyo_optim:SurfaceComms:F] Running Loop=1 2025-04-28T20:24:27.679Z,1745871867.679 [sci2_noyo_optim:SurfaceComms:F] Stopped 2025-04-28T20:24:27.679Z,1745871867.679 [sci2_noyo_optim:SurfaceComms](INFO): Completed sci2_noyo_optim:SurfaceComms 2025-04-28T20:24:27.679Z,1745871867.679 [sci2_noyo_optim:SurfaceComms] Stopped 2025-04-28T20:24:27.680Z,1745871867.680 [sci2_noyo_optim:SurfaceComms](DEBUG): Aggregate::uninitialize sci2_noyo_optim:SurfaceComms 2025-04-28T20:24:28.087Z,1745871868.087 [sci2_noyo_optim:StartingMission](INFO): Completed sci2_noyo_optim:StartingMission 2025-04-28T20:24:28.087Z,1745871868.087 [sci2_noyo_optim:StartingMission] Stopped 2025-04-28T20:24:28.087Z,1745871868.087 [sci2_noyo_optim:StartingMission](DEBUG): Aggregate::uninitialize sci2_noyo_optim:StartingMission 2025-04-28T20:24:28.087Z,1745871868.087 [sci2_noyo_optim:Lap] Running Loop=1 2025-04-28T20:24:28.087Z,1745871868.087 [sci2_noyo_optim:Lap](DEBUG): Aggregate::initialize sci2_noyo_optim:Lap 2025-04-28T20:24:28.087Z,1745871868.087 [sci2_noyo_optim:Lap:Dive] Running Loop=1 2025-04-28T20:24:28.088Z,1745871868.088 [sci2_noyo_optim:Lap:Dive](DEBUG): Aggregate::initialize sci2_noyo_optim:Lap:Dive 2025-04-28T20:24:28.088Z,1745871868.088 [sci2_noyo_optim:Lap:Dive:A] Running Loop=1 2025-04-28T20:24:28.088Z,1745871868.088 [sci2_noyo_optim:Lap:Dive:A](DEBUG): Aggregate::initialize sci2_noyo_optim:Lap:Dive:A 2025-04-28T20:24:28.088Z,1745871868.088 [sci2_noyo_optim:Lap:DepthByLap] Running Loop=1 2025-04-28T20:24:28.088Z,1745871868.088 [sci2_noyo_optim:Lap:DepthByLap](DEBUG): Aggregate::initialize sci2_noyo_optim:Lap:DepthByLap 2025-04-28T20:24:28.088Z,1745871868.088 [sci2_noyo_optim:Lap:DepthByLap:A] Running Loop=1 2025-04-28T20:24:28.473Z,1745871868.473 [sci2_noyo_optim:Lap:DepthByLap:A] Stopped 2025-04-28T20:24:28.473Z,1745871868.473 [sci2_noyo_optim:Lap:DepthByLap:Wpt1] Running Loop=1 2025-04-28T20:24:28.473Z,1745871868.473 [sci2_noyo_optim:Lap:DepthByLap:Wpt1](DEBUG): Aggregate::initialize sci2_noyo_optim:Lap:DepthByLap:Wpt1 2025-04-28T20:24:28.473Z,1745871868.473 [sci2_noyo_optim:Lap:DepthByLap:Wpt1:A.Waypoint] Running Loop=1 2025-04-28T20:24:28.473Z,1745871868.473 [sci2_noyo_optim:Lap:DepthByLap:Wpt1:A.Waypoint](DEBUG): Initialize WaypointComponent. 2025-04-28T20:24:28.474Z,1745871868.474 [sci2_noyo_optim:Lap:DepthByLap:Wpt1:A.Waypoint](IMPORTANT): Navigating to waypoint: 36.795620,-121.862396 2025-04-28T20:24:28.479Z,1745871868.479 [sci2_noyo_optim:Lap:Dive] Running Loop=1 2025-04-28T20:24:28.479Z,1745871868.479 [sci2_noyo_optim:DiveCmd] Running Loop=1 2025-04-28T20:24:28.479Z,1745871868.479 [sci2_noyo_optim:DiveCmd](DEBUG): Aggregate::initialize sci2_noyo_optim:DiveCmd 2025-04-28T20:24:28.479Z,1745871868.479 [sci2_noyo_optim:DiveCmd:SpiralDive] Running Loop=1 2025-04-28T20:24:28.479Z,1745871868.479 [sci2_noyo_optim:DiveCmd:SpiralDive](DEBUG): Aggregate::initialize sci2_noyo_optim:DiveCmd:SpiralDive 2025-04-28T20:24:28.479Z,1745871868.479 [sci2_noyo_optim:DiveCmd:SpiralDive:dive] Running Loop=1 2025-04-28T20:24:28.479Z,1745871868.479 [sci2_noyo_optim:DiveCmd:SpiralDive:dive](DEBUG): Aggregate::initialize sci2_noyo_optim:DiveCmd:SpiralDive:dive 2025-04-28T20:24:28.479Z,1745871868.479 [sci2_noyo_optim:DiveCmd:SpiralDive:dive:A.Mass] Running Loop=1 2025-04-28T20:24:28.479Z,1745871868.479 [sci2_noyo_optim:DiveCmd:SpiralDive:dive:A.Mass](DEBUG): Initialize. 2025-04-28T20:24:28.480Z,1745871868.480 [sci2_noyo_optim:DiveCmd:SpiralDive:dive:B] Running Loop=1 2025-04-28T20:24:28.480Z,1745871868.480 [sci2_noyo_optim:DiveCmd:SettleAtDepth] Running Loop=1 2025-04-28T20:24:28.480Z,1745871868.480 [sci2_noyo_optim:DiveCmd:SettleAtDepth](DEBUG): Aggregate::initialize sci2_noyo_optim:DiveCmd:SettleAtDepth 2025-04-28T20:24:28.480Z,1745871868.480 [sci2_noyo_optim:DiveCmd:SettleAtDepth:dive] Running Loop=1 2025-04-28T20:24:28.480Z,1745871868.480 [sci2_noyo_optim:DiveCmd:SettleAtDepth:dive](DEBUG): Aggregate::initialize sci2_noyo_optim:DiveCmd:SettleAtDepth:dive 2025-04-28T20:24:28.480Z,1745871868.480 [sci2_noyo_optim:DiveCmd:SettleAtDepth:dive:B.Pitch] Running Loop=1 2025-04-28T20:24:28.480Z,1745871868.480 [sci2_noyo_optim:DiveCmd:SettleAtDepth:dive:B.Pitch](DEBUG): Initialize. 2025-04-28T20:24:28.481Z,1745871868.481 [sci2_noyo_optim:DiveCmd:SettleAtDepth:dive:A] Running Loop=1 2025-04-28T20:24:28.481Z,1745871868.481 [sci2_noyo_optim:DiveCmd:MaintainDepth] Running Loop=1 2025-04-28T20:24:28.481Z,1745871868.481 [sci2_noyo_optim:DiveCmd:MaintainDepth](DEBUG): Aggregate::initialize sci2_noyo_optim:DiveCmd:MaintainDepth 2025-04-28T20:24:28.481Z,1745871868.481 [sci2_noyo_optim:DiveCmd:MaintainDepth:A] Running Loop=1 2025-04-28T20:24:28.481Z,1745871868.481 [sci2_noyo_optim:DiveCmd:MaintainDepth:Multiray] Running Loop=1 2025-04-28T20:24:28.481Z,1745871868.481 [sci2_noyo_optim:DiveCmd:MaintainDepth:Multiray](DEBUG): Aggregate::initialize sci2_noyo_optim:DiveCmd:MaintainDepth:Multiray 2025-04-28T20:24:28.481Z,1745871868.481 [sci2_noyo_optim:DiveCmd:MaintainDepth:Multiray:A] Running Loop=1 2025-04-28T20:24:28.481Z,1745871868.481 [sci2_noyo_optim:DiveCmd:MaintainDepth:dive] Running Loop=1 2025-04-28T20:24:28.481Z,1745871868.481 [sci2_noyo_optim:DiveCmd:MaintainDepth:dive](DEBUG): Aggregate::initialize sci2_noyo_optim:DiveCmd:MaintainDepth:dive 2025-04-28T20:24:28.481Z,1745871868.481 [sci2_noyo_optim:DiveCmd:MaintainDepth:dive:ThrusterDive] Running Loop=1 2025-04-28T20:24:28.482Z,1745871868.482 [sci2_noyo_optim:DiveCmd:MaintainDepth:dive:ThrusterDive](DEBUG): Aggregate::initialize sci2_noyo_optim:DiveCmd:MaintainDepth:dive:ThrusterDive 2025-04-28T20:24:28.482Z,1745871868.482 [sci2_noyo_optim:DiveCmd:MaintainDepth:dive:ThrusterDive:A.Mass] Running Loop=1 2025-04-28T20:24:28.482Z,1745871868.482 [sci2_noyo_optim:DiveCmd:MaintainDepth:dive:ThrusterDive:A.Mass](DEBUG): Initialize. 2025-04-28T20:24:28.482Z,1745871868.482 [sci2_noyo_optim:DiveCmd:MaintainDepth:dive:ThrusterDive:B.SetSpeed] Running Loop=1 2025-04-28T20:24:28.482Z,1745871868.482 [sci2_noyo_optim:DiveCmd:MaintainDepth:dive:ThrusterDive:B.SetSpeed](DEBUG): Initialize. 2025-04-28T20:24:28.482Z,1745871868.482 [sci2_noyo_optim:DiveCmd:MaintainDepth:dive:B.Pitch] Running Loop=1 2025-04-28T20:24:28.482Z,1745871868.482 [sci2_noyo_optim:DiveCmd:MaintainDepth:dive:B.Pitch](DEBUG): Initialize. 2025-04-28T20:24:28.482Z,1745871868.482 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow] Running Loop=1 2025-04-28T20:24:28.487Z,1745871868.487 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow](DEBUG): Aggregate::initialize sci2_noyo_optim:DiveCmd:MaintainDepthSlow 2025-04-28T20:24:28.487Z,1745871868.487 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:A] Running Loop=1 2025-04-28T20:24:28.487Z,1745871868.487 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:Multiray] Running Loop=1 2025-04-28T20:24:28.487Z,1745871868.487 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:Multiray](DEBUG): Aggregate::initialize sci2_noyo_optim:DiveCmd:MaintainDepthSlow:Multiray 2025-04-28T20:24:28.487Z,1745871868.487 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:Multiray:A] Running Loop=1 2025-04-28T20:24:28.487Z,1745871868.487 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:C.SetSpeed] Running Loop=1 2025-04-28T20:24:28.487Z,1745871868.487 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:C.SetSpeed](DEBUG): Initialize. 2025-04-28T20:24:28.487Z,1745871868.487 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:D] Running Loop=1 2025-04-28T20:24:28.487Z,1745871868.487 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:E.Buoyancy] Running Loop=1 2025-04-28T20:24:28.487Z,1745871868.487 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:E.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-04-28T20:24:28.488Z,1745871868.488 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:F.Pitch] Running Loop=1 2025-04-28T20:24:28.488Z,1745871868.488 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:F.Pitch](DEBUG): Initialize. 2025-04-28T20:24:28.488Z,1745871868.488 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow] Stopped 2025-04-28T20:24:28.489Z,1745871868.489 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow](DEBUG): Aggregate::uninitialize sci2_noyo_optim:DiveCmd:MaintainDepthSlow 2025-04-28T20:24:28.489Z,1745871868.489 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:A] Stopped 2025-04-28T20:24:28.489Z,1745871868.489 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:Multiray] Stopped 2025-04-28T20:24:28.489Z,1745871868.489 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:Multiray](DEBUG): Aggregate::uninitialize sci2_noyo_optim:DiveCmd:MaintainDepthSlow:Multiray 2025-04-28T20:24:28.489Z,1745871868.489 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:Multiray:A] Stopped 2025-04-28T20:24:28.489Z,1745871868.489 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:C.SetSpeed] Stopped 2025-04-28T20:24:28.489Z,1745871868.489 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:C.SetSpeed](DEBUG): Uninitialize. 2025-04-28T20:24:28.489Z,1745871868.489 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:D] Stopped 2025-04-28T20:24:28.489Z,1745871868.489 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:E.Buoyancy] Stopped 2025-04-28T20:24:28.489Z,1745871868.489 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:E.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-04-28T20:24:28.489Z,1745871868.489 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:F.Pitch] Stopped 2025-04-28T20:24:28.489Z,1745871868.489 [sci2_noyo_optim:DiveCmd:MaintainDepth] Stopped 2025-04-28T20:24:28.489Z,1745871868.489 [sci2_noyo_optim:DiveCmd:MaintainDepth](DEBUG): Aggregate::uninitialize sci2_noyo_optim:DiveCmd:MaintainDepth 2025-04-28T20:24:28.489Z,1745871868.489 [sci2_noyo_optim:DiveCmd:MaintainDepth:A] Stopped 2025-04-28T20:24:28.489Z,1745871868.489 [sci2_noyo_optim:DiveCmd:MaintainDepth:Multiray] Stopped 2025-04-28T20:24:28.490Z,1745871868.490 [sci2_noyo_optim:DiveCmd:MaintainDepth:Multiray](DEBUG): Aggregate::uninitialize sci2_noyo_optim:DiveCmd:MaintainDepth:Multiray 2025-04-28T20:24:28.490Z,1745871868.490 [sci2_noyo_optim:DiveCmd:MaintainDepth:Multiray:A] Stopped 2025-04-28T20:24:28.490Z,1745871868.490 [sci2_noyo_optim:DiveCmd:MaintainDepth:dive] Stopped 2025-04-28T20:24:28.490Z,1745871868.490 [sci2_noyo_optim:DiveCmd:MaintainDepth:dive](DEBUG): Aggregate::uninitialize sci2_noyo_optim:DiveCmd:MaintainDepth:dive 2025-04-28T20:24:28.490Z,1745871868.490 [sci2_noyo_optim:DiveCmd:MaintainDepth:dive:ThrusterDive] Stopped 2025-04-28T20:24:28.490Z,1745871868.490 [sci2_noyo_optim:DiveCmd:MaintainDepth:dive:ThrusterDive](DEBUG): Aggregate::uninitialize sci2_noyo_optim:DiveCmd:MaintainDepth:dive:ThrusterDive 2025-04-28T20:24:28.490Z,1745871868.490 [sci2_noyo_optim:DiveCmd:MaintainDepth:dive:ThrusterDive:A.Mass] Stopped 2025-04-28T20:24:28.490Z,1745871868.490 [sci2_noyo_optim:DiveCmd:MaintainDepth:dive:ThrusterDive:A.Mass](DEBUG): Uninitialize. 2025-04-28T20:24:28.490Z,1745871868.490 [sci2_noyo_optim:DiveCmd:MaintainDepth:dive:ThrusterDive:B.SetSpeed] Stopped 2025-04-28T20:24:28.490Z,1745871868.490 [sci2_noyo_optim:DiveCmd:MaintainDepth:dive:ThrusterDive:B.SetSpeed](DEBUG): Uninitialize. 2025-04-28T20:24:28.490Z,1745871868.490 [sci2_noyo_optim:DiveCmd:MaintainDepth:dive:B.Pitch] Stopped 2025-04-28T20:24:28.515Z,1745871868.515 [sci2_noyo_optim:DiveCmd:SettleAtDepth] Stopped 2025-04-28T20:24:28.515Z,1745871868.515 [sci2_noyo_optim:DiveCmd:SettleAtDepth](DEBUG): Aggregate::uninitialize sci2_noyo_optim:DiveCmd:SettleAtDepth 2025-04-28T20:24:28.515Z,1745871868.515 [sci2_noyo_optim:DiveCmd:SettleAtDepth:dive] Stopped 2025-04-28T20:24:28.515Z,1745871868.515 [sci2_noyo_optim:DiveCmd:SettleAtDepth:dive](DEBUG): Aggregate::uninitialize sci2_noyo_optim:DiveCmd:SettleAtDepth:dive 2025-04-28T20:24:28.515Z,1745871868.515 [sci2_noyo_optim:DiveCmd:SettleAtDepth:dive:A] Stopped 2025-04-28T20:24:28.515Z,1745871868.515 [sci2_noyo_optim:DiveCmd:SettleAtDepth:dive:B.Pitch] Stopped 2025-04-28T20:24:28.515Z,1745871868.515 [sci2_noyo_optim:DiveCmd:SpiralDive] Running Loop=1 2025-04-28T20:24:28.516Z,1745871868.516 [sci2_noyo_optim:DiveCmd:SpiralDive:dive:B](INFO): Moving to 150.000000 m 2025-04-28T20:24:28.516Z,1745871868.516 [sci2_noyo_optim:DiveCmd:SpiralDive:dive:B] Stopped 2025-04-28T20:24:28.516Z,1745871868.516 [sci2_noyo_optim:DiveCmd:SpiralDive:dive:ApproachDepth] Running Loop=1 2025-04-28T20:24:28.516Z,1745871868.516 [sci2_noyo_optim:DiveCmd:SpiralDive:dive:ApproachDepth](DEBUG): Aggregate::initialize sci2_noyo_optim:DiveCmd:SpiralDive:dive:ApproachDepth 2025-04-28T20:24:28.516Z,1745871868.516 [sci2_noyo_optim:DiveCmd:SpiralDive:dive:ApproachDepth:A.SetSpeed] Running Loop=1 2025-04-28T20:24:28.516Z,1745871868.516 [sci2_noyo_optim:DiveCmd:SpiralDive:dive:ApproachDepth:A.SetSpeed](DEBUG): Initialize. 2025-04-28T20:24:28.516Z,1745871868.516 [sci2_noyo_optim:DiveCmd:SpiralDive:dive:ApproachDepth:B.Point] Running Loop=1 2025-04-28T20:24:28.516Z,1745871868.516 [sci2_noyo_optim:DiveCmd:SpiralDive:dive:ApproachDepth:B.Point](DEBUG): Initialize. 2025-04-28T20:24:28.517Z,1745871868.517 [sci2_noyo_optim:DiveCmd:SpiralDive:dive:ApproachDepth:C.Pitch] Running Loop=1 2025-04-28T20:24:28.517Z,1745871868.517 [sci2_noyo_optim:DiveCmd:SpiralDive:dive:ApproachDepth:C.Pitch](DEBUG): Initialize. 2025-04-28T20:24:28.517Z,1745871868.517 [sci2_noyo_optim:DiveCmd:SpiralDive:dive:A.Mass] Running Loop=1 2025-04-28T20:24:28.897Z,1745871868.897 [sci2_noyo_optim:Lap:DepthByLap:Wpt1:A.Waypoint](INFO): Navigating to waypoint: 36.795620,-121.862396 2025-04-28T20:24:28.907Z,1745871868.907 [sci2_noyo_optim:DiveCmd:SpiralDive:dive:ApproachDepth:B.Point] Running Loop=1 2025-04-28T20:24:28.908Z,1745871868.908 [sci2_noyo_optim:DiveCmd:SpiralDive:dive:ApproachDepth:A.SetSpeed] Running Loop=1 2025-04-28T20:24:57.536Z,1745871897.536 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-04-28T20:24:57.615Z,1745871897.615 [NAL9602](ERROR): received: +CSQ:0 OK054, 2, 0, 0, 0 OK 2025-04-28T20:25:24.644Z,1745871924.644 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 25.46 mm (1 active estimators). 2025-04-28T20:26:05.451Z,1745871965.451 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-28T20:26:08.463Z,1745871968.463 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.243233 2025-04-28T20:26:25.135Z,1745871985.135 [Radio_Surface](INFO): Powering down 2025-04-28T20:26:40.607Z,1745872000.607 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-28T20:26:41.612Z,1745872001.612 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-04-28T20:29:29.436Z,1745872169.436 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-28T20:30:00.136Z,1745872200.136 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-28T20:36:44.155Z,1745872604.155 [sci2_noyo_optim:DiveCmd:SpiralDive:dive:ApproachDepth:C.Pitch] Stopped 2025-04-28T20:36:44.156Z,1745872604.156 [sci2_noyo_optim:DiveCmd:SpiralDive:dive:ApproachDepth](INFO): Completed sci2_noyo_optim:DiveCmd:SpiralDive:dive:ApproachDepth 2025-04-28T20:36:44.156Z,1745872604.156 [sci2_noyo_optim:DiveCmd:SpiralDive:dive:ApproachDepth] Stopped 2025-04-28T20:36:44.156Z,1745872604.156 [sci2_noyo_optim:DiveCmd:SpiralDive:dive:ApproachDepth](DEBUG): Aggregate::uninitialize sci2_noyo_optim:DiveCmd:SpiralDive:dive:ApproachDepth 2025-04-28T20:36:44.156Z,1745872604.156 [sci2_noyo_optim:DiveCmd:SpiralDive:dive:ApproachDepth:A.SetSpeed] Stopped 2025-04-28T20:36:44.156Z,1745872604.156 [sci2_noyo_optim:DiveCmd:SpiralDive:dive:ApproachDepth:A.SetSpeed](DEBUG): Uninitialize. 2025-04-28T20:36:44.156Z,1745872604.156 [sci2_noyo_optim:DiveCmd:SpiralDive:dive:ApproachDepth:B.Point] Stopped 2025-04-28T20:36:44.157Z,1745872604.157 [sci2_noyo_optim:DiveCmd:SpiralDive:dive:D] Running Loop=1 2025-04-28T20:36:44.562Z,1745872604.562 [sci2_noyo_optim:DiveCmd:SpiralDive:dive:D] Stopped 2025-04-28T20:36:44.563Z,1745872604.563 [sci2_noyo_optim:DiveCmd:SpiralDive:dive](INFO): Completed sci2_noyo_optim:DiveCmd:SpiralDive:dive 2025-04-28T20:36:44.563Z,1745872604.563 [sci2_noyo_optim:DiveCmd:SpiralDive:dive] Stopped 2025-04-28T20:36:44.563Z,1745872604.563 [sci2_noyo_optim:DiveCmd:SpiralDive:dive](DEBUG): Aggregate::uninitialize sci2_noyo_optim:DiveCmd:SpiralDive:dive 2025-04-28T20:36:44.563Z,1745872604.563 [sci2_noyo_optim:DiveCmd:SpiralDive:dive:A.Mass] Stopped 2025-04-28T20:36:44.563Z,1745872604.563 [sci2_noyo_optim:DiveCmd:SpiralDive:dive:A.Mass](DEBUG): Uninitialize. 2025-04-28T20:36:44.564Z,1745872604.564 [sci2_noyo_optim:DiveCmd:SpiralDive](INFO): Completed sci2_noyo_optim:DiveCmd:SpiralDive 2025-04-28T20:36:44.575Z,1745872604.575 [HorizontalControl](DEBUG): kpHeading = 0.400000 2025-04-28T20:36:44.576Z,1745872604.576 [HorizontalControl](DEBUG): kiHeading = 0.001000 2025-04-28T20:36:44.576Z,1745872604.576 [HorizontalControl](DEBUG): kdHeading = 0.050000 2025-04-28T20:36:44.974Z,1745872604.974 [sci2_noyo_optim:DiveCmd:SettleAtDepth] Running Loop=1 2025-04-28T20:36:44.974Z,1745872604.974 [sci2_noyo_optim:DiveCmd:SettleAtDepth](DEBUG): Aggregate::initialize sci2_noyo_optim:DiveCmd:SettleAtDepth 2025-04-28T20:36:44.974Z,1745872604.974 [sci2_noyo_optim:DiveCmd:SettleAtDepth:dive] Running Loop=1 2025-04-28T20:36:44.979Z,1745872604.979 [sci2_noyo_optim:DiveCmd:SettleAtDepth:dive](DEBUG): Aggregate::initialize sci2_noyo_optim:DiveCmd:SettleAtDepth:dive 2025-04-28T20:36:44.979Z,1745872604.979 [sci2_noyo_optim:DiveCmd:SettleAtDepth:dive:B.Pitch] Running Loop=1 2025-04-28T20:36:44.979Z,1745872604.979 [sci2_noyo_optim:DiveCmd:SettleAtDepth:dive:B.Pitch](DEBUG): Initialize. 2025-04-28T20:36:44.979Z,1745872604.979 [sci2_noyo_optim:DiveCmd:SettleAtDepth:dive:A] Running Loop=1 2025-04-28T20:36:44.979Z,1745872604.979 [sci2_noyo_optim:DiveCmd:SettleAtDepth] Running Loop=1 2025-04-28T20:36:44.979Z,1745872604.979 [sci2_noyo_optim:DiveCmd:SettleAtDepth:dive:B.Pitch] Running Loop=1 2025-04-28T20:36:44.980Z,1745872604.980 [sci2_noyo_optim:DiveCmd:SettleAtDepth:dive:A](INFO): Reached 150.126144 m , settling for 1.000000 min 2025-04-28T20:36:44.980Z,1745872604.980 [sci2_noyo_optim:DiveCmd:SettleAtDepth:dive:A] Stopped 2025-04-28T20:36:44.981Z,1745872604.981 [sci2_noyo_optim:DiveCmd:SettleAtDepth:dive:C.Wait] Running Loop=1 2025-04-28T20:36:44.981Z,1745872604.981 [sci2_noyo_optim:DiveCmd:SettleAtDepth:dive:C.Wait](DEBUG): Initialize Wait Component. 2025-04-28T20:37:45.586Z,1745872665.586 [sci2_noyo_optim:DiveCmd:SettleAtDepth:dive:C.Wait](INFO): Done Waiting. 2025-04-28T20:37:45.586Z,1745872665.586 [sci2_noyo_optim:DiveCmd:SettleAtDepth:dive:C.Wait] Stopped 2025-04-28T20:37:45.586Z,1745872665.586 [sci2_noyo_optim:DiveCmd:SettleAtDepth:dive:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-28T20:37:45.586Z,1745872665.586 [sci2_noyo_optim:DiveCmd:SettleAtDepth:dive:EnableRecordingAtDepth] Running Loop=1 2025-04-28T20:37:45.587Z,1745872665.587 [sci2_noyo_optim:DiveCmd:SettleAtDepth:dive:EnableRecordingAtDepth](DEBUG): Aggregate::initialize sci2_noyo_optim:DiveCmd:SettleAtDepth:dive:EnableRecordingAtDepth 2025-04-28T20:37:45.587Z,1745872665.587 [sci2_noyo_optim:DiveCmd:SettleAtDepth:dive:EnableRecordingAtDepth:A] Running Loop=1 2025-04-28T20:37:45.967Z,1745872665.967 [sci2_noyo_optim:DiveCmd:SettleAtDepth:dive:EnableRecordingAtDepth:A](INFO): Reached depth, initial recording state is 0 bool 2025-04-28T20:37:45.967Z,1745872665.967 [sci2_noyo_optim:DiveCmd:SettleAtDepth:dive:EnableRecordingAtDepth:A] Stopped 2025-04-28T20:37:45.967Z,1745872665.967 [sci2_noyo_optim:DiveCmd:SettleAtDepth:dive:EnableRecordingAtDepth:B] Running Loop=1 2025-04-28T20:37:46.371Z,1745872666.371 [sci2_noyo_optim:DiveCmd:SettleAtDepth:dive:EnableRecordingAtDepth:B] Stopped 2025-04-28T20:37:46.371Z,1745872666.371 [sci2_noyo_optim:DiveCmd:SettleAtDepth:dive:EnableRecordingAtDepth:EnableLightsAtDepth] Running Loop=1 2025-04-28T20:37:46.371Z,1745872666.371 [sci2_noyo_optim:DiveCmd:SettleAtDepth:dive:EnableRecordingAtDepth:EnableLightsAtDepth](DEBUG): Aggregate::initialize sci2_noyo_optim:DiveCmd:SettleAtDepth:dive:EnableRecordingAtDepth:EnableLightsAtDepth 2025-04-28T20:37:46.371Z,1745872666.371 [sci2_noyo_optim:DiveCmd:SettleAtDepth:dive:EnableRecordingAtDepth:EnableLightsAtDepth:A] Running Loop=1 2025-04-28T20:37:46.780Z,1745872666.780 [sci2_noyo_optim:DiveCmd:SettleAtDepth:dive:EnableRecordingAtDepth:EnableLightsAtDepth] Stopped 2025-04-28T20:37:46.780Z,1745872666.780 [sci2_noyo_optim:DiveCmd:SettleAtDepth:dive:EnableRecordingAtDepth:EnableLightsAtDepth](DEBUG): Aggregate::uninitialize sci2_noyo_optim:DiveCmd:SettleAtDepth:dive:EnableRecordingAtDepth:EnableLightsAtDepth 2025-04-28T20:37:46.780Z,1745872666.780 [sci2_noyo_optim:DiveCmd:SettleAtDepth:dive:EnableRecordingAtDepth:EnableLightsAtDepth:A] Stopped 2025-04-28T20:37:46.781Z,1745872666.781 [sci2_noyo_optim:DiveCmd:SettleAtDepth:dive:EnableRecordingAtDepth](INFO): Completed sci2_noyo_optim:DiveCmd:SettleAtDepth:dive:EnableRecordingAtDepth 2025-04-28T20:37:46.781Z,1745872666.781 [sci2_noyo_optim:DiveCmd:SettleAtDepth:dive:EnableRecordingAtDepth] Stopped 2025-04-28T20:37:46.781Z,1745872666.781 [sci2_noyo_optim:DiveCmd:SettleAtDepth:dive:EnableRecordingAtDepth](DEBUG): Aggregate::uninitialize sci2_noyo_optim:DiveCmd:SettleAtDepth:dive:EnableRecordingAtDepth 2025-04-28T20:37:46.781Z,1745872666.781 [sci2_noyo_optim:DiveCmd:SettleAtDepth:dive:E] Running Loop=1 2025-04-28T20:37:47.173Z,1745872667.173 [sci2_noyo_optim:DiveCmd:SettleAtDepth:dive:E] Stopped 2025-04-28T20:37:47.173Z,1745872667.173 [sci2_noyo_optim:DiveCmd:SettleAtDepth:dive](INFO): Completed sci2_noyo_optim:DiveCmd:SettleAtDepth:dive 2025-04-28T20:37:47.173Z,1745872667.173 [sci2_noyo_optim:DiveCmd:SettleAtDepth:dive] Stopped 2025-04-28T20:37:47.174Z,1745872667.174 [sci2_noyo_optim:DiveCmd:SettleAtDepth:dive](DEBUG): Aggregate::uninitialize sci2_noyo_optim:DiveCmd:SettleAtDepth:dive 2025-04-28T20:37:47.174Z,1745872667.174 [sci2_noyo_optim:DiveCmd:SettleAtDepth:dive:B.Pitch] Stopped 2025-04-28T20:37:47.174Z,1745872667.174 [sci2_noyo_optim:DiveCmd:SettleAtDepth](INFO): Completed sci2_noyo_optim:DiveCmd:SettleAtDepth 2025-04-28T20:37:47.579Z,1745872667.579 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow] Running Loop=1 2025-04-28T20:37:47.579Z,1745872667.579 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow](DEBUG): Aggregate::initialize sci2_noyo_optim:DiveCmd:MaintainDepthSlow 2025-04-28T20:37:47.579Z,1745872667.579 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:A] Running Loop=1 2025-04-28T20:37:47.579Z,1745872667.579 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:Multiray] Running Loop=1 2025-04-28T20:37:47.579Z,1745872667.579 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:Multiray](DEBUG): Aggregate::initialize sci2_noyo_optim:DiveCmd:MaintainDepthSlow:Multiray 2025-04-28T20:37:47.579Z,1745872667.579 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:Multiray:A] Running Loop=1 2025-04-28T20:37:47.579Z,1745872667.579 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:C.SetSpeed] Running Loop=1 2025-04-28T20:37:47.580Z,1745872667.580 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:C.SetSpeed](DEBUG): Initialize. 2025-04-28T20:37:47.580Z,1745872667.580 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:D] Running Loop=1 2025-04-28T20:37:47.580Z,1745872667.580 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:E.Buoyancy] Running Loop=1 2025-04-28T20:37:47.580Z,1745872667.580 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:E.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-04-28T20:37:47.580Z,1745872667.580 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:F.Pitch] Running Loop=1 2025-04-28T20:37:47.580Z,1745872667.580 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:F.Pitch](DEBUG): Initialize. 2025-04-28T20:37:47.580Z,1745872667.580 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow] Running Loop=1 2025-04-28T20:37:47.580Z,1745872667.580 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:F.Pitch] Running Loop=1 2025-04-28T20:37:47.581Z,1745872667.581 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:E.Buoyancy] Running Loop=1 2025-04-28T20:37:47.581Z,1745872667.581 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:D] Running Loop=1 2025-04-28T20:37:47.581Z,1745872667.581 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:C.SetSpeed] Running Loop=1 2025-04-28T20:37:47.581Z,1745872667.581 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:Multiray] Stopped 2025-04-28T20:37:47.582Z,1745872667.582 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:Multiray](DEBUG): Aggregate::uninitialize sci2_noyo_optim:DiveCmd:MaintainDepthSlow:Multiray 2025-04-28T20:37:47.582Z,1745872667.582 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:Multiray:A] Stopped 2025-04-28T20:37:47.582Z,1745872667.582 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:A] Running Loop=1 2025-04-28T20:45:07.117Z,1745873107.117 [CommandExec](IMPORTANT): got command set sci2_noyo_optim.EnableRecording 1 bool 2025-04-28T20:45:07.394Z,1745873107.394 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:Multiray] Running Loop=1 2025-04-28T20:45:07.394Z,1745873107.394 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:Multiray](DEBUG): Aggregate::initialize sci2_noyo_optim:DiveCmd:MaintainDepthSlow:Multiray 2025-04-28T20:45:07.395Z,1745873107.395 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:Multiray:A] Running Loop=1 2025-04-28T20:45:07.395Z,1745873107.395 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:Multiray] Running Loop=1 2025-04-28T20:45:07.395Z,1745873107.395 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:Multiray:A] Stopped 2025-04-28T20:45:07.395Z,1745873107.395 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:Multiray](INFO): Completed sci2_noyo_optim:DiveCmd:MaintainDepthSlow:Multiray 2025-04-28T20:45:07.397Z,1745873107.397 [sci2_noyo_optim:PowerOnly:B] Running Loop=1 2025-04-28T20:45:07.397Z,1745873107.397 [sci2_noyo_optim:PowerOnly:B] Running Loop=1 2025-04-28T20:45:07.398Z,1745873107.398 [sci2_noyo_optim:PowerOnly:B](DEBUG): Initialize ReadDataComponent to sense PowerOnly.sampleLoad1 2025-04-28T20:45:07.398Z,1745873107.398 [sci2_noyo_optim:BackseatDriver:A.BackseatDriver](INFO): Initializing backseat 2025-04-28T20:45:07.515Z,1745873107.515 [BackseatComponent](INFO): Powering up 2025-04-28T20:45:07.516Z,1745873107.516 [BackseatComponent](INFO): Subscribing to LCM channels. 2025-04-28T20:45:07.782Z,1745873107.782 [PowerOnly](INFO): Powering up loadControl 2025-04-28T20:45:09.816Z,1745873109.816 [MultiRay](IMPORTANT): MultiRay white lights ON 2025-04-28T20:50:25.919Z,1745873425.919 [BackseatComponent](FAULT): Failed to receive device response within the specified timeout. 2025-04-28T20:50:25.919Z,1745873425.919 [BackseatComponent] Communications Fault, FailCount= 1 2025-04-28T20:50:25.919Z,1745873425.919 [BackseatComponent](ERROR): Communications Fault 2025-04-28T20:50:25.919Z,1745873425.919 [Power24vConverter] Hardware Fault, FailCount= 1 2025-04-28T20:50:25.919Z,1745873425.919 [Power24vConverter](ERROR): Hardware Fault 2025-04-28T20:50:25.946Z,1745873425.946 [CBIT](ERROR): Communications Fault in component: BackseatComponent 2025-04-28T20:50:25.946Z,1745873425.946 [CBIT](ERROR): Hardware Fault in component: Power24vConverter 2025-04-28T20:50:26.167Z,1745873426.167 [Power24vConverter](INFO): Powering down. 2025-04-28T20:50:26.327Z,1745873426.327 [BackseatComponent](INFO): Sent LCM shutdown request. 2025-04-28T20:50:26.328Z,1745873426.328 [BackseatComponent](INFO): Shutdown requested. Waiting (18.00 sec to power down.). 2025-04-28T20:50:26.985Z,1745873426.985 [Power24vConverter](INFO): Powering up. 2025-04-28T20:50:27.262Z,1745873427.262 [CBIT](INFO): Clearing failed state for component Power24vConverter 2025-04-28T20:50:27.262Z,1745873427.262 [Power24vConverter] No Fault, FailCount= 1 2025-04-28T20:50:28.582Z,1745873428.582 [PowerOnly](FAULT): loadControlLCB fault: Current Limiter Activated. 2025-04-28T20:50:28.582Z,1745873428.582 [PowerOnly] Hardware Fault, FailCount= 1 2025-04-28T20:50:28.582Z,1745873428.582 [PowerOnly](ERROR): Hardware Fault 2025-04-28T20:50:28.734Z,1745873428.734 [CBIT](ERROR): Hardware Fault in component: PowerOnly 2025-04-28T20:50:28.988Z,1745873428.988 [PowerOnly](INFO): Powering down loadControl 2025-04-28T20:50:29.398Z,1745873429.398 [PowerOnly](FAULT): loadControl LCB fault: Current Limiter Activated. 2025-04-28T20:50:29.792Z,1745873429.792 [DAT](FAULT): LCB fault: Current Limiter Activated. 2025-04-28T20:50:29.792Z,1745873429.792 [DAT] Hardware Fault, FailCount= 1 2025-04-28T20:50:29.792Z,1745873429.792 [DAT](ERROR): Hardware Fault 2025-04-28T20:50:29.792Z,1745873429.792 [DAT](INFO): Powering down 2025-04-28T20:50:29.801Z,1745873429.801 [PowerOnly](INFO): Powering down loadControl 2025-04-28T20:50:29.974Z,1745873429.974 [CBIT](ERROR): Hardware Fault in component: DAT 2025-04-28T20:50:30.197Z,1745873430.197 [PowerOnly](FAULT): loadControl LCB fault: Current Limiter Activated. 2025-04-28T20:50:30.268Z,1745873430.268 [DAT](INFO): Powering down 2025-04-28T20:50:30.608Z,1745873430.608 [PowerOnly](INFO): Powering down loadControl 2025-04-28T20:50:30.672Z,1745873430.672 [DAT](FAULT): LCB fault: Current Limiter Activated. 2025-04-28T20:50:31.014Z,1745873431.014 [PowerOnly](FAULT): loadControl LCB fault: Current Limiter Activated. 2025-04-28T20:50:31.150Z,1745873431.150 [CBIT](INFO): Clearing failed state for component DAT 2025-04-28T20:50:31.150Z,1745873431.150 [DAT] No Fault, FailCount= 1 2025-04-28T20:50:31.409Z,1745873431.409 [PowerOnly](INFO): Powering down loadControl 2025-04-28T20:50:32.219Z,1745873432.219 [PowerOnly](INFO): Powering up loadControl 2025-04-28T20:50:32.402Z,1745873432.402 [CBIT](INFO): Clearing failed state for component PowerOnly 2025-04-28T20:50:32.402Z,1745873432.402 [PowerOnly] No Fault, FailCount= 1 2025-04-28T20:50:33.495Z,1745873433.495 [DAT](INFO): Powering up 2025-04-28T20:50:33.495Z,1745873433.495 [DAT](DEBUG): Initializing DAT. 2025-04-28T20:50:44.507Z,1745873444.507 [BackseatComponent](INFO): Powering down and starting shutdown timer (18.00 sec). 2025-04-28T20:50:44.508Z,1745873444.508 [BackseatComponent](INFO): Unsubscribing from LCM channels. 2025-04-28T20:50:44.509Z,1745873444.509 [LcmUniversalReporter](INFO): Deactivating messaging. 2025-04-28T20:50:45.107Z,1745873445.107 [DAT](INFO): DAT read: 2025-04-28T20:50:45.108Z,1745873445.108 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2025-04-28T20:50:46.373Z,1745873446.373 [DAT](INFO): DAT read: MF Frequency Band 2025-04-28T20:50:46.374Z,1745873446.374 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2025-04-28T20:50:46.375Z,1745873446.375 [DAT](INFO): DAT read: Apr 28 2025 20:50:41 2025-04-28T20:50:47.629Z,1745873447.629 [DAT](INFO): DAT read: Features enabled [Bearing] 2025-04-28T20:50:47.630Z,1745873447.630 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2025-04-28T20:50:47.630Z,1745873447.630 [DAT](INFO): commRate: 800 2025-04-28T20:50:49.703Z,1745873449.703 [DAT](INFO): entering command mode 2025-04-28T20:50:49.903Z,1745873449.903 [DAT](INFO): DAT read: 2025-04-28T20:50:49.904Z,1745873449.904 [DAT](INFO): DAT read: user:1> 2025-04-28T20:50:49.904Z,1745873449.904 [DAT](INFO): setting verbose to 3 2025-04-28T20:50:50.158Z,1745873450.158 [DAT](INFO): DAT read: user:1> 2025-04-28T20:50:50.159Z,1745873450.159 [DAT](INFO): DAT read: Verbose | 3 2025-04-28T20:50:50.159Z,1745873450.159 [DAT](INFO): set verbose to 3 2025-04-28T20:50:50.160Z,1745873450.160 [DAT](INFO): setting DatVerbose to 27440 2025-04-28T20:50:50.412Z,1745873450.412 [DAT](INFO): DAT read: user:2> 2025-04-28T20:50:50.412Z,1745873450.412 [DAT](INFO): DAT read: DatVerbose | 27440 2025-04-28T20:50:50.413Z,1745873450.413 [DAT](INFO): set DatVerbose to 27440 2025-04-28T20:50:50.413Z,1745873450.413 [DAT](INFO): setting transmit power to 8 2025-04-28T20:50:50.663Z,1745873450.663 [DAT](INFO): DAT read: user:3> 2025-04-28T20:50:50.664Z,1745873450.664 [DAT](INFO): DAT read: TxPower | 8 (Max) 2025-04-28T20:50:50.665Z,1745873450.665 [DAT](INFO): set transmit power to 8 2025-04-28T20:50:50.665Z,1745873450.665 [DAT](INFO): setting local address to 9 2025-04-28T20:50:50.915Z,1745873450.915 [DAT](INFO): DAT read: user:4> 2025-04-28T20:50:50.916Z,1745873450.916 [DAT](INFO): DAT read: LocalAddr | 9 2025-04-28T20:50:50.917Z,1745873450.917 [DAT](INFO): set local address to 9 2025-04-28T20:50:50.918Z,1745873450.918 [DAT](INFO): Setting time to: 20:50:50 And date to:4/28/2025 2025-04-28T20:50:51.167Z,1745873451.167 [DAT](INFO): DAT read: user:5> 2025-04-28T20:50:51.168Z,1745873451.168 [DAT](INFO): DAT read: Mon Apr 28, 2025 20:50:50 2025-04-28T20:50:51.169Z,1745873451.169 [DAT](INFO): Local DAT time set to Mon Apr 28, 2025 20:50:50 2025-04-28T20:51:03.091Z,1745873463.091 [BackseatComponent](INFO): Powering up 2025-04-28T20:51:03.091Z,1745873463.091 [BackseatComponent](INFO): Subscribing to LCM channels. 2025-04-28T20:51:03.499Z,1745873463.499 [CBIT](INFO): Clearing failed state for component BackseatComponent 2025-04-28T20:51:03.500Z,1745873463.500 [BackseatComponent] No Fault, FailCount= 1 2025-04-28T20:55:07.231Z,1745873707.231 [CommandExec](IMPORTANT): got command set sci2_noyo_optim.EnableRecording 0 bool 2025-04-28T20:55:07.311Z,1745873707.311 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:Multiray] Running Loop=1 2025-04-28T20:55:07.311Z,1745873707.311 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:Multiray](DEBUG): Aggregate::initialize sci2_noyo_optim:DiveCmd:MaintainDepthSlow:Multiray 2025-04-28T20:55:07.311Z,1745873707.311 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:Multiray:A] Running Loop=1 2025-04-28T20:55:07.312Z,1745873707.312 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:Multiray:A] Stopped 2025-04-28T20:55:07.312Z,1745873707.312 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:Multiray](INFO): Completed sci2_noyo_optim:DiveCmd:MaintainDepthSlow:Multiray 2025-04-28T20:55:07.313Z,1745873707.313 [sci2_noyo_optim:PowerOnly:B] Stopped 2025-04-28T20:55:07.313Z,1745873707.313 [sci2_noyo_optim:BackseatDriver:A.BackseatDriver](INFO): Uninitializing backseat 2025-04-28T20:55:08.089Z,1745873708.089 [MultiRay](IMPORTANT): MultiRay all lights OFF 2025-04-28T20:55:08.489Z,1745873708.489 [MultiRay](INFO): Powering down 2025-04-28T20:55:08.603Z,1745873708.603 [MultiRay](INFO): Powering down LCB2 2025-04-28T20:55:08.964Z,1745873708.964 [BackseatComponent](INFO): Sent LCM shutdown request. 2025-04-28T20:55:08.964Z,1745873708.964 [BackseatComponent](INFO): Shutdown requested. Waiting (18.00 sec to power down.). 2025-04-28T20:55:27.135Z,1745873727.135 [BackseatComponent](INFO): Powering down and starting shutdown timer (18.00 sec). 2025-04-28T20:55:27.136Z,1745873727.136 [BackseatComponent](INFO): Unsubscribing from LCM channels. 2025-04-28T20:55:27.136Z,1745873727.136 [LcmUniversalReporter](INFO): Deactivating messaging. 2025-04-28T20:56:37.796Z,1745873797.796 [PowerOnly](INFO): Powering down loadControl 2025-04-28T21:00:12.516Z,1745874012.516 [sci2_noyo_optim:Lap:DepthByLap:Wpt1:A.Waypoint](IMPORTANT): Reached waypoint: 36.795620,-121.862396 2025-04-28T21:00:12.516Z,1745874012.516 [sci2_noyo_optim:Lap:DepthByLap:Wpt1:A.Waypoint] Stopped 2025-04-28T21:00:12.516Z,1745874012.516 [sci2_noyo_optim:Lap:DepthByLap:Wpt1:A.Waypoint](DEBUG): Uninitialize WaypointComponent. 2025-04-28T21:00:12.516Z,1745874012.516 [sci2_noyo_optim:Lap:DepthByLap:Wpt1](INFO): Completed sci2_noyo_optim:Lap:DepthByLap:Wpt1 2025-04-28T21:00:12.516Z,1745874012.516 [sci2_noyo_optim:Lap:DepthByLap:Wpt1] Stopped 2025-04-28T21:00:12.516Z,1745874012.516 [sci2_noyo_optim:Lap:DepthByLap:Wpt1](DEBUG): Aggregate::uninitialize sci2_noyo_optim:Lap:DepthByLap:Wpt1 2025-04-28T21:00:12.517Z,1745874012.517 [sci2_noyo_optim:Lap:DepthByLap:Wpt2] Running Loop=1 2025-04-28T21:00:12.517Z,1745874012.517 [sci2_noyo_optim:Lap:DepthByLap:Wpt2](DEBUG): Aggregate::initialize sci2_noyo_optim:Lap:DepthByLap:Wpt2 2025-04-28T21:00:12.517Z,1745874012.517 [sci2_noyo_optim:Lap:DepthByLap:Wpt2:A.Waypoint] Running Loop=1 2025-04-28T21:00:12.517Z,1745874012.517 [sci2_noyo_optim:Lap:DepthByLap:Wpt2:A.Waypoint](DEBUG): Initialize WaypointComponent. 2025-04-28T21:01:25.869Z,1745874085.869 [sci2_noyo_optim:Lap:DepthByLap:Wpt2:A.Waypoint] Stopped 2025-04-28T21:01:25.869Z,1745874085.869 [sci2_noyo_optim:Lap:DepthByLap:Wpt2:A.Waypoint](DEBUG): Uninitialize WaypointComponent. 2025-04-28T21:01:25.870Z,1745874085.870 [sci2_noyo_optim:Lap:DepthByLap:Wpt2](INFO): Completed sci2_noyo_optim:Lap:DepthByLap:Wpt2 2025-04-28T21:01:25.870Z,1745874085.870 [sci2_noyo_optim:Lap:DepthByLap:Wpt2] Stopped 2025-04-28T21:01:25.870Z,1745874085.870 [sci2_noyo_optim:Lap:DepthByLap:Wpt2](DEBUG): Aggregate::uninitialize sci2_noyo_optim:Lap:DepthByLap:Wpt2 2025-04-28T21:01:25.870Z,1745874085.870 [sci2_noyo_optim:Lap:DepthByLap:Wpt3] Running Loop=1 2025-04-28T21:01:25.870Z,1745874085.870 [sci2_noyo_optim:Lap:DepthByLap:Wpt3](DEBUG): Aggregate::initialize sci2_noyo_optim:Lap:DepthByLap:Wpt3 2025-04-28T21:01:25.870Z,1745874085.870 [sci2_noyo_optim:Lap:DepthByLap:Wpt3:A.Waypoint] Running Loop=1 2025-04-28T21:01:25.874Z,1745874085.874 [sci2_noyo_optim:Lap:DepthByLap:Wpt3:A.Waypoint](DEBUG): Initialize WaypointComponent. 2025-04-28T21:01:26.260Z,1745874086.260 [sci2_noyo_optim:Lap:DepthByLap:Wpt3:A.Waypoint] Stopped 2025-04-28T21:01:26.261Z,1745874086.261 [sci2_noyo_optim:Lap:DepthByLap:Wpt3:A.Waypoint](DEBUG): Uninitialize WaypointComponent. 2025-04-28T21:01:26.261Z,1745874086.261 [sci2_noyo_optim:Lap:DepthByLap:Wpt3](INFO): Completed sci2_noyo_optim:Lap:DepthByLap:Wpt3 2025-04-28T21:01:26.261Z,1745874086.261 [sci2_noyo_optim:Lap:DepthByLap:Wpt3] Stopped 2025-04-28T21:01:26.261Z,1745874086.261 [sci2_noyo_optim:Lap:DepthByLap:Wpt3](DEBUG): Aggregate::uninitialize sci2_noyo_optim:Lap:DepthByLap:Wpt3 2025-04-28T21:01:26.261Z,1745874086.261 [sci2_noyo_optim:Lap:DepthByLap:Wpt4] Running Loop=1 2025-04-28T21:01:26.261Z,1745874086.261 [sci2_noyo_optim:Lap:DepthByLap:Wpt4](DEBUG): Aggregate::initialize sci2_noyo_optim:Lap:DepthByLap:Wpt4 2025-04-28T21:01:26.262Z,1745874086.262 [sci2_noyo_optim:Lap:DepthByLap:Wpt4:A.Waypoint] Running Loop=1 2025-04-28T21:01:26.262Z,1745874086.262 [sci2_noyo_optim:Lap:DepthByLap:Wpt4:A.Waypoint](DEBUG): Initialize WaypointComponent. 2025-04-28T21:01:26.665Z,1745874086.665 [sci2_noyo_optim:Lap:DepthByLap:Wpt4:A.Waypoint] Stopped 2025-04-28T21:01:26.665Z,1745874086.665 [sci2_noyo_optim:Lap:DepthByLap:Wpt4:A.Waypoint](DEBUG): Uninitialize WaypointComponent. 2025-04-28T21:01:26.665Z,1745874086.665 [sci2_noyo_optim:Lap:DepthByLap:Wpt4](INFO): Completed sci2_noyo_optim:Lap:DepthByLap:Wpt4 2025-04-28T21:01:26.665Z,1745874086.665 [sci2_noyo_optim:Lap:DepthByLap:Wpt4] Stopped 2025-04-28T21:01:26.665Z,1745874086.665 [sci2_noyo_optim:Lap:DepthByLap:Wpt4](DEBUG): Aggregate::uninitialize sci2_noyo_optim:Lap:DepthByLap:Wpt4 2025-04-28T21:01:26.665Z,1745874086.665 [sci2_noyo_optim:Lap:DepthByLap:Wpt5] Running Loop=1 2025-04-28T21:01:26.665Z,1745874086.665 [sci2_noyo_optim:Lap:DepthByLap:Wpt5](DEBUG): Aggregate::initialize sci2_noyo_optim:Lap:DepthByLap:Wpt5 2025-04-28T21:01:26.666Z,1745874086.666 [sci2_noyo_optim:Lap:DepthByLap:Wpt5:A.Waypoint] Running Loop=1 2025-04-28T21:01:26.666Z,1745874086.666 [sci2_noyo_optim:Lap:DepthByLap:Wpt5:A.Waypoint](DEBUG): Initialize WaypointComponent. 2025-04-28T21:01:27.074Z,1745874087.074 [sci2_noyo_optim:Lap:DepthByLap:Wpt5:A.Waypoint] Stopped 2025-04-28T21:01:27.075Z,1745874087.075 [sci2_noyo_optim:Lap:DepthByLap:Wpt5:A.Waypoint](DEBUG): Uninitialize WaypointComponent. 2025-04-28T21:01:27.075Z,1745874087.075 [sci2_noyo_optim:Lap:DepthByLap:Wpt5](INFO): Completed sci2_noyo_optim:Lap:DepthByLap:Wpt5 2025-04-28T21:01:27.075Z,1745874087.075 [sci2_noyo_optim:Lap:DepthByLap:Wpt5] Stopped 2025-04-28T21:01:27.075Z,1745874087.075 [sci2_noyo_optim:Lap:DepthByLap:Wpt5](DEBUG): Aggregate::uninitialize sci2_noyo_optim:Lap:DepthByLap:Wpt5 2025-04-28T21:01:27.075Z,1745874087.075 [sci2_noyo_optim:Lap:DepthByLap:Wpt6] Running Loop=1 2025-04-28T21:01:27.075Z,1745874087.075 [sci2_noyo_optim:Lap:DepthByLap:Wpt6](DEBUG): Aggregate::initialize sci2_noyo_optim:Lap:DepthByLap:Wpt6 2025-04-28T21:01:27.075Z,1745874087.075 [sci2_noyo_optim:Lap:DepthByLap:Wpt6:A.Waypoint] Running Loop=1 2025-04-28T21:01:27.075Z,1745874087.075 [sci2_noyo_optim:Lap:DepthByLap:Wpt6:A.Waypoint](DEBUG): Initialize WaypointComponent. 2025-04-28T21:01:27.492Z,1745874087.492 [sci2_noyo_optim:Lap:DepthByLap:Wpt6:A.Waypoint] Stopped 2025-04-28T21:01:27.493Z,1745874087.493 [sci2_noyo_optim:Lap:DepthByLap:Wpt6:A.Waypoint](DEBUG): Uninitialize WaypointComponent. 2025-04-28T21:01:27.493Z,1745874087.493 [sci2_noyo_optim:Lap:DepthByLap:Wpt6](INFO): Completed sci2_noyo_optim:Lap:DepthByLap:Wpt6 2025-04-28T21:01:27.493Z,1745874087.493 [sci2_noyo_optim:Lap:DepthByLap:Wpt6] Stopped 2025-04-28T21:01:27.493Z,1745874087.493 [sci2_noyo_optim:Lap:DepthByLap:Wpt6](DEBUG): Aggregate::uninitialize sci2_noyo_optim:Lap:DepthByLap:Wpt6 2025-04-28T21:01:27.493Z,1745874087.493 [sci2_noyo_optim:Lap:DepthByLap:Wpt7] Running Loop=1 2025-04-28T21:01:27.493Z,1745874087.493 [sci2_noyo_optim:Lap:DepthByLap:Wpt7](DEBUG): Aggregate::initialize sci2_noyo_optim:Lap:DepthByLap:Wpt7 2025-04-28T21:01:27.493Z,1745874087.493 [sci2_noyo_optim:Lap:DepthByLap:Wpt7:A.Waypoint] Running Loop=1 2025-04-28T21:01:27.493Z,1745874087.493 [sci2_noyo_optim:Lap:DepthByLap:Wpt7:A.Waypoint](DEBUG): Initialize WaypointComponent. 2025-04-28T21:01:27.875Z,1745874087.875 [sci2_noyo_optim:Lap:DepthByLap:Wpt7:A.Waypoint] Stopped 2025-04-28T21:01:27.876Z,1745874087.876 [sci2_noyo_optim:Lap:DepthByLap:Wpt7:A.Waypoint](DEBUG): Uninitialize WaypointComponent. 2025-04-28T21:01:27.876Z,1745874087.876 [sci2_noyo_optim:Lap:DepthByLap:Wpt7](INFO): Completed sci2_noyo_optim:Lap:DepthByLap:Wpt7 2025-04-28T21:01:27.876Z,1745874087.876 [sci2_noyo_optim:Lap:DepthByLap:Wpt7] Stopped 2025-04-28T21:01:27.876Z,1745874087.876 [sci2_noyo_optim:Lap:DepthByLap:Wpt7](DEBUG): Aggregate::uninitialize sci2_noyo_optim:Lap:DepthByLap:Wpt7 2025-04-28T21:01:27.876Z,1745874087.876 [sci2_noyo_optim:Lap:DepthByLap:Wpt8] Running Loop=1 2025-04-28T21:01:27.876Z,1745874087.876 [sci2_noyo_optim:Lap:DepthByLap:Wpt8](DEBUG): Aggregate::initialize sci2_noyo_optim:Lap:DepthByLap:Wpt8 2025-04-28T21:01:27.876Z,1745874087.876 [sci2_noyo_optim:Lap:DepthByLap:Wpt8:A.Waypoint] Running Loop=1 2025-04-28T21:01:27.876Z,1745874087.876 [sci2_noyo_optim:Lap:DepthByLap:Wpt8:A.Waypoint](DEBUG): Initialize WaypointComponent. 2025-04-28T21:01:28.282Z,1745874088.282 [sci2_noyo_optim:Lap:DepthByLap:Wpt8:A.Waypoint] Stopped 2025-04-28T21:01:28.282Z,1745874088.282 [sci2_noyo_optim:Lap:DepthByLap:Wpt8:A.Waypoint](DEBUG): Uninitialize WaypointComponent. 2025-04-28T21:01:28.283Z,1745874088.283 [sci2_noyo_optim:Lap:DepthByLap:Wpt8](INFO): Completed sci2_noyo_optim:Lap:DepthByLap:Wpt8 2025-04-28T21:01:28.283Z,1745874088.283 [sci2_noyo_optim:Lap:DepthByLap:Wpt8] Stopped 2025-04-28T21:01:28.283Z,1745874088.283 [sci2_noyo_optim:Lap:DepthByLap:Wpt8](DEBUG): Aggregate::uninitialize sci2_noyo_optim:Lap:DepthByLap:Wpt8 2025-04-28T21:01:28.283Z,1745874088.283 [sci2_noyo_optim:Lap:DepthByLap:Leg2] Running Loop=1 2025-04-28T21:01:28.283Z,1745874088.283 [sci2_noyo_optim:Lap:DepthByLap:Leg2](DEBUG): Aggregate::initialize sci2_noyo_optim:Lap:DepthByLap:Leg2 2025-04-28T21:01:28.283Z,1745874088.283 [sci2_noyo_optim:Lap:DepthByLap:Leg2:A] Running Loop=1 2025-04-28T21:01:28.695Z,1745874088.695 [sci2_noyo_optim:Lap:DepthByLap:Leg2] Stopped 2025-04-28T21:01:28.696Z,1745874088.696 [sci2_noyo_optim:Lap:DepthByLap:Leg2](DEBUG): Aggregate::uninitialize sci2_noyo_optim:Lap:DepthByLap:Leg2 2025-04-28T21:01:28.696Z,1745874088.696 [sci2_noyo_optim:Lap:DepthByLap:Leg2:A] Stopped 2025-04-28T21:01:28.696Z,1745874088.696 [sci2_noyo_optim:Lap:DepthByLap:Leg3] Running Loop=1 2025-04-28T21:01:28.696Z,1745874088.696 [sci2_noyo_optim:Lap:DepthByLap:Leg3](DEBUG): Aggregate::initialize sci2_noyo_optim:Lap:DepthByLap:Leg3 2025-04-28T21:01:28.696Z,1745874088.696 [sci2_noyo_optim:Lap:DepthByLap:Leg3:A] Running Loop=1 2025-04-28T21:01:29.087Z,1745874089.087 [sci2_noyo_optim:Lap:DepthByLap:Leg3] Stopped 2025-04-28T21:01:29.087Z,1745874089.087 [sci2_noyo_optim:Lap:DepthByLap:Leg3](DEBUG): Aggregate::uninitialize sci2_noyo_optim:Lap:DepthByLap:Leg3 2025-04-28T21:01:29.087Z,1745874089.087 [sci2_noyo_optim:Lap:DepthByLap:Leg3:A] Stopped 2025-04-28T21:01:29.088Z,1745874089.088 [sci2_noyo_optim:Lap:DepthByLap:Leg4] Running Loop=1 2025-04-28T21:01:29.088Z,1745874089.088 [sci2_noyo_optim:Lap:DepthByLap:Leg4](DEBUG): Aggregate::initialize sci2_noyo_optim:Lap:DepthByLap:Leg4 2025-04-28T21:01:29.088Z,1745874089.088 [sci2_noyo_optim:Lap:DepthByLap:Leg4:A] Running Loop=1 2025-04-28T21:01:29.495Z,1745874089.495 [sci2_noyo_optim:Lap:DepthByLap:Leg4] Stopped 2025-04-28T21:01:29.495Z,1745874089.495 [sci2_noyo_optim:Lap:DepthByLap:Leg4](DEBUG): Aggregate::uninitialize sci2_noyo_optim:Lap:DepthByLap:Leg4 2025-04-28T21:01:29.495Z,1745874089.495 [sci2_noyo_optim:Lap:DepthByLap:Leg4:A] Stopped 2025-04-28T21:01:29.496Z,1745874089.496 [sci2_noyo_optim:Lap:DepthByLap:Leg5] Running Loop=1 2025-04-28T21:01:29.496Z,1745874089.496 [sci2_noyo_optim:Lap:DepthByLap:Leg5](DEBUG): Aggregate::initialize sci2_noyo_optim:Lap:DepthByLap:Leg5 2025-04-28T21:01:29.496Z,1745874089.496 [sci2_noyo_optim:Lap:DepthByLap:Leg5:A] Running Loop=1 2025-04-28T21:01:29.898Z,1745874089.898 [sci2_noyo_optim:Lap:DepthByLap:Leg5] Stopped 2025-04-28T21:01:29.898Z,1745874089.898 [sci2_noyo_optim:Lap:DepthByLap:Leg5](DEBUG): Aggregate::uninitialize sci2_noyo_optim:Lap:DepthByLap:Leg5 2025-04-28T21:01:29.898Z,1745874089.898 [sci2_noyo_optim:Lap:DepthByLap:Leg5:A] Stopped 2025-04-28T21:01:29.898Z,1745874089.898 [sci2_noyo_optim:Lap:DepthByLap:Leg6] Running Loop=1 2025-04-28T21:01:29.898Z,1745874089.898 [sci2_noyo_optim:Lap:DepthByLap:Leg6](DEBUG): Aggregate::initialize sci2_noyo_optim:Lap:DepthByLap:Leg6 2025-04-28T21:01:29.899Z,1745874089.899 [sci2_noyo_optim:Lap:DepthByLap:Leg6:A] Running Loop=1 2025-04-28T21:01:30.313Z,1745874090.313 [sci2_noyo_optim:Lap:DepthByLap:Leg6] Stopped 2025-04-28T21:01:30.313Z,1745874090.313 [sci2_noyo_optim:Lap:DepthByLap:Leg6](DEBUG): Aggregate::uninitialize sci2_noyo_optim:Lap:DepthByLap:Leg6 2025-04-28T21:01:30.313Z,1745874090.313 [sci2_noyo_optim:Lap:DepthByLap:Leg6:A] Stopped 2025-04-28T21:01:30.313Z,1745874090.313 [sci2_noyo_optim:Lap:DepthByLap:Leg7] Running Loop=1 2025-04-28T21:01:30.313Z,1745874090.313 [sci2_noyo_optim:Lap:DepthByLap:Leg7](DEBUG): Aggregate::initialize sci2_noyo_optim:Lap:DepthByLap:Leg7 2025-04-28T21:01:30.313Z,1745874090.313 [sci2_noyo_optim:Lap:DepthByLap:Leg7:A] Running Loop=1 2025-04-28T21:01:30.704Z,1745874090.704 [sci2_noyo_optim:Lap:DepthByLap:Leg7] Stopped 2025-04-28T21:01:30.704Z,1745874090.704 [sci2_noyo_optim:Lap:DepthByLap:Leg7](DEBUG): Aggregate::uninitialize sci2_noyo_optim:Lap:DepthByLap:Leg7 2025-04-28T21:01:30.704Z,1745874090.704 [sci2_noyo_optim:Lap:DepthByLap:Leg7:A] Stopped 2025-04-28T21:01:30.704Z,1745874090.704 [sci2_noyo_optim:Lap:DepthByLap:Leg8] Running Loop=1 2025-04-28T21:01:30.705Z,1745874090.705 [sci2_noyo_optim:Lap:DepthByLap:Leg8](DEBUG): Aggregate::initialize sci2_noyo_optim:Lap:DepthByLap:Leg8 2025-04-28T21:01:30.705Z,1745874090.705 [sci2_noyo_optim:Lap:DepthByLap:Leg8:A] Running Loop=1 2025-04-28T21:01:31.116Z,1745874091.116 [sci2_noyo_optim:Lap:DepthByLap:Leg8] Stopped 2025-04-28T21:01:31.116Z,1745874091.116 [sci2_noyo_optim:Lap:DepthByLap:Leg8](DEBUG): Aggregate::uninitialize sci2_noyo_optim:Lap:DepthByLap:Leg8 2025-04-28T21:01:31.116Z,1745874091.116 [sci2_noyo_optim:Lap:DepthByLap:Leg8:A] Stopped 2025-04-28T21:01:31.116Z,1745874091.116 [sci2_noyo_optim:Lap:DepthByLap](INFO): Completed sci2_noyo_optim:Lap:DepthByLap 2025-04-28T21:01:31.116Z,1745874091.116 [sci2_noyo_optim:Lap:DepthByLap] Stopped 2025-04-28T21:01:31.116Z,1745874091.116 [sci2_noyo_optim:Lap:DepthByLap](DEBUG): Aggregate::uninitialize sci2_noyo_optim:Lap:DepthByLap 2025-04-28T21:01:31.116Z,1745874091.116 [sci2_noyo_optim:Lap:DepthByLeg] Running Loop=1 2025-04-28T21:01:31.116Z,1745874091.116 [sci2_noyo_optim:Lap:DepthByLeg](DEBUG): Aggregate::initialize sci2_noyo_optim:Lap:DepthByLeg 2025-04-28T21:01:31.117Z,1745874091.117 [sci2_noyo_optim:Lap:DepthByLeg:Leg1] Running Loop=1 2025-04-28T21:01:31.117Z,1745874091.117 [sci2_noyo_optim:Lap:DepthByLeg:Leg1](DEBUG): Aggregate::initialize sci2_noyo_optim:Lap:DepthByLeg:Leg1 2025-04-28T21:01:31.117Z,1745874091.117 [sci2_noyo_optim:Lap:DepthByLeg:Leg1:A] Running Loop=1 2025-04-28T21:01:31.519Z,1745874091.519 [sci2_noyo_optim:Lap:DepthByLeg] Stopped 2025-04-28T21:01:31.519Z,1745874091.519 [sci2_noyo_optim:Lap:DepthByLeg](DEBUG): Aggregate::uninitialize sci2_noyo_optim:Lap:DepthByLeg 2025-04-28T21:01:31.519Z,1745874091.519 [sci2_noyo_optim:Lap:DepthByLeg:Leg1] Stopped 2025-04-28T21:01:31.519Z,1745874091.519 [sci2_noyo_optim:Lap:DepthByLeg:Leg1](DEBUG): Aggregate::uninitialize sci2_noyo_optim:Lap:DepthByLeg:Leg1 2025-04-28T21:01:31.519Z,1745874091.519 [sci2_noyo_optim:Lap:DepthByLeg:Leg1:A] Stopped 2025-04-28T21:01:31.519Z,1745874091.519 [sci2_noyo_optim:Lap](INFO): Completed sci2_noyo_optim:Lap 2025-04-28T21:01:31.519Z,1745874091.519 [sci2_noyo_optim:Lap] Stopped 2025-04-28T21:01:31.520Z,1745874091.520 [sci2_noyo_optim:Lap](DEBUG): Aggregate::uninitialize sci2_noyo_optim:Lap 2025-04-28T21:01:31.520Z,1745874091.520 [sci2_noyo_optim:Lap:Dive] Stopped 2025-04-28T21:01:31.520Z,1745874091.520 [sci2_noyo_optim:Lap:Dive](DEBUG): Aggregate::uninitialize sci2_noyo_optim:Lap:Dive 2025-04-28T21:01:31.520Z,1745874091.520 [sci2_noyo_optim:Lap:Dive:A] Stopped 2025-04-28T21:01:31.520Z,1745874091.520 [sci2_noyo_optim:Lap:Dive:A](DEBUG): Aggregate::uninitialize sci2_noyo_optim:Lap:Dive:A 2025-04-28T21:01:31.529Z,1745874091.529 [sci2_noyo_optim](INFO): Completed sci2_noyo_optim 2025-04-28T21:01:31.529Z,1745874091.529 [MissionManager](INFO): sci2_noyo_optim is completed. 2025-04-28T21:01:31.529Z,1745874091.529 [MissionManager](INFO): Uninitializing Mission sci2_noyo_optim 2025-04-28T21:01:31.529Z,1745874091.529 [sci2_noyo_optim] Stopped 2025-04-28T21:01:31.529Z,1745874091.529 [sci2_noyo_optim](DEBUG): Aggregate::uninitialize sci2_noyo_optim 2025-04-28T21:01:31.529Z,1745874091.529 [sci2_noyo_optim:Science] Stopped 2025-04-28T21:01:31.529Z,1745874091.529 [sci2_noyo_optim:Science](DEBUG): Aggregate::uninitialize sci2_noyo_optim:Science 2025-04-28T21:01:31.529Z,1745874091.529 [sci2_noyo_optim:Science:B] Stopped 2025-04-28T21:01:31.530Z,1745874091.530 [sci2_noyo_optim:Science:C] Stopped 2025-04-28T21:01:31.530Z,1745874091.530 [sci2_noyo_optim:Science:E] Stopped 2025-04-28T21:01:31.530Z,1745874091.530 [sci2_noyo_optim:Science:OceanCurrent] Stopped 2025-04-28T21:01:31.530Z,1745874091.530 [sci2_noyo_optim:Science:OceanCurrent](DEBUG): Aggregate::uninitialize sci2_noyo_optim:Science:OceanCurrent 2025-04-28T21:01:31.530Z,1745874091.530 [sci2_noyo_optim:Science:OceanCurrent:A.] Stopped 2025-04-28T21:01:31.530Z,1745874091.530 [sci2_noyo_optim:StandardEnvelopes] Stopped 2025-04-28T21:01:31.530Z,1745874091.530 [sci2_noyo_optim:StandardEnvelopes](DEBUG): Aggregate::uninitialize sci2_noyo_optim:StandardEnvelopes 2025-04-28T21:01:31.530Z,1745874091.530 [sci2_noyo_optim:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2025-04-28T21:01:31.530Z,1745874091.530 [sci2_noyo_optim:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2025-04-28T21:01:31.530Z,1745874091.530 [sci2_noyo_optim:StandardEnvelopes:B.DepthEnvelope] Stopped 2025-04-28T21:01:31.530Z,1745874091.530 [sci2_noyo_optim:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2025-04-28T21:01:31.531Z,1745874091.531 [sci2_noyo_optim:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2025-04-28T21:01:31.531Z,1745874091.531 [sci2_noyo_optim:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2025-04-28T21:01:31.531Z,1745874091.531 [sci2_noyo_optim:BackseatDriver] Stopped 2025-04-28T21:01:31.531Z,1745874091.531 [sci2_noyo_optim:BackseatDriver](DEBUG): Aggregate::uninitialize sci2_noyo_optim:BackseatDriver 2025-04-28T21:01:31.531Z,1745874091.531 [sci2_noyo_optim:BackseatDriver:A.BackseatDriver] Stopped 2025-04-28T21:01:31.531Z,1745874091.531 [sci2_noyo_optim:PowerOnly] Stopped 2025-04-28T21:01:31.531Z,1745874091.531 [sci2_noyo_optim:PowerOnly](DEBUG): Aggregate::uninitialize sci2_noyo_optim:PowerOnly 2025-04-28T21:01:31.531Z,1745874091.531 [sci2_noyo_optim:PowerOnly:E.Wait] Stopped 2025-04-28T21:01:31.531Z,1745874091.531 [sci2_noyo_optim:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2025-04-28T21:01:31.531Z,1745874091.531 [sci2_noyo_optim:L] Stopped 2025-04-28T21:01:31.531Z,1745874091.531 [sci2_noyo_optim:M.Buoyancy] Stopped 2025-04-28T21:01:31.531Z,1745874091.531 [sci2_noyo_optim:M.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-04-28T21:01:31.532Z,1745874091.532 [sci2_noyo_optim:SetRecording] Stopped 2025-04-28T21:01:31.532Z,1745874091.532 [sci2_noyo_optim:SetRecording](DEBUG): Aggregate::uninitialize sci2_noyo_optim:SetRecording 2025-04-28T21:01:31.532Z,1745874091.532 [sci2_noyo_optim:SetRecording:A] Stopped 2025-04-28T21:01:31.532Z,1745874091.532 [sci2_noyo_optim:SetRecording:B] Stopped 2025-04-28T21:01:31.532Z,1745874091.532 [sci2_noyo_optim:DiveCmd] Stopped 2025-04-28T21:01:31.532Z,1745874091.532 [sci2_noyo_optim:DiveCmd](DEBUG): Aggregate::uninitialize sci2_noyo_optim:DiveCmd 2025-04-28T21:01:31.532Z,1745874091.532 [sci2_noyo_optim:DiveCmd:SpiralDive] Stopped 2025-04-28T21:01:31.532Z,1745874091.532 [sci2_noyo_optim:DiveCmd:SpiralDive](DEBUG): Aggregate::uninitialize sci2_noyo_optim:DiveCmd:SpiralDive 2025-04-28T21:01:31.532Z,1745874091.532 [sci2_noyo_optim:DiveCmd:SettleAtDepth] Stopped 2025-04-28T21:01:31.532Z,1745874091.532 [sci2_noyo_optim:DiveCmd:SettleAtDepth](DEBUG): Aggregate::uninitialize sci2_noyo_optim:DiveCmd:SettleAtDepth 2025-04-28T21:01:31.532Z,1745874091.532 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow] Stopped 2025-04-28T21:01:31.532Z,1745874091.532 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow](DEBUG): Aggregate::uninitialize sci2_noyo_optim:DiveCmd:MaintainDepthSlow 2025-04-28T21:01:31.533Z,1745874091.533 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:A] Stopped 2025-04-28T21:01:31.533Z,1745874091.533 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:Multiray] Stopped 2025-04-28T21:01:31.533Z,1745874091.533 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:Multiray](DEBUG): Aggregate::uninitialize sci2_noyo_optim:DiveCmd:MaintainDepthSlow:Multiray 2025-04-28T21:01:31.533Z,1745874091.533 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:C.SetSpeed] Stopped 2025-04-28T21:01:31.533Z,1745874091.533 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:C.SetSpeed](DEBUG): Uninitialize. 2025-04-28T21:01:31.533Z,1745874091.533 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:D] Stopped 2025-04-28T21:01:31.533Z,1745874091.533 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:E.Buoyancy] Stopped 2025-04-28T21:01:31.533Z,1745874091.533 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:E.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-04-28T21:01:31.533Z,1745874091.533 [sci2_noyo_optim:DiveCmd:MaintainDepthSlow:F.Pitch] Stopped 2025-04-28T21:01:31.635Z,1745874091.635 [WetLabsBB2FL](INFO): Powering down 2025-04-28T21:01:32.204Z,1745874092.204 [MissionManager](IMPORTANT): Started mission Default 2025-04-28T21:01:32.205Z,1745874092.205 [Default] Running Loop=1 2025-04-28T21:01:32.205Z,1745874092.205 [Default](DEBUG): Aggregate::initialize Default 2025-04-28T21:01:32.205Z,1745874092.205 [Default:B.GoToSurface] Running Loop=1 2025-04-28T21:01:32.205Z,1745874092.205 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-04-28T21:01:32.205Z,1745874092.205 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-04-28T21:01:32.205Z,1745874092.205 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-04-28T21:01:32.206Z,1745874092.206 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-04-28T21:01:32.206Z,1745874092.206 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-04-28T21:01:32.206Z,1745874092.206 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-04-28T21:01:32.207Z,1745874092.207 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-04-28T21:01:32.207Z,1745874092.207 [Default:A.Wait] Running Loop=1 2025-04-28T21:01:32.207Z,1745874092.207 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-04-28T21:01:32.592Z,1745874092.592 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -25.00 mm (2 active estimators). 2025-04-28T21:01:32.975Z,1745874092.975 [Radio_Surface](INFO): Powering up 2025-04-28T21:01:39.192Z,1745874099.192 [DataOverHttps](INFO): Radio surface powered ON. 2025-04-28T21:01:45.539Z,1745874105.539 [Default:A.Wait](INFO): Done Waiting. 2025-04-28T21:01:45.539Z,1745874105.539 [Default:A.Wait] Stopped 2025-04-28T21:01:45.539Z,1745874105.539 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-04-28T21:02:09.311Z,1745874129.311 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-28T21:02:44.451Z,1745874164.451 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-28T21:03:19.591Z,1745874199.591 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-28T21:03:54.744Z,1745874234.744 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-28T21:04:29.883Z,1745874269.883 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-28T21:05:05.023Z,1745874305.023 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-28T21:05:40.175Z,1745874340.175 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-28T21:06:15.315Z,1745874375.315 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-28T21:06:50.455Z,1745874410.455 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-28T21:06:54.223Z,1745874414.223 [Default:CheckIn] Running Loop=1 2025-04-28T21:06:54.223Z,1745874414.223 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-28T21:06:54.223Z,1745874414.223 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-28T21:07:25.603Z,1745874445.603 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-28T21:07:26.910Z,1745874446.910 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-04-28T21:07:26.995Z,1745874446.995 [NAL9602](ERROR): received: +CSQ:0 OK054, 2, 0, 0, 0 OK 2025-04-28T21:08:00.751Z,1745874480.751 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-28T21:08:14.235Z,1745874494.235 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003428 2025-04-28T21:11:47.933Z,1745874707.933 [NAL9602](INFO): SBD MO Status=2, MOMSN=19054, MT Status=2, MTMSN=0 2025-04-28T21:11:47.933Z,1745874707.933 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-28T21:11:54.430Z,1745874714.430 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-28T21:06:54.2Z 2025-04-28T21:11:54.430Z,1745874714.430 [Default:CheckIn:Read_GPS] Stopped 2025-04-28T21:11:54.430Z,1745874714.430 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-28T21:12:04.084Z,1745874724.084 [NAL9602](INFO): SBD MO Status=2, MOMSN=19054, MT Status=2, MTMSN=0 2025-04-28T21:12:04.084Z,1745874724.084 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-28T21:12:04.497Z,1745874724.497 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-28T21:12:08.179Z,1745874728.179 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20250428T202201/Courier0003.lzma 2025-04-28T21:12:09.180Z,1745874729.180 [DataOverHttps](INFO): Moved sent file to Logs/20250428T202201/Courier0003.lzma.bak 2025-04-28T21:12:09.181Z,1745874729.181 [DataOverHttps](IMPORTANT): SBD MOMSN=24740862, MTMSN=20250428T211208