2022-05-05T16:46:13.369Z,1651769173.369 [CommandExec](IMPORTANT): got command restart logs
2022-05-05T16:46:32.091Z,1651769192.091 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 34.318 degree
2022-05-05T16:46:32.095Z,1651769192.095 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.latitude_fix" handled in the control thread.
2022-05-05T16:46:32.095Z,1651769192.095 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -119.774 degree
2022-05-05T16:46:32.099Z,1651769192.099 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.longitude_fix" handled in the control thread.
2022-05-05T16:46:32.240Z,1651769192.240 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,Depth_Keller,DVL_micro,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,Maintain_NAL9602.latitude_fix,Maintain_NAL9602.longitude_fix,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,ShortestDistanceToPolygonSidesCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2022-05-05T16:46:36.217Z,1651769196.217 [ESPComponent](IMPORTANT): [sample #1] ESP sampling sequence completed normally.
2022-05-05T16:46:36.218Z,1651769196.218 [ESPComponent](DEBUG): Writing samplingActive=1, sampleNumber=1
2022-05-05T16:46:36.622Z,1651769196.622 [ESPComponent](IMPORTANT): [sample #1] ESP log summary report (3 messages):
@08:57:52.09 Selecting Cartridge 36
@08:59:29.06 Sampled  10.0ml
@09:41:23.37 SPRsummary: 3.14159,2.71828,6.022e+23,-42 after 1234s

2022-05-05T16:46:36.623Z,1651769196.623 [ESPComponent](INFO): powering down ESP
2022-05-05T16:46:36.623Z,1651769196.623 [ESPComponent](INFO): powering down ESP secondary power supply
2022-05-05T16:46:44.975Z,1651769204.975 [CommandExec](IMPORTANT): got command set NAL9602.time_fix 1651769196 second
2022-05-05T16:46:45.138Z,1651769205.138 [Default:CheckIn:Read_GPS] Stopped
2022-05-05T16:46:45.138Z,1651769205.138 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-05-05T16:46:45.557Z,1651769205.557 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-05-05T16:46:52.863Z,1651769212.863 [DataOverHttps](INFO): Sending 105 bytes from file Logs/20220505T154843/Courier0004.lzma
2022-05-05T16:46:53.866Z,1651769213.866 [DataOverHttps](INFO): Moved sent file to Logs/20220505T154843/Courier0004.lzma.bak
2022-05-05T16:46:53.866Z,1651769213.866 [DataOverHttps](INFO): SBD MOMSN=16697541
2022-05-05T16:47:09.668Z,1651769229.668 [DataOverHttps](INFO): Sending 365 bytes from file Logs/20220505T164613/Courier0000.lzma
2022-05-05T16:47:10.670Z,1651769230.670 [DataOverHttps](INFO): Moved sent file to Logs/20220505T164613/Courier0000.lzma.bak
2022-05-05T16:47:10.670Z,1651769230.670 [DataOverHttps](INFO): SBD MOMSN=16697544
2022-05-05T16:47:18.251Z,1651769238.251 [NAL9602](INFO): Not Powering down - fast GPS
2022-05-05T16:47:26.327Z,1651769246.327 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220505T164613/Courier0003.lzma
2022-05-05T16:47:27.329Z,1651769247.329 [DataOverHttps](INFO): Moved sent file to Logs/20220505T164613/Courier0003.lzma.bak
2022-05-05T16:47:27.330Z,1651769247.330 [DataOverHttps](INFO): SBD MOMSN=16697548
2022-05-05T16:47:43.098Z,1651769263.098 [DataOverHttps](INFO): Sending 1592 bytes from file Logs/20220505T154843/Express0005.lzma
2022-05-05T16:47:44.098Z,1651769264.098 [DataOverHttps](INFO): Moved sent file to Logs/20220505T154843/Express0005.lzma.bak
2022-05-05T16:47:44.098Z,1651769264.098 [DataOverHttps](INFO): SBD MOMSN=16697550
2022-05-05T16:47:55.832Z,1651769275.832 [DVL_micro](ERROR): only read 0 of 4 data items
2022-05-05T16:47:55.833Z,1651769275.833 [DVL_micro](ERROR): Failed to parse:
:RD,,+9999.99,,+9999.99
2022-05-05T16:47:59.900Z,1651769279.900 [DataOverHttps](INFO): Sending 500 bytes from file Logs/20220505T164613/Express0001.lzma
2022-05-05T16:48:00.902Z,1651769280.902 [DataOverHttps](INFO): Moved sent file to Logs/20220505T164613/Express0001.lzma.bak
2022-05-05T16:48:00.902Z,1651769280.902 [DataOverHttps](INFO): SBD MOMSN=16697606
2022-05-05T16:48:16.591Z,1651769296.591 [DataOverHttps](INFO): Sending 60 bytes from file Logs/20220505T164613/Express0004.lzma
2022-05-05T16:48:18.024Z,1651769298.024 [DataOverHttps](INFO): Moved sent file to Logs/20220505T164613/Express0004.lzma.bak
2022-05-05T16:48:18.024Z,1651769298.024 [DataOverHttps](INFO): SBD MOMSN=16697613
2022-05-05T16:48:18.862Z,1651769298.862 [Default:CheckIn:Read_Iridium] Stopped
2022-05-05T16:48:18.863Z,1651769298.863 [Default:CheckIn:C.Wait] Running Loop=1
2022-05-05T16:48:18.863Z,1651769298.863 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-05-05T16:53:19.444Z,1651769599.444 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-05-05T16:53:19.444Z,1651769599.444 [Default:CheckIn:C.Wait] Stopped
2022-05-05T16:53:19.444Z,1651769599.444 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-05-05T16:53:19.444Z,1651769599.444 [Default:CheckIn:D] Running Loop=1
2022-05-05T16:53:19.842Z,1651769599.842 [Default:CheckIn:D] Stopped
2022-05-05T16:53:19.843Z,1651769599.843 [Default:CheckIn:E] Running Loop=1
2022-05-05T16:53:20.247Z,1651769600.247 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.887735 min 
2022-05-05T16:53:20.247Z,1651769600.247 [Default:CheckIn:E] Stopped
2022-05-05T16:53:20.247Z,1651769600.247 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-05-05T16:53:20.247Z,1651769600.247 [Default:CheckIn] Stopped
2022-05-05T16:53:20.247Z,1651769600.247 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-05-05T16:53:20.248Z,1651769600.248 [Default:CheckIn](INFO): Running loop #2
2022-05-05T16:53:20.248Z,1651769600.248 [Default:CheckIn] Running Loop=2
2022-05-05T16:53:20.248Z,1651769600.248 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-05-05T16:53:20.248Z,1651769600.248 [Default:CheckIn:Read_GPS] Running Loop=1
2022-05-05T16:57:01.246Z,1651769821.246 [DVL_micro](ERROR): Failed to parse:
00000.00,+00000000.00,+00000009.99,000.00
2022-05-05T16:58:20.035Z,1651769900.035 [DVL_micro](ERROR): only read 3 of 4 data items
2022-05-05T16:58:20.035Z,1651769900.035 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+9999.999
2022-05-05T16:58:20.425Z,1651769900.425 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-05-05T16:53:20.2Z
2022-05-05T16:58:20.425Z,1651769900.425 [Default:CheckIn:Read_GPS] Stopped
2022-05-05T16:58:20.425Z,1651769900.425 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-05-05T16:58:24.453Z,1651769904.453 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-05-05T16:58:27.675Z,1651769907.675 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220505T164613/Courier0006.lzma
2022-05-05T16:58:28.678Z,1651769908.678 [DataOverHttps](INFO): Moved sent file to Logs/20220505T164613/Courier0006.lzma.bak
2022-05-05T16:58:28.678Z,1651769908.678 [DataOverHttps](INFO): SBD MOMSN=16697678
2022-05-05T16:58:44.464Z,1651769924.464 [DataOverHttps](INFO): Sending 317 bytes from file Logs/20220505T164613/Express0007.lzma
2022-05-05T16:58:45.466Z,1651769925.466 [DataOverHttps](INFO): Moved sent file to Logs/20220505T164613/Express0007.lzma.bak
2022-05-05T16:58:45.466Z,1651769925.466 [DataOverHttps](INFO): SBD MOMSN=16697680
2022-05-05T16:58:46.692Z,1651769926.692 [Default:CheckIn:Read_Iridium] Stopped
2022-05-05T16:58:46.692Z,1651769926.692 [Default:CheckIn:C.Wait] Running Loop=1
2022-05-05T16:58:46.692Z,1651769926.692 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-05-05T16:58:55.155Z,1651769935.155 [NAL9602](INFO): Not Powering down - fast GPS
2022-05-05T17:03:18.268Z,1651770198.268 [CommandExec](IMPORTANT): got command quit
2022-05-05T17:03:19.272Z,1651770199.272 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-05T17:03:19.272Z,1651770199.272 [CommandExec](INFO): Uninitializing the command executive.
2022-05-05T17:03:19.272Z,1651770199.272 [CommandExec](INFO): Uninitializing the command scheduler.
2022-05-05T17:03:19.272Z,1651770199.272 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-05-05T17:03:19.448Z,1651770199.448 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2022-05-05T17:03:19.449Z,1651770199.449 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2022-05-05T17:03:19.449Z,1651770199.449 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-05-05T17:03:19.450Z,1651770199.450 [NavChartDb](INFO): Join timeout helper Thread ID is 6478
2022-05-05T17:03:19.520Z,1651770199.520 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-05T17:03:19.520Z,1651770199.520 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-05-05T17:03:19.528Z,1651770199.528 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2022-05-05T17:03:19.528Z,1651770199.528 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-05-05T17:03:19.528Z,1651770199.528 [Radio_Surface](INFO): Join timeout helper Thread ID is 6479
2022-05-05T17:03:19.720Z,1651770199.720 [Radio_Surface](INFO): Powering down
2022-05-05T17:03:19.721Z,1651770199.721 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-05T17:03:19.721Z,1651770199.721 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-05-05T17:03:19.728Z,1651770199.728 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2022-05-05T17:03:19.728Z,1651770199.728 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-05-05T17:03:19.728Z,1651770199.728 [Onboard](INFO): Join timeout helper Thread ID is 6480
2022-05-05T17:03:20.456Z,1651770200.456 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2022-05-05T17:03:21.384Z,1651770201.384 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-05T17:03:21.384Z,1651770201.384 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-05-05T17:03:21.388Z,1651770201.388 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2022-05-05T17:03:21.388Z,1651770201.388 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-05-05T17:03:21.388Z,1651770201.388 [DataOverHttps](INFO): Join timeout helper Thread ID is 6481
2022-05-05T17:03:21.460Z,1651770201.460 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-05T17:03:21.460Z,1651770201.460 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-05-05T17:03:21.464Z,1651770201.464 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2022-05-05T17:03:21.464Z,1651770201.464 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-05-05T17:03:21.464Z,1651770201.464 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 6482
2022-05-05T17:03:21.560Z,1651770201.560 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-05T17:03:21.560Z,1651770201.560 [WetLabsBB2FL](INFO): Powering down
2022-05-05T17:03:21.561Z,1651770201.561 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-05-05T17:03:21.573Z,1651770201.573 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2022-05-05T17:03:21.573Z,1651770201.573 [logger ThreadHandler](INFO): Thread cancelled.
2022-05-05T17:03:21.590Z,1651770201.590 [logger](INFO): Join timeout helper Thread ID is 6483
2022-05-05T17:03:21.600Z,1651770201.600 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-05T17:03:21.600Z,1651770201.600 [logger ThreadHandler](INFO): Thread cancelled.
2022-05-05T17:03:21.608Z,1651770201.608 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2022-05-05T17:03:21.609Z,1651770201.609 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-05-05T17:03:21.609Z,1651770201.609 [CommandLine](INFO): Join timeout helper Thread ID is 6484
2022-05-05T17:03:21.624Z,1651770201.624 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-05T17:03:21.625Z,1651770201.625 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-05-05T17:03:21.629Z,1651770201.629 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2022-05-05T17:03:21.629Z,1651770201.629 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-05-05T17:03:21.629Z,1651770201.629 [CommandExec](INFO): Join timeout helper Thread ID is 6485
2022-05-05T17:03:21.630Z,1651770201.630 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2022-05-05T17:03:21.630Z,1651770201.630 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-05-05T17:03:21.631Z,1651770201.631 [controlThread](INFO): Join timeout helper Thread ID is 6486
2022-05-05T17:03:21.800Z,1651770201.800 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-05T17:03:21.800Z,1651770201.800 [controlThread](DEBUG): Uninitializing ControlThread
2022-05-05T17:03:21.802Z,1651770201.802 [AHRS_M2](INFO): Powering down
2022-05-05T17:03:21.944Z,1651770201.944 [DVL_micro](INFO): Powering down
2022-05-05T17:03:21.945Z,1651770201.945 [NAL9602](INFO): Powering down
2022-05-05T17:03:21.947Z,1651770201.947 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2022-05-05T17:03:21.947Z,1651770201.947 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2022-05-05T17:03:21.948Z,1651770201.948 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2022-05-05T17:03:21.949Z,1651770201.949 [MissionManager](INFO): Uninitializing Mission Default
2022-05-05T17:03:21.949Z,1651770201.949 [Default] Stopped
2022-05-05T17:03:21.949Z,1651770201.949 [Default](DEBUG): Aggregate::uninitialize Default
2022-05-05T17:03:21.949Z,1651770201.949 [Default:B.GoToSurface] Stopped
2022-05-05T17:03:21.949Z,1651770201.949 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-05-05T17:03:21.949Z,1651770201.949 [Default:CheckIn] Stopped
2022-05-05T17:03:21.949Z,1651770201.949 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-05-05T17:03:21.950Z,1651770201.950 [Default:CheckIn:C.Wait] Stopped
2022-05-05T17:03:21.950Z,1651770201.950 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-05-05T17:03:21.952Z,1651770201.952 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2022-05-05T17:03:21.953Z,1651770201.953 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2022-05-05T17:03:21.953Z,1651770201.953 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2022-05-05T17:03:21.953Z,1651770201.953 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2022-05-05T17:03:21.953Z,1651770201.953 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2022-05-05T17:03:21.954Z,1651770201.954 [BuoyancyServo](INFO): Powering down
2022-05-05T17:03:21.968Z,1651770201.968 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2022-05-05T17:03:21.968Z,1651770201.968 [ElevatorServo](INFO): Powering down
2022-05-05T17:03:21.969Z,1651770201.969 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2022-05-05T17:03:21.969Z,1651770201.969 [ThrusterHE](INFO): Powering down
2022-05-05T17:03:21.970Z,1651770201.970 [SBIT](DEBUG): Uninitialize SBIT Component.
2022-05-05T17:03:21.970Z,1651770201.970 [IBIT](DEBUG): Uninitialize IBIT Component.
2022-05-05T17:03:21.971Z,1651770201.971 [CBIT](DEBUG): Uninitialize CBIT Component.
2022-05-05T17:03:21.971Z,1651770201.971 [CBIT](DEBUG): Powering off loads.
2022-05-05T17:03:21.984Z,1651770201.984 [CBIT](DEBUG): Disabling WDT.
2022-05-05T17:03:21.996Z,1651770201.996 [CBIT](DEBUG): Opening all GF detection circuits.
2022-05-05T17:03:21.996Z,1651770201.996 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-05-05T17:03:22.036Z,1651770202.036 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-05-05T17:03:22.160Z,1651770202.160 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-05-05T17:03:22.162Z,1651770202.162 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-05-05T17:03:22.169Z,1651770202.169 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-05-05T17:03:22.224Z,1651770202.224 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-05-05T17:03:22.273Z,1651770202.273 [logger ThreadHandler](INFO): Thread cancelled.