2020-06-16T18:12:58.739Z,1592331178.739 [NAL9602](INFO): Received command:restart logs
2020-06-16T18:12:58.744Z,1592331178.744 [CommandLine](IMPORTANT): got command restart logs
2020-06-16T18:13:29.356Z,1592331209.356 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-16T18:16:26.731Z,1592331386.731 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-16T18:16:26.731Z,1592331386.731 [Default:CheckIn:C.Wait] Stopped
2020-06-16T18:16:26.731Z,1592331386.731 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-16T18:16:26.732Z,1592331386.732 [Default:CheckIn:D] Running Loop=1
2020-06-16T18:16:27.119Z,1592331387.119 [Default:CheckIn:D] Stopped
2020-06-16T18:16:27.120Z,1592331387.120 [Default:CheckIn:E] Running Loop=1
2020-06-16T18:16:27.529Z,1592331387.529 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.551640 min 
2020-06-16T18:16:27.529Z,1592331387.529 [Default:CheckIn:E] Stopped
2020-06-16T18:16:27.529Z,1592331387.529 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-06-16T18:16:27.529Z,1592331387.529 [Default:CheckIn] Stopped
2020-06-16T18:16:27.529Z,1592331387.529 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-16T18:16:27.530Z,1592331387.530 [Default:CheckIn](INFO): Running loop #8
2020-06-16T18:16:27.530Z,1592331387.530 [Default:CheckIn] Running Loop=8
2020-06-16T18:16:27.530Z,1592331387.530 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-16T18:16:27.530Z,1592331387.530 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-16T18:16:29.542Z,1592331389.542 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181628.00,A,3648.41383,N,12147.11748,W,5.268,183.28,160620,,,A*7C
2020-06-16T18:16:29.544Z,1592331389.544 [NAL9602](INFO): GPS fix at 20200616T181628: (36.806897, -121.785291)
2020-06-16T18:16:29.554Z,1592331389.554 [Default:CheckIn:Read_GPS] Stopped
2020-06-16T18:16:29.554Z,1592331389.554 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-16T18:16:36.718Z,1592331396.718 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200616T162020/Courier0042.lzma
2020-06-16T18:16:37.721Z,1592331397.721 [DataOverHttps](INFO): Moved sent file to Logs/20200616T162020/Courier0042.lzma.bak
2020-06-16T18:16:37.722Z,1592331397.722 [DataOverHttps](INFO): SBD MOMSN=12392244
2020-06-16T18:16:53.428Z,1592331413.428 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20200616T181258/Courier0000.lzma
2020-06-16T18:16:54.429Z,1592331414.429 [DataOverHttps](INFO): Moved sent file to Logs/20200616T181258/Courier0000.lzma.bak
2020-06-16T18:16:54.429Z,1592331414.429 [DataOverHttps](INFO): SBD MOMSN=12392249
2020-06-16T18:17:00.242Z,1592331420.242 [NAL9602](INFO): SBD MO Status=0, MOMSN=21367, MT Status=0, MTMSN=0
2020-06-16T18:17:00.242Z,1592331420.242 [NAL9602](INFO): No messages in MT queue
2020-06-16T18:17:10.387Z,1592331430.387 [DataOverHttps](INFO): Sending 126 bytes from file Logs/20200616T162020/Express0043.lzma
2020-06-16T18:17:11.389Z,1592331431.389 [DataOverHttps](INFO): Moved sent file to Logs/20200616T162020/Express0043.lzma.bak
2020-06-16T18:17:11.389Z,1592331431.389 [DataOverHttps](INFO): SBD MOMSN=12392261
2020-06-16T18:17:27.248Z,1592331447.248 [DataOverHttps](INFO): Sending 416 bytes from file Logs/20200616T181258/Express0001.lzma
2020-06-16T18:17:28.249Z,1592331448.249 [DataOverHttps](INFO): Moved sent file to Logs/20200616T181258/Express0001.lzma.bak
2020-06-16T18:17:28.249Z,1592331448.249 [DataOverHttps](INFO): SBD MOMSN=12392264
2020-06-16T18:17:29.755Z,1592331449.755 [Default:CheckIn:Read_Iridium] Stopped
2020-06-16T18:17:29.755Z,1592331449.755 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-16T18:17:29.755Z,1592331449.755 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-16T18:17:30.952Z,1592331450.952 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-16T18:22:30.321Z,1592331750.321 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-16T18:22:30.321Z,1592331750.321 [Default:CheckIn:C.Wait] Stopped
2020-06-16T18:22:30.321Z,1592331750.321 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-16T18:22:30.321Z,1592331750.321 [Default:CheckIn:D] Running Loop=1
2020-06-16T18:22:30.729Z,1592331750.729 [Default:CheckIn:D] Stopped
2020-06-16T18:22:30.729Z,1592331750.729 [Default:CheckIn:E] Running Loop=1
2020-06-16T18:22:31.142Z,1592331751.142 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 48.611796 min 
2020-06-16T18:22:31.142Z,1592331751.142 [Default:CheckIn:E] Stopped
2020-06-16T18:22:31.142Z,1592331751.142 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-06-16T18:22:31.143Z,1592331751.143 [Default:CheckIn] Stopped
2020-06-16T18:22:31.143Z,1592331751.143 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-16T18:22:31.143Z,1592331751.143 [Default:CheckIn](INFO): Running loop #9
2020-06-16T18:22:31.143Z,1592331751.143 [Default:CheckIn] Running Loop=9
2020-06-16T18:22:31.143Z,1592331751.143 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-16T18:22:31.143Z,1592331751.143 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-16T18:22:33.142Z,1592331753.142 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182231.00,A,3648.15081,N,12147.22088,W,0.136,236.39,160620,,,D*7D
2020-06-16T18:22:33.144Z,1592331753.144 [NAL9602](INFO): GPS fix at 20200616T182231: (36.802514, -121.787015)
2020-06-16T18:22:33.154Z,1592331753.154 [Default:CheckIn:Read_GPS] Stopped
2020-06-16T18:22:33.154Z,1592331753.154 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-16T18:22:39.903Z,1592331759.903 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200616T181258/Courier0003.lzma
2020-06-16T18:22:40.905Z,1592331760.905 [DataOverHttps](INFO): Moved sent file to Logs/20200616T181258/Courier0003.lzma.bak
2020-06-16T18:22:40.905Z,1592331760.905 [DataOverHttps](INFO): SBD MOMSN=12392281
2020-06-16T18:22:56.595Z,1592331776.595 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20200616T181258/Express0004.lzma
2020-06-16T18:22:57.597Z,1592331777.597 [DataOverHttps](INFO): Moved sent file to Logs/20200616T181258/Express0004.lzma.bak
2020-06-16T18:22:57.597Z,1592331777.597 [DataOverHttps](INFO): SBD MOMSN=12392284
2020-06-16T18:22:59.015Z,1592331779.015 [Default:CheckIn:Read_Iridium] Stopped
2020-06-16T18:22:59.015Z,1592331779.015 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-16T18:22:59.015Z,1592331779.015 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-16T18:23:09.097Z,1592331789.097 [NAL9602](INFO): SBD MO Status=0, MOMSN=21368, MT Status=0, MTMSN=0
2020-06-16T18:23:09.098Z,1592331789.098 [NAL9602](INFO): No messages in MT queue
2020-06-16T18:23:39.794Z,1592331819.794 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-16T18:27:59.585Z,1592332079.585 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-16T18:27:59.585Z,1592332079.585 [Default:CheckIn:C.Wait] Stopped
2020-06-16T18:27:59.585Z,1592332079.585 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-16T18:27:59.585Z,1592332079.585 [Default:CheckIn:D] Running Loop=1
2020-06-16T18:27:59.996Z,1592332079.996 [Default:CheckIn:D] Stopped
2020-06-16T18:27:59.996Z,1592332079.996 [Default:CheckIn:E] Running Loop=1
2020-06-16T18:28:00.384Z,1592332080.384 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 54.099581 min 
2020-06-16T18:28:00.384Z,1592332080.384 [Default:CheckIn:E] Stopped
2020-06-16T18:28:00.384Z,1592332080.384 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-06-16T18:28:00.384Z,1592332080.384 [Default:CheckIn] Stopped
2020-06-16T18:28:00.384Z,1592332080.384 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-16T18:28:00.384Z,1592332080.384 [Default:CheckIn](INFO): Running loop #10
2020-06-16T18:28:00.384Z,1592332080.384 [Default:CheckIn] Running Loop=10
2020-06-16T18:28:00.384Z,1592332080.384 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-16T18:28:00.385Z,1592332080.385 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-16T18:28:02.402Z,1592332082.402 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182801.00,A,3648.14827,N,12147.22453,W,0.330,236.39,160620,,,D*77
2020-06-16T18:28:02.404Z,1592332082.404 [NAL9602](INFO): GPS fix at 20200616T182801: (36.802471, -121.787076)
2020-06-16T18:28:02.413Z,1592332082.413 [Default:CheckIn:Read_GPS] Stopped
2020-06-16T18:28:02.414Z,1592332082.414 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-16T18:28:11.751Z,1592332091.751 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200616T181258/Courier0006.lzma
2020-06-16T18:28:12.753Z,1592332092.753 [DataOverHttps](INFO): Moved sent file to Logs/20200616T181258/Courier0006.lzma.bak
2020-06-16T18:28:12.753Z,1592332092.753 [DataOverHttps](INFO): SBD MOMSN=12392294
2020-06-16T18:28:28.519Z,1592332108.519 [DataOverHttps](INFO): Sending 136 bytes from file Logs/20200616T181258/Express0007.lzma
2020-06-16T18:28:29.521Z,1592332109.521 [DataOverHttps](INFO): Moved sent file to Logs/20200616T181258/Express0007.lzma.bak
2020-06-16T18:28:29.521Z,1592332109.521 [DataOverHttps](INFO): SBD MOMSN=12392311
2020-06-16T18:28:30.728Z,1592332110.728 [Default:CheckIn:Read_Iridium] Stopped
2020-06-16T18:28:30.728Z,1592332110.728 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-16T18:28:30.728Z,1592332110.728 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-16T18:30:10.882Z,1592332210.882 [NAL9602](INFO): SBD MO Status=2, MOMSN=21369, MT Status=2, MTMSN=0
2020-06-16T18:30:10.882Z,1592332210.882 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-06-16T18:31:04.204Z,1592332264.204 [NAL9602](INFO): SBD MO Status=0, MOMSN=21369, MT Status=0, MTMSN=0
2020-06-16T18:31:04.204Z,1592332264.204 [NAL9602](INFO): No messages in MT queue
2020-06-16T18:31:34.918Z,1592332294.918 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-16T18:33:31.273Z,1592332411.273 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-16T18:33:31.273Z,1592332411.273 [Default:CheckIn:C.Wait] Stopped
2020-06-16T18:33:31.274Z,1592332411.274 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-16T18:33:31.274Z,1592332411.274 [Default:CheckIn:D] Running Loop=1
2020-06-16T18:33:31.681Z,1592332411.681 [Default:CheckIn:D] Stopped
2020-06-16T18:33:31.681Z,1592332411.681 [Default:CheckIn:E] Running Loop=1
2020-06-16T18:33:32.091Z,1592332412.091 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 59.627665 min 
2020-06-16T18:33:32.091Z,1592332412.091 [Default:CheckIn:E] Stopped
2020-06-16T18:33:32.091Z,1592332412.091 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-06-16T18:33:32.091Z,1592332412.091 [Default:CheckIn] Stopped
2020-06-16T18:33:32.091Z,1592332412.091 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-16T18:33:32.091Z,1592332412.091 [Default:CheckIn](INFO): Running loop #11
2020-06-16T18:33:32.091Z,1592332412.091 [Default:CheckIn] Running Loop=11
2020-06-16T18:33:32.091Z,1592332412.091 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-16T18:33:32.092Z,1592332412.092 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-16T18:33:34.094Z,1592332414.094 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183332.00,A,3648.15054,N,12147.22395,W,0.019,236.39,160620,,,D*75
2020-06-16T18:33:34.096Z,1592332414.096 [NAL9602](INFO): GPS fix at 20200616T183332: (36.802509, -121.787066)
2020-06-16T18:33:34.130Z,1592332414.130 [Default:CheckIn:Read_GPS] Stopped
2020-06-16T18:33:34.130Z,1592332414.130 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-16T18:33:41.491Z,1592332421.491 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20200616T181258/Courier0009.lzma
2020-06-16T18:33:41.764Z,1592332421.764 [NAL9602](INFO): SBD MO Status=0, MOMSN=21370, MT Status=0, MTMSN=0
2020-06-16T18:33:41.764Z,1592332421.764 [NAL9602](INFO): No messages in MT queue
2020-06-16T18:33:42.493Z,1592332422.493 [DataOverHttps](INFO): Moved sent file to Logs/20200616T181258/Courier0009.lzma.bak
2020-06-16T18:33:42.493Z,1592332422.493 [DataOverHttps](INFO): SBD MOMSN=12392318
2020-06-16T18:33:58.475Z,1592332438.475 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20200616T181258/Express0010.lzma
2020-06-16T18:33:59.477Z,1592332439.477 [DataOverHttps](INFO): Moved sent file to Logs/20200616T181258/Express0010.lzma.bak
2020-06-16T18:33:59.477Z,1592332439.477 [DataOverHttps](INFO): SBD MOMSN=12392332
2020-06-16T18:34:00.774Z,1592332440.774 [Default:CheckIn:Read_Iridium] Stopped
2020-06-16T18:34:00.774Z,1592332440.774 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-16T18:34:00.774Z,1592332440.774 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-16T18:34:12.466Z,1592332452.466 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-16T18:39:01.360Z,1592332741.360 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-16T18:39:01.360Z,1592332741.360 [Default:CheckIn:C.Wait] Stopped
2020-06-16T18:39:01.360Z,1592332741.360 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-16T18:39:01.361Z,1592332741.361 [Default:CheckIn:D] Running Loop=1
2020-06-16T18:39:01.760Z,1592332741.760 [Default:CheckIn:D] Stopped
2020-06-16T18:39:01.760Z,1592332741.760 [Default:CheckIn:E] Running Loop=1
2020-06-16T18:39:02.152Z,1592332742.152 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 65.128979 min 
2020-06-16T18:39:02.152Z,1592332742.152 [Default:CheckIn:E] Stopped
2020-06-16T18:39:02.152Z,1592332742.152 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-06-16T18:39:02.152Z,1592332742.152 [Default:CheckIn] Stopped
2020-06-16T18:39:02.152Z,1592332742.152 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-16T18:39:02.152Z,1592332742.152 [Default:CheckIn](INFO): Running loop #12
2020-06-16T18:39:02.152Z,1592332742.152 [Default:CheckIn] Running Loop=12
2020-06-16T18:39:02.152Z,1592332742.152 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-16T18:39:02.152Z,1592332742.152 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-16T18:39:04.170Z,1592332744.170 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183902.00,A,3648.16920,N,12147.28008,W,0.330,207.99,160620,,,A*7D
2020-06-16T18:39:04.172Z,1592332744.172 [NAL9602](INFO): GPS fix at 20200616T183902: (36.802820, -121.788001)
2020-06-16T18:39:04.182Z,1592332744.182 [Default:CheckIn:Read_GPS] Stopped
2020-06-16T18:39:04.182Z,1592332744.182 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-16T18:39:12.035Z,1592332752.035 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200616T181258/Courier0012.lzma
2020-06-16T18:39:13.037Z,1592332753.037 [DataOverHttps](INFO): Moved sent file to Logs/20200616T181258/Courier0012.lzma.bak
2020-06-16T18:39:13.037Z,1592332753.037 [DataOverHttps](INFO): SBD MOMSN=12392342
2020-06-16T18:39:22.002Z,1592332762.002 [CBIT](IMPORTANT): Beginning ground fault scan
2020-06-16T18:39:29.051Z,1592332769.051 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20200616T181258/Express0013.lzma
2020-06-16T18:39:30.053Z,1592332770.053 [DataOverHttps](INFO): Moved sent file to Logs/20200616T181258/Express0013.lzma.bak
2020-06-16T18:39:30.053Z,1592332770.053 [DataOverHttps](INFO): SBD MOMSN=12392349
2020-06-16T18:39:31.272Z,1592332771.272 [Default:CheckIn:Read_Iridium] Stopped
2020-06-16T18:39:31.272Z,1592332771.272 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-16T18:39:31.272Z,1592332771.272 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-16T18:39:32.906Z,1592332772.906 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002390
CHAN A1 (24V): -0.000685
CHAN A2 (12V): -0.001980
CHAN A3 (5V): -0.001531
CHAN B0 (3.3V): -0.000002
CHAN B1 (3.15aV): -0.000152
CHAN B2 (3.15bV): -0.000005
CHAN B3 (GND): 0.000134
OPEN: -0.000816
Full Scale Calc: 4.765 mA, -1.589 mA
2020-06-16T18:39:33.274Z,1592332773.274 [NAL9602](INFO): SBD MO Status=2, MOMSN=21371, MT Status=2, MTMSN=0
2020-06-16T18:39:33.274Z,1592332773.274 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-06-16T18:39:51.456Z,1592332791.456 [NAL9602](INFO): SBD MO Status=0, MOMSN=21371, MT Status=0, MTMSN=0
2020-06-16T18:39:51.456Z,1592332791.456 [NAL9602](INFO): No messages in MT queue
2020-06-16T18:40:22.160Z,1592332822.160 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-16T18:44:31.857Z,1592333071.857 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-16T18:44:31.857Z,1592333071.857 [Default:CheckIn:C.Wait] Stopped
2020-06-16T18:44:31.857Z,1592333071.857 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-16T18:44:31.858Z,1592333071.858 [Default:CheckIn:D] Running Loop=1
2020-06-16T18:44:32.263Z,1592333072.263 [Default:CheckIn:D] Stopped
2020-06-16T18:44:32.263Z,1592333072.263 [Default:CheckIn:E] Running Loop=1
2020-06-16T18:44:32.660Z,1592333072.660 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 70.637362 min 
2020-06-16T18:44:32.660Z,1592333072.660 [Default:CheckIn:E] Stopped
2020-06-16T18:44:32.661Z,1592333072.661 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-06-16T18:44:32.661Z,1592333072.661 [Default:CheckIn] Stopped
2020-06-16T18:44:32.661Z,1592333072.661 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-16T18:44:32.661Z,1592333072.661 [Default:CheckIn](INFO): Running loop #13
2020-06-16T18:44:32.661Z,1592333072.661 [Default:CheckIn] Running Loop=13
2020-06-16T18:44:32.661Z,1592333072.661 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-16T18:44:32.661Z,1592333072.661 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-16T18:44:34.678Z,1592333074.678 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184433.00,A,3648.16967,N,12147.28013,W,0.583,210.84,160620,,,A*78
2020-06-16T18:44:34.680Z,1592333074.680 [NAL9602](INFO): GPS fix at 20200616T184433: (36.802828, -121.788002)
2020-06-16T18:44:34.690Z,1592333074.690 [Default:CheckIn:Read_GPS] Stopped
2020-06-16T18:44:34.690Z,1592333074.690 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-16T18:44:42.383Z,1592333082.383 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200616T181258/Courier0015.lzma
2020-06-16T18:44:43.385Z,1592333083.385 [DataOverHttps](INFO): Moved sent file to Logs/20200616T181258/Courier0015.lzma.bak
2020-06-16T18:44:43.385Z,1592333083.385 [DataOverHttps](INFO): SBD MOMSN=12392368
2020-06-16T18:44:59.420Z,1592333099.420 [DataOverHttps](INFO): Sending 344 bytes from file Logs/20200616T181258/Express0016.lzma
2020-06-16T18:45:00.421Z,1592333100.421 [DataOverHttps](INFO): Moved sent file to Logs/20200616T181258/Express0016.lzma.bak
2020-06-16T18:45:00.421Z,1592333100.421 [DataOverHttps](INFO): SBD MOMSN=12392371
2020-06-16T18:45:01.763Z,1592333101.763 [Default:CheckIn:Read_Iridium] Stopped
2020-06-16T18:45:01.763Z,1592333101.763 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-16T18:45:01.763Z,1592333101.763 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-16T18:45:55.070Z,1592333155.070 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2020-06-16T18:49:36.866Z,1592333376.866 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-06-16T18:50:02.328Z,1592333402.328 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-16T18:50:02.328Z,1592333402.328 [Default:CheckIn:C.Wait] Stopped
2020-06-16T18:50:02.328Z,1592333402.328 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-16T18:50:02.328Z,1592333402.328 [Default:CheckIn:D] Running Loop=1
2020-06-16T18:50:02.740Z,1592333402.740 [Default:CheckIn:D] Stopped
2020-06-16T18:50:02.740Z,1592333402.740 [Default:CheckIn:E] Running Loop=1
2020-06-16T18:50:03.156Z,1592333403.156 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 76.145321 min 
2020-06-16T18:50:03.156Z,1592333403.156 [Default:CheckIn:E] Stopped
2020-06-16T18:50:03.157Z,1592333403.157 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-06-16T18:50:03.157Z,1592333403.157 [Default:CheckIn] Stopped
2020-06-16T18:50:03.157Z,1592333403.157 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-16T18:50:03.157Z,1592333403.157 [Default:CheckIn](INFO): Running loop #14
2020-06-16T18:50:03.157Z,1592333403.157 [Default:CheckIn] Running Loop=14
2020-06-16T18:50:03.157Z,1592333403.157 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-16T18:50:03.157Z,1592333403.157 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-16T18:50:05.154Z,1592333405.154 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185003.00,A,3648.17325,N,12147.27645,W,0.350,79.46,160620,,,A*42
2020-06-16T18:50:05.156Z,1592333405.156 [NAL9602](INFO): GPS fix at 20200616T185003: (36.802887, -121.787941)
2020-06-16T18:50:05.166Z,1592333405.166 [Default:CheckIn:Read_GPS] Stopped
2020-06-16T18:50:05.166Z,1592333405.166 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-16T18:50:13.123Z,1592333413.123 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200616T181258/Courier0018.lzma
2020-06-16T18:50:14.125Z,1592333414.125 [DataOverHttps](INFO): Moved sent file to Logs/20200616T181258/Courier0018.lzma.bak
2020-06-16T18:50:14.125Z,1592333414.125 [DataOverHttps](INFO): SBD MOMSN=12392392
2020-06-16T18:50:29.855Z,1592333429.855 [DataOverHttps](INFO): Sending 236 bytes from file Logs/20200616T181258/Express0019.lzma
2020-06-16T18:50:30.857Z,1592333430.857 [DataOverHttps](INFO): Moved sent file to Logs/20200616T181258/Express0019.lzma.bak
2020-06-16T18:50:30.857Z,1592333430.857 [DataOverHttps](INFO): SBD MOMSN=12392395
2020-06-16T18:50:32.231Z,1592333432.231 [Default:CheckIn:Read_Iridium] Stopped
2020-06-16T18:50:32.231Z,1592333432.231 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-16T18:50:32.231Z,1592333432.231 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-16T18:50:37.064Z,1592333437.064 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-16T18:55:32.811Z,1592333732.811 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-16T18:55:32.811Z,1592333732.811 [Default:CheckIn:C.Wait] Stopped
2020-06-16T18:55:32.812Z,1592333732.812 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-16T18:55:32.812Z,1592333732.812 [Default:CheckIn:D] Running Loop=1
2020-06-16T18:55:33.229Z,1592333733.229 [Default:CheckIn:D] Stopped
2020-06-16T18:55:33.229Z,1592333733.229 [Default:CheckIn:E] Running Loop=1
2020-06-16T18:55:33.611Z,1592333733.611 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 81.653475 min 
2020-06-16T18:55:33.612Z,1592333733.612 [Default:CheckIn:E] Stopped
2020-06-16T18:55:33.612Z,1592333733.612 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-06-16T18:55:33.612Z,1592333733.612 [Default:CheckIn] Stopped
2020-06-16T18:55:33.612Z,1592333733.612 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-16T18:55:33.612Z,1592333733.612 [Default:CheckIn](INFO): Running loop #15
2020-06-16T18:55:33.612Z,1592333733.612 [Default:CheckIn] Running Loop=15
2020-06-16T18:55:33.612Z,1592333733.612 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-16T18:55:33.612Z,1592333733.612 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-16T18:55:35.630Z,1592333735.630 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185534.00,A,3648.16955,N,12147.28013,W,0.836,79.46,160620,,,A*4E
2020-06-16T18:55:35.632Z,1592333735.632 [NAL9602](INFO): GPS fix at 20200616T185534: (36.802826, -121.788002)
2020-06-16T18:55:35.642Z,1592333735.642 [Default:CheckIn:Read_GPS] Stopped
2020-06-16T18:55:35.642Z,1592333735.642 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-16T18:55:43.711Z,1592333743.711 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200616T181258/Courier0021.lzma
2020-06-16T18:55:44.713Z,1592333744.713 [DataOverHttps](INFO): Moved sent file to Logs/20200616T181258/Courier0021.lzma.bak
2020-06-16T18:55:44.713Z,1592333744.713 [DataOverHttps](INFO): SBD MOMSN=12392415
2020-06-16T18:56:00.587Z,1592333760.587 [DataOverHttps](INFO): Sending 137 bytes from file Logs/20200616T181258/Express0022.lzma
2020-06-16T18:56:01.589Z,1592333761.589 [DataOverHttps](INFO): Moved sent file to Logs/20200616T181258/Express0022.lzma.bak
2020-06-16T18:56:01.589Z,1592333761.589 [DataOverHttps](INFO): SBD MOMSN=12392418
2020-06-16T18:56:02.710Z,1592333762.710 [Default:CheckIn:Read_Iridium] Stopped
2020-06-16T18:56:02.711Z,1592333762.711 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-16T18:56:02.711Z,1592333762.711 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-16T18:56:06.328Z,1592333766.328 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2020-06-16T18:56:06.411Z,1592333766.411 [NAL9602](FAULT): received: 
+CSQ:0

OK371, 0, 0, 0, 0

OK
2020-06-16T18:56:06.411Z,1592333766.411 [NAL9602] Data Fault, FailCount= 1
2020-06-16T18:56:06.411Z,1592333766.411 [NAL9602](ERROR): Data Fault
2020-06-16T18:56:06.447Z,1592333766.447 [CBIT](ERROR): Data Fault in component: NAL9602
2020-06-16T18:56:06.732Z,1592333766.732 [NAL9602](INFO): Powering down
2020-06-16T18:56:07.639Z,1592333767.639 [CBIT](INFO): Clearing failed state for component NAL9602
2020-06-16T18:56:07.640Z,1592333767.640 [NAL9602] No Fault, FailCount= 1
2020-06-16T18:56:37.056Z,1592333797.056 [NAL9602](INFO): Powering up NAL9602
2020-06-16T18:56:47.964Z,1592333807.964 [NAL9602](INFO): NAL9602 initialized
2020-06-16T18:57:19.072Z,1592333839.072 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-16T18:58:12.409Z,1592333892.409 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2020-06-16T19:01:03.319Z,1592334063.319 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-16T19:01:03.319Z,1592334063.319 [Default:CheckIn:C.Wait] Stopped
2020-06-16T19:01:03.319Z,1592334063.319 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-16T19:01:03.319Z,1592334063.319 [Default:CheckIn:D] Running Loop=1
2020-06-16T19:01:03.713Z,1592334063.713 [Default:CheckIn:D] Stopped
2020-06-16T19:01:03.713Z,1592334063.713 [Default:CheckIn:E] Running Loop=1
2020-06-16T19:01:04.118Z,1592334064.118 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 87.161532 min 
2020-06-16T19:01:04.118Z,1592334064.118 [Default:CheckIn:E] Stopped
2020-06-16T19:01:04.118Z,1592334064.118 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-06-16T19:01:04.119Z,1592334064.119 [Default:CheckIn] Stopped
2020-06-16T19:01:04.119Z,1592334064.119 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-16T19:01:04.119Z,1592334064.119 [Default:CheckIn](INFO): Running loop #16
2020-06-16T19:01:04.119Z,1592334064.119 [Default:CheckIn] Running Loop=16
2020-06-16T19:01:04.121Z,1592334064.121 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-16T19:01:04.121Z,1592334064.121 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-16T19:01:06.125Z,1592334066.125 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190103.00,A,3648.17066,N,12147.27822,W,0.719,0.00,160620,,,A*79
2020-06-16T19:01:06.127Z,1592334066.127 [NAL9602](INFO): GPS fix at 20200616T190103: (36.802844, -121.787970)
2020-06-16T19:01:06.137Z,1592334066.137 [Default:CheckIn:Read_GPS] Stopped
2020-06-16T19:01:06.137Z,1592334066.137 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-16T19:01:07.744Z,1592334067.744 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-06-16T19:01:13.131Z,1592334073.131 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200616T181258/Courier0024.lzma
2020-06-16T19:01:14.133Z,1592334074.133 [DataOverHttps](INFO): Moved sent file to Logs/20200616T181258/Courier0024.lzma.bak
2020-06-16T19:01:14.133Z,1592334074.133 [DataOverHttps](INFO): SBD MOMSN=12392421
2020-06-16T19:01:29.836Z,1592334089.836 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20200616T181258/Express0025.lzma
2020-06-16T19:01:30.837Z,1592334090.837 [DataOverHttps](INFO): Moved sent file to Logs/20200616T181258/Express0025.lzma.bak
2020-06-16T19:01:30.837Z,1592334090.837 [DataOverHttps](INFO): SBD MOMSN=12392424
2020-06-16T19:01:32.003Z,1592334092.003 [Default:CheckIn:Read_Iridium] Stopped
2020-06-16T19:01:32.004Z,1592334092.004 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-16T19:01:32.004Z,1592334092.004 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-16T19:01:38.448Z,1592334098.448 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-16T19:01:59.855Z,1592334119.855 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-06-16T19:01:59.857Z,1592334119.857 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2020-06-16T19:06:32.577Z,1592334392.577 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-16T19:06:32.577Z,1592334392.577 [Default:CheckIn:C.Wait] Stopped
2020-06-16T19:06:32.577Z,1592334392.577 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-16T19:06:32.577Z,1592334392.577 [Default:CheckIn:D] Running Loop=1
2020-06-16T19:06:32.986Z,1592334392.986 [Default:CheckIn:D] Stopped
2020-06-16T19:06:32.987Z,1592334392.987 [Default:CheckIn:E] Running Loop=1
2020-06-16T19:06:33.400Z,1592334393.400 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 92.649422 min 
2020-06-16T19:06:33.400Z,1592334393.400 [Default:CheckIn:E] Stopped
2020-06-16T19:06:33.401Z,1592334393.401 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-06-16T19:06:33.401Z,1592334393.401 [Default:CheckIn] Stopped
2020-06-16T19:06:33.401Z,1592334393.401 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-16T19:06:33.401Z,1592334393.401 [Default:CheckIn](INFO): Running loop #17
2020-06-16T19:06:33.401Z,1592334393.401 [Default:CheckIn] Running Loop=17
2020-06-16T19:06:33.401Z,1592334393.401 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-16T19:06:33.401Z,1592334393.401 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-16T19:06:35.398Z,1592334395.398 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190634.00,A,3648.16758,N,12147.28187,W,0.330,0.00,160620,,,A*77
2020-06-16T19:06:35.400Z,1592334395.400 [NAL9602](INFO): GPS fix at 20200616T190634: (36.802793, -121.788031)
2020-06-16T19:06:35.410Z,1592334395.410 [Default:CheckIn:Read_GPS] Stopped
2020-06-16T19:06:35.410Z,1592334395.410 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-16T19:06:42.631Z,1592334402.631 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20200616T181258/Courier0027.lzma
2020-06-16T19:06:43.633Z,1592334403.633 [DataOverHttps](INFO): Moved sent file to Logs/20200616T181258/Courier0027.lzma.bak
2020-06-16T19:06:43.633Z,1592334403.633 [DataOverHttps](INFO): SBD MOMSN=12392444
2020-06-16T19:06:59.311Z,1592334419.311 [DataOverHttps](INFO): Sending 213 bytes from file Logs/20200616T181258/Express0028.lzma
2020-06-16T19:07:00.313Z,1592334420.313 [DataOverHttps](INFO): Moved sent file to Logs/20200616T181258/Express0028.lzma.bak
2020-06-16T19:07:00.313Z,1592334420.313 [DataOverHttps](INFO): SBD MOMSN=12392447
2020-06-16T19:07:01.682Z,1592334421.682 [Default:CheckIn:Read_Iridium] Stopped
2020-06-16T19:07:01.682Z,1592334421.682 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-16T19:07:01.682Z,1592334421.682 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-16T19:08:20.036Z,1592334500.036 [NAL9602](INFO): SBD MO Status=0, MOMSN=21372, MT Status=0, MTMSN=0
2020-06-16T19:08:20.036Z,1592334500.036 [NAL9602](INFO): No messages in MT queue
2020-06-16T19:08:50.738Z,1592334530.738 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-16T19:12:02.246Z,1592334722.246 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-16T19:12:02.246Z,1592334722.246 [Default:CheckIn:C.Wait] Stopped
2020-06-16T19:12:02.246Z,1592334722.246 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-16T19:12:02.246Z,1592334722.246 [Default:CheckIn:D] Running Loop=1
2020-06-16T19:12:02.651Z,1592334722.651 [Default:CheckIn:D] Stopped
2020-06-16T19:12:02.651Z,1592334722.651 [Default:CheckIn:E] Running Loop=1
2020-06-16T19:12:03.053Z,1592334723.053 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 98.143840 min 
2020-06-16T19:12:03.053Z,1592334723.053 [Default:CheckIn:E] Stopped
2020-06-16T19:12:03.053Z,1592334723.053 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-06-16T19:12:03.053Z,1592334723.053 [Default:CheckIn] Stopped
2020-06-16T19:12:03.054Z,1592334723.054 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-16T19:12:03.054Z,1592334723.054 [Default:CheckIn](INFO): Running loop #18
2020-06-16T19:12:03.054Z,1592334723.054 [Default:CheckIn] Running Loop=18
2020-06-16T19:12:03.054Z,1592334723.054 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-16T19:12:03.054Z,1592334723.054 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-16T19:12:05.066Z,1592334725.066 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191203.00,A,3648.16366,N,12147.28094,W,0.078,0.00,160620,,,A*73
2020-06-16T19:12:05.068Z,1592334725.068 [NAL9602](INFO): GPS fix at 20200616T191203: (36.802728, -121.788016)
2020-06-16T19:12:05.078Z,1592334725.078 [Default:CheckIn:Read_GPS] Stopped
2020-06-16T19:12:05.078Z,1592334725.078 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-16T19:12:12.240Z,1592334732.240 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200616T181258/Courier0030.lzma
2020-06-16T19:12:13.241Z,1592334733.241 [DataOverHttps](INFO): Moved sent file to Logs/20200616T181258/Courier0030.lzma.bak
2020-06-16T19:12:13.241Z,1592334733.241 [DataOverHttps](INFO): SBD MOMSN=12392452
2020-06-16T19:12:28.923Z,1592334748.923 [DataOverHttps](INFO): Sending 136 bytes from file Logs/20200616T181258/Express0031.lzma
2020-06-16T19:12:29.925Z,1592334749.925 [DataOverHttps](INFO): Moved sent file to Logs/20200616T181258/Express0031.lzma.bak
2020-06-16T19:12:29.925Z,1592334749.925 [DataOverHttps](INFO): SBD MOMSN=12392455
2020-06-16T19:12:31.337Z,1592334751.337 [Default:CheckIn:Read_Iridium] Stopped
2020-06-16T19:12:31.337Z,1592334751.337 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-16T19:12:31.337Z,1592334751.337 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-16T19:12:39.001Z,1592334759.001 [NAL9602](INFO): SBD MO Status=0, MOMSN=21373, MT Status=0, MTMSN=0
2020-06-16T19:12:39.002Z,1592334759.002 [NAL9602](INFO): No messages in MT queue
2020-06-16T19:13:09.700Z,1592334789.700 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-16T19:17:31.903Z,1592335051.903 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-16T19:17:31.904Z,1592335051.904 [Default:CheckIn:C.Wait] Stopped
2020-06-16T19:17:31.904Z,1592335051.904 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-16T19:17:31.904Z,1592335051.904 [Default:CheckIn:D] Running Loop=1
2020-06-16T19:17:32.308Z,1592335052.308 [Default:CheckIn:D] Stopped
2020-06-16T19:17:32.308Z,1592335052.308 [Default:CheckIn:E] Running Loop=1
2020-06-16T19:17:32.723Z,1592335052.723 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 103.638118 min 
2020-06-16T19:17:32.723Z,1592335052.723 [Default:CheckIn:E] Stopped
2020-06-16T19:17:32.723Z,1592335052.723 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-06-16T19:17:32.723Z,1592335052.723 [Default:CheckIn] Stopped
2020-06-16T19:17:32.723Z,1592335052.723 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-16T19:17:32.724Z,1592335052.724 [Default:CheckIn](INFO): Running loop #19
2020-06-16T19:17:32.724Z,1592335052.724 [Default:CheckIn] Running Loop=19
2020-06-16T19:17:32.724Z,1592335052.724 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-16T19:17:32.724Z,1592335052.724 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-16T19:17:34.730Z,1592335054.730 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191733.00,A,3648.16621,N,12147.27225,W,0.311,0.00,160620,,,A*78
2020-06-16T19:17:34.742Z,1592335054.742 [NAL9602](INFO): GPS fix at 20200616T191733: (36.802770, -121.787871)
2020-06-16T19:17:34.756Z,1592335054.756 [Default:CheckIn:Read_GPS] Stopped
2020-06-16T19:17:34.756Z,1592335054.756 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-16T19:17:41.942Z,1592335061.942 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200616T181258/Courier0033.lzma
2020-06-16T19:17:42.945Z,1592335062.945 [DataOverHttps](INFO): Moved sent file to Logs/20200616T181258/Courier0033.lzma.bak
2020-06-16T19:17:42.945Z,1592335062.945 [DataOverHttps](INFO): SBD MOMSN=12392459
2020-06-16T19:17:58.939Z,1592335078.939 [DataOverHttps](INFO): Sending 136 bytes from file Logs/20200616T181258/Express0034.lzma
2020-06-16T19:18:23.031Z,1592335103.031 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2020-06-16T19:18:30.491Z,1592335110.491 [DataOverHttps](INFO): Sending 136 bytes from file Logs/20200616T181258/Express0034.lzma
2020-06-16T19:18:31.493Z,1592335111.493 [DataOverHttps](INFO): Moved sent file to Logs/20200616T181258/Express0034.lzma.bak
2020-06-16T19:18:31.493Z,1592335111.493 [DataOverHttps](INFO): SBD MOMSN=12392462
2020-06-16T19:18:32.939Z,1592335112.939 [Default:CheckIn:Read_Iridium] Stopped
2020-06-16T19:18:32.939Z,1592335112.939 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-16T19:18:32.940Z,1592335112.940 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-16T19:22:36.916Z,1592335356.916 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-06-16T19:23:07.624Z,1592335387.624 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-16T19:23:33.508Z,1592335413.508 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-16T19:23:33.508Z,1592335413.508 [Default:CheckIn:C.Wait] Stopped
2020-06-16T19:23:33.508Z,1592335413.508 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-16T19:23:33.509Z,1592335413.509 [Default:CheckIn:D] Running Loop=1
2020-06-16T19:23:33.924Z,1592335413.924 [Default:CheckIn:D] Stopped
2020-06-16T19:23:33.924Z,1592335413.924 [Default:CheckIn:E] Running Loop=1
2020-06-16T19:23:34.311Z,1592335414.311 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 109.665039 min 
2020-06-16T19:23:34.311Z,1592335414.311 [Default:CheckIn:E] Stopped
2020-06-16T19:23:34.311Z,1592335414.311 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-06-16T19:23:34.311Z,1592335414.311 [Default:CheckIn] Stopped
2020-06-16T19:23:34.312Z,1592335414.312 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-16T19:23:34.312Z,1592335414.312 [Default:CheckIn](INFO): Running loop #20
2020-06-16T19:23:34.312Z,1592335414.312 [Default:CheckIn] Running Loop=20
2020-06-16T19:23:34.312Z,1592335414.312 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-16T19:23:34.312Z,1592335414.312 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-16T19:23:36.306Z,1592335416.306 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192335.00,A,3648.16280,N,12147.27598,W,0.875,3.79,160620,,,A*73
2020-06-16T19:23:36.308Z,1592335416.308 [NAL9602](INFO): GPS fix at 20200616T192335: (36.802713, -121.787933)
2020-06-16T19:23:36.331Z,1592335416.331 [Default:CheckIn:Read_GPS] Stopped
2020-06-16T19:23:36.331Z,1592335416.331 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-16T19:23:48.255Z,1592335428.255 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200616T181258/Courier0036.lzma
2020-06-16T19:23:49.257Z,1592335429.257 [DataOverHttps](INFO): Moved sent file to Logs/20200616T181258/Courier0036.lzma.bak
2020-06-16T19:23:49.257Z,1592335429.257 [DataOverHttps](INFO): SBD MOMSN=12392465
2020-06-16T19:24:06.852Z,1592335446.852 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2020-06-16T19:24:06.935Z,1592335446.935 [NAL9602](FAULT): received: 
+CSQ:0

OK373, 0, 0, 0, 0

OK
2020-06-16T19:24:06.935Z,1592335446.935 [NAL9602] Data Fault, FailCount= 1
2020-06-16T19:24:06.935Z,1592335446.935 [NAL9602](ERROR): Data Fault
2020-06-16T19:24:07.036Z,1592335447.036 [CBIT](ERROR): Data Fault in component: NAL9602
2020-06-16T19:24:07.265Z,1592335447.265 [NAL9602](INFO): Powering down
2020-06-16T19:24:08.124Z,1592335448.124 [CBIT](INFO): Clearing failed state for component NAL9602
2020-06-16T19:24:08.124Z,1592335448.124 [NAL9602] No Fault, FailCount= 1
2020-06-16T19:24:14.263Z,1592335454.263 [DataOverHttps](INFO): Sending 229 bytes from file Logs/20200616T181258/Express0037.lzma
2020-06-16T19:24:15.265Z,1592335455.265 [DataOverHttps](INFO): Moved sent file to Logs/20200616T181258/Express0037.lzma.bak
2020-06-16T19:24:15.265Z,1592335455.265 [DataOverHttps](INFO): SBD MOMSN=12392468
2020-06-16T19:24:16.556Z,1592335456.556 [Default:CheckIn:Read_Iridium] Stopped
2020-06-16T19:24:16.556Z,1592335456.556 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-16T19:24:16.557Z,1592335456.557 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-16T19:24:37.556Z,1592335477.556 [NAL9602](INFO): Powering up NAL9602
2020-06-16T19:24:48.464Z,1592335488.464 [NAL9602](INFO): NAL9602 initialized
2020-06-16T19:25:19.576Z,1592335519.576 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-16T19:26:41.245Z,1592335601.245 [CommandLine](IMPORTANT): got command failComponent
2020-06-16T19:26:41.245Z,1592335601.245 [CommandLine](IMPORTANT): Failed components:
2020-06-16T19:26:41.245Z,1592335601.245 [CommandLine](IMPORTANT): No failed Components.
2020-06-16T19:26:43.604Z,1592335603.604 [CommandLine](IMPORTANT): got command quit
2020-06-16T19:26:44.611Z,1592335604.611 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-16T19:26:44.612Z,1592335604.612 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-06-16T19:26:44.811Z,1592335604.811 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-06-16T19:26:44.811Z,1592335604.811 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-06-16T19:26:44.812Z,1592335604.812 [CommandLine](INFO): Join timeout helper Thread ID is 2345
2020-06-16T19:26:44.820Z,1592335604.820 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-06-16T19:26:44.821Z,1592335604.821 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-06-16T19:26:44.821Z,1592335604.821 [NavChartDb](INFO): Join timeout helper Thread ID is 2346
2020-06-16T19:26:45.195Z,1592335605.195 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-16T19:26:45.196Z,1592335605.196 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-06-16T19:26:45.207Z,1592335605.207 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2020-06-16T19:26:45.207Z,1592335605.207 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-06-16T19:26:45.208Z,1592335605.208 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2347
2020-06-16T19:26:45.392Z,1592335605.392 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-16T19:26:45.392Z,1592335605.392 [WetLabsBB2FL](INFO): Powering down
2020-06-16T19:26:45.393Z,1592335605.393 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-06-16T19:26:45.399Z,1592335605.399 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2020-06-16T19:26:45.399Z,1592335605.399 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-06-16T19:26:45.400Z,1592335605.400 [CTD_Seabird](INFO): Join timeout helper Thread ID is 2348
2020-06-16T19:26:45.921Z,1592335605.921 [CTD_Seabird](INFO): Powering down
2020-06-16T19:26:45.938Z,1592335605.938 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-16T19:26:45.938Z,1592335605.938 [CTD_Seabird](INFO): Powering down
2020-06-16T19:26:45.951Z,1592335605.951 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-06-16T19:26:45.963Z,1592335605.963 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-06-16T19:26:45.963Z,1592335605.963 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-06-16T19:26:45.964Z,1592335605.964 [Radio_Surface](INFO): Join timeout helper Thread ID is 2349
2020-06-16T19:26:46.271Z,1592335606.271 [Radio_Surface](INFO): Powering down
2020-06-16T19:26:46.272Z,1592335606.272 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-16T19:26:46.272Z,1592335606.272 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-06-16T19:26:46.288Z,1592335606.288 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2020-06-16T19:26:46.288Z,1592335606.288 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-06-16T19:26:46.289Z,1592335606.289 [Onboard](INFO): Join timeout helper Thread ID is 2350
2020-06-16T19:26:47.199Z,1592335607.199 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2020-06-16T19:26:51.179Z,1592335611.179 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-16T19:26:51.179Z,1592335611.179 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-06-16T19:26:51.196Z,1592335611.196 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-06-16T19:26:51.196Z,1592335611.196 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-06-16T19:26:51.196Z,1592335611.196 [DataOverHttps](INFO): Join timeout helper Thread ID is 2351
2020-06-16T19:26:51.215Z,1592335611.215 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-16T19:26:51.215Z,1592335611.215 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-06-16T19:26:51.236Z,1592335611.236 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-06-16T19:26:51.236Z,1592335611.236 [logger ThreadHandler](INFO): Thread cancelled.
2020-06-16T19:26:51.236Z,1592335611.236 [logger](INFO): Join timeout helper Thread ID is 2352
2020-06-16T19:26:51.243Z,1592335611.243 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-16T19:26:51.243Z,1592335611.243 [logger ThreadHandler](INFO): Thread cancelled.
2020-06-16T19:26:51.256Z,1592335611.256 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-06-16T19:26:51.256Z,1592335611.256 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-06-16T19:26:51.256Z,1592335611.256 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-06-16T19:26:51.256Z,1592335611.256 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-06-16T19:26:51.256Z,1592335611.256 [controlThread](INFO): Join timeout helper Thread ID is 2353
2020-06-16T19:26:51.375Z,1592335611.375 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-16T19:26:51.375Z,1592335611.375 [controlThread](DEBUG): Uninitializing ControlThread
2020-06-16T19:26:51.376Z,1592335611.376 [AHRS_M2](INFO): Powering down
2020-06-16T19:26:51.448Z,1592335611.448 [NAL9602](INFO): Powering down
2020-06-16T19:26:51.450Z,1592335611.450 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-06-16T19:26:51.451Z,1592335611.451 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-06-16T19:26:51.451Z,1592335611.451 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-06-16T19:26:51.452Z,1592335611.452 [MissionManager](INFO): Uninitializing Mission Default
2020-06-16T19:26:51.452Z,1592335611.452 [Default] Stopped
2020-06-16T19:26:51.452Z,1592335611.452 [Default](DEBUG): Aggregate::uninitialize Default
2020-06-16T19:26:51.452Z,1592335611.452 [Default:B.GoToSurface] Stopped
2020-06-16T19:26:51.452Z,1592335611.452 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-06-16T19:26:51.452Z,1592335611.452 [Default:CheckIn] Stopped
2020-06-16T19:26:51.452Z,1592335611.452 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-16T19:26:51.453Z,1592335611.453 [Default:CheckIn:C.Wait] Stopped
2020-06-16T19:26:51.453Z,1592335611.453 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-16T19:26:51.455Z,1592335611.455 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-06-16T19:26:51.455Z,1592335611.455 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-06-16T19:26:51.456Z,1592335611.456 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-06-16T19:26:51.456Z,1592335611.456 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-06-16T19:26:51.456Z,1592335611.456 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-06-16T19:26:51.456Z,1592335611.456 [BuoyancyServo](INFO): Powering down
2020-06-16T19:26:51.471Z,1592335611.471 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-06-16T19:26:51.471Z,1592335611.471 [ElevatorServo](INFO): Powering down
2020-06-16T19:26:51.472Z,1592335611.472 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-06-16T19:26:51.472Z,1592335611.472 [MassServo](INFO): Powering down
2020-06-16T19:26:51.473Z,1592335611.473 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-06-16T19:26:51.473Z,1592335611.473 [RudderServo](INFO): Powering down
2020-06-16T19:26:51.474Z,1592335611.474 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-06-16T19:26:51.474Z,1592335611.474 [ThrusterServo](INFO): Powering down
2020-06-16T19:26:51.474Z,1592335611.474 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-06-16T19:26:51.475Z,1592335611.475 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-06-16T19:26:51.475Z,1592335611.475 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-06-16T19:26:51.475Z,1592335611.475 [CBIT](DEBUG): Powering off loads.
2020-06-16T19:26:51.487Z,1592335611.487 [CBIT](DEBUG): Disabling WDT.
2020-06-16T19:26:51.499Z,1592335611.499 [CBIT](DEBUG): Opening all GF detection circuits.
2020-06-16T19:26:51.500Z,1592335611.500 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-06-16T19:26:51.595Z,1592335611.595 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-06-16T19:26:51.598Z,1592335611.598 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-06-16T19:26:51.603Z,1592335611.603 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-06-16T19:26:51.639Z,1592335611.639 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-06-16T19:26:51.644Z,1592335611.644 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-06-16T19:26:51.687Z,1592335611.687 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-06-16T19:26:51.795Z,1592335611.795 [logger ThreadHandler](INFO): Thread cancelled.