2024-05-15T17:52:20.359Z,1715795540.359 [CommandExec](IMPORTANT): got command restart logs 2024-05-15T17:52:20.738Z,1715795540.738 [OnDock:restartDHCP] Running Loop=1 2024-05-15T17:52:20.739Z,1715795540.739 [OnDock:restartDHCP](DEBUG): Aggregate::initialize OnDock:restartDHCP 2024-05-15T17:52:20.739Z,1715795540.739 [OnDock:restartDHCP:A] Running Loop=1 2024-05-15T17:52:20.739Z,1715795540.739 [OnDock:restartDHCP:A](IMPORTANT): Restarting DHCP... 2024-05-15T17:52:20.739Z,1715795540.739 [OnDock:restartDHCP:A] Stopped 2024-05-15T17:52:20.739Z,1715795540.739 [OnDock:restartDHCP:B.Execute] Running Loop=1 2024-05-15T17:52:21.134Z,1715795541.134 [OnDock:restartDHCP:B.Execute](INFO): Executing command ! /mnt/mmc/LRAUV/Tools/dhcp.sh 2024-05-15T17:52:21.135Z,1715795541.135 [OnDock:restartDHCP:B.Execute] Stopped 2024-05-15T17:52:21.135Z,1715795541.135 [OnDock:restartDHCP](INFO): Completed OnDock:restartDHCP 2024-05-15T17:52:21.135Z,1715795541.135 [OnDock:restartDHCP] Stopped 2024-05-15T17:52:21.135Z,1715795541.135 [OnDock:restartDHCP](DEBUG): Aggregate::uninitialize OnDock:restartDHCP 2024-05-15T17:52:21.556Z,1715795541.556 [OnDock:M](INFO): Completed OnDock:M 2024-05-15T17:52:21.556Z,1715795541.556 [OnDock:M] Stopped 2024-05-15T17:52:21.556Z,1715795541.556 [OnDock:M](DEBUG): Aggregate::uninitialize OnDock:M 2024-05-15T17:52:21.556Z,1715795541.556 [OnDock:O.Docked] Running Loop=1 2024-05-15T17:52:21.556Z,1715795541.556 [OnDock:O.Docked](DEBUG): Initialize. 2024-05-15T17:52:21.556Z,1715795541.556 [OnDock:O.Docked](DEBUG): Initializing internal variables to default values. 2024-05-15T17:52:22.007Z,1715795542.007 [DockingStepper](INFO): Deisolating dock charge 2024-05-15T17:52:28.810Z,1715795548.810 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-15T17:52:30.989Z,1715795550.989 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240515T170244/Courier0028.lzma 2024-05-15T17:52:31.992Z,1715795551.992 [DataOverHttps](INFO): Moved sent file to Logs/20240515T170244/Courier0028.lzma.bak 2024-05-15T17:52:31.992Z,1715795551.992 [DataOverHttps](INFO): SBD MOMSN=21022636 2024-05-15T17:52:35.200Z,1715795555.200 [CommandExec](IMPORTANT): got command ! /mnt/mmc/LRAUV/Tools/dhcp.sh 2024-05-15T17:52:35.974Z,1715795555.974 [CommandExec](IMPORTANT): /mnt/mmc/started/2024-05-15UTC175235_sh 2024-05-15T17:52:53.686Z,1715795573.686 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240515T170244/Courier0031.lzma 2024-05-15T17:52:54.687Z,1715795574.687 [DataOverHttps](INFO): Moved sent file to Logs/20240515T170244/Courier0031.lzma.bak 2024-05-15T17:52:54.687Z,1715795574.687 [DataOverHttps](INFO): SBD MOMSN=21022638 2024-05-15T17:53:14.898Z,1715795594.898 [DataOverHttps](INFO): Sending 600 bytes from file Logs/20240515T170244/Express0029.lzma 2024-05-15T17:53:15.904Z,1715795595.904 [DataOverHttps](INFO): Moved sent file to Logs/20240515T170244/Express0029.lzma.bak 2024-05-15T17:53:15.904Z,1715795595.904 [DataOverHttps](INFO): SBD MOMSN=21022640 2024-05-15T17:53:35.425Z,1715795615.425 [DataOverHttps](INFO): Sending 126 bytes from file Logs/20240515T170244/Express0032.lzma 2024-05-15T17:53:36.431Z,1715795616.431 [DataOverHttps](INFO): Moved sent file to Logs/20240515T170244/Express0032.lzma.bak 2024-05-15T17:53:36.432Z,1715795616.432 [DataOverHttps](INFO): SBD MOMSN=21022648 2024-05-15T17:54:17.894Z,1715795657.894 [OnDock:I.](INFO): *** querying acoustic contact *** 2024-05-15T17:54:17.930Z,1715795657.930 [DAT](INFO): ****** received valid address query ****** 2024-05-15T17:54:17.930Z,1715795657.930 [DAT](INFO): ****** received valid ping request ****** 2024-05-15T17:54:17.930Z,1715795657.930 [DAT](INFO): setting remote address to 0 2024-05-15T17:54:18.182Z,1715795658.182 [DAT](INFO): DAT read: user:6> 2024-05-15T17:54:18.183Z,1715795658.183 [DAT](INFO): DAT read: RemoteAddr | 0 2024-05-15T17:54:18.183Z,1715795658.183 [DAT](INFO): set remote address to 0 2024-05-15T17:54:18.184Z,1715795658.184 [DAT](INFO): ****** received valid address query ****** 2024-05-15T17:54:18.184Z,1715795658.184 [DAT](INFO): ****** received valid ping request ****** 2024-05-15T17:54:18.184Z,1715795658.184 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode. 2024-05-15T17:54:18.476Z,1715795658.476 [DAT](INFO): DAT read: user:7> 2024-05-15T17:54:18.478Z,1715795658.478 [DAT](INFO): DAT read: Tx time:17:54:18.1236 2024-05-15T17:54:18.478Z,1715795658.478 [DAT](INFO): Ping request sent. 2024-05-15T17:54:18.478Z,1715795658.478 [DAT](INFO): transmitted an acoustic signal 2024-05-15T17:54:18.479Z,1715795658.479 [DAT](INFO): DAT read: TxSync time:17:54:18.1228 2024-05-15T17:54:18.479Z,1715795658.479 [DAT](INFO): unknown deviceResponse_: TxSync time:17:54:18.1228 2024-05-15T17:54:18.480Z,1715795658.480 [DAT](INFO): publishing transmit ping time 2024-05-15T17:54:18.481Z,1715795658.481 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.002324 2024-05-15T17:54:18.732Z,1715795658.732 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.253635 2024-05-15T17:54:18.982Z,1715795658.982 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.504175 2024-05-15T17:54:19.235Z,1715795659.235 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.756796 2024-05-15T17:54:19.486Z,1715795659.486 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.008170 2024-05-15T17:54:19.738Z,1715795659.738 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.260366 2024-05-15T17:54:19.991Z,1715795659.991 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.512470 2024-05-15T17:54:20.242Z,1715795660.242 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.764124 2024-05-15T17:54:20.495Z,1715795660.495 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.017408 2024-05-15T17:54:20.746Z,1715795660.746 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.268196 2024-05-15T17:54:20.998Z,1715795660.998 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.520157 2024-05-15T17:54:21.251Z,1715795661.251 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.772465 2024-05-15T17:54:21.502Z,1715795661.502 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.024242 2024-05-15T17:54:21.754Z,1715795661.754 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.276183 2024-05-15T17:54:22.007Z,1715795662.007 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.528582 2024-05-15T17:54:22.258Z,1715795662.258 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.780222 2024-05-15T17:54:22.510Z,1715795662.510 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.032418 2024-05-15T17:54:22.762Z,1715795662.762 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.284191 2024-05-15T17:54:23.017Z,1715795663.017 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.539288 2024-05-15T17:54:23.267Z,1715795663.267 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.788453 2024-05-15T17:54:23.518Z,1715795663.518 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.040413 2024-05-15T17:54:23.770Z,1715795663.770 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.292271 2024-05-15T17:54:24.022Z,1715795664.022 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.544132 2024-05-15T17:54:24.274Z,1715795664.274 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.796183 2024-05-15T17:54:24.526Z,1715795664.526 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.048263 2024-05-15T17:54:24.778Z,1715795664.778 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.300184 2024-05-15T17:54:25.030Z,1715795665.030 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.552175 2024-05-15T17:54:25.283Z,1715795665.283 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.804643 2024-05-15T17:54:25.534Z,1715795665.534 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.055916 2024-05-15T17:54:25.786Z,1715795665.786 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.308414 2024-05-15T17:54:26.039Z,1715795666.039 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.560592 2024-05-15T17:54:26.290Z,1715795666.290 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.812176 2024-05-15T17:54:26.542Z,1715795666.542 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.064133 2024-05-15T17:54:26.794Z,1715795666.794 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.316244 2024-05-15T17:54:27.046Z,1715795667.046 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.568169 2024-05-15T17:54:27.298Z,1715795667.298 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.820434 2024-05-15T17:54:27.550Z,1715795667.550 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.071978 2024-05-15T17:54:27.805Z,1715795667.805 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.327398 2024-05-15T17:54:28.059Z,1715795668.059 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.580517 2024-05-15T17:54:28.310Z,1715795668.310 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.832262 2024-05-15T17:54:30.830Z,1715795670.830 [DAT](INFO): DAT read: Response Not Received 2024-05-15T17:54:30.830Z,1715795670.830 [DAT](INFO): response not received 2024-05-15T17:54:30.830Z,1715795670.830 [DAT](ERROR): No response from remote modem. 2024-05-15T17:56:17.946Z,1715795777.946 [OnDock:I.](INFO): *** querying acoustic contact *** 2024-05-15T17:56:18.014Z,1715795778.014 [DAT](INFO): ****** received valid address query ****** 2024-05-15T17:56:18.014Z,1715795778.014 [DAT](INFO): ****** received valid ping request ****** 2024-05-15T17:56:18.014Z,1715795778.014 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode. 2024-05-15T17:56:18.266Z,1715795778.266 [DAT](INFO): DAT read: user:8> 2024-05-15T17:56:18.267Z,1715795778.267 [DAT](INFO): DAT read: Tx time:17:56:17.9248 2024-05-15T17:56:18.267Z,1715795778.267 [DAT](INFO): Ping request sent. 2024-05-15T17:56:18.267Z,1715795778.267 [DAT](INFO): transmitted an acoustic signal 2024-05-15T17:56:18.267Z,1715795778.267 [DAT](INFO): publishing transmit ping time 2024-05-15T17:56:18.268Z,1715795778.268 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000710 2024-05-15T17:56:18.518Z,1715795778.518 [DAT](INFO): DAT read: TxSync time:17:56:17.9240 2024-05-15T17:56:18.519Z,1715795778.519 [DAT](INFO): unknown deviceResponse_: TxSync time:17:56:17.9240 2024-05-15T17:56:18.519Z,1715795778.519 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.252111 2024-05-15T17:56:18.770Z,1715795778.770 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.503074 2024-05-15T17:56:19.022Z,1715795779.022 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.755057 2024-05-15T17:56:19.275Z,1715795779.275 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.007575 2024-05-15T17:56:19.526Z,1715795779.526 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258903 2024-05-15T17:56:19.778Z,1715795779.778 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.511069 2024-05-15T17:56:20.031Z,1715795780.031 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.763850 2024-05-15T17:56:20.282Z,1715795780.282 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.015145 2024-05-15T17:56:20.534Z,1715795780.534 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.267085 2024-05-15T17:56:20.786Z,1715795780.786 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.519099 2024-05-15T17:56:21.038Z,1715795781.038 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.771109 2024-05-15T17:56:21.291Z,1715795781.291 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.023501 2024-05-15T17:56:21.542Z,1715795781.542 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274845 2024-05-15T17:56:21.794Z,1715795781.794 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.527273 2024-05-15T17:56:22.047Z,1715795782.047 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.779286 2024-05-15T17:56:22.298Z,1715795782.298 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.031365 2024-05-15T17:56:22.551Z,1715795782.551 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.283695 2024-05-15T17:56:22.802Z,1715795782.802 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.535072 2024-05-15T17:56:23.054Z,1715795783.054 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.787088 2024-05-15T17:56:23.306Z,1715795783.306 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.039129 2024-05-15T17:56:23.558Z,1715795783.558 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.290844 2024-05-15T17:56:23.810Z,1715795783.810 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.543373 2024-05-15T17:56:24.062Z,1715795784.062 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.795091 2024-05-15T17:56:24.314Z,1715795784.314 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.047054 2024-05-15T17:56:24.566Z,1715795784.566 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.299099 2024-05-15T17:56:24.818Z,1715795784.818 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.551139 2024-05-15T17:56:25.070Z,1715795785.070 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.802995 2024-05-15T17:56:25.322Z,1715795785.322 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.055063 2024-05-15T17:56:25.574Z,1715795785.574 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.307113 2024-05-15T17:56:25.826Z,1715795785.826 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.559355 2024-05-15T17:56:26.079Z,1715795786.079 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.811638 2024-05-15T17:56:26.330Z,1715795786.330 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.063087 2024-05-15T17:56:26.586Z,1715795786.586 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.318570 2024-05-15T17:56:26.834Z,1715795786.834 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.567054 2024-05-15T17:56:27.086Z,1715795787.086 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.819088 2024-05-15T17:56:27.339Z,1715795787.339 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.071469 2024-05-15T17:56:27.590Z,1715795787.590 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.323043 2024-05-15T17:56:27.846Z,1715795787.846 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.579238 2024-05-15T17:56:28.099Z,1715795788.099 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.831800 2024-05-15T17:56:30.625Z,1715795790.625 [DAT](INFO): DAT read: Response Not Received 2024-05-15T17:56:30.626Z,1715795790.626 [DAT](INFO): response not received 2024-05-15T17:56:30.626Z,1715795790.626 [DAT](ERROR): No response from remote modem. 2024-05-15T17:58:18.112Z,1715795898.112 [OnDock:I.](INFO): *** querying acoustic contact *** 2024-05-15T17:58:18.274Z,1715795898.274 [DAT](INFO): ****** received valid address query ****** 2024-05-15T17:58:18.274Z,1715795898.274 [DAT](INFO): ****** received valid ping request ****** 2024-05-15T17:58:18.274Z,1715795898.274 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode. 2024-05-15T17:58:18.528Z,1715795898.528 [DAT](INFO): DAT read: user:9> 2024-05-15T17:58:18.529Z,1715795898.529 [DAT](INFO): DAT read: Tx time:17:58:18.1759 2024-05-15T17:58:18.529Z,1715795898.529 [DAT](INFO): Ping request sent. 2024-05-15T17:58:18.530Z,1715795898.530 [DAT](INFO): transmitted an acoustic signal 2024-05-15T17:58:18.530Z,1715795898.530 [DAT](INFO): DAT read: TxSync time:17:58:18.1751 2024-05-15T17:58:18.531Z,1715795898.531 [DAT](INFO): unknown deviceResponse_: TxSync time:17:58:18.1751 2024-05-15T17:58:18.531Z,1715795898.531 [DAT](INFO): publishing transmit ping time 2024-05-15T17:58:18.531Z,1715795898.531 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.001682 2024-05-15T17:58:18.778Z,1715795898.778 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.248644 2024-05-15T17:58:19.032Z,1715795899.032 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502378 2024-05-15T17:58:19.282Z,1715795899.282 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.752478 2024-05-15T17:58:19.535Z,1715795899.535 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.004928 2024-05-15T17:58:19.787Z,1715795899.787 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.256875 2024-05-15T17:58:20.039Z,1715795900.039 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.509006 2024-05-15T17:58:20.290Z,1715795900.290 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.760546 2024-05-15T17:58:20.542Z,1715795900.542 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.012630 2024-05-15T17:58:20.794Z,1715795900.794 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.264485 2024-05-15T17:58:21.047Z,1715795901.047 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.517254 2024-05-15T17:58:21.298Z,1715795901.298 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.768522 2024-05-15T17:58:21.550Z,1715795901.550 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.020777 2024-05-15T17:58:21.803Z,1715795901.803 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.273201 2024-05-15T17:58:22.054Z,1715795902.054 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.524595 2024-05-15T17:58:22.307Z,1715795902.307 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.777409 2024-05-15T17:58:22.558Z,1715795902.558 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.028719 2024-05-15T17:58:22.810Z,1715795902.810 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.280619 2024-05-15T17:58:23.062Z,1715795903.062 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.532684 2024-05-15T17:58:23.314Z,1715795903.314 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.784557 2024-05-15T17:58:23.566Z,1715795903.566 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.036618 2024-05-15T17:58:23.818Z,1715795903.818 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.288301 2024-05-15T17:58:24.072Z,1715795904.072 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.542674 2024-05-15T17:58:24.327Z,1715795904.327 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.797182 2024-05-15T17:58:24.578Z,1715795904.578 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.048535 2024-05-15T17:58:24.831Z,1715795904.831 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.301295 2024-05-15T17:58:25.090Z,1715795905.090 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.560544 2024-05-15T17:58:25.343Z,1715795905.343 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.812894 2024-05-15T17:58:25.594Z,1715795905.594 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.064532 2024-05-15T17:58:25.846Z,1715795905.846 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.316609 2024-05-15T17:58:26.098Z,1715795906.098 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.568410 2024-05-15T17:58:26.351Z,1715795906.351 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.820765 2024-05-15T17:58:26.605Z,1715795906.605 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.074733 2024-05-15T17:58:26.855Z,1715795906.855 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.324957 2024-05-15T17:58:27.106Z,1715795907.106 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.576423 2024-05-15T17:58:27.358Z,1715795907.358 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.828511 2024-05-15T17:58:27.612Z,1715795907.612 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.082452 2024-05-15T17:58:27.863Z,1715795907.863 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.332874 2024-05-15T17:58:28.114Z,1715795908.114 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.584516 2024-05-15T17:58:28.367Z,1715795908.367 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.836713 2024-05-15T17:58:30.891Z,1715795910.891 [DAT](INFO): DAT read: Response Not Received 2024-05-15T17:58:30.891Z,1715795910.891 [DAT](INFO): response not received 2024-05-15T17:58:30.892Z,1715795910.892 [DAT](ERROR): No response from remote modem. 2024-05-15T18:00:18.354Z,1715796018.354 [OnDock:I.](INFO): *** querying acoustic contact *** 2024-05-15T18:00:18.592Z,1715796018.592 [DAT](INFO): ****** received valid address query ****** 2024-05-15T18:00:18.593Z,1715796018.593 [DAT](INFO): ****** received valid ping request ****** 2024-05-15T18:00:18.594Z,1715796018.594 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode. 2024-05-15T18:00:18.842Z,1715796018.842 [DAT](INFO): DAT read: user:10> 2024-05-15T18:00:18.843Z,1715796018.843 [DAT](INFO): DAT read: Tx time:18:00:18.5271 2024-05-15T18:00:18.843Z,1715796018.843 [DAT](INFO): Ping request sent. 2024-05-15T18:00:18.843Z,1715796018.843 [DAT](INFO): transmitted an acoustic signal 2024-05-15T18:00:18.843Z,1715796018.843 [DAT](INFO): publishing transmit ping time 2024-05-15T18:00:18.844Z,1715796018.844 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000730 2024-05-15T18:00:19.094Z,1715796019.094 [DAT](INFO): DAT read: TxSync time:18:00:18.5263 2024-05-15T18:00:19.095Z,1715796019.095 [DAT](INFO): unknown deviceResponse_: TxSync time:18:00:18.5263 2024-05-15T18:00:19.095Z,1715796019.095 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.252045 2024-05-15T18:00:19.347Z,1715796019.347 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.503589 2024-05-15T18:00:19.598Z,1715796019.598 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754943 2024-05-15T18:00:19.850Z,1715796019.850 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.007236 2024-05-15T18:00:20.103Z,1715796020.103 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.259386 2024-05-15T18:00:20.354Z,1715796020.354 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510749 2024-05-15T18:00:20.609Z,1715796020.609 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.765280 2024-05-15T18:00:20.858Z,1715796020.858 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014977 2024-05-15T18:00:21.110Z,1715796021.110 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266944 2024-05-15T18:00:21.363Z,1715796021.363 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.519688 2024-05-15T18:00:21.614Z,1715796021.614 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770812 2024-05-15T18:00:21.866Z,1715796021.866 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.023084 2024-05-15T18:00:22.119Z,1715796022.119 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.275469 2024-05-15T18:00:22.370Z,1715796022.370 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.526929 2024-05-15T18:00:22.624Z,1715796022.624 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.780598 2024-05-15T18:00:22.877Z,1715796022.877 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.033509 2024-05-15T18:00:23.126Z,1715796023.126 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.283006 2024-05-15T18:00:23.379Z,1715796023.379 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.535253 2024-05-15T18:00:23.630Z,1715796023.630 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.786997 2024-05-15T18:00:23.882Z,1715796023.882 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.039009 2024-05-15T18:00:24.135Z,1715796024.135 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.291473 2024-05-15T18:00:24.386Z,1715796024.386 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.542914 2024-05-15T18:00:24.649Z,1715796024.649 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.805014 2024-05-15T18:00:24.898Z,1715796024.898 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.054916 2024-05-15T18:00:25.150Z,1715796025.150 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.306939 2024-05-15T18:00:25.403Z,1715796025.403 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.559809 2024-05-15T18:00:25.654Z,1715796025.654 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.810943 2024-05-15T18:00:25.906Z,1715796025.906 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.063166 2024-05-15T18:00:26.162Z,1715796026.162 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.318192 2024-05-15T18:00:26.410Z,1715796026.410 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.566910 2024-05-15T18:00:26.662Z,1715796026.662 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.819184 2024-05-15T18:00:26.914Z,1715796026.914 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.070903 2024-05-15T18:00:27.167Z,1715796027.167 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.323463 2024-05-15T18:00:27.418Z,1715796027.418 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.574957 2024-05-15T18:00:27.670Z,1715796027.670 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.827031 2024-05-15T18:00:27.922Z,1715796027.922 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.078872 2024-05-15T18:00:28.174Z,1715796028.174 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.330937 2024-05-15T18:00:28.426Z,1715796028.426 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.582885 2024-05-15T18:00:28.678Z,1715796028.678 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.835266 2024-05-15T18:00:29.554Z,1715796029.554 [CommandExec](IMPORTANT): got command stop 2024-05-15T18:00:29.554Z,1715796029.554 [CommandExec](IMPORTANT): Scheduling is paused 2024-05-15T18:00:29.554Z,1715796029.554 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop 2024-05-15T18:00:29.714Z,1715796029.714 [MissionManager](INFO): MissionManager is completed. 2024-05-15T18:00:29.714Z,1715796029.714 [MissionManager](INFO): Uninitializing Mission OnDock 2024-05-15T18:00:29.714Z,1715796029.714 [OnDock] Stopped 2024-05-15T18:00:29.715Z,1715796029.715 [OnDock](DEBUG): Aggregate::uninitialize OnDock 2024-05-15T18:00:29.715Z,1715796029.715 [OnDock:StandardEnvelopes] Stopped 2024-05-15T18:00:29.715Z,1715796029.715 [OnDock:StandardEnvelopes](DEBUG): Aggregate::uninitialize OnDock:StandardEnvelopes 2024-05-15T18:00:29.715Z,1715796029.715 [OnDock:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2024-05-15T18:00:29.715Z,1715796029.715 [OnDock:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2024-05-15T18:00:29.715Z,1715796029.715 [OnDock:StandardEnvelopes:B.DepthEnvelope] Stopped 2024-05-15T18:00:29.715Z,1715796029.715 [OnDock:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2024-05-15T18:00:29.715Z,1715796029.715 [OnDock:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2024-05-15T18:00:29.715Z,1715796029.715 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2024-05-15T18:00:29.715Z,1715796029.715 [OnDock:BackseatDriver] Stopped 2024-05-15T18:00:29.715Z,1715796029.715 [OnDock:BackseatDriver](DEBUG): Aggregate::uninitialize OnDock:BackseatDriver 2024-05-15T18:00:29.715Z,1715796029.715 [OnDock:BackseatDriver:A.BackseatDriver] Stopped 2024-05-15T18:00:29.716Z,1715796029.716 [OnDock:PowerOnly] Stopped 2024-05-15T18:00:29.716Z,1715796029.716 [OnDock:PowerOnly](DEBUG): Aggregate::uninitialize OnDock:PowerOnly 2024-05-15T18:00:29.716Z,1715796029.716 [OnDock:PowerOnly:E.Wait] Stopped 2024-05-15T18:00:29.716Z,1715796029.716 [OnDock:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2024-05-15T18:00:29.716Z,1715796029.716 [OnDock:H] Stopped 2024-05-15T18:00:29.716Z,1715796029.716 [OnDock:I.] Stopped 2024-05-15T18:00:29.716Z,1715796029.716 [OnDock:I.](DEBUG): Uninitializing TrackAcousticContact. 2024-05-15T18:00:29.716Z,1715796029.716 [OnDock:PeriodicCheck] Stopped 2024-05-15T18:00:29.716Z,1715796029.716 [OnDock:PeriodicCheck](DEBUG): Aggregate::uninitialize OnDock:PeriodicCheck 2024-05-15T18:00:29.716Z,1715796029.716 [OnDock:PeriodicCheck:CheckIP] Stopped 2024-05-15T18:00:29.716Z,1715796029.716 [OnDock:PeriodicCheck:CheckIP](DEBUG): Aggregate::uninitialize OnDock:PeriodicCheck:CheckIP 2024-05-15T18:00:29.717Z,1715796029.717 [OnDock:PeriodicCheck:CheckIP:B.Wait] Stopped 2024-05-15T18:00:29.717Z,1715796029.717 [OnDock:PeriodicCheck:CheckIP:B.Wait](DEBUG): Uninitialize Wait Component. 2024-05-15T18:00:29.717Z,1715796029.717 [OnDock:O.Docked] Stopped 2024-05-15T18:00:30.095Z,1715796030.095 [MissionManager](IMPORTANT): Started mission Default 2024-05-15T18:00:30.095Z,1715796030.095 [Default] Running Loop=1 2024-05-15T18:00:30.095Z,1715796030.095 [Default](DEBUG): Aggregate::initialize Default 2024-05-15T18:00:30.095Z,1715796030.095 [Default:B.GoToSurface] Running Loop=1 2024-05-15T18:00:30.095Z,1715796030.095 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-05-15T18:00:30.095Z,1715796030.095 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-05-15T18:00:30.096Z,1715796030.096 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-05-15T18:00:30.096Z,1715796030.096 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-05-15T18:00:30.096Z,1715796030.096 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-05-15T18:00:30.096Z,1715796030.096 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-05-15T18:00:30.117Z,1715796030.117 [Default:A.Wait] Running Loop=1 2024-05-15T18:00:30.117Z,1715796030.117 [Default:A.Wait](DEBUG): Initialize Wait Component. 2024-05-15T18:00:31.200Z,1715796031.200 [DAT](INFO): DAT read: Response Not Received 2024-05-15T18:00:31.200Z,1715796031.200 [DAT](INFO): response not received 2024-05-15T18:00:31.200Z,1715796031.200 [DAT](ERROR): No response from remote modem. 2024-05-15T18:00:43.148Z,1715796043.148 [Default:A.Wait](INFO): Done Waiting. 2024-05-15T18:00:43.148Z,1715796043.148 [Default:A.Wait] Stopped 2024-05-15T18:00:43.148Z,1715796043.148 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2024-05-15T18:00:43.570Z,1715796043.570 [Default:CheckIn] Running Loop=1 2024-05-15T18:00:43.570Z,1715796043.570 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-15T18:00:43.571Z,1715796043.571 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-15T18:00:45.511Z,1715796045.511 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180044.00,A,3648.16301,N,12147.27985,W,0.214,104.07,150524,,,A*7B 2024-05-15T18:00:45.513Z,1715796045.513 [NAL9602](INFO): GPS fix at 20240515T180044: (36.802717, -121.787998) 2024-05-15T18:00:45.557Z,1715796045.557 [Default:CheckIn:Read_GPS] Stopped 2024-05-15T18:00:45.582Z,1715796045.582 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-15T18:01:00.377Z,1715796060.377 [DataOverHttps](INFO): Sending 268 bytes from file Logs/20240515T175220/Courier0000.lzma 2024-05-15T18:01:01.371Z,1715796061.371 [DataOverHttps](INFO): Moved sent file to Logs/20240515T175220/Courier0000.lzma.bak 2024-05-15T18:01:01.371Z,1715796061.371 [DataOverHttps](INFO): SBD MOMSN=21022663 2024-05-15T18:01:26.035Z,1715796086.035 [DataOverHttps](INFO): Sending 579 bytes from file Logs/20240515T175220/Express0001.lzma 2024-05-15T18:01:27.035Z,1715796087.035 [DataOverHttps](INFO): Moved sent file to Logs/20240515T175220/Express0001.lzma.bak 2024-05-15T18:01:27.035Z,1715796087.035 [DataOverHttps](INFO): SBD MOMSN=21022667 2024-05-15T18:01:30.045Z,1715796090.045 [Default:CheckIn:Read_Iridium] Stopped 2024-05-15T18:01:30.046Z,1715796090.046 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-15T18:01:30.046Z,1715796090.046 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-15T18:01:41.344Z,1715796101.344 [NAL9602](INFO): SBD MO Status=2, MOMSN=37338, MT Status=2, MTMSN=0 2024-05-15T18:01:41.344Z,1715796101.344 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-15T18:02:01.979Z,1715796121.979 [NAL9602](INFO): SBD MO Status=2, MOMSN=37338, MT Status=2, MTMSN=0 2024-05-15T18:02:01.980Z,1715796121.980 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-15T18:02:25.007Z,1715796145.007 [NAL9602](INFO): SBD MO Status=2, MOMSN=37338, MT Status=2, MTMSN=0 2024-05-15T18:02:25.008Z,1715796145.008 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-15T18:04:12.064Z,1715796252.064 [NAL9602](INFO): SBD MO Status=2, MOMSN=37338, MT Status=2, MTMSN=0 2024-05-15T18:04:12.064Z,1715796252.064 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-15T18:05:47.402Z,1715796347.402 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-05-15T18:06:14.881Z,1715796374.881 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 0.00, 0.00, 0.00, 0.00 2024-05-15T18:06:18.105Z,1715796378.105 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-15T18:06:30.710Z,1715796390.710 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-15T18:06:30.710Z,1715796390.710 [Default:CheckIn:C.Wait] Stopped 2024-05-15T18:06:30.710Z,1715796390.710 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-15T18:06:30.711Z,1715796390.711 [Default:CheckIn:D] Running Loop=1 2024-05-15T18:06:31.044Z,1715796391.044 [Default:CheckIn:D] Stopped 2024-05-15T18:06:31.044Z,1715796391.044 [Default:CheckIn:E] Running Loop=1 2024-05-15T18:06:31.448Z,1715796391.448 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.015820 min 2024-05-15T18:06:31.448Z,1715796391.448 [Default:CheckIn:E] Stopped 2024-05-15T18:06:31.449Z,1715796391.449 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-15T18:06:31.449Z,1715796391.449 [Default:CheckIn] Stopped 2024-05-15T18:06:31.449Z,1715796391.449 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-15T18:06:31.450Z,1715796391.450 [Default:CheckIn](INFO): Running loop #2 2024-05-15T18:06:31.450Z,1715796391.450 [Default:CheckIn] Running Loop=2 2024-05-15T18:06:31.450Z,1715796391.450 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-15T18:06:31.450Z,1715796391.450 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-15T18:06:33.459Z,1715796393.459 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180632.00,A,3648.16442,N,12147.27128,W,1.613,1.95,150524,,,A*7E 2024-05-15T18:06:33.470Z,1715796393.470 [NAL9602](INFO): GPS fix at 20240515T180632: (36.802740, -121.787855) 2024-05-15T18:06:33.481Z,1715796393.481 [Default:CheckIn:Read_GPS] Stopped 2024-05-15T18:06:33.482Z,1715796393.482 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-15T18:06:34.699Z,1715796394.699 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for water velocity. Device response is::WS,-32768,-32768V 2024-05-15T18:06:42.769Z,1715796402.769 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240515T175220/Courier0003.lzma 2024-05-15T18:06:43.771Z,1715796403.771 [DataOverHttps](INFO): Moved sent file to Logs/20240515T175220/Courier0003.lzma.bak 2024-05-15T18:06:43.771Z,1715796403.771 [DataOverHttps](INFO): SBD MOMSN=21022682 2024-05-15T18:06:44.827Z,1715796404.827 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout 2024-05-15T18:06:44.827Z,1715796404.827 [RDI_Pathfinder] Communications Fault, FailCount= 1 2024-05-15T18:06:44.827Z,1715796404.827 [RDI_Pathfinder](ERROR): Communications Fault 2024-05-15T18:06:44.982Z,1715796404.982 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2024-05-15T18:06:45.176Z,1715796405.176 [RDI_Pathfinder](INFO): Powering down 2024-05-15T18:06:46.170Z,1715796406.170 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2024-05-15T18:06:46.171Z,1715796406.171 [RDI_Pathfinder] No Fault, FailCount= 1 2024-05-15T18:06:46.387Z,1715796406.387 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2024-05-15T18:07:02.605Z,1715796422.605 [DataOverHttps](INFO): Sending 259 bytes from file Logs/20240515T175220/Express0004.lzma 2024-05-15T18:07:03.607Z,1715796423.607 [DataOverHttps](INFO): Moved sent file to Logs/20240515T175220/Express0004.lzma.bak 2024-05-15T18:07:03.607Z,1715796423.607 [DataOverHttps](INFO): SBD MOMSN=21022685 2024-05-15T18:07:04.180Z,1715796424.180 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2024-05-15T18:07:04.265Z,1715796424.265 [NAL9602](ERROR): received: +CSQ:0 OK338, 2, 0, 0, 0 OK 2024-05-15T18:07:06.614Z,1715796426.614 [Default:CheckIn:Read_Iridium] Stopped 2024-05-15T18:07:06.614Z,1715796426.614 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-15T18:07:06.615Z,1715796426.615 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-15T18:08:39.938Z,1715796519.938 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for water velocity. Device response is::WS,-32768,-3768,V 2024-05-15T18:09:01.327Z,1715796541.327 [NAL9602](INFO): SBD MO Status=2, MOMSN=37338, MT Status=2, MTMSN=0 2024-05-15T18:09:01.327Z,1715796541.327 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-15T18:09:45.359Z,1715796585.359 [NAL9602](INFO): SBD MO Status=2, MOMSN=37338, MT Status=2, MTMSN=0 2024-05-15T18:09:45.359Z,1715796585.359 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-15T18:10:37.476Z,1715796637.476 [NAL9602](INFO): SBD MO Status=2, MOMSN=37338, MT Status=2, MTMSN=0 2024-05-15T18:10:37.476Z,1715796637.476 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-15T18:11:35.260Z,1715796695.260 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-05-15T18:12:06.014Z,1715796726.014 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-15T18:12:07.240Z,1715796727.240 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-15T18:12:07.240Z,1715796727.240 [Default:CheckIn:C.Wait] Stopped 2024-05-15T18:12:07.240Z,1715796727.240 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-15T18:12:07.241Z,1715796727.241 [Default:CheckIn:D] Running Loop=1 2024-05-15T18:12:07.640Z,1715796727.640 [Default:CheckIn:D] Stopped 2024-05-15T18:12:07.640Z,1715796727.640 [Default:CheckIn:E] Running Loop=1 2024-05-15T18:12:08.082Z,1715796728.082 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.625760 min 2024-05-15T18:12:08.082Z,1715796728.082 [Default:CheckIn:E] Stopped 2024-05-15T18:12:08.082Z,1715796728.082 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-15T18:12:08.082Z,1715796728.082 [Default:CheckIn] Stopped 2024-05-15T18:12:08.082Z,1715796728.082 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-15T18:12:08.083Z,1715796728.083 [Default:CheckIn](INFO): Running loop #3 2024-05-15T18:12:08.083Z,1715796728.083 [Default:CheckIn] Running Loop=3 2024-05-15T18:12:08.083Z,1715796728.083 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-15T18:12:08.083Z,1715796728.083 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-15T18:12:10.060Z,1715796730.060 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181209.00,A,3648.16477,N,12147.28217,W,0.369,1.95,150524,,,A*7C 2024-05-15T18:12:10.063Z,1715796730.063 [NAL9602](INFO): GPS fix at 20240515T181209: (36.802746, -121.788036) 2024-05-15T18:12:10.147Z,1715796730.147 [Default:CheckIn:Read_GPS] Stopped 2024-05-15T18:12:10.148Z,1715796730.148 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-15T18:12:18.193Z,1715796738.193 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240515T175220/Courier0006.lzma 2024-05-15T18:12:19.199Z,1715796739.199 [DataOverHttps](INFO): Moved sent file to Logs/20240515T175220/Courier0006.lzma.bak 2024-05-15T18:12:19.199Z,1715796739.199 [DataOverHttps](INFO): SBD MOMSN=21022695 2024-05-15T18:12:36.910Z,1715796756.910 [DataOverHttps](INFO): Sending 276 bytes from file Logs/20240515T175220/Express0007.lzma 2024-05-15T18:12:37.911Z,1715796757.911 [DataOverHttps](INFO): Moved sent file to Logs/20240515T175220/Express0007.lzma.bak 2024-05-15T18:12:37.911Z,1715796757.911 [DataOverHttps](INFO): SBD MOMSN=21022698 2024-05-15T18:12:40.514Z,1715796760.514 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2024-05-15T18:12:40.589Z,1715796760.589 [NAL9602](ERROR): received: +CSQ:0 OK338, 2, 0, 0, 0 OK 2024-05-15T18:12:41.350Z,1715796761.350 [Default:CheckIn:Read_Iridium] Stopped 2024-05-15T18:12:41.350Z,1715796761.350 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-15T18:12:41.350Z,1715796761.350 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-15T18:17:31.174Z,1715797051.174 [NAL9602](INFO): SBD MO Status=2, MOMSN=37338, MT Status=2, MTMSN=0 2024-05-15T18:17:31.175Z,1715797051.175 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-15T18:17:31.578Z,1715797051.578 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-05-15T18:17:42.119Z,1715797062.119 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-15T18:17:42.120Z,1715797062.120 [Default:CheckIn:C.Wait] Stopped 2024-05-15T18:17:42.120Z,1715797062.120 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-15T18:17:42.120Z,1715797062.120 [Default:CheckIn:D] Running Loop=1 2024-05-15T18:17:42.496Z,1715797062.496 [Default:CheckIn:D] Stopped 2024-05-15T18:17:42.497Z,1715797062.497 [Default:CheckIn:E] Running Loop=1 2024-05-15T18:17:42.900Z,1715797062.900 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 17.206694 min 2024-05-15T18:17:42.900Z,1715797062.900 [Default:CheckIn:E] Stopped 2024-05-15T18:17:42.900Z,1715797062.900 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-15T18:17:42.900Z,1715797062.900 [Default:CheckIn] Stopped 2024-05-15T18:17:42.900Z,1715797062.900 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-15T18:17:42.901Z,1715797062.901 [Default:CheckIn](INFO): Running loop #4 2024-05-15T18:17:42.901Z,1715797062.901 [Default:CheckIn] Running Loop=4 2024-05-15T18:17:42.901Z,1715797062.901 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-15T18:17:42.901Z,1715797062.901 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-15T18:17:44.916Z,1715797064.916 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181744.00,A,3648.17153,N,12147.28327,W,0.058,35.97,150524,,,A*44 2024-05-15T18:17:44.918Z,1715797064.918 [NAL9602](INFO): GPS fix at 20240515T181744: (36.802859, -121.788055) 2024-05-15T18:17:44.934Z,1715797064.934 [Default:CheckIn:Read_GPS] Stopped 2024-05-15T18:17:44.934Z,1715797064.934 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-15T18:17:54.441Z,1715797074.441 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240515T175220/Courier0009.lzma 2024-05-15T18:17:55.443Z,1715797075.443 [DataOverHttps](INFO): Moved sent file to Logs/20240515T175220/Courier0009.lzma.bak 2024-05-15T18:17:55.444Z,1715797075.444 [DataOverHttps](INFO): SBD MOMSN=21022906 2024-05-15T18:18:14.369Z,1715797094.369 [DataOverHttps](INFO): Sending 217 bytes from file Logs/20240515T175220/Express0010.lzma 2024-05-15T18:18:15.371Z,1715797095.371 [DataOverHttps](INFO): Moved sent file to Logs/20240515T175220/Express0010.lzma.bak 2024-05-15T18:18:15.371Z,1715797095.371 [DataOverHttps](INFO): SBD MOMSN=21022909 2024-05-15T18:18:17.634Z,1715797097.634 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-15T18:18:18.477Z,1715797098.477 [Default:CheckIn:Read_Iridium] Stopped 2024-05-15T18:18:18.478Z,1715797098.478 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-15T18:18:18.478Z,1715797098.478 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-15T18:20:46.426Z,1715797246.426 [DockingStepper](ERROR): only read 3 of 4 ADC values. Response is: ÿ/0`16320,16366,400 2024-05-15T18:20:46.426Z,1715797246.426 [DockingStepper](FAULT): Docking arm not reporting position 2024-05-15T18:20:46.426Z,1715797246.426 [DockingStepper] Communications Fault, FailCount= 1 2024-05-15T18:20:46.426Z,1715797246.426 [DockingStepper](ERROR): Communications Fault 2024-05-15T18:20:46.430Z,1715797246.430 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-15T18:20:46.732Z,1715797246.732 [DockingStepper](INFO): Stop 2024-05-15T18:20:46.732Z,1715797246.732 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-15T18:20:46.732Z,1715797246.732 [DockingStepper](INFO): Powering down 2024-05-15T18:20:47.100Z,1715797247.100 [DockingStepper](INFO): Stopping 2024-05-15T18:20:47.539Z,1715797247.539 [DockingStepper](INFO): Stopped 2024-05-15T18:20:47.547Z,1715797247.547 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-15T18:20:47.547Z,1715797247.547 [DockingStepper] No Fault, FailCount= 1 2024-05-15T18:20:47.968Z,1715797247.968 [DockingStepper](INFO): Start 2024-05-15T18:20:47.968Z,1715797247.968 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-15T18:20:47.969Z,1715797247.969 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-15T18:20:48.339Z,1715797248.339 [DockingStepper](INFO): Starting 2024-05-15T18:20:48.755Z,1715797248.755 [DockingStepper](INFO): Starting 2024-05-15T18:20:49.151Z,1715797249.151 [DockingStepper](INFO): Starting 2024-05-15T18:20:49.196Z,1715797249.196 [DockingStepper](INFO): Init string: /1z16367aE13561N3m50 2024-05-15T18:20:49.556Z,1715797249.556 [DockingStepper](INFO): Starting 2024-05-15T18:20:49.976Z,1715797249.976 [DockingStepper](INFO): Starting 2024-05-15T18:20:50.385Z,1715797250.385 [DockingStepper](INFO): Starting 2024-05-15T18:20:50.722Z,1715797250.722 [DockingStepper](INFO): Starting 2024-05-15T18:20:50.753Z,1715797250.753 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-15T18:20:51.130Z,1715797251.130 [DockingStepper](INFO): Starting 2024-05-15T18:20:56.094Z,1715797256.094 [DockingStepper](ERROR): only read 0 of 4 ADC values. Response is: ÿ/0@ 2024-05-15T18:20:56.094Z,1715797256.094 [DockingStepper](FAULT): Docking arm not reporting position 2024-05-15T18:20:56.094Z,1715797256.094 [DockingStepper] Communications Fault, FailCount= 1 2024-05-15T18:20:56.094Z,1715797256.094 [DockingStepper](ERROR): Communications Fault 2024-05-15T18:20:56.112Z,1715797256.112 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-15T18:20:56.446Z,1715797256.446 [DockingStepper](INFO): Stop 2024-05-15T18:20:56.446Z,1715797256.446 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-15T18:20:56.446Z,1715797256.446 [DockingStepper](INFO): Powering down 2024-05-15T18:20:56.824Z,1715797256.824 [DockingStepper](INFO): Stopping 2024-05-15T18:20:57.254Z,1715797257.254 [DockingStepper](INFO): Stopped 2024-05-15T18:20:57.290Z,1715797257.290 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-15T18:20:57.290Z,1715797257.290 [DockingStepper] No Fault, FailCount= 1 2024-05-15T18:20:57.651Z,1715797257.651 [DockingStepper](INFO): Start 2024-05-15T18:20:57.651Z,1715797257.651 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-15T18:20:57.651Z,1715797257.651 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-15T18:20:58.139Z,1715797258.139 [DockingStepper](INFO): Starting 2024-05-15T18:20:58.473Z,1715797258.473 [DockingStepper](INFO): Starting 2024-05-15T18:20:58.856Z,1715797258.856 [DockingStepper](INFO): Starting 2024-05-15T18:20:58.902Z,1715797258.902 [DockingStepper](INFO): Init string: /1z16366aE13561N3m50 2024-05-15T18:20:59.279Z,1715797259.279 [DockingStepper](INFO): Starting 2024-05-15T18:20:59.688Z,1715797259.688 [DockingStepper](INFO): Starting 2024-05-15T18:21:00.233Z,1715797260.233 [DockingStepper](INFO): Starting 2024-05-15T18:21:00.483Z,1715797260.483 [DockingStepper](INFO): Starting 2024-05-15T18:21:00.523Z,1715797260.523 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-15T18:21:00.891Z,1715797260.891 [DockingStepper](INFO): Starting 2024-05-15T18:21:13.995Z,1715797273.995 [CommandExec](IMPORTANT): got command run ./Missions/Engineering/OnDock.tl 2024-05-15T18:21:13.996Z,1715797273.996 [MissionManager](INFO): Loading Mission from file: ./Missions/Engineering/OnDock.tl 2024-05-15T18:21:13.996Z,1715797273.996 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Engineering/OnDock.tl 2024-05-15T18:21:13.998Z,1715797273.998 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Engineering/OnDock.tl 2024-05-15T18:21:14.648Z,1715797274.648 [MissionManager](DEBUG): TethyslAPI: ./Missions/Engineering/OnDock.tl translated into: 8 2 1 5 2 30 5 Restarting DHCP... ! /mnt/mmc/LRAUV/Tools/dhcp.sh Restarting logs restart logs ! ifconfig Mission ending, breaking off. Range: . 2024-05-15T18:21:14.654Z,1715797274.654 [MissionManager](INFO): DefineArg OnDock.DockedTime = 8.000000 h 2024-05-15T18:21:14.656Z,1715797274.656 [MissionManager](INFO): DefineArg OnDock.DetachedTimeout = 300.000000 s 2024-05-15T18:21:14.659Z,1715797274.659 [MissionManager](INFO): DefineArg OnDock.RangeTimeout = 600.000000 s 2024-05-15T18:21:14.661Z,1715797274.661 [MissionManager](INFO): DefineArg OnDock.TrackingUpdatePeriod = 2.000000 min 2024-05-15T18:21:14.667Z,1715797274.667 [MissionManager](INFO): DefineArg OnDock.NumberOfPings = 1.000000 count 2024-05-15T18:21:14.670Z,1715797274.670 [MissionManager](INFO): DefineArg OnDock.TransponderCode = 201 count 2024-05-15T18:21:14.673Z,1715797274.673 [MissionManager](INFO): DefineArg OnDock.MaxDepth = 215.000000 m 2024-05-15T18:21:14.675Z,1715797274.675 [MissionManager](INFO): DefineArg OnDock.MinAltitude = 5.000000 m 2024-05-15T18:21:14.678Z,1715797274.678 [MissionManager](INFO): DefineArg OnDock.MinOffshore = 2.000000 km 2024-05-15T18:21:14.681Z,1715797274.681 [MissionManager](INFO): DefineArg OnDock.StayOnDock = 0 bool 2024-05-15T18:21:14.685Z,1715797274.685 [MissionManager](INFO): DefineArg OnDock.IfconfigInterval = 30.000000 min 2024-05-15T18:21:14.736Z,1715797274.736 [MissionManager](INFO): DefineArg OnDock.MissionTimeout = 8.000000 h 2024-05-15T18:21:14.737Z,1715797274.737 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl 2024-05-15T18:21:14.746Z,1715797274.746 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl 2024-05-15T18:21:14.747Z,1715797274.747 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl 2024-05-15T18:21:14.888Z,1715797274.888 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into: 5 0 200 2000 2024-05-15T18:21:14.892Z,1715797274.892 [MissionManager](INFO): DefineArg OnDock:StandardEnvelopes.MinAltitude = 5.000000 m 2024-05-15T18:21:14.904Z,1715797274.904 [MissionManager](INFO): DefineArg OnDock:StandardEnvelopes.MaxDepthIgnore = 0.000000 m 2024-05-15T18:21:14.907Z,1715797274.907 [MissionManager](INFO): DefineArg OnDock:StandardEnvelopes.MaxDepth = 200.000000 m 2024-05-15T18:21:14.909Z,1715797274.909 [MissionManager](INFO): DefineArg OnDock:StandardEnvelopes.MinOffshore = 2000.000000 m 2024-05-15T18:21:14.910Z,1715797274.910 [OnDock:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2024-05-15T18:21:14.916Z,1715797274.916 [OnDock:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2024-05-15T18:21:14.922Z,1715797274.922 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2024-05-15T18:21:15.010Z,1715797275.010 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl 2024-05-15T18:21:15.010Z,1715797275.010 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl 2024-05-15T18:21:15.011Z,1715797275.011 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl 2024-05-15T18:21:15.073Z,1715797275.073 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into: 2024-05-15T18:21:15.085Z,1715797275.085 [MissionManager](INFO): DefineArg OnDock:BackseatDriver.EnableBackseat = 0 bool 2024-05-15T18:21:15.087Z,1715797275.087 [OnDock:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver. 2024-05-15T18:21:15.090Z,1715797275.090 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl 2024-05-15T18:21:15.090Z,1715797275.090 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl 2024-05-15T18:21:15.092Z,1715797275.092 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl 2024-05-15T18:21:15.238Z,1715797275.238 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into: 99999 2024-05-15T18:21:15.242Z,1715797275.242 [MissionManager](INFO): DefineArg OnDock:PowerOnly.SampleAll = 0 bool 2024-05-15T18:21:15.244Z,1715797275.244 [MissionManager](INFO): DefineArg OnDock:PowerOnly.SampleLoad1 = 0 bool 2024-05-15T18:21:15.246Z,1715797275.246 [MissionManager](INFO): DefineArg OnDock:PowerOnly.SampleLoad2 = 0 bool 2024-05-15T18:21:15.247Z,1715797275.247 [MissionManager](INFO): DefineArg OnDock:PowerOnly.SampleLoad3 = 0 bool 2024-05-15T18:21:15.250Z,1715797275.250 [MissionManager](INFO): DefineArg OnDock:PowerOnly.EnabledPowerOnly = 0 bool 2024-05-15T18:21:15.256Z,1715797275.256 [OnDock:PowerOnly:E.Wait](DEBUG): Construct Wait. 2024-05-15T18:21:15.399Z,1715797275.399 [OnDock:restartDHCP:B.Execute](DEBUG): Construct Execute. 2024-05-15T18:21:15.401Z,1715797275.401 [OnDock:L.Execute](DEBUG): Construct Execute. 2024-05-15T18:21:15.408Z,1715797275.408 [OnDock:PeriodicCheck:CheckIP:A.Execute](DEBUG): Construct Execute. 2024-05-15T18:21:15.412Z,1715797275.412 [OnDock:PeriodicCheck:CheckIP:B.Wait](DEBUG): Construct Wait. 2024-05-15T18:21:15.430Z,1715797275.430 [OnDock:O.Docked](DEBUG): Construct. 2024-05-15T18:21:15.430Z,1715797275.430 [OnDock:O.Docked](DEBUG): Initializing internal variables to default values. 2024-05-15T18:21:15.465Z,1715797275.465 [OnDock:Detach:B.Undock](DEBUG): Construct. 2024-05-15T18:21:15.465Z,1715797275.465 [OnDock:Detach:B.Undock](DEBUG): Initializing internal variables to default values. 2024-05-15T18:21:15.492Z,1715797275.492 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission { """ Wait on dock, communicating when possible """ arguments { DockedTime = 8 hour """ Time to stay on dock. Mission timeout will be five minutes longer. """ DetachedTimeout = Dock:Docked.detachTimeout """ Time duration limit for determining if the vehicle is detached from the dock. """ RangeTimeout = Dock:Docked.rangeTimeout """ Maximum allowed time without acoustic range. """ TrackingUpdatePeriod = 2 minute """ How long to wait between acoustic queries. """ NumberOfPings = 1 count """ Number of pings requested each time. """ TransponderCode = Dock:Dock.transponderCode """ Transponder Address. """ MaxDepth = BIT:CBIT.stopDepth """ Maximum depth while docked. """ MinAltitude = 5 meter """ Minimum altitude """ MinOffshore = 2 km """ Minimum offshore """ StayOnDock = false """ If true, don't detach from dock at end of mission """ IfconfigInterval = 30 minute """ How long to wait between ifconfig runs """ # Mission variables (do not change) MissionTimeout = DockedTime """ Mission timeout (local variable, do not change) """ } timeout duration=MissionTimeout insert Insert/StandardEnvelopes.tl assign in sequence StandardEnvelopes:MinAltitude = MinAltitude assign in sequence StandardEnvelopes:MaxDepth = MaxDepth assign in sequence StandardEnvelopes:MinOffshore = MinOffshore # End docking 5 minutes before end of mission to allow clean undock assign in sequence MissionTimeout = DockedTime + 5 minute insert Insert/BackseatDriver.tl insert Insert/PowerOnly.tl # Send data when connection exists readData { while ( Sensor:DataOverHttps.connectionStatus == true ) Universal:platform_communications } behavior Estimation:TrackAcousticContact { run in parallel set contactLabelSetting = TransponderCode set numberOfSamplesSetting = NumberOfPings set updatePeriodSetting = TrackingUpdatePeriod } aggregate restartDHCP { run when ( called ) syslog important "Restarting DHCP..." behavior Guidance:Execute { run in sequence set command = "! /mnt/mmc/LRAUV/Tools/dhcp.sh" } } syslog important "Restarting logs" behavior Guidance:Execute { run in sequence set command = "restart logs" } # Kick DHCP again, in case it took us a while to close the arm in Docked call refId="restartDHCP" aggregate PeriodicCheck { run in parallel aggregate CheckIP { run in sequence behavior Guidance:Execute { run in sequence set command = "! ifconfig" } behavior Guidance:Wait { run in sequence set duration = IfconfigInterval } } } behavior Dock:Docked { run in sequence timeout duration=DockedTime set detachTimeout = DetachedTimeout set rangeTimeout = RangeTimeout } # Undock cleanly, instead of relying on DefaultWithUndock to come next aggregate Detach { run in sequence break if ( StayOnDock == true ) syslog important "Mission ending, breaking off. Range: " + Estimation:TrackAcousticContact.range_to_contact~meter + "." behavior Dock:Undock { run in sequence } # Get our 10.89 IP back once we're on the surface call refId="restartDHCP" } } 2024-05-15T18:21:15.495Z,1715797275.495 [CommandExec](IMPORTANT): Running ./Missions/Engineering/OnDock.tl 2024-05-15T18:21:15.849Z,1715797275.849 [Default] Stopped 2024-05-15T18:21:15.849Z,1715797275.849 [Default](DEBUG): Aggregate::uninitialize Default 2024-05-15T18:21:15.849Z,1715797275.849 [Default:B.GoToSurface] Stopped 2024-05-15T18:21:15.849Z,1715797275.849 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-05-15T18:21:15.850Z,1715797275.850 [Default:CheckIn] Stopped 2024-05-15T18:21:15.850Z,1715797275.850 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-15T18:21:15.850Z,1715797275.850 [Default:CheckIn:C.Wait] Stopped 2024-05-15T18:21:15.850Z,1715797275.850 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-15T18:21:15.850Z,1715797275.850 [MissionManager](IMPORTANT): Started mission OnDock 2024-05-15T18:21:15.850Z,1715797275.850 [OnDock] Running Loop=1 2024-05-15T18:21:15.850Z,1715797275.850 [OnDock](DEBUG): Aggregate::initialize OnDock 2024-05-15T18:21:15.850Z,1715797275.850 [OnDock:StandardEnvelopes] Running Loop=1 2024-05-15T18:21:15.851Z,1715797275.851 [OnDock:StandardEnvelopes](DEBUG): Aggregate::initialize OnDock:StandardEnvelopes 2024-05-15T18:21:15.851Z,1715797275.851 [OnDock:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2024-05-15T18:21:15.851Z,1715797275.851 [OnDock:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2024-05-15T18:21:15.851Z,1715797275.851 [OnDock:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2024-05-15T18:21:15.851Z,1715797275.851 [OnDock:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2024-05-15T18:21:15.851Z,1715797275.851 [OnDock:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2024-05-15T18:21:15.851Z,1715797275.851 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2024-05-15T18:21:15.851Z,1715797275.851 [OnDock:BackseatDriver] Running Loop=1 2024-05-15T18:21:15.851Z,1715797275.851 [OnDock:BackseatDriver](DEBUG): Aggregate::initialize OnDock:BackseatDriver 2024-05-15T18:21:15.851Z,1715797275.851 [OnDock:BackseatDriver:A.BackseatDriver] Running Loop=1 2024-05-15T18:21:15.852Z,1715797275.852 [OnDock:PowerOnly] Running Loop=1 2024-05-15T18:21:15.852Z,1715797275.852 [OnDock:PowerOnly](DEBUG): Aggregate::initialize OnDock:PowerOnly 2024-05-15T18:21:15.852Z,1715797275.852 [OnDock:PowerOnly:A] Running Loop=1 2024-05-15T18:21:15.852Z,1715797275.852 [OnDock:PowerOnly:B] Running Loop=1 2024-05-15T18:21:15.852Z,1715797275.852 [OnDock:PowerOnly:C] Running Loop=1 2024-05-15T18:21:15.852Z,1715797275.852 [OnDock:PowerOnly:D] Running Loop=1 2024-05-15T18:21:15.852Z,1715797275.852 [OnDock:PowerOnly:E.Wait] Running Loop=1 2024-05-15T18:21:15.852Z,1715797275.852 [OnDock:PowerOnly:E.Wait](DEBUG): Initialize Wait Component. 2024-05-15T18:21:15.852Z,1715797275.852 [OnDock:H] Running Loop=1 2024-05-15T18:21:15.852Z,1715797275.852 [OnDock:I.] Running Loop=1 2024-05-15T18:21:15.852Z,1715797275.852 [OnDock:I.](INFO): Initializing TrackAcousticContact. 2024-05-15T18:21:15.852Z,1715797275.852 [OnDock:PeriodicCheck] Running Loop=1 2024-05-15T18:21:15.853Z,1715797275.853 [OnDock:PeriodicCheck](DEBUG): Aggregate::initialize OnDock:PeriodicCheck 2024-05-15T18:21:15.853Z,1715797275.853 [OnDock:PeriodicCheck:CheckIP] Running Loop=1 2024-05-15T18:21:15.853Z,1715797275.853 [OnDock:PeriodicCheck:CheckIP](DEBUG): Aggregate::initialize OnDock:PeriodicCheck:CheckIP 2024-05-15T18:21:15.853Z,1715797275.853 [OnDock:PeriodicCheck:CheckIP:A.Execute] Running Loop=1 2024-05-15T18:21:15.853Z,1715797275.853 [OnDock:B] Running Loop=1 2024-05-15T18:21:15.854Z,1715797275.854 [OnDock:PeriodicCheck] Running Loop=1 2024-05-15T18:21:15.854Z,1715797275.854 [OnDock:PeriodicCheck:CheckIP:A.Execute](INFO): Executing command ! ifconfig 2024-05-15T18:21:15.854Z,1715797275.854 [OnDock:PeriodicCheck:CheckIP:A.Execute] Stopped 2024-05-15T18:21:15.854Z,1715797275.854 [OnDock:PeriodicCheck:CheckIP:B.Wait] Running Loop=1 2024-05-15T18:21:15.854Z,1715797275.854 [OnDock:PeriodicCheck:CheckIP:B.Wait](DEBUG): Initialize Wait Component. 2024-05-15T18:21:15.854Z,1715797275.854 [OnDock:I.] Running Loop=1 2024-05-15T18:21:15.854Z,1715797275.854 [OnDock:H] Running Loop=1 2024-05-15T18:21:15.855Z,1715797275.855 [OnDock:H](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-05-15T18:21:15.855Z,1715797275.855 [OnDock:PowerOnly] Running Loop=1 2024-05-15T18:21:15.855Z,1715797275.855 [OnDock:PowerOnly:D] Stopped 2024-05-15T18:21:15.856Z,1715797275.856 [OnDock:PowerOnly:C] Stopped 2024-05-15T18:21:15.856Z,1715797275.856 [OnDock:PowerOnly:B] Stopped 2024-05-15T18:21:15.856Z,1715797275.856 [OnDock:PowerOnly:A] Stopped 2024-05-15T18:21:15.856Z,1715797275.856 [OnDock:BackseatDriver] Running Loop=1 2024-05-15T18:21:15.856Z,1715797275.856 [OnDock:B] Stopped 2024-05-15T18:21:15.856Z,1715797275.856 [OnDock:C] Running Loop=1 2024-05-15T18:21:15.856Z,1715797275.856 [OnDock:StandardEnvelopes] Running Loop=1 2024-05-15T18:21:15.856Z,1715797275.856 [OnDock:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2024-05-15T18:21:15.857Z,1715797275.857 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](IMPORTANT): Offshore envelope ACTIVE 2024-05-15T18:21:15.864Z,1715797275.864 [OnDock:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2024-05-15T18:21:15.864Z,1715797275.864 [OnDock:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2024-05-15T18:21:15.915Z,1715797275.915 [CommandExec](IMPORTANT): got command ! ifconfig 2024-05-15T18:21:15.938Z,1715797275.938 [DockingStepper](INFO): Deisolating dock charge 2024-05-15T18:21:16.197Z,1715797276.197 [CommandExec](IMPORTANT): eth0 Link encap:Ethernet HWaddr 50:2D:F4:07:45:4B inet addr:10.89.10.2 Bcast:10.89.255.255 Mask:255.255.0.0 UP BROADCAST RUNNING MULTICAST MTU:1500 Metric:1 RX packets:299370 errors:0 dropped:0 overruns:0 frame:0 TX packets:529035 errors:0 dropped:0 overruns:0 carrier:0 collisions:1865 txqueuelen:1000 RX bytes:46833958 (44.6 Mb) TX bytes:722728065 (689.2 Mb) Interrupt:29 lo Link encap:Local Loopback inet addr:127.0.0.1 Mask:255.0.0.0 UP LOOPBACK RUNNING MULTICAST MTU:16436 Metric:1 RX packets:703 errors:0 dropped:0 overruns:0 frame:0 TX packets:703 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:0 RX bytes:58661 (57.2 Kb) TX bytes:58661 (57.2 Kb) 2024-05-15T18:21:16.791Z,1715797276.791 [OnDock:C] Stopped 2024-05-15T18:21:16.791Z,1715797276.791 [OnDock:D] Running Loop=1 2024-05-15T18:21:17.171Z,1715797277.171 [OnDock:D] Stopped 2024-05-15T18:21:17.171Z,1715797277.171 [OnDock:E] Running Loop=1 2024-05-15T18:21:17.563Z,1715797277.563 [OnDock:E] Stopped 2024-05-15T18:21:17.563Z,1715797277.563 [OnDock:K] Running Loop=1 2024-05-15T18:21:17.998Z,1715797277.998 [OnDock:K](IMPORTANT): Restarting logs 2024-05-15T18:21:17.998Z,1715797277.998 [OnDock:K] Stopped 2024-05-15T18:21:17.998Z,1715797277.998 [OnDock:L.Execute] Running Loop=1