2026-01-16T01:45:00.076Z,1768527900.076 [CommandExec](IMPORTANT): got command schedule clear 2026-01-16T01:45:00.076Z,1768527900.076 [CommandExec](IMPORTANT): Cleared 2 scheduled commands. 2026-01-16T01:45:00.077Z,1768527900.077 [CommandExec](IMPORTANT): got command schedule resume 2026-01-16T01:45:00.077Z,1768527900.077 [CommandExec](IMPORTANT): got command restart logs 2026-01-16T01:47:24.646Z,1768528044.646 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2026-01-16T01:50:51.017Z,1768528251.017 [CTD_Seabird](ERROR): Failed to parse device response: 4.021,4.01118,4065.93 2026-01-16T01:54:19.576Z,1768528459.576 [DVL_micro](ERROR): DVL uart error: serial timeout 2026-01-16T01:54:19.576Z,1768528459.576 [DVL_micro] Communications Fault, FailCount= 1 2026-01-16T01:54:19.576Z,1768528459.576 [DVL_micro](ERROR): Communications Fault 2026-01-16T01:54:19.576Z,1768528459.576 [DVL_micro](ERROR): Failed to parse: 2026-01-16T01:54:19.675Z,1768528459.675 [CBIT](ERROR): Communications Fault in component: DVL_micro 2026-01-16T01:54:20.008Z,1768528460.008 [DVL_micro](INFO): Powering down 2026-01-16T01:54:20.847Z,1768528460.847 [CBIT](INFO): Clearing failed state for component DVL_micro 2026-01-16T01:54:20.847Z,1768528460.847 [DVL_micro] No Fault, FailCount= 1 2026-01-16T01:54:38.304Z,1768528478.304 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99,+9999.99,+9999.99,+9999.99 2026-01-16T01:56:31.907Z,1768528591.907 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2026-01-16T01:56:31.910Z,1768528591.910 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8. 2026-01-16T02:06:29.071Z,1768529189.071 [DVL_micro](ERROR): Failed to parse: :WI,-00493,-00784,+00317,+00000,A 2026-01-16T02:15:51.643Z,1768529751.643 [DVL_micro](ERROR): Failed to parse: :T6,-00408,+00094,+00000,A 2026-01-16T02:15:52.199Z,1768529752.199 [CTD_Seabird](ERROR): Failed to parse device response: 8,4.02549,4051.21 2026-01-16T02:23:12.057Z,1768530192.057 [CTD_Seabird](ERROR): Failed to parse device response: 3.68,14.2589,4.021580 2026-01-16T02:28:40.380Z,1768530520.380 [DVL_micro](ERROR): Failed to parse: :SA,-07.63,+06.43,068.5 2026-01-16T02:30:40.075Z,1768530640.075 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2026-01-16T02:32:24.353Z,1768530744.353 [CTD_Seabird](ERROR): Failed to parse device response: 3.73,14.233.18 2026-01-16T02:34:15.772Z,1768530855.772 [GFScanner](IMPORTANT): Beginning ground fault scan 2026-01-16T02:34:15.809Z,1768530855.809 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2026-01-16T02:34:15.811Z,1768530855.811 [CTD_Seabird](INFO): some bad data, not updating bins 2026-01-16T02:34:16.816Z,1768530856.816 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2026-01-16T02:34:16.817Z,1768530856.817 [CTD_Seabird](INFO): some bad data, not updating bins 2026-01-16T02:34:17.817Z,1768530857.817 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2026-01-16T02:34:17.819Z,1768530857.819 [CTD_Seabird](INFO): some bad data, not updating bins 2026-01-16T02:34:18.822Z,1768530858.822 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2026-01-16T02:34:18.823Z,1768530858.823 [CTD_Seabird](INFO): some bad data, not updating bins 2026-01-16T02:34:19.825Z,1768530859.825 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2026-01-16T02:34:19.826Z,1768530859.826 [CTD_Seabird](INFO): some bad data, not updating bins 2026-01-16T02:34:20.090Z,1768530860.090 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.003571 CHAN A1 (24V): 0.006840 CHAN A2 (12V): -0.005746 CHAN A3 (5V): -0.008731 CHAN B0 (3.3V): -0.004416 CHAN B1 (3.15aV): -0.001703 CHAN B2 (3.15bV): -0.001676 CHAN B3 (GND): -0.005977 OPEN: 0.012313 Full Scale: +/- 1 mA 2026-01-16T02:37:34.038Z,1768531054.038 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2026-01-16T02:37:34.047Z,1768531054.047 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8. 2026-01-16T02:43:25.661Z,1768531405.661 [sci2_vtyoyo:NeedComms] Running Loop=1 2026-01-16T02:43:25.661Z,1768531405.661 [sci2_vtyoyo:NeedComms](DEBUG): Aggregate::initialize sci2_vtyoyo:NeedComms 2026-01-16T02:43:25.661Z,1768531405.661 [sci2_vtyoyo:NeedComms:C.GoToSurface] Running Loop=1 2026-01-16T02:43:25.661Z,1768531405.661 [sci2_vtyoyo:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2026-01-16T02:43:25.661Z,1768531405.661 [sci2_vtyoyo:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s. 2026-01-16T02:43:25.662Z,1768531405.662 [sci2_vtyoyo:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2026-01-16T02:43:25.662Z,1768531405.662 [sci2_vtyoyo:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2026-01-16T02:43:25.662Z,1768531405.662 [sci2_vtyoyo:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds. 2026-01-16T02:43:25.663Z,1768531405.663 [sci2_vtyoyo:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2026-01-16T02:43:25.663Z,1768531405.663 [sci2_vtyoyo:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2026-01-16T02:43:25.663Z,1768531405.663 [sci2_vtyoyo:NeedComms:A] Running Loop=1 2026-01-16T02:43:25.665Z,1768531405.665 [sci2_vtyoyo:NeedComms:A](INFO): last time_fix was: 1768525995.000000 second since 1970/01/01T00:00:00Z 2026-01-16T02:43:25.665Z,1768531405.665 [sci2_vtyoyo:NeedComms:A] Stopped 2026-01-16T02:43:25.665Z,1768531405.665 [sci2_vtyoyo:NeedComms:setActive] Running Loop=1 2026-01-16T02:43:25.665Z,1768531405.665 [sci2_vtyoyo:NeedComms:setActive](DEBUG): Aggregate::initialize sci2_vtyoyo:NeedComms:setActive 2026-01-16T02:43:25.666Z,1768531405.666 [sci2_vtyoyo:NeedComms:setActive:A] Running Loop=1 2026-01-16T02:43:26.086Z,1768531406.086 [sci2_vtyoyo:NeedComms:setActive:A] Stopped 2026-01-16T02:43:26.086Z,1768531406.086 [sci2_vtyoyo:NeedComms:setActive:B] Running Loop=1 2026-01-16T02:43:26.486Z,1768531406.486 [sci2_vtyoyo:NeedComms:setActive:B] Stopped 2026-01-16T02:43:26.486Z,1768531406.486 [sci2_vtyoyo:NeedComms:setActive](INFO): Completed sci2_vtyoyo:NeedComms:setActive 2026-01-16T02:43:26.486Z,1768531406.486 [sci2_vtyoyo:NeedComms:setActive] Stopped 2026-01-16T02:43:26.486Z,1768531406.486 [sci2_vtyoyo:NeedComms:setActive](DEBUG): Aggregate::uninitialize sci2_vtyoyo:NeedComms:setActive 2026-01-16T02:43:26.956Z,1768531406.956 [sci2_vtyoyo:manageDiveMode:syncWhileActive] Running Loop=1 2026-01-16T02:43:26.957Z,1768531406.957 [sci2_vtyoyo:manageDiveMode:syncWhileActive](DEBUG): Aggregate::initialize sci2_vtyoyo:manageDiveMode:syncWhileActive 2026-01-16T02:43:26.957Z,1768531406.957 [sci2_vtyoyo:manageDiveMode:syncWhileActive:A] Running Loop=1 2026-01-16T02:43:27.292Z,1768531407.292 [sci2_vtyoyo:Lap:Dive] Stopped 2026-01-16T02:43:27.292Z,1768531407.292 [sci2_vtyoyo:Lap:Dive](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Lap:Dive 2026-01-16T02:43:27.292Z,1768531407.292 [sci2_vtyoyo:Lap:Dive:A] Stopped 2026-01-16T02:43:27.292Z,1768531407.292 [sci2_vtyoyo:Lap:Dive:A](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Lap:Dive:A 2026-01-16T02:43:27.292Z,1768531407.292 [sci2_vtyoyo:Lap:Dive:A:SurfaceSink] Stopped 2026-01-16T02:43:27.292Z,1768531407.292 [sci2_vtyoyo:Lap:Dive:A:SurfaceSink](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Lap:Dive:A:SurfaceSink 2026-01-16T02:43:27.292Z,1768531407.292 [sci2_vtyoyo:Lap:Dive:A:YoYoDive] Stopped 2026-01-16T02:43:27.292Z,1768531407.292 [sci2_vtyoyo:Lap:Dive:A:YoYoDive](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Lap:Dive:A:YoYoDive 2026-01-16T02:43:27.293Z,1768531407.293 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive] Stopped 2026-01-16T02:43:27.293Z,1768531407.293 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive 2026-01-16T02:43:27.293Z,1768531407.293 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:A.Pitch] Stopped 2026-01-16T02:43:27.293Z,1768531407.293 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:B.Buoyancy] Stopped 2026-01-16T02:43:27.293Z,1768531407.293 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2026-01-16T02:43:27.293Z,1768531407.293 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:C.SetSpeed] Stopped 2026-01-16T02:43:27.293Z,1768531407.293 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:C.SetSpeed](DEBUG): Uninitialize. 2026-01-16T02:43:27.293Z,1768531407.293 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:D.DepthEnvelope] Stopped 2026-01-16T02:43:27.293Z,1768531407.293 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:D.DepthEnvelope](DEBUG): Uninitialize. 2026-01-16T02:43:27.293Z,1768531407.293 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:E.AltitudeEnvelope] Stopped 2026-01-16T02:43:27.293Z,1768531407.293 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:E.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2026-01-16T02:43:27.293Z,1768531407.293 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:F.YoYoPARLicor] Stopped 2026-01-16T02:43:27.293Z,1768531407.293 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:F.YoYoPARLicor](DEBUG): Uninitialize Value-Terminated YoYo. 2026-01-16T02:43:27.295Z,1768531407.295 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:G.YoYo] Stopped 2026-01-16T02:43:27.295Z,1768531407.295 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:G.YoYo](DEBUG): Uninitialize YoYoComponent. 2026-01-16T02:43:53.037Z,1768531433.037 [sci2_vtyoyo:NeedComms:D] Running Loop=1 2026-01-16T02:43:53.716Z,1768531433.716 [Radio_Surface](INFO): Powering up 2026-01-16T02:43:59.344Z,1768531439.344 [DataOverHttps](INFO): Radio surface powered ON. 2026-01-16T02:44:25.774Z,1768531465.774 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2026-01-16T02:44:25.848Z,1768531465.848 [NAL9602](ERROR): received: +SBDI: 2, 25247, 2, 0, 0, 0 OK 2026-01-16T02:44:52.847Z,1768531492.847 [NAL9602](INFO): SBD MO Status=2, MOMSN=25247, MT Status=2, MTMSN=0 2026-01-16T02:44:52.872Z,1768531492.872 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2026-01-16T02:45:09.401Z,1768531509.401 [NAL9602](INFO): SBD MO Status=2, MOMSN=25247, MT Status=2, MTMSN=0 2026-01-16T02:45:09.401Z,1768531509.401 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2026-01-16T02:45:51.014Z,1768531551.014 [NAL9602](INFO): SBD MO Status=2, MOMSN=25247, MT Status=2, MTMSN=0 2026-01-16T02:45:51.015Z,1768531551.015 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2026-01-16T02:46:09.598Z,1768531569.598 [NAL9602](INFO): SBD MO Status=2, MOMSN=25247, MT Status=2, MTMSN=0 2026-01-16T02:46:09.598Z,1768531569.598 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2026-01-16T02:46:15.904Z,1768531575.904 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-16T02:46:22.538Z,1768531582.538 [NAL9602](INFO): SBD MO Status=1, MOMSN=25247, MT Status=0, MTMSN=0 2026-01-16T02:46:22.538Z,1768531582.538 [NAL9602](INFO): No messages in MT queue 2026-01-16T02:46:23.739Z,1768531583.739 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,024621.00,A,3642.97455,N,12211.32930,W,0.486,57.85,160126,,,A*4B 2026-01-16T02:46:23.741Z,1768531583.741 [NAL9602](INFO): GPS fix at 20260116T024621: (36.716242, -122.188822) 2026-01-16T02:46:23.752Z,1768531583.752 [UniversalFixResidualReporter](INFO): Fix residual: 41.4 %DT, over the last 3313.9 m. Residual distance 1372.1 m at bearing 95.6 degrees. Fix at (36.7162, -122.1888) with 4604.7 m made good. 2026-01-16T02:46:23.754Z,1768531583.754 [sci2_vtyoyo:Lap:Wpt2:A.Waypoint](IMPORTANT): Reached waypoint: 36.712109,-122.187027 2026-01-16T02:46:23.754Z,1768531583.754 [sci2_vtyoyo:Lap:Wpt2:A.Waypoint] Stopped 2026-01-16T02:46:23.754Z,1768531583.754 [sci2_vtyoyo:Lap:Wpt2:A.Waypoint](DEBUG): Uninitialize WaypointComponent. 2026-01-16T02:46:23.754Z,1768531583.754 [sci2_vtyoyo:Lap:Wpt2](INFO): Completed sci2_vtyoyo:Lap:Wpt2 2026-01-16T02:46:23.755Z,1768531583.755 [sci2_vtyoyo:Lap:Wpt2] Stopped 2026-01-16T02:46:23.755Z,1768531583.755 [sci2_vtyoyo:Lap:Wpt2](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Lap:Wpt2 2026-01-16T02:46:23.755Z,1768531583.755 [sci2_vtyoyo:Lap:Wpt3] Running Loop=1 2026-01-16T02:46:23.755Z,1768531583.755 [sci2_vtyoyo:Lap:Wpt3](DEBUG): Aggregate::initialize sci2_vtyoyo:Lap:Wpt3 2026-01-16T02:46:23.755Z,1768531583.755 [sci2_vtyoyo:Lap:Wpt3:A.Waypoint] Running Loop=1 2026-01-16T02:46:23.755Z,1768531583.755 [sci2_vtyoyo:Lap:Wpt3:A.Waypoint](DEBUG): Initialize WaypointComponent. 2026-01-16T02:46:23.761Z,1768531583.761 [sci2_vtyoyo:NeedComms:D] Stopped 2026-01-16T02:46:23.761Z,1768531583.761 [sci2_vtyoyo:NeedComms:E] Running Loop=1 2026-01-16T02:46:47.036Z,1768531607.036 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-16T02:46:49.675Z,1768531609.675 [NAL9602](INFO): SBD MO Status=2, MOMSN=25248, MT Status=2, MTMSN=0 2026-01-16T02:46:49.676Z,1768531609.676 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2026-01-16T02:47:13.282Z,1768531633.282 [NAL9602](INFO): SBD MO Status=2, MOMSN=25248, MT Status=2, MTMSN=0 2026-01-16T02:47:13.283Z,1768531633.283 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2026-01-16T02:47:18.160Z,1768531638.160 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-16T02:47:39.299Z,1768531659.299 [NAL9602](INFO): SBD MO Status=2, MOMSN=25248, MT Status=2, MTMSN=0 2026-01-16T02:47:39.299Z,1768531659.299 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2026-01-16T02:47:49.288Z,1768531669.288 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-16T02:48:20.417Z,1768531700.417 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-16T02:48:51.540Z,1768531731.540 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-16T02:49:22.664Z,1768531762.664 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-16T02:49:27.574Z,1768531767.574 [DVL_micro](ERROR): Failed to parse: :SA,+01.62,+00.66,063.1 2026-01-16T02:49:53.788Z,1768531793.788 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-16T02:50:24.912Z,1768531824.912 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-16T02:50:56.036Z,1768531856.036 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-16T02:51:20.422Z,1768531880.422 [NAL9602](INFO): SBD MO Status=2, MOMSN=25248, MT Status=2, MTMSN=0 2026-01-16T02:51:20.422Z,1768531880.422 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2026-01-16T02:51:27.162Z,1768531887.162 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-16T02:51:56.473Z,1768531916.473 [DataOverHttps](IMPORTANT): SBD MTMSN=20260116T025153 2026-01-16T02:51:56.476Z,1768531916.476 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003201 2026-01-16T02:52:05.021Z,1768531925.021 [NAL9602](INFO): SBD MO Status=2, MOMSN=25248, MT Status=2, MTMSN=0 2026-01-16T02:52:05.022Z,1768531925.022 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2026-01-16T02:52:05.276Z,1768531925.276 [DataOverHttps](INFO): Sending 90 bytes from file Logs/20260115T143402/Courier0037.lzma 2026-01-16T02:52:05.278Z,1768531925.278 [DataOverHttps](INFO): Received command: sched 20260116T1445 "restart logs" 2026-01-16T02:52:05.282Z,1768531925.282 [CommandExec](IMPORTANT): got command schedule 20260116T144500 "restart logs" 2026-01-16T02:52:05.282Z,1768531925.282 [CommandExec](IMPORTANT): Scheduled #7: "restart logs", AT TIME: 2026-01-16T14:45:00Z 2026-01-16T02:52:06.471Z,1768531926.471 [DataOverHttps](INFO): Moved sent file to Logs/20260115T143402/Courier0037.lzma.bak 2026-01-16T02:52:06.471Z,1768531926.471 [DataOverHttps](IMPORTANT): SBD MOMSN=26575872, MTMSN=20260116T025203 2026-01-16T02:52:14.182Z,1768531934.182 [DataOverHttps](INFO): Received command: sched 20260116T1445 "load Science/profile_station_vt.tl;set profile_station.MissionTimeout 14 h;set profile_station.NeedCommsTime 90 min;set profile_station.Lat 36.71211 degree;set profile_station.Lon -122.187028 degree" 3jxzg 1 3 2026-01-16T02:52:14.234Z,1768531934.234 [CommandExec](IMPORTANT): got command schedule 20260116T144500 "load Science/profile_station_vt.tl;set profile_station.MissionTimeout 14 h;set profile_station.NeedCommsTime 90 min;set profile_station.Lat 36.71211 degree;set profile_station.Lon -122.187028 degree" 3jxzg 1 3.000000 2026-01-16T02:52:14.234Z,1768531934.234 [CommandExec](IMPORTANT): Scheduling command #1 of 3 with id=3jxzg 2026-01-16T02:52:14.236Z,1768531934.236 [CommandExec](IMPORTANT): Scheduled #8 (#1 of 3 with id='3jxzg'): "load Science/profile_station_vt.tl;set profile_station.MissionTimeout 14 h;set profile_station.NeedCommsTime 90 min;set profile_station.Lat 36.71211 degree;set profile_station.Lon -122.187028 degree", AT TIME: 2026-01-16T14:45:00Z 2026-01-16T02:52:15.522Z,1768531935.522 [DataOverHttps](IMPORTANT): SBD MTMSN=20260116T025212 2026-01-16T02:52:23.424Z,1768531943.424 [DataOverHttps](INFO): Sending 371 bytes from file Logs/20260116T014500/Courier0000.lzma 2026-01-16T02:52:23.427Z,1768531943.427 [DataOverHttps](INFO): Received command: sched 20260116T1445 "set profile_station.Radius 3000 m;set profile_station.YoYoMinDepth 4 m;set profile_station.YoYoMaxDepth 50 m;set profile_station.Speed .9 m/s;set profile_station.SinkOffSurface 1 bool" 3jxzg 2 3 2026-01-16T02:52:23.533Z,1768531943.533 [CommandExec](IMPORTANT): got command schedule 20260116T144500 "set profile_station.Radius 3000 m;set profile_station.YoYoMinDepth 4 m;set profile_station.YoYoMaxDepth 50 m;set profile_station.Speed .9 m/s;set profile_station.SinkOffSurface 1 bool" 3jxzg 2 3.000000 2026-01-16T02:52:23.533Z,1768531943.533 [CommandExec](IMPORTANT): Scheduling command #2 of 3 with id=3jxzg 2026-01-16T02:52:23.535Z,1768531943.535 [CommandExec](IMPORTANT): Scheduled #9 (#2 of 3 with id='3jxzg'): "set profile_station.Radius 3000 m;set profile_station.YoYoMinDepth 4 m;set profile_station.YoYoMaxDepth 50 m;set profile_station.Speed .9 m/s;set profile_station.SinkOffSurface 1 bool", AT TIME: 2026-01-16T14:45:00Z 2026-01-16T02:52:24.614Z,1768531944.614 [DataOverHttps](INFO): Moved sent file to Logs/20260116T014500/Courier0000.lzma.bak 2026-01-16T02:52:24.614Z,1768531944.614 [DataOverHttps](IMPORTANT): SBD MOMSN=26575877, MTMSN=20260116T025221 2026-01-16T02:52:33.312Z,1768531953.312 [DataOverHttps](INFO): Received command: sched 20260116T1445 "set profile_station.SinkDepthTimeout 2 min;set profile_station.MaxDepth 70 m;set profile_station:BackseatDriver.EnableBackseat 1 bool;run" 3jxzg 3 3 2026-01-16T02:52:33.339Z,1768531953.339 [CommandExec](IMPORTANT): got command schedule 20260116T144500 "set profile_station.SinkDepthTimeout 2 min;set profile_station.MaxDepth 70 m;set profile_station:BackseatDriver.EnableBackseat 1 bool;run" 3jxzg 3 3.000000 2026-01-16T02:52:33.341Z,1768531953.341 [CommandExec](IMPORTANT): Scheduling command #3 of 3 with id=3jxzg 2026-01-16T02:52:33.343Z,1768531953.343 [CommandExec](IMPORTANT): Scheduled #10 (#3 of 3 with id='3jxzg'): "set profile_station.SinkDepthTimeout 2 min;set profile_station.MaxDepth 70 m;set profile_station:BackseatDriver.EnableBackseat 1 bool;run", AT TIME: 2026-01-16T14:45:00Z 2026-01-16T02:52:35.645Z,1768531955.645 [NAL9602](INFO): Not Powering down - fast GPS 2026-01-16T02:52:42.331Z,1768531962.331 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20260116T014500/Courier0003.lzma 2026-01-16T02:52:43.334Z,1768531963.334 [DataOverHttps](INFO): Moved sent file to Logs/20260116T014500/Courier0003.lzma.bak 2026-01-16T02:52:43.334Z,1768531963.334 [DataOverHttps](INFO): SBD MOMSN=26575883 2026-01-16T02:53:01.003Z,1768531981.003 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20260116T014500/Courier0006.lzma 2026-01-16T02:53:02.006Z,1768531982.006 [DataOverHttps](INFO): Moved sent file to Logs/20260116T014500/Courier0006.lzma.bak 2026-01-16T02:53:02.006Z,1768531982.006 [DataOverHttps](INFO): SBD MOMSN=26575885 2026-01-16T02:53:18.803Z,1768531998.803 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20260116T014500/Courier0009.lzma 2026-01-16T02:53:19.546Z,1768531999.546 [CTD_Seabird](ERROR): Failed to parse device response: 0.14,14.2191,4.01793, 2026-01-16T02:53:19.806Z,1768531999.806 [DataOverHttps](INFO): Moved sent file to Logs/20260116T014500/Courier0009.lzma.bak 2026-01-16T02:53:19.806Z,1768531999.806 [DataOverHttps](INFO): SBD MOMSN=26575888 2026-01-16T02:53:36.924Z,1768532016.924 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20260116T014500/Courier0012.lzma 2026-01-16T02:53:37.926Z,1768532017.926 [DataOverHttps](INFO): Moved sent file to Logs/20260116T014500/Courier0012.lzma.bak 2026-01-16T02:53:37.926Z,1768532017.926 [DataOverHttps](INFO): SBD MOMSN=26575890 2026-01-16T02:53:54.936Z,1768532034.936 [DataOverHttps](INFO): Sending 612 bytes from file Logs/20260115T143402/Express0039.lzma 2026-01-16T02:53:55.938Z,1768532035.938 [DataOverHttps](INFO): Moved sent file to Logs/20260115T143402/Express0039.lzma.bak 2026-01-16T02:53:55.938Z,1768532035.938 [DataOverHttps](INFO): SBD MOMSN=26575892 2026-01-16T02:54:12.902Z,1768532052.902 [DataOverHttps](INFO): Sending 1659 bytes from file Logs/20260116T014500/Express0001.lzma 2026-01-16T02:54:13.902Z,1768532053.902 [DataOverHttps](INFO): Moved sent file to Logs/20260116T014500/Express0001.lzma.bak 2026-01-16T02:54:13.902Z,1768532053.902 [DataOverHttps](INFO): SBD MOMSN=26575896 2026-01-16T02:54:28.405Z,1768532068.405 [DVL_micro](ERROR): only read 1 of 4 data items for bottom velocity. Device response is::BI,000,I 2026-01-16T02:54:28.405Z,1768532068.405 [DVL_micro](ERROR): Failed to parse: :BI,000,I 2026-01-16T02:54:30.900Z,1768532070.900 [DataOverHttps](INFO): Sending 272 bytes from file Logs/20260116T014500/Express0004.lzma 2026-01-16T02:54:31.902Z,1768532071.902 [DataOverHttps](INFO): Moved sent file to Logs/20260116T014500/Express0004.lzma.bak 2026-01-16T02:54:31.902Z,1768532071.902 [DataOverHttps](INFO): SBD MOMSN=26575907 2026-01-16T02:54:51.876Z,1768532091.876 [DataOverHttps](INFO): Sending 388 bytes from file Logs/20260116T014500/Express0007.lzma 2026-01-16T02:54:52.878Z,1768532092.878 [DataOverHttps](INFO): Moved sent file to Logs/20260116T014500/Express0007.lzma.bak 2026-01-16T02:54:52.878Z,1768532092.878 [DataOverHttps](INFO): SBD MOMSN=26575912 2026-01-16T02:55:10.052Z,1768532110.052 [DataOverHttps](INFO): Sending 356 bytes from file Logs/20260116T014500/Express0010.lzma 2026-01-16T02:55:11.054Z,1768532111.054 [DataOverHttps](INFO): Moved sent file to Logs/20260116T014500/Express0010.lzma.bak 2026-01-16T02:55:11.054Z,1768532111.054 [DataOverHttps](INFO): SBD MOMSN=26575918 2026-01-16T02:55:28.083Z,1768532128.083 [DataOverHttps](INFO): Sending 355 bytes from file Logs/20260116T014500/Express0013.lzma 2026-01-16T02:55:29.082Z,1768532129.082 [DataOverHttps](INFO): Moved sent file to Logs/20260116T014500/Express0013.lzma.bak 2026-01-16T02:55:29.082Z,1768532129.082 [DataOverHttps](INFO): SBD MOMSN=26575924 2026-01-16T02:55:30.417Z,1768532130.417 [sci2_vtyoyo:NeedComms:E] Stopped 2026-01-16T02:55:30.418Z,1768532130.418 [sci2_vtyoyo:NeedComms:F] Running Loop=1 2026-01-16T02:55:32.445Z,1768532132.445 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,025529.00,A,3643.01008,N,12211.40496,W,1.050,275.72,160126,,,A*73 2026-01-16T02:55:32.447Z,1768532132.447 [NAL9602](INFO): GPS fix at 20260116T025529: (36.716835, -122.190083) 2026-01-16T02:55:32.493Z,1768532132.493 [sci2_vtyoyo:NeedComms:F] Stopped 2026-01-16T02:55:32.493Z,1768532132.493 [sci2_vtyoyo:NeedComms:setRestoreMode] Running Loop=1 2026-01-16T02:55:32.493Z,1768532132.493 [sci2_vtyoyo:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize sci2_vtyoyo:NeedComms:setRestoreMode 2026-01-16T02:55:32.493Z,1768532132.493 [sci2_vtyoyo:NeedComms:setRestoreMode:A] Running Loop=1 2026-01-16T02:55:32.853Z,1768532132.853 [sci2_vtyoyo:NeedComms:setRestoreMode:A] Stopped 2026-01-16T02:55:32.853Z,1768532132.853 [sci2_vtyoyo:NeedComms:setRestoreMode](INFO): Completed sci2_vtyoyo:NeedComms:setRestoreMode 2026-01-16T02:55:32.853Z,1768532132.853 [sci2_vtyoyo:NeedComms:setRestoreMode] Stopped 2026-01-16T02:55:32.853Z,1768532132.853 [sci2_vtyoyo:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize sci2_vtyoyo:NeedComms:setRestoreMode 2026-01-16T02:55:32.853Z,1768532132.853 [sci2_vtyoyo:NeedComms:setInactive] Running Loop=1 2026-01-16T02:55:32.853Z,1768532132.853 [sci2_vtyoyo:NeedComms:setInactive](DEBUG): Aggregate::initialize sci2_vtyoyo:NeedComms:setInactive 2026-01-16T02:55:32.854Z,1768532132.854 [sci2_vtyoyo:NeedComms:setInactive:A] Running Loop=1 2026-01-16T02:55:33.268Z,1768532133.268 [sci2_vtyoyo:NeedComms:setInactive:A] Stopped 2026-01-16T02:55:33.269Z,1768532133.269 [sci2_vtyoyo:NeedComms:setInactive](INFO): Completed sci2_vtyoyo:NeedComms:setInactive 2026-01-16T02:55:33.269Z,1768532133.269 [sci2_vtyoyo:NeedComms:setInactive] Stopped 2026-01-16T02:55:33.269Z,1768532133.269 [sci2_vtyoyo:NeedComms:setInactive](DEBUG): Aggregate::uninitialize sci2_vtyoyo:NeedComms:setInactive 2026-01-16T02:55:33.270Z,1768532133.270 [sci2_vtyoyo:NeedComms](INFO): Completed sci2_vtyoyo:NeedComms 2026-01-16T02:55:33.270Z,1768532133.270 [sci2_vtyoyo:NeedComms] Stopped 2026-01-16T02:55:33.270Z,1768532133.270 [sci2_vtyoyo:NeedComms](DEBUG): Aggregate::uninitialize sci2_vtyoyo:NeedComms 2026-01-16T02:55:33.270Z,1768532133.270 [sci2_vtyoyo:NeedComms:C.GoToSurface] Stopped 2026-01-16T02:55:33.270Z,1768532133.270 [sci2_vtyoyo:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2026-01-16T02:55:33.670Z,1768532133.670 [sci2_vtyoyo:Lap:Dive] Running Loop=1 2026-01-16T02:55:33.670Z,1768532133.670 [sci2_vtyoyo:Lap:Dive](DEBUG): Aggregate::initialize sci2_vtyoyo:Lap:Dive 2026-01-16T02:55:33.670Z,1768532133.670 [sci2_vtyoyo:Lap:Dive:A] Running Loop=1 2026-01-16T02:55:33.670Z,1768532133.670 [sci2_vtyoyo:Lap:Dive:A](DEBUG): Aggregate::initialize sci2_vtyoyo:Lap:Dive:A 2026-01-16T02:55:33.670Z,1768532133.670 [sci2_vtyoyo:Lap:Dive:A:SurfaceSink] Running Loop=1 2026-01-16T02:55:33.670Z,1768532133.670 [sci2_vtyoyo:Lap:Dive:A:SurfaceSink](DEBUG): Aggregate::initialize sci2_vtyoyo:Lap:Dive:A:SurfaceSink 2026-01-16T02:55:33.670Z,1768532133.670 [sci2_vtyoyo:Lap:Dive:A:SurfaceSink:sink] Running Loop=1 2026-01-16T02:55:33.670Z,1768532133.670 [sci2_vtyoyo:Lap:Dive:A:SurfaceSink:sink](DEBUG): Aggregate::initialize sci2_vtyoyo:Lap:Dive:A:SurfaceSink:sink 2026-01-16T02:55:33.670Z,1768532133.670 [sci2_vtyoyo:Lap:Dive:A:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2026-01-16T02:55:33.670Z,1768532133.670 [sci2_vtyoyo:Lap:Dive:A:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2026-01-16T02:55:33.671Z,1768532133.671 [sci2_vtyoyo:Lap:Dive:A:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2026-01-16T02:55:33.671Z,1768532133.671 [sci2_vtyoyo:Lap:Dive:A:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2026-01-16T02:55:33.671Z,1768532133.671 [sci2_vtyoyo:Lap:Dive:A:SurfaceSink:sink:C] Running Loop=1 2026-01-16T02:55:33.671Z,1768532133.671 [sci2_vtyoyo:Lap:Dive:A:YoYoDive] Running Loop=1 2026-01-16T02:55:33.671Z,1768532133.671 [sci2_vtyoyo:Lap:Dive:A:YoYoDive](DEBUG): Aggregate::initialize sci2_vtyoyo:Lap:Dive:A:YoYoDive 2026-01-16T02:55:33.671Z,1768532133.671 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive] Running Loop=1 2026-01-16T02:55:33.671Z,1768532133.671 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive](DEBUG): Aggregate::initialize sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive 2026-01-16T02:55:33.671Z,1768532133.671 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:A.Pitch] Running Loop=1 2026-01-16T02:55:33.671Z,1768532133.671 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:A.Pitch](DEBUG): Initialize. 2026-01-16T02:55:33.671Z,1768532133.671 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:B.Buoyancy] Running Loop=1 2026-01-16T02:55:33.671Z,1768532133.671 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:B.Buoyancy](DEBUG): Initialize Buoyancy Component. 2026-01-16T02:55:33.680Z,1768532133.680 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:C.SetSpeed] Running Loop=1 2026-01-16T02:55:33.680Z,1768532133.680 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:C.SetSpeed](DEBUG): Initialize. 2026-01-16T02:55:33.680Z,1768532133.680 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:D.DepthEnvelope] Running Loop=1 2026-01-16T02:55:33.680Z,1768532133.680 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:D.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2026-01-16T02:55:33.680Z,1768532133.680 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:E.AltitudeEnvelope] Running Loop=1 2026-01-16T02:55:33.680Z,1768532133.680 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:E.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2026-01-16T02:55:33.681Z,1768532133.681 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:F.YoYoPARLicor] Running Loop=1 2026-01-16T02:55:33.681Z,1768532133.681 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:F.YoYoPARLicor](DEBUG): Initialize Value-Terminated YoYo. 2026-01-16T02:55:33.696Z,1768532133.696 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:G.YoYo] Running Loop=1 2026-01-16T02:55:33.696Z,1768532133.696 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:G.YoYo](DEBUG): Initialize YoYoComponent. 2026-01-16T02:55:33.697Z,1768532133.697 [sci2_vtyoyo:Lap:Dive:A:YoYoDive] Stopped 2026-01-16T02:55:33.697Z,1768532133.697 [sci2_vtyoyo:Lap:Dive:A:YoYoDive](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Lap:Dive:A:YoYoDive 2026-01-16T02:55:33.697Z,1768532133.697 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive] Stopped 2026-01-16T02:55:33.697Z,1768532133.697 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive 2026-01-16T02:55:33.697Z,1768532133.697 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:A.Pitch] Stopped 2026-01-16T02:55:33.697Z,1768532133.697 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:B.Buoyancy] Stopped 2026-01-16T02:55:33.697Z,1768532133.697 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2026-01-16T02:55:33.697Z,1768532133.697 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:C.SetSpeed] Stopped 2026-01-16T02:55:33.697Z,1768532133.697 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:C.SetSpeed](DEBUG): Uninitialize. 2026-01-16T02:55:33.697Z,1768532133.697 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:D.DepthEnvelope] Stopped 2026-01-16T02:55:33.697Z,1768532133.697 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:D.DepthEnvelope](DEBUG): Uninitialize. 2026-01-16T02:55:33.697Z,1768532133.697 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:E.AltitudeEnvelope] Stopped 2026-01-16T02:55:33.697Z,1768532133.697 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:E.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2026-01-16T02:55:33.698Z,1768532133.698 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:F.YoYoPARLicor] Stopped 2026-01-16T02:55:33.698Z,1768532133.698 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:F.YoYoPARLicor](DEBUG): Uninitialize Value-Terminated YoYo. 2026-01-16T02:55:33.699Z,1768532133.699 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:G.YoYo] Stopped 2026-01-16T02:55:33.699Z,1768532133.699 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:G.YoYo](DEBUG): Uninitialize YoYoComponent. 2026-01-16T02:55:33.700Z,1768532133.700 [sci2_vtyoyo:Lap:Dive:A:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2026-01-16T02:55:33.708Z,1768532133.708 [sci2_vtyoyo:Lap:Dive:A:SurfaceSink:sink:C] Stopped 2026-01-16T02:55:33.708Z,1768532133.708 [sci2_vtyoyo:Lap:Dive:A:SurfaceSink:sink:D.Pitch] Running Loop=1 2026-01-16T02:55:33.708Z,1768532133.708 [sci2_vtyoyo:Lap:Dive:A:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2026-01-16T02:55:33.709Z,1768532133.709 [sci2_vtyoyo:manageDiveMode:syncWhileActive] Stopped 2026-01-16T02:55:33.709Z,1768532133.709 [sci2_vtyoyo:manageDiveMode:syncWhileActive](DEBUG): Aggregate::uninitialize sci2_vtyoyo:manageDiveMode:syncWhileActive 2026-01-16T02:55:33.709Z,1768532133.709 [sci2_vtyoyo:manageDiveMode:syncWhileActive:A] Stopped 2026-01-16T02:56:04.399Z,1768532164.399 [NAL9602](INFO): SBD MO Status=2, MOMSN=25248, MT Status=2, MTMSN=0 2026-01-16T02:56:04.399Z,1768532164.399 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2026-01-16T02:56:21.789Z,1768532181.789 [NAL9602](INFO): SBD MO Status=2, MOMSN=25248, MT Status=2, MTMSN=0 2026-01-16T02:56:21.789Z,1768532181.789 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2026-01-16T02:56:43.599Z,1768532203.599 [NAL9602](INFO): SBD MO Status=2, MOMSN=25248, MT Status=2, MTMSN=0 2026-01-16T02:56:43.599Z,1768532203.599 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2026-01-16T02:57:32.684Z,1768532252.684 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-16T02:57:34.055Z,1768532254.055 [sci2_vtyoyo:Lap:Dive:A:SurfaceSink:sink:D.Pitch](INFO): Timed out from 2026-01-16T02:55:33.7Z 2026-01-16T02:57:34.055Z,1768532254.055 [sci2_vtyoyo:Lap:Dive:A:SurfaceSink:sink:D.Pitch:A_Timeout] Running Loop=1 2026-01-16T02:57:34.055Z,1768532254.055 [sci2_vtyoyo:Lap:Dive:A:SurfaceSink:sink:D.Pitch:A_Timeout](DEBUG): Aggregate::initialize sci2_vtyoyo:Lap:Dive:A:SurfaceSink:sink:D.Pitch:A_Timeout 2026-01-16T02:57:34.056Z,1768532254.056 [sci2_vtyoyo:Lap:Dive:A:SurfaceSink:sink:D.Pitch:A_Timeout:A] Running Loop=1 2026-01-16T02:57:34.058Z,1768532254.058 [sci2_vtyoyo:Lap:Dive:A:SurfaceSink:sink:D.Pitch:A_Timeout:A](IMPORTANT): Timed out while trying to reach target sink depth at current depth of 4.725242 m 2026-01-16T02:57:34.058Z,1768532254.058 [sci2_vtyoyo:Lap:Dive:A:SurfaceSink:sink:D.Pitch:A_Timeout:A] Stopped 2026-01-16T02:57:34.059Z,1768532254.059 [sci2_vtyoyo:Lap:Dive:A:SurfaceSink:sink:D.Pitch:A_Timeout](INFO): Completed sci2_vtyoyo:Lap:Dive:A:SurfaceSink:sink:D.Pitch:A_Timeout 2026-01-16T02:57:34.059Z,1768532254.059 [sci2_vtyoyo:Lap:Dive:A:SurfaceSink:sink:D.Pitch] Stopped 2026-01-16T02:57:34.059Z,1768532254.059 [sci2_vtyoyo:Lap:Dive:A:SurfaceSink:sink:E] Running Loop=1 2026-01-16T02:57:34.497Z,1768532254.497 [sci2_vtyoyo:Lap:Dive:A:SurfaceSink:sink:E] Stopped 2026-01-16T02:57:34.497Z,1768532254.497 [sci2_vtyoyo:Lap:Dive:A:SurfaceSink:sink](INFO): Completed sci2_vtyoyo:Lap:Dive:A:SurfaceSink:sink 2026-01-16T02:57:34.497Z,1768532254.497 [sci2_vtyoyo:Lap:Dive:A:SurfaceSink:sink] Stopped 2026-01-16T02:57:34.498Z,1768532254.498 [sci2_vtyoyo:Lap:Dive:A:SurfaceSink:sink](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Lap:Dive:A:SurfaceSink:sink 2026-01-16T02:57:34.498Z,1768532254.498 [sci2_vtyoyo:Lap:Dive:A:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2026-01-16T02:57:34.498Z,1768532254.498 [sci2_vtyoyo:Lap:Dive:A:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2026-01-16T02:57:34.498Z,1768532254.498 [sci2_vtyoyo:Lap:Dive:A:SurfaceSink:sink:B.SetSpeed] Stopped 2026-01-16T02:57:34.498Z,1768532254.498 [sci2_vtyoyo:Lap:Dive:A:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2026-01-16T02:57:34.498Z,1768532254.498 [sci2_vtyoyo:Lap:Dive:A:SurfaceSink](INFO): Completed sci2_vtyoyo:Lap:Dive:A:SurfaceSink 2026-01-16T02:57:34.884Z,1768532254.884 [sci2_vtyoyo:Lap:Dive:A:YoYoDive] Running Loop=1 2026-01-16T02:57:34.884Z,1768532254.884 [sci2_vtyoyo:Lap:Dive:A:YoYoDive](DEBUG): Aggregate::initialize sci2_vtyoyo:Lap:Dive:A:YoYoDive 2026-01-16T02:57:34.885Z,1768532254.885 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive] Running Loop=1 2026-01-16T02:57:34.885Z,1768532254.885 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive](DEBUG): Aggregate::initialize sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive 2026-01-16T02:57:34.885Z,1768532254.885 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:A.Pitch] Running Loop=1 2026-01-16T02:57:34.885Z,1768532254.885 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:A.Pitch](DEBUG): Initialize. 2026-01-16T02:57:34.885Z,1768532254.885 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:B.Buoyancy] Running Loop=1 2026-01-16T02:57:34.885Z,1768532254.885 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:B.Buoyancy](DEBUG): Initialize Buoyancy Component. 2026-01-16T02:57:34.885Z,1768532254.885 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:C.SetSpeed] Running Loop=1 2026-01-16T02:57:34.885Z,1768532254.885 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:C.SetSpeed](DEBUG): Initialize. 2026-01-16T02:57:34.885Z,1768532254.885 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:D.DepthEnvelope] Running Loop=1 2026-01-16T02:57:34.885Z,1768532254.885 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:D.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2026-01-16T02:57:34.886Z,1768532254.886 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:E.AltitudeEnvelope] Running Loop=1 2026-01-16T02:57:34.886Z,1768532254.886 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:E.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2026-01-16T02:57:34.886Z,1768532254.886 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:F.YoYoPARLicor] Running Loop=1 2026-01-16T02:57:34.886Z,1768532254.886 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:F.YoYoPARLicor](DEBUG): Initialize Value-Terminated YoYo. 2026-01-16T02:57:34.890Z,1768532254.890 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:G.YoYo] Running Loop=1 2026-01-16T02:57:34.890Z,1768532254.890 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:G.YoYo](DEBUG): Initialize YoYoComponent. 2026-01-16T02:57:39.760Z,1768532259.760 [sci2_vtyoyo:Lap:Wpt3:A.Waypoint] Stopped 2026-01-16T02:57:39.760Z,1768532259.760 [sci2_vtyoyo:Lap:Wpt3:A.Waypoint](DEBUG): Uninitialize WaypointComponent. 2026-01-16T02:57:39.761Z,1768532259.761 [sci2_vtyoyo:Lap:Wpt3](INFO): Completed sci2_vtyoyo:Lap:Wpt3 2026-01-16T02:57:39.761Z,1768532259.761 [sci2_vtyoyo:Lap:Wpt3] Stopped 2026-01-16T02:57:39.761Z,1768532259.761 [sci2_vtyoyo:Lap:Wpt3](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Lap:Wpt3 2026-01-16T02:57:39.761Z,1768532259.761 [sci2_vtyoyo:Lap:Wpt4] Running Loop=1 2026-01-16T02:57:39.761Z,1768532259.761 [sci2_vtyoyo:Lap:Wpt4](DEBUG): Aggregate::initialize sci2_vtyoyo:Lap:Wpt4 2026-01-16T02:57:39.761Z,1768532259.761 [sci2_vtyoyo:Lap:Wpt4:A.Waypoint] Running Loop=1 2026-01-16T02:57:39.761Z,1768532259.761 [sci2_vtyoyo:Lap:Wpt4:A.Waypoint](DEBUG): Initialize WaypointComponent. 2026-01-16T02:57:40.137Z,1768532260.137 [sci2_vtyoyo:Lap:Wpt4:A.Waypoint] Stopped 2026-01-16T02:57:40.137Z,1768532260.137 [sci2_vtyoyo:Lap:Wpt4:A.Waypoint](DEBUG): Uninitialize WaypointComponent. 2026-01-16T02:57:40.137Z,1768532260.137 [sci2_vtyoyo:Lap:Wpt4](INFO): Completed sci2_vtyoyo:Lap:Wpt4 2026-01-16T02:57:40.137Z,1768532260.137 [sci2_vtyoyo:Lap:Wpt4] Stopped 2026-01-16T02:57:40.137Z,1768532260.137 [sci2_vtyoyo:Lap:Wpt4](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Lap:Wpt4 2026-01-16T02:57:40.137Z,1768532260.137 [sci2_vtyoyo:Lap:Wpt5] Running Loop=1 2026-01-16T02:57:40.137Z,1768532260.137 [sci2_vtyoyo:Lap:Wpt5](DEBUG): Aggregate::initialize sci2_vtyoyo:Lap:Wpt5 2026-01-16T02:57:40.137Z,1768532260.137 [sci2_vtyoyo:Lap:Wpt5:A.Waypoint] Running Loop=1 2026-01-16T02:57:40.137Z,1768532260.137 [sci2_vtyoyo:Lap:Wpt5:A.Waypoint](DEBUG): Initialize WaypointComponent. 2026-01-16T02:57:40.527Z,1768532260.527 [sci2_vtyoyo:Lap:Wpt5:A.Waypoint] Stopped 2026-01-16T02:57:40.527Z,1768532260.527 [sci2_vtyoyo:Lap:Wpt5:A.Waypoint](DEBUG): Uninitialize WaypointComponent. 2026-01-16T02:57:40.527Z,1768532260.527 [sci2_vtyoyo:Lap:Wpt5](INFO): Completed sci2_vtyoyo:Lap:Wpt5 2026-01-16T02:57:40.527Z,1768532260.527 [sci2_vtyoyo:Lap:Wpt5] Stopped 2026-01-16T02:57:40.527Z,1768532260.527 [sci2_vtyoyo:Lap:Wpt5](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Lap:Wpt5 2026-01-16T02:57:40.527Z,1768532260.527 [sci2_vtyoyo:Lap:Wpt6] Running Loop=1 2026-01-16T02:57:40.527Z,1768532260.527 [sci2_vtyoyo:Lap:Wpt6](DEBUG): Aggregate::initialize sci2_vtyoyo:Lap:Wpt6 2026-01-16T02:57:40.527Z,1768532260.527 [sci2_vtyoyo:Lap:Wpt6:A.Waypoint] Running Loop=1 2026-01-16T02:57:40.527Z,1768532260.527 [sci2_vtyoyo:Lap:Wpt6:A.Waypoint](DEBUG): Initialize WaypointComponent. 2026-01-16T02:57:40.944Z,1768532260.944 [sci2_vtyoyo:Lap:Wpt6:A.Waypoint] Stopped 2026-01-16T02:57:40.944Z,1768532260.944 [sci2_vtyoyo:Lap:Wpt6:A.Waypoint](DEBUG): Uninitialize WaypointComponent. 2026-01-16T02:57:40.944Z,1768532260.944 [sci2_vtyoyo:Lap:Wpt6](INFO): Completed sci2_vtyoyo:Lap:Wpt6 2026-01-16T02:57:40.945Z,1768532260.945 [sci2_vtyoyo:Lap:Wpt6] Stopped 2026-01-16T02:57:40.945Z,1768532260.945 [sci2_vtyoyo:Lap:Wpt6](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Lap:Wpt6 2026-01-16T02:57:40.945Z,1768532260.945 [sci2_vtyoyo:Lap:Wpt7] Running Loop=1 2026-01-16T02:57:40.945Z,1768532260.945 [sci2_vtyoyo:Lap:Wpt7](DEBUG): Aggregate::initialize sci2_vtyoyo:Lap:Wpt7 2026-01-16T02:57:40.945Z,1768532260.945 [sci2_vtyoyo:Lap:Wpt7:A.Waypoint] Running Loop=1 2026-01-16T02:57:40.945Z,1768532260.945 [sci2_vtyoyo:Lap:Wpt7:A.Waypoint](DEBUG): Initialize WaypointComponent. 2026-01-16T02:57:41.333Z,1768532261.333 [sci2_vtyoyo:Lap:Wpt7:A.Waypoint] Stopped 2026-01-16T02:57:41.333Z,1768532261.333 [sci2_vtyoyo:Lap:Wpt7:A.Waypoint](DEBUG): Uninitialize WaypointComponent. 2026-01-16T02:57:41.333Z,1768532261.333 [sci2_vtyoyo:Lap:Wpt7](INFO): Completed sci2_vtyoyo:Lap:Wpt7 2026-01-16T02:57:41.333Z,1768532261.333 [sci2_vtyoyo:Lap:Wpt7] Stopped 2026-01-16T02:57:41.333Z,1768532261.333 [sci2_vtyoyo:Lap:Wpt7](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Lap:Wpt7 2026-01-16T02:57:41.355Z,1768532261.355 [sci2_vtyoyo:Lap](INFO): Completed sci2_vtyoyo:Lap 2026-01-16T02:57:41.355Z,1768532261.355 [sci2_vtyoyo:Lap] Stopped 2026-01-16T02:57:41.355Z,1768532261.355 [sci2_vtyoyo:Lap](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Lap 2026-01-16T02:57:41.355Z,1768532261.355 [sci2_vtyoyo:Lap:Dive] Stopped 2026-01-16T02:57:41.355Z,1768532261.355 [sci2_vtyoyo:Lap:Dive](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Lap:Dive 2026-01-16T02:57:41.355Z,1768532261.355 [sci2_vtyoyo:Lap:Dive:A] Stopped 2026-01-16T02:57:41.355Z,1768532261.355 [sci2_vtyoyo:Lap:Dive:A](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Lap:Dive:A 2026-01-16T02:57:41.355Z,1768532261.355 [sci2_vtyoyo:Lap:Dive:A:SurfaceSink] Stopped 2026-01-16T02:57:41.355Z,1768532261.355 [sci2_vtyoyo:Lap:Dive:A:SurfaceSink](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Lap:Dive:A:SurfaceSink 2026-01-16T02:57:41.355Z,1768532261.355 [sci2_vtyoyo:Lap:Dive:A:YoYoDive] Stopped 2026-01-16T02:57:41.355Z,1768532261.355 [sci2_vtyoyo:Lap:Dive:A:YoYoDive](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Lap:Dive:A:YoYoDive 2026-01-16T02:57:41.355Z,1768532261.355 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive] Stopped 2026-01-16T02:57:41.368Z,1768532261.368 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive 2026-01-16T02:57:41.368Z,1768532261.368 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:A.Pitch] Stopped 2026-01-16T02:57:41.368Z,1768532261.368 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:B.Buoyancy] Stopped 2026-01-16T02:57:41.368Z,1768532261.368 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2026-01-16T02:57:41.368Z,1768532261.368 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:C.SetSpeed] Stopped 2026-01-16T02:57:41.368Z,1768532261.368 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:C.SetSpeed](DEBUG): Uninitialize. 2026-01-16T02:57:41.368Z,1768532261.368 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:D.DepthEnvelope] Stopped 2026-01-16T02:57:41.368Z,1768532261.368 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:D.DepthEnvelope](DEBUG): Uninitialize. 2026-01-16T02:57:41.368Z,1768532261.368 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:E.AltitudeEnvelope] Stopped 2026-01-16T02:57:41.368Z,1768532261.368 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:E.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2026-01-16T02:57:41.369Z,1768532261.369 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:F.YoYoPARLicor] Stopped 2026-01-16T02:57:41.369Z,1768532261.369 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:F.YoYoPARLicor](DEBUG): Uninitialize Value-Terminated YoYo. 2026-01-16T02:57:41.370Z,1768532261.370 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:G.YoYo] Stopped 2026-01-16T02:57:41.370Z,1768532261.370 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:G.YoYo](DEBUG): Uninitialize YoYoComponent. 2026-01-16T02:57:41.375Z,1768532261.375 [sci2_vtyoyo](INFO): Completed sci2_vtyoyo 2026-01-16T02:57:41.375Z,1768532261.375 [MissionManager](INFO): sci2_vtyoyo is completed. 2026-01-16T02:57:41.375Z,1768532261.375 [MissionManager](INFO): Uninitializing Mission sci2_vtyoyo 2026-01-16T02:57:41.375Z,1768532261.375 [sci2_vtyoyo] Stopped 2026-01-16T02:57:41.375Z,1768532261.375 [sci2_vtyoyo](DEBUG): Aggregate::uninitialize sci2_vtyoyo 2026-01-16T02:57:41.375Z,1768532261.375 [sci2_vtyoyo:Science] Stopped 2026-01-16T02:57:41.375Z,1768532261.375 [sci2_vtyoyo:Science](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Science 2026-01-16T02:57:41.375Z,1768532261.375 [sci2_vtyoyo:Science:B] Stopped 2026-01-16T02:57:41.376Z,1768532261.376 [sci2_vtyoyo:Science:C] Stopped 2026-01-16T02:57:41.376Z,1768532261.376 [sci2_vtyoyo:Science:E] Stopped 2026-01-16T02:57:41.376Z,1768532261.376 [sci2_vtyoyo:Science:OceanCurrent] Stopped 2026-01-16T02:57:41.376Z,1768532261.376 [sci2_vtyoyo:Science:OceanCurrent](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Science:OceanCurrent 2026-01-16T02:57:41.376Z,1768532261.376 [sci2_vtyoyo:Science:OceanCurrent:A.] Stopped 2026-01-16T02:57:41.376Z,1768532261.376 [sci2_vtyoyo:StandardEnvelopes] Stopped 2026-01-16T02:57:41.376Z,1768532261.376 [sci2_vtyoyo:StandardEnvelopes](DEBUG): Aggregate::uninitialize sci2_vtyoyo:StandardEnvelopes 2026-01-16T02:57:41.376Z,1768532261.376 [sci2_vtyoyo:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2026-01-16T02:57:41.376Z,1768532261.376 [sci2_vtyoyo:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2026-01-16T02:57:41.376Z,1768532261.376 [sci2_vtyoyo:StandardEnvelopes:B.DepthEnvelope] Stopped 2026-01-16T02:57:41.376Z,1768532261.376 [sci2_vtyoyo:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2026-01-16T02:57:41.377Z,1768532261.377 [sci2_vtyoyo:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2026-01-16T02:57:41.377Z,1768532261.377 [sci2_vtyoyo:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2026-01-16T02:57:41.377Z,1768532261.377 [sci2_vtyoyo:BackseatDriver] Stopped 2026-01-16T02:57:41.377Z,1768532261.377 [sci2_vtyoyo:BackseatDriver](DEBUG): Aggregate::uninitialize sci2_vtyoyo:BackseatDriver 2026-01-16T02:57:41.377Z,1768532261.377 [sci2_vtyoyo:BackseatDriver:A.BackseatDriver] Stopped 2026-01-16T02:57:41.378Z,1768532261.378 [sci2_vtyoyo:PowerOnly] Stopped 2026-01-16T02:57:41.378Z,1768532261.378 [sci2_vtyoyo:PowerOnly](DEBUG): Aggregate::uninitialize sci2_vtyoyo:PowerOnly 2026-01-16T02:57:41.378Z,1768532261.378 [sci2_vtyoyo:PowerOnly:E.Wait] Stopped 2026-01-16T02:57:41.378Z,1768532261.378 [sci2_vtyoyo:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2026-01-16T02:57:41.378Z,1768532261.378 [sci2_vtyoyo:manageDiveMode] Stopped 2026-01-16T02:57:41.378Z,1768532261.378 [sci2_vtyoyo:manageDiveMode](DEBUG): Aggregate::uninitialize sci2_vtyoyo:manageDiveMode 2026-01-16T02:57:41.840Z,1768532261.840 [WetLabsBB2FL](INFO): Powering down 2026-01-16T02:57:41.900Z,1768532261.900 [MissionManager](IMPORTANT): Started mission Default 2026-01-16T02:57:41.900Z,1768532261.900 [Default] Running Loop=1 2026-01-16T02:57:41.900Z,1768532261.900 [Default](DEBUG): Aggregate::initialize Default 2026-01-16T02:57:41.901Z,1768532261.901 [Default:B.GoToSurface] Running Loop=1 2026-01-16T02:57:41.901Z,1768532261.901 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2026-01-16T02:57:41.901Z,1768532261.901 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2026-01-16T02:57:41.901Z,1768532261.901 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2026-01-16T02:57:41.901Z,1768532261.901 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2026-01-16T02:57:41.902Z,1768532261.902 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2026-01-16T02:57:41.902Z,1768532261.902 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2026-01-16T02:57:41.902Z,1768532261.902 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2026-01-16T02:57:41.903Z,1768532261.903 [Default:A.Wait] Running Loop=1 2026-01-16T02:57:41.903Z,1768532261.903 [Default:A.Wait](DEBUG): Initialize Wait Component. 2026-01-16T02:57:41.996Z,1768532261.996 [BackseatComponent](INFO): Sent LCM shutdown request. 2026-01-16T02:57:41.996Z,1768532261.996 [BackseatComponent](INFO): Shutdown requested. Waiting (18.00 sec to power down.). 2026-01-16T02:57:42.271Z,1768532262.271 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -13.00 mm (3 active estimators). 2026-01-16T02:57:54.990Z,1768532274.990 [Default:A.Wait](INFO): Done Waiting. 2026-01-16T02:57:54.990Z,1768532274.990 [Default:A.Wait] Stopped 2026-01-16T02:57:54.990Z,1768532274.990 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2026-01-16T02:58:00.152Z,1768532280.152 [BackseatComponent](INFO): Powering down and starting shutdown timer (18.00 sec). 2026-01-16T02:58:00.153Z,1768532280.153 [BackseatComponent](INFO): Unsubscribing from LCM channels. 2026-01-16T02:58:00.154Z,1768532280.154 [LcmPublisher](INFO): Deactivating messaging. 2026-01-16T02:58:03.817Z,1768532283.817 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-16T02:58:14.267Z,1768532294.267 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 0.80 m/s, pitch -20.00 deg, mass-position 30.65 mm. 2026-01-16T02:58:14.268Z,1768532294.268 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 0.80 m/s, pitch 20.00 deg, mass-position 30.65 mm. 2026-01-16T02:58:14.273Z,1768532294.273 [Default:CheckIn] Running Loop=1 2026-01-16T02:58:14.273Z,1768532294.273 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2026-01-16T02:58:14.274Z,1768532294.274 [Default:CheckIn:Read_GPS] Running Loop=1 2026-01-16T02:58:34.960Z,1768532314.960 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-16T03:00:34.041Z,1768532434.041 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2026-01-16T03:00:35.272Z,1768532435.272 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,030032.00,A,3643.02106,N,12211.43247,W,0.700,271.22,160126,,,A*7F 2026-01-16T03:00:35.274Z,1768532435.274 [NAL9602](INFO): GPS fix at 20260116T030032: (36.717018, -122.190541) 2026-01-16T03:00:35.317Z,1768532435.317 [Default:CheckIn:Read_GPS] Stopped 2026-01-16T03:00:35.317Z,1768532435.317 [Default:CheckIn:Read_Iridium] Running Loop=1 2026-01-16T03:00:42.807Z,1768532442.807 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20260116T014500/Courier0015.lzma 2026-01-16T03:00:43.810Z,1768532443.810 [DataOverHttps](INFO): Moved sent file to Logs/20260116T014500/Courier0015.lzma.bak 2026-01-16T03:00:43.810Z,1768532443.810 [DataOverHttps](INFO): SBD MOMSN=26575959 2026-01-16T03:01:00.081Z,1768532460.081 [DataOverHttps](INFO): Sending 521 bytes from file Logs/20260116T014500/Express0016.lzma 2026-01-16T03:01:01.082Z,1768532461.082 [DataOverHttps](INFO): Moved sent file to Logs/20260116T014500/Express0016.lzma.bak 2026-01-16T03:01:01.082Z,1768532461.082 [DataOverHttps](INFO): SBD MOMSN=26575962 2026-01-16T03:01:02.555Z,1768532462.555 [Default:CheckIn:Read_Iridium] Stopped 2026-01-16T03:01:02.555Z,1768532462.555 [Default:CheckIn:C.Wait] Running Loop=1 2026-01-16T03:01:02.555Z,1768532462.555 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2026-01-16T03:01:07.378Z,1768532467.378 [NAL9602](INFO): Not Powering down - fast GPS 2026-01-16T03:06:03.179Z,1768532763.179 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2026-01-16T03:06:03.180Z,1768532763.180 [Default:CheckIn:C.Wait] Stopped 2026-01-16T03:06:03.180Z,1768532763.180 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2026-01-16T03:06:03.181Z,1768532763.181 [Default:CheckIn:D] Running Loop=1 2026-01-16T03:06:03.589Z,1768532763.589 [Default:CheckIn:D] Stopped 2026-01-16T03:06:03.589Z,1768532763.589 [Default:CheckIn:E] Running Loop=1 2026-01-16T03:06:03.993Z,1768532763.993 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 8.361478 min 2026-01-16T03:06:03.993Z,1768532763.993 [Default:CheckIn:E] Stopped 2026-01-16T03:06:03.993Z,1768532763.993 [Default:CheckIn](INFO): Completed Default:CheckIn 2026-01-16T03:06:03.993Z,1768532763.993 [Default:CheckIn] Stopped 2026-01-16T03:06:03.993Z,1768532763.993 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2026-01-16T03:06:03.993Z,1768532763.993 [Default:CheckIn](INFO): Running loop #2 2026-01-16T03:06:03.993Z,1768532763.993 [Default:CheckIn] Running Loop=2 2026-01-16T03:06:03.993Z,1768532763.993 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2026-01-16T03:06:03.993Z,1768532763.993 [Default:CheckIn:Read_GPS] Running Loop=1 2026-01-16T03:06:05.982Z,1768532765.982 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,030603.00,A,3643.04613,N,12211.46874,W,0.311,316.33,160126,,,D*70 2026-01-16T03:06:05.984Z,1768532765.984 [NAL9602](INFO): GPS fix at 20260116T030603: (36.717436, -122.191146) 2026-01-16T03:06:05.994Z,1768532765.994 [Default:CheckIn:Read_GPS] Stopped 2026-01-16T03:06:05.994Z,1768532765.994 [Default:CheckIn:Read_Iridium] Running Loop=1 2026-01-16T03:06:13.015Z,1768532773.015 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20260116T014500/Courier0018.lzma 2026-01-16T03:06:14.018Z,1768532774.018 [DataOverHttps](INFO): Moved sent file to Logs/20260116T014500/Courier0018.lzma.bak 2026-01-16T03:06:14.018Z,1768532774.018 [DataOverHttps](INFO): SBD MOMSN=26575969 2026-01-16T03:06:30.373Z,1768532790.373 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20260116T014500/Express0019.lzma 2026-01-16T03:06:31.374Z,1768532791.374 [DataOverHttps](INFO): Moved sent file to Logs/20260116T014500/Express0019.lzma.bak 2026-01-16T03:06:31.374Z,1768532791.374 [DataOverHttps](INFO): SBD MOMSN=26575972 2026-01-16T03:06:32.699Z,1768532792.699 [Default:CheckIn:Read_Iridium] Stopped 2026-01-16T03:06:32.699Z,1768532792.699 [Default:CheckIn:C.Wait] Running Loop=1 2026-01-16T03:06:32.699Z,1768532792.699 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2026-01-16T03:06:36.766Z,1768532796.766 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2026-01-16T03:06:36.844Z,1768532796.844 [NAL9602](ERROR): received: +CSQ:0 OK248, 2, 0, 0, 0 OK 2026-01-16T03:07:11.485Z,1768532831.485 [NAL9602](INFO): SBD MO Status=1, MOMSN=25248, MT Status=0, MTMSN=0 2026-01-16T03:07:11.485Z,1768532831.485 [NAL9602](INFO): No messages in MT queue 2026-01-16T03:07:42.182Z,1768532862.182 [NAL9602](INFO): Not Powering down - fast GPS 2026-01-16T03:11:33.277Z,1768533093.277 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2026-01-16T03:11:33.277Z,1768533093.277 [Default:CheckIn:C.Wait] Stopped 2026-01-16T03:11:33.277Z,1768533093.277 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2026-01-16T03:11:33.278Z,1768533093.278 [Default:CheckIn:D] Running Loop=1 2026-01-16T03:11:33.680Z,1768533093.680 [Default:CheckIn:D] Stopped 2026-01-16T03:11:33.680Z,1768533093.680 [Default:CheckIn:E] Running Loop=1 2026-01-16T03:11:34.089Z,1768533094.089 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 13.862986 min 2026-01-16T03:11:34.089Z,1768533094.089 [Default:CheckIn:E] Stopped 2026-01-16T03:11:34.089Z,1768533094.089 [Default:CheckIn](INFO): Completed Default:CheckIn 2026-01-16T03:11:34.089Z,1768533094.089 [Default:CheckIn] Stopped 2026-01-16T03:11:34.089Z,1768533094.089 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2026-01-16T03:11:34.089Z,1768533094.089 [Default:CheckIn](INFO): Running loop #3 2026-01-16T03:11:34.089Z,1768533094.089 [Default:CheckIn] Running Loop=3 2026-01-16T03:11:34.089Z,1768533094.089 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2026-01-16T03:11:34.089Z,1768533094.089 [Default:CheckIn:Read_GPS] Running Loop=1 2026-01-16T03:11:36.119Z,1768533096.119 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,031133.00,A,3643.07167,N,12211.50381,W,0.719,321.08,160126,,,D*74 2026-01-16T03:11:36.121Z,1768533096.121 [NAL9602](INFO): GPS fix at 20260116T031133: (36.717861, -122.191730) 2026-01-16T03:11:36.131Z,1768533096.131 [Default:CheckIn:Read_GPS] Stopped 2026-01-16T03:11:36.131Z,1768533096.131 [Default:CheckIn:Read_Iridium] Running Loop=1 2026-01-16T03:11:43.387Z,1768533103.387 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20260116T014500/Courier0021.lzma 2026-01-16T03:11:44.391Z,1768533104.391 [DataOverHttps](INFO): Moved sent file to Logs/20260116T014500/Courier0021.lzma.bak 2026-01-16T03:11:44.391Z,1768533104.391 [DataOverHttps](IMPORTANT): SBD MOMSN=26576009, MTMSN=20260116T031141 2026-01-16T03:11:52.342Z,1768533112.342 [DataOverHttps](INFO): Received command: schedule list 2026-01-16T03:11:52.418Z,1768533112.418 [CommandExec](IMPORTANT): got command schedule list 2026-01-16T03:11:52.419Z,1768533112.419 [CommandExec](IMPORTANT): Scheduled #7: "restart logs", AT TIME: 2026-01-16T14:45:00Z 2026-01-16T03:11:52.420Z,1768533112.420 [CommandExec](IMPORTANT): Scheduled #8 (#1 of 3 with id='3jxzg'): "load Science/profile_station_vt.tl;set profile_station.MissionTimeout 14 h;set profile_station.NeedCommsTime 90 min;set profile_station.Lat 36.71211 degree;set profile_station.Lon -122.187028 degree", AT TIME: 2026-01-16T14:45:00Z 2026-01-16T03:11:52.421Z,1768533112.421 [CommandExec](IMPORTANT): Scheduled #9 (#2 of 3 with id='3jxzg'): "set profile_station.Radius 3000 m;set profile_station.YoYoMinDepth 4 m;set profile_station.YoYoMaxDepth 50 m;set profile_station.Speed .9 m/s;set profile_station.SinkOffSurface 1 bool", AT TIME: 2026-01-16T14:45:00Z 2026-01-16T03:11:52.422Z,1768533112.422 [CommandExec](IMPORTANT): Scheduled #10 (#3 of 3 with id='3jxzg'): "set profile_station.SinkDepthTimeout 2 min;set profile_station.MaxDepth 70 m;set profile_station:BackseatDriver.EnableBackseat 1 bool;run", AT TIME: 2026-01-16T14:45:00Z 2026-01-16T03:12:00.888Z,1768533120.888 [DataOverHttps](INFO): Sending 194 bytes from file Logs/20260116T014500/Express0022.lzma 2026-01-16T03:12:01.890Z,1768533121.890 [DataOverHttps](INFO): Moved sent file to Logs/20260116T014500/Express0022.lzma.bak 2026-01-16T03:12:01.890Z,1768533121.890 [DataOverHttps](INFO): SBD MOMSN=26576013 2026-01-16T03:12:16.540Z,1768533136.540 [NAL9602](INFO): SBD MO Status=1, MOMSN=25249, MT Status=0, MTMSN=0 2026-01-16T03:12:16.540Z,1768533136.540 [NAL9602](INFO): No messages in MT queue 2026-01-16T03:12:18.271Z,1768533138.271 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20260116T014500/Courier0024.lzma 2026-01-16T03:12:19.274Z,1768533139.274 [DataOverHttps](INFO): Moved sent file to Logs/20260116T014500/Courier0024.lzma.bak 2026-01-16T03:12:19.274Z,1768533139.274 [DataOverHttps](INFO): SBD MOMSN=26576016 2026-01-16T03:12:36.698Z,1768533156.698 [DataOverHttps](INFO): Sending 482 bytes from file Logs/20260116T014500/Express0025.lzma 2026-01-16T03:12:37.698Z,1768533157.698 [DataOverHttps](INFO): Moved sent file to Logs/20260116T014500/Express0025.lzma.bak 2026-01-16T03:12:37.699Z,1768533157.699 [DataOverHttps](INFO): SBD MOMSN=26576018 2026-01-16T03:12:39.169Z,1768533159.169 [Default:CheckIn:Read_Iridium] Stopped 2026-01-16T03:12:39.169Z,1768533159.169 [Default:CheckIn:C.Wait] Running Loop=1 2026-01-16T03:12:39.169Z,1768533159.169 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2026-01-16T03:12:47.230Z,1768533167.230 [NAL9602](INFO): Not Powering down - fast GPS 2026-01-16T03:15:19.833Z,1768533319.833 [DataOverHttps](IMPORTANT): SBD MTMSN=20260116T031516 2026-01-16T03:15:27.668Z,1768533327.668 [DataOverHttps](INFO): Received command: restart logs