2020-01-25T22:07:33.870Z,1579990053.870 [CommandLine](IMPORTANT): got command restart logs
2020-01-25T22:08:59.679Z,1579990139.679 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-25T22:08:59.679Z,1579990139.679 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.23, 307.53, 295.55
2020-01-25T22:11:16.258Z,1579990276.258 [CommandLine](IMPORTANT): got command show variable kiHeading
2020-01-25T22:11:16.272Z,1579990276.272 [CommandLine](IMPORTANT): HorizontalControl.kiHeading (reciprocal_second)
2020-01-25T22:11:19.862Z,1579990279.862 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-25T22:11:19.862Z,1579990279.862 [RDI_Pathfinder](ERROR): Failed to parse:
:TS    +2870.74,      -61.67,  30.29,  0.00
2020-01-25T22:11:42.504Z,1579990302.504 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-25T22:11:42.505Z,1579990302.505 [Default:CheckIn:C.Wait] Stopped
2020-01-25T22:11:42.505Z,1579990302.505 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-25T22:11:42.505Z,1579990302.505 [Default:CheckIn:D] Running Loop=1
2020-01-25T22:11:42.924Z,1579990302.924 [Default:CheckIn:D] Stopped
2020-01-25T22:11:42.924Z,1579990302.924 [Default:CheckIn:E] Running Loop=1
2020-01-25T22:11:43.306Z,1579990303.306 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 13.529633 min 
2020-01-25T22:11:43.307Z,1579990303.307 [Default:CheckIn:E] Stopped
2020-01-25T22:11:43.307Z,1579990303.307 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-25T22:11:43.307Z,1579990303.307 [Default:CheckIn] Stopped
2020-01-25T22:11:43.307Z,1579990303.307 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-25T22:11:43.307Z,1579990303.307 [Default:CheckIn](INFO): Running loop #3
2020-01-25T22:11:43.307Z,1579990303.307 [Default:CheckIn] Running Loop=3
2020-01-25T22:11:43.307Z,1579990303.307 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-25T22:11:43.307Z,1579990303.307 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-25T22:11:45.305Z,1579990305.305 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221136.00,A,3648.55452,N,12148.61895,W,1.127,198.51,250120,,,A*7B
2020-01-25T22:11:45.307Z,1579990305.307 [NAL9602](INFO): GPS fix at 20200125T221136: (36.809242, -121.810316)
2020-01-25T22:11:45.341Z,1579990305.341 [Default:CheckIn:Read_GPS] Stopped
2020-01-25T22:11:45.341Z,1579990305.341 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-25T22:11:52.737Z,1579990312.737 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200125T204958/Courier0028.lzma
2020-01-25T22:11:54.744Z,1579990314.744 [DataOverHttps](INFO): Moved sent file to Logs/20200125T204958/Courier0028.lzma.bak
2020-01-25T22:11:54.744Z,1579990314.744 [DataOverHttps](INFO): SBD MOMSN=12204062
2020-01-25T22:11:57.047Z,1579990317.047 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-25T22:11:57.047Z,1579990317.047 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012515245373,35.0, -0.1,   0.0,10
2020-01-25T22:12:12.454Z,1579990332.454 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20200125T220733/Courier0000.lzma
2020-01-25T22:12:12.769Z,1579990332.769 [NAL9602](INFO): SBD MO Status=2, MOMSN=10278, MT Status=2, MTMSN=0
2020-01-25T22:12:12.769Z,1579990332.769 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-25T22:12:14.459Z,1579990334.459 [DataOverHttps](INFO): Moved sent file to Logs/20200125T220733/Courier0000.lzma.bak
2020-01-25T22:12:14.459Z,1579990334.459 [DataOverHttps](INFO): SBD MOMSN=12204064
2020-01-25T22:12:18.044Z,1579990338.044 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-25T22:12:18.044Z,1579990338.044 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,200125155.0, -0.1,   0.0,1448.9,  0
2020-01-25T22:12:30.142Z,1579990350.142 [NAL9602](INFO): SBD MO Status=2, MOMSN=10278, MT Status=2, MTMSN=0
2020-01-25T22:12:30.142Z,1579990350.142 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-25T22:12:32.469Z,1579990352.469 [DataOverHttps](INFO): Sending 97 bytes from file Logs/20200125T204958/Express0029.lzma
2020-01-25T22:12:34.475Z,1579990354.475 [DataOverHttps](INFO): Moved sent file to Logs/20200125T204958/Express0029.lzma.bak
2020-01-25T22:12:34.476Z,1579990354.476 [DataOverHttps](INFO): SBD MOMSN=12204068
2020-01-25T22:12:51.994Z,1579990371.994 [DataOverHttps](INFO): Sending 389 bytes from file Logs/20200125T220733/Express0001.lzma
2020-01-25T22:12:53.999Z,1579990373.999 [DataOverHttps](INFO): Moved sent file to Logs/20200125T220733/Express0001.lzma.bak
2020-01-25T22:12:53.999Z,1579990373.999 [DataOverHttps](INFO): SBD MOMSN=12204071
2020-01-25T22:13:11.697Z,1579990391.697 [DataOverHttps](INFO): Sending 53 bytes from file Logs/20200125T220733/Express0002.lzma
2020-01-25T22:13:13.704Z,1579990393.704 [DataOverHttps](INFO): Moved sent file to Logs/20200125T220733/Express0002.lzma.bak
2020-01-25T22:13:13.704Z,1579990393.704 [DataOverHttps](INFO): SBD MOMSN=12204077
2020-01-25T22:13:15.024Z,1579990395.024 [Default:CheckIn:Read_Iridium] Stopped
2020-01-25T22:13:15.024Z,1579990395.024 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-25T22:13:15.024Z,1579990395.024 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-25T22:14:32.549Z,1579990472.549 [NAL9602](INFO): SBD MO Status=2, MOMSN=10278, MT Status=2, MTMSN=0
2020-01-25T22:14:32.549Z,1579990472.549 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-25T22:14:43.869Z,1579990483.869 [NAL9602](INFO): SBD MO Status=0, MOMSN=10278, MT Status=0, MTMSN=0
2020-01-25T22:14:43.869Z,1579990483.869 [NAL9602](INFO): No messages in MT queue
2020-01-25T22:15:14.568Z,1579990514.568 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-25T22:18:15.672Z,1579990695.672 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-25T22:18:15.672Z,1579990695.672 [Default:CheckIn:C.Wait] Stopped
2020-01-25T22:18:15.672Z,1579990695.672 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-25T22:18:15.673Z,1579990695.673 [Default:CheckIn:D] Running Loop=1
2020-01-25T22:18:16.025Z,1579990696.025 [Default:CheckIn:D] Stopped
2020-01-25T22:18:16.025Z,1579990696.025 [Default:CheckIn:E] Running Loop=1
2020-01-25T22:18:16.418Z,1579990696.418 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 20.081315 min 
2020-01-25T22:18:16.419Z,1579990696.419 [Default:CheckIn:E] Stopped
2020-01-25T22:18:16.419Z,1579990696.419 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-25T22:18:16.419Z,1579990696.419 [Default:CheckIn] Stopped
2020-01-25T22:18:16.419Z,1579990696.419 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-25T22:18:16.419Z,1579990696.419 [Default:CheckIn](INFO): Running loop #4
2020-01-25T22:18:16.419Z,1579990696.419 [Default:CheckIn] Running Loop=4
2020-01-25T22:18:16.419Z,1579990696.419 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-25T22:18:16.419Z,1579990696.419 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-25T22:18:18.411Z,1579990698.411 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221809.00,A,3648.50536,N,12148.58583,W,1.166,168.76,250120,,,A*77
2020-01-25T22:18:18.413Z,1579990698.413 [NAL9602](INFO): GPS fix at 20200125T221809: (36.808423, -121.809764)
2020-01-25T22:18:18.438Z,1579990698.438 [Default:CheckIn:Read_GPS] Stopped
2020-01-25T22:18:18.438Z,1579990698.438 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-25T22:18:27.797Z,1579990707.797 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200125T220733/Courier0005.lzma
2020-01-25T22:18:29.803Z,1579990709.803 [DataOverHttps](INFO): Moved sent file to Logs/20200125T220733/Courier0005.lzma.bak
2020-01-25T22:18:29.803Z,1579990709.803 [DataOverHttps](INFO): SBD MOMSN=12204081
2020-01-25T22:18:40.238Z,1579990720.238 [NAL9602](INFO): SBD MO Status=0, MOMSN=10279, MT Status=0, MTMSN=0
2020-01-25T22:18:40.238Z,1579990720.238 [NAL9602](INFO): No messages in MT queue
2020-01-25T22:18:42.251Z,1579990722.251 [DataOverHttps](IMPORTANT): SBD MTMSN=20200125T221832
2020-01-25T22:18:49.893Z,1579990729.893 [DataOverHttps](INFO): Sending 122 bytes from file Logs/20200125T220733/Express0006.lzma
2020-01-25T22:18:49.896Z,1579990729.896 [DataOverHttps](INFO): Received command:restart app
2020-01-25T22:18:49.930Z,1579990729.930 [CommandLine](IMPORTANT): got command restart application
2020-01-25T22:18:50.934Z,1579990730.934 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2020-01-25T22:18:50.934Z,1579990730.934 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-25T22:18:50.934Z,1579990730.934 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-25T22:18:51.066Z,1579990731.066 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-01-25T22:18:51.066Z,1579990731.066 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-25T22:18:51.067Z,1579990731.067 [CommandLine](INFO): Join timeout helper Thread ID is 3244
2020-01-25T22:18:51.072Z,1579990731.072 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-01-25T22:18:51.072Z,1579990731.072 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-25T22:18:51.072Z,1579990731.072 [NavChartDb](INFO): Join timeout helper Thread ID is 3245
2020-01-25T22:18:51.242Z,1579990731.242 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-25T22:18:51.242Z,1579990731.242 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-25T22:18:51.254Z,1579990731.254 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2020-01-25T22:18:51.254Z,1579990731.254 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-25T22:18:51.254Z,1579990731.254 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 3246
2020-01-25T22:18:51.350Z,1579990731.350 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-25T22:18:51.350Z,1579990731.350 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-01-25T22:18:51.351Z,1579990731.351 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-25T22:18:51.358Z,1579990731.358 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-01-25T22:18:51.358Z,1579990731.358 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-25T22:18:51.358Z,1579990731.358 [Radio_Surface](INFO): Join timeout helper Thread ID is 3247
2020-01-25T22:18:51.686Z,1579990731.686 [Radio_Surface](INFO): Powering down
2020-01-25T22:18:51.687Z,1579990731.687 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-25T22:18:51.687Z,1579990731.687 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-25T22:18:51.690Z,1579990731.690 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-01-25T22:18:51.690Z,1579990731.690 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-25T22:18:51.690Z,1579990731.690 [DataOverHttps](INFO): Join timeout helper Thread ID is 3248
2020-01-25T22:18:51.898Z,1579990731.898 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-25T22:18:51.901Z,1579990731.901 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-25T22:18:51.911Z,1579990731.911 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-01-25T22:18:51.911Z,1579990731.911 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-25T22:18:51.911Z,1579990731.911 [logger](INFO): Join timeout helper Thread ID is 3249
2020-01-25T22:18:51.930Z,1579990731.930 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-25T22:18:51.930Z,1579990731.930 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-25T22:18:51.931Z,1579990731.931 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-01-25T22:18:51.932Z,1579990731.932 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-25T22:18:51.932Z,1579990731.932 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-01-25T22:18:51.932Z,1579990731.932 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-25T22:18:51.932Z,1579990731.932 [controlThread](INFO): Join timeout helper Thread ID is 3250
2020-01-25T22:18:51.932Z,1579990731.932 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-25T22:18:51.933Z,1579990731.933 [controlThread](DEBUG): Uninitializing ControlThread
2020-01-25T22:18:51.933Z,1579990731.933 [AHRS_M2](INFO): Powering down
2020-01-25T22:18:52.006Z,1579990732.006 [DDM](INFO): Powering down
2020-01-25T22:18:52.098Z,1579990732.098 [DUSBL_Hydroid](INFO): Powering down
2020-01-25T22:18:52.170Z,1579990732.170 [Micromodem](INFO): Powering down
2020-01-25T22:18:52.266Z,1579990732.266 [NAL9602](INFO): Powering down
2020-01-25T22:18:52.338Z,1579990732.338 [RDI_Pathfinder](INFO): Powering down
2020-01-25T22:18:52.339Z,1579990732.339 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-01-25T22:18:52.340Z,1579990732.340 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-01-25T22:18:52.341Z,1579990732.341 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-01-25T22:18:52.341Z,1579990732.341 [MissionManager](INFO): Uninitializing Mission Default
2020-01-25T22:18:52.341Z,1579990732.341 [Default] Stopped
2020-01-25T22:18:52.341Z,1579990732.341 [Default](DEBUG): Aggregate::uninitialize Default
2020-01-25T22:18:52.341Z,1579990732.341 [Default:B.GoToSurface] Stopped
2020-01-25T22:18:52.342Z,1579990732.342 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-25T22:18:52.342Z,1579990732.342 [Default:CheckIn] Stopped
2020-01-25T22:18:52.342Z,1579990732.342 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-25T22:18:52.342Z,1579990732.342 [Default:CheckIn:Read_Iridium] Stopped
2020-01-25T22:18:52.344Z,1579990732.344 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-01-25T22:18:52.344Z,1579990732.344 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-01-25T22:18:52.345Z,1579990732.345 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-01-25T22:18:52.345Z,1579990732.345 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-01-25T22:18:52.345Z,1579990732.345 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-01-25T22:18:52.345Z,1579990732.345 [BuoyancyServo](INFO): Powering down
2020-01-25T22:18:52.358Z,1579990732.358 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-01-25T22:18:52.358Z,1579990732.358 [ElevatorServo](INFO): Powering down
2020-01-25T22:18:52.359Z,1579990732.359 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-01-25T22:18:52.359Z,1579990732.359 [MassServo](INFO): Powering down
2020-01-25T22:18:52.360Z,1579990732.360 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-01-25T22:18:52.360Z,1579990732.360 [RudderServo](INFO): Powering down
2020-01-25T22:18:52.360Z,1579990732.360 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-01-25T22:18:52.361Z,1579990732.361 [ThrusterServo](INFO): Powering down
2020-01-25T22:18:52.361Z,1579990732.361 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-01-25T22:18:52.362Z,1579990732.362 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-01-25T22:18:52.362Z,1579990732.362 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-01-25T22:18:52.362Z,1579990732.362 [CBIT](DEBUG): Powering off loads.
2020-01-25T22:18:52.374Z,1579990732.374 [CBIT](DEBUG): Disabling WDT.
2020-01-25T22:18:52.386Z,1579990732.386 [CBIT](DEBUG): Opening all GF detection circuits.
2020-01-25T22:18:52.387Z,1579990732.387 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-25T22:18:52.433Z,1579990732.433 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-25T22:18:52.444Z,1579990732.444 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-25T22:18:52.486Z,1579990732.486 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-25T22:18:52.545Z,1579990732.545 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-25T22:18:52.617Z,1579990732.617 [logger ThreadHandler](INFO): Thread cancelled.