2024-05-21T16:39:07.708Z,1716309547.708 [OnDock:L.Execute](INFO): Executing command restart logs 2024-05-21T16:39:07.708Z,1716309547.708 [OnDock:L.Execute] Stopped 2024-05-21T16:39:07.709Z,1716309547.709 [OnDock:M] Running Loop=1 2024-05-21T16:39:07.709Z,1716309547.709 [OnDock:M](DEBUG): Aggregate::initialize OnDock:M 2024-05-21T16:39:07.749Z,1716309547.749 [CommandExec](IMPORTANT): got command restart logs 2024-05-21T16:39:07.767Z,1716309547.767 [DockingStepper](INFO): Starting 2024-05-21T16:39:08.177Z,1716309548.177 [OnDock:restartDHCP] Running Loop=1 2024-05-21T16:39:08.177Z,1716309548.177 [OnDock:restartDHCP](DEBUG): Aggregate::initialize OnDock:restartDHCP 2024-05-21T16:39:08.177Z,1716309548.177 [OnDock:restartDHCP:A] Running Loop=1 2024-05-21T16:39:08.177Z,1716309548.177 [OnDock:restartDHCP:A](IMPORTANT): Restarting DHCP... 2024-05-21T16:39:08.177Z,1716309548.177 [OnDock:restartDHCP:A] Stopped 2024-05-21T16:39:08.177Z,1716309548.177 [OnDock:restartDHCP:B.Execute] Running Loop=1 2024-05-21T16:39:08.227Z,1716309548.227 [DockingStepper](INFO): Changing to mode: 2 2024-05-21T16:39:08.227Z,1716309548.227 [DockingStepper](INFO): Armed mode. 2024-05-21T16:39:08.278Z,1716309548.278 [DockingStepper](INFO): Commanding negative:52592 2024-05-21T16:39:08.601Z,1716309548.601 [OnDock:restartDHCP:B.Execute](INFO): Executing command ! /mnt/mmc/LRAUV/Tools/dhcp.sh 2024-05-21T16:39:08.609Z,1716309548.609 [OnDock:restartDHCP:B.Execute] Stopped 2024-05-21T16:39:08.610Z,1716309548.610 [OnDock:restartDHCP](INFO): Completed OnDock:restartDHCP 2024-05-21T16:39:08.610Z,1716309548.610 [OnDock:restartDHCP] Stopped 2024-05-21T16:39:08.610Z,1716309548.610 [OnDock:restartDHCP](DEBUG): Aggregate::uninitialize OnDock:restartDHCP 2024-05-21T16:39:08.984Z,1716309548.984 [OnDock:M](INFO): Completed OnDock:M 2024-05-21T16:39:08.984Z,1716309548.984 [OnDock:M] Stopped 2024-05-21T16:39:08.984Z,1716309548.984 [OnDock:M](DEBUG): Aggregate::uninitialize OnDock:M 2024-05-21T16:39:08.984Z,1716309548.984 [OnDock:O.Docked] Running Loop=1 2024-05-21T16:39:08.984Z,1716309548.984 [OnDock:O.Docked](DEBUG): Initialize. 2024-05-21T16:39:08.985Z,1716309548.985 [OnDock:O.Docked](DEBUG): Initializing internal variables to default values. 2024-05-21T16:39:09.027Z,1716309549.027 [DockingStepper](INFO): Changing to mode: 1 2024-05-21T16:39:09.027Z,1716309549.027 [DockingStepper](INFO): Standby mode. 2024-05-21T16:39:09.459Z,1716309549.459 [DockingStepper](INFO): Deisolating dock charge 2024-05-21T16:39:13.922Z,1716309553.922 [DockingStepper](INFO): Commanding positive:54205 2024-05-21T16:39:18.293Z,1716309558.293 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240521T155732/Courier0034.lzma 2024-05-21T16:39:19.295Z,1716309559.295 [DataOverHttps](INFO): Moved sent file to Logs/20240521T155732/Courier0034.lzma.bak 2024-05-21T16:39:19.296Z,1716309559.296 [DataOverHttps](INFO): SBD MOMSN=21060417 2024-05-21T16:39:20.588Z,1716309560.588 [CommandExec](IMPORTANT): got command ! /mnt/mmc/LRAUV/Tools/dhcp.sh 2024-05-21T16:39:21.603Z,1716309561.603 [CommandExec](IMPORTANT): /mnt/mmc/started/2024-05-21UTC163921_sh 2024-05-21T16:39:42.817Z,1716309582.817 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240521T155732/Courier0037.lzma 2024-05-21T16:39:43.819Z,1716309583.819 [DataOverHttps](INFO): Moved sent file to Logs/20240521T155732/Courier0037.lzma.bak 2024-05-21T16:39:43.820Z,1716309583.820 [DataOverHttps](INFO): SBD MOMSN=21060420 2024-05-21T16:40:00.044Z,1716309600.044 [DataOverHttps](IMPORTANT): SBD MTMSN=20240521T163959 2024-05-21T16:40:08.658Z,1716309608.658 [DataOverHttps](INFO): Sending 653 bytes from file Logs/20240521T155732/Express0035.lzma 2024-05-21T16:40:08.662Z,1716309608.662 [DataOverHttps](INFO): Received command: sched "stop" 2024-05-21T16:40:08.675Z,1716309608.675 [CommandExec](IMPORTANT): got command schedule "stop" 2024-05-21T16:40:08.676Z,1716309608.676 [CommandExec](IMPORTANT): Scheduled #6: "stop", AFTER MISSION 2024-05-21T16:40:09.956Z,1716309609.956 [DataOverHttps](INFO): Moved sent file to Logs/20240521T155732/Express0035.lzma.bak 2024-05-21T16:40:09.957Z,1716309609.957 [DataOverHttps](INFO): SBD MOMSN=21060423 2024-05-21T16:40:28.869Z,1716309628.869 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240521T163907/Courier0000.lzma 2024-05-21T16:40:29.871Z,1716309629.871 [DataOverHttps](INFO): Moved sent file to Logs/20240521T163907/Courier0000.lzma.bak 2024-05-21T16:40:29.872Z,1716309629.872 [DataOverHttps](IMPORTANT): SBD MOMSN=21060450, MTMSN=20240521T164029 2024-05-21T16:40:38.924Z,1716309638.924 [NAL9602](INFO): SBD MO Status=1, MOMSN=37726, MT Status=0, MTMSN=0 2024-05-21T16:40:38.924Z,1716309638.924 [NAL9602](INFO): No messages in MT queue 2024-05-21T16:40:40.965Z,1716309640.965 [DataOverHttps](INFO): Received command: stop 2024-05-21T16:40:40.970Z,1716309640.970 [CommandExec](IMPORTANT): got command stop 2024-05-21T16:40:40.971Z,1716309640.971 [CommandExec](IMPORTANT): Scheduling is paused for 3 commands 2024-05-21T16:40:40.971Z,1716309640.971 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop 2024-05-21T16:40:40.980Z,1716309640.980 [MissionManager](INFO): MissionManager is completed. 2024-05-21T16:40:40.980Z,1716309640.980 [MissionManager](INFO): Uninitializing Mission OnDock 2024-05-21T16:40:40.980Z,1716309640.980 [OnDock] Stopped 2024-05-21T16:40:40.980Z,1716309640.980 [OnDock](DEBUG): Aggregate::uninitialize OnDock 2024-05-21T16:40:40.980Z,1716309640.980 [OnDock:StandardEnvelopes] Stopped 2024-05-21T16:40:40.980Z,1716309640.980 [OnDock:StandardEnvelopes](DEBUG): Aggregate::uninitialize OnDock:StandardEnvelopes 2024-05-21T16:40:40.980Z,1716309640.980 [OnDock:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2024-05-21T16:40:40.980Z,1716309640.980 [OnDock:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2024-05-21T16:40:40.981Z,1716309640.981 [OnDock:StandardEnvelopes:B.DepthEnvelope] Stopped 2024-05-21T16:40:40.981Z,1716309640.981 [OnDock:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2024-05-21T16:40:40.981Z,1716309640.981 [OnDock:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2024-05-21T16:40:40.981Z,1716309640.981 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2024-05-21T16:40:40.981Z,1716309640.981 [OnDock:BackseatDriver] Stopped 2024-05-21T16:40:40.981Z,1716309640.981 [OnDock:BackseatDriver](DEBUG): Aggregate::uninitialize OnDock:BackseatDriver 2024-05-21T16:40:40.981Z,1716309640.981 [OnDock:BackseatDriver:A.BackseatDriver] Stopped 2024-05-21T16:40:40.981Z,1716309640.981 [OnDock:PowerOnly] Stopped 2024-05-21T16:40:40.981Z,1716309640.981 [OnDock:PowerOnly](DEBUG): Aggregate::uninitialize OnDock:PowerOnly 2024-05-21T16:40:40.981Z,1716309640.981 [OnDock:PowerOnly:E.Wait] Stopped 2024-05-21T16:40:40.982Z,1716309640.982 [OnDock:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2024-05-21T16:40:40.982Z,1716309640.982 [OnDock:H] Stopped 2024-05-21T16:40:40.982Z,1716309640.982 [OnDock:I.] Stopped 2024-05-21T16:40:40.982Z,1716309640.982 [OnDock:I.](DEBUG): Uninitializing TrackAcousticContact. 2024-05-21T16:40:40.982Z,1716309640.982 [OnDock:PeriodicCheck] Stopped 2024-05-21T16:40:40.982Z,1716309640.982 [OnDock:PeriodicCheck](DEBUG): Aggregate::uninitialize OnDock:PeriodicCheck 2024-05-21T16:40:40.982Z,1716309640.982 [OnDock:PeriodicCheck:CheckIP] Stopped 2024-05-21T16:40:40.982Z,1716309640.982 [OnDock:PeriodicCheck:CheckIP](DEBUG): Aggregate::uninitialize OnDock:PeriodicCheck:CheckIP 2024-05-21T16:40:40.982Z,1716309640.982 [OnDock:PeriodicCheck:CheckIP:B.Wait] Stopped 2024-05-21T16:40:40.982Z,1716309640.982 [OnDock:PeriodicCheck:CheckIP:B.Wait](DEBUG): Uninitialize Wait Component. 2024-05-21T16:40:40.982Z,1716309640.982 [OnDock:O.Docked] Stopped 2024-05-21T16:40:41.364Z,1716309641.364 [MissionManager](IMPORTANT): Started mission DefaultTankUndock 2024-05-21T16:40:41.364Z,1716309641.364 [DefaultTankUndock] Running Loop=1 2024-05-21T16:40:41.364Z,1716309641.364 [DefaultTankUndock](DEBUG): Aggregate::initialize DefaultTankUndock 2024-05-21T16:40:41.364Z,1716309641.364 [DefaultTankUndock:B.GoToSurface] Running Loop=1 2024-05-21T16:40:41.364Z,1716309641.364 [DefaultTankUndock:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-05-21T16:40:41.364Z,1716309641.364 [DefaultTankUndock:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-05-21T16:40:41.365Z,1716309641.365 [DefaultTankUndock:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-05-21T16:40:41.365Z,1716309641.365 [DefaultTankUndock:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-05-21T16:40:41.365Z,1716309641.365 [DefaultTankUndock:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-05-21T16:40:41.366Z,1716309641.366 [DefaultTankUndock:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-05-21T16:40:41.366Z,1716309641.366 [DefaultTankUndock:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-05-21T16:40:41.367Z,1716309641.367 [DefaultTankUndock:A.Wait] Running Loop=1 2024-05-21T16:40:41.367Z,1716309641.367 [DefaultTankUndock:A.Wait](DEBUG): Initialize Wait Component. 2024-05-21T16:40:41.967Z,1716309641.967 [DataOverHttps](IMPORTANT): SBD MTMSN=20240521T164041 2024-05-21T16:40:50.271Z,1716309650.271 [DataOverHttps](INFO): Sending 387 bytes from file Logs/20240521T155732/Express0036.lzma 2024-05-21T16:40:50.282Z,1716309650.282 [DataOverHttps](INFO): Received command: schedule clear 2024-05-21T16:40:50.347Z,1716309650.347 [CommandExec](IMPORTANT): got command schedule clear 2024-05-21T16:40:50.348Z,1716309650.348 [CommandExec](IMPORTANT): Cleared 3 scheduled commands. 2024-05-21T16:40:51.267Z,1716309651.267 [DataOverHttps](INFO): Moved sent file to Logs/20240521T155732/Express0036.lzma.bak 2024-05-21T16:40:51.268Z,1716309651.268 [DataOverHttps](INFO): SBD MOMSN=21060454 2024-05-21T16:40:54.686Z,1716309654.686 [DefaultTankUndock:A.Wait](INFO): Done Waiting. 2024-05-21T16:40:54.686Z,1716309654.686 [DefaultTankUndock:A.Wait] Stopped 2024-05-21T16:40:54.686Z,1716309654.686 [DefaultTankUndock:A.Wait](DEBUG): Uninitialize Wait Component. 2024-05-21T16:40:55.110Z,1716309655.110 [DefaultTankUndock:C.Execute] Running Loop=1 2024-05-21T16:40:55.500Z,1716309655.500 [DefaultTankUndock:C.Execute](INFO): Executing command dock arm 2024-05-21T16:40:55.500Z,1716309655.500 [DefaultTankUndock:C.Execute] Stopped 2024-05-21T16:40:55.500Z,1716309655.500 [DefaultTankUndock:CheckIn] Running Loop=1 2024-05-21T16:40:55.500Z,1716309655.500 [DefaultTankUndock:CheckIn](DEBUG): Aggregate::initialize DefaultTankUndock:CheckIn 2024-05-21T16:40:55.501Z,1716309655.501 [DefaultTankUndock:CheckIn:Read_GPS] Running Loop=1 2024-05-21T16:40:55.547Z,1716309655.547 [CommandExec](IMPORTANT): got command docking arm 2024-05-21T16:40:55.547Z,1716309655.547 [CommandExec](IMPORTANT): Switching docking moudle to ARM. 2024-05-21T16:40:55.935Z,1716309655.935 [DockingStepper](INFO): Changing to mode: 2 2024-05-21T16:40:55.935Z,1716309655.935 [DockingStepper](INFO): Armed mode. 2024-05-21T16:40:55.986Z,1716309655.986 [DockingStepper](INFO): Commanding negative:54500 2024-05-21T16:40:57.619Z,1716309657.619 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164057.00,A,3648.16390,N,12147.28110,W,0.058,0.00,210524,,,A*7F 2024-05-21T16:40:57.622Z,1716309657.622 [NAL9602](INFO): GPS fix at 20240521T164057: (36.802732, -121.788018) 2024-05-21T16:40:57.649Z,1716309657.649 [DefaultTankUndock:CheckIn:Read_GPS] Stopped 2024-05-21T16:40:57.650Z,1716309657.650 [DefaultTankUndock:CheckIn:Read_Iridium] Running Loop=1 2024-05-21T16:41:18.355Z,1716309678.355 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20240521T163907/Courier0003.lzma 2024-05-21T16:41:19.356Z,1716309679.356 [DataOverHttps](INFO): Moved sent file to Logs/20240521T163907/Courier0003.lzma.bak 2024-05-21T16:41:19.356Z,1716309679.356 [DataOverHttps](INFO): SBD MOMSN=21060460 2024-05-21T16:41:29.935Z,1716309689.935 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-21T16:41:39.669Z,1716309699.669 [DataOverHttps](INFO): Sending 125 bytes from file Logs/20240521T155732/Express0038.lzma 2024-05-21T16:41:40.671Z,1716309700.671 [DataOverHttps](INFO): Moved sent file to Logs/20240521T155732/Express0038.lzma.bak 2024-05-21T16:41:40.672Z,1716309700.672 [DataOverHttps](INFO): SBD MOMSN=21060463 2024-05-21T16:41:55.920Z,1716309715.920 [RDI_Pathfinder](ERROR): only read 1 of 4 data items for water velocity. Device response is::WS,-32768-32768,V 2024-05-21T16:42:04.994Z,1716309724.994 [DataOverHttps](INFO): Sending 593 bytes from file Logs/20240521T163907/Express0001.lzma 2024-05-21T16:42:05.999Z,1716309725.999 [DataOverHttps](INFO): Moved sent file to Logs/20240521T163907/Express0001.lzma.bak 2024-05-21T16:42:05.999Z,1716309725.999 [DataOverHttps](INFO): SBD MOMSN=21060466 2024-05-21T16:42:28.698Z,1716309748.698 [DataOverHttps](INFO): Sending 327 bytes from file Logs/20240521T163907/Express0004.lzma 2024-05-21T16:42:29.696Z,1716309749.696 [DataOverHttps](INFO): Moved sent file to Logs/20240521T163907/Express0004.lzma.bak 2024-05-21T16:42:29.697Z,1716309749.697 [DataOverHttps](INFO): SBD MOMSN=21060475 2024-05-21T16:42:33.663Z,1716309753.663 [DefaultTankUndock:CheckIn:Read_Iridium] Stopped 2024-05-21T16:42:33.665Z,1716309753.665 [DefaultTankUndock:CheckIn:C.Wait] Running Loop=1 2024-05-21T16:42:33.666Z,1716309753.666 [DefaultTankUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-21T16:43:11.228Z,1716309791.228 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for beam range. Device response is::RA, 0.00, 0.00, 100.12 2024-05-21T16:45:31.077Z,1716309931.077 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for bottom velocity. Device response is::BS, +9, -0,A 2024-05-21T16:47:34.113Z,1716310054.113 [DefaultTankUndock:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-21T16:47:34.113Z,1716310054.113 [DefaultTankUndock:CheckIn:C.Wait] Stopped 2024-05-21T16:47:34.113Z,1716310054.113 [DefaultTankUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-21T16:47:34.114Z,1716310054.114 [DefaultTankUndock:CheckIn:D] Running Loop=1 2024-05-21T16:47:34.489Z,1716310054.489 [DefaultTankUndock:CheckIn:D] Stopped 2024-05-21T16:47:34.489Z,1716310054.489 [DefaultTankUndock:CheckIn:E] Running Loop=1 2024-05-21T16:47:34.928Z,1716310054.928 [DefaultTankUndock:CheckIn:E](IMPORTANT): Default mission has been running for 6.885414 min 2024-05-21T16:47:34.928Z,1716310054.928 [DefaultTankUndock:CheckIn:E] Stopped 2024-05-21T16:47:34.928Z,1716310054.928 [DefaultTankUndock:CheckIn](INFO): Completed DefaultTankUndock:CheckIn 2024-05-21T16:47:34.928Z,1716310054.928 [DefaultTankUndock:CheckIn] Stopped 2024-05-21T16:47:34.928Z,1716310054.928 [DefaultTankUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultTankUndock:CheckIn 2024-05-21T16:47:34.929Z,1716310054.929 [DefaultTankUndock:CheckIn](INFO): Running loop #2 2024-05-21T16:47:34.929Z,1716310054.929 [DefaultTankUndock:CheckIn] Running Loop=2 2024-05-21T16:47:34.929Z,1716310054.929 [DefaultTankUndock:CheckIn](DEBUG): Aggregate::initialize DefaultTankUndock:CheckIn 2024-05-21T16:47:34.929Z,1716310054.929 [DefaultTankUndock:CheckIn:Read_GPS] Running Loop=1 2024-05-21T16:47:36.910Z,1716310056.910 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164736.00,A,3648.16539,N,12147.28359,W,0.641,0.00,210524,,,A*7B 2024-05-21T16:47:36.914Z,1716310056.914 [NAL9602](INFO): GPS fix at 20240521T164736: (36.802757, -121.788060) 2024-05-21T16:47:36.967Z,1716310056.967 [DefaultTankUndock:CheckIn:Read_GPS] Stopped 2024-05-21T16:47:36.967Z,1716310056.967 [DefaultTankUndock:CheckIn:Read_Iridium] Running Loop=1 2024-05-21T16:47:46.478Z,1716310066.478 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240521T163907/Courier0006.lzma 2024-05-21T16:47:47.479Z,1716310067.479 [DataOverHttps](INFO): Moved sent file to Logs/20240521T163907/Courier0006.lzma.bak 2024-05-21T16:47:47.479Z,1716310067.479 [DataOverHttps](INFO): SBD MOMSN=21060498 2024-05-21T16:47:56.206Z,1716310076.206 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for bottom velocity. Device response is::BS, +5, +0,A 2024-05-21T16:48:07.758Z,1716310087.758 [DataOverHttps](INFO): Sending 191 bytes from file Logs/20240521T163907/Express0008.lzma 2024-05-21T16:48:08.759Z,1716310088.759 [DataOverHttps](INFO): Moved sent file to Logs/20240521T163907/Express0008.lzma.bak 2024-05-21T16:48:08.760Z,1716310088.760 [DataOverHttps](INFO): SBD MOMSN=21060501 2024-05-21T16:48:13.774Z,1716310093.774 [DefaultTankUndock:CheckIn:Read_Iridium] Stopped 2024-05-21T16:48:13.774Z,1716310093.774 [DefaultTankUndock:CheckIn:C.Wait] Running Loop=1 2024-05-21T16:48:13.774Z,1716310093.774 [DefaultTankUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-21T16:48:32.712Z,1716310112.712 [NAL9602](INFO): SBD MO Status=1, MOMSN=37727, MT Status=0, MTMSN=0 2024-05-21T16:48:32.712Z,1716310112.712 [NAL9602](INFO): No messages in MT queue 2024-05-21T16:49:03.483Z,1716310143.483 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-21T16:50:21.508Z,1716310221.508 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for water velocity. Device response is::WS,-32768,-32768, 2024-05-21T16:53:14.353Z,1716310394.353 [DefaultTankUndock:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-21T16:53:14.353Z,1716310394.353 [DefaultTankUndock:CheckIn:C.Wait] Stopped 2024-05-21T16:53:14.353Z,1716310394.353 [DefaultTankUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-21T16:53:14.354Z,1716310394.354 [DefaultTankUndock:CheckIn:D] Running Loop=1 2024-05-21T16:53:14.751Z,1716310394.751 [DefaultTankUndock:CheckIn:D] Stopped 2024-05-21T16:53:14.751Z,1716310394.751 [DefaultTankUndock:CheckIn:E] Running Loop=1 2024-05-21T16:53:15.192Z,1716310395.192 [DefaultTankUndock:CheckIn:E](IMPORTANT): Default mission has been running for 12.556446 min 2024-05-21T16:53:15.192Z,1716310395.192 [DefaultTankUndock:CheckIn:E] Stopped 2024-05-21T16:53:15.192Z,1716310395.192 [DefaultTankUndock:CheckIn](INFO): Completed DefaultTankUndock:CheckIn 2024-05-21T16:53:15.192Z,1716310395.192 [DefaultTankUndock:CheckIn] Stopped 2024-05-21T16:53:15.193Z,1716310395.193 [DefaultTankUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultTankUndock:CheckIn 2024-05-21T16:53:15.193Z,1716310395.193 [DefaultTankUndock:CheckIn](INFO): Running loop #3 2024-05-21T16:53:15.193Z,1716310395.193 [DefaultTankUndock:CheckIn] Running Loop=3 2024-05-21T16:53:15.193Z,1716310395.193 [DefaultTankUndock:CheckIn](DEBUG): Aggregate::initialize DefaultTankUndock:CheckIn 2024-05-21T16:53:15.193Z,1716310395.193 [DefaultTankUndock:CheckIn:Read_GPS] Running Loop=1 2024-05-21T16:53:17.168Z,1716310397.168 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165317.00,A,3648.16156,N,12147.28301,W,0.311,0.00,210524,,,A*7D 2024-05-21T16:53:17.170Z,1716310397.170 [NAL9602](INFO): GPS fix at 20240521T165317: (36.802693, -121.788050) 2024-05-21T16:53:17.257Z,1716310397.257 [DefaultTankUndock:CheckIn:Read_GPS] Stopped 2024-05-21T16:53:17.257Z,1716310397.257 [DefaultTankUndock:CheckIn:Read_Iridium] Running Loop=1 2024-05-21T16:53:21.889Z,1716310401.889 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240521T163907/Courier0012.lzma 2024-05-21T16:53:22.947Z,1716310402.947 [DataOverHttps](INFO): Moved sent file to Logs/20240521T163907/Courier0012.lzma.bak 2024-05-21T16:53:22.947Z,1716310402.947 [DataOverHttps](INFO): SBD MOMSN=21060519 2024-05-21T16:53:44.487Z,1716310424.487 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20240521T163907/Express0013.lzma 2024-05-21T16:53:45.487Z,1716310425.487 [DataOverHttps](INFO): Moved sent file to Logs/20240521T163907/Express0013.lzma.bak 2024-05-21T16:53:45.488Z,1716310425.488 [DataOverHttps](INFO): SBD MOMSN=21060522 2024-05-21T16:53:49.380Z,1716310429.380 [DefaultTankUndock:CheckIn:Read_Iridium] Stopped 2024-05-21T16:53:49.381Z,1716310429.381 [DefaultTankUndock:CheckIn:C.Wait] Running Loop=1 2024-05-21T16:53:49.381Z,1716310429.381 [DefaultTankUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-21T16:54:43.615Z,1716310483.615 [DataOverHttps](IMPORTANT): SBD MTMSN=20240521T165443 2024-05-21T16:54:51.789Z,1716310491.789 [DataOverHttps](INFO): Received command: dock standby 2024-05-21T16:54:51.847Z,1716310491.847 [CommandExec](IMPORTANT): got command docking standby 2024-05-21T16:54:51.847Z,1716310491.847 [CommandExec](IMPORTANT): Switching docking moudle to STANDBY. 2024-05-21T16:54:51.864Z,1716310491.864 [DockingStepper](INFO): Changing to mode: 1 2024-05-21T16:54:51.864Z,1716310491.864 [DockingStepper](INFO): Standby mode. 2024-05-21T16:54:51.910Z,1716310491.910 [DockingStepper](INFO): Commanding positive:54599 2024-05-21T16:54:57.582Z,1716310497.582 [DockingStepper](INFO): Commanding positive:27508 2024-05-21T16:55:00.806Z,1716310500.806 [DockingStepper](INFO): Commanding positive:27318 2024-05-21T16:55:03.794Z,1716310503.794 [DockingStepper](INFO): Commanding positive:27213 2024-05-21T16:55:06.602Z,1716310506.602 [DockingStepper](INFO): Commanding positive:27114 2024-05-21T16:55:09.638Z,1716310509.638 [DockingStepper](INFO): Commanding positive:26911 2024-05-21T16:55:12.810Z,1716310512.810 [DockingStepper](INFO): Commanding positive:26819 2024-05-21T16:55:15.618Z,1716310515.618 [DockingStepper](INFO): Commanding positive:26819 2024-05-21T16:55:18.530Z,1716310518.530 [DockingStepper](INFO): Commanding positive:26825 2024-05-21T16:55:21.354Z,1716310521.354 [DockingStepper](INFO): Commanding positive:26825 2024-05-21T16:55:24.270Z,1716310524.270 [DockingStepper](INFO): Commanding positive:26819 2024-05-21T16:55:27.038Z,1716310527.038 [DockingStepper](INFO): Commanding positive:26825 2024-05-21T16:55:27.342Z,1716310527.342 [DockingStepper](FAULT): uart error: serial timeout moving from: 12143 to: 16500 response: 2024-05-21T16:55:27.342Z,1716310527.342 [DockingStepper] Communications Fault, FailCount= 1 2024-05-21T16:55:27.342Z,1716310527.342 [DockingStepper](ERROR): Communications Fault 2024-05-21T16:55:27.346Z,1716310527.346 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-21T16:55:27.406Z,1716310527.406 [DockingStepper](INFO): Stop 2024-05-21T16:55:27.406Z,1716310527.406 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-21T16:55:27.406Z,1716310527.406 [DockingStepper](INFO): Powering down 2024-05-21T16:55:27.854Z,1716310527.854 [DockingStepper](INFO): Stopping 2024-05-21T16:55:28.207Z,1716310528.207 [DockingStepper](INFO): Stopped 2024-05-21T16:55:28.211Z,1716310528.211 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-21T16:55:28.211Z,1716310528.211 [DockingStepper] No Fault, FailCount= 1 2024-05-21T16:55:28.602Z,1716310528.602 [DockingStepper](INFO): Start 2024-05-21T16:55:28.603Z,1716310528.603 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-21T16:55:28.603Z,1716310528.603 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-21T16:55:29.024Z,1716310529.024 [DockingStepper](INFO): Starting 2024-05-21T16:55:29.403Z,1716310529.403 [DockingStepper](INFO): Starting 2024-05-21T16:55:29.826Z,1716310529.826 [DockingStepper](INFO): Starting 2024-05-21T16:55:29.871Z,1716310529.871 [DockingStepper](INFO): Init string: /1z12143aE13561N3m50 2024-05-21T16:55:30.230Z,1716310530.230 [DockingStepper](INFO): Starting 2024-05-21T16:55:30.647Z,1716310530.647 [DockingStepper](INFO): Starting 2024-05-21T16:55:31.036Z,1716310531.036 [DockingStepper](INFO): Starting 2024-05-21T16:55:31.426Z,1716310531.426 [DockingStepper](INFO): Starting 2024-05-21T16:55:31.454Z,1716310531.454 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-21T16:55:31.865Z,1716310531.865 [DockingStepper](INFO): Starting 2024-05-21T16:55:32.318Z,1716310532.318 [DockingStepper](INFO): Commanding positive:26819 2024-05-21T16:55:32.332Z,1716310532.332 [DockingStepper](FAULT): Invalid EZ Servo response:"\r\n" 2024-05-21T16:55:32.332Z,1716310532.332 [DockingStepper] Communications Fault, FailCount= 2 2024-05-21T16:55:32.332Z,1716310532.332 [DockingStepper](ERROR): Communications Fault 2024-05-21T16:55:32.430Z,1716310532.430 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-21T16:55:32.656Z,1716310532.656 [DockingStepper](INFO): Stop 2024-05-21T16:55:32.657Z,1716310532.657 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-21T16:55:32.657Z,1716310532.657 [DockingStepper](INFO): Powering down 2024-05-21T16:55:33.063Z,1716310533.063 [DockingStepper](INFO): Stopping 2024-05-21T16:55:33.462Z,1716310533.462 [DockingStepper](INFO): Stopped 2024-05-21T16:55:33.466Z,1716310533.466 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-21T16:55:33.466Z,1716310533.466 [DockingStepper] No Fault, FailCount= 2 2024-05-21T16:55:33.854Z,1716310533.854 [DockingStepper](INFO): Start 2024-05-21T16:55:33.854Z,1716310533.854 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-21T16:55:33.854Z,1716310533.854 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-21T16:55:34.304Z,1716310534.304 [DockingStepper](INFO): Starting 2024-05-21T16:55:34.671Z,1716310534.671 [DockingStepper](INFO): Starting 2024-05-21T16:55:35.097Z,1716310535.097 [DockingStepper](INFO): Starting 2024-05-21T16:55:35.150Z,1716310535.150 [DockingStepper](INFO): Init string: /1z12143aE13561N3m50 2024-05-21T16:55:35.467Z,1716310535.467 [DockingStepper](INFO): Starting 2024-05-21T16:55:35.885Z,1716310535.885 [DockingStepper](INFO): Starting 2024-05-21T16:55:36.301Z,1716310536.301 [DockingStepper](INFO): Starting 2024-05-21T16:55:36.692Z,1716310536.692 [DockingStepper](INFO): Starting 2024-05-21T16:55:36.718Z,1716310536.718 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-21T16:55:37.104Z,1716310537.104 [DockingStepper](INFO): Starting 2024-05-21T16:55:37.550Z,1716310537.550 [DockingStepper](INFO): Commanding positive:26825 2024-05-21T16:55:40.382Z,1716310540.382 [DockingStepper](INFO): Commanding positive:26720 2024-05-21T16:55:43.198Z,1716310543.198 [DockingStepper](INFO): Commanding positive:26807 2024-05-21T16:55:43.282Z,1716310543.282 [DockingStepper](FAULT): uart error: serial timeout moving from: 12146 to: 16500 response: ú 2024-05-21T16:55:43.282Z,1716310543.282 [DockingStepper] Communications Fault, FailCount= 3 2024-05-21T16:55:43.282Z,1716310543.282 [DockingStepper](ERROR): Communications Fault 2024-05-21T16:55:43.286Z,1716310543.286 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-21T16:55:43.547Z,1716310543.547 [DockingStepper](INFO): Stop 2024-05-21T16:55:43.547Z,1716310543.547 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-21T16:55:43.548Z,1716310543.548 [DockingStepper](INFO): Powering down 2024-05-21T16:55:43.961Z,1716310543.961 [DockingStepper](INFO): Stopping 2024-05-21T16:55:44.385Z,1716310544.385 [DockingStepper](INFO): Stopped 2024-05-21T16:55:44.389Z,1716310544.389 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-21T16:55:44.390Z,1716310544.390 [DockingStepper] No Fault, FailCount= 3 2024-05-21T16:55:44.752Z,1716310544.752 [DockingStepper](INFO): Start 2024-05-21T16:55:44.752Z,1716310544.752 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-21T16:55:44.753Z,1716310544.753 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-21T16:55:45.185Z,1716310545.185 [DockingStepper](INFO): Starting 2024-05-21T16:55:45.582Z,1716310545.582 [DockingStepper](INFO): Starting 2024-05-21T16:55:45.964Z,1716310545.964 [DockingStepper](INFO): Starting 2024-05-21T16:55:46.018Z,1716310546.018 [DockingStepper](INFO): Init string: /1z12145aE13561N3m50 2024-05-21T16:55:46.387Z,1716310546.387 [DockingStepper](INFO): Starting 2024-05-21T16:55:46.794Z,1716310546.794 [DockingStepper](INFO): Starting 2024-05-21T16:55:47.208Z,1716310547.208 [DockingStepper](INFO): Starting 2024-05-21T16:55:47.579Z,1716310547.579 [DockingStepper](INFO): Starting 2024-05-21T16:55:47.606Z,1716310547.606 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-21T16:55:47.991Z,1716310547.991 [DockingStepper](INFO): Starting 2024-05-21T16:55:48.458Z,1716310548.458 [DockingStepper](INFO): Commanding positive:26819 2024-05-21T16:55:51.294Z,1716310551.294 [DockingStepper](INFO): Commanding positive:26720 2024-05-21T16:55:54.114Z,1716310554.114 [DockingStepper](INFO): Commanding positive:26825 2024-05-21T16:55:56.950Z,1716310556.950 [DockingStepper](INFO): Commanding positive:26720 2024-05-21T16:55:59.782Z,1716310559.782 [DockingStepper](INFO): Commanding positive:26819 2024-05-21T16:56:02.602Z,1716310562.602 [DockingStepper](INFO): Commanding positive:26807 2024-05-21T16:56:03.195Z,1716310563.195 [DataOverHttps](IMPORTANT): SBD MTMSN=20240521T165602 2024-05-21T16:56:05.466Z,1716310565.466 [DockingStepper](INFO): Commanding positive:26727 2024-05-21T16:56:08.246Z,1716310568.246 [DockingStepper](INFO): Commanding positive:26628 2024-05-21T16:56:11.062Z,1716310571.062 [DockingStepper](INFO): Commanding positive:26720 2024-05-21T16:56:11.506Z,1716310571.506 [DataOverHttps](INFO): Received command: dock arm 2024-05-21T16:56:11.560Z,1716310571.560 [CommandExec](IMPORTANT): got command docking arm 2024-05-21T16:56:11.560Z,1716310571.560 [CommandExec](IMPORTANT): Switching docking moudle to ARM. 2024-05-21T16:56:11.842Z,1716310571.842 [DockingStepper](INFO): Changing to mode: 2 2024-05-21T16:56:11.842Z,1716310571.842 [DockingStepper](INFO): Armed mode. 2024-05-21T16:56:13.914Z,1716310573.914 [DockingStepper](INFO): Commanding negative:28684 2024-05-21T16:56:35.285Z,1716310595.285 [DockingStepper](ERROR): only read 0 of 4 ADC values. Response is: ÿ/0@ 2024-05-21T16:56:35.286Z,1716310595.286 [DockingStepper](FAULT): Docking arm not reporting position 2024-05-21T16:56:35.286Z,1716310595.286 [DockingStepper] Communications Fault, FailCount= 1 2024-05-21T16:56:35.286Z,1716310595.286 [DockingStepper](ERROR): Communications Fault 2024-05-21T16:56:35.295Z,1716310595.295 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-21T16:56:35.661Z,1716310595.661 [DockingStepper](INFO): Stop 2024-05-21T16:56:35.661Z,1716310595.661 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-21T16:56:35.661Z,1716310595.661 [DockingStepper](INFO): Powering down 2024-05-21T16:56:36.122Z,1716310596.122 [DockingStepper](INFO): Stopping 2024-05-21T16:56:36.472Z,1716310596.472 [DockingStepper](INFO): Stopped 2024-05-21T16:56:36.492Z,1716310596.492 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-21T16:56:36.492Z,1716310596.492 [DockingStepper] No Fault, FailCount= 1 2024-05-21T16:56:36.919Z,1716310596.919 [DockingStepper](INFO): Start 2024-05-21T16:56:36.919Z,1716310596.919 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-21T16:56:36.920Z,1716310596.920 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-21T16:56:37.279Z,1716310597.279 [DockingStepper](INFO): Starting 2024-05-21T16:56:37.695Z,1716310597.695 [DockingStepper](INFO): Starting 2024-05-21T16:56:38.122Z,1716310598.122 [DockingStepper](INFO): Starting 2024-05-21T16:56:38.166Z,1716310598.166 [DockingStepper](INFO): Init string: /1z7551aE13561N3m50 2024-05-21T16:56:38.495Z,1716310598.495 [DockingStepper](INFO): Starting 2024-05-21T16:56:38.952Z,1716310598.952 [DockingStepper](INFO): Starting 2024-05-21T16:56:39.311Z,1716310599.311 [DockingStepper](INFO): Starting 2024-05-21T16:56:39.704Z,1716310599.704 [DockingStepper](INFO): Starting 2024-05-21T16:56:39.730Z,1716310599.730 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-21T16:56:40.124Z,1716310600.124 [DockingStepper](INFO): Starting 2024-05-21T16:56:46.572Z,1716310606.572 [DockingStepper](ERROR): only read 0 of 4 ADC values. Response is: ÿ/0@ 2024-05-21T16:56:46.572Z,1716310606.572 [DockingStepper](FAULT): Docking arm not reporting position 2024-05-21T16:56:46.573Z,1716310606.573 [DockingStepper] Communications Fault, FailCount= 1 2024-05-21T16:56:46.573Z,1716310606.573 [DockingStepper](ERROR): Communications Fault 2024-05-21T16:56:46.578Z,1716310606.578 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-21T16:56:47.035Z,1716310607.035 [DockingStepper](INFO): Stop 2024-05-21T16:56:47.035Z,1716310607.035 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-21T16:56:47.036Z,1716310607.036 [DockingStepper](INFO): Powering down 2024-05-21T16:56:47.403Z,1716310607.403 [DockingStepper](INFO): Stopping 2024-05-21T16:56:47.679Z,1716310607.679 [DataOverHttps](IMPORTANT): SBD MTMSN=20240521T165647 2024-05-21T16:56:47.784Z,1716310607.784 [DockingStepper](INFO): Stopped 2024-05-21T16:56:47.789Z,1716310607.789 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-21T16:56:47.789Z,1716310607.789 [DockingStepper] No Fault, FailCount= 1 2024-05-21T16:56:48.202Z,1716310608.202 [DockingStepper](INFO): Start 2024-05-21T16:56:48.203Z,1716310608.203 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-21T16:56:48.203Z,1716310608.203 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-21T16:56:48.594Z,1716310608.594 [DockingStepper](INFO): Starting 2024-05-21T16:56:49.053Z,1716310609.053 [DockingStepper](INFO): Starting 2024-05-21T16:56:49.433Z,1716310609.433 [DockingStepper](INFO): Starting 2024-05-21T16:56:49.486Z,1716310609.486 [DockingStepper](INFO): Init string: /1z7551aE13561N3m50 2024-05-21T16:56:49.801Z,1716310609.801 [DockingStepper](INFO): Starting 2024-05-21T16:56:50.228Z,1716310610.228 [DockingStepper](INFO): Starting 2024-05-21T16:56:50.631Z,1716310610.631 [DockingStepper](INFO): Starting 2024-05-21T16:56:51.099Z,1716310611.099 [DockingStepper](INFO): Starting 2024-05-21T16:56:51.134Z,1716310611.134 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-21T16:56:51.480Z,1716310611.480 [DockingStepper](INFO): Starting 2024-05-21T16:56:55.942Z,1716310615.942 [DataOverHttps](INFO): Received command: dock slide 2024-05-21T16:56:56.007Z,1716310616.007 [CommandExec](IMPORTANT): got command docking slide 2024-05-21T16:56:56.007Z,1716310616.007 [CommandExec](IMPORTANT): Switching docking module to SLIDE. 2024-05-21T16:56:56.288Z,1716310616.288 [DockingStepper](INFO): Changing to mode: 4 2024-05-21T16:56:56.288Z,1716310616.288 [DockingStepper](INFO): Slide mode. 2024-05-21T16:56:56.338Z,1716310616.338 [DockingStepper](INFO): Commanding positive:27385 2024-05-21T16:57:05.589Z,1716310625.589 [DockingStepper](ERROR): only read 0 of 4 ADC values. Response is: ÿ/0@ 2024-05-21T16:57:05.590Z,1716310625.590 [DockingStepper](FAULT): Docking arm not reporting position 2024-05-21T16:57:05.590Z,1716310625.590 [DockingStepper] Communications Fault, FailCount= 1 2024-05-21T16:57:05.590Z,1716310625.590 [DockingStepper](ERROR): Communications Fault 2024-05-21T16:57:05.595Z,1716310625.595 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-21T16:57:05.968Z,1716310625.968 [DockingStepper](INFO): Stop 2024-05-21T16:57:05.968Z,1716310625.968 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-21T16:57:05.968Z,1716310625.968 [DockingStepper](INFO): Powering down 2024-05-21T16:57:06.410Z,1716310626.410 [DockingStepper](INFO): Stopping 2024-05-21T16:57:06.796Z,1716310626.796 [DockingStepper](INFO): Stopped 2024-05-21T16:57:06.800Z,1716310626.800 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-21T16:57:06.800Z,1716310626.800 [DockingStepper] No Fault, FailCount= 1 2024-05-21T16:57:07.189Z,1716310627.189 [DockingStepper](INFO): Start 2024-05-21T16:57:07.189Z,1716310627.189 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-21T16:57:07.190Z,1716310627.190 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-21T16:57:07.614Z,1716310627.614 [DockingStepper](INFO): Starting 2024-05-21T16:57:07.996Z,1716310627.996 [DockingStepper](INFO): Starting 2024-05-21T16:57:08.461Z,1716310628.461 [DockingStepper](INFO): Starting 2024-05-21T16:57:08.514Z,1716310628.514 [DockingStepper](INFO): Init string: /1z11952aE13561N3m50 2024-05-21T16:57:08.854Z,1716310628.854 [DockingStepper](INFO): Starting 2024-05-21T16:57:09.303Z,1716310629.303 [DockingStepper](INFO): Starting 2024-05-21T16:57:09.705Z,1716310629.705 [DockingStepper](INFO): Starting 2024-05-21T16:57:10.017Z,1716310630.017 [DockingStepper](INFO): Starting 2024-05-21T16:57:10.046Z,1716310630.046 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-21T16:57:10.451Z,1716310630.451 [DockingStepper](INFO): Starting 2024-05-21T16:57:32.359Z,1716310652.359 [DataOverHttps](IMPORTANT): SBD MTMSN=20240521T165732 2024-05-21T16:57:40.441Z,1716310660.441 [DataOverHttps](INFO): Received command: dock standby 2024-05-21T16:57:40.527Z,1716310660.527 [CommandExec](IMPORTANT): got command docking standby 2024-05-21T16:57:40.527Z,1716310660.527 [CommandExec](IMPORTANT): Switching docking moudle to STANDBY. 2024-05-21T16:57:40.719Z,1716310660.719 [DockingStepper](INFO): Changing to mode: 1 2024-05-21T16:57:40.719Z,1716310660.719 [DockingStepper](INFO): Standby mode. 2024-05-21T16:57:40.768Z,1716310660.768 [DockingStepper](INFO): Commanding positive:28099 2024-05-21T16:57:44.018Z,1716310664.018 [DockingStepper](INFO): Commanding positive:3577 2024-05-21T16:57:44.822Z,1716310664.822 [DockingStepper](INFO): Commanding positive:3281 2024-05-21T16:57:45.242Z,1716310665.242 [DockingStepper](INFO): Commanding positive:3275 2024-05-21T16:57:46.030Z,1716310666.030 [DockingStepper](INFO): Commanding positive:3183 2024-05-21T16:57:46.043Z,1716310666.043 [DockingStepper](FAULT): Invalid EZ Servo response:"\r\n" 2024-05-21T16:57:46.043Z,1716310666.043 [DockingStepper] Communications Fault, FailCount= 1 2024-05-21T16:57:46.043Z,1716310666.043 [DockingStepper](ERROR): Communications Fault 2024-05-21T16:57:46.126Z,1716310666.126 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-21T16:57:46.366Z,1716310666.366 [DockingStepper](INFO): Stop 2024-05-21T16:57:46.367Z,1716310666.367 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-21T16:57:46.367Z,1716310666.367 [DockingStepper](INFO): Powering down 2024-05-21T16:57:46.772Z,1716310666.772 [DockingStepper](INFO): Stopping 2024-05-21T16:57:47.200Z,1716310667.200 [DockingStepper](INFO): Stopped 2024-05-21T16:57:47.204Z,1716310667.204 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-21T16:57:47.204Z,1716310667.204 [DockingStepper] No Fault, FailCount= 1 2024-05-21T16:57:47.584Z,1716310667.584 [DockingStepper](INFO): Start 2024-05-21T16:57:47.584Z,1716310667.584 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-21T16:57:47.584Z,1716310667.584 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-21T16:57:47.976Z,1716310667.976 [NAL9602](INFO): SBD MO Status=1, MOMSN=37728, MT Status=0, MTMSN=0 2024-05-21T16:57:47.976Z,1716310667.976 [NAL9602](INFO): No messages in MT queue 2024-05-21T16:57:48.039Z,1716310668.039 [DockingStepper](INFO): Starting 2024-05-21T16:57:48.394Z,1716310668.394 [DockingStepper](INFO): Starting 2024-05-21T16:57:48.805Z,1716310668.805 [DockingStepper](INFO): Starting 2024-05-21T16:57:48.859Z,1716310668.859 [DockingStepper](INFO): Init string: /1z15985aE13561N3m50 2024-05-21T16:57:49.228Z,1716310669.228 [DockingStepper](INFO): Starting 2024-05-21T16:57:49.607Z,1716310669.607 [DockingStepper](INFO): Starting 2024-05-21T16:57:50.022Z,1716310670.022 [DockingStepper](INFO): Starting 2024-05-21T16:57:50.417Z,1716310670.417 [DockingStepper](INFO): Starting 2024-05-21T16:57:50.462Z,1716310670.462 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-21T16:57:50.830Z,1716310670.830 [DockingStepper](INFO): Starting 2024-05-21T16:57:51.310Z,1716310671.310 [DockingStepper](INFO): Commanding positive:3176 2024-05-21T16:58:18.678Z,1716310698.678 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-21T16:58:20.963Z,1716310700.963 [DataOverHttps](IMPORTANT): SBD MTMSN=20240521T165820 2024-05-21T16:58:29.041Z,1716310709.041 [DataOverHttps](INFO): Received command: schedule resume 2024-05-21T16:58:29.112Z,1716310709.112 [CommandExec](IMPORTANT): got command schedule resume 2024-05-21T16:58:29.112Z,1716310709.112 [CommandExec](IMPORTANT): Scheduling is resumed 2024-05-21T16:58:30.044Z,1716310710.044 [DataOverHttps](IMPORTANT): SBD MTMSN=20240521T165829 2024-05-21T16:58:39.530Z,1716310719.530 [DataOverHttps](INFO): Received command: load Engineering/sink.tl;set sink.MissionTimeout 8 min;set sink.Depth 1..25 m;set sink.DepthDeadband .5 m;set sink.DoBallastAndTrim 0 bool;set sink.MinAltitude 0.00001 m;set sink.MaxDepth 5 m;set sink.MinOffshore 0.000001 km;run 2024-05-21T16:58:39.684Z,1716310719.684 [CommandExec](IMPORTANT): got command load ./Missions/Engineering/sink.tl 2024-05-21T16:58:39.685Z,1716310719.685 [MissionManager](INFO): Loading Mission from file: ./Missions/Engineering/sink.tl 2024-05-21T16:58:39.685Z,1716310719.685 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Engineering/sink.tl 2024-05-21T16:58:39.687Z,1716310719.687 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Engineering/sink.tl 2024-05-21T16:58:43.088Z,1716310723.088 [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-05-21T16:58:43.094Z,1716310723.094 [MissionManager](INFO): DefineArg sink.MissionTimeout = 2.000000 h 2024-05-21T16:58:43.097Z,1716310723.097 [MissionManager](INFO): DefineArg sink.Depth = 20.000000 m 2024-05-21T16:58:43.103Z,1716310723.103 [MissionManager](INFO): DefineArg sink.DepthDeadband = 1.000000 m 2024-05-21T16:58:43.105Z,1716310723.105 [MissionManager](INFO): DefineArg sink.SurfaceThreshold = 2.500000 m 2024-05-21T16:58:43.108Z,1716310723.108 [MissionManager](INFO): DefineArg sink.WaitToFloat = 20.000000 min 2024-05-21T16:58:43.110Z,1716310723.110 [MissionManager](INFO): DefineArg sink.TargetDepthTimeout = 20.000000 min 2024-05-21T16:58:43.112Z,1716310723.112 [MissionManager](INFO): DefineArg sink.DoBallastAndTrim = 1 bool 2024-05-21T16:58:43.115Z,1716310723.115 [MissionManager](INFO): DefineArg sink.BTSettleTime = 10.000000 min 2024-05-21T16:58:43.118Z,1716310723.118 [MissionManager](INFO): DefineArg sink.EstimationTimeout = 45.000000 min 2024-05-21T16:58:43.121Z,1716310723.121 [MissionManager](INFO): DefineArg sink.MinEstimationTime = 10.000000 min 2024-05-21T16:58:43.124Z,1716310723.124 [MissionManager](INFO): DefineArg sink.MassEstimationErrorBound = 0.500000 mm 2024-05-21T16:58:43.175Z,1716310723.175 [MissionManager](INFO): DefineArg sink.BuoyEstimationErrorBound = 25.000000 cc 2024-05-21T16:58:43.177Z,1716310723.177 [MissionManager](INFO): DefineArg sink.EstimationConfidence = 98.000000 % 2024-05-21T16:58:43.187Z,1716310723.187 [MissionManager](INFO): DefineArg sink.MinAltitude = 5.000000 m 2024-05-21T16:58:43.198Z,1716310723.198 [MissionManager](INFO): DefineArg sink.MaxDepth = 30.000000 m 2024-05-21T16:58:43.200Z,1716310723.200 [MissionManager](INFO): DefineArg sink.MinOffshore = 2.000000 km 2024-05-21T16:58:43.201Z,1716310723.201 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/Science.tl 2024-05-21T16:58:43.201Z,1716310723.201 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/Science.tl 2024-05-21T16:58:43.215Z,1716310723.215 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/Science.tl 2024-05-21T16:58:45.052Z,1716310725.052 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/Science.tl translated into: NaN 3 100 80 20 5 NaN NaN 2.0 NaN NaN 5 NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN Triggered. PeakChl values on consecutive yo-yo profiles fall in [ ] (NaN bound is ignored). 2024-05-21T16:58:45.092Z,1716310725.092 [MissionManager](INFO): DefineArg sink:Science.PeakDetectChlActive = 0 bool 2024-05-21T16:58:45.094Z,1716310725.094 [MissionManager](INFO): DefineArg sink:Science.TimeWindowPeakReport = nan min 2024-05-21T16:58:45.096Z,1716310725.096 [MissionManager](INFO): DefineArg sink:Science.HighestChlPeakReportActive = 0 bool 2024-05-21T16:58:45.099Z,1716310725.099 [MissionManager](INFO): DefineArg sink:Science.HighestSaltPeakReportActive = 0 bool 2024-05-21T16:58:45.101Z,1716310725.101 [MissionManager](INFO): DefineArg sink:Science.HighestOilPeakReportActive = 0 bool 2024-05-21T16:58:45.103Z,1716310725.103 [MissionManager](INFO): DefineArg sink:Science.PatchTracking = 0 bool 2024-05-21T16:58:45.105Z,1716310725.105 [MissionManager](INFO): DefineArg sink:Science.FilterWidthHorizontal = 3.000000 count 2024-05-21T16:58:45.107Z,1716310725.107 [MissionManager](INFO): DefineArg sink:Science.NumProfilesSlidingwindow = 100.000000 count 2024-05-21T16:58:45.146Z,1716310725.146 [MissionManager](INFO): DefineArg sink:Science.OffPeakFractionHorizontal = 80.000000 % 2024-05-21T16:58:45.149Z,1716310725.149 [MissionManager](INFO): DefineArg sink:Science.PeakDetectNO3Active = 0 bool 2024-05-21T16:58:45.156Z,1716310725.156 [MissionManager](INFO): DefineArg sink:Science.PeakDetectOilActive = 0 bool 2024-05-21T16:58:45.174Z,1716310725.174 [MissionManager](INFO): DefineArg sink:Science.PeakDetectFDOMActive = 0 bool 2024-05-21T16:58:45.176Z,1716310725.176 [MissionManager](INFO): DefineArg sink:Science.PeakDetectSalinityActive = 0 bool 2024-05-21T16:58:45.189Z,1716310725.189 [MissionManager](INFO): DefineArg sink:Science.UpwardDerivativeOfTemperatureActive = 0 bool 2024-05-21T16:58:45.196Z,1716310725.196 [MissionManager](INFO): DefineArg sink:Science.EnabledAanderaaO2 = 0 bool 2024-05-21T16:58:45.202Z,1716310725.202 [MissionManager](INFO): DefineArg sink:Science.EnabledNeilBrown = 0 bool 2024-05-21T16:58:45.205Z,1716310725.205 [MissionManager](INFO): DefineArg sink:Science.EnabledSeabird = 1 bool 2024-05-21T16:58:45.212Z,1716310725.212 [MissionManager](INFO): DefineArg sink:Science.EnabledWetLabsBB2FL = 1 bool 2024-05-21T16:58:45.218Z,1716310725.218 [MissionManager](INFO): DefineArg sink:Science.EnabledWetLabsSeaOWL_UV_A = 0 bool 2024-05-21T16:58:45.221Z,1716310725.221 [MissionManager](INFO): DefineArg sink:Science.EnabledWetLabsUBAT = 1 bool 2024-05-21T16:58:45.243Z,1716310725.243 [MissionManager](INFO): DefineArg sink:Science.LowPassWindowLength = 20.000000 count 2024-05-21T16:58:45.254Z,1716310725.254 [MissionManager](INFO): DefineArg sink:Science.MedianFilterLen = 5.000000 count 2024-05-21T16:58:45.256Z,1716310725.256 [MissionManager](INFO): DefineArg sink:Science.PeakShallowBound = nan m 2024-05-21T16:58:45.266Z,1716310725.266 [MissionManager](INFO): DefineArg sink:Science.PeakDeepBound = nan m 2024-05-21T16:58:45.268Z,1716310725.268 [MissionManager](INFO): DefineArg sink:Science.DepChangeThreshForAttitudeFlip = 2.000000 m 2024-05-21T16:58:45.273Z,1716310725.273 [MissionManager](INFO): DefineArg sink:Science.ChlThreshTriggerActive = 0 bool 2024-05-21T16:58:45.286Z,1716310725.286 [MissionManager](INFO): DefineArg sink:Science.ChlLowerThresh = nan ug/l 2024-05-21T16:58:45.288Z,1716310725.288 [MissionManager](INFO): DefineArg sink:Science.ChlUpperThresh = nan ug/l 2024-05-21T16:58:45.299Z,1716310725.299 [MissionManager](INFO): DefineArg sink:Science.NumProfilesThresh = 5.000000 count 2024-05-21T16:58:45.301Z,1716310725.301 [MissionManager](INFO): DefineOutput sink:Science.PeakChl = nan ug/l 2024-05-21T16:58:45.315Z,1716310725.315 [MissionManager](INFO): DefineOutput sink:Science.PeakChlDepth = nan m 2024-05-21T16:58:45.358Z,1716310725.358 [MissionManager](INFO): DefineOutput sink:Science.PeakChlTemperature = nan degC 2024-05-21T16:58:45.362Z,1716310725.362 [MissionManager](INFO): DefineOutput sink:Science.PeakChlLatitude = nan arcdeg 2024-05-21T16:58:45.365Z,1716310725.365 [MissionManager](INFO): DefineOutput sink:Science.PeakChlLongitude = nan arcdeg 2024-05-21T16:58:45.371Z,1716310725.371 [MissionManager](INFO): DefineOutput sink:Science.PeakNO3 = nan umol/l 2024-05-21T16:58:45.382Z,1716310725.382 [MissionManager](INFO): DefineOutput sink:Science.PeakNO3Depth = nan m 2024-05-21T16:58:45.384Z,1716310725.384 [MissionManager](INFO): DefineOutput sink:Science.PeakNO3Latitude = nan arcdeg 2024-05-21T16:58:45.398Z,1716310725.398 [MissionManager](INFO): DefineOutput sink:Science.PeakNO3Longitude = nan arcdeg 2024-05-21T16:58:45.400Z,1716310725.400 [MissionManager](INFO): DefineOutput sink:Science.PeakOil = nan kg/m3 2024-05-21T16:58:45.407Z,1716310725.407 [MissionManager](INFO): DefineOutput sink:Science.PeakOilDepth = nan m 2024-05-21T16:58:45.415Z,1716310725.415 [MissionManager](INFO): DefineOutput sink:Science.PeakOilLatitude = nan arcdeg 2024-05-21T16:58:45.422Z,1716310725.422 [MissionManager](INFO): DefineOutput sink:Science.PeakOilLongitude = nan arcdeg 2024-05-21T16:58:45.430Z,1716310725.430 [MissionManager](INFO): DefineOutput sink:Science.PeakFDOM = nan ppb 2024-05-21T16:58:45.432Z,1716310725.432 [MissionManager](INFO): DefineOutput sink:Science.PeakFDOMDepth = nan m 2024-05-21T16:58:45.439Z,1716310725.439 [MissionManager](INFO): DefineOutput sink:Science.PeakFDOMTemperature = nan degC 2024-05-21T16:58:45.445Z,1716310725.445 [MissionManager](INFO): DefineOutput sink:Science.PeakFDOMLatitude = nan arcdeg 2024-05-21T16:58:45.448Z,1716310725.448 [MissionManager](INFO): DefineOutput sink:Science.PeakFDOMLongitude = nan arcdeg 2024-05-21T16:58:45.451Z,1716310725.451 [MissionManager](INFO): DefineOutput sink:Science.PeakSalt = nan psu 2024-05-21T16:58:45.453Z,1716310725.453 [MissionManager](INFO): DefineOutput sink:Science.PeakSaltDepth = nan m 2024-05-21T16:58:45.464Z,1716310725.464 [MissionManager](INFO): DefineOutput sink:Science.PeakSaltTemperature = nan degC 2024-05-21T16:58:45.470Z,1716310725.470 [MissionManager](INFO): DefineOutput sink:Science.PeakSaltLatitude = nan arcdeg 2024-05-21T16:58:45.473Z,1716310725.473 [MissionManager](INFO): DefineOutput sink:Science.PeakSaltLongitude = nan arcdeg 2024-05-21T16:58:45.480Z,1716310725.480 [MissionManager](INFO): DefineOutput sink:Science.PatchChl = nan ug/l 2024-05-21T16:58:45.483Z,1716310725.483 [MissionManager](INFO): DefineOutput sink:Science.PatchChlDepth = nan m 2024-05-21T16:58:45.491Z,1716310725.491 [MissionManager](INFO): DefineOutput sink:Science.PatchChlLatitude = nan arcdeg 2024-05-21T16:58:45.500Z,1716310725.500 [MissionManager](INFO): DefineOutput sink:Science.PatchChlLongitude = nan arcdeg 2024-05-21T16:58:45.539Z,1716310725.539 [MissionManager](INFO): DefineOutput sink:Science.PatchChlDistance = nan m 2024-05-21T16:58:45.550Z,1716310725.550 [MissionManager](INFO): DefineOutput sink:Science.PatchSalt = nan psu 2024-05-21T16:58:45.552Z,1716310725.552 [MissionManager](INFO): DefineOutput sink:Science.PatchSaltDepth = nan m 2024-05-21T16:58:45.563Z,1716310725.563 [MissionManager](INFO): DefineOutput sink:Science.PatchSaltLatitude = nan arcdeg 2024-05-21T16:58:45.565Z,1716310725.565 [MissionManager](INFO): DefineOutput sink:Science.PatchSaltLongitude = nan arcdeg 2024-05-21T16:58:45.576Z,1716310725.576 [MissionManager](INFO): DefineOutput sink:Science.PatchSaltDistance = nan m 2024-05-21T16:58:45.586Z,1716310725.586 [MissionManager](INFO): DefineOutput sink:Science.PatchOil = nan psu 2024-05-21T16:58:45.588Z,1716310725.588 [MissionManager](INFO): DefineOutput sink:Science.PatchOilDepth = nan m 2024-05-21T16:58:45.599Z,1716310725.599 [MissionManager](INFO): DefineOutput sink:Science.PatchOilLatitude = nan arcdeg 2024-05-21T16:58:45.610Z,1716310725.610 [MissionManager](INFO): DefineOutput sink:Science.PatchOilLongitude = nan arcdeg 2024-05-21T16:58:45.612Z,1716310725.612 [MissionManager](INFO): DefineOutput sink:Science.PatchOilDistance = nan m 2024-05-21T16:58:45.620Z,1716310725.620 [MissionManager](INFO): DefineOutput sink:Science.TriggeredOnChl = 0 bool 2024-05-21T16:58:45.639Z,1716310725.639 [MissionManager](INFO): DefineOutput sink:Science.ChlTriggering = nan ug/l 2024-05-21T16:58:45.754Z,1716310725.754 [sink:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2024-05-21T16:58:45.790Z,1716310725.790 [sink:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2024-05-21T16:58:45.826Z,1716310725.826 [sink:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2024-05-21T16:58:45.851Z,1716310725.851 [sink:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2024-05-21T16:58:45.883Z,1716310725.883 [sink:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2024-05-21T16:58:45.898Z,1716310725.898 [sink:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2024-05-21T16:58:45.915Z,1716310725.915 [sink:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2024-05-21T16:58:45.930Z,1716310725.930 [sink:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2024-05-21T16:58:45.947Z,1716310725.947 [sink:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Construct ValueDetect. 2024-05-21T16:58:45.954Z,1716310725.954 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl 2024-05-21T16:58:45.955Z,1716310725.955 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl 2024-05-21T16:58:45.956Z,1716310725.956 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl 2024-05-21T16:58:46.331Z,1716310726.331 [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-05-21T16:58:46.376Z,1716310726.376 [MissionManager](INFO): DefineArg sink:NeedComms.DiveInterval = 1.000000 h 2024-05-21T16:58:46.386Z,1716310726.386 [MissionManager](INFO): DefineArg sink:NeedComms.WaitForPitchUp = 10.000000 min 2024-05-21T16:58:46.388Z,1716310726.388 [MissionManager](INFO): DefineArg sink:NeedComms.SurfacePitch = 20.000000 arcdeg 2024-05-21T16:58:46.396Z,1716310726.396 [MissionManager](INFO): DefineArg sink:NeedComms.SurfaceDepthRate = nan m/s 2024-05-21T16:58:46.411Z,1716310726.411 [MissionManager](INFO): DefineArg sink:NeedComms.SurfaceSpeed = 1.000000 m/s 2024-05-21T16:58:46.413Z,1716310726.413 [MissionManager](INFO): DefineArg sink:NeedComms.SurfacingTimeout = 1000.000000 s 2024-05-21T16:58:46.419Z,1716310726.419 [MissionManager](INFO): DefineArg sink:NeedComms.GPSTimeout = 7.000000 min 2024-05-21T16:58:46.426Z,1716310726.426 [MissionManager](INFO): DefineArg sink:NeedComms.CommsTimeout = 30.000000 min 2024-05-21T16:58:46.436Z,1716310726.436 [sink:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-05-21T16:58:46.468Z,1716310726.468 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl 2024-05-21T16:58:46.468Z,1716310726.468 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl 2024-05-21T16:58:46.474Z,1716310726.474 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl 2024-05-21T16:58:46.664Z,1716310726.664 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into: 5 0 200 2000 2024-05-21T16:58:46.668Z,1716310726.668 [MissionManager](INFO): DefineArg sink:StandardEnvelopes.MinAltitude = 5.000000 m 2024-05-21T16:58:46.670Z,1716310726.670 [MissionManager](INFO): DefineArg sink:StandardEnvelopes.MaxDepthIgnore = 0.000000 m 2024-05-21T16:58:46.673Z,1716310726.673 [MissionManager](INFO): DefineArg sink:StandardEnvelopes.MaxDepth = 200.000000 m 2024-05-21T16:58:46.743Z,1716310726.743 [MissionManager](INFO): DefineArg sink:StandardEnvelopes.MinOffshore = 2000.000000 m 2024-05-21T16:58:46.756Z,1716310726.756 [sink:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2024-05-21T16:58:46.785Z,1716310726.785 [sink:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2024-05-21T16:58:46.804Z,1716310726.804 [sink:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2024-05-21T16:58:46.835Z,1716310726.835 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl 2024-05-21T16:58:46.835Z,1716310726.835 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl 2024-05-21T16:58:46.836Z,1716310726.836 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl 2024-05-21T16:58:46.894Z,1716310726.894 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into: 2024-05-21T16:58:46.897Z,1716310726.897 [MissionManager](INFO): DefineArg sink:BackseatDriver.EnableBackseat = 0 bool 2024-05-21T16:58:46.903Z,1716310726.903 [sink:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver. 2024-05-21T16:58:46.916Z,1716310726.916 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl 2024-05-21T16:58:46.917Z,1716310726.917 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl 2024-05-21T16:58:46.922Z,1716310726.922 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl 2024-05-21T16:58:47.254Z,1716310727.254 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into: 99999 2024-05-21T16:58:47.262Z,1716310727.262 [MissionManager](INFO): DefineArg sink:PowerOnly.SampleAll = 0 bool 2024-05-21T16:58:47.264Z,1716310727.264 [MissionManager](INFO): DefineArg sink:PowerOnly.SampleLoad1 = 0 bool 2024-05-21T16:58:47.270Z,1716310727.270 [MissionManager](INFO): DefineArg sink:PowerOnly.SampleLoad2 = 0 bool 2024-05-21T16:58:47.272Z,1716310727.272 [MissionManager](INFO): DefineArg sink:PowerOnly.SampleLoad3 = 0 bool 2024-05-21T16:58:47.329Z,1716310727.329 [MissionManager](INFO): DefineArg sink:PowerOnly.EnabledPowerOnly = 0 bool 2024-05-21T16:58:47.375Z,1716310727.375 [sink:PowerOnly:E.Wait](DEBUG): Construct Wait. 2024-05-21T16:58:47.402Z,1716310727.402 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BallastAndTrim.tl 2024-05-21T16:58:47.402Z,1716310727.402 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BallastAndTrim.tl 2024-05-21T16:58:47.404Z,1716310727.404 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BallastAndTrim.tl 2024-05-21T16:58:48.099Z,1716310728.099 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BallastAndTrim.tl translated into: 25 0 0.4 20 0 20 10 45 10 0.25 15 99.9 1 0.25 0.005 0.00075 0 Going to target depth. Speed set to 0 13 0 Waiting for while pumping down to neutral. Moving to Timed out trying to reach the target depth. Stopping mission at current depth of stop 0 Waiting for the vehicle to settle. Depth = gfscan Running ballast and trim. Depth = 2024-05-21T16:58:48.106Z,1716310728.106 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.InsertDepth = 25.000000 m 2024-05-21T16:58:48.108Z,1716310728.108 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.InsertApproachSpeed = 0.000000 m/s 2024-05-21T16:58:48.132Z,1716310728.132 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.InsertApproachDepthRate = 0.400000 m/s 2024-05-21T16:58:48.135Z,1716310728.135 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.InsertApproachPitchLimit = 20.000000 arcdeg 2024-05-21T16:58:48.137Z,1716310728.137 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.InsertApproachSettleTimePreDive = 0.000000 min 2024-05-21T16:58:48.139Z,1716310728.139 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.InsertApproachDepthTimeout = 20.000000 min 2024-05-21T16:58:48.141Z,1716310728.141 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.InsertSettleTime = 10.000000 min 2024-05-21T16:58:48.143Z,1716310728.143 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.EstTimeout = 45.000000 min 2024-05-21T16:58:48.146Z,1716310728.146 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.MinEstTime = 10.000000 min 2024-05-21T16:58:48.148Z,1716310728.148 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.MassEstErrorBound = 0.250000 mm 2024-05-21T16:58:48.190Z,1716310728.190 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.BuoyEstErrorBound = 15.000000 cc 2024-05-21T16:58:48.192Z,1716310728.192 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.EstConfidence = 99.900002 % 2024-05-21T16:58:48.226Z,1716310728.226 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.InsertDepthDeadband = 1.000000 m 2024-05-21T16:58:48.228Z,1716310728.228 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.InsertMassDeadband = 0.250000 mm 2024-05-21T16:58:48.260Z,1716310728.260 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Construct. 2024-05-21T16:58:48.274Z,1716310728.274 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Construct Buoyancy. 2024-05-21T16:58:48.280Z,1716310728.280 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Construct. 2024-05-21T16:58:48.340Z,1716310728.340 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Construct Wait. 2024-05-21T16:58:48.348Z,1716310728.348 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Construct. 2024-05-21T16:58:48.361Z,1716310728.361 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Construct. 2024-05-21T16:58:48.376Z,1716310728.376 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute. 2024-05-21T16:58:48.384Z,1716310728.384 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Construct. 2024-05-21T16:58:48.390Z,1716310728.390 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Construct. 2024-05-21T16:58:48.398Z,1716310728.398 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Construct Wait. 2024-05-21T16:58:48.400Z,1716310728.400 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:E.Execute](DEBUG): Construct Execute. 2024-05-21T16:58:48.407Z,1716310728.407 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](DEBUG): Construct BallastAndTrim. 2024-05-21T16:58:48.412Z,1716310728.412 [MissionManager](INFO): RedefineArg sink:RunSink:BallastAndTrim.InsertDepthDeadband = reader:sink.DepthDeadband 2024-05-21T16:58:48.414Z,1716310728.414 [MissionManager](INFO): RedefineArg sink:RunSink:BallastAndTrim.InsertApproachDepthTimeout = reader:sink.TargetDepthTimeout 2024-05-21T16:58:48.416Z,1716310728.416 [MissionManager](INFO): RedefineArg sink:RunSink:BallastAndTrim.InsertSettleTime = reader:sink.BTSettleTime 2024-05-21T16:58:48.417Z,1716310728.417 [MissionManager](INFO): RedefineArg sink:RunSink:BallastAndTrim.EstTimeout = reader:sink.EstimationTimeout 2024-05-21T16:58:48.419Z,1716310728.418 [MissionManager](INFO): RedefineArg sink:RunSink:BallastAndTrim.MinEstTime = reader:sink.MinEstimationTime 2024-05-21T16:58:48.420Z,1716310728.420 [MissionManager](INFO): RedefineArg sink:RunSink:BallastAndTrim.MassEstErrorBound = reader:sink.MassEstimationErrorBound 2024-05-21T16:58:48.421Z,1716310728.421 [MissionManager](INFO): RedefineArg sink:RunSink:BallastAndTrim.BuoyEstErrorBound = reader:sink.BuoyEstimationErrorBound 2024-05-21T16:58:48.423Z,1716310728.423 [MissionManager](INFO): RedefineArg sink:RunSink:BallastAndTrim.EstConfidence = reader:sink.EstimationConfidence 2024-05-21T16:58:48.453Z,1716310728.453 [sink:Float_Up:A.Buoyancy](DEBUG): Construct Buoyancy. 2024-05-21T16:58:48.464Z,1716310728.464 [sink:Float_Up:B.Wait](DEBUG): Construct Wait. 2024-05-21T16:58:48.505Z,1716310728.505 [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-05-21T16:58:48.506Z,1716310728.506 [CommandExec](IMPORTANT): Loaded ./Missions/Engineering/sink.tl id=sink 2024-05-21T16:58:49.876Z,1716310729.876 [DefaultTankUndock:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-21T16:58:49.876Z,1716310729.876 [DefaultTankUndock:CheckIn:C.Wait] Stopped 2024-05-21T16:58:49.876Z,1716310729.876 [DefaultTankUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-21T16:58:49.876Z,1716310729.876 [DefaultTankUndock:CheckIn:D] Running Loop=1 2024-05-21T16:58:50.230Z,1716310730.230 [DefaultTankUndock:CheckIn:D] Stopped 2024-05-21T16:58:50.230Z,1716310730.230 [DefaultTankUndock:CheckIn:E] Running Loop=1 2024-05-21T16:58:50.615Z,1716310730.615 [DefaultTankUndock:CheckIn:E](IMPORTANT): Default mission has been running for 18.147424 min 2024-05-21T16:58:50.615Z,1716310730.615 [DefaultTankUndock:CheckIn:E] Stopped 2024-05-21T16:58:50.615Z,1716310730.615 [DefaultTankUndock:CheckIn](INFO): Completed DefaultTankUndock:CheckIn 2024-05-21T16:58:50.615Z,1716310730.615 [DefaultTankUndock:CheckIn] Stopped 2024-05-21T16:58:50.615Z,1716310730.615 [DefaultTankUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultTankUndock:CheckIn 2024-05-21T16:58:50.616Z,1716310730.616 [DefaultTankUndock:CheckIn](INFO): Running loop #4 2024-05-21T16:58:50.616Z,1716310730.616 [DefaultTankUndock:CheckIn] Running Loop=4 2024-05-21T16:58:50.616Z,1716310730.616 [DefaultTankUndock:CheckIn](DEBUG): Aggregate::initialize DefaultTankUndock:CheckIn 2024-05-21T16:58:50.616Z,1716310730.616 [DefaultTankUndock:CheckIn:Read_GPS] Running Loop=1 2024-05-21T16:58:52.612Z,1716310732.612 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165852.00,A,3648.16112,N,12147.28674,W,0.233,0.00,210524,,,A*71 2024-05-21T16:58:52.623Z,1716310732.623 [NAL9602](INFO): GPS fix at 20240521T165852: (36.802685, -121.788112) 2024-05-21T16:58:52.653Z,1716310732.653 [DefaultTankUndock:CheckIn:Read_GPS] Stopped 2024-05-21T16:58:52.653Z,1716310732.653 [DefaultTankUndock:CheckIn:Read_Iridium] Running Loop=1 2024-05-21T16:59:07.734Z,1716310747.734 [CommandExec](IMPORTANT): got command set sink.MissionTimeout 8 minute 2024-05-21T16:59:07.735Z,1716310747.735 [CommandExec](IMPORTANT): got command set sink.Depth 1.25 meter 2024-05-21T16:59:07.736Z,1716310747.736 [CommandExec](IMPORTANT): got command set sink.DepthDeadband .5 meter 2024-05-21T16:59:07.739Z,1716310747.739 [CommandExec](IMPORTANT): got command set sink.DoBallastAndTrim 0 bool 2024-05-21T16:59:07.743Z,1716310747.743 [CommandExec](IMPORTANT): got command set sink.MinAltitude 0.00001 meter 2024-05-21T16:59:07.743Z,1716310747.743 [CommandExec](IMPORTANT): got command set sink.MaxDepth 5 meter 2024-05-21T16:59:07.744Z,1716310747.744 [CommandExec](IMPORTANT): got command set sink.MinOffshore 0.000001 kilometer 2024-05-21T16:59:07.744Z,1716310747.744 [CommandExec](IMPORTANT): got command run 2024-05-21T16:59:07.775Z,1716310747.775 [CommandExec](IMPORTANT): Running 2024-05-21T16:59:08.053Z,1716310748.053 [DefaultTankUndock] Stopped 2024-05-21T16:59:08.054Z,1716310748.054 [DefaultTankUndock](DEBUG): Aggregate::uninitialize DefaultTankUndock 2024-05-21T16:59:08.054Z,1716310748.054 [DefaultTankUndock:B.GoToSurface] Stopped 2024-05-21T16:59:08.054Z,1716310748.054 [DefaultTankUndock:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-05-21T16:59:08.055Z,1716310748.055 [DefaultTankUndock:CheckIn] Stopped 2024-05-21T16:59:08.055Z,1716310748.055 [DefaultTankUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultTankUndock:CheckIn 2024-05-21T16:59:08.055Z,1716310748.055 [DefaultTankUndock:CheckIn:Read_Iridium] Stopped 2024-05-21T16:59:08.055Z,1716310748.055 [MissionManager](IMPORTANT): Started mission sink 2024-05-21T16:59:08.056Z,1716310748.056 [sink] Running Loop=1 2024-05-21T16:59:08.056Z,1716310748.056 [sink](DEBUG): Aggregate::initialize sink 2024-05-21T16:59:08.057Z,1716310748.057 [sink:Science] Running Loop=1 2024-05-21T16:59:08.057Z,1716310748.057 [sink:Science](DEBUG): Aggregate::initialize sink:Science 2024-05-21T16:59:08.057Z,1716310748.057 [sink:Science:A] Running Loop=1 2024-05-21T16:59:08.058Z,1716310748.058 [sink:Science:B] Running Loop=1 2024-05-21T16:59:08.058Z,1716310748.058 [sink:Science:C] Running Loop=1 2024-05-21T16:59:08.058Z,1716310748.058 [sink:Science:D] Running Loop=1 2024-05-21T16:59:08.058Z,1716310748.058 [sink:Science:E] Running Loop=1 2024-05-21T16:59:08.058Z,1716310748.058 [sink:Science:F] Running Loop=1 2024-05-21T16:59:08.058Z,1716310748.058 [sink:Science:Read_Oil] Running Loop=1 2024-05-21T16:59:08.059Z,1716310748.059 [sink:Science:PeakDetectChl] Running Loop=1 2024-05-21T16:59:08.059Z,1716310748.059 [sink:Science:PeakDetectChl](DEBUG): Aggregate::initialize sink:Science:PeakDetectChl 2024-05-21T16:59:08.059Z,1716310748.059 [sink:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1 2024-05-21T16:59:08.060Z,1716310748.060 [sink:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize. 2024-05-21T16:59:08.076Z,1716310748.076 [sink:Science:HighestChlPeakReport] Running Loop=1 2024-05-21T16:59:08.076Z,1716310748.076 [sink:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize sink:Science:HighestChlPeakReport 2024-05-21T16:59:08.077Z,1716310748.077 [sink:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1 2024-05-21T16:59:08.077Z,1716310748.077 [sink:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2024-05-21T16:59:08.082Z,1716310748.082 [sink:Science:PeakDetectNO3] Running Loop=1 2024-05-21T16:59:08.082Z,1716310748.082 [sink:Science:PeakDetectNO3](DEBUG): Aggregate::initialize sink:Science:PeakDetectNO3 2024-05-21T16:59:08.082Z,1716310748.082 [sink:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1 2024-05-21T16:59:08.082Z,1716310748.082 [sink:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize. 2024-05-21T16:59:08.083Z,1716310748.083 [sink:Science:PeakDetectOil] Running Loop=1 2024-05-21T16:59:08.083Z,1716310748.083 [sink:Science:PeakDetectOil](DEBUG): Aggregate::initialize sink:Science:PeakDetectOil 2024-05-21T16:59:08.083Z,1716310748.083 [sink:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1 2024-05-21T16:59:08.084Z,1716310748.084 [sink:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize. 2024-05-21T16:59:08.085Z,1716310748.085 [sink:Science:HighestOilPeakReport] Running Loop=1 2024-05-21T16:59:08.085Z,1716310748.085 [sink:Science:HighestOilPeakReport](DEBUG): Aggregate::initialize sink:Science:HighestOilPeakReport 2024-05-21T16:59:08.085Z,1716310748.085 [sink:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Running Loop=1 2024-05-21T16:59:08.085Z,1716310748.085 [sink:Science:HighestOilPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2024-05-21T16:59:08.090Z,1716310748.090 [sink:Science:PeakDetectFDOM] Running Loop=1 2024-05-21T16:59:08.090Z,1716310748.090 [sink:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize sink:Science:PeakDetectFDOM 2024-05-21T16:59:08.090Z,1716310748.090 [sink:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1 2024-05-21T16:59:08.090Z,1716310748.090 [sink:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize. 2024-05-21T16:59:08.091Z,1716310748.091 [sink:Science:PeakDetectSalinity] Running Loop=1 2024-05-21T16:59:08.091Z,1716310748.091 [sink:Science:PeakDetectSalinity](DEBUG): Aggregate::initialize sink:Science:PeakDetectSalinity 2024-05-21T16:59:08.091Z,1716310748.091 [sink:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Running Loop=1 2024-05-21T16:59:08.091Z,1716310748.091 [sink:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Initialize. 2024-05-21T16:59:08.092Z,1716310748.092 [sink:Science:HighestSaltPeakReport] Running Loop=1 2024-05-21T16:59:08.092Z,1716310748.092 [sink:Science:HighestSaltPeakReport](DEBUG): Aggregate::initialize sink:Science:HighestSaltPeakReport 2024-05-21T16:59:08.093Z,1716310748.093 [sink:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Running Loop=1 2024-05-21T16:59:08.093Z,1716310748.093 [sink:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2024-05-21T16:59:08.093Z,1716310748.093 [sink:Science:ChlThreshTrigger] Running Loop=1 2024-05-21T16:59:08.097Z,1716310748.097 [sink:Science:ChlThreshTrigger](DEBUG): Aggregate::initialize sink:Science:ChlThreshTrigger 2024-05-21T16:59:08.098Z,1716310748.098 [sink:Science:ChlThreshTrigger:A.ValueDetect] Running Loop=1 2024-05-21T16:59:08.098Z,1716310748.098 [sink:Science:ChlThreshTrigger:A.ValueDetect](INFO): Initialize. 2024-05-21T16:59:08.098Z,1716310748.098 [sink:Science:OceanCurrent] Running Loop=1 2024-05-21T16:59:08.098Z,1716310748.098 [sink:Science:OceanCurrent](DEBUG): Aggregate::initialize sink:Science:OceanCurrent 2024-05-21T16:59:08.098Z,1716310748.098 [sink:Science:OceanCurrent:A.] Running Loop=1 2024-05-21T16:59:08.098Z,1716310748.098 [sink:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator. 2024-05-21T16:59:08.098Z,1716310748.098 [sink:StandardEnvelopes] Running Loop=1 2024-05-21T16:59:08.099Z,1716310748.099 [sink:StandardEnvelopes](DEBUG): Aggregate::initialize sink:StandardEnvelopes 2024-05-21T16:59:08.099Z,1716310748.099 [sink:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2024-05-21T16:59:08.099Z,1716310748.099 [sink:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2024-05-21T16:59:08.099Z,1716310748.099 [sink:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2024-05-21T16:59:08.099Z,1716310748.099 [sink:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2024-05-21T16:59:08.099Z,1716310748.099 [sink:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2024-05-21T16:59:08.099Z,1716310748.099 [sink:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2024-05-21T16:59:08.099Z,1716310748.099 [sink:BackseatDriver] Running Loop=1 2024-05-21T16:59:08.099Z,1716310748.099 [sink:BackseatDriver](DEBUG): Aggregate::initialize sink:BackseatDriver 2024-05-21T16:59:08.100Z,1716310748.100 [sink:BackseatDriver:A.BackseatDriver] Running Loop=1 2024-05-21T16:59:08.100Z,1716310748.100 [sink:PowerOnly] Running Loop=1 2024-05-21T16:59:08.100Z,1716310748.100 [sink:PowerOnly](DEBUG): Aggregate::initialize sink:PowerOnly 2024-05-21T16:59:08.100Z,1716310748.100 [sink:PowerOnly:A] Running Loop=1 2024-05-21T16:59:08.100Z,1716310748.100 [sink:PowerOnly:B] Running Loop=1 2024-05-21T16:59:08.100Z,1716310748.100 [sink:PowerOnly:C] Running Loop=1 2024-05-21T16:59:08.100Z,1716310748.100 [sink:PowerOnly:D] Running Loop=1 2024-05-21T16:59:08.100Z,1716310748.100 [sink:PowerOnly:E.Wait] Running Loop=1 2024-05-21T16:59:08.100Z,1716310748.100 [sink:PowerOnly:E.Wait](DEBUG): Initialize Wait Component. 2024-05-21T16:59:08.100Z,1716310748.100 [sink:C] Running Loop=1 2024-05-21T16:59:08.101Z,1716310748.101 [sink:PowerOnly] Running Loop=1 2024-05-21T16:59:08.106Z,1716310748.106 [sink:PowerOnly:D] Stopped 2024-05-21T16:59:08.106Z,1716310748.106 [sink:PowerOnly:C] Stopped 2024-05-21T16:59:08.106Z,1716310748.106 [sink:PowerOnly:B] Stopped 2024-05-21T16:59:08.106Z,1716310748.106 [sink:PowerOnly:A] Stopped 2024-05-21T16:59:08.106Z,1716310748.106 [sink:BackseatDriver] Running Loop=1 2024-05-21T16:59:08.106Z,1716310748.106 [sink:StandardEnvelopes] Running Loop=1 2024-05-21T16:59:08.106Z,1716310748.106 [sink:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2024-05-21T16:59:08.106Z,1716310748.106 [sink:StandardEnvelopes:C.OffshoreEnvelope](IMPORTANT): Offshore envelope ACTIVE 2024-05-21T16:59:08.126Z,1716310748.126 [sink:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2024-05-21T16:59:08.126Z,1716310748.126 [sink:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2024-05-21T16:59:08.127Z,1716310748.127 [sink:C] Stopped 2024-05-21T16:59:08.127Z,1716310748.127 [sink:E] Running Loop=1 2024-05-21T16:59:08.127Z,1716310748.127 [sink:Science] Running Loop=1 2024-05-21T16:59:08.128Z,1716310748.128 [sink:Science:ChlThreshTrigger] Stopped 2024-05-21T16:59:08.128Z,1716310748.128 [sink:Science:ChlThreshTrigger](DEBUG): Aggregate::uninitialize sink:Science:ChlThreshTrigger 2024-05-21T16:59:08.128Z,1716310748.128 [sink:Science:ChlThreshTrigger:A.ValueDetect] Stopped 2024-05-21T16:59:08.128Z,1716310748.128 [sink:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Uninitialize. 2024-05-21T16:59:08.128Z,1716310748.128 [sink:Science:HighestSaltPeakReport] Stopped 2024-05-21T16:59:08.128Z,1716310748.128 [sink:Science:HighestSaltPeakReport](DEBUG): Aggregate::uninitialize sink:Science:HighestSaltPeakReport 2024-05-21T16:59:08.129Z,1716310748.129 [sink:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Stopped 2024-05-21T16:59:08.129Z,1716310748.129 [sink:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2024-05-21T16:59:08.129Z,1716310748.129 [sink:Science:PeakDetectSalinity] Stopped 2024-05-21T16:59:08.129Z,1716310748.129 [sink:Science:PeakDetectSalinity](DEBUG): Aggregate::uninitialize sink:Science:PeakDetectSalinity 2024-05-21T16:59:08.129Z,1716310748.129 [sink:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Stopped 2024-05-21T16:59:08.129Z,1716310748.129 [sink:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2024-05-21T16:59:08.129Z,1716310748.129 [sink:Science:PeakDetectFDOM] Stopped 2024-05-21T16:59:08.129Z,1716310748.129 [sink:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize sink:Science:PeakDetectFDOM 2024-05-21T16:59:08.129Z,1716310748.129 [sink:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped 2024-05-21T16:59:08.133Z,1716310748.133 [sink:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2024-05-21T16:59:08.134Z,1716310748.134 [sink:Science:HighestOilPeakReport] Stopped 2024-05-21T16:59:08.134Z,1716310748.134 [sink:Science:HighestOilPeakReport](DEBUG): Aggregate::uninitialize sink:Science:HighestOilPeakReport 2024-05-21T16:59:08.134Z,1716310748.134 [sink:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Stopped 2024-05-21T16:59:08.134Z,1716310748.134 [sink:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2024-05-21T16:59:08.134Z,1716310748.134 [sink:Science:PeakDetectOil] Stopped 2024-05-21T16:59:08.134Z,1716310748.134 [sink:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize sink:Science:PeakDetectOil 2024-05-21T16:59:08.134Z,1716310748.134 [sink:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped 2024-05-21T16:59:08.134Z,1716310748.134 [sink:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2024-05-21T16:59:08.134Z,1716310748.134 [sink:Science:PeakDetectNO3] Stopped 2024-05-21T16:59:08.134Z,1716310748.134 [sink:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize sink:Science:PeakDetectNO3 2024-05-21T16:59:08.134Z,1716310748.134 [sink:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped 2024-05-21T16:59:08.134Z,1716310748.134 [sink:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2024-05-21T16:59:08.135Z,1716310748.135 [sink:Science:HighestChlPeakReport] Stopped 2024-05-21T16:59:08.135Z,1716310748.135 [sink:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize sink:Science:HighestChlPeakReport 2024-05-21T16:59:08.135Z,1716310748.135 [sink:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped 2024-05-21T16:59:08.135Z,1716310748.135 [sink:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2024-05-21T16:59:08.135Z,1716310748.135 [sink:Science:PeakDetectChl] Stopped 2024-05-21T16:59:08.135Z,1716310748.135 [sink:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize sink:Science:PeakDetectChl 2024-05-21T16:59:08.135Z,1716310748.135 [sink:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped 2024-05-21T16:59:08.135Z,1716310748.135 [sink:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2024-05-21T16:59:08.135Z,1716310748.135 [sink:Science:OceanCurrent:A.] Running Loop=1 2024-05-21T16:59:08.135Z,1716310748.135 [sink:Science:Read_Oil] Stopped 2024-05-21T16:59:08.135Z,1716310748.135 [sink:Science:F] Running Loop=1 2024-05-21T16:59:08.136Z,1716310748.136 [sink:Science:F](DEBUG): Initialize ReadDataComponent to sense WetLabsUBAT.average_bioluminescence 2024-05-21T16:59:08.136Z,1716310748.136 [sink:Science:E] Running Loop=1 2024-05-21T16:59:08.136Z,1716310748.136 [sink:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water 2024-05-21T16:59:08.137Z,1716310748.137 [sink:Science:D] Stopped 2024-05-21T16:59:08.137Z,1716310748.137 [sink:Science:C] Running Loop=1 2024-05-21T16:59:08.137Z,1716310748.137 [sink:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature 2024-05-21T16:59:08.142Z,1716310748.142 [sink:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity 2024-05-21T16:59:08.142Z,1716310748.142 [sink:Science:B] Running Loop=1 2024-05-21T16:59:08.142Z,1716310748.142 [sink:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature 2024-05-21T16:59:08.143Z,1716310748.143 [sink:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity 2024-05-21T16:59:08.143Z,1716310748.143 [sink:Science:A] Stopped 2024-05-21T16:59:08.303Z,1716310748.303 [WetLabsBB2FL](INFO): Powering up 2024-05-21T16:59:08.476Z,1716310748.476 [sink:E] Stopped 2024-05-21T16:59:08.476Z,1716310748.476 [sink:F] Running Loop=1 2024-05-21T16:59:08.517Z,1716310748.517 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240521T163907/Courier0015.lzma 2024-05-21T16:59:08.652Z,1716310748.652 [WetLabsUBAT](INFO): Powering up 2024-05-21T16:59:08.834Z,1716310748.834 [sink:F] Stopped 2024-05-21T16:59:08.834Z,1716310748.834 [sink:G] Running Loop=1 2024-05-21T16:59:09.251Z,1716310749.251 [sink:G] Stopped 2024-05-21T16:59:09.251Z,1716310749.251 [sink:StartingMission] Running Loop=1 2024-05-21T16:59:09.251Z,1716310749.251 [sink:StartingMission](DEBUG): Aggregate::initialize sink:StartingMission 2024-05-21T16:59:09.520Z,1716310749.520 [DataOverHttps](INFO): Moved sent file to Logs/20240521T163907/Courier0015.lzma.bak 2024-05-21T16:59:09.520Z,1716310749.520 [DataOverHttps](IMPORTANT): SBD MOMSN=21060555, MTMSN=20240521T165909 2024-05-21T16:59:09.646Z,1716310749.646 [sink:NeedComms] Running Loop=1 2024-05-21T16:59:09.647Z,1716310749.647 [sink:NeedComms](DEBUG): Aggregate::initialize sink:NeedComms 2024-05-21T16:59:09.647Z,1716310749.647 [sink:NeedComms:B.GoToSurface] Running Loop=1 2024-05-21T16:59:09.647Z,1716310749.647 [sink:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-05-21T16:59:09.647Z,1716310749.647 [sink:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2024-05-21T16:59:09.647Z,1716310749.647 [sink:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2024-05-21T16:59:09.648Z,1716310749.648 [sink:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2024-05-21T16:59:09.648Z,1716310749.648 [sink:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2024-05-21T16:59:09.648Z,1716310749.648 [sink:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-05-21T16:59:09.649Z,1716310749.649 [sink:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-05-21T16:59:09.650Z,1716310749.650 [sink:NeedComms:A] Running Loop=1 2024-05-21T16:59:09.651Z,1716310749.651 [sink:NeedComms:A](INFO): last time_fix was: 1716310732.000000 second since 1970/01/01T00:00:00Z 2024-05-21T16:59:09.651Z,1716310749.651 [sink:NeedComms:A] Stopped 2024-05-21T16:59:10.042Z,1716310750.042 [sink:NeedComms:C] Running Loop=1 2024-05-21T16:59:10.518Z,1716310750.518 [sink:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix 2024-05-21T16:59:11.376Z,1716310751.376 [CTD_Seabird](ERROR): Failed to parse device response: 2024-05-21T16:59:18.845Z,1716310758.845 [WetLabsUBAT](FAULT): UBAT flow rate is below the specified threshold of 0.05 l/s. 2024-05-21T16:59:20.420Z,1716310760.420 [DataOverHttps](INFO): Received command: sched "load Engineering/OnDock.tl;set OnDock.DockedTime 45 min;set OnDock.DetachedTimeout 8 h;set OnDock.RangeTimeout 8 h;set OnDock.MaxDepth 5 m;set OnDock.MinAltitude 0.00001 m;set OnDock.MinOffshore 0.000001 km" 3bttx 1 2 2024-05-21T16:59:20.496Z,1716310760.496 [CommandExec](IMPORTANT): got command schedule "load Engineering/OnDock.tl;set OnDock.DockedTime 45 min;set OnDock.DetachedTimeout 8 h;set OnDock.RangeTimeout 8 h;set OnDock.MaxDepth 5 m;set OnDock.MinAltitude 0.00001 m;set OnDock.MinOffshore 0.000001 km" 3bttx 1 2.000000 2024-05-21T16:59:20.498Z,1716310760.498 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=3bttx 2024-05-21T16:59:20.499Z,1716310760.499 [CommandExec](IMPORTANT): Scheduled #7 (#1 of 2 with id='3bttx'): "load Engineering/OnDock.tl;set OnDock.DockedTime 45 min;set OnDock.DetachedTimeout 8 h;set OnDock.RangeTimeout 8 h;set OnDock.MaxDepth 5 m;set OnDock.MinAltitude 0.00001 m;set OnDock.MinOffshore 0.000001 km", AFTER MISSION 2024-05-21T16:59:21.416Z,1716310761.416 [DataOverHttps](IMPORTANT): SBD MTMSN=20240521T165921 2024-05-21T16:59:28.280Z,1716310768.280 [NAL9602](INFO): SBD MO Status=2, MOMSN=37729, MT Status=2, MTMSN=0 2024-05-21T16:59:28.280Z,1716310768.280 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-21T16:59:30.826Z,1716310770.826 [DataOverHttps](INFO): Received command: sched "set OnDock.IfconfigInterval 5 min;run" 3bttx 2 2 2024-05-21T16:59:30.920Z,1716310770.920 [CommandExec](IMPORTANT): got command schedule "set OnDock.IfconfigInterval 5 min;run" 3bttx 2 2.000000 2024-05-21T16:59:30.920Z,1716310770.920 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=3bttx 2024-05-21T16:59:30.921Z,1716310770.921 [CommandExec](IMPORTANT): Scheduled #8 (#2 of 2 with id='3bttx'): "set OnDock.IfconfigInterval 5 min;run", AFTER MISSION 2024-05-21T17:00:03.846Z,1716310803.846 [NAL9602](INFO): SBD MO Status=1, MOMSN=37729, MT Status=0, MTMSN=0 2024-05-21T17:00:03.846Z,1716310803.846 [NAL9602](INFO): No messages in MT queue 2024-05-21T17:00:05.052Z,1716310805.052 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170005.00,A,3648.16169,N,12147.28430,W,0.097,0.00,210524,,,D*78 2024-05-21T17:00:05.055Z,1716310805.055 [NAL9602](INFO): GPS fix at 20240521T170005: (36.802695, -121.788072) 2024-05-21T17:00:05.214Z,1716310805.214 [sink:NeedComms:C] Stopped 2024-05-21T17:00:05.215Z,1716310805.215 [sink:NeedComms:D] Running Loop=1 2024-05-21T17:00:05.504Z,1716310805.504 [sink:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-05-21T17:00:08.649Z,1716310808.649 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240521T163907/Courier0020.lzma 2024-05-21T17:00:09.655Z,1716310809.655 [DataOverHttps](INFO): Moved sent file to Logs/20240521T163907/Courier0020.lzma.bak 2024-05-21T17:00:09.655Z,1716310809.655 [DataOverHttps](INFO): SBD MOMSN=21060566 2024-05-21T17:00:37.786Z,1716310837.786 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-21T17:00:38.967Z,1716310838.967 [DataOverHttps](INFO): Sending 578 bytes from file Logs/20240521T163907/Express0016.lzma 2024-05-21T17:00:39.967Z,1716310839.967 [DataOverHttps](INFO): Moved sent file to Logs/20240521T163907/Express0016.lzma.bak 2024-05-21T17:00:39.968Z,1716310839.968 [DataOverHttps](INFO): SBD MOMSN=21060571 2024-05-21T17:00:40.126Z,1716310840.126 [DockingStepper](FAULT): Could not parse cable state. Received: 2024-05-21T17:00:40.126Z,1716310840.126 [DockingStepper] Communications Fault, FailCount= 1 2024-05-21T17:00:40.126Z,1716310840.126 [DockingStepper](ERROR): Communications Fault 2024-05-21T17:00:40.137Z,1716310840.137 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-21T17:00:40.542Z,1716310840.542 [DockingStepper](INFO): Stop 2024-05-21T17:00:40.542Z,1716310840.542 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-21T17:00:40.543Z,1716310840.543 [DockingStepper](INFO): Powering down 2024-05-21T17:00:40.923Z,1716310840.923 [DockingStepper](INFO): Stopping 2024-05-21T17:00:41.188Z,1716310841.188 [DockingStepper](INFO): Stopped 2024-05-21T17:00:41.192Z,1716310841.192 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-21T17:00:41.192Z,1716310841.192 [DockingStepper] No Fault, FailCount= 1 2024-05-21T17:00:41.613Z,1716310841.613 [DockingStepper](INFO): Start 2024-05-21T17:00:41.633Z,1716310841.633 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-21T17:00:41.634Z,1716310841.634 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-21T17:00:41.978Z,1716310841.978 [DockingStepper](INFO): Starting 2024-05-21T17:00:42.388Z,1716310842.388 [DockingStepper](INFO): Starting 2024-05-21T17:00:42.828Z,1716310842.828 [DockingStepper](INFO): Starting 2024-05-21T17:00:42.875Z,1716310842.875 [DockingStepper](INFO): Init string: /1z16031aE13561N3m50 2024-05-21T17:00:43.201Z,1716310843.201 [DockingStepper](INFO): Starting 2024-05-21T17:00:43.650Z,1716310843.650 [DockingStepper](INFO): Starting 2024-05-21T17:00:44.028Z,1716310844.028 [DockingStepper](INFO): Starting 2024-05-21T17:00:44.393Z,1716310844.393 [DockingStepper](INFO): Starting 2024-05-21T17:00:44.451Z,1716310844.451 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-21T17:00:44.803Z,1716310844.803 [DockingStepper](INFO): Starting 2024-05-21T17:01:02.593Z,1716310862.593 [DataOverHttps](INFO): Sending 969 bytes from file Logs/20240521T163907/Express0022.lzma 2024-05-21T17:01:03.592Z,1716310863.592 [DataOverHttps](INFO): Moved sent file to Logs/20240521T163907/Express0022.lzma.bak 2024-05-21T17:01:03.592Z,1716310863.592 [DataOverHttps](INFO): SBD MOMSN=21060599 2024-05-21T17:01:07.012Z,1716310867.012 [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,1202.71 2024-05-21T17:01:26.937Z,1716310886.937 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240521T163907/Express0025.lzma 2024-05-21T17:01:27.946Z,1716310887.946 [DataOverHttps](INFO): Moved sent file to Logs/20240521T163907/Express0025.lzma.bak 2024-05-21T17:01:27.946Z,1716310887.946 [DataOverHttps](INFO): SBD MOMSN=21060621 2024-05-21T17:01:33.044Z,1716310893.044 [sink:NeedComms:D] Stopped 2024-05-21T17:01:33.045Z,1716310893.045 [sink:NeedComms:E] Running Loop=1 2024-05-21T17:01:33.508Z,1716310893.508 [sink:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix 2024-05-21T17:01:35.045Z,1716310895.045 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170135.00,A,3648.16171,N,12147.28145,W,0.078,0.00,210524,,,D*75 2024-05-21T17:01:35.048Z,1716310895.048 [NAL9602](INFO): GPS fix at 20240521T170135: (36.802695, -121.788024) 2024-05-21T17:01:35.123Z,1716310895.123 [sink:NeedComms:E] Stopped 2024-05-21T17:01:35.124Z,1716310895.124 [sink:NeedComms](INFO): Completed sink:NeedComms 2024-05-21T17:01:35.124Z,1716310895.124 [sink:NeedComms] Stopped 2024-05-21T17:01:35.125Z,1716310895.125 [sink:NeedComms](DEBUG): Aggregate::uninitialize sink:NeedComms 2024-05-21T17:01:35.125Z,1716310895.125 [sink:NeedComms:B.GoToSurface] Stopped 2024-05-21T17:01:35.125Z,1716310895.125 [sink:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-05-21T17:01:35.535Z,1716310895.535 [sink:StartingMission](INFO): Completed sink:StartingMission 2024-05-21T17:01:35.535Z,1716310895.535 [sink:StartingMission] Stopped 2024-05-21T17:01:35.535Z,1716310895.535 [sink:StartingMission](DEBUG): Aggregate::uninitialize sink:StartingMission 2024-05-21T17:01:35.535Z,1716310895.535 [sink:RunSink] Running Loop=1 2024-05-21T17:01:35.536Z,1716310895.536 [sink:RunSink](DEBUG): Aggregate::initialize sink:RunSink 2024-05-21T17:01:35.536Z,1716310895.536 [sink:RunSink:RunBallastAndTrim] Running Loop=1 2024-05-21T17:01:35.536Z,1716310895.536 [sink:RunSink:RunBallastAndTrim](DEBUG): Aggregate::initialize sink:RunSink:RunBallastAndTrim 2024-05-21T17:01:35.536Z,1716310895.536 [sink:RunSink:RunBallastAndTrim:A] Running Loop=1 2024-05-21T17:01:35.895Z,1716310895.895 [sink:RunSink:RunBallastAndTrim] Stopped 2024-05-21T17:01:35.895Z,1716310895.895 [sink:RunSink:RunBallastAndTrim](DEBUG): Aggregate::uninitialize sink:RunSink:RunBallastAndTrim 2024-05-21T17:01:35.895Z,1716310895.895 [sink:RunSink:RunBallastAndTrim:A] Stopped 2024-05-21T17:01:35.895Z,1716310895.895 [sink:RunSink:JustSink] Running Loop=1 2024-05-21T17:01:35.895Z,1716310895.895 [sink:RunSink:JustSink](DEBUG): Aggregate::initialize sink:RunSink:JustSink 2024-05-21T17:01:35.895Z,1716310895.895 [sink:RunSink:JustSink:A] Running Loop=1 2024-05-21T17:01:36.330Z,1716310896.330 [sink:RunSink:JustSink:A] Stopped 2024-05-21T17:01:36.330Z,1716310896.330 [sink:RunSink:JustSink:B] Running Loop=1 2024-05-21T17:01:36.721Z,1716310896.721 [sink:RunSink:JustSink:B] Stopped 2024-05-21T17:01:36.721Z,1716310896.721 [sink:RunSink:JustSink:C] Running Loop=1 2024-05-21T17:01:36.721Z,1716310896.721 [sink:RunSink:JustSink:C](DEBUG): Aggregate::initialize sink:RunSink:JustSink:C 2024-05-21T17:01:37.093Z,1716310897.093 [sink:RunSink:BallastAndTrim] Running Loop=1 2024-05-21T17:01:37.093Z,1716310897.093 [sink:RunSink:BallastAndTrim](DEBUG): Aggregate::initialize sink:RunSink:BallastAndTrim 2024-05-21T17:01:37.093Z,1716310897.093 [sink:RunSink:BallastAndTrim:A] Running Loop=1 2024-05-21T17:01:37.093Z,1716310897.093 [sink:RunSink:BallastAndTrim:B] Running Loop=1 2024-05-21T17:01:37.094Z,1716310897.094 [sink:RunSink:BallastAndTrim:C] Running Loop=1 2024-05-21T17:01:37.094Z,1716310897.094 [sink:RunSink:BallastAndTrim:D] Running Loop=1 2024-05-21T17:01:37.094Z,1716310897.094 [sink:RunSink:BallastAndTrim:E] Running Loop=1 2024-05-21T17:01:37.094Z,1716310897.094 [sink:RunSink:BallastAndTrim:F] Running Loop=1 2024-05-21T17:01:37.096Z,1716310897.096 [sink:RunSink:BallastAndTrim:F](INFO): Going to target depth. Speed set to 0.000000 m/s 2024-05-21T17:01:37.096Z,1716310897.096 [sink:RunSink:BallastAndTrim:F] Stopped 2024-05-21T17:01:37.097Z,1716310897.097 [sink:RunSink:BallastAndTrim:GoToTargetDepth] Running Loop=1 2024-05-21T17:01:37.097Z,1716310897.097 [sink:RunSink:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::initialize sink:RunSink:BallastAndTrim:GoToTargetDepth 2024-05-21T17:01:37.097Z,1716310897.097 [sink:RunSink:BallastAndTrim:GoToTargetDepth:A] Running Loop=1 2024-05-21T17:01:37.097Z,1716310897.097 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold] Running Loop=1 2024-05-21T17:01:37.097Z,1716310897.097 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::initialize sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold 2024-05-21T17:01:37.098Z,1716310897.098 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Running Loop=1 2024-05-21T17:01:37.098Z,1716310897.098 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Initialize. 2024-05-21T17:01:37.098Z,1716310897.098 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Running Loop=1 2024-05-21T17:01:37.098Z,1716310897.098 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Initialize Buoyancy Component. 2024-05-21T17:01:37.098Z,1716310897.098 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Running Loop=1 2024-05-21T17:01:37.098Z,1716310897.098 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Initialize. 2024-05-21T17:01:37.098Z,1716310897.098 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive] Running Loop=1 2024-05-21T17:01:37.098Z,1716310897.098 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::initialize sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive 2024-05-21T17:01:37.099Z,1716310897.099 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Running Loop=1 2024-05-21T17:01:37.099Z,1716310897.099 [sink:RunSink:BallastAndTrim:E] Running Loop=1 2024-05-21T17:01:37.099Z,1716310897.099 [sink:RunSink:BallastAndTrim:D] Running Loop=1 2024-05-21T17:01:37.099Z,1716310897.099 [sink:RunSink:BallastAndTrim:C] Running Loop=1 2024-05-21T17:01:37.099Z,1716310897.099 [sink:RunSink:BallastAndTrim:B] Running Loop=1 2024-05-21T17:01:37.100Z,1716310897.100 [sink:RunSink:BallastAndTrim:A] Running Loop=1 2024-05-21T17:01:37.568Z,1716310897.568 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive:A](INFO): Waiting for 0.000000 min while pumping down to neutral. 2024-05-21T17:01:37.568Z,1716310897.568 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Stopped 2024-05-21T17:01:37.569Z,1716310897.569 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait] Running Loop=1 2024-05-21T17:01:37.569Z,1716310897.569 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Initialize Wait Component. 2024-05-21T17:01:37.569Z,1716310897.569 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold] Stopped 2024-05-21T17:01:37.569Z,1716310897.569 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::uninitialize sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold 2024-05-21T17:01:37.569Z,1716310897.569 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Stopped 2024-05-21T17:01:37.569Z,1716310897.569 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Stopped 2024-05-21T17:01:37.569Z,1716310897.569 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2024-05-21T17:01:37.569Z,1716310897.569 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Stopped 2024-05-21T17:01:37.569Z,1716310897.569 [sink:RunSink:BallastAndTrim:GoToTargetDepth:A] Running Loop=1 2024-05-21T17:01:37.923Z,1716310897.923 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](INFO): Done Waiting. 2024-05-21T17:01:37.923Z,1716310897.923 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait] Stopped 2024-05-21T17:01:37.923Z,1716310897.923 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Uninitialize Wait Component. 2024-05-21T17:01:37.923Z,1716310897.923 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive](INFO): Completed sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive 2024-05-21T17:01:37.923Z,1716310897.923 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive] Stopped 2024-05-21T17:01:37.924Z,1716310897.924 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::uninitialize sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive 2024-05-21T17:01:37.924Z,1716310897.924 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth] Running Loop=1 2024-05-21T17:01:37.924Z,1716310897.924 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::initialize sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth 2024-05-21T17:01:37.924Z,1716310897.924 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1 2024-05-21T17:01:37.924Z,1716310897.924 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Initialize. 2024-05-21T17:01:37.924Z,1716310897.924 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Running Loop=1 2024-05-21T17:01:38.431Z,1716310898.431 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1 2024-05-21T17:01:38.432Z,1716310898.432 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:A](INFO): Moving to 1.250000 m 2024-05-21T17:01:38.433Z,1716310898.433 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Stopped 2024-05-21T17:01:38.433Z,1716310898.433 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Running Loop=1 2024-05-21T17:01:38.433Z,1716310898.433 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Initialize. 2024-05-21T17:02:09.387Z,1716310929.387 [DockingStepper](ERROR): only read 0 of 4 ADC values. Response is: ÿ/0@ 2024-05-21T17:02:09.387Z,1716310929.387 [DockingStepper](FAULT): Docking arm not reporting position 2024-05-21T17:02:09.387Z,1716310929.387 [DockingStepper] Communications Fault, FailCount= 1 2024-05-21T17:02:09.388Z,1716310929.388 [DockingStepper](ERROR): Communications Fault 2024-05-21T17:02:09.534Z,1716310929.534 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-21T17:02:09.920Z,1716310929.920 [DockingStepper](INFO): Stop 2024-05-21T17:02:09.920Z,1716310929.920 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-21T17:02:09.920Z,1716310929.920 [DockingStepper](INFO): Powering down 2024-05-21T17:02:10.383Z,1716310930.383 [DockingStepper](INFO): Stopping 2024-05-21T17:02:10.615Z,1716310930.615 [DockingStepper](INFO): Stopped 2024-05-21T17:02:10.619Z,1716310930.619 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-21T17:02:10.619Z,1716310930.619 [DockingStepper] No Fault, FailCount= 1 2024-05-21T17:02:11.111Z,1716310931.111 [DockingStepper](INFO): Start 2024-05-21T17:02:11.111Z,1716310931.111 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-21T17:02:11.111Z,1716310931.111 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-21T17:02:11.451Z,1716310931.451 [DockingStepper](INFO): Starting 2024-05-21T17:02:11.899Z,1716310931.899 [DockingStepper](INFO): Starting 2024-05-21T17:02:12.311Z,1716310932.311 [DockingStepper](INFO): Starting 2024-05-21T17:02:12.360Z,1716310932.360 [DockingStepper](INFO): Init string: /1z16031aE13561N3m50 2024-05-21T17:02:12.611Z,1716310932.611 [DockingStepper](INFO): Starting 2024-05-21T17:02:13.123Z,1716310933.123 [DockingStepper](INFO): Starting 2024-05-21T17:02:13.454Z,1716310933.454 [DockingStepper](INFO): Starting 2024-05-21T17:02:13.943Z,1716310933.943 [DockingStepper](INFO): Starting 2024-05-21T17:02:13.966Z,1716310933.966 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-21T17:02:14.339Z,1716310934.339 [DockingStepper](INFO): Starting 2024-05-21T17:02:37.314Z,1716310957.314 [DockingStepper](ERROR): only read 0 of 4 ADC values. Response is: ÿ/0@ 2024-05-21T17:02:37.315Z,1716310957.315 [DockingStepper](FAULT): Docking arm not reporting position 2024-05-21T17:02:37.315Z,1716310957.315 [DockingStepper] Communications Fault, FailCount= 1 2024-05-21T17:02:37.315Z,1716310957.315 [DockingStepper](ERROR): Communications Fault 2024-05-21T17:02:37.322Z,1716310957.322 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-21T17:02:37.698Z,1716310957.698 [DockingStepper](INFO): Stop 2024-05-21T17:02:37.699Z,1716310957.699 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-21T17:02:37.699Z,1716310957.699 [DockingStepper](INFO): Powering down 2024-05-21T17:02:38.067Z,1716310958.067 [DockingStepper](INFO): Stopping 2024-05-21T17:02:38.534Z,1716310958.534 [DockingStepper](INFO): Stopped 2024-05-21T17:02:38.539Z,1716310958.539 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-21T17:02:38.539Z,1716310958.539 [DockingStepper] No Fault, FailCount= 1 2024-05-21T17:02:38.883Z,1716310958.883 [DockingStepper](INFO): Start 2024-05-21T17:02:38.883Z,1716310958.883 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-21T17:02:38.883Z,1716310958.883 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-21T17:02:39.322Z,1716310959.322 [DockingStepper](INFO): Starting 2024-05-21T17:02:39.684Z,1716310959.684 [DockingStepper](INFO): Starting 2024-05-21T17:02:40.112Z,1716310960.112 [DockingStepper](INFO): Starting 2024-05-21T17:02:40.164Z,1716310960.164 [DockingStepper](INFO): Init string: /1z16031aE13561N3m50 2024-05-21T17:02:40.578Z,1716310960.578 [DockingStepper](INFO): Starting 2024-05-21T17:02:40.875Z,1716310960.875 [DockingStepper](INFO): Starting 2024-05-21T17:02:41.394Z,1716310961.394 [DockingStepper](INFO): Starting 2024-05-21T17:02:41.722Z,1716310961.722 [DockingStepper](INFO): Starting 2024-05-21T17:02:41.766Z,1716310961.766 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-21T17:02:42.111Z,1716310962.111 [DockingStepper](INFO): Starting 2024-05-21T17:03:27.087Z,1716311007.087 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD, +1.26, +2 -1.22, 0.00, 2.00 2024-05-21T17:03:27.906Z,1716311007.906 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-21T17:03:31.924Z,1716311011.924 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.252529 2024-05-21T17:03:42.933Z,1716311022.933 [DockingStepper](ERROR): only read 0 of 4 ADC values. Response is: ÿ/0@ 2024-05-21T17:03:42.934Z,1716311022.934 [DockingStepper](FAULT): Docking arm not reporting position 2024-05-21T17:03:42.934Z,1716311022.934 [DockingStepper] Communications Fault, FailCount= 1 2024-05-21T17:03:42.935Z,1716311022.935 [DockingStepper](ERROR): Communications Fault 2024-05-21T17:03:42.939Z,1716311022.939 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-21T17:03:43.291Z,1716311023.291 [DockingStepper](INFO): Stop 2024-05-21T17:03:43.291Z,1716311023.291 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-21T17:03:43.291Z,1716311023.291 [DockingStepper](INFO): Powering down 2024-05-21T17:03:43.667Z,1716311023.667 [DockingStepper](INFO): Stopping 2024-05-21T17:03:44.080Z,1716311024.080 [DockingStepper](INFO): Stopped 2024-05-21T17:03:44.084Z,1716311024.084 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-21T17:03:44.084Z,1716311024.084 [DockingStepper] No Fault, FailCount= 1 2024-05-21T17:03:44.452Z,1716311024.452 [DockingStepper](INFO): Start 2024-05-21T17:03:44.452Z,1716311024.452 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-21T17:03:44.453Z,1716311024.453 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-21T17:03:44.929Z,1716311024.929 [DockingStepper](INFO): Starting 2024-05-21T17:03:45.286Z,1716311025.286 [DockingStepper](INFO): Starting 2024-05-21T17:03:45.661Z,1716311025.661 [DockingStepper](INFO): Starting 2024-05-21T17:03:45.714Z,1716311025.714 [DockingStepper](INFO): Init string: /1z16031aE13561N3m50 2024-05-21T17:03:46.094Z,1716311026.094 [DockingStepper](INFO): Starting 2024-05-21T17:03:46.495Z,1716311026.495 [DockingStepper](INFO): Starting 2024-05-21T17:03:46.975Z,1716311026.975 [DockingStepper](INFO): Starting 2024-05-21T17:03:47.301Z,1716311027.301 [DockingStepper](INFO): Starting 2024-05-21T17:03:47.335Z,1716311027.335 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-21T17:03:47.683Z,1716311027.683 [DockingStepper](INFO): Starting 2024-05-21T17:04:03.050Z,1716311043.050 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-21T17:04:08.774Z,1716311048.774 [DockingStepper](INFO): Commanding positive:3084 2024-05-21T17:04:24.053Z,1716311064.053 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Stopped 2024-05-21T17:04:24.054Z,1716311064.054 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth](INFO): Completed sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth 2024-05-21T17:04:24.054Z,1716311064.054 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth] Stopped 2024-05-21T17:04:24.054Z,1716311064.054 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::uninitialize sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth 2024-05-21T17:04:24.054Z,1716311064.054 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Stopped 2024-05-21T17:04:24.054Z,1716311064.054 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Uninitialize. 2024-05-21T17:04:24.055Z,1716311064.055 [sink:RunSink:BallastAndTrim:GoToTargetDepth](INFO): Completed sink:RunSink:BallastAndTrim:GoToTargetDepth 2024-05-21T17:04:24.055Z,1716311064.055 [sink:RunSink:BallastAndTrim:GoToTargetDepth] Stopped 2024-05-21T17:04:24.055Z,1716311064.055 [sink:RunSink:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::uninitialize sink:RunSink:BallastAndTrim:GoToTargetDepth 2024-05-21T17:04:24.055Z,1716311064.055 [sink:RunSink:BallastAndTrim:GoToTargetDepth:A] Stopped 2024-05-21T17:04:24.055Z,1716311064.055 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth] Running Loop=1 2024-05-21T17:04:24.055Z,1716311064.055 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::initialize sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth 2024-05-21T17:04:24.055Z,1716311064.055 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1 2024-05-21T17:04:24.055Z,1716311064.055 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Initialize. 2024-05-21T17:04:24.055Z,1716311064.055 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1 2024-05-21T17:04:24.056Z,1716311064.056 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Initialize. 2024-05-21T17:04:24.056Z,1716311064.056 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Running Loop=1 2024-05-21T17:04:24.519Z,1716311064.519 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:C](INFO): Waiting for the vehicle to settle. Depth = 1.259529 m 2024-05-21T17:04:24.520Z,1716311064.520 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Stopped 2024-05-21T17:04:24.520Z,1716311064.520 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Running Loop=1 2024-05-21T17:04:24.520Z,1716311064.520 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Initialize Wait Component. 2024-05-21T17:04:24.520Z,1716311064.520 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1 2024-05-21T17:04:24.520Z,1716311064.520 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1 2024-05-21T17:04:38.202Z,1716311078.202 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-21T17:05:02.112Z,1716311102.112 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. Device response is::WD, +0.42 +0.15, -0.22, 20.00, 87.26 2024-05-21T17:05:11.027Z,1716311111.027 [Radio_Surface](INFO): Powering down 2024-05-21T17:05:13.354Z,1716311113.354 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-21T17:05:14.360Z,1716311114.360 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2024-05-21T17:05:45.370Z,1716311145.370 [DockingStepper](ERROR): only read 0 of 4 ADC values. Response is: ÿ/0@ 2024-05-21T17:05:45.370Z,1716311145.370 [DockingStepper](FAULT): Docking arm not reporting position 2024-05-21T17:05:45.370Z,1716311145.370 [DockingStepper] Communications Fault, FailCount= 1 2024-05-21T17:05:45.370Z,1716311145.370 [DockingStepper](ERROR): Communications Fault 2024-05-21T17:05:45.381Z,1716311145.381 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-21T17:05:45.807Z,1716311145.807 [DockingStepper](INFO): Stop 2024-05-21T17:05:45.807Z,1716311145.807 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-21T17:05:45.807Z,1716311145.807 [DockingStepper](INFO): Powering down 2024-05-21T17:05:46.170Z,1716311146.170 [DockingStepper](INFO): Stopping 2024-05-21T17:05:46.595Z,1716311146.595 [DockingStepper](INFO): Stopped 2024-05-21T17:05:46.608Z,1716311146.608 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-21T17:05:46.608Z,1716311146.608 [DockingStepper] No Fault, FailCount= 1 2024-05-21T17:05:47.007Z,1716311147.007 [DockingStepper](INFO): Start 2024-05-21T17:05:47.007Z,1716311147.007 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-21T17:05:47.008Z,1716311147.008 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-21T17:05:47.442Z,1716311147.442 [DockingStepper](INFO): Starting 2024-05-21T17:05:47.946Z,1716311147.946 [DockingStepper](INFO): Starting 2024-05-21T17:05:48.206Z,1716311148.206 [DockingStepper](INFO): Starting 2024-05-21T17:05:48.252Z,1716311148.252 [DockingStepper](INFO): Init string: /1z16127aE13561N3m50 2024-05-21T17:05:48.590Z,1716311148.590 [DockingStepper](INFO): Starting 2024-05-21T17:05:49.029Z,1716311149.029 [DockingStepper](INFO): Starting 2024-05-21T17:05:49.406Z,1716311149.406 [DockingStepper](INFO): Starting 2024-05-21T17:05:49.879Z,1716311149.879 [DockingStepper](INFO): Starting 2024-05-21T17:05:49.907Z,1716311149.907 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-21T17:05:50.227Z,1716311150.227 [DockingStepper](INFO): Starting 2024-05-21T17:06:37.836Z,1716311197.836 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-05-21T17:06:44.714Z,1716311204.714 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for water velocity. Device response is::WS,-32768,-3768,V 2024-05-21T17:06:59.383Z,1716311219.383 [CTD_Seabird](ERROR): Failed to parse device response: 1.85,18.84476,1609.74 2024-05-21T17:07:08.172Z,1716311228.172 [sink](INFO): Timed out from 2024-05-21T16:59:08.1Z 2024-05-21T17:07:08.172Z,1716311228.172 [MissionManager](INFO): sink is completed. 2024-05-21T17:07:08.172Z,1716311228.172 [MissionManager](INFO): Uninitializing Mission sink 2024-05-21T17:07:08.172Z,1716311228.172 [sink] Stopped 2024-05-21T17:07:08.172Z,1716311228.172 [sink](DEBUG): Aggregate::uninitialize sink 2024-05-21T17:07:08.172Z,1716311228.172 [sink:Science] Stopped 2024-05-21T17:07:08.173Z,1716311228.173 [sink:Science](DEBUG): Aggregate::uninitialize sink:Science 2024-05-21T17:07:08.173Z,1716311228.173 [sink:Science:B] Stopped 2024-05-21T17:07:08.173Z,1716311228.173 [sink:Science:C] Stopped 2024-05-21T17:07:08.173Z,1716311228.173 [sink:Science:E] Stopped 2024-05-21T17:07:08.173Z,1716311228.173 [sink:Science:F] Stopped 2024-05-21T17:07:08.173Z,1716311228.173 [sink:Science:OceanCurrent] Stopped 2024-05-21T17:07:08.173Z,1716311228.173 [sink:Science:OceanCurrent](DEBUG): Aggregate::uninitialize sink:Science:OceanCurrent 2024-05-21T17:07:08.173Z,1716311228.173 [sink:Science:OceanCurrent:A.] Stopped 2024-05-21T17:07:08.173Z,1716311228.173 [sink:StandardEnvelopes] Stopped 2024-05-21T17:07:08.173Z,1716311228.173 [sink:StandardEnvelopes](DEBUG): Aggregate::uninitialize sink:StandardEnvelopes 2024-05-21T17:07:08.174Z,1716311228.174 [sink:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2024-05-21T17:07:08.174Z,1716311228.174 [sink:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2024-05-21T17:07:08.174Z,1716311228.174 [sink:StandardEnvelopes:B.DepthEnvelope] Stopped 2024-05-21T17:07:08.174Z,1716311228.174 [sink:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2024-05-21T17:07:08.174Z,1716311228.174 [sink:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2024-05-21T17:07:08.174Z,1716311228.174 [sink:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2024-05-21T17:07:08.174Z,1716311228.174 [sink:BackseatDriver] Stopped 2024-05-21T17:07:08.174Z,1716311228.174 [sink:BackseatDriver](DEBUG): Aggregate::uninitialize sink:BackseatDriver 2024-05-21T17:07:08.174Z,1716311228.174 [sink:BackseatDriver:A.BackseatDriver] Stopped 2024-05-21T17:07:08.174Z,1716311228.174 [sink:PowerOnly] Stopped 2024-05-21T17:07:08.174Z,1716311228.174 [sink:PowerOnly](DEBUG): Aggregate::uninitialize sink:PowerOnly 2024-05-21T17:07:08.174Z,1716311228.174 [sink:PowerOnly:E.Wait] Stopped 2024-05-21T17:07:08.174Z,1716311228.174 [sink:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2024-05-21T17:07:08.174Z,1716311228.174 [sink:RunSink] Stopped 2024-05-21T17:07:08.175Z,1716311228.175 [sink:RunSink](DEBUG): Aggregate::uninitialize sink:RunSink 2024-05-21T17:07:08.175Z,1716311228.175 [sink:RunSink:BallastAndTrim] Stopped 2024-05-21T17:07:08.175Z,1716311228.175 [sink:RunSink:BallastAndTrim](DEBUG): Aggregate::uninitialize sink:RunSink:BallastAndTrim 2024-05-21T17:07:08.175Z,1716311228.175 [sink:RunSink:BallastAndTrim:A] Stopped 2024-05-21T17:07:08.175Z,1716311228.175 [sink:RunSink:BallastAndTrim:B] Stopped 2024-05-21T17:07:08.175Z,1716311228.175 [sink:RunSink:BallastAndTrim:C] Stopped 2024-05-21T17:07:08.175Z,1716311228.175 [sink:RunSink:BallastAndTrim:D] Stopped 2024-05-21T17:07:08.175Z,1716311228.175 [sink:RunSink:BallastAndTrim:E] Stopped 2024-05-21T17:07:08.175Z,1716311228.175 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth] Stopped 2024-05-21T17:07:08.175Z,1716311228.175 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::uninitialize sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth 2024-05-21T17:07:08.175Z,1716311228.175 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Stopped 2024-05-21T17:07:08.175Z,1716311228.175 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Uninitialize. 2024-05-21T17:07:08.175Z,1716311228.175 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Stopped 2024-05-21T17:07:08.175Z,1716311228.175 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Stopped 2024-05-21T17:07:08.175Z,1716311228.175 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Uninitialize Wait Component. 2024-05-21T17:07:08.176Z,1716311228.176 [sink:RunSink:JustSink] Stopped 2024-05-21T17:07:08.176Z,1716311228.176 [sink:RunSink:JustSink](DEBUG): Aggregate::uninitialize sink:RunSink:JustSink 2024-05-21T17:07:08.176Z,1716311228.176 [sink:RunSink:JustSink:C] Stopped 2024-05-21T17:07:08.176Z,1716311228.176 [sink:RunSink:JustSink:C](DEBUG): Aggregate::uninitialize sink:RunSink:JustSink:C 2024-05-21T17:07:08.252Z,1716311228.252 [CommandExec](IMPORTANT): got command load ./Missions/Engineering/OnDock.tl 2024-05-21T17:07:08.320Z,1716311228.320 [MissionManager](INFO): Loading Mission from file: ./Missions/Engineering/OnDock.tl 2024-05-21T17:07:08.320Z,1716311228.320 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Engineering/OnDock.tl 2024-05-21T17:07:08.323Z,1716311228.323 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Engineering/OnDock.tl 2024-05-21T17:07:08.354Z,1716311228.354 [WetLabsUBAT](INFO): Powering down 2024-05-21T17:07:08.534Z,1716311228.533 [WetLabsBB2FL](INFO): Powering down 2024-05-21T17:07:08.540Z,1716311228.540 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-21T17:07:09.544Z,1716311229.544 [MissionManager](DEBUG): TethyslAPI: ./Missions/Engineering/OnDock.tl translated into: 8 2 1 5 2 30 5 Restarting DHCP... ! /mnt/mmc/LRAUV/Tools/dhcp.sh Restarting logs restart logs ! ifconfig Mission ending, breaking off. Range: . 2024-05-21T17:07:09.556Z,1716311229.556 [MissionManager](INFO): DefineArg OnDock.DockedTime = 8.000000 h 2024-05-21T17:07:09.559Z,1716311229.559 [MissionManager](INFO): DefineArg OnDock.DetachedTimeout = 28800.000000 s 2024-05-21T17:07:09.562Z,1716311229.562 [MissionManager](INFO): DefineArg OnDock.RangeTimeout = 600.000000 s 2024-05-21T17:07:09.564Z,1716311229.564 [MissionManager](INFO): DefineArg OnDock.TrackingUpdatePeriod = 2.000000 min 2024-05-21T17:07:09.566Z,1716311229.566 [MissionManager](INFO): DefineArg OnDock.NumberOfPings = 1.000000 count 2024-05-21T17:07:09.569Z,1716311229.569 [MissionManager](INFO): DefineArg OnDock.TransponderCode = 201 count 2024-05-21T17:07:09.571Z,1716311229.571 [MissionManager](INFO): DefineArg OnDock.MaxDepth = 215.000000 m 2024-05-21T17:07:09.574Z,1716311229.574 [MissionManager](INFO): DefineArg OnDock.MinAltitude = 5.000000 m 2024-05-21T17:07:09.576Z,1716311229.576 [MissionManager](INFO): DefineArg OnDock.MinOffshore = 2.000000 km 2024-05-21T17:07:09.578Z,1716311229.578 [MissionManager](INFO): DefineArg OnDock.StayOnDock = 0 bool 2024-05-21T17:07:09.580Z,1716311229.580 [MissionManager](INFO): DefineArg OnDock.IfconfigInterval = 30.000000 min 2024-05-21T17:07:09.582Z,1716311229.582 [MissionManager](INFO): DefineArg OnDock.MissionTimeout = 8.000000 h 2024-05-21T17:07:09.583Z,1716311229.583 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl 2024-05-21T17:07:09.583Z,1716311229.583 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl 2024-05-21T17:07:09.584Z,1716311229.584 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl 2024-05-21T17:07:09.716Z,1716311229.716 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into: 5 0 200 2000 2024-05-21T17:07:09.720Z,1716311229.720 [MissionManager](INFO): DefineArg OnDock:StandardEnvelopes.MinAltitude = 5.000000 m 2024-05-21T17:07:09.723Z,1716311229.723 [MissionManager](INFO): DefineArg OnDock:StandardEnvelopes.MaxDepthIgnore = 0.000000 m 2024-05-21T17:07:09.776Z,1716311229.776 [MissionManager](INFO): DefineArg OnDock:StandardEnvelopes.MaxDepth = 200.000000 m 2024-05-21T17:07:09.790Z,1716311229.790 [MissionManager](INFO): DefineArg OnDock:StandardEnvelopes.MinOffshore = 2000.000000 m 2024-05-21T17:07:09.791Z,1716311229.791 [OnDock:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2024-05-21T17:07:09.805Z,1716311229.805 [OnDock:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2024-05-21T17:07:09.832Z,1716311229.832 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2024-05-21T17:07:09.891Z,1716311229.891 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl 2024-05-21T17:07:09.892Z,1716311229.892 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl 2024-05-21T17:07:09.899Z,1716311229.899 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl 2024-05-21T17:07:09.963Z,1716311229.963 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into: 2024-05-21T17:07:09.966Z,1716311229.966 [MissionManager](INFO): DefineArg OnDock:BackseatDriver.EnableBackseat = 0 bool 2024-05-21T17:07:09.969Z,1716311229.969 [OnDock:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver. 2024-05-21T17:07:09.972Z,1716311229.972 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl 2024-05-21T17:07:09.973Z,1716311229.973 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl 2024-05-21T17:07:10.002Z,1716311230.002 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl 2024-05-21T17:07:10.111Z,1716311230.111 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into: 99999 2024-05-21T17:07:10.114Z,1716311230.114 [MissionManager](INFO): DefineArg OnDock:PowerOnly.SampleAll = 0 bool 2024-05-21T17:07:10.116Z,1716311230.116 [MissionManager](INFO): DefineArg OnDock:PowerOnly.SampleLoad1 = 0 bool 2024-05-21T17:07:10.178Z,1716311230.178 [MissionManager](INFO): DefineArg OnDock:PowerOnly.SampleLoad2 = 0 bool 2024-05-21T17:07:10.180Z,1716311230.180 [MissionManager](INFO): DefineArg OnDock:PowerOnly.SampleLoad3 = 0 bool 2024-05-21T17:07:10.191Z,1716311230.191 [MissionManager](INFO): DefineArg OnDock:PowerOnly.EnabledPowerOnly = 0 bool 2024-05-21T17:07:10.219Z,1716311230.219 [OnDock:PowerOnly:E.Wait](DEBUG): Construct Wait. 2024-05-21T17:07:10.295Z,1716311230.295 [OnDock:restartDHCP:B.Execute](DEBUG): Construct Execute. 2024-05-21T17:07:10.302Z,1716311230.302 [OnDock:L.Execute](DEBUG): Construct Execute. 2024-05-21T17:07:10.311Z,1716311230.311 [OnDock:PeriodicCheck:CheckIP:A.Execute](DEBUG): Construct Execute. 2024-05-21T17:07:10.313Z,1716311230.313 [OnDock:PeriodicCheck:CheckIP:B.Wait](DEBUG): Construct Wait. 2024-05-21T17:07:10.319Z,1716311230.319 [OnDock:O.Docked](DEBUG): Construct. 2024-05-21T17:07:10.319Z,1716311230.319 [OnDock:O.Docked](DEBUG): Initializing internal variables to default values. 2024-05-21T17:07:10.356Z,1716311230.356 [OnDock:Detach:B.Undock](DEBUG): Construct. 2024-05-21T17:07:10.356Z,1716311230.356 [OnDock:Detach:B.Undock](DEBUG): Initializing internal variables to default values. 2024-05-21T17:07:10.383Z,1716311230.383 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission { """ Wait on dock, communicating when possible """ arguments { DockedTime = 8 hour """ Time to stay on dock. Mission timeout will be five minutes longer. """ DetachedTimeout = Dock:Docked.detachTimeout """ Time duration limit for determining if the vehicle is detached from the dock. """ RangeTimeout = Dock:Docked.rangeTimeout """ Maximum allowed time without acoustic range. """ TrackingUpdatePeriod = 2 minute """ How long to wait between acoustic queries. """ NumberOfPings = 1 count """ Number of pings requested each time. """ TransponderCode = Dock:Dock.transponderCode """ Transponder Address. """ MaxDepth = BIT:CBIT.stopDepth """ Maximum depth while docked. """ MinAltitude = 5 meter """ Minimum altitude """ MinOffshore = 2 km """ Minimum offshore """ StayOnDock = false """ If true, don't detach from dock at end of mission """ IfconfigInterval = 30 minute """ How long to wait between ifconfig runs """ # Mission variables (do not change) MissionTimeout = DockedTime """ Mission timeout (local variable, do not change) """ } timeout duration=MissionTimeout insert Insert/StandardEnvelopes.tl assign in sequence StandardEnvelopes:MinAltitude = MinAltitude assign in sequence StandardEnvelopes:MaxDepth = MaxDepth assign in sequence StandardEnvelopes:MinOffshore = MinOffshore # End docking 5 minutes before end of mission to allow clean undock assign in sequence MissionTimeout = DockedTime + 5 minute insert Insert/BackseatDriver.tl insert Insert/PowerOnly.tl # Send data when connection exists readData { while ( Sensor:DataOverHttps.connectionStatus == true ) Universal:platform_communications } behavior Estimation:TrackAcousticContact { run in parallel set contactLabelSetting = TransponderCode set numberOfSamplesSetting = NumberOfPings set updatePeriodSetting = TrackingUpdatePeriod } aggregate restartDHCP { run when ( called ) syslog important "Restarting DHCP..." behavior Guidance:Execute { run in sequence set command = "! /mnt/mmc/LRAUV/Tools/dhcp.sh" } } syslog important "Restarting logs" behavior Guidance:Execute { run in sequence set command = "restart logs" } # Kick DHCP again, in case it took us a while to close the arm in Docked call refId="restartDHCP" aggregate PeriodicCheck { run in parallel aggregate CheckIP { run in sequence behavior Guidance:Execute { run in sequence set command = "! ifconfig" } behavior Guidance:Wait { run in sequence set duration = IfconfigInterval } } } behavior Dock:Docked { run in sequence timeout duration=DockedTime set detachTimeout = DetachedTimeout set rangeTimeout = RangeTimeout } # Undock cleanly, instead of relying on DefaultWithUndock to come next aggregate Detach { run in sequence break if ( StayOnDock == true ) syslog important "Mission ending, breaking off. Range: " + Estimation:TrackAcousticContact.range_to_contact~meter + "." behavior Dock:Undock { run in sequence } # Get our 10.89 IP back once we're on the surface call refId="restartDHCP" } } 2024-05-21T17:07:10.384Z,1716311230.384 [CommandExec](IMPORTANT): Loaded ./Missions/Engineering/OnDock.tl id=OnDock 2024-05-21T17:07:10.582Z,1716311230.582 [MissionManager](IMPORTANT): Started mission DefaultTankUndock 2024-05-21T17:07:10.582Z,1716311230.582 [DefaultTankUndock] Running Loop=1 2024-05-21T17:07:10.583Z,1716311230.583 [DefaultTankUndock](DEBUG): Aggregate::initialize DefaultTankUndock 2024-05-21T17:07:10.583Z,1716311230.583 [DefaultTankUndock:B.GoToSurface] Running Loop=1 2024-05-21T17:07:10.583Z,1716311230.583 [DefaultTankUndock:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-05-21T17:07:10.583Z,1716311230.583 [DefaultTankUndock:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-05-21T17:07:10.583Z,1716311230.583 [DefaultTankUndock:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-05-21T17:07:10.584Z,1716311230.584 [DefaultTankUndock:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-05-21T17:07:10.584Z,1716311230.584 [DefaultTankUndock:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-05-21T17:07:10.584Z,1716311230.584 [DefaultTankUndock:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-05-21T17:07:10.585Z,1716311230.585 [DefaultTankUndock:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-05-21T17:07:10.585Z,1716311230.585 [DefaultTankUndock:A.Wait] Running Loop=1 2024-05-21T17:07:10.585Z,1716311230.585 [DefaultTankUndock:A.Wait](DEBUG): Initialize Wait Component. 2024-05-21T17:07:10.600Z,1716311230.600 [DockingStepper](INFO): Deisolating dock charge 2024-05-21T17:07:11.046Z,1716311231.046 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -25.00 mm (1 active estimators). 2024-05-21T17:07:11.107Z,1716311231.107 [Radio_Surface](INFO): Powering up 2024-05-21T17:07:16.866Z,1716311236.866 [DataOverHttps](INFO): Radio surface powered ON. 2024-05-21T17:07:23.764Z,1716311243.764 [DefaultTankUndock:A.Wait](INFO): Done Waiting. 2024-05-21T17:07:23.764Z,1716311243.764 [DefaultTankUndock:A.Wait] Stopped 2024-05-21T17:07:23.764Z,1716311243.764 [DefaultTankUndock:A.Wait](DEBUG): Uninitialize Wait Component. 2024-05-21T17:07:24.395Z,1716311244.395 [CommandExec](IMPORTANT): got command set OnDock.DockedTime 45 minute 2024-05-21T17:07:24.396Z,1716311244.396 [CommandExec](IMPORTANT): got command set OnDock.DetachedTimeout 8 hour 2024-05-21T17:07:24.396Z,1716311244.396 [CommandExec](IMPORTANT): got command set OnDock.RangeTimeout 8 hour 2024-05-21T17:07:24.397Z,1716311244.397 [CommandExec](IMPORTANT): got command set OnDock.MaxDepth 5 meter 2024-05-21T17:07:24.398Z,1716311244.398 [CommandExec](IMPORTANT): got command set OnDock.MinAltitude 0.00001 meter 2024-05-21T17:07:24.399Z,1716311244.399 [CommandExec](IMPORTANT): got command set OnDock.MinOffshore 0.000001 kilometer 2024-05-21T17:07:27.569Z,1716311247.569 [CommandExec](IMPORTANT): got command set OnDock.IfconfigInterval 5 minute 2024-05-21T17:07:27.570Z,1716311247.570 [CommandExec](IMPORTANT): got command run 2024-05-21T17:07:27.572Z,1716311247.572 [CommandExec](IMPORTANT): Running 2024-05-21T17:07:27.860Z,1716311247.860 [DefaultTankUndock] Stopped 2024-05-21T17:07:27.860Z,1716311247.860 [DefaultTankUndock](DEBUG): Aggregate::uninitialize DefaultTankUndock 2024-05-21T17:07:27.860Z,1716311247.860 [DefaultTankUndock:B.GoToSurface] Stopped 2024-05-21T17:07:27.860Z,1716311247.860 [DefaultTankUndock:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-05-21T17:07:27.860Z,1716311247.860 [MissionManager](IMPORTANT): Started mission OnDock 2024-05-21T17:07:27.861Z,1716311247.861 [OnDock] Running Loop=1 2024-05-21T17:07:27.861Z,1716311247.861 [OnDock](DEBUG): Aggregate::initialize OnDock 2024-05-21T17:07:27.861Z,1716311247.861 [OnDock:StandardEnvelopes] Running Loop=1 2024-05-21T17:07:27.861Z,1716311247.861 [OnDock:StandardEnvelopes](DEBUG): Aggregate::initialize OnDock:StandardEnvelopes 2024-05-21T17:07:27.861Z,1716311247.861 [OnDock:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2024-05-21T17:07:27.861Z,1716311247.861 [OnDock:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2024-05-21T17:07:27.862Z,1716311247.862 [OnDock:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2024-05-21T17:07:27.862Z,1716311247.862 [OnDock:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2024-05-21T17:07:27.862Z,1716311247.862 [OnDock:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2024-05-21T17:07:27.862Z,1716311247.862 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2024-05-21T17:07:27.862Z,1716311247.862 [OnDock:BackseatDriver] Running Loop=1 2024-05-21T17:07:27.862Z,1716311247.862 [OnDock:BackseatDriver](DEBUG): Aggregate::initialize OnDock:BackseatDriver 2024-05-21T17:07:27.862Z,1716311247.862 [OnDock:BackseatDriver:A.BackseatDriver] Running Loop=1 2024-05-21T17:07:27.862Z,1716311247.862 [OnDock:PowerOnly] Running Loop=1 2024-05-21T17:07:27.863Z,1716311247.863 [OnDock:PowerOnly](DEBUG): Aggregate::initialize OnDock:PowerOnly 2024-05-21T17:07:27.863Z,1716311247.863 [OnDock:PowerOnly:A] Running Loop=1 2024-05-21T17:07:27.863Z,1716311247.863 [OnDock:PowerOnly:B] Running Loop=1 2024-05-21T17:07:27.863Z,1716311247.863 [OnDock:PowerOnly:C] Running Loop=1 2024-05-21T17:07:27.863Z,1716311247.863 [OnDock:PowerOnly:D] Running Loop=1 2024-05-21T17:07:27.863Z,1716311247.863 [OnDock:PowerOnly:E.Wait] Running Loop=1 2024-05-21T17:07:27.863Z,1716311247.863 [OnDock:PowerOnly:E.Wait](DEBUG): Initialize Wait Component. 2024-05-21T17:07:27.863Z,1716311247.863 [OnDock:H] Running Loop=1 2024-05-21T17:07:27.863Z,1716311247.863 [OnDock:I.] Running Loop=1 2024-05-21T17:07:27.863Z,1716311247.863 [OnDock:I.](INFO): Initializing TrackAcousticContact. 2024-05-21T17:07:27.863Z,1716311247.863 [OnDock:PeriodicCheck] Running Loop=1 2024-05-21T17:07:27.863Z,1716311247.863 [OnDock:PeriodicCheck](DEBUG): Aggregate::initialize OnDock:PeriodicCheck 2024-05-21T17:07:27.864Z,1716311247.864 [OnDock:PeriodicCheck:CheckIP] Running Loop=1 2024-05-21T17:07:27.864Z,1716311247.864 [OnDock:PeriodicCheck:CheckIP](DEBUG): Aggregate::initialize OnDock:PeriodicCheck:CheckIP 2024-05-21T17:07:27.864Z,1716311247.864 [OnDock:PeriodicCheck:CheckIP:A.Execute] Running Loop=1 2024-05-21T17:07:27.864Z,1716311247.864 [OnDock:B] Running Loop=1 2024-05-21T17:07:27.864Z,1716311247.864 [OnDock:PeriodicCheck] Running Loop=1 2024-05-21T17:07:27.864Z,1716311247.864 [OnDock:PeriodicCheck:CheckIP:A.Execute](INFO): Executing command ! ifconfig 2024-05-21T17:07:27.865Z,1716311247.865 [OnDock:PeriodicCheck:CheckIP:A.Execute] Stopped 2024-05-21T17:07:27.865Z,1716311247.865 [OnDock:PeriodicCheck:CheckIP:B.Wait] Running Loop=1 2024-05-21T17:07:27.865Z,1716311247.865 [OnDock:PeriodicCheck:CheckIP:B.Wait](DEBUG): Initialize Wait Component. 2024-05-21T17:07:27.865Z,1716311247.865 [OnDock:I.] Running Loop=1 2024-05-21T17:07:27.865Z,1716311247.865 [OnDock:H] Stopped 2024-05-21T17:07:27.865Z,1716311247.865 [OnDock:PowerOnly] Running Loop=1 2024-05-21T17:07:27.866Z,1716311247.866 [OnDock:PowerOnly:D] Stopped 2024-05-21T17:07:27.866Z,1716311247.866 [OnDock:PowerOnly:C] Stopped 2024-05-21T17:07:27.866Z,1716311247.866 [OnDock:PowerOnly:B] Stopped 2024-05-21T17:07:27.866Z,1716311247.866 [OnDock:PowerOnly:A] Stopped 2024-05-21T17:07:27.866Z,1716311247.866 [OnDock:BackseatDriver] Running Loop=1 2024-05-21T17:07:27.866Z,1716311247.866 [OnDock:B] Stopped 2024-05-21T17:07:27.867Z,1716311247.867 [OnDock:C] Running Loop=1 2024-05-21T17:07:27.867Z,1716311247.867 [OnDock:StandardEnvelopes] Running Loop=1 2024-05-21T17:07:27.867Z,1716311247.867 [OnDock:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2024-05-21T17:07:27.867Z,1716311247.867 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](IMPORTANT): Offshore envelope ACTIVE 2024-05-21T17:07:27.875Z,1716311247.875 [OnDock:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2024-05-21T17:07:27.875Z,1716311247.875 [OnDock:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2024-05-21T17:07:27.885Z,1716311247.885 [CommandExec](IMPORTANT): got command ! ifconfig 2024-05-21T17:07:28.237Z,1716311248.237 [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:11649 errors:0 dropped:0 overruns:0 frame:0 TX packets:14601 errors:0 dropped:0 overruns:0 carrier:0 collisions:20 txqueuelen:1000 RX bytes:2661085 (2.5 Mb) TX bytes:3947361 (3.7 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:919 errors:0 dropped:0 overruns:0 frame:0 TX packets:919 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:0 RX bytes:87522 (85.4 Kb) TX bytes:87522 (85.4 Kb) 2024-05-21T17:07:28.381Z,1716311248.381 [OnDock:C] Stopped 2024-05-21T17:07:28.382Z,1716311248.382 [OnDock:D] Running Loop=1 2024-05-21T17:07:28.782Z,1716311248.782 [OnDock:D] Stopped 2024-05-21T17:07:28.783Z,1716311248.783 [OnDock:E] Running Loop=1 2024-05-21T17:07:29.255Z,1716311249.255 [OnDock:E] Stopped 2024-05-21T17:07:29.255Z,1716311249.255 [OnDock:K] Running Loop=1 2024-05-21T17:07:29.700Z,1716311249.700 [OnDock:K](IMPORTANT): Restarting logs 2024-05-21T17:07:29.700Z,1716311249.700 [OnDock:K] Stopped 2024-05-21T17:07:29.700Z,1716311249.700 [OnDock:L.Execute] Running Loop=1 2024-05-21T17:07:30.074Z,1716311250.074 [OnDock:L.Execute](INFO): Executing command restart logs