2024-08-07T20:18:21.981Z,1723061901.981 [CommandExec](IMPORTANT): got command restart logs 2024-08-07T20:18:22.407Z,1723061902.407 [Reporter](INFO): DockingStepper.armValue 10720 count 2024-08-07T20:18:22.695Z,1723061902.695 [Reporter](INFO): DockingStepper.armValue 10719 count 2024-08-07T20:18:25.898Z,1723061905.898 [Reporter](INFO): DockingStepper.armValue 10720 count 2024-08-07T20:18:26.762Z,1723061906.762 [Reporter](INFO): DockingStepper.armValue 10719 count 2024-08-07T20:18:27.087Z,1723061907.087 [Reporter](INFO): DockingStepper.armValue 10720 count 2024-08-07T20:18:27.871Z,1723061907.871 [Reporter](INFO): DockingStepper.armValue 10719 count 2024-08-07T20:18:33.919Z,1723061913.919 [Reporter](INFO): DockingStepper.armValue 10720 count 2024-08-07T20:18:34.327Z,1723061914.327 [Reporter](INFO): DockingStepper.armValue 10719 count 2024-08-07T20:18:35.551Z,1723061915.551 [Reporter](INFO): DockingStepper.armValue 10720 count 2024-08-07T20:18:35.955Z,1723061915.955 [Reporter](INFO): DockingStepper.armValue 10718 count 2024-08-07T20:18:36.435Z,1723061916.435 [Reporter](INFO): DockingStepper.armValue 10720 count 2024-08-07T20:18:36.843Z,1723061916.843 [Reporter](INFO): DockingStepper.armValue 10719 count 2024-08-07T20:18:37.942Z,1723061917.942 [Reporter](INFO): DockingStepper.armValue 10720 count 2024-08-07T20:18:38.835Z,1723061918.835 [Reporter](INFO): DockingStepper.armValue 10719 count 2024-08-07T20:18:40.064Z,1723061920.064 [Reporter](INFO): DockingStepper.armValue 10720 count 2024-08-07T20:18:40.659Z,1723061920.659 [Reporter](INFO): DockingStepper.armValue 10719 count 2024-08-07T20:18:44.142Z,1723061924.142 [Reporter](INFO): DockingStepper.armValue 10720 count 2024-08-07T20:18:44.662Z,1723061924.662 [Reporter](INFO): DockingStepper.armValue 10719 count 2024-08-07T20:18:45.818Z,1723061925.818 [Reporter](INFO): DockingStepper.armValue 10720 count 2024-08-07T20:18:46.151Z,1723061926.151 [Reporter](INFO): DockingStepper.armValue 10719 count 2024-08-07T20:18:47.390Z,1723061927.390 [Reporter](INFO): DockingStepper.armValue 10720 count 2024-08-07T20:18:47.883Z,1723061927.883 [Reporter](INFO): DockingStepper.armValue 10718 count 2024-08-07T20:18:48.183Z,1723061928.183 [Reporter](INFO): DockingStepper.armValue 10719 count 2024-08-07T20:18:48.726Z,1723061928.726 [Reporter](INFO): DockingStepper.armValue 10718 count 2024-08-07T20:18:48.975Z,1723061928.975 [Reporter](INFO): DockingStepper.armValue 10719 count 2024-08-07T20:18:49.383Z,1723061929.383 [Reporter](INFO): DockingStepper.armValue 10718 count 2024-08-07T20:18:49.866Z,1723061929.866 [Reporter](INFO): DockingStepper.armValue 10719 count 2024-08-07T20:18:50.207Z,1723061930.207 [Reporter](INFO): DockingStepper.armValue 10718 count 2024-08-07T20:18:50.751Z,1723061930.751 [Reporter](INFO): DockingStepper.armValue 10719 count 2024-08-07T20:18:53.672Z,1723061933.672 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240807T201236/Courier0003.lzma 2024-08-07T20:18:53.882Z,1723061933.882 [Reporter](INFO): DockingStepper.armValue 10720 count 2024-08-07T20:18:54.223Z,1723061934.223 [Reporter](INFO): DockingStepper.armValue 10719 count 2024-08-07T20:18:54.675Z,1723061934.675 [DataOverHttps](INFO): Moved sent file to Logs/20240807T201236/Courier0003.lzma.bak 2024-08-07T20:18:54.675Z,1723061934.675 [DataOverHttps](INFO): SBD MOMSN=21445833 2024-08-07T20:18:55.489Z,1723061935.489 [Reporter](INFO): DockingStepper.armValue 10720 count 2024-08-07T20:18:55.883Z,1723061935.883 [Reporter](INFO): DockingStepper.armValue 10719 count 2024-08-07T20:18:58.294Z,1723061938.294 [Reporter](INFO): DockingStepper.armValue 10718 count 2024-08-07T20:18:58.878Z,1723061938.878 [Reporter](INFO): DockingStepper.armValue 10719 count 2024-08-07T20:18:59.142Z,1723061939.142 [Reporter](INFO): DockingStepper.armValue 10720 count 2024-08-07T20:19:00.046Z,1723061940.046 [Reporter](INFO): DockingStepper.armValue 10719 count 2024-08-07T20:19:02.986Z,1723061942.986 [Reporter](INFO): DockingStepper.armValue 10720 count 2024-08-07T20:19:03.214Z,1723061943.214 [Reporter](INFO): DockingStepper.armValue 10719 count 2024-08-07T20:19:05.358Z,1723061945.358 [Reporter](INFO): DockingStepper.armValue 10720 count 2024-08-07T20:19:05.708Z,1723061945.708 [Reporter](INFO): DockingStepper.armValue 10719 count 2024-08-07T20:19:06.561Z,1723061946.561 [Reporter](INFO): DockingStepper.armValue 10720 count 2024-08-07T20:19:06.987Z,1723061946.987 [Reporter](INFO): DockingStepper.armValue 10719 count 2024-08-07T20:19:08.207Z,1723061948.207 [Reporter](INFO): DockingStepper.armValue 10718 count 2024-08-07T20:19:09.052Z,1723061949.052 [Reporter](INFO): DockingStepper.armValue 10720 count 2024-08-07T20:19:09.314Z,1723061949.314 [Reporter](INFO): DockingStepper.armValue 10717 count 2024-08-07T20:19:09.744Z,1723061949.744 [Reporter](INFO): DockingStepper.armValue 10719 count 2024-08-07T20:19:11.363Z,1723061951.363 [Reporter](INFO): DockingStepper.armValue 10720 count 2024-08-07T20:19:11.759Z,1723061951.759 [Reporter](INFO): DockingStepper.armValue 10719 count 2024-08-07T20:19:12.580Z,1723061952.580 [Reporter](INFO): DockingStepper.armValue 10718 count 2024-08-07T20:19:13.011Z,1723061953.011 [Reporter](INFO): DockingStepper.armValue 10719 count 2024-08-07T20:19:15.391Z,1723061955.391 [Reporter](INFO): DockingStepper.armValue 10720 count 2024-08-07T20:19:16.236Z,1723061956.236 [Reporter](INFO): DockingStepper.armValue 10719 count 2024-08-07T20:19:17.839Z,1723061957.839 [Reporter](INFO): DockingStepper.armValue 10736 count 2024-08-07T20:19:21.111Z,1723061961.111 [Reporter](INFO): DockingStepper.armValue 10735 count 2024-08-07T20:19:21.432Z,1723061961.432 [Reporter](INFO): DockingStepper.armValue 10736 count 2024-08-07T20:19:21.857Z,1723061961.857 [Reporter](INFO): DockingStepper.armValue 10735 count 2024-08-07T20:19:23.017Z,1723061963.017 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240807T201236/Courier0006.lzma 2024-08-07T20:19:23.343Z,1723061963.343 [Reporter](INFO): DockingStepper.armValue 10736 count 2024-08-07T20:19:23.937Z,1723061963.937 [Reporter](INFO): DockingStepper.armValue 10735 count 2024-08-07T20:19:24.019Z,1723061964.019 [DataOverHttps](INFO): Moved sent file to Logs/20240807T201236/Courier0006.lzma.bak 2024-08-07T20:19:24.019Z,1723061964.019 [DataOverHttps](INFO): SBD MOMSN=21445835 2024-08-07T20:19:24.353Z,1723061964.353 [Reporter](INFO): DockingStepper.armValue 10736 count 2024-08-07T20:19:25.435Z,1723061965.435 [Reporter](INFO): DockingStepper.armValue 10735 count 2024-08-07T20:19:25.620Z,1723061965.620 [Reporter](INFO): DockingStepper.armValue 10736 count 2024-08-07T20:19:26.328Z,1723061966.328 [Reporter](INFO): DockingStepper.armValue 10735 count 2024-08-07T20:19:26.619Z,1723061966.619 [Reporter](INFO): DockingStepper.armValue 10736 count 2024-08-07T20:19:29.419Z,1723061969.419 [Reporter](INFO): DockingStepper.armValue 10735 count 2024-08-07T20:19:29.751Z,1723061969.751 [Reporter](INFO): DockingStepper.armValue 10736 count 2024-08-07T20:19:30.259Z,1723061970.259 [Reporter](INFO): DockingStepper.armValue 10735 count 2024-08-07T20:19:30.995Z,1723061970.995 [Reporter](INFO): DockingStepper.armValue 10736 count 2024-08-07T20:19:31.363Z,1723061971.363 [Reporter](INFO): DockingStepper.armValue 10735 count 2024-08-07T20:19:31.759Z,1723061971.759 [Reporter](INFO): DockingStepper.armValue 10736 count 2024-08-07T20:19:33.006Z,1723061973.006 [Reporter](INFO): DockingStepper.armValue 10735 count 2024-08-07T20:19:33.427Z,1723061973.427 [Reporter](INFO): DockingStepper.armValue 10736 count 2024-08-07T20:19:34.418Z,1723061974.418 [Reporter](INFO): DockingStepper.armValue 10735 count 2024-08-07T20:19:35.054Z,1723061975.054 [Reporter](INFO): DockingStepper.armValue 10736 count 2024-08-07T20:19:35.468Z,1723061975.468 [Reporter](INFO): DockingStepper.armValue 10735 count 2024-08-07T20:19:36.319Z,1723061976.319 [Reporter](INFO): DockingStepper.armValue 10736 count 2024-08-07T20:19:37.111Z,1723061977.111 [Reporter](INFO): DockingStepper.armValue 10735 count 2024-08-07T20:19:39.607Z,1723061979.607 [Reporter](INFO): DockingStepper.armValue 10736 count 2024-08-07T20:19:40.034Z,1723061980.034 [Reporter](INFO): DockingStepper.armValue 10735 count 2024-08-07T20:19:41.739Z,1723061981.739 [Reporter](INFO): DockingStepper.armValue 10736 count 2024-08-07T20:19:42.507Z,1723061982.507 [Reporter](INFO): DockingStepper.armValue 10735 count 2024-08-07T20:19:44.043Z,1723061984.043 [Reporter](INFO): DockingStepper.armValue 10736 count 2024-08-07T20:19:45.667Z,1723061985.667 [Reporter](INFO): DockingStepper.armValue 10735 count 2024-08-07T20:19:45.869Z,1723061985.869 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20240807T163423/Express0098.lzma 2024-08-07T20:19:46.531Z,1723061986.531 [Reporter](INFO): DockingStepper.armValue 10736 count 2024-08-07T20:19:46.839Z,1723061986.839 [Reporter](INFO): DockingStepper.armValue 10735 count 2024-08-07T20:19:46.886Z,1723061986.886 [DataOverHttps](INFO): Moved sent file to Logs/20240807T163423/Express0098.lzma.bak 2024-08-07T20:19:46.886Z,1723061986.886 [DataOverHttps](INFO): SBD MOMSN=21445837 2024-08-07T20:19:47.707Z,1723061987.707 [Reporter](INFO): DockingStepper.armValue 10736 count 2024-08-07T20:19:48.079Z,1723061988.079 [Reporter](INFO): DockingStepper.armValue 10735 count 2024-08-07T20:19:48.543Z,1723061988.543 [Reporter](INFO): DockingStepper.armValue 10736 count 2024-08-07T20:19:48.859Z,1723061988.859 [Reporter](INFO): DockingStepper.armValue 10735 count 2024-08-07T20:19:49.290Z,1723061989.290 [Reporter](INFO): DockingStepper.armValue 10736 count 2024-08-07T20:19:49.763Z,1723061989.763 [Reporter](INFO): DockingStepper.armValue 10735 count 2024-08-07T20:19:50.903Z,1723061990.903 [Reporter](INFO): DockingStepper.armValue 10736 count 2024-08-07T20:19:51.374Z,1723061991.374 [Reporter](INFO): DockingStepper.armValue 10735 count 2024-08-07T20:19:51.794Z,1723061991.794 [Reporter](INFO): DockingStepper.armValue 10736 count 2024-08-07T20:19:53.462Z,1723061993.462 [CommandExec](IMPORTANT): got command report clear 2024-08-07T20:20:00.713Z,1723062000.713 [CommandExec](IMPORTANT): got command get Dock.dock_cable_present 2024-08-07T20:20:00.714Z,1723062000.714 [CommandExec](IMPORTANT): Dock.dock_cable_present 0 bool 2024-08-07T20:20:13.035Z,1723062013.035 [CommandExec](IMPORTANT): got command report mod Dock.dock_cable_present 2024-08-07T20:20:13.330Z,1723062013.330 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:20:18.303Z,1723062018.303 [CommandExec](IMPORTANT): got command docking standby 2024-08-07T20:20:18.303Z,1723062018.303 [CommandExec](IMPORTANT): Switching docking moudle to STANDBY. 2024-08-07T20:20:18.696Z,1723062018.696 [DockingStepper](INFO): Changing to mode: 1 2024-08-07T20:20:18.696Z,1723062018.696 [DockingStepper](INFO): Standby mode. 2024-08-07T20:20:18.765Z,1723062018.765 [DockingStepper](INFO): Commanding positive:35482 2024-08-07T20:20:19.100Z,1723062019.100 [DataOverHttps](INFO): Sending 529 bytes from file Logs/20240807T201236/Express0001.lzma 2024-08-07T20:20:20.095Z,1723062020.095 [DataOverHttps](INFO): Moved sent file to Logs/20240807T201236/Express0001.lzma.bak 2024-08-07T20:20:20.095Z,1723062020.095 [DataOverHttps](INFO): SBD MOMSN=21445840 2024-08-07T20:20:21.095Z,1723062021.095 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:20:23.752Z,1723062023.752 [CommandExec](IMPORTANT): got command docking arm 2024-08-07T20:20:23.753Z,1723062023.753 [CommandExec](IMPORTANT): Switching docking moudle to ARM. 2024-08-07T20:20:23.916Z,1723062023.916 [DockingStepper](INFO): Changing to mode: 2 2024-08-07T20:20:23.916Z,1723062023.916 [DockingStepper](INFO): Armed mode. 2024-08-07T20:20:23.993Z,1723062023.993 [DockingStepper](INFO): Commanding negative:54205 2024-08-07T20:20:28.890Z,1723062028.890 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:20:37.592Z,1723062037.592 [DataOverHttps](INFO): Sending 78 bytes from file Logs/20240807T201236/Express0004.lzma 2024-08-07T20:20:38.595Z,1723062038.595 [DataOverHttps](INFO): Moved sent file to Logs/20240807T201236/Express0004.lzma.bak 2024-08-07T20:20:38.595Z,1723062038.595 [DataOverHttps](INFO): SBD MOMSN=21445850 2024-08-07T20:20:56.177Z,1723062056.177 [DataOverHttps](INFO): Sending 237 bytes from file Logs/20240807T201236/Express0007.lzma 2024-08-07T20:20:56.618Z,1723062056.618 [CommandExec](IMPORTANT): got command docking slide 2024-08-07T20:20:56.618Z,1723062056.618 [CommandExec](IMPORTANT): Switching docking module to SLIDE. 2024-08-07T20:20:56.702Z,1723062056.702 [DockingStepper](INFO): Changing to mode: 4 2024-08-07T20:20:56.702Z,1723062056.702 [DockingStepper](INFO): Slide mode. 2024-08-07T20:20:56.765Z,1723062056.765 [DockingStepper](INFO): Commanding positive:18082 2024-08-07T20:20:57.179Z,1723062057.179 [DataOverHttps](INFO): Moved sent file to Logs/20240807T201236/Express0007.lzma.bak 2024-08-07T20:20:57.179Z,1723062057.179 [DataOverHttps](INFO): SBD MOMSN=21445852 2024-08-07T20:20:58.310Z,1723062058.310 [Default:CheckIn:Read_Iridium] Stopped 2024-08-07T20:20:58.311Z,1723062058.311 [Default:CheckIn:C.Wait] Running Loop=1 2024-08-07T20:20:58.311Z,1723062058.311 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-08-07T20:21:07.497Z,1723062067.497 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:21:16.645Z,1723062076.645 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:21:25.990Z,1723062085.990 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:21:35.251Z,1723062095.251 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:21:40.189Z,1723062100.189 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:21:40.542Z,1723062100.542 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:21:44.203Z,1723062104.203 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:21:44.401Z,1723062104.401 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:21:45.391Z,1723062105.391 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:21:46.226Z,1723062106.226 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:21:48.287Z,1723062108.287 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:21:48.639Z,1723062108.639 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:21:49.019Z,1723062109.019 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:21:50.655Z,1723062110.655 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:21:53.981Z,1723062113.981 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:21:54.299Z,1723062114.299 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:21:56.776Z,1723062116.776 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:21:58.755Z,1723062118.755 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:22:03.129Z,1723062123.129 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:22:16.162Z,1723062136.162 [CommandExec](IMPORTANT): got command report mod DockingStepper.cableValue 2024-08-07T20:22:16.499Z,1723062136.499 [Reporter](INFO): DockingStepper.cableValue 16335 count 2024-08-07T20:22:17.287Z,1723062137.287 [Reporter](INFO): DockingStepper.cableValue 16336 count 2024-08-07T20:22:17.767Z,1723062137.767 [Reporter](INFO): DockingStepper.cableValue 16335 count 2024-08-07T20:22:17.821Z,1723062137.821 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:22:18.083Z,1723062138.083 [Reporter](INFO): DockingStepper.cableValue 16336 count 2024-08-07T20:22:18.538Z,1723062138.538 [Reporter](INFO): DockingStepper.cableValue 16335 count 2024-08-07T20:22:18.958Z,1723062138.958 [Reporter](INFO): DockingStepper.cableValue 16336 count 2024-08-07T20:22:20.198Z,1723062140.198 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:22:20.198Z,1723062140.198 [Reporter](INFO): DockingStepper.cableValue 4000 count 2024-08-07T20:22:20.632Z,1723062140.632 [Reporter](INFO): DockingStepper.cableValue 4047 count 2024-08-07T20:22:21.062Z,1723062141.062 [Reporter](INFO): DockingStepper.cableValue 4033 count 2024-08-07T20:22:21.422Z,1723062141.422 [Reporter](INFO): DockingStepper.cableValue 4032 count 2024-08-07T20:22:21.957Z,1723062141.957 [Reporter](INFO): DockingStepper.cableValue 4033 count 2024-08-07T20:22:22.315Z,1723062142.315 [Reporter](INFO): DockingStepper.cableValue 4032 count 2024-08-07T20:22:23.095Z,1723062143.095 [Reporter](INFO): DockingStepper.cableValue 4031 count 2024-08-07T20:22:23.443Z,1723062143.443 [Reporter](INFO): DockingStepper.cableValue 4032 count 2024-08-07T20:22:24.207Z,1723062144.207 [Reporter](INFO): DockingStepper.cableValue 4031 count 2024-08-07T20:22:24.619Z,1723062144.619 [Reporter](INFO): DockingStepper.cableValue 4032 count 2024-08-07T20:22:25.031Z,1723062145.031 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:22:25.031Z,1723062145.031 [Reporter](INFO): DockingStepper.cableValue 16335 count 2024-08-07T20:22:25.443Z,1723062145.443 [Reporter](INFO): DockingStepper.cableValue 16336 count 2024-08-07T20:22:26.967Z,1723062146.967 [Reporter](INFO): DockingStepper.cableValue 16335 count 2024-08-07T20:22:27.202Z,1723062147.202 [Reporter](INFO): DockingStepper.cableValue 16336 count 2024-08-07T20:22:27.967Z,1723062147.967 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:22:27.968Z,1723062147.968 [Reporter](INFO): DockingStepper.cableValue 4000 count 2024-08-07T20:22:28.347Z,1723062148.347 [Reporter](INFO): DockingStepper.cableValue 3999 count 2024-08-07T20:22:28.804Z,1723062148.804 [Reporter](INFO): DockingStepper.cableValue 4000 count 2024-08-07T20:22:29.167Z,1723062149.167 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:22:29.167Z,1723062149.167 [Reporter](INFO): DockingStepper.cableValue 16336 count 2024-08-07T20:22:29.538Z,1723062149.538 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:22:29.578Z,1723062149.578 [Reporter](INFO): DockingStepper.cableValue 16335 count 2024-08-07T20:22:29.999Z,1723062149.999 [Reporter](INFO): DockingStepper.cableValue 16336 count 2024-08-07T20:22:30.368Z,1723062150.368 [Reporter](INFO): DockingStepper.cableValue 16335 count 2024-08-07T20:22:30.888Z,1723062150.888 [Reporter](INFO): DockingStepper.cableValue 16336 count 2024-08-07T20:22:32.399Z,1723062152.399 [Reporter](INFO): DockingStepper.cableValue 16335 count 2024-08-07T20:22:34.055Z,1723062154.055 [Reporter](INFO): DockingStepper.cableValue 16336 count 2024-08-07T20:22:34.427Z,1723062154.427 [Reporter](INFO): DockingStepper.cableValue 16335 count 2024-08-07T20:22:34.659Z,1723062154.659 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-08-07T20:22:34.907Z,1723062154.907 [Reporter](INFO): DockingStepper.cableValue 16336 count 2024-08-07T20:22:36.071Z,1723062156.071 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:22:36.072Z,1723062156.072 [Reporter](INFO): DockingStepper.cableValue 4034 count 2024-08-07T20:22:36.419Z,1723062156.419 [Reporter](INFO): DockingStepper.cableValue 4000 count 2024-08-07T20:22:36.851Z,1723062156.851 [Reporter](INFO): DockingStepper.cableValue 4032 count 2024-08-07T20:22:37.262Z,1723062157.262 [Reporter](INFO): DockingStepper.cableValue 3999 count 2024-08-07T20:22:37.639Z,1723062157.639 [Reporter](INFO): DockingStepper.cableValue 4032 count 2024-08-07T20:22:38.055Z,1723062158.055 [Reporter](INFO): DockingStepper.cableValue 3999 count 2024-08-07T20:22:38.821Z,1723062158.821 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:22:38.874Z,1723062158.874 [Reporter](INFO): DockingStepper.cableValue 4000 count 2024-08-07T20:22:39.295Z,1723062159.295 [Reporter](INFO): DockingStepper.cableValue 4047 count 2024-08-07T20:22:39.655Z,1723062159.655 [Reporter](INFO): DockingStepper.cableValue 4032 count 2024-08-07T20:22:40.087Z,1723062160.087 [Reporter](INFO): DockingStepper.cableValue 4000 count 2024-08-07T20:22:41.351Z,1723062161.351 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:22:41.351Z,1723062161.351 [Reporter](INFO): DockingStepper.cableValue 16336 count 2024-08-07T20:22:41.772Z,1723062161.772 [Reporter](INFO): DockingStepper.cableValue 16335 count 2024-08-07T20:22:42.127Z,1723062162.127 [Reporter](INFO): DockingStepper.cableValue 16336 count 2024-08-07T20:22:42.531Z,1723062162.531 [Reporter](INFO): DockingStepper.cableValue 16335 count 2024-08-07T20:22:42.899Z,1723062162.899 [Reporter](INFO): DockingStepper.cableValue 16336 count 2024-08-07T20:22:43.331Z,1723062163.331 [Reporter](INFO): DockingStepper.cableValue 16335 count 2024-08-07T20:22:43.795Z,1723062163.795 [Reporter](INFO): DockingStepper.cableValue 16336 count 2024-08-07T20:22:46.539Z,1723062166.539 [Reporter](INFO): DockingStepper.cableValue 16335 count 2024-08-07T20:22:46.932Z,1723062166.932 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:22:46.933Z,1723062166.933 [Reporter](INFO): DockingStepper.cableValue 4000 count 2024-08-07T20:22:48.170Z,1723062168.170 [Reporter](INFO): DockingStepper.cableValue 4035 count 2024-08-07T20:22:48.289Z,1723062168.289 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:22:48.547Z,1723062168.547 [Reporter](INFO): DockingStepper.cableValue 4000 count 2024-08-07T20:22:48.960Z,1723062168.960 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:22:48.960Z,1723062168.960 [Reporter](INFO): DockingStepper.cableValue 16335 count 2024-08-07T20:22:49.771Z,1723062169.771 [Reporter](INFO): DockingStepper.cableValue 16336 count 2024-08-07T20:22:50.202Z,1723062170.202 [Reporter](INFO): DockingStepper.cableValue 16335 count 2024-08-07T20:22:50.607Z,1723062170.607 [Reporter](INFO): DockingStepper.cableValue 16336 count 2024-08-07T20:22:51.450Z,1723062171.450 [Reporter](INFO): DockingStepper.cableValue 16335 count 2024-08-07T20:22:51.819Z,1723062171.819 [Reporter](INFO): DockingStepper.cableValue 16336 count 2024-08-07T20:22:52.247Z,1723062172.247 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:22:52.247Z,1723062172.247 [Reporter](INFO): DockingStepper.cableValue 4000 count 2024-08-07T20:22:52.603Z,1723062172.603 [Reporter](INFO): DockingStepper.cableValue 4001 count 2024-08-07T20:22:53.021Z,1723062173.021 [Reporter](INFO): DockingStepper.cableValue 4000 count 2024-08-07T20:22:54.263Z,1723062174.263 [Reporter](INFO): DockingStepper.cableValue 4043 count 2024-08-07T20:22:54.622Z,1723062174.622 [Reporter](INFO): DockingStepper.cableValue 4038 count 2024-08-07T20:22:55.017Z,1723062175.017 [Reporter](INFO): DockingStepper.cableValue 4032 count 2024-08-07T20:22:55.447Z,1723062175.447 [Reporter](INFO): DockingStepper.cableValue 4031 count 2024-08-07T20:22:55.827Z,1723062175.827 [Reporter](INFO): DockingStepper.cableValue 4033 count 2024-08-07T20:22:56.243Z,1723062176.243 [Reporter](INFO): DockingStepper.cableValue 4032 count 2024-08-07T20:22:57.459Z,1723062177.459 [Reporter](INFO): DockingStepper.cableValue 4031 count 2024-08-07T20:22:57.594Z,1723062177.594 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:22:57.858Z,1723062177.858 [Reporter](INFO): DockingStepper.cableValue 4032 count 2024-08-07T20:22:58.282Z,1723062178.282 [Reporter](INFO): DockingStepper.cableValue 4031 count 2024-08-07T20:22:58.643Z,1723062178.643 [Reporter](INFO): DockingStepper.cableValue 4032 count 2024-08-07T20:22:59.895Z,1723062179.895 [Reporter](INFO): DockingStepper.cableValue 4031 count 2024-08-07T20:23:00.324Z,1723062180.324 [Reporter](INFO): DockingStepper.cableValue 4032 count 2024-08-07T20:23:00.675Z,1723062180.675 [Reporter](INFO): DockingStepper.cableValue 4031 count 2024-08-07T20:23:01.104Z,1723062181.104 [Reporter](INFO): DockingStepper.cableValue 4032 count 2024-08-07T20:23:01.554Z,1723062181.554 [Reporter](INFO): DockingStepper.cableValue 4031 count 2024-08-07T20:23:02.387Z,1723062182.387 [Reporter](INFO): DockingStepper.cableValue 4032 count 2024-08-07T20:23:05.366Z,1723062185.366 [NAL9602](INFO): Not Powering down - fast GPS 2024-08-07T20:23:05.568Z,1723062185.568 [Reporter](INFO): DockingStepper.cableValue 4031 count 2024-08-07T20:23:05.935Z,1723062185.935 [Reporter](INFO): DockingStepper.cableValue 4032 count 2024-08-07T20:23:06.363Z,1723062186.363 [Reporter](INFO): DockingStepper.cableValue 4031 count 2024-08-07T20:23:06.969Z,1723062186.969 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:23:07.130Z,1723062187.130 [Reporter](INFO): DockingStepper.cableValue 4032 count 2024-08-07T20:23:07.958Z,1723062187.958 [Reporter](INFO): DockingStepper.cableValue 4031 count 2024-08-07T20:23:08.399Z,1723062188.399 [Reporter](INFO): DockingStepper.cableValue 4032 count 2024-08-07T20:23:09.179Z,1723062189.179 [Reporter](INFO): DockingStepper.cableValue 4031 count 2024-08-07T20:23:09.634Z,1723062189.634 [Reporter](INFO): DockingStepper.cableValue 4032 count 2024-08-07T20:23:09.999Z,1723062189.999 [Reporter](INFO): DockingStepper.cableValue 4031 count 2024-08-07T20:23:10.475Z,1723062190.475 [Reporter](INFO): DockingStepper.cableValue 4032 count 2024-08-07T20:23:12.030Z,1723062192.030 [Reporter](INFO): DockingStepper.cableValue 3999 count 2024-08-07T20:23:13.238Z,1723062193.238 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:23:13.239Z,1723062193.239 [Reporter](INFO): DockingStepper.cableValue 16287 count 2024-08-07T20:23:13.664Z,1723062193.664 [Reporter](INFO): DockingStepper.cableValue 16335 count 2024-08-07T20:23:14.011Z,1723062194.011 [Reporter](INFO): DockingStepper.cableValue 16336 count 2024-08-07T20:23:14.470Z,1723062194.470 [Reporter](INFO): DockingStepper.cableValue 16335 count 2024-08-07T20:23:14.819Z,1723062194.819 [Reporter](INFO): DockingStepper.cableValue 16336 count 2024-08-07T20:23:15.648Z,1723062195.648 [Reporter](INFO): DockingStepper.cableValue 16335 count 2024-08-07T20:23:16.479Z,1723062196.479 [Reporter](INFO): DockingStepper.cableValue 16336 count 2024-08-07T20:23:16.701Z,1723062196.701 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:23:17.232Z,1723062197.232 [Reporter](INFO): DockingStepper.cableValue 16335 count 2024-08-07T20:23:18.042Z,1723062198.042 [Reporter](INFO): DockingStepper.cableValue 16336 count 2024-08-07T20:23:18.471Z,1723062198.471 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:23:18.471Z,1723062198.471 [Reporter](INFO): DockingStepper.cableValue 4000 count 2024-08-07T20:23:18.915Z,1723062198.915 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:23:18.915Z,1723062198.915 [Reporter](INFO): DockingStepper.cableValue 16336 count 2024-08-07T20:23:19.743Z,1723062199.743 [Reporter](INFO): DockingStepper.cableValue 16335 count 2024-08-07T20:23:20.551Z,1723062200.551 [Reporter](INFO): DockingStepper.cableValue 16336 count 2024-08-07T20:23:20.939Z,1723062200.939 [Reporter](INFO): DockingStepper.cableValue 16335 count 2024-08-07T20:23:21.775Z,1723062201.775 [Reporter](INFO): DockingStepper.cableValue 16336 count 2024-08-07T20:23:22.123Z,1723062202.123 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:23:22.124Z,1723062202.124 [Reporter](INFO): DockingStepper.cableValue 3999 count 2024-08-07T20:23:22.567Z,1723062202.567 [Reporter](INFO): DockingStepper.cableValue 4032 count 2024-08-07T20:23:23.735Z,1723062203.735 [Reporter](INFO): Dock.dock_cable_present 0 bool 2024-08-07T20:23:23.736Z,1723062203.736 [Reporter](INFO): DockingStepper.cableValue 16336 count 2024-08-07T20:23:24.947Z,1723062204.947 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:23:24.948Z,1723062204.948 [Reporter](INFO): DockingStepper.cableValue 4032 count 2024-08-07T20:23:25.315Z,1723062205.315 [Reporter](INFO): DockingStepper.cableValue 4000 count 2024-08-07T20:23:25.731Z,1723062205.731 [Reporter](INFO): DockingStepper.cableValue 4032 count 2024-08-07T20:23:26.045Z,1723062206.045 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:23:26.143Z,1723062206.143 [Reporter](INFO): DockingStepper.cableValue 4031 count 2024-08-07T20:23:26.519Z,1723062206.519 [Reporter](INFO): DockingStepper.cableValue 4032 count 2024-08-07T20:23:28.559Z,1723062208.559 [Reporter](INFO): DockingStepper.cableValue 4031 count 2024-08-07T20:23:29.007Z,1723062209.007 [Reporter](INFO): DockingStepper.cableValue 4032 count 2024-08-07T20:23:29.807Z,1723062209.807 [Reporter](INFO): DockingStepper.cableValue 4031 count 2024-08-07T20:23:30.167Z,1723062210.167 [Reporter](INFO): DockingStepper.cableValue 4032 count 2024-08-07T20:23:31.411Z,1723062211.411 [Reporter](INFO): DockingStepper.cableValue 4031 count 2024-08-07T20:23:31.888Z,1723062211.888 [Reporter](INFO): DockingStepper.cableValue 4032 count 2024-08-07T20:23:32.608Z,1723062212.608 [Reporter](INFO): DockingStepper.cableValue 4031 count 2024-08-07T20:23:33.399Z,1723062213.399 [Reporter](INFO): DockingStepper.cableValue 4032 count 2024-08-07T20:23:33.818Z,1723062213.818 [Reporter](INFO): DockingStepper.cableValue 4031 count 2024-08-07T20:23:34.192Z,1723062214.192 [Reporter](INFO): DockingStepper.cableValue 4032 count 2024-08-07T20:23:34.615Z,1723062214.615 [Reporter](INFO): DockingStepper.cableValue 4031 count 2024-08-07T20:23:35.373Z,1723062215.373 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:23:35.427Z,1723062215.427 [Reporter](INFO): DockingStepper.cableValue 4032 count 2024-08-07T20:23:35.843Z,1723062215.843 [Reporter](INFO): DockingStepper.cableValue 4031 count 2024-08-07T20:23:36.219Z,1723062216.219 [Reporter](INFO): DockingStepper.cableValue 4032 count 2024-08-07T20:23:37.915Z,1723062217.915 [Reporter](INFO): DockingStepper.cableValue 4031 count 2024-08-07T20:23:38.664Z,1723062218.664 [Reporter](INFO): DockingStepper.cableValue 4032 count 2024-08-07T20:23:39.496Z,1723062219.496 [Reporter](INFO): DockingStepper.cableValue 4031 count 2024-08-07T20:23:39.498Z,1723062219.498 [CommandExec](IMPORTANT): got command report clear 2024-08-07T20:23:44.589Z,1723062224.589 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:23:53.251Z,1723062233.251 [CommandExec](IMPORTANT): got command report mod Dock.dock_cable_present 2024-08-07T20:23:53.263Z,1723062233.263 [Reporter](INFO): Dock.dock_cable_present 1 bool 2024-08-07T20:23:56.493Z,1723062236.493 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:24:05.885Z,1723062245.885 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:24:06.919Z,1723062246.919 [CommandExec](IMPORTANT): got command schedule resume 2024-08-07T20:24:06.919Z,1723062246.919 [CommandExec](IMPORTANT): Scheduling is resumed 2024-08-07T20:24:11.911Z,1723062251.911 [CommandExec](IMPORTANT): got command schedule pause 2024-08-07T20:24:11.911Z,1723062251.911 [CommandExec](IMPORTANT): Scheduling is paused 2024-08-07T20:24:15.229Z,1723062255.229 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:24:23.687Z,1723062263.687 [DataOverHttps](IMPORTANT): SBD MTMSN=20240807T202422 2024-08-07T20:24:31.943Z,1723062271.943 [DataOverHttps](INFO): Received command: sched "load Engineering/sink.tl;set sink.MissionTimeout 7 min;set sink.Depth 1.25 m;set sink.DepthDeadband .5 m;set sink.DoBallastAndTrim 0 bool;set sink.MinAltitude .0001 m;set sink.MaxDepth 5 m;set sink.MinOffshore .00001 km;run" 2024-08-07T20:24:32.058Z,1723062272.058 [CommandExec](IMPORTANT): got command schedule "load Engineering/sink.tl;set sink.MissionTimeout 7 min;set sink.Depth 1.25 m;set sink.DepthDeadband .5 m;set sink.DoBallastAndTrim 0 bool;set sink.MinAltitude .0001 m;set sink.MaxDepth 5 m;set sink.MinOffshore .00001 km;run" 2024-08-07T20:24:32.059Z,1723062272.059 [CommandExec](IMPORTANT): Scheduled #1: "load Engineering/sink.tl;set sink.MissionTimeout 7 min;set sink.Depth 1.25 m;set sink.DepthDeadband .5 m;set sink.DoBallastAndTrim 0 bool;set sink.MinAltitude .0001 m;set sink.MaxDepth 5 m;set sink.MinOffshore .00001 km;run", AFTER MISSION 2024-08-07T20:24:32.059Z,1723062272.059 [CommandExec](FAULT): Scheduling was paused by a command at 2024-08-07T20:24:11Z 2024-08-07T20:24:33.945Z,1723062273.945 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:24:43.251Z,1723062283.251 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:24:51.796Z,1723062291.796 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 0.00, 112.21, 00.32, 100.32 2024-08-07T20:24:56.626Z,1723062296.626 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:25:05.946Z,1723062305.946 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:25:15.333Z,1723062315.333 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:25:24.509Z,1723062324.509 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:25:33.829Z,1723062333.829 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:25:42.997Z,1723062342.997 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:25:52.257Z,1723062352.257 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:25:58.864Z,1723062358.864 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-08-07T20:25:58.865Z,1723062358.865 [Default:CheckIn:C.Wait] Stopped 2024-08-07T20:25:58.865Z,1723062358.865 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-08-07T20:25:58.865Z,1723062358.865 [Default:CheckIn:D] Running Loop=1 2024-08-07T20:25:59.244Z,1723062359.244 [Default:CheckIn:D] Stopped 2024-08-07T20:25:59.244Z,1723062359.244 [Default:CheckIn:E] Running Loop=1 2024-08-07T20:25:59.658Z,1723062359.658 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 228.808626 min 2024-08-07T20:25:59.658Z,1723062359.658 [Default:CheckIn:E] Stopped 2024-08-07T20:25:59.658Z,1723062359.658 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-08-07T20:25:59.658Z,1723062359.658 [Default:CheckIn] Stopped 2024-08-07T20:25:59.658Z,1723062359.658 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-08-07T20:25:59.658Z,1723062359.658 [Default:CheckIn](INFO): Running loop #34 2024-08-07T20:25:59.659Z,1723062359.659 [Default:CheckIn] Running Loop=34 2024-08-07T20:25:59.659Z,1723062359.659 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-08-07T20:25:59.659Z,1723062359.659 [Default:CheckIn:Read_GPS] Running Loop=1 2024-08-07T20:26:01.653Z,1723062361.653 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:26:01.668Z,1723062361.668 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202600.00,A,3648.15870,N,12147.28169,W,0.175,174.63,070824,,,D*75 2024-08-07T20:26:01.670Z,1723062361.670 [NAL9602](INFO): GPS fix at 20240807T202600: (36.802645, -121.788028) 2024-08-07T20:26:01.682Z,1723062361.682 [Default:CheckIn:Read_GPS] Stopped 2024-08-07T20:26:01.682Z,1723062361.682 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-08-07T20:26:09.077Z,1723062369.077 [DataOverHttps](INFO): Sending 266 bytes from file Logs/20240807T201821/Courier0000.lzma 2024-08-07T20:26:10.079Z,1723062370.079 [DataOverHttps](INFO): Moved sent file to Logs/20240807T201821/Courier0000.lzma.bak 2024-08-07T20:26:10.079Z,1723062370.079 [DataOverHttps](INFO): SBD MOMSN=21445918 2024-08-07T20:26:27.616Z,1723062387.616 [DataOverHttps](INFO): Sending 935 bytes from file Logs/20240807T201821/Express0001.lzma 2024-08-07T20:26:28.616Z,1723062388.616 [DataOverHttps](INFO): Moved sent file to Logs/20240807T201821/Express0001.lzma.bak 2024-08-07T20:26:28.616Z,1723062388.616 [DataOverHttps](IMPORTANT): SBD MOMSN=21445923, MTMSN=20240807T202627 2024-08-07T20:26:32.406Z,1723062392.406 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2024-08-07T20:26:32.485Z,1723062392.485 [NAL9602](ERROR): received: +CSQ:0 OK380, 2, 0, 0, 0 OK 2024-08-07T20:26:36.911Z,1723062396.911 [DataOverHttps](INFO): Received command: sched "load Engineering/OnDock.tl;set OnDock.DockedTime 15 min;set OnDock.DetachedTimeout 100 min;set OnDock.RangeTimeout 100 min;set OnDock.TrackingUpdatePeriod 100 min;set OnDock.MinAltitude 0.0001 m;set OnDock.MinOffshore 0.0001 km" 27cs0 1 2 2024-08-07T20:26:36.940Z,1723062396.940 [CommandExec](IMPORTANT): got command schedule "load Engineering/OnDock.tl;set OnDock.DockedTime 15 min;set OnDock.DetachedTimeout 100 min;set OnDock.RangeTimeout 100 min;set OnDock.TrackingUpdatePeriod 100 min;set OnDock.MinAltitude 0.0001 m;set OnDock.MinOffshore 0.0001 km" 27cs0 1 2.000000 2024-08-07T20:26:36.940Z,1723062396.940 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=27cs0 2024-08-07T20:26:36.943Z,1723062396.943 [CommandExec](IMPORTANT): Scheduled #2 (#1 of 2 with id='27cs0'): "load Engineering/OnDock.tl;set OnDock.DockedTime 15 min;set OnDock.DetachedTimeout 100 min;set OnDock.RangeTimeout 100 min;set OnDock.TrackingUpdatePeriod 100 min;set OnDock.MinAltitude 0.0001 m;set OnDock.MinOffshore 0.0001 km", AFTER MISSION 2024-08-07T20:26:36.944Z,1723062396.944 [CommandExec](FAULT): Scheduling was paused by a command at 2024-08-07T20:24:11Z 2024-08-07T20:26:38.183Z,1723062398.183 [DataOverHttps](IMPORTANT): SBD MTMSN=20240807T202636 2024-08-07T20:26:41.655Z,1723062401.655 [CommandExec](IMPORTANT): got command schedule resume 2024-08-07T20:26:41.655Z,1723062401.655 [CommandExec](IMPORTANT): Scheduling is resumed 2024-08-07T20:26:41.916Z,1723062401.916 [CommandExec](IMPORTANT): got command load ./Missions/Engineering/sink.tl 2024-08-07T20:26:41.933Z,1723062401.933 [MissionManager](INFO): Loading Mission from file: ./Missions/Engineering/sink.tl 2024-08-07T20:26:41.933Z,1723062401.933 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Engineering/sink.tl 2024-08-07T20:26:41.936Z,1723062401.936 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Engineering/sink.tl 2024-08-07T20:26:54.085Z,1723062414.085 [MissionManager](DEBUG): TethyslAPI: ./Missions/Engineering/sink.tl translated into: 2 20 1 2.5 20 20 10 45 10 0.5 25 98 5 30 2 2024-08-07T20:26:54.160Z,1723062414.160 [MissionManager](INFO): DefineArg sink.MissionTimeout = 2.000000 h 2024-08-07T20:26:54.176Z,1723062414.176 [MissionManager](INFO): DefineArg sink.Depth = 20.000000 m 2024-08-07T20:26:54.183Z,1723062414.183 [MissionManager](INFO): DefineArg sink.DepthDeadband = 1.000000 m 2024-08-07T20:26:54.243Z,1723062414.243 [MissionManager](INFO): DefineArg sink.SurfaceThreshold = 2.500000 m 2024-08-07T20:26:54.254Z,1723062414.254 [MissionManager](INFO): DefineArg sink.WaitToFloat = 20.000000 min 2024-08-07T20:26:54.297Z,1723062414.297 [MissionManager](INFO): DefineArg sink.TargetDepthTimeout = 20.000000 min 2024-08-07T20:26:54.300Z,1723062414.300 [MissionManager](INFO): DefineArg sink.DoBallastAndTrim = 1 bool 2024-08-07T20:26:54.323Z,1723062414.323 [MissionManager](INFO): DefineArg sink.BTSettleTime = 10.000000 min 2024-08-07T20:26:54.374Z,1723062414.374 [MissionManager](INFO): DefineArg sink.EstimationTimeout = 45.000000 min 2024-08-07T20:26:54.394Z,1723062414.394 [MissionManager](INFO): DefineArg sink.MinEstimationTime = 10.000000 min 2024-08-07T20:26:54.413Z,1723062414.413 [MissionManager](INFO): DefineArg sink.MassEstimationErrorBound = 0.500000 mm 2024-08-07T20:26:54.465Z,1723062414.465 [MissionManager](INFO): DefineArg sink.BuoyEstimationErrorBound = 25.000000 cc 2024-08-07T20:26:54.468Z,1723062414.468 [MissionManager](INFO): DefineArg sink.EstimationConfidence = 98.000000 % 2024-08-07T20:26:54.491Z,1723062414.491 [MissionManager](INFO): DefineArg sink.MinAltitude = 5.000000 m 2024-08-07T20:26:54.556Z,1723062414.556 [MissionManager](INFO): DefineArg sink.MaxDepth = 30.000000 m 2024-08-07T20:26:54.627Z,1723062414.627 [MissionManager](INFO): DefineArg sink.MinOffshore = 2.000000 km 2024-08-07T20:26:54.627Z,1723062414.627 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/Science.tl 2024-08-07T20:26:54.627Z,1723062414.627 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/Science.tl 2024-08-07T20:26:54.652Z,1723062414.652 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/Science.tl 2024-08-07T20:26:57.864Z,1723062417.864 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20240807T201821/Express0004.lzma 2024-08-07T20:26:57.867Z,1723062417.867 [DataOverHttps](INFO): Received command: sched "set OnDock.IfconfigInterval 3 min;run" 27cs0 2 2 2024-08-07T20:26:58.655Z,1723062418.655 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/Science.tl translated into: NaN 3 100 80 20 5 NaN NaN 2.0 NaN NaN 5 NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN Triggered. PeakChl values on consecutive yo-yo profiles fall in [ ] (NaN bound is ignored). 2024-08-07T20:26:58.700Z,1723062418.700 [MissionManager](INFO): DefineArg sink:Science.PeakDetectChlActive = 0 bool 2024-08-07T20:26:58.703Z,1723062418.703 [MissionManager](INFO): DefineArg sink:Science.TimeWindowPeakReport = nan min 2024-08-07T20:26:58.753Z,1723062418.753 [MissionManager](INFO): DefineArg sink:Science.HighestChlPeakReportActive = 0 bool 2024-08-07T20:26:58.756Z,1723062418.756 [MissionManager](INFO): DefineArg sink:Science.HighestSaltPeakReportActive = 0 bool 2024-08-07T20:26:58.767Z,1723062418.767 [MissionManager](INFO): DefineArg sink:Science.HighestOilPeakReportActive = 0 bool 2024-08-07T20:26:58.778Z,1723062418.778 [MissionManager](INFO): DefineArg sink:Science.PatchTracking = 0 bool 2024-08-07T20:26:58.780Z,1723062418.780 [MissionManager](INFO): DefineArg sink:Science.FilterWidthHorizontal = 3.000000 count 2024-08-07T20:26:58.808Z,1723062418.808 [MissionManager](INFO): DefineArg sink:Science.NumProfilesSlidingwindow = 100.000000 count 2024-08-07T20:26:58.816Z,1723062418.816 [MissionManager](INFO): DefineArg sink:Science.OffPeakFractionHorizontal = 80.000000 % 2024-08-07T20:26:58.827Z,1723062418.827 [MissionManager](INFO): DefineArg sink:Science.PeakDetectNO3Active = 0 bool 2024-08-07T20:26:58.831Z,1723062418.831 [MissionManager](INFO): DefineArg sink:Science.PeakDetectOilActive = 0 bool 2024-08-07T20:26:58.834Z,1723062418.834 [MissionManager](INFO): DefineArg sink:Science.PeakDetectFDOMActive = 0 bool 2024-08-07T20:26:58.845Z,1723062418.845 [MissionManager](INFO): DefineArg sink:Science.PeakDetectSalinityActive = 0 bool 2024-08-07T20:26:58.848Z,1723062418.848 [MissionManager](INFO): DefineArg sink:Science.UpwardDerivativeOfTemperatureActive = 0 bool 2024-08-07T20:26:58.904Z,1723062418.904 [MissionManager](INFO): DefineArg sink:Science.EnabledAanderaaO2 = 0 bool 2024-08-07T20:26:58.912Z,1723062418.912 [MissionManager](INFO): DefineArg sink:Science.EnabledNeilBrown = 0 bool 2024-08-07T20:26:58.932Z,1723062418.932 [MissionManager](INFO): DefineArg sink:Science.EnabledSeabird = 1 bool 2024-08-07T20:26:58.988Z,1723062418.988 [MissionManager](INFO): DefineArg sink:Science.EnabledWetLabsBB2FL = 1 bool 2024-08-07T20:26:58.999Z,1723062418.999 [MissionManager](INFO): DefineArg sink:Science.EnabledWetLabsSeaOWL_UV_A = 0 bool 2024-08-07T20:26:59.015Z,1723062419.015 [MissionManager](INFO): DefineArg sink:Science.EnabledWetLabsUBAT = 1 bool 2024-08-07T20:26:59.046Z,1723062419.046 [MissionManager](INFO): DefineArg sink:Science.LowPassWindowLength = 20.000000 count 2024-08-07T20:26:59.077Z,1723062419.077 [MissionManager](INFO): DefineArg sink:Science.MedianFilterLen = 5.000000 count 2024-08-07T20:26:59.107Z,1723062419.107 [MissionManager](INFO): DefineArg sink:Science.PeakShallowBound = nan m 2024-08-07T20:26:59.129Z,1723062419.129 [MissionManager](INFO): DefineArg sink:Science.PeakDeepBound = nan m 2024-08-07T20:26:59.138Z,1723062419.138 [MissionManager](INFO): DefineArg sink:Science.DepChangeThreshForAttitudeFlip = 2.000000 m 2024-08-07T20:26:59.149Z,1723062419.149 [MissionManager](INFO): DefineArg sink:Science.ChlThreshTriggerActive = 0 bool 2024-08-07T20:26:59.152Z,1723062419.152 [MissionManager](INFO): DefineArg sink:Science.ChlLowerThresh = nan ug/l 2024-08-07T20:26:59.179Z,1723062419.179 [MissionManager](INFO): DefineArg sink:Science.ChlUpperThresh = nan ug/l 2024-08-07T20:26:59.219Z,1723062419.219 [MissionManager](INFO): DefineArg sink:Science.NumProfilesThresh = 5.000000 count 2024-08-07T20:26:59.242Z,1723062419.242 [MissionManager](INFO): DefineOutput sink:Science.PeakChl = nan ug/l 2024-08-07T20:26:59.251Z,1723062419.251 [MissionManager](INFO): DefineOutput sink:Science.PeakChlDepth = nan m 2024-08-07T20:26:59.282Z,1723062419.282 [MissionManager](INFO): DefineOutput sink:Science.PeakChlTemperature = nan degC 2024-08-07T20:26:59.290Z,1723062419.290 [MissionManager](INFO): DefineOutput sink:Science.PeakChlLatitude = nan arcdeg 2024-08-07T20:26:59.310Z,1723062419.310 [MissionManager](INFO): DefineOutput sink:Science.PeakChlLongitude = nan arcdeg 2024-08-07T20:26:59.319Z,1723062419.319 [MissionManager](INFO): DefineOutput sink:Science.PeakNO3 = nan umol/l 2024-08-07T20:26:59.338Z,1723062419.338 [MissionManager](INFO): DefineOutput sink:Science.PeakNO3Depth = nan m 2024-08-07T20:26:59.353Z,1723062419.353 [MissionManager](INFO): DefineOutput sink:Science.PeakNO3Latitude = nan arcdeg 2024-08-07T20:26:59.356Z,1723062419.356 [MissionManager](INFO): DefineOutput sink:Science.PeakNO3Longitude = nan arcdeg 2024-08-07T20:26:59.367Z,1723062419.367 [MissionManager](INFO): DefineOutput sink:Science.PeakOil = nan kg/m3 2024-08-07T20:26:59.375Z,1723062419.375 [MissionManager](INFO): DefineOutput sink:Science.PeakOilDepth = nan m 2024-08-07T20:26:59.398Z,1723062419.398 [MissionManager](INFO): DefineOutput sink:Science.PeakOilLatitude = nan arcdeg 2024-08-07T20:26:59.409Z,1723062419.409 [MissionManager](INFO): DefineOutput sink:Science.PeakOilLongitude = nan arcdeg 2024-08-07T20:26:59.412Z,1723062419.412 [MissionManager](INFO): DefineOutput sink:Science.PeakFDOM = nan ppb 2024-08-07T20:26:59.432Z,1723062419.432 [MissionManager](INFO): DefineOutput sink:Science.PeakFDOMDepth = nan m 2024-08-07T20:26:59.443Z,1723062419.443 [MissionManager](INFO): DefineOutput sink:Science.PeakFDOMTemperature = nan degC 2024-08-07T20:26:59.451Z,1723062419.451 [MissionManager](INFO): DefineOutput sink:Science.PeakFDOMLatitude = nan arcdeg 2024-08-07T20:26:59.483Z,1723062419.483 [MissionManager](INFO): DefineOutput sink:Science.PeakFDOMLongitude = nan arcdeg 2024-08-07T20:26:59.541Z,1723062419.541 [MissionManager](INFO): DefineOutput sink:Science.PeakSalt = nan psu 2024-08-07T20:26:59.550Z,1723062419.550 [MissionManager](INFO): DefineOutput sink:Science.PeakSaltDepth = nan m 2024-08-07T20:26:59.557Z,1723062419.557 [MissionManager](INFO): DefineOutput sink:Science.PeakSaltTemperature = nan degC 2024-08-07T20:26:59.638Z,1723062419.638 [MissionManager](INFO): DefineOutput sink:Science.PeakSaltLatitude = nan arcdeg 2024-08-07T20:26:59.652Z,1723062419.652 [MissionManager](INFO): DefineOutput sink:Science.PeakSaltLongitude = nan arcdeg 2024-08-07T20:26:59.689Z,1723062419.689 [MissionManager](INFO): DefineOutput sink:Science.PatchChl = nan ug/l 2024-08-07T20:26:59.707Z,1723062419.707 [MissionManager](INFO): DefineOutput sink:Science.PatchChlDepth = nan m 2024-08-07T20:26:59.718Z,1723062419.718 [MissionManager](INFO): DefineOutput sink:Science.PatchChlLatitude = nan arcdeg 2024-08-07T20:26:59.734Z,1723062419.734 [MissionManager](INFO): DefineOutput sink:Science.PatchChlLongitude = nan arcdeg 2024-08-07T20:26:59.749Z,1723062419.749 [MissionManager](INFO): DefineOutput sink:Science.PatchChlDistance = nan m 2024-08-07T20:26:59.752Z,1723062419.752 [MissionManager](INFO): DefineOutput sink:Science.PatchSalt = nan psu 2024-08-07T20:26:59.768Z,1723062419.768 [MissionManager](INFO): DefineOutput sink:Science.PatchSaltDepth = nan m 2024-08-07T20:26:59.786Z,1723062419.786 [MissionManager](INFO): DefineOutput sink:Science.PatchSaltLatitude = nan arcdeg 2024-08-07T20:26:59.792Z,1723062419.792 [MissionManager](INFO): DefineOutput sink:Science.PatchSaltLongitude = nan arcdeg 2024-08-07T20:26:59.803Z,1723062419.803 [MissionManager](INFO): DefineOutput sink:Science.PatchSaltDistance = nan m 2024-08-07T20:26:59.807Z,1723062419.807 [MissionManager](INFO): DefineOutput sink:Science.PatchOil = nan psu 2024-08-07T20:26:59.831Z,1723062419.831 [MissionManager](INFO): DefineOutput sink:Science.PatchOilDepth = nan m 2024-08-07T20:26:59.836Z,1723062419.836 [MissionManager](INFO): DefineOutput sink:Science.PatchOilLatitude = nan arcdeg 2024-08-07T20:26:59.852Z,1723062419.852 [MissionManager](INFO): DefineOutput sink:Science.PatchOilLongitude = nan arcdeg 2024-08-07T20:26:59.864Z,1723062419.864 [DataOverHttps](INFO): Moved sent file to Logs/20240807T201821/Express0004.lzma.bak 2024-08-07T20:26:59.864Z,1723062419.864 [DataOverHttps](INFO): SBD MOMSN=21445950 2024-08-07T20:26:59.878Z,1723062419.878 [MissionManager](INFO): DefineOutput sink:Science.PatchOilDistance = nan m 2024-08-07T20:26:59.910Z,1723062419.910 [MissionManager](INFO): DefineOutput sink:Science.TriggeredOnChl = 0 bool 2024-08-07T20:26:59.925Z,1723062419.925 [MissionManager](INFO): DefineOutput sink:Science.ChlTriggering = nan ug/l 2024-08-07T20:27:00.036Z,1723062420.036 [sink:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2024-08-07T20:27:00.089Z,1723062420.089 [sink:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2024-08-07T20:27:00.116Z,1723062420.116 [sink:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2024-08-07T20:27:00.173Z,1723062420.173 [sink:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2024-08-07T20:27:00.216Z,1723062420.216 [sink:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2024-08-07T20:27:00.250Z,1723062420.250 [sink:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2024-08-07T20:27:00.285Z,1723062420.285 [sink:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2024-08-07T20:27:00.350Z,1723062420.350 [sink:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2024-08-07T20:27:00.402Z,1723062420.402 [sink:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Construct ValueDetect. 2024-08-07T20:27:00.430Z,1723062420.430 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl 2024-08-07T20:27:00.430Z,1723062420.430 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl 2024-08-07T20:27:00.432Z,1723062420.432 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl 2024-08-07T20:27:01.183Z,1723062421.183 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/NeedComms.tl translated into: 1 10 20 NaN 1 1000 7 30 0 last time_fix was: first GPS update timeout Comms timed out after minutes second GPS update timeout 2024-08-07T20:27:01.198Z,1723062421.198 [MissionManager](INFO): DefineArg sink:NeedComms.DiveInterval = 1.000000 h 2024-08-07T20:27:01.210Z,1723062421.210 [MissionManager](INFO): DefineArg sink:NeedComms.WaitForPitchUp = 10.000000 min 2024-08-07T20:27:01.216Z,1723062421.216 [MissionManager](INFO): DefineArg sink:NeedComms.SurfacePitch = 20.000000 arcdeg 2024-08-07T20:27:01.236Z,1723062421.236 [MissionManager](INFO): DefineArg sink:NeedComms.SurfaceDepthRate = nan m/s 2024-08-07T20:27:01.243Z,1723062421.243 [MissionManager](INFO): DefineArg sink:NeedComms.SurfaceSpeed = 1.000000 m/s 2024-08-07T20:27:01.270Z,1723062421.270 [MissionManager](INFO): DefineArg sink:NeedComms.SurfacingTimeout = 1000.000000 s 2024-08-07T20:27:01.276Z,1723062421.276 [MissionManager](INFO): DefineArg sink:NeedComms.GPSTimeout = 7.000000 min 2024-08-07T20:27:01.304Z,1723062421.304 [MissionManager](INFO): DefineArg sink:NeedComms.CommsTimeout = 30.000000 min 2024-08-07T20:27:01.324Z,1723062421.324 [sink:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-08-07T20:27:01.385Z,1723062421.385 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl 2024-08-07T20:27:01.385Z,1723062421.385 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl 2024-08-07T20:27:01.386Z,1723062421.386 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl 2024-08-07T20:27:01.679Z,1723062421.679 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into: 5 0 200 2000 2024-08-07T20:27:01.707Z,1723062421.707 [MissionManager](INFO): DefineArg sink:StandardEnvelopes.MinAltitude = 5.000000 m 2024-08-07T20:27:01.711Z,1723062421.711 [MissionManager](INFO): DefineArg sink:StandardEnvelopes.MaxDepthIgnore = 0.000000 m 2024-08-07T20:27:01.716Z,1723062421.716 [MissionManager](INFO): DefineArg sink:StandardEnvelopes.MaxDepth = 200.000000 m 2024-08-07T20:27:01.746Z,1723062421.746 [MissionManager](INFO): DefineArg sink:StandardEnvelopes.MinOffshore = 2000.000000 m 2024-08-07T20:27:01.747Z,1723062421.747 [sink:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2024-08-07T20:27:01.782Z,1723062421.782 [sink:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2024-08-07T20:27:01.841Z,1723062421.841 [sink:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2024-08-07T20:27:01.902Z,1723062421.902 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl 2024-08-07T20:27:01.903Z,1723062421.903 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl 2024-08-07T20:27:01.904Z,1723062421.904 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl 2024-08-07T20:27:02.008Z,1723062422.008 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into: 2024-08-07T20:27:02.024Z,1723062422.024 [MissionManager](INFO): DefineArg sink:BackseatDriver.EnableBackseat = 0 bool 2024-08-07T20:27:02.039Z,1723062422.039 [sink:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver. 2024-08-07T20:27:02.050Z,1723062422.050 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl 2024-08-07T20:27:02.050Z,1723062422.050 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl 2024-08-07T20:27:02.052Z,1723062422.052 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl 2024-08-07T20:27:02.453Z,1723062422.453 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into: 99999 2024-08-07T20:27:02.466Z,1723062422.466 [MissionManager](INFO): DefineArg sink:PowerOnly.SampleAll = 0 bool 2024-08-07T20:27:02.482Z,1723062422.482 [MissionManager](INFO): DefineArg sink:PowerOnly.SampleLoad1 = 0 bool 2024-08-07T20:27:02.494Z,1723062422.494 [MissionManager](INFO): DefineArg sink:PowerOnly.SampleLoad2 = 0 bool 2024-08-07T20:27:02.508Z,1723062422.508 [MissionManager](INFO): DefineArg sink:PowerOnly.SampleLoad3 = 0 bool 2024-08-07T20:27:02.535Z,1723062422.535 [MissionManager](INFO): DefineArg sink:PowerOnly.EnabledPowerOnly = 0 bool 2024-08-07T20:27:02.558Z,1723062422.558 [sink:PowerOnly:E.Wait](DEBUG): Construct Wait. 2024-08-07T20:27:02.569Z,1723062422.569 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BallastAndTrim.tl 2024-08-07T20:27:02.569Z,1723062422.569 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BallastAndTrim.tl 2024-08-07T20:27:02.571Z,1723062422.571 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BallastAndTrim.tl 2024-08-07T20:27:04.238Z,1723062424.238 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BallastAndTrim.tl translated into: 25 0 0.4 20 0 20 10 45 10 0.25 15 99.9 1 0.25 0.005 0.00075 0 Going to target depth. Speed set to 0 13 0 Waiting for while pumping down to neutral. Moving to Timed out trying to reach the target depth. Stopping mission at current depth of stop 0 Waiting for the vehicle to settle. Depth = gfscan Running ballast and trim. Depth = 2024-08-07T20:27:04.273Z,1723062424.273 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.InsertDepth = 25.000000 m 2024-08-07T20:27:04.276Z,1723062424.276 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.InsertApproachSpeed = 0.000000 m/s 2024-08-07T20:27:04.309Z,1723062424.309 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.InsertApproachDepthRate = 0.400000 m/s 2024-08-07T20:27:04.312Z,1723062424.312 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.InsertApproachPitchLimit = 20.000000 arcdeg 2024-08-07T20:27:04.324Z,1723062424.324 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.InsertApproachSettleTimePreDive = 0.000000 min 2024-08-07T20:27:04.331Z,1723062424.331 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.InsertApproachDepthTimeout = 20.000000 min 2024-08-07T20:27:04.366Z,1723062424.366 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.InsertSettleTime = 10.000000 min 2024-08-07T20:27:04.390Z,1723062424.390 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.EstTimeout = 45.000000 min 2024-08-07T20:27:04.392Z,1723062424.392 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.MinEstTime = 10.000000 min 2024-08-07T20:27:04.400Z,1723062424.400 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.MassEstErrorBound = 0.250000 mm 2024-08-07T20:27:04.421Z,1723062424.421 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.BuoyEstErrorBound = 15.000000 cc 2024-08-07T20:27:04.424Z,1723062424.424 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.EstConfidence = 99.900002 % 2024-08-07T20:27:04.435Z,1723062424.435 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.InsertDepthDeadband = 1.000000 m 2024-08-07T20:27:04.447Z,1723062424.447 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.InsertMassDeadband = 0.250000 mm 2024-08-07T20:27:04.539Z,1723062424.539 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Construct. 2024-08-07T20:27:04.550Z,1723062424.550 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Construct Buoyancy. 2024-08-07T20:27:04.562Z,1723062424.562 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Construct. 2024-08-07T20:27:04.599Z,1723062424.599 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Construct Wait. 2024-08-07T20:27:04.611Z,1723062424.611 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Construct. 2024-08-07T20:27:04.623Z,1723062424.623 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Construct. 2024-08-07T20:27:04.657Z,1723062424.657 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute. 2024-08-07T20:27:04.670Z,1723062424.670 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Construct. 2024-08-07T20:27:04.695Z,1723062424.695 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Construct. 2024-08-07T20:27:04.770Z,1723062424.770 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Construct Wait. 2024-08-07T20:27:04.771Z,1723062424.771 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:E.Execute](DEBUG): Construct Execute. 2024-08-07T20:27:04.798Z,1723062424.798 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](DEBUG): Construct BallastAndTrim. 2024-08-07T20:27:04.878Z,1723062424.878 [MissionManager](INFO): RedefineArg sink:RunSink:BallastAndTrim.InsertDepthDeadband = reader:sink.DepthDeadband 2024-08-07T20:27:04.880Z,1723062424.880 [MissionManager](INFO): RedefineArg sink:RunSink:BallastAndTrim.InsertApproachDepthTimeout = reader:sink.TargetDepthTimeout 2024-08-07T20:27:04.890Z,1723062424.890 [MissionManager](INFO): RedefineArg sink:RunSink:BallastAndTrim.InsertSettleTime = reader:sink.BTSettleTime 2024-08-07T20:27:04.892Z,1723062424.892 [MissionManager](INFO): RedefineArg sink:RunSink:BallastAndTrim.EstTimeout = reader:sink.EstimationTimeout 2024-08-07T20:27:04.902Z,1723062424.902 [MissionManager](INFO): RedefineArg sink:RunSink:BallastAndTrim.MinEstTime = reader:sink.MinEstimationTime 2024-08-07T20:27:04.904Z,1723062424.904 [MissionManager](INFO): RedefineArg sink:RunSink:BallastAndTrim.MassEstErrorBound = reader:sink.MassEstimationErrorBound 2024-08-07T20:27:04.926Z,1723062424.926 [MissionManager](INFO): RedefineArg sink:RunSink:BallastAndTrim.BuoyEstErrorBound = reader:sink.BuoyEstimationErrorBound 2024-08-07T20:27:04.928Z,1723062424.928 [MissionManager](INFO): RedefineArg sink:RunSink:BallastAndTrim.EstConfidence = reader:sink.EstimationConfidence 2024-08-07T20:27:05.012Z,1723062425.012 [sink:Float_Up:A.Buoyancy](DEBUG): Construct Buoyancy. 2024-08-07T20:27:05.058Z,1723062425.058 [sink:Float_Up:B.Wait](DEBUG): Construct Wait. 2024-08-07T20:27:05.186Z,1723062425.186 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission sink { arguments { MissionTimeout = 2 hour """ Maximum duration of mission. """ Depth = 20 meter """ Target depth """ DepthDeadband = 1 meter """ How much vertical drift from the specified depth is allowed during the mission. """ SurfaceThreshold = 2.5 meter """ Surface threshold depth, relevant only to the ascent. """ WaitToFloat = 20 minute """ Timeout for reaching surface threshold on float ascent, vehicle will drive up when reached """ TargetDepthTimeout = 20 minute """ Maximum wait time for the vehicle to reach the target depth. """ DoBallastAndTrim = true """ If true, run B&T, if false, just sit at depth. """ BTSettleTime = 10 minute """ If running B&T, how long to wait after reaching target depth before starting """ EstimationTimeout = 45 minute """ If running B&T, timeout for estimation. """ MinEstimationTime = 10 minute """ If running B&T, minimum time to run estimation before determining convergence (must be shorter than timeout). """ MassEstimationErrorBound = 0.5 millimeter """ If running B&T, desired range of the estimated sample mean from the true mean statistic (smaller values take longer to converge). """ BuoyEstimationErrorBound = 25 cubic_centimeter """ If running B&T, desired range of the estimated sample mean from the true mean statistic (smaller values take longer to converge). """ EstimationConfidence = 98 percent """ If running B&T, confidence level that the estimator has reached the desired error range (larger values take longer to converge). """ MinAltitude = 5 meter """ Minimum height above the sea floor for the entire mission. """ MaxDepth = 30 meter """ Maximum depth for the entire mission. """ MinOffshore = 2 kilometer """ Minimum offshore distance for the entire mission. """ } timeout duration=MissionTimeout insert Insert/Science.tl insert id="NeedComms" Insert/NeedComms.tl assign in sequence NeedComms:DiveInterval = MissionTimeout insert Insert/StandardEnvelopes.tl assign in sequence StandardEnvelopes:MinAltitude = MinAltitude assign in sequence StandardEnvelopes:MaxDepth = MaxDepth assign in sequence StandardEnvelopes:MinOffshore = MinOffshore insert Insert/BackseatDriver.tl insert Insert/PowerOnly.tl call id="StartingMission" refId="NeedComms" aggregate RunSink { run in sequence insert Insert/BallastAndTrim.tl { redefineArg InsertDepthDeadband = DepthDeadband redefineArg InsertApproachDepthTimeout = TargetDepthTimeout redefineArg InsertSettleTime = BTSettleTime redefineArg EstTimeout = EstimationTimeout redefineArg MinEstTime = MinEstimationTime redefineArg MassEstErrorBound = MassEstimationErrorBound redefineArg BuoyEstErrorBound = BuoyEstimationErrorBound redefineArg EstConfidence = EstimationConfidence } aggregate RunBallastAndTrim { run in sequence break if ( isNaN ( Depth ) or not DoBallastAndTrim ) assign in sequence BallastAndTrim:InsertDepth = Depth call refId="BallastAndTrim" } aggregate JustSink { run in sequence break if ( isNaN ( Depth ) or DoBallastAndTrim ) assign in sequence BallastAndTrim:InsertDepth = Depth # The B&T insert will never actually invoke the B&T behavior here. # This is a little bit of a hack to avoid duplicating the ApproachDepth etc. # logic the B&T insert uses. assign in sequence BTSettleTime = MissionTimeout call refId="BallastAndTrim" } } aggregate Float_Up { """ Float to surface instead of driving. """ run in sequence break if ( Universal:depth < SurfaceThreshold ) behavior Guidance:Buoyancy { run in parallel set position = Control:VerticalControl.buoyancyDefault } behavior Guidance:Wait { run in sequence set duration = WaitToFloat } } call id="EndingMission" refId="NeedComms" } 2024-08-07T20:27:05.186Z,1723062425.186 [CommandExec](IMPORTANT): Loaded ./Missions/Engineering/sink.tl id=sink 2024-08-07T20:27:26.412Z,1723062446.412 [CommandExec](IMPORTANT): got command set sink.MissionTimeout 7 minute 2024-08-07T20:27:26.413Z,1723062446.413 [CommandExec](IMPORTANT): got command set sink.Depth 1.25 meter 2024-08-07T20:27:26.414Z,1723062446.414 [CommandExec](IMPORTANT): got command set sink.DepthDeadband .5 meter 2024-08-07T20:27:26.415Z,1723062446.415 [CommandExec](IMPORTANT): got command set sink.DoBallastAndTrim 0 bool 2024-08-07T20:27:26.415Z,1723062446.415 [CommandExec](IMPORTANT): got command set sink.MinAltitude .0001 meter 2024-08-07T20:27:26.416Z,1723062446.416 [CommandExec](IMPORTANT): got command set sink.MaxDepth 5 meter 2024-08-07T20:27:26.417Z,1723062446.417 [CommandExec](IMPORTANT): got command set sink.MinOffshore .00001 kilometer 2024-08-07T20:27:26.417Z,1723062446.417 [CommandExec](IMPORTANT): got command run 2024-08-07T20:27:26.421Z,1723062446.421 [CommandExec](IMPORTANT): Running 2024-08-07T20:27:26.426Z,1723062446.426 [CommandExec](IMPORTANT): got command schedule "set OnDock.IfconfigInterval 3 min;run" 27cs0 2 2.000000 2024-08-07T20:27:26.427Z,1723062446.427 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=27cs0 2024-08-07T20:27:26.428Z,1723062446.428 [CommandExec](IMPORTANT): Scheduled #3 (#2 of 2 with id='27cs0'): "set OnDock.IfconfigInterval 3 min;run", AFTER MISSION 2024-08-07T20:27:26.586Z,1723062446.586 [Default] Stopped 2024-08-07T20:27:26.586Z,1723062446.586 [Default](DEBUG): Aggregate::uninitialize Default 2024-08-07T20:27:26.587Z,1723062446.587 [Default:B.GoToSurface] Stopped 2024-08-07T20:27:26.587Z,1723062446.587 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-08-07T20:27:26.587Z,1723062446.587 [Default:CheckIn] Stopped 2024-08-07T20:27:26.587Z,1723062446.587 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-08-07T20:27:26.587Z,1723062446.587 [Default:CheckIn:Read_Iridium] Stopped 2024-08-07T20:27:26.587Z,1723062446.587 [MissionManager](IMPORTANT): Started mission sink 2024-08-07T20:27:26.587Z,1723062446.587 [sink] Running Loop=1 2024-08-07T20:27:26.587Z,1723062446.587 [sink](DEBUG): Aggregate::initialize sink 2024-08-07T20:27:26.587Z,1723062446.587 [sink:Science] Running Loop=1 2024-08-07T20:27:26.588Z,1723062446.588 [sink:Science](DEBUG): Aggregate::initialize sink:Science 2024-08-07T20:27:26.588Z,1723062446.588 [sink:Science:A] Running Loop=1 2024-08-07T20:27:26.588Z,1723062446.588 [sink:Science:B] Running Loop=1 2024-08-07T20:27:26.588Z,1723062446.588 [sink:Science:C] Running Loop=1 2024-08-07T20:27:26.588Z,1723062446.588 [sink:Science:D] Running Loop=1 2024-08-07T20:27:26.588Z,1723062446.588 [sink:Science:E] Running Loop=1 2024-08-07T20:27:26.588Z,1723062446.588 [sink:Science:F] Running Loop=1 2024-08-07T20:27:26.588Z,1723062446.588 [sink:Science:Read_Oil] Running Loop=1 2024-08-07T20:27:26.588Z,1723062446.588 [sink:Science:PeakDetectChl] Running Loop=1 2024-08-07T20:27:26.588Z,1723062446.588 [sink:Science:PeakDetectChl](DEBUG): Aggregate::initialize sink:Science:PeakDetectChl 2024-08-07T20:27:26.588Z,1723062446.588 [sink:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1 2024-08-07T20:27:26.588Z,1723062446.588 [sink:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize. 2024-08-07T20:27:26.603Z,1723062446.603 [sink:Science:HighestChlPeakReport] Running Loop=1 2024-08-07T20:27:26.603Z,1723062446.603 [sink:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize sink:Science:HighestChlPeakReport 2024-08-07T20:27:26.603Z,1723062446.603 [sink:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1 2024-08-07T20:27:26.603Z,1723062446.603 [sink:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2024-08-07T20:27:26.604Z,1723062446.604 [sink:Science:PeakDetectNO3] Running Loop=1 2024-08-07T20:27:26.604Z,1723062446.604 [sink:Science:PeakDetectNO3](DEBUG): Aggregate::initialize sink:Science:PeakDetectNO3 2024-08-07T20:27:26.604Z,1723062446.604 [sink:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1 2024-08-07T20:27:26.604Z,1723062446.604 [sink:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize. 2024-08-07T20:27:26.614Z,1723062446.614 [sink:Science:PeakDetectOil] Running Loop=1 2024-08-07T20:27:26.614Z,1723062446.614 [sink:Science:PeakDetectOil](DEBUG): Aggregate::initialize sink:Science:PeakDetectOil 2024-08-07T20:27:26.614Z,1723062446.614 [sink:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1 2024-08-07T20:27:26.615Z,1723062446.615 [sink:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize. 2024-08-07T20:27:26.616Z,1723062446.616 [sink:Science:HighestOilPeakReport] Running Loop=1 2024-08-07T20:27:26.621Z,1723062446.621 [sink:Science:HighestOilPeakReport](DEBUG): Aggregate::initialize sink:Science:HighestOilPeakReport 2024-08-07T20:27:26.621Z,1723062446.621 [sink:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Running Loop=1 2024-08-07T20:27:26.621Z,1723062446.621 [sink:Science:HighestOilPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2024-08-07T20:27:26.622Z,1723062446.622 [sink:Science:PeakDetectFDOM] Running Loop=1 2024-08-07T20:27:26.622Z,1723062446.622 [sink:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize sink:Science:PeakDetectFDOM 2024-08-07T20:27:26.622Z,1723062446.622 [sink:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1 2024-08-07T20:27:26.622Z,1723062446.622 [sink:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize. 2024-08-07T20:27:26.624Z,1723062446.624 [sink:Science:PeakDetectSalinity] Running Loop=1 2024-08-07T20:27:26.624Z,1723062446.624 [sink:Science:PeakDetectSalinity](DEBUG): Aggregate::initialize sink:Science:PeakDetectSalinity 2024-08-07T20:27:26.624Z,1723062446.624 [sink:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Running Loop=1 2024-08-07T20:27:26.624Z,1723062446.624 [sink:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Initialize. 2024-08-07T20:27:26.634Z,1723062446.634 [sink:Science:HighestSaltPeakReport] Running Loop=1 2024-08-07T20:27:26.635Z,1723062446.635 [sink:Science:HighestSaltPeakReport](DEBUG): Aggregate::initialize sink:Science:HighestSaltPeakReport 2024-08-07T20:27:26.635Z,1723062446.635 [sink:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Running Loop=1 2024-08-07T20:27:26.635Z,1723062446.635 [sink:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2024-08-07T20:27:26.635Z,1723062446.635 [sink:Science:ChlThreshTrigger] Running Loop=1 2024-08-07T20:27:26.635Z,1723062446.635 [sink:Science:ChlThreshTrigger](DEBUG): Aggregate::initialize sink:Science:ChlThreshTrigger 2024-08-07T20:27:26.635Z,1723062446.635 [sink:Science:ChlThreshTrigger:A.ValueDetect] Running Loop=1 2024-08-07T20:27:26.636Z,1723062446.636 [sink:Science:ChlThreshTrigger:A.ValueDetect](INFO): Initialize. 2024-08-07T20:27:26.636Z,1723062446.636 [sink:Science:OceanCurrent] Running Loop=1 2024-08-07T20:27:26.636Z,1723062446.636 [sink:Science:OceanCurrent](DEBUG): Aggregate::initialize sink:Science:OceanCurrent 2024-08-07T20:27:26.636Z,1723062446.636 [sink:Science:OceanCurrent:A.] Running Loop=1 2024-08-07T20:27:26.636Z,1723062446.636 [sink:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator. 2024-08-07T20:27:26.636Z,1723062446.636 [sink:StandardEnvelopes] Running Loop=1 2024-08-07T20:27:26.636Z,1723062446.636 [sink:StandardEnvelopes](DEBUG): Aggregate::initialize sink:StandardEnvelopes 2024-08-07T20:27:26.654Z,1723062446.654 [sink:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2024-08-07T20:27:26.655Z,1723062446.655 [sink:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2024-08-07T20:27:26.655Z,1723062446.655 [sink:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2024-08-07T20:27:26.655Z,1723062446.655 [sink:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2024-08-07T20:27:26.655Z,1723062446.655 [sink:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2024-08-07T20:27:26.655Z,1723062446.655 [sink:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2024-08-07T20:27:26.655Z,1723062446.655 [sink:BackseatDriver] Running Loop=1 2024-08-07T20:27:26.655Z,1723062446.655 [sink:BackseatDriver](DEBUG): Aggregate::initialize sink:BackseatDriver 2024-08-07T20:27:26.656Z,1723062446.656 [sink:BackseatDriver:A.BackseatDriver] Running Loop=1 2024-08-07T20:27:26.656Z,1723062446.656 [sink:PowerOnly] Running Loop=1 2024-08-07T20:27:26.656Z,1723062446.656 [sink:PowerOnly](DEBUG): Aggregate::initialize sink:PowerOnly 2024-08-07T20:27:26.656Z,1723062446.656 [sink:PowerOnly:A] Running Loop=1 2024-08-07T20:27:26.656Z,1723062446.656 [sink:PowerOnly:B] Running Loop=1 2024-08-07T20:27:26.656Z,1723062446.656 [sink:PowerOnly:C] Running Loop=1 2024-08-07T20:27:26.656Z,1723062446.656 [sink:PowerOnly:D] Running Loop=1 2024-08-07T20:27:26.656Z,1723062446.656 [sink:PowerOnly:E.Wait] Running Loop=1 2024-08-07T20:27:26.656Z,1723062446.656 [sink:PowerOnly:E.Wait](DEBUG): Initialize Wait Component. 2024-08-07T20:27:26.656Z,1723062446.656 [sink:C] Running Loop=1 2024-08-07T20:27:26.661Z,1723062446.661 [sink:PowerOnly] Running Loop=1 2024-08-07T20:27:26.662Z,1723062446.662 [sink:PowerOnly:D] Stopped 2024-08-07T20:27:26.662Z,1723062446.662 [sink:PowerOnly:C] Stopped 2024-08-07T20:27:26.662Z,1723062446.662 [sink:PowerOnly:B] Stopped 2024-08-07T20:27:26.662Z,1723062446.662 [sink:PowerOnly:A] Stopped 2024-08-07T20:27:26.662Z,1723062446.662 [sink:BackseatDriver] Running Loop=1 2024-08-07T20:27:26.662Z,1723062446.662 [sink:StandardEnvelopes] Running Loop=1 2024-08-07T20:27:26.662Z,1723062446.662 [sink:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2024-08-07T20:27:26.663Z,1723062446.663 [sink:StandardEnvelopes:C.OffshoreEnvelope](IMPORTANT): Offshore envelope ACTIVE 2024-08-07T20:27:26.680Z,1723062446.680 [sink:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2024-08-07T20:27:26.685Z,1723062446.685 [sink:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2024-08-07T20:27:26.686Z,1723062446.686 [sink:C] Stopped 2024-08-07T20:27:26.686Z,1723062446.686 [sink:E] Running Loop=1 2024-08-07T20:27:26.686Z,1723062446.686 [sink:Science] Running Loop=1 2024-08-07T20:27:26.687Z,1723062446.687 [sink:Science:ChlThreshTrigger] Stopped 2024-08-07T20:27:26.687Z,1723062446.687 [sink:Science:ChlThreshTrigger](DEBUG): Aggregate::uninitialize sink:Science:ChlThreshTrigger 2024-08-07T20:27:26.687Z,1723062446.687 [sink:Science:ChlThreshTrigger:A.ValueDetect] Stopped 2024-08-07T20:27:26.687Z,1723062446.687 [sink:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Uninitialize. 2024-08-07T20:27:26.687Z,1723062446.687 [sink:Science:HighestSaltPeakReport] Stopped 2024-08-07T20:27:26.687Z,1723062446.687 [sink:Science:HighestSaltPeakReport](DEBUG): Aggregate::uninitialize sink:Science:HighestSaltPeakReport 2024-08-07T20:27:26.687Z,1723062446.687 [sink:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Stopped 2024-08-07T20:27:26.688Z,1723062446.688 [sink:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2024-08-07T20:27:26.688Z,1723062446.688 [sink:Science:PeakDetectSalinity] Stopped 2024-08-07T20:27:26.688Z,1723062446.688 [sink:Science:PeakDetectSalinity](DEBUG): Aggregate::uninitialize sink:Science:PeakDetectSalinity 2024-08-07T20:27:26.688Z,1723062446.688 [sink:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Stopped 2024-08-07T20:27:26.688Z,1723062446.688 [sink:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2024-08-07T20:27:26.688Z,1723062446.688 [sink:Science:PeakDetectFDOM] Stopped 2024-08-07T20:27:26.688Z,1723062446.688 [sink:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize sink:Science:PeakDetectFDOM 2024-08-07T20:27:26.688Z,1723062446.688 [sink:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped 2024-08-07T20:27:26.693Z,1723062446.693 [sink:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2024-08-07T20:27:26.693Z,1723062446.693 [sink:Science:HighestOilPeakReport] Stopped 2024-08-07T20:27:26.693Z,1723062446.693 [sink:Science:HighestOilPeakReport](DEBUG): Aggregate::uninitialize sink:Science:HighestOilPeakReport 2024-08-07T20:27:26.693Z,1723062446.693 [sink:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Stopped 2024-08-07T20:27:26.693Z,1723062446.693 [sink:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2024-08-07T20:27:26.693Z,1723062446.693 [sink:Science:PeakDetectOil] Stopped 2024-08-07T20:27:26.693Z,1723062446.693 [sink:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize sink:Science:PeakDetectOil 2024-08-07T20:27:26.693Z,1723062446.693 [sink:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped 2024-08-07T20:27:26.693Z,1723062446.693 [sink:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2024-08-07T20:27:26.693Z,1723062446.693 [sink:Science:PeakDetectNO3] Stopped 2024-08-07T20:27:26.694Z,1723062446.694 [sink:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize sink:Science:PeakDetectNO3 2024-08-07T20:27:26.694Z,1723062446.694 [sink:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped 2024-08-07T20:27:26.694Z,1723062446.694 [sink:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2024-08-07T20:27:26.694Z,1723062446.694 [sink:Science:HighestChlPeakReport] Stopped 2024-08-07T20:27:26.694Z,1723062446.694 [sink:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize sink:Science:HighestChlPeakReport 2024-08-07T20:27:26.694Z,1723062446.694 [sink:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped 2024-08-07T20:27:26.694Z,1723062446.694 [sink:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2024-08-07T20:27:26.694Z,1723062446.694 [sink:Science:PeakDetectChl] Stopped 2024-08-07T20:27:26.694Z,1723062446.694 [sink:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize sink:Science:PeakDetectChl 2024-08-07T20:27:26.694Z,1723062446.694 [sink:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped 2024-08-07T20:27:26.694Z,1723062446.694 [sink:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2024-08-07T20:27:26.694Z,1723062446.694 [sink:Science:OceanCurrent:A.] Running Loop=1 2024-08-07T20:27:26.696Z,1723062446.696 [sink:Science:Read_Oil] Stopped 2024-08-07T20:27:26.696Z,1723062446.696 [sink:Science:F] Running Loop=1 2024-08-07T20:27:26.701Z,1723062446.701 [sink:Science:F](DEBUG): Initialize ReadDataComponent to sense WetLabsUBAT.average_bioluminescence 2024-08-07T20:27:26.702Z,1723062446.702 [sink:Science:E] Running Loop=1 2024-08-07T20:27:26.702Z,1723062446.702 [sink:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water 2024-08-07T20:27:26.702Z,1723062446.702 [sink:Science:D] Stopped 2024-08-07T20:27:26.702Z,1723062446.702 [sink:Science:C] Running Loop=1 2024-08-07T20:27:26.703Z,1723062446.703 [sink:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature 2024-08-07T20:27:26.704Z,1723062446.704 [sink:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity 2024-08-07T20:27:26.704Z,1723062446.704 [sink:Science:B] Running Loop=1 2024-08-07T20:27:26.706Z,1723062446.706 [sink:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature 2024-08-07T20:27:26.707Z,1723062446.707 [sink:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity 2024-08-07T20:27:26.707Z,1723062446.707 [sink:Science:A] Stopped 2024-08-07T20:27:26.717Z,1723062446.717 [HorizontalControl](DEBUG): kpHeading = 0.400000 2024-08-07T20:27:26.717Z,1723062446.717 [HorizontalControl](DEBUG): kiHeading = 0.001000 2024-08-07T20:27:26.718Z,1723062446.718 [HorizontalControl](DEBUG): kdHeading = 0.050000 2024-08-07T20:27:27.071Z,1723062447.071 [sink:E] Stopped 2024-08-07T20:27:27.071Z,1723062447.071 [sink:F] Running Loop=1 2024-08-07T20:27:27.111Z,1723062447.111 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240807T201821/Courier0006.lzma 2024-08-07T20:27:27.184Z,1723062447.184 [WetLabsBB2FL](INFO): Powering up 2024-08-07T20:27:27.373Z,1723062447.373 [WetLabsUBAT](INFO): Powering up 2024-08-07T20:27:27.451Z,1723062447.451 [sink:F] Stopped 2024-08-07T20:27:27.451Z,1723062447.451 [sink:G] Running Loop=1 2024-08-07T20:27:27.819Z,1723062447.819 [sink:G] Stopped 2024-08-07T20:27:27.819Z,1723062447.819 [sink:StartingMission] Running Loop=1 2024-08-07T20:27:27.819Z,1723062447.819 [sink:StartingMission](DEBUG): Aggregate::initialize sink:StartingMission 2024-08-07T20:27:28.110Z,1723062448.110 [DataOverHttps](INFO): Moved sent file to Logs/20240807T201821/Courier0006.lzma.bak 2024-08-07T20:27:28.111Z,1723062448.111 [DataOverHttps](INFO): SBD MOMSN=21445953 2024-08-07T20:27:28.219Z,1723062448.219 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for bottom velocity. Device response is::BS, -4, +2,A 2024-08-07T20:27:28.232Z,1723062448.232 [sink:NeedComms] Running Loop=1 2024-08-07T20:27:28.232Z,1723062448.232 [sink:NeedComms](DEBUG): Aggregate::initialize sink:NeedComms 2024-08-07T20:27:28.233Z,1723062448.233 [sink:NeedComms:B.GoToSurface] Running Loop=1 2024-08-07T20:27:28.233Z,1723062448.233 [sink:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-08-07T20:27:28.233Z,1723062448.233 [sink:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2024-08-07T20:27:28.234Z,1723062448.234 [sink:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2024-08-07T20:27:28.234Z,1723062448.234 [sink:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2024-08-07T20:27:28.234Z,1723062448.234 [sink:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2024-08-07T20:27:28.235Z,1723062448.235 [sink:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-08-07T20:27:28.235Z,1723062448.235 [sink:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-08-07T20:27:28.235Z,1723062448.235 [sink:NeedComms:A] Running Loop=1 2024-08-07T20:27:28.236Z,1723062448.236 [sink:NeedComms:A](INFO): last time_fix was: 1723062360.000000 second since 1970/01/01T00:00:00Z 2024-08-07T20:27:28.238Z,1723062448.238 [sink:NeedComms:A] Stopped 2024-08-07T20:27:28.628Z,1723062448.628 [sink:NeedComms:C] Running Loop=1 2024-08-07T20:27:29.039Z,1723062449.039 [sink:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix 2024-08-07T20:27:29.998Z,1723062449.998 [CTD_Seabird](ERROR): Failed to parse device response: 2024-08-07T20:27:37.563Z,1723062457.563 [WetLabsUBAT](FAULT): UBAT flow rate is below the specified threshold of 0.05 l/s. 2024-08-07T20:27:39.249Z,1723062459.249 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:27:49.655Z,1723062469.655 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:27:59.833Z,1723062479.833 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:28:10.225Z,1723062490.225 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:28:20.689Z,1723062500.689 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:28:36.702Z,1723062516.702 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:28:46.975Z,1723062526.975 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:28:57.418Z,1723062537.418 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:29:08.590Z,1723062548.590 [CommandExec](IMPORTANT): got command report periodic 30.000000 platform_buoyancy_position 2024-08-07T20:29:08.966Z,1723062548.966 [Reporter](INFO): platform_buoyancy_position 996.478699 cc 2024-08-07T20:29:11.129Z,1723062551.129 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:29:21.317Z,1723062561.317 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:29:32.165Z,1723062572.165 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:29:33.336Z,1723062573.336 [CommandExec](IMPORTANT): got command set sink.MissionTimeout 20 minute 2024-08-07T20:29:39.311Z,1723062579.311 [Reporter](INFO): platform_buoyancy_position 996.478699 cc 2024-08-07T20:29:42.335Z,1723062582.335 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,24080712310135,35.0, +21.2, 07, 0 0.00, 112.20, 89.97, 90.49, 10WI,-32768,-32768,-32768,32768,-32768,-32768,V 2024-08-07T20:29:42.346Z,1723062582.346 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:29:45.757Z,1723062585.757 [WetLabsUBAT](ERROR): Failed to parse incomplete device message. 2024-08-07T20:29:52.705Z,1723062592.705 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:30:03.110Z,1723062603.110 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:30:09.639Z,1723062609.639 [Reporter](INFO): platform_buoyancy_position 996.478699 cc 2024-08-07T20:30:13.169Z,1723062613.169 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:30:23.521Z,1723062623.521 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:30:32.521Z,1723062632.521 [WetLabsUBAT](ERROR): Failed to parse incomplete device message. 2024-08-07T20:30:38.957Z,1723062638.957 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:30:39.719Z,1723062639.719 [Reporter](INFO): platform_buoyancy_position 996.478699 cc 2024-08-07T20:30:51.587Z,1723062651.587 [CommandExec](IMPORTANT): got command get platform_communications 2024-08-07T20:30:51.588Z,1723062651.588 [CommandExec](IMPORTANT): platform_communications 1 bool 2024-08-07T20:30:54.301Z,1723062654.301 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:30:59.431Z,1723062659.431 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for bottom velocity. Device response is::BS, +2, +7,A 2024-08-07T20:31:04.285Z,1723062664.285 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T20:31:04.287Z,1723062664.287 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-08-07T20:31:09.760Z,1723062669.760 [Reporter](INFO): platform_buoyancy_position 996.478699 cc 2024-08-07T20:31:09.916Z,1723062669.916 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203109.00,A,3648.16283,N,12147.28302,W,0.117,126.42,070824,,,D*70 2024-08-07T20:31:09.918Z,1723062669.918 [NAL9602](INFO): GPS fix at 20240807T203109: (36.802714, -121.788050) 2024-08-07T20:31:09.978Z,1723062669.978 [sink:NeedComms:C] Stopped 2024-08-07T20:31:09.979Z,1723062669.979 [sink:NeedComms:D] Running Loop=1 2024-08-07T20:31:10.447Z,1723062670.447 [sink:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-08-07T20:31:13.189Z,1723062673.189 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240807T201821/Courier0009.lzma 2024-08-07T20:31:14.191Z,1723062674.191 [DataOverHttps](INFO): Moved sent file to Logs/20240807T201821/Courier0009.lzma.bak 2024-08-07T20:31:14.191Z,1723062674.191 [DataOverHttps](INFO): SBD MOMSN=21445956 2024-08-07T20:31:33.789Z,1723062693.789 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240807T201821/Courier0012.lzma 2024-08-07T20:31:34.791Z,1723062694.791 [DataOverHttps](INFO): Moved sent file to Logs/20240807T201821/Courier0012.lzma.bak 2024-08-07T20:31:34.791Z,1723062694.791 [DataOverHttps](INFO): SBD MOMSN=21445958 2024-08-07T20:31:40.107Z,1723062700.107 [Reporter](INFO): platform_buoyancy_position 996.478699 cc 2024-08-07T20:31:42.271Z,1723062702.271 [NAL9602](INFO): Not Powering down - fast GPS 2024-08-07T20:31:54.070Z,1723062714.070 [DataOverHttps](INFO): Sending 365 bytes from file Logs/20240807T201821/Express0007.lzma 2024-08-07T20:31:55.073Z,1723062715.073 [DataOverHttps](INFO): Moved sent file to Logs/20240807T201821/Express0007.lzma.bak 2024-08-07T20:31:55.073Z,1723062715.073 [DataOverHttps](INFO): SBD MOMSN=21445961 2024-08-07T20:32:04.045Z,1723062724.045 [WetLabsUBAT](ERROR): Failed to parse incomplete device message. 2024-08-07T20:32:07.332Z,1723062727.332 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 0.00, 112.72, 96.69, 100.83 2024-08-07T20:32:10.427Z,1723062730.427 [Reporter](INFO): platform_buoyancy_position 996.478699 cc 2024-08-07T20:32:14.210Z,1723062734.210 [WetLabsUBAT](ERROR): Failed to parse incomplete device message. 2024-08-07T20:32:15.963Z,1723062735.963 [DataOverHttps](INFO): Sending 193 bytes from file Logs/20240807T201821/Express0010.lzma 2024-08-07T20:32:16.963Z,1723062736.963 [DataOverHttps](INFO): Moved sent file to Logs/20240807T201821/Express0010.lzma.bak 2024-08-07T20:32:16.963Z,1723062736.963 [DataOverHttps](INFO): SBD MOMSN=21445968 2024-08-07T20:32:17.257Z,1723062737.257 [WetLabsUBAT](ERROR): Failed to parse incomplete device message. 2024-08-07T20:32:18.277Z,1723062738.277 [WetLabsUBAT](ERROR): Failed to parse incomplete device message.