2020-01-17T18:53:16.020Z,1579287196.020 [CommandLine](IMPORTANT): got command restart logs
2020-01-17T18:53:23.037Z,1579287203.037 [Default:A.Wait](INFO): Done Waiting.
2020-01-17T18:53:23.037Z,1579287203.037 [Default:A.Wait] Stopped
2020-01-17T18:53:23.037Z,1579287203.037 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-01-17T18:53:23.458Z,1579287203.458 [Default:CheckIn] Running Loop=1
2020-01-17T18:53:23.458Z,1579287203.458 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-17T18:53:23.458Z,1579287203.458 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-17T18:53:25.429Z,1579287205.429 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185228.00,A,3648.12048,N,12147.21172,W,0.097,247.76,170120,,,D*76
2020-01-17T18:53:25.431Z,1579287205.431 [NAL9602](INFO): GPS fix at 20200117T185228: (36.802008, -121.786862)
2020-01-17T18:53:25.503Z,1579287205.503 [Default:CheckIn:Read_GPS] Stopped
2020-01-17T18:53:25.503Z,1579287205.503 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-17T18:53:33.652Z,1579287213.652 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20200117T185316/Courier0000.lzma
2020-01-17T18:53:35.663Z,1579287215.663 [DataOverHttps](INFO): Moved sent file to Logs/20200117T185316/Courier0000.lzma.bak
2020-01-17T18:53:35.663Z,1579287215.663 [DataOverHttps](INFO): SBD MOMSN=12196888
2020-01-17T18:53:52.116Z,1579287232.116 [NAL9602](INFO): SBD MO Status=2, MOMSN=10166, MT Status=2, MTMSN=0
2020-01-17T18:53:52.125Z,1579287232.125 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-17T18:53:56.813Z,1579287236.813 [DataOverHttps](INFO): Sending 307 bytes from file Logs/20200117T174557/Express0032.lzma
2020-01-17T18:53:58.819Z,1579287238.819 [DataOverHttps](INFO): Moved sent file to Logs/20200117T174557/Express0032.lzma.bak
2020-01-17T18:53:58.819Z,1579287238.819 [DataOverHttps](INFO): SBD MOMSN=12196892
2020-01-17T18:54:16.677Z,1579287256.677 [DataOverHttps](INFO): Sending 255 bytes from file Logs/20200117T185316/Express0001.lzma
2020-01-17T18:54:18.682Z,1579287258.682 [DataOverHttps](INFO): Moved sent file to Logs/20200117T185316/Express0001.lzma.bak
2020-01-17T18:54:18.682Z,1579287258.682 [DataOverHttps](INFO): SBD MOMSN=12196904
2020-01-17T18:54:20.035Z,1579287260.035 [Default:CheckIn:Read_Iridium] Stopped
2020-01-17T18:54:20.035Z,1579287260.035 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-17T18:54:20.035Z,1579287260.035 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-17T18:54:42.617Z,1579287282.617 [NAL9602](INFO): SBD MO Status=2, MOMSN=10166, MT Status=2, MTMSN=0
2020-01-17T18:54:42.618Z,1579287282.618 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-17T18:54:55.952Z,1579287295.952 [NAL9602](INFO): SBD MO Status=0, MOMSN=10166, MT Status=0, MTMSN=0
2020-01-17T18:54:55.952Z,1579287295.952 [NAL9602](INFO): No messages in MT queue
2020-01-17T18:55:26.655Z,1579287326.655 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-17T18:56:38.616Z,1579287398.616 [RDI_Pathfinder](ERROR): Failed to parse:
:SA,   +0.00,   +0.00,  0.00
2020-01-17T18:59:20.620Z,1579287560.620 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-17T18:59:20.620Z,1579287560.620 [Default:CheckIn:C.Wait] Stopped
2020-01-17T18:59:20.620Z,1579287560.620 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-17T18:59:20.620Z,1579287560.620 [Default:CheckIn:D] Running Loop=1
2020-01-17T18:59:21.021Z,1579287561.021 [Default:CheckIn:D] Stopped
2020-01-17T18:59:21.021Z,1579287561.021 [Default:CheckIn:E] Running Loop=1
2020-01-17T18:59:21.461Z,1579287561.461 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.188323 min 
2020-01-17T18:59:21.461Z,1579287561.461 [Default:CheckIn:E] Stopped
2020-01-17T18:59:21.461Z,1579287561.461 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-17T18:59:21.461Z,1579287561.461 [Default:CheckIn] Stopped
2020-01-17T18:59:21.462Z,1579287561.462 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-17T18:59:21.462Z,1579287561.462 [Default:CheckIn](INFO): Running loop #2
2020-01-17T18:59:21.462Z,1579287561.462 [Default:CheckIn] Running Loop=2
2020-01-17T18:59:21.462Z,1579287561.462 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-17T18:59:21.462Z,1579287561.462 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-17T18:59:23.395Z,1579287563.395 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185826.00,A,3648.12305,N,12147.22382,W,0.078,231.35,170120,,,D*71
2020-01-17T18:59:23.409Z,1579287563.409 [NAL9602](INFO): GPS fix at 20200117T185826: (36.802051, -121.787064)
2020-01-17T18:59:23.441Z,1579287563.441 [Default:CheckIn:Read_GPS] Stopped
2020-01-17T18:59:23.441Z,1579287563.441 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-17T18:59:34.812Z,1579287574.812 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20200117T185316/Courier0003.lzma
2020-01-17T18:59:36.818Z,1579287576.818 [DataOverHttps](INFO): Moved sent file to Logs/20200117T185316/Courier0003.lzma.bak
2020-01-17T18:59:36.818Z,1579287576.818 [DataOverHttps](INFO): SBD MOMSN=12196909
2020-01-17T18:59:38.374Z,1579287578.374 [NAL9602](INFO): SBD MO Status=0, MOMSN=10167, MT Status=0, MTMSN=0
2020-01-17T18:59:38.375Z,1579287578.375 [NAL9602](INFO): No messages in MT queue
2020-01-17T18:59:57.873Z,1579287597.873 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20200117T185316/Express0004.lzma
2020-01-17T18:59:59.875Z,1579287599.875 [DataOverHttps](INFO): Moved sent file to Logs/20200117T185316/Express0004.lzma.bak
2020-01-17T18:59:59.875Z,1579287599.875 [DataOverHttps](INFO): SBD MOMSN=12196912
2020-01-17T19:00:01.471Z,1579287601.471 [Default:CheckIn:Read_Iridium] Stopped
2020-01-17T19:00:01.471Z,1579287601.471 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-17T19:00:01.471Z,1579287601.471 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-17T19:00:09.066Z,1579287609.066 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-17T19:00:40.719Z,1579287640.719 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-01-17T19:00:55.122Z,1579287655.122 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-17T19:00:55.122Z,1579287655.122 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-17T19:00:57.155Z,1579287657.155 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-01-17T19:00:57.158Z,1579287657.158 [BPC1](INFO): Received data from all battery sticks.
2020-01-17T19:01:38.751Z,1579287698.751 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-17T19:01:38.751Z,1579287698.751 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-17T19:02:53.932Z,1579287773.932 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-17T19:02:53.932Z,1579287773.932 [RDI_Pathfinder](ERROR): Failed to parse:
:TS2145473,35.0, -0.1,   0.0,1448.9,  0
2020-01-17T19:03:02.822Z,1579287782.822 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-17T19:03:02.822Z,1579287782.822 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20011712150373,35.0, -0.1,   0.0,1448.RA,  0.00,   0.00,   0.00,   0.00,   0.00
2020-01-17T19:03:33.941Z,1579287813.941 [RDI_Pathfinder](ERROR): Failed to parse:
:R,   0.00,   0.00,   0.00,   0.00
2020-01-17T19:03:36.764Z,1579287816.764 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-17T19:03:36.764Z,1579287816.764 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,200117125.0, -0.1,   0.0,1448.9,  0
2020-01-17T19:05:02.009Z,1579287902.009 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-17T19:05:02.009Z,1579287902.009 [Default:CheckIn:C.Wait] Stopped
2020-01-17T19:05:02.010Z,1579287902.010 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-17T19:05:02.010Z,1579287902.010 [Default:CheckIn:D] Running Loop=1
2020-01-17T19:05:02.429Z,1579287902.429 [Default:CheckIn:D] Stopped
2020-01-17T19:05:02.429Z,1579287902.429 [Default:CheckIn:E] Running Loop=1
2020-01-17T19:05:02.829Z,1579287902.829 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.878452 min 
2020-01-17T19:05:02.829Z,1579287902.829 [Default:CheckIn:E] Stopped
2020-01-17T19:05:02.829Z,1579287902.829 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-17T19:05:02.829Z,1579287902.829 [Default:CheckIn] Stopped
2020-01-17T19:05:02.829Z,1579287902.829 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-17T19:05:02.829Z,1579287902.829 [Default:CheckIn](INFO): Running loop #3
2020-01-17T19:05:02.830Z,1579287902.830 [Default:CheckIn] Running Loop=3
2020-01-17T19:05:02.830Z,1579287902.830 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-17T19:05:02.830Z,1579287902.830 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-17T19:05:04.803Z,1579287904.803 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190407.00,A,3648.13790,N,12147.23617,W,1.283,287.30,170120,,,D*74
2020-01-17T19:05:04.805Z,1579287904.805 [NAL9602](INFO): GPS fix at 20200117T190407: (36.802298, -121.787269)
2020-01-17T19:05:04.856Z,1579287904.856 [Default:CheckIn:Read_GPS] Stopped
2020-01-17T19:05:04.856Z,1579287904.856 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-17T19:05:14.514Z,1579287914.514 [DataOverHttps](INFO): Sending 221 bytes from file Logs/20200117T185316/Courier0006.lzma
2020-01-17T19:05:16.518Z,1579287916.518 [DataOverHttps](INFO): Moved sent file to Logs/20200117T185316/Courier0006.lzma.bak
2020-01-17T19:05:16.518Z,1579287916.518 [DataOverHttps](INFO): SBD MOMSN=12196917
2020-01-17T19:05:16.928Z,1579287916.928 [NAL9602](INFO): SBD MO Status=0, MOMSN=10168, MT Status=0, MTMSN=0
2020-01-17T19:05:16.928Z,1579287916.928 [NAL9602](INFO): No messages in MT queue
2020-01-17T19:05:38.387Z,1579287938.387 [RDI_Pathfinder](ERROR): Failed to parse:
00,   0.00,   0.00,   0.00,   0.00
2020-01-17T19:05:40.488Z,1579287940.488 [DataOverHttps](INFO): Sending 118 bytes from file Logs/20200117T185316/Express0007.lzma
2020-01-17T19:05:42.495Z,1579287942.495 [DataOverHttps](INFO): Moved sent file to Logs/20200117T185316/Express0007.lzma.bak
2020-01-17T19:05:42.495Z,1579287942.495 [DataOverHttps](INFO): SBD MOMSN=12196920
2020-01-17T19:05:44.032Z,1579287944.032 [Default:CheckIn:Read_Iridium] Stopped
2020-01-17T19:05:44.033Z,1579287944.033 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-17T19:05:44.033Z,1579287944.033 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-17T19:05:47.631Z,1579287947.631 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-17T19:06:56.811Z,1579288016.811 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2020-01-17T19:07:34.780Z,1579288054.780 [RDI_Pathfinder](ERROR): Failed to parse:
:B  +1.07,       -2.28,       -0.00,   0.00,670.98
2020-01-17T19:08:16.776Z,1579288096.776 [RDI_Pathfinder](ERROR): only read 0 of 4 data items
2020-01-17T19:08:16.777Z,1579288096.777 [RDI_Pathfinder](ERROR): Failed to parse:
:RA   0.00,   0.00,   0.00,   0.00
2020-01-17T19:08:22.833Z,1579288102.833 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-17T19:08:22.833Z,1579288102.833 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-17T19:09:33.934Z,1579288173.934 [RDI_Pathfinder](ERROR): Failed to parse:
00,   0.00,   0.00,   0.00,   0.00
2020-01-17T19:10:44.643Z,1579288244.643 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-17T19:10:44.643Z,1579288244.643 [Default:CheckIn:C.Wait] Stopped
2020-01-17T19:10:44.643Z,1579288244.643 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-17T19:10:44.645Z,1579288244.645 [Default:CheckIn:D] Running Loop=1
2020-01-17T19:10:45.077Z,1579288245.077 [Default:CheckIn:D] Stopped
2020-01-17T19:10:45.077Z,1579288245.077 [Default:CheckIn:E] Running Loop=1
2020-01-17T19:10:45.453Z,1579288245.453 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 17.589203 min 
2020-01-17T19:10:45.453Z,1579288245.453 [Default:CheckIn:E] Stopped
2020-01-17T19:10:45.453Z,1579288245.453 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-17T19:10:45.454Z,1579288245.454 [Default:CheckIn] Stopped
2020-01-17T19:10:45.454Z,1579288245.454 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-17T19:10:45.454Z,1579288245.454 [Default:CheckIn](INFO): Running loop #4
2020-01-17T19:10:45.454Z,1579288245.454 [Default:CheckIn] Running Loop=4
2020-01-17T19:10:45.454Z,1579288245.454 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-17T19:10:45.454Z,1579288245.454 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-17T19:10:47.439Z,1579288247.439 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190950.00,A,3648.16786,N,12147.28063,W,0.505,168.90,170120,,,A*72
2020-01-17T19:10:47.441Z,1579288247.441 [NAL9602](INFO): GPS fix at 20200117T190950: (36.802798, -121.788010)
2020-01-17T19:10:47.494Z,1579288247.494 [Default:CheckIn:Read_GPS] Stopped
2020-01-17T19:10:47.494Z,1579288247.494 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-17T19:10:57.092Z,1579288257.092 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20200117T185316/Courier0009.lzma
2020-01-17T19:10:59.098Z,1579288259.098 [DataOverHttps](INFO): Moved sent file to Logs/20200117T185316/Courier0009.lzma.bak
2020-01-17T19:10:59.098Z,1579288259.098 [DataOverHttps](INFO): SBD MOMSN=12196925
2020-01-17T19:11:18.720Z,1579288278.720 [DataOverHttps](INFO): Sending 118 bytes from file Logs/20200117T185316/Express0010.lzma
2020-01-17T19:11:20.726Z,1579288280.726 [DataOverHttps](INFO): Moved sent file to Logs/20200117T185316/Express0010.lzma.bak
2020-01-17T19:11:20.726Z,1579288280.726 [DataOverHttps](INFO): SBD MOMSN=12196928
2020-01-17T19:11:22.224Z,1579288282.224 [Default:CheckIn:Read_Iridium] Stopped
2020-01-17T19:11:22.224Z,1579288282.224 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-17T19:11:22.225Z,1579288282.225 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-17T19:11:36.397Z,1579288296.397 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2020-01-17T19:15:38.154Z,1579288538.154 [CommandLine](IMPORTANT): got command show variable pwramp
2020-01-17T19:15:38.225Z,1579288538.225 [CommandLine](IMPORTANT): Micromodem.pwrampTXLevel (enum)
2020-01-17T19:15:49.664Z,1579288549.664 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-01-17T19:15:54.162Z,1579288554.162 [CommandLine](IMPORTANT): got command get Micromodem.pwrampTXLevel
2020-01-17T19:15:54.162Z,1579288554.162 [CommandLine](IMPORTANT): Micromodem.pwrampTXLevel 0 enum
2020-01-17T19:16:13.557Z,1579288573.557 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-17T19:16:13.558Z,1579288573.558 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20011712281473,35.0, -0.1 0
2020-01-17T19:16:14.794Z,1579288574.794 [CommandLine](IMPORTANT): got command configSet Micromodem.pwrampTXLevel 1.000000 enum persist
2020-01-17T19:16:18.387Z,1579288578.387 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-17T19:16:18.388Z,1579288578.388 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-3276,-32768,V
2020-01-17T19:16:20.379Z,1579288580.379 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-17T19:16:22.845Z,1579288582.845 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-17T19:16:22.845Z,1579288582.845 [Default:CheckIn:C.Wait] Stopped
2020-01-17T19:16:22.845Z,1579288582.845 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-17T19:16:22.846Z,1579288582.846 [Default:CheckIn:D] Running Loop=1
2020-01-17T19:16:23.225Z,1579288583.225 [Default:CheckIn:D] Stopped
2020-01-17T19:16:23.225Z,1579288583.225 [Default:CheckIn:E] Running Loop=1
2020-01-17T19:16:23.631Z,1579288583.631 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 23.225067 min 
2020-01-17T19:16:23.631Z,1579288583.631 [Default:CheckIn:E] Stopped
2020-01-17T19:16:23.631Z,1579288583.631 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-17T19:16:23.631Z,1579288583.631 [Default:CheckIn] Stopped
2020-01-17T19:16:23.631Z,1579288583.631 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-17T19:16:23.631Z,1579288583.631 [Default:CheckIn](INFO): Running loop #5
2020-01-17T19:16:23.631Z,1579288583.631 [Default:CheckIn] Running Loop=5
2020-01-17T19:16:23.631Z,1579288583.631 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-17T19:16:23.631Z,1579288583.631 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-17T19:16:25.617Z,1579288585.617 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191528.00,A,3648.15688,N,12147.27474,W,0.952,50.82,170120,,,A*46
2020-01-17T19:16:25.619Z,1579288585.619 [NAL9602](INFO): GPS fix at 20200117T191528: (36.802615, -121.787912)
2020-01-17T19:16:25.672Z,1579288585.672 [Default:CheckIn:Read_GPS] Stopped
2020-01-17T19:16:25.672Z,1579288585.672 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-17T19:16:35.612Z,1579288595.612 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20200117T185316/Courier0012.lzma
2020-01-17T19:16:37.082Z,1579288597.082 [CommandLine](IMPORTANT): got command failComponent
2020-01-17T19:16:37.083Z,1579288597.083 [CommandLine](IMPORTANT): Failed components:
2020-01-17T19:16:37.083Z,1579288597.083 [CommandLine](IMPORTANT): No failed Components.
2020-01-17T19:16:37.618Z,1579288597.618 [DataOverHttps](INFO): Moved sent file to Logs/20200117T185316/Courier0012.lzma.bak
2020-01-17T19:16:37.618Z,1579288597.618 [DataOverHttps](INFO): SBD MOMSN=12196931
2020-01-17T19:16:42.836Z,1579288602.836 [CommandLine](IMPORTANT): got command configSet 
2020-01-17T19:16:42.836Z,1579288602.836 [CommandLine](FAULT): Incomplete syntax. Try: help configSet
2020-01-17T19:16:42.848Z,1579288602.848 [CommandLine](IMPORTANT): got command help
2020-01-17T19:16:42.887Z,1579288602.887 [CommandLine](IMPORTANT): got command strobe 
2020-01-17T19:16:42.887Z,1579288602.887 [CommandLine](FAULT): Incomplete syntax. Try: help strobe
2020-01-17T19:16:42.894Z,1579288602.894 [CommandLine](IMPORTANT): got command 
2020-01-17T19:16:42.895Z,1579288602.895 [CommandLine](FAULT): Incomplete syntax. Try: help
2020-01-17T19:16:42.904Z,1579288602.904 [CommandLine](IMPORTANT): got command 
2020-01-17T19:16:42.904Z,1579288602.904 [CommandLine](FAULT): Incomplete syntax. Try: help
2020-01-17T19:16:43.011Z,1579288603.011 [CommandLine](IMPORTANT): got command resume
2020-01-17T19:16:43.012Z,1579288603.012 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/DUSBL.xml
2020-01-17T19:16:43.192Z,1579288603.192 [MissionManager](INFO): DefineArg DUSBL.MissionTimeout = 90.000000 min
2020-01-17T19:16:43.210Z,1579288603.210 [MissionManager](INFO): DefineArg DUSBL.NumberOfRequests = 10.000000 count
2020-01-17T19:16:43.212Z,1579288603.212 [MissionManager](INFO): DefineArg DUSBL.TransponderCode = 2.000000 count
2020-01-17T19:16:43.226Z,1579288603.226 [MissionManager](INFO): DefineArg DUSBL.NumberOfPings = 1.000000 count
2020-01-17T19:16:43.228Z,1579288603.228 [MissionManager](INFO): DefineArg DUSBL.EnabledDUSBL = DUSBL_Hydroid.loadAtStartup
2020-01-17T19:16:43.241Z,1579288603.241 [DUSBL:A.Pitch](DEBUG): Construct.
2020-01-17T19:16:43.257Z,1579288603.257 [DUSBL:B.SetSpeed](DEBUG): Construct.
2020-01-17T19:16:43.276Z,1579288603.276 [DUSBL:RequestRepeater:C.Wait](DEBUG): Construct Wait.
2020-01-17T19:16:43.292Z,1579288603.292 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<!-- ====================
     Generated by TethysL
     ==================== -->
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Control="Tethys/Control"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd"
       Id="DUSBL">

    <Description>
        This mission is requests a DUSBL arming.
    </Description>

    <DefineArg Name="MissionTimeout"><Description>
        How long to let the mission run.
    </Description><Units:minute/><Value>90</Value></DefineArg>

    <DefineArg Name="NumberOfRequests"><Description>
        Number of pings to requst.
    </Description><Units:count/><Value>10</Value></DefineArg>

    <DefineArg Name="TransponderCode"><Description>
        Transponder Address.
    </Description><Units:count/><Value>2</Value></DefineArg>   

    <DefineArg Name="NumberOfPings"><Description>
        Number of pings requested each time.
    </Description><Units:count/><Value>1</Value></DefineArg>      
    
    <DefineArg Name="EnabledDUSBL"><Description>
        Automatically set to true if the DUSBL is enabled. Set to false to disable
        reading from the DUSBL.
    </Description><Sensor:DUSBL_Hydroid.loadAtStartup/></DefineArg>    

    <Timeout Duration="MissionTimeout"/>

    <Guidance:Pitch>
        <Parallel/>
        <Setting><Guidance:Pitch.massPosition/><Control:VerticalControl.massDefault/></Setting>
    </Guidance:Pitch>

    <Guidance:SetSpeed>
        <Parallel/>
        <Setting><Guidance:SetSpeed.speed/><Units:meter_per_second/><Value>0</Value></Setting>
    </Guidance:SetSpeed>

    <ReadData>
        <When>
            <Arg Name="EnabledDUSBL"/>
        </When>
            <Universal:acoustic_contact_range/>
            <Universal:acoustic_contact_direction_vehicle_frame/>
    </ReadData>
    
      <Aggregate Id="RequestRepeater">

        <Sequence Repeat="NumberOfRequests"/>
        <Assign><Parallel/><Sensor:DUSBL_Hydroid.transponderCode/><Arg Name="TransponderCode"/></Assign>
        <Assign><Parallel/><Sensor:DUSBL_Hydroid.pingRequested/><Arg Name="NumberOfPings"/></Assign>
          <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:second/><Value>90</Value></Setting>
          </Guidance:Wait>
      </Aggregate>    
</Mission>


2020-01-17T19:16:43.321Z,1579288603.321 [MissionManager](IMPORTANT): Resuming from info in Logs/20200117T174557/Maintenance_DUSBL_xml.state
2020-01-17T19:16:43.338Z,1579288603.338 [MissionManager](INFO): Default->Default:CheckIn/0
2020-01-17T19:16:43.338Z,1579288603.338 [MissionManager](INFO): Default:A.Wait->null/0
2020-01-17T19:16:43.338Z,1579288603.338 [MissionManager](INFO): Default:B.GoToSurface->null/0
2020-01-17T19:16:43.339Z,1579288603.339 [MissionManager](INFO): Default:CheckIn->Default:CheckIn:Read_Iridium/4
2020-01-17T19:16:43.339Z,1579288603.339 [MissionManager](INFO): Default:CheckIn:Read_GPS->null/0
2020-01-17T19:16:43.339Z,1579288603.339 [MissionManager](INFO): Default:CheckIn:Read_Iridium->null/0
2020-01-17T19:16:43.340Z,1579288603.340 [MissionManager](INFO): Default:CheckIn:C.Wait->null/0
2020-01-17T19:16:43.340Z,1579288603.340 [MissionManager](INFO): Default:CheckIn:D->null/0
2020-01-17T19:16:43.340Z,1579288603.340 [MissionManager](INFO): Default:CheckIn:E->null/0
2020-01-17T19:16:43.353Z,1579288603.353 [MissionManager](INFO): DUSBL->DUSBL:RequestRepeater/0
2020-01-17T19:16:43.353Z,1579288603.353 [MissionManager](INFO): DUSBL:RequestRepeater->DUSBL:RequestRepeater:C.Wait/3
2020-01-17T19:16:43.354Z,1579288603.354 [MissionManager](INFO): DUSBL:RequestRepeater:C.Wait->null/0
2020-01-17T19:16:43.354Z,1579288603.354 [CommandLine](IMPORTANT): Resuming mission and schedule
2020-01-17T19:16:43.354Z,1579288603.354 [CommandLine](IMPORTANT): Scheduling is resumed
2020-01-17T19:16:43.473Z,1579288603.473 [Default] Stopped
2020-01-17T19:16:43.473Z,1579288603.473 [Default](DEBUG): Aggregate::uninitialize Default
2020-01-17T19:16:43.473Z,1579288603.473 [Default:B.GoToSurface] Stopped
2020-01-17T19:16:43.473Z,1579288603.473 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-17T19:16:43.473Z,1579288603.473 [Default:CheckIn] Stopped
2020-01-17T19:16:43.473Z,1579288603.473 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-17T19:16:43.473Z,1579288603.473 [Default:CheckIn:Read_Iridium] Stopped
2020-01-17T19:16:43.474Z,1579288603.474 [MissionManager](IMPORTANT): Started mission DUSBL
2020-01-17T19:16:43.474Z,1579288603.474 [DUSBL] Running Loop=1
2020-01-17T19:16:43.474Z,1579288603.474 [DUSBL](DEBUG): Aggregate::initialize DUSBL
2020-01-17T19:16:43.474Z,1579288603.474 [DUSBL:A.Pitch] Running Loop=1
2020-01-17T19:16:43.474Z,1579288603.474 [DUSBL:A.Pitch](DEBUG): Initialize.
2020-01-17T19:16:43.474Z,1579288603.474 [DUSBL:B.SetSpeed] Running Loop=1
2020-01-17T19:16:43.474Z,1579288603.474 [DUSBL:B.SetSpeed](DEBUG): Initialize.
2020-01-17T19:16:43.474Z,1579288603.474 [DUSBL:C] Running Loop=1
2020-01-17T19:16:43.474Z,1579288603.474 [DUSBL:RequestRepeater] Running Loop=4
2020-01-17T19:16:43.475Z,1579288603.475 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2020-01-17T19:16:43.475Z,1579288603.475 [DUSBL:RequestRepeater:A] Running Loop=1
2020-01-17T19:16:43.475Z,1579288603.475 [DUSBL:RequestRepeater:B] Running Loop=1
2020-01-17T19:16:43.475Z,1579288603.475 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2020-01-17T19:16:43.475Z,1579288603.475 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2020-01-17T19:16:43.475Z,1579288603.475 [DUSBL:RequestRepeater:B] Running Loop=1
2020-01-17T19:16:43.475Z,1579288603.475 [DUSBL:RequestRepeater:A] Running Loop=1
2020-01-17T19:16:43.476Z,1579288603.476 [DUSBL:C](DEBUG): Initialize ReadDataComponent to sense acoustic_contact_range
2020-01-17T19:16:43.486Z,1579288603.486 [CommandLine](IMPORTANT): got command strobe on
2020-01-17T19:16:43.487Z,1579288603.487 [CommandLine](IMPORTANT): Activating strobe
2020-01-17T19:16:43.476Z,1579288603.476 [DUSBL:C](DEBUG): Initialize ReadDataComponent to sense acoustic_contact_direction_vehicle_frame
2020-01-17T19:16:43.493Z,1579288603.493 [DUSBL:B.SetSpeed] Running Loop=1
2020-01-17T19:16:43.494Z,1579288603.494 [DUSBL:A.Pitch] Running Loop=1
2020-01-17T19:16:43.564Z,1579288603.564 [CommandLine](IMPORTANT): got command 
2020-01-17T19:16:43.564Z,1579288603.564 [CommandLine](FAULT): Incomplete syntax. Try: help
2020-01-17T19:16:43.626Z,1579288603.626 [CommandLine](IMPORTANT): got command 
2020-01-17T19:16:43.626Z,1579288603.626 [CommandLine](FAULT): Incomplete syntax. Try: help
2020-01-17T19:16:43.685Z,1579288603.685 [CommandLine](IMPORTANT): got command 
2020-01-17T19:16:43.685Z,1579288603.685 [CommandLine](FAULT): Incomplete syntax. Try: help
2020-01-17T19:16:45.407Z,1579288605.407 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2020-01-17T19:16:45.813Z,1579288605.813 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2020-01-17T19:16:46.623Z,1579288606.623 [Micromodem](INFO): Nmea in: $SNTTA,,,,,191646.50*56

2020-01-17T19:16:47.758Z,1579288607.758 [CommandLine](IMPORTANT): got command maintain servo 
2020-01-17T19:16:47.758Z,1579288607.758 [CommandLine](FAULT): Incomplete syntax. Try: help maintain
2020-01-17T19:16:49.042Z,1579288609.042 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-01-17T19:16:49.256Z,1579288609.256 [CommandLine](IMPORTANT): got command configSet 
2020-01-17T19:16:49.256Z,1579288609.256 [CommandLine](FAULT): Incomplete syntax. Try: help configSet
2020-01-17T19:16:49.406Z,1579288609.406 [CommandLine](IMPORTANT): got command configSet 
2020-01-17T19:16:49.406Z,1579288609.406 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-01-17T19:16:49.407Z,1579288609.407 [CommandLine](IMPORTANT): DDM.verbosity=3 count;

2020-01-17T19:16:49.407Z,1579288609.407 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold=75 count;

2020-01-17T19:16:49.407Z,1579288609.407 [CommandLine](IMPORTANT): Micromodem.pwrampTXLevel=1 enum;

2020-01-17T19:16:49.407Z,1579288609.407 [CommandLine](IMPORTANT): Micromodem.sendExpress=1 bool;

2020-01-17T19:16:49.407Z,1579288609.407 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=105 cubic_centimeter;

2020-01-17T19:16:49.407Z,1579288609.407 [CommandLine](IMPORTANT): VerticalControl.massDefault=-5 millimeter;

2020-01-17T19:16:49.417Z,1579288609.417 [CommandLine](IMPORTANT): got command 
2020-01-17T19:16:49.417Z,1579288609.417 [CommandLine](FAULT): Incomplete syntax. Try: help
2020-01-17T19:16:49.427Z,1579288609.427 [CommandLine](IMPORTANT): got command 
2020-01-17T19:16:49.427Z,1579288609.427 [CommandLine](FAULT): Incomplete syntax. Try: help
2020-01-17T19:16:49.523Z,1579288609.523 [CommandLine](IMPORTANT): got command 
2020-01-17T19:16:49.523Z,1579288609.523 [CommandLine](FAULT): Incomplete syntax. Try: help
2020-01-17T19:16:49.534Z,1579288609.534 [CommandLine](IMPORTANT): got command exit 
2020-01-17T19:16:49.861Z,1579288609.861 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2020-01-17T19:16:50.266Z,1579288610.266 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2020-01-17T19:16:50.537Z,1579288610.537 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2020-01-17T19:16:50.537Z,1579288610.537 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-17T19:16:50.537Z,1579288610.537 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-17T19:16:50.557Z,1579288610.557 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-01-17T19:16:50.557Z,1579288610.557 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-17T19:16:50.558Z,1579288610.558 [CommandLine](INFO): Join timeout helper Thread ID is 4978
2020-01-17T19:16:50.558Z,1579288610.558 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-01-17T19:16:50.558Z,1579288610.558 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-17T19:16:50.559Z,1579288610.559 [NavChartDb](INFO): Join timeout helper Thread ID is 4979
2020-01-17T19:16:50.605Z,1579288610.605 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-17T19:16:50.605Z,1579288610.605 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-17T19:16:50.617Z,1579288610.617 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2020-01-17T19:16:50.617Z,1579288610.617 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-17T19:16:50.617Z,1579288610.617 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 4980
2020-01-17T19:16:50.715Z,1579288610.715 [MissionManager](INFO): MissionManager is completed.
2020-01-17T19:16:50.715Z,1579288610.715 [MissionManager](INFO): Uninitializing Mission DUSBL
2020-01-17T19:16:50.715Z,1579288610.715 [DUSBL] Stopped
2020-01-17T19:16:50.715Z,1579288610.715 [DUSBL](DEBUG): Aggregate::uninitialize DUSBL
2020-01-17T19:16:50.715Z,1579288610.715 [DUSBL:A.Pitch] Stopped
2020-01-17T19:16:50.715Z,1579288610.715 [DUSBL:B.SetSpeed] Stopped
2020-01-17T19:16:50.715Z,1579288610.715 [DUSBL:B.SetSpeed](DEBUG): Uninitialize.
2020-01-17T19:16:50.716Z,1579288610.716 [DUSBL:C] Stopped
2020-01-17T19:16:50.716Z,1579288610.716 [DUSBL:RequestRepeater] Stopped
2020-01-17T19:16:50.716Z,1579288610.716 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2020-01-17T19:16:50.716Z,1579288610.716 [DUSBL:RequestRepeater:A] Stopped
2020-01-17T19:16:50.716Z,1579288610.716 [DUSBL:RequestRepeater:B] Stopped
2020-01-17T19:16:50.716Z,1579288610.716 [DUSBL:RequestRepeater:C.Wait] Stopped
2020-01-17T19:16:50.716Z,1579288610.716 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-17T19:16:50.877Z,1579288610.877 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-17T19:16:50.877Z,1579288610.877 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-01-17T19:16:50.878Z,1579288610.878 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-17T19:16:50.881Z,1579288610.881 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2020-01-17T19:16:50.881Z,1579288610.881 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-17T19:16:50.881Z,1579288610.881 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 4981
2020-01-17T19:16:50.929Z,1579288610.929 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-17T19:16:50.929Z,1579288610.929 [CTD_NeilBrown](INFO): Powering down
2020-01-17T19:16:50.941Z,1579288610.941 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-17T19:16:50.945Z,1579288610.945 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-01-17T19:16:50.945Z,1579288610.945 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-17T19:16:50.945Z,1579288610.945 [Radio_Surface](INFO): Join timeout helper Thread ID is 4982
2020-01-17T19:16:51.075Z,1579288611.075 [Micromodem](INFO): Nmea in: $SNTTA,,,,,191650.96*5B

2020-01-17T19:16:51.102Z,1579288611.102 [MissionManager](IMPORTANT): Started mission Default
2020-01-17T19:16:51.102Z,1579288611.102 [Default] Running Loop=1
2020-01-17T19:16:51.102Z,1579288611.102 [Default](DEBUG): Aggregate::initialize Default
2020-01-17T19:16:51.102Z,1579288611.102 [Default:B.GoToSurface] Running Loop=1
2020-01-17T19:16:51.102Z,1579288611.102 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-17T19:16:51.103Z,1579288611.103 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-17T19:16:51.103Z,1579288611.103 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-17T19:16:51.103Z,1579288611.103 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-17T19:16:51.104Z,1579288611.104 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-17T19:16:51.104Z,1579288611.104 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-17T19:16:51.104Z,1579288611.104 [Default:A.Wait] Running Loop=1
2020-01-17T19:16:51.104Z,1579288611.104 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-01-17T19:16:51.263Z,1579288611.263 [Radio_Surface](INFO): Powering down
2020-01-17T19:16:51.264Z,1579288611.264 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-17T19:16:51.264Z,1579288611.264 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-17T19:16:51.266Z,1579288611.266 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-01-17T19:16:51.266Z,1579288611.266 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-17T19:16:51.266Z,1579288611.266 [DataOverHttps](INFO): Join timeout helper Thread ID is 4983
2020-01-17T19:16:52.265Z,1579288612.265 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-17T19:16:52.265Z,1579288612.265 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-17T19:16:52.279Z,1579288612.279 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-01-17T19:16:52.279Z,1579288612.279 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-17T19:16:52.279Z,1579288612.279 [logger](INFO): Join timeout helper Thread ID is 4984
2020-01-17T19:16:52.305Z,1579288612.305 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-17T19:16:52.305Z,1579288612.305 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-17T19:16:52.318Z,1579288612.318 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-01-17T19:16:52.318Z,1579288612.318 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-17T19:16:52.318Z,1579288612.318 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-01-17T19:16:52.318Z,1579288612.318 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-17T19:16:52.318Z,1579288612.318 [controlThread](INFO): Join timeout helper Thread ID is 4985
2020-01-17T19:16:52.326Z,1579288612.326 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-17T19:16:52.326Z,1579288612.326 [controlThread](DEBUG): Uninitializing ControlThread
2020-01-17T19:16:52.326Z,1579288612.326 [AHRS_M2](INFO): Powering down
2020-01-17T19:16:52.398Z,1579288612.398 [DDM](INFO): Powering down
2020-01-17T19:16:52.518Z,1579288612.518 [DUSBL_Hydroid](INFO): Powering down
2020-01-17T19:16:52.589Z,1579288612.589 [Micromodem](INFO): Powering down
2020-01-17T19:16:52.685Z,1579288612.685 [NAL9602](INFO): Powering down
2020-01-17T19:16:52.757Z,1579288612.757 [RDI_Pathfinder](INFO): Powering down
2020-01-17T19:16:52.758Z,1579288612.758 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-01-17T19:16:52.759Z,1579288612.759 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-01-17T19:16:52.760Z,1579288612.760 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-01-17T19:16:52.760Z,1579288612.760 [MissionManager](INFO): Uninitializing Mission Default
2020-01-17T19:16:52.760Z,1579288612.760 [Default] Stopped
2020-01-17T19:16:52.760Z,1579288612.760 [Default](DEBUG): Aggregate::uninitialize Default
2020-01-17T19:16:52.760Z,1579288612.760 [Default:A.Wait] Stopped
2020-01-17T19:16:52.760Z,1579288612.760 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-01-17T19:16:52.761Z,1579288612.761 [Default:B.GoToSurface] Stopped
2020-01-17T19:16:52.761Z,1579288612.761 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-17T19:16:52.763Z,1579288612.763 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-01-17T19:16:52.763Z,1579288612.763 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-01-17T19:16:52.763Z,1579288612.763 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-01-17T19:16:52.764Z,1579288612.764 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-01-17T19:16:52.764Z,1579288612.764 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-01-17T19:16:52.764Z,1579288612.764 [BuoyancyServo](INFO): Powering down
2020-01-17T19:16:52.777Z,1579288612.777 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-01-17T19:16:52.777Z,1579288612.777 [ElevatorServo](INFO): Powering down
2020-01-17T19:16:52.778Z,1579288612.778 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-01-17T19:16:52.778Z,1579288612.778 [MassServo](INFO): Powering down
2020-01-17T19:16:52.779Z,1579288612.779 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-01-17T19:16:52.779Z,1579288612.779 [RudderServo](INFO): Powering down
2020-01-17T19:16:52.780Z,1579288612.780 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-01-17T19:16:52.780Z,1579288612.780 [ThrusterServo](INFO): Powering down
2020-01-17T19:16:52.780Z,1579288612.780 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-01-17T19:16:52.781Z,1579288612.781 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-01-17T19:16:52.781Z,1579288612.781 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-01-17T19:16:52.781Z,1579288612.781 [CBIT](DEBUG): Powering off loads.
2020-01-17T19:16:52.793Z,1579288612.793 [CBIT](DEBUG): Disabling WDT.
2020-01-17T19:16:52.805Z,1579288612.805 [CBIT](DEBUG): Opening all GF detection circuits.
2020-01-17T19:16:52.806Z,1579288612.806 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-17T19:16:52.897Z,1579288612.897 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-17T19:16:52.919Z,1579288612.919 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-17T19:16:53.021Z,1579288613.021 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-17T19:16:53.024Z,1579288613.024 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-17T19:16:53.098Z,1579288613.098 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-17T19:16:53.169Z,1579288613.169 [logger ThreadHandler](INFO): Thread cancelled.