2025-04-21T22:06:16.454Z,1745273176.454 [NAL9602](INFO): Received command: restart logs 2025-04-21T22:06:16.462Z,1745273176.462 [CommandExec](IMPORTANT): got command restart logs 2025-04-21T22:06:17.638Z,1745273177.638 [DataOverHttps](INFO): Sending 590 bytes from file Logs/20250421T190207/Express0068.lzma 2025-04-21T22:06:18.639Z,1745273178.639 [DataOverHttps](INFO): Moved sent file to Logs/20250421T190207/Express0068.lzma.bak 2025-04-21T22:06:18.639Z,1745273178.639 [DataOverHttps](INFO): SBD MOMSN=24702897 2025-04-21T22:06:39.049Z,1745273199.049 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250421T190207/Courier0070.lzma 2025-04-21T22:06:40.051Z,1745273200.051 [DataOverHttps](INFO): Moved sent file to Logs/20250421T190207/Courier0070.lzma.bak 2025-04-21T22:06:40.051Z,1745273200.051 [DataOverHttps](INFO): SBD MOMSN=24702917 2025-04-21T22:06:47.130Z,1745273207.130 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-21T22:06:59.633Z,1745273219.633 [DataOverHttps](INFO): Sending 136 bytes from file Logs/20250421T190207/Express0071.lzma 2025-04-21T22:07:00.636Z,1745273220.636 [DataOverHttps](INFO): Moved sent file to Logs/20250421T190207/Express0071.lzma.bak 2025-04-21T22:07:00.636Z,1745273220.636 [DataOverHttps](INFO): SBD MOMSN=24702923 2025-04-21T22:07:02.090Z,1745273222.090 [sci2:NeedComms:D] Stopped 2025-04-21T22:07:02.090Z,1745273222.090 [sci2:NeedComms:E] Running Loop=1 2025-04-21T22:07:02.495Z,1745273222.495 [sci2:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-04-21T22:07:04.100Z,1745273224.100 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220704.00,A,3647.42670,N,12150.83384,W,1.108,122.16,210425,,,D*76 2025-04-21T22:07:04.103Z,1745273224.103 [NAL9602](INFO): GPS fix at 20250421T220704: (36.790445, -121.847231) 2025-04-21T22:07:04.134Z,1745273224.134 [sci2:NeedComms:E] Stopped 2025-04-21T22:07:04.135Z,1745273224.135 [sci2:NeedComms](INFO): Completed sci2:NeedComms 2025-04-21T22:07:04.135Z,1745273224.135 [sci2:NeedComms] Stopped 2025-04-21T22:07:04.135Z,1745273224.135 [sci2:NeedComms](DEBUG): Aggregate::uninitialize sci2:NeedComms 2025-04-21T22:07:04.135Z,1745273224.135 [sci2:NeedComms:B.GoToSurface] Stopped 2025-04-21T22:07:04.135Z,1745273224.135 [sci2:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-04-21T22:07:04.539Z,1745273224.539 [sci2:SurfaceComms:B](INFO): Completed sci2:SurfaceComms:B 2025-04-21T22:07:04.539Z,1745273224.539 [sci2:SurfaceComms:B] Stopped 2025-04-21T22:07:04.539Z,1745273224.539 [sci2:SurfaceComms:B](DEBUG): Aggregate::uninitialize sci2:SurfaceComms:B 2025-04-21T22:07:04.539Z,1745273224.539 [sci2:SurfaceComms:setInitial] Running Loop=1 2025-04-21T22:07:04.539Z,1745273224.539 [sci2:SurfaceComms:setInitial](DEBUG): Aggregate::initialize sci2:SurfaceComms:setInitial 2025-04-21T22:07:04.539Z,1745273224.539 [sci2:SurfaceComms:setInitial:A] Running Loop=1 2025-04-21T22:07:04.955Z,1745273224.955 [sci2:SurfaceComms:setInitial:A] Stopped 2025-04-21T22:07:04.955Z,1745273224.955 [sci2:SurfaceComms:setInitial](INFO): Completed sci2:SurfaceComms:setInitial 2025-04-21T22:07:04.955Z,1745273224.955 [sci2:SurfaceComms:setInitial] Stopped 2025-04-21T22:07:04.955Z,1745273224.955 [sci2:SurfaceComms:setInitial](DEBUG): Aggregate::uninitialize sci2:SurfaceComms:setInitial 2025-04-21T22:07:04.955Z,1745273224.955 [sci2:SurfaceComms:setSink] Running Loop=1 2025-04-21T22:07:04.955Z,1745273224.955 [sci2:SurfaceComms:setSink](DEBUG): Aggregate::initialize sci2:SurfaceComms:setSink 2025-04-21T22:07:04.955Z,1745273224.955 [sci2:SurfaceComms:setSink:A] Running Loop=1 2025-04-21T22:07:05.330Z,1745273225.330 [sci2:SurfaceComms:setSink] Stopped 2025-04-21T22:07:05.330Z,1745273225.330 [sci2:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize sci2:SurfaceComms:setSink 2025-04-21T22:07:05.331Z,1745273225.331 [sci2:SurfaceComms:setSink:A] Stopped 2025-04-21T22:07:05.331Z,1745273225.331 [sci2:SurfaceComms](INFO): Completed sci2:SurfaceComms 2025-04-21T22:07:05.331Z,1745273225.331 [sci2:SurfaceComms] Stopped 2025-04-21T22:07:05.331Z,1745273225.331 [sci2:SurfaceComms](DEBUG): Aggregate::uninitialize sci2:SurfaceComms 2025-04-21T22:07:05.739Z,1745273225.739 [sci2:StartingMission](INFO): Completed sci2:StartingMission 2025-04-21T22:07:05.739Z,1745273225.739 [sci2:StartingMission] Stopped 2025-04-21T22:07:05.739Z,1745273225.739 [sci2:StartingMission](DEBUG): Aggregate::uninitialize sci2:StartingMission 2025-04-21T22:07:05.740Z,1745273225.740 [sci2:Lap] Running Loop=1 2025-04-21T22:07:05.740Z,1745273225.740 [sci2:Lap](DEBUG): Aggregate::initialize sci2:Lap 2025-04-21T22:07:05.740Z,1745273225.740 [sci2:Lap:Dive] Running Loop=1 2025-04-21T22:07:05.740Z,1745273225.740 [sci2:Lap:Dive](DEBUG): Aggregate::initialize sci2:Lap:Dive 2025-04-21T22:07:05.740Z,1745273225.740 [sci2:Lap:Dive:A] Running Loop=1 2025-04-21T22:07:05.740Z,1745273225.740 [sci2:Lap:Dive:A](DEBUG): Aggregate::initialize sci2:Lap:Dive:A 2025-04-21T22:07:05.740Z,1745273225.740 [sci2:Lap:Wpt1] Running Loop=1 2025-04-21T22:07:05.740Z,1745273225.740 [sci2:Lap:Wpt1](DEBUG): Aggregate::initialize sci2:Lap:Wpt1 2025-04-21T22:07:05.740Z,1745273225.740 [sci2:Lap:Wpt1:A.Waypoint] Running Loop=1 2025-04-21T22:07:05.741Z,1745273225.741 [sci2:Lap:Wpt1:A.Waypoint](DEBUG): Initialize WaypointComponent. 2025-04-21T22:07:05.750Z,1745273225.750 [sci2:Lap:Wpt1:A.Waypoint](IMPORTANT): Navigating to waypoint: 36.815102,-121.923698 2025-04-21T22:07:06.168Z,1745273226.168 [sci2:Lap:Wpt1:A.Waypoint](INFO): Navigating to waypoint: 36.815102,-121.923698 2025-04-21T22:07:06.193Z,1745273226.193 [sci2:Lap:Dive] Running Loop=1 2025-04-21T22:07:06.193Z,1745273226.193 [sci2:DiveCmd] Running Loop=1 2025-04-21T22:07:06.194Z,1745273226.194 [sci2:DiveCmd](DEBUG): Aggregate::initialize sci2:DiveCmd 2025-04-21T22:07:06.194Z,1745273226.194 [sci2:DiveCmd:SurfaceSink] Running Loop=1 2025-04-21T22:07:06.194Z,1745273226.194 [sci2:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize sci2:DiveCmd:SurfaceSink 2025-04-21T22:07:06.194Z,1745273226.194 [sci2:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-04-21T22:07:06.194Z,1745273226.194 [sci2:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize sci2:DiveCmd:SurfaceSink:sink 2025-04-21T22:07:06.194Z,1745273226.194 [sci2:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-04-21T22:07:06.194Z,1745273226.194 [sci2:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-04-21T22:07:06.194Z,1745273226.194 [sci2:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-04-21T22:07:06.194Z,1745273226.194 [sci2:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-04-21T22:07:06.194Z,1745273226.194 [sci2:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-04-21T22:07:06.194Z,1745273226.194 [sci2:DiveCmd:YoYoDive] Running Loop=1 2025-04-21T22:07:06.195Z,1745273226.195 [sci2:DiveCmd:YoYoDive](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive 2025-04-21T22:07:06.195Z,1745273226.195 [sci2:DiveCmd:YoYoDive:dive] Running Loop=1 2025-04-21T22:07:06.195Z,1745273226.195 [sci2:DiveCmd:YoYoDive:dive](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive 2025-04-21T22:07:06.195Z,1745273226.195 [sci2:DiveCmd:YoYoDive:dive:A.Pitch] Running Loop=1 2025-04-21T22:07:06.195Z,1745273226.195 [sci2:DiveCmd:YoYoDive:dive:A.Pitch](DEBUG): Initialize. 2025-04-21T22:07:06.195Z,1745273226.195 [sci2:DiveCmd:YoYoDive:dive:B.Buoyancy] Running Loop=1 2025-04-21T22:07:06.195Z,1745273226.195 [sci2:DiveCmd:YoYoDive:dive:B.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-04-21T22:07:06.195Z,1745273226.195 [sci2:DiveCmd:YoYoDive:dive:SetSpeed] Running Loop=1 2025-04-21T22:07:06.195Z,1745273226.195 [sci2:DiveCmd:YoYoDive:dive:SetSpeed](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive:SetSpeed 2025-04-21T22:07:06.195Z,1745273226.195 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive] Running Loop=1 2025-04-21T22:07:06.196Z,1745273226.196 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive 2025-04-21T22:07:06.196Z,1745273226.196 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed] Running Loop=1 2025-04-21T22:07:06.196Z,1745273226.196 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed 2025-04-21T22:07:06.196Z,1745273226.196 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed] Running Loop=1 2025-04-21T22:07:06.196Z,1745273226.196 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed](DEBUG): Initialize. 2025-04-21T22:07:06.196Z,1745273226.196 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed] Running Loop=1 2025-04-21T22:07:06.196Z,1745273226.196 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed 2025-04-21T22:07:06.196Z,1745273226.196 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed] Running Loop=1 2025-04-21T22:07:06.196Z,1745273226.196 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed](DEBUG): Initialize. 2025-04-21T22:07:06.196Z,1745273226.196 [sci2:DiveCmd:YoYoDive:dive:D.DepthEnvelope] Running Loop=1 2025-04-21T22:07:06.197Z,1745273226.197 [sci2:DiveCmd:YoYoDive:dive:D.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2025-04-21T22:07:06.205Z,1745273226.205 [sci2:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope] Running Loop=1 2025-04-21T22:07:06.205Z,1745273226.205 [sci2:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2025-04-21T22:07:06.205Z,1745273226.205 [sci2:DiveCmd:YoYoDive:dive:F.YoYo] Running Loop=1 2025-04-21T22:07:06.205Z,1745273226.205 [sci2:DiveCmd:YoYoDive:dive:F.YoYo](DEBUG): Initialize YoYoComponent. 2025-04-21T22:07:06.206Z,1745273226.206 [sci2:DiveCmd:YoYoDive] Running Loop=1 2025-04-21T22:07:06.206Z,1745273226.206 [sci2:DiveCmd:YoYoDive:dive:F.YoYo] Running Loop=1 2025-04-21T22:07:06.206Z,1745273226.206 [sci2:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope] Running Loop=1 2025-04-21T22:07:06.207Z,1745273226.207 [sci2:DiveCmd:YoYoDive:dive:D.DepthEnvelope] Running Loop=1 2025-04-21T22:07:06.207Z,1745273226.207 [sci2:DiveCmd:YoYoDive:dive:SetSpeed] Running Loop=1 2025-04-21T22:07:06.208Z,1745273226.208 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed] Running Loop=1 2025-04-21T22:07:06.208Z,1745273226.208 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed] Running Loop=1 2025-04-21T22:07:06.208Z,1745273226.208 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive] Stopped 2025-04-21T22:07:06.208Z,1745273226.208 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive 2025-04-21T22:07:06.208Z,1745273226.208 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed] Stopped 2025-04-21T22:07:06.208Z,1745273226.208 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed 2025-04-21T22:07:06.208Z,1745273226.208 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed] Stopped 2025-04-21T22:07:06.208Z,1745273226.208 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed](DEBUG): Uninitialize. 2025-04-21T22:07:06.209Z,1745273226.209 [sci2:DiveCmd:YoYoDive:dive:B.Buoyancy] Running Loop=1 2025-04-21T22:07:06.217Z,1745273226.217 [sci2:DiveCmd:YoYoDive:dive:A.Pitch] Running Loop=1 2025-04-21T22:07:06.217Z,1745273226.217 [sci2:DiveCmd:SurfaceSink] Stopped 2025-04-21T22:07:06.218Z,1745273226.218 [sci2:DiveCmd:SurfaceSink](DEBUG): Aggregate::uninitialize sci2:DiveCmd:SurfaceSink 2025-04-21T22:07:06.218Z,1745273226.218 [sci2:DiveCmd:SurfaceSink:sink] Stopped 2025-04-21T22:07:06.218Z,1745273226.218 [sci2:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize sci2:DiveCmd:SurfaceSink:sink 2025-04-21T22:07:06.218Z,1745273226.218 [sci2:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-04-21T22:07:06.218Z,1745273226.218 [sci2:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-04-21T22:07:06.218Z,1745273226.218 [sci2:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-04-21T22:07:06.218Z,1745273226.218 [sci2:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-04-21T22:07:06.218Z,1745273226.218 [sci2:DiveCmd:SurfaceSink:sink:C] Stopped 2025-04-21T22:07:06.242Z,1745273226.242 [HorizontalControl](DEBUG): kpHeading = 0.400000 2025-04-21T22:07:06.242Z,1745273226.242 [HorizontalControl](DEBUG): kiHeading = 0.001000 2025-04-21T22:07:06.242Z,1745273226.242 [HorizontalControl](DEBUG): kdHeading = 0.050000 2025-04-21T22:07:07.762Z,1745273227.762 [sci2:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope](ERROR): Altitude Measurement is not Active. 2025-04-21T22:07:19.586Z,1745273239.586 [BackseatComponent](IMPORTANT): netra: running plankton_proxies.predict backseat application. 2025-04-21T22:07:22.096Z,1745273242.096 [BackseatComponent](INFO): Created data reader for CTD_Seabird.enableBroadcast with code: 1893 2025-04-21T22:07:22.097Z,1745273242.097 [BackseatComponent](INFO): Requesting data from CTD_Seabird.enableBroadcast (1893). Requested size is: 1 2025-04-21T22:07:22.099Z,1745273242.099 [BackseatComponent](INFO): Created data reader for WetLabsBB2FL.enableBroadcast with code: 1933 2025-04-21T22:07:22.099Z,1745273242.099 [BackseatComponent](INFO): Requesting data from WetLabsBB2FL.enableBroadcast (1933). Requested size is: 2 2025-04-21T22:07:22.163Z,1745273242.163 [CTD_Seabird](ERROR): Failed to copy CTD_Seabird.enableBroadcast's data value to LCM message. 2025-04-21T22:07:22.163Z,1745273242.163 [CTD_Seabird](INFO): Activating messaging. 2025-04-21T22:07:22.491Z,1745273242.491 [WetLabsBB2FL](ERROR): Failed to copy WetLabsBB2FL.enableBroadcast's data value to LCM message. 2025-04-21T22:07:22.491Z,1745273242.491 [WetLabsBB2FL](INFO): Activating messaging. 2025-04-21T22:07:23.475Z,1745273243.475 [BackseatComponent](IMPORTANT): ptvr: Running Planktivore backseat app. Logging to: 20250421T220720, NVMEDATA = 9887560 B 2025-04-21T22:07:26.180Z,1745273246.180 [BackseatComponent](INFO): Created data reader for Depth_Keller.enableBroadcast with code: 1711 2025-04-21T22:07:26.181Z,1745273246.181 [BackseatComponent](INFO): Requesting data from Depth_Keller.enableBroadcast (1711). Requested size is: 3 2025-04-21T22:07:26.183Z,1745273246.183 [BackseatComponent](INFO): Created universal data reader for depth with code: 6 2025-04-21T22:07:26.183Z,1745273246.183 [BackseatComponent](INFO): Requesting data from depth (6). Requested size is: 4 2025-04-21T22:07:26.375Z,1745273246.375 [Depth_Keller](ERROR): Failed to copy Depth_Keller.enableBroadcast's data value to LCM message. 2025-04-21T22:07:26.375Z,1745273246.375 [Depth_Keller](INFO): Activating messaging. 2025-04-21T22:07:40.049Z,1745273260.049 [NAL9602](INFO): SBD MO Status=2, MOMSN=23578, MT Status=2, MTMSN=0 2025-04-21T22:07:40.049Z,1745273260.049 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-21T22:08:04.317Z,1745273284.317 [sci2:BackseatDriver:A.BackseatDriver](INFO): Created data writer for _.planktivore_HM_AvgRois with code: 2517 2025-04-21T22:08:04.320Z,1745273284.320 [sci2:BackseatDriver:A.BackseatDriver](INFO): Created data writer for _.planktivore_LM_AvgRois with code: 2518 2025-04-21T22:08:04.324Z,1745273284.324 [sci2:BackseatDriver:A.BackseatDriver](INFO): Created data writer for _.planktivore_caseHumidity with code: 2519 2025-04-21T22:08:04.327Z,1745273284.327 [sci2:BackseatDriver:A.BackseatDriver](INFO): Created data writer for _.planktivore_casePress with code: 2520 2025-04-21T22:08:04.355Z,1745273284.355 [sci2:BackseatDriver:A.BackseatDriver](INFO): Created data writer for _.planktivore_caseTemp with code: 2521 2025-04-21T22:08:32.690Z,1745273312.690 [sci2:BackseatDriver:A.BackseatDriver](INFO): Created data writer for _.planktivore_diatoms with code: 2522 2025-04-21T22:08:32.693Z,1745273312.693 [sci2:BackseatDriver:A.BackseatDriver](INFO): Created data writer for _.planktivore_dinoflagellates with code: 2523 2025-04-21T22:08:33.845Z,1745273313.845 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-21T22:08:38.501Z,1745273318.501 [Radio_Surface](INFO): Powering down 2025-04-21T22:09:04.982Z,1745273344.982 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-21T22:09:05.985Z,1745273345.985 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-04-21T22:09:24.658Z,1745273364.658 [Waterlinked](ERROR): only read 0 of 1 data item for altitude. Device response::BD, +0.00, +0.00, +0.0 2025-04-21T22:10:10.282Z,1745273410.282 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.246338 2025-04-21T22:11:06.074Z,1745273466.074 [Waterlinked](ERROR): only read 0 of 1 data item for altitude. Device response::BD, +0.00, +0.00, +0 +0,V 2025-04-21T22:12:06.987Z,1745273526.987 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-21T22:12:37.454Z,1745273557.454 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-21T22:19:19.331Z,1745273959.331 [Waterlinked](ERROR): only read 2 of 5 data items. Device response::BI, +0, +0, +,V 2025-04-21T22:24:05.104Z,1745274245.104 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error. Device response::TS,24052713182357, 0.0, +0.0, 0.I, +0, +0, +0, +0,V 2025-04-21T22:24:25.557Z,1745274265.557 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-21T22:24:41.100Z,1745274281.100 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-21T22:25:06.625Z,1745274306.625 [CTD_Seabird](ERROR): Failed to parse device response: 29.43,10.4199,3.726032 2025-04-21T22:27:17.270Z,1745274437.270 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-21T22:27:30.569Z,1745274450.569 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-21T22:27:44.315Z,1745274464.315 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-21T22:27:58.429Z,1745274478.429 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-21T22:28:12.756Z,1745274492.756 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-21T22:28:26.361Z,1745274506.361 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-21T22:28:39.964Z,1745274519.964 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-21T22:28:53.306Z,1745274533.306 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-21T22:29:07.378Z,1745274547.378 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-21T22:29:21.628Z,1745274561.628 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-21T22:29:34.955Z,1745274574.955 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-21T22:31:14.833Z,1745274674.833 [BPC1](ERROR): Battery stick #29 (s/n: 00A7) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-04-21T22:31:14.834Z,1745274674.834 [BPC1](ERROR): Battery stick #51 (s/n: 01D6) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-04-21T22:31:14.835Z,1745274674.835 [BPC1](ERROR): Battery stick #52 (s/n: 0160) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-04-21T22:31:14.836Z,1745274674.836 [BPC1](ERROR): Battery stick #59 (s/n: 0184) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-04-21T22:31:14.836Z,1745274674.836 [BPC1](INFO): Calculating totals. Valid battery stick count: 42. Valid reserve battery stick count: 5. 2025-04-21T22:31:14.855Z,1745274674.855 [BPC1](INFO): Received data from all battery sticks. 2025-04-21T22:37:04.236Z,1745275024.236 [sci2:SurfaceComms] Running Loop=1 2025-04-21T22:37:04.236Z,1745275024.236 [sci2:SurfaceComms](DEBUG): Aggregate::initialize sci2:SurfaceComms 2025-04-21T22:37:04.236Z,1745275024.236 [sci2:SurfaceComms:A] Running Loop=1 2025-04-21T22:37:04.237Z,1745275024.237 [sci2:SurfaceComms:A] Stopped 2025-04-21T22:37:04.241Z,1745275024.241 [sci2:SurfaceComms:B] Running Loop=1 2025-04-21T22:37:04.241Z,1745275024.241 [sci2:SurfaceComms:B](DEBUG): Aggregate::initialize sci2:SurfaceComms:B 2025-04-21T22:37:04.250Z,1745275024.250 [sci2:NeedComms] Running Loop=1 2025-04-21T22:37:04.250Z,1745275024.250 [sci2:NeedComms](DEBUG): Aggregate::initialize sci2:NeedComms 2025-04-21T22:37:04.250Z,1745275024.250 [sci2:NeedComms:B.GoToSurface] Running Loop=1 2025-04-21T22:37:04.250Z,1745275024.250 [sci2:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-04-21T22:37:04.250Z,1745275024.250 [sci2:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-04-21T22:37:04.251Z,1745275024.251 [sci2:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-04-21T22:37:04.251Z,1745275024.251 [sci2:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-04-21T22:37:04.252Z,1745275024.252 [sci2:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-04-21T22:37:04.252Z,1745275024.252 [sci2:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-04-21T22:37:04.252Z,1745275024.252 [sci2:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-04-21T22:37:04.252Z,1745275024.252 [sci2:NeedComms:A] Running Loop=1 2025-04-21T22:37:04.258Z,1745275024.258 [sci2:NeedComms:A](INFO): last time_fix was: 1745273224.000000 second since 1970/01/01T00:00:00Z 2025-04-21T22:37:04.258Z,1745275024.258 [sci2:NeedComms:A] Stopped 2025-04-21T22:37:04.557Z,1745275024.557 [sci2:Lap:Dive] Stopped 2025-04-21T22:37:04.558Z,1745275024.558 [sci2:Lap:Dive](DEBUG): Aggregate::uninitialize sci2:Lap:Dive 2025-04-21T22:37:04.558Z,1745275024.558 [sci2:Lap:Dive:A] Stopped 2025-04-21T22:37:04.558Z,1745275024.558 [sci2:Lap:Dive:A](DEBUG): Aggregate::uninitialize sci2:Lap:Dive:A 2025-04-21T22:37:04.558Z,1745275024.558 [sci2:DiveCmd:YoYoDive] Stopped 2025-04-21T22:37:04.558Z,1745275024.558 [sci2:DiveCmd:YoYoDive](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive 2025-04-21T22:37:04.558Z,1745275024.558 [sci2:DiveCmd:YoYoDive:dive] Stopped 2025-04-21T22:37:04.558Z,1745275024.558 [sci2:DiveCmd:YoYoDive:dive](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive:dive 2025-04-21T22:37:04.559Z,1745275024.559 [sci2:DiveCmd:YoYoDive:dive:A.Pitch] Stopped 2025-04-21T22:37:04.559Z,1745275024.559 [sci2:DiveCmd:YoYoDive:dive:B.Buoyancy] Stopped 2025-04-21T22:37:04.559Z,1745275024.559 [sci2:DiveCmd:YoYoDive:dive:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-04-21T22:37:04.559Z,1745275024.559 [sci2:DiveCmd:YoYoDive:dive:SetSpeed] Stopped 2025-04-21T22:37:04.559Z,1745275024.559 [sci2:DiveCmd:YoYoDive:dive:SetSpeed](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive:dive:SetSpeed 2025-04-21T22:37:04.559Z,1745275024.559 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed] Stopped 2025-04-21T22:37:04.559Z,1745275024.559 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed 2025-04-21T22:37:04.559Z,1745275024.559 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed] Stopped 2025-04-21T22:37:04.559Z,1745275024.559 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed](DEBUG): Uninitialize. 2025-04-21T22:37:04.559Z,1745275024.559 [sci2:DiveCmd:YoYoDive:dive:D.DepthEnvelope] Stopped 2025-04-21T22:37:04.559Z,1745275024.559 [sci2:DiveCmd:YoYoDive:dive:D.DepthEnvelope](DEBUG): Uninitialize. 2025-04-21T22:37:04.559Z,1745275024.559 [sci2:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope] Stopped 2025-04-21T22:37:04.559Z,1745275024.559 [sci2:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2025-04-21T22:37:04.560Z,1745275024.560 [sci2:DiveCmd:YoYoDive:dive:F.YoYo] Stopped 2025-04-21T22:37:04.560Z,1745275024.560 [sci2:DiveCmd:YoYoDive:dive:F.YoYo](DEBUG): Uninitialize YoYoComponent. 2025-04-21T22:37:31.758Z,1745275051.758 [CTD_Seabird](ERROR): Failed to parse device response: 133.08,8.651.01 2025-04-21T22:39:15.932Z,1745275155.932 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error. Device response::TS,24052713333483, 0.0, +0.0, 0.0,1 +0.00, +0.00, +0.00, 0.00, 0.00 2025-04-21T22:40:28.457Z,1745275228.457 [BuoyancyServo](ERROR): getPosition uart error serial timeout 2025-04-21T22:40:28.457Z,1745275228.457 [BuoyancyServo](FAULT): Buoyancy getPosition uart error.serial timeout 2025-04-21T22:40:28.457Z,1745275228.457 [BuoyancyServo] Communications Fault, FailCount= 1 2025-04-21T22:40:28.457Z,1745275228.457 [BuoyancyServo](ERROR): Communications Fault 2025-04-21T22:40:28.701Z,1745275228.701 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2025-04-21T22:40:30.431Z,1745275230.431 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2025-04-21T22:40:30.431Z,1745275230.431 [BuoyancyServo](INFO): Powering down 2025-04-21T22:40:31.526Z,1745275231.526 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2025-04-21T22:40:31.526Z,1745275231.526 [BuoyancyServo] No Fault, FailCount= 1 2025-04-21T22:40:31.726Z,1745275231.726 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2025-04-21T22:40:31.726Z,1745275231.726 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2025-04-21T22:43:21.959Z,1745275401.959 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error. Device response::TS,24052713374057, 0.0, +0.0, 0.0,14 +0, +0, +0,V 2025-04-21T22:45:13.730Z,1745275513.730 [CTD_Seabird](ERROR): Failed to parse device response: .47 2025-04-21T22:45:26.855Z,1745275526.855 [sci2:NeedComms:C] Running Loop=1 2025-04-21T22:45:27.277Z,1745275527.277 [Radio_Surface](INFO): Powering up 2025-04-21T22:45:27.293Z,1745275527.293 [Waterlinked](INFO): Powering down 2025-04-21T22:45:33.351Z,1745275533.351 [DataOverHttps](INFO): Radio surface powered ON. 2025-04-21T22:45:59.546Z,1745275559.546 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-04-21T22:45:59.621Z,1745275559.621 [NAL9602](ERROR): received: +CSQ:0 OK578, 2, 0, 0, 0 OK 2025-04-21T22:46:03.469Z,1745275563.469 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-21T22:46:34.601Z,1745275594.601 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-21T22:46:55.314Z,1745275615.314 [NAL9602](INFO): SBD MO Status=2, MOMSN=23578, MT Status=2, MTMSN=0 2025-04-21T22:46:55.314Z,1745275615.314 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-21T22:47:05.726Z,1745275625.726 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-21T22:47:15.505Z,1745275635.505 [NAL9602](INFO): SBD MO Status=2, MOMSN=23578, MT Status=2, MTMSN=0 2025-04-21T22:47:15.505Z,1745275635.505 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-21T22:47:32.065Z,1745275652.065 [NAL9602](INFO): SBD MO Status=2, MOMSN=23578, MT Status=2, MTMSN=0 2025-04-21T22:47:32.065Z,1745275652.065 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-21T22:47:36.849Z,1745275656.849 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-21T22:47:52.265Z,1745275672.265 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=23578, MT Status=1, MTMSN=768 2025-04-21T22:47:52.266Z,1745275672.266 [NAL9602](INFO): Data available in MT queue 2025-04-21T22:47:52.765Z,1745275672.765 [NAL9602](INFO): Received command: restart logs;