2024-08-07T20:32:19.619Z,1723062739.619 [CommandExec](IMPORTANT): got command restart logs 2024-08-07T20:32:20.305Z,1723062740.305 [WetLabsUBAT](ERROR): Failed to parse incomplete device message. 2024-08-07T20:32:30.482Z,1723062750.482 [WetLabsUBAT](ERROR): Failed to parse incomplete device message. 2024-08-07T20:32:38.628Z,1723062758.628 [WetLabsUBAT](ERROR): Failed to parse incomplete device message. 2024-08-07T20:32:40.528Z,1723062760.528 [Reporter](INFO): platform_buoyancy_position 996.478699 cc 2024-08-07T20:32:56.666Z,1723062776.666 [DataOverHttps](INFO): Sending 1027 bytes from file Logs/20240807T201821/Express0013.lzma 2024-08-07T20:32:57.667Z,1723062777.667 [DataOverHttps](INFO): Moved sent file to Logs/20240807T201821/Express0013.lzma.bak 2024-08-07T20:32:57.667Z,1723062777.667 [DataOverHttps](INFO): SBD MOMSN=21446032 2024-08-07T20:33:10.890Z,1723062790.890 [Reporter](INFO): platform_buoyancy_position 996.478699 cc 2024-08-07T20:33:17.052Z,1723062797.052 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240807T201821/Courier0015.lzma 2024-08-07T20:33:18.056Z,1723062798.056 [DataOverHttps](INFO): Moved sent file to Logs/20240807T201821/Courier0015.lzma.bak 2024-08-07T20:33:18.056Z,1723062798.056 [DataOverHttps](INFO): SBD MOMSN=21446057 2024-08-07T20:33:19.329Z,1723062799.329 [WetLabsUBAT](ERROR): Failed to parse incomplete device message. 2024-08-07T20:33:29.494Z,1723062809.494 [WetLabsUBAT](ERROR): Failed to parse incomplete device message. 2024-08-07T20:33:39.920Z,1723062819.920 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,24080712345887,35.0, 9.28, 100.31 2024-08-07T20:33:41.090Z,1723062821.090 [Reporter](INFO): platform_buoyancy_position 996.478699 cc 2024-08-07T20:33:43.965Z,1723062823.965 [DataOverHttps](INFO): Sending 107 bytes from file Logs/20240807T201821/Express0016.lzma 2024-08-07T20:33:44.967Z,1723062824.967 [DataOverHttps](INFO): Moved sent file to Logs/20240807T201821/Express0016.lzma.bak 2024-08-07T20:33:44.967Z,1723062824.967 [DataOverHttps](INFO): SBD MOMSN=21446059 2024-08-07T20:33:46.103Z,1723062826.103 [sink:NeedComms:D] Stopped 2024-08-07T20:33:46.103Z,1723062826.103 [sink:NeedComms:E] Running Loop=1 2024-08-07T20:33:46.489Z,1723062826.489 [sink:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix 2024-08-07T20:33:48.096Z,1723062828.096 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203347.00,A,3648.16596,N,12147.28520,W,0.194,139.44,070824,,,D*7E 2024-08-07T20:33:48.122Z,1723062828.122 [NAL9602](INFO): GPS fix at 20240807T203347: (36.802766, -121.788087) 2024-08-07T20:33:48.152Z,1723062828.152 [sink:NeedComms:E] Stopped 2024-08-07T20:33:48.169Z,1723062828.169 [sink:NeedComms](INFO): Completed sink:NeedComms 2024-08-07T20:33:48.169Z,1723062828.169 [sink:NeedComms] Stopped 2024-08-07T20:33:48.169Z,1723062828.169 [sink:NeedComms](DEBUG): Aggregate::uninitialize sink:NeedComms 2024-08-07T20:33:48.169Z,1723062828.169 [sink:NeedComms:B.GoToSurface] Stopped 2024-08-07T20:33:48.169Z,1723062828.169 [sink:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-08-07T20:33:48.595Z,1723062828.595 [sink:StartingMission](INFO): Completed sink:StartingMission 2024-08-07T20:33:48.595Z,1723062828.595 [sink:StartingMission] Stopped 2024-08-07T20:33:48.595Z,1723062828.595 [sink:StartingMission](DEBUG): Aggregate::uninitialize sink:StartingMission 2024-08-07T20:33:48.595Z,1723062828.595 [sink:RunSink] Running Loop=1 2024-08-07T20:33:48.595Z,1723062828.595 [sink:RunSink](DEBUG): Aggregate::initialize sink:RunSink 2024-08-07T20:33:48.595Z,1723062828.595 [sink:RunSink:RunBallastAndTrim] Running Loop=1 2024-08-07T20:33:48.595Z,1723062828.595 [sink:RunSink:RunBallastAndTrim](DEBUG): Aggregate::initialize sink:RunSink:RunBallastAndTrim 2024-08-07T20:33:48.595Z,1723062828.595 [sink:RunSink:RunBallastAndTrim:A] Running Loop=1 2024-08-07T20:33:48.833Z,1723062828.833 [WetLabsUBAT](ERROR): Failed to parse incomplete device message. 2024-08-07T20:33:49.066Z,1723062829.066 [sink:RunSink:RunBallastAndTrim] Stopped 2024-08-07T20:33:49.066Z,1723062829.066 [sink:RunSink:RunBallastAndTrim](DEBUG): Aggregate::uninitialize sink:RunSink:RunBallastAndTrim 2024-08-07T20:33:49.066Z,1723062829.066 [sink:RunSink:RunBallastAndTrim:A] Stopped 2024-08-07T20:33:49.066Z,1723062829.066 [sink:RunSink:JustSink] Running Loop=1 2024-08-07T20:33:49.066Z,1723062829.066 [sink:RunSink:JustSink](DEBUG): Aggregate::initialize sink:RunSink:JustSink 2024-08-07T20:33:49.066Z,1723062829.066 [sink:RunSink:JustSink:A] Running Loop=1 2024-08-07T20:33:49.333Z,1723062829.333 [sink:RunSink:JustSink:A] Stopped 2024-08-07T20:33:49.333Z,1723062829.333 [sink:RunSink:JustSink:B] Running Loop=1 2024-08-07T20:33:49.937Z,1723062829.937 [sink:RunSink:JustSink:B] Stopped 2024-08-07T20:33:49.937Z,1723062829.937 [sink:RunSink:JustSink:C] Running Loop=1 2024-08-07T20:33:49.938Z,1723062829.938 [sink:RunSink:JustSink:C](DEBUG): Aggregate::initialize sink:RunSink:JustSink:C 2024-08-07T20:33:50.257Z,1723062830.257 [sink:RunSink:BallastAndTrim] Running Loop=1 2024-08-07T20:33:50.257Z,1723062830.257 [sink:RunSink:BallastAndTrim](DEBUG): Aggregate::initialize sink:RunSink:BallastAndTrim 2024-08-07T20:33:50.257Z,1723062830.257 [sink:RunSink:BallastAndTrim:A] Running Loop=1 2024-08-07T20:33:50.257Z,1723062830.257 [sink:RunSink:BallastAndTrim:B] Running Loop=1 2024-08-07T20:33:50.257Z,1723062830.257 [sink:RunSink:BallastAndTrim:C] Running Loop=1 2024-08-07T20:33:50.258Z,1723062830.258 [sink:RunSink:BallastAndTrim:D] Running Loop=1 2024-08-07T20:33:50.258Z,1723062830.258 [sink:RunSink:BallastAndTrim:E] Running Loop=1 2024-08-07T20:33:50.258Z,1723062830.258 [sink:RunSink:BallastAndTrim:F] Running Loop=1 2024-08-07T20:33:50.260Z,1723062830.260 [sink:RunSink:BallastAndTrim:F](INFO): Going to target depth. Speed set to 0.000000 m/s 2024-08-07T20:33:50.260Z,1723062830.260 [sink:RunSink:BallastAndTrim:F] Stopped 2024-08-07T20:33:50.260Z,1723062830.260 [sink:RunSink:BallastAndTrim:GoToTargetDepth] Running Loop=1 2024-08-07T20:33:50.260Z,1723062830.260 [sink:RunSink:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::initialize sink:RunSink:BallastAndTrim:GoToTargetDepth 2024-08-07T20:33:50.289Z,1723062830.289 [sink:RunSink:BallastAndTrim:GoToTargetDepth:A] Running Loop=1 2024-08-07T20:33:50.289Z,1723062830.289 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold] Running Loop=1 2024-08-07T20:33:50.289Z,1723062830.289 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::initialize sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold 2024-08-07T20:33:50.289Z,1723062830.289 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Running Loop=1 2024-08-07T20:33:50.289Z,1723062830.289 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Initialize. 2024-08-07T20:33:50.289Z,1723062830.289 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Running Loop=1 2024-08-07T20:33:50.289Z,1723062830.289 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Initialize Buoyancy Component. 2024-08-07T20:33:50.289Z,1723062830.289 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Running Loop=1 2024-08-07T20:33:50.290Z,1723062830.290 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Initialize. 2024-08-07T20:33:50.290Z,1723062830.290 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive] Running Loop=1 2024-08-07T20:33:50.290Z,1723062830.290 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::initialize sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive 2024-08-07T20:33:50.290Z,1723062830.290 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Running Loop=1 2024-08-07T20:33:50.290Z,1723062830.290 [sink:RunSink:BallastAndTrim:E] Running Loop=1 2024-08-07T20:33:50.291Z,1723062830.291 [sink:RunSink:BallastAndTrim:D] Running Loop=1 2024-08-07T20:33:50.291Z,1723062830.291 [sink:RunSink:BallastAndTrim:C] Running Loop=1 2024-08-07T20:33:50.291Z,1723062830.291 [sink:RunSink:BallastAndTrim:B] Running Loop=1 2024-08-07T20:33:50.291Z,1723062830.291 [sink:RunSink:BallastAndTrim:A] Running Loop=1 2024-08-07T20:33:50.617Z,1723062830.617 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive:A](INFO): Waiting for 0.000000 min while pumping down to neutral. 2024-08-07T20:33:50.617Z,1723062830.617 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Stopped 2024-08-07T20:33:50.618Z,1723062830.618 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait] Running Loop=1 2024-08-07T20:33:50.618Z,1723062830.618 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Initialize Wait Component. 2024-08-07T20:33:50.618Z,1723062830.618 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold] Stopped 2024-08-07T20:33:50.618Z,1723062830.618 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::uninitialize sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold 2024-08-07T20:33:50.618Z,1723062830.618 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Stopped 2024-08-07T20:33:50.618Z,1723062830.618 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Stopped 2024-08-07T20:33:50.618Z,1723062830.618 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2024-08-07T20:33:50.618Z,1723062830.618 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Stopped 2024-08-07T20:33:50.618Z,1723062830.618 [sink:RunSink:BallastAndTrim:GoToTargetDepth:A] Running Loop=1 2024-08-07T20:33:51.093Z,1723062831.093 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](INFO): Done Waiting. 2024-08-07T20:33:51.094Z,1723062831.094 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait] Stopped 2024-08-07T20:33:51.094Z,1723062831.094 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Uninitialize Wait Component. 2024-08-07T20:33:51.094Z,1723062831.094 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive](INFO): Completed sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive 2024-08-07T20:33:51.094Z,1723062831.094 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive] Stopped 2024-08-07T20:33:51.094Z,1723062831.094 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::uninitialize sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive 2024-08-07T20:33:51.094Z,1723062831.094 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth] Running Loop=1 2024-08-07T20:33:51.095Z,1723062831.095 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::initialize sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth 2024-08-07T20:33:51.095Z,1723062831.095 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1 2024-08-07T20:33:51.095Z,1723062831.095 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Initialize. 2024-08-07T20:33:51.095Z,1723062831.095 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Running Loop=1 2024-08-07T20:33:51.423Z,1723062831.423 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1 2024-08-07T20:33:51.424Z,1723062831.424 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:A](INFO): Moving to 1.250000 m 2024-08-07T20:33:51.424Z,1723062831.424 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Stopped 2024-08-07T20:33:51.424Z,1723062831.424 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Running Loop=1 2024-08-07T20:33:51.424Z,1723062831.424 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Initialize. 2024-08-07T20:33:57.389Z,1723062837.389 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:34:07.987Z,1723062847.987 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:34:11.194Z,1723062851.194 [Reporter](INFO): platform_buoyancy_position 867.819336 cc 2024-08-07T20:34:18.624Z,1723062858.624 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2024-08-07T20:34:18.701Z,1723062858.701 [NAL9602](ERROR): received: +CSQ:0 OK380, 2, 0, 0, 0 OK 2024-08-07T20:34:18.918Z,1723062858.918 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:34:27.446Z,1723062867.446 [RDI_Pathfinder](ERROR): only read 1 of 4 data items for bottom velocity. Device response is::BS, +4, E, +4, -2, +0,A 2024-08-07T20:34:28.477Z,1723062868.477 [WetLabsUBAT](ERROR): Failed to parse incomplete device message. 2024-08-07T20:34:32.710Z,1723062872.710 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,24080712350, +21.2, 0.0,1524.7, 0 2024-08-07T20:34:36.606Z,1723062876.606 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:34:41.208Z,1723062881.208 [Reporter](INFO): platform_buoyancy_position 622.990479 cc 2024-08-07T20:34:44.045Z,1723062884.045 [DockingStepper](ERROR): getADC uart error serial timeout 2024-08-07T20:34:44.045Z,1723062884.045 [DockingStepper](FAULT): uart error - getPosition..serial timeout 2024-08-07T20:34:44.046Z,1723062884.046 [DockingStepper] Communications Fault, FailCount= 1 2024-08-07T20:34:44.046Z,1723062884.046 [DockingStepper](ERROR): Communications Fault 2024-08-07T20:34:44.077Z,1723062884.077 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-08-07T20:34:44.078Z,1723062884.078 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:34:44.307Z,1723062884.307 [DockingStepper](INFO): Stop 2024-08-07T20:34:44.307Z,1723062884.307 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-08-07T20:34:44.307Z,1723062884.307 [DockingStepper](INFO): Powering down 2024-08-07T20:34:44.674Z,1723062884.674 [DockingStepper](INFO): Stopping 2024-08-07T20:34:45.074Z,1723062885.074 [DockingStepper](INFO): Stopped 2024-08-07T20:34:45.202Z,1723062885.202 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-08-07T20:34:45.202Z,1723062885.202 [DockingStepper] No Fault, FailCount= 1 2024-08-07T20:34:45.450Z,1723062885.450 [DockingStepper](INFO): Start 2024-08-07T20:34:45.450Z,1723062885.450 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-08-07T20:34:45.570Z,1723062885.570 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-08-07T20:34:45.859Z,1723062885.859 [DockingStepper](INFO): Starting 2024-08-07T20:34:46.254Z,1723062886.254 [DockingStepper](INFO): Starting 2024-08-07T20:34:46.686Z,1723062886.686 [DockingStepper](INFO): Starting 2024-08-07T20:34:46.730Z,1723062886.730 [DockingStepper](INFO): Init string: /1z10560aE13561N3m50 2024-08-07T20:34:47.082Z,1723062887.082 [DockingStepper](INFO): Starting 2024-08-07T20:34:47.442Z,1723062887.442 [DockingStepper](INFO): Starting 2024-08-07T20:34:47.846Z,1723062887.846 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:34:47.895Z,1723062887.895 [DockingStepper](INFO): Starting 2024-08-07T20:34:48.302Z,1723062888.302 [DockingStepper](INFO): Starting 2024-08-07T20:34:48.335Z,1723062888.335 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-08-07T20:34:48.698Z,1723062888.698 [DockingStepper](INFO): Starting 2024-08-07T20:34:48.711Z,1723062888.711 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:34:49.137Z,1723062889.137 [DockingStepper](INFO): Commanding positive:36479 2024-08-07T20:34:58.611Z,1723062898.611 [CommandExec](IMPORTANT): got command docking standby 2024-08-07T20:34:58.611Z,1723062898.611 [CommandExec](IMPORTANT): Switching docking moudle to STANDBY. 2024-08-07T20:34:58.622Z,1723062898.622 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:34:59.006Z,1723062899.006 [DockingStepper](INFO): Changing to mode: 1 2024-08-07T20:34:59.006Z,1723062899.006 [DockingStepper](INFO): Standby mode. 2024-08-07T20:35:00.798Z,1723062900.798 [CommandExec](IMPORTANT): got command docking slide 2024-08-07T20:35:00.798Z,1723062900.798 [CommandExec](IMPORTANT): Switching docking module to SLIDE. 2024-08-07T20:35:01.023Z,1723062901.023 [DockingStepper](INFO): Changing to mode: 4 2024-08-07T20:35:01.023Z,1723062901.023 [DockingStepper](INFO): Slide mode. 2024-08-07T20:35:01.077Z,1723062901.077 [DockingStepper](INFO): Commanding negative:35512 2024-08-07T20:35:11.443Z,1723062911.443 [Reporter](INFO): platform_buoyancy_position 371.446533 cc 2024-08-07T20:35:30.630Z,1723062930.630 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:35:41.535Z,1723062941.535 [Reporter](INFO): platform_buoyancy_position 351.973022 cc 2024-08-07T20:35:57.738Z,1723062957.738 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.120117 2024-08-07T20:35:58.741Z,1723062958.741 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:36:11.959Z,1723062971.959 [Reporter](INFO): platform_buoyancy_position 339.214539 cc 2024-08-07T20:36:28.878Z,1723062988.878 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-08-07T20:36:29.881Z,1723062989.881 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:36:42.284Z,1723063002.284 [Reporter](INFO): platform_buoyancy_position 324.038635 cc 2024-08-07T20:37:00.001Z,1723063020.001 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-08-07T20:37:01.005Z,1723063021.005 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:37:12.531Z,1723063032.531 [Reporter](INFO): platform_buoyancy_position 306.848236 cc 2024-08-07T20:37:12.991Z,1723063032.991 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:37:13.339Z,1723063033.339 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:37:14.438Z,1723063034.438 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:37:18.115Z,1723063038.115 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:37:20.251Z,1723063040.251 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:37:24.679Z,1723063044.679 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:37:31.128Z,1723063051.128 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-08-07T20:37:31.260Z,1723063051.260 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:37:32.129Z,1723063052.129 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:37:32.411Z,1723063052.411 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:37:36.696Z,1723063056.696 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:37:42.738Z,1723063062.738 [Reporter](INFO): platform_buoyancy_position 360.702515 cc 2024-08-07T20:38:02.250Z,1723063082.250 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-08-07T20:38:03.253Z,1723063083.253 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:38:05.759Z,1723063085.759 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:38:07.583Z,1723063087.583 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 0.00, 98.93, 89.28, 88.93 2024-08-07T20:38:12.779Z,1723063092.779 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:38:12.780Z,1723063092.780 [Reporter](INFO): platform_buoyancy_position 356.136353 cc 2024-08-07T20:38:19.818Z,1723063099.818 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Stopped 2024-08-07T20:38:19.819Z,1723063099.819 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth](INFO): Completed sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth 2024-08-07T20:38:19.819Z,1723063099.819 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth] Stopped 2024-08-07T20:38:19.819Z,1723063099.819 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::uninitialize sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth 2024-08-07T20:38:19.819Z,1723063099.819 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Stopped 2024-08-07T20:38:19.819Z,1723063099.819 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Uninitialize. 2024-08-07T20:38:19.820Z,1723063099.820 [sink:RunSink:BallastAndTrim:GoToTargetDepth](INFO): Completed sink:RunSink:BallastAndTrim:GoToTargetDepth 2024-08-07T20:38:19.820Z,1723063099.820 [sink:RunSink:BallastAndTrim:GoToTargetDepth] Stopped 2024-08-07T20:38:19.820Z,1723063099.820 [sink:RunSink:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::uninitialize sink:RunSink:BallastAndTrim:GoToTargetDepth 2024-08-07T20:38:19.820Z,1723063099.820 [sink:RunSink:BallastAndTrim:GoToTargetDepth:A] Stopped 2024-08-07T20:38:19.820Z,1723063099.820 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth] Running Loop=1 2024-08-07T20:38:19.820Z,1723063099.820 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::initialize sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth 2024-08-07T20:38:19.820Z,1723063099.820 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1 2024-08-07T20:38:19.820Z,1723063099.820 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Initialize. 2024-08-07T20:38:19.820Z,1723063099.820 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1 2024-08-07T20:38:19.820Z,1723063099.820 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Initialize. 2024-08-07T20:38:19.824Z,1723063099.824 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Running Loop=1 2024-08-07T20:38:20.337Z,1723063100.337 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:C](INFO): Waiting for the vehicle to settle. Depth = 1.279850 m 2024-08-07T20:38:20.338Z,1723063100.338 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Stopped 2024-08-07T20:38:20.338Z,1723063100.338 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Running Loop=1 2024-08-07T20:38:20.338Z,1723063100.338 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Initialize Wait Component. 2024-08-07T20:38:20.338Z,1723063100.338 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1 2024-08-07T20:38:20.338Z,1723063100.338 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1 2024-08-07T20:38:30.074Z,1723063110.074 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:38:30.431Z,1723063110.431 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:38:33.373Z,1723063113.373 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-08-07T20:38:34.377Z,1723063114.377 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:38:43.075Z,1723063123.075 [Reporter](INFO): platform_buoyancy_position 382.324829 cc 2024-08-07T20:38:50.563Z,1723063130.563 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-08-07T20:39:04.501Z,1723063144.501 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-08-07T20:39:05.505Z,1723063145.505 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:39:13.408Z,1723063153.408 [Reporter](INFO): platform_buoyancy_position 347.944031 cc 2024-08-07T20:39:17.671Z,1723063157.671 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for bottom velocity. Device response is::BS, -14, +7, 2024-08-07T20:39:21.190Z,1723063161.190 [NAL9602](INFO): Not Powering down - fast GPS 2024-08-07T20:39:28.569Z,1723063168.569 [WetLabsUBAT](ERROR): Failed to parse incomplete device message. 2024-08-07T20:39:35.662Z,1723063175.662 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-08-07T20:39:36.665Z,1723063176.665 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:39:43.538Z,1723063183.538 [Reporter](INFO): platform_buoyancy_position 369.432068 cc 2024-08-07T20:40:08.151Z,1723063208.151 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005287 2024-08-07T20:40:09.148Z,1723063209.148 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:40:13.951Z,1723063213.951 [Reporter](INFO): platform_buoyancy_position 356.539246 cc 2024-08-07T20:40:25.207Z,1723063225.207 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:40:28.638Z,1723063228.638 [WetLabsUBAT](ERROR): Failed to parse incomplete device message. 2024-08-07T20:40:38.361Z,1723063238.361 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:40:43.992Z,1723063243.992 [Reporter](INFO): platform_buoyancy_position 373.729614 cc 2024-08-07T20:40:48.209Z,1723063248.209 [sink:NeedComms] Running Loop=1 2024-08-07T20:40:48.209Z,1723063248.209 [sink:NeedComms](DEBUG): Aggregate::initialize sink:NeedComms 2024-08-07T20:40:48.210Z,1723063248.210 [sink:NeedComms:B.GoToSurface] Running Loop=1 2024-08-07T20:40:48.210Z,1723063248.210 [sink:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-08-07T20:40:48.210Z,1723063248.210 [sink:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2024-08-07T20:40:48.210Z,1723063248.210 [sink:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2024-08-07T20:40:48.211Z,1723063248.211 [sink:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2024-08-07T20:40:48.211Z,1723063248.211 [sink:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2024-08-07T20:40:48.211Z,1723063248.211 [sink:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-08-07T20:40:48.212Z,1723063248.212 [sink:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-08-07T20:40:48.212Z,1723063248.212 [sink:NeedComms:A] Running Loop=1 2024-08-07T20:40:48.214Z,1723063248.214 [sink:NeedComms:A](INFO): last time_fix was: 1723062827.000000 second since 1970/01/01T00:00:00Z 2024-08-07T20:40:48.214Z,1723063248.214 [sink:NeedComms:A] Stopped 2024-08-07T20:40:48.581Z,1723063248.581 [sink:NeedComms:C] Running Loop=1 2024-08-07T20:40:49.642Z,1723063249.642 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:40:50.720Z,1723063250.720 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204050.00,A,3648.16547,N,12147.28586,W,0.933,43.29,070824,,,D*4E 2024-08-07T20:40:50.723Z,1723063250.723 [NAL9602](INFO): GPS fix at 20240807T204050: (36.802758, -121.788098) 2024-08-07T20:40:50.739Z,1723063250.739 [sink:NeedComms:C] Stopped 2024-08-07T20:40:50.739Z,1723063250.739 [sink:NeedComms:D] Running Loop=1 2024-08-07T20:40:59.226Z,1723063259.226 [DataOverHttps](INFO): Sending 269 bytes from file Logs/20240807T203219/Courier0000.lzma 2024-08-07T20:41:00.227Z,1723063260.227 [DataOverHttps](INFO): Moved sent file to Logs/20240807T203219/Courier0000.lzma.bak 2024-08-07T20:41:00.227Z,1723063260.227 [DataOverHttps](INFO): SBD MOMSN=21446092 2024-08-07T20:41:14.206Z,1723063274.206 [CommandExec](IMPORTANT): got command stop 2024-08-07T20:41:14.206Z,1723063274.206 [CommandExec](IMPORTANT): Scheduling is paused for 2 commands 2024-08-07T20:41:14.207Z,1723063274.207 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop 2024-08-07T20:41:14.471Z,1723063274.471 [Reporter](INFO): platform_buoyancy_position 693.766541 cc 2024-08-07T20:41:14.650Z,1723063274.650 [MissionManager](INFO): MissionManager is completed. 2024-08-07T20:41:14.651Z,1723063274.651 [MissionManager](INFO): Uninitializing Mission sink 2024-08-07T20:41:14.651Z,1723063274.651 [sink] Stopped 2024-08-07T20:41:14.651Z,1723063274.651 [sink](DEBUG): Aggregate::uninitialize sink 2024-08-07T20:41:14.651Z,1723063274.651 [sink:Science] Stopped 2024-08-07T20:41:14.651Z,1723063274.651 [sink:Science](DEBUG): Aggregate::uninitialize sink:Science 2024-08-07T20:41:14.651Z,1723063274.651 [sink:Science:B] Stopped 2024-08-07T20:41:14.651Z,1723063274.651 [sink:Science:C] Stopped 2024-08-07T20:41:14.651Z,1723063274.651 [sink:Science:E] Stopped 2024-08-07T20:41:14.651Z,1723063274.651 [sink:Science:F] Stopped 2024-08-07T20:41:14.651Z,1723063274.651 [sink:Science:OceanCurrent] Stopped 2024-08-07T20:41:14.651Z,1723063274.651 [sink:Science:OceanCurrent](DEBUG): Aggregate::uninitialize sink:Science:OceanCurrent 2024-08-07T20:41:14.651Z,1723063274.651 [sink:Science:OceanCurrent:A.] Stopped 2024-08-07T20:41:14.651Z,1723063274.651 [sink:NeedComms] Stopped 2024-08-07T20:41:14.651Z,1723063274.651 [sink:NeedComms](DEBUG): Aggregate::uninitialize sink:NeedComms 2024-08-07T20:41:14.652Z,1723063274.652 [sink:NeedComms:B.GoToSurface] Stopped 2024-08-07T20:41:14.652Z,1723063274.652 [sink:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-08-07T20:41:14.652Z,1723063274.652 [sink:NeedComms:D] Stopped 2024-08-07T20:41:14.652Z,1723063274.652 [sink:StandardEnvelopes] Stopped 2024-08-07T20:41:14.652Z,1723063274.652 [sink:StandardEnvelopes](DEBUG): Aggregate::uninitialize sink:StandardEnvelopes 2024-08-07T20:41:14.652Z,1723063274.652 [sink:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2024-08-07T20:41:14.652Z,1723063274.652 [sink:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2024-08-07T20:41:14.652Z,1723063274.652 [sink:StandardEnvelopes:B.DepthEnvelope] Stopped 2024-08-07T20:41:14.652Z,1723063274.652 [sink:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2024-08-07T20:41:14.652Z,1723063274.652 [sink:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2024-08-07T20:41:14.652Z,1723063274.652 [sink:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2024-08-07T20:41:14.652Z,1723063274.652 [sink:BackseatDriver] Stopped 2024-08-07T20:41:14.661Z,1723063274.661 [sink:BackseatDriver](DEBUG): Aggregate::uninitialize sink:BackseatDriver 2024-08-07T20:41:14.661Z,1723063274.661 [sink:BackseatDriver:A.BackseatDriver] Stopped 2024-08-07T20:41:14.661Z,1723063274.661 [sink:PowerOnly] Stopped 2024-08-07T20:41:14.661Z,1723063274.661 [sink:PowerOnly](DEBUG): Aggregate::uninitialize sink:PowerOnly 2024-08-07T20:41:14.661Z,1723063274.661 [sink:PowerOnly:E.Wait] Stopped 2024-08-07T20:41:14.661Z,1723063274.661 [sink:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2024-08-07T20:41:14.661Z,1723063274.661 [sink:RunSink] Stopped 2024-08-07T20:41:14.661Z,1723063274.661 [sink:RunSink](DEBUG): Aggregate::uninitialize sink:RunSink 2024-08-07T20:41:14.661Z,1723063274.661 [sink:RunSink:BallastAndTrim] Stopped 2024-08-07T20:41:14.662Z,1723063274.662 [sink:RunSink:BallastAndTrim](DEBUG): Aggregate::uninitialize sink:RunSink:BallastAndTrim 2024-08-07T20:41:14.662Z,1723063274.662 [sink:RunSink:BallastAndTrim:A] Stopped 2024-08-07T20:41:14.662Z,1723063274.662 [sink:RunSink:BallastAndTrim:B] Stopped 2024-08-07T20:41:14.662Z,1723063274.662 [sink:RunSink:BallastAndTrim:C] Stopped 2024-08-07T20:41:14.662Z,1723063274.662 [sink:RunSink:BallastAndTrim:D] Stopped 2024-08-07T20:41:14.662Z,1723063274.662 [sink:RunSink:BallastAndTrim:E] Stopped 2024-08-07T20:41:14.662Z,1723063274.662 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth] Stopped 2024-08-07T20:41:14.662Z,1723063274.662 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::uninitialize sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth 2024-08-07T20:41:14.662Z,1723063274.662 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Stopped 2024-08-07T20:41:14.662Z,1723063274.662 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Uninitialize. 2024-08-07T20:41:14.662Z,1723063274.662 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Stopped 2024-08-07T20:41:14.662Z,1723063274.662 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Stopped 2024-08-07T20:41:14.662Z,1723063274.662 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Uninitialize Wait Component. 2024-08-07T20:41:14.662Z,1723063274.662 [sink:RunSink:JustSink] Stopped 2024-08-07T20:41:14.663Z,1723063274.663 [sink:RunSink:JustSink](DEBUG): Aggregate::uninitialize sink:RunSink:JustSink 2024-08-07T20:41:14.663Z,1723063274.663 [sink:RunSink:JustSink:C] Stopped 2024-08-07T20:41:14.663Z,1723063274.663 [sink:RunSink:JustSink:C](DEBUG): Aggregate::uninitialize sink:RunSink:JustSink:C 2024-08-07T20:41:14.897Z,1723063274.897 [WetLabsUBAT](INFO): Powering down 2024-08-07T20:41:14.941Z,1723063274.941 [WetLabsBB2FL](INFO): Powering down 2024-08-07T20:41:15.122Z,1723063275.122 [MissionManager](IMPORTANT): Started mission Default 2024-08-07T20:41:15.123Z,1723063275.123 [Default] Running Loop=1 2024-08-07T20:41:15.123Z,1723063275.123 [Default](DEBUG): Aggregate::initialize Default 2024-08-07T20:41:15.123Z,1723063275.123 [Default:B.GoToSurface] Running Loop=1 2024-08-07T20:41:15.123Z,1723063275.123 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-08-07T20:41:15.123Z,1723063275.123 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-08-07T20:41:15.123Z,1723063275.123 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-08-07T20:41:15.124Z,1723063275.124 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-08-07T20:41:15.124Z,1723063275.124 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-08-07T20:41:15.124Z,1723063275.124 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-08-07T20:41:15.133Z,1723063275.133 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-08-07T20:41:15.133Z,1723063275.133 [Default:A.Wait] Running Loop=1 2024-08-07T20:41:15.133Z,1723063275.133 [Default:A.Wait](DEBUG): Initialize Wait Component. 2024-08-07T20:41:15.205Z,1723063275.205 [WetLabsUBAT](FAULT): LCB fault: LCB Watchdog Reset. 2024-08-07T20:41:15.206Z,1723063275.206 [WetLabsUBAT] Hardware Fault, FailCount= 1 2024-08-07T20:41:15.206Z,1723063275.206 [WetLabsUBAT](ERROR): Hardware Fault 2024-08-07T20:41:15.328Z,1723063275.328 [CBIT](ERROR): Hardware Fault in component: WetLabsUBAT 2024-08-07T20:41:15.730Z,1723063275.730 [CBIT](INFO): Clearing failed state for component WetLabsUBAT 2024-08-07T20:41:15.730Z,1723063275.730 [WetLabsUBAT] No Fault, FailCount= 1 2024-08-07T20:41:16.014Z,1723063276.014 [WetLabsUBAT](INFO): Powering up 2024-08-07T20:41:20.722Z,1723063280.722 [DataOverHttps](INFO): Sending 764 bytes from file Logs/20240807T203219/Express0001.lzma 2024-08-07T20:41:21.296Z,1723063281.296 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2024-08-07T20:41:21.379Z,1723063281.379 [NAL9602](ERROR): received: +CSQ:0 OK380, 2, 0, 0, 0 OK 2024-08-07T20:41:21.718Z,1723063281.718 [DataOverHttps](INFO): Moved sent file to Logs/20240807T203219/Express0001.lzma.bak 2024-08-07T20:41:21.719Z,1723063281.719 [DataOverHttps](INFO): SBD MOMSN=21446097 2024-08-07T20:41:25.285Z,1723063285.285 [WetLabsUBAT](INFO): Powering down 2024-08-07T20:41:28.286Z,1723063288.286 [Default:A.Wait](INFO): Done Waiting. 2024-08-07T20:41:28.286Z,1723063288.286 [Default:A.Wait] Stopped 2024-08-07T20:41:28.286Z,1723063288.286 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2024-08-07T20:41:28.731Z,1723063288.731 [Default:CheckIn] Running Loop=1 2024-08-07T20:41:28.732Z,1723063288.732 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-08-07T20:41:28.732Z,1723063288.732 [Default:CheckIn:Read_GPS] Running Loop=1 2024-08-07T20:41:32.305Z,1723063292.305 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:41:41.676Z,1723063301.676 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:41:44.246Z,1723063304.246 [CommandExec](IMPORTANT): got command docking arm 2024-08-07T20:41:44.247Z,1723063304.247 [CommandExec](IMPORTANT): Switching docking moudle to ARM. 2024-08-07T20:41:44.519Z,1723063304.519 [DockingStepper](INFO): Changing to mode: 2 2024-08-07T20:41:44.519Z,1723063304.519 [DockingStepper](INFO): Armed mode. 2024-08-07T20:41:44.586Z,1723063304.586 [DockingStepper](INFO): Commanding negative:20120 2024-08-07T20:41:44.983Z,1723063304.983 [Reporter](INFO): platform_buoyancy_position 992.584167 cc 2024-08-07T20:41:49.715Z,1723063309.715 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:41:51.233Z,1723063311.233 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:41:52.566Z,1723063312.566 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:41:53.012Z,1723063313.012 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:41:53.371Z,1723063313.371 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:42:00.510Z,1723063320.510 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:42:09.551Z,1723063329.551 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:42:09.829Z,1723063329.829 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:42:10.343Z,1723063330.343 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:42:12.427Z,1723063332.427 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:42:13.595Z,1723063333.595 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:42:15.193Z,1723063335.193 [Reporter](INFO): platform_buoyancy_position 992.584167 cc 2024-08-07T20:42:16.018Z,1723063336.018 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:42:18.435Z,1723063338.435 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:42:19.041Z,1723063339.041 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:42:19.669Z,1723063339.669 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:42:28.883Z,1723063348.883 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:42:42.323Z,1723063362.323 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:42:45.600Z,1723063365.600 [Reporter](INFO): platform_buoyancy_position 992.584167 cc 2024-08-07T20:42:51.501Z,1723063371.501 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:42:53.103Z,1723063373.103 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for bottom velocity. Device response is::BS, +7, +8, 2024-08-07T20:43:00.879Z,1723063380.879 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:43:02.536Z,1723063382.536 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:43:04.183Z,1723063384.183 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:43:10.009Z,1723063390.009 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:43:13.059Z,1723063393.059 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:43:15.871Z,1723063395.871 [Reporter](INFO): platform_buoyancy_position 992.584167 cc 2024-08-07T20:43:19.345Z,1723063399.345 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:43:28.781Z,1723063408.781 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:43:29.599Z,1723063409.599 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:43:34.519Z,1723063414.519 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:43:36.898Z,1723063416.898 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:43:37.959Z,1723063417.959 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:43:43.798Z,1723063423.798 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:43:44.583Z,1723063424.583 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:43:46.143Z,1723063426.143 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:43:46.143Z,1723063426.143 [Reporter](INFO): platform_buoyancy_position 992.584167 cc 2024-08-07T20:43:46.979Z,1723063426.979 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:43:47.421Z,1723063427.421 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:43:53.859Z,1723063433.859 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:43:54.651Z,1723063434.651 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:43:55.882Z,1723063435.882 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:43:56.565Z,1723063436.565 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:43:58.712Z,1723063438.712 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:44:05.953Z,1723063445.953 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:44:08.427Z,1723063448.427 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:44:08.848Z,1723063448.848 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:44:16.314Z,1723063456.314 [Reporter](INFO): platform_buoyancy_position 992.584167 cc 2024-08-07T20:44:19.431Z,1723063459.431 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:44:19.549Z,1723063459.549 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:44:29.518Z,1723063469.518 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:44:32.767Z,1723063472.767 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:44:38.889Z,1723063478.889 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:44:44.439Z,1723063484.439 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:44:46.444Z,1723063486.444 [Reporter](INFO): platform_buoyancy_position 992.584167 cc 2024-08-07T20:44:48.046Z,1723063488.046 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:44:57.313Z,1723063497.313 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:45:03.859Z,1723063503.859 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:45:06.461Z,1723063506.461 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:45:15.741Z,1723063515.741 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:45:16.744Z,1723063516.744 [Reporter](INFO): platform_buoyancy_position 992.584167 cc 2024-08-07T20:45:25.201Z,1723063525.201 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:45:34.329Z,1723063534.329 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:45:43.841Z,1723063543.841 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:45:47.055Z,1723063547.055 [Reporter](INFO): platform_buoyancy_position 992.584167 cc 2024-08-07T20:45:53.017Z,1723063553.017 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:45:53.345Z,1723063553.345 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-08-07T20:45:59.008Z,1723063559.008 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204558.00,A,3648.16096,N,12147.28201,W,0.467,43.29,070824,,,D*4E 2024-08-07T20:45:59.022Z,1723063559.022 [NAL9602](INFO): GPS fix at 20240807T204558: (36.802683, -121.788033) 2024-08-07T20:45:59.054Z,1723063559.054 [Default:CheckIn:Read_GPS] Stopped 2024-08-07T20:45:59.055Z,1723063559.055 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-08-07T20:46:00.498Z,1723063560.498 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240807T203219/Courier0003.lzma 2024-08-07T20:46:01.204Z,1723063561.204 [CommandExec](IMPORTANT): got command schedule clear 2024-08-07T20:46:01.204Z,1723063561.204 [CommandExec](IMPORTANT): Cleared 2 scheduled commands. 2024-08-07T20:46:01.451Z,1723063561.451 [DataOverHttps](INFO): Moved sent file to Logs/20240807T203219/Courier0003.lzma.bak 2024-08-07T20:46:01.451Z,1723063561.451 [DataOverHttps](INFO): SBD MOMSN=21446161 2024-08-07T20:46:17.298Z,1723063577.298 [Reporter](INFO): platform_buoyancy_position 992.584167 cc 2024-08-07T20:46:22.965Z,1723063582.965 [DataOverHttps](INFO): Sending 378 bytes from file Logs/20240807T203219/Express0004.lzma 2024-08-07T20:46:23.967Z,1723063583.967 [DataOverHttps](INFO): Moved sent file to Logs/20240807T203219/Express0004.lzma.bak 2024-08-07T20:46:23.968Z,1723063583.968 [DataOverHttps](INFO): SBD MOMSN=21446164 2024-08-07T20:46:25.142Z,1723063585.142 [Default:CheckIn:Read_Iridium] Stopped 2024-08-07T20:46:25.142Z,1723063585.142 [Default:CheckIn:C.Wait] Running Loop=1 2024-08-07T20:46:25.143Z,1723063585.143 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-08-07T20:46:31.166Z,1723063591.166 [NAL9602](INFO): Not Powering down - fast GPS 2024-08-07T20:46:33.211Z,1723063593.211 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 0.66, 89.97, 98.25, 100.32 2024-08-07T20:46:33.227Z,1723063593.227 [DataOverHttps](IMPORTANT): SBD MTMSN=20240807T204632 2024-08-07T20:46:41.630Z,1723063601.630 [DataOverHttps](INFO): Received command: sched "load Engineering/sink.tl;set sink.MissionTimeout 7 min;set sink.Depth 0.75 m;set sink.DepthDeadband .5 m;set sink.DoBallastAndTrim 0 bool;set sink.MinAltitude .0001 m;set sink.MaxDepth 5 m;set sink.MinOffshore .00001 km;run" 2024-08-07T20:46:41.663Z,1723063601.663 [CommandExec](IMPORTANT): got command schedule "load Engineering/sink.tl;set sink.MissionTimeout 7 min;set sink.Depth 0.75 m;set sink.DepthDeadband .5 m;set sink.DoBallastAndTrim 0 bool;set sink.MinAltitude .0001 m;set sink.MaxDepth 5 m;set sink.MinOffshore .00001 km;run" 2024-08-07T20:46:41.664Z,1723063601.664 [CommandExec](IMPORTANT): Scheduled #4: "load Engineering/sink.tl;set sink.MissionTimeout 7 min;set sink.Depth 0.75 m;set sink.DepthDeadband .5 m;set sink.DoBallastAndTrim 0 bool;set sink.MinAltitude .0001 m;set sink.MaxDepth 5 m;set sink.MinOffshore .00001 km;run", AFTER MISSION 2024-08-07T20:46:41.664Z,1723063601.664 [CommandExec](FAULT): Scheduling was paused by a command at 2024-08-07T20:41:14Z 2024-08-07T20:46:42.631Z,1723063602.631 [DataOverHttps](IMPORTANT): SBD MTMSN=20240807T204641 2024-08-07T20:46:47.459Z,1723063607.459 [Reporter](INFO): platform_buoyancy_position 992.584167 cc 2024-08-07T20:46:50.902Z,1723063610.902 [DataOverHttps](INFO): Received command: sched "load Engineering/OnDock.tl;set OnDock.DockedTime 15 min;set OnDock.DetachedTimeout 100 min;set OnDock.RangeTimeout 100 min;set OnDock.TrackingUpdatePeriod 100 min;set OnDock.MinAltitude 0.0001 m;set OnDock.MinOffshore 0.0001 km" 27dpm 1 2 2024-08-07T20:46:50.976Z,1723063610.976 [CommandExec](IMPORTANT): got command schedule "load Engineering/OnDock.tl;set OnDock.DockedTime 15 min;set OnDock.DetachedTimeout 100 min;set OnDock.RangeTimeout 100 min;set OnDock.TrackingUpdatePeriod 100 min;set OnDock.MinAltitude 0.0001 m;set OnDock.MinOffshore 0.0001 km" 27dpm 1 2.000000 2024-08-07T20:46:50.976Z,1723063610.976 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=27dpm 2024-08-07T20:46:50.977Z,1723063610.977 [CommandExec](IMPORTANT): Scheduled #5 (#1 of 2 with id='27dpm'): "load Engineering/OnDock.tl;set OnDock.DockedTime 15 min;set OnDock.DetachedTimeout 100 min;set OnDock.RangeTimeout 100 min;set OnDock.TrackingUpdatePeriod 100 min;set OnDock.MinAltitude 0.0001 m;set OnDock.MinOffshore 0.0001 km", AFTER MISSION 2024-08-07T20:46:50.978Z,1723063610.978 [CommandExec](FAULT): Scheduling was paused by a command at 2024-08-07T20:41:14Z 2024-08-07T20:46:51.895Z,1723063611.895 [DataOverHttps](IMPORTANT): SBD MTMSN=20240807T204650 2024-08-07T20:47:00.201Z,1723063620.201 [DataOverHttps](INFO): Received command: sched "set OnDock.IfconfigInterval 3 min;run" 27dpm 2 2 2024-08-07T20:47:00.262Z,1723063620.262 [CommandExec](IMPORTANT): got command schedule "set OnDock.IfconfigInterval 3 min;run" 27dpm 2 2.000000 2024-08-07T20:47:00.263Z,1723063620.263 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=27dpm 2024-08-07T20:47:00.263Z,1723063620.263 [CommandExec](IMPORTANT): Scheduled #6 (#2 of 2 with id='27dpm'): "set OnDock.IfconfigInterval 3 min;run", AFTER MISSION 2024-08-07T20:47:00.264Z,1723063620.264 [CommandExec](FAULT): Scheduling was paused by a command at 2024-08-07T20:41:14Z 2024-08-07T20:47:02.205Z,1723063622.205 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:47:11.769Z,1723063631.769 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:47:17.695Z,1723063637.695 [Reporter](INFO): platform_buoyancy_position 992.584167 cc 2024-08-07T20:47:20.874Z,1723063640.874 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:47:30.421Z,1723063650.421 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:47:38.989Z,1723063658.989 [CommandExec](IMPORTANT): got command schedule resume 2024-08-07T20:47:38.990Z,1723063658.990 [CommandExec](IMPORTANT): Scheduling is resumed 2024-08-07T20:47:39.119Z,1723063659.119 [CommandExec](IMPORTANT): got command load ./Missions/Engineering/sink.tl 2024-08-07T20:47:39.120Z,1723063659.120 [MissionManager](INFO): Loading Mission from file: ./Missions/Engineering/sink.tl 2024-08-07T20:47:39.120Z,1723063659.120 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Engineering/sink.tl 2024-08-07T20:47:39.122Z,1723063659.122 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Engineering/sink.tl 2024-08-07T20:47:39.725Z,1723063659.725 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:47:43.735Z,1723063663.735 [MissionManager](DEBUG): TethyslAPI: ./Missions/Engineering/sink.tl translated into: 2 20 1 2.5 20 20 10 45 10 0.5 25 98 5 30 2 2024-08-07T20:47:43.744Z,1723063663.744 [MissionManager](INFO): DefineArg sink.MissionTimeout = 2.000000 h 2024-08-07T20:47:43.799Z,1723063663.799 [MissionManager](INFO): DefineArg sink.Depth = 20.000000 m 2024-08-07T20:47:43.806Z,1723063663.806 [MissionManager](INFO): DefineArg sink.DepthDeadband = 1.000000 m 2024-08-07T20:47:43.808Z,1723063663.808 [MissionManager](INFO): DefineArg sink.SurfaceThreshold = 2.500000 m 2024-08-07T20:47:43.887Z,1723063663.887 [MissionManager](INFO): DefineArg sink.WaitToFloat = 20.000000 min 2024-08-07T20:47:43.898Z,1723063663.898 [MissionManager](INFO): DefineArg sink.TargetDepthTimeout = 20.000000 min 2024-08-07T20:47:43.909Z,1723063663.909 [MissionManager](INFO): DefineArg sink.DoBallastAndTrim = 1 bool 2024-08-07T20:47:43.912Z,1723063663.912 [MissionManager](INFO): DefineArg sink.BTSettleTime = 10.000000 min 2024-08-07T20:47:43.922Z,1723063663.922 [MissionManager](INFO): DefineArg sink.EstimationTimeout = 45.000000 min 2024-08-07T20:47:43.933Z,1723063663.933 [MissionManager](INFO): DefineArg sink.MinEstimationTime = 10.000000 min 2024-08-07T20:47:43.935Z,1723063663.935 [MissionManager](INFO): DefineArg sink.MassEstimationErrorBound = 0.500000 mm 2024-08-07T20:47:43.954Z,1723063663.954 [MissionManager](INFO): DefineArg sink.BuoyEstimationErrorBound = 25.000000 cc 2024-08-07T20:47:43.966Z,1723063663.966 [MissionManager](INFO): DefineArg sink.EstimationConfidence = 98.000000 % 2024-08-07T20:47:43.999Z,1723063663.999 [MissionManager](INFO): DefineArg sink.MinAltitude = 5.000000 m 2024-08-07T20:47:44.053Z,1723063664.053 [MissionManager](INFO): DefineArg sink.MaxDepth = 30.000000 m 2024-08-07T20:47:44.056Z,1723063664.056 [MissionManager](INFO): DefineArg sink.MinOffshore = 2.000000 km 2024-08-07T20:47:44.056Z,1723063664.056 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/Science.tl 2024-08-07T20:47:44.056Z,1723063664.056 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/Science.tl 2024-08-07T20:47:44.075Z,1723063664.075 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/Science.tl 2024-08-07T20:47:47.829Z,1723063667.829 [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-08-07T20:47:47.902Z,1723063667.902 [MissionManager](INFO): DefineArg sink:Science.PeakDetectChlActive = 0 bool 2024-08-07T20:47:47.909Z,1723063667.909 [MissionManager](INFO): DefineArg sink:Science.TimeWindowPeakReport = nan min 2024-08-07T20:47:47.912Z,1723063667.912 [MissionManager](INFO): DefineArg sink:Science.HighestChlPeakReportActive = 0 bool 2024-08-07T20:47:47.918Z,1723063667.918 [MissionManager](INFO): DefineArg sink:Science.HighestSaltPeakReportActive = 0 bool 2024-08-07T20:47:47.920Z,1723063667.920 [MissionManager](INFO): DefineArg sink:Science.HighestOilPeakReportActive = 0 bool 2024-08-07T20:47:47.926Z,1723063667.926 [MissionManager](INFO): DefineArg sink:Science.PatchTracking = 0 bool 2024-08-07T20:47:47.928Z,1723063667.928 [MissionManager](INFO): DefineArg sink:Science.FilterWidthHorizontal = 3.000000 count 2024-08-07T20:47:47.935Z,1723063667.935 [MissionManager](INFO): DefineArg sink:Science.NumProfilesSlidingwindow = 100.000000 count 2024-08-07T20:47:47.977Z,1723063667.977 [MissionManager](INFO): DefineArg sink:Science.OffPeakFractionHorizontal = 80.000000 % 2024-08-07T20:47:47.980Z,1723063667.980 [MissionManager](INFO): DefineArg sink:Science.PeakDetectNO3Active = 0 bool 2024-08-07T20:47:48.018Z,1723063668.018 [Reporter](INFO): platform_buoyancy_position 988.420776 cc 2024-08-07T20:47:48.033Z,1723063668.033 [MissionManager](INFO): DefineArg sink:Science.PeakDetectOilActive = 0 bool 2024-08-07T20:47:48.035Z,1723063668.035 [MissionManager](INFO): DefineArg sink:Science.PeakDetectFDOMActive = 0 bool 2024-08-07T20:47:48.073Z,1723063668.073 [MissionManager](INFO): DefineArg sink:Science.PeakDetectSalinityActive = 0 bool 2024-08-07T20:47:48.091Z,1723063668.091 [MissionManager](INFO): DefineArg sink:Science.UpwardDerivativeOfTemperatureActive = 0 bool 2024-08-07T20:47:48.122Z,1723063668.122 [MissionManager](INFO): DefineArg sink:Science.EnabledAanderaaO2 = 0 bool 2024-08-07T20:47:48.124Z,1723063668.124 [MissionManager](INFO): DefineArg sink:Science.EnabledNeilBrown = 0 bool 2024-08-07T20:47:48.139Z,1723063668.139 [MissionManager](INFO): DefineArg sink:Science.EnabledSeabird = 1 bool 2024-08-07T20:47:48.153Z,1723063668.153 [MissionManager](INFO): DefineArg sink:Science.EnabledWetLabsBB2FL = 1 bool 2024-08-07T20:47:48.167Z,1723063668.167 [MissionManager](INFO): DefineArg sink:Science.EnabledWetLabsSeaOWL_UV_A = 0 bool 2024-08-07T20:47:48.171Z,1723063668.171 [MissionManager](INFO): DefineArg sink:Science.EnabledWetLabsUBAT = 1 bool 2024-08-07T20:47:48.213Z,1723063668.213 [MissionManager](INFO): DefineArg sink:Science.LowPassWindowLength = 20.000000 count 2024-08-07T20:47:48.215Z,1723063668.215 [MissionManager](INFO): DefineArg sink:Science.MedianFilterLen = 5.000000 count 2024-08-07T20:47:48.230Z,1723063668.230 [MissionManager](INFO): DefineArg sink:Science.PeakShallowBound = nan m 2024-08-07T20:47:48.232Z,1723063668.232 [MissionManager](INFO): DefineArg sink:Science.PeakDeepBound = nan m 2024-08-07T20:47:48.258Z,1723063668.258 [MissionManager](INFO): DefineArg sink:Science.DepChangeThreshForAttitudeFlip = 2.000000 m 2024-08-07T20:47:48.260Z,1723063668.260 [MissionManager](INFO): DefineArg sink:Science.ChlThreshTriggerActive = 0 bool 2024-08-07T20:47:48.278Z,1723063668.278 [MissionManager](INFO): DefineArg sink:Science.ChlLowerThresh = nan ug/l 2024-08-07T20:47:48.280Z,1723063668.280 [MissionManager](INFO): DefineArg sink:Science.ChlUpperThresh = nan ug/l 2024-08-07T20:47:48.299Z,1723063668.299 [MissionManager](INFO): DefineArg sink:Science.NumProfilesThresh = 5.000000 count 2024-08-07T20:47:48.317Z,1723063668.317 [MissionManager](INFO): DefineOutput sink:Science.PeakChl = nan ug/l 2024-08-07T20:47:48.319Z,1723063668.319 [MissionManager](INFO): DefineOutput sink:Science.PeakChlDepth = nan m 2024-08-07T20:47:48.369Z,1723063668.369 [MissionManager](INFO): DefineOutput sink:Science.PeakChlTemperature = nan degC 2024-08-07T20:47:48.371Z,1723063668.371 [MissionManager](INFO): DefineOutput sink:Science.PeakChlLatitude = nan arcdeg 2024-08-07T20:47:48.390Z,1723063668.390 [MissionManager](INFO): DefineOutput sink:Science.PeakChlLongitude = nan arcdeg 2024-08-07T20:47:48.392Z,1723063668.392 [MissionManager](INFO): DefineOutput sink:Science.PeakNO3 = nan umol/l 2024-08-07T20:47:48.430Z,1723063668.430 [MissionManager](INFO): DefineOutput sink:Science.PeakNO3Depth = nan m 2024-08-07T20:47:48.433Z,1723063668.433 [MissionManager](INFO): DefineOutput sink:Science.PeakNO3Latitude = nan arcdeg 2024-08-07T20:47:48.435Z,1723063668.435 [MissionManager](INFO): DefineOutput sink:Science.PeakNO3Longitude = nan arcdeg 2024-08-07T20:47:48.464Z,1723063668.464 [MissionManager](INFO): DefineOutput sink:Science.PeakOil = nan kg/m3 2024-08-07T20:47:48.493Z,1723063668.493 [MissionManager](INFO): DefineOutput sink:Science.PeakOilDepth = nan m 2024-08-07T20:47:48.503Z,1723063668.503 [MissionManager](INFO): DefineOutput sink:Science.PeakOilLatitude = nan arcdeg 2024-08-07T20:47:48.516Z,1723063668.516 [MissionManager](INFO): DefineOutput sink:Science.PeakOilLongitude = nan arcdeg 2024-08-07T20:47:48.530Z,1723063668.530 [MissionManager](INFO): DefineOutput sink:Science.PeakFDOM = nan ppb 2024-08-07T20:47:48.532Z,1723063668.532 [MissionManager](INFO): DefineOutput sink:Science.PeakFDOMDepth = nan m 2024-08-07T20:47:48.551Z,1723063668.551 [MissionManager](INFO): DefineOutput sink:Science.PeakFDOMTemperature = nan degC 2024-08-07T20:47:48.562Z,1723063668.562 [MissionManager](INFO): DefineOutput sink:Science.PeakFDOMLatitude = nan arcdeg 2024-08-07T20:47:48.564Z,1723063668.564 [MissionManager](INFO): DefineOutput sink:Science.PeakFDOMLongitude = nan arcdeg 2024-08-07T20:47:48.582Z,1723063668.582 [MissionManager](INFO): DefineOutput sink:Science.PeakSalt = nan psu 2024-08-07T20:47:48.609Z,1723063668.609 [MissionManager](INFO): DefineOutput sink:Science.PeakSaltDepth = nan m 2024-08-07T20:47:48.611Z,1723063668.611 [MissionManager](INFO): DefineOutput sink:Science.PeakSaltTemperature = nan degC 2024-08-07T20:47:48.626Z,1723063668.626 [MissionManager](INFO): DefineOutput sink:Science.PeakSaltLatitude = nan arcdeg 2024-08-07T20:47:48.628Z,1723063668.628 [MissionManager](INFO): DefineOutput sink:Science.PeakSaltLongitude = nan arcdeg 2024-08-07T20:47:48.674Z,1723063668.674 [MissionManager](INFO): DefineOutput sink:Science.PatchChl = nan ug/l 2024-08-07T20:47:48.697Z,1723063668.697 [MissionManager](INFO): DefineOutput sink:Science.PatchChlDepth = nan m 2024-08-07T20:47:48.699Z,1723063668.699 [MissionManager](INFO): DefineOutput sink:Science.PatchChlLatitude = nan arcdeg 2024-08-07T20:47:48.714Z,1723063668.714 [MissionManager](INFO): DefineOutput sink:Science.PatchChlLongitude = nan arcdeg 2024-08-07T20:47:48.716Z,1723063668.716 [MissionManager](INFO): DefineOutput sink:Science.PatchChlDistance = nan m 2024-08-07T20:47:48.727Z,1723063668.727 [MissionManager](INFO): DefineOutput sink:Science.PatchSalt = nan psu 2024-08-07T20:47:48.739Z,1723063668.739 [MissionManager](INFO): DefineOutput sink:Science.PatchSaltDepth = nan m 2024-08-07T20:47:48.750Z,1723063668.750 [MissionManager](INFO): DefineOutput sink:Science.PatchSaltLatitude = nan arcdeg 2024-08-07T20:47:48.752Z,1723063668.752 [MissionManager](INFO): DefineOutput sink:Science.PatchSaltLongitude = nan arcdeg 2024-08-07T20:47:48.775Z,1723063668.775 [MissionManager](INFO): DefineOutput sink:Science.PatchSaltDistance = nan m 2024-08-07T20:47:48.786Z,1723063668.786 [MissionManager](INFO): DefineOutput sink:Science.PatchOil = nan psu 2024-08-07T20:47:48.807Z,1723063668.807 [MissionManager](INFO): DefineOutput sink:Science.PatchOilDepth = nan m 2024-08-07T20:47:48.822Z,1723063668.822 [MissionManager](INFO): DefineOutput sink:Science.PatchOilLatitude = nan arcdeg 2024-08-07T20:47:48.847Z,1723063668.847 [MissionManager](INFO): DefineOutput sink:Science.PatchOilLongitude = nan arcdeg 2024-08-07T20:47:48.873Z,1723063668.873 [MissionManager](INFO): DefineOutput sink:Science.PatchOilDistance = nan m 2024-08-07T20:47:48.875Z,1723063668.875 [MissionManager](INFO): DefineOutput sink:Science.TriggeredOnChl = 0 bool 2024-08-07T20:47:48.886Z,1723063668.886 [MissionManager](INFO): DefineOutput sink:Science.ChlTriggering = nan ug/l 2024-08-07T20:47:49.033Z,1723063669.033 [sink:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2024-08-07T20:47:49.143Z,1723063669.143 [sink:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2024-08-07T20:47:49.196Z,1723063669.196 [sink:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2024-08-07T20:47:49.278Z,1723063669.278 [sink:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2024-08-07T20:47:49.339Z,1723063669.339 [sink:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2024-08-07T20:47:49.371Z,1723063669.371 [sink:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2024-08-07T20:47:49.450Z,1723063669.450 [sink:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2024-08-07T20:47:49.514Z,1723063669.514 [sink:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2024-08-07T20:47:49.609Z,1723063669.609 [sink:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Construct ValueDetect. 2024-08-07T20:47:49.643Z,1723063669.643 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl 2024-08-07T20:47:49.643Z,1723063669.643 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl 2024-08-07T20:47:49.653Z,1723063669.653 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl 2024-08-07T20:47:50.467Z,1723063670.467 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/NeedComms.tl translated into: 1 10 20 NaN 1 1000 7 30 0 last time_fix was: first GPS update timeout Comms timed out after minutes second GPS update timeout 2024-08-07T20:47:50.480Z,1723063670.480 [MissionManager](INFO): DefineArg sink:NeedComms.DiveInterval = 1.000000 h 2024-08-07T20:47:50.495Z,1723063670.495 [MissionManager](INFO): DefineArg sink:NeedComms.WaitForPitchUp = 10.000000 min 2024-08-07T20:47:50.513Z,1723063670.513 [MissionManager](INFO): DefineArg sink:NeedComms.SurfacePitch = 20.000000 arcdeg 2024-08-07T20:47:50.515Z,1723063670.515 [MissionManager](INFO): DefineArg sink:NeedComms.SurfaceDepthRate = nan m/s 2024-08-07T20:47:50.525Z,1723063670.525 [MissionManager](INFO): DefineArg sink:NeedComms.SurfaceSpeed = 1.000000 m/s 2024-08-07T20:47:50.528Z,1723063670.528 [MissionManager](INFO): DefineArg sink:NeedComms.SurfacingTimeout = 1000.000000 s 2024-08-07T20:47:50.534Z,1723063670.534 [MissionManager](INFO): DefineArg sink:NeedComms.GPSTimeout = 7.000000 min 2024-08-07T20:47:50.536Z,1723063670.536 [MissionManager](INFO): DefineArg sink:NeedComms.CommsTimeout = 30.000000 min 2024-08-07T20:47:50.602Z,1723063670.602 [sink:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-08-07T20:47:50.706Z,1723063670.706 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl 2024-08-07T20:47:50.706Z,1723063670.706 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl 2024-08-07T20:47:50.707Z,1723063670.707 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl 2024-08-07T20:47:50.901Z,1723063670.901 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into: 5 0 200 2000 2024-08-07T20:47:50.909Z,1723063670.909 [MissionManager](INFO): DefineArg sink:StandardEnvelopes.MinAltitude = 5.000000 m 2024-08-07T20:47:50.911Z,1723063670.911 [MissionManager](INFO): DefineArg sink:StandardEnvelopes.MaxDepthIgnore = 0.000000 m 2024-08-07T20:47:50.917Z,1723063670.917 [MissionManager](INFO): DefineArg sink:StandardEnvelopes.MaxDepth = 200.000000 m 2024-08-07T20:47:50.919Z,1723063670.919 [MissionManager](INFO): DefineArg sink:StandardEnvelopes.MinOffshore = 2000.000000 m 2024-08-07T20:47:50.920Z,1723063670.920 [sink:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2024-08-07T20:47:50.934Z,1723063670.934 [sink:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2024-08-07T20:47:50.944Z,1723063670.944 [sink:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2024-08-07T20:47:51.041Z,1723063671.041 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl 2024-08-07T20:47:51.041Z,1723063671.041 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl 2024-08-07T20:47:51.042Z,1723063671.042 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl 2024-08-07T20:47:51.183Z,1723063671.183 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into: 2024-08-07T20:47:51.202Z,1723063671.202 [MissionManager](INFO): DefineArg sink:BackseatDriver.EnableBackseat = 0 bool 2024-08-07T20:47:51.216Z,1723063671.216 [sink:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver. 2024-08-07T20:47:51.226Z,1723063671.226 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl 2024-08-07T20:47:51.227Z,1723063671.227 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl 2024-08-07T20:47:51.228Z,1723063671.228 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl 2024-08-07T20:47:51.512Z,1723063671.512 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into: 99999 2024-08-07T20:47:51.524Z,1723063671.524 [MissionManager](INFO): DefineArg sink:PowerOnly.SampleAll = 0 bool 2024-08-07T20:47:51.534Z,1723063671.534 [MissionManager](INFO): DefineArg sink:PowerOnly.SampleLoad1 = 0 bool 2024-08-07T20:47:51.536Z,1723063671.536 [MissionManager](INFO): DefineArg sink:PowerOnly.SampleLoad2 = 0 bool 2024-08-07T20:47:51.542Z,1723063671.542 [MissionManager](INFO): DefineArg sink:PowerOnly.SampleLoad3 = 0 bool 2024-08-07T20:47:51.544Z,1723063671.544 [MissionManager](INFO): DefineArg sink:PowerOnly.EnabledPowerOnly = 0 bool 2024-08-07T20:47:51.595Z,1723063671.595 [sink:PowerOnly:E.Wait](DEBUG): Construct Wait. 2024-08-07T20:47:51.618Z,1723063671.618 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BallastAndTrim.tl 2024-08-07T20:47:51.618Z,1723063671.618 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BallastAndTrim.tl 2024-08-07T20:47:51.620Z,1723063671.620 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BallastAndTrim.tl 2024-08-07T20:47:52.497Z,1723063672.497 [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-08-07T20:47:52.504Z,1723063672.504 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.InsertDepth = 25.000000 m 2024-08-07T20:47:52.506Z,1723063672.506 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.InsertApproachSpeed = 0.000000 m/s 2024-08-07T20:47:52.508Z,1723063672.508 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.InsertApproachDepthRate = 0.400000 m/s 2024-08-07T20:47:52.553Z,1723063672.553 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.InsertApproachPitchLimit = 20.000000 arcdeg 2024-08-07T20:47:52.555Z,1723063672.555 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.InsertApproachSettleTimePreDive = 0.000000 min 2024-08-07T20:47:52.558Z,1723063672.558 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.InsertApproachDepthTimeout = 20.000000 min 2024-08-07T20:47:52.560Z,1723063672.560 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.InsertSettleTime = 10.000000 min 2024-08-07T20:47:52.587Z,1723063672.587 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.EstTimeout = 45.000000 min 2024-08-07T20:47:52.593Z,1723063672.593 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.MinEstTime = 10.000000 min 2024-08-07T20:47:52.596Z,1723063672.596 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.MassEstErrorBound = 0.250000 mm 2024-08-07T20:47:52.602Z,1723063672.602 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.BuoyEstErrorBound = 15.000000 cc 2024-08-07T20:47:52.604Z,1723063672.604 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.EstConfidence = 99.900002 % 2024-08-07T20:47:52.612Z,1723063672.612 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.InsertDepthDeadband = 1.000000 m 2024-08-07T20:47:52.630Z,1723063672.630 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.InsertMassDeadband = 0.250000 mm 2024-08-07T20:47:52.703Z,1723063672.703 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Construct. 2024-08-07T20:47:52.720Z,1723063672.720 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Construct Buoyancy. 2024-08-07T20:47:52.727Z,1723063672.727 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Construct. 2024-08-07T20:47:52.760Z,1723063672.760 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Construct Wait. 2024-08-07T20:47:52.777Z,1723063672.777 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Construct. 2024-08-07T20:47:52.780Z,1723063672.780 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Construct. 2024-08-07T20:47:52.801Z,1723063672.801 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute. 2024-08-07T20:47:52.814Z,1723063672.814 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Construct. 2024-08-07T20:47:52.816Z,1723063672.816 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Construct. 2024-08-07T20:47:52.845Z,1723063672.845 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Construct Wait. 2024-08-07T20:47:52.846Z,1723063672.846 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:E.Execute](DEBUG): Construct Execute. 2024-08-07T20:47:52.850Z,1723063672.850 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](DEBUG): Construct BallastAndTrim. 2024-08-07T20:47:52.855Z,1723063672.855 [MissionManager](INFO): RedefineArg sink:RunSink:BallastAndTrim.InsertDepthDeadband = reader:sink.DepthDeadband 2024-08-07T20:47:52.857Z,1723063672.857 [MissionManager](INFO): RedefineArg sink:RunSink:BallastAndTrim.InsertApproachDepthTimeout = reader:sink.TargetDepthTimeout 2024-08-07T20:47:52.858Z,1723063672.858 [MissionManager](INFO): RedefineArg sink:RunSink:BallastAndTrim.InsertSettleTime = reader:sink.BTSettleTime 2024-08-07T20:47:52.860Z,1723063672.860 [MissionManager](INFO): RedefineArg sink:RunSink:BallastAndTrim.EstTimeout = reader:sink.EstimationTimeout 2024-08-07T20:47:52.869Z,1723063672.869 [MissionManager](INFO): RedefineArg sink:RunSink:BallastAndTrim.MinEstTime = reader:sink.MinEstimationTime 2024-08-07T20:47:52.871Z,1723063672.871 [MissionManager](INFO): RedefineArg sink:RunSink:BallastAndTrim.MassEstErrorBound = reader:sink.MassEstimationErrorBound 2024-08-07T20:47:52.872Z,1723063672.872 [MissionManager](INFO): RedefineArg sink:RunSink:BallastAndTrim.BuoyEstErrorBound = reader:sink.BuoyEstimationErrorBound 2024-08-07T20:47:52.884Z,1723063672.884 [MissionManager](INFO): RedefineArg sink:RunSink:BallastAndTrim.EstConfidence = reader:sink.EstimationConfidence 2024-08-07T20:47:52.944Z,1723063672.944 [sink:Float_Up:A.Buoyancy](DEBUG): Construct Buoyancy. 2024-08-07T20:47:52.959Z,1723063672.959 [sink:Float_Up:B.Wait](DEBUG): Construct Wait. 2024-08-07T20:47:53.076Z,1723063673.076 [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 sink { arguments { MissionTimeout = 2 hour """ Maximum duration of mission. """ Depth = 20 meter """ Target depth """ DepthDeadband = 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. """ WaitToFloat = 20 minute """ Timeout for reaching surface threshold on float ascent, vehicle will drive up when reached """ TargetDepthTimeout = 20 minute """ Maximum wait time for the vehicle to reach the target depth. """ DoBallastAndTrim = true """ If true, run B&T, if false, just sit at depth. """ BTSettleTime = 10 minute """ If running B&T, how long to wait after reaching target depth before starting """ EstimationTimeout = 45 minute """ If running B&T, timeout for estimation. """ MinEstimationTime = 10 minute """ If running B&T, minimum time to run estimation before determining convergence (must be shorter than timeout). """ MassEstimationErrorBound = 0.5 millimeter """ If running B&T, desired range of the estimated sample mean from the true mean statistic (smaller values take longer to converge). """ BuoyEstimationErrorBound = 25 cubic_centimeter """ If running B&T, desired range of the estimated sample mean from the true mean statistic (smaller values take longer to converge). """ EstimationConfidence = 98 percent """ If running B&T, confidence level that the estimator has reached the desired error range (larger values take longer to converge). """ MinAltitude = 5 meter """ Minimum height above the sea floor for the entire mission. """ MaxDepth = 30 meter """ Maximum depth for the entire mission. """ MinOffshore = 2 kilometer """ Minimum offshore distance for the entire mission. """ } timeout duration=MissionTimeout insert Insert/Science.tl insert id="NeedComms" Insert/NeedComms.tl assign in sequence NeedComms:DiveInterval = MissionTimeout insert Insert/StandardEnvelopes.tl assign in sequence StandardEnvelopes:MinAltitude = MinAltitude assign in sequence StandardEnvelopes:MaxDepth = MaxDepth assign in sequence StandardEnvelopes:MinOffshore = MinOffshore insert Insert/BackseatDriver.tl insert Insert/PowerOnly.tl call id="StartingMission" refId="NeedComms" aggregate RunSink { run in sequence insert Insert/BallastAndTrim.tl { redefineArg InsertDepthDeadband = DepthDeadband redefineArg InsertApproachDepthTimeout = TargetDepthTimeout redefineArg InsertSettleTime = BTSettleTime redefineArg EstTimeout = EstimationTimeout redefineArg MinEstTime = MinEstimationTime redefineArg MassEstErrorBound = MassEstimationErrorBound redefineArg BuoyEstErrorBound = BuoyEstimationErrorBound redefineArg EstConfidence = EstimationConfidence } aggregate RunBallastAndTrim { run in sequence break if ( isNaN ( Depth ) or not DoBallastAndTrim ) assign in sequence BallastAndTrim:InsertDepth = Depth call refId="BallastAndTrim" } aggregate JustSink { run in sequence break if ( isNaN ( Depth ) or DoBallastAndTrim ) assign in sequence BallastAndTrim:InsertDepth = Depth # The B&T insert will never actually invoke the B&T behavior here. # This is a little bit of a hack to avoid duplicating the ApproachDepth etc. # logic the B&T insert uses. assign in sequence BTSettleTime = MissionTimeout 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 } } call id="EndingMission" refId="NeedComms" } 2024-08-07T20:47:53.081Z,1723063673.081 [CommandExec](IMPORTANT): Loaded ./Missions/Engineering/sink.tl id=sink 2024-08-07T20:47:55.109Z,1723063675.109 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:48:08.885Z,1723063688.885 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:48:12.963Z,1723063692.963 [CommandExec](IMPORTANT): got command set sink.MissionTimeout 7 minute 2024-08-07T20:48:12.963Z,1723063692.963 [CommandExec](IMPORTANT): got command set sink.Depth 0.75 meter 2024-08-07T20:48:12.964Z,1723063692.964 [CommandExec](IMPORTANT): got command set sink.DepthDeadband .5 meter 2024-08-07T20:48:12.969Z,1723063692.969 [CommandExec](IMPORTANT): got command set sink.DoBallastAndTrim 0 bool 2024-08-07T20:48:12.970Z,1723063692.970 [CommandExec](IMPORTANT): got command set sink.MinAltitude .0001 meter 2024-08-07T20:48:12.971Z,1723063692.971 [CommandExec](IMPORTANT): got command set sink.MaxDepth 5 meter 2024-08-07T20:48:12.972Z,1723063692.972 [CommandExec](IMPORTANT): got command set sink.MinOffshore .00001 kilometer 2024-08-07T20:48:12.972Z,1723063692.972 [CommandExec](IMPORTANT): got command run 2024-08-07T20:48:13.068Z,1723063693.068 [CommandExec](IMPORTANT): Running 2024-08-07T20:48:13.311Z,1723063693.311 [Default] Stopped 2024-08-07T20:48:13.312Z,1723063693.312 [Default](DEBUG): Aggregate::uninitialize Default 2024-08-07T20:48:13.312Z,1723063693.312 [Default:B.GoToSurface] Stopped 2024-08-07T20:48:13.312Z,1723063693.312 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-08-07T20:48:13.312Z,1723063693.312 [Default:CheckIn] Stopped 2024-08-07T20:48:13.312Z,1723063693.312 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-08-07T20:48:13.312Z,1723063693.312 [Default:CheckIn:C.Wait] Stopped 2024-08-07T20:48:13.312Z,1723063693.312 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-08-07T20:48:13.312Z,1723063693.312 [MissionManager](IMPORTANT): Started mission sink 2024-08-07T20:48:13.312Z,1723063693.312 [sink] Running Loop=1 2024-08-07T20:48:13.321Z,1723063693.321 [sink](DEBUG): Aggregate::initialize sink 2024-08-07T20:48:13.321Z,1723063693.321 [sink:Science] Running Loop=1 2024-08-07T20:48:13.321Z,1723063693.321 [sink:Science](DEBUG): Aggregate::initialize sink:Science 2024-08-07T20:48:13.321Z,1723063693.321 [sink:Science:A] Running Loop=1 2024-08-07T20:48:13.322Z,1723063693.322 [sink:Science:B] Running Loop=1 2024-08-07T20:48:13.322Z,1723063693.322 [sink:Science:C] Running Loop=1 2024-08-07T20:48:13.322Z,1723063693.322 [sink:Science:D] Running Loop=1 2024-08-07T20:48:13.322Z,1723063693.322 [sink:Science:E] Running Loop=1 2024-08-07T20:48:13.322Z,1723063693.322 [sink:Science:F] Running Loop=1 2024-08-07T20:48:13.322Z,1723063693.322 [sink:Science:Read_Oil] Running Loop=1 2024-08-07T20:48:13.322Z,1723063693.322 [sink:Science:PeakDetectChl] Running Loop=1 2024-08-07T20:48:13.322Z,1723063693.322 [sink:Science:PeakDetectChl](DEBUG): Aggregate::initialize sink:Science:PeakDetectChl 2024-08-07T20:48:13.322Z,1723063693.322 [sink:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1 2024-08-07T20:48:13.322Z,1723063693.322 [sink:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize. 2024-08-07T20:48:13.330Z,1723063693.330 [sink:Science:HighestChlPeakReport] Running Loop=1 2024-08-07T20:48:13.330Z,1723063693.330 [sink:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize sink:Science:HighestChlPeakReport 2024-08-07T20:48:13.330Z,1723063693.330 [sink:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1 2024-08-07T20:48:13.330Z,1723063693.330 [sink:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2024-08-07T20:48:13.330Z,1723063693.330 [sink:Science:PeakDetectNO3] Running Loop=1 2024-08-07T20:48:13.330Z,1723063693.330 [sink:Science:PeakDetectNO3](DEBUG): Aggregate::initialize sink:Science:PeakDetectNO3 2024-08-07T20:48:13.331Z,1723063693.331 [sink:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1 2024-08-07T20:48:13.331Z,1723063693.331 [sink:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize. 2024-08-07T20:48:13.332Z,1723063693.332 [sink:Science:PeakDetectOil] Running Loop=1 2024-08-07T20:48:13.332Z,1723063693.332 [sink:Science:PeakDetectOil](DEBUG): Aggregate::initialize sink:Science:PeakDetectOil 2024-08-07T20:48:13.332Z,1723063693.332 [sink:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1 2024-08-07T20:48:13.332Z,1723063693.332 [sink:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize. 2024-08-07T20:48:13.337Z,1723063693.337 [sink:Science:HighestOilPeakReport] Running Loop=1 2024-08-07T20:48:13.337Z,1723063693.337 [sink:Science:HighestOilPeakReport](DEBUG): Aggregate::initialize sink:Science:HighestOilPeakReport 2024-08-07T20:48:13.338Z,1723063693.338 [sink:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Running Loop=1 2024-08-07T20:48:13.338Z,1723063693.338 [sink:Science:HighestOilPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2024-08-07T20:48:13.338Z,1723063693.338 [sink:Science:PeakDetectFDOM] Running Loop=1 2024-08-07T20:48:13.338Z,1723063693.338 [sink:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize sink:Science:PeakDetectFDOM 2024-08-07T20:48:13.338Z,1723063693.338 [sink:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1 2024-08-07T20:48:13.338Z,1723063693.338 [sink:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize. 2024-08-07T20:48:13.339Z,1723063693.339 [sink:Science:PeakDetectSalinity] Running Loop=1 2024-08-07T20:48:13.339Z,1723063693.339 [sink:Science:PeakDetectSalinity](DEBUG): Aggregate::initialize sink:Science:PeakDetectSalinity 2024-08-07T20:48:13.340Z,1723063693.340 [sink:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Running Loop=1 2024-08-07T20:48:13.340Z,1723063693.340 [sink:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Initialize. 2024-08-07T20:48:13.365Z,1723063693.365 [sink:Science:HighestSaltPeakReport] Running Loop=1 2024-08-07T20:48:13.365Z,1723063693.365 [sink:Science:HighestSaltPeakReport](DEBUG): Aggregate::initialize sink:Science:HighestSaltPeakReport 2024-08-07T20:48:13.365Z,1723063693.365 [sink:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Running Loop=1 2024-08-07T20:48:13.365Z,1723063693.365 [sink:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2024-08-07T20:48:13.366Z,1723063693.366 [sink:Science:ChlThreshTrigger] Running Loop=1 2024-08-07T20:48:13.366Z,1723063693.366 [sink:Science:ChlThreshTrigger](DEBUG): Aggregate::initialize sink:Science:ChlThreshTrigger 2024-08-07T20:48:13.366Z,1723063693.366 [sink:Science:ChlThreshTrigger:A.ValueDetect] Running Loop=1 2024-08-07T20:48:13.366Z,1723063693.366 [sink:Science:ChlThreshTrigger:A.ValueDetect](INFO): Initialize. 2024-08-07T20:48:13.366Z,1723063693.366 [sink:Science:OceanCurrent] Running Loop=1 2024-08-07T20:48:13.367Z,1723063693.367 [sink:Science:OceanCurrent](DEBUG): Aggregate::initialize sink:Science:OceanCurrent 2024-08-07T20:48:13.367Z,1723063693.367 [sink:Science:OceanCurrent:A.] Running Loop=1 2024-08-07T20:48:13.367Z,1723063693.367 [sink:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator. 2024-08-07T20:48:13.367Z,1723063693.367 [sink:StandardEnvelopes] Running Loop=1 2024-08-07T20:48:13.367Z,1723063693.367 [sink:StandardEnvelopes](DEBUG): Aggregate::initialize sink:StandardEnvelopes 2024-08-07T20:48:13.367Z,1723063693.367 [sink:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2024-08-07T20:48:13.367Z,1723063693.367 [sink:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2024-08-07T20:48:13.367Z,1723063693.367 [sink:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2024-08-07T20:48:13.367Z,1723063693.367 [sink:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2024-08-07T20:48:13.367Z,1723063693.367 [sink:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2024-08-07T20:48:13.368Z,1723063693.368 [sink:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2024-08-07T20:48:13.368Z,1723063693.368 [sink:BackseatDriver] Running Loop=1 2024-08-07T20:48:13.368Z,1723063693.368 [sink:BackseatDriver](DEBUG): Aggregate::initialize sink:BackseatDriver 2024-08-07T20:48:13.368Z,1723063693.368 [sink:BackseatDriver:A.BackseatDriver] Running Loop=1 2024-08-07T20:48:13.368Z,1723063693.368 [sink:PowerOnly] Running Loop=1 2024-08-07T20:48:13.368Z,1723063693.368 [sink:PowerOnly](DEBUG): Aggregate::initialize sink:PowerOnly 2024-08-07T20:48:13.368Z,1723063693.368 [sink:PowerOnly:A] Running Loop=1 2024-08-07T20:48:13.368Z,1723063693.368 [sink:PowerOnly:B] Running Loop=1 2024-08-07T20:48:13.368Z,1723063693.368 [sink:PowerOnly:C] Running Loop=1 2024-08-07T20:48:13.368Z,1723063693.368 [sink:PowerOnly:D] Running Loop=1 2024-08-07T20:48:13.368Z,1723063693.368 [sink:PowerOnly:E.Wait] Running Loop=1 2024-08-07T20:48:13.381Z,1723063693.381 [sink:PowerOnly:E.Wait](DEBUG): Initialize Wait Component. 2024-08-07T20:48:13.381Z,1723063693.381 [sink:C] Running Loop=1 2024-08-07T20:48:13.382Z,1723063693.382 [sink:PowerOnly] Running Loop=1 2024-08-07T20:48:13.382Z,1723063693.382 [sink:PowerOnly:D] Stopped 2024-08-07T20:48:13.382Z,1723063693.382 [sink:PowerOnly:C] Stopped 2024-08-07T20:48:13.382Z,1723063693.382 [sink:PowerOnly:B] Stopped 2024-08-07T20:48:13.382Z,1723063693.382 [sink:PowerOnly:A] Stopped 2024-08-07T20:48:13.382Z,1723063693.382 [sink:BackseatDriver] Running Loop=1 2024-08-07T20:48:13.382Z,1723063693.382 [sink:StandardEnvelopes] Running Loop=1 2024-08-07T20:48:13.382Z,1723063693.382 [sink:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2024-08-07T20:48:13.383Z,1723063693.383 [sink:StandardEnvelopes:C.OffshoreEnvelope](IMPORTANT): Offshore envelope ACTIVE 2024-08-07T20:48:13.446Z,1723063693.446 [sink:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2024-08-07T20:48:13.447Z,1723063693.447 [sink:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2024-08-07T20:48:13.448Z,1723063693.448 [sink:C] Stopped 2024-08-07T20:48:13.461Z,1723063693.461 [sink:E] Running Loop=1 2024-08-07T20:48:13.461Z,1723063693.461 [sink:Science] Running Loop=1 2024-08-07T20:48:13.464Z,1723063693.464 [sink:Science:ChlThreshTrigger] Stopped 2024-08-07T20:48:13.464Z,1723063693.464 [sink:Science:ChlThreshTrigger](DEBUG): Aggregate::uninitialize sink:Science:ChlThreshTrigger 2024-08-07T20:48:13.473Z,1723063693.473 [sink:Science:ChlThreshTrigger:A.ValueDetect] Stopped 2024-08-07T20:48:13.473Z,1723063693.473 [sink:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Uninitialize. 2024-08-07T20:48:13.474Z,1723063693.474 [sink:Science:HighestSaltPeakReport] Stopped 2024-08-07T20:48:13.474Z,1723063693.474 [sink:Science:HighestSaltPeakReport](DEBUG): Aggregate::uninitialize sink:Science:HighestSaltPeakReport 2024-08-07T20:48:13.474Z,1723063693.474 [sink:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Stopped 2024-08-07T20:48:13.474Z,1723063693.474 [sink:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2024-08-07T20:48:13.474Z,1723063693.474 [sink:Science:PeakDetectSalinity] Stopped 2024-08-07T20:48:13.475Z,1723063693.475 [sink:Science:PeakDetectSalinity](DEBUG): Aggregate::uninitialize sink:Science:PeakDetectSalinity 2024-08-07T20:48:13.475Z,1723063693.475 [sink:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Stopped 2024-08-07T20:48:13.475Z,1723063693.475 [sink:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2024-08-07T20:48:13.475Z,1723063693.475 [sink:Science:PeakDetectFDOM] Stopped 2024-08-07T20:48:13.476Z,1723063693.476 [sink:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize sink:Science:PeakDetectFDOM 2024-08-07T20:48:13.476Z,1723063693.476 [sink:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped 2024-08-07T20:48:13.476Z,1723063693.476 [sink:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2024-08-07T20:48:13.476Z,1723063693.476 [sink:Science:HighestOilPeakReport] Stopped 2024-08-07T20:48:13.476Z,1723063693.476 [sink:Science:HighestOilPeakReport](DEBUG): Aggregate::uninitialize sink:Science:HighestOilPeakReport 2024-08-07T20:48:13.493Z,1723063693.493 [sink:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Stopped 2024-08-07T20:48:13.493Z,1723063693.493 [sink:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2024-08-07T20:48:13.493Z,1723063693.493 [sink:Science:PeakDetectOil] Stopped 2024-08-07T20:48:13.494Z,1723063693.494 [sink:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize sink:Science:PeakDetectOil 2024-08-07T20:48:13.494Z,1723063693.494 [sink:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped 2024-08-07T20:48:13.494Z,1723063693.494 [sink:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2024-08-07T20:48:13.494Z,1723063693.494 [sink:Science:PeakDetectNO3] Stopped 2024-08-07T20:48:13.495Z,1723063693.495 [sink:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize sink:Science:PeakDetectNO3 2024-08-07T20:48:13.495Z,1723063693.495 [sink:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped 2024-08-07T20:48:13.495Z,1723063693.495 [sink:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2024-08-07T20:48:13.495Z,1723063693.495 [sink:Science:HighestChlPeakReport] Stopped 2024-08-07T20:48:13.495Z,1723063693.495 [sink:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize sink:Science:HighestChlPeakReport 2024-08-07T20:48:13.495Z,1723063693.495 [sink:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped 2024-08-07T20:48:13.496Z,1723063693.496 [sink:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2024-08-07T20:48:13.496Z,1723063693.496 [sink:Science:PeakDetectChl] Stopped 2024-08-07T20:48:13.496Z,1723063693.496 [sink:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize sink:Science:PeakDetectChl 2024-08-07T20:48:13.496Z,1723063693.496 [sink:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped 2024-08-07T20:48:13.496Z,1723063693.496 [sink:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2024-08-07T20:48:13.505Z,1723063693.505 [sink:Science:OceanCurrent:A.] Running Loop=1 2024-08-07T20:48:13.506Z,1723063693.506 [sink:Science:Read_Oil] Stopped 2024-08-07T20:48:13.506Z,1723063693.506 [sink:Science:F] Running Loop=1 2024-08-07T20:48:13.509Z,1723063693.509 [sink:Science:F](DEBUG): Initialize ReadDataComponent to sense WetLabsUBAT.average_bioluminescence 2024-08-07T20:48:13.509Z,1723063693.509 [sink:Science:E] Running Loop=1 2024-08-07T20:48:13.510Z,1723063693.510 [sink:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water 2024-08-07T20:48:13.510Z,1723063693.510 [sink:Science:D] Stopped 2024-08-07T20:48:13.510Z,1723063693.510 [sink:Science:C] Running Loop=1 2024-08-07T20:48:13.510Z,1723063693.510 [sink:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature 2024-08-07T20:48:13.511Z,1723063693.511 [sink:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity 2024-08-07T20:48:13.511Z,1723063693.511 [sink:Science:B] Running Loop=1 2024-08-07T20:48:13.511Z,1723063693.511 [sink:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature 2024-08-07T20:48:13.512Z,1723063693.512 [sink:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity 2024-08-07T20:48:13.512Z,1723063693.512 [sink:Science:A] Stopped 2024-08-07T20:48:13.886Z,1723063693.886 [sink:E] Stopped 2024-08-07T20:48:13.887Z,1723063693.887 [sink:F] Running Loop=1 2024-08-07T20:48:13.949Z,1723063693.949 [WetLabsUBAT](INFO): Powering up 2024-08-07T20:48:13.961Z,1723063693.961 [WetLabsBB2FL](INFO): Powering up 2024-08-07T20:48:14.251Z,1723063694.251 [sink:F] Stopped 2024-08-07T20:48:14.251Z,1723063694.251 [sink:G] Running Loop=1 2024-08-07T20:48:14.671Z,1723063694.671 [sink:G] Stopped 2024-08-07T20:48:14.672Z,1723063694.672 [sink:StartingMission] Running Loop=1 2024-08-07T20:48:14.672Z,1723063694.672 [sink:StartingMission](DEBUG): Aggregate::initialize sink:StartingMission 2024-08-07T20:48:15.015Z,1723063695.015 [sink:NeedComms] Running Loop=1 2024-08-07T20:48:15.015Z,1723063695.015 [sink:NeedComms](DEBUG): Aggregate::initialize sink:NeedComms 2024-08-07T20:48:15.016Z,1723063695.016 [sink:NeedComms:B.GoToSurface] Running Loop=1 2024-08-07T20:48:15.016Z,1723063695.016 [sink:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-08-07T20:48:15.016Z,1723063695.016 [sink:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2024-08-07T20:48:15.016Z,1723063695.016 [sink:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2024-08-07T20:48:15.033Z,1723063695.033 [sink:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2024-08-07T20:48:15.033Z,1723063695.033 [sink:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2024-08-07T20:48:15.034Z,1723063695.034 [sink:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-08-07T20:48:15.034Z,1723063695.034 [sink:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-08-07T20:48:15.034Z,1723063695.034 [sink:NeedComms:A] Running Loop=1 2024-08-07T20:48:15.036Z,1723063695.036 [sink:NeedComms:A](INFO): last time_fix was: 1723063558.000000 second since 1970/01/01T00:00:00Z 2024-08-07T20:48:15.036Z,1723063695.036 [sink:NeedComms:A] Stopped 2024-08-07T20:48:15.484Z,1723063695.484 [sink:NeedComms:C] Running Loop=1 2024-08-07T20:48:16.039Z,1723063696.039 [sink:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix 2024-08-07T20:48:16.662Z,1723063696.662 [CTD_Seabird](ERROR): Failed to parse device response: 2024-08-07T20:48:17.606Z,1723063697.606 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204816.00,A,3648.15892,N,12147.28358,W,0.117,158.65,070824,,,D*7A 2024-08-07T20:48:17.617Z,1723063697.617 [NAL9602](INFO): GPS fix at 20240807T204816: (36.802649, -121.788060) 2024-08-07T20:48:17.758Z,1723063697.758 [sink:NeedComms:C] Stopped 2024-08-07T20:48:17.758Z,1723063697.758 [sink:NeedComms:D] Running Loop=1 2024-08-07T20:48:18.150Z,1723063698.150 [Reporter](INFO): platform_buoyancy_position 990.301025 cc 2024-08-07T20:48:18.240Z,1723063698.240 [sink:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-08-07T20:48:20.767Z,1723063700.767 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240807T203219/Courier0006.lzma 2024-08-07T20:48:21.386Z,1723063701.386 [DataOverHttps](INFO): Moved sent file to Logs/20240807T203219/Courier0006.lzma.bak 2024-08-07T20:48:21.387Z,1723063701.387 [DataOverHttps](INFO): SBD MOMSN=21446182 2024-08-07T20:48:24.138Z,1723063704.138 [WetLabsUBAT](FAULT): UBAT flow rate is below the specified threshold of 0.05 l/s. 2024-08-07T20:48:48.395Z,1723063728.395 [Reporter](INFO): platform_buoyancy_position 990.301025 cc 2024-08-07T20:48:48.546Z,1723063728.546 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2024-08-07T20:48:48.629Z,1723063728.629 [NAL9602](ERROR): received: +CSQ:0 OK380, 2, 0, 0, 0 OK 2024-08-07T20:48:49.916Z,1723063729.916 [DataOverHttps](INFO): Sending 825 bytes from file Logs/20240807T203219/Express0007.lzma 2024-08-07T20:48:50.656Z,1723063730.656 [CommandExec](IMPORTANT): got command set sink:NeedComms.CommsTimeout 30 second 2024-08-07T20:48:50.661Z,1723063730.661 [CommandExec](IMPORTANT): got command set sink:NeedComms.GPSTimeout 30 second 2024-08-07T20:48:50.911Z,1723063730.911 [DataOverHttps](INFO): Moved sent file to Logs/20240807T203219/Express0007.lzma.bak 2024-08-07T20:48:50.911Z,1723063730.911 [DataOverHttps](INFO): SBD MOMSN=21446187 2024-08-07T20:48:51.060Z,1723063731.060 [sink:NeedComms:D](INFO): Timed out from 2024-08-07T20:48:17.8Z 2024-08-07T20:48:51.060Z,1723063731.060 [sink:NeedComms:D:A_Timeout] Running Loop=1 2024-08-07T20:48:51.060Z,1723063731.060 [sink:NeedComms:D:A_Timeout](DEBUG): Aggregate::initialize sink:NeedComms:D:A_Timeout 2024-08-07T20:48:51.060Z,1723063731.060 [sink:NeedComms:D:A_Timeout:A] Running Loop=1 2024-08-07T20:48:51.066Z,1723063731.066 [sink:NeedComms:D:A_Timeout:A](IMPORTANT): Comms timed out after 0.500000 min minutes 2024-08-07T20:48:51.066Z,1723063731.066 [sink:NeedComms:D:A_Timeout:A] Stopped 2024-08-07T20:48:51.066Z,1723063731.066 [sink:NeedComms:D:A_Timeout](INFO): Completed sink:NeedComms:D:A_Timeout 2024-08-07T20:48:51.066Z,1723063731.066 [sink:NeedComms:D] Stopped 2024-08-07T20:48:51.066Z,1723063731.066 [sink:NeedComms:E] Running Loop=1 2024-08-07T20:48:51.403Z,1723063731.403 [sink:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix 2024-08-07T20:48:58.331Z,1723063738.331 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,241746,35.0, +21.2, 0.0,1524.9,A, 0.00, 110.66, 90.49, 99.80, 100.32 2024-08-07T20:48:58.721Z,1723063738.721 [WetLabsUBAT](ERROR): Failed to parse incomplete device message. 2024-08-07T20:49:07.782Z,1723063747.782 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:49:18.434Z,1723063758.434 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:49:18.791Z,1723063758.791 [Reporter](INFO): platform_buoyancy_position 990.301025 cc 2024-08-07T20:49:21.516Z,1723063761.516 [sink:NeedComms:E](INFO): Timed out from 2024-08-07T20:48:51.1Z 2024-08-07T20:49:21.516Z,1723063761.516 [sink:NeedComms:E:A_Timeout] Running Loop=1 2024-08-07T20:49:21.529Z,1723063761.529 [sink:NeedComms:E:A_Timeout](DEBUG): Aggregate::initialize sink:NeedComms:E:A_Timeout 2024-08-07T20:49:21.530Z,1723063761.530 [sink:NeedComms:E:A_Timeout:A] Running Loop=1 2024-08-07T20:49:21.531Z,1723063761.531 [sink:NeedComms:E:A_Timeout:A](IMPORTANT): second GPS update timeout 2024-08-07T20:49:21.531Z,1723063761.531 [sink:NeedComms:E:A_Timeout:A] Stopped 2024-08-07T20:49:21.532Z,1723063761.532 [sink:NeedComms:E:A_Timeout](INFO): Completed sink:NeedComms:E:A_Timeout 2024-08-07T20:49:21.532Z,1723063761.532 [sink:NeedComms:E] Stopped 2024-08-07T20:49:21.537Z,1723063761.537 [sink:NeedComms](INFO): Completed sink:NeedComms 2024-08-07T20:49:21.537Z,1723063761.537 [sink:NeedComms] Stopped 2024-08-07T20:49:21.537Z,1723063761.537 [sink:NeedComms](DEBUG): Aggregate::uninitialize sink:NeedComms 2024-08-07T20:49:21.538Z,1723063761.538 [sink:NeedComms:B.GoToSurface] Stopped 2024-08-07T20:49:21.538Z,1723063761.538 [sink:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-08-07T20:49:21.912Z,1723063761.912 [sink:StartingMission](INFO): Completed sink:StartingMission 2024-08-07T20:49:21.912Z,1723063761.912 [sink:StartingMission] Stopped 2024-08-07T20:49:21.941Z,1723063761.941 [sink:StartingMission](DEBUG): Aggregate::uninitialize sink:StartingMission 2024-08-07T20:49:21.941Z,1723063761.941 [sink:RunSink] Running Loop=1 2024-08-07T20:49:21.941Z,1723063761.941 [sink:RunSink](DEBUG): Aggregate::initialize sink:RunSink 2024-08-07T20:49:21.941Z,1723063761.941 [sink:RunSink:RunBallastAndTrim] Running Loop=1 2024-08-07T20:49:21.941Z,1723063761.941 [sink:RunSink:RunBallastAndTrim](DEBUG): Aggregate::initialize sink:RunSink:RunBallastAndTrim 2024-08-07T20:49:21.941Z,1723063761.941 [sink:RunSink:RunBallastAndTrim:A] Running Loop=1 2024-08-07T20:49:22.267Z,1723063762.267 [sink:RunSink:RunBallastAndTrim] Stopped 2024-08-07T20:49:22.268Z,1723063762.268 [sink:RunSink:RunBallastAndTrim](DEBUG): Aggregate::uninitialize sink:RunSink:RunBallastAndTrim 2024-08-07T20:49:22.268Z,1723063762.268 [sink:RunSink:RunBallastAndTrim:A] Stopped 2024-08-07T20:49:22.268Z,1723063762.268 [sink:RunSink:JustSink] Running Loop=1 2024-08-07T20:49:22.268Z,1723063762.268 [sink:RunSink:JustSink](DEBUG): Aggregate::initialize sink:RunSink:JustSink 2024-08-07T20:49:22.268Z,1723063762.268 [sink:RunSink:JustSink:A] Running Loop=1 2024-08-07T20:49:22.734Z,1723063762.734 [sink:RunSink:JustSink:A] Stopped 2024-08-07T20:49:22.734Z,1723063762.734 [sink:RunSink:JustSink:B] Running Loop=1 2024-08-07T20:49:23.155Z,1723063763.155 [sink:RunSink:JustSink:B] Stopped 2024-08-07T20:49:23.155Z,1723063763.155 [sink:RunSink:JustSink:C] Running Loop=1 2024-08-07T20:49:23.155Z,1723063763.155 [sink:RunSink:JustSink:C](DEBUG): Aggregate::initialize sink:RunSink:JustSink:C 2024-08-07T20:49:23.592Z,1723063763.592 [sink:RunSink:BallastAndTrim] Running Loop=1 2024-08-07T20:49:23.592Z,1723063763.592 [sink:RunSink:BallastAndTrim](DEBUG): Aggregate::initialize sink:RunSink:BallastAndTrim 2024-08-07T20:49:23.592Z,1723063763.592 [sink:RunSink:BallastAndTrim:A] Running Loop=1 2024-08-07T20:49:23.592Z,1723063763.592 [sink:RunSink:BallastAndTrim:B] Running Loop=1 2024-08-07T20:49:23.592Z,1723063763.592 [sink:RunSink:BallastAndTrim:C] Running Loop=1 2024-08-07T20:49:23.592Z,1723063763.592 [sink:RunSink:BallastAndTrim:D] Running Loop=1 2024-08-07T20:49:23.592Z,1723063763.592 [sink:RunSink:BallastAndTrim:E] Running Loop=1 2024-08-07T20:49:23.592Z,1723063763.592 [sink:RunSink:BallastAndTrim:F] Running Loop=1 2024-08-07T20:49:23.599Z,1723063763.599 [sink:RunSink:BallastAndTrim:F](INFO): Going to target depth. Speed set to 0.000000 m/s 2024-08-07T20:49:23.599Z,1723063763.599 [sink:RunSink:BallastAndTrim:F] Stopped 2024-08-07T20:49:23.599Z,1723063763.599 [sink:RunSink:BallastAndTrim:GoToTargetDepth] Running Loop=1 2024-08-07T20:49:23.599Z,1723063763.599 [sink:RunSink:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::initialize sink:RunSink:BallastAndTrim:GoToTargetDepth 2024-08-07T20:49:23.599Z,1723063763.599 [sink:RunSink:BallastAndTrim:GoToTargetDepth:A] Running Loop=1 2024-08-07T20:49:23.599Z,1723063763.599 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold] Running Loop=1 2024-08-07T20:49:23.600Z,1723063763.600 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::initialize sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold 2024-08-07T20:49:23.600Z,1723063763.600 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Running Loop=1 2024-08-07T20:49:23.600Z,1723063763.600 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Initialize. 2024-08-07T20:49:23.600Z,1723063763.600 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Running Loop=1 2024-08-07T20:49:23.600Z,1723063763.600 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Initialize Buoyancy Component. 2024-08-07T20:49:23.600Z,1723063763.600 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Running Loop=1 2024-08-07T20:49:23.600Z,1723063763.600 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Initialize. 2024-08-07T20:49:23.600Z,1723063763.600 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive] Running Loop=1 2024-08-07T20:49:23.600Z,1723063763.600 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::initialize sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive 2024-08-07T20:49:23.605Z,1723063763.605 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Running Loop=1 2024-08-07T20:49:23.605Z,1723063763.605 [sink:RunSink:BallastAndTrim:E] Running Loop=1 2024-08-07T20:49:23.605Z,1723063763.605 [sink:RunSink:BallastAndTrim:D] Running Loop=1 2024-08-07T20:49:23.605Z,1723063763.605 [sink:RunSink:BallastAndTrim:C] Running Loop=1 2024-08-07T20:49:23.605Z,1723063763.605 [sink:RunSink:BallastAndTrim:B] Running Loop=1 2024-08-07T20:49:23.606Z,1723063763.606 [sink:RunSink:BallastAndTrim:A] Running Loop=1 2024-08-07T20:49:23.977Z,1723063763.977 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive:A](INFO): Waiting for 0.000000 min while pumping down to neutral. 2024-08-07T20:49:23.977Z,1723063763.977 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Stopped 2024-08-07T20:49:23.977Z,1723063763.977 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait] Running Loop=1 2024-08-07T20:49:23.977Z,1723063763.977 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Initialize Wait Component. 2024-08-07T20:49:23.977Z,1723063763.977 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold] Stopped 2024-08-07T20:49:23.977Z,1723063763.977 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::uninitialize sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold 2024-08-07T20:49:23.978Z,1723063763.978 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Stopped 2024-08-07T20:49:23.978Z,1723063763.978 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Stopped 2024-08-07T20:49:23.978Z,1723063763.978 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2024-08-07T20:49:23.978Z,1723063763.978 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Stopped 2024-08-07T20:49:23.978Z,1723063763.978 [sink:RunSink:BallastAndTrim:GoToTargetDepth:A] Running Loop=1 2024-08-07T20:49:24.358Z,1723063764.358 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](INFO): Done Waiting. 2024-08-07T20:49:24.358Z,1723063764.358 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait] Stopped 2024-08-07T20:49:24.358Z,1723063764.358 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Uninitialize Wait Component. 2024-08-07T20:49:24.359Z,1723063764.359 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive](INFO): Completed sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive 2024-08-07T20:49:24.359Z,1723063764.359 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive] Stopped 2024-08-07T20:49:24.359Z,1723063764.359 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::uninitialize sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive 2024-08-07T20:49:24.359Z,1723063764.359 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth] Running Loop=1 2024-08-07T20:49:24.359Z,1723063764.359 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::initialize sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth 2024-08-07T20:49:24.359Z,1723063764.359 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1 2024-08-07T20:49:24.359Z,1723063764.359 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Initialize. 2024-08-07T20:49:24.359Z,1723063764.359 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Running Loop=1 2024-08-07T20:49:24.778Z,1723063764.778 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1 2024-08-07T20:49:24.779Z,1723063764.779 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:A](INFO): Moving to 0.750000 m 2024-08-07T20:49:24.779Z,1723063764.779 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Stopped 2024-08-07T20:49:24.780Z,1723063764.780 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Running Loop=1 2024-08-07T20:49:24.780Z,1723063764.780 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Initialize. 2024-08-07T20:49:29.336Z,1723063769.336 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:49:40.146Z,1723063780.146 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:49:48.959Z,1723063788.959 [Reporter](INFO): platform_buoyancy_position 824.709106 cc 2024-08-07T20:49:51.261Z,1723063791.261 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:49:55.136Z,1723063795.136 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:50:02.047Z,1723063802.047 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:50:08.259Z,1723063808.259 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. Device response is::WD, +0. +0.00, +0.00, 20.00,2679.33 2024-08-07T20:50:13.058Z,1723063813.058 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:50:19.060Z,1723063819.060 [Reporter](INFO): platform_buoyancy_position 577.731384 cc 2024-08-07T20:50:24.301Z,1723063824.301 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:50:35.223Z,1723063835.223 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:50:36.712Z,1723063836.712 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 0.00, 112.03, 91.69, 61.01, 2024-08-07T20:50:44.534Z,1723063844.534 [WetLabsUBAT](ERROR): Failed to parse incomplete device message. 2024-08-07T20:50:49.094Z,1723063849.094 [Reporter](INFO): platform_buoyancy_position 438.193665 cc 2024-08-07T20:50:54.705Z,1723063854.705 [WetLabsUBAT](ERROR): Failed to parse incomplete device message. 2024-08-07T20:51:01.022Z,1723063861.022 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Stopped 2024-08-07T20:51:01.022Z,1723063861.022 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth](INFO): Completed sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth 2024-08-07T20:51:01.022Z,1723063861.022 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth] Stopped 2024-08-07T20:51:01.023Z,1723063861.023 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::uninitialize sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth 2024-08-07T20:51:01.023Z,1723063861.023 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Stopped 2024-08-07T20:51:01.024Z,1723063861.024 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Uninitialize. 2024-08-07T20:51:01.037Z,1723063861.037 [sink:RunSink:BallastAndTrim:GoToTargetDepth](INFO): Completed sink:RunSink:BallastAndTrim:GoToTargetDepth 2024-08-07T20:51:01.037Z,1723063861.037 [sink:RunSink:BallastAndTrim:GoToTargetDepth] Stopped 2024-08-07T20:51:01.037Z,1723063861.037 [sink:RunSink:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::uninitialize sink:RunSink:BallastAndTrim:GoToTargetDepth 2024-08-07T20:51:01.037Z,1723063861.037 [sink:RunSink:BallastAndTrim:GoToTargetDepth:A] Stopped 2024-08-07T20:51:01.037Z,1723063861.037 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth] Running Loop=1 2024-08-07T20:51:01.038Z,1723063861.038 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::initialize sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth 2024-08-07T20:51:01.038Z,1723063861.038 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1 2024-08-07T20:51:01.038Z,1723063861.038 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Initialize. 2024-08-07T20:51:01.038Z,1723063861.038 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1 2024-08-07T20:51:01.038Z,1723063861.038 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Initialize. 2024-08-07T20:51:01.038Z,1723063861.038 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Running Loop=1 2024-08-07T20:51:01.417Z,1723063861.417 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:C](INFO): Waiting for the vehicle to settle. Depth = 0.748370 m 2024-08-07T20:51:01.417Z,1723063861.417 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Stopped 2024-08-07T20:51:01.417Z,1723063861.417 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Running Loop=1 2024-08-07T20:51:01.417Z,1723063861.417 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Initialize Wait Component. 2024-08-07T20:51:01.418Z,1723063861.418 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1 2024-08-07T20:51:01.420Z,1723063861.420 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1 2024-08-07T20:51:02.394Z,1723063862.394 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:51:02.772Z,1723063862.772 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:51:06.361Z,1723063866.361 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:51:19.375Z,1723063879.375 [Reporter](INFO): platform_buoyancy_position 502.926300 cc 2024-08-07T20:51:23.602Z,1723063883.602 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,24080712524259,35.0, 0.0,1524.7, 0 2024-08-07T20:51:34.474Z,1723063894.474 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.258972 2024-08-07T20:51:35.479Z,1723063895.479 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:51:44.863Z,1723063904.863 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:51:45.639Z,1723063905.639 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:51:48.420Z,1723063908.420 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:51:48.916Z,1723063908.916 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:51:49.744Z,1723063909.744 [Reporter](INFO): platform_buoyancy_position 487.616028 cc 2024-08-07T20:51:52.432Z,1723063912.432 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:51:52.953Z,1723063912.953 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:51:55.023Z,1723063915.023 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:51:56.131Z,1723063916.131 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:52:01.837Z,1723063921.837 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:52:04.703Z,1723063924.703 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:52:05.598Z,1723063925.598 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-08-07T20:52:06.601Z,1723063926.601 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:52:09.975Z,1723063929.975 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:52:12.291Z,1723063932.291 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:52:19.835Z,1723063939.835 [Reporter](INFO): platform_buoyancy_position 446.788818 cc 2024-08-07T20:52:29.350Z,1723063949.350 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:52:34.762Z,1723063954.762 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:52:35.994Z,1723063955.994 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:52:36.721Z,1723063956.721 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-08-07T20:52:37.750Z,1723063957.750 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:52:39.963Z,1723063959.963 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:52:50.156Z,1723063970.156 [Reporter](INFO): platform_buoyancy_position 453.638123 cc 2024-08-07T20:53:07.119Z,1723063987.119 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:53:07.869Z,1723063987.869 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-08-07T20:53:08.873Z,1723063988.873 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:53:13.923Z,1723063993.923 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:53:20.371Z,1723064000.371 [Reporter](INFO): platform_buoyancy_position 502.523376 cc 2024-08-07T20:53:20.586Z,1723064000.586 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-08-07T20:53:38.199Z,1723064018.199 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:53:38.993Z,1723064018.993 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-08-07T20:53:39.999Z,1723064019.999 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:53:41.799Z,1723064021.799 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:53:43.419Z,1723064023.419 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:53:50.842Z,1723064030.842 [Reporter](INFO): platform_buoyancy_position 476.872070 cc 2024-08-07T20:53:51.286Z,1723064031.286 [NAL9602](INFO): Not Powering down - fast GPS 2024-08-07T20:54:10.117Z,1723064050.117 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-08-07T20:54:11.122Z,1723064051.122 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:54:12.535Z,1723064052.535 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:54:17.759Z,1723064057.759 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:54:20.937Z,1723064060.937 [Reporter](INFO): platform_buoyancy_position 468.276855 cc 2024-08-07T20:54:36.699Z,1723064076.699 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:54:41.241Z,1723064081.241 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-08-07T20:54:42.251Z,1723064082.251 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:54:49.220Z,1723064089.220 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:54:51.259Z,1723064091.259 [Reporter](INFO): platform_buoyancy_position 478.886536 cc 2024-08-07T20:54:53.333Z,1723064093.333 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:54:54.947Z,1723064094.947 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:54:59.315Z,1723064099.315 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:55:10.254Z,1723064110.254 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:55:12.369Z,1723064112.369 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-08-07T20:55:13.094Z,1723064113.094 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:55:13.339Z,1723064113.339 [sink](INFO): Timed out from 2024-08-07T20:48:13.3Z 2024-08-07T20:55:13.339Z,1723064113.339 [MissionManager](INFO): sink is completed. 2024-08-07T20:55:13.340Z,1723064113.340 [MissionManager](INFO): Uninitializing Mission sink 2024-08-07T20:55:13.340Z,1723064113.340 [sink] Stopped 2024-08-07T20:55:13.340Z,1723064113.340 [sink](DEBUG): Aggregate::uninitialize sink 2024-08-07T20:55:13.340Z,1723064113.340 [sink:Science] Stopped 2024-08-07T20:55:13.340Z,1723064113.340 [sink:Science](DEBUG): Aggregate::uninitialize sink:Science 2024-08-07T20:55:13.340Z,1723064113.340 [sink:Science:B] Stopped 2024-08-07T20:55:13.340Z,1723064113.340 [sink:Science:C] Stopped 2024-08-07T20:55:13.340Z,1723064113.340 [sink:Science:E] Stopped 2024-08-07T20:55:13.340Z,1723064113.340 [sink:Science:F] Stopped 2024-08-07T20:55:13.340Z,1723064113.340 [sink:Science:OceanCurrent] Stopped 2024-08-07T20:55:13.340Z,1723064113.340 [sink:Science:OceanCurrent](DEBUG): Aggregate::uninitialize sink:Science:OceanCurrent 2024-08-07T20:55:13.340Z,1723064113.340 [sink:Science:OceanCurrent:A.] Stopped 2024-08-07T20:55:13.341Z,1723064113.341 [sink:StandardEnvelopes] Stopped 2024-08-07T20:55:13.341Z,1723064113.341 [sink:StandardEnvelopes](DEBUG): Aggregate::uninitialize sink:StandardEnvelopes 2024-08-07T20:55:13.341Z,1723064113.341 [sink:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2024-08-07T20:55:13.341Z,1723064113.341 [sink:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2024-08-07T20:55:13.341Z,1723064113.341 [sink:StandardEnvelopes:B.DepthEnvelope] Stopped 2024-08-07T20:55:13.341Z,1723064113.341 [sink:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2024-08-07T20:55:13.341Z,1723064113.341 [sink:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2024-08-07T20:55:13.341Z,1723064113.341 [sink:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2024-08-07T20:55:13.341Z,1723064113.341 [sink:BackseatDriver] Stopped 2024-08-07T20:55:13.342Z,1723064113.342 [sink:BackseatDriver](DEBUG): Aggregate::uninitialize sink:BackseatDriver 2024-08-07T20:55:13.342Z,1723064113.342 [sink:BackseatDriver:A.BackseatDriver] Stopped 2024-08-07T20:55:13.342Z,1723064113.342 [sink:PowerOnly] Stopped 2024-08-07T20:55:13.342Z,1723064113.342 [sink:PowerOnly](DEBUG): Aggregate::uninitialize sink:PowerOnly 2024-08-07T20:55:13.342Z,1723064113.342 [sink:PowerOnly:E.Wait] Stopped 2024-08-07T20:55:13.342Z,1723064113.342 [sink:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2024-08-07T20:55:13.342Z,1723064113.342 [sink:RunSink] Stopped 2024-08-07T20:55:13.342Z,1723064113.342 [sink:RunSink](DEBUG): Aggregate::uninitialize sink:RunSink 2024-08-07T20:55:13.342Z,1723064113.342 [sink:RunSink:BallastAndTrim] Stopped 2024-08-07T20:55:13.342Z,1723064113.342 [sink:RunSink:BallastAndTrim](DEBUG): Aggregate::uninitialize sink:RunSink:BallastAndTrim 2024-08-07T20:55:13.342Z,1723064113.342 [sink:RunSink:BallastAndTrim:A] Stopped 2024-08-07T20:55:13.342Z,1723064113.342 [sink:RunSink:BallastAndTrim:B] Stopped 2024-08-07T20:55:13.342Z,1723064113.342 [sink:RunSink:BallastAndTrim:C] Stopped 2024-08-07T20:55:13.342Z,1723064113.342 [sink:RunSink:BallastAndTrim:D] Stopped 2024-08-07T20:55:13.342Z,1723064113.342 [sink:RunSink:BallastAndTrim:E] Stopped 2024-08-07T20:55:13.342Z,1723064113.342 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth] Stopped 2024-08-07T20:55:13.343Z,1723064113.343 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::uninitialize sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth 2024-08-07T20:55:13.343Z,1723064113.343 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Stopped 2024-08-07T20:55:13.343Z,1723064113.343 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Uninitialize. 2024-08-07T20:55:13.343Z,1723064113.343 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Stopped 2024-08-07T20:55:13.343Z,1723064113.343 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Stopped 2024-08-07T20:55:13.343Z,1723064113.343 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Uninitialize Wait Component. 2024-08-07T20:55:13.343Z,1723064113.343 [sink:RunSink:JustSink] Stopped 2024-08-07T20:55:13.343Z,1723064113.343 [sink:RunSink:JustSink](DEBUG): Aggregate::uninitialize sink:RunSink:JustSink 2024-08-07T20:55:13.343Z,1723064113.343 [sink:RunSink:JustSink:C] Stopped 2024-08-07T20:55:13.343Z,1723064113.343 [sink:RunSink:JustSink:C](DEBUG): Aggregate::uninitialize sink:RunSink:JustSink:C 2024-08-07T20:55:13.374Z,1723064113.374 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:55:13.477Z,1723064113.477 [WetLabsUBAT](INFO): Powering down 2024-08-07T20:55:13.490Z,1723064113.490 [CommandExec](IMPORTANT): got command load ./Missions/Engineering/OnDock.tl 2024-08-07T20:55:13.499Z,1723064113.499 [MissionManager](INFO): Loading Mission from file: ./Missions/Engineering/OnDock.tl 2024-08-07T20:55:13.499Z,1723064113.499 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Engineering/OnDock.tl 2024-08-07T20:55:13.504Z,1723064113.504 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Engineering/OnDock.tl 2024-08-07T20:55:13.577Z,1723064113.577 [WetLabsBB2FL](INFO): Powering down 2024-08-07T20:55:14.423Z,1723064114.423 [MissionManager](DEBUG): TethyslAPI: ./Missions/Engineering/OnDock.tl translated into: 8 2 1 5 2 30 5 Restarting logs restart logs ! ifconfig Mission ending, breaking off. Range: . 2024-08-07T20:55:14.430Z,1723064114.430 [MissionManager](INFO): DefineArg OnDock.DockedTime = 8.000000 h 2024-08-07T20:55:14.458Z,1723064114.458 [MissionManager](INFO): DefineArg OnDock.DetachedTimeout = 30.000000 s 2024-08-07T20:55:14.463Z,1723064114.463 [MissionManager](INFO): DefineArg OnDock.RangeTimeout = 600.000000 s 2024-08-07T20:55:14.484Z,1723064114.484 [MissionManager](INFO): DefineArg OnDock.TrackingUpdatePeriod = 2.000000 min 2024-08-07T20:55:14.528Z,1723064114.528 [MissionManager](INFO): DefineArg OnDock.NumberOfPings = 1.000000 count 2024-08-07T20:55:14.536Z,1723064114.536 [MissionManager](INFO): DefineArg OnDock.TransponderCode = 50 count 2024-08-07T20:55:14.572Z,1723064114.572 [MissionManager](INFO): DefineArg OnDock.MaxDepth = 215.000000 m 2024-08-07T20:55:14.579Z,1723064114.579 [MissionManager](INFO): DefineArg OnDock.MinAltitude = 5.000000 m 2024-08-07T20:55:14.607Z,1723064114.607 [MissionManager](INFO): DefineArg OnDock.MinOffshore = 2.000000 km 2024-08-07T20:55:14.621Z,1723064114.621 [MissionManager](INFO): DefineArg OnDock.StayOnDock = 0 bool 2024-08-07T20:55:14.634Z,1723064114.634 [MissionManager](INFO): DefineArg OnDock.IfconfigInterval = 30.000000 min 2024-08-07T20:55:14.638Z,1723064114.638 [MissionManager](INFO): DefineArg OnDock.MissionTimeout = 8.000000 h 2024-08-07T20:55:14.638Z,1723064114.638 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl 2024-08-07T20:55:14.639Z,1723064114.639 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl 2024-08-07T20:55:14.640Z,1723064114.640 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl 2024-08-07T20:55:14.871Z,1723064114.871 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into: 5 0 200 2000 2024-08-07T20:55:14.885Z,1723064114.885 [MissionManager](INFO): DefineArg OnDock:StandardEnvelopes.MinAltitude = 5.000000 m 2024-08-07T20:55:14.892Z,1723064114.892 [MissionManager](INFO): DefineArg OnDock:StandardEnvelopes.MaxDepthIgnore = 0.000000 m 2024-08-07T20:55:14.928Z,1723064114.928 [MissionManager](INFO): DefineArg OnDock:StandardEnvelopes.MaxDepth = 200.000000 m 2024-08-07T20:55:14.944Z,1723064114.944 [MissionManager](INFO): DefineArg OnDock:StandardEnvelopes.MinOffshore = 2000.000000 m 2024-08-07T20:55:14.954Z,1723064114.954 [OnDock:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2024-08-07T20:55:14.987Z,1723064114.987 [OnDock:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2024-08-07T20:55:15.015Z,1723064115.015 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2024-08-07T20:55:15.066Z,1723064115.066 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl 2024-08-07T20:55:15.066Z,1723064115.066 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl 2024-08-07T20:55:15.067Z,1723064115.067 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl 2024-08-07T20:55:15.141Z,1723064115.141 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into: 2024-08-07T20:55:15.179Z,1723064115.179 [MissionManager](INFO): DefineArg OnDock:BackseatDriver.EnableBackseat = 0 bool 2024-08-07T20:55:15.192Z,1723064115.192 [OnDock:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver. 2024-08-07T20:55:15.207Z,1723064115.207 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl 2024-08-07T20:55:15.208Z,1723064115.208 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl 2024-08-07T20:55:15.213Z,1723064115.213 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl 2024-08-07T20:55:15.484Z,1723064115.484 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into: 99999 2024-08-07T20:55:15.499Z,1723064115.499 [MissionManager](INFO): DefineArg OnDock:PowerOnly.SampleAll = 0 bool 2024-08-07T20:55:15.504Z,1723064115.504 [MissionManager](INFO): DefineArg OnDock:PowerOnly.SampleLoad1 = 0 bool 2024-08-07T20:55:15.521Z,1723064115.521 [MissionManager](INFO): DefineArg OnDock:PowerOnly.SampleLoad2 = 0 bool 2024-08-07T20:55:15.527Z,1723064115.527 [MissionManager](INFO): DefineArg OnDock:PowerOnly.SampleLoad3 = 0 bool 2024-08-07T20:55:15.540Z,1723064115.540 [MissionManager](INFO): DefineArg OnDock:PowerOnly.EnabledPowerOnly = 0 bool 2024-08-07T20:55:15.580Z,1723064115.580 [OnDock:PowerOnly:E.Wait](DEBUG): Construct Wait. 2024-08-07T20:55:15.800Z,1723064115.800 [OnDock:K.Execute](DEBUG): Construct Execute. 2024-08-07T20:55:15.812Z,1723064115.812 [OnDock:PeriodicCheck:CheckIP:A.Execute](DEBUG): Construct Execute. 2024-08-07T20:55:15.829Z,1723064115.829 [OnDock:PeriodicCheck:CheckIP:B.Wait](DEBUG): Construct Wait. 2024-08-07T20:55:15.831Z,1723064115.831 [OnDock:M.Docked](DEBUG): Construct. 2024-08-07T20:55:15.831Z,1723064115.831 [OnDock:M.Docked](DEBUG): Initializing internal variables to default values. 2024-08-07T20:55:15.890Z,1723064115.890 [OnDock:Detach:B.Undock](DEBUG): Construct. 2024-08-07T20:55:15.890Z,1723064115.890 [OnDock:Detach:B.Undock](DEBUG): Initializing internal variables to default values. 2024-08-07T20:55:15.966Z,1723064115.966 [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 } syslog important "Restarting logs" behavior Guidance:Execute { run in sequence set command = "restart logs" } 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 } } } 2024-08-07T20:55:15.966Z,1723064115.966 [CommandExec](IMPORTANT): Loaded ./Missions/Engineering/OnDock.tl id=OnDock 2024-08-07T20:55:16.144Z,1723064116.144 [MissionManager](IMPORTANT): Started mission Default 2024-08-07T20:55:16.153Z,1723064116.153 [Default] Running Loop=1 2024-08-07T20:55:16.153Z,1723064116.153 [Default](DEBUG): Aggregate::initialize Default 2024-08-07T20:55:16.153Z,1723064116.153 [Default:B.GoToSurface] Running Loop=1 2024-08-07T20:55:16.153Z,1723064116.153 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-08-07T20:55:16.153Z,1723064116.153 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-08-07T20:55:16.154Z,1723064116.154 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-08-07T20:55:16.154Z,1723064116.154 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-08-07T20:55:16.154Z,1723064116.154 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-08-07T20:55:16.155Z,1723064116.155 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-08-07T20:55:16.155Z,1723064116.155 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-08-07T20:55:16.155Z,1723064116.155 [Default:A.Wait] Running Loop=1 2024-08-07T20:55:16.155Z,1723064116.155 [Default:A.Wait](DEBUG): Initialize Wait Component. 2024-08-07T20:55:21.334Z,1723064121.334 [Reporter](INFO): platform_buoyancy_position 554.228882 cc 2024-08-07T20:55:29.207Z,1723064129.207 [Default:A.Wait](INFO): Done Waiting. 2024-08-07T20:55:29.207Z,1723064129.207 [Default:A.Wait] Stopped 2024-08-07T20:55:29.208Z,1723064129.208 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2024-08-07T20:55:29.574Z,1723064129.574 [Default:CheckIn] Running Loop=1 2024-08-07T20:55:29.574Z,1723064129.574 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-08-07T20:55:29.574Z,1723064129.574 [Default:CheckIn:Read_GPS] Running Loop=1 2024-08-07T20:55:29.750Z,1723064129.750 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:55:30.955Z,1723064130.955 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:55:31.558Z,1723064131.558 [CommandExec](IMPORTANT): got command set OnDock.DockedTime 15 minute 2024-08-07T20:55:31.558Z,1723064131.558 [CommandExec](IMPORTANT): got command set OnDock.DetachedTimeout 100 minute 2024-08-07T20:55:31.559Z,1723064131.559 [CommandExec](IMPORTANT): got command set OnDock.RangeTimeout 100 minute 2024-08-07T20:55:31.560Z,1723064131.560 [CommandExec](IMPORTANT): got command set OnDock.TrackingUpdatePeriod 100 minute 2024-08-07T20:55:31.560Z,1723064131.560 [CommandExec](IMPORTANT): got command set OnDock.MinAltitude 0.0001 meter 2024-08-07T20:55:31.561Z,1723064131.561 [CommandExec](IMPORTANT): got command set OnDock.MinOffshore 0.0001 kilometer 2024-08-07T20:55:33.803Z,1723064133.803 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:55:34.293Z,1723064134.293 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:55:34.524Z,1723064134.524 [CommandExec](IMPORTANT): got command set OnDock.IfconfigInterval 3 minute 2024-08-07T20:55:34.524Z,1723064134.524 [CommandExec](IMPORTANT): got command run 2024-08-07T20:55:34.527Z,1723064134.527 [CommandExec](IMPORTANT): Running 2024-08-07T20:55:34.801Z,1723064134.801 [Default] Stopped 2024-08-07T20:55:34.801Z,1723064134.801 [Default](DEBUG): Aggregate::uninitialize Default 2024-08-07T20:55:34.801Z,1723064134.801 [Default:B.GoToSurface] Stopped 2024-08-07T20:55:34.801Z,1723064134.801 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-08-07T20:55:34.801Z,1723064134.801 [Default:CheckIn] Stopped 2024-08-07T20:55:34.801Z,1723064134.801 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-08-07T20:55:34.801Z,1723064134.801 [Default:CheckIn:Read_GPS] Stopped 2024-08-07T20:55:34.801Z,1723064134.801 [MissionManager](IMPORTANT): Started mission OnDock 2024-08-07T20:55:34.802Z,1723064134.802 [OnDock] Running Loop=1 2024-08-07T20:55:34.802Z,1723064134.802 [OnDock](DEBUG): Aggregate::initialize OnDock 2024-08-07T20:55:34.802Z,1723064134.802 [OnDock:StandardEnvelopes] Running Loop=1 2024-08-07T20:55:34.802Z,1723064134.802 [OnDock:StandardEnvelopes](DEBUG): Aggregate::initialize OnDock:StandardEnvelopes 2024-08-07T20:55:34.802Z,1723064134.802 [OnDock:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2024-08-07T20:55:34.802Z,1723064134.802 [OnDock:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2024-08-07T20:55:34.802Z,1723064134.802 [OnDock:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2024-08-07T20:55:34.802Z,1723064134.802 [OnDock:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2024-08-07T20:55:34.803Z,1723064134.803 [OnDock:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2024-08-07T20:55:34.803Z,1723064134.803 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2024-08-07T20:55:34.803Z,1723064134.803 [OnDock:BackseatDriver] Running Loop=1 2024-08-07T20:55:34.803Z,1723064134.803 [OnDock:BackseatDriver](DEBUG): Aggregate::initialize OnDock:BackseatDriver 2024-08-07T20:55:34.803Z,1723064134.803 [OnDock:BackseatDriver:A.BackseatDriver] Running Loop=1 2024-08-07T20:55:34.803Z,1723064134.803 [OnDock:PowerOnly] Running Loop=1 2024-08-07T20:55:34.803Z,1723064134.803 [OnDock:PowerOnly](DEBUG): Aggregate::initialize OnDock:PowerOnly 2024-08-07T20:55:34.803Z,1723064134.803 [OnDock:PowerOnly:A] Running Loop=1 2024-08-07T20:55:34.803Z,1723064134.803 [OnDock:PowerOnly:B] Running Loop=1 2024-08-07T20:55:34.803Z,1723064134.803 [OnDock:PowerOnly:C] Running Loop=1 2024-08-07T20:55:34.803Z,1723064134.803 [OnDock:PowerOnly:D] Running Loop=1 2024-08-07T20:55:34.804Z,1723064134.804 [OnDock:PowerOnly:E.Wait] Running Loop=1 2024-08-07T20:55:34.804Z,1723064134.804 [OnDock:PowerOnly:E.Wait](DEBUG): Initialize Wait Component. 2024-08-07T20:55:34.804Z,1723064134.804 [OnDock:H] Running Loop=1 2024-08-07T20:55:34.804Z,1723064134.804 [OnDock:I.] Running Loop=1 2024-08-07T20:55:34.804Z,1723064134.804 [OnDock:I.](INFO): Initializing TrackAcousticContact. 2024-08-07T20:55:34.804Z,1723064134.804 [OnDock:PeriodicCheck] Running Loop=1 2024-08-07T20:55:34.804Z,1723064134.804 [OnDock:PeriodicCheck](DEBUG): Aggregate::initialize OnDock:PeriodicCheck 2024-08-07T20:55:34.804Z,1723064134.804 [OnDock:PeriodicCheck:CheckIP] Running Loop=1 2024-08-07T20:55:34.805Z,1723064134.805 [OnDock:PeriodicCheck:CheckIP](DEBUG): Aggregate::initialize OnDock:PeriodicCheck:CheckIP 2024-08-07T20:55:34.805Z,1723064134.805 [OnDock:PeriodicCheck:CheckIP:A.Execute] Running Loop=1 2024-08-07T20:55:34.806Z,1723064134.806 [OnDock:B] Running Loop=1 2024-08-07T20:55:34.806Z,1723064134.806 [OnDock:PeriodicCheck] Running Loop=1 2024-08-07T20:55:34.806Z,1723064134.806 [OnDock:PeriodicCheck:CheckIP:A.Execute](INFO): Executing command ! ifconfig 2024-08-07T20:55:34.806Z,1723064134.806 [OnDock:PeriodicCheck:CheckIP:A.Execute] Stopped 2024-08-07T20:55:34.806Z,1723064134.806 [OnDock:PeriodicCheck:CheckIP:B.Wait] Running Loop=1 2024-08-07T20:55:34.806Z,1723064134.806 [OnDock:PeriodicCheck:CheckIP:B.Wait](DEBUG): Initialize Wait Component. 2024-08-07T20:55:34.807Z,1723064134.807 [OnDock:I.] Running Loop=1 2024-08-07T20:55:34.807Z,1723064134.807 [OnDock:I.](INFO): Received request for long update period 100.000000 minutes, decreasing to 5 minutes. 2024-08-07T20:55:34.808Z,1723064134.808 [OnDock:H] Stopped 2024-08-07T20:55:34.808Z,1723064134.808 [OnDock:PowerOnly] Running Loop=1 2024-08-07T20:55:34.808Z,1723064134.808 [OnDock:PowerOnly:D] Stopped 2024-08-07T20:55:34.808Z,1723064134.808 [OnDock:PowerOnly:C] Stopped 2024-08-07T20:55:34.808Z,1723064134.808 [OnDock:PowerOnly:B] Stopped 2024-08-07T20:55:34.808Z,1723064134.808 [OnDock:PowerOnly:A] Stopped 2024-08-07T20:55:34.808Z,1723064134.808 [OnDock:BackseatDriver] Running Loop=1 2024-08-07T20:55:34.829Z,1723064134.829 [OnDock:B] Stopped 2024-08-07T20:55:34.829Z,1723064134.829 [OnDock:C] Running Loop=1 2024-08-07T20:55:34.829Z,1723064134.829 [OnDock:StandardEnvelopes] Running Loop=1 2024-08-07T20:55:34.829Z,1723064134.829 [OnDock:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2024-08-07T20:55:34.830Z,1723064134.830 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](IMPORTANT): Offshore envelope ACTIVE 2024-08-07T20:55:34.838Z,1723064134.838 [CommandExec](IMPORTANT): got command ! ifconfig 2024-08-07T20:55:34.864Z,1723064134.864 [OnDock:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2024-08-07T20:55:34.873Z,1723064134.873 [OnDock:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2024-08-07T20:55:35.119Z,1723064135.119 [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:42866 errors:0 dropped:0 overruns:0 frame:0 TX packets:48683 errors:0 dropped:0 overruns:0 carrier:0 collisions:156 txqueuelen:1000 RX bytes:15550287 (14.8 Mb) TX bytes:17228474 (16.4 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:368 errors:0 dropped:0 overruns:0 frame:0 TX packets:368 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:0 RX bytes:27760 (27.1 Kb) TX bytes:27760 (27.1 Kb) 2024-08-07T20:55:35.356Z,1723064135.356 [OnDock:I.](INFO): Received request for long update period 100.000000 minutes, decreasing to 5 minutes. 2024-08-07T20:55:35.366Z,1723064135.366 [OnDock:C] Stopped 2024-08-07T20:55:35.366Z,1723064135.366 [OnDock:D] Running Loop=1 2024-08-07T20:55:35.620Z,1723064135.620 [OnDock:I.](INFO): Received request for long update period 100.000000 minutes, decreasing to 5 minutes. 2024-08-07T20:55:35.622Z,1723064135.622 [OnDock:D] Stopped 2024-08-07T20:55:35.622Z,1723064135.622 [OnDock:E] Running Loop=1 2024-08-07T20:55:35.795Z,1723064135.795 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:55:36.087Z,1723064136.087 [OnDock:I.](INFO): Received request for long update period 100.000000 minutes, decreasing to 5 minutes. 2024-08-07T20:55:36.088Z,1723064136.088 [OnDock:E] Stopped 2024-08-07T20:55:36.088Z,1723064136.088 [OnDock:J] Running Loop=1 2024-08-07T20:55:36.328Z,1723064136.328 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:55:36.453Z,1723064136.453 [OnDock:J](IMPORTANT): Restarting logs 2024-08-07T20:55:36.453Z,1723064136.453 [OnDock:J] Stopped 2024-08-07T20:55:36.454Z,1723064136.454 [OnDock:K.Execute] Running Loop=1 2024-08-07T20:55:36.454Z,1723064136.454 [OnDock:I.](INFO): Received request for long update period 100.000000 minutes, decreasing to 5 minutes. 2024-08-07T20:55:36.824Z,1723064136.824 [OnDock:K.Execute](INFO): Executing command restart logs 2024-08-07T20:55:36.824Z,1723064136.824 [OnDock:K.Execute] Stopped