2024-05-22T23:10:42.403Z,1716419442.403 [OnDock:L.Execute](INFO): Executing command restart logs 2024-05-22T23:10:42.403Z,1716419442.403 [OnDock:L.Execute] Stopped 2024-05-22T23:10:42.403Z,1716419442.403 [OnDock:M] Running Loop=1 2024-05-22T23:10:42.403Z,1716419442.403 [OnDock:M](DEBUG): Aggregate::initialize OnDock:M 2024-05-22T23:10:42.404Z,1716419442.404 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2024-05-22T23:10:42.417Z,1716419442.417 [CommandExec](IMPORTANT): got command restart logs 2024-05-22T23:10:42.785Z,1716419442.785 [OnDock:restartDHCP] Running Loop=1 2024-05-22T23:10:42.785Z,1716419442.785 [OnDock:restartDHCP](DEBUG): Aggregate::initialize OnDock:restartDHCP 2024-05-22T23:10:42.786Z,1716419442.786 [OnDock:restartDHCP:A] Running Loop=1 2024-05-22T23:10:42.786Z,1716419442.786 [OnDock:restartDHCP:A](IMPORTANT): Skipping DHCP restart to test autonetwork 2024-05-22T23:10:42.786Z,1716419442.786 [OnDock:restartDHCP:A] Stopped 2024-05-22T23:10:42.786Z,1716419442.786 [OnDock:restartDHCP](INFO): Completed OnDock:restartDHCP 2024-05-22T23:10:42.786Z,1716419442.786 [OnDock:restartDHCP] Stopped 2024-05-22T23:10:42.786Z,1716419442.786 [OnDock:restartDHCP](DEBUG): Aggregate::uninitialize OnDock:restartDHCP 2024-05-22T23:10:42.787Z,1716419442.787 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2024-05-22T23:10:43.208Z,1716419443.208 [OnDock:M](INFO): Completed OnDock:M 2024-05-22T23:10:43.208Z,1716419443.208 [OnDock:M] Stopped 2024-05-22T23:10:43.208Z,1716419443.208 [OnDock:M](DEBUG): Aggregate::uninitialize OnDock:M 2024-05-22T23:10:43.209Z,1716419443.209 [OnDock:O.Docked] Running Loop=1 2024-05-22T23:10:43.209Z,1716419443.209 [OnDock:O.Docked](DEBUG): Initialize. 2024-05-22T23:10:43.209Z,1716419443.209 [OnDock:O.Docked](DEBUG): Initializing internal variables to default values. 2024-05-22T23:10:43.211Z,1716419443.211 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2024-05-22T23:10:43.224Z,1716419443.224 [DockingStepper](INFO): Changing to mode: 1 2024-05-22T23:10:43.224Z,1716419443.224 [DockingStepper](INFO): Standby mode. 2024-05-22T23:10:43.276Z,1716419443.276 [DockingStepper](INFO): Commanding positive:53718 2024-05-22T23:10:43.594Z,1716419443.594 [OnDock:O.Docked](FAULT): Off dock, depth -0.085978 above surface threshold 2024-05-22T23:10:43.600Z,1716419443.600 [CommandExec](INFO): Found local IP address 10.89.10.2 for eth0 2024-05-22T23:10:43.600Z,1716419443.600 [OnDock:O.Docked](IMPORTANT): No IP address on dock subnet, restarting DHCP 2024-05-22T23:10:43.601Z,1716419443.601 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2024-05-22T23:10:43.625Z,1716419443.625 [DockingStepper](INFO): Deisolating dock charge 2024-05-22T23:10:43.999Z,1716419443.999 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2024-05-22T23:10:44.430Z,1716419444.430 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2024-05-22T23:10:44.803Z,1716419444.803 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2024-05-22T23:10:45.234Z,1716419445.234 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2024-05-22T23:10:45.639Z,1716419445.639 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2024-05-22T23:10:46.052Z,1716419446.052 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2024-05-22T23:10:46.487Z,1716419446.487 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2024-05-22T23:10:46.841Z,1716419446.841 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2024-05-22T23:10:47.291Z,1716419447.291 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2024-05-22T23:10:47.664Z,1716419447.664 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2024-05-22T23:10:47.706Z,1716419447.706 [DockingStepper](ERROR): only read 0 of 4 ADC values. Response is: ÿ/0O 2024-05-22T23:10:47.706Z,1716419447.706 [DockingStepper](FAULT): Command Overflow Error 2024-05-22T23:10:47.706Z,1716419447.706 [DockingStepper](FAULT): Docking arm not reporting position 2024-05-22T23:10:47.706Z,1716419447.706 [DockingStepper] Communications Fault, FailCount= 1 2024-05-22T23:10:47.706Z,1716419447.706 [DockingStepper](ERROR): Communications Fault 2024-05-22T23:10:47.715Z,1716419447.715 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-22T23:10:48.035Z,1716419448.035 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2024-05-22T23:10:48.085Z,1716419448.085 [DockingStepper](INFO): Stop 2024-05-22T23:10:48.085Z,1716419448.085 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-22T23:10:48.085Z,1716419448.085 [DockingStepper](INFO): Powering down 2024-05-22T23:10:48.470Z,1716419448.470 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2024-05-22T23:10:48.505Z,1716419448.505 [DockingStepper](INFO): Stopping 2024-05-22T23:10:48.506Z,1716419448.506 [DockingStepper](FAULT): LCB fault: Current Limiter Activated. 2024-05-22T23:10:48.506Z,1716419448.506 [DockingStepper] Hardware Fault, FailCount= 1 2024-05-22T23:10:48.506Z,1716419448.506 [DockingStepper](ERROR): Hardware Fault 2024-05-22T23:10:48.852Z,1716419448.852 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2024-05-22T23:10:48.885Z,1716419448.885 [DockingStepper](INFO): Stopped 2024-05-22T23:10:48.889Z,1716419448.889 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-22T23:10:48.889Z,1716419448.889 [DockingStepper] No Fault, FailCount= 1 2024-05-22T23:10:49.298Z,1716419449.298 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2024-05-22T23:10:49.345Z,1716419449.345 [DockingStepper](INFO): Start 2024-05-22T23:10:49.346Z,1716419449.346 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-22T23:10:49.346Z,1716419449.346 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-22T23:10:49.651Z,1716419449.651 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2024-05-22T23:10:49.697Z,1716419449.697 [DockingStepper](INFO): Starting 2024-05-22T23:10:50.055Z,1716419450.055 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2024-05-22T23:10:50.101Z,1716419450.101 [DockingStepper](INFO): Starting 2024-05-22T23:10:50.526Z,1716419450.526 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2024-05-22T23:10:50.573Z,1716419450.573 [DockingStepper](INFO): Starting 2024-05-22T23:10:50.620Z,1716419450.620 [DockingStepper](INFO): Init string: /1z16367aE13561N3m50 2024-05-22T23:10:51.018Z,1716419451.018 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2024-05-22T23:10:51.053Z,1716419451.053 [DockingStepper](INFO): Starting 2024-05-22T23:10:51.339Z,1716419451.339 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2024-05-22T23:10:51.387Z,1716419451.387 [DockingStepper](INFO): Starting 2024-05-22T23:10:51.685Z,1716419451.685 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2024-05-22T23:10:51.721Z,1716419451.721 [DockingStepper](INFO): Starting 2024-05-22T23:10:52.094Z,1716419452.094 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2024-05-22T23:10:52.129Z,1716419452.129 [DockingStepper](INFO): Starting 2024-05-22T23:10:52.152Z,1716419452.152 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-22T23:10:52.514Z,1716419452.514 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2024-05-22T23:10:52.548Z,1716419452.548 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240522T224328/Courier0012.lzma 2024-05-22T23:10:52.551Z,1716419452.551 [DockingStepper](INFO): Starting 2024-05-22T23:10:52.887Z,1716419452.887 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2024-05-22T23:10:52.925Z,1716419452.925 [DockingStepper](INFO): Deisolating dock charge 2024-05-22T23:10:53.023Z,1716419453.023 [CommandExec](IMPORTANT): got command ! /mnt/mmc/LRAUV/Tools/dhcp.sh 2024-05-22T23:10:53.395Z,1716419453.395 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2024-05-22T23:10:53.555Z,1716419453.555 [DataOverHttps](INFO): Moved sent file to Logs/20240522T224328/Courier0012.lzma.bak 2024-05-22T23:10:53.555Z,1716419453.555 [DataOverHttps](INFO): SBD MOMSN=21073292 2024-05-22T23:10:53.718Z,1716419453.718 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2024-05-22T23:10:53.856Z,1716419453.856 [CommandExec](IMPORTANT): /mnt/mmc/started/2024-05-22UTC231053_sh 2024-05-22T23:10:54.415Z,1716419454.415 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2024-05-22T23:10:54.771Z,1716419454.771 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2024-05-22T23:10:55.119Z,1716419455.119 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2024-05-22T23:10:55.554Z,1716419455.554 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2024-05-22T23:10:55.889Z,1716419455.889 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2024-05-22T23:10:56.340Z,1716419456.340 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2024-05-22T23:10:56.759Z,1716419456.759 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2024-05-22T23:10:57.091Z,1716419457.091 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2024-05-22T23:10:57.517Z,1716419457.517 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2024-05-22T23:10:57.885Z,1716419457.885 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2024-05-22T23:10:58.305Z,1716419458.305 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2024-05-22T23:10:58.716Z,1716419458.716 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2024-05-22T23:10:59.111Z,1716419459.111 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2024-05-22T23:10:59.515Z,1716419459.515 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2024-05-22T23:10:59.913Z,1716419459.913 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2024-05-22T23:11:00.354Z,1716419460.354 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2024-05-22T23:11:00.740Z,1716419460.740 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2024-05-22T23:11:01.119Z,1716419461.119 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2024-05-22T23:11:01.354Z,1716419461.354 [CommandExec](IMPORTANT): got command stop 2024-05-22T23:11:01.354Z,1716419461.354 [CommandExec](IMPORTANT): Scheduling is paused 2024-05-22T23:11:01.355Z,1716419461.355 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop 2024-05-22T23:11:01.568Z,1716419461.568 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2024-05-22T23:11:01.569Z,1716419461.569 [MissionManager](INFO): MissionManager is completed. 2024-05-22T23:11:01.569Z,1716419461.569 [MissionManager](INFO): Uninitializing Mission OnDock 2024-05-22T23:11:01.569Z,1716419461.569 [OnDock] Stopped 2024-05-22T23:11:01.569Z,1716419461.569 [OnDock](DEBUG): Aggregate::uninitialize OnDock 2024-05-22T23:11:01.569Z,1716419461.569 [OnDock:StandardEnvelopes] Stopped 2024-05-22T23:11:01.569Z,1716419461.569 [OnDock:StandardEnvelopes](DEBUG): Aggregate::uninitialize OnDock:StandardEnvelopes 2024-05-22T23:11:01.569Z,1716419461.569 [OnDock:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2024-05-22T23:11:01.569Z,1716419461.569 [OnDock:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2024-05-22T23:11:01.569Z,1716419461.569 [OnDock:StandardEnvelopes:B.DepthEnvelope] Stopped 2024-05-22T23:11:01.569Z,1716419461.569 [OnDock:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2024-05-22T23:11:01.569Z,1716419461.569 [OnDock:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2024-05-22T23:11:01.570Z,1716419461.570 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2024-05-22T23:11:01.570Z,1716419461.570 [OnDock:BackseatDriver] Stopped 2024-05-22T23:11:01.570Z,1716419461.570 [OnDock:BackseatDriver](DEBUG): Aggregate::uninitialize OnDock:BackseatDriver 2024-05-22T23:11:01.570Z,1716419461.570 [OnDock:BackseatDriver:A.BackseatDriver] Stopped 2024-05-22T23:11:01.570Z,1716419461.570 [OnDock:PowerOnly] Stopped 2024-05-22T23:11:01.570Z,1716419461.570 [OnDock:PowerOnly](DEBUG): Aggregate::uninitialize OnDock:PowerOnly 2024-05-22T23:11:01.570Z,1716419461.570 [OnDock:PowerOnly:E.Wait] Stopped 2024-05-22T23:11:01.570Z,1716419461.570 [OnDock:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2024-05-22T23:11:01.570Z,1716419461.570 [OnDock:H] Stopped 2024-05-22T23:11:01.570Z,1716419461.570 [OnDock:I.] Stopped 2024-05-22T23:11:01.570Z,1716419461.570 [OnDock:I.](DEBUG): Uninitializing TrackAcousticContact. 2024-05-22T23:11:01.571Z,1716419461.571 [OnDock:PeriodicCheck] Stopped 2024-05-22T23:11:01.571Z,1716419461.571 [OnDock:PeriodicCheck](DEBUG): Aggregate::uninitialize OnDock:PeriodicCheck 2024-05-22T23:11:01.571Z,1716419461.571 [OnDock:PeriodicCheck:CheckIP] Stopped 2024-05-22T23:11:01.571Z,1716419461.571 [OnDock:PeriodicCheck:CheckIP](DEBUG): Aggregate::uninitialize OnDock:PeriodicCheck:CheckIP 2024-05-22T23:11:01.571Z,1716419461.571 [OnDock:PeriodicCheck:CheckIP:B.Wait] Stopped 2024-05-22T23:11:01.571Z,1716419461.571 [OnDock:PeriodicCheck:CheckIP:B.Wait](DEBUG): Uninitialize Wait Component. 2024-05-22T23:11:01.571Z,1716419461.571 [OnDock:O.Docked] Stopped 2024-05-22T23:11:01.911Z,1716419461.911 [MissionManager](IMPORTANT): Started mission DefaultTankUndock 2024-05-22T23:11:01.916Z,1716419461.916 [DefaultTankUndock] Running Loop=1 2024-05-22T23:11:01.916Z,1716419461.916 [DefaultTankUndock](DEBUG): Aggregate::initialize DefaultTankUndock 2024-05-22T23:11:01.916Z,1716419461.916 [DefaultTankUndock:B.GoToSurface] Running Loop=1 2024-05-22T23:11:01.916Z,1716419461.916 [DefaultTankUndock:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-05-22T23:11:01.916Z,1716419461.916 [DefaultTankUndock:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-05-22T23:11:01.917Z,1716419461.917 [DefaultTankUndock:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-05-22T23:11:01.917Z,1716419461.917 [DefaultTankUndock:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-05-22T23:11:01.917Z,1716419461.917 [DefaultTankUndock:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-05-22T23:11:01.918Z,1716419461.918 [DefaultTankUndock:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-05-22T23:11:01.918Z,1716419461.918 [DefaultTankUndock:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-05-22T23:11:01.918Z,1716419461.918 [DefaultTankUndock:A.Wait] Running Loop=1 2024-05-22T23:11:01.918Z,1716419461.918 [DefaultTankUndock:A.Wait](DEBUG): Initialize Wait Component. 2024-05-22T23:11:04.162Z,1716419464.162 [CommandExec](IMPORTANT): got command schedule resume 2024-05-22T23:11:04.163Z,1716419464.163 [CommandExec](IMPORTANT): Scheduling is resumed 2024-05-22T23:11:14.169Z,1716419474.169 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20240522T230539/Courier0000.lzma 2024-05-22T23:11:15.170Z,1716419475.170 [DataOverHttps](INFO): Moved sent file to Logs/20240522T230539/Courier0000.lzma.bak 2024-05-22T23:11:15.170Z,1716419475.170 [DataOverHttps](INFO): SBD MOMSN=21073299 2024-05-22T23:11:15.238Z,1716419475.238 [DefaultTankUndock:A.Wait](INFO): Done Waiting. 2024-05-22T23:11:15.239Z,1716419475.239 [DefaultTankUndock:A.Wait] Stopped 2024-05-22T23:11:15.239Z,1716419475.239 [DefaultTankUndock:A.Wait](DEBUG): Uninitialize Wait Component. 2024-05-22T23:11:15.784Z,1716419475.784 [DefaultTankUndock:C.Execute] Running Loop=1 2024-05-22T23:11:16.123Z,1716419476.123 [DefaultTankUndock:C.Execute](INFO): Executing command dock arm 2024-05-22T23:11:16.123Z,1716419476.123 [DefaultTankUndock:C.Execute] Stopped 2024-05-22T23:11:16.123Z,1716419476.123 [DefaultTankUndock:CheckIn] Running Loop=1 2024-05-22T23:11:16.125Z,1716419476.125 [CommandExec](IMPORTANT): got command docking arm 2024-05-22T23:11:16.125Z,1716419476.125 [CommandExec](IMPORTANT): Switching docking moudle to ARM. 2024-05-22T23:11:16.123Z,1716419476.123 [DefaultTankUndock:CheckIn](DEBUG): Aggregate::initialize DefaultTankUndock:CheckIn 2024-05-22T23:11:16.126Z,1716419476.126 [DefaultTankUndock:CheckIn:Read_GPS] Running Loop=1 2024-05-22T23:11:16.169Z,1716419476.169 [DockingStepper](INFO): Changing to mode: 2 2024-05-22T23:11:16.169Z,1716419476.169 [DockingStepper](INFO): Armed mode. 2024-05-22T23:11:16.174Z,1716419476.174 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T23:11:16.224Z,1716419476.224 [DockingStepper](INFO): Commanding negative:54599 2024-05-22T23:11:25.676Z,1716419485.676 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T23:11:33.559Z,1716419493.559 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-05-22T23:11:34.744Z,1716419494.744 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T23:11:34.886Z,1716419494.886 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,231135.00,A,3648.16522,N,12147.27865,W,0.428,150.47,220524,,,A*75 2024-05-22T23:11:34.889Z,1716419494.889 [NAL9602](INFO): GPS fix at 20240522T231135: (36.802754, -121.787977) 2024-05-22T23:11:34.919Z,1716419494.919 [DefaultTankUndock:CheckIn:Read_GPS] Stopped 2024-05-22T23:11:34.920Z,1716419494.920 [DefaultTankUndock:CheckIn:Read_Iridium] Running Loop=1 2024-05-22T23:11:35.271Z,1716419495.271 [DefaultTankUndock:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-05-22T23:11:44.575Z,1716419504.575 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-22T23:11:46.543Z,1716419506.543 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240522T230539/Courier0004.lzma 2024-05-22T23:11:47.546Z,1716419507.546 [DataOverHttps](INFO): Moved sent file to Logs/20240522T230539/Courier0004.lzma.bak 2024-05-22T23:11:47.547Z,1716419507.547 [DataOverHttps](INFO): SBD MOMSN=21073302 2024-05-22T23:11:51.443Z,1716419511.443 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-22T23:12:03.967Z,1716419523.967 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-22T23:12:07.609Z,1716419527.609 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-22T23:12:09.932Z,1716419529.932 [DataOverHttps](INFO): Sending 266 bytes from file Logs/20240522T231042/Courier0000.lzma 2024-05-22T23:12:10.934Z,1716419530.934 [DataOverHttps](INFO): Moved sent file to Logs/20240522T231042/Courier0000.lzma.bak 2024-05-22T23:12:10.934Z,1716419530.934 [DataOverHttps](INFO): SBD MOMSN=21073304 2024-05-22T23:12:16.894Z,1716419536.894 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-22T23:12:30.072Z,1716419550.072 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-22T23:12:35.716Z,1716419555.716 [DataOverHttps](INFO): Sending 255 bytes from file Logs/20240522T224328/Express0013.lzma 2024-05-22T23:12:36.718Z,1716419556.718 [DataOverHttps](INFO): Moved sent file to Logs/20240522T224328/Express0013.lzma.bak 2024-05-22T23:12:36.719Z,1716419556.719 [DataOverHttps](INFO): SBD MOMSN=21073308 2024-05-22T23:12:42.588Z,1716419562.588 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-22T23:12:55.542Z,1716419575.542 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-22T23:12:59.121Z,1716419579.121 [CommandExec](IMPORTANT): got command strobe off 2024-05-22T23:12:59.121Z,1716419579.121 [CommandExec](IMPORTANT): Deactivating strobe 2024-05-22T23:12:59.259Z,1716419579.259 [DataOverHttps](INFO): Sending 1367 bytes from file Logs/20240522T230539/Express0001.lzma 2024-05-22T23:13:00.258Z,1716419580.258 [DataOverHttps](INFO): Moved sent file to Logs/20240522T230539/Express0001.lzma.bak 2024-05-22T23:13:00.258Z,1716419580.258 [DataOverHttps](INFO): SBD MOMSN=21073326 2024-05-22T23:13:08.455Z,1716419588.455 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-22T23:13:20.848Z,1716419600.848 [DataOverHttps](INFO): Sending 98 bytes from file Logs/20240522T230539/Express0002.lzma 2024-05-22T23:13:21.850Z,1716419601.850 [DataOverHttps](INFO): Moved sent file to Logs/20240522T230539/Express0002.lzma.bak 2024-05-22T23:13:21.850Z,1716419601.850 [DataOverHttps](INFO): SBD MOMSN=21073369 2024-05-22T23:13:22.508Z,1716419602.508 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-22T23:13:27.500Z,1716419607.500 [DockingStepper](ERROR): only read 0 of 4 ADC values. Response is: ÿ/0@ 2024-05-22T23:13:27.500Z,1716419607.500 [DockingStepper](FAULT): Docking arm not reporting position 2024-05-22T23:13:27.500Z,1716419607.500 [DockingStepper] Communications Fault, FailCount= 1 2024-05-22T23:13:27.500Z,1716419607.500 [DockingStepper](ERROR): Communications Fault 2024-05-22T23:13:27.508Z,1716419607.508 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-22T23:13:27.880Z,1716419607.880 [DockingStepper](INFO): Stop 2024-05-22T23:13:27.880Z,1716419607.880 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-22T23:13:27.880Z,1716419607.880 [DockingStepper](INFO): Powering down 2024-05-22T23:13:28.284Z,1716419608.284 [DockingStepper](INFO): Stopping 2024-05-22T23:13:28.717Z,1716419608.717 [DockingStepper](INFO): Stopped 2024-05-22T23:13:28.721Z,1716419608.721 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-22T23:13:28.721Z,1716419608.721 [DockingStepper] No Fault, FailCount= 1 2024-05-22T23:13:29.077Z,1716419609.077 [DockingStepper](INFO): Start 2024-05-22T23:13:29.077Z,1716419609.077 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-22T23:13:29.078Z,1716419609.078 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-22T23:13:29.515Z,1716419609.515 [DockingStepper](INFO): Starting 2024-05-22T23:13:29.917Z,1716419609.917 [DockingStepper](INFO): Starting 2024-05-22T23:13:30.278Z,1716419610.278 [DockingStepper](INFO): Starting 2024-05-22T23:13:30.328Z,1716419610.328 [DockingStepper](INFO): Init string: /1z7599aE13561N3m50 2024-05-22T23:13:30.731Z,1716419610.731 [DockingStepper](INFO): Starting 2024-05-22T23:13:31.105Z,1716419611.105 [DockingStepper](INFO): Starting 2024-05-22T23:13:31.553Z,1716419611.553 [DockingStepper](INFO): Starting 2024-05-22T23:13:31.936Z,1716419611.936 [DockingStepper](INFO): Starting 2024-05-22T23:13:31.965Z,1716419611.965 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-22T23:13:32.290Z,1716419612.290 [DockingStepper](INFO): Starting 2024-05-22T23:13:35.921Z,1716419615.921 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-22T23:13:42.492Z,1716419622.492 [DataOverHttps](INFO): Sending 480 bytes from file Logs/20240522T230539/Express0005.lzma 2024-05-22T23:13:43.494Z,1716419623.494 [DataOverHttps](INFO): Moved sent file to Logs/20240522T230539/Express0005.lzma.bak 2024-05-22T23:13:43.494Z,1716419623.494 [DataOverHttps](INFO): SBD MOMSN=21073373 2024-05-22T23:13:48.423Z,1716419628.423 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-22T23:14:01.004Z,1716419641.004 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-22T23:14:07.857Z,1716419647.857 [DataOverHttps](INFO): Sending 909 bytes from file Logs/20240522T231042/Express0001.lzma 2024-05-22T23:14:08.858Z,1716419648.858 [DataOverHttps](INFO): Moved sent file to Logs/20240522T231042/Express0001.lzma.bak 2024-05-22T23:14:08.858Z,1716419648.858 [DataOverHttps](INFO): SBD MOMSN=21073378 2024-05-22T23:14:16.581Z,1716419656.581 [DefaultTankUndock:CheckIn:Read_Iridium] Stopped 2024-05-22T23:14:16.581Z,1716419656.581 [DefaultTankUndock:CheckIn:C.Wait] Running Loop=1 2024-05-22T23:14:16.581Z,1716419656.581 [DefaultTankUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-22T23:14:27.197Z,1716419667.197 [DataOverHttps](IMPORTANT): SBD MTMSN=20240522T231427 2024-05-22T23:14:35.447Z,1716419675.447 [DataOverHttps](INFO): Received command: dock slide 2024-05-22T23:14:35.473Z,1716419675.473 [CommandExec](IMPORTANT): got command docking slide 2024-05-22T23:14:35.473Z,1716419675.473 [CommandExec](IMPORTANT): Switching docking module to SLIDE. 2024-05-22T23:14:35.558Z,1716419675.558 [DockingStepper](INFO): Changing to mode: 4 2024-05-22T23:14:35.558Z,1716419675.558 [DockingStepper](INFO): Slide mode. 2024-05-22T23:14:35.561Z,1716419675.561 [DockingStepper](ERROR): only read 0 of 4 ADC values. Response is: ÿ/0@ 2024-05-22T23:14:35.562Z,1716419675.562 [DockingStepper](FAULT): Docking arm not reporting position 2024-05-22T23:14:35.562Z,1716419675.562 [DockingStepper] Communications Fault, FailCount= 1 2024-05-22T23:14:35.562Z,1716419675.562 [DockingStepper](ERROR): Communications Fault 2024-05-22T23:14:35.567Z,1716419675.567 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-22T23:14:35.798Z,1716419675.798 [DockingStepper](INFO): Stop 2024-05-22T23:14:35.798Z,1716419675.798 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-22T23:14:35.798Z,1716419675.798 [DockingStepper](INFO): Powering down 2024-05-22T23:14:36.340Z,1716419676.340 [DockingStepper](INFO): Stopping 2024-05-22T23:14:36.833Z,1716419676.833 [DockingStepper](INFO): Stopped 2024-05-22T23:14:36.850Z,1716419676.850 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-22T23:14:36.850Z,1716419676.850 [DockingStepper] No Fault, FailCount= 1 2024-05-22T23:14:37.108Z,1716419677.108 [DockingStepper](INFO): Start 2024-05-22T23:14:37.108Z,1716419677.108 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-22T23:14:37.109Z,1716419677.109 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-22T23:14:37.445Z,1716419677.445 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T23:14:37.534Z,1716419677.534 [DockingStepper](INFO): Starting 2024-05-22T23:14:37.883Z,1716419677.883 [DockingStepper](INFO): Starting 2024-05-22T23:14:38.204Z,1716419678.204 [DockingStepper](INFO): Starting 2024-05-22T23:14:38.253Z,1716419678.253 [DockingStepper](INFO): Init string: /1z7592aE13561N3m50 2024-05-22T23:14:38.635Z,1716419678.635 [DockingStepper](INFO): Starting 2024-05-22T23:14:39.029Z,1716419679.029 [DockingStepper](INFO): Starting 2024-05-22T23:14:39.412Z,1716419679.412 [DockingStepper](INFO): Starting 2024-05-22T23:14:39.855Z,1716419679.855 [DockingStepper](INFO): Starting 2024-05-22T23:14:39.900Z,1716419679.900 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-22T23:14:40.254Z,1716419680.254 [DockingStepper](INFO): Starting 2024-05-22T23:14:40.681Z,1716419680.681 [DockingStepper](INFO): Changing to mode: 4 2024-05-22T23:14:40.682Z,1716419680.682 [DockingStepper](INFO): Slide mode. 2024-05-22T23:14:40.732Z,1716419680.732 [DockingStepper](INFO): Commanding positive:27096 2024-05-22T23:14:46.652Z,1716419686.652 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T23:14:55.952Z,1716419695.952 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T23:15:05.612Z,1716419705.612 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T23:15:14.841Z,1716419714.841 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T23:15:24.173Z,1716419724.173 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T23:15:33.309Z,1716419733.309 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T23:15:42.649Z,1716419742.649 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T23:15:50.750Z,1716419750.750 [DataOverHttps](IMPORTANT): SBD MTMSN=20240522T231550 2024-05-22T23:15:59.108Z,1716419759.108 [DataOverHttps](INFO): Received command: dock standby 2024-05-22T23:15:59.113Z,1716419759.113 [CommandExec](IMPORTANT): got command docking standby 2024-05-22T23:15:59.113Z,1716419759.113 [CommandExec](IMPORTANT): Switching docking moudle to STANDBY. 2024-05-22T23:15:59.429Z,1716419759.429 [DockingStepper](INFO): Changing to mode: 1 2024-05-22T23:15:59.429Z,1716419759.429 [DockingStepper](INFO): Standby mode. 2024-05-22T23:15:59.473Z,1716419759.473 [DockingStepper](INFO): Commanding positive:27896 2024-05-22T23:16:01.112Z,1716419761.112 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T23:16:02.428Z,1716419762.428 [DockingStepper](INFO): Commanding positive:6926 2024-05-22T23:16:03.589Z,1716419763.589 [DockingStepper](INFO): Commanding positive:6230 2024-05-22T23:16:03.600Z,1716419763.600 [DockingStepper](FAULT): Invalid EZ Servo response:"\r\n" 2024-05-22T23:16:03.600Z,1716419763.600 [DockingStepper] Communications Fault, FailCount= 1 2024-05-22T23:16:03.600Z,1716419763.600 [DockingStepper](ERROR): Communications Fault 2024-05-22T23:16:03.666Z,1716419763.666 [DockingStepper](FAULT): Could not parse cable state. Received:ÿ/0@ 2024-05-22T23:16:03.695Z,1716419763.695 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-22T23:16:04.005Z,1716419764.005 [DockingStepper](INFO): Stop 2024-05-22T23:16:04.005Z,1716419764.005 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-22T23:16:04.005Z,1716419764.005 [DockingStepper](INFO): Powering down 2024-05-22T23:16:04.299Z,1716419764.299 [DockingStepper](INFO): Stopping 2024-05-22T23:16:04.803Z,1716419764.803 [DockingStepper](INFO): Stopped 2024-05-22T23:16:04.823Z,1716419764.823 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-22T23:16:04.872Z,1716419764.872 [DockingStepper] No Fault, FailCount= 1 2024-05-22T23:16:05.221Z,1716419765.221 [DockingStepper](INFO): Start 2024-05-22T23:16:05.221Z,1716419765.221 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-22T23:16:05.222Z,1716419765.222 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-22T23:16:05.546Z,1716419765.546 [DockingStepper](INFO): Starting 2024-05-22T23:16:06.039Z,1716419766.039 [DockingStepper](INFO): Starting 2024-05-22T23:16:06.378Z,1716419766.378 [DockingStepper](INFO): Starting 2024-05-22T23:16:06.427Z,1716419766.427 [DockingStepper](INFO): Init string: /1z15662aE13561N3m50 2024-05-22T23:16:06.825Z,1716419766.825 [DockingStepper](INFO): Starting 2024-05-22T23:16:07.253Z,1716419767.253 [DockingStepper](INFO): Starting 2024-05-22T23:16:07.569Z,1716419767.569 [DockingStepper](INFO): Starting 2024-05-22T23:16:08.122Z,1716419768.122 [DockingStepper](INFO): Starting 2024-05-22T23:16:08.160Z,1716419768.160 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-22T23:16:08.427Z,1716419768.427 [DockingStepper](INFO): Starting 2024-05-22T23:16:08.912Z,1716419768.912 [DockingStepper](INFO): Commanding positive:5153 2024-05-22T23:16:09.695Z,1716419769.695 [DockingStepper](INFO): Commanding positive:4168 2024-05-22T23:16:10.472Z,1716419770.472 [DockingStepper](INFO): Commanding positive:3971 2024-05-22T23:16:10.976Z,1716419770.976 [DockingStepper](INFO): Commanding positive:3712 2024-05-22T23:16:11.661Z,1716419771.661 [DockingStepper](INFO): Commanding positive:3669 2024-05-22T23:16:12.124Z,1716419772.124 [DockingStepper](INFO): Commanding positive:3478 2024-05-22T23:16:12.863Z,1716419772.863 [DockingStepper](INFO): Commanding positive:3318 2024-05-22T23:16:12.885Z,1716419772.885 [DockingStepper](FAULT): Invalid EZ Servo response:"\r\n" 2024-05-22T23:16:12.885Z,1716419772.885 [DockingStepper] Communications Fault, FailCount= 2 2024-05-22T23:16:12.885Z,1716419772.885 [DockingStepper](ERROR): Communications Fault 2024-05-22T23:16:12.994Z,1716419772.994 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-22T23:16:13.270Z,1716419773.270 [DataOverHttps](IMPORTANT): SBD MTMSN=20240522T231613 2024-05-22T23:16:13.405Z,1716419773.405 [DockingStepper](INFO): Stop 2024-05-22T23:16:13.405Z,1716419773.405 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-22T23:16:13.405Z,1716419773.405 [DockingStepper](INFO): Powering down 2024-05-22T23:16:13.627Z,1716419773.627 [DockingStepper](INFO): Stopping 2024-05-22T23:16:14.243Z,1716419774.243 [DockingStepper](INFO): Stopped 2024-05-22T23:16:14.260Z,1716419774.260 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-22T23:16:14.260Z,1716419774.260 [DockingStepper] No Fault, FailCount= 2 2024-05-22T23:16:14.492Z,1716419774.492 [DockingStepper](INFO): Start 2024-05-22T23:16:14.492Z,1716419774.492 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-22T23:16:14.493Z,1716419774.493 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-22T23:16:14.881Z,1716419774.881 [DockingStepper](INFO): Starting 2024-05-22T23:16:15.306Z,1716419775.306 [DockingStepper](INFO): Starting 2024-05-22T23:16:15.648Z,1716419775.648 [DockingStepper](INFO): Starting 2024-05-22T23:16:15.712Z,1716419775.712 [DockingStepper](INFO): Init string: /1z15967aE13561N3m50 2024-05-22T23:16:16.069Z,1716419776.069 [DockingStepper](INFO): Starting 2024-05-22T23:16:16.509Z,1716419776.509 [DockingStepper](INFO): Starting 2024-05-22T23:16:16.872Z,1716419776.872 [DockingStepper](INFO): Starting 2024-05-22T23:16:17.314Z,1716419777.314 [DockingStepper](INFO): Starting 2024-05-22T23:16:17.355Z,1716419777.355 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-22T23:16:17.682Z,1716419777.682 [DockingStepper](INFO): Starting 2024-05-22T23:16:18.160Z,1716419778.160 [DockingStepper](INFO): Commanding positive:3275 2024-05-22T23:16:18.945Z,1716419778.945 [DockingStepper](INFO): Commanding positive:3084 2024-05-22T23:16:20.548Z,1716419780.548 [DockingStepper](INFO): Commanding positive:3078 2024-05-22T23:16:21.300Z,1716419781.300 [DataOverHttps](INFO): Received command: load Maintenance/tank_ballast_and_trim.tl;set ballast_and_trim.MissionTimeout 8 min;set ballast_and_trim.Depth1 1.25 m;set ballast_and_trim.DepthDeadband 0.25 m;set ballast_and_trim.SettleTime 20 min;run 2024-05-22T23:16:21.421Z,1716419781.421 [CommandExec](IMPORTANT): got command load ./Missions/Maintenance/tank_ballast_and_trim.tl 2024-05-22T23:16:21.421Z,1716419781.421 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/tank_ballast_and_trim.tl 2024-05-22T23:16:21.421Z,1716419781.421 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Maintenance/tank_ballast_and_trim.tl 2024-05-22T23:16:21.424Z,1716419781.424 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Maintenance/tank_ballast_and_trim.tl 2024-05-22T23:16:22.302Z,1716419782.302 [DataOverHttps](IMPORTANT): SBD MTMSN=20240522T231622 2024-05-22T23:16:23.993Z,1716419783.993 [MissionManager](DEBUG): TethyslAPI: ./Missions/Maintenance/tank_ballast_and_trim.tl translated into: 120 7 NaN 0.1 2.5 20 10 45 10 0.25 15 99.9 5 2024-05-22T23:16:23.999Z,1716419783.999 [MissionManager](INFO): DefineArg ballast_and_trim.MissionTimeout = 120.000000 min 2024-05-22T23:16:24.002Z,1716419784.002 [MissionManager](INFO): DefineArg ballast_and_trim.Depth1 = 7.000000 m 2024-05-22T23:16:24.008Z,1716419784.008 [MissionManager](INFO): DefineArg ballast_and_trim.Depth2 = nan m 2024-05-22T23:16:24.040Z,1716419784.040 [MissionManager](INFO): DefineArg ballast_and_trim.DepthDeadband = 0.100000 m 2024-05-22T23:16:24.075Z,1716419784.075 [MissionManager](INFO): DefineArg ballast_and_trim.SurfaceThreshold = 2.500000 m 2024-05-22T23:16:24.098Z,1716419784.098 [MissionManager](INFO): DefineArg ballast_and_trim.TargetDepthTimeout = 20.000000 min 2024-05-22T23:16:24.109Z,1716419784.109 [MissionManager](INFO): DefineArg ballast_and_trim.SettleTime = 10.000000 min 2024-05-22T23:16:24.132Z,1716419784.132 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationTimeout = 45.000000 min 2024-05-22T23:16:24.135Z,1716419784.135 [MissionManager](INFO): DefineArg ballast_and_trim.MinEstimationTime = 10.000000 min 2024-05-22T23:16:24.166Z,1716419784.166 [MissionManager](INFO): DefineArg ballast_and_trim.MassEstimationErrorBound = 0.250000 mm 2024-05-22T23:16:24.173Z,1716419784.173 [MissionManager](INFO): DefineArg ballast_and_trim.BuoyEstimationErrorBound = 15.000000 cc 2024-05-22T23:16:24.196Z,1716419784.196 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationConfidence = 99.900002 % 2024-05-22T23:16:24.199Z,1716419784.199 [MissionManager](INFO): DefineArg ballast_and_trim.WaitToFloat = 5.000000 min 2024-05-22T23:16:24.199Z,1716419784.199 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/Science.tl 2024-05-22T23:16:24.209Z,1716419784.209 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/Science.tl 2024-05-22T23:16:24.228Z,1716419784.228 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/Science.tl 2024-05-22T23:16:27.465Z,1716419787.465 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/Science.tl translated into: NaN 3 100 80 20 5 NaN NaN 2.0 NaN NaN 5 NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN Triggered. PeakChl values on consecutive yo-yo profiles fall in [ ] (NaN bound is ignored). 2024-05-22T23:16:27.524Z,1716419787.524 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectChlActive = 0 bool 2024-05-22T23:16:27.537Z,1716419787.537 [MissionManager](INFO): DefineArg ballast_and_trim:Science.TimeWindowPeakReport = nan min 2024-05-22T23:16:27.549Z,1716419787.549 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestChlPeakReportActive = 0 bool 2024-05-22T23:16:27.575Z,1716419787.575 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestSaltPeakReportActive = 0 bool 2024-05-22T23:16:27.587Z,1716419787.587 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestOilPeakReportActive = 0 bool 2024-05-22T23:16:27.618Z,1716419787.618 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PatchTracking = 0 bool 2024-05-22T23:16:27.625Z,1716419787.625 [MissionManager](INFO): DefineArg ballast_and_trim:Science.FilterWidthHorizontal = 3.000000 count 2024-05-22T23:16:27.640Z,1716419787.640 [MissionManager](INFO): DefineArg ballast_and_trim:Science.NumProfilesSlidingwindow = 100.000000 count 2024-05-22T23:16:27.643Z,1716419787.643 [MissionManager](INFO): DefineArg ballast_and_trim:Science.OffPeakFractionHorizontal = 80.000000 % 2024-05-22T23:16:27.683Z,1716419787.683 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectNO3Active = 0 bool 2024-05-22T23:16:27.702Z,1716419787.702 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectOilActive = 0 bool 2024-05-22T23:16:27.721Z,1716419787.721 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectFDOMActive = 0 bool 2024-05-22T23:16:27.735Z,1716419787.735 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectSalinityActive = 0 bool 2024-05-22T23:16:27.746Z,1716419787.746 [MissionManager](INFO): DefineArg ballast_and_trim:Science.UpwardDerivativeOfTemperatureActive = 0 bool 2024-05-22T23:16:27.780Z,1716419787.780 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledAanderaaO2 = 0 bool 2024-05-22T23:16:27.794Z,1716419787.794 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledNeilBrown = 0 bool 2024-05-22T23:16:27.807Z,1716419787.807 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledSeabird = 1 bool 2024-05-22T23:16:27.850Z,1716419787.850 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsBB2FL = 1 bool 2024-05-22T23:16:27.878Z,1716419787.878 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsSeaOWL_UV_A = 0 bool 2024-05-22T23:16:27.886Z,1716419787.886 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsUBAT = 1 bool 2024-05-22T23:16:27.913Z,1716419787.913 [MissionManager](INFO): DefineArg ballast_and_trim:Science.LowPassWindowLength = 20.000000 count 2024-05-22T23:16:27.924Z,1716419787.924 [MissionManager](INFO): DefineArg ballast_and_trim:Science.MedianFilterLen = 5.000000 count 2024-05-22T23:16:27.927Z,1716419787.927 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakShallowBound = nan m 2024-05-22T23:16:27.934Z,1716419787.934 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDeepBound = nan m 2024-05-22T23:16:27.965Z,1716419787.965 [MissionManager](INFO): DefineArg ballast_and_trim:Science.DepChangeThreshForAttitudeFlip = 2.000000 m 2024-05-22T23:16:27.967Z,1716419787.967 [MissionManager](INFO): DefineArg ballast_and_trim:Science.ChlThreshTriggerActive = 0 bool 2024-05-22T23:16:27.975Z,1716419787.975 [MissionManager](INFO): DefineArg ballast_and_trim:Science.ChlLowerThresh = nan ug/l 2024-05-22T23:16:27.982Z,1716419787.982 [MissionManager](INFO): DefineArg ballast_and_trim:Science.ChlUpperThresh = nan ug/l 2024-05-22T23:16:28.001Z,1716419788.001 [MissionManager](INFO): DefineArg ballast_and_trim:Science.NumProfilesThresh = 5.000000 count 2024-05-22T23:16:28.016Z,1716419788.016 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChl = nan ug/l 2024-05-22T23:16:28.019Z,1716419788.019 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlDepth = nan m 2024-05-22T23:16:28.034Z,1716419788.034 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlTemperature = nan degC 2024-05-22T23:16:28.042Z,1716419788.042 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLatitude = nan arcdeg 2024-05-22T23:16:28.086Z,1716419788.086 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLongitude = nan arcdeg 2024-05-22T23:16:28.123Z,1716419788.123 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3 = nan umol/l 2024-05-22T23:16:28.155Z,1716419788.155 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Depth = nan m 2024-05-22T23:16:28.171Z,1716419788.171 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Latitude = nan arcdeg 2024-05-22T23:16:28.198Z,1716419788.198 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Longitude = nan arcdeg 2024-05-22T23:16:28.209Z,1716419788.209 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOil = nan kg/m3 2024-05-22T23:16:28.236Z,1716419788.236 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilDepth = nan m 2024-05-22T23:16:28.239Z,1716419788.239 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLatitude = nan arcdeg 2024-05-22T23:16:28.266Z,1716419788.266 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLongitude = nan arcdeg 2024-05-22T23:16:28.281Z,1716419788.281 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOM = nan ppb 2024-05-22T23:16:28.289Z,1716419788.289 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMDepth = nan m 2024-05-22T23:16:28.317Z,1716419788.317 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMTemperature = nan degC 2024-05-22T23:16:28.329Z,1716419788.329 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLatitude = nan arcdeg 2024-05-22T23:16:28.342Z,1716419788.342 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLongitude = nan arcdeg 2024-05-22T23:16:28.350Z,1716419788.350 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSalt = nan psu 2024-05-22T23:16:28.380Z,1716419788.380 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltDepth = nan m 2024-05-22T23:16:28.392Z,1716419788.392 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltTemperature = nan degC 2024-05-22T23:16:28.404Z,1716419788.404 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltLatitude = nan arcdeg 2024-05-22T23:16:28.417Z,1716419788.417 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltLongitude = nan arcdeg 2024-05-22T23:16:28.436Z,1716419788.436 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChl = nan ug/l 2024-05-22T23:16:28.439Z,1716419788.439 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDepth = nan m 2024-05-22T23:16:28.479Z,1716419788.479 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLatitude = nan arcdeg 2024-05-22T23:16:28.495Z,1716419788.495 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLongitude = nan arcdeg 2024-05-22T23:16:28.506Z,1716419788.506 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDistance = nan m 2024-05-22T23:16:28.537Z,1716419788.537 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSalt = nan psu 2024-05-22T23:16:28.584Z,1716419788.584 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltDepth = nan m 2024-05-22T23:16:28.587Z,1716419788.587 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltLatitude = nan arcdeg 2024-05-22T23:16:28.602Z,1716419788.602 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltLongitude = nan arcdeg 2024-05-22T23:16:28.626Z,1716419788.626 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltDistance = nan m 2024-05-22T23:16:28.633Z,1716419788.633 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOil = nan psu 2024-05-22T23:16:28.664Z,1716419788.664 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilDepth = nan m 2024-05-22T23:16:28.667Z,1716419788.667 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilLatitude = nan arcdeg 2024-05-22T23:16:28.703Z,1716419788.703 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilLongitude = nan arcdeg 2024-05-22T23:16:28.716Z,1716419788.716 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilDistance = nan m 2024-05-22T23:16:28.741Z,1716419788.741 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.TriggeredOnChl = 0 bool 2024-05-22T23:16:28.761Z,1716419788.761 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.ChlTriggering = nan ug/l 2024-05-22T23:16:28.903Z,1716419788.903 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2024-05-22T23:16:28.978Z,1716419788.978 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2024-05-22T23:16:29.094Z,1716419789.094 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2024-05-22T23:16:29.173Z,1716419789.173 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2024-05-22T23:16:29.216Z,1716419789.216 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2024-05-22T23:16:29.358Z,1716419789.358 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2024-05-22T23:16:29.422Z,1716419789.422 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2024-05-22T23:16:29.506Z,1716419789.506 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2024-05-22T23:16:29.598Z,1716419789.598 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Construct ValueDetect. 2024-05-22T23:16:29.621Z,1716419789.621 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl 2024-05-22T23:16:29.622Z,1716419789.622 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl 2024-05-22T23:16:29.623Z,1716419789.623 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl 2024-05-22T23:16:29.708Z,1716419789.708 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into: 2024-05-22T23:16:29.728Z,1716419789.728 [MissionManager](INFO): DefineArg ballast_and_trim:BackseatDriver.EnableBackseat = 0 bool 2024-05-22T23:16:29.731Z,1716419789.731 [ballast_and_trim:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver. 2024-05-22T23:16:29.742Z,1716419789.742 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl 2024-05-22T23:16:29.742Z,1716419789.742 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl 2024-05-22T23:16:29.743Z,1716419789.743 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl 2024-05-22T23:16:30.237Z,1716419790.237 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into: 99999 2024-05-22T23:16:30.264Z,1716419790.264 [MissionManager](INFO): DefineArg ballast_and_trim:PowerOnly.SampleAll = 0 bool 2024-05-22T23:16:30.267Z,1716419790.267 [MissionManager](INFO): DefineArg ballast_and_trim:PowerOnly.SampleLoad1 = 0 bool 2024-05-22T23:16:30.305Z,1716419790.305 [MissionManager](INFO): DefineArg ballast_and_trim:PowerOnly.SampleLoad2 = 0 bool 2024-05-22T23:16:30.316Z,1716419790.316 [MissionManager](INFO): DefineArg ballast_and_trim:PowerOnly.SampleLoad3 = 0 bool 2024-05-22T23:16:30.326Z,1716419790.326 [MissionManager](INFO): DefineArg ballast_and_trim:PowerOnly.EnabledPowerOnly = 0 bool 2024-05-22T23:16:30.358Z,1716419790.358 [ballast_and_trim:PowerOnly:E.Wait](DEBUG): Construct Wait. 2024-05-22T23:16:30.368Z,1716419790.368 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BallastAndTrim.tl 2024-05-22T23:16:30.368Z,1716419790.368 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BallastAndTrim.tl 2024-05-22T23:16:30.370Z,1716419790.370 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BallastAndTrim.tl 2024-05-22T23:16:32.159Z,1716419792.159 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BallastAndTrim.tl translated into: 25 0 0.4 20 0 20 10 45 10 0.25 15 99.9 1 0.25 0.005 0.00075 0 Going to target depth. Speed set to 0 13 0 Waiting for while pumping down to neutral. Moving to Timed out trying to reach the target depth. Stopping mission at current depth of stop 0 Waiting for the vehicle to settle. Depth = gfscan Running ballast and trim. Depth = 2024-05-22T23:16:32.232Z,1716419792.232 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepth = 25.000000 m 2024-05-22T23:16:32.235Z,1716419792.235 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSpeed = 0.000000 m/s 2024-05-22T23:16:32.246Z,1716419792.246 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthRate = 0.400000 m/s 2024-05-22T23:16:32.274Z,1716419792.274 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachPitchLimit = 20.000000 arcdeg 2024-05-22T23:16:32.283Z,1716419792.283 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSettleTimePreDive = 0.000000 min 2024-05-22T23:16:32.299Z,1716419792.299 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = 20.000000 min 2024-05-22T23:16:32.322Z,1716419792.322 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = 10.000000 min 2024-05-22T23:16:32.338Z,1716419792.338 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstTimeout = 45.000000 min 2024-05-22T23:16:32.345Z,1716419792.345 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MinEstTime = 10.000000 min 2024-05-22T23:16:32.360Z,1716419792.360 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = 0.250000 mm 2024-05-22T23:16:32.388Z,1716419792.388 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = 15.000000 cc 2024-05-22T23:16:32.391Z,1716419792.391 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstConfidence = 99.900002 % 2024-05-22T23:16:32.403Z,1716419792.403 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = 1.000000 m 2024-05-22T23:16:32.414Z,1716419792.414 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertMassDeadband = 0.250000 mm 2024-05-22T23:16:32.471Z,1716419792.471 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Construct. 2024-05-22T23:16:32.494Z,1716419792.494 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Construct Buoyancy. 2024-05-22T23:16:32.529Z,1716419792.529 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Construct. 2024-05-22T23:16:32.578Z,1716419792.578 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Construct Wait. 2024-05-22T23:16:32.594Z,1716419792.594 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Construct. 2024-05-22T23:16:32.609Z,1716419792.609 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Construct. 2024-05-22T23:16:32.653Z,1716419792.653 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute. 2024-05-22T23:16:32.666Z,1716419792.666 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Construct. 2024-05-22T23:16:32.675Z,1716419792.675 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Construct. 2024-05-22T23:16:32.705Z,1716419792.705 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Construct Wait. 2024-05-22T23:16:32.707Z,1716419792.707 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E.Execute](DEBUG): Construct Execute. 2024-05-22T23:16:32.727Z,1716419792.727 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](DEBUG): Construct BallastAndTrim. 2024-05-22T23:16:32.789Z,1716419792.789 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = reader:ballast_and_trim.DepthDeadband 2024-05-22T23:16:32.791Z,1716419792.791 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = reader:ballast_and_trim.TargetDepthTimeout 2024-05-22T23:16:32.801Z,1716419792.801 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = reader:ballast_and_trim.SettleTime 2024-05-22T23:16:32.802Z,1716419792.802 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstTimeout = reader:ballast_and_trim.EstimationTimeout 2024-05-22T23:16:32.820Z,1716419792.820 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MinEstTime = reader:ballast_and_trim.MinEstimationTime 2024-05-22T23:16:32.822Z,1716419792.822 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = reader:ballast_and_trim.MassEstimationErrorBound 2024-05-22T23:16:32.832Z,1716419792.832 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = reader:ballast_and_trim.BuoyEstimationErrorBound 2024-05-22T23:16:32.834Z,1716419792.834 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstConfidence = reader:ballast_and_trim.EstimationConfidence 2024-05-22T23:16:32.881Z,1716419792.881 [ballast_and_trim:Float_Up:A.Buoyancy](DEBUG): Construct Buoyancy. 2024-05-22T23:16:32.889Z,1716419792.889 [ballast_and_trim:Float_Up:B.Wait](DEBUG): Construct Wait. 2024-05-22T23:16:33.023Z,1716419793.023 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission ballast_and_trim { arguments { MissionTimeout = 120 minute """ Maximum duration of mission. """ Depth1 = 7 meter """ Target depth for ballast and trim. """ Depth2 = NaN meter """ Target depth to run a second ballast and trim, skipped if NaN. """ DepthDeadband = 0.1 meter """ How much vertical drift from the specified depth is allowed during the mission. """ SurfaceThreshold = 2.5 meter """ Surface threshold depth, relevant only to the ascent. """ TargetDepthTimeout = 20 minute """ Maximum wait time for the vehicle to reach the target ballast and trim depth. """ SettleTime = 10 minute """ How long to wait after reaching target depth before starting ballast and trim. """ EstimationTimeout = 45 minute """ Timeout for ballast and trim estimation. """ MinEstimationTime = 10 minute """ Minimum time to run ballast and trim estimation before determining convergence (must be shorter than timeout). """ MassEstimationErrorBound = 0.25 millimeter """ Desired range of the estimated sample mean from the true mean statistic (smaller values take longer to converge). """ BuoyEstimationErrorBound = 15 cubic_centimeter """ Desired range of the estimated sample mean from the true mean statistic (smaller values take longer to converge). """ EstimationConfidence = 99.9 percent """ Confidence level that the estimator has reached the desired error range (larger values take longer to converge). """ WaitToFloat = 5 minute """ Timeout for reaching surface threshold on float ascent, mission will end when reached. """ } timeout duration=MissionTimeout insert Insert/Science.tl insert Insert/BackseatDriver.tl insert Insert/PowerOnly.tl aggregate RunBallastAndTrim { run in sequence insert Insert/BallastAndTrim.tl { redefineArg InsertDepthDeadband = DepthDeadband redefineArg InsertApproachDepthTimeout = TargetDepthTimeout redefineArg InsertSettleTime = SettleTime redefineArg EstTimeout = EstimationTimeout redefineArg MinEstTime = MinEstimationTime redefineArg MassEstErrorBound = MassEstimationErrorBound redefineArg BuoyEstErrorBound = BuoyEstimationErrorBound redefineArg EstConfidence = EstimationConfidence } aggregate Depth1 { run in sequence break if ( isNaN ( Depth1 ) ) assign in sequence BallastAndTrim:InsertDepth = Depth1 call refId="BallastAndTrim" } aggregate Depth2 { run in sequence break if ( isNaN ( Depth2 ) ) assign in sequence BallastAndTrim:InsertDepth = Depth2 call refId="BallastAndTrim" } } aggregate Float_Up { """ Float to surface instead of driving. """ run in sequence break if ( Universal:depth < SurfaceThreshold ) behavior Guidance:Buoyancy { run in parallel set position = Control:VerticalControl.buoyancyDefault } behavior Guidance:Wait { run in sequence set duration = WaitToFloat } } } 2024-05-22T23:16:33.023Z,1716419793.023 [CommandExec](IMPORTANT): Loaded ./Missions/Maintenance/tank_ballast_and_trim.tl id=ballast_and_trim 2024-05-22T23:16:37.876Z,1716419797.876 [DataOverHttps](INFO): Received command: sched "load Engineering/OnDock.tl;set OnDock.DockedTime 45 min;set OnDock.DetachedTimeout Dock:Docked.detachTimeout h;set OnDock.RangeTimeout Dock:Docked.rangeTimeout h;set OnDock.MaxDepth 5 m;set OnDock.MinAltitude 0.00001 m" 3e5z6 1 2 2024-05-22T23:16:38.877Z,1716419798.877 [DataOverHttps](IMPORTANT): SBD MTMSN=20240522T231638 2024-05-22T23:16:51.500Z,1716419811.500 [DataOverHttps](INFO): Received command: sched "set OnDock.MinOffshore 0.000001 km;set OnDock.IfconfigInterval 5 min;run" 3e5z6 2 2 2024-05-22T23:16:52.070Z,1716419812.070 [CommandExec](IMPORTANT): got command set ballast_and_trim.MissionTimeout 8 minute 2024-05-22T23:16:52.073Z,1716419812.073 [CommandExec](IMPORTANT): got command set ballast_and_trim.Depth1 1.25 meter 2024-05-22T23:16:52.074Z,1716419812.074 [CommandExec](IMPORTANT): got command set ballast_and_trim.DepthDeadband 0.25 meter 2024-05-22T23:16:52.074Z,1716419812.074 [CommandExec](IMPORTANT): got command set ballast_and_trim.SettleTime 20 minute 2024-05-22T23:16:52.075Z,1716419812.075 [CommandExec](IMPORTANT): got command run 2024-05-22T23:16:52.078Z,1716419812.078 [CommandExec](IMPORTANT): Running 2024-05-22T23:16:52.100Z,1716419812.100 [CommandExec](IMPORTANT): got command schedule "load Engineering/OnDock.tl;set OnDock.DockedTime 45 min;set OnDock.DetachedTimeout Dock:Docked.detachTimeout h;set OnDock.RangeTimeout Dock:Docked.rangeTimeout h;set OnDock.MaxDepth 5 m;set OnDock.MinAltitude 0.00001 m" 3e5z6 1 2.000000 2024-05-22T23:16:52.101Z,1716419812.101 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=3e5z6 2024-05-22T23:16:52.102Z,1716419812.102 [CommandExec](IMPORTANT): Scheduled #1 (#1 of 2 with id='3e5z6'): "load Engineering/OnDock.tl;set OnDock.DockedTime 45 min;set OnDock.DetachedTimeout Dock:Docked.detachTimeout h;set OnDock.RangeTimeout Dock:Docked.rangeTimeout h;set OnDock.MaxDepth 5 m;set OnDock.MinAltitude 0.00001 m", AFTER MISSION 2024-05-22T23:16:52.108Z,1716419812.108 [CommandExec](IMPORTANT): got command schedule "set OnDock.MinOffshore 0.000001 km;set OnDock.IfconfigInterval 5 min;run" 3e5z6 2 2.000000 2024-05-22T23:16:52.144Z,1716419812.144 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=3e5z6 2024-05-22T23:16:52.145Z,1716419812.145 [CommandExec](IMPORTANT): Scheduled #2 (#2 of 2 with id='3e5z6'): "set OnDock.MinOffshore 0.000001 km;set OnDock.IfconfigInterval 5 min;run", AFTER MISSION 2024-05-22T23:16:52.352Z,1716419812.352 [DefaultTankUndock] Stopped 2024-05-22T23:16:52.353Z,1716419812.353 [DefaultTankUndock](DEBUG): Aggregate::uninitialize DefaultTankUndock 2024-05-22T23:16:52.353Z,1716419812.353 [DefaultTankUndock:B.GoToSurface] Stopped 2024-05-22T23:16:52.353Z,1716419812.353 [DefaultTankUndock:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-05-22T23:16:52.353Z,1716419812.353 [DefaultTankUndock:CheckIn] Stopped 2024-05-22T23:16:52.353Z,1716419812.353 [DefaultTankUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultTankUndock:CheckIn 2024-05-22T23:16:52.353Z,1716419812.353 [DefaultTankUndock:CheckIn:C.Wait] Stopped 2024-05-22T23:16:52.353Z,1716419812.353 [DefaultTankUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-22T23:16:52.353Z,1716419812.353 [MissionManager](IMPORTANT): Started mission ballast_and_trim 2024-05-22T23:16:52.353Z,1716419812.353 [ballast_and_trim] Running Loop=1 2024-05-22T23:16:52.354Z,1716419812.354 [ballast_and_trim](DEBUG): Aggregate::initialize ballast_and_trim 2024-05-22T23:16:52.354Z,1716419812.354 [ballast_and_trim:Science] Running Loop=1 2024-05-22T23:16:52.354Z,1716419812.354 [ballast_and_trim:Science](DEBUG): Aggregate::initialize ballast_and_trim:Science 2024-05-22T23:16:52.354Z,1716419812.354 [ballast_and_trim:Science:A] Running Loop=1 2024-05-22T23:16:52.354Z,1716419812.354 [ballast_and_trim:Science:B] Running Loop=1 2024-05-22T23:16:52.354Z,1716419812.354 [ballast_and_trim:Science:C] Running Loop=1 2024-05-22T23:16:52.354Z,1716419812.354 [ballast_and_trim:Science:D] Running Loop=1 2024-05-22T23:16:52.354Z,1716419812.354 [ballast_and_trim:Science:E] Running Loop=1 2024-05-22T23:16:52.354Z,1716419812.354 [ballast_and_trim:Science:F] Running Loop=1 2024-05-22T23:16:52.354Z,1716419812.354 [ballast_and_trim:Science:Read_Oil] Running Loop=1 2024-05-22T23:16:52.354Z,1716419812.354 [ballast_and_trim:Science:PeakDetectChl] Running Loop=1 2024-05-22T23:16:52.354Z,1716419812.354 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectChl 2024-05-22T23:16:52.355Z,1716419812.355 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1 2024-05-22T23:16:52.355Z,1716419812.355 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize. 2024-05-22T23:16:52.357Z,1716419812.357 [ballast_and_trim:Science:HighestChlPeakReport] Running Loop=1 2024-05-22T23:16:52.357Z,1716419812.357 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestChlPeakReport 2024-05-22T23:16:52.357Z,1716419812.357 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1 2024-05-22T23:16:52.357Z,1716419812.357 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2024-05-22T23:16:52.358Z,1716419812.358 [ballast_and_trim:Science:PeakDetectNO3] Running Loop=1 2024-05-22T23:16:52.358Z,1716419812.358 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectNO3 2024-05-22T23:16:52.358Z,1716419812.358 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1 2024-05-22T23:16:52.358Z,1716419812.358 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize. 2024-05-22T23:16:52.360Z,1716419812.360 [ballast_and_trim:Science:PeakDetectOil] Running Loop=1 2024-05-22T23:16:52.360Z,1716419812.360 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectOil 2024-05-22T23:16:52.360Z,1716419812.360 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1 2024-05-22T23:16:52.360Z,1716419812.360 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize. 2024-05-22T23:16:52.362Z,1716419812.362 [ballast_and_trim:Science:HighestOilPeakReport] Running Loop=1 2024-05-22T23:16:52.362Z,1716419812.362 [ballast_and_trim:Science:HighestOilPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestOilPeakReport 2024-05-22T23:16:52.362Z,1716419812.362 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Running Loop=1 2024-05-22T23:16:52.363Z,1716419812.363 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2024-05-22T23:16:52.363Z,1716419812.363 [ballast_and_trim:Science:PeakDetectFDOM] Running Loop=1 2024-05-22T23:16:52.363Z,1716419812.363 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectFDOM 2024-05-22T23:16:52.363Z,1716419812.363 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1 2024-05-22T23:16:52.363Z,1716419812.363 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize. 2024-05-22T23:16:52.365Z,1716419812.365 [ballast_and_trim:Science:PeakDetectSalinity] Running Loop=1 2024-05-22T23:16:52.365Z,1716419812.365 [ballast_and_trim:Science:PeakDetectSalinity](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectSalinity 2024-05-22T23:16:52.365Z,1716419812.365 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Running Loop=1 2024-05-22T23:16:52.365Z,1716419812.365 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Initialize. 2024-05-22T23:16:52.367Z,1716419812.367 [ballast_and_trim:Science:HighestSaltPeakReport] Running Loop=1 2024-05-22T23:16:52.367Z,1716419812.367 [ballast_and_trim:Science:HighestSaltPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestSaltPeakReport 2024-05-22T23:16:52.367Z,1716419812.367 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Running Loop=1 2024-05-22T23:16:52.367Z,1716419812.367 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2024-05-22T23:16:52.384Z,1716419812.384 [ballast_and_trim:Science:ChlThreshTrigger] Running Loop=1 2024-05-22T23:16:52.384Z,1716419812.384 [ballast_and_trim:Science:ChlThreshTrigger](DEBUG): Aggregate::initialize ballast_and_trim:Science:ChlThreshTrigger 2024-05-22T23:16:52.384Z,1716419812.384 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect] Running Loop=1 2024-05-22T23:16:52.384Z,1716419812.384 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect](INFO): Initialize. 2024-05-22T23:16:52.385Z,1716419812.385 [ballast_and_trim:Science:OceanCurrent] Running Loop=1 2024-05-22T23:16:52.385Z,1716419812.385 [ballast_and_trim:Science:OceanCurrent](DEBUG): Aggregate::initialize ballast_and_trim:Science:OceanCurrent 2024-05-22T23:16:52.385Z,1716419812.385 [ballast_and_trim:Science:OceanCurrent:A.] Running Loop=1 2024-05-22T23:16:52.385Z,1716419812.385 [ballast_and_trim:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator. 2024-05-22T23:16:52.385Z,1716419812.385 [ballast_and_trim:BackseatDriver] Running Loop=1 2024-05-22T23:16:52.385Z,1716419812.385 [ballast_and_trim:BackseatDriver](DEBUG): Aggregate::initialize ballast_and_trim:BackseatDriver 2024-05-22T23:16:52.385Z,1716419812.385 [ballast_and_trim:BackseatDriver:A.BackseatDriver] Running Loop=1 2024-05-22T23:16:52.385Z,1716419812.385 [ballast_and_trim:PowerOnly] Running Loop=1 2024-05-22T23:16:52.386Z,1716419812.386 [ballast_and_trim:PowerOnly](DEBUG): Aggregate::initialize ballast_and_trim:PowerOnly 2024-05-22T23:16:52.386Z,1716419812.386 [ballast_and_trim:PowerOnly:A] Running Loop=1 2024-05-22T23:16:52.386Z,1716419812.386 [ballast_and_trim:PowerOnly:B] Running Loop=1 2024-05-22T23:16:52.386Z,1716419812.386 [ballast_and_trim:PowerOnly:C] Running Loop=1 2024-05-22T23:16:52.386Z,1716419812.386 [ballast_and_trim:PowerOnly:D] Running Loop=1 2024-05-22T23:16:52.386Z,1716419812.386 [ballast_and_trim:PowerOnly:E.Wait] Running Loop=1 2024-05-22T23:16:52.386Z,1716419812.386 [ballast_and_trim:PowerOnly:E.Wait](DEBUG): Initialize Wait Component. 2024-05-22T23:16:52.386Z,1716419812.386 [ballast_and_trim:RunBallastAndTrim] Running Loop=1 2024-05-22T23:16:52.386Z,1716419812.386 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim 2024-05-22T23:16:52.386Z,1716419812.386 [ballast_and_trim:RunBallastAndTrim:Depth1] Running Loop=1 2024-05-22T23:16:52.386Z,1716419812.386 [ballast_and_trim:RunBallastAndTrim:Depth1](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1 2024-05-22T23:16:52.387Z,1716419812.387 [ballast_and_trim:RunBallastAndTrim:Depth1:A] Running Loop=1 2024-05-22T23:16:52.387Z,1716419812.387 [ballast_and_trim:RunBallastAndTrim:Depth1:A] Stopped 2024-05-22T23:16:52.387Z,1716419812.387 [ballast_and_trim:RunBallastAndTrim:Depth1:B] Running Loop=1 2024-05-22T23:16:52.387Z,1716419812.387 [ballast_and_trim:RunBallastAndTrim:Depth1:B](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1:B 2024-05-22T23:16:52.392Z,1716419812.392 [ballast_and_trim:PowerOnly] Running Loop=1 2024-05-22T23:16:52.392Z,1716419812.392 [ballast_and_trim:PowerOnly:D] Stopped 2024-05-22T23:16:52.392Z,1716419812.392 [ballast_and_trim:PowerOnly:C] Stopped 2024-05-22T23:16:52.392Z,1716419812.392 [ballast_and_trim:PowerOnly:B] Stopped 2024-05-22T23:16:52.392Z,1716419812.392 [ballast_and_trim:PowerOnly:A] Stopped 2024-05-22T23:16:52.392Z,1716419812.392 [ballast_and_trim:BackseatDriver] Running Loop=1 2024-05-22T23:16:52.393Z,1716419812.393 [ballast_and_trim:Science] Running Loop=1 2024-05-22T23:16:52.393Z,1716419812.393 [ballast_and_trim:Science:ChlThreshTrigger] Stopped 2024-05-22T23:16:52.394Z,1716419812.394 [ballast_and_trim:Science:ChlThreshTrigger](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:ChlThreshTrigger 2024-05-22T23:16:52.394Z,1716419812.394 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect] Stopped 2024-05-22T23:16:52.394Z,1716419812.394 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Uninitialize. 2024-05-22T23:16:52.394Z,1716419812.394 [ballast_and_trim:Science:HighestSaltPeakReport] Stopped 2024-05-22T23:16:52.394Z,1716419812.394 [ballast_and_trim:Science:HighestSaltPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestSaltPeakReport 2024-05-22T23:16:52.394Z,1716419812.394 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Stopped 2024-05-22T23:16:52.394Z,1716419812.394 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2024-05-22T23:16:52.394Z,1716419812.394 [ballast_and_trim:Science:PeakDetectSalinity] Stopped 2024-05-22T23:16:52.394Z,1716419812.394 [ballast_and_trim:Science:PeakDetectSalinity](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectSalinity 2024-05-22T23:16:52.394Z,1716419812.394 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Stopped 2024-05-22T23:16:52.394Z,1716419812.394 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2024-05-22T23:16:52.394Z,1716419812.394 [ballast_and_trim:Science:PeakDetectFDOM] Stopped 2024-05-22T23:16:52.394Z,1716419812.394 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectFDOM 2024-05-22T23:16:52.395Z,1716419812.395 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped 2024-05-22T23:16:52.395Z,1716419812.395 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2024-05-22T23:16:52.395Z,1716419812.395 [ballast_and_trim:Science:HighestOilPeakReport] Stopped 2024-05-22T23:16:52.395Z,1716419812.395 [ballast_and_trim:Science:HighestOilPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestOilPeakReport 2024-05-22T23:16:52.395Z,1716419812.395 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Stopped 2024-05-22T23:16:52.395Z,1716419812.395 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2024-05-22T23:16:52.395Z,1716419812.395 [ballast_and_trim:Science:PeakDetectOil] Stopped 2024-05-22T23:16:52.395Z,1716419812.395 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectOil 2024-05-22T23:16:52.395Z,1716419812.395 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped 2024-05-22T23:16:52.395Z,1716419812.395 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2024-05-22T23:16:52.395Z,1716419812.395 [ballast_and_trim:Science:PeakDetectNO3] Stopped 2024-05-22T23:16:52.395Z,1716419812.395 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectNO3 2024-05-22T23:16:52.395Z,1716419812.395 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped 2024-05-22T23:16:52.396Z,1716419812.396 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2024-05-22T23:16:52.400Z,1716419812.400 [ballast_and_trim:Science:HighestChlPeakReport] Stopped 2024-05-22T23:16:52.400Z,1716419812.400 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestChlPeakReport 2024-05-22T23:16:52.400Z,1716419812.400 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped 2024-05-22T23:16:52.400Z,1716419812.400 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2024-05-22T23:16:52.400Z,1716419812.400 [ballast_and_trim:Science:PeakDetectChl] Stopped 2024-05-22T23:16:52.400Z,1716419812.400 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectChl 2024-05-22T23:16:52.400Z,1716419812.400 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped 2024-05-22T23:16:52.400Z,1716419812.400 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2024-05-22T23:16:52.401Z,1716419812.401 [ballast_and_trim:Science:OceanCurrent:A.] Running Loop=1 2024-05-22T23:16:52.402Z,1716419812.402 [ballast_and_trim:Science:Read_Oil] Stopped 2024-05-22T23:16:52.402Z,1716419812.402 [ballast_and_trim:Science:F] Running Loop=1 2024-05-22T23:16:52.403Z,1716419812.403 [ballast_and_trim:Science:F](DEBUG): Initialize ReadDataComponent to sense WetLabsUBAT.average_bioluminescence 2024-05-22T23:16:52.403Z,1716419812.403 [ballast_and_trim:Science:E] Running Loop=1 2024-05-22T23:16:52.405Z,1716419812.405 [ballast_and_trim:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water 2024-05-22T23:16:52.406Z,1716419812.406 [ballast_and_trim:Science:D] Stopped 2024-05-22T23:16:52.406Z,1716419812.406 [ballast_and_trim:Science:C] Running Loop=1 2024-05-22T23:16:52.424Z,1716419812.424 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature 2024-05-22T23:16:52.426Z,1716419812.426 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity 2024-05-22T23:16:52.426Z,1716419812.426 [ballast_and_trim:Science:B] Running Loop=1 2024-05-22T23:16:52.440Z,1716419812.440 [ballast_and_trim:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature 2024-05-22T23:16:52.442Z,1716419812.442 [ballast_and_trim:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity 2024-05-22T23:16:52.442Z,1716419812.442 [ballast_and_trim:Science:A] Stopped 2024-05-22T23:16:52.705Z,1716419812.705 [WetLabsBB2FL](INFO): Powering up 2024-05-22T23:16:52.921Z,1716419812.921 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim] Running Loop=1 2024-05-22T23:16:52.921Z,1716419812.921 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim 2024-05-22T23:16:52.922Z,1716419812.922 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1 2024-05-22T23:16:52.922Z,1716419812.922 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1 2024-05-22T23:16:52.922Z,1716419812.922 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1 2024-05-22T23:16:52.922Z,1716419812.922 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1 2024-05-22T23:16:52.922Z,1716419812.922 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1 2024-05-22T23:16:52.922Z,1716419812.922 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Running Loop=1 2024-05-22T23:16:52.928Z,1716419812.928 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F](INFO): Going to target depth. Speed set to 0.000000 m/s 2024-05-22T23:16:52.928Z,1716419812.928 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Stopped 2024-05-22T23:16:52.928Z,1716419812.928 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Running Loop=1 2024-05-22T23:16:52.928Z,1716419812.928 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth 2024-05-22T23:16:52.928Z,1716419812.928 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1 2024-05-22T23:16:52.929Z,1716419812.929 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Running Loop=1 2024-05-22T23:16:52.929Z,1716419812.929 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold 2024-05-22T23:16:52.929Z,1716419812.929 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Running Loop=1 2024-05-22T23:16:52.929Z,1716419812.929 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Initialize. 2024-05-22T23:16:52.929Z,1716419812.929 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Running Loop=1 2024-05-22T23:16:52.929Z,1716419812.929 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Initialize Buoyancy Component. 2024-05-22T23:16:52.929Z,1716419812.929 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Running Loop=1 2024-05-22T23:16:52.929Z,1716419812.929 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Initialize. 2024-05-22T23:16:52.930Z,1716419812.930 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Running Loop=1 2024-05-22T23:16:52.930Z,1716419812.930 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive 2024-05-22T23:16:52.930Z,1716419812.930 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Running Loop=1 2024-05-22T23:16:52.930Z,1716419812.930 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1 2024-05-22T23:16:52.930Z,1716419812.930 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1 2024-05-22T23:16:52.931Z,1716419812.931 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1 2024-05-22T23:16:52.931Z,1716419812.931 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1 2024-05-22T23:16:52.931Z,1716419812.931 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1 2024-05-22T23:16:53.026Z,1716419813.026 [WetLabsUBAT](INFO): Powering up 2024-05-22T23:16:53.159Z,1716419813.159 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A](INFO): Waiting for 0.000000 min while pumping down to neutral. 2024-05-22T23:16:53.159Z,1716419813.159 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Stopped 2024-05-22T23:16:53.159Z,1716419813.159 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait] Running Loop=1 2024-05-22T23:16:53.159Z,1716419813.159 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Initialize Wait Component. 2024-05-22T23:16:53.159Z,1716419813.159 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Stopped 2024-05-22T23:16:53.159Z,1716419813.159 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold 2024-05-22T23:16:53.159Z,1716419813.159 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Stopped 2024-05-22T23:16:53.159Z,1716419813.159 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Stopped 2024-05-22T23:16:53.159Z,1716419813.159 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2024-05-22T23:16:53.159Z,1716419813.159 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Stopped 2024-05-22T23:16:53.159Z,1716419813.159 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1 2024-05-22T23:16:53.504Z,1716419813.504 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T23:16:53.604Z,1716419813.604 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](INFO): Done Waiting. 2024-05-22T23:16:53.604Z,1716419813.604 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait] Stopped 2024-05-22T23:16:53.604Z,1716419813.604 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Uninitialize Wait Component. 2024-05-22T23:16:53.604Z,1716419813.604 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive 2024-05-22T23:16:53.605Z,1716419813.605 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Stopped 2024-05-22T23:16:53.605Z,1716419813.605 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive 2024-05-22T23:16:53.605Z,1716419813.605 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Running Loop=1 2024-05-22T23:16:53.605Z,1716419813.605 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth 2024-05-22T23:16:53.605Z,1716419813.605 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1 2024-05-22T23:16:53.605Z,1716419813.605 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Initialize. 2024-05-22T23:16:53.605Z,1716419813.605 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Running Loop=1 2024-05-22T23:16:53.981Z,1716419813.981 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1 2024-05-22T23:16:53.982Z,1716419813.982 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A](INFO): Moving to 1.250000 m 2024-05-22T23:16:53.982Z,1716419813.982 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Stopped 2024-05-22T23:16:53.982Z,1716419813.982 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Running Loop=1 2024-05-22T23:16:53.982Z,1716419813.982 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Initialize. 2024-05-22T23:16:55.802Z,1716419815.802 [CTD_Seabird](ERROR): Failed to parse device response: 2024-05-22T23:17:03.230Z,1716419823.230 [WetLabsUBAT](FAULT): UBAT flow rate is below the specified threshold of 0.05 l/s. 2024-05-22T23:17:04.089Z,1716419824.089 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T23:17:04.120Z,1716419824.120 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-22T23:17:11.984Z,1716419831.984 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for beam range. Device response is::RA, 0.00, 94.97, 101.17 2024-05-22T23:17:14.536Z,1716419834.536 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T23:17:16.411Z,1716419836.411 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-22T23:17:25.265Z,1716419845.265 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T23:17:29.291Z,1716419849.291 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-22T23:17:35.749Z,1716419855.749 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T23:17:42.447Z,1716419862.447 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-22T23:17:46.516Z,1716419866.516 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T23:17:55.442Z,1716419875.442 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-22T23:17:57.268Z,1716419877.268 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T23:18:02.506Z,1716419882.506 [DockingStepper](INFO): Commanding positive:3078 2024-05-22T23:18:07.708Z,1716419887.708 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T23:18:08.775Z,1716419888.775 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-22T23:18:18.422Z,1716419898.422 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T23:18:22.365Z,1716419902.365 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-22T23:18:23.561Z,1716419903.561 [WetLabsUBAT](ERROR): Failed to parse incomplete device message. 2024-05-22T23:18:34.472Z,1716419914.472 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T23:18:35.181Z,1716419915.181 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-22T23:18:44.949Z,1716419924.949 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T23:18:48.585Z,1716419928.585 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-22T23:18:55.092Z,1716419935.092 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T23:19:01.957Z,1716419941.957 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-22T23:19:05.604Z,1716419945.604 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T23:19:14.881Z,1716419954.881 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-22T23:19:15.844Z,1716419955.844 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T23:19:26.362Z,1716419966.362 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T23:19:36.856Z,1716419976.856 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T23:19:46.876Z,1716419986.876 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T23:19:57.305Z,1716419997.305 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T23:20:10.229Z,1716420010.229 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T23:20:24.156Z,1716420024.156 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T23:20:34.470Z,1716420034.470 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T23:20:44.800Z,1716420044.800 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T23:20:54.812Z,1716420054.812 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T23:21:05.200Z,1716420065.200 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T23:21:15.281Z,1716420075.281 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T23:21:15.734Z,1716420075.734 [DockingStepper](ERROR): only read 0 of 4 ADC values. Response is: ÿ/0@ 2024-05-22T23:21:15.735Z,1716420075.735 [DockingStepper](FAULT): Docking arm not reporting position 2024-05-22T23:21:15.735Z,1716420075.735 [DockingStepper] Communications Fault, FailCount= 1 2024-05-22T23:21:15.735Z,1716420075.735 [DockingStepper](ERROR): Communications Fault 2024-05-22T23:21:15.765Z,1716420075.765 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-22T23:21:16.131Z,1716420076.131 [DockingStepper](INFO): Stop 2024-05-22T23:21:16.131Z,1716420076.131 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-22T23:21:16.131Z,1716420076.131 [DockingStepper](INFO): Powering down 2024-05-22T23:21:16.595Z,1716420076.595 [DockingStepper](INFO): Stopping 2024-05-22T23:21:16.981Z,1716420076.981 [DockingStepper](INFO): Stopped 2024-05-22T23:21:17.048Z,1716420077.048 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-22T23:21:17.048Z,1716420077.048 [DockingStepper] No Fault, FailCount= 1 2024-05-22T23:21:17.518Z,1716420077.518 [DockingStepper](INFO): Start 2024-05-22T23:21:17.518Z,1716420077.518 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-22T23:21:17.523Z,1716420077.523 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-22T23:21:17.817Z,1716420077.817 [DockingStepper](INFO): Starting 2024-05-22T23:21:18.225Z,1716420078.225 [DockingStepper](INFO): Starting 2024-05-22T23:21:18.661Z,1716420078.661 [DockingStepper](INFO): Starting 2024-05-22T23:21:18.705Z,1716420078.705 [DockingStepper](INFO): Init string: /1z16107aE13561N3m50 2024-05-22T23:21:19.007Z,1716420079.007 [DockingStepper](INFO): Starting 2024-05-22T23:21:19.379Z,1716420079.379 [DockingStepper](INFO): Starting 2024-05-22T23:21:19.843Z,1716420079.843 [DockingStepper](INFO): Starting 2024-05-22T23:21:20.209Z,1716420080.209 [DockingStepper](INFO): Starting 2024-05-22T23:21:20.236Z,1716420080.236 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-22T23:21:20.576Z,1716420080.576 [DockingStepper](INFO): Starting 2024-05-22T23:21:24.618Z,1716420084.618 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-22T23:21:25.432Z,1716420085.432 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T23:21:35.832Z,1716420095.832 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T23:21:37.535Z,1716420097.535 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-22T23:21:45.917Z,1716420105.917 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T23:21:50.117Z,1716420110.117 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-22T23:21:55.491Z,1716420115.491 [DockingStepper](ERROR): only read 0 of 4 ADC values. Response is: ÿ/0@ 2024-05-22T23:21:55.491Z,1716420115.491 [DockingStepper](FAULT): Docking arm not reporting position 2024-05-22T23:21:55.512Z,1716420115.512 [DockingStepper] Communications Fault, FailCount= 1 2024-05-22T23:21:55.512Z,1716420115.512 [DockingStepper](ERROR): Communications Fault 2024-05-22T23:21:55.522Z,1716420115.522 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-22T23:21:55.929Z,1716420115.929 [DockingStepper](INFO): Stop 2024-05-22T23:21:55.929Z,1716420115.929 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-22T23:21:55.929Z,1716420115.929 [DockingStepper](INFO): Powering down 2024-05-22T23:21:56.325Z,1716420116.325 [DockingStepper](INFO): Stopping 2024-05-22T23:21:56.360Z,1716420116.360 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T23:21:56.682Z,1716420116.682 [DockingStepper](INFO): Stopped 2024-05-22T23:21:56.686Z,1716420116.686 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-22T23:21:56.686Z,1716420116.686 [DockingStepper] No Fault, FailCount= 1 2024-05-22T23:21:57.133Z,1716420117.133 [DockingStepper](INFO): Start 2024-05-22T23:21:57.133Z,1716420117.133 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-22T23:21:57.133Z,1716420117.133 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-22T23:21:57.511Z,1716420117.511 [DockingStepper](INFO): Starting 2024-05-22T23:21:57.926Z,1716420117.926 [DockingStepper](INFO): Starting 2024-05-22T23:21:58.335Z,1716420118.335 [DockingStepper](INFO): Starting 2024-05-22T23:21:58.413Z,1716420118.413 [DockingStepper](INFO): Init string: /1z16111aE13561N3m50 2024-05-22T23:21:58.781Z,1716420118.781 [DockingStepper](INFO): Starting 2024-05-22T23:21:59.203Z,1716420119.203 [DockingStepper](INFO): Starting 2024-05-22T23:21:59.538Z,1716420119.538 [DockingStepper](INFO): Starting 2024-05-22T23:21:59.950Z,1716420119.950 [DockingStepper](INFO): Starting 2024-05-22T23:21:59.982Z,1716420119.982 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-22T23:22:00.394Z,1716420120.394 [DockingStepper](INFO): Starting 2024-05-22T23:22:03.254Z,1716420123.254 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-22T23:22:16.392Z,1716420136.392 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-22T23:22:22.546Z,1716420142.546 [DockingStepper](ERROR): only read 0 of 4 ADC values. Response is: ÿ/0@ 2024-05-22T23:22:22.547Z,1716420142.547 [DockingStepper](FAULT): Docking arm not reporting position 2024-05-22T23:22:22.547Z,1716420142.547 [DockingStepper] Communications Fault, FailCount= 1 2024-05-22T23:22:22.547Z,1716420142.547 [DockingStepper](ERROR): Communications Fault 2024-05-22T23:22:22.551Z,1716420142.551 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-22T23:22:22.973Z,1716420142.973 [DockingStepper](INFO): Stop 2024-05-22T23:22:22.973Z,1716420142.973 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-22T23:22:22.973Z,1716420142.973 [DockingStepper](INFO): Powering down 2024-05-22T23:22:23.373Z,1716420143.373 [DockingStepper](INFO): Stopping 2024-05-22T23:22:23.753Z,1716420143.753 [DockingStepper](INFO): Stopped 2024-05-22T23:22:23.813Z,1716420143.813 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-22T23:22:23.813Z,1716420143.813 [DockingStepper] No Fault, FailCount= 1 2024-05-22T23:22:24.173Z,1716420144.173 [DockingStepper](INFO): Start 2024-05-22T23:22:24.173Z,1716420144.173 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-22T23:22:24.173Z,1716420144.173 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-22T23:22:24.573Z,1716420144.573 [DockingStepper](INFO): Starting 2024-05-22T23:22:24.955Z,1716420144.955 [DockingStepper](INFO): Starting 2024-05-22T23:22:25.397Z,1716420145.397 [DockingStepper](INFO): Starting 2024-05-22T23:22:25.444Z,1716420145.444 [DockingStepper](INFO): Init string: /1z16110aE13561N3m50 2024-05-22T23:22:25.781Z,1716420145.781 [DockingStepper](INFO): Starting 2024-05-22T23:22:26.197Z,1716420146.197 [DockingStepper](INFO): Starting 2024-05-22T23:22:26.593Z,1716420146.593 [DockingStepper](INFO): Starting 2024-05-22T23:22:26.983Z,1716420146.983 [DockingStepper](INFO): Starting 2024-05-22T23:22:27.012Z,1716420147.012 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-22T23:22:27.409Z,1716420147.409 [DockingStepper](INFO): Starting 2024-05-22T23:22:27.624Z,1716420147.624 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T23:22:29.722Z,1716420149.722 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-22T23:22:42.656Z,1716420162.656 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-22T23:22:54.798Z,1716420174.798 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-22T23:22:55.747Z,1716420175.747 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.389374 2024-05-22T23:22:56.749Z,1716420176.749 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T23:23:08.145Z,1716420188.145 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-22T23:23:20.654Z,1716420200.654 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-22T23:23:26.034Z,1716420206.034 [DockingStepper](ERROR): only read 0 of 4 ADC values. Response is: ÿ/0@ 2024-05-22T23:23:26.034Z,1716420206.034 [DockingStepper](FAULT): Docking arm not reporting position 2024-05-22T23:23:26.035Z,1716420206.035 [DockingStepper] Communications Fault, FailCount= 1 2024-05-22T23:23:26.035Z,1716420206.035 [DockingStepper](ERROR): Communications Fault 2024-05-22T23:23:26.047Z,1716420206.047 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-22T23:23:26.355Z,1716420206.355 [DockingStepper](INFO): Stop 2024-05-22T23:23:26.355Z,1716420206.355 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-22T23:23:26.356Z,1716420206.356 [DockingStepper](INFO): Powering down 2024-05-22T23:23:26.761Z,1716420206.761 [DockingStepper](INFO): Stopping 2024-05-22T23:23:26.869Z,1716420206.869 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-22T23:23:27.158Z,1716420207.158 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD, -0.76, -3 -0.26, 8.60, 0.00 2024-05-22T23:23:27.232Z,1716420207.232 [DockingStepper](INFO): Stopped 2024-05-22T23:23:27.264Z,1716420207.264 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-22T23:23:27.264Z,1716420207.264 [DockingStepper] No Fault, FailCount= 1 2024-05-22T23:23:27.563Z,1716420207.563 [DockingStepper](INFO): Start 2024-05-22T23:23:27.564Z,1716420207.564 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-22T23:23:27.565Z,1716420207.565 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-22T23:23:27.873Z,1716420207.873 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T23:23:27.994Z,1716420207.994 [DockingStepper](INFO): Starting 2024-05-22T23:23:28.361Z,1716420208.361 [DockingStepper](INFO): Starting 2024-05-22T23:23:28.778Z,1716420208.778 [DockingStepper](INFO): Starting 2024-05-22T23:23:28.823Z,1716420208.823 [DockingStepper](INFO): Init string: /1z16096aE13561N3m50 2024-05-22T23:23:29.187Z,1716420209.187 [DockingStepper](INFO): Starting 2024-05-22T23:23:29.583Z,1716420209.583 [DockingStepper](INFO): Starting 2024-05-22T23:23:30.019Z,1716420210.019 [DockingStepper](INFO): Starting 2024-05-22T23:23:30.405Z,1716420210.405 [DockingStepper](INFO): Starting 2024-05-22T23:23:30.443Z,1716420210.443 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-22T23:23:30.817Z,1716420210.817 [DockingStepper](INFO): Starting 2024-05-22T23:23:32.620Z,1716420212.620 [Radio_Surface](INFO): Stop 2024-05-22T23:23:32.620Z,1716420212.620 [Radio_Surface](INFO): Powering down 2024-05-22T23:23:33.024Z,1716420213.024 [Radio_Surface](INFO): Stopping 2024-05-22T23:23:33.428Z,1716420213.428 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:33.832Z,1716420213.832 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:34.028Z,1716420214.028 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-22T23:23:34.236Z,1716420214.236 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:34.640Z,1716420214.640 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:35.044Z,1716420215.044 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:35.448Z,1716420215.448 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:35.852Z,1716420215.852 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:36.256Z,1716420216.256 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:36.660Z,1716420216.660 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:37.064Z,1716420217.064 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:37.469Z,1716420217.469 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:37.872Z,1716420217.872 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:38.276Z,1716420218.276 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:38.680Z,1716420218.680 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:39.086Z,1716420219.086 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:39.488Z,1716420219.488 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:39.896Z,1716420219.896 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:40.296Z,1716420220.296 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:40.700Z,1716420220.700 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:41.105Z,1716420221.105 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:41.511Z,1716420221.511 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:41.912Z,1716420221.912 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:42.316Z,1716420222.316 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:42.720Z,1716420222.720 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:43.126Z,1716420223.126 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:43.528Z,1716420223.528 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:43.932Z,1716420223.932 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:44.336Z,1716420224.336 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:44.740Z,1716420224.740 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:45.145Z,1716420225.145 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:45.548Z,1716420225.548 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:45.952Z,1716420225.952 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:46.356Z,1716420226.356 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:46.760Z,1716420226.760 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:47.165Z,1716420227.165 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:47.570Z,1716420227.570 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:47.972Z,1716420227.972 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:48.376Z,1716420228.376 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:48.781Z,1716420228.781 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:49.184Z,1716420229.184 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:49.588Z,1716420229.588 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:49.992Z,1716420229.992 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:50.396Z,1716420230.396 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:50.800Z,1716420230.800 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:51.205Z,1716420231.205 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:51.608Z,1716420231.608 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:52.013Z,1716420232.013 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:52.416Z,1716420232.416 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:52.820Z,1716420232.820 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:53.225Z,1716420233.225 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:53.631Z,1716420233.631 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:54.032Z,1716420234.032 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:54.436Z,1716420234.436 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:54.840Z,1716420234.840 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:55.246Z,1716420235.246 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:55.648Z,1716420235.648 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:56.052Z,1716420236.052 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:56.456Z,1716420236.456 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:56.860Z,1716420236.860 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:57.264Z,1716420237.264 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:57.668Z,1716420237.668 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:57.996Z,1716420237.996 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-22T23:23:58.072Z,1716420238.072 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:58.477Z,1716420238.477 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:58.880Z,1716420238.880 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:59.000Z,1716420239.000 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2024-05-22T23:23:59.001Z,1716420239.001 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T23:23:59.284Z,1716420239.284 [Radio_Surface](INFO): Stopped 2024-05-22T23:23:59.688Z,1716420239.688 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:00.093Z,1716420240.093 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:00.497Z,1716420240.497 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:00.900Z,1716420240.900 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:01.304Z,1716420241.304 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:01.708Z,1716420241.708 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:02.112Z,1716420242.112 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:02.517Z,1716420242.517 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:02.920Z,1716420242.920 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:03.324Z,1716420243.324 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:03.728Z,1716420243.728 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:04.132Z,1716420244.132 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:04.536Z,1716420244.536 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:04.941Z,1716420244.941 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:05.344Z,1716420245.344 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:05.748Z,1716420245.748 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:06.152Z,1716420246.152 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:06.556Z,1716420246.556 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:06.960Z,1716420246.960 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:07.364Z,1716420247.364 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:07.768Z,1716420247.768 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:08.172Z,1716420248.172 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:08.576Z,1716420248.576 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:08.980Z,1716420248.980 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:09.384Z,1716420249.384 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:09.788Z,1716420249.788 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:10.192Z,1716420250.192 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:10.596Z,1716420250.596 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:11.000Z,1716420251.000 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:11.404Z,1716420251.404 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:11.809Z,1716420251.809 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:12.212Z,1716420252.212 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:12.617Z,1716420252.617 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:13.020Z,1716420253.020 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:13.424Z,1716420253.424 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:13.828Z,1716420253.828 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:14.232Z,1716420254.232 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:14.636Z,1716420254.636 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:15.040Z,1716420255.040 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:15.444Z,1716420255.444 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:15.848Z,1716420255.848 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:16.252Z,1716420256.252 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:16.656Z,1716420256.656 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:17.060Z,1716420257.060 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:17.464Z,1716420257.464 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:17.868Z,1716420257.868 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:18.272Z,1716420258.272 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:18.676Z,1716420258.676 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:19.080Z,1716420259.080 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:19.484Z,1716420259.484 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:19.888Z,1716420259.888 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:20.292Z,1716420260.292 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:20.696Z,1716420260.696 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:21.100Z,1716420261.100 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:21.506Z,1716420261.506 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:21.908Z,1716420261.908 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:22.314Z,1716420262.314 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:22.716Z,1716420262.716 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:23.120Z,1716420263.120 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:23.524Z,1716420263.524 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:23.928Z,1716420263.928 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:24.332Z,1716420264.332 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:24.736Z,1716420264.736 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:25.141Z,1716420265.141 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:25.545Z,1716420265.545 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:25.948Z,1716420265.948 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:26.352Z,1716420266.352 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:26.757Z,1716420266.757 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:27.160Z,1716420267.160 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:27.564Z,1716420267.564 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:27.968Z,1716420267.968 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:28.372Z,1716420268.372 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:28.777Z,1716420268.777 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:29.129Z,1716420269.129 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-22T23:24:29.183Z,1716420269.183 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:29.584Z,1716420269.584 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:29.989Z,1716420269.989 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:30.135Z,1716420270.135 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T23:24:30.392Z,1716420270.392 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:30.797Z,1716420270.797 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:31.201Z,1716420271.201 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:31.605Z,1716420271.605 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:32.008Z,1716420272.008 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:32.412Z,1716420272.412 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:32.817Z,1716420272.817 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:33.220Z,1716420273.220 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:33.624Z,1716420273.624 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:34.028Z,1716420274.028 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:34.432Z,1716420274.432 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:34.837Z,1716420274.837 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:35.241Z,1716420275.241 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:35.644Z,1716420275.644 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:36.049Z,1716420276.049 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:36.453Z,1716420276.453 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:36.857Z,1716420276.857 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:37.266Z,1716420277.266 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:37.668Z,1716420277.668 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:38.072Z,1716420278.072 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:38.478Z,1716420278.478 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:38.881Z,1716420278.881 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:39.284Z,1716420279.284 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:39.689Z,1716420279.689 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:40.093Z,1716420280.093 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:40.496Z,1716420280.496 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:40.901Z,1716420280.901 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:41.304Z,1716420281.304 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:41.708Z,1716420281.708 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:42.026Z,1716420282.026 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 0.00, 88.43, 84.30, 0.00 2024-05-22T23:24:42.112Z,1716420282.112 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:42.516Z,1716420282.516 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:42.921Z,1716420282.921 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:43.324Z,1716420283.324 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:43.728Z,1716420283.728 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:44.133Z,1716420284.133 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:44.536Z,1716420284.536 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:44.810Z,1716420284.810 [DockingStepper](ERROR): only read 0 of 4 ADC values. Response is: ÿ/0@ 2024-05-22T23:24:44.810Z,1716420284.810 [DockingStepper](FAULT): Docking arm not reporting position 2024-05-22T23:24:44.810Z,1716420284.810 [DockingStepper] Communications Fault, FailCount= 1 2024-05-22T23:24:44.810Z,1716420284.810 [DockingStepper](ERROR): Communications Fault 2024-05-22T23:24:44.818Z,1716420284.818 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-22T23:24:44.940Z,1716420284.940 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:45.226Z,1716420285.226 [DockingStepper](INFO): Stop 2024-05-22T23:24:45.226Z,1716420285.226 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-22T23:24:45.226Z,1716420285.226 [DockingStepper](INFO): Powering down 2024-05-22T23:24:45.348Z,1716420285.348 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:45.594Z,1716420285.594 [DockingStepper](INFO): Stopping 2024-05-22T23:24:45.748Z,1716420285.748 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:46.074Z,1716420286.074 [DockingStepper](INFO): Stopped 2024-05-22T23:24:46.119Z,1716420286.119 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-22T23:24:46.119Z,1716420286.119 [DockingStepper] No Fault, FailCount= 1 2024-05-22T23:24:46.152Z,1716420286.152 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:46.453Z,1716420286.453 [DockingStepper](INFO): Start 2024-05-22T23:24:46.453Z,1716420286.453 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-22T23:24:46.453Z,1716420286.453 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-22T23:24:46.556Z,1716420286.556 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:46.868Z,1716420286.868 [DockingStepper](INFO): Starting 2024-05-22T23:24:46.960Z,1716420286.960 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:47.294Z,1716420287.294 [DockingStepper](INFO): Starting 2024-05-22T23:24:47.368Z,1716420287.368 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:47.651Z,1716420287.651 [DockingStepper](INFO): Starting 2024-05-22T23:24:47.712Z,1716420287.712 [DockingStepper](INFO): Init string: /1z16098aE13561N3m50 2024-05-22T23:24:47.772Z,1716420287.772 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:48.113Z,1716420288.113 [DockingStepper](INFO): Starting 2024-05-22T23:24:48.176Z,1716420288.176 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:48.455Z,1716420288.455 [DockingStepper](INFO): Starting 2024-05-22T23:24:48.580Z,1716420288.580 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:48.844Z,1716420288.844 [DockingStepper](INFO): Starting 2024-05-22T23:24:48.985Z,1716420288.985 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:49.268Z,1716420289.268 [DockingStepper](INFO): Starting 2024-05-22T23:24:49.297Z,1716420289.297 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-22T23:24:49.388Z,1716420289.388 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:49.688Z,1716420289.688 [DockingStepper](INFO): Starting 2024-05-22T23:24:49.792Z,1716420289.792 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:50.198Z,1716420290.198 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:50.600Z,1716420290.600 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:51.004Z,1716420291.004 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:51.408Z,1716420291.408 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:51.813Z,1716420291.813 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:52.216Z,1716420292.216 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:52.466Z,1716420292.466 [ballast_and_trim](INFO): Timed out from 2024-05-22T23:16:52.4Z 2024-05-22T23:24:52.466Z,1716420292.466 [MissionManager](INFO): ballast_and_trim is completed. 2024-05-22T23:24:52.466Z,1716420292.466 [MissionManager](INFO): Uninitializing Mission ballast_and_trim 2024-05-22T23:24:52.466Z,1716420292.466 [ballast_and_trim] Stopped 2024-05-22T23:24:52.466Z,1716420292.466 [ballast_and_trim](DEBUG): Aggregate::uninitialize ballast_and_trim 2024-05-22T23:24:52.466Z,1716420292.466 [ballast_and_trim:Science] Stopped 2024-05-22T23:24:52.466Z,1716420292.466 [ballast_and_trim:Science](DEBUG): Aggregate::uninitialize ballast_and_trim:Science 2024-05-22T23:24:52.466Z,1716420292.466 [ballast_and_trim:Science:B] Stopped 2024-05-22T23:24:52.466Z,1716420292.466 [ballast_and_trim:Science:C] Stopped 2024-05-22T23:24:52.466Z,1716420292.466 [ballast_and_trim:Science:E] Stopped 2024-05-22T23:24:52.466Z,1716420292.466 [ballast_and_trim:Science:F] Stopped 2024-05-22T23:24:52.466Z,1716420292.466 [ballast_and_trim:Science:OceanCurrent] Stopped 2024-05-22T23:24:52.467Z,1716420292.467 [ballast_and_trim:Science:OceanCurrent](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:OceanCurrent 2024-05-22T23:24:52.467Z,1716420292.467 [ballast_and_trim:Science:OceanCurrent:A.] Stopped 2024-05-22T23:24:52.467Z,1716420292.467 [ballast_and_trim:BackseatDriver] Stopped 2024-05-22T23:24:52.467Z,1716420292.467 [ballast_and_trim:BackseatDriver](DEBUG): Aggregate::uninitialize ballast_and_trim:BackseatDriver 2024-05-22T23:24:52.468Z,1716420292.468 [ballast_and_trim:BackseatDriver:A.BackseatDriver] Stopped 2024-05-22T23:24:52.468Z,1716420292.468 [ballast_and_trim:PowerOnly] Stopped 2024-05-22T23:24:52.468Z,1716420292.468 [ballast_and_trim:PowerOnly](DEBUG): Aggregate::uninitialize ballast_and_trim:PowerOnly 2024-05-22T23:24:52.468Z,1716420292.468 [ballast_and_trim:PowerOnly:E.Wait] Stopped 2024-05-22T23:24:52.468Z,1716420292.468 [ballast_and_trim:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2024-05-22T23:24:52.468Z,1716420292.468 [ballast_and_trim:RunBallastAndTrim] Stopped 2024-05-22T23:24:52.468Z,1716420292.468 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim 2024-05-22T23:24:52.468Z,1716420292.468 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim] Stopped 2024-05-22T23:24:52.468Z,1716420292.468 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim 2024-05-22T23:24:52.468Z,1716420292.468 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Stopped 2024-05-22T23:24:52.469Z,1716420292.469 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Stopped 2024-05-22T23:24:52.469Z,1716420292.469 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Stopped 2024-05-22T23:24:52.469Z,1716420292.469 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Stopped 2024-05-22T23:24:52.469Z,1716420292.469 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Stopped 2024-05-22T23:24:52.469Z,1716420292.469 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Stopped 2024-05-22T23:24:52.469Z,1716420292.469 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth 2024-05-22T23:24:52.469Z,1716420292.469 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Stopped 2024-05-22T23:24:52.469Z,1716420292.469 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Stopped 2024-05-22T23:24:52.469Z,1716420292.469 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth 2024-05-22T23:24:52.469Z,1716420292.469 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Stopped 2024-05-22T23:24:52.469Z,1716420292.469 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Uninitialize. 2024-05-22T23:24:52.469Z,1716420292.469 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Stopped 2024-05-22T23:24:52.469Z,1716420292.469 [ballast_and_trim:RunBallastAndTrim:Depth1] Stopped 2024-05-22T23:24:52.469Z,1716420292.469 [ballast_and_trim:RunBallastAndTrim:Depth1](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1 2024-05-22T23:24:52.469Z,1716420292.469 [ballast_and_trim:RunBallastAndTrim:Depth1:B] Stopped 2024-05-22T23:24:52.470Z,1716420292.470 [ballast_and_trim:RunBallastAndTrim:Depth1:B](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1:B 2024-05-22T23:24:52.569Z,1716420292.569 [CommandExec](IMPORTANT): got command load ./Missions/Engineering/OnDock.tl 2024-05-22T23:24:52.596Z,1716420292.596 [WetLabsUBAT](INFO): Powering down 2024-05-22T23:24:52.608Z,1716420292.608 [MissionManager](INFO): Loading Mission from file: ./Missions/Engineering/OnDock.tl 2024-05-22T23:24:52.608Z,1716420292.608 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Engineering/OnDock.tl 2024-05-22T23:24:52.610Z,1716420292.610 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Engineering/OnDock.tl 2024-05-22T23:24:52.620Z,1716420292.620 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:53.004Z,1716420293.004 [WetLabsBB2FL](INFO): Powering down 2024-05-22T23:24:53.025Z,1716420293.025 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:53.436Z,1716420293.436 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:53.483Z,1716420293.483 [MissionManager](DEBUG): TethyslAPI: ./Missions/Engineering/OnDock.tl translated into: 8 2 1 5 2 30 5 Skipping DHCP restart to test autonetwork Restarting logs restart logs ! ifconfig Mission ending, breaking off. Range: . 2024-05-22T23:24:53.488Z,1716420293.488 [MissionManager](INFO): DefineArg OnDock.DockedTime = 8.000000 h 2024-05-22T23:24:53.490Z,1716420293.490 [MissionManager](INFO): DefineArg OnDock.DetachedTimeout = 28800.000000 s 2024-05-22T23:24:53.494Z,1716420293.494 [MissionManager](INFO): DefineArg OnDock.RangeTimeout = 600.000000 s 2024-05-22T23:24:53.497Z,1716420293.497 [MissionManager](INFO): DefineArg OnDock.TrackingUpdatePeriod = 2.000000 min 2024-05-22T23:24:53.502Z,1716420293.502 [MissionManager](INFO): DefineArg OnDock.NumberOfPings = 1.000000 count 2024-05-22T23:24:53.508Z,1716420293.508 [MissionManager](INFO): DefineArg OnDock.TransponderCode = 201 count 2024-05-22T23:24:53.512Z,1716420293.512 [MissionManager](INFO): DefineArg OnDock.MaxDepth = 215.000000 m 2024-05-22T23:24:53.515Z,1716420293.515 [MissionManager](INFO): DefineArg OnDock.MinAltitude = 5.000000 m 2024-05-22T23:24:53.518Z,1716420293.518 [MissionManager](INFO): DefineArg OnDock.MinOffshore = 2.000000 km 2024-05-22T23:24:53.522Z,1716420293.522 [MissionManager](INFO): DefineArg OnDock.StayOnDock = 0 bool 2024-05-22T23:24:53.526Z,1716420293.526 [MissionManager](INFO): DefineArg OnDock.IfconfigInterval = 30.000000 min 2024-05-22T23:24:53.530Z,1716420293.530 [MissionManager](INFO): DefineArg OnDock.MissionTimeout = 8.000000 h 2024-05-22T23:24:53.530Z,1716420293.530 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl 2024-05-22T23:24:53.530Z,1716420293.530 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl 2024-05-22T23:24:53.531Z,1716420293.531 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl 2024-05-22T23:24:53.736Z,1716420293.736 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into: 5 0 200 2000 2024-05-22T23:24:53.769Z,1716420293.769 [MissionManager](INFO): DefineArg OnDock:StandardEnvelopes.MinAltitude = 5.000000 m 2024-05-22T23:24:53.771Z,1716420293.771 [MissionManager](INFO): DefineArg OnDock:StandardEnvelopes.MaxDepthIgnore = 0.000000 m 2024-05-22T23:24:53.775Z,1716420293.775 [MissionManager](INFO): DefineArg OnDock:StandardEnvelopes.MaxDepth = 200.000000 m 2024-05-22T23:24:53.783Z,1716420293.783 [MissionManager](INFO): DefineArg OnDock:StandardEnvelopes.MinOffshore = 2000.000000 m 2024-05-22T23:24:53.788Z,1716420293.788 [OnDock:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2024-05-22T23:24:53.798Z,1716420293.798 [OnDock:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2024-05-22T23:24:53.835Z,1716420293.835 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2024-05-22T23:24:53.842Z,1716420293.842 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:53.893Z,1716420293.893 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl 2024-05-22T23:24:53.893Z,1716420293.893 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl 2024-05-22T23:24:53.902Z,1716420293.902 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl 2024-05-22T23:24:53.934Z,1716420293.934 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into: 2024-05-22T23:24:53.937Z,1716420293.937 [MissionManager](INFO): DefineArg OnDock:BackseatDriver.EnableBackseat = 0 bool 2024-05-22T23:24:53.939Z,1716420293.939 [OnDock:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver. 2024-05-22T23:24:53.941Z,1716420293.941 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl 2024-05-22T23:24:53.942Z,1716420293.942 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl 2024-05-22T23:24:53.943Z,1716420293.943 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl 2024-05-22T23:24:54.063Z,1716420294.063 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into: 99999 2024-05-22T23:24:54.067Z,1716420294.067 [MissionManager](INFO): DefineArg OnDock:PowerOnly.SampleAll = 0 bool 2024-05-22T23:24:54.069Z,1716420294.069 [MissionManager](INFO): DefineArg OnDock:PowerOnly.SampleLoad1 = 0 bool 2024-05-22T23:24:54.071Z,1716420294.071 [MissionManager](INFO): DefineArg OnDock:PowerOnly.SampleLoad2 = 0 bool 2024-05-22T23:24:54.073Z,1716420294.073 [MissionManager](INFO): DefineArg OnDock:PowerOnly.SampleLoad3 = 0 bool 2024-05-22T23:24:54.075Z,1716420294.075 [MissionManager](INFO): DefineArg OnDock:PowerOnly.EnabledPowerOnly = 0 bool 2024-05-22T23:24:54.210Z,1716420294.210 [OnDock:PowerOnly:E.Wait](DEBUG): Construct Wait. 2024-05-22T23:24:54.244Z,1716420294.244 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:54.300Z,1716420294.300 [OnDock:L.Execute](DEBUG): Construct Execute. 2024-05-22T23:24:54.303Z,1716420294.303 [OnDock:PeriodicCheck:CheckIP:A.Execute](DEBUG): Construct Execute. 2024-05-22T23:24:54.317Z,1716420294.317 [OnDock:PeriodicCheck:CheckIP:B.Wait](DEBUG): Construct Wait. 2024-05-22T23:24:54.319Z,1716420294.319 [OnDock:O.Docked](DEBUG): Construct. 2024-05-22T23:24:54.319Z,1716420294.319 [OnDock:O.Docked](DEBUG): Initializing internal variables to default values. 2024-05-22T23:24:54.366Z,1716420294.366 [OnDock:Detach:B.Undock](DEBUG): Construct. 2024-05-22T23:24:54.366Z,1716420294.366 [OnDock:Detach:B.Undock](DEBUG): Initializing internal variables to default values. 2024-05-22T23:24:54.405Z,1716420294.405 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission { """ Wait on dock, communicating when possible """ arguments { DockedTime = 8 hour """ Time to stay on dock. Mission timeout will be five minutes longer. """ DetachedTimeout = Dock:Docked.detachTimeout """ Time duration limit for determining if the vehicle is detached from the dock. """ RangeTimeout = Dock:Docked.rangeTimeout """ Maximum allowed time without acoustic range. """ TrackingUpdatePeriod = 2 minute """ How long to wait between acoustic queries. """ NumberOfPings = 1 count """ Number of pings requested each time. """ TransponderCode = Dock:Dock.transponderCode """ Transponder Address. """ MaxDepth = BIT:CBIT.stopDepth """ Maximum depth while docked. """ MinAltitude = 5 meter """ Minimum altitude """ MinOffshore = 2 km """ Minimum offshore """ StayOnDock = false """ If true, don't detach from dock at end of mission """ IfconfigInterval = 30 minute """ How long to wait between ifconfig runs """ # Mission variables (do not change) MissionTimeout = DockedTime """ Mission timeout (local variable, do not change) """ } timeout duration=MissionTimeout insert Insert/StandardEnvelopes.tl assign in sequence StandardEnvelopes:MinAltitude = MinAltitude assign in sequence StandardEnvelopes:MaxDepth = MaxDepth assign in sequence StandardEnvelopes:MinOffshore = MinOffshore # End docking 5 minutes before end of mission to allow clean undock assign in sequence MissionTimeout = DockedTime + 5 minute insert Insert/BackseatDriver.tl insert Insert/PowerOnly.tl # Send data when connection exists readData { while ( Sensor:DataOverHttps.connectionStatus == true ) Universal:platform_communications } behavior Estimation:TrackAcousticContact { run in parallel set contactLabelSetting = TransponderCode set numberOfSamplesSetting = NumberOfPings set updatePeriodSetting = TrackingUpdatePeriod } aggregate restartDHCP { run when ( called ) syslog important "Skipping DHCP restart to test autonetwork" # behavior Guidance:Execute { # run in sequence # set command = "! /mnt/mmc/LRAUV/Tools/dhcp.sh" # } } syslog important "Restarting logs" behavior Guidance:Execute { run in sequence set command = "restart logs" } # Kick DHCP again, in case it took us a while to close the arm in Docked call refId="restartDHCP" aggregate PeriodicCheck { run in parallel aggregate CheckIP { run in sequence behavior Guidance:Execute { run in sequence set command = "! ifconfig" } behavior Guidance:Wait { run in sequence set duration = IfconfigInterval } } } behavior Dock:Docked { run in sequence timeout duration=DockedTime set detachTimeout = DetachedTimeout set rangeTimeout = RangeTimeout } # Undock cleanly, instead of relying on DefaultWithUndock to come next aggregate Detach { run in sequence break if ( StayOnDock == true ) syslog important "Mission ending, breaking off. Range: " + Estimation:TrackAcousticContact.range_to_contact~meter + "." behavior Dock:Undock { run in sequence } # Get our 10.89 IP back once we're on the surface call refId="restartDHCP" } } 2024-05-22T23:24:54.412Z,1716420294.412 [CommandExec](IMPORTANT): Loaded ./Missions/Engineering/OnDock.tl id=OnDock 2024-05-22T23:24:54.573Z,1716420294.573 [MissionManager](IMPORTANT): Started mission DefaultTankUndock 2024-05-22T23:24:54.574Z,1716420294.574 [DefaultTankUndock] Running Loop=1 2024-05-22T23:24:54.574Z,1716420294.574 [DefaultTankUndock](DEBUG): Aggregate::initialize DefaultTankUndock 2024-05-22T23:24:54.574Z,1716420294.574 [DefaultTankUndock:B.GoToSurface] Running Loop=1 2024-05-22T23:24:54.574Z,1716420294.574 [DefaultTankUndock:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-05-22T23:24:54.574Z,1716420294.574 [DefaultTankUndock:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-05-22T23:24:54.574Z,1716420294.574 [DefaultTankUndock:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-05-22T23:24:54.575Z,1716420294.575 [DefaultTankUndock:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-05-22T23:24:54.575Z,1716420294.575 [DefaultTankUndock:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-05-22T23:24:54.575Z,1716420294.575 [DefaultTankUndock:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-05-22T23:24:54.584Z,1716420294.584 [DefaultTankUndock:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-05-22T23:24:54.584Z,1716420294.584 [DefaultTankUndock:A.Wait] Running Loop=1 2024-05-22T23:24:54.584Z,1716420294.584 [DefaultTankUndock:A.Wait](DEBUG): Initialize Wait Component. 2024-05-22T23:24:54.649Z,1716420294.649 [Radio_Surface](INFO): Stopped 2024-05-22T23:24:54.666Z,1716420294.666 [DockingStepper](INFO): Deisolating dock charge 2024-05-22T23:24:54.950Z,1716420294.950 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -25.00 mm (1 active estimators). 2024-05-22T23:24:55.052Z,1716420295.052 [Radio_Surface](INFO): Start 2024-05-22T23:24:55.052Z,1716420295.052 [Radio_Surface](INFO): Powering up 2024-05-22T23:24:55.456Z,1716420295.456 [Radio_Surface](INFO): Starting 2024-05-22T23:24:55.860Z,1716420295.860 [Radio_Surface](INFO): Starting 2024-05-22T23:24:56.265Z,1716420296.265 [Radio_Surface](INFO): Starting 2024-05-22T23:24:56.668Z,1716420296.668 [Radio_Surface](INFO): Starting 2024-05-22T23:24:57.072Z,1716420297.072 [Radio_Surface](INFO): Starting 2024-05-22T23:24:57.476Z,1716420297.476 [Radio_Surface](INFO): Starting 2024-05-22T23:24:57.880Z,1716420297.880 [Radio_Surface](INFO): Starting 2024-05-22T23:24:58.285Z,1716420298.285 [Radio_Surface](INFO): Starting 2024-05-22T23:24:58.688Z,1716420298.688 [Radio_Surface](INFO): Starting 2024-05-22T23:24:59.092Z,1716420299.092 [Radio_Surface](INFO): Starting 2024-05-22T23:24:59.482Z,1716420299.482 [CommandExec](IMPORTANT): got command set OnDock.DockedTime 45 minute 2024-05-22T23:24:59.482Z,1716420299.482 [CommandExec](IMPORTANT): got command set OnDock.DetachedTimeout string 2024-05-22T23:24:59.483Z,1716420299.483 [CommandExec](FAULT): Incomplete syntax. Try: help set 2024-05-22T23:24:59.496Z,1716420299.496 [Radio_Surface](INFO): Starting 2024-05-22T23:24:59.900Z,1716420299.900 [Radio_Surface](INFO): Starting 2024-05-22T23:25:00.256Z,1716420300.256 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-22T23:25:00.305Z,1716420300.305 [Radio_Surface](INFO): Starting 2024-05-22T23:25:00.306Z,1716420300.306 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.10 2024-05-22T23:25:00.307Z,1716420300.307 [CommandExec](INFO): Found local IP address 10.89.10.2 for eth0 2024-05-22T23:25:01.261Z,1716420301.261 [DataOverHttps](INFO): Radio surface powered ON. 2024-05-22T23:25:01.261Z,1716420301.261 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T23:25:04.608Z,1716420304.608 [CommandExec](IMPORTANT): got command set OnDock.MinOffshore 0.000001 kilometer 2024-05-22T23:25:04.609Z,1716420304.609 [CommandExec](IMPORTANT): got command set OnDock.IfconfigInterval 5 minute 2024-05-22T23:25:04.610Z,1716420304.610 [CommandExec](IMPORTANT): got command run 2024-05-22T23:25:04.612Z,1716420304.612 [CommandExec](IMPORTANT): Running 2024-05-22T23:25:04.869Z,1716420304.869 [DefaultTankUndock] Stopped 2024-05-22T23:25:04.870Z,1716420304.870 [DefaultTankUndock](DEBUG): Aggregate::uninitialize DefaultTankUndock 2024-05-22T23:25:04.870Z,1716420304.870 [DefaultTankUndock:A.Wait] Stopped 2024-05-22T23:25:04.870Z,1716420304.870 [DefaultTankUndock:A.Wait](DEBUG): Uninitialize Wait Component. 2024-05-22T23:25:04.870Z,1716420304.870 [DefaultTankUndock:B.GoToSurface] Stopped 2024-05-22T23:25:04.870Z,1716420304.870 [DefaultTankUndock:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-05-22T23:25:04.870Z,1716420304.870 [MissionManager](IMPORTANT): Started mission OnDock 2024-05-22T23:25:04.870Z,1716420304.870 [OnDock] Running Loop=1 2024-05-22T23:25:04.870Z,1716420304.870 [OnDock](DEBUG): Aggregate::initialize OnDock 2024-05-22T23:25:04.870Z,1716420304.870 [OnDock:StandardEnvelopes] Running Loop=1 2024-05-22T23:25:04.871Z,1716420304.871 [OnDock:StandardEnvelopes](DEBUG): Aggregate::initialize OnDock:StandardEnvelopes 2024-05-22T23:25:04.871Z,1716420304.871 [OnDock:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2024-05-22T23:25:04.871Z,1716420304.871 [OnDock:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2024-05-22T23:25:04.871Z,1716420304.871 [OnDock:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2024-05-22T23:25:04.871Z,1716420304.871 [OnDock:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2024-05-22T23:25:04.871Z,1716420304.871 [OnDock:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2024-05-22T23:25:04.871Z,1716420304.871 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2024-05-22T23:25:04.871Z,1716420304.871 [OnDock:BackseatDriver] Running Loop=1 2024-05-22T23:25:04.871Z,1716420304.871 [OnDock:BackseatDriver](DEBUG): Aggregate::initialize OnDock:BackseatDriver 2024-05-22T23:25:04.871Z,1716420304.871 [OnDock:BackseatDriver:A.BackseatDriver] Running Loop=1 2024-05-22T23:25:04.873Z,1716420304.873 [OnDock:PowerOnly] Running Loop=1 2024-05-22T23:25:04.873Z,1716420304.873 [OnDock:PowerOnly](DEBUG): Aggregate::initialize OnDock:PowerOnly 2024-05-22T23:25:04.873Z,1716420304.873 [OnDock:PowerOnly:A] Running Loop=1 2024-05-22T23:25:04.873Z,1716420304.873 [OnDock:PowerOnly:B] Running Loop=1 2024-05-22T23:25:04.873Z,1716420304.873 [OnDock:PowerOnly:C] Running Loop=1 2024-05-22T23:25:04.873Z,1716420304.873 [OnDock:PowerOnly:D] Running Loop=1 2024-05-22T23:25:04.873Z,1716420304.873 [OnDock:PowerOnly:E.Wait] Running Loop=1 2024-05-22T23:25:04.873Z,1716420304.873 [OnDock:PowerOnly:E.Wait](DEBUG): Initialize Wait Component. 2024-05-22T23:25:04.874Z,1716420304.874 [OnDock:H] Running Loop=1 2024-05-22T23:25:04.874Z,1716420304.874 [OnDock:I.] Running Loop=1 2024-05-22T23:25:04.874Z,1716420304.874 [OnDock:I.](INFO): Initializing TrackAcousticContact. 2024-05-22T23:25:04.874Z,1716420304.874 [OnDock:PeriodicCheck] Running Loop=1 2024-05-22T23:25:04.874Z,1716420304.874 [OnDock:PeriodicCheck](DEBUG): Aggregate::initialize OnDock:PeriodicCheck 2024-05-22T23:25:04.874Z,1716420304.874 [OnDock:PeriodicCheck:CheckIP] Running Loop=1 2024-05-22T23:25:04.874Z,1716420304.874 [OnDock:PeriodicCheck:CheckIP](DEBUG): Aggregate::initialize OnDock:PeriodicCheck:CheckIP 2024-05-22T23:25:04.874Z,1716420304.874 [OnDock:PeriodicCheck:CheckIP:A.Execute] Running Loop=1 2024-05-22T23:25:04.874Z,1716420304.874 [OnDock:B] Running Loop=1 2024-05-22T23:25:04.875Z,1716420304.875 [OnDock:PeriodicCheck] Running Loop=1 2024-05-22T23:25:04.875Z,1716420304.875 [OnDock:PeriodicCheck:CheckIP:A.Execute](INFO): Executing command ! ifconfig 2024-05-22T23:25:04.875Z,1716420304.875 [OnDock:PeriodicCheck:CheckIP:A.Execute] Stopped 2024-05-22T23:25:04.875Z,1716420304.875 [OnDock:PeriodicCheck:CheckIP:B.Wait] Running Loop=1 2024-05-22T23:25:04.875Z,1716420304.875 [OnDock:PeriodicCheck:CheckIP:B.Wait](DEBUG): Initialize Wait Component. 2024-05-22T23:25:04.875Z,1716420304.875 [OnDock:I.] Running Loop=1 2024-05-22T23:25:04.892Z,1716420304.892 [OnDock:H] Stopped 2024-05-22T23:25:04.892Z,1716420304.892 [OnDock:PowerOnly] Running Loop=1 2024-05-22T23:25:04.892Z,1716420304.892 [OnDock:PowerOnly:D] Stopped 2024-05-22T23:25:04.892Z,1716420304.892 [OnDock:PowerOnly:C] Stopped 2024-05-22T23:25:04.892Z,1716420304.892 [OnDock:PowerOnly:B] Stopped 2024-05-22T23:25:04.892Z,1716420304.892 [OnDock:PowerOnly:A] Stopped 2024-05-22T23:25:04.892Z,1716420304.892 [OnDock:BackseatDriver] Running Loop=1 2024-05-22T23:25:04.893Z,1716420304.893 [OnDock:B] Stopped 2024-05-22T23:25:04.893Z,1716420304.893 [OnDock:C] Running Loop=1 2024-05-22T23:25:04.893Z,1716420304.893 [OnDock:StandardEnvelopes] Running Loop=1 2024-05-22T23:25:04.893Z,1716420304.893 [OnDock:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2024-05-22T23:25:04.894Z,1716420304.894 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](IMPORTANT): Offshore envelope ACTIVE 2024-05-22T23:25:04.917Z,1716420304.917 [OnDock:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2024-05-22T23:25:04.917Z,1716420304.917 [OnDock:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2024-05-22T23:25:04.925Z,1716420304.925 [CommandExec](IMPORTANT): got command ! ifconfig 2024-05-22T23:25:04.956Z,1716420304.956 [HorizontalControl](DEBUG): kpHeading = 0.400000 2024-05-22T23:25:04.957Z,1716420304.957 [HorizontalControl](DEBUG): kiHeading = 0.001000 2024-05-22T23:25:04.957Z,1716420304.957 [HorizontalControl](DEBUG): kdHeading = 0.050000 2024-05-22T23:25:05.030Z,1716420305.030 [DockingStepper](ERROR): only read 0 of 4 ADC values. Response is: ÿ/0@ 2024-05-22T23:25:05.031Z,1716420305.031 [DockingStepper](FAULT): Docking arm not reporting position 2024-05-22T23:25:05.031Z,1716420305.031 [DockingStepper] Communications Fault, FailCount= 1 2024-05-22T23:25:05.031Z,1716420305.031 [DockingStepper](ERROR): Communications Fault 2024-05-22T23:25:05.112Z,1716420305.112 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-22T23:25:05.223Z,1716420305.223 [CommandExec](IMPORTANT): eth0 Link encap:Ethernet HWaddr 50:2D:F4:07:45:4B inet addr:10.89.10.2 Bcast:10.89.255.255 Mask:255.255.0.0 UP BROADCAST RUNNING MULTICAST MTU:1500 Metric:1 RX packets:8710 errors:0 dropped:0 overruns:0 frame:0 TX packets:9677 errors:0 dropped:0 overruns:0 carrier:0 collisions:18 txqueuelen:1000 RX bytes:3310029 (3.1 Mb) TX bytes:1942277 (1.8 Mb) Interrupt:29 lo Link encap:Local Loopback inet addr:127.0.0.1 Mask:255.0.0.0 UP LOOPBACK RUNNING MULTICAST MTU:16436 Metric:1 RX packets:382 errors:0 dropped:0 overruns:0 frame:0 TX packets:382 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:0 RX bytes:31300 (30.5 Kb) TX bytes:31300 (30.5 Kb) 2024-05-22T23:25:05.318Z,1716420305.318 [OnDock:C] Stopped 2024-05-22T23:25:05.319Z,1716420305.319 [OnDock:D] Running Loop=1 2024-05-22T23:25:05.481Z,1716420305.481 [DockingStepper](INFO): Stop 2024-05-22T23:25:05.481Z,1716420305.481 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-22T23:25:05.481Z,1716420305.481 [DockingStepper](INFO): Powering down 2024-05-22T23:25:05.719Z,1716420305.719 [OnDock:D] Stopped 2024-05-22T23:25:05.719Z,1716420305.719 [OnDock:E] Running Loop=1 2024-05-22T23:25:05.777Z,1716420305.777 [DockingStepper](INFO): Stopping 2024-05-22T23:25:06.093Z,1716420306.093 [OnDock:E] Stopped 2024-05-22T23:25:06.093Z,1716420306.093 [OnDock:K] Running Loop=1 2024-05-22T23:25:06.141Z,1716420306.141 [DockingStepper](INFO): Stopped 2024-05-22T23:25:06.344Z,1716420306.344 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-22T23:25:06.344Z,1716420306.344 [DockingStepper] No Fault, FailCount= 1 2024-05-22T23:25:06.500Z,1716420306.500 [OnDock:K](IMPORTANT): Restarting logs 2024-05-22T23:25:06.500Z,1716420306.500 [OnDock:K] Stopped 2024-05-22T23:25:06.500Z,1716420306.500 [OnDock:L.Execute] Running Loop=1 2024-05-22T23:25:06.545Z,1716420306.545 [DockingStepper](INFO): Start 2024-05-22T23:25:06.545Z,1716420306.545 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-22T23:25:06.546Z,1716420306.546 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-22T23:25:06.907Z,1716420306.907 [OnDock:L.Execute](INFO): Executing command restart logs 2024-05-22T23:25:06.908Z,1716420306.908 [OnDock:L.Execute] Stopped 2024-05-22T23:25:06.908Z,1716420306.908 [OnDock:M] Running Loop=1 2024-05-22T23:25:06.908Z,1716420306.908 [OnDock:M](DEBUG): Aggregate::initialize OnDock:M