2024-05-15T18:57:12.945Z,1715799432.945 [CommandExec](IMPORTANT): got command restart logs 2024-05-15T18:57:13.272Z,1715799433.272 [OnDock:restartDHCP] Running Loop=1 2024-05-15T18:57:13.273Z,1715799433.273 [OnDock:restartDHCP](DEBUG): Aggregate::initialize OnDock:restartDHCP 2024-05-15T18:57:13.273Z,1715799433.273 [OnDock:restartDHCP:A] Running Loop=1 2024-05-15T18:57:13.273Z,1715799433.273 [OnDock:restartDHCP:A](IMPORTANT): Restarting DHCP... 2024-05-15T18:57:13.273Z,1715799433.273 [OnDock:restartDHCP:A] Stopped 2024-05-15T18:57:13.273Z,1715799433.273 [OnDock:restartDHCP:B.Execute] Running Loop=1 2024-05-15T18:57:13.680Z,1715799433.680 [OnDock:restartDHCP:B.Execute](INFO): Executing command ! /mnt/mmc/LRAUV/Tools/dhcp.sh 2024-05-15T18:57:13.680Z,1715799433.680 [OnDock:restartDHCP:B.Execute] Stopped 2024-05-15T18:57:13.680Z,1715799433.680 [OnDock:restartDHCP](INFO): Completed OnDock:restartDHCP 2024-05-15T18:57:13.680Z,1715799433.680 [OnDock:restartDHCP] Stopped 2024-05-15T18:57:13.680Z,1715799433.680 [OnDock:restartDHCP](DEBUG): Aggregate::uninitialize OnDock:restartDHCP 2024-05-15T18:57:13.769Z,1715799433.769 [DataOverHttps](INFO): Moved sent file to Logs/20240515T185031/Courier0007.lzma.bak 2024-05-15T18:57:13.769Z,1715799433.769 [DataOverHttps](INFO): SBD MOMSN=21023276 2024-05-15T18:57:14.083Z,1715799434.083 [OnDock:M](INFO): Completed OnDock:M 2024-05-15T18:57:14.083Z,1715799434.083 [OnDock:M] Stopped 2024-05-15T18:57:14.083Z,1715799434.083 [OnDock:M](DEBUG): Aggregate::uninitialize OnDock:M 2024-05-15T18:57:14.083Z,1715799434.083 [OnDock:O.Docked] Running Loop=1 2024-05-15T18:57:14.083Z,1715799434.083 [OnDock:O.Docked](DEBUG): Initialize. 2024-05-15T18:57:14.083Z,1715799434.083 [OnDock:O.Docked](DEBUG): Initializing internal variables to default values. 2024-05-15T18:57:27.376Z,1715799447.376 [CommandExec](IMPORTANT): got command ! /mnt/mmc/LRAUV/Tools/dhcp.sh 2024-05-15T18:57:28.337Z,1715799448.337 [CommandExec](IMPORTANT): /mnt/mmc/started/2024-05-15UTC185728_sh 2024-05-15T18:57:39.826Z,1715799459.826 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20240515T185645/Courier0000.lzma 2024-05-15T18:57:40.829Z,1715799460.829 [DataOverHttps](INFO): Moved sent file to Logs/20240515T185645/Courier0000.lzma.bak 2024-05-15T18:57:40.829Z,1715799460.829 [DataOverHttps](INFO): SBD MOMSN=21023278 2024-05-15T18:58:00.469Z,1715799480.469 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240515T185645/Courier0003.lzma 2024-05-15T18:58:00.737Z,1715799480.737 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-15T18:58:01.480Z,1715799481.480 [DataOverHttps](INFO): Moved sent file to Logs/20240515T185645/Courier0003.lzma.bak 2024-05-15T18:58:01.480Z,1715799481.480 [DataOverHttps](INFO): SBD MOMSN=21023281 2024-05-15T18:58:07.207Z,1715799487.207 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-15T18:58:16.512Z,1715799496.512 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,240515105940 +21.2, 0.0,1524.7, 0 2024-05-15T18:58:20.147Z,1715799500.147 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-15T18:58:21.747Z,1715799501.747 [DataOverHttps](INFO): Sending 397 bytes from file Logs/20240515T185031/Express0005.lzma 2024-05-15T18:58:22.749Z,1715799502.749 [DataOverHttps](INFO): Moved sent file to Logs/20240515T185031/Express0005.lzma.bak 2024-05-15T18:58:22.749Z,1715799502.749 [DataOverHttps](INFO): SBD MOMSN=21023283 2024-05-15T18:58:32.382Z,1715799512.382 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-15T18:58:44.635Z,1715799524.635 [DataOverHttps](INFO): Sending 607 bytes from file Logs/20240515T185031/Express0008.lzma 2024-05-15T18:58:44.909Z,1715799524.909 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-15T18:58:45.616Z,1715799525.616 [DataOverHttps](INFO): Moved sent file to Logs/20240515T185031/Express0008.lzma.bak 2024-05-15T18:58:45.616Z,1715799525.616 [DataOverHttps](INFO): SBD MOMSN=21023289 2024-05-15T18:58:57.855Z,1715799537.855 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-15T18:59:05.503Z,1715799545.503 [DataOverHttps](INFO): Sending 943 bytes from file Logs/20240515T185645/Express0001.lzma 2024-05-15T18:59:06.369Z,1715799546.369 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for water velocity. Device response is::WS,-3276,-32768,V 2024-05-15T18:59:06.507Z,1715799546.507 [DataOverHttps](INFO): Moved sent file to Logs/20240515T185645/Express0001.lzma.bak 2024-05-15T18:59:06.507Z,1715799546.507 [DataOverHttps](INFO): SBD MOMSN=21023304 2024-05-15T18:59:09.973Z,1715799549.973 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-15T18:59:10.435Z,1715799550.435 [OnDock:I.](INFO): *** querying acoustic contact *** 2024-05-15T18:59:10.556Z,1715799550.556 [DAT](INFO): ****** received valid address query ****** 2024-05-15T18:59:10.556Z,1715799550.556 [DAT](INFO): ****** received valid ping request ****** 2024-05-15T18:59:10.557Z,1715799550.557 [DAT](INFO): setting remote address to 0 2024-05-15T18:59:10.807Z,1715799550.807 [DAT](INFO): DAT read: user:6> 2024-05-15T18:59:10.808Z,1715799550.808 [DAT](INFO): DAT read: RemoteAddr | 0 2024-05-15T18:59:10.808Z,1715799550.808 [DAT](INFO): set remote address to 0 2024-05-15T18:59:10.809Z,1715799550.809 [DAT](INFO): ****** received valid address query ****** 2024-05-15T18:59:10.809Z,1715799550.809 [DAT](INFO): ****** received valid ping request ****** 2024-05-15T18:59:10.809Z,1715799550.809 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode. 2024-05-15T18:59:11.061Z,1715799551.061 [DAT](INFO): DAT read: user:7> 2024-05-15T18:59:11.063Z,1715799551.063 [DAT](INFO): DAT read: Tx time:18:59:09.5262 2024-05-15T18:59:11.063Z,1715799551.063 [DAT](INFO): Ping request sent. 2024-05-15T18:59:11.063Z,1715799551.063 [DAT](INFO): transmitted an acoustic signal 2024-05-15T18:59:11.064Z,1715799551.064 [DAT](INFO): DAT read: TxSync time:18:59:09.5254 2024-05-15T18:59:11.064Z,1715799551.064 [DAT](INFO): unknown deviceResponse_: TxSync time:18:59:09.5254 2024-05-15T18:59:11.064Z,1715799551.064 [DAT](INFO): publishing transmit ping time 2024-05-15T18:59:11.065Z,1715799551.065 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.002085 2024-05-15T18:59:11.311Z,1715799551.311 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.247977 2024-05-15T18:59:11.563Z,1715799551.563 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.500171 2024-05-15T18:59:11.815Z,1715799551.815 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.752119 2024-05-15T18:59:12.068Z,1715799552.068 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.004363 2024-05-15T18:59:12.319Z,1715799552.319 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.256150 2024-05-15T18:59:12.572Z,1715799552.572 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.508357 2024-05-15T18:59:12.823Z,1715799552.823 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.760201 2024-05-15T18:59:13.075Z,1715799553.075 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.012246 2024-05-15T18:59:13.327Z,1715799553.327 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.264099 2024-05-15T18:59:13.579Z,1715799553.579 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.516081 2024-05-15T18:59:13.831Z,1715799553.831 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.768166 2024-05-15T18:59:14.083Z,1715799554.083 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.020001 2024-05-15T18:59:14.336Z,1715799554.336 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.272351 2024-05-15T18:59:14.589Z,1715799554.589 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.525621 2024-05-15T18:59:14.839Z,1715799554.839 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.776157 2024-05-15T18:59:15.091Z,1715799555.091 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.028107 2024-05-15T18:59:15.344Z,1715799555.344 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.280474 2024-05-15T18:59:15.595Z,1715799555.595 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.532071 2024-05-15T18:59:15.849Z,1715799555.849 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.786087 2024-05-15T18:59:16.100Z,1715799556.100 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.036664 2024-05-15T18:59:16.351Z,1715799556.351 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.288000 2024-05-15T18:59:16.443Z,1715799556.443 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,24051511004016,35.0, +21.21524.7, 0 2024-05-15T18:59:16.604Z,1715799556.604 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.540606 2024-05-15T18:59:16.858Z,1715799556.858 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.794704 2024-05-15T18:59:17.107Z,1715799557.107 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.043982 2024-05-15T18:59:17.360Z,1715799557.360 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.296548 2024-05-15T18:59:17.611Z,1715799557.611 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.548098 2024-05-15T18:59:17.863Z,1715799557.863 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.800061 2024-05-15T18:59:18.117Z,1715799558.117 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.053608 2024-05-15T18:59:18.367Z,1715799558.367 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.304069 2024-05-15T18:59:18.620Z,1715799558.620 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.556285 2024-05-15T18:59:18.871Z,1715799558.871 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.808021 2024-05-15T18:59:19.123Z,1715799559.123 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.060044 2024-05-15T18:59:19.379Z,1715799559.379 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.314846 2024-05-15T18:59:19.627Z,1715799559.627 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.564028 2024-05-15T18:59:19.879Z,1715799559.879 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.816114 2024-05-15T18:59:20.132Z,1715799560.132 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.068394 2024-05-15T18:59:20.383Z,1715799560.383 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.320005 2024-05-15T18:59:20.637Z,1715799560.637 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.573476 2024-05-15T18:59:20.887Z,1715799560.887 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.824138 2024-05-15T18:59:22.497Z,1715799562.497 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-15T18:59:23.407Z,1715799563.407 [DAT](INFO): DAT read: Response Not Received 2024-05-15T18:59:23.408Z,1715799563.408 [DAT](INFO): response not received 2024-05-15T18:59:23.408Z,1715799563.408 [DAT](ERROR): No response from remote modem. 2024-05-15T18:59:26.370Z,1715799566.370 [DataOverHttps](INFO): Sending 124 bytes from file Logs/20240515T185645/Express0004.lzma 2024-05-15T18:59:27.372Z,1715799567.372 [DataOverHttps](INFO): Moved sent file to Logs/20240515T185645/Express0004.lzma.bak 2024-05-15T18:59:27.372Z,1715799567.372 [DataOverHttps](INFO): SBD MOMSN=21023319 2024-05-15T18:59:35.443Z,1715799575.443 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-15T18:59:47.562Z,1715799587.562 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-15T18:59:50.979Z,1715799590.979 [CommandExec](IMPORTANT): got command stop 2024-05-15T18:59:50.980Z,1715799590.980 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop 2024-05-15T18:59:51.284Z,1715799591.284 [MissionManager](INFO): MissionManager is completed. 2024-05-15T18:59:51.284Z,1715799591.284 [MissionManager](INFO): Uninitializing Mission OnDock 2024-05-15T18:59:51.284Z,1715799591.284 [OnDock] Stopped 2024-05-15T18:59:51.284Z,1715799591.284 [OnDock](DEBUG): Aggregate::uninitialize OnDock 2024-05-15T18:59:51.284Z,1715799591.284 [OnDock:StandardEnvelopes] Stopped 2024-05-15T18:59:51.284Z,1715799591.284 [OnDock:StandardEnvelopes](DEBUG): Aggregate::uninitialize OnDock:StandardEnvelopes 2024-05-15T18:59:51.285Z,1715799591.285 [OnDock:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2024-05-15T18:59:51.285Z,1715799591.285 [OnDock:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2024-05-15T18:59:51.285Z,1715799591.285 [OnDock:StandardEnvelopes:B.DepthEnvelope] Stopped 2024-05-15T18:59:51.285Z,1715799591.285 [OnDock:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2024-05-15T18:59:51.285Z,1715799591.285 [OnDock:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2024-05-15T18:59:51.285Z,1715799591.285 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2024-05-15T18:59:51.285Z,1715799591.285 [OnDock:BackseatDriver] Stopped 2024-05-15T18:59:51.285Z,1715799591.285 [OnDock:BackseatDriver](DEBUG): Aggregate::uninitialize OnDock:BackseatDriver 2024-05-15T18:59:51.285Z,1715799591.285 [OnDock:BackseatDriver:A.BackseatDriver] Stopped 2024-05-15T18:59:51.285Z,1715799591.285 [OnDock:PowerOnly] Stopped 2024-05-15T18:59:51.285Z,1715799591.285 [OnDock:PowerOnly](DEBUG): Aggregate::uninitialize OnDock:PowerOnly 2024-05-15T18:59:51.285Z,1715799591.285 [OnDock:PowerOnly:E.Wait] Stopped 2024-05-15T18:59:51.286Z,1715799591.286 [OnDock:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2024-05-15T18:59:51.286Z,1715799591.286 [OnDock:H] Stopped 2024-05-15T18:59:51.286Z,1715799591.286 [OnDock:I.] Stopped 2024-05-15T18:59:51.286Z,1715799591.286 [OnDock:I.](DEBUG): Uninitializing TrackAcousticContact. 2024-05-15T18:59:51.286Z,1715799591.286 [OnDock:PeriodicCheck] Stopped 2024-05-15T18:59:51.286Z,1715799591.286 [OnDock:PeriodicCheck](DEBUG): Aggregate::uninitialize OnDock:PeriodicCheck 2024-05-15T18:59:51.314Z,1715799591.314 [OnDock:PeriodicCheck:CheckIP] Stopped 2024-05-15T18:59:51.314Z,1715799591.314 [OnDock:PeriodicCheck:CheckIP](DEBUG): Aggregate::uninitialize OnDock:PeriodicCheck:CheckIP 2024-05-15T18:59:51.315Z,1715799591.315 [OnDock:PeriodicCheck:CheckIP:B.Wait] Stopped 2024-05-15T18:59:51.315Z,1715799591.315 [OnDock:PeriodicCheck:CheckIP:B.Wait](DEBUG): Uninitialize Wait Component. 2024-05-15T18:59:51.315Z,1715799591.315 [OnDock:O.Docked] Stopped 2024-05-15T18:59:51.637Z,1715799591.637 [MissionManager](IMPORTANT): Started mission Default 2024-05-15T18:59:51.638Z,1715799591.638 [Default] Running Loop=1 2024-05-15T18:59:51.638Z,1715799591.638 [Default](DEBUG): Aggregate::initialize Default 2024-05-15T18:59:51.638Z,1715799591.638 [Default:B.GoToSurface] Running Loop=1 2024-05-15T18:59:51.638Z,1715799591.638 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-05-15T18:59:51.643Z,1715799591.643 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-05-15T18:59:51.644Z,1715799591.644 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-05-15T18:59:51.644Z,1715799591.644 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-05-15T18:59:51.644Z,1715799591.644 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-05-15T18:59:51.645Z,1715799591.645 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-05-15T18:59:51.645Z,1715799591.645 [Default:A.Wait] Running Loop=1 2024-05-15T18:59:51.645Z,1715799591.645 [Default:A.Wait](DEBUG): Initialize Wait Component. 2024-05-15T19:00:00.085Z,1715799600.085 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-15T19:00:04.947Z,1715799604.947 [Default:A.Wait](INFO): Done Waiting. 2024-05-15T19:00:04.947Z,1715799604.947 [Default:A.Wait] Stopped 2024-05-15T19:00:04.947Z,1715799604.947 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2024-05-15T19:00:05.355Z,1715799605.355 [Default:CheckIn] Running Loop=1 2024-05-15T19:00:05.355Z,1715799605.355 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-15T19:00:05.355Z,1715799605.355 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-15T19:00:07.365Z,1715799607.365 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190006.00,A,3648.16520,N,12147.27914,W,0.661,0.00,150524,,,A*75 2024-05-15T19:00:07.368Z,1715799607.368 [NAL9602](INFO): GPS fix at 20240515T190006: (36.802753, -121.787986) 2024-05-15T19:00:07.379Z,1715799607.379 [Default:CheckIn:Read_GPS] Stopped 2024-05-15T19:00:07.379Z,1715799607.379 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-15T19:00:13.105Z,1715799613.105 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-15T19:00:16.245Z,1715799616.245 [DataOverHttps](INFO): Sending 268 bytes from file Logs/20240515T185712/Courier0000.lzma 2024-05-15T19:00:17.246Z,1715799617.246 [DataOverHttps](INFO): Moved sent file to Logs/20240515T185712/Courier0000.lzma.bak 2024-05-15T19:00:17.246Z,1715799617.246 [DataOverHttps](INFO): SBD MOMSN=21023322 2024-05-15T19:00:26.034Z,1715799626.034 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-15T19:00:35.880Z,1715799635.880 [DockingStepper](ERROR): only read 1 of 4 ADC values. Response is: ÿ/0`16319, 2024-05-15T19:00:35.880Z,1715799635.880 [DockingStepper](FAULT): Docking arm not reporting position 2024-05-15T19:00:35.880Z,1715799635.880 [DockingStepper] Communications Fault, FailCount= 1 2024-05-15T19:00:35.880Z,1715799635.880 [DockingStepper](ERROR): Communications Fault 2024-05-15T19:00:35.885Z,1715799635.885 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-15T19:00:36.351Z,1715799636.351 [DockingStepper](INFO): Stop 2024-05-15T19:00:36.352Z,1715799636.352 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-15T19:00:36.352Z,1715799636.352 [DockingStepper](INFO): Powering down 2024-05-15T19:00:36.709Z,1715799636.709 [DockingStepper](INFO): Stopping 2024-05-15T19:00:37.070Z,1715799637.070 [DockingStepper](INFO): Stopped 2024-05-15T19:00:37.083Z,1715799637.083 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-15T19:00:37.083Z,1715799637.083 [DockingStepper] No Fault, FailCount= 1 2024-05-15T19:00:37.656Z,1715799637.656 [DockingStepper](INFO): Start 2024-05-15T19:00:37.656Z,1715799637.656 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-15T19:00:37.656Z,1715799637.656 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-15T19:00:37.828Z,1715799637.828 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2024-05-15T19:00:37.910Z,1715799637.910 [NAL9602](ERROR): received: +CSQ:0 OK 2024-05-15T19:00:37.984Z,1715799637.984 [DockingStepper](INFO): Starting 2024-05-15T19:00:38.447Z,1715799638.447 [DockingStepper](INFO): Starting 2024-05-15T19:00:38.701Z,1715799638.701 [DockingStepper](INFO): Starting 2024-05-15T19:00:38.748Z,1715799638.748 [DockingStepper](INFO): Init string: /1z14960aE13561N3m50 2024-05-15T19:00:39.096Z,1715799639.096 [DockingStepper](INFO): Starting 2024-05-15T19:00:39.509Z,1715799639.509 [DockingStepper](INFO): Starting 2024-05-15T19:00:39.900Z,1715799639.900 [DockingStepper](INFO): Starting 2024-05-15T19:00:39.943Z,1715799639.943 [DataOverHttps](INFO): Sending 571 bytes from file Logs/20240515T185712/Express0001.lzma 2024-05-15T19:00:40.322Z,1715799640.322 [DockingStepper](INFO): Starting 2024-05-15T19:00:40.367Z,1715799640.367 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-15T19:00:40.680Z,1715799640.680 [DockingStepper](INFO): Starting 2024-05-15T19:00:40.896Z,1715799640.896 [DataOverHttps](INFO): Moved sent file to Logs/20240515T185712/Express0001.lzma.bak 2024-05-15T19:00:40.897Z,1715799640.897 [DataOverHttps](INFO): SBD MOMSN=21023326 2024-05-15T19:00:45.799Z,1715799645.799 [Default:CheckIn:Read_Iridium] Stopped 2024-05-15T19:00:45.799Z,1715799645.799 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-15T19:00:45.799Z,1715799645.799 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-15T19:01:03.723Z,1715799663.723 [DockingStepper](ERROR): only read 0 of 4 ADC values. Response is: ÿ/0@ 2024-05-15T19:01:03.723Z,1715799663.723 [DockingStepper](FAULT): Docking arm not reporting position 2024-05-15T19:01:03.723Z,1715799663.723 [DockingStepper] Communications Fault, FailCount= 1 2024-05-15T19:01:03.723Z,1715799663.723 [DockingStepper](ERROR): Communications Fault 2024-05-15T19:01:03.740Z,1715799663.740 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-15T19:01:04.156Z,1715799664.156 [DockingStepper](INFO): Stop 2024-05-15T19:01:04.156Z,1715799664.156 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-15T19:01:04.157Z,1715799664.157 [DockingStepper](INFO): Powering down 2024-05-15T19:01:04.600Z,1715799664.600 [DockingStepper](INFO): Stopping 2024-05-15T19:01:04.904Z,1715799664.904 [DockingStepper](INFO): Stopped 2024-05-15T19:01:04.953Z,1715799664.953 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-15T19:01:04.953Z,1715799664.953 [DockingStepper] No Fault, FailCount= 1 2024-05-15T19:01:05.273Z,1715799665.273 [DockingStepper](INFO): Start 2024-05-15T19:01:05.273Z,1715799665.273 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-15T19:01:05.274Z,1715799665.274 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-15T19:01:05.696Z,1715799665.696 [DockingStepper](INFO): Starting 2024-05-15T19:01:06.109Z,1715799666.109 [DockingStepper](INFO): Starting 2024-05-15T19:01:06.578Z,1715799666.578 [DockingStepper](INFO): Starting 2024-05-15T19:01:06.631Z,1715799666.631 [DockingStepper](INFO): Init string: /1z14945aE13561N3m50 2024-05-15T19:01:06.899Z,1715799666.899 [DockingStepper](INFO): Starting 2024-05-15T19:01:07.329Z,1715799667.329 [DockingStepper](INFO): Starting 2024-05-15T19:01:07.714Z,1715799667.714 [DockingStepper](INFO): Starting 2024-05-15T19:01:08.235Z,1715799668.235 [DockingStepper](INFO): Starting 2024-05-15T19:01:08.267Z,1715799668.267 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-15T19:01:08.616Z,1715799668.616 [DockingStepper](INFO): Starting 2024-05-15T19:01:56.539Z,1715799716.539 [RDI_Pathfinder](ERROR): only read 1 of 4 data items for beam range. Device response is::RA, 0.00, 0.00, :WI,-32768,-32768,-32768,-32768,V 2024-05-15T19:02:15.476Z,1715799735.476 [CommandExec](IMPORTANT): got command restart system 2024-05-15T19:02:17.575Z,1715799737.575 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-15T19:02:17.575Z,1715799737.575 [CommandExec](INFO): Uninitializing the command executive. 2024-05-15T19:02:17.575Z,1715799737.575 [CommandExec](INFO): Uninitializing the command scheduler. 2024-05-15T19:02:17.575Z,1715799737.575 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-05-15T19:02:17.637Z,1715799737.637 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2024-05-15T19:02:17.638Z,1715799737.638 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2024-05-15T19:02:17.638Z,1715799737.638 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-05-15T19:02:17.639Z,1715799737.639 [NavChartDb](INFO): Join timeout helper Thread ID is 1052 2024-05-15T19:02:17.707Z,1715799737.707 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-15T19:02:17.707Z,1715799737.707 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-05-15T19:02:17.719Z,1715799737.719 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler 2024-05-15T19:02:17.719Z,1715799737.719 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2024-05-15T19:02:17.719Z,1715799737.719 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 1053 2024-05-15T19:02:18.119Z,1715799738.119 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-15T19:02:18.119Z,1715799738.119 [WetLabsUBAT](INFO): Powering down 2024-05-15T19:02:18.120Z,1715799738.120 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2024-05-15T19:02:18.138Z,1715799738.138 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2024-05-15T19:02:18.138Z,1715799738.138 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-05-15T19:02:18.139Z,1715799738.139 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1054 2024-05-15T19:02:18.595Z,1715799738.595 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-15T19:02:18.595Z,1715799738.595 [WetLabsBB2FL](INFO): Powering down 2024-05-15T19:02:18.596Z,1715799738.596 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-05-15T19:02:18.606Z,1715799738.606 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2024-05-15T19:02:18.606Z,1715799738.606 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-05-15T19:02:18.607Z,1715799738.607 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1055 2024-05-15T19:02:18.795Z,1715799738.795 [CTD_Seabird](INFO): Powering down 2024-05-15T19:02:18.819Z,1715799738.819 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-15T19:02:18.819Z,1715799738.819 [CTD_Seabird](INFO): Powering down 2024-05-15T19:02:18.831Z,1715799738.831 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-05-15T19:02:18.851Z,1715799738.851 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2024-05-15T19:02:18.851Z,1715799738.851 [DAT ThreadHandler](INFO): Thread cancelled. 2024-05-15T19:02:18.851Z,1715799738.851 [DAT](INFO): Join timeout helper Thread ID is 1056 2024-05-15T19:02:18.935Z,1715799738.935 [DAT](INFO): Powering down 2024-05-15T19:02:18.936Z,1715799738.936 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-15T19:02:18.936Z,1715799738.936 [DAT](INFO): Powering down 2024-05-15T19:02:18.937Z,1715799738.937 [DAT ThreadHandler](INFO): Thread cancelled. 2024-05-15T19:02:18.951Z,1715799738.951 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2024-05-15T19:02:18.951Z,1715799738.951 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-05-15T19:02:18.952Z,1715799738.952 [Radio_Surface](INFO): Join timeout helper Thread ID is 1057 2024-05-15T19:02:19.075Z,1715799739.075 [Radio_Surface](INFO): Powering down 2024-05-15T19:02:19.076Z,1715799739.076 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-15T19:02:19.076Z,1715799739.076 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-05-15T19:02:19.091Z,1715799739.091 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2024-05-15T19:02:19.091Z,1715799739.091 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-05-15T19:02:19.092Z,1715799739.092 [Onboard](INFO): Join timeout helper Thread ID is 1058 2024-05-15T19:02:19.280Z,1715799739.280 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2024-05-15T19:02:21.347Z,1715799741.347 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-15T19:02:21.347Z,1715799741.347 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-05-15T19:02:21.351Z,1715799741.351 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2024-05-15T19:02:21.352Z,1715799741.352 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-05-15T19:02:21.352Z,1715799741.352 [DataOverHttps](INFO): Join timeout helper Thread ID is 1059 2024-05-15T19:02:22.290Z,1715799742.290 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-15T19:02:22.291Z,1715799742.291 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-05-15T19:02:22.295Z,1715799742.295 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2024-05-15T19:02:22.295Z,1715799742.295