2024-08-13T17:55:46.240Z,1723571746.240 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-08-13T17:50:46.0Z
2024-08-13T17:55:46.240Z,1723571746.240 [Default:CheckIn:Read_GPS] Stopped
2024-08-13T17:55:46.240Z,1723571746.240 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-13T17:55:53.879Z,1723571753.879 [DataOverHttps](INFO): Sending 82 bytes from file Logs/20240813T011835/Courier0286.lzma
2024-08-13T17:55:54.882Z,1723571754.882 [DataOverHttps](INFO): Moved sent file to Logs/20240813T011835/Courier0286.lzma.bak
2024-08-13T17:55:54.882Z,1723571754.882 [DataOverHttps](INFO): SBD MOMSN=21496593
2024-08-13T17:56:10.739Z,1723571770.739 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20240813T175103/Courier0000.lzma
2024-08-13T17:56:11.742Z,1723571771.742 [DataOverHttps](INFO): Moved sent file to Logs/20240813T175103/Courier0000.lzma.bak
2024-08-13T17:56:11.742Z,1723571771.742 [DataOverHttps](INFO): SBD MOMSN=21496596
2024-08-13T17:56:18.129Z,1723571778.129 [NAL9602](INFO): Not Powering down - fast GPS
2024-08-13T17:56:28.924Z,1723571788.924 [DataOverHttps](INFO): Sending 510 bytes from file Logs/20240813T011835/Express0287.lzma
2024-08-13T17:56:29.926Z,1723571789.926 [DataOverHttps](INFO): Moved sent file to Logs/20240813T011835/Express0287.lzma.bak
2024-08-13T17:56:29.926Z,1723571789.926 [DataOverHttps](INFO): SBD MOMSN=21496599
2024-08-13T17:56:47.228Z,1723571807.228 [DataOverHttps](INFO): Sending 265 bytes from file Logs/20240813T175103/Express0001.lzma
2024-08-13T17:56:48.230Z,1723571808.230 [DataOverHttps](INFO): Moved sent file to Logs/20240813T175103/Express0001.lzma.bak
2024-08-13T17:56:48.230Z,1723571808.230 [DataOverHttps](INFO): SBD MOMSN=21496614
2024-08-13T17:56:50.894Z,1723571810.894 [Default:CheckIn:Read_Iridium] Stopped
2024-08-13T17:56:50.894Z,1723571810.894 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-13T17:56:50.895Z,1723571810.895 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-13T18:01:51.459Z,1723572111.459 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-08-13T18:01:51.459Z,1723572111.459 [Default:CheckIn:C.Wait] Stopped
2024-08-13T18:01:51.460Z,1723572111.460 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-08-13T18:01:51.460Z,1723572111.460 [Default:CheckIn:D] Running Loop=1
2024-08-13T18:01:51.878Z,1723572111.878 [Default:CheckIn:D] Stopped
2024-08-13T18:01:51.878Z,1723572111.878 [Default:CheckIn:E] Running Loop=1
2024-08-13T18:01:52.275Z,1723572112.275 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  999.174935 min 
2024-08-13T18:01:52.275Z,1723572112.275 [Default:CheckIn:E] Stopped
2024-08-13T18:01:52.275Z,1723572112.275 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-08-13T18:01:52.275Z,1723572112.275 [Default:CheckIn] Stopped
2024-08-13T18:01:52.276Z,1723572112.276 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-13T18:01:52.276Z,1723572112.276 [Default:CheckIn](INFO): Running loop #96
2024-08-13T18:01:52.276Z,1723572112.276 [Default:CheckIn] Running Loop=96
2024-08-13T18:01:52.276Z,1723572112.276 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-13T18:01:52.276Z,1723572112.276 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-13T18:01:53.877Z,1723572113.877 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-08-13T18:01:53.877Z,1723572113.877 [NAL9602] Data Fault, FailCount= 1
2024-08-13T18:01:53.877Z,1723572113.877 [NAL9602](ERROR): Data Fault
2024-08-13T18:01:53.899Z,1723572113.899 [CBIT](ERROR): Data Fault in component: NAL9602
2024-08-13T18:01:54.276Z,1723572114.276 [NAL9602](INFO): Powering down
2024-08-13T18:01:55.115Z,1723572115.115 [CBIT](INFO): Clearing failed state for component NAL9602
2024-08-13T18:01:55.115Z,1723572115.115 [NAL9602] No Fault, FailCount= 1
2024-08-13T18:02:24.589Z,1723572144.589 [NAL9602](INFO): Powering up NAL9602
2024-08-13T18:02:35.500Z,1723572155.500 [NAL9602](INFO): NAL9602 initialized
2024-08-13T18:06:39.220Z,1723572399.220 [CommandExec](IMPORTANT): got command failComponent
2024-08-13T18:06:39.220Z,1723572399.220 [CommandExec](IMPORTANT): Failed components:
2024-08-13T18:06:39.221Z,1723572399.221 [CommandExec](IMPORTANT): No failed Components.
2024-08-13T18:06:43.649Z,1723572403.649 [CommandExec](IMPORTANT): got command get latitude
2024-08-13T18:06:43.649Z,1723572403.649 [CommandExec](IMPORTANT): latitude 36.799999 arcdeg
2024-08-13T18:06:52.476Z,1723572412.476 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-08-13T18:01:52.3Z
2024-08-13T18:06:52.476Z,1723572412.476 [Default:CheckIn:Read_GPS] Stopped
2024-08-13T18:06:52.476Z,1723572412.476 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-13T18:06:59.683Z,1723572419.683 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240813T175103/Courier0003.lzma
2024-08-13T18:07:00.686Z,1723572420.686 [DataOverHttps](INFO): Moved sent file to Logs/20240813T175103/Courier0003.lzma.bak
2024-08-13T18:07:00.686Z,1723572420.686 [DataOverHttps](INFO): SBD MOMSN=21496670
2024-08-13T18:07:06.169Z,1723572426.169 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2024-08-13T18:07:17.683Z,1723572437.683 [CommandExec](IMPORTANT): got command set NAL9602.time_fix 1723572426 second
2024-08-13T18:07:19.840Z,1723572439.840 [DataOverHttps](INFO): Sending 314 bytes from file Logs/20240813T175103/Express0004.lzma
2024-08-13T18:07:20.842Z,1723572440.842 [DataOverHttps](INFO): Moved sent file to Logs/20240813T175103/Express0004.lzma.bak
2024-08-13T18:07:20.842Z,1723572440.842 [DataOverHttps](INFO): SBD MOMSN=21496672
2024-08-13T18:07:23.690Z,1723572443.690 [Default:CheckIn:Read_Iridium] Stopped
2024-08-13T18:07:23.690Z,1723572443.690 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-13T18:07:23.690Z,1723572443.690 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-13T18:07:37.688Z,1723572457.688 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2024-08-13T18:07:38.505Z,1723572458.505 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-08-13T18:08:09.191Z,1723572489.191 [NAL9602](INFO): Not Powering down - fast GPS
2024-08-13T18:12:24.131Z,1723572744.131 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-08-13T18:12:24.131Z,1723572744.131 [Default:CheckIn:C.Wait] Stopped
2024-08-13T18:12:24.131Z,1723572744.131 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-08-13T18:12:24.131Z,1723572744.131 [Default:CheckIn:D] Running Loop=1
2024-08-13T18:12:24.533Z,1723572744.533 [Default:CheckIn:D] Stopped
2024-08-13T18:12:24.533Z,1723572744.533 [Default:CheckIn:E] Running Loop=1
2024-08-13T18:12:24.943Z,1723572744.943 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  1009.719206 min 
2024-08-13T18:12:24.943Z,1723572744.943 [Default:CheckIn:E] Stopped
2024-08-13T18:12:24.944Z,1723572744.944 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-08-13T18:12:24.944Z,1723572744.944 [Default:CheckIn] Stopped
2024-08-13T18:12:24.944Z,1723572744.944 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-13T18:12:24.944Z,1723572744.944 [Default:CheckIn](INFO): Running loop #97
2024-08-13T18:12:24.944Z,1723572744.944 [Default:CheckIn] Running Loop=97
2024-08-13T18:12:24.944Z,1723572744.944 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-13T18:12:24.944Z,1723572744.944 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-13T18:12:57.658Z,1723572777.658 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-08-13T18:12:57.736Z,1723572777.736 [NAL9602](ERROR): received: 
+CSQ:0

OK
2024-08-13T18:13:19.474Z,1723572799.474 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2024-08-13T18:14:56.343Z,1723572896.343 [CommandExec](IMPORTANT): got command get ESPComponent.simulateHardware
2024-08-13T18:14:56.343Z,1723572896.343 [CommandExec](IMPORTANT): ESPComponent.simulateHardware 0 bool
2024-08-13T18:15:08.798Z,1723572908.798 [CommandExec](IMPORTANT): got command configSet ESPComponent.simulateHardware 1 bool persist
2024-08-13T18:15:08.799Z,1723572908.799 [CommandExec](IMPORTANT): configSet ESPComponent.simulateHardware requires a restart to take effect.
2024-08-13T18:15:10.789Z,1723572910.789 [CommandExec](IMPORTANT): got command restart application
2024-08-13T18:15:11.792Z,1723572911.792 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2024-08-13T18:15:11.792Z,1723572911.792 [CommandExec](INFO): Uninitializing the command executive.
2024-08-13T18:15:11.792Z,1723572911.792 [CommandExec](INFO): Uninitializing the command scheduler.
2024-08-13T18:15:11.792Z,1723572911.792 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:15:11.812Z,1723572911.812 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2024-08-13T18:15:11.812Z,1723572911.812 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2024-08-13T18:15:11.813Z,1723572911.813 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:15:11.813Z,1723572911.813 [NavChartDb](INFO): Join timeout helper Thread ID is 2876
2024-08-13T18:15:12.092Z,1723572912.092 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2024-08-13T18:15:12.093Z,1723572912.093 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:15:12.112Z,1723572912.112 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2024-08-13T18:15:12.112Z,1723572912.112 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:15:12.112Z,1723572912.112 [Radio_Surface](INFO): Join timeout helper Thread ID is 2877
2024-08-13T18:15:12.368Z,1723572912.368 [Radio_Surface](INFO): Powering down
2024-08-13T18:15:12.369Z,1723572912.369 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2024-08-13T18:15:12.369Z,1723572912.369 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:15:12.376Z,1723572912.376 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2024-08-13T18:15:12.376Z,1723572912.376 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:15:12.376Z,1723572912.376 [Onboard](INFO): Join timeout helper Thread ID is 2878
2024-08-13T18:15:12.464Z,1723572912.464 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2024-08-13T18:15:12.464Z,1723572912.464 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:15:12.476Z,1723572912.476 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2024-08-13T18:15:12.476Z,1723572912.476 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:15:12.476Z,1723572912.476 [DataOverHttps](INFO): Join timeout helper Thread ID is 2879
2024-08-13T18:15:15.271Z,1723572915.271 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2024-08-13T18:15:15.274Z,1723572915.274 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:15:15.280Z,1723572915.280 [ComponentRegistry](INFO): Shutting down DATMMP ThreadHandler
2024-08-13T18:15:15.280Z,1723572915.280 [DATMMP ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:15:15.280Z,1723572915.280 [DATMMP](INFO): Join timeout helper Thread ID is 2880
2024-08-13T18:15:15.404Z,1723572915.404 [DATMMP](INFO): Stop
2024-08-13T18:15:15.404Z,1723572915.404 [DATMMP](INFO): uninitialize
2024-08-13T18:15:15.405Z,1723572915.405 [DATMMP ThreadHandler](INFO): Uninitializing protected caller thread.
2024-08-13T18:15:15.405Z,1723572915.405 [DATMMP](INFO): uninitialize
2024-08-13T18:15:15.405Z,1723572915.405 [DATMMP ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:15:15.413Z,1723572915.413 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2024-08-13T18:15:15.413Z,1723572915.413 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:15:15.413Z,1723572915.413 [BackseatComponent](INFO): Join timeout helper Thread ID is 2881
2024-08-13T18:15:15.468Z,1723572915.468 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2024-08-13T18:15:15.468Z,1723572915.468 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:15:15.477Z,1723572915.477 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2024-08-13T18:15:15.477Z,1723572915.477 [logger ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:15:15.477Z,1723572915.477 [logger](INFO): Join timeout helper Thread ID is 2882
2024-08-13T18:15:15.494Z,1723572915.494 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2024-08-13T18:15:15.495Z,1723572915.495 [logger ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:15:15.512Z,1723572915.512 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2024-08-13T18:15:15.513Z,1723572915.513 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:15:15.513Z,1723572915.513 [CommandLine](INFO): Join timeout helper Thread ID is 2883
2024-08-13T18:15:15.536Z,1723572915.536 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2024-08-13T18:15:15.536Z,1723572915.536 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:15:15.552Z,1723572915.552 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2024-08-13T18:15:15.553Z,1723572915.553 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:15:15.553Z,1723572915.553 [CommandExec](INFO): Join timeout helper Thread ID is 2884
2024-08-13T18:15:15.554Z,1723572915.554 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2024-08-13T18:15:15.554Z,1723572915.554 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:15:15.555Z,1723572915.555 [controlThread](INFO): Join timeout helper Thread ID is 2885
2024-08-13T18:15:15.796Z,1723572915.796 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2024-08-13T18:15:15.796Z,1723572915.796 [controlThread](DEBUG): Uninitializing ControlThread
2024-08-13T18:15:15.798Z,1723572915.798 [AHRS_M2](INFO): Powering down
2024-08-13T18:15:15.869Z,1723572915.869 [NAL9602](INFO): Powering down
2024-08-13T18:15:15.870Z,1723572915.870 [Sonardyne_Nano](INFO): Powering down
2024-08-13T18:15:15.989Z,1723572915.989 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2024-08-13T18:15:15.990Z,1723572915.990 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2024-08-13T18:15:15.990Z,1723572915.990 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2024-08-13T18:15:15.990Z,1723572915.990 [MissionManager](INFO): Uninitializing Mission Default
2024-08-13T18:15:15.991Z,1723572915.991 [Default] Stopped
2024-08-13T18:15:15.991Z,1723572915.991 [Default](DEBUG): Aggregate::uninitialize Default
2024-08-13T18:15:15.991Z,1723572915.991 [Default:B.GoToSurface] Stopped
2024-08-13T18:15:15.991Z,1723572915.991 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-08-13T18:15:15.991Z,1723572915.991 [Default:CheckIn] Stopped
2024-08-13T18:15:15.991Z,1723572915.991 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-13T18:15:15.991Z,1723572915.991 [Default:CheckIn:Read_GPS] Stopped
2024-08-13T18:15:15.994Z,1723572915.994 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2024-08-13T18:15:15.994Z,1723572915.994 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2024-08-13T18:15:15.994Z,1723572915.994 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2024-08-13T18:15:15.995Z,1723572915.995 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2024-08-13T18:15:15.995Z,1723572915.995 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2024-08-13T18:15:15.995Z,1723572915.995 [BuoyancyServo](INFO): Powering down
2024-08-13T18:15:16.008Z,1723572916.008 [MassServo](DEBUG): Uninitialize Mass Servo.
2024-08-13T18:15:16.008Z,1723572916.008 [MassServo](INFO): Powering down
2024-08-13T18:15:16.009Z,1723572916.009 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2024-08-13T18:15:16.009Z,1723572916.009 [ThrusterHE](INFO): Powering down
2024-08-13T18:15:16.010Z,1723572916.010 [SBIT](DEBUG): Uninitialize SBIT Component.
2024-08-13T18:15:16.010Z,1723572916.010 [IBIT](DEBUG): Uninitialize IBIT Component.
2024-08-13T18:15:16.010Z,1723572916.010 [CBIT](DEBUG): Uninitialize CBIT Component.
2024-08-13T18:15:16.010Z,1723572916.010 [CBIT](DEBUG): Powering off loads.
2024-08-13T18:15:16.024Z,1723572916.024 [CBIT](DEBUG): Disabling WDT.
2024-08-13T18:15:16.036Z,1723572916.036 [CBIT](DEBUG): Opening all GF detection circuits.
2024-08-13T18:15:16.037Z,1723572916.037 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:15:16.111Z,1723572916.111 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:15:16.114Z,1723572916.114 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:15:16.120Z,1723572916.120 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:15:16.126Z,1723572916.126 [DATMMP ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:15:16.236Z,1723572916.236 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:15:16.290Z,1723572916.290 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:15:16.338Z,1723572916.338 [logger ThreadHandler](INFO): Thread cancelled.