2025-04-15T20:54:21.259Z,1744750461.259 [OnDock:N.Docked](DEBUG): Initialize. 2025-04-15T20:54:21.259Z,1744750461.259 [OnDock:N.Docked](DEBUG): Initializing internal variables to default values. 2025-04-15T20:54:21.309Z,1744750461.309 [CommandExec](IMPORTANT): got command restart logs 2025-04-15T20:54:21.446Z,1744750461.446 [Reporter](INFO): platform_buoyancy_position 701.555969 cc 2025-04-15T20:54:21.688Z,1744750461.688 [OnDock:N.Docked](FAULT): Off dock, depth 0.030871 above surface threshold 2025-04-15T20:54:21.689Z,1744750461.689 [OnDock:N.Docked](IMPORTANT): State switched to DETACHED. Waiting for state timeout to act... 2025-04-15T20:54:21.690Z,1744750461.690 [OnDock:N.Docked](INFO): Found local IP address 10.89.10.2 for eth0 2025-04-15T20:54:21.690Z,1744750461.690 [OnDock:N.Docked](IMPORTANT): No IP address on dock subnet, restarting DHCP 2025-04-15T20:54:21.722Z,1744750461.722 [DAT](IMPORTANT): Requesting device enable set for address 0. 2025-04-15T20:54:21.837Z,1744750461.837 [DockingStepper](INFO): Resume 2025-04-15T20:54:21.844Z,1744750461.844 [Reporter](INFO): platform_buoyancy_position 706.793640 cc 2025-04-15T20:54:21.973Z,1744750461.973 [DAT](INFO): DAT read: user:7> 2025-04-15T20:54:21.974Z,1744750461.974 [DAT](INFO): DAT read: Tx time:20:54:21.1296 2025-04-15T20:54:21.974Z,1744750461.974 [DAT](INFO): Ping request sent. 2025-04-15T20:54:21.974Z,1744750461.974 [DAT](INFO): transmitted an acoustic signal 2025-04-15T20:54:21.975Z,1744750461.975 [DAT](INFO): publishing transmit ping time 2025-04-15T20:54:21.975Z,1744750461.975 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000989 2025-04-15T20:54:21.976Z,1744750461.976 [DAT](IMPORTANT): Requesting device enable set for address 0. 2025-04-15T20:54:22.225Z,1744750462.225 [DockingStepper](INFO): Resuming 2025-04-15T20:54:22.227Z,1744750462.227 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.252215 2025-04-15T20:54:22.227Z,1744750462.227 [DAT](IMPORTANT): Requesting device enable set for address 0. 2025-04-15T20:54:22.242Z,1744750462.242 [Reporter](INFO): platform_buoyancy_position 711.225586 cc 2025-04-15T20:54:22.477Z,1744750462.477 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502807 2025-04-15T20:54:22.533Z,1744750462.533 [DockingStepper](INFO): Start 2025-04-15T20:54:22.534Z,1744750462.534 [DockingStepper](DEBUG): Initializing EZServoServo. 2025-04-15T20:54:22.653Z,1744750462.653 [DockingStepper](DEBUG): Initializing DockingStepper. 2025-04-15T20:54:22.659Z,1744750462.659 [Reporter](INFO): platform_buoyancy_position 715.388855 cc 2025-04-15T20:54:22.730Z,1744750462.730 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.755093 2025-04-15T20:54:22.981Z,1744750462.981 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006972 2025-04-15T20:54:23.233Z,1744750463.233 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258721 2025-04-15T20:54:23.485Z,1744750463.485 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510960 2025-04-15T20:54:23.737Z,1744750463.737 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762939 2025-04-15T20:54:23.989Z,1744750463.989 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014834 2025-04-15T20:54:24.242Z,1744750464.242 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.267205 2025-04-15T20:54:24.494Z,1744750464.494 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.519085 2025-04-15T20:54:24.745Z,1744750464.745 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770772 2025-04-15T20:54:24.998Z,1744750464.998 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.023143 2025-04-15T20:54:25.249Z,1744750465.249 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274935 2025-04-15T20:54:25.501Z,1744750465.501 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.526798 2025-04-15T20:54:25.754Z,1744750465.754 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.779047 2025-04-15T20:54:26.005Z,1744750466.005 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.030922 2025-04-15T20:54:26.257Z,1744750466.257 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.282825 2025-04-15T20:54:26.378Z,1744750466.378 [DockingStepper](INFO): Starting 2025-04-15T20:54:26.423Z,1744750466.423 [DockingStepper](INFO): Init string: /1z16367aE13561N3m50 2025-04-15T20:54:26.465Z,1744750466.465 [Reporter](INFO): platform_buoyancy_position 676.441895 cc 2025-04-15T20:54:26.510Z,1744750466.510 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.535096 2025-04-15T20:54:26.594Z,1744750466.593 [DockingStepper](INFO): Starting 2025-04-15T20:54:26.599Z,1744750466.599 [Reporter](INFO): platform_buoyancy_position 672.278564 cc 2025-04-15T20:54:26.761Z,1744750466.761 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.786873 2025-04-15T20:54:26.977Z,1744750466.977 [DockingStepper](INFO): Starting 2025-04-15T20:54:26.983Z,1744750466.983 [Reporter](INFO): platform_buoyancy_position 668.115234 cc 2025-04-15T20:54:27.013Z,1744750467.013 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.038814 2025-04-15T20:54:27.265Z,1744750467.265 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.290827 2025-04-15T20:54:27.378Z,1744750467.378 [DockingStepper](INFO): Starting 2025-04-15T20:54:27.383Z,1744750467.383 [Reporter](INFO): platform_buoyancy_position 661.534546 cc 2025-04-15T20:54:27.517Z,1744750467.517 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.542865 2025-04-15T20:54:27.769Z,1744750467.769 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.794797 2025-04-15T20:54:27.841Z,1744750467.841 [DockingStepper](INFO): Starting 2025-04-15T20:54:27.848Z,1744750467.848 [Reporter](INFO): platform_buoyancy_position 657.236938 cc 2025-04-15T20:54:28.022Z,1744750468.022 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.047087 2025-04-15T20:54:28.274Z,1744750468.274 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.299092 2025-04-15T20:54:28.525Z,1744750468.525 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.550886 2025-04-15T20:54:28.777Z,1744750468.777 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.802887 2025-04-15T20:54:29.029Z,1744750469.029 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.054914 2025-04-15T20:54:29.281Z,1744750469.281 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.306851 2025-04-15T20:54:29.534Z,1744750469.534 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.559036 2025-04-15T20:54:29.786Z,1744750469.786 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.811008 2025-04-15T20:54:30.037Z,1744750470.037 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.062906 2025-04-15T20:54:30.290Z,1744750470.290 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.315070 2025-04-15T20:54:30.541Z,1744750470.541 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.566904 2025-04-15T20:54:30.793Z,1744750470.793 [DAT](INFO): DAT read: Response Not Received 2025-04-15T20:54:30.794Z,1744750470.794 [DAT](INFO): response not received 2025-04-15T20:54:30.794Z,1744750470.794 [DAT](INFO): DAT read: user:8> 2025-04-15T20:54:30.795Z,1744750470.795 [DAT](INFO): DAT read: Tx time:20:54:30.0797 2025-04-15T20:54:30.795Z,1744750470.795 [DAT](INFO): Ping request sent. 2025-04-15T20:54:30.795Z,1744750470.795 [DAT](INFO): transmitted an acoustic signal 2025-04-15T20:54:30.795Z,1744750470.795 [DAT](INFO): publishing transmit ping time 2025-04-15T20:54:30.796Z,1744750470.796 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000719 2025-04-15T20:54:31.046Z,1744750471.046 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250441 2025-04-15T20:54:31.297Z,1744750471.297 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502168 2025-04-15T20:54:31.549Z,1744750471.549 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.753996 2025-04-15T20:54:31.737Z,1744750471.737 [DockingStepper](INFO): Starting 2025-04-15T20:54:31.761Z,1744750471.761 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2025-04-15T20:54:31.767Z,1744750471.767 [Reporter](INFO): platform_buoyancy_position 605.800049 cc 2025-04-15T20:54:31.801Z,1744750471.801 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006265 2025-04-15T20:54:31.873Z,1744750471.873 [DockingStepper](INFO): Starting 2025-04-15T20:54:31.879Z,1744750471.879 [Reporter](INFO): platform_buoyancy_position 603.516968 cc 2025-04-15T20:54:32.053Z,1744750472.053 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258129 2025-04-15T20:54:32.246Z,1744750472.246 [DockingStepper](INFO): Changing to mode: 1 2025-04-15T20:54:32.246Z,1744750472.246 [DockingStepper](INFO): Standby mode. 2025-04-15T20:54:32.305Z,1744750472.305 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510041 2025-04-15T20:54:32.308Z,1744750472.308 [Reporter](INFO): platform_buoyancy_position 599.219360 cc 2025-04-15T20:54:32.557Z,1744750472.557 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762080 2025-04-15T20:54:32.762Z,1744750472.762 [Reporter](INFO): platform_buoyancy_position 592.907288 cc 2025-04-15T20:54:32.809Z,1744750472.809 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014146 2025-04-15T20:54:32.967Z,1744750472.967 [CommandExec](IMPORTANT): got command ! /mnt/mmc/LRAUV/Tools/dhcp.sh 2025-04-15T20:54:33.062Z,1744750473.062 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266612 2025-04-15T20:54:33.167Z,1744750473.167 [Reporter](INFO): platform_buoyancy_position 587.669556 cc 2025-04-15T20:54:33.314Z,1744750473.314 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518615 2025-04-15T20:54:33.565Z,1744750473.565 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770265 2025-04-15T20:54:33.817Z,1744750473.817 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022163 2025-04-15T20:54:34.019Z,1744750474.019 [CommandExec](IMPORTANT): /mnt/mmc/started/2025-04-15UTC205433_sh 2025-04-15T20:54:34.070Z,1744750474.070 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274299 2025-04-15T20:54:34.322Z,1744750474.322 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.526287 2025-04-15T20:54:34.575Z,1744750474.575 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.779696 2025-04-15T20:54:34.826Z,1744750474.826 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.030390 2025-04-15T20:54:35.077Z,1744750475.077 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.282120 2025-04-15T20:54:35.329Z,1744750475.329 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.534079 2025-04-15T20:54:35.582Z,1744750475.582 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.786414 2025-04-15T20:54:35.834Z,1744750475.834 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.038237 2025-04-15T20:54:36.085Z,1744750476.085 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.290072 2025-04-15T20:54:36.338Z,1744750476.338 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.542354 2025-04-15T20:54:36.591Z,1744750476.591 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.795799 2025-04-15T20:54:36.841Z,1744750476.841 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.045950 2025-04-15T20:54:37.094Z,1744750477.094 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.298490 2025-04-15T20:54:37.333Z,1744750477.333 [Reporter](INFO): platform_buoyancy_position 532.740845 cc 2025-04-15T20:54:37.345Z,1744750477.345 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.550188 2025-04-15T20:54:37.471Z,1744750477.471 [Reporter](INFO): platform_buoyancy_position 530.457764 cc 2025-04-15T20:54:37.600Z,1744750477.600 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.803897 2025-04-15T20:54:37.853Z,1744750477.853 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.058169 2025-04-15T20:54:37.912Z,1744750477.912 [Reporter](INFO): platform_buoyancy_position 524.279968 cc 2025-04-15T20:54:38.105Z,1744750478.105 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.310089 2025-04-15T20:54:38.291Z,1744750478.291 [Reporter](INFO): platform_buoyancy_position 519.982361 cc 2025-04-15T20:54:38.357Z,1744750478.357 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.562244 2025-04-15T20:54:38.612Z,1744750478.612 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.815882 2025-04-15T20:54:38.745Z,1744750478.745 [Reporter](INFO): platform_buoyancy_position 513.938843 cc 2025-04-15T20:54:38.861Z,1744750478.861 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.066146 2025-04-15T20:54:39.113Z,1744750479.113 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.317936 2025-04-15T20:54:39.369Z,1744750479.369 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.573978 2025-04-15T20:54:39.624Z,1744750479.624 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.824443 2025-04-15T20:54:39.870Z,1744750479.870 [DAT](INFO): DAT read: Response Not Received 2025-04-15T20:54:39.870Z,1744750479.870 [DAT](INFO): response not received 2025-04-15T20:54:39.870Z,1744750479.870 [DAT](INFO): DAT read: user:9> 2025-04-15T20:54:39.871Z,1744750479.871 [DAT](INFO): DAT read: Tx time:20:54:39.0297 2025-04-15T20:54:39.871Z,1744750479.871 [DAT](INFO): Ping request sent. 2025-04-15T20:54:39.871Z,1744750479.871 [DAT](INFO): transmitted an acoustic signal 2025-04-15T20:54:39.871Z,1744750479.871 [DAT](INFO): publishing transmit ping time 2025-04-15T20:54:39.872Z,1744750479.872 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000811 2025-04-15T20:54:40.123Z,1744750480.123 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251305 2025-04-15T20:54:40.373Z,1744750480.373 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502030 2025-04-15T20:54:40.628Z,1744750480.628 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.755948 2025-04-15T20:54:40.878Z,1744750480.878 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006257 2025-04-15T20:54:41.128Z,1744750481.128 [Reporter](INFO): platform_buoyancy_position 483.184143 cc 2025-04-15T20:54:41.129Z,1744750481.129 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258100 2025-04-15T20:54:41.287Z,1744750481.287 [Reporter](INFO): platform_buoyancy_position 481.035339 cc 2025-04-15T20:54:41.381Z,1744750481.381 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.509952 2025-04-15T20:54:41.637Z,1744750481.637 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.764883 2025-04-15T20:54:41.670Z,1744750481.670 [Reporter](INFO): platform_buoyancy_position 476.872070 cc 2025-04-15T20:54:41.885Z,1744750481.885 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.013977 2025-04-15T20:54:42.138Z,1744750482.138 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.267094 2025-04-15T20:54:42.227Z,1744750482.227 [Reporter](INFO): platform_buoyancy_position 470.425659 cc 2025-04-15T20:54:42.393Z,1744750482.393 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.520898 2025-04-15T20:54:42.644Z,1744750482.644 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.772000 2025-04-15T20:54:42.857Z,1744750482.857 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-15T20:54:42.893Z,1744750482.893 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.021971 2025-04-15T20:54:43.145Z,1744750483.145 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.273982 2025-04-15T20:54:43.398Z,1744750483.398 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.526213 2025-04-15T20:54:43.652Z,1744750483.652 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.779964 2025-04-15T20:54:43.862Z,1744750483.862 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-04-15T20:54:43.901Z,1744750483.901 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.030123 2025-04-15T20:54:44.153Z,1744750484.153 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.282105 2025-04-15T20:54:44.405Z,1744750484.405 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.534097 2025-04-15T20:54:44.658Z,1744750484.658 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.786268 2025-04-15T20:54:44.909Z,1744750484.909 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.038131 2025-04-15T20:54:45.161Z,1744750485.161 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.289895 2025-04-15T20:54:45.414Z,1744750485.414 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.542736 2025-04-15T20:54:45.668Z,1744750485.668 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.795952 2025-04-15T20:54:45.917Z,1744750485.917 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.045944 2025-04-15T20:54:46.169Z,1744750486.169 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.298029 2025-04-15T20:54:46.421Z,1744750486.421 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.550128 2025-04-15T20:54:46.675Z,1744750486.675 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.803681 2025-04-15T20:54:46.925Z,1744750486.925 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.054205 2025-04-15T20:54:47.177Z,1744750487.177 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.306155 2025-04-15T20:54:47.429Z,1744750487.429 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.558108 2025-04-15T20:54:47.681Z,1744750487.681 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.810123 2025-04-15T20:54:47.933Z,1744750487.933 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.062193 2025-04-15T20:54:48.186Z,1744750488.186 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.314241 2025-04-15T20:54:48.438Z,1744750488.438 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.566213 2025-04-15T20:54:48.689Z,1744750488.689 [DAT](INFO): DAT read: Response Not Received 2025-04-15T20:54:48.690Z,1744750488.690 [DAT](INFO): response not received 2025-04-15T20:54:48.690Z,1744750488.690 [DAT](ERROR): No response from remote modem. 2025-04-15T20:54:48.690Z,1744750488.690 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.819028 2025-04-15T20:54:48.941Z,1744750488.941 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.069953 2025-04-15T20:54:49.194Z,1744750489.194 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.322248 2025-04-15T20:54:49.445Z,1744750489.445 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.574032 2025-04-15T20:54:49.697Z,1744750489.697 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.825977 2025-04-15T20:55:14.353Z,1744750514.353 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-04-15T20:55:15.215Z,1744750515.215 [CommandExec](IMPORTANT): got command get platform_buoyancy_position 2025-04-15T20:55:15.215Z,1744750515.215 [CommandExec](IMPORTANT): platform_buoyancy_position 470.425659 cc 2025-04-15T20:55:24.749Z,1744750524.749 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003182 2025-04-15T20:55:33.914Z,1744750533.914 [CommandExec](IMPORTANT): got command get VerticalControl.depthCmd 2025-04-15T20:55:33.915Z,1744750533.915 [CommandExec](IMPORTANT): VerticalControl.depthCmd 0.750000 m 2025-04-15T20:55:38.128Z,1744750538.128 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20250415T203821/Courier0004.lzma 2025-04-15T20:55:39.130Z,1744750539.130 [DataOverHttps](INFO): Moved sent file to Logs/20250415T203821/Courier0004.lzma.bak 2025-04-15T20:55:39.130Z,1744750539.130 [DataOverHttps](INFO): SBD MOMSN=24667871 2025-04-15T20:55:56.021Z,1744750556.021 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-15T20:55:58.424Z,1744750558.424 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250415T203821/Courier0010.lzma 2025-04-15T20:55:59.426Z,1744750559.426 [DataOverHttps](INFO): Moved sent file to Logs/20250415T203821/Courier0010.lzma.bak 2025-04-15T20:55:59.426Z,1744750559.426 [DataOverHttps](INFO): SBD MOMSN=24667877 2025-04-15T20:56:17.094Z,1744750577.094 [OnDock:J.](INFO): *** querying acoustic contact *** 2025-04-15T20:56:17.201Z,1744750577.201 [DAT](DEBUG): Acoustic response timeout 2025-04-15T20:56:17.202Z,1744750577.202 [DAT](INFO): setting remote address to 0 2025-04-15T20:56:17.453Z,1744750577.453 [DAT](INFO): DAT read: user:10> 2025-04-15T20:56:17.454Z,1744750577.454 [DAT](INFO): DAT read: RemoteAddr | 0 2025-04-15T20:56:17.454Z,1744750577.454 [DAT](INFO): set remote address to 0 2025-04-15T20:56:17.455Z,1744750577.455 [DAT](DEBUG): Acoustic response timeout 2025-04-15T20:56:17.455Z,1744750577.455 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-04-15T20:56:17.705Z,1744750577.705 [DAT](INFO): DAT read: user:11> 2025-04-15T20:56:17.706Z,1744750577.706 [DAT](INFO): DAT read: Tx time:20:56:16.8816 2025-04-15T20:56:17.706Z,1744750577.706 [DAT](INFO): Ping request sent. 2025-04-15T20:56:17.707Z,1744750577.707 [DAT](INFO): transmitted an acoustic signal 2025-04-15T20:56:17.707Z,1744750577.707 [DAT](INFO): publishing transmit ping time 2025-04-15T20:56:17.707Z,1744750577.707 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000711 2025-04-15T20:56:17.957Z,1744750577.957 [DAT](INFO): DAT read: TxSync time:20:56:16.8808 2025-04-15T20:56:17.958Z,1744750577.958 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251592 2025-04-15T20:56:18.209Z,1744750578.209 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502649 2025-04-15T20:56:18.464Z,1744750578.464 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.757111 2025-04-15T20:56:18.714Z,1744750578.714 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.007312 2025-04-15T20:56:18.965Z,1744750578.965 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258615 2025-04-15T20:56:19.224Z,1744750579.224 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.517092 2025-04-15T20:56:19.478Z,1744750579.478 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.770960 2025-04-15T20:56:19.729Z,1744750579.729 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.022884 2025-04-15T20:56:19.981Z,1744750579.981 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.274863 2025-04-15T20:56:20.233Z,1744750580.233 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.526685 2025-04-15T20:56:20.485Z,1744750580.485 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.778684 2025-04-15T20:56:20.738Z,1744750580.738 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.030983 2025-04-15T20:56:20.990Z,1744750580.990 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.283153 2025-04-15T20:56:21.241Z,1744750581.241 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.534899 2025-04-15T20:56:21.493Z,1744750581.493 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.786624 2025-04-15T20:56:21.745Z,1744750581.745 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.038696 2025-04-15T20:56:21.997Z,1744750581.997 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.290602 2025-04-15T20:56:22.249Z,1744750582.249 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.542691 2025-04-15T20:56:22.501Z,1744750582.501 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.794715 2025-04-15T20:56:22.753Z,1744750582.753 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.046665 2025-04-15T20:56:23.006Z,1744750583.006 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.298910 2025-04-15T20:56:23.258Z,1744750583.258 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.551120 2025-04-15T20:56:23.509Z,1744750583.509 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.802642 2025-04-15T20:56:23.761Z,1744750583.761 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.054887 2025-04-15T20:56:24.013Z,1744750584.013 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.306747 2025-04-15T20:56:24.265Z,1744750584.265 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.558636 2025-04-15T20:56:24.517Z,1744750584.517 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.810671 2025-04-15T20:56:24.769Z,1744750584.769 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.062715 2025-04-15T20:56:25.021Z,1744750585.021 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.314660 2025-04-15T20:56:25.274Z,1744750585.274 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.567457 2025-04-15T20:56:25.525Z,1744750585.525 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.818796 2025-04-15T20:56:25.777Z,1744750585.777 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.070647 2025-04-15T20:56:26.029Z,1744750586.029 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.322769 2025-04-15T20:56:26.281Z,1744750586.281 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.574738 2025-04-15T20:56:26.533Z,1744750586.533 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.826647 2025-04-15T20:56:26.785Z,1744750586.785 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.078687 2025-04-15T20:56:27.038Z,1744750587.038 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.331093 2025-04-15T20:56:27.289Z,1744750587.289 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.582717 2025-04-15T20:56:27.544Z,1744750587.544 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.837556 2025-04-15T20:56:27.793Z,1744750587.793 [DAT](INFO): Reached modem response timeout 2025-04-15T20:56:28.553Z,1744750588.553 [DAT](INFO): DAT read: Response Not Received 2025-04-15T20:56:28.553Z,1744750588.553 [DAT](INFO): response not received 2025-04-15T20:56:28.553Z,1744750588.553 [DAT](ERROR): No response from remote modem. 2025-04-15T20:56:32.737Z,1744750592.737 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-15T20:56:38.148Z,1744750598.148 [OnDock:N.Docked](FAULT): Off dock, range timeout exceeded 2025-04-15T20:56:38.148Z,1744750598.148 [OnDock:N.Docked](IMPORTANT): State switched to DETACHED. Waiting for state timeout to act... 2025-04-15T20:56:59.849Z,1744750619.849 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.423401 2025-04-15T20:57:30.973Z,1744750650.973 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-15T20:57:31.977Z,1744750651.977 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-04-15T20:58:02.097Z,1744750682.097 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-15T20:58:03.101Z,1744750683.101 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-04-15T20:58:20.387Z,1744750700.387 [OnDock:J.](INFO): *** querying acoustic contact *** 2025-04-15T20:58:20.549Z,1744750700.549 [DAT](DEBUG): Acoustic response timeout 2025-04-15T20:58:20.550Z,1744750700.550 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-04-15T20:58:20.801Z,1744750700.801 [DAT](INFO): DAT read: user:12> 2025-04-15T20:58:20.802Z,1744750700.802 [DAT](INFO): DAT read: Tx time:20:58:19.9826 2025-04-15T20:58:20.802Z,1744750700.802 [DAT](INFO): Ping request sent. 2025-04-15T20:58:20.802Z,1744750700.802 [DAT](INFO): transmitted an acoustic signal 2025-04-15T20:58:20.802Z,1744750700.802 [DAT](INFO): publishing transmit ping time 2025-04-15T20:58:20.803Z,1744750700.803 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000729 2025-04-15T20:58:21.053Z,1744750701.053 [DAT](INFO): DAT read: TxSync time:20:58:19.9818 2025-04-15T20:58:21.054Z,1744750701.054 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.252131 2025-04-15T20:58:21.306Z,1744750701.306 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.503149 2025-04-15T20:58:21.557Z,1744750701.557 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754861 2025-04-15T20:58:21.809Z,1744750701.809 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006952 2025-04-15T20:58:22.061Z,1744750702.061 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258918 2025-04-15T20:58:22.313Z,1744750702.313 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.511017 2025-04-15T20:58:22.565Z,1744750702.565 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.763071 2025-04-15T20:58:22.817Z,1744750702.817 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014978 2025-04-15T20:58:23.069Z,1744750703.069 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266872 2025-04-15T20:58:23.321Z,1744750703.321 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518885 2025-04-15T20:58:23.573Z,1744750703.573 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.771070 2025-04-15T20:58:23.825Z,1744750703.825 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.023000 2025-04-15T20:58:24.077Z,1744750704.077 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.275099 2025-04-15T20:58:24.329Z,1744750704.329 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.527046 2025-04-15T20:58:24.581Z,1744750704.581 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.778915 2025-04-15T20:58:24.833Z,1744750704.833 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.031071 2025-04-15T20:58:25.085Z,1744750705.085 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.282971 2025-04-15T20:58:25.338Z,1744750705.338 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.535287 2025-04-15T20:58:25.590Z,1744750705.590 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.787854 2025-04-15T20:58:25.841Z,1744750705.841 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.038959 2025-04-15T20:58:26.094Z,1744750706.094 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.291476 2025-04-15T20:58:26.345Z,1744750706.345 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.542876 2025-04-15T20:58:26.597Z,1744750706.597 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.795148 2025-04-15T20:58:26.849Z,1744750706.849 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.046989 2025-04-15T20:58:27.101Z,1744750707.101 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.298879 2025-04-15T20:58:27.353Z,1744750707.353 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.551117 2025-04-15T20:58:27.605Z,1744750707.605 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.803061 2025-04-15T20:58:27.857Z,1744750707.857 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.055117 2025-04-15T20:58:28.109Z,1744750708.109 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.307113 2025-04-15T20:58:28.361Z,1744750708.361 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.559028 2025-04-15T20:58:28.614Z,1744750708.614 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.811185 2025-04-15T20:58:28.865Z,1744750708.865 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.063037 2025-04-15T20:58:29.117Z,1744750709.117 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.315037 2025-04-15T20:58:29.370Z,1744750709.370 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.567171 2025-04-15T20:58:29.622Z,1744750709.622 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.819403 2025-04-15T20:58:29.873Z,1744750709.873 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.070865 2025-04-15T20:58:30.126Z,1744750710.126 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.323333 2025-04-15T20:58:30.377Z,1744750710.377 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.575098 2025-04-15T20:58:30.629Z,1744750710.629 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.827046 2025-04-15T20:58:30.881Z,1744750710.881 [DAT](INFO): Reached modem response timeout 2025-04-15T20:58:31.638Z,1744750711.638 [DAT](INFO): DAT read: Response Not Received 2025-04-15T20:58:31.638Z,1744750711.638 [DAT](INFO): response not received 2025-04-15T20:58:31.638Z,1744750711.638 [DAT](ERROR): No response from remote modem. 2025-04-15T20:58:33.221Z,1744750713.221 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-15T20:58:34.225Z,1744750714.225 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-04-15T20:59:04.345Z,1744750744.345 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-15T20:59:05.349Z,1744750745.349 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-04-15T20:59:35.489Z,1744750775.489 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-15T20:59:36.493Z,1744750776.493 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-04-15T21:00:06.613Z,1744750806.613 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-15T21:00:07.617Z,1744750807.617 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-04-15T21:00:22.366Z,1744750822.366 [OnDock:J.](INFO): *** querying acoustic contact *** 2025-04-15T21:00:22.597Z,1744750822.597 [DAT](DEBUG): Acoustic response timeout 2025-04-15T21:00:22.597Z,1744750822.597 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-04-15T21:00:22.850Z,1744750822.850 [DAT](INFO): DAT read: user:13> 2025-04-15T21:00:22.850Z,1744750822.850 [DAT](INFO): DAT read: Tx time:21:00:21.9836 2025-04-15T21:00:22.851Z,1744750822.851 [DAT](INFO): Ping request sent. 2025-04-15T21:00:22.851Z,1744750822.851 [DAT](INFO): transmitted an acoustic signal 2025-04-15T21:00:22.851Z,1744750822.851 [DAT](INFO): DAT read: TxSync time:21:00:21.9829 2025-04-15T21:00:22.852Z,1744750822.852 [DAT](INFO): publishing transmit ping time 2025-04-15T21:00:22.852Z,1744750822.852 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.001609 2025-04-15T21:00:23.101Z,1744750823.101 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250614 2025-04-15T21:00:23.353Z,1744750823.353 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502569 2025-04-15T21:00:23.606Z,1744750823.606 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754777 2025-04-15T21:00:23.857Z,1744750823.857 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006610 2025-04-15T21:00:24.109Z,1744750824.109 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258533 2025-04-15T21:00:24.362Z,1744750824.362 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510828 2025-04-15T21:00:24.613Z,1744750824.613 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762653 2025-04-15T21:00:24.865Z,1744750824.865 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014583 2025-04-15T21:00:25.118Z,1744750825.118 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266833 2025-04-15T21:00:25.369Z,1744750825.369 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518570 2025-04-15T21:00:25.621Z,1744750825.621 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770402 2025-04-15T21:00:25.873Z,1744750825.873 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022669 2025-04-15T21:00:26.126Z,1744750826.126 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.275297 2025-04-15T21:00:26.377Z,1744750826.377 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.526439 2025-04-15T21:00:26.545Z,1744750826.545 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003187 2025-04-15T21:00:26.630Z,1744750826.630 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.779514 2025-04-15T21:00:26.881Z,1744750826.881 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.030548 2025-04-15T21:00:27.133Z,1744750827.133 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.282381 2025-04-15T21:00:27.386Z,1744750827.386 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.534794 2025-04-15T21:00:27.637Z,1744750827.637 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.786657 2025-04-15T21:00:27.889Z,1744750827.889 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.038530 2025-04-15T21:00:28.141Z,1744750828.141 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.290610 2025-04-15T21:00:28.394Z,1744750828.394 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.542699 2025-04-15T21:00:28.645Z,1744750828.645 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.794615 2025-04-15T21:00:28.898Z,1744750828.898 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.046735 2025-04-15T21:00:29.149Z,1744750829.149 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.298590 2025-04-15T21:00:29.401Z,1744750829.401 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.550343 2025-04-15T21:00:29.654Z,1744750829.654 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.803254 2025-04-15T21:00:29.905Z,1744750829.905 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.054672 2025-04-15T21:00:30.157Z,1744750830.157 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.306384 2025-04-15T21:00:30.409Z,1744750830.409 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.558511 2025-04-15T21:00:30.662Z,1744750830.662 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.810906 2025-04-15T21:00:30.913Z,1744750830.913 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.062440 2025-04-15T21:00:31.165Z,1744750831.165 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.314421 2025-04-15T21:00:31.417Z,1744750831.417 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.566621 2025-04-15T21:00:31.669Z,1744750831.669 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.818431 2025-04-15T21:00:31.925Z,1744750831.925 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.073900 2025-04-15T21:00:32.174Z,1744750832.174 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.322805 2025-04-15T21:00:32.425Z,1744750832.425 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.574587 2025-04-15T21:00:32.678Z,1744750832.678 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.826780 2025-04-15T21:00:32.929Z,1744750832.929 [DAT](INFO): Reached modem response timeout 2025-04-15T21:00:33.685Z,1744750833.685 [DAT](INFO): DAT read: Response Not Received 2025-04-15T21:00:33.686Z,1744750833.686 [DAT](INFO): response not received 2025-04-15T21:00:33.686Z,1744750833.686 [DAT](ERROR): No response from remote modem. 2025-04-15T21:00:38.996Z,1744750838.996 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250415T205421/Courier0000.lzma 2025-04-15T21:00:39.999Z,1744750839.999 [DataOverHttps](INFO): Moved sent file to Logs/20250415T205421/Courier0000.lzma.bak 2025-04-15T21:00:39.999Z,1744750839.999 [DataOverHttps](INFO): SBD MOMSN=24667882 2025-04-15T21:00:57.269Z,1744750857.269 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-15T21:01:01.867Z,1744750861.867 [DataOverHttps](INFO): Sending 575 bytes from file Logs/20250415T203821/Express0006.lzma 2025-04-15T21:01:02.866Z,1744750862.866 [DataOverHttps](INFO): Moved sent file to Logs/20250415T203821/Express0006.lzma.bak 2025-04-15T21:01:02.866Z,1744750862.866 [DataOverHttps](INFO): SBD MOMSN=24667885 2025-04-15T21:01:27.121Z,1744750887.121 [DataOverHttps](INFO): Sending 551 bytes from file Logs/20250415T203821/Express0011.lzma 2025-04-15T21:01:28.122Z,1744750888.122 [DataOverHttps](INFO): Moved sent file to Logs/20250415T203821/Express0011.lzma.bak 2025-04-15T21:01:28.123Z,1744750888.123 [DataOverHttps](INFO): SBD MOMSN=24667909 2025-04-15T21:01:50.697Z,1744750910.697 [DataOverHttps](INFO): Sending 628 bytes from file Logs/20250415T205421/Express0001.lzma 2025-04-15T21:01:51.698Z,1744750911.698 [DataOverHttps](INFO): Moved sent file to Logs/20250415T205421/Express0001.lzma.bak 2025-04-15T21:01:51.698Z,1744750911.698 [DataOverHttps](INFO): SBD MOMSN=24667915 2025-04-15T21:01:57.914Z,1744750917.914 [OnDock:dataRead:A] Stopped 2025-04-15T21:01:57.914Z,1744750917.914 [OnDock:dataRead:B.Wait] Running Loop=1 2025-04-15T21:01:57.914Z,1744750917.914 [OnDock:dataRead:B.Wait](DEBUG): Initialize Wait Component. 2025-04-15T21:01:58.798Z,1744750918.798 [DockingStepper](ERROR): only read 3 of 4 ADC values. Response is: ÿ/0`351,3328,16319 2025-04-15T21:01:58.798Z,1744750918.798 [DockingStepper](FAULT): Docking arm not reporting position 2025-04-15T21:01:58.798Z,1744750918.798 [DockingStepper] Communications Fault, FailCount= 1 2025-04-15T21:01:58.798Z,1744750918.798 [DockingStepper](ERROR): Communications Fault 2025-04-15T21:01:58.807Z,1744750918.807 [CBIT](ERROR): Communications Fault in component: DockingStepper 2025-04-15T21:01:59.188Z,1744750919.188 [DockingStepper](INFO): Stop 2025-04-15T21:01:59.188Z,1744750919.188 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2025-04-15T21:01:59.188Z,1744750919.188 [DockingStepper](INFO): Powering down 2025-04-15T21:01:59.560Z,1744750919.560 [DockingStepper](INFO): Stopping 2025-04-15T21:01:59.953Z,1744750919.953 [DockingStepper](INFO): Stopping 2025-04-15T21:02:00.439Z,1744750920.439 [DockingStepper](INFO): Stopping 2025-04-15T21:02:00.749Z,1744750920.749 [DockingStepper](INFO): Stopping 2025-04-15T21:02:01.207Z,1744750921.207 [DockingStepper](INFO): Stopping 2025-04-15T21:02:01.632Z,1744750921.632 [DockingStepper](INFO): Stopping 2025-04-15T21:02:01.963Z,1744750921.963 [DockingStepper](INFO): Stopping 2025-04-15T21:02:02.401Z,1744750922.401 [DockingStepper](INFO): Stopping 2025-04-15T21:02:02.750Z,1744750922.750 [DockingStepper](INFO): Stopping 2025-04-15T21:02:03.186Z,1744750923.186 [DockingStepper](INFO): Stopping 2025-04-15T21:02:03.571Z,1744750923.571 [DockingStepper](INFO): Stopping 2025-04-15T21:02:03.989Z,1744750923.989 [DockingStepper](INFO): Stopping 2025-04-15T21:02:04.409Z,1744750924.409 [DockingStepper](INFO): Stopping 2025-04-15T21:02:04.773Z,1744750924.773 [DockingStepper](INFO): Stopping 2025-04-15T21:02:05.286Z,1744750925.286 [DockingStepper](INFO): Stopping 2025-04-15T21:02:05.665Z,1744750925.665 [DockingStepper](INFO): Stopping 2025-04-15T21:02:06.060Z,1744750926.060 [DockingStepper](INFO): Stopping 2025-04-15T21:02:06.545Z,1744750926.545 [DockingStepper](INFO): Stopping 2025-04-15T21:02:06.874Z,1744750926.874 [DockingStepper](INFO): Stopping 2025-04-15T21:02:07.333Z,1744750927.333 [DockingStepper](INFO): Stopping 2025-04-15T21:02:07.746Z,1744750927.746 [DockingStepper](INFO): Stopping 2025-04-15T21:02:08.086Z,1744750928.086 [DockingStepper](INFO): Stopping 2025-04-15T21:02:08.566Z,1744750928.566 [DockingStepper](INFO): Stopping 2025-04-15T21:02:08.920Z,1744750928.920 [DockingStepper](INFO): Stopping 2025-04-15T21:02:09.355Z,1744750929.355 [DockingStepper](INFO): Stopping 2025-04-15T21:02:09.695Z,1744750929.695 [DockingStepper](INFO): Stopping 2025-04-15T21:02:10.056Z,1744750930.056 [DockingStepper](INFO): Stopping 2025-04-15T21:02:10.556Z,1744750930.556 [DockingStepper](INFO): Stopping 2025-04-15T21:02:10.863Z,1744750930.863 [DockingStepper](INFO): Stopping 2025-04-15T21:02:11.334Z,1744750931.334 [DockingStepper](INFO): Stopping 2025-04-15T21:02:11.743Z,1744750931.743 [DockingStepper](INFO): Stopping 2025-04-15T21:02:12.114Z,1744750932.114 [DockingStepper](INFO): Stopping 2025-04-15T21:02:12.547Z,1744750932.547 [DockingStepper](INFO): Stopping 2025-04-15T21:02:12.892Z,1744750932.892 [DockingStepper](INFO): Stopping 2025-04-15T21:02:13.397Z,1744750933.397 [DockingStepper](INFO): Stopping 2025-04-15T21:02:13.823Z,1744750933.823 [DockingStepper](INFO): Stopping 2025-04-15T21:02:14.144Z,1744750934.144 [DockingStepper](INFO): Stopping 2025-04-15T21:02:14.566Z,1744750934.566 [DockingStepper](INFO): Stopping 2025-04-15T21:02:14.955Z,1744750934.955 [DockingStepper](INFO): Stopping 2025-04-15T21:02:15.422Z,1744750935.422 [DockingStepper](INFO): Stopping 2025-04-15T21:02:15.819Z,1744750935.819 [DockingStepper](INFO): Stopping 2025-04-15T21:02:16.194Z,1744750936.194 [DockingStepper](INFO): Stopping 2025-04-15T21:02:16.660Z,1744750936.660 [DockingStepper](INFO): Stopping 2025-04-15T21:02:17.036Z,1744750937.036 [DockingStepper](INFO): Stopping 2025-04-15T21:02:17.470Z,1744750937.470 [DockingStepper](INFO): Stopping 2025-04-15T21:02:17.955Z,1744750937.955 [DockingStepper](INFO): Stopping 2025-04-15T21:02:18.220Z,1744750938.220 [DockingStepper](INFO): Stopping 2025-04-15T21:02:18.707Z,1744750938.707 [DockingStepper](INFO): Stopping 2025-04-15T21:02:19.031Z,1744750939.031 [DockingStepper](INFO): Stopping 2025-04-15T21:02:19.499Z,1744750939.499 [DockingStepper](INFO): Stopping 2025-04-15T21:02:19.839Z,1744750939.839 [DockingStepper](INFO): Stopped 2025-04-15T21:02:19.879Z,1744750939.879 [CBIT](INFO): Clearing failed state for component DockingStepper 2025-04-15T21:02:19.879Z,1744750939.879 [DockingStepper] No Fault, FailCount= 1 2025-04-15T21:02:20.244Z,1744750940.244 [DockingStepper](INFO): Start 2025-04-15T21:02:20.245Z,1744750940.245 [DockingStepper](DEBUG): Initializing EZServoServo. 2025-04-15T21:02:20.245Z,1744750940.245 [DockingStepper](DEBUG): Initializing DockingStepper. 2025-04-15T21:02:20.624Z,1744750940.624 [DockingStepper](INFO): Starting 2025-04-15T21:02:20.995Z,1744750940.995 [DockingStepper](INFO): Starting 2025-04-15T21:02:21.413Z,1744750941.413 [DockingStepper](INFO): Starting 2025-04-15T21:02:21.492Z,1744750941.492 [DockingStepper](INFO): Init string: /1z16368aE13561N3m50 2025-04-15T21:02:21.806Z,1744750941.806 [DockingStepper](INFO): Starting 2025-04-15T21:02:22.200Z,1744750942.200 [DockingStepper](INFO): Starting 2025-04-15T21:02:22.617Z,1744750942.617 [OnDock:J.](INFO): *** querying acoustic contact *** 2025-04-15T21:02:22.623Z,1744750942.623 [DockingStepper](INFO): Starting 2025-04-15T21:02:22.729Z,1744750942.729 [DAT](DEBUG): Acoustic response timeout 2025-04-15T21:02:22.730Z,1744750942.730 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-04-15T21:02:22.981Z,1744750942.981 [DAT](INFO): DAT read: user:14> 2025-04-15T21:02:22.983Z,1744750942.983 [DAT](INFO): DAT read: Tx time:21:02:22.1346 2025-04-15T21:02:22.984Z,1744750942.984 [DAT](INFO): Ping request sent. 2025-04-15T21:02:22.985Z,1744750942.985 [DAT](INFO): transmitted an acoustic signal 2025-04-15T21:02:22.985Z,1744750942.985 [DAT](INFO): publishing transmit ping time 2025-04-15T21:02:22.987Z,1744750942.987 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.002159 2025-04-15T21:02:23.027Z,1744750943.027 [DockingStepper](INFO): Starting 2025-04-15T21:02:23.061Z,1744750943.061 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2025-04-15T21:02:23.234Z,1744750943.234 [DAT](INFO): DAT read: TxSync time:21:02:22.1338 2025-04-15T21:02:23.235Z,1744750943.235 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250153 2025-04-15T21:02:23.411Z,1744750943.411 [DockingStepper](INFO): Starting 2025-04-15T21:02:23.490Z,1744750943.490 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.504747 2025-04-15T21:02:23.737Z,1744750943.737 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.752989 2025-04-15T21:02:23.855Z,1744750943.855 [DockingStepper](INFO): Changing to mode: 1 2025-04-15T21:02:23.856Z,1744750943.856 [DockingStepper](INFO): Standby mode. 2025-04-15T21:02:23.989Z,1744750943.989 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.005056 2025-04-15T21:02:24.242Z,1744750944.242 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.256902 2025-04-15T21:02:24.496Z,1744750944.496 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.511301 2025-04-15T21:02:24.565Z,1744750944.565 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-04-15T21:02:24.745Z,1744750944.745 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.760788 2025-04-15T21:02:24.997Z,1744750944.997 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.012896 2025-04-15T21:02:25.252Z,1744750945.252 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.267456 2025-04-15T21:02:25.505Z,1744750945.505 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.519433 2025-04-15T21:02:25.753Z,1744750945.753 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.768880 2025-04-15T21:02:26.005Z,1744750946.005 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.020967 2025-04-15T21:02:26.258Z,1744750946.258 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.273231 2025-04-15T21:02:26.515Z,1744750946.515 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.530506 2025-04-15T21:02:26.761Z,1744750946.761 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.776895 2025-04-15T21:02:27.013Z,1744750947.013 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.028685 2025-04-15T21:02:27.265Z,1744750947.265 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.280889 2025-04-15T21:02:27.520Z,1744750947.520 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.535118 2025-04-15T21:02:27.769Z,1744750947.769 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.784877 2025-04-15T21:02:28.021Z,1744750948.021 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.036956 2025-04-15T21:02:28.274Z,1744750948.274 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.289350 2025-04-15T21:02:28.528Z,1744750948.528 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.542807 2025-04-15T21:02:28.778Z,1744750948.778 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.793249 2025-04-15T21:02:29.029Z,1744750949.029 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.044827 2025-04-15T21:02:29.281Z,1744750949.281 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.296870 2025-04-15T21:02:29.536Z,1744750949.536 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.550890 2025-04-15T21:02:29.785Z,1744750949.785 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.800785 2025-04-15T21:02:30.037Z,1744750950.037 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.052892 2025-04-15T21:02:30.290Z,1744750950.290 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.305204 2025-04-15T21:02:30.544Z,1744750950.544 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.558793 2025-04-15T21:02:30.793Z,1744750950.793 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.808877 2025-04-15T21:02:31.045Z,1744750951.045 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.060876 2025-04-15T21:02:31.297Z,1744750951.297 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.312871 2025-04-15T21:02:31.550Z,1744750951.550 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.566056 2025-04-15T21:02:31.801Z,1744750951.801 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.816823 2025-04-15T21:02:32.053Z,1744750952.053 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.068830 2025-04-15T21:02:32.306Z,1744750952.306 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.321246 2025-04-15T21:02:32.558Z,1744750952.558 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.573085 2025-04-15T21:02:32.809Z,1744750952.809 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.824878 2025-04-15T21:02:33.065Z,1744750953.065 [DAT](INFO): Reached modem response timeout 2025-04-15T21:02:33.733Z,1744750953.733 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-04-15T21:02:33.821Z,1744750953.821 [DAT](INFO): DAT read: Response Not Received 2025-04-15T21:02:33.822Z,1744750953.822 [DAT](INFO): response not received 2025-04-15T21:02:33.822Z,1744750953.822 [DAT](ERROR): No response from remote modem. 2025-04-15T21:02:43.889Z,1744750963.889 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-04-15T21:02:53.089Z,1744750973.089 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-04-15T21:03:02.161Z,1744750982.161 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-04-15T21:03:11.249Z,1744750991.249 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-04-15T21:03:20.209Z,1744751000.209 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-04-15T21:03:29.693Z,1744751009.693 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-04-15T21:03:38.817Z,1744751018.817 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-04-15T21:03:47.833Z,1744751027.833 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-04-15T21:03:56.062Z,1744751036.062 [DataOverHttps](IMPORTANT): SBD MTMSN=20250415T210355 2025-04-15T21:04:04.036Z,1744751044.036 [DataOverHttps](INFO): Received command: restart app 2025-04-15T21:04:04.045Z,1744751044.045 [CommandExec](IMPORTANT): got command restart application 2025-04-15T21:04:05.048Z,1744751045.048 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2025-04-15T21:04:05.049Z,1744751045.049 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-15T21:04:05.049Z,1744751045.049 [CommandExec](INFO): Uninitializing the command executive. 2025-04-15T21:04:05.049Z,1744751045.049 [CommandExec](INFO): Uninitializing the command scheduler. 2025-04-15T21:04:05.049Z,1744751045.049 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-04-15T21:04:05.220Z,1744751045.220 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2025-04-15T21:04:05.221Z,1744751045.221 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2025-04-15T21:04:05.221Z,1744751045.221 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-04-15T21:04:05.221Z,1744751045.221 [NavChartDb](INFO): Join timeout helper Thread ID is 7155 2025-04-15T21:04:05.307Z,1744751045.307 [MissionManager](INFO): MissionManager is completed. 2025-04-15T21:04:05.307Z,1744751045.307 [MissionManager](INFO): Uninitializing Mission OnDock 2025-04-15T21:04:05.307Z,1744751045.307 [OnDock] Stopped 2025-04-15T21:04:05.307Z,1744751045.307 [OnDock](DEBUG): Aggregate::uninitialize OnDock 2025-04-15T21:04:05.307Z,1744751045.307 [OnDock:StandardEnvelopes] Stopped 2025-04-15T21:04:05.307Z,1744751045.307 [OnDock:StandardEnvelopes](DEBUG): Aggregate::uninitialize OnDock:StandardEnvelopes 2025-04-15T21:04:05.307Z,1744751045.307 [OnDock:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2025-04-15T21:04:05.307Z,1744751045.307 [OnDock:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2025-04-15T21:04:05.307Z,1744751045.307 [OnDock:StandardEnvelopes:B.DepthEnvelope] Stopped 2025-04-15T21:04:05.307Z,1744751045.307 [OnDock:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2025-04-15T21:04:05.308Z,1744751045.308 [OnDock:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2025-04-15T21:04:05.308Z,1744751045.308 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2025-04-15T21:04:05.308Z,1744751045.308 [OnDock:BackseatDriver] Stopped 2025-04-15T21:04:05.308Z,1744751045.308 [OnDock:BackseatDriver](DEBUG): Aggregate::uninitialize OnDock:BackseatDriver 2025-04-15T21:04:05.308Z,1744751045.308 [OnDock:BackseatDriver:A.BackseatDriver] Stopped 2025-04-15T21:04:05.308Z,1744751045.308 [OnDock:PowerOnly] Stopped 2025-04-15T21:04:05.308Z,1744751045.308 [OnDock:PowerOnly](DEBUG): Aggregate::uninitialize OnDock:PowerOnly 2025-04-15T21:04:05.316Z,1744751045.316 [OnDock:PowerOnly:E.Wait] Stopped 2025-04-15T21:04:05.316Z,1744751045.316 [OnDock:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2025-04-15T21:04:05.317Z,1744751045.317 [OnDock:dataRead] Stopped 2025-04-15T21:04:05.317Z,1744751045.317 [OnDock:dataRead](DEBUG): Aggregate::uninitialize OnDock:dataRead 2025-04-15T21:04:05.317Z,1744751045.317 [OnDock:dataRead:B.Wait] Stopped 2025-04-15T21:04:05.317Z,1744751045.317 [OnDock:dataRead:B.Wait](DEBUG): Uninitialize Wait Component. 2025-04-15T21:04:05.317Z,1744751045.317 [OnDock:J.] Stopped 2025-04-15T21:04:05.317Z,1744751045.317 [OnDock:J.](DEBUG): Uninitializing TrackAcousticContact. 2025-04-15T21:04:05.317Z,1744751045.317 [OnDock:PeriodicCheck] Stopped 2025-04-15T21:04:05.317Z,1744751045.317 [OnDock:PeriodicCheck](DEBUG): Aggregate::uninitialize OnDock:PeriodicCheck 2025-04-15T21:04:05.317Z,1744751045.317 [OnDock:PeriodicCheck:CheckIP] Stopped 2025-04-15T21:04:05.317Z,1744751045.317 [OnDock:PeriodicCheck:CheckIP](DEBUG): Aggregate::uninitialize OnDock:PeriodicCheck:CheckIP 2025-04-15T21:04:05.317Z,1744751045.317 [OnDock:PeriodicCheck:CheckIP:B.Wait] Stopped 2025-04-15T21:04:05.317Z,1744751045.317 [OnDock:PeriodicCheck:CheckIP:B.Wait](DEBUG): Uninitialize Wait Component. 2025-04-15T21:04:05.318Z,1744751045.318 [OnDock:N.Docked] Stopped 2025-04-15T21:04:05.337Z,1744751045.337 [DAT](IMPORTANT): Requesting device enable clr for address 50. 2025-04-15T21:04:05.393Z,1744751045.393 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-15T21:04:05.393Z,1744751045.393 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-04-15T21:04:05.412Z,1744751045.412 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler 2025-04-15T21:04:05.413Z,1744751045.413 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2025-04-15T21:04:05.413Z,1744751045.413 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 7156 2025-04-15T21:04:05.589Z,1744751045.589 [DAT](INFO): DAT read: user:15> 2025-04-15T21:04:05.590Z,1744751045.590 [DAT](INFO): DAT read: Tx time:21:04:04.7345 2025-04-15T21:04:05.590Z,1744751045.590 [DAT](INFO): Ping request sent. 2025-04-15T21:04:05.591Z,1744751045.591 [DAT](INFO): transmitted an acoustic signal 2025-04-15T21:04:05.591Z,1744751045.591 [DAT](INFO): publishing transmit ping time 2025-04-15T21:04:05.591Z,1744751045.591 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000723 2025-04-15T21:04:05.592Z,1744751045.592 [DAT](IMPORTANT): Requesting device enable clr for address 50. 2025-04-15T21:04:05.665Z,1744751045.665 [MissionManager](IMPORTANT): Started mission DefaultWithUndock 2025-04-15T21:04:05.665Z,1744751045.665 [DefaultWithUndock] Running Loop=1 2025-04-15T21:04:05.666Z,1744751045.666 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock 2025-04-15T21:04:05.666Z,1744751045.666 [DefaultWithUndock:A.Wait] Running Loop=1 2025-04-15T21:04:05.666Z,1744751045.666 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component. 2025-04-15T21:04:05.683Z,1744751045.683 [DockingStepper](INFO): Pause 2025-04-15T21:04:05.773Z,1744751045.773 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-15T21:04:05.773Z,1744751045.773 [WetLabsUBAT](INFO): Powering down 2025-04-15T21:04:05.774Z,1744751045.774 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2025-04-15T21:04:05.796Z,1744751045.796 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2025-04-15T21:04:05.796Z,1744751045.796 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-04-15T21:04:05.797Z,1744751045.797 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 7157 2025-04-15T21:04:05.845Z,1744751045.845 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.254796 2025-04-15T21:04:05.846Z,1744751045.846 [DAT](IMPORTANT): Requesting device enable clr for address 50. 2025-04-15T21:04:06.042Z,1744751046.042 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-04-15T21:04:06.101Z,1744751046.101 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.510580 2025-04-15T21:04:06.185Z,1744751046.185 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-15T21:04:06.185Z,1744751046.185 [WetLabsBB2FL](INFO): Powering down 2025-04-15T21:04:06.186Z,1744751046.186 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-04-15T21:04:06.204Z,1744751046.204 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2025-04-15T21:04:06.205Z,1744751046.205 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-04-15T21:04:06.205Z,1744751046.205 [CTD_Seabird](INFO): Join timeout helper Thread ID is 7158 2025-04-15T21:04:06.354Z,1744751046.354 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.763076 2025-04-15T21:04:06.606Z,1744751046.606 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.014940 2025-04-15T21:04:06.657Z,1744751046.657 [CTD_Seabird](INFO): Powering down 2025-04-15T21:04:06.669Z,1744751046.669 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-15T21:04:06.669Z,1744751046.669 [CTD_Seabird](INFO): Powering down 2025-04-15T21:04:06.685Z,1744751046.685 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-04-15T21:04:06.685Z,1744751046.685 [ComponentRegistry](INFO): Shutting down TrnDataBridge ThreadHandler 2025-04-15T21:04:06.686Z,1744751046.686 [TrnDataBridge ThreadHandler](INFO): Thread cancelled. 2025-04-15T21:04:06.686Z,1744751046.686 [TrnDataBridge](INFO): Join timeout helper Thread ID is 7159 2025-04-15T21:04:06.861Z,1744751046.861 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.270688 2025-04-15T21:04:07.042Z,1744751047.042 [TrnDataBridge ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-15T21:04:07.042Z,1744751047.042 [TrnDataBridge ThreadHandler](INFO): Thread cancelled. 2025-04-15T21:04:07.048Z,1744751047.048 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2025-04-15T21:04:07.048Z,1744751047.048 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-04-15T21:04:07.065Z,1744751047.065 [Radio_Surface](INFO): Join timeout helper Thread ID is 7160 2025-04-15T21:04:07.114Z,1744751047.114 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.523062 2025-04-15T21:04:07.365Z,1744751047.365 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.774869 2025-04-15T21:04:07.377Z,1744751047.377 [Radio_Surface](INFO): Powering down 2025-04-15T21:04:07.378Z,1744751047.378 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-15T21:04:07.378Z,1744751047.378 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-04-15T21:04:07.389Z,1744751047.389 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2025-04-15T21:04:07.389Z,1744751047.389 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-04-15T21:04:07.390Z,1744751047.390 [Onboard](INFO): Join timeout helper Thread ID is 7161 2025-04-15T21:04:07.618Z,1744751047.618 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.027709 2025-04-15T21:04:07.795Z,1744751047.795 [Reporter](INFO): platform_buoyancy_position 466.128052 cc 2025-04-15T21:04:07.870Z,1744751047.870 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.278885 2025-04-15T21:04:08.121Z,1744751048.121 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.530709 2025-04-15T21:04:08.253Z,1744751048.253 [Reporter](INFO): platform_buoyancy_position 465.993774 cc 2025-04-15T21:04:08.373Z,1744751048.373 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.782622 2025-04-15T21:04:08.542Z,1744751048.542 [Reporter](INFO): platform_buoyancy_position 468.142578 cc 2025-04-15T21:04:08.625Z,1744751048.625 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.034723 2025-04-15T21:04:08.877Z,1744751048.877 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.286778 2025-04-15T21:04:09.005Z,1744751049.005 [Reporter](INFO): platform_buoyancy_position 468.276855 cc 2025-04-15T21:04:09.129Z,1744751049.129 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.538687 2025-04-15T21:04:09.381Z,1744751049.381 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.790706 2025-04-15T21:04:09.454Z,1744751049.454 [Reporter](INFO): platform_buoyancy_position 474.857544 cc 2025-04-15T21:04:09.633Z,1744751049.633 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.042786 2025-04-15T21:04:09.797Z,1744751049.797 [Reporter](INFO): platform_buoyancy_position 481.035339 cc 2025-04-15T21:04:09.885Z,1744751049.885 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.294633 2025-04-15T21:04:10.138Z,1744751050.138 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.546920 2025-04-15T21:04:10.257Z,1744751050.257 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-15T21:04:10.257Z,1744751050.257 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-04-15T21:04:10.269Z,1744751050.269 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2025-04-15T21:04:10.269Z,1744751050.269 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-04-15T21:04:10.270Z,1744751050.270 [DataOverHttps](INFO): Join timeout helper Thread ID is 7162 2025-04-15T21:04:10.277Z,1744751050.277 [Reporter](INFO): platform_buoyancy_position 486.944580 cc 2025-04-15T21:04:10.389Z,1744751050.389 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.798690 2025-04-15T21:04:10.587Z,1744751050.587 [Reporter](INFO): platform_buoyancy_position 489.899200 cc 2025-04-15T21:04:10.645Z,1744751050.645 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.054277 2025-04-15T21:04:10.893Z,1744751050.893 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.302639 2025-04-15T21:04:11.045Z,1744751051.045 [Reporter](INFO): platform_buoyancy_position 496.211243 cc 2025-04-15T21:04:11.145Z,1744751051.145 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.554774 2025-04-15T21:04:11.397Z,1744751051.397 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.806668 2025-04-15T21:04:11.478Z,1744751051.478 [Reporter](INFO): platform_buoyancy_position 502.523376 cc 2025-04-15T21:04:11.649Z,1744751051.649 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.058763 2025-04-15T21:04:11.811Z,1744751051.811 [Reporter](INFO): platform_buoyancy_position 506.820923 cc 2025-04-15T21:04:11.901Z,1744751051.901 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.310701 2025-04-15T21:04:12.153Z,1744751052.153 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.562715 2025-04-15T21:04:12.310Z,1744751052.310 [Reporter](INFO): platform_buoyancy_position 513.401672 cc 2025-04-15T21:04:12.405Z,1744751052.405 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.814638 2025-04-15T21:04:12.651Z,1744751052.651 [Reporter](INFO): platform_buoyancy_position 517.564941 cc 2025-04-15T21:04:12.658Z,1744751052.658 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.066931 2025-04-15T21:04:12.909Z,1744751052.909 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.318629 2025-04-15T21:04:13.059Z,1744751053.059 [Reporter](INFO): platform_buoyancy_position 524.011353 cc 2025-04-15T21:04:13.161Z,1744751053.161 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.570717 2025-04-15T21:04:13.413Z,1744751053.413 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.822766 2025-04-15T21:04:13.534Z,1744751053.534 [Reporter](INFO): platform_buoyancy_position 530.457764 cc 2025-04-15T21:04:13.665Z,1744751053.665 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.074718 2025-04-15T21:04:13.851Z,1744751053.851 [Reporter](INFO): platform_buoyancy_position 534.755371 cc 2025-04-15T21:04:13.917Z,1744751053.917 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.326638 2025-04-15T21:04:14.169Z,1744751054.169 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.578653 2025-04-15T21:04:14.376Z,1744751054.376 [Reporter](INFO): platform_buoyancy_position 541.201782 cc 2025-04-15T21:04:14.421Z,1744751054.421 [DAT](INFO): DAT read: Response Not Received 2025-04-15T21:04:14.422Z,1744751054.422 [DAT](INFO): response not received 2025-04-15T21:04:14.422Z,1744751054.422 [DAT](INFO): DAT read: user:16> 2025-04-15T21:04:14.423Z,1744751054.423 [DAT](INFO): DAT read: Tx time:21:04:13.6846 2025-04-15T21:04:14.423Z,1744751054.423 [DAT](INFO): Ping request sent. 2025-04-15T21:04:14.423Z,1744751054.423 [DAT](INFO): transmitted an acoustic signal 2025-04-15T21:04:14.423Z,1744751054.423 [DAT](INFO): publishing transmit ping time 2025-04-15T21:04:14.424Z,1744751054.424 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000724 2025-04-15T21:04:14.673Z,1744751054.673 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250150 2025-04-15T21:04:14.674Z,1744751054.674 [Reporter](INFO): platform_buoyancy_position 545.365051 cc 2025-04-15T21:04:14.925Z,1744751054.925 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502204 2025-04-15T21:04:15.153Z,1744751055.153 [Reporter](INFO): platform_buoyancy_position 549.931274 cc 2025-04-15T21:04:15.177Z,1744751055.177 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754187 2025-04-15T21:04:15.287Z,1744751055.287 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-15T21:04:15.290Z,1744751055.290 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-04-15T21:04:15.305Z,1744751055.305 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2025-04-15T21:04:15.305Z,1744751055.305 [DAT ThreadHandler](INFO): Thread cancelled. 2025-04-15T21:04:15.306Z,1744751055.306 [DAT](INFO): Join timeout helper Thread ID is 7163 2025-04-15T21:04:15.437Z,1744751055.437 [DAT](INFO): Powering down 2025-04-15T21:04:15.437Z,1744751055.437 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-15T21:04:15.438Z,1744751055.438 [DAT](INFO): Powering down 2025-04-15T21:04:15.438Z,1744751055.438 [DAT ThreadHandler](INFO): Thread cancelled. 2025-04-15T21:04:15.440Z,1744751055.440 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2025-04-15T21:04:15.440Z,1744751055.440 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-04-15T21:04:15.440Z,1744751055.440 [BackseatComponent](INFO): Join timeout helper Thread ID is 7164 2025-04-15T21:04:15.457Z,1744751055.457 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-15T21:04:15.457Z,1744751055.457 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-04-15T21:04:15.461Z,1744751055.461 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2025-04-15T21:04:15.461Z,1744751055.461 [logger ThreadHandler](INFO): Thread cancelled. 2025-04-15T21:04:15.462Z,1744751055.462 [logger](INFO): Join timeout helper Thread ID is 7165 2025-04-15T21:04:15.485Z,1744751055.485 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-15T21:04:15.485Z,1744751055.485 [logger ThreadHandler](INFO): Thread cancelled. 2025-04-15T21:04:15.499Z,1744751055.499 [Reporter](INFO): platform_buoyancy_position 556.243347 cc 2025-04-15T21:04:15.501Z,1744751055.501 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2025-04-15T21:04:15.501Z,1744751055.501 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-04-15T21:04:15.502Z,1744751055.502 [CommandLine](INFO): Join timeout helper Thread ID is 7166 2025-04-15T21:04:15.521Z,1744751055.521 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-15T21:04:15.521Z,1744751055.521 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-04-15T21:04:15.522Z,1744751055.522 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2025-04-15T21:04:15.522Z,1744751055.522 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-04-15T21:04:15.523Z,1744751055.523 [CommandExec](INFO): Join timeout helper Thread ID is 7167 2025-04-15T21:04:15.524Z,1744751055.524 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2025-04-15T21:04:15.524Z,1744751055.524 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-04-15T21:04:15.525Z,1744751055.525 [controlThread](INFO): Join timeout helper Thread ID is 7168 2025-04-15T21:04:15.757Z,1744751055.757 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-15T21:04:15.757Z,1744751055.757 [controlThread](DEBUG): Uninitializing ControlThread 2025-04-15T21:04:15.757Z,1744751055.757 [AHRS_M2](INFO): Powering down 2025-04-15T21:04:15.758Z,1744751055.758 [NAL9602](INFO): Powering down 2025-04-15T21:04:15.759Z,1744751055.759 [RDI_Pathfinder](INFO): Powering down 2025-04-15T21:04:15.760Z,1744751055.760 [Sonardyne_Nano](INFO): Powering down 2025-04-15T21:04:15.761Z,1744751055.761 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2025-04-15T21:04:15.762Z,1744751055.762 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2025-04-15T21:04:15.762Z,1744751055.762 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2025-04-15T21:04:15.763Z,1744751055.763 [MissionManager](INFO): Uninitializing Mission DefaultWithUndock 2025-04-15T21:04:15.763Z,1744751055.763 [DefaultWithUndock] Stopped 2025-04-15T21:04:15.763Z,1744751055.763 [DefaultWithUndock](DEBUG): Aggregate::uninitialize DefaultWithUndock 2025-04-15T21:04:15.763Z,1744751055.763 [DefaultWithUndock:A.Wait] Stopped 2025-04-15T21:04:15.763Z,1744751055.763 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component. 2025-04-15T21:04:15.770Z,1744751055.770 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2025-04-15T21:04:15.771Z,1744751055.771 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2025-04-15T21:04:15.771Z,1744751055.771 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2025-04-15T21:04:15.771Z,1744751055.771 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2025-04-15T21:04:15.772Z,1744751055.772 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2025-04-15T21:04:15.773Z,1744751055.773 [BuoyancyServo](INFO): Powering down 2025-04-15T21:04:15.789Z,1744751055.789 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2025-04-15T21:04:15.789Z,1744751055.789 [ElevatorServo](INFO): Powering down 2025-04-15T21:04:15.790Z,1744751055.790 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2025-04-15T21:04:15.790Z,1744751055.790 [DockingStepper](INFO): Powering down 2025-04-15T21:04:15.791Z,1744751055.791 [MassServo](DEBUG): Uninitialize Mass Servo. 2025-04-15T21:04:15.791Z,1744751055.791 [MassServo](INFO): Powering down 2025-04-15T21:04:15.792Z,1744751055.792 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2025-04-15T21:04:15.792Z,1744751055.792 [RudderServo](INFO): Powering down 2025-04-15T21:04:15.793Z,1744751055.793 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2025-04-15T21:04:15.793Z,1744751055.793 [ThrusterServo](INFO): Powering down 2025-04-15T21:04:15.794Z,1744751055.794 [SBIT](DEBUG): Uninitialize SBIT Component. 2025-04-15T21:04:15.794Z,1744751055.794 [IBIT](DEBUG): Uninitialize IBIT Component. 2025-04-15T21:04:15.794Z,1744751055.794 [CBIT](DEBUG): Uninitialize CBIT Component. 2025-04-15T21:04:15.794Z,1744751055.794 [CBIT](DEBUG): Powering off loads. 2025-04-15T21:04:15.808Z,1744751055.808 [CBIT](DEBUG): Disabling WDT. 2025-04-15T21:04:15.820Z,1744751055.820 [CBIT](DEBUG): Opening all GF detection circuits. 2025-04-15T21:04:15.821Z,1744751055.821 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-04-15T21:04:15.906Z,1744751055.906 [TrnDataBridge ThreadHandler](INFO): Thread cancelled. 2025-04-15T21:04:15.922Z,1744751055.922 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-04-15T21:04:15.924Z,1744751055.924 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-04-15T21:04:15.929Z,1744751055.929 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-04-15T21:04:15.942Z,1744751055.942 [DAT ThreadHandler](INFO): Thread cancelled. 2025-04-15T21:04:16.001Z,1744751056.001 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-04-15T21:04:16.096Z,1744751056.096 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2025-04-15T21:04:16.102Z,1744751056.102 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-04-15T21:04:16.106Z,1744751056.106 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-04-15T21:04:16.115Z,1744751056.115 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-04-15T21:04:16.234Z,1744751056.234 [logger ThreadHandler](INFO): Thread cancelled.