2024-05-15T18:21:18.365Z,1715797278.365 [OnDock:L.Execute](INFO): Executing command restart logs 2024-05-15T18:21:18.365Z,1715797278.365 [OnDock:L.Execute] Stopped 2024-05-15T18:21:18.365Z,1715797278.365 [OnDock:M] Running Loop=1 2024-05-15T18:21:18.366Z,1715797278.366 [OnDock:M](DEBUG): Aggregate::initialize OnDock:M 2024-05-15T18:21:18.383Z,1715797278.383 [CommandExec](IMPORTANT): got command restart logs 2024-05-15T18:21:18.803Z,1715797278.803 [OnDock:restartDHCP] Running Loop=1 2024-05-15T18:21:18.803Z,1715797278.803 [OnDock:restartDHCP](DEBUG): Aggregate::initialize OnDock:restartDHCP 2024-05-15T18:21:18.803Z,1715797278.803 [OnDock:restartDHCP:A] Running Loop=1 2024-05-15T18:21:18.803Z,1715797278.803 [OnDock:restartDHCP:A](IMPORTANT): Restarting DHCP... 2024-05-15T18:21:18.803Z,1715797278.803 [OnDock:restartDHCP:A] Stopped 2024-05-15T18:21:18.803Z,1715797278.803 [OnDock:restartDHCP:B.Execute] Running Loop=1 2024-05-15T18:21:19.212Z,1715797279.212 [OnDock:restartDHCP:B.Execute](INFO): Executing command ! /mnt/mmc/LRAUV/Tools/dhcp.sh 2024-05-15T18:21:19.212Z,1715797279.212 [OnDock:restartDHCP:B.Execute] Stopped 2024-05-15T18:21:19.212Z,1715797279.212 [OnDock:restartDHCP](INFO): Completed OnDock:restartDHCP 2024-05-15T18:21:19.212Z,1715797279.212 [OnDock:restartDHCP] Stopped 2024-05-15T18:21:19.212Z,1715797279.212 [OnDock:restartDHCP](DEBUG): Aggregate::uninitialize OnDock:restartDHCP 2024-05-15T18:21:19.590Z,1715797279.590 [OnDock:M](INFO): Completed OnDock:M 2024-05-15T18:21:19.590Z,1715797279.590 [OnDock:M] Stopped 2024-05-15T18:21:19.590Z,1715797279.590 [OnDock:M](DEBUG): Aggregate::uninitialize OnDock:M 2024-05-15T18:21:19.590Z,1715797279.590 [OnDock:O.Docked] Running Loop=1 2024-05-15T18:21:19.591Z,1715797279.591 [OnDock:O.Docked](DEBUG): Initialize. 2024-05-15T18:21:19.591Z,1715797279.591 [OnDock:O.Docked](DEBUG): Initializing internal variables to default values. 2024-05-15T18:21:27.735Z,1715797287.735 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240515T175220/Courier0012.lzma 2024-05-15T18:21:28.731Z,1715797288.731 [DataOverHttps](INFO): Moved sent file to Logs/20240515T175220/Courier0012.lzma.bak 2024-05-15T18:21:28.731Z,1715797288.731 [DataOverHttps](INFO): SBD MOMSN=21022913 2024-05-15T18:21:31.419Z,1715797291.419 [CommandExec](IMPORTANT): got command ! /mnt/mmc/LRAUV/Tools/dhcp.sh 2024-05-15T18:21:32.351Z,1715797292.351 [CommandExec](IMPORTANT): /mnt/mmc/started/2024-05-15UTC182132_sh 2024-05-15T18:21:53.286Z,1715797313.286 [DataOverHttps](INFO): Sending 653 bytes from file Logs/20240515T175220/Express0013.lzma 2024-05-15T18:21:54.287Z,1715797314.287 [DataOverHttps](INFO): Moved sent file to Logs/20240515T175220/Express0013.lzma.bak 2024-05-15T18:21:54.287Z,1715797314.287 [DataOverHttps](INFO): SBD MOMSN=21022921 2024-05-15T18:22:14.023Z,1715797334.023 [DataOverHttps](INFO): Sending 125 bytes from file Logs/20240515T175220/Express0016.lzma 2024-05-15T18:22:15.023Z,1715797335.023 [DataOverHttps](INFO): Moved sent file to Logs/20240515T175220/Express0016.lzma.bak 2024-05-15T18:22:15.023Z,1715797335.023 [DataOverHttps](INFO): SBD MOMSN=21022931 2024-05-15T18:23:16.122Z,1715797396.122 [OnDock:I.](INFO): *** querying acoustic contact *** 2024-05-15T18:23:16.258Z,1715797396.258 [DAT](INFO): ****** received valid address query ****** 2024-05-15T18:23:16.259Z,1715797396.259 [DAT](INFO): ****** received valid ping request ****** 2024-05-15T18:23:16.259Z,1715797396.259 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode. 2024-05-15T18:23:16.510Z,1715797396.510 [DAT](INFO): DAT read: user:11> 2024-05-15T18:23:16.511Z,1715797396.511 [DAT](INFO): DAT read: Tx time:18:23:16.1901 2024-05-15T18:23:16.511Z,1715797396.511 [DAT](INFO): Ping request sent. 2024-05-15T18:23:16.511Z,1715797396.511 [DAT](INFO): transmitted an acoustic signal 2024-05-15T18:23:16.511Z,1715797396.511 [DAT](INFO): publishing transmit ping time 2024-05-15T18:23:16.512Z,1715797396.512 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000727 2024-05-15T18:23:16.763Z,1715797396.763 [DAT](INFO): DAT read: TxSync time:18:23:16.1893 2024-05-15T18:23:16.763Z,1715797396.763 [DAT](INFO): unknown deviceResponse_: TxSync time:18:23:16.1893 2024-05-15T18:23:16.763Z,1715797396.763 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.252227 2024-05-15T18:23:17.015Z,1715797397.015 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.503652 2024-05-15T18:23:17.270Z,1715797397.270 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.758860 2024-05-15T18:23:17.523Z,1715797397.523 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.011642 2024-05-15T18:23:17.776Z,1715797397.776 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.264846 2024-05-15T18:23:18.026Z,1715797398.026 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.514911 2024-05-15T18:23:18.279Z,1715797398.279 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.767263 2024-05-15T18:23:18.530Z,1715797398.530 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.018969 2024-05-15T18:23:18.782Z,1715797398.782 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.270977 2024-05-15T18:23:19.035Z,1715797399.035 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.523867 2024-05-15T18:23:19.286Z,1715797399.286 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.774940 2024-05-15T18:23:19.538Z,1715797399.538 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.027018 2024-05-15T18:23:19.790Z,1715797399.790 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.278905 2024-05-15T18:23:20.042Z,1715797400.042 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.531000 2024-05-15T18:23:20.294Z,1715797400.294 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.782895 2024-05-15T18:23:20.546Z,1715797400.546 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.034882 2024-05-15T18:23:20.798Z,1715797400.798 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.287003 2024-05-15T18:23:21.050Z,1715797401.050 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.538959 2024-05-15T18:23:21.302Z,1715797401.302 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.790972 2024-05-15T18:23:21.555Z,1715797401.555 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.043272 2024-05-15T18:23:21.787Z,1715797401.787 [CommandExec](IMPORTANT): got command get Dock.dock_voltage_present 2024-05-15T18:23:21.788Z,1715797401.788 [CommandExec](IMPORTANT): Dock.dock_voltage_present 0 bool 2024-05-15T18:23:21.806Z,1715797401.806 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.295069 2024-05-15T18:23:22.058Z,1715797402.058 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.547159 2024-05-15T18:23:22.311Z,1715797402.311 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.800096 2024-05-15T18:23:22.562Z,1715797402.562 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.050929 2024-05-15T18:23:22.814Z,1715797402.814 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.302972 2024-05-15T18:23:23.067Z,1715797403.067 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.555670 2024-05-15T18:23:23.318Z,1715797403.318 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.806693 2024-05-15T18:23:23.572Z,1715797403.572 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.060978 2024-05-15T18:23:23.822Z,1715797403.822 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.310907 2024-05-15T18:23:24.074Z,1715797404.074 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.562967 2024-05-15T18:23:24.327Z,1715797404.327 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.815375 2024-05-15T18:23:24.578Z,1715797404.578 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.067032 2024-05-15T18:23:24.830Z,1715797404.830 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.318959 2024-05-15T18:23:25.083Z,1715797405.083 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.571469 2024-05-15T18:23:25.334Z,1715797405.334 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.822891 2024-05-15T18:23:25.586Z,1715797405.586 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.074949 2024-05-15T18:23:25.838Z,1715797405.838 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.326953 2024-05-15T18:23:26.090Z,1715797406.090 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.579027 2024-05-15T18:23:26.343Z,1715797406.343 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.831264 2024-05-15T18:23:28.862Z,1715797408.862 [DAT](INFO): DAT read: Response Not Received 2024-05-15T18:23:28.862Z,1715797408.862 [DAT](INFO): response not received 2024-05-15T18:23:28.863Z,1715797408.863 [DAT](ERROR): No response from remote modem. 2024-05-15T18:24:05.941Z,1715797445.941 [CommandExec](IMPORTANT): got command report mod Dock.dock_voltage_present 2024-05-15T18:24:06.142Z,1715797446.142 [Reporter](INFO): Dock.dock_voltage_present 0 bool 2024-05-15T18:25:16.228Z,1715797516.228 [OnDock:I.](INFO): *** querying acoustic contact *** 2024-05-15T18:25:16.238Z,1715797516.238 [DAT](INFO): ****** received valid address query ****** 2024-05-15T18:25:16.238Z,1715797516.238 [DAT](INFO): ****** received valid ping request ****** 2024-05-15T18:25:16.238Z,1715797516.238 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode. 2024-05-15T18:25:16.490Z,1715797516.490 [DAT](INFO): DAT read: user:12> 2024-05-15T18:25:16.491Z,1715797516.491 [DAT](INFO): DAT read: Tx time:18:25:16.1413 2024-05-15T18:25:16.492Z,1715797516.492 [DAT](INFO): Ping request sent. 2024-05-15T18:25:16.492Z,1715797516.492 [DAT](INFO): transmitted an acoustic signal 2024-05-15T18:25:16.493Z,1715797516.493 [DAT](INFO): DAT read: TxSync time:18:25:16.1405 2024-05-15T18:25:16.493Z,1715797516.493 [DAT](INFO): unknown deviceResponse_: TxSync time:18:25:16.1405 2024-05-15T18:25:16.493Z,1715797516.493 [DAT](INFO): publishing transmit ping time 2024-05-15T18:25:16.494Z,1715797516.494 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.002295 2024-05-15T18:25:16.743Z,1715797516.743 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250483 2024-05-15T18:25:16.994Z,1715797516.994 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502085 2024-05-15T18:25:17.246Z,1715797517.246 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754417 2024-05-15T18:25:17.498Z,1715797517.498 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006257 2024-05-15T18:25:17.753Z,1715797517.753 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.260089 2024-05-15T18:25:18.003Z,1715797518.003 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510591 2024-05-15T18:25:18.254Z,1715797518.254 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762219 2024-05-15T18:25:18.506Z,1715797518.506 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014093 2024-05-15T18:25:18.758Z,1715797518.758 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266185 2024-05-15T18:25:19.010Z,1715797519.010 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518157 2024-05-15T18:25:19.262Z,1715797519.262 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770429 2024-05-15T18:25:19.514Z,1715797519.514 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022142 2024-05-15T18:25:19.768Z,1715797519.768 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.275438 2024-05-15T18:25:20.018Z,1715797520.018 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.526155 2024-05-15T18:25:20.270Z,1715797520.270 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.778215 2024-05-15T18:25:20.522Z,1715797520.522 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.030167 2024-05-15T18:25:20.774Z,1715797520.774 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.282199 2024-05-15T18:25:21.026Z,1715797521.026 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.534151 2024-05-15T18:25:21.156Z,1715797521.156 [DockingStepper](ERROR): only read 0 of 4 ADC values. Response is: ÿ/0@ 2024-05-15T18:25:21.157Z,1715797521.157 [DockingStepper](FAULT): Docking arm not reporting position 2024-05-15T18:25:21.161Z,1715797521.161 [DockingStepper] Communications Fault, FailCount= 1 2024-05-15T18:25:21.161Z,1715797521.161 [DockingStepper](ERROR): Communications Fault 2024-05-15T18:25:21.236Z,1715797521.236 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-15T18:25:21.238Z,1715797521.238 [Reporter](INFO): Dock.dock_voltage_present 0 bool 2024-05-15T18:25:21.278Z,1715797521.278 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.786219 2024-05-15T18:25:21.513Z,1715797521.513 [DockingStepper](INFO): Stop 2024-05-15T18:25:21.530Z,1715797521.530 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.037932 2024-05-15T18:25:21.535Z,1715797521.535 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-15T18:25:21.535Z,1715797521.535 [DockingStepper](INFO): Powering down 2024-05-15T18:25:21.784Z,1715797521.784 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.291825 2024-05-15T18:25:21.974Z,1715797521.974 [DockingStepper](INFO): Stopping 2024-05-15T18:25:22.034Z,1715797522.034 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.542253 2024-05-15T18:25:22.286Z,1715797522.286 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.794092 2024-05-15T18:25:22.367Z,1715797522.367 [DockingStepper](INFO): Stopped 2024-05-15T18:25:22.397Z,1715797522.397 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-15T18:25:22.397Z,1715797522.397 [DockingStepper] No Fault, FailCount= 1 2024-05-15T18:25:22.538Z,1715797522.538 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.046415 2024-05-15T18:25:22.754Z,1715797522.754 [DockingStepper](INFO): Start 2024-05-15T18:25:22.754Z,1715797522.754 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-15T18:25:22.755Z,1715797522.755 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-15T18:25:22.790Z,1715797522.790 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.298134 2024-05-15T18:25:23.042Z,1715797523.042 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.550222 2024-05-15T18:25:23.155Z,1715797523.155 [DockingStepper](INFO): Starting 2024-05-15T18:25:23.294Z,1715797523.294 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.802493 2024-05-15T18:25:23.546Z,1715797523.546 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.054116 2024-05-15T18:25:23.615Z,1715797523.615 [DockingStepper](INFO): Starting 2024-05-15T18:25:23.798Z,1715797523.798 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.306150 2024-05-15T18:25:24.040Z,1715797524.040 [DockingStepper](INFO): Starting 2024-05-15T18:25:24.050Z,1715797524.050 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.558078 2024-05-15T18:25:24.092Z,1715797524.092 [DockingStepper](INFO): Init string: /1z16352aE13561N3m50 2024-05-15T18:25:24.302Z,1715797524.302 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.810022 2024-05-15T18:25:24.392Z,1715797524.392 [DockingStepper](INFO): Starting 2024-05-15T18:25:24.554Z,1715797524.554 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.062163 2024-05-15T18:25:24.770Z,1715797524.770 [DockingStepper](INFO): Starting 2024-05-15T18:25:24.806Z,1715797524.806 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.314220 2024-05-15T18:25:25.058Z,1715797525.058 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.566242 2024-05-15T18:25:25.162Z,1715797525.162 [DockingStepper](INFO): Starting 2024-05-15T18:25:25.310Z,1715797525.310 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.818162 2024-05-15T18:25:25.563Z,1715797525.563 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.071083 2024-05-15T18:25:25.676Z,1715797525.676 [DockingStepper](INFO): Starting 2024-05-15T18:25:25.726Z,1715797525.726 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-15T18:25:25.814Z,1715797525.814 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.322268 2024-05-15T18:25:26.066Z,1715797526.066 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.574105 2024-05-15T18:25:26.116Z,1715797526.116 [DockingStepper](INFO): Starting 2024-05-15T18:25:26.148Z,1715797526.148 [Reporter](INFO): Dock.dock_voltage_present 0 bool 2024-05-15T18:25:26.318Z,1715797526.318 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.825972 2024-05-15T18:25:26.401Z,1715797526.401 [DockingStepper](INFO): Deisolating dock charge 2024-05-15T18:25:28.841Z,1715797528.841 [DAT](INFO): DAT read: Response Not Received 2024-05-15T18:25:28.841Z,1715797528.841 [DAT](INFO): response not received 2024-05-15T18:25:28.841Z,1715797528.841 [DAT](ERROR): No response from remote modem. 2024-05-15T18:25:30.305Z,1715797530.305 [CommandExec](IMPORTANT): got command report touch Dock.dock_voltage_present 2024-05-15T18:25:30.604Z,1715797530.604 [Reporter](INFO): Dock.dock_voltage_present 0 bool 2024-05-15T18:25:30.990Z,1715797530.990 [Reporter](INFO): Dock.dock_voltage_present 0 bool 2024-05-15T18:25:31.404Z,1715797531.404 [Reporter](INFO): Dock.dock_voltage_present 0 bool 2024-05-15T18:25:31.808Z,1715797531.808 [Reporter](INFO): Dock.dock_voltage_present 0 bool 2024-05-15T18:25:32.255Z,1715797532.255 [Reporter](INFO): Dock.dock_voltage_present 0 bool 2024-05-15T18:25:32.606Z,1715797532.606 [Reporter](INFO): Dock.dock_voltage_present 0 bool 2024-05-15T18:25:33.066Z,1715797533.066 [Reporter](INFO): Dock.dock_voltage_present 0 bool 2024-05-15T18:25:33.416Z,1715797533.416 [Reporter](INFO): Dock.dock_voltage_present 0 bool 2024-05-15T18:25:33.818Z,1715797533.818 [Reporter](INFO): Dock.dock_voltage_present 0 bool 2024-05-15T18:25:34.274Z,1715797534.274 [Reporter](INFO): Dock.dock_voltage_present 0 bool 2024-05-15T18:25:34.635Z,1715797534.635 [Reporter](INFO): Dock.dock_voltage_present 0 bool 2024-05-15T18:25:35.029Z,1715797535.029 [Reporter](INFO): Dock.dock_voltage_present 0 bool 2024-05-15T18:25:35.454Z,1715797535.454 [Reporter](INFO): Dock.dock_voltage_present 0 bool 2024-05-15T18:25:35.850Z,1715797535.850 [Reporter](INFO): Dock.dock_voltage_present 0 bool 2024-05-15T18:25:36.298Z,1715797536.298 [Reporter](INFO): Dock.dock_voltage_present 0 bool 2024-05-15T18:25:36.547Z,1715797536.547 [CommandExec](IMPORTANT): got command report clear 2024-05-15T18:25:40.080Z,1715797540.080 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for bottom velocity. Device response is::BS,-32768,-32768 2024-05-15T18:27:02.635Z,1715797622.635 [CommandExec](IMPORTANT): got command show variable discrete 2024-05-15T18:27:02.934Z,1715797622.934 [CommandExec](IMPORTANT): AHRS_M2.component_discreteIn (bool) 2024-05-15T18:27:02.935Z,1715797622.935 [CommandExec](IMPORTANT): BackseatComponent.component_discreteIn (bool) 2024-05-15T18:27:02.960Z,1715797622.960 [CommandExec](IMPORTANT): Depth_Keller.component_discreteIn (bool) 2024-05-15T18:27:02.969Z,1715797622.969 [CommandExec](IMPORTANT): NAL9602.component_discreteIn (bool) 2024-05-15T18:27:02.991Z,1715797622.991 [CommandExec](IMPORTANT): Power24vConverter.component_discreteIn (bool) 2024-05-15T18:27:02.992Z,1715797622.992 [CommandExec](IMPORTANT): Radio_Surface.component_discreteIn (bool) 2024-05-15T18:27:03.001Z,1715797623.001 [CommandExec](IMPORTANT): RDI_Pathfinder.component_discreteIn (bool) 2024-05-15T18:27:03.004Z,1715797623.004 [CommandExec](IMPORTANT): DAT.component_discreteIn (bool) 2024-05-15T18:27:03.091Z,1715797623.091 [CommandExec](IMPORTANT): BuoyancyServo.component_discreteIn (bool) 2024-05-15T18:27:03.092Z,1715797623.092 [CommandExec](IMPORTANT): ElevatorServo.component_discreteIn (bool) 2024-05-15T18:27:03.092Z,1715797623.092 [CommandExec](IMPORTANT): DockingStepper.component_discreteIn (bool) 2024-05-15T18:27:03.131Z,1715797623.131 [CommandExec](IMPORTANT): MassServo.component_discreteIn (bool) 2024-05-15T18:27:03.132Z,1715797623.132 [CommandExec](IMPORTANT): RudderServo.component_discreteIn (bool) 2024-05-15T18:27:03.133Z,1715797623.133 [CommandExec](IMPORTANT): ThrusterServo.component_discreteIn (bool) 2024-05-15T18:27:03.141Z,1715797623.141 [CommandExec](IMPORTANT): CTD_Seabird.component_discreteIn (bool) 2024-05-15T18:27:03.157Z,1715797623.157 [CommandExec](IMPORTANT): ESPComponent.component_discreteIn (bool) 2024-05-15T18:27:03.168Z,1715797623.168 [CommandExec](IMPORTANT): PAR_Licor.component_discreteIn (bool) 2024-05-15T18:27:03.169Z,1715797623.169 [CommandExec](IMPORTANT): WetLabsBB2FL.component_discreteIn (bool) 2024-05-15T18:27:03.186Z,1715797623.186 [CommandExec](IMPORTANT): WetLabsUBAT.component_discreteIn (bool) 2024-05-15T18:27:14.136Z,1715797634.136 [CommandExec](IMPORTANT): got command get DockingStepper.component_discreteIn 2024-05-15T18:27:14.137Z,1715797634.137 [CommandExec](IMPORTANT): DockingStepper.component_discreteIn 0 bool 2024-05-15T18:27:16.332Z,1715797636.332 [OnDock:I.](INFO): *** querying acoustic contact *** 2024-05-15T18:27:16.470Z,1715797636.470 [DAT](INFO): ****** received valid address query ****** 2024-05-15T18:27:16.471Z,1715797636.471 [DAT](INFO): ****** received valid ping request ****** 2024-05-15T18:27:16.471Z,1715797636.471 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode. 2024-05-15T18:27:16.722Z,1715797636.722 [DAT](INFO): DAT read: user:13> 2024-05-15T18:27:16.723Z,1715797636.723 [DAT](INFO): DAT read: Tx time:18:27:16.3925 2024-05-15T18:27:16.723Z,1715797636.723 [DAT](INFO): Ping request sent. 2024-05-15T18:27:16.723Z,1715797636.723 [DAT](INFO): transmitted an acoustic signal 2024-05-15T18:27:16.723Z,1715797636.723 [DAT](INFO): publishing transmit ping time 2024-05-15T18:27:16.724Z,1715797636.724 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000722 2024-05-15T18:27:16.975Z,1715797636.975 [DAT](INFO): DAT read: TxSync time:18:27:16.3917 2024-05-15T18:27:16.975Z,1715797636.975 [DAT](INFO): unknown deviceResponse_: TxSync time:18:27:16.3917 2024-05-15T18:27:16.976Z,1715797636.976 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.252764 2024-05-15T18:27:17.226Z,1715797637.226 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.503140 2024-05-15T18:27:17.478Z,1715797637.478 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.755156 2024-05-15T18:27:17.732Z,1715797637.732 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.008922 2024-05-15T18:27:17.982Z,1715797637.982 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.259124 2024-05-15T18:27:18.234Z,1715797638.234 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.511454 2024-05-15T18:27:18.487Z,1715797638.487 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.763736 2024-05-15T18:27:18.738Z,1715797638.738 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014893 2024-05-15T18:27:18.992Z,1715797638.992 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.268818 2024-05-15T18:27:19.243Z,1715797639.243 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.519483 2024-05-15T18:27:19.494Z,1715797639.494 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.771143 2024-05-15T18:27:19.750Z,1715797639.750 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.026670 2024-05-15T18:27:19.998Z,1715797639.998 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.275100 2024-05-15T18:27:20.250Z,1715797640.250 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.527184 2024-05-15T18:27:20.504Z,1715797640.504 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.780528 2024-05-15T18:27:20.754Z,1715797640.754 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.030928 2024-05-15T18:27:21.011Z,1715797641.011 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.288388 2024-05-15T18:27:21.262Z,1715797641.262 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.539407 2024-05-15T18:27:21.514Z,1715797641.514 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.791205 2024-05-15T18:27:21.777Z,1715797641.777 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.053583 2024-05-15T18:27:22.026Z,1715797642.026 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.303068 2024-05-15T18:27:22.278Z,1715797642.278 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.555225 2024-05-15T18:27:22.531Z,1715797642.531 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.807389 2024-05-15T18:27:22.782Z,1715797642.782 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.058903 2024-05-15T18:27:23.034Z,1715797643.034 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.311457 2024-05-15T18:27:23.287Z,1715797643.287 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.564197 2024-05-15T18:27:23.538Z,1715797643.538 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.815108 2024-05-15T18:27:23.793Z,1715797643.793 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.069492 2024-05-15T18:27:24.042Z,1715797644.042 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.319144 2024-05-15T18:27:24.295Z,1715797644.295 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.571436 2024-05-15T18:27:24.546Z,1715797644.546 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.823153 2024-05-15T18:27:24.798Z,1715797644.798 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.075035 2024-05-15T18:27:25.050Z,1715797645.050 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.327091 2024-05-15T18:27:25.302Z,1715797645.302 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.579100 2024-05-15T18:27:25.554Z,1715797645.554 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.831151 2024-05-15T18:27:25.806Z,1715797645.806 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.083138 2024-05-15T18:27:26.058Z,1715797646.058 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.335162 2024-05-15T18:27:26.310Z,1715797646.310 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.587076 2024-05-15T18:27:26.562Z,1715797646.562 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.839387 2024-05-15T18:27:29.086Z,1715797649.086 [DAT](INFO): DAT read: Response Not Received 2024-05-15T18:27:29.086Z,1715797649.086 [DAT](INFO): response not received 2024-05-15T18:27:29.087Z,1715797649.087 [DAT](ERROR): No response from remote modem. 2024-05-15T18:27:30.645Z,1715797650.645 [DockingStepper](ERROR): getADC uart error serial timeout 2024-05-15T18:27:30.646Z,1715797650.646 [DockingStepper](FAULT): uart error - getPosition..serial timeout 2024-05-15T18:27:30.646Z,1715797650.646 [DockingStepper] Communications Fault, FailCount= 1 2024-05-15T18:27:30.646Z,1715797650.646 [DockingStepper](ERROR): Communications Fault 2024-05-15T18:27:30.717Z,1715797650.717 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-15T18:27:31.012Z,1715797651.012 [DockingStepper](INFO): Stop 2024-05-15T18:27:31.012Z,1715797651.012 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-15T18:27:31.013Z,1715797651.013 [DockingStepper](INFO): Powering down 2024-05-15T18:27:31.394Z,1715797651.394 [DockingStepper](INFO): Stopping 2024-05-15T18:27:31.866Z,1715797651.866 [DockingStepper](INFO): Stopped 2024-05-15T18:27:31.906Z,1715797651.906 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-15T18:27:31.906Z,1715797651.906 [DockingStepper] No Fault, FailCount= 1 2024-05-15T18:27:32.226Z,1715797652.226 [DockingStepper](INFO): Start 2024-05-15T18:27:32.227Z,1715797652.227 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-15T18:27:32.227Z,1715797652.227 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-15T18:27:32.601Z,1715797652.601 [DockingStepper](INFO): Starting 2024-05-15T18:27:32.987Z,1715797652.987 [DockingStepper](INFO): Starting 2024-05-15T18:27:33.407Z,1715797653.407 [DockingStepper](INFO): Starting 2024-05-15T18:27:33.464Z,1715797653.464 [DockingStepper](INFO): Init string: /1z16351aE13561N3m50 2024-05-15T18:27:33.849Z,1715797653.849 [DockingStepper](INFO): Starting 2024-05-15T18:27:34.256Z,1715797654.256 [DockingStepper](INFO): Starting 2024-05-15T18:27:34.629Z,1715797654.629 [DockingStepper](INFO): Starting 2024-05-15T18:27:34.683Z,1715797654.683 [CommandExec](IMPORTANT): got command get CTD_Seabird.component_discreteIn 2024-05-15T18:27:34.683Z,1715797654.683 [CommandExec](IMPORTANT): CTD_Seabird.component_discreteIn 0 bool 2024-05-15T18:27:35.031Z,1715797655.031 [DockingStepper](INFO): Starting 2024-05-15T18:27:35.058Z,1715797655.058 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-15T18:27:35.381Z,1715797655.381 [DockingStepper](INFO): Starting 2024-05-15T18:27:35.915Z,1715797655.915 [DockingStepper](INFO): Deisolating dock charge 2024-05-15T18:27:51.410Z,1715797671.410 [CommandExec](IMPORTANT): got command stop 2024-05-15T18:27:51.410Z,1715797671.410 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop 2024-05-15T18:27:51.552Z,1715797671.552 [MissionManager](INFO): MissionManager is completed. 2024-05-15T18:27:51.552Z,1715797671.552 [MissionManager](INFO): Uninitializing Mission OnDock 2024-05-15T18:27:51.552Z,1715797671.552 [OnDock] Stopped 2024-05-15T18:27:51.552Z,1715797671.552 [OnDock](DEBUG): Aggregate::uninitialize OnDock 2024-05-15T18:27:51.553Z,1715797671.553 [OnDock:StandardEnvelopes] Stopped 2024-05-15T18:27:51.553Z,1715797671.553 [OnDock:StandardEnvelopes](DEBUG): Aggregate::uninitialize OnDock:StandardEnvelopes 2024-05-15T18:27:51.553Z,1715797671.553 [OnDock:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2024-05-15T18:27:51.553Z,1715797671.553 [OnDock:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2024-05-15T18:27:51.553Z,1715797671.553 [OnDock:StandardEnvelopes:B.DepthEnvelope] Stopped 2024-05-15T18:27:51.557Z,1715797671.557 [OnDock:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2024-05-15T18:27:51.557Z,1715797671.557 [OnDock:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2024-05-15T18:27:51.557Z,1715797671.557 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2024-05-15T18:27:51.557Z,1715797671.557 [OnDock:BackseatDriver] Stopped 2024-05-15T18:27:51.558Z,1715797671.558 [OnDock:BackseatDriver](DEBUG): Aggregate::uninitialize OnDock:BackseatDriver 2024-05-15T18:27:51.558Z,1715797671.558 [OnDock:BackseatDriver:A.BackseatDriver] Stopped 2024-05-15T18:27:51.558Z,1715797671.558 [OnDock:PowerOnly] Stopped 2024-05-15T18:27:51.558Z,1715797671.558 [OnDock:PowerOnly](DEBUG): Aggregate::uninitialize OnDock:PowerOnly 2024-05-15T18:27:51.558Z,1715797671.558 [OnDock:PowerOnly:E.Wait] Stopped 2024-05-15T18:27:51.558Z,1715797671.558 [OnDock:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2024-05-15T18:27:51.558Z,1715797671.558 [OnDock:H] Stopped 2024-05-15T18:27:51.558Z,1715797671.558 [OnDock:I.] Stopped 2024-05-15T18:27:51.558Z,1715797671.558 [OnDock:I.](DEBUG): Uninitializing TrackAcousticContact. 2024-05-15T18:27:51.558Z,1715797671.558 [OnDock:PeriodicCheck] Stopped 2024-05-15T18:27:51.558Z,1715797671.558 [OnDock:PeriodicCheck](DEBUG): Aggregate::uninitialize OnDock:PeriodicCheck 2024-05-15T18:27:51.558Z,1715797671.558 [OnDock:PeriodicCheck:CheckIP] Stopped 2024-05-15T18:27:51.559Z,1715797671.559 [OnDock:PeriodicCheck:CheckIP](DEBUG): Aggregate::uninitialize OnDock:PeriodicCheck:CheckIP 2024-05-15T18:27:51.559Z,1715797671.559 [OnDock:PeriodicCheck:CheckIP:B.Wait] Stopped 2024-05-15T18:27:51.559Z,1715797671.559 [OnDock:PeriodicCheck:CheckIP:B.Wait](DEBUG): Uninitialize Wait Component. 2024-05-15T18:27:51.559Z,1715797671.559 [OnDock:O.Docked] Stopped 2024-05-15T18:27:51.953Z,1715797671.953 [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:27:51.967Z,1715797671.967 [MissionManager](IMPORTANT): Started mission Default 2024-05-15T18:27:51.967Z,1715797671.967 [Default] Running Loop=1 2024-05-15T18:27:51.968Z,1715797671.968 [Default](DEBUG): Aggregate::initialize Default 2024-05-15T18:27:51.968Z,1715797671.968 [Default:B.GoToSurface] Running Loop=1 2024-05-15T18:27:51.968Z,1715797671.968 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-05-15T18:27:51.968Z,1715797671.968 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-05-15T18:27:51.968Z,1715797671.968 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-05-15T18:27:51.969Z,1715797671.969 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-05-15T18:27:52.009Z,1715797672.009 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-05-15T18:27:52.010Z,1715797672.010 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-05-15T18:27:52.010Z,1715797672.010 [Default:A.Wait] Running Loop=1 2024-05-15T18:27:52.010Z,1715797672.010 [Default:A.Wait](DEBUG): Initialize Wait Component. 2024-05-15T18:28:05.284Z,1715797685.284 [Default:A.Wait](INFO): Done Waiting. 2024-05-15T18:28:05.284Z,1715797685.284 [Default:A.Wait] Stopped 2024-05-15T18:28:05.284Z,1715797685.284 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2024-05-15T18:28:05.691Z,1715797685.691 [Default:CheckIn] Running Loop=1 2024-05-15T18:28:05.691Z,1715797685.691 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-15T18:28:05.691Z,1715797685.691 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-15T18:28:07.708Z,1715797687.708 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182806.00,A,3648.16829,N,12147.28554,W,0.156,0.00,150524,,,A*7E 2024-05-15T18:28:07.710Z,1715797687.710 [NAL9602](INFO): GPS fix at 20240515T182806: (36.802805, -121.788092) 2024-05-15T18:28:07.732Z,1715797687.732 [Default:CheckIn:Read_GPS] Stopped 2024-05-15T18:28:07.733Z,1715797687.733 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-15T18:28:17.405Z,1715797697.405 [DataOverHttps](INFO): Sending 268 bytes from file Logs/20240515T182118/Courier0000.lzma 2024-05-15T18:28:18.407Z,1715797698.407 [DataOverHttps](INFO): Moved sent file to Logs/20240515T182118/Courier0000.lzma.bak 2024-05-15T18:28:18.407Z,1715797698.407 [DataOverHttps](INFO): SBD MOMSN=21022940 2024-05-15T18:28:23.212Z,1715797703.212 [DockingStepper](ERROR): only read 0 of 4 ADC values. Response is: ÿ/0@ 2024-05-15T18:28:23.212Z,1715797703.212 [DockingStepper](FAULT): Docking arm not reporting position 2024-05-15T18:28:23.213Z,1715797703.213 [DockingStepper] Communications Fault, FailCount= 1 2024-05-15T18:28:23.213Z,1715797703.213 [DockingStepper](ERROR): Communications Fault 2024-05-15T18:28:23.218Z,1715797703.218 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-15T18:28:23.671Z,1715797703.671 [DockingStepper](INFO): Stop 2024-05-15T18:28:23.671Z,1715797703.671 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-15T18:28:23.671Z,1715797703.671 [DockingStepper](INFO): Powering down 2024-05-15T18:28:24.107Z,1715797704.107 [DockingStepper](INFO): Stopping 2024-05-15T18:28:24.455Z,1715797704.455 [DockingStepper](INFO): Stopped 2024-05-15T18:28:24.463Z,1715797704.463 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-15T18:28:24.463Z,1715797704.463 [DockingStepper] No Fault, FailCount= 1 2024-05-15T18:28:24.867Z,1715797704.867 [DockingStepper](INFO): Start 2024-05-15T18:28:24.867Z,1715797704.867 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-15T18:28:24.868Z,1715797704.868 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-15T18:28:25.298Z,1715797705.298 [DockingStepper](INFO): Starting 2024-05-15T18:28:25.671Z,1715797705.671 [DockingStepper](INFO): Starting 2024-05-15T18:28:26.091Z,1715797706.091 [DockingStepper](INFO): Starting 2024-05-15T18:28:26.134Z,1715797706.134 [DockingStepper](INFO): Init string: /1z16349aE13561N3m50 2024-05-15T18:28:26.474Z,1715797706.474 [DockingStepper](INFO): Starting 2024-05-15T18:28:26.882Z,1715797706.882 [DockingStepper](INFO): Starting 2024-05-15T18:28:27.297Z,1715797707.297 [DockingStepper](INFO): Starting 2024-05-15T18:28:27.682Z,1715797707.682 [DockingStepper](INFO): Starting 2024-05-15T18:28:27.714Z,1715797707.714 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-15T18:28:28.098Z,1715797708.098 [DockingStepper](INFO): Starting 2024-05-15T18:28:37.430Z,1715797717.430 [DataOverHttps](INFO): Sending 1017 bytes from file Logs/20240515T182118/Express0001.lzma 2024-05-15T18:28:38.431Z,1715797718.431 [DataOverHttps](INFO): Moved sent file to Logs/20240515T182118/Express0001.lzma.bak 2024-05-15T18:28:38.431Z,1715797718.431 [DataOverHttps](INFO): SBD MOMSN=21022944 2024-05-15T18:28:41.438Z,1715797721.438 [Default:CheckIn:Read_Iridium] Stopped 2024-05-15T18:28:41.438Z,1715797721.438 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-15T18:28:41.439Z,1715797721.439 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-15T18:30:00.164Z,1715797800.164 [NAL9602](INFO): SBD MO Status=2, MOMSN=37338, MT Status=2, MTMSN=0 2024-05-15T18:30:00.165Z,1715797800.165 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-15T18:30:23.595Z,1715797823.595 [NAL9602](INFO): SBD MO Status=2, MOMSN=37338, MT Status=2, MTMSN=0 2024-05-15T18:30:23.595Z,1715797823.595 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-15T18:30:36.130Z,1715797836.130 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for beam range. Device response is::RA, 0.00, 0.00, 0.00,V 2024-05-15T18:31:00.837Z,1715797860.837 [NAL9602](INFO): SBD MO Status=2, MOMSN=37338, MT Status=2, MTMSN=0 2024-05-15T18:31:00.837Z,1715797860.837 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-15T18:32:59.235Z,1715797979.235 [DockingStepper](ERROR): only read 0 of 4 ADC values. Response is: ÿ/0@ 2024-05-15T18:32:59.236Z,1715797979.236 [DockingStepper](FAULT): Docking arm not reporting position 2024-05-15T18:32:59.236Z,1715797979.236 [DockingStepper] Communications Fault, FailCount= 1 2024-05-15T18:32:59.237Z,1715797979.237 [DockingStepper](ERROR): Communications Fault 2024-05-15T18:32:59.242Z,1715797979.242 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-15T18:32:59.622Z,1715797979.622 [DockingStepper](INFO): Stop 2024-05-15T18:32:59.622Z,1715797979.622 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-15T18:32:59.622Z,1715797979.622 [DockingStepper](INFO): Powering down 2024-05-15T18:33:00.045Z,1715797980.045 [DockingStepper](INFO): Stopping 2024-05-15T18:33:00.439Z,1715797980.439 [DockingStepper](INFO): Stopped 2024-05-15T18:33:00.443Z,1715797980.443 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-15T18:33:00.443Z,1715797980.443 [DockingStepper] No Fault, FailCount= 1 2024-05-15T18:33:00.829Z,1715797980.829 [DockingStepper](INFO): Start 2024-05-15T18:33:00.829Z,1715797980.829 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-15T18:33:00.830Z,1715797980.830 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-15T18:33:01.234Z,1715797981.234 [DockingStepper](INFO): Starting 2024-05-15T18:33:01.658Z,1715797981.658 [DockingStepper](INFO): Starting 2024-05-15T18:33:02.119Z,1715797982.119 [DockingStepper](INFO): Starting 2024-05-15T18:33:02.170Z,1715797982.170 [DockingStepper](INFO): Init string: /1z16339aE13561N3m50 2024-05-15T18:33:02.485Z,1715797982.485 [DockingStepper](INFO): Starting 2024-05-15T18:33:02.895Z,1715797982.895 [DockingStepper](INFO): Starting 2024-05-15T18:33:03.278Z,1715797983.278 [DockingStepper](INFO): Starting 2024-05-15T18:33:03.679Z,1715797983.679 [DockingStepper](INFO): Starting 2024-05-15T18:33:03.718Z,1715797983.718 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-15T18:33:04.118Z,1715797984.118 [DockingStepper](INFO): Starting 2024-05-15T18:33:10.510Z,1715797990.510 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-05-15T18:33:41.214Z,1715798021.214 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-15T18:33:42.063Z,1715798022.063 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-15T18:33:42.063Z,1715798022.063 [Default:CheckIn:C.Wait] Stopped 2024-05-15T18:33:42.063Z,1715798022.063 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-15T18:33:42.063Z,1715798022.063 [Default:CheckIn:D] Running Loop=1 2024-05-15T18:33:42.446Z,1715798022.446 [Default:CheckIn:D] Stopped 2024-05-15T18:33:42.446Z,1715798022.446 [Default:CheckIn:E] Running Loop=1 2024-05-15T18:33:42.841Z,1715798022.841 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 5.841305 min 2024-05-15T18:33:42.841Z,1715798022.841 [Default:CheckIn:E] Stopped 2024-05-15T18:33:42.841Z,1715798022.841 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-15T18:33:42.842Z,1715798022.842 [Default:CheckIn] Stopped 2024-05-15T18:33:42.842Z,1715798022.842 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-15T18:33:42.842Z,1715798022.842 [Default:CheckIn](INFO): Running loop #2 2024-05-15T18:33:42.842Z,1715798022.842 [Default:CheckIn] Running Loop=2 2024-05-15T18:33:42.842Z,1715798022.842 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-15T18:33:42.842Z,1715798022.842 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-15T18:33:44.851Z,1715798024.851 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183344.00,A,3648.17017,N,12147.28100,W,0.447,0.00,150524,,,A*76 2024-05-15T18:33:44.854Z,1715798024.854 [NAL9602](INFO): GPS fix at 20240515T183344: (36.802836, -121.788017) 2024-05-15T18:33:44.888Z,1715798024.888 [Default:CheckIn:Read_GPS] Stopped 2024-05-15T18:33:44.889Z,1715798024.889 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-15T18:33:53.981Z,1715798033.981 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240515T182118/Courier0003.lzma 2024-05-15T18:33:54.983Z,1715798034.983 [DataOverHttps](INFO): Moved sent file to Logs/20240515T182118/Courier0003.lzma.bak 2024-05-15T18:33:54.983Z,1715798034.983 [DataOverHttps](INFO): SBD MOMSN=21022989 2024-05-15T18:34:14.229Z,1715798054.229 [DataOverHttps](INFO): Sending 298 bytes from file Logs/20240515T182118/Express0004.lzma 2024-05-15T18:34:15.231Z,1715798055.231 [DataOverHttps](INFO): Moved sent file to Logs/20240515T182118/Express0004.lzma.bak 2024-05-15T18:34:15.231Z,1715798055.231 [DataOverHttps](INFO): SBD MOMSN=21022992 2024-05-15T18:34:15.370Z,1715798055.370 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2024-05-15T18:34:15.453Z,1715798055.453 [NAL9602](ERROR): received: +CSQ:0 OK338, 2, 0, 0, 0 OK 2024-05-15T18:34:18.225Z,1715798058.225 [Default:CheckIn:Read_Iridium] Stopped 2024-05-15T18:34:18.230Z,1715798058.230 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-15T18:34:18.231Z,1715798058.231 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-15T18:35:05.056Z,1715798105.056 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-15T18:35:20.408Z,1715798120.408 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-15T18:35:36.568Z,1715798136.568 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-15T18:35:51.204Z,1715798151.204 [DockingStepper](ERROR): only read 0 of 4 ADC values. Response is: ÿ/0@ 2024-05-15T18:35:51.205Z,1715798151.205 [DockingStepper](FAULT): Docking arm not reporting position 2024-05-15T18:35:51.206Z,1715798151.206 [DockingStepper] Communications Fault, FailCount= 1 2024-05-15T18:35:51.206Z,1715798151.206 [DockingStepper](ERROR): Communications Fault 2024-05-15T18:35:51.211Z,1715798151.211 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-15T18:35:51.516Z,1715798151.516 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-15T18:35:51.540Z,1715798151.540 [DockingStepper](INFO): Stop 2024-05-15T18:35:51.540Z,1715798151.540 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-15T18:35:51.540Z,1715798151.540 [DockingStepper](INFO): Powering down 2024-05-15T18:35:51.998Z,1715798151.998 [DockingStepper](INFO): Stopping 2024-05-15T18:35:52.364Z,1715798152.364 [DockingStepper](INFO): Stopped 2024-05-15T18:35:52.368Z,1715798152.368 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-15T18:35:52.368Z,1715798152.368 [DockingStepper] No Fault, FailCount= 1 2024-05-15T18:35:52.747Z,1715798152.747 [DockingStepper](INFO): Start 2024-05-15T18:35:52.747Z,1715798152.747 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-15T18:35:52.748Z,1715798152.748 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-15T18:35:53.150Z,1715798153.150 [DockingStepper](INFO): Starting 2024-05-15T18:35:53.555Z,1715798153.555 [DockingStepper](INFO): Starting 2024-05-15T18:35:53.989Z,1715798153.989 [DockingStepper](INFO): Starting 2024-05-15T18:35:54.044Z,1715798154.044 [DockingStepper](INFO): Init string: /1z16351aE13561N3m50 2024-05-15T18:35:54.403Z,1715798154.403 [DockingStepper](INFO): Starting 2024-05-15T18:35:54.787Z,1715798154.787 [DockingStepper](INFO): Starting 2024-05-15T18:35:55.183Z,1715798155.183 [DockingStepper](INFO): Starting 2024-05-15T18:35:55.573Z,1715798155.573 [DockingStepper](INFO): Starting 2024-05-15T18:35:55.605Z,1715798155.605 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-15T18:35:56.029Z,1715798156.029 [DockingStepper](INFO): Starting 2024-05-15T18:36:06.868Z,1715798166.868 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-15T18:36:22.220Z,1715798182.220 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-15T18:36:33.557Z,1715798193.557 [DockingStepper](ERROR): only read 0 of 4 ADC values. Response is: ÿ/0@ 2024-05-15T18:36:33.558Z,1715798193.558 [DockingStepper](FAULT): Docking arm not reporting position 2024-05-15T18:36:33.558Z,1715798193.558 [DockingStepper] Communications Fault, FailCount= 1 2024-05-15T18:36:33.558Z,1715798193.558 [DockingStepper](ERROR): Communications Fault 2024-05-15T18:36:33.563Z,1715798193.563 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-15T18:36:33.979Z,1715798193.979 [DockingStepper](INFO): Stop 2024-05-15T18:36:33.979Z,1715798193.979 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-15T18:36:33.979Z,1715798193.979 [DockingStepper](INFO): Powering down 2024-05-15T18:36:34.379Z,1715798194.379 [DockingStepper](INFO): Stopping 2024-05-15T18:36:34.762Z,1715798194.762 [DockingStepper](INFO): Stopped 2024-05-15T18:36:34.766Z,1715798194.766 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-15T18:36:34.766Z,1715798194.766 [DockingStepper] No Fault, FailCount= 1 2024-05-15T18:36:35.169Z,1715798195.169 [DockingStepper](INFO): Start 2024-05-15T18:36:35.169Z,1715798195.169 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-15T18:36:35.170Z,1715798195.170 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-15T18:36:35.583Z,1715798195.583 [DockingStepper](INFO): Starting 2024-05-15T18:36:36.070Z,1715798196.070 [DockingStepper](INFO): Starting 2024-05-15T18:36:36.428Z,1715798196.428 [DockingStepper](INFO): Starting 2024-05-15T18:36:36.475Z,1715798196.475 [DockingStepper](INFO): Init string: /1z16352aE13561N3m50 2024-05-15T18:36:36.832Z,1715798196.832 [DockingStepper](INFO): Starting 2024-05-15T18:36:37.221Z,1715798197.221 [DockingStepper](INFO): Starting 2024-05-15T18:36:37.610Z,1715798197.610 [DockingStepper](INFO): Starting 2024-05-15T18:36:38.039Z,1715798198.039 [DockingStepper](INFO): Starting 2024-05-15T18:36:38.070Z,1715798198.070 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-15T18:36:38.406Z,1715798198.406 [DockingStepper](INFO): Starting 2024-05-15T18:37:11.124Z,1715798231.124 [DockingStepper](ERROR): only read 0 of 4 ADC values. Response is: ÿ/0@ 2024-05-15T18:37:11.125Z,1715798231.125 [DockingStepper](FAULT): Docking arm not reporting position 2024-05-15T18:37:11.125Z,1715798231.125 [DockingStepper] Communications Fault, FailCount= 1 2024-05-15T18:37:11.126Z,1715798231.126 [DockingStepper](ERROR): Communications Fault 2024-05-15T18:37:11.130Z,1715798231.130 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-15T18:37:11.558Z,1715798231.558 [DockingStepper](INFO): Stop 2024-05-15T18:37:11.558Z,1715798231.558 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-15T18:37:11.558Z,1715798231.558 [DockingStepper](INFO): Powering down 2024-05-15T18:37:11.971Z,1715798231.971 [DockingStepper](INFO): Stopping 2024-05-15T18:37:12.362Z,1715798232.362 [DockingStepper](INFO): Stopped 2024-05-15T18:37:12.367Z,1715798232.367 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-15T18:37:12.367Z,1715798232.367 [DockingStepper] No Fault, FailCount= 1 2024-05-15T18:37:12.779Z,1715798232.779 [DockingStepper](INFO): Start 2024-05-15T18:37:12.779Z,1715798232.779 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-15T18:37:12.780Z,1715798232.780 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-15T18:37:13.148Z,1715798233.148 [DockingStepper](INFO): Starting 2024-05-15T18:37:13.557Z,1715798233.557 [DockingStepper](INFO): Starting 2024-05-15T18:37:13.979Z,1715798233.979 [DockingStepper](INFO): Starting 2024-05-15T18:37:14.034Z,1715798234.034 [DockingStepper](INFO): Init string: /1z16351aE13561N3m50 2024-05-15T18:37:14.360Z,1715798234.360 [DockingStepper](INFO): Starting 2024-05-15T18:37:14.779Z,1715798234.779 [DockingStepper](INFO): Starting 2024-05-15T18:37:15.166Z,1715798235.166 [DockingStepper](INFO): Starting 2024-05-15T18:37:15.574Z,1715798235.574 [DockingStepper](INFO): Starting 2024-05-15T18:37:15.621Z,1715798235.621 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-15T18:37:15.998Z,1715798235.998 [DockingStepper](INFO): Starting 2024-05-15T18:38:34.348Z,1715798314.348 [DockingStepper](ERROR): only read 0 of 4 ADC values. Response is: ÿ/0@ 2024-05-15T18:38:34.349Z,1715798314.349 [DockingStepper](FAULT): Docking arm not reporting position 2024-05-15T18:38:34.349Z,1715798314.349 [DockingStepper] Communications Fault, FailCount= 1 2024-05-15T18:38:34.350Z,1715798314.350 [DockingStepper](ERROR): Communications Fault 2024-05-15T18:38:34.354Z,1715798314.354 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-15T18:38:34.758Z,1715798314.758 [DockingStepper](INFO): Stop 2024-05-15T18:38:34.758Z,1715798314.758 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-15T18:38:34.758Z,1715798314.758 [DockingStepper](INFO): Powering down 2024-05-15T18:38:35.179Z,1715798315.179 [DockingStepper](INFO): Stopping 2024-05-15T18:38:35.581Z,1715798315.581 [DockingStepper](INFO): Stopped 2024-05-15T18:38:35.586Z,1715798315.586 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-15T18:38:35.586Z,1715798315.586 [DockingStepper] No Fault, FailCount= 1 2024-05-15T18:38:36.010Z,1715798316.010 [DockingStepper](INFO): Start 2024-05-15T18:38:36.010Z,1715798316.010 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-15T18:38:36.011Z,1715798316.011 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-15T18:38:36.377Z,1715798316.377 [DockingStepper](INFO): Starting 2024-05-15T18:38:36.791Z,1715798316.791 [DockingStepper](INFO): Starting 2024-05-15T18:38:37.258Z,1715798317.258 [DockingStepper](INFO): Starting 2024-05-15T18:38:37.308Z,1715798317.308 [DockingStepper](INFO): Init string: /1z16352aE13561N3m50 2024-05-15T18:38:37.588Z,1715798317.588 [DockingStepper](INFO): Starting 2024-05-15T18:38:38.043Z,1715798318.043 [DockingStepper](INFO): Starting 2024-05-15T18:38:38.430Z,1715798318.430 [DockingStepper](INFO): Starting 2024-05-15T18:38:38.829Z,1715798318.829 [DockingStepper](INFO): Starting 2024-05-15T18:38:38.863Z,1715798318.863 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-15T18:38:39.240Z,1715798319.240 [DockingStepper](INFO): Starting 2024-05-15T18:38:47.304Z,1715798327.304 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-05-15T18:38:53.360Z,1715798333.360 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-15T18:39:06.693Z,1715798346.693 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-15T18:39:18.026Z,1715798358.026 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-15T18:39:18.824Z,1715798358.824 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-15T18:39:18.824Z,1715798358.824 [Default:CheckIn:C.Wait] Stopped 2024-05-15T18:39:18.824Z,1715798358.824 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-15T18:39:18.825Z,1715798358.825 [Default:CheckIn:D] Running Loop=1 2024-05-15T18:39:19.275Z,1715798359.275 [Default:CheckIn:D] Stopped 2024-05-15T18:39:19.275Z,1715798359.275 [Default:CheckIn:E] Running Loop=1 2024-05-15T18:39:19.621Z,1715798359.621 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-15T18:39:19.640Z,1715798359.640 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.455118 min 2024-05-15T18:39:19.640Z,1715798359.640 [Default:CheckIn:E] Stopped 2024-05-15T18:39:19.640Z,1715798359.640 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-15T18:39:19.640Z,1715798359.640 [Default:CheckIn] Stopped 2024-05-15T18:39:19.640Z,1715798359.640 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-15T18:39:19.640Z,1715798359.640 [Default:CheckIn](INFO): Running loop #3 2024-05-15T18:39:19.640Z,1715798359.640 [Default:CheckIn] Running Loop=3 2024-05-15T18:39:19.641Z,1715798359.641 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-15T18:39:19.641Z,1715798359.641 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-15T18:39:21.643Z,1715798361.643 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183920.00,A,3648.15775,N,12147.28254,W,0.428,0.00,150524,,,A*74 2024-05-15T18:39:21.655Z,1715798361.655 [NAL9602](INFO): GPS fix at 20240515T183920: (36.802629, -121.788042) 2024-05-15T18:39:21.667Z,1715798361.667 [Default:CheckIn:Read_GPS] Stopped 2024-05-15T18:39:21.667Z,1715798361.667 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-15T18:39:30.617Z,1715798370.617 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240515T182118/Courier0006.lzma 2024-05-15T18:39:31.619Z,1715798371.619 [DataOverHttps](INFO): Moved sent file to Logs/20240515T182118/Courier0006.lzma.bak 2024-05-15T18:39:31.620Z,1715798371.620 [DataOverHttps](INFO): SBD MOMSN=21023004 2024-05-15T18:39:32.552Z,1715798372.552 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-15T18:39:45.090Z,1715798385.090 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-15T18:39:49.712Z,1715798389.712 [DataOverHttps](INFO): Sending 261 bytes from file Logs/20240515T182118/Express0007.lzma 2024-05-15T18:39:50.699Z,1715798390.699 [DataOverHttps](INFO): Moved sent file to Logs/20240515T182118/Express0007.lzma.bak 2024-05-15T18:39:50.699Z,1715798390.699 [DataOverHttps](INFO): SBD MOMSN=21023007 2024-05-15T18:39:52.355Z,1715798392.355 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2024-05-15T18:39:52.429Z,1715798392.429 [NAL9602](ERROR): received: +CSQ:0 OK338, 2, 0, 0, 0 OK 2024-05-15T18:39:53.586Z,1715798393.586 [Default:CheckIn:Read_Iridium] Stopped 2024-05-15T18:39:53.587Z,1715798393.587 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-15T18:39:53.587Z,1715798393.587 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-15T18:39:58.131Z,1715798398.131 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-15T18:40:10.604Z,1715798410.604 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-15T18:40:18.284Z,1715798418.284 [NAL9602](INFO): SBD MO Status=2, MOMSN=37338, MT Status=2, MTMSN=0 2024-05-15T18:40:18.285Z,1715798418.285 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-15T18:40:24.015Z,1715798424.015 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-15T18:40:36.892Z,1715798436.892 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-15T18:40:49.846Z,1715798449.846 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-15T18:41:02.851Z,1715798462.851 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-15T18:41:15.795Z,1715798475.795 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-15T18:41:22.305Z,1715798482.305 [DockingStepper](ERROR): only read 0 of 4 ADC values. Response is: ÿ/0@ 2024-05-15T18:41:22.309Z,1715798482.309 [DockingStepper](FAULT): Docking arm not reporting position 2024-05-15T18:41:22.309Z,1715798482.309 [DockingStepper] Communications Fault, FailCount= 1 2024-05-15T18:41:22.309Z,1715798482.309 [DockingStepper](ERROR): Communications Fault 2024-05-15T18:41:22.319Z,1715798482.319 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-15T18:41:22.712Z,1715798482.712 [DockingStepper](INFO): Stop 2024-05-15T18:41:22.712Z,1715798482.712 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-15T18:41:22.712Z,1715798482.712 [DockingStepper](INFO): Powering down 2024-05-15T18:41:23.120Z,1715798483.120 [DockingStepper](INFO): Stopping 2024-05-15T18:41:23.491Z,1715798483.491 [DockingStepper](INFO): Stopped 2024-05-15T18:41:23.495Z,1715798483.495 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-15T18:41:23.495Z,1715798483.495 [DockingStepper] No Fault, FailCount= 1 2024-05-15T18:41:23.913Z,1715798483.913 [DockingStepper](INFO): Start 2024-05-15T18:41:23.913Z,1715798483.913 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-15T18:41:23.914Z,1715798483.914 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-15T18:41:24.316Z,1715798484.316 [DockingStepper](INFO): Starting 2024-05-15T18:41:24.702Z,1715798484.702 [DockingStepper](INFO): Starting 2024-05-15T18:41:25.106Z,1715798485.106 [DockingStepper](INFO): Starting 2024-05-15T18:41:25.154Z,1715798485.154 [DockingStepper](INFO): Init string: /1z16351aE13561N3m50 2024-05-15T18:41:25.515Z,1715798485.515 [DockingStepper](INFO): Starting 2024-05-15T18:41:25.929Z,1715798485.929 [DockingStepper](INFO): Starting 2024-05-15T18:41:26.307Z,1715798486.307 [DockingStepper](INFO): Starting 2024-05-15T18:41:26.713Z,1715798486.713 [DockingStepper](INFO): Starting 2024-05-15T18:41:26.749Z,1715798486.749 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-15T18:41:27.126Z,1715798487.126 [DockingStepper](INFO): Starting 2024-05-15T18:42:34.589Z,1715798554.589 [DockingStepper](ERROR): only read 0 of 4 ADC values. Response is: ÿ/0@ 2024-05-15T18:42:34.594Z,1715798554.594 [DockingStepper](FAULT): Docking arm not reporting position 2024-05-15T18:42:34.594Z,1715798554.594 [DockingStepper] Communications Fault, FailCount= 1 2024-05-15T18:42:34.594Z,1715798554.594 [DockingStepper](ERROR): Communications Fault 2024-05-15T18:42:34.603Z,1715798554.603 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-15T18:42:34.983Z,1715798554.983 [DockingStepper](INFO): Stop 2024-05-15T18:42:34.983Z,1715798554.983 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-15T18:42:34.983Z,1715798554.983 [DockingStepper](INFO): Powering down 2024-05-15T18:42:35.390Z,1715798555.390 [DockingStepper](INFO): Stopping 2024-05-15T18:42:35.844Z,1715798555.844 [DockingStepper](INFO): Stopped 2024-05-15T18:42:35.853Z,1715798555.853 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-15T18:42:35.853Z,1715798555.853 [DockingStepper] No Fault, FailCount= 1 2024-05-15T18:42:36.259Z,1715798556.259 [DockingStepper](INFO): Start 2024-05-15T18:42:36.259Z,1715798556.259 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-15T18:42:36.259Z,1715798556.259 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-15T18:42:36.640Z,1715798556.640 [DockingStepper](INFO): Starting 2024-05-15T18:42:37.044Z,1715798557.044 [DockingStepper](INFO): Starting 2024-05-15T18:42:37.440Z,1715798557.440 [DockingStepper](INFO): Starting 2024-05-15T18:42:37.494Z,1715798557.494 [DockingStepper](INFO): Init string: /1z16352aE13561N3m50 2024-05-15T18:42:37.902Z,1715798557.902 [DockingStepper](INFO): Starting 2024-05-15T18:42:38.216Z,1715798558.216 [DockingStepper](INFO): Starting 2024-05-15T18:42:38.635Z,1715798558.635 [DockingStepper](INFO): Starting 2024-05-15T18:42:39.039Z,1715798559.039 [DockingStepper](INFO): Starting 2024-05-15T18:42:39.074Z,1715798559.074 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-15T18:42:39.447Z,1715798559.447 [DockingStepper](INFO): Starting 2024-05-15T18:43:52.124Z,1715798632.124 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-15T18:44:05.052Z,1715798645.052 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-15T18:44:18.385Z,1715798658.385 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-15T18:44:24.088Z,1715798664.088 [DockingStepper](ERROR): only read 0 of 4 ADC values. Response is: ÿ/0@ 2024-05-15T18:44:24.088Z,1715798664.088 [DockingStepper](FAULT): Docking arm not reporting position 2024-05-15T18:44:24.089Z,1715798664.089 [DockingStepper] Communications Fault, FailCount= 1 2024-05-15T18:44:24.089Z,1715798664.089 [DockingStepper](ERROR): Communications Fault 2024-05-15T18:44:24.094Z,1715798664.094 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-15T18:44:24.447Z,1715798664.447 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-05-15T18:44:24.464Z,1715798664.464 [DockingStepper](INFO): Stop 2024-05-15T18:44:24.464Z,1715798664.464 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-15T18:44:24.464Z,1715798664.464 [DockingStepper](INFO): Powering down 2024-05-15T18:44:24.879Z,1715798664.879 [DockingStepper](INFO): Stopping 2024-05-15T18:44:25.333Z,1715798665.333 [DockingStepper](INFO): Stopped 2024-05-15T18:44:25.338Z,1715798665.338 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-15T18:44:25.338Z,1715798665.338 [DockingStepper] No Fault, FailCount= 1 2024-05-15T18:44:25.696Z,1715798665.696 [DockingStepper](INFO): Start 2024-05-15T18:44:25.697Z,1715798665.697 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-15T18:44:25.702Z,1715798665.702 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-15T18:44:26.127Z,1715798666.127 [DockingStepper](INFO): Starting 2024-05-15T18:44:26.502Z,1715798666.502 [DockingStepper](INFO): Starting 2024-05-15T18:44:26.902Z,1715798666.902 [DockingStepper](INFO): Starting 2024-05-15T18:44:26.947Z,1715798666.947 [DockingStepper](INFO): Init string: /1z16352aE13561N3m50 2024-05-15T18:44:27.298Z,1715798667.298 [DockingStepper](INFO): Starting 2024-05-15T18:44:27.708Z,1715798667.708 [DockingStepper](INFO): Starting 2024-05-15T18:44:28.140Z,1715798668.140 [DockingStepper](INFO): Starting 2024-05-15T18:44:28.510Z,1715798668.510 [DockingStepper](INFO): Starting 2024-05-15T18:44:28.550Z,1715798668.550 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-15T18:44:28.916Z,1715798668.916 [DockingStepper](INFO): Starting 2024-05-15T18:44:31.724Z,1715798671.724 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-15T18:44:43.052Z,1715798683.052 [DockingStepper](ERROR): only read 0 of 4 ADC values. Response is: ÿ/0@ 2024-05-15T18:44:43.053Z,1715798683.053 [DockingStepper](FAULT): Docking arm not reporting position 2024-05-15T18:44:43.054Z,1715798683.054 [DockingStepper] Communications Fault, FailCount= 1 2024-05-15T18:44:43.054Z,1715798683.054 [DockingStepper](ERROR): Communications Fault 2024-05-15T18:44:43.059Z,1715798683.059 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-15T18:44:43.451Z,1715798683.451 [DockingStepper](INFO): Stop 2024-05-15T18:44:43.451Z,1715798683.451 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-15T18:44:43.451Z,1715798683.451 [DockingStepper](INFO): Powering down 2024-05-15T18:44:43.879Z,1715798683.879 [DockingStepper](INFO): Stopping 2024-05-15T18:44:44.304Z,1715798684.304 [DockingStepper](INFO): Stopped 2024-05-15T18:44:44.308Z,1715798684.308 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-15T18:44:44.308Z,1715798684.308 [DockingStepper] No Fault, FailCount= 1 2024-05-15T18:44:44.644Z,1715798684.644 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-15T18:44:44.670Z,1715798684.670 [DockingStepper](INFO): Start 2024-05-15T18:44:44.670Z,1715798684.670 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-15T18:44:44.671Z,1715798684.671 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-15T18:44:45.086Z,1715798685.086 [DockingStepper](INFO): Starting 2024-05-15T18:44:45.481Z,1715798685.481 [DockingStepper](INFO): Starting 2024-05-15T18:44:45.918Z,1715798685.918 [DockingStepper](INFO): Starting 2024-05-15T18:44:45.971Z,1715798685.971 [DockingStepper](INFO): Init string: /1z16351aE13561N3m50 2024-05-15T18:44:46.283Z,1715798686.283 [DockingStepper](INFO): Starting 2024-05-15T18:44:46.692Z,1715798686.692 [DockingStepper](INFO): Starting 2024-05-15T18:44:47.086Z,1715798687.086 [DockingStepper](INFO): Starting 2024-05-15T18:44:47.489Z,1715798687.489 [DockingStepper](INFO): Starting 2024-05-15T18:44:47.533Z,1715798687.533 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-15T18:44:47.915Z,1715798687.915 [DockingStepper](INFO): Starting 2024-05-15T18:44:54.357Z,1715798694.357 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-15T18:44:54.357Z,1715798694.357 [Default:CheckIn:C.Wait] Stopped 2024-05-15T18:44:54.357Z,1715798694.357 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-15T18:44:54.358Z,1715798694.358 [Default:CheckIn:D] Running Loop=1 2024-05-15T18:44:54.757Z,1715798694.757 [Default:CheckIn:D] Stopped 2024-05-15T18:44:54.757Z,1715798694.757 [Default:CheckIn:E] Running Loop=1 2024-05-15T18:44:55.149Z,1715798695.149 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-15T18:44:55.182Z,1715798695.182 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 17.046489 min 2024-05-15T18:44:55.182Z,1715798695.182 [Default:CheckIn:E] Stopped 2024-05-15T18:44:55.182Z,1715798695.182 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-15T18:44:55.182Z,1715798695.182 [Default:CheckIn] Stopped 2024-05-15T18:44:55.183Z,1715798695.183 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-15T18:44:55.183Z,1715798695.183 [Default:CheckIn](INFO): Running loop #4 2024-05-15T18:44:55.183Z,1715798695.183 [Default:CheckIn] Running Loop=4 2024-05-15T18:44:55.183Z,1715798695.183 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-15T18:44:55.183Z,1715798695.183 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-15T18:44:57.214Z,1715798697.214 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184456.00,A,3648.16774,N,12147.28130,W,0.875,0.00,150524,,,A*78 2024-05-15T18:44:57.216Z,1715798697.216 [NAL9602](INFO): GPS fix at 20240515T184456: (36.802796, -121.788022) 2024-05-15T18:44:57.248Z,1715798697.248 [Default:CheckIn:Read_GPS] Stopped 2024-05-15T18:44:57.248Z,1715798697.248 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-15T18:44:57.572Z,1715798697.572 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-15T18:45:06.237Z,1715798706.237 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240515T182118/Courier0009.lzma 2024-05-15T18:45:07.239Z,1715798707.239 [DataOverHttps](INFO): Moved sent file to Logs/20240515T182118/Courier0009.lzma.bak 2024-05-15T18:45:07.239Z,1715798707.239 [DataOverHttps](INFO): SBD MOMSN=21023021 2024-05-15T18:45:10.587Z,1715798710.587 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-15T18:45:24.069Z,1715798724.069 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-15T18:45:26.197Z,1715798726.197 [DataOverHttps](INFO): Sending 262 bytes from file Logs/20240515T182118/Express0010.lzma 2024-05-15T18:45:27.195Z,1715798727.195 [DataOverHttps](INFO): Moved sent file to Logs/20240515T182118/Express0010.lzma.bak 2024-05-15T18:45:27.196Z,1715798727.196 [DataOverHttps](INFO): SBD MOMSN=21023024 2024-05-15T18:45:27.707Z,1715798727.707 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2024-05-15T18:45:27.793Z,1715798727.793 [NAL9602](ERROR): received: +CSQ:0 OK338, 2, 0, 0, 0 OK 2024-05-15T18:45:30.215Z,1715798730.215 [Default:CheckIn:Read_Iridium] Stopped 2024-05-15T18:45:30.215Z,1715798730.215 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-15T18:45:30.215Z,1715798730.215 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-15T18:45:37.396Z,1715798737.396 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-15T18:45:50.732Z,1715798750.732 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-15T18:46:03.328Z,1715798763.328 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-15T18:46:07.006Z,1715798767.006 [DockingStepper](ERROR): only read 0 of 4 ADC values. Response is: ÿ/0@ 2024-05-15T18:46:07.007Z,1715798767.007 [DockingStepper](FAULT): Docking arm not reporting position 2024-05-15T18:46:07.008Z,1715798767.008 [DockingStepper] Communications Fault, FailCount= 1 2024-05-15T18:46:07.008Z,1715798767.008 [DockingStepper](ERROR): Communications Fault 2024-05-15T18:46:07.013Z,1715798767.013 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-15T18:46:07.391Z,1715798767.391 [DockingStepper](INFO): Stop 2024-05-15T18:46:07.391Z,1715798767.391 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-15T18:46:07.391Z,1715798767.391 [DockingStepper](INFO): Powering down 2024-05-15T18:46:07.855Z,1715798767.855 [DockingStepper](INFO): Stopping 2024-05-15T18:46:08.210Z,1715798768.210 [DockingStepper](INFO): Stopped 2024-05-15T18:46:08.214Z,1715798768.214 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-15T18:46:08.214Z,1715798768.214 [DockingStepper] No Fault, FailCount= 1 2024-05-15T18:46:08.630Z,1715798768.630 [DockingStepper](INFO): Start 2024-05-15T18:46:08.630Z,1715798768.630 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-15T18:46:08.631Z,1715798768.631 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-15T18:46:09.024Z,1715798769.024 [DockingStepper](INFO): Starting 2024-05-15T18:46:09.424Z,1715798769.424 [DockingStepper](INFO): Starting 2024-05-15T18:46:09.851Z,1715798769.851 [DockingStepper](INFO): Starting 2024-05-15T18:46:09.914Z,1715798769.914 [DockingStepper](INFO): Init string: /1z16353aE13561N3m50 2024-05-15T18:46:10.224Z,1715798770.224 [DockingStepper](INFO): Starting 2024-05-15T18:46:10.638Z,1715798770.638 [DockingStepper](INFO): Starting 2024-05-15T18:46:11.022Z,1715798771.022 [DockingStepper](INFO): Starting 2024-05-15T18:46:11.431Z,1715798771.431 [DockingStepper](INFO): Starting 2024-05-15T18:46:11.465Z,1715798771.465 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-15T18:46:11.859Z,1715798771.859 [DockingStepper](INFO): Starting 2024-05-15T18:46:45.393Z,1715798805.393 [DockingStepper](ERROR): only read 0 of 4 ADC values. Response is: ÿ/0@ 2024-05-15T18:46:45.394Z,1715798805.394 [DockingStepper](FAULT): Docking arm not reporting position 2024-05-15T18:46:45.394Z,1715798805.394 [DockingStepper] Communications Fault, FailCount= 1 2024-05-15T18:46:45.395Z,1715798805.395 [DockingStepper](ERROR): Communications Fault 2024-05-15T18:46:45.399Z,1715798805.399 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-15T18:46:45.886Z,1715798805.886 [DockingStepper](INFO): Stop 2024-05-15T18:46:45.887Z,1715798805.887 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-15T18:46:45.887Z,1715798805.887 [DockingStepper](INFO): Powering down 2024-05-15T18:46:46.179Z,1715798806.179 [DockingStepper](INFO): Stopping 2024-05-15T18:46:46.607Z,1715798806.607 [DockingStepper](INFO): Stopped 2024-05-15T18:46:46.612Z,1715798806.612 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-15T18:46:46.612Z,1715798806.612 [DockingStepper] No Fault, FailCount= 1 2024-05-15T18:46:47.015Z,1715798807.015 [DockingStepper](INFO): Start 2024-05-15T18:46:47.015Z,1715798807.015 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-15T18:46:47.016Z,1715798807.016 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-15T18:46:47.394Z,1715798807.394 [DockingStepper](INFO): Starting 2024-05-15T18:46:47.895Z,1715798807.895 [DockingStepper](INFO): Starting 2024-05-15T18:46:48.198Z,1715798808.198 [DockingStepper](INFO): Starting 2024-05-15T18:46:48.248Z,1715798808.248 [DockingStepper](INFO): Init string: /1z16351aE13561N3m50 2024-05-15T18:46:48.620Z,1715798808.620 [DockingStepper](INFO): Starting 2024-05-15T18:46:49.006Z,1715798809.006 [DockingStepper](INFO): Starting 2024-05-15T18:46:49.412Z,1715798809.412 [DockingStepper](INFO): Starting 2024-05-15T18:46:49.906Z,1715798809.906 [DockingStepper](INFO): Starting 2024-05-15T18:46:49.940Z,1715798809.940 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-15T18:46:50.231Z,1715798810.231 [DockingStepper](INFO): Starting 2024-05-15T18:46:51.828Z,1715798811.828 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,240515104815 +21.0, 0.0,1524.2, 0 2024-05-15T18:47:12.036Z,1715798832.036 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,24051510483570,35.0, +21.0, 0.0,15 2024-05-15T18:48:12.003Z,1715798892.003 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for bottom velocity. Device response is::BS,-32768,-32768, 2024-05-15T18:48:27.893Z,1715798907.893 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-15T18:48:34.361Z,1715798914.361 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-15T18:48:42.163Z,1715798922.163 [CommandExec](IMPORTANT): got command restart system 2024-05-15T18:48:44.322Z,1715798924.322 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-15T18:48:44.322Z,1715798924.322 [CommandExec](INFO): Uninitializing the command executive. 2024-05-15T18:48:44.322Z,1715798924.322 [CommandExec](INFO): Uninitializing the command scheduler. 2024-05-15T18:48:44.322Z,1715798924.322 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-05-15T18:48:44.349Z,1715798924.349 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2024-05-15T18:48:44.350Z,1715798924.350 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2024-05-15T18:48:44.350Z,1715798924.350 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-05-15T18:48:44.351Z,1715798924.351 [NavChartDb](INFO): Join timeout helper Thread ID is 1993 2024-05-15T18:48:44.565Z,1715798924.565 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-15T18:48:44.566Z,1715798924.566 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-05-15T18:48:44.577Z,1715798924.577 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler 2024-05-15T18:48:44.578Z,1715798924.578 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2024-05-15T18:48:44.578Z,1715798924.578 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 1994 2024-05-15T18:48:44.877Z,1715798924.877 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-15T18:48:44.878Z,1715798924.878 [WetLabsUBAT](INFO): Powering down 2024-05-15T18:48:44.879Z,1715798924.879 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2024-05-15T18:48:44.879Z,1715798924.879 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2024-05-15T18:48:44.879Z,1715798924.879 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-05-15T18:48:44.880Z,1715798924.880 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1995 2024-05-15T18:48:45.354Z,1715798925.354 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-15T18:48:45.354Z,1715798925.354 [WetLabsBB2FL](INFO): Powering down 2024-05-15T18:48:45.355Z,1715798925.355 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-05-15T18:48:45.361Z,1715798925.361 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2024-05-15T18:48:45.361Z,1715798925.361 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-05-15T18:48:45.362Z,1715798925.362 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1996 2024-05-15T18:48:46.198Z,1715798926.198 [CTD_Seabird](INFO): Powering down 2024-05-15T18:48:46.210Z,1715798926.210 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-15T18:48:46.210Z,1715798926.210 [CTD_Seabird](INFO): Powering down 2024-05-15T18:48:46.225Z,1715798926.225 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-05-15T18:48:46.229Z,1715798926.229 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2024-05-15T18:48:46.229Z,1715798926.229 [DAT ThreadHandler](INFO): Thread cancelled. 2024-05-15T18:48:46.230Z,1715798926.230 [DAT](INFO): Join timeout helper Thread ID is 1997 2024-05-15T18:48:46.454Z,1715798926.454 [DAT](INFO): Powering down 2024-05-15T18:48:46.455Z,1715798926.455 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-15T18:48:46.455Z,1715798926.455 [DAT](INFO): Powering down 2024-05-15T18:48:46.456Z,1715798926.456 [DAT ThreadHandler](INFO): Thread cancelled. 2024-05-15T18:48:46.474Z,1715798926.474 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2024-05-15T18:48:46.474Z,1715798926.474 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-05-15T18:48:46.475Z,1715798926.475 [Radio_Surface](INFO): Join timeout helper Thread ID is 1998 2024-05-15T18:48:46.561Z,1715798926.561 [Radio_Surface](INFO): Powering down 2024-05-15T18:48:46.562Z,1715798926.562 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-15T18:48:46.563Z,1715798926.563 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-05-15T18:48:46.582Z,1715798926.582 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2024-05-15T18:48:46.582Z,1715798926.582 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-05-15T18:48:46.582Z,1715798926.582 [Onboard](INFO): Join timeout helper Thread ID is 1999 2024-05-15T18:48:46.790Z,1715798926.790 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-15T18:48:46.790Z,1715798926.790 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-05-15T18:48:46.802Z,1715798926.802 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2024-05-15T18:48:46.803Z,1715798926.803 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-05-15T18:48:46.803Z,1715798926.803 [DataOverHttps](INFO): Join timeout helper Thread ID is 2000 2024-05-15T18:48:47.288Z,1715798927.288 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-15T18:48:47.341Z,1715798927.341 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-15T18:48:47.342Z,1715798927.342 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-05-15T18:48:47.358Z,1715798927.358 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2024-05-15T18:48:47.358Z,1715798927.358 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-05-15T18:48:47.359Z,1715798927.359 [BackseatComponent](INFO): Join timeout helper Thread ID is 2001 2024-05-15T18:48:47.537Z,1715798927.537 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-15T18:48:47.538Z,1715798927.538 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-05-15T18:48:47.546Z,1715798927.546 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2024-05-15T18:48:47.546Z,1715798927.546 [logger ThreadHandler](INFO): Thread cancelled. 2024-05-15T18:48:47.546Z,1715798927.546 [logger](INFO): Join timeout helper Thread ID is 2002 2024-05-15T18:48:47.581Z,1715798927.581 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-15T18:48:47.582Z,1715798927.582 [logger ThreadHandler](INFO): Thread cancelled. 2024-05-15T18:48:47.587Z,1715798927.587 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2024-05-15T18:48:47.588Z,1715798927.588 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-05-15T18:48:47.590Z,1715798927.590 [CommandLine](INFO): Join timeout helper Thread ID is 2003 2024-05-15T18:48:47.594Z,1715798927.594 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-15T18:48:47.594Z,1715798927.594 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-05-15T18:48:47.610Z,1715798927.610 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2024-05-15T18:48:47.610Z,1715798927.610 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-05-15T18:48:47.610Z,1715798927.610 [CommandExec](INFO): Join timeout helper Thread ID is 2004 2024-05-15T18:48:47.612Z,1715798927.612 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2024-05-15T18:48:47.612Z,1715798927.612 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-05-15T18:48:47.612Z,1715798927.612 [controlThread](INFO): Join timeout helper Thread ID is 2005 2024-05-15T18:48:47.677Z,1715798927.677 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-15T18:48:47.678Z,1715798927.678 [controlThread](DEBUG): Uninitializing ControlThread 2024-05-15T18:48:47.678Z,1715798927.678 [AHRS_M2](INFO): Powering down 2024-05-15T18:48:47.680Z,1715798927.680 [NAL9602](INFO): Powering down 2024-05-15T18:48:47.681Z,1715798927.681 [RDI_Pathfinder](INFO): Powering down 2024-05-15T18:48:47.683Z,1715798927.683 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2024-05-15T18:48:47.683Z,1715798927.683 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2024-05-15T18:48:47.684Z,1715798927.684 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2024-05-15T18:48:47.684Z,1715798927.684 [MissionManager](INFO): Uninitializing Mission Default 2024-05-15T18:48:47.684Z,1715798927.684 [Default] Stopped 2024-05-15T18:48:47.684Z,1715798927.684 [Default](DEBUG): Aggregate::uninitialize Default 2024-05-15T18:48:47.684Z,1715798927.684 [Default:B.GoToSurface] Stopped 2024-05-15T18:48:47.684Z,1715798927.684 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-05-15T18:48:47.684Z,1715798927.684 [Default:CheckIn] Stopped 2024-05-15T18:48:47.684Z,1715798927.684 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-15T18:48:47.685Z,1715798927.685 [Default:CheckIn:C.Wait] Stopped 2024-05-15T18:48:47.685Z,1715798927.685 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-15T18:48:47.688Z,1715798927.688 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2024-05-15T18:48:47.688Z,1715798927.688 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2024-05-15T18:48:47.688Z,1715798927.688 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2024-05-15T18:48:47.689Z,1715798927.689 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2024-05-15T18:48:47.689Z,1715798927.689 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2024-05-15T18:48:47.689Z,1715798927.689 [BuoyancyServo](INFO): Powering down 2024-05-15T18:48:47.702Z,1715798927.702 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2024-05-15T18:48:47.702Z,1715798927.702 [ElevatorServo](INFO): Powering down 2024-05-15T18:48:47.703Z,1715798927.703 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-15T18:48:47.704Z,1715798927.704 [DockingStepper](INFO): Powering down 2024-05-15T18:48:47.705Z,1715798927.704 [MassServo](DEBUG): Uninitialize Mass Servo. 2024-05-15T18:48:47.705Z,1715798927.705 [MassServo](INFO): Powering down 2024-05-15T18:48:47.706Z,1715798927.706 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-05-15T18:48:47.706Z,1715798927.706 [RudderServo](INFO): Powering down 2024-05-15T18:48:47.707Z,1715798927.707 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2024-05-15T18:48:47.707Z,1715798927.707 [ThrusterServo](INFO): Powering down 2024-05-15T18:48:47.708Z,1715798927.708 [SBIT](DEBUG): Uninitialize SBIT Component. 2024-05-15T18:48:47.708Z,1715798927.708 [IBIT](DEBUG): Uninitialize IBIT Component. 2024-05-15T18:48:47.708Z,1715798927.708 [CBIT](DEBUG): Uninitialize CBIT Component. 2024-05-15T18:48:47.708Z,1715798927.708 [CBIT](DEBUG): Powering off loads. 2024-05-15T18:48:47.721Z,1715798927.721 [CBIT](DEBUG): Disabling WDT. 2024-05-15T18:48:47.733Z,1715798927.733 [CBIT](DEBUG): Opening all GF detection circuits. 2024-05-15T18:48:47.736Z,1715798927.736 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-05-15T18:48:47.835Z,1715798927.835 [DAT ThreadHandler](INFO): Thread cancelled. 2024-05-15T18:48:47.843Z,1715798927.843 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-05-15T18:48:47.845Z,1715798927.845 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-05-15T18:48:47.852Z,1715798927.852 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-05-15T18:48:47.896Z,1715798927.896 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-05-15T18:48:47.974Z,1715798927.974 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2024-05-15T18:48:47.975Z,1715798927.975 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-05-15T18:48:47.982Z,1715798927.982 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-05-15T18:48:47.993Z,1715798927.993 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-05-15T18:48:48.078Z,1715798928.078 [logger ThreadHandler](INFO): Thread cancelled.