2021-04-13T17:16:31.438Z,1618334191.438 [CommandLine](IMPORTANT): got command restart logs
2021-04-13T17:19:45.890Z,1618334385.890 [DVL_micro](ERROR): only read 3 of 4 data items
2021-04-13T17:19:45.890Z,1618334385.890 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+9999.99,
2021-04-13T17:19:53.971Z,1618334393.971 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-13T17:19:53.971Z,1618334393.971 [Default:CheckIn:C.Wait] Stopped
2021-04-13T17:19:53.971Z,1618334393.971 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-13T17:19:53.971Z,1618334393.971 [Default:CheckIn:D] Running Loop=1
2021-04-13T17:19:54.365Z,1618334394.365 [Default:CheckIn:D] Stopped
2021-04-13T17:19:54.366Z,1618334394.366 [Default:CheckIn:E] Running Loop=1
2021-04-13T17:19:54.769Z,1618334394.769 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 22.870097 min 
2021-04-13T17:19:54.769Z,1618334394.769 [Default:CheckIn:E] Stopped
2021-04-13T17:19:54.769Z,1618334394.769 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-13T17:19:54.769Z,1618334394.769 [Default:CheckIn] Stopped
2021-04-13T17:19:54.770Z,1618334394.770 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-13T17:19:54.770Z,1618334394.770 [Default:CheckIn](INFO): Running loop #5
2021-04-13T17:19:54.770Z,1618334394.770 [Default:CheckIn] Running Loop=5
2021-04-13T17:19:54.770Z,1618334394.770 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-13T17:19:54.770Z,1618334394.770 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-13T17:19:56.782Z,1618334396.782 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171956.00,A,3648.34754,N,12147.88631,W,14.559,93.77,130421,,,A*7F
2021-04-13T17:19:56.785Z,1618334396.785 [NAL9602](INFO): GPS fix at 20210413T171956: (36.805792, -121.798105)
2021-04-13T17:19:56.796Z,1618334396.796 [Default:CheckIn:Read_GPS] Stopped
2021-04-13T17:19:56.796Z,1618334396.796 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-13T17:20:07.053Z,1618334407.053 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210413T143223/Courier0051.lzma
2021-04-13T17:20:08.056Z,1618334408.056 [DataOverHttps](INFO): Moved sent file to Logs/20210413T143223/Courier0051.lzma.bak
2021-04-13T17:20:08.056Z,1618334408.056 [DataOverHttps](INFO): SBD MOMSN=15565088
2021-04-13T17:20:13.836Z,1618334413.836 [NAL9602](INFO): SBD MO Status=1, MOMSN=1977, MT Status=0, MTMSN=0
2021-04-13T17:20:13.836Z,1618334413.836 [NAL9602](INFO): No messages in MT queue
2021-04-13T17:20:29.346Z,1618334429.346 [DataOverHttps](INFO): Sending 266 bytes from file Logs/20210413T171631/Courier0000.lzma
2021-04-13T17:20:31.351Z,1618334431.351 [DataOverHttps](INFO): Moved sent file to Logs/20210413T171631/Courier0000.lzma.bak
2021-04-13T17:20:31.352Z,1618334431.352 [DataOverHttps](INFO): SBD MOMSN=15565092
2021-04-13T17:20:44.553Z,1618334444.553 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-13T17:20:48.502Z,1618334448.502 [DataOverHttps](INFO): Sending 104 bytes from file Logs/20210413T143223/Express0052.lzma
2021-04-13T17:20:50.507Z,1618334450.507 [DataOverHttps](INFO): Moved sent file to Logs/20210413T143223/Express0052.lzma.bak
2021-04-13T17:20:50.508Z,1618334450.508 [DataOverHttps](INFO): SBD MOMSN=15565096
2021-04-13T17:21:06.910Z,1618334466.910 [DataOverHttps](INFO): Sending 394 bytes from file Logs/20210413T171631/Express0001.lzma
2021-04-13T17:21:07.912Z,1618334467.912 [DataOverHttps](INFO): Moved sent file to Logs/20210413T171631/Express0001.lzma.bak
2021-04-13T17:21:07.912Z,1618334467.912 [DataOverHttps](INFO): SBD MOMSN=15565099
2021-04-13T17:21:25.571Z,1618334485.571 [DataOverHttps](INFO): Sending 51 bytes from file Logs/20210413T171631/Express0002.lzma
2021-04-13T17:21:26.036Z,1618334486.036 [DataOverHttps](INFO): Moved sent file to Logs/20210413T171631/Express0002.lzma.bak
2021-04-13T17:21:26.036Z,1618334486.036 [DataOverHttps](INFO): SBD MOMSN=15565103
2021-04-13T17:21:28.259Z,1618334488.259 [Default:CheckIn:Read_Iridium] Stopped
2021-04-13T17:21:28.259Z,1618334488.259 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-13T17:21:28.259Z,1618334488.259 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-13T17:23:46.025Z,1618334626.025 [DVL_micro](ERROR): Failed to parse:
:SA,+00.82,-03.61,264.4
2021-04-13T17:26:28.866Z,1618334788.866 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-13T17:26:28.866Z,1618334788.866 [Default:CheckIn:C.Wait] Stopped
2021-04-13T17:26:28.866Z,1618334788.866 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-13T17:26:28.866Z,1618334788.866 [Default:CheckIn:D] Running Loop=1
2021-04-13T17:26:29.282Z,1618334789.282 [Default:CheckIn:D] Stopped
2021-04-13T17:26:29.282Z,1618334789.282 [Default:CheckIn:E] Running Loop=1
2021-04-13T17:26:29.679Z,1618334789.679 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 29.451766 min 
2021-04-13T17:26:29.679Z,1618334789.679 [Default:CheckIn:E] Stopped
2021-04-13T17:26:29.679Z,1618334789.679 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-13T17:26:29.679Z,1618334789.679 [Default:CheckIn] Stopped
2021-04-13T17:26:29.679Z,1618334789.679 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-13T17:26:29.680Z,1618334789.680 [Default:CheckIn](INFO): Running loop #6
2021-04-13T17:26:29.680Z,1618334789.680 [Default:CheckIn] Running Loop=6
2021-04-13T17:26:29.680Z,1618334789.680 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-13T17:26:29.680Z,1618334789.680 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-13T17:26:31.681Z,1618334791.681 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172630.00,A,3648.22310,N,12147.15308,W,4.801,199.80,130421,,,D*7C
2021-04-13T17:26:31.692Z,1618334791.692 [NAL9602](INFO): GPS fix at 20210413T172630: (36.803718, -121.785885)
2021-04-13T17:26:31.703Z,1618334791.703 [Default:CheckIn:Read_GPS] Stopped
2021-04-13T17:26:31.703Z,1618334791.703 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-13T17:26:39.501Z,1618334799.501 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210413T171631/Courier0005.lzma
2021-04-13T17:26:40.504Z,1618334800.504 [DataOverHttps](INFO): Moved sent file to Logs/20210413T171631/Courier0005.lzma.bak
2021-04-13T17:26:40.504Z,1618334800.504 [DataOverHttps](INFO): SBD MOMSN=15565148
2021-04-13T17:26:45.018Z,1618334805.018 [NAL9602](INFO): SBD MO Status=1, MOMSN=1978, MT Status=0, MTMSN=0
2021-04-13T17:26:45.018Z,1618334805.018 [NAL9602](INFO): No messages in MT queue
2021-04-13T17:26:57.500Z,1618334817.500 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20210413T171631/Express0007.lzma
2021-04-13T17:26:58.496Z,1618334818.496 [DataOverHttps](INFO): Moved sent file to Logs/20210413T171631/Express0007.lzma.bak
2021-04-13T17:26:58.496Z,1618334818.496 [DataOverHttps](INFO): SBD MOMSN=15565158
2021-04-13T17:27:00.787Z,1618334820.787 [Default:CheckIn:Read_Iridium] Stopped
2021-04-13T17:27:00.787Z,1618334820.787 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-13T17:27:00.787Z,1618334820.787 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-13T17:27:15.725Z,1618334835.725 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-13T17:27:33.486Z,1618334853.486 [DVL_micro](ERROR): Failed to parse:
:000000000,.4,0000.0,1489.0,000
2021-04-13T17:28:53.091Z,1618334933.091 [DVL_micro](ERROR): only read 2 of 4 data items
2021-04-13T17:28:53.091Z,1618334933.091 [DVL_micro](ERROR): Failed to parse:
:BI,-00088,-00217+00000,I
2021-04-13T17:29:18.530Z,1618334958.530 [DVL_micro](ERROR): Failed to parse:
:T00000000,35,0000.0,10
2021-04-13T17:32:01.430Z,1618335121.430 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-13T17:32:01.430Z,1618335121.430 [Default:CheckIn:C.Wait] Stopped
2021-04-13T17:32:01.431Z,1618335121.431 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-13T17:32:01.431Z,1618335121.431 [Default:CheckIn:D] Running Loop=1
2021-04-13T17:32:01.843Z,1618335121.843 [Default:CheckIn:D] Stopped
2021-04-13T17:32:01.843Z,1618335121.843 [Default:CheckIn:E] Running Loop=1
2021-04-13T17:32:02.270Z,1618335122.270 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 34.994722 min 
2021-04-13T17:32:02.270Z,1618335122.270 [Default:CheckIn:E] Stopped
2021-04-13T17:32:02.270Z,1618335122.270 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-13T17:32:02.270Z,1618335122.270 [Default:CheckIn] Stopped
2021-04-13T17:32:02.270Z,1618335122.270 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-13T17:32:02.270Z,1618335122.270 [Default:CheckIn](INFO): Running loop #7
2021-04-13T17:32:02.270Z,1618335122.270 [Default:CheckIn] Running Loop=7
2021-04-13T17:32:02.271Z,1618335122.271 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-13T17:32:02.271Z,1618335122.271 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-13T17:32:04.246Z,1618335124.246 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173203.00,A,3648.13997,N,12147.20706,W,0.019,227.47,130421,,,D*7A
2021-04-13T17:32:04.248Z,1618335124.248 [NAL9602](INFO): GPS fix at 20210413T173203: (36.802333, -121.786784)
2021-04-13T17:32:04.268Z,1618335124.268 [Default:CheckIn:Read_GPS] Stopped
2021-04-13T17:32:04.268Z,1618335124.268 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-13T17:32:15.878Z,1618335135.878 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210413T171631/Courier0009.lzma
2021-04-13T17:32:16.880Z,1618335136.880 [DataOverHttps](INFO): Moved sent file to Logs/20210413T171631/Courier0009.lzma.bak
2021-04-13T17:32:16.880Z,1618335136.880 [DataOverHttps](INFO): SBD MOMSN=15565181
2021-04-13T17:32:20.977Z,1618335140.977 [NAL9602](INFO): SBD MO Status=1, MOMSN=1979, MT Status=0, MTMSN=0
2021-04-13T17:32:20.977Z,1618335140.977 [NAL9602](INFO): No messages in MT queue
2021-04-13T17:32:37.553Z,1618335157.553 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20210413T171631/Express0010.lzma
2021-04-13T17:32:38.555Z,1618335158.555 [DataOverHttps](INFO): Moved sent file to Logs/20210413T171631/Express0010.lzma.bak
2021-04-13T17:32:38.556Z,1618335158.556 [DataOverHttps](INFO): SBD MOMSN=15565184
2021-04-13T17:32:41.252Z,1618335161.252 [Default:CheckIn:Read_Iridium] Stopped
2021-04-13T17:32:41.252Z,1618335161.252 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-13T17:32:41.252Z,1618335161.252 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-13T17:32:51.699Z,1618335171.699 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-13T17:36:30.351Z,1618335390.351 [DataOverHttps](IMPORTANT): SBD MTMSN=20210413T173629
2021-04-13T17:36:37.681Z,1618335397.681 [DataOverHttps](INFO): Received command:Ctd_seabird.loadatstartup 0 bool persist
2021-04-13T17:36:37.746Z,1618335397.746 [CommandLine](IMPORTANT): got command 
2021-04-13T17:36:37.746Z,1618335397.746 [CommandLine](FAULT): Incomplete syntax. Try: help
2021-04-13T17:37:14.143Z,1618335434.143 [DataOverHttps](IMPORTANT): SBD MTMSN=20210413T173713
2021-04-13T17:37:21.605Z,1618335441.605 [DataOverHttps](INFO): Received command:Configset list
2021-04-13T17:37:21.694Z,1618335441.694 [CommandLine](IMPORTANT): got command configSet list
2021-04-13T17:37:21.698Z,1618335441.698 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2021-04-13T17:37:21.705Z,1618335441.705 [CommandLine](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;

2021-04-13T17:37:21.705Z,1618335441.705 [CommandLine](IMPORTANT): BackseatComponent.loadAtStartup=1 bool;

2021-04-13T17:37:21.705Z,1618335441.705 [CommandLine](IMPORTANT): BackseatComponent.simulateHardware=1 bool;

2021-04-13T17:37:21.706Z,1618335441.706 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=214.815570 cubic_centimeter;

2021-04-13T17:37:21.706Z,1618335441.706 [CommandLine](IMPORTANT): VerticalControl.massDefault=-1.247810 millimeter;

2021-04-13T17:37:41.885Z,1618335461.885 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-13T17:37:41.885Z,1618335461.885 [Default:CheckIn:C.Wait] Stopped
2021-04-13T17:37:41.885Z,1618335461.885 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-13T17:37:41.886Z,1618335461.886 [Default:CheckIn:D] Running Loop=1
2021-04-13T17:37:42.289Z,1618335462.289 [Default:CheckIn:D] Stopped
2021-04-13T17:37:42.289Z,1618335462.289 [Default:CheckIn:E] Running Loop=1
2021-04-13T17:37:42.679Z,1618335462.679 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 40.668819 min 
2021-04-13T17:37:42.679Z,1618335462.679 [Default:CheckIn:E] Stopped
2021-04-13T17:37:42.679Z,1618335462.679 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-13T17:37:42.679Z,1618335462.679 [Default:CheckIn] Stopped
2021-04-13T17:37:42.679Z,1618335462.679 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-13T17:37:42.679Z,1618335462.679 [Default:CheckIn](INFO): Running loop #8
2021-04-13T17:37:42.679Z,1618335462.679 [Default:CheckIn] Running Loop=8
2021-04-13T17:37:42.680Z,1618335462.680 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-13T17:37:42.680Z,1618335462.680 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-13T17:37:44.699Z,1618335464.699 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173743.00,A,3648.14020,N,12147.20813,W,0.019,227.47,130421,,,D*72
2021-04-13T17:37:44.701Z,1618335464.701 [NAL9602](INFO): GPS fix at 20210413T173743: (36.802337, -121.786802)
2021-04-13T17:37:44.712Z,1618335464.712 [Default:CheckIn:Read_GPS] Stopped
2021-04-13T17:37:44.712Z,1618335464.712 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-13T17:37:53.253Z,1618335473.253 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210413T171631/Courier0012.lzma
2021-04-13T17:37:54.256Z,1618335474.256 [DataOverHttps](INFO): Moved sent file to Logs/20210413T171631/Courier0012.lzma.bak
2021-04-13T17:37:54.256Z,1618335474.256 [DataOverHttps](INFO): SBD MOMSN=15565194
2021-04-13T17:38:09.365Z,1618335489.365 [NAL9602](INFO): SBD MO Status=2, MOMSN=1980, MT Status=2, MTMSN=0
2021-04-13T17:38:09.366Z,1618335489.366 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-04-13T17:38:13.915Z,1618335493.915 [DataOverHttps](INFO): Sending 466 bytes from file Logs/20210413T171631/Express0013.lzma
2021-04-13T17:38:14.912Z,1618335494.912 [DataOverHttps](INFO): Moved sent file to Logs/20210413T171631/Express0013.lzma.bak
2021-04-13T17:38:14.912Z,1618335494.912 [DataOverHttps](INFO): SBD MOMSN=15565197
2021-04-13T17:38:37.321Z,1618335517.321 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20210413T171631/Express0016.lzma
2021-04-13T17:38:38.324Z,1618335518.324 [DataOverHttps](INFO): Moved sent file to Logs/20210413T171631/Express0016.lzma.bak
2021-04-13T17:38:38.324Z,1618335518.324 [DataOverHttps](INFO): SBD MOMSN=15565211
2021-04-13T17:38:40.724Z,1618335520.724 [Default:CheckIn:Read_Iridium] Stopped
2021-04-13T17:38:40.724Z,1618335520.724 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-13T17:38:40.724Z,1618335520.724 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-13T17:38:51.194Z,1618335531.194 [NAL9602](INFO): SBD MO Status=1, MOMSN=1980, MT Status=0, MTMSN=0
2021-04-13T17:38:51.194Z,1618335531.194 [NAL9602](INFO): No messages in MT queue
2021-04-13T17:39:14.197Z,1618335554.197 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2021-04-13T17:39:14.197Z,1618335554.197 [DVL_micro](ERROR): Failed to parse:
:TS,000000005.0,+11.7,0000.0,1489.0,000
2021-04-13T17:39:21.880Z,1618335561.880 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-13T17:39:31.983Z,1618335571.983 [DataOverHttps](IMPORTANT): SBD MTMSN=20210413T173931
2021-04-13T17:39:39.270Z,1618335579.270 [DataOverHttps](INFO): Received command:Configset ctd_seabird.loadatstartup 0 bool persist; configset list
2021-04-13T17:39:39.476Z,1618335579.476 [CommandLine](IMPORTANT): got command configSet CTD_Seabird.loadAtStartup 0.000000 bool persist
2021-04-13T17:39:39.477Z,1618335579.477 [CommandLine](IMPORTANT): configSet CTD_Seabird.loadAtStartup requires a restart to take effect.
2021-04-13T17:39:39.480Z,1618335579.480 [CommandLine](IMPORTANT): got command configSet list
2021-04-13T17:39:39.480Z,1618335579.480 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2021-04-13T17:39:39.481Z,1618335579.481 [CommandLine](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;

2021-04-13T17:39:39.481Z,1618335579.481 [CommandLine](IMPORTANT): BackseatComponent.loadAtStartup=1 bool;

2021-04-13T17:39:39.482Z,1618335579.482 [CommandLine](IMPORTANT): BackseatComponent.simulateHardware=1 bool;

2021-04-13T17:39:39.482Z,1618335579.482 [CommandLine](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool;

2021-04-13T17:39:39.482Z,1618335579.482 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=214.815570 cubic_centimeter;

2021-04-13T17:39:39.482Z,1618335579.482 [CommandLine](IMPORTANT): VerticalControl.massDefault=-1.247810 millimeter;

2021-04-13T17:43:41.311Z,1618335821.311 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-13T17:43:41.311Z,1618335821.311 [Default:CheckIn:C.Wait] Stopped
2021-04-13T17:43:41.311Z,1618335821.311 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-13T17:43:41.312Z,1618335821.312 [Default:CheckIn:D] Running Loop=1
2021-04-13T17:43:41.727Z,1618335821.727 [Default:CheckIn:D] Stopped
2021-04-13T17:43:41.727Z,1618335821.727 [Default:CheckIn:E] Running Loop=1
2021-04-13T17:43:42.135Z,1618335822.135 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 46.659452 min 
2021-04-13T17:43:42.135Z,1618335822.135 [Default:CheckIn:E] Stopped
2021-04-13T17:43:42.135Z,1618335822.135 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-13T17:43:42.135Z,1618335822.135 [Default:CheckIn] Stopped
2021-04-13T17:43:42.136Z,1618335822.136 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-13T17:43:42.136Z,1618335822.136 [Default:CheckIn](INFO): Running loop #9
2021-04-13T17:43:42.136Z,1618335822.136 [Default:CheckIn] Running Loop=9
2021-04-13T17:43:42.136Z,1618335822.136 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-13T17:43:42.136Z,1618335822.136 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-13T17:43:44.145Z,1618335824.145 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174343.00,A,3648.13990,N,12147.20723,W,0.000,227.47,130421,,,D*70
2021-04-13T17:43:44.160Z,1618335824.160 [NAL9602](INFO): GPS fix at 20210413T174343: (36.802332, -121.786787)
2021-04-13T17:43:44.219Z,1618335824.219 [Default:CheckIn:Read_GPS] Stopped
2021-04-13T17:43:44.219Z,1618335824.219 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-13T17:43:48.962Z,1618335828.962 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20210413T171631/Courier0018.lzma
2021-04-13T17:43:49.224Z,1618335829.224 [DataOverHttps](INFO): Moved sent file to Logs/20210413T171631/Courier0018.lzma.bak
2021-04-13T17:43:49.224Z,1618335829.224 [DataOverHttps](INFO): SBD MOMSN=15565218
2021-04-13T17:44:08.201Z,1618335848.201 [NAL9602](INFO): SBD MO Status=1, MOMSN=1981, MT Status=0, MTMSN=0
2021-04-13T17:44:08.201Z,1618335848.201 [NAL9602](INFO): No messages in MT queue
2021-04-13T17:44:09.470Z,1618335849.470 [DataOverHttps](INFO): Sending 468 bytes from file Logs/20210413T171631/Express0019.lzma
2021-04-13T17:44:10.472Z,1618335850.472 [DataOverHttps](INFO): Moved sent file to Logs/20210413T171631/Express0019.lzma.bak
2021-04-13T17:44:10.472Z,1618335850.472 [DataOverHttps](INFO): SBD MOMSN=15565221
2021-04-13T17:44:37.277Z,1618335877.277 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20210413T171631/Express0022.lzma
2021-04-13T17:44:38.280Z,1618335878.280 [DataOverHttps](INFO): Moved sent file to Logs/20210413T171631/Express0022.lzma.bak
2021-04-13T17:44:38.280Z,1618335878.280 [DataOverHttps](INFO): SBD MOMSN=15565237
2021-04-13T17:44:38.611Z,1618335878.611 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-13T17:44:40.655Z,1618335880.655 [Default:CheckIn:Read_Iridium] Stopped
2021-04-13T17:44:40.655Z,1618335880.655 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-13T17:44:40.655Z,1618335880.655 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-13T17:44:50.001Z,1618335890.001 [CommandLine](IMPORTANT): got command configSet list
2021-04-13T17:44:50.001Z,1618335890.001 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2021-04-13T17:44:50.002Z,1618335890.002 [CommandLine](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;

2021-04-13T17:44:50.002Z,1618335890.002 [CommandLine](IMPORTANT): BackseatComponent.loadAtStartup=1 bool;

2021-04-13T17:44:50.002Z,1618335890.002 [CommandLine](IMPORTANT): BackseatComponent.simulateHardware=1 bool;

2021-04-13T17:44:50.002Z,1618335890.002 [CommandLine](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool;

2021-04-13T17:44:50.003Z,1618335890.003 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=214.815570 cubic_centimeter;

2021-04-13T17:44:50.003Z,1618335890.003 [CommandLine](IMPORTANT): VerticalControl.massDefault=-1.247810 millimeter;

2021-04-13T17:45:13.044Z,1618335913.044 [CommandLine](IMPORTANT): got command ! echo i > /dev/loadC6
2021-04-13T17:45:17.204Z,1618335917.204 [CommandLine](IMPORTANT): got command restart application
2021-04-13T17:45:18.206Z,1618335918.206 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-13T17:45:18.206Z,1618335918.206 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-04-13T17:45:18.366Z,1618335918.366 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2021-04-13T17:45:18.366Z,1618335918.366 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-04-13T17:45:18.367Z,1618335918.367 [CommandLine](INFO): Join timeout helper Thread ID is 8959
2021-04-13T17:45:18.368Z,1618335918.368 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2021-04-13T17:45:18.368Z,1618335918.368 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-04-13T17:45:18.368Z,1618335918.368 [NavChartDb](INFO): Join timeout helper Thread ID is 8960
2021-04-13T17:45:18.414Z,1618335918.414 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-13T17:45:18.414Z,1618335918.414 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-04-13T17:45:18.426Z,1618335918.426 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2021-04-13T17:45:18.426Z,1618335918.426 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-04-13T17:45:18.426Z,1618335918.426 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 8961
2021-04-13T17:45:18.766Z,1618335918.766 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-13T17:45:18.766Z,1618335918.766 [WetLabsBB2FL](INFO): Powering down
2021-04-13T17:45:18.767Z,1618335918.767 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-04-13T17:45:18.790Z,1618335918.790 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2021-04-13T17:45:18.790Z,1618335918.790 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-04-13T17:45:18.790Z,1618335918.790 [CTD_Seabird](INFO): Join timeout helper Thread ID is 8962
2021-04-13T17:45:19.710Z,1618335919.710 [CTD_Seabird](INFO): Powering down
2021-04-13T17:45:19.722Z,1618335919.722 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-13T17:45:19.722Z,1618335919.722 [CTD_Seabird](INFO): Powering down
2021-04-13T17:45:19.734Z,1618335919.734 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-04-13T17:45:19.754Z,1618335919.754 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2021-04-13T17:45:19.754Z,1618335919.754 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-04-13T17:45:19.754Z,1618335919.754 [Radio_Surface](INFO): Join timeout helper Thread ID is 8963
2021-04-13T17:45:19.906Z,1618335919.906 [Radio_Surface](INFO): Powering down
2021-04-13T17:45:19.907Z,1618335919.907 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-13T17:45:19.907Z,1618335919.907 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-04-13T17:45:19.923Z,1618335919.923 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2021-04-13T17:45:19.923Z,1618335919.923 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-04-13T17:45:19.923Z,1618335919.923 [Onboard](INFO): Join timeout helper Thread ID is 8964
2021-04-13T17:45:20.562Z,1618335920.562 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2021-04-13T17:45:24.262Z,1618335924.262 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-13T17:45:24.262Z,1618335924.262 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-04-13T17:45:24.278Z,1618335924.278 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2021-04-13T17:45:24.279Z,1618335924.279 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-04-13T17:45:24.279Z,1618335924.279 [DataOverHttps](INFO): Join timeout helper Thread ID is 8965
2021-04-13T17:45:24.578Z,1618335924.578 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-13T17:45:24.578Z,1618335924.578 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-04-13T17:45:24.594Z,1618335924.594 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2021-04-13T17:45:24.595Z,1618335924.595 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2021-04-13T17:45:24.595Z,1618335924.595 [BackseatComponent](INFO): Join timeout helper Thread ID is 8966
2021-04-13T17:45:24.606Z,1618335924.606 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-13T17:45:24.606Z,1618335924.606 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2021-04-13T17:45:24.614Z,1618335924.614 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2021-04-13T17:45:24.615Z,1618335924.615 [logger ThreadHandler](INFO): Thread cancelled.
2021-04-13T17:45:24.615Z,1618335924.615 [logger](INFO): Join timeout helper Thread ID is 8967
2021-04-13T17:45:24.626Z,1618335924.626 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-13T17:45:24.626Z,1618335924.626 [logger ThreadHandler](INFO): Thread cancelled.
2021-04-13T17:45:24.634Z,1618335924.634 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2021-04-13T17:45:24.635Z,1618335924.635 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-04-13T17:45:24.635Z,1618335924.635 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2021-04-13T17:45:24.635Z,1618335924.635 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-04-13T17:45:24.635Z,1618335924.635 [controlThread](INFO): Join timeout helper Thread ID is 8968
2021-04-13T17:45:24.646Z,1618335924.646 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-13T17:45:24.646Z,1618335924.646 [controlThread](DEBUG): Uninitializing ControlThread
2021-04-13T17:45:24.647Z,1618335924.647 [AHRS_M2](INFO): Powering down
2021-04-13T17:45:24.790Z,1618335924.790 [DVL_micro](INFO): Powering down
2021-04-13T17:45:24.791Z,1618335924.791 [NAL9602](INFO): Powering down
2021-04-13T17:45:24.792Z,1618335924.792 [DAT](INFO): Powering down
2021-04-13T17:45:24.912Z,1618335924.912 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2021-04-13T17:45:24.913Z,1618335924.913 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2021-04-13T17:45:24.913Z,1618335924.913 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2021-04-13T17:45:24.914Z,1618335924.914 [MissionManager](INFO): Uninitializing Mission Default
2021-04-13T17:45:24.914Z,1618335924.914 [Default] Stopped
2021-04-13T17:45:24.914Z,1618335924.914 [Default](DEBUG): Aggregate::uninitialize Default
2021-04-13T17:45:24.914Z,1618335924.914 [Default:B.GoToSurface] Stopped
2021-04-13T17:45:24.915Z,1618335924.915 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-04-13T17:45:24.915Z,1618335924.915 [Default:CheckIn] Stopped
2021-04-13T17:45:24.915Z,1618335924.915 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-13T17:45:24.915Z,1618335924.915 [Default:CheckIn:C.Wait] Stopped
2021-04-13T17:45:24.915Z,1618335924.915 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-13T17:45:24.918Z,1618335924.918 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2021-04-13T17:45:24.918Z,1618335924.918 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2021-04-13T17:45:24.918Z,1618335924.918 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2021-04-13T17:45:24.919Z,1618335924.919 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2021-04-13T17:45:24.919Z,1618335924.919 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2021-04-13T17:45:24.919Z,1618335924.919 [BuoyancyServo](INFO): Powering down
2021-04-13T17:45:24.934Z,1618335924.934 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2021-04-13T17:45:24.934Z,1618335924.934 [ElevatorServo](INFO): Powering down
2021-04-13T17:45:24.935Z,1618335924.935 [MassServo](DEBUG): Uninitialize Mass Servo.
2021-04-13T17:45:24.935Z,1618335924.935 [MassServo](INFO): Powering down
2021-04-13T17:45:24.936Z,1618335924.936 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-04-13T17:45:24.936Z,1618335924.936 [RudderServo](INFO): Powering down
2021-04-13T17:45:24.937Z,1618335924.937 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2021-04-13T17:45:24.937Z,1618335924.937 [ThrusterServo](INFO): Powering down
2021-04-13T17:45:24.938Z,1618335924.938 [SBIT](DEBUG): Uninitialize SBIT Component.
2021-04-13T17:45:24.938Z,1618335924.938 [IBIT](DEBUG): Uninitialize IBIT Component.
2021-04-13T17:45:24.939Z,1618335924.939 [CBIT](DEBUG): Uninitialize CBIT Component.
2021-04-13T17:45:24.939Z,1618335924.939 [CBIT](DEBUG): Powering off loads.
2021-04-13T17:45:24.950Z,1618335924.950 [CBIT](DEBUG): Disabling WDT.
2021-04-13T17:45:24.962Z,1618335924.962 [CBIT](DEBUG): Opening all GF detection circuits.
2021-04-13T17:45:24.963Z,1618335924.963 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-04-13T17:45:25.015Z,1618335925.015 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-04-13T17:45:25.017Z,1618335925.017 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-04-13T17:45:25.025Z,1618335925.025 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-04-13T17:45:25.070Z,1618335925.070 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2021-04-13T17:45:25.132Z,1618335925.132 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-04-13T17:45:25.211Z,1618335925.211 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-04-13T17:45:25.234Z,1618335925.234 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-04-13T17:45:25.318Z,1618335925.318 [logger ThreadHandler](INFO): Thread cancelled.