2021-07-01T15:45:42.912Z,1625154342.912 [CommandLine](IMPORTANT): got command restart logs
2021-07-01T15:46:05.768Z,1625154365.768 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-07-01T15:46:05.768Z,1625154365.768 [Default:CheckIn:C.Wait] Stopped
2021-07-01T15:46:05.768Z,1625154365.768 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-07-01T15:46:05.768Z,1625154365.768 [Default:CheckIn:D] Running Loop=1
2021-07-01T15:46:06.174Z,1625154366.174 [Default:CheckIn:D] Stopped
2021-07-01T15:46:06.174Z,1625154366.174 [Default:CheckIn:E] Running Loop=1
2021-07-01T15:46:06.585Z,1625154366.585 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 47.684953 min 
2021-07-01T15:46:06.585Z,1625154366.585 [Default:CheckIn:E] Stopped
2021-07-01T15:46:06.585Z,1625154366.585 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-07-01T15:46:06.585Z,1625154366.585 [Default:CheckIn] Stopped
2021-07-01T15:46:06.585Z,1625154366.585 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-07-01T15:46:06.585Z,1625154366.585 [Default:CheckIn](INFO): Running loop #9
2021-07-01T15:46:06.585Z,1625154366.585 [Default:CheckIn] Running Loop=9
2021-07-01T15:46:06.586Z,1625154366.586 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-07-01T15:46:06.586Z,1625154366.586 [Default:CheckIn:Read_GPS] Running Loop=1
2021-07-01T15:46:08.591Z,1625154368.591 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154608.00,A,3648.16676,N,12150.75177,W,8.533,49.93,010721,,,D*45
2021-07-01T15:46:08.593Z,1625154368.593 [NAL9602](INFO): GPS fix at 20210701T154608: (36.802779, -121.845863)
2021-07-01T15:46:08.640Z,1625154368.640 [Default:CheckIn:Read_GPS] Stopped
2021-07-01T15:46:08.640Z,1625154368.640 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-07-01T15:46:14.272Z,1625154374.272 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210701T145446/Courier0027.lzma
2021-07-01T15:46:15.278Z,1625154375.278 [DataOverHttps](INFO): Moved sent file to Logs/20210701T145446/Courier0027.lzma.bak
2021-07-01T15:46:15.278Z,1625154375.278 [DataOverHttps](INFO): SBD MOMSN=15743105
2021-07-01T15:46:30.183Z,1625154390.183 [NAL9602](INFO): SBD MO Status=0, MOMSN=28476, MT Status=0, MTMSN=0
2021-07-01T15:46:30.183Z,1625154390.183 [NAL9602](INFO): No messages in MT queue
2021-07-01T15:46:38.752Z,1625154398.752 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20210701T154542/Courier0000.lzma
2021-07-01T15:46:39.754Z,1625154399.754 [DataOverHttps](INFO): Moved sent file to Logs/20210701T154542/Courier0000.lzma.bak
2021-07-01T15:46:39.754Z,1625154399.754 [DataOverHttps](INFO): SBD MOMSN=15743107
2021-07-01T15:46:55.623Z,1625154415.623 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210701T154542/Courier0003.lzma
2021-07-01T15:46:56.625Z,1625154416.625 [DataOverHttps](INFO): Moved sent file to Logs/20210701T154542/Courier0003.lzma.bak
2021-07-01T15:46:56.626Z,1625154416.626 [DataOverHttps](INFO): SBD MOMSN=15743112
2021-07-01T15:47:00.926Z,1625154420.926 [NAL9602](INFO): Not Powering down - fast GPS
2021-07-01T15:47:14.195Z,1625154434.195 [DataOverHttps](INFO): Sending 138 bytes from file Logs/20210701T145446/Express0028.lzma
2021-07-01T15:47:15.198Z,1625154435.198 [DataOverHttps](INFO): Moved sent file to Logs/20210701T145446/Express0028.lzma.bak
2021-07-01T15:47:15.198Z,1625154435.198 [DataOverHttps](INFO): SBD MOMSN=15743114
2021-07-01T15:47:33.592Z,1625154453.592 [DataOverHttps](INFO): Sending 342 bytes from file Logs/20210701T154542/Express0001.lzma
2021-07-01T15:47:35.598Z,1625154455.598 [DataOverHttps](INFO): Moved sent file to Logs/20210701T154542/Express0001.lzma.bak
2021-07-01T15:47:35.598Z,1625154455.598 [DataOverHttps](INFO): SBD MOMSN=15743117
2021-07-01T15:47:54.300Z,1625154474.300 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20210701T154542/Express0004.lzma
2021-07-01T15:47:55.302Z,1625154475.302 [DataOverHttps](INFO): Moved sent file to Logs/20210701T154542/Express0004.lzma.bak
2021-07-01T15:47:55.302Z,1625154475.302 [DataOverHttps](INFO): SBD MOMSN=15743121
2021-07-01T15:47:58.313Z,1625154478.313 [Default:CheckIn:Read_Iridium] Stopped
2021-07-01T15:47:58.314Z,1625154478.314 [Default:CheckIn:C.Wait] Running Loop=1
2021-07-01T15:47:58.314Z,1625154478.314 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-07-01T15:51:32.853Z,1625154692.853 [CBIT](IMPORTANT): Beginning ground fault scan
2021-07-01T15:51:43.822Z,1625154703.822 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.002299
CHAN A1 (24V): -0.181194
CHAN A2 (12V): -0.002000
CHAN A3 (5V): -0.001319
CHAN B0 (3.3V): -0.000113
CHAN B1 (3.15aV): -0.000201
CHAN B2 (3.15bV): 0.000111
CHAN B3 (GND): 0.000018
OPEN: -0.000473
Full Scale Calc: 4.765 mA, -1.589 mA
2021-07-01T15:52:58.945Z,1625154778.945 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-07-01T15:52:58.945Z,1625154778.945 [Default:CheckIn:C.Wait] Stopped
2021-07-01T15:52:58.945Z,1625154778.945 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-07-01T15:52:58.946Z,1625154778.946 [Default:CheckIn:D] Running Loop=1
2021-07-01T15:52:59.357Z,1625154779.357 [Default:CheckIn:D] Stopped
2021-07-01T15:52:59.357Z,1625154779.357 [Default:CheckIn:E] Running Loop=1
2021-07-01T15:52:59.752Z,1625154779.752 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 54.571338 min 
2021-07-01T15:52:59.752Z,1625154779.752 [Default:CheckIn:E] Stopped
2021-07-01T15:52:59.752Z,1625154779.752 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-07-01T15:52:59.752Z,1625154779.752 [Default:CheckIn] Stopped
2021-07-01T15:52:59.752Z,1625154779.752 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-07-01T15:52:59.753Z,1625154779.753 [Default:CheckIn](INFO): Running loop #10
2021-07-01T15:52:59.753Z,1625154779.753 [Default:CheckIn] Running Loop=10
2021-07-01T15:52:59.753Z,1625154779.753 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-07-01T15:52:59.753Z,1625154779.753 [Default:CheckIn:Read_GPS] Running Loop=1
2021-07-01T15:53:01.767Z,1625154781.767 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155301.00,A,3648.45576,N,12149.44315,W,0.272,78.41,010721,,,A*43
2021-07-01T15:53:01.770Z,1625154781.770 [NAL9602](INFO): GPS fix at 20210701T155301: (36.807596, -121.824052)
2021-07-01T15:53:01.781Z,1625154781.781 [Default:CheckIn:Read_GPS] Stopped
2021-07-01T15:53:01.781Z,1625154781.781 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-07-01T15:53:10.547Z,1625154790.547 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20210701T154542/Courier0006.lzma
2021-07-01T15:53:11.550Z,1625154791.550 [DataOverHttps](INFO): Moved sent file to Logs/20210701T154542/Courier0006.lzma.bak
2021-07-01T15:53:11.550Z,1625154791.550 [DataOverHttps](INFO): SBD MOMSN=15743128
2021-07-01T15:53:15.907Z,1625154795.907 [NAL9602](INFO): SBD MO Status=0, MOMSN=28477, MT Status=0, MTMSN=0
2021-07-01T15:53:15.907Z,1625154795.907 [NAL9602](INFO): No messages in MT queue
2021-07-01T15:53:29.604Z,1625154809.604 [DataOverHttps](INFO): Sending 452 bytes from file Logs/20210701T154542/Express0007.lzma
2021-07-01T15:53:30.606Z,1625154810.606 [DataOverHttps](INFO): Moved sent file to Logs/20210701T154542/Express0007.lzma.bak
2021-07-01T15:53:30.606Z,1625154810.606 [DataOverHttps](INFO): SBD MOMSN=15743135
2021-07-01T15:53:33.712Z,1625154813.712 [Default:CheckIn:Read_Iridium] Stopped
2021-07-01T15:53:33.712Z,1625154813.712 [Default:CheckIn:C.Wait] Running Loop=1
2021-07-01T15:53:33.712Z,1625154813.712 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-07-01T15:53:46.621Z,1625154826.621 [NAL9602](INFO): Not Powering down - fast GPS
2021-07-01T15:56:53.771Z,1625155013.771 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2021-07-01T15:56:53.774Z,1625155013.774 [BPC1](INFO): Received data from all battery sticks.
2021-07-01T15:58:34.516Z,1625155114.516 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-07-01T15:58:34.516Z,1625155114.516 [Default:CheckIn:C.Wait] Stopped
2021-07-01T15:58:34.516Z,1625155114.516 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-07-01T15:58:34.517Z,1625155114.517 [Default:CheckIn:D] Running Loop=1
2021-07-01T15:58:34.900Z,1625155114.900 [Default:CheckIn:D] Stopped
2021-07-01T15:58:34.900Z,1625155114.900 [Default:CheckIn:E] Running Loop=1
2021-07-01T15:58:35.318Z,1625155115.318 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 60.163713 min 
2021-07-01T15:58:35.318Z,1625155115.318 [Default:CheckIn:E] Stopped
2021-07-01T15:58:35.318Z,1625155115.318 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-07-01T15:58:35.318Z,1625155115.318 [Default:CheckIn] Stopped
2021-07-01T15:58:35.318Z,1625155115.318 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-07-01T15:58:35.319Z,1625155115.319 [Default:CheckIn](INFO): Running loop #11
2021-07-01T15:58:35.319Z,1625155115.319 [Default:CheckIn] Running Loop=11
2021-07-01T15:58:35.319Z,1625155115.319 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-07-01T15:58:35.319Z,1625155115.319 [Default:CheckIn:Read_GPS] Running Loop=1
2021-07-01T15:58:37.311Z,1625155117.311 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155837.00,A,3648.47267,N,12149.38115,W,0.272,82.27,010721,,,D*41
2021-07-01T15:58:37.313Z,1625155117.313 [NAL9602](INFO): GPS fix at 20210701T155837: (36.807878, -121.823019)
2021-07-01T15:58:37.373Z,1625155117.373 [Default:CheckIn:Read_GPS] Stopped
2021-07-01T15:58:37.373Z,1625155117.373 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-07-01T15:58:45.992Z,1625155125.992 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20210701T154542/Courier0009.lzma
2021-07-01T15:58:46.994Z,1625155126.994 [DataOverHttps](INFO): Moved sent file to Logs/20210701T154542/Courier0009.lzma.bak
2021-07-01T15:58:46.994Z,1625155126.994 [DataOverHttps](INFO): SBD MOMSN=15743149
2021-07-01T15:58:51.591Z,1625155131.591 [NAL9602](INFO): SBD MO Status=0, MOMSN=28478, MT Status=0, MTMSN=0
2021-07-01T15:58:51.591Z,1625155131.591 [NAL9602](INFO): No messages in MT queue
2021-07-01T15:59:04.820Z,1625155144.820 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20210701T154542/Express0010.lzma
2021-07-01T15:59:05.822Z,1625155145.822 [DataOverHttps](INFO): Moved sent file to Logs/20210701T154542/Express0010.lzma.bak
2021-07-01T15:59:05.822Z,1625155145.822 [DataOverHttps](INFO): SBD MOMSN=15743152
2021-07-01T15:59:08.616Z,1625155148.616 [Default:CheckIn:Read_Iridium] Stopped
2021-07-01T15:59:08.616Z,1625155148.616 [Default:CheckIn:C.Wait] Running Loop=1
2021-07-01T15:59:08.616Z,1625155148.616 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-07-01T15:59:22.313Z,1625155162.313 [NAL9602](INFO): Not Powering down - fast GPS
2021-07-01T16:04:09.287Z,1625155449.287 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-07-01T16:04:09.287Z,1625155449.287 [Default:CheckIn:C.Wait] Stopped
2021-07-01T16:04:09.287Z,1625155449.287 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-07-01T16:04:09.288Z,1625155449.288 [Default:CheckIn:D] Running Loop=1
2021-07-01T16:04:09.676Z,1625155449.676 [Default:CheckIn:D] Stopped
2021-07-01T16:04:09.676Z,1625155449.676 [Default:CheckIn:E] Running Loop=1
2021-07-01T16:04:10.081Z,1625155450.081 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 65.743311 min 
2021-07-01T16:04:10.082Z,1625155450.082 [Default:CheckIn:E] Stopped
2021-07-01T16:04:10.082Z,1625155450.082 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-07-01T16:04:10.082Z,1625155450.082 [Default:CheckIn] Stopped
2021-07-01T16:04:10.082Z,1625155450.082 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-07-01T16:04:10.082Z,1625155450.082 [Default:CheckIn](INFO): Running loop #12
2021-07-01T16:04:10.082Z,1625155450.082 [Default:CheckIn] Running Loop=12
2021-07-01T16:04:10.082Z,1625155450.082 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-07-01T16:04:10.082Z,1625155450.082 [Default:CheckIn:Read_GPS] Running Loop=1
2021-07-01T16:04:12.099Z,1625155452.099 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160411.00,A,3648.40138,N,12149.17806,W,4.568,117.10,010721,,,A*73
2021-07-01T16:04:12.101Z,1625155452.101 [NAL9602](INFO): GPS fix at 20210701T160411: (36.806690, -121.819634)
2021-07-01T16:04:12.113Z,1625155452.113 [Default:CheckIn:Read_GPS] Stopped
2021-07-01T16:04:12.113Z,1625155452.113 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-07-01T16:04:22.372Z,1625155462.372 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-07-01T16:04:53.521Z,1625155493.521 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-07-01T16:05:12.291Z,1625155512.291 [NAL9602](INFO): SBD MO Status=0, MOMSN=28479, MT Status=0, MTMSN=0
2021-07-01T16:05:12.291Z,1625155512.291 [NAL9602](INFO): No messages in MT queue
2021-07-01T16:05:24.648Z,1625155524.648 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-07-01T16:05:42.993Z,1625155542.993 [NAL9602](INFO): Not Powering down - fast GPS
2021-07-01T16:05:55.776Z,1625155555.776 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-07-01T16:06:26.900Z,1625155586.900 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-07-01T16:06:29.913Z,1625155589.913 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.903564
2021-07-01T16:07:01.347Z,1625155621.347 [NAL9602](INFO): SBD MO Status=2, MOMSN=28480, MT Status=2, MTMSN=0
2021-07-01T16:07:01.347Z,1625155621.347 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-07-01T16:07:14.688Z,1625155634.688 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003281
2021-07-01T16:07:22.071Z,1625155642.071 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210701T154542/Courier0012.lzma
2021-07-01T16:07:23.074Z,1625155643.074 [DataOverHttps](INFO): Moved sent file to Logs/20210701T154542/Courier0012.lzma.bak
2021-07-01T16:07:23.074Z,1625155643.074 [DataOverHttps](INFO): SBD MOMSN=15743185
2021-07-01T16:07:31.818Z,1625155651.818 [NAL9602](INFO): SBD MO Status=2, MOMSN=28480, MT Status=2, MTMSN=0
2021-07-01T16:07:31.818Z,1625155651.818 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-07-01T16:07:42.313Z,1625155662.313 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20210701T154542/Express0013.lzma
2021-07-01T16:07:43.038Z,1625155663.038 [DataOverHttps](INFO): Moved sent file to Logs/20210701T154542/Express0013.lzma.bak
2021-07-01T16:07:43.038Z,1625155663.038 [DataOverHttps](INFO): SBD MOMSN=15743189
2021-07-01T16:07:46.012Z,1625155666.012 [Default:CheckIn:Read_Iridium] Stopped
2021-07-01T16:07:46.013Z,1625155666.013 [Default:CheckIn:C.Wait] Running Loop=1
2021-07-01T16:07:46.013Z,1625155666.013 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-07-01T16:08:02.525Z,1625155682.525 [NAL9602](INFO): Not Powering down - fast GPS
2021-07-01T16:12:46.672Z,1625155966.672 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-07-01T16:12:46.673Z,1625155966.673 [Default:CheckIn:C.Wait] Stopped
2021-07-01T16:12:46.673Z,1625155966.673 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-07-01T16:12:46.673Z,1625155966.673 [Default:CheckIn:D] Running Loop=1
2021-07-01T16:12:47.063Z,1625155967.063 [Default:CheckIn:D] Stopped
2021-07-01T16:12:47.064Z,1625155967.064 [Default:CheckIn:E] Running Loop=1
2021-07-01T16:12:47.468Z,1625155967.468 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 74.366439 min 
2021-07-01T16:12:47.468Z,1625155967.468 [Default:CheckIn:E] Stopped
2021-07-01T16:12:47.468Z,1625155967.468 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-07-01T16:12:47.468Z,1625155967.468 [Default:CheckIn] Stopped
2021-07-01T16:12:47.468Z,1625155967.468 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-07-01T16:12:47.468Z,1625155967.468 [Default:CheckIn](INFO): Running loop #13
2021-07-01T16:12:47.468Z,1625155967.468 [Default:CheckIn] Running Loop=13
2021-07-01T16:12:47.469Z,1625155967.469 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-07-01T16:12:47.469Z,1625155967.469 [Default:CheckIn:Read_GPS] Running Loop=1
2021-07-01T16:12:49.487Z,1625155969.487 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161249.00,A,3648.22175,N,12148.43514,W,4.296,110.70,010721,,,D*78
2021-07-01T16:12:49.490Z,1625155969.490 [NAL9602](INFO): GPS fix at 20210701T161249: (36.803696, -121.807252)
2021-07-01T16:12:49.501Z,1625155969.501 [Default:CheckIn:Read_GPS] Stopped
2021-07-01T16:12:49.501Z,1625155969.501 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-07-01T16:12:57.683Z,1625155977.683 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210701T154542/Courier0015.lzma
2021-07-01T16:12:58.686Z,1625155978.686 [DataOverHttps](INFO): Moved sent file to Logs/20210701T154542/Courier0015.lzma.bak
2021-07-01T16:12:58.686Z,1625155978.686 [DataOverHttps](INFO): SBD MOMSN=15743208
2021-07-01T16:13:16.352Z,1625155996.352 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20210701T154542/Express0016.lzma
2021-07-01T16:13:17.354Z,1625155997.354 [DataOverHttps](INFO): Moved sent file to Logs/20210701T154542/Express0016.lzma.bak
2021-07-01T16:13:17.354Z,1625155997.354 [DataOverHttps](INFO): SBD MOMSN=15743214
2021-07-01T16:13:20.206Z,1625156000.206 [Default:CheckIn:Read_Iridium] Stopped
2021-07-01T16:13:20.206Z,1625156000.206 [Default:CheckIn:C.Wait] Running Loop=1
2021-07-01T16:13:20.207Z,1625156000.207 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-07-01T16:14:05.843Z,1625156045.843 [NAL9602](INFO): SBD MO Status=2, MOMSN=28480, MT Status=2, MTMSN=0
2021-07-01T16:14:05.843Z,1625156045.843 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-07-01T16:14:23.211Z,1625156063.211 [NAL9602](INFO): SBD MO Status=1, MOMSN=28480, MT Status=0, MTMSN=0
2021-07-01T16:14:23.211Z,1625156063.211 [NAL9602](INFO): No messages in MT queue
2021-07-01T16:14:53.910Z,1625156093.910 [NAL9602](INFO): Not Powering down - fast GPS
2021-07-01T16:18:20.801Z,1625156300.801 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-07-01T16:18:20.801Z,1625156300.801 [Default:CheckIn:C.Wait] Stopped
2021-07-01T16:18:20.801Z,1625156300.801 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-07-01T16:18:20.801Z,1625156300.801 [Default:CheckIn:D] Running Loop=1
2021-07-01T16:18:21.182Z,1625156301.182 [Default:CheckIn:D] Stopped
2021-07-01T16:18:21.182Z,1625156301.182 [Default:CheckIn:E] Running Loop=1
2021-07-01T16:18:21.590Z,1625156301.590 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 79.935083 min 
2021-07-01T16:18:21.590Z,1625156301.590 [Default:CheckIn:E] Stopped
2021-07-01T16:18:21.590Z,1625156301.590 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-07-01T16:18:21.590Z,1625156301.590 [Default:CheckIn] Stopped
2021-07-01T16:18:21.590Z,1625156301.590 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-07-01T16:18:21.591Z,1625156301.591 [Default:CheckIn](INFO): Running loop #14
2021-07-01T16:18:21.591Z,1625156301.591 [Default:CheckIn] Running Loop=14
2021-07-01T16:18:21.591Z,1625156301.591 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-07-01T16:18:21.591Z,1625156301.591 [Default:CheckIn:Read_GPS] Running Loop=1
2021-07-01T16:18:23.595Z,1625156303.595 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161823.00,A,3648.16711,N,12147.87257,W,6.162,83.77,010721,,,A*49
2021-07-01T16:18:23.597Z,1625156303.597 [NAL9602](INFO): GPS fix at 20210701T161823: (36.802785, -121.797876)
2021-07-01T16:18:23.640Z,1625156303.640 [Default:CheckIn:Read_GPS] Stopped
2021-07-01T16:18:23.640Z,1625156303.640 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-07-01T16:18:35.916Z,1625156315.916 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210701T154542/Courier0018.lzma
2021-07-01T16:18:36.918Z,1625156316.918 [DataOverHttps](INFO): Moved sent file to Logs/20210701T154542/Courier0018.lzma.bak
2021-07-01T16:18:36.918Z,1625156316.918 [DataOverHttps](INFO): SBD MOMSN=15743264
2021-07-01T16:18:45.487Z,1625156325.487 [NAL9602](INFO): SBD MO Status=1, MOMSN=28481, MT Status=0, MTMSN=0
2021-07-01T16:18:45.487Z,1625156325.487 [NAL9602](INFO): No messages in MT queue
2021-07-01T16:18:54.608Z,1625156334.608 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20210701T154542/Express0019.lzma
2021-07-01T16:18:55.610Z,1625156335.610 [DataOverHttps](INFO): Moved sent file to Logs/20210701T154542/Express0019.lzma.bak
2021-07-01T16:18:55.610Z,1625156335.610 [DataOverHttps](INFO): SBD MOMSN=15743267
2021-07-01T16:18:59.272Z,1625156339.272 [Default:CheckIn:Read_Iridium] Stopped
2021-07-01T16:18:59.273Z,1625156339.273 [Default:CheckIn:C.Wait] Running Loop=1
2021-07-01T16:18:59.273Z,1625156339.273 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-07-01T16:19:16.189Z,1625156356.189 [NAL9602](INFO): Not Powering down - fast GPS
2021-07-01T16:23:59.846Z,1625156639.846 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-07-01T16:23:59.846Z,1625156639.846 [Default:CheckIn:C.Wait] Stopped
2021-07-01T16:23:59.846Z,1625156639.846 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-07-01T16:23:59.846Z,1625156639.846 [Default:CheckIn:D] Running Loop=1
2021-07-01T16:24:00.262Z,1625156640.262 [Default:CheckIn:D] Stopped
2021-07-01T16:24:00.262Z,1625156640.262 [Default:CheckIn:E] Running Loop=1
2021-07-01T16:24:00.651Z,1625156640.651 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 85.586418 min 
2021-07-01T16:24:00.651Z,1625156640.651 [Default:CheckIn:E] Stopped
2021-07-01T16:24:00.651Z,1625156640.651 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-07-01T16:24:00.651Z,1625156640.651 [Default:CheckIn] Stopped
2021-07-01T16:24:00.651Z,1625156640.651 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-07-01T16:24:00.652Z,1625156640.652 [Default:CheckIn](INFO): Running loop #15
2021-07-01T16:24:00.652Z,1625156640.652 [Default:CheckIn] Running Loop=15
2021-07-01T16:24:00.652Z,1625156640.652 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-07-01T16:24:00.652Z,1625156640.652 [Default:CheckIn:Read_GPS] Running Loop=1
2021-07-01T16:24:02.667Z,1625156642.667 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162402.00,A,3648.31816,N,12147.50467,W,4.335,61.98,010721,,,A*48
2021-07-01T16:24:02.669Z,1625156642.669 [NAL9602](INFO): GPS fix at 20210701T162402: (36.805303, -121.791744)
2021-07-01T16:24:02.681Z,1625156642.681 [Default:CheckIn:Read_GPS] Stopped
2021-07-01T16:24:02.681Z,1625156642.681 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-07-01T16:24:11.535Z,1625156651.535 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210701T154542/Courier0021.lzma
2021-07-01T16:24:12.538Z,1625156652.538 [DataOverHttps](INFO): Moved sent file to Logs/20210701T154542/Courier0021.lzma.bak
2021-07-01T16:24:12.538Z,1625156652.538 [DataOverHttps](INFO): SBD MOMSN=15743281
2021-07-01T16:24:20.099Z,1625156660.099 [NAL9602](INFO): SBD MO Status=1, MOMSN=28482, MT Status=0, MTMSN=0
2021-07-01T16:24:20.099Z,1625156660.099 [NAL9602](INFO): No messages in MT queue
2021-07-01T16:24:35.676Z,1625156675.676 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20210701T154542/Express0022.lzma
2021-07-01T16:24:36.678Z,1625156676.678 [DataOverHttps](INFO): Moved sent file to Logs/20210701T154542/Express0022.lzma.bak
2021-07-01T16:24:36.678Z,1625156676.678 [DataOverHttps](INFO): SBD MOMSN=15743284
2021-07-01T16:24:41.640Z,1625156681.640 [Default:CheckIn:Read_Iridium] Stopped
2021-07-01T16:24:41.640Z,1625156681.640 [Default:CheckIn:C.Wait] Running Loop=1
2021-07-01T16:24:41.640Z,1625156681.640 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-07-01T16:24:50.889Z,1625156690.889 [NAL9602](INFO): Not Powering down - fast GPS
2021-07-01T16:29:42.341Z,1625156982.341 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-07-01T16:29:42.341Z,1625156982.341 [Default:CheckIn:C.Wait] Stopped
2021-07-01T16:29:42.341Z,1625156982.341 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-07-01T16:29:42.341Z,1625156982.341 [Default:CheckIn:D] Running Loop=1
2021-07-01T16:29:42.731Z,1625156982.731 [Default:CheckIn:D] Stopped
2021-07-01T16:29:42.731Z,1625156982.731 [Default:CheckIn:E] Running Loop=1
2021-07-01T16:29:43.136Z,1625156983.136 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 91.294230 min 
2021-07-01T16:29:43.136Z,1625156983.136 [Default:CheckIn:E] Stopped
2021-07-01T16:29:43.136Z,1625156983.136 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-07-01T16:29:43.136Z,1625156983.136 [Default:CheckIn] Stopped
2021-07-01T16:29:43.136Z,1625156983.136 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-07-01T16:29:43.137Z,1625156983.137 [Default:CheckIn](INFO): Running loop #16
2021-07-01T16:29:43.137Z,1625156983.137 [Default:CheckIn] Running Loop=16
2021-07-01T16:29:43.137Z,1625156983.137 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-07-01T16:29:43.137Z,1625156983.137 [Default:CheckIn:Read_GPS] Running Loop=1
2021-07-01T16:29:45.155Z,1625156985.155 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162945.00,A,3648.48830,N,12147.11313,W,1.322,170.04,010721,,,D*7F
2021-07-01T16:29:45.157Z,1625156985.157 [NAL9602](INFO): GPS fix at 20210701T162945: (36.808138, -121.785219)
2021-07-01T16:29:45.168Z,1625156985.168 [Default:CheckIn:Read_GPS] Stopped
2021-07-01T16:29:45.168Z,1625156985.168 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-07-01T16:29:53.667Z,1625156993.667 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20210701T154542/Courier0024.lzma
2021-07-01T16:29:54.670Z,1625156994.670 [DataOverHttps](INFO): Moved sent file to Logs/20210701T154542/Courier0024.lzma.bak
2021-07-01T16:29:54.670Z,1625156994.670 [DataOverHttps](INFO): SBD MOMSN=15743295
2021-07-01T16:30:12.240Z,1625157012.240 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20210701T154542/Express0025.lzma
2021-07-01T16:30:13.242Z,1625157013.242 [DataOverHttps](INFO): Moved sent file to Logs/20210701T154542/Express0025.lzma.bak
2021-07-01T16:30:13.242Z,1625157013.242 [DataOverHttps](INFO): SBD MOMSN=15743298
2021-07-01T16:30:16.825Z,1625157016.825 [Default:CheckIn:Read_Iridium] Stopped
2021-07-01T16:30:16.825Z,1625157016.825 [Default:CheckIn:C.Wait] Running Loop=1
2021-07-01T16:30:16.825Z,1625157016.825 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-07-01T16:30:22.445Z,1625157022.445 [NAL9602](INFO): SBD MO Status=2, MOMSN=28483, MT Status=2, MTMSN=0
2021-07-01T16:30:22.445Z,1625157022.445 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-07-01T16:32:22.958Z,1625157142.958 [NAL9602](INFO): SBD MO Status=2, MOMSN=28483, MT Status=2, MTMSN=0
2021-07-01T16:32:22.959Z,1625157142.959 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-07-01T16:32:31.855Z,1625157151.855 [NAL9602](INFO): SBD MO Status=1, MOMSN=28483, MT Status=0, MTMSN=0
2021-07-01T16:32:31.855Z,1625157151.855 [NAL9602](INFO): No messages in MT queue
2021-07-01T16:33:02.553Z,1625157182.553 [NAL9602](INFO): Not Powering down - fast GPS
2021-07-01T16:35:17.495Z,1625157317.495 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-07-01T16:35:17.495Z,1625157317.495 [Default:CheckIn:C.Wait] Stopped
2021-07-01T16:35:17.495Z,1625157317.495 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-07-01T16:35:17.495Z,1625157317.495 [Default:CheckIn:D] Running Loop=1
2021-07-01T16:35:17.901Z,1625157317.901 [Default:CheckIn:D] Stopped
2021-07-01T16:35:17.901Z,1625157317.901 [Default:CheckIn:E] Running Loop=1
2021-07-01T16:35:18.322Z,1625157318.322 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 96.880404 min 
2021-07-01T16:35:18.322Z,1625157318.322 [Default:CheckIn:E] Stopped
2021-07-01T16:35:18.322Z,1625157318.322 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-07-01T16:35:18.322Z,1625157318.322 [Default:CheckIn] Stopped
2021-07-01T16:35:18.322Z,1625157318.322 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-07-01T16:35:18.322Z,1625157318.322 [Default:CheckIn](INFO): Running loop #17
2021-07-01T16:35:18.322Z,1625157318.322 [Default:CheckIn] Running Loop=17
2021-07-01T16:35:18.322Z,1625157318.322 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-07-01T16:35:18.323Z,1625157318.323 [Default:CheckIn:Read_GPS] Running Loop=1
2021-07-01T16:35:20.319Z,1625157320.319 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163520.00,A,3648.17585,N,12147.16865,W,2.799,205.43,010721,,,D*70
2021-07-01T16:35:20.322Z,1625157320.322 [NAL9602](INFO): GPS fix at 20210701T163520: (36.802931, -121.786144)
2021-07-01T16:35:20.353Z,1625157320.353 [Default:CheckIn:Read_GPS] Stopped
2021-07-01T16:35:20.353Z,1625157320.353 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-07-01T16:35:28.727Z,1625157328.727 [DataOverHttps](INFO): Sending 70 bytes from file Logs/20210701T154542/Courier0027.lzma
2021-07-01T16:35:29.732Z,1625157329.732 [DataOverHttps](INFO): Moved sent file to Logs/20210701T154542/Courier0027.lzma.bak
2021-07-01T16:35:29.733Z,1625157329.733 [DataOverHttps](INFO): SBD MOMSN=15743312
2021-07-01T16:35:45.363Z,1625157345.363 [NAL9602](INFO): SBD MO Status=1, MOMSN=28484, MT Status=0, MTMSN=0
2021-07-01T16:35:45.363Z,1625157345.363 [NAL9602](INFO): No messages in MT queue
2021-07-01T16:35:47.304Z,1625157347.304 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20210701T154542/Express0028.lzma
2021-07-01T16:35:48.306Z,1625157348.306 [DataOverHttps](INFO): Moved sent file to Logs/20210701T154542/Express0028.lzma.bak
2021-07-01T16:35:48.306Z,1625157348.306 [DataOverHttps](INFO): SBD MOMSN=15743315
2021-07-01T16:35:51.040Z,1625157351.040 [Default:CheckIn:Read_Iridium] Stopped
2021-07-01T16:35:51.040Z,1625157351.040 [Default:CheckIn:C.Wait] Running Loop=1
2021-07-01T16:35:51.040Z,1625157351.040 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-07-01T16:36:16.069Z,1625157376.069 [NAL9602](INFO): Not Powering down - fast GPS
2021-07-01T16:40:51.619Z,1625157651.619 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-07-01T16:40:51.619Z,1625157651.619 [Default:CheckIn:C.Wait] Stopped
2021-07-01T16:40:51.619Z,1625157651.619 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-07-01T16:40:51.620Z,1625157651.620 [Default:CheckIn:D] Running Loop=1
2021-07-01T16:40:52.052Z,1625157652.052 [Default:CheckIn:D] Stopped
2021-07-01T16:40:52.052Z,1625157652.052 [Default:CheckIn:E] Running Loop=1
2021-07-01T16:40:52.420Z,1625157652.420 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 102.449585 min 
2021-07-01T16:40:52.420Z,1625157652.420 [Default:CheckIn:E] Stopped
2021-07-01T16:40:52.420Z,1625157652.420 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-07-01T16:40:52.420Z,1625157652.420 [Default:CheckIn] Stopped
2021-07-01T16:40:52.421Z,1625157652.421 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-07-01T16:40:52.421Z,1625157652.421 [Default:CheckIn](INFO): Running loop #18
2021-07-01T16:40:52.421Z,1625157652.421 [Default:CheckIn] Running Loop=18
2021-07-01T16:40:52.421Z,1625157652.421 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-07-01T16:40:52.421Z,1625157652.421 [Default:CheckIn:Read_GPS] Running Loop=1
2021-07-01T16:40:54.435Z,1625157654.435 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164054.00,A,3648.13163,N,12147.20997,W,0.194,300.88,010721,,,D*7A
2021-07-01T16:40:54.438Z,1625157654.438 [NAL9602](INFO): GPS fix at 20210701T164054: (36.802194, -121.786833)
2021-07-01T16:40:54.448Z,1625157654.448 [Default:CheckIn:Read_GPS] Stopped
2021-07-01T16:40:54.449Z,1625157654.449 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-07-01T16:41:03.363Z,1625157663.363 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210701T154542/Courier0030.lzma
2021-07-01T16:41:04.366Z,1625157664.366 [DataOverHttps](INFO): Moved sent file to Logs/20210701T154542/Courier0030.lzma.bak
2021-07-01T16:41:04.366Z,1625157664.366 [DataOverHttps](INFO): SBD MOMSN=15743329
2021-07-01T16:41:21.956Z,1625157681.956 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20210701T154542/Express0031.lzma
2021-07-01T16:41:22.950Z,1625157682.950 [DataOverHttps](INFO): Moved sent file to Logs/20210701T154542/Express0031.lzma.bak
2021-07-01T16:41:22.950Z,1625157682.950 [DataOverHttps](INFO): SBD MOMSN=15743332
2021-07-01T16:41:25.992Z,1625157685.992 [Default:CheckIn:Read_Iridium] Stopped
2021-07-01T16:41:25.993Z,1625157685.993 [Default:CheckIn:C.Wait] Running Loop=1
2021-07-01T16:41:25.993Z,1625157685.993 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-07-01T16:41:43.719Z,1625157703.719 [NAL9602](INFO): SBD MO Status=2, MOMSN=28485, MT Status=2, MTMSN=0
2021-07-01T16:41:43.719Z,1625157703.719 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-07-01T16:42:18.059Z,1625157738.059 [NAL9602](INFO): SBD MO Status=2, MOMSN=28485, MT Status=2, MTMSN=0
2021-07-01T16:42:18.059Z,1625157738.059 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-07-01T16:42:35.041Z,1625157755.041 [NAL9602](INFO): SBD MO Status=2, MOMSN=28485, MT Status=2, MTMSN=0
2021-07-01T16:42:35.042Z,1625157755.042 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-07-01T16:45:56.635Z,1625157956.635 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-07-01T16:46:26.549Z,1625157986.549 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-07-01T16:46:26.549Z,1625157986.549 [Default:CheckIn:C.Wait] Stopped
2021-07-01T16:46:26.549Z,1625157986.549 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-07-01T16:46:26.549Z,1625157986.549 [Default:CheckIn:D] Running Loop=1
2021-07-01T16:46:26.954Z,1625157986.954 [Default:CheckIn:D] Stopped
2021-07-01T16:46:26.954Z,1625157986.954 [Default:CheckIn:E] Running Loop=1
2021-07-01T16:46:27.341Z,1625157987.341 [NAL9602](INFO): Not Powering down - fast GPS
2021-07-01T16:46:27.358Z,1625157987.358 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 108.031283 min 
2021-07-01T16:46:27.358Z,1625157987.358 [Default:CheckIn:E] Stopped
2021-07-01T16:46:27.358Z,1625157987.358 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-07-01T16:46:27.358Z,1625157987.358 [Default:CheckIn] Stopped
2021-07-01T16:46:27.358Z,1625157987.358 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-07-01T16:46:27.359Z,1625157987.359 [Default:CheckIn](INFO): Running loop #19
2021-07-01T16:46:27.359Z,1625157987.359 [Default:CheckIn] Running Loop=19
2021-07-01T16:46:27.359Z,1625157987.359 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-07-01T16:46:27.359Z,1625157987.359 [Default:CheckIn:Read_GPS] Running Loop=1
2021-07-01T16:46:29.363Z,1625157989.363 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164629.00,A,3648.12974,N,12147.20941,W,0.156,300.88,010721,,,D*7C
2021-07-01T16:46:29.365Z,1625157989.365 [NAL9602](INFO): GPS fix at 20210701T164629: (36.802162, -121.786823)
2021-07-01T16:46:29.401Z,1625157989.401 [Default:CheckIn:Read_GPS] Stopped
2021-07-01T16:46:29.401Z,1625157989.401 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-07-01T16:46:39.379Z,1625157999.379 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210701T154542/Courier0033.lzma
2021-07-01T16:46:40.382Z,1625158000.382 [DataOverHttps](INFO): Moved sent file to Logs/20210701T154542/Courier0033.lzma.bak
2021-07-01T16:46:40.382Z,1625158000.382 [DataOverHttps](INFO): SBD MOMSN=15743348
2021-07-01T16:46:58.056Z,1625158018.056 [DataOverHttps](INFO): Sending 233 bytes from file Logs/20210701T154542/Express0034.lzma
2021-07-01T16:46:59.058Z,1625158019.058 [DataOverHttps](INFO): Moved sent file to Logs/20210701T154542/Express0034.lzma.bak
2021-07-01T16:46:59.058Z,1625158019.058 [DataOverHttps](INFO): SBD MOMSN=15743352
2021-07-01T16:47:00.108Z,1625158020.108 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2021-07-01T16:47:00.188Z,1625158020.188 [NAL9602](ERROR): received: 
+CSQ:0

OK485, 2, 0, 0, 0

OK
2021-07-01T16:47:02.114Z,1625158022.114 [Default:CheckIn:Read_Iridium] Stopped
2021-07-01T16:47:02.114Z,1625158022.114 [Default:CheckIn:C.Wait] Running Loop=1
2021-07-01T16:47:02.114Z,1625158022.114 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-07-01T16:51:30.797Z,1625158290.797 [NAL9602](INFO): SBD MO Status=2, MOMSN=28485, MT Status=2, MTMSN=0
2021-07-01T16:51:30.798Z,1625158290.798 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-07-01T16:51:31.601Z,1625158291.601 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-07-01T16:52:02.301Z,1625158322.301 [NAL9602](INFO): Not Powering down - fast GPS
2021-07-01T16:52:02.721Z,1625158322.721 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-07-01T16:52:02.722Z,1625158322.722 [Default:CheckIn:C.Wait] Stopped
2021-07-01T16:52:02.722Z,1625158322.722 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-07-01T16:52:02.722Z,1625158322.722 [Default:CheckIn:D] Running Loop=1
2021-07-01T16:52:03.133Z,1625158323.133 [Default:CheckIn:D] Stopped
2021-07-01T16:52:03.133Z,1625158323.133 [Default:CheckIn:E] Running Loop=1
2021-07-01T16:52:03.547Z,1625158323.547 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 113.634269 min 
2021-07-01T16:52:03.547Z,1625158323.547 [Default:CheckIn:E] Stopped
2021-07-01T16:52:03.547Z,1625158323.547 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-07-01T16:52:03.547Z,1625158323.547 [Default:CheckIn] Stopped
2021-07-01T16:52:03.547Z,1625158323.547 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-07-01T16:52:03.547Z,1625158323.547 [Default:CheckIn](INFO): Running loop #20
2021-07-01T16:52:03.548Z,1625158323.548 [Default:CheckIn] Running Loop=20
2021-07-01T16:52:03.548Z,1625158323.548 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-07-01T16:52:03.548Z,1625158323.548 [Default:CheckIn:Read_GPS] Running Loop=1
2021-07-01T16:52:05.535Z,1625158325.535 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165205.00,A,3648.13857,N,12147.20739,W,0.330,334.63,010721,,,D*77
2021-07-01T16:52:05.537Z,1625158325.537 [NAL9602](INFO): GPS fix at 20210701T165205: (36.802309, -121.786790)
2021-07-01T16:52:05.549Z,1625158325.549 [Default:CheckIn:Read_GPS] Stopped
2021-07-01T16:52:05.549Z,1625158325.549 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-07-01T16:52:13.927Z,1625158333.927 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210701T154542/Courier0036.lzma
2021-07-01T16:52:14.930Z,1625158334.930 [DataOverHttps](INFO): Moved sent file to Logs/20210701T154542/Courier0036.lzma.bak
2021-07-01T16:52:14.930Z,1625158334.930 [DataOverHttps](INFO): SBD MOMSN=15743368
2021-07-01T16:52:35.969Z,1625158355.969 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2021-07-01T16:52:36.052Z,1625158356.052 [NAL9602](ERROR): received: 
+SBDI: 2, 28485, 2, 0, 0, 0

OK
2021-07-01T16:52:40.192Z,1625158360.192 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20210701T154542/Express0037.lzma
2021-07-01T16:52:41.194Z,1625158361.194 [DataOverHttps](INFO): Moved sent file to Logs/20210701T154542/Express0037.lzma.bak
2021-07-01T16:52:41.194Z,1625158361.194 [DataOverHttps](INFO): SBD MOMSN=15743371
2021-07-01T16:52:44.940Z,1625158364.940 [Default:CheckIn:Read_Iridium] Stopped
2021-07-01T16:52:44.941Z,1625158364.941 [Default:CheckIn:C.Wait] Running Loop=1
2021-07-01T16:52:44.941Z,1625158364.941 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-07-01T16:53:02.247Z,1625158382.247 [NAL9602](INFO): SBD MO Status=1, MOMSN=28485, MT Status=0, MTMSN=0
2021-07-01T16:53:02.247Z,1625158382.247 [NAL9602](INFO): No messages in MT queue
2021-07-01T16:53:32.953Z,1625158412.953 [NAL9602](INFO): Not Powering down - fast GPS
2021-07-01T16:57:45.551Z,1625158665.551 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-07-01T16:57:45.551Z,1625158665.551 [Default:CheckIn:C.Wait] Stopped
2021-07-01T16:57:45.551Z,1625158665.551 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-07-01T16:57:45.552Z,1625158665.552 [Default:CheckIn:D] Running Loop=1
2021-07-01T16:57:45.953Z,1625158665.953 [Default:CheckIn:D] Stopped
2021-07-01T16:57:45.953Z,1625158665.953 [Default:CheckIn:E] Running Loop=1
2021-07-01T16:57:46.369Z,1625158666.369 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 119.347933 min 
2021-07-01T16:57:46.369Z,1625158666.369 [Default:CheckIn:E] Stopped
2021-07-01T16:57:46.369Z,1625158666.369 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-07-01T16:57:46.369Z,1625158666.369 [Default:CheckIn] Stopped
2021-07-01T16:57:46.369Z,1625158666.369 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-07-01T16:57:46.370Z,1625158666.370 [Default:CheckIn](INFO): Running loop #21
2021-07-01T16:57:46.370Z,1625158666.370 [Default:CheckIn] Running Loop=21
2021-07-01T16:57:46.370Z,1625158666.370 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-07-01T16:57:46.370Z,1625158666.370 [Default:CheckIn:Read_GPS] Running Loop=1
2021-07-01T16:57:48.367Z,1625158668.367 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165748.00,A,3648.16781,N,12147.27859,W,0.525,192.96,010721,,,A*77
2021-07-01T16:57:48.369Z,1625158668.369 [NAL9602](INFO): GPS fix at 20210701T165748: (36.802797, -121.787976)
2021-07-01T16:57:48.381Z,1625158668.381 [Default:CheckIn:Read_GPS] Stopped
2021-07-01T16:57:48.381Z,1625158668.381 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-07-01T16:57:56.991Z,1625158676.991 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20210701T154542/Courier0039.lzma
2021-07-01T16:57:57.994Z,1625158677.994 [DataOverHttps](INFO): Moved sent file to Logs/20210701T154542/Courier0039.lzma.bak
2021-07-01T16:57:57.994Z,1625158677.994 [DataOverHttps](INFO): SBD MOMSN=15743431
2021-07-01T16:58:15.560Z,1625158695.560 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20210701T154542/Express0040.lzma
2021-07-01T16:58:16.562Z,1625158696.562 [DataOverHttps](INFO): Moved sent file to Logs/20210701T154542/Express0040.lzma.bak
2021-07-01T16:58:16.562Z,1625158696.562 [DataOverHttps](INFO): SBD MOMSN=15743434
2021-07-01T16:58:20.762Z,1625158700.762 [Default:CheckIn:Read_Iridium] Stopped
2021-07-01T16:58:20.762Z,1625158700.762 [Default:CheckIn:C.Wait] Running Loop=1
2021-07-01T16:58:20.762Z,1625158700.762 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-07-01T17:02:50.761Z,1625158970.761 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-07-01T17:03:21.461Z,1625159001.461 [NAL9602](INFO): Not Powering down - fast GPS
2021-07-01T17:03:21.481Z,1625159001.481 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-07-01T17:03:21.481Z,1625159001.481 [Default:CheckIn:C.Wait] Stopped
2021-07-01T17:03:21.481Z,1625159001.481 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-07-01T17:03:21.481Z,1625159001.481 [Default:CheckIn:D] Running Loop=1
2021-07-01T17:03:21.903Z,1625159001.903 [Default:CheckIn:D] Stopped
2021-07-01T17:03:21.903Z,1625159001.903 [Default:CheckIn:E] Running Loop=1
2021-07-01T17:03:22.294Z,1625159002.294 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 124.947103 min 
2021-07-01T17:03:22.294Z,1625159002.294 [Default:CheckIn:E] Stopped
2021-07-01T17:03:22.295Z,1625159002.295 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-07-01T17:03:22.295Z,1625159002.295 [Default:CheckIn] Stopped
2021-07-01T17:03:22.295Z,1625159002.295 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-07-01T17:03:22.295Z,1625159002.295 [Default:CheckIn](INFO): Running loop #22
2021-07-01T17:03:22.295Z,1625159002.295 [Default:CheckIn] Running Loop=22
2021-07-01T17:03:22.295Z,1625159002.295 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-07-01T17:03:22.295Z,1625159002.295 [Default:CheckIn:Read_GPS] Running Loop=1
2021-07-01T17:03:24.357Z,1625159004.357 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170324.00,A,3648.17167,N,12147.33994,W,8.942,286.48,010721,,,A*77
2021-07-01T17:03:24.359Z,1625159004.359 [NAL9602](INFO): GPS fix at 20210701T170324: (36.802861, -121.788999)
2021-07-01T17:03:24.387Z,1625159004.387 [Default:CheckIn:Read_GPS] Stopped
2021-07-01T17:03:24.387Z,1625159004.387 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-07-01T17:03:32.731Z,1625159012.731 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210701T154542/Courier0042.lzma
2021-07-01T17:03:33.735Z,1625159013.735 [DataOverHttps](INFO): Moved sent file to Logs/20210701T154542/Courier0042.lzma.bak
2021-07-01T17:03:33.735Z,1625159013.735 [DataOverHttps](INFO): SBD MOMSN=15743457
2021-07-01T17:03:51.736Z,1625159031.736 [DataOverHttps](INFO): Sending 218 bytes from file Logs/20210701T154542/Express0043.lzma
2021-07-01T17:03:52.738Z,1625159032.738 [DataOverHttps](INFO): Moved sent file to Logs/20210701T154542/Express0043.lzma.bak
2021-07-01T17:03:52.738Z,1625159032.738 [DataOverHttps](INFO): SBD MOMSN=15743460
2021-07-01T17:03:54.993Z,1625159034.993 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2021-07-01T17:03:55.068Z,1625159035.068 [NAL9602](ERROR): received: 
+CSQ:0

OK485, 0, 0, 0, 0

OK
2021-07-01T17:03:55.865Z,1625159035.865 [Default:CheckIn:Read_Iridium] Stopped
2021-07-01T17:03:55.866Z,1625159035.866 [Default:CheckIn:C.Wait] Running Loop=1
2021-07-01T17:03:55.866Z,1625159035.866 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-07-01T17:04:18.430Z,1625159058.430 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2021-07-01T17:04:18.433Z,1625159058.433 [BPC1](INFO): Received data from all battery sticks.
2021-07-01T17:08:26.493Z,1625159306.493 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-07-01T17:08:56.397Z,1625159336.397 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-07-01T17:08:56.397Z,1625159336.397 [Default:CheckIn:C.Wait] Stopped
2021-07-01T17:08:56.397Z,1625159336.397 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-07-01T17:08:56.398Z,1625159336.398 [Default:CheckIn:D] Running Loop=1
2021-07-01T17:08:56.813Z,1625159336.813 [Default:CheckIn:D] Stopped
2021-07-01T17:08:56.813Z,1625159336.813 [Default:CheckIn:E] Running Loop=1
2021-07-01T17:08:57.224Z,1625159337.224 [NAL9602](INFO): Not Powering down - fast GPS
2021-07-01T17:08:57.237Z,1625159337.237 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 130.528931 min 
2021-07-01T17:08:57.237Z,1625159337.237 [Default:CheckIn:E] Stopped
2021-07-01T17:08:57.237Z,1625159337.237 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-07-01T17:08:57.237Z,1625159337.237 [Default:CheckIn] Stopped
2021-07-01T17:08:57.237Z,1625159337.237 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-07-01T17:08:57.237Z,1625159337.237 [Default:CheckIn](INFO): Running loop #23
2021-07-01T17:08:57.238Z,1625159337.238 [Default:CheckIn] Running Loop=23
2021-07-01T17:08:57.238Z,1625159337.238 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-07-01T17:08:57.238Z,1625159337.238 [Default:CheckIn:Read_GPS] Running Loop=1
2021-07-01T17:08:59.226Z,1625159339.226 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170859.00,A,3648.16384,N,12147.29173,W,2.177,207.23,010721,,,A*72
2021-07-01T17:08:59.229Z,1625159339.229 [NAL9602](INFO): GPS fix at 20210701T170859: (36.802731, -121.788196)
2021-07-01T17:08:59.240Z,1625159339.240 [Default:CheckIn:Read_GPS] Stopped
2021-07-01T17:08:59.240Z,1625159339.240 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-07-01T17:09:07.707Z,1625159347.707 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20210701T154542/Courier0045.lzma
2021-07-01T17:09:08.710Z,1625159348.710 [DataOverHttps](INFO): Moved sent file to Logs/20210701T154542/Courier0045.lzma.bak
2021-07-01T17:09:08.710Z,1625159348.710 [DataOverHttps](INFO): SBD MOMSN=15743478
2021-07-01T17:09:26.244Z,1625159366.244 [DataOverHttps](INFO): Sending 219 bytes from file Logs/20210701T154542/Express0046.lzma
2021-07-01T17:09:27.250Z,1625159367.250 [DataOverHttps](INFO): Moved sent file to Logs/20210701T154542/Express0046.lzma.bak
2021-07-01T17:09:27.250Z,1625159367.250 [DataOverHttps](INFO): SBD MOMSN=15743482
2021-07-01T17:09:29.921Z,1625159369.921 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2021-07-01T17:09:30.004Z,1625159370.004 [NAL9602](ERROR): received: 
+CSQ:0

OK485, 0, 0, 0, 0

OK
2021-07-01T17:09:30.044Z,1625159370.044 [Default:CheckIn:Read_Iridium] Stopped
2021-07-01T17:09:30.044Z,1625159370.044 [Default:CheckIn:C.Wait] Running Loop=1
2021-07-01T17:09:30.045Z,1625159370.045 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-07-01T17:14:01.839Z,1625159641.839 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-07-01T17:14:30.579Z,1625159670.579 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-07-01T17:14:30.579Z,1625159670.579 [Default:CheckIn:C.Wait] Stopped
2021-07-01T17:14:30.579Z,1625159670.579 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-07-01T17:14:30.579Z,1625159670.579 [Default:CheckIn:D] Running Loop=1
2021-07-01T17:14:30.992Z,1625159670.992 [Default:CheckIn:D] Stopped
2021-07-01T17:14:30.992Z,1625159670.992 [Default:CheckIn:E] Running Loop=1
2021-07-01T17:14:31.394Z,1625159671.394 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 136.098584 min 
2021-07-01T17:14:31.394Z,1625159671.394 [Default:CheckIn:E] Stopped
2021-07-01T17:14:31.394Z,1625159671.394 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-07-01T17:14:31.394Z,1625159671.394 [Default:CheckIn] Stopped
2021-07-01T17:14:31.395Z,1625159671.395 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-07-01T17:14:31.395Z,1625159671.395 [Default:CheckIn](INFO): Running loop #24
2021-07-01T17:14:31.395Z,1625159671.395 [Default:CheckIn] Running Loop=24
2021-07-01T17:14:31.395Z,1625159671.395 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-07-01T17:14:31.395Z,1625159671.395 [Default:CheckIn:Read_GPS] Running Loop=1
2021-07-01T17:14:33.399Z,1625159673.399 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171433.00,A,3648.17876,N,12147.28718,W,6.337,348.94,010721,,,A*7A
2021-07-01T17:14:33.402Z,1625159673.402 [NAL9602](INFO): GPS fix at 20210701T171433: (36.802979, -121.788120)
2021-07-01T17:14:33.417Z,1625159673.417 [Default:CheckIn:Read_GPS] Stopped
2021-07-01T17:14:33.417Z,1625159673.417 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-07-01T17:14:43.267Z,1625159683.267 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210701T154542/Courier0048.lzma
2021-07-01T17:14:44.270Z,1625159684.270 [DataOverHttps](INFO): Moved sent file to Logs/20210701T154542/Courier0048.lzma.bak
2021-07-01T17:14:44.270Z,1625159684.270 [DataOverHttps](INFO): SBD MOMSN=15743505
2021-07-01T17:15:02.088Z,1625159702.088 [DataOverHttps](INFO): Sending 217 bytes from file Logs/20210701T154542/Express0049.lzma
2021-07-01T17:15:03.090Z,1625159703.090 [DataOverHttps](INFO): Moved sent file to Logs/20210701T154542/Express0049.lzma.bak
2021-07-01T17:15:03.090Z,1625159703.090 [DataOverHttps](INFO): SBD MOMSN=15743508
2021-07-01T17:15:05.713Z,1625159705.713 [NAL9602](INFO): Not Powering down - fast GPS
2021-07-01T17:15:06.152Z,1625159706.152 [Default:CheckIn:Read_Iridium] Stopped
2021-07-01T17:15:06.152Z,1625159706.152 [Default:CheckIn:C.Wait] Running Loop=1
2021-07-01T17:15:06.152Z,1625159706.152 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-07-01T17:20:06.815Z,1625160006.815 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-07-01T17:20:06.815Z,1625160006.815 [Default:CheckIn:C.Wait] Stopped
2021-07-01T17:20:06.815Z,1625160006.815 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-07-01T17:20:06.816Z,1625160006.816 [Default:CheckIn:D] Running Loop=1
2021-07-01T17:20:07.243Z,1625160007.243 [Default:CheckIn:D] Stopped
2021-07-01T17:20:07.244Z,1625160007.244 [Default:CheckIn:E] Running Loop=1
2021-07-01T17:20:07.623Z,1625160007.623 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 141.702767 min 
2021-07-01T17:20:07.623Z,1625160007.623 [Default:CheckIn:E] Stopped
2021-07-01T17:20:07.623Z,1625160007.623 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-07-01T17:20:07.623Z,1625160007.623 [Default:CheckIn] Stopped
2021-07-01T17:20:07.623Z,1625160007.623 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-07-01T17:20:07.623Z,1625160007.623 [Default:CheckIn](INFO): Running loop #25
2021-07-01T17:20:07.636Z,1625160007.636 [Default:CheckIn] Running Loop=25
2021-07-01T17:20:07.636Z,1625160007.636 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-07-01T17:20:07.636Z,1625160007.636 [Default:CheckIn:Read_GPS] Running Loop=1
2021-07-01T17:20:09.639Z,1625160009.639 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172009.00,A,3648.16717,N,12147.28645,W,1.108,206.34,010721,,,A*7C
2021-07-01T17:20:09.642Z,1625160009.642 [NAL9602](INFO): GPS fix at 20210701T172009: (36.802786, -121.788107)
2021-07-01T17:20:09.653Z,1625160009.653 [Default:CheckIn:Read_GPS] Stopped
2021-07-01T17:20:09.653Z,1625160009.653 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-07-01T17:20:17.976Z,1625160017.976 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210701T154542/Courier0051.lzma
2021-07-01T17:20:18.978Z,1625160018.978 [DataOverHttps](INFO): Moved sent file to Logs/20210701T154542/Courier0051.lzma.bak
2021-07-01T17:20:18.978Z,1625160018.978 [DataOverHttps](INFO): SBD MOMSN=15743521
2021-07-01T17:20:40.433Z,1625160040.433 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2021-07-01T17:20:40.508Z,1625160040.508 [NAL9602](ERROR): received: 
+CSQ:0

OK485, 0, 0, 0, 0

OK
2021-07-01T17:20:42.276Z,1625160042.276 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20210701T154542/Express0052.lzma
2021-07-01T17:20:43.278Z,1625160043.278 [DataOverHttps](INFO): Moved sent file to Logs/20210701T154542/Express0052.lzma.bak
2021-07-01T17:20:43.278Z,1625160043.278 [DataOverHttps](INFO): SBD MOMSN=15743525
2021-07-01T17:20:46.549Z,1625160046.549 [Default:CheckIn:Read_Iridium] Stopped
2021-07-01T17:20:46.549Z,1625160046.549 [Default:CheckIn:C.Wait] Running Loop=1
2021-07-01T17:20:46.549Z,1625160046.549 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-07-01T17:25:11.957Z,1625160311.957 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-07-01T17:25:42.663Z,1625160342.663 [NAL9602](INFO): Not Powering down - fast GPS
2021-07-01T17:25:47.114Z,1625160347.114 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-07-01T17:25:47.114Z,1625160347.114 [Default:CheckIn:C.Wait] Stopped
2021-07-01T17:25:47.115Z,1625160347.115 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-07-01T17:25:47.115Z,1625160347.115 [Default:CheckIn:D] Running Loop=1
2021-07-01T17:25:47.526Z,1625160347.526 [Default:CheckIn:D] Stopped
2021-07-01T17:25:47.526Z,1625160347.526 [Default:CheckIn:E] Running Loop=1
2021-07-01T17:25:47.933Z,1625160347.933 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 147.374154 min 
2021-07-01T17:25:47.933Z,1625160347.933 [Default:CheckIn:E] Stopped
2021-07-01T17:25:47.934Z,1625160347.934 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-07-01T17:25:47.934Z,1625160347.934 [Default:CheckIn] Stopped
2021-07-01T17:25:47.934Z,1625160347.934 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-07-01T17:25:47.934Z,1625160347.934 [Default:CheckIn](INFO): Running loop #26
2021-07-01T17:25:47.934Z,1625160347.934 [Default:CheckIn] Running Loop=26
2021-07-01T17:25:47.934Z,1625160347.934 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-07-01T17:25:47.934Z,1625160347.934 [Default:CheckIn:Read_GPS] Running Loop=1
2021-07-01T17:25:49.939Z,1625160349.939 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172549.00,A,3648.17524,N,12147.27671,W,1.283,19.73,010721,,,A*49
2021-07-01T17:25:49.951Z,1625160349.951 [NAL9602](INFO): GPS fix at 20210701T172549: (36.802921, -121.787945)
2021-07-01T17:25:49.963Z,1625160349.963 [Default:CheckIn:Read_GPS] Stopped
2021-07-01T17:25:49.963Z,1625160349.963 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-07-01T17:25:58.623Z,1625160358.623 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210701T154542/Courier0054.lzma
2021-07-01T17:25:59.626Z,1625160359.626 [DataOverHttps](INFO): Moved sent file to Logs/20210701T154542/Courier0054.lzma.bak
2021-07-01T17:25:59.626Z,1625160359.626 [DataOverHttps](INFO): SBD MOMSN=15743543
2021-07-01T17:26:17.292Z,1625160377.292 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20210701T154542/Express0055.lzma
2021-07-01T17:26:18.298Z,1625160378.298 [DataOverHttps](INFO): Moved sent file to Logs/20210701T154542/Express0055.lzma.bak
2021-07-01T17:26:18.298Z,1625160378.298 [DataOverHttps](INFO): SBD MOMSN=15743546
2021-07-01T17:26:20.665Z,1625160380.665 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2021-07-01T17:26:20.748Z,1625160380.748 [NAL9602](ERROR): received: 
+CSQ:0

OK485, 0, 0, 0, 0

OK
2021-07-01T17:26:22.750Z,1625160382.750 [Default:CheckIn:Read_Iridium] Stopped
2021-07-01T17:26:22.750Z,1625160382.750 [Default:CheckIn:C.Wait] Running Loop=1
2021-07-01T17:26:22.750Z,1625160382.750 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-07-01T17:30:52.657Z,1625160652.657 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-07-01T17:31:23.360Z,1625160683.360 [NAL9602](INFO): Not Powering down - fast GPS
2021-07-01T17:31:23.406Z,1625160683.406 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-07-01T17:31:23.406Z,1625160683.406 [Default:CheckIn:C.Wait] Stopped
2021-07-01T17:31:23.409Z,1625160683.409 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-07-01T17:31:23.411Z,1625160683.411 [Default:CheckIn:D] Running Loop=1
2021-07-01T17:31:23.779Z,1625160683.779 [Default:CheckIn:D] Stopped
2021-07-01T17:31:23.779Z,1625160683.779 [Default:CheckIn:E] Running Loop=1
2021-07-01T17:31:24.179Z,1625160684.179 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 152.978369 min 
2021-07-01T17:31:24.179Z,1625160684.179 [Default:CheckIn:E] Stopped
2021-07-01T17:31:24.179Z,1625160684.179 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-07-01T17:31:24.179Z,1625160684.179 [Default:CheckIn] Stopped
2021-07-01T17:31:24.180Z,1625160684.180 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-07-01T17:31:24.181Z,1625160684.181 [Default:CheckIn](INFO): Running loop #27
2021-07-01T17:31:24.181Z,1625160684.181 [Default:CheckIn] Running Loop=27
2021-07-01T17:31:24.181Z,1625160684.181 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-07-01T17:31:24.181Z,1625160684.181 [Default:CheckIn:Read_GPS] Running Loop=1
2021-07-01T17:31:26.193Z,1625160686.193 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173126.00,A,3648.15881,N,12147.29683,W,1.497,211.57,010721,,,A*79
2021-07-01T17:31:26.195Z,1625160686.195 [NAL9602](INFO): GPS fix at 20210701T173126: (36.802647, -121.788280)
2021-07-01T17:31:26.207Z,1625160686.207 [Default:CheckIn:Read_GPS] Stopped
2021-07-01T17:31:26.208Z,1625160686.208 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-07-01T17:31:34.391Z,1625160694.391 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210701T154542/Courier0057.lzma
2021-07-01T17:31:35.394Z,1625160695.394 [DataOverHttps](INFO): Moved sent file to Logs/20210701T154542/Courier0057.lzma.bak
2021-07-01T17:31:35.394Z,1625160695.394 [DataOverHttps](INFO): SBD MOMSN=15743570
2021-07-01T17:31:52.868Z,1625160712.868 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20210701T154542/Express0058.lzma
2021-07-01T17:31:53.870Z,1625160713.870 [DataOverHttps](INFO): Moved sent file to Logs/20210701T154542/Express0058.lzma.bak
2021-07-01T17:31:53.870Z,1625160713.870 [DataOverHttps](INFO): SBD MOMSN=15743575
2021-07-01T17:31:56.897Z,1625160716.897 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2021-07-01T17:31:56.980Z,1625160716.980 [NAL9602](ERROR): received: 
+CSQ:0

OK485, 0, 0, 0, 0

OK
2021-07-01T17:31:57.028Z,1625160717.028 [Default:CheckIn:Read_Iridium] Stopped
2021-07-01T17:31:57.028Z,1625160717.028 [Default:CheckIn:C.Wait] Running Loop=1
2021-07-01T17:31:57.028Z,1625160717.028 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-07-01T17:36:15.845Z,1625160975.845 [CommandLine](IMPORTANT): got command quit
2021-07-01T17:36:16.856Z,1625160976.856 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2021-07-01T17:36:16.856Z,1625160976.856 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2021-07-01T17:36:16.857Z,1625160976.857 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-07-01T17:36:17.044Z,1625160977.044 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2021-07-01T17:36:17.044Z,1625160977.044 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-07-01T17:36:17.045Z,1625160977.045 [CommandLine](INFO): Join timeout helper Thread ID is 3045
2021-07-01T17:36:17.048Z,1625160977.048 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2021-07-01T17:36:17.048Z,1625160977.048 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-07-01T17:36:17.048Z,1625160977.048 [NavChartDb](INFO): Join timeout helper Thread ID is 3046
2021-07-01T17:36:17.240Z,1625160977.240 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2021-07-01T17:36:17.240Z,1625160977.240 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-07-01T17:36:17.248Z,1625160977.248 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2021-07-01T17:36:17.248Z,1625160977.248 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-07-01T17:36:17.248Z,1625160977.248 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3047
2021-07-01T17:36:17.268Z,1625160977.268 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2021-07-01T17:36:17.268Z,1625160977.268 [WetLabsBB2FL](INFO): Powering down
2021-07-01T17:36:17.269Z,1625160977.269 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-07-01T17:36:17.288Z,1625160977.288 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2021-07-01T17:36:17.288Z,1625160977.288 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-07-01T17:36:17.288Z,1625160977.288 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3048
2021-07-01T17:36:17.560Z,1625160977.560 [CTD_Seabird](INFO): Powering down
2021-07-01T17:36:17.576Z,1625160977.576 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2021-07-01T17:36:17.576Z,1625160977.576 [CTD_Seabird](INFO): Powering down
2021-07-01T17:36:17.588Z,1625160977.588 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-07-01T17:36:17.596Z,1625160977.596 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2021-07-01T17:36:17.596Z,1625160977.596 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-07-01T17:36:17.596Z,1625160977.596 [Radio_Surface](INFO): Join timeout helper Thread ID is 3049
2021-07-01T17:36:17.676Z,1625160977.676 [Radio_Surface](INFO): Powering down
2021-07-01T17:36:17.677Z,1625160977.677 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2021-07-01T17:36:17.677Z,1625160977.677 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-07-01T17:36:17.685Z,1625160977.685 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2021-07-01T17:36:17.685Z,1625160977.685 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-07-01T17:36:17.685Z,1625160977.685 [Onboard](INFO): Join timeout helper Thread ID is 3050
2021-07-01T17:36:18.624Z,1625160978.624 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2021-07-01T17:36:18.624Z,1625160978.624 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-07-01T17:36:18.642Z,1625160978.642 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2021-07-01T17:36:18.642Z,1625160978.642 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-07-01T17:36:18.642Z,1625160978.642 [DataOverHttps](INFO): Join timeout helper Thread ID is 3051
2021-07-01T17:36:19.320Z,1625160979.320 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2021-07-01T17:36:19.320Z,1625160979.320 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-07-01T17:36:19.341Z,1625160979.341 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2021-07-01T17:36:19.341Z,1625160979.341 [logger ThreadHandler](INFO): Thread cancelled.
2021-07-01T17:36:19.341Z,1625160979.341 [logger](INFO): Join timeout helper Thread ID is 3052
2021-07-01T17:36:19.344Z,1625160979.344 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2021-07-01T17:36:19.344Z,1625160979.344 [logger ThreadHandler](INFO): Thread cancelled.
2021-07-01T17:36:19.360Z,1625160979.360 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2021-07-01T17:36:19.361Z,1625160979.361 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-07-01T17:36:19.361Z,1625160979.361 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2021-07-01T17:36:19.361Z,1625160979.361 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-07-01T17:36:19.361Z,1625160979.361 [controlThread](INFO): Join timeout helper Thread ID is 3053
2021-07-01T17:36:19.512Z,1625160979.512 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2021-07-01T17:36:19.512Z,1625160979.512 [controlThread](DEBUG): Uninitializing ControlThread
2021-07-01T17:36:19.513Z,1625160979.513 [AHRS_M2](INFO): Powering down
2021-07-01T17:36:19.585Z,1625160979.585 [NAL9602](INFO): Powering down
2021-07-01T17:36:19.587Z,1625160979.587 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2021-07-01T17:36:19.589Z,1625160979.589 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2021-07-01T17:36:19.589Z,1625160979.589 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2021-07-01T17:36:19.590Z,1625160979.590 [MissionManager](INFO): Uninitializing Mission Default
2021-07-01T17:36:19.590Z,1625160979.590 [Default] Stopped
2021-07-01T17:36:19.590Z,1625160979.590 [Default](DEBUG): Aggregate::uninitialize Default
2021-07-01T17:36:19.590Z,1625160979.590 [Default:B.GoToSurface] Stopped
2021-07-01T17:36:19.590Z,1625160979.590 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-07-01T17:36:19.590Z,1625160979.590 [Default:CheckIn] Stopped
2021-07-01T17:36:19.590Z,1625160979.590 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-07-01T17:36:19.590Z,1625160979.590 [Default:CheckIn:C.Wait] Stopped
2021-07-01T17:36:19.590Z,1625160979.590 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-07-01T17:36:19.594Z,1625160979.594 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2021-07-01T17:36:19.594Z,1625160979.594 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2021-07-01T17:36:19.594Z,1625160979.594 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2021-07-01T17:36:19.595Z,1625160979.595 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2021-07-01T17:36:19.595Z,1625160979.595 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2021-07-01T17:36:19.595Z,1625160979.595 [BuoyancyServo](INFO): Powering down
2021-07-01T17:36:19.608Z,1625160979.608 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2021-07-01T17:36:19.608Z,1625160979.608 [ElevatorServo](INFO): Powering down
2021-07-01T17:36:19.609Z,1625160979.609 [MassServo](DEBUG): Uninitialize Mass Servo.
2021-07-01T17:36:19.609Z,1625160979.609 [MassServo](INFO): Powering down
2021-07-01T17:36:19.610Z,1625160979.610 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-07-01T17:36:19.610Z,1625160979.610 [RudderServo](INFO): Powering down
2021-07-01T17:36:19.611Z,1625160979.611 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2021-07-01T17:36:19.611Z,1625160979.611 [ThrusterServo](INFO): Powering down
2021-07-01T17:36:19.612Z,1625160979.612 [SBIT](DEBUG): Uninitialize SBIT Component.
2021-07-01T17:36:19.612Z,1625160979.612 [IBIT](DEBUG): Uninitialize IBIT Component.
2021-07-01T17:36:19.613Z,1625160979.613 [CBIT](DEBUG): Uninitialize CBIT Component.
2021-07-01T17:36:19.613Z,1625160979.613 [CBIT](DEBUG): Powering off loads.
2021-07-01T17:36:19.624Z,1625160979.624 [CBIT](DEBUG): Disabling WDT.
2021-07-01T17:36:19.636Z,1625160979.636 [CBIT](DEBUG): Opening all GF detection circuits.
2021-07-01T17:36:19.637Z,1625160979.637 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-07-01T17:36:19.676Z,1625160979.676 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-07-01T17:36:19.679Z,1625160979.679 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-07-01T17:36:19.685Z,1625160979.685 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-07-01T17:36:19.757Z,1625160979.757 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-07-01T17:36:19.761Z,1625160979.761 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-07-01T17:36:19.801Z,1625160979.801 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-07-01T17:36:19.886Z,1625160979.886 [logger ThreadHandler](INFO): Thread cancelled.