2022-08-08T14:23:00.568Z,1659968580.568 [CommandExec](IMPORTANT): got command restart logs
2022-08-08T14:24:49.724Z,1659968689.724 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-08T14:24:49.725Z,1659968689.725 [Default:CheckIn:C.Wait] Stopped
2022-08-08T14:24:49.725Z,1659968689.725 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-08T14:24:49.725Z,1659968689.725 [Default:CheckIn:D] Running Loop=1
2022-08-08T14:24:50.126Z,1659968690.126 [Default:CheckIn:D] Stopped
2022-08-08T14:24:50.126Z,1659968690.126 [Default:CheckIn:E] Running Loop=1
2022-08-08T14:24:50.533Z,1659968690.533 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.618210 min 
2022-08-08T14:24:50.533Z,1659968690.533 [Default:CheckIn:E] Stopped
2022-08-08T14:24:50.534Z,1659968690.534 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-08T14:24:50.534Z,1659968690.534 [Default:CheckIn] Stopped
2022-08-08T14:24:50.534Z,1659968690.534 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-08T14:24:50.534Z,1659968690.534 [Default:CheckIn](INFO): Running loop #3
2022-08-08T14:24:50.534Z,1659968690.534 [Default:CheckIn] Running Loop=3
2022-08-08T14:24:50.534Z,1659968690.534 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-08T14:24:50.535Z,1659968690.535 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-08T14:24:52.545Z,1659968692.545 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,142454.00,A,4651.46577,N,09106.24145,W,0.097,109.50,080822,,,D*71
2022-08-08T14:24:52.558Z,1659968692.558 [NAL9602](INFO): GPS fix at 20220808T142454: (46.857763, -91.104024)
2022-08-08T14:24:52.569Z,1659968692.569 [Default:CheckIn:Read_GPS] Stopped
2022-08-08T14:24:52.569Z,1659968692.569 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-08T14:24:59.962Z,1659968699.962 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220808T021944/Courier0103.lzma
2022-08-08T14:25:00.964Z,1659968700.964 [DataOverHttps](INFO): Moved sent file to Logs/20220808T021944/Courier0103.lzma.bak
2022-08-08T14:25:00.965Z,1659968700.965 [DataOverHttps](INFO): SBD MOMSN=17096466
2022-08-08T14:25:19.706Z,1659968719.706 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20220808T142300/Courier0000.lzma
2022-08-08T14:25:20.709Z,1659968720.709 [DataOverHttps](INFO): Moved sent file to Logs/20220808T142300/Courier0000.lzma.bak
2022-08-08T14:25:20.709Z,1659968720.709 [DataOverHttps](INFO): SBD MOMSN=17096468
2022-08-08T14:25:27.353Z,1659968727.353 [NAL9602](INFO): SBD MO Status=0, MOMSN=8448, MT Status=0, MTMSN=0
2022-08-08T14:25:27.353Z,1659968727.353 [NAL9602](INFO): No messages in MT queue
2022-08-08T14:25:37.175Z,1659968737.175 [DataOverHttps](INFO): Sending 660 bytes from file Logs/20220808T021944/Express0104.lzma
2022-08-08T14:25:38.176Z,1659968738.176 [DataOverHttps](INFO): Moved sent file to Logs/20220808T021944/Express0104.lzma.bak
2022-08-08T14:25:38.176Z,1659968738.176 [DataOverHttps](INFO): SBD MOMSN=17096472
2022-08-08T14:25:53.939Z,1659968753.939 [DataOverHttps](INFO): Sending 501 bytes from file Logs/20220808T142300/Express0001.lzma
2022-08-08T14:25:54.940Z,1659968754.940 [DataOverHttps](INFO): Moved sent file to Logs/20220808T142300/Express0001.lzma.bak
2022-08-08T14:25:54.940Z,1659968754.940 [DataOverHttps](INFO): SBD MOMSN=17096477
2022-08-08T14:25:56.451Z,1659968756.451 [Default:CheckIn:Read_Iridium] Stopped
2022-08-08T14:25:56.451Z,1659968756.451 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-08T14:25:56.451Z,1659968756.451 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-08T14:25:58.055Z,1659968758.055 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-08T14:26:04.538Z,1659968764.538 [CBIT](INFO): Clearing failed state for component BPC1
2022-08-08T14:26:04.539Z,1659968764.539 [BPC1] No Fault, FailCount= 2
2022-08-08T14:27:39.071Z,1659968859.071 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2022-08-08T14:28:32.432Z,1659968912.432 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-08T14:28:46.586Z,1659968926.586 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-08T14:28:59.988Z,1659968939.988 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-08T14:29:13.706Z,1659968953.706 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-08T14:29:26.968Z,1659968966.968 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-08T14:29:40.768Z,1659968980.768 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-08T14:29:54.098Z,1659968994.098 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-08T14:30:08.184Z,1659969008.184 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-08T14:30:21.605Z,1659969021.605 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-08T14:30:35.250Z,1659969035.250 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-08T14:30:48.978Z,1659969048.978 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-08T14:30:57.072Z,1659969057.072 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-08T14:30:57.073Z,1659969057.073 [Default:CheckIn:C.Wait] Stopped
2022-08-08T14:30:57.073Z,1659969057.073 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-08T14:30:57.073Z,1659969057.073 [Default:CheckIn:D] Running Loop=1
2022-08-08T14:30:57.477Z,1659969057.477 [Default:CheckIn:D] Stopped
2022-08-08T14:30:57.478Z,1659969057.478 [Default:CheckIn:E] Running Loop=1
2022-08-08T14:30:57.879Z,1659969057.879 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 17.740731 min 
2022-08-08T14:30:57.879Z,1659969057.879 [Default:CheckIn:E] Stopped
2022-08-08T14:30:57.879Z,1659969057.879 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-08T14:30:57.879Z,1659969057.879 [Default:CheckIn] Stopped
2022-08-08T14:30:57.879Z,1659969057.879 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-08T14:30:57.880Z,1659969057.880 [Default:CheckIn](INFO): Running loop #4
2022-08-08T14:30:57.880Z,1659969057.880 [Default:CheckIn] Running Loop=4
2022-08-08T14:30:57.880Z,1659969057.880 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-08T14:30:57.880Z,1659969057.880 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-08T14:30:59.907Z,1659969059.907 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,143102.00,A,4651.46888,N,09106.23058,W,0.019,117.85,080822,,,D*70
2022-08-08T14:30:59.909Z,1659969059.909 [NAL9602](INFO): GPS fix at 20220808T143102: (46.857815, -91.103843)
2022-08-08T14:30:59.933Z,1659969059.933 [Default:CheckIn:Read_GPS] Stopped
2022-08-08T14:30:59.933Z,1659969059.933 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-08T14:31:10.586Z,1659969070.586 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220808T142300/Courier0006.lzma
2022-08-08T14:31:11.590Z,1659969071.590 [DataOverHttps](INFO): Moved sent file to Logs/20220808T142300/Courier0006.lzma.bak
2022-08-08T14:31:11.590Z,1659969071.590 [DataOverHttps](INFO): SBD MOMSN=17096482
2022-08-08T14:31:14.101Z,1659969074.101 [NAL9602](INFO): SBD MO Status=0, MOMSN=8449, MT Status=0, MTMSN=0
2022-08-08T14:31:14.101Z,1659969074.101 [NAL9602](INFO): No messages in MT queue
2022-08-08T14:31:27.460Z,1659969087.460 [DataOverHttps](INFO): Sending 817 bytes from file Logs/20220808T142300/Express0007.lzma
2022-08-08T14:31:28.460Z,1659969088.460 [DataOverHttps](INFO): Moved sent file to Logs/20220808T142300/Express0007.lzma.bak
2022-08-08T14:31:28.460Z,1659969088.460 [DataOverHttps](INFO): SBD MOMSN=17096485
2022-08-08T14:31:30.277Z,1659969090.277 [Default:CheckIn:Read_Iridium] Stopped
2022-08-08T14:31:30.278Z,1659969090.278 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-08T14:31:30.278Z,1659969090.278 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-08T14:31:44.823Z,1659969104.823 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-08T14:33:30.262Z,1659969210.262 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 5.
2022-08-08T14:33:30.265Z,1659969210.265 [BPC1](INFO): Received data from all battery sticks.
2022-08-08T14:36:30.870Z,1659969390.870 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-08T14:36:30.870Z,1659969390.870 [Default:CheckIn:C.Wait] Stopped
2022-08-08T14:36:30.870Z,1659969390.870 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-08T14:36:30.870Z,1659969390.870 [Default:CheckIn:D] Running Loop=1
2022-08-08T14:36:31.269Z,1659969391.269 [Default:CheckIn:D] Stopped
2022-08-08T14:36:31.269Z,1659969391.269 [Default:CheckIn:E] Running Loop=1
2022-08-08T14:36:31.673Z,1659969391.673 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 23.303920 min 
2022-08-08T14:36:31.673Z,1659969391.673 [Default:CheckIn:E] Stopped
2022-08-08T14:36:31.673Z,1659969391.673 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-08T14:36:31.674Z,1659969391.674 [Default:CheckIn] Stopped
2022-08-08T14:36:31.674Z,1659969391.674 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-08T14:36:31.674Z,1659969391.674 [Default:CheckIn](INFO): Running loop #5
2022-08-08T14:36:31.674Z,1659969391.674 [Default:CheckIn] Running Loop=5
2022-08-08T14:36:31.674Z,1659969391.674 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-08T14:36:31.674Z,1659969391.674 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-08T14:36:33.685Z,1659969393.685 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,143635.00,A,4651.46901,N,09106.23065,W,0.019,117.85,080822,,,D*7D
2022-08-08T14:36:33.687Z,1659969393.687 [NAL9602](INFO): GPS fix at 20220808T143635: (46.857817, -91.103844)
2022-08-08T14:36:33.698Z,1659969393.698 [Default:CheckIn:Read_GPS] Stopped
2022-08-08T14:36:33.698Z,1659969393.698 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-08T14:36:40.686Z,1659969400.686 [DataOverHttps](INFO): Sending 201 bytes from file Logs/20220808T142300/Courier0009.lzma
2022-08-08T14:36:41.688Z,1659969401.688 [DataOverHttps](INFO): Moved sent file to Logs/20220808T142300/Courier0009.lzma.bak
2022-08-08T14:36:41.688Z,1659969401.688 [DataOverHttps](INFO): SBD MOMSN=17096493
2022-08-08T14:36:53.600Z,1659969413.600 [NAL9602](INFO): SBD MO Status=0, MOMSN=8450, MT Status=0, MTMSN=0
2022-08-08T14:36:53.600Z,1659969413.600 [NAL9602](INFO): No messages in MT queue
2022-08-08T14:36:58.078Z,1659969418.078 [DataOverHttps](INFO): Sending 138 bytes from file Logs/20220808T142300/Express0010.lzma
2022-08-08T14:36:59.080Z,1659969419.080 [DataOverHttps](INFO): Moved sent file to Logs/20220808T142300/Express0010.lzma.bak
2022-08-08T14:36:59.080Z,1659969419.080 [DataOverHttps](INFO): SBD MOMSN=17096496
2022-08-08T14:37:01.319Z,1659969421.319 [Default:CheckIn:Read_Iridium] Stopped
2022-08-08T14:37:01.319Z,1659969421.319 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-08T14:37:01.319Z,1659969421.319 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-08T14:37:24.349Z,1659969444.349 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-08T14:42:01.999Z,1659969721.999 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-08T14:42:01.999Z,1659969721.999 [Default:CheckIn:C.Wait] Stopped
2022-08-08T14:42:01.999Z,1659969721.999 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-08T14:42:01.999Z,1659969721.999 [Default:CheckIn:D] Running Loop=1
2022-08-08T14:42:02.395Z,1659969722.395 [Default:CheckIn:D] Stopped
2022-08-08T14:42:02.395Z,1659969722.395 [Default:CheckIn:E] Running Loop=1
2022-08-08T14:42:02.789Z,1659969722.789 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 28.822693 min 
2022-08-08T14:42:02.789Z,1659969722.789 [Default:CheckIn:E] Stopped
2022-08-08T14:42:02.789Z,1659969722.789 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-08T14:42:02.789Z,1659969722.789 [Default:CheckIn] Stopped
2022-08-08T14:42:02.790Z,1659969722.790 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-08T14:42:02.790Z,1659969722.790 [Default:CheckIn](INFO): Running loop #6
2022-08-08T14:42:02.790Z,1659969722.790 [Default:CheckIn] Running Loop=6
2022-08-08T14:42:02.790Z,1659969722.790 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-08T14:42:02.790Z,1659969722.790 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-08T14:42:04.812Z,1659969724.812 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,144207.00,A,4651.46849,N,09106.23069,W,0.058,117.85,080822,,,D*7B
2022-08-08T14:42:04.814Z,1659969724.814 [NAL9602](INFO): GPS fix at 20220808T144207: (46.857808, -91.103845)
2022-08-08T14:42:04.825Z,1659969724.825 [Default:CheckIn:Read_GPS] Stopped
2022-08-08T14:42:04.825Z,1659969724.825 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-08T14:42:12.006Z,1659969732.006 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220808T142300/Courier0012.lzma
2022-08-08T14:42:13.008Z,1659969733.008 [DataOverHttps](INFO): Moved sent file to Logs/20220808T142300/Courier0012.lzma.bak
2022-08-08T14:42:13.008Z,1659969733.008 [DataOverHttps](INFO): SBD MOMSN=17096525
2022-08-08T14:42:26.693Z,1659969746.693 [NAL9602](INFO): SBD MO Status=2, MOMSN=8451, MT Status=2, MTMSN=0
2022-08-08T14:42:26.694Z,1659969746.694 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-08-08T14:42:33.446Z,1659969753.446 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20220808T142300/Express0013.lzma
2022-08-08T14:42:34.449Z,1659969754.449 [DataOverHttps](INFO): Moved sent file to Logs/20220808T142300/Express0013.lzma.bak
2022-08-08T14:42:34.449Z,1659969754.449 [DataOverHttps](INFO): SBD MOMSN=17096528
2022-08-08T14:42:35.991Z,1659969755.991 [Default:CheckIn:Read_Iridium] Stopped
2022-08-08T14:42:35.991Z,1659969755.991 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-08T14:42:35.991Z,1659969755.991 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-08T14:43:56.373Z,1659969836.373 [NAL9602](INFO): SBD MO Status=2, MOMSN=8451, MT Status=2, MTMSN=0
2022-08-08T14:43:56.373Z,1659969836.373 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-08-08T14:45:13.585Z,1659969913.585 [NAL9602](INFO): SBD MO Status=0, MOMSN=8451, MT Status=0, MTMSN=0
2022-08-08T14:45:13.585Z,1659969913.585 [NAL9602](INFO): No messages in MT queue
2022-08-08T14:45:44.283Z,1659969944.283 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-08T14:46:51.848Z,1659970011.848 [DataOverHttps](IMPORTANT): SBD MTMSN=20220808T144653
2022-08-08T14:46:59.202Z,1659970019.202 [DataOverHttps](INFO): Received command: configSet VerticalControl.massDefault 0.5 cm persist
2022-08-08T14:46:59.976Z,1659970019.976 [CommandExec](IMPORTANT): got command configSet VerticalControl.massDefault 0.5 centimeter persist
2022-08-08T14:47:36.614Z,1659970056.614 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-08T14:47:36.615Z,1659970056.615 [Default:CheckIn:C.Wait] Stopped
2022-08-08T14:47:36.615Z,1659970056.615 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-08T14:47:36.615Z,1659970056.615 [Default:CheckIn:D] Running Loop=1
2022-08-08T14:47:37.029Z,1659970057.029 [Default:CheckIn:D] Stopped
2022-08-08T14:47:37.029Z,1659970057.029 [Default:CheckIn:E] Running Loop=1
2022-08-08T14:47:37.427Z,1659970057.427 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 34.399927 min 
2022-08-08T14:47:37.427Z,1659970057.427 [Default:CheckIn:E] Stopped
2022-08-08T14:47:37.427Z,1659970057.427 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-08T14:47:37.427Z,1659970057.427 [Default:CheckIn] Stopped
2022-08-08T14:47:37.427Z,1659970057.427 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-08T14:47:37.428Z,1659970057.428 [Default:CheckIn](INFO): Running loop #7
2022-08-08T14:47:37.428Z,1659970057.428 [Default:CheckIn] Running Loop=7
2022-08-08T14:47:37.428Z,1659970057.428 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-08T14:47:37.428Z,1659970057.428 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-08T14:47:39.438Z,1659970059.438 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,144741.00,A,4651.46958,N,09106.23067,W,0.078,117.85,080822,,,D*71
2022-08-08T14:47:39.440Z,1659970059.440 [NAL9602](INFO): GPS fix at 20220808T144741: (46.857826, -91.103844)
2022-08-08T14:47:39.451Z,1659970059.451 [Default:CheckIn:Read_GPS] Stopped
2022-08-08T14:47:39.451Z,1659970059.451 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-08T14:47:47.190Z,1659970067.190 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220808T142300/Courier0015.lzma
2022-08-08T14:47:48.194Z,1659970068.194 [DataOverHttps](INFO): Moved sent file to Logs/20220808T142300/Courier0015.lzma.bak
2022-08-08T14:47:48.194Z,1659970068.194 [DataOverHttps](INFO): SBD MOMSN=17096538
2022-08-08T14:48:04.063Z,1659970084.063 [DataOverHttps](INFO): Sending 264 bytes from file Logs/20220808T142300/Express0016.lzma
2022-08-08T14:48:05.060Z,1659970085.060 [DataOverHttps](INFO): Moved sent file to Logs/20220808T142300/Express0016.lzma.bak
2022-08-08T14:48:05.060Z,1659970085.060 [DataOverHttps](INFO): SBD MOMSN=17096541
2022-08-08T14:48:10.541Z,1659970090.541 [NAL9602](INFO): SBD MO Status=0, MOMSN=8452, MT Status=0, MTMSN=0
2022-08-08T14:48:10.541Z,1659970090.541 [NAL9602](INFO): No messages in MT queue
2022-08-08T14:48:23.890Z,1659970103.890 [DataOverHttps](INFO): Sending 70 bytes from file Logs/20220808T142300/Express0019.lzma
2022-08-08T14:48:24.892Z,1659970104.892 [DataOverHttps](INFO): Moved sent file to Logs/20220808T142300/Express0019.lzma.bak
2022-08-08T14:48:24.892Z,1659970104.892 [DataOverHttps](INFO): SBD MOMSN=17096546
2022-08-08T14:48:26.798Z,1659970106.798 [Default:CheckIn:Read_Iridium] Stopped
2022-08-08T14:48:26.798Z,1659970106.798 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-08T14:48:26.798Z,1659970106.798 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-08T14:48:41.327Z,1659970121.327 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-08T14:53:27.425Z,1659970407.425 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-08T14:53:27.425Z,1659970407.425 [Default:CheckIn:C.Wait] Stopped
2022-08-08T14:53:27.426Z,1659970407.426 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-08T14:53:27.426Z,1659970407.426 [Default:CheckIn:D] Running Loop=1
2022-08-08T14:53:27.833Z,1659970407.833 [Default:CheckIn:D] Stopped
2022-08-08T14:53:27.833Z,1659970407.833 [Default:CheckIn:E] Running Loop=1
2022-08-08T14:53:28.238Z,1659970408.238 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 40.246655 min 
2022-08-08T14:53:28.238Z,1659970408.238 [Default:CheckIn:E] Stopped
2022-08-08T14:53:28.238Z,1659970408.238 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-08T14:53:28.238Z,1659970408.238 [Default:CheckIn] Stopped
2022-08-08T14:53:28.238Z,1659970408.238 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-08T14:53:28.238Z,1659970408.238 [Default:CheckIn](INFO): Running loop #8
2022-08-08T14:53:28.239Z,1659970408.239 [Default:CheckIn] Running Loop=8
2022-08-08T14:53:28.239Z,1659970408.239 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-08T14:53:28.239Z,1659970408.239 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-08T14:53:30.248Z,1659970410.248 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,145332.00,A,4651.47630,N,09106.23478,W,0.156,325.29,080822,,,D*72
2022-08-08T14:53:30.259Z,1659970410.259 [NAL9602](INFO): GPS fix at 20220808T145332: (46.857938, -91.103913)
2022-08-08T14:53:30.278Z,1659970410.278 [Default:CheckIn:Read_GPS] Stopped
2022-08-08T14:53:30.278Z,1659970410.278 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-08T14:53:37.286Z,1659970417.286 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220808T142300/Courier0021.lzma
2022-08-08T14:53:38.288Z,1659970418.288 [DataOverHttps](INFO): Moved sent file to Logs/20220808T142300/Courier0021.lzma.bak
2022-08-08T14:53:38.288Z,1659970418.288 [DataOverHttps](INFO): SBD MOMSN=17096551
2022-08-08T14:53:54.962Z,1659970434.962 [DataOverHttps](INFO): Sending 389 bytes from file Logs/20220808T142300/Express0022.lzma
2022-08-08T14:53:55.964Z,1659970435.964 [DataOverHttps](INFO): Moved sent file to Logs/20220808T142300/Express0022.lzma.bak
2022-08-08T14:53:55.964Z,1659970435.964 [DataOverHttps](INFO): SBD MOMSN=17096554
2022-08-08T14:53:57.827Z,1659970437.827 [Default:CheckIn:Read_Iridium] Stopped
2022-08-08T14:53:57.827Z,1659970437.827 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-08T14:53:57.827Z,1659970437.827 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-08T14:54:28.925Z,1659970468.925 [NAL9602](INFO): SBD MO Status=2, MOMSN=8453, MT Status=2, MTMSN=0
2022-08-08T14:54:28.925Z,1659970468.925 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-08-08T14:55:26.284Z,1659970526.284 [NAL9602](INFO): SBD MO Status=2, MOMSN=8453, MT Status=2, MTMSN=0
2022-08-08T14:55:26.284Z,1659970526.284 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-08-08T14:55:38.339Z,1659970538.339 [CommandExec](IMPORTANT): got command report mod platform_buoyancy_position 
2022-08-08T14:55:38.470Z,1659970538.470 [Reporter](INFO): platform_buoyancy_position 958.068970 cc
2022-08-08T14:55:51.087Z,1659970551.087 [CommandExec](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 280 cubic_centimeter
2022-08-08T14:55:51.091Z,1659970551.091 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread.
2022-08-08T14:55:51.378Z,1659970551.378 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2022-08-08T14:55:52.969Z,1659970552.969 [NAL9602](INFO): SBD MO Status=2, MOMSN=8453, MT Status=2, MTMSN=0
2022-08-08T14:55:52.969Z,1659970552.969 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-08-08T14:55:53.480Z,1659970553.480 [Reporter](INFO): platform_buoyancy_position 960.620728 cc
2022-08-08T14:55:53.825Z,1659970553.825 [Reporter](INFO): platform_buoyancy_position 960.217773 cc
2022-08-08T14:55:54.229Z,1659970554.229 [Reporter](INFO): platform_buoyancy_position 958.203247 cc
2022-08-08T14:55:54.651Z,1659970554.651 [Reporter](INFO): platform_buoyancy_position 947.324951 cc
2022-08-08T14:55:55.065Z,1659970555.065 [Reporter](INFO): platform_buoyancy_position 945.310425 cc
2022-08-08T14:55:55.457Z,1659970555.457 [Reporter](INFO): platform_buoyancy_position 943.161743 cc
2022-08-08T14:55:55.856Z,1659970555.856 [Reporter](INFO): platform_buoyancy_position 938.864136 cc
2022-08-08T14:55:56.261Z,1659970556.261 [Reporter](INFO): platform_buoyancy_position 934.566528 cc
2022-08-08T14:55:56.673Z,1659970556.673 [Reporter](INFO): platform_buoyancy_position 930.268921 cc
2022-08-08T14:55:57.077Z,1659970557.077 [Reporter](INFO): platform_buoyancy_position 925.031128 cc
2022-08-08T14:55:57.504Z,1659970557.504 [Reporter](INFO): platform_buoyancy_position 919.390625 cc
2022-08-08T14:55:57.884Z,1659970557.884 [Reporter](INFO): platform_buoyancy_position 914.958740 cc
2022-08-08T14:55:58.289Z,1659970558.289 [Reporter](INFO): platform_buoyancy_position 908.780884 cc
2022-08-08T14:55:58.687Z,1659970558.687 [Reporter](INFO): platform_buoyancy_position 903.946045 cc
2022-08-08T14:55:59.070Z,1659970559.070 [Reporter](INFO): platform_buoyancy_position 897.902588 cc
2022-08-08T14:55:59.485Z,1659970559.485 [Reporter](INFO): platform_buoyancy_position 893.604980 cc
2022-08-08T14:55:59.905Z,1659970559.905 [Reporter](INFO): platform_buoyancy_position 887.964355 cc
2022-08-08T14:56:00.322Z,1659970560.322 [Reporter](INFO): platform_buoyancy_position 882.995239 cc
2022-08-08T14:56:00.737Z,1659970560.737 [Reporter](INFO): platform_buoyancy_position 878.563354 cc
2022-08-08T14:56:01.101Z,1659970561.101 [Reporter](INFO): platform_buoyancy_position 872.251221 cc
2022-08-08T14:56:01.519Z,1659970561.519 [Reporter](INFO): platform_buoyancy_position 867.819336 cc
2022-08-08T14:56:01.909Z,1659970561.909 [Reporter](INFO): platform_buoyancy_position 861.641602 cc
2022-08-08T14:56:02.324Z,1659970562.324 [Reporter](INFO): platform_buoyancy_position 857.075317 cc
2022-08-08T14:56:02.713Z,1659970562.713 [Reporter](INFO): platform_buoyancy_position 850.897583 cc
2022-08-08T14:56:03.129Z,1659970563.129 [Reporter](INFO): platform_buoyancy_position 846.331421 cc
2022-08-08T14:56:03.544Z,1659970563.544 [Reporter](INFO): platform_buoyancy_position 839.885010 cc
2022-08-08T14:56:03.940Z,1659970563.940 [Reporter](INFO): platform_buoyancy_position 835.721680 cc
2022-08-08T14:56:04.325Z,1659970564.325 [Reporter](INFO): platform_buoyancy_position 831.155518 cc
2022-08-08T14:56:04.745Z,1659970564.745 [Reporter](INFO): platform_buoyancy_position 824.843384 cc
2022-08-08T14:56:05.140Z,1659970565.140 [Reporter](INFO): platform_buoyancy_position 818.531250 cc
2022-08-08T14:56:05.563Z,1659970565.563 [Reporter](INFO): platform_buoyancy_position 814.099365 cc
2022-08-08T14:56:05.947Z,1659970565.947 [Reporter](INFO): platform_buoyancy_position 808.995972 cc
2022-08-08T14:56:06.367Z,1659970566.367 [Reporter](INFO): platform_buoyancy_position 803.489685 cc
2022-08-08T14:56:06.765Z,1659970566.765 [Reporter](INFO): platform_buoyancy_position 797.043274 cc
2022-08-08T14:56:07.147Z,1659970567.147 [Reporter](INFO): platform_buoyancy_position 792.611389 cc
2022-08-08T14:56:07.564Z,1659970567.564 [Reporter](INFO): platform_buoyancy_position 788.313782 cc
2022-08-08T14:56:07.975Z,1659970567.975 [Reporter](INFO): platform_buoyancy_position 782.001648 cc
2022-08-08T14:56:08.417Z,1659970568.417 [Reporter](INFO): platform_buoyancy_position 777.569763 cc
2022-08-08T14:56:08.779Z,1659970568.779 [Reporter](INFO): platform_buoyancy_position 771.391968 cc
2022-08-08T14:56:09.192Z,1659970569.192 [Reporter](INFO): platform_buoyancy_position 766.825745 cc
2022-08-08T14:56:09.605Z,1659970569.605 [Reporter](INFO): platform_buoyancy_position 761.050842 cc
2022-08-08T14:56:09.984Z,1659970569.984 [Reporter](INFO): platform_buoyancy_position 756.081787 cc
2022-08-08T14:56:10.385Z,1659970570.385 [Reporter](INFO): platform_buoyancy_position 751.649841 cc
2022-08-08T14:56:10.805Z,1659970570.805 [Reporter](INFO): platform_buoyancy_position 745.472046 cc
2022-08-08T14:56:11.220Z,1659970571.220 [Reporter](INFO): platform_buoyancy_position 740.905884 cc
2022-08-08T14:56:11.617Z,1659970571.617 [Reporter](INFO): platform_buoyancy_position 734.728088 cc
2022-08-08T14:56:12.001Z,1659970572.001 [Reporter](INFO): platform_buoyancy_position 730.296143 cc
2022-08-08T14:56:12.421Z,1659970572.421 [Reporter](INFO): platform_buoyancy_position 723.984070 cc
2022-08-08T14:56:12.833Z,1659970572.833 [Reporter](INFO): platform_buoyancy_position 719.552185 cc
2022-08-08T14:56:13.234Z,1659970573.234 [Reporter](INFO): platform_buoyancy_position 713.240051 cc
2022-08-08T14:56:13.613Z,1659970573.613 [Reporter](INFO): platform_buoyancy_position 708.808167 cc
2022-08-08T14:56:14.039Z,1659970574.039 [Reporter](INFO): platform_buoyancy_position 702.496094 cc
2022-08-08T14:56:14.456Z,1659970574.456 [Reporter](INFO): platform_buoyancy_position 698.064148 cc
2022-08-08T14:56:14.861Z,1659970574.861 [Reporter](INFO): platform_buoyancy_position 691.752075 cc
2022-08-08T14:56:15.241Z,1659970575.241 [Reporter](INFO): platform_buoyancy_position 687.320190 cc
2022-08-08T14:56:15.678Z,1659970575.678 [Reporter](INFO): platform_buoyancy_position 681.008057 cc
2022-08-08T14:56:16.073Z,1659970576.073 [Reporter](INFO): platform_buoyancy_position 676.576172 cc
2022-08-08T14:56:16.461Z,1659970576.461 [Reporter](INFO): platform_buoyancy_position 670.264038 cc
2022-08-08T14:56:16.884Z,1659970576.884 [Reporter](INFO): platform_buoyancy_position 665.832153 cc
2022-08-08T14:56:17.285Z,1659970577.285 [Reporter](INFO): platform_buoyancy_position 659.520081 cc
2022-08-08T14:56:17.677Z,1659970577.677 [Reporter](INFO): platform_buoyancy_position 655.088135 cc
2022-08-08T14:56:18.081Z,1659970578.081 [Reporter](INFO): platform_buoyancy_position 650.253357 cc
2022-08-08T14:56:18.481Z,1659970578.481 [Reporter](INFO): platform_buoyancy_position 644.344177 cc
2022-08-08T14:56:18.892Z,1659970578.892 [Reporter](INFO): platform_buoyancy_position 640.046570 cc
2022-08-08T14:56:19.309Z,1659970579.309 [Reporter](INFO): platform_buoyancy_position 633.600159 cc
2022-08-08T14:56:19.693Z,1659970579.693 [Reporter](INFO): platform_buoyancy_position 629.436890 cc
2022-08-08T14:56:20.097Z,1659970580.097 [Reporter](INFO): platform_buoyancy_position 623.527649 cc
2022-08-08T14:56:20.525Z,1659970580.525 [Reporter](INFO): platform_buoyancy_position 618.558533 cc
2022-08-08T14:56:20.893Z,1659970580.893 [Reporter](INFO): platform_buoyancy_position 613.992371 cc
2022-08-08T14:56:21.428Z,1659970581.428 [Reporter](INFO): platform_buoyancy_position 607.814575 cc
2022-08-08T14:56:21.797Z,1659970581.797 [Reporter](INFO): platform_buoyancy_position 601.502441 cc
2022-08-08T14:56:22.081Z,1659970582.081 [NAL9602](INFO): SBD MO Status=0, MOMSN=8453, MT Status=0, MTMSN=0
2022-08-08T14:56:22.082Z,1659970582.082 [NAL9602](INFO): No messages in MT queue
2022-08-08T14:56:22.231Z,1659970582.231 [Reporter](INFO): platform_buoyancy_position 595.190369 cc
2022-08-08T14:56:22.614Z,1659970582.614 [Reporter](INFO): platform_buoyancy_position 590.624146 cc
2022-08-08T14:56:23.083Z,1659970583.083 [Reporter](INFO): platform_buoyancy_position 584.177734 cc
2022-08-08T14:56:23.433Z,1659970583.433 [Reporter](INFO): platform_buoyancy_position 580.014465 cc
2022-08-08T14:56:23.839Z,1659970583.839 [Reporter](INFO): platform_buoyancy_position 575.582581 cc
2022-08-08T14:56:24.242Z,1659970584.242 [Reporter](INFO): platform_buoyancy_position 569.136169 cc
2022-08-08T14:56:24.693Z,1659970584.693 [Reporter](INFO): platform_buoyancy_position 562.689758 cc
2022-08-08T14:56:25.084Z,1659970585.084 [Reporter](INFO): platform_buoyancy_position 558.392151 cc
2022-08-08T14:56:25.449Z,1659970585.449 [Reporter](INFO): platform_buoyancy_position 554.094543 cc
2022-08-08T14:56:25.841Z,1659970585.841 [Reporter](INFO): platform_buoyancy_position 547.782471 cc
2022-08-08T14:56:26.225Z,1659970586.225 [Reporter](INFO): platform_buoyancy_position 543.350525 cc
2022-08-08T14:56:26.631Z,1659970586.631 [Reporter](INFO): platform_buoyancy_position 537.978577 cc
2022-08-08T14:56:27.019Z,1659970587.019 [Reporter](INFO): platform_buoyancy_position 532.606567 cc
2022-08-08T14:56:27.416Z,1659970587.416 [Reporter](INFO): platform_buoyancy_position 528.308960 cc
2022-08-08T14:56:27.805Z,1659970587.805 [Reporter](INFO): platform_buoyancy_position 521.996826 cc
2022-08-08T14:56:28.261Z,1659970588.261 [Reporter](INFO): platform_buoyancy_position 516.356262 cc
2022-08-08T14:56:28.639Z,1659970588.639 [Reporter](INFO): platform_buoyancy_position 511.252838 cc
2022-08-08T14:56:29.042Z,1659970589.042 [Reporter](INFO): platform_buoyancy_position 506.686615 cc
2022-08-08T14:56:29.457Z,1659970589.457 [Reporter](INFO): platform_buoyancy_position 500.508881 cc
2022-08-08T14:56:29.845Z,1659970589.845 [Reporter](INFO): platform_buoyancy_position 496.076935 cc
2022-08-08T14:56:30.253Z,1659970590.253 [Reporter](INFO): platform_buoyancy_position 491.645081 cc
2022-08-08T14:56:30.652Z,1659970590.652 [Reporter](INFO): platform_buoyancy_position 485.467224 cc
2022-08-08T14:56:31.041Z,1659970591.041 [Reporter](INFO): platform_buoyancy_position 480.766724 cc
2022-08-08T14:56:31.456Z,1659970591.456 [Reporter](INFO): platform_buoyancy_position 474.723267 cc
2022-08-08T14:56:31.870Z,1659970591.870 [Reporter](INFO): platform_buoyancy_position 470.291321 cc
2022-08-08T14:56:32.238Z,1659970592.238 [Reporter](INFO): platform_buoyancy_position 463.844971 cc
2022-08-08T14:56:32.649Z,1659970592.649 [Reporter](INFO): platform_buoyancy_position 459.681641 cc
2022-08-08T14:56:33.073Z,1659970593.073 [Reporter](INFO): platform_buoyancy_position 453.369568 cc
2022-08-08T14:56:33.485Z,1659970593.485 [Reporter](INFO): platform_buoyancy_position 448.669067 cc
2022-08-08T14:56:33.857Z,1659970593.857 [Reporter](INFO): platform_buoyancy_position 442.491272 cc
2022-08-08T14:56:34.269Z,1659970594.269 [Reporter](INFO): platform_buoyancy_position 438.059326 cc
2022-08-08T14:56:34.691Z,1659970594.691 [Reporter](INFO): platform_buoyancy_position 431.612976 cc
2022-08-08T14:56:35.104Z,1659970595.104 [Reporter](INFO): platform_buoyancy_position 427.181030 cc
2022-08-08T14:56:35.469Z,1659970595.469 [Reporter](INFO): platform_buoyancy_position 421.003235 cc
2022-08-08T14:56:35.887Z,1659970595.887 [Reporter](INFO): platform_buoyancy_position 416.571350 cc
2022-08-08T14:56:36.281Z,1659970596.281 [Reporter](INFO): platform_buoyancy_position 411.333618 cc
2022-08-08T14:56:36.709Z,1659970596.709 [Reporter](INFO): platform_buoyancy_position 405.827332 cc
2022-08-08T14:56:37.112Z,1659970597.112 [Reporter](INFO): platform_buoyancy_position 399.515259 cc
2022-08-08T14:56:37.497Z,1659970597.497 [Reporter](INFO): platform_buoyancy_position 395.217651 cc
2022-08-08T14:56:37.917Z,1659970597.917 [Reporter](INFO): platform_buoyancy_position 390.651428 cc
2022-08-08T14:56:38.333Z,1659970598.333 [Reporter](INFO): platform_buoyancy_position 384.339355 cc
2022-08-08T14:56:38.729Z,1659970598.729 [Reporter](INFO): platform_buoyancy_position 378.295837 cc
2022-08-08T14:56:39.116Z,1659970599.116 [Reporter](INFO): platform_buoyancy_position 373.729614 cc
2022-08-08T14:56:39.533Z,1659970599.533 [Reporter](INFO): platform_buoyancy_position 369.029114 cc
2022-08-08T14:56:39.949Z,1659970599.949 [Reporter](INFO): platform_buoyancy_position 362.985657 cc
2022-08-08T14:56:40.325Z,1659970600.325 [Reporter](INFO): platform_buoyancy_position 358.419434 cc
2022-08-08T14:56:40.733Z,1659970600.733 [Reporter](INFO): platform_buoyancy_position 352.107361 cc
2022-08-08T14:56:41.153Z,1659970601.153 [Reporter](INFO): platform_buoyancy_position 347.944031 cc
2022-08-08T14:56:41.544Z,1659970601.544 [Reporter](INFO): platform_buoyancy_position 342.706360 cc
2022-08-08T14:56:41.966Z,1659970601.966 [Reporter](INFO): platform_buoyancy_position 337.065735 cc
2022-08-08T14:56:42.352Z,1659970602.352 [Reporter](INFO): platform_buoyancy_position 332.633850 cc
2022-08-08T14:56:42.761Z,1659970602.761 [Reporter](INFO): platform_buoyancy_position 326.590332 cc
2022-08-08T14:56:43.189Z,1659970603.189 [Reporter](INFO): platform_buoyancy_position 320.143921 cc
2022-08-08T14:56:43.560Z,1659970603.560 [Reporter](INFO): platform_buoyancy_position 315.712036 cc
2022-08-08T14:56:43.969Z,1659970603.969 [Reporter](INFO): platform_buoyancy_position 309.668549 cc
2022-08-08T14:56:44.391Z,1659970604.391 [Reporter](INFO): platform_buoyancy_position 304.833740 cc
2022-08-08T14:56:44.797Z,1659970604.797 [Reporter](INFO): platform_buoyancy_position 299.461731 cc
2022-08-08T14:56:45.193Z,1659970605.193 [Reporter](INFO): platform_buoyancy_position 294.224030 cc
2022-08-08T14:56:45.601Z,1659970605.601 [Reporter](INFO): platform_buoyancy_position 289.792145 cc
2022-08-08T14:56:52.804Z,1659970612.804 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-08T14:58:54.295Z,1659970734.295 [CommandExec](IMPORTANT): got command configSet VerticalControl.massDefault 1 centimeter persist
2022-08-08T14:58:58.465Z,1659970738.465 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-08T14:58:58.466Z,1659970738.466 [Default:CheckIn:C.Wait] Stopped
2022-08-08T14:58:58.466Z,1659970738.466 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-08T14:58:58.482Z,1659970738.482 [Default:CheckIn:D] Running Loop=1
2022-08-08T14:58:58.903Z,1659970738.903 [Default:CheckIn:D] Stopped
2022-08-08T14:58:58.903Z,1659970738.903 [Default:CheckIn:E] Running Loop=1
2022-08-08T14:58:59.307Z,1659970739.307 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 45.764486 min 
2022-08-08T14:58:59.307Z,1659970739.307 [Default:CheckIn:E] Stopped
2022-08-08T14:58:59.307Z,1659970739.307 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-08T14:58:59.307Z,1659970739.307 [Default:CheckIn] Stopped
2022-08-08T14:58:59.307Z,1659970739.307 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-08T14:58:59.307Z,1659970739.307 [Default:CheckIn](INFO): Running loop #9
2022-08-08T14:58:59.307Z,1659970739.307 [Default:CheckIn] Running Loop=9
2022-08-08T14:58:59.307Z,1659970739.307 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-08T14:58:59.308Z,1659970739.308 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-08T14:59:01.281Z,1659970741.281 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,145903.00,A,4651.47359,N,09106.23546,W,0.214,332.45,080822,,,D*75
2022-08-08T14:59:01.284Z,1659970741.284 [NAL9602](INFO): GPS fix at 20220808T145903: (46.857893, -91.103924)
2022-08-08T14:59:01.303Z,1659970741.303 [Default:CheckIn:Read_GPS] Stopped
2022-08-08T14:59:01.303Z,1659970741.303 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-08T14:59:11.254Z,1659970751.254 [NAL9602](INFO): SBD MO Status=0, MOMSN=8454, MT Status=0, MTMSN=0
2022-08-08T14:59:11.254Z,1659970751.254 [NAL9602](INFO): No messages in MT queue
2022-08-08T14:59:13.042Z,1659970753.042 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220808T142300/Courier0024.lzma
2022-08-08T14:59:13.937Z,1659970753.937 [CommandExec](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 200 cubic_centimeter
2022-08-08T14:59:14.045Z,1659970754.045 [DataOverHttps](INFO): Moved sent file to Logs/20220808T142300/Courier0024.lzma.bak
2022-08-08T14:59:14.045Z,1659970754.045 [DataOverHttps](INFO): SBD MOMSN=17096558
2022-08-08T14:59:15.681Z,1659970755.681 [Reporter](INFO): platform_buoyancy_position 283.480042 cc
2022-08-08T14:59:16.065Z,1659970756.065 [Reporter](INFO): platform_buoyancy_position 283.345734 cc
2022-08-08T14:59:16.481Z,1659970756.481 [Reporter](INFO): platform_buoyancy_position 281.331238 cc
2022-08-08T14:59:16.877Z,1659970756.877 [Reporter](INFO): platform_buoyancy_position 276.765045 cc
2022-08-08T14:59:17.289Z,1659970757.289 [Reporter](INFO): platform_buoyancy_position 272.601746 cc
2022-08-08T14:59:17.712Z,1659970757.712 [Reporter](INFO): platform_buoyancy_position 268.438446 cc
2022-08-08T14:59:18.083Z,1659970758.083 [Reporter](INFO): platform_buoyancy_position 263.872223 cc
2022-08-08T14:59:18.513Z,1659970758.513 [Reporter](INFO): platform_buoyancy_position 257.560120 cc
2022-08-08T14:59:18.932Z,1659970758.932 [Reporter](INFO): platform_buoyancy_position 253.262558 cc
2022-08-08T14:59:19.330Z,1659970759.330 [Reporter](INFO): platform_buoyancy_position 246.950424 cc
2022-08-08T14:59:19.724Z,1659970759.724 [Reporter](INFO): platform_buoyancy_position 242.518524 cc
2022-08-08T14:59:20.162Z,1659970760.162 [Reporter](INFO): platform_buoyancy_position 236.072144 cc
2022-08-08T14:59:20.547Z,1659970760.547 [Reporter](INFO): platform_buoyancy_position 231.774536 cc
2022-08-08T14:59:20.920Z,1659970760.920 [Reporter](INFO): platform_buoyancy_position 227.611237 cc
2022-08-08T14:59:21.330Z,1659970761.330 [Reporter](INFO): platform_buoyancy_position 221.164825 cc
2022-08-08T14:59:21.745Z,1659970761.745 [Reporter](INFO): platform_buoyancy_position 216.732941 cc
2022-08-08T14:59:22.156Z,1659970762.156 [Reporter](INFO): platform_buoyancy_position 210.286530 cc
2022-08-08T14:59:22.548Z,1659970762.548 [Reporter](INFO): platform_buoyancy_position 206.123230 cc
2022-08-08T14:59:30.171Z,1659970770.171 [DataOverHttps](INFO): Sending 601 bytes from file Logs/20220808T142300/Express0025.lzma
2022-08-08T14:59:31.172Z,1659970771.172 [DataOverHttps](INFO): Moved sent file to Logs/20220808T142300/Express0025.lzma.bak
2022-08-08T14:59:31.172Z,1659970771.172 [DataOverHttps](INFO): SBD MOMSN=17096561
2022-08-08T14:59:33.040Z,1659970773.040 [Default:CheckIn:Read_Iridium] Stopped
2022-08-08T14:59:33.040Z,1659970773.040 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-08T14:59:33.040Z,1659970773.040 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-08T14:59:41.867Z,1659970781.867 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-08T15:03:55.860Z,1659971035.860 [CommandExec](IMPORTANT): got command configSet VerticalControl.massDefault 1.25 centimeter persist
2022-08-08T15:04:33.583Z,1659971073.583 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-08T15:04:33.584Z,1659971073.584 [Default:CheckIn:C.Wait] Stopped
2022-08-08T15:04:33.584Z,1659971073.584 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-08T15:04:33.584Z,1659971073.584 [Default:CheckIn:D] Running Loop=1
2022-08-08T15:04:33.997Z,1659971073.997 [Default:CheckIn:D] Stopped
2022-08-08T15:04:33.997Z,1659971073.997 [Default:CheckIn:E] Running Loop=1
2022-08-08T15:04:34.405Z,1659971074.405 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 51.349390 min 
2022-08-08T15:04:34.405Z,1659971074.405 [Default:CheckIn:E] Stopped
2022-08-08T15:04:34.405Z,1659971074.405 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-08T15:04:34.405Z,1659971074.405 [Default:CheckIn] Stopped
2022-08-08T15:04:34.406Z,1659971074.406 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-08T15:04:34.406Z,1659971074.406 [Default:CheckIn](INFO): Running loop #10
2022-08-08T15:04:34.406Z,1659971074.406 [Default:CheckIn] Running Loop=10
2022-08-08T15:04:34.406Z,1659971074.406 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-08T15:04:34.406Z,1659971074.406 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-08T15:04:36.320Z,1659971076.320 [CommandExec](IMPORTANT): got command configSet VerticalControl.buoyancyNeutral 200 cubic_centimeter persist
2022-08-08T15:04:36.405Z,1659971076.405 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,150438.00,A,4651.47136,N,09106.23875,W,0.097,274.12,080822,,,D*7A
2022-08-08T15:04:36.408Z,1659971076.408 [NAL9602](INFO): GPS fix at 20220808T150438: (46.857856, -91.103979)
2022-08-08T15:04:36.446Z,1659971076.446 [Default:CheckIn:Read_GPS] Stopped
2022-08-08T15:04:36.446Z,1659971076.446 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-08T15:04:43.378Z,1659971083.378 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220808T142300/Courier0027.lzma
2022-08-08T15:04:44.380Z,1659971084.380 [DataOverHttps](INFO): Moved sent file to Logs/20220808T142300/Courier0027.lzma.bak
2022-08-08T15:04:44.380Z,1659971084.380 [DataOverHttps](INFO): SBD MOMSN=17096570
2022-08-08T15:04:51.849Z,1659971091.849 [NAL9602](INFO): SBD MO Status=0, MOMSN=8455, MT Status=0, MTMSN=0
2022-08-08T15:04:51.849Z,1659971091.849 [NAL9602](INFO): No messages in MT queue
2022-08-08T15:04:58.881Z,1659971098.881 [CommandExec](IMPORTANT): got command configSet list
2022-08-08T15:04:58.881Z,1659971098.881 [CommandExec](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2022-08-08T15:04:58.886Z,1659971098.886 [CommandExec](IMPORTANT): BPC1.batteryMissingStickThreshold=4 count;

2022-08-08T15:04:58.886Z,1659971098.886 [CommandExec](IMPORTANT): BackseatComponent.alwaysOn=0 bool;

2022-08-08T15:04:58.887Z,1659971098.887 [CommandExec](IMPORTANT): CTD_Seabird.minSalinityBound=0 practical_salinity_unit;

2022-08-08T15:04:58.887Z,1659971098.887 [CommandExec](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;

2022-08-08T15:04:58.887Z,1659971098.887 [CommandExec](IMPORTANT): Express none WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water;

2022-08-08T15:04:58.887Z,1659971098.887 [CommandExec](IMPORTANT): Express linearApproximation platform_roll_angle 5.000000 degree;

2022-08-08T15:04:58.887Z,1659971098.887 [CommandExec](IMPORTANT): VerticalControl.buoyancyNeutral=200 cubic_centimeter;

2022-08-08T15:04:58.887Z,1659971098.887 [CommandExec](IMPORTANT): VerticalControl.massDefault=1.25 centimeter;

2022-08-08T15:05:00.571Z,1659971100.571 [DataOverHttps](INFO): Sending 676 bytes from file Logs/20220808T142300/Express0028.lzma
2022-08-08T15:05:01.572Z,1659971101.572 [DataOverHttps](INFO): Moved sent file to Logs/20220808T142300/Express0028.lzma.bak
2022-08-08T15:05:01.572Z,1659971101.572 [DataOverHttps](INFO): SBD MOMSN=17096573
2022-08-08T15:05:03.607Z,1659971103.607 [Default:CheckIn:Read_Iridium] Stopped
2022-08-08T15:05:03.607Z,1659971103.607 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-08T15:05:03.607Z,1659971103.607 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-08T15:05:15.180Z,1659971115.180 [CommandExec](IMPORTANT): got command maintain clear
2022-08-08T15:05:15.369Z,1659971115.369 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2022-08-08T15:05:17.059Z,1659971117.059 [CommandExec](IMPORTANT): got command report clear
2022-08-08T15:05:22.591Z,1659971122.591 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-08T15:10:04.309Z,1659971404.309 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-08T15:10:04.309Z,1659971404.309 [Default:CheckIn:C.Wait] Stopped
2022-08-08T15:10:04.309Z,1659971404.309 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-08T15:10:04.310Z,1659971404.310 [Default:CheckIn:D] Running Loop=1
2022-08-08T15:10:04.716Z,1659971404.716 [Default:CheckIn:D] Stopped
2022-08-08T15:10:04.717Z,1659971404.717 [Default:CheckIn:E] Running Loop=1
2022-08-08T15:10:05.111Z,1659971405.111 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 56.861381 min 
2022-08-08T15:10:05.111Z,1659971405.111 [Default:CheckIn:E] Stopped
2022-08-08T15:10:05.111Z,1659971405.111 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-08T15:10:05.111Z,1659971405.111 [Default:CheckIn] Stopped
2022-08-08T15:10:05.111Z,1659971405.111 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-08T15:10:05.111Z,1659971405.111 [Default:CheckIn](INFO): Running loop #11
2022-08-08T15:10:05.111Z,1659971405.111 [Default:CheckIn] Running Loop=11
2022-08-08T15:10:05.111Z,1659971405.111 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-08T15:10:05.111Z,1659971405.111 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-08T15:10:07.109Z,1659971407.109 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,151009.00,A,4651.46616,N,09106.22649,W,0.097,59.28,080822,,,D*4D
2022-08-08T15:10:07.111Z,1659971407.111 [NAL9602](INFO): GPS fix at 20220808T151009: (46.857769, -91.103775)
2022-08-08T15:10:07.122Z,1659971407.122 [Default:CheckIn:Read_GPS] Stopped
2022-08-08T15:10:07.122Z,1659971407.122 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-08T15:10:14.810Z,1659971414.810 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220808T142300/Courier0030.lzma
2022-08-08T15:10:15.812Z,1659971415.812 [DataOverHttps](INFO): Moved sent file to Logs/20220808T142300/Courier0030.lzma.bak
2022-08-08T15:10:15.812Z,1659971415.812 [DataOverHttps](INFO): SBD MOMSN=17096581
2022-08-08T15:10:28.181Z,1659971428.181 [NAL9602](INFO): SBD MO Status=0, MOMSN=8456, MT Status=0, MTMSN=0
2022-08-08T15:10:28.181Z,1659971428.181 [NAL9602](INFO): No messages in MT queue
2022-08-08T15:10:36.331Z,1659971436.331 [DataOverHttps](INFO): Sending 1096 bytes from file Logs/20220808T142300/Express0031.lzma
2022-08-08T15:10:37.333Z,1659971437.333 [DataOverHttps](INFO): Moved sent file to Logs/20220808T142300/Express0031.lzma.bak
2022-08-08T15:10:37.333Z,1659971437.333 [DataOverHttps](INFO): SBD MOMSN=17096584
2022-08-08T15:10:39.106Z,1659971439.106 [Default:CheckIn:Read_Iridium] Stopped
2022-08-08T15:10:39.106Z,1659971439.106 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-08T15:10:39.106Z,1659971439.106 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-08T15:10:58.888Z,1659971458.888 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-08T15:15:39.735Z,1659971739.735 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-08T15:15:39.735Z,1659971739.735 [Default:CheckIn:C.Wait] Stopped
2022-08-08T15:15:39.735Z,1659971739.735 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-08T15:15:39.735Z,1659971739.735 [Default:CheckIn:D] Running Loop=1
2022-08-08T15:15:40.129Z,1659971740.129 [Default:CheckIn:D] Stopped
2022-08-08T15:15:40.129Z,1659971740.129 [Default:CheckIn:E] Running Loop=1
2022-08-08T15:15:40.532Z,1659971740.532 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 62.451583 min 
2022-08-08T15:15:40.532Z,1659971740.532 [Default:CheckIn:E] Stopped
2022-08-08T15:15:40.532Z,1659971740.532 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-08T15:15:40.532Z,1659971740.532 [Default:CheckIn] Stopped
2022-08-08T15:15:40.532Z,1659971740.532 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-08T15:15:40.533Z,1659971740.533 [Default:CheckIn](INFO): Running loop #12
2022-08-08T15:15:40.533Z,1659971740.533 [Default:CheckIn] Running Loop=12
2022-08-08T15:15:40.533Z,1659971740.533 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-08T15:15:40.533Z,1659971740.533 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-08T15:15:42.541Z,1659971742.541 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,151544.00,A,4651.46498,N,09106.22661,W,0.311,107.41,080822,,,D*77
2022-08-08T15:15:42.551Z,1659971742.551 [NAL9602](INFO): GPS fix at 20220808T151544: (46.857750, -91.103777)
2022-08-08T15:15:42.563Z,1659971742.563 [Default:CheckIn:Read_GPS] Stopped
2022-08-08T15:15:42.563Z,1659971742.563 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-08T15:15:49.742Z,1659971749.742 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220808T142300/Courier0033.lzma
2022-08-08T15:15:50.744Z,1659971750.744 [DataOverHttps](INFO): Moved sent file to Logs/20220808T142300/Courier0033.lzma.bak
2022-08-08T15:15:50.744Z,1659971750.744 [DataOverHttps](INFO): SBD MOMSN=17096600
2022-08-08T15:16:06.706Z,1659971766.706 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20220808T142300/Express0034.lzma
2022-08-08T15:16:07.584Z,1659971767.584 [NAL9602](INFO): SBD MO Status=2, MOMSN=8457, MT Status=2, MTMSN=0
2022-08-08T15:16:07.584Z,1659971767.584 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-08-08T15:16:07.709Z,1659971767.709 [DataOverHttps](INFO): Moved sent file to Logs/20220808T142300/Express0034.lzma.bak
2022-08-08T15:16:07.709Z,1659971767.709 [DataOverHttps](INFO): SBD MOMSN=17096604
2022-08-08T15:16:09.220Z,1659971769.220 [Default:CheckIn:Read_Iridium] Stopped
2022-08-08T15:16:09.220Z,1659971769.220 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-08T15:16:09.220Z,1659971769.220 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-08T15:17:17.882Z,1659971837.882 [NAL9602](INFO): SBD MO Status=0, MOMSN=8457, MT Status=0, MTMSN=0
2022-08-08T15:17:17.882Z,1659971837.882 [NAL9602](INFO): No messages in MT queue
2022-08-08T15:17:48.582Z,1659971868.582 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-08T15:21:09.833Z,1659972069.833 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-08T15:21:09.833Z,1659972069.833 [Default:CheckIn:C.Wait] Stopped
2022-08-08T15:21:09.833Z,1659972069.833 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-08T15:21:09.834Z,1659972069.834 [Default:CheckIn:D] Running Loop=1
2022-08-08T15:21:10.237Z,1659972070.237 [Default:CheckIn:D] Stopped
2022-08-08T15:21:10.237Z,1659972070.237 [Default:CheckIn:E] Running Loop=1
2022-08-08T15:21:10.633Z,1659972070.633 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 67.953389 min 
2022-08-08T15:21:10.633Z,1659972070.633 [Default:CheckIn:E] Stopped
2022-08-08T15:21:10.634Z,1659972070.634 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-08T15:21:10.634Z,1659972070.634 [Default:CheckIn] Stopped
2022-08-08T15:21:10.634Z,1659972070.634 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-08T15:21:10.634Z,1659972070.634 [Default:CheckIn](INFO): Running loop #13
2022-08-08T15:21:10.634Z,1659972070.634 [Default:CheckIn] Running Loop=13
2022-08-08T15:21:10.634Z,1659972070.634 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-08T15:21:10.634Z,1659972070.634 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-08T15:21:12.645Z,1659972072.645 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,152114.00,A,4651.46841,N,09106.22688,W,0.156,197.87,080822,,,A*7D
2022-08-08T15:21:12.648Z,1659972072.648 [NAL9602](INFO): GPS fix at 20220808T152114: (46.857807, -91.103781)
2022-08-08T15:21:12.659Z,1659972072.659 [Default:CheckIn:Read_GPS] Stopped
2022-08-08T15:21:12.659Z,1659972072.659 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-08T15:21:20.862Z,1659972080.862 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220808T142300/Courier0036.lzma
2022-08-08T15:21:21.865Z,1659972081.865 [DataOverHttps](INFO): Moved sent file to Logs/20220808T142300/Courier0036.lzma.bak
2022-08-08T15:21:21.865Z,1659972081.865 [DataOverHttps](INFO): SBD MOMSN=17096608
2022-08-08T15:21:37.706Z,1659972097.706 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20220808T142300/Express0037.lzma
2022-08-08T15:21:38.709Z,1659972098.709 [DataOverHttps](INFO): Moved sent file to Logs/20220808T142300/Express0037.lzma.bak
2022-08-08T15:21:38.709Z,1659972098.709 [DataOverHttps](INFO): SBD MOMSN=17096612
2022-08-08T15:21:40.541Z,1659972100.541 [Default:CheckIn:Read_Iridium] Stopped
2022-08-08T15:21:40.541Z,1659972100.541 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-08T15:21:40.541Z,1659972100.541 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-08T15:22:03.145Z,1659972123.145 [NAL9602](INFO): SBD MO Status=2, MOMSN=8458, MT Status=2, MTMSN=0
2022-08-08T15:22:03.145Z,1659972123.145 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-08-08T15:22:20.141Z,1659972140.141 [NAL9602](INFO): SBD MO Status=2, MOMSN=8458, MT Status=2, MTMSN=0
2022-08-08T15:22:20.142Z,1659972140.142 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-08-08T15:22:50.865Z,1659972170.865 [NAL9602](INFO): SBD MO Status=2, MOMSN=8458, MT Status=2, MTMSN=0
2022-08-08T15:22:50.865Z,1659972170.865 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-08-08T15:23:21.835Z,1659972201.835 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2022-08-08T15:23:25.851Z,1659972205.851 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.315392
2022-08-08T15:24:31.110Z,1659972271.110 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-08-08T15:24:46.610Z,1659972286.610 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002988
2022-08-08T15:26:15.316Z,1659972375.316 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-08-08T15:26:41.189Z,1659972401.189 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-08T15:26:41.189Z,1659972401.189 [Default:CheckIn:C.Wait] Stopped
2022-08-08T15:26:41.189Z,1659972401.189 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-08T15:26:41.189Z,1659972401.189 [Default:CheckIn:D] Running Loop=1
2022-08-08T15:26:41.592Z,1659972401.592 [Default:CheckIn:D] Stopped
2022-08-08T15:26:41.592Z,1659972401.592 [Default:CheckIn:E] Running Loop=1
2022-08-08T15:26:41.989Z,1659972401.989 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 73.475977 min 
2022-08-08T15:26:41.989Z,1659972401.989 [Default:CheckIn:E] Stopped
2022-08-08T15:26:41.989Z,1659972401.989 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-08T15:26:41.989Z,1659972401.989 [Default:CheckIn] Stopped
2022-08-08T15:26:41.990Z,1659972401.990 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-08T15:26:41.990Z,1659972401.990 [Default:CheckIn](INFO): Running loop #14
2022-08-08T15:26:41.990Z,1659972401.990 [Default:CheckIn] Running Loop=14
2022-08-08T15:26:41.990Z,1659972401.990 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-08T15:26:41.991Z,1659972401.991 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-08T15:26:44.001Z,1659972404.001 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,152646.00,A,4651.46063,N,09106.21907,W,0.233,149.53,080822,,,A*74
2022-08-08T15:26:44.004Z,1659972404.004 [NAL9602](INFO): GPS fix at 20220808T152646: (46.857677, -91.103651)
2022-08-08T15:26:44.014Z,1659972404.014 [Default:CheckIn:Read_GPS] Stopped
2022-08-08T15:26:44.014Z,1659972404.014 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-08T15:26:51.898Z,1659972411.898 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220808T142300/Courier0039.lzma
2022-08-08T15:26:54.908Z,1659972414.908 [DataOverHttps](INFO): Moved sent file to Logs/20220808T142300/Courier0039.lzma.bak
2022-08-08T15:26:54.908Z,1659972414.908 [DataOverHttps](INFO): SBD MOMSN=17096615
2022-08-08T15:27:16.447Z,1659972436.447 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-08T15:27:29.022Z,1659972449.022 [DataOverHttps](INFO): Sending 241 bytes from file Logs/20220808T142300/Express0040.lzma
2022-08-08T15:27:35.044Z,1659972455.044 [DataOverHttps](INFO): Moved sent file to Logs/20220808T142300/Express0040.lzma.bak
2022-08-08T15:27:35.044Z,1659972455.044 [DataOverHttps](INFO): SBD MOMSN=17096618
2022-08-08T15:27:36.672Z,1659972456.672 [Default:CheckIn:Read_Iridium] Stopped
2022-08-08T15:27:36.672Z,1659972456.672 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-08T15:27:36.672Z,1659972456.672 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-08T15:28:06.639Z,1659972486.639 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2022-08-08T15:29:57.207Z,1659972597.207 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.623184
2022-08-08T15:30:11.678Z,1659972611.678 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002973
2022-08-08T15:32:30.921Z,1659972750.921 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.262020
2022-08-08T15:32:37.237Z,1659972757.237 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-08T15:32:37.237Z,1659972757.237 [Default:CheckIn:C.Wait] Stopped
2022-08-08T15:32:37.237Z,1659972757.237 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-08T15:32:37.238Z,1659972757.238 [Default:CheckIn:D] Running Loop=1
2022-08-08T15:32:37.649Z,1659972757.649 [Default:CheckIn:D] Stopped
2022-08-08T15:32:37.649Z,1659972757.649 [Default:CheckIn:E] Running Loop=1
2022-08-08T15:32:38.045Z,1659972758.045 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 79.410254 min 
2022-08-08T15:32:38.045Z,1659972758.045 [Default:CheckIn:E] Stopped
2022-08-08T15:32:38.045Z,1659972758.045 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-08T15:32:38.045Z,1659972758.045 [Default:CheckIn] Stopped
2022-08-08T15:32:38.046Z,1659972758.046 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-08T15:32:38.046Z,1659972758.046 [Default:CheckIn](INFO): Running loop #15
2022-08-08T15:32:38.046Z,1659972758.046 [Default:CheckIn] Running Loop=15
2022-08-08T15:32:38.046Z,1659972758.046 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-08T15:32:38.046Z,1659972758.046 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-08T15:32:40.052Z,1659972760.052 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,153242.00,A,4651.22219,N,09106.04677,W,21.577,89.15,080822,,,A*7C
2022-08-08T15:32:40.055Z,1659972760.055 [NAL9602](INFO): GPS fix at 20220808T153242: (46.853703, -91.100780)
2022-08-08T15:32:40.065Z,1659972760.065 [Default:CheckIn:Read_GPS] Stopped
2022-08-08T15:32:40.065Z,1659972760.065 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-08T15:33:25.139Z,1659972805.139 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-08-08T15:33:41.652Z,1659972821.652 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003808
2022-08-08T15:33:49.002Z,1659972829.002 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220808T142300/Courier0042.lzma
2022-08-08T15:33:50.004Z,1659972830.004 [DataOverHttps](INFO): Moved sent file to Logs/20220808T142300/Courier0042.lzma.bak
2022-08-08T15:33:50.004Z,1659972830.004 [DataOverHttps](INFO): SBD MOMSN=17096622
2022-08-08T15:34:05.750Z,1659972845.750 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20220808T142300/Express0043.lzma
2022-08-08T15:34:07.756Z,1659972847.756 [DataOverHttps](INFO): Moved sent file to Logs/20220808T142300/Express0043.lzma.bak
2022-08-08T15:34:07.756Z,1659972847.756 [DataOverHttps](INFO): SBD MOMSN=17096625
2022-08-08T15:34:09.470Z,1659972849.470 [Default:CheckIn:Read_Iridium] Stopped
2022-08-08T15:34:09.471Z,1659972849.471 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-08T15:34:09.471Z,1659972849.471 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-08T15:34:12.275Z,1659972852.275 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-08T15:35:58.940Z,1659972958.940 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-08T15:36:12.676Z,1659972972.676 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-08T15:36:26.007Z,1659972986.007 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-08T15:36:39.372Z,1659972999.372 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-08T15:36:52.687Z,1659973012.687 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-08T15:37:06.430Z,1659973026.430 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-08T15:37:19.772Z,1659973039.772 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-08T15:37:33.910Z,1659973053.910 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-08T15:37:47.648Z,1659973067.648 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-08T15:38:01.382Z,1659973081.382 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-08T15:38:14.714Z,1659973094.714 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-08T15:39:10.099Z,1659973150.099 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-08T15:39:10.099Z,1659973150.099 [Default:CheckIn:C.Wait] Stopped
2022-08-08T15:39:10.099Z,1659973150.099 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-08T15:39:10.099Z,1659973150.099 [Default:CheckIn:D] Running Loop=1
2022-08-08T15:39:10.487Z,1659973150.487 [Default:CheckIn:D] Stopped
2022-08-08T15:39:10.487Z,1659973150.487 [Default:CheckIn:E] Running Loop=1
2022-08-08T15:39:10.889Z,1659973150.889 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 85.957560 min 
2022-08-08T15:39:10.889Z,1659973150.889 [Default:CheckIn:E] Stopped
2022-08-08T15:39:10.889Z,1659973150.889 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-08T15:39:10.889Z,1659973150.889 [Default:CheckIn] Stopped
2022-08-08T15:39:10.889Z,1659973150.889 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-08T15:39:10.889Z,1659973150.889 [Default:CheckIn](INFO): Running loop #16
2022-08-08T15:39:10.890Z,1659973150.890 [Default:CheckIn] Running Loop=16
2022-08-08T15:39:10.890Z,1659973150.890 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-08T15:39:10.890Z,1659973150.890 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-08T15:39:12.905Z,1659973152.905 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,153915.00,A,4646.91429,N,09104.94101,W,43.192,168.77,080822,,,A*42
2022-08-08T15:39:12.908Z,1659973152.908 [NAL9602](INFO): GPS fix at 20220808T153915: (46.781905, -91.082350)
2022-08-08T15:39:12.919Z,1659973152.919 [Default:CheckIn:Read_GPS] Stopped
2022-08-08T15:39:12.919Z,1659973152.919 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-08T15:39:19.978Z,1659973159.978 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220808T142300/Courier0045.lzma
2022-08-08T15:39:20.980Z,1659973160.980 [DataOverHttps](INFO): Moved sent file to Logs/20220808T142300/Courier0045.lzma.bak
2022-08-08T15:39:20.980Z,1659973160.980 [DataOverHttps](INFO): SBD MOMSN=17096628
2022-08-08T15:39:37.006Z,1659973177.006 [DataOverHttps](INFO): Sending 185 bytes from file Logs/20220808T142300/Express0046.lzma
2022-08-08T15:39:38.008Z,1659973178.008 [DataOverHttps](INFO): Moved sent file to Logs/20220808T142300/Express0046.lzma.bak
2022-08-08T15:39:38.008Z,1659973178.008 [DataOverHttps](INFO): SBD MOMSN=17096631
2022-08-08T15:39:39.677Z,1659973179.677 [Default:CheckIn:Read_Iridium] Stopped
2022-08-08T15:39:39.677Z,1659973179.677 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-08T15:39:39.677Z,1659973179.677 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-08T15:41:56.619Z,1659973316.619 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-08-08T15:41:58.627Z,1659973318.627 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.259300
2022-08-08T15:42:31.763Z,1659973351.763 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-08-08T15:43:06.903Z,1659973386.903 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-08-08T15:43:42.054Z,1659973422.054 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-08-08T15:44:15.188Z,1659973455.188 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-08-08T15:44:17.195Z,1659973457.195 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-08-08T15:44:40.248Z,1659973480.248 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-08T15:44:40.248Z,1659973480.248 [Default:CheckIn:C.Wait] Stopped
2022-08-08T15:44:40.248Z,1659973480.248 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-08T15:44:40.248Z,1659973480.248 [Default:CheckIn:D] Running Loop=1
2022-08-08T15:44:40.661Z,1659973480.661 [Default:CheckIn:D] Stopped
2022-08-08T15:44:40.661Z,1659973480.661 [Default:CheckIn:E] Running Loop=1
2022-08-08T15:44:41.069Z,1659973481.069 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 91.460449 min 
2022-08-08T15:44:41.070Z,1659973481.070 [Default:CheckIn:E] Stopped
2022-08-08T15:44:41.070Z,1659973481.070 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-08T15:44:41.070Z,1659973481.070 [Default:CheckIn] Stopped
2022-08-08T15:44:41.070Z,1659973481.070 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-08T15:44:41.070Z,1659973481.070 [Default:CheckIn](INFO): Running loop #17
2022-08-08T15:44:41.070Z,1659973481.070 [Default:CheckIn] Running Loop=17
2022-08-08T15:44:41.070Z,1659973481.070 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-08T15:44:41.071Z,1659973481.071 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-08T15:44:43.119Z,1659973483.119 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154445.00,A,4643.66364,N,09103.02183,W,44.864,140.90,080822,,,A*48
2022-08-08T15:44:43.122Z,1659973483.122 [NAL9602](INFO): GPS fix at 20220808T154445: (46.727727, -91.050364)
2022-08-08T15:44:43.165Z,1659973483.165 [Default:CheckIn:Read_GPS] Stopped
2022-08-08T15:44:43.165Z,1659973483.165 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-08T15:44:52.375Z,1659973492.375 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-08-08T15:45:13.768Z,1659973513.768 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response. 
2022-08-08T15:45:13.850Z,1659973513.850 [NAL9602](ERROR): received: 
+CSQ:0

OK58, 2, 0, 0, 0

OK
2022-08-08T15:45:14.004Z,1659973514.004 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.008905
2022-08-08T15:45:21.890Z,1659973521.890 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220808T142300/Courier0048.lzma
2022-08-08T15:45:22.892Z,1659973522.892 [DataOverHttps](INFO): Moved sent file to Logs/20220808T142300/Courier0048.lzma.bak
2022-08-08T15:45:22.892Z,1659973522.892 [DataOverHttps](INFO): SBD MOMSN=17096634
2022-08-08T15:45:37.644Z,1659973537.644 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-08T15:45:37.645Z,1659973537.645 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5.
2022-08-08T15:45:37.676Z,1659973537.676 [BPC1](INFO): Received data from all battery sticks.
2022-08-08T15:45:41.840Z,1659973541.840 [DataOverHttps](INFO): Sending 243 bytes from file Logs/20220808T142300/Express0049.lzma
2022-08-08T15:45:42.840Z,1659973542.840 [DataOverHttps](INFO): Moved sent file to Logs/20220808T142300/Express0049.lzma.bak
2022-08-08T15:45:42.840Z,1659973542.840 [DataOverHttps](INFO): SBD MOMSN=17096637
2022-08-08T15:45:44.532Z,1659973544.532 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-08T15:45:44.551Z,1659973544.551 [Default:CheckIn:Read_Iridium] Stopped
2022-08-08T15:45:44.551Z,1659973544.551 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-08T15:45:44.551Z,1659973544.551 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-08T15:50:45.241Z,1659973845.241 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-08T15:50:45.241Z,1659973845.241 [Default:CheckIn:C.Wait] Stopped
2022-08-08T15:50:45.241Z,1659973845.241 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-08T15:50:45.241Z,1659973845.241 [Default:CheckIn:D] Running Loop=1
2022-08-08T15:50:45.646Z,1659973845.646 [Default:CheckIn:D] Stopped
2022-08-08T15:50:45.646Z,1659973845.646 [Default:CheckIn:E] Running Loop=1
2022-08-08T15:50:46.040Z,1659973846.040 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 97.543530 min 
2022-08-08T15:50:46.040Z,1659973846.040 [Default:CheckIn:E] Stopped
2022-08-08T15:50:46.040Z,1659973846.040 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-08T15:50:46.040Z,1659973846.040 [Default:CheckIn] Stopped
2022-08-08T15:50:46.040Z,1659973846.040 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-08T15:50:46.041Z,1659973846.041 [Default:CheckIn](INFO): Running loop #18
2022-08-08T15:50:46.041Z,1659973846.041 [Default:CheckIn] Running Loop=18
2022-08-08T15:50:46.041Z,1659973846.041 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-08T15:50:46.041Z,1659973846.041 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-08T15:50:48.089Z,1659973848.089 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155050.00,A,4641.31763,N,09057.46095,W,47.857,89.69,080822,,,A*7D
2022-08-08T15:50:48.124Z,1659973848.124 [NAL9602](INFO): GPS fix at 20220808T155050: (46.688627, -90.957683)
2022-08-08T15:50:48.186Z,1659973848.186 [Default:CheckIn:Read_GPS] Stopped
2022-08-08T15:50:48.186Z,1659973848.186 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-08T15:50:55.542Z,1659973855.542 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20220808T142300/Courier0051.lzma
2022-08-08T15:50:56.545Z,1659973856.545 [DataOverHttps](INFO): Moved sent file to Logs/20220808T142300/Courier0051.lzma.bak
2022-08-08T15:50:56.545Z,1659973856.545 [DataOverHttps](INFO): SBD MOMSN=17096641
2022-08-08T15:51:22.387Z,1659973882.387 [DataOverHttps](INFO): Sending 199 bytes from file Logs/20220808T142300/Express0052.lzma
2022-08-08T15:51:24.392Z,1659973884.392 [DataOverHttps](INFO): Moved sent file to Logs/20220808T142300/Express0052.lzma.bak
2022-08-08T15:51:24.392Z,1659973884.392 [DataOverHttps](INFO): SBD MOMSN=17096644
2022-08-08T15:51:26.169Z,1659973886.169 [Default:CheckIn:Read_Iridium] Stopped
2022-08-08T15:51:26.170Z,1659973886.170 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-08T15:51:26.170Z,1659973886.170 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-08T15:52:25.059Z,1659973945.059 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.667110
2022-08-08T15:53:06.223Z,1659973986.223 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-08-08T15:53:18.686Z,1659973998.686 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002941
2022-08-08T15:55:50.448Z,1659974150.448 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-08-08T15:56:21.143Z,1659974181.143 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-08T15:56:26.813Z,1659974186.813 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-08T15:56:26.813Z,1659974186.813 [Default:CheckIn:C.Wait] Stopped
2022-08-08T15:56:26.813Z,1659974186.813 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-08T15:56:26.815Z,1659974186.815 [Default:CheckIn:D] Running Loop=1
2022-08-08T15:56:27.225Z,1659974187.225 [Default:CheckIn:D] Stopped
2022-08-08T15:56:27.225Z,1659974187.225 [Default:CheckIn:E] Running Loop=1
2022-08-08T15:56:27.618Z,1659974187.618 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 103.236523 min 
2022-08-08T15:56:27.618Z,1659974187.618 [Default:CheckIn:E] Stopped
2022-08-08T15:56:27.618Z,1659974187.618 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-08T15:56:27.619Z,1659974187.619 [Default:CheckIn] Stopped
2022-08-08T15:56:27.619Z,1659974187.619 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-08T15:56:27.619Z,1659974187.619 [Default:CheckIn](INFO): Running loop #19
2022-08-08T15:56:27.619Z,1659974187.619 [Default:CheckIn] Running Loop=19
2022-08-08T15:56:27.619Z,1659974187.619 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-08T15:56:27.619Z,1659974187.619 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-08T15:56:29.629Z,1659974189.629 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155631.00,A,4640.21838,N,09054.84530,W,29.352,251.20,080822,,,A*46
2022-08-08T15:56:29.631Z,1659974189.631 [NAL9602](INFO): GPS fix at 20220808T155631: (46.670306, -90.914088)
2022-08-08T15:56:29.641Z,1659974189.641 [Default:CheckIn:Read_GPS] Stopped
2022-08-08T15:56:29.641Z,1659974189.641 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-08T15:56:37.250Z,1659974197.250 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220808T142300/Courier0054.lzma
2022-08-08T15:56:38.252Z,1659974198.252 [DataOverHttps](INFO): Moved sent file to Logs/20220808T142300/Courier0054.lzma.bak
2022-08-08T15:56:38.252Z,1659974198.252 [DataOverHttps](INFO): SBD MOMSN=17096647
2022-08-08T15:56:54.238Z,1659974214.238 [DataOverHttps](INFO): Sending 244 bytes from file Logs/20220808T142300/Express0055.lzma
2022-08-08T15:56:55.240Z,1659974215.240 [DataOverHttps](INFO): Moved sent file to Logs/20220808T142300/Express0055.lzma.bak
2022-08-08T15:56:55.240Z,1659974215.240 [DataOverHttps](INFO): SBD MOMSN=17096650
2022-08-08T15:56:57.115Z,1659974217.115 [Default:CheckIn:Read_Iridium] Stopped
2022-08-08T15:56:57.115Z,1659974217.115 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-08T15:56:57.115Z,1659974217.115 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-08T15:57:00.336Z,1659974220.336 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-08-08T15:57:00.410Z,1659974220.410 [NAL9602](ERROR): received: 
+CSQ:0

OK58, 2, 0, 0, 0

OK
2022-08-08T16:01:32.303Z,1659974492.303 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-08-08T16:01:57.759Z,1659974517.759 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-08T16:01:57.760Z,1659974517.760 [Default:CheckIn:C.Wait] Stopped
2022-08-08T16:01:57.760Z,1659974517.760 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-08T16:01:57.760Z,1659974517.760 [Default:CheckIn:D] Running Loop=1
2022-08-08T16:01:58.169Z,1659974518.169 [Default:CheckIn:D] Stopped
2022-08-08T16:01:58.169Z,1659974518.169 [Default:CheckIn:E] Running Loop=1
2022-08-08T16:01:58.570Z,1659974518.570 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 108.752262 min 
2022-08-08T16:01:58.570Z,1659974518.570 [Default:CheckIn:E] Stopped
2022-08-08T16:01:58.570Z,1659974518.570 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-08T16:01:58.570Z,1659974518.570 [Default:CheckIn] Stopped
2022-08-08T16:01:58.570Z,1659974518.570 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-08T16:01:58.571Z,1659974518.571 [Default:CheckIn](INFO): Running loop #20
2022-08-08T16:01:58.571Z,1659974518.571 [Default:CheckIn] Running Loop=20
2022-08-08T16:01:58.571Z,1659974518.571 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-08T16:01:58.571Z,1659974518.571 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-08T16:02:00.585Z,1659974520.585 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160202.00,A,4636.25733,N,09057.05422,W,48.985,221.72,080822,,,A*4A
2022-08-08T16:02:00.587Z,1659974520.587 [NAL9602](INFO): GPS fix at 20220808T160202: (46.604289, -90.950904)
2022-08-08T16:02:00.613Z,1659974520.613 [Default:CheckIn:Read_GPS] Stopped
2022-08-08T16:02:00.613Z,1659974520.613 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-08T16:02:07.891Z,1659974527.891 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220808T142300/Courier0057.lzma
2022-08-08T16:02:08.884Z,1659974528.884 [DataOverHttps](INFO): Moved sent file to Logs/20220808T142300/Courier0057.lzma.bak
2022-08-08T16:02:08.884Z,1659974528.884 [DataOverHttps](INFO): SBD MOMSN=17096654
2022-08-08T16:02:24.874Z,1659974544.874 [DataOverHttps](INFO): Sending 244 bytes from file Logs/20220808T142300/Express0058.lzma
2022-08-08T16:02:25.876Z,1659974545.876 [DataOverHttps](INFO): Moved sent file to Logs/20220808T142300/Express0058.lzma.bak
2022-08-08T16:02:25.876Z,1659974545.876 [DataOverHttps](INFO): SBD MOMSN=17096657
2022-08-08T16:02:27.663Z,1659974547.663 [Default:CheckIn:Read_Iridium] Stopped
2022-08-08T16:02:27.663Z,1659974547.663 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-08T16:02:27.663Z,1659974547.663 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-08T16:02:33.303Z,1659974553.303 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-08T16:07:28.340Z,1659974848.340 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-08T16:07:28.340Z,1659974848.340 [Default:CheckIn:C.Wait] Stopped
2022-08-08T16:07:28.340Z,1659974848.340 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-08T16:07:28.340Z,1659974848.340 [Default:CheckIn:D] Running Loop=1
2022-08-08T16:07:28.753Z,1659974848.753 [Default:CheckIn:D] Stopped
2022-08-08T16:07:28.753Z,1659974848.753 [Default:CheckIn:E] Running Loop=1
2022-08-08T16:07:29.149Z,1659974849.149 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 114.261995 min 
2022-08-08T16:07:29.149Z,1659974849.149 [Default:CheckIn:E] Stopped
2022-08-08T16:07:29.149Z,1659974849.149 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-08T16:07:29.149Z,1659974849.149 [Default:CheckIn] Stopped
2022-08-08T16:07:29.150Z,1659974849.150 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-08T16:07:29.150Z,1659974849.150 [Default:CheckIn](INFO): Running loop #21
2022-08-08T16:07:29.150Z,1659974849.150 [Default:CheckIn] Running Loop=21
2022-08-08T16:07:29.150Z,1659974849.150 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-08T16:07:29.150Z,1659974849.150 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-08T16:07:31.161Z,1659974851.161 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160733.00,A,4635.09923,N,09054.04714,W,25.037,57.85,080822,,,A*7B
2022-08-08T16:07:31.163Z,1659974851.163 [NAL9602](INFO): GPS fix at 20220808T160733: (46.584987, -90.900786)
2022-08-08T16:07:31.174Z,1659974851.174 [Default:CheckIn:Read_GPS] Stopped
2022-08-08T16:07:31.174Z,1659974851.174 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-08T16:07:39.006Z,1659974859.006 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220808T142300/Courier0060.lzma
2022-08-08T16:07:40.008Z,1659974860.008 [DataOverHttps](INFO): Moved sent file to Logs/20220808T142300/Courier0060.lzma.bak
2022-08-08T16:07:40.008Z,1659974860.008 [DataOverHttps](INFO): SBD MOMSN=17096661
2022-08-08T16:07:56.859Z,1659974876.859 [DataOverHttps](INFO): Sending 197 bytes from file Logs/20220808T142300/Express0061.lzma
2022-08-08T16:07:57.856Z,1659974877.856 [DataOverHttps](INFO): Moved sent file to Logs/20220808T142300/Express0061.lzma.bak
2022-08-08T16:07:57.857Z,1659974877.857 [DataOverHttps](INFO): SBD MOMSN=17096664
2022-08-08T16:07:59.539Z,1659974879.539 [Default:CheckIn:Read_Iridium] Stopped
2022-08-08T16:07:59.539Z,1659974879.539 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-08T16:07:59.539Z,1659974879.539 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-08T16:08:01.944Z,1659974881.944 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-08-08T16:08:02.042Z,1659974882.042 [NAL9602](ERROR): received: 
+CSQ:0

OK58, 2, 0, 0, 0

OK
2022-08-08T16:12:33.472Z,1659975153.472 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-08-08T16:13:00.163Z,1659975180.163 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-08T16:13:00.163Z,1659975180.163 [Default:CheckIn:C.Wait] Stopped
2022-08-08T16:13:00.163Z,1659975180.163 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-08T16:13:00.163Z,1659975180.163 [Default:CheckIn:D] Running Loop=1
2022-08-08T16:13:00.559Z,1659975180.559 [Default:CheckIn:D] Stopped
2022-08-08T16:13:00.559Z,1659975180.559 [Default:CheckIn:E] Running Loop=1
2022-08-08T16:13:00.966Z,1659975180.966 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 119.792098 min 
2022-08-08T16:13:00.967Z,1659975180.967 [Default:CheckIn:E] Stopped
2022-08-08T16:13:00.967Z,1659975180.967 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-08T16:13:00.967Z,1659975180.967 [Default:CheckIn] Stopped
2022-08-08T16:13:00.967Z,1659975180.967 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-08T16:13:00.967Z,1659975180.967 [Default:CheckIn](INFO): Running loop #22
2022-08-08T16:13:00.967Z,1659975180.967 [Default:CheckIn] Running Loop=22
2022-08-08T16:13:00.967Z,1659975180.967 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-08T16:13:00.967Z,1659975180.967 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-08T16:13:02.964Z,1659975182.964 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161305.00,A,4636.28904,N,09051.32362,W,27.894,59.09,080822,,,A*72
2022-08-08T16:13:02.967Z,1659975182.967 [NAL9602](INFO): GPS fix at 20220808T161305: (46.604817, -90.855394)
2022-08-08T16:13:03.010Z,1659975183.010 [Default:CheckIn:Read_GPS] Stopped
2022-08-08T16:13:03.010Z,1659975183.010 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-08T16:13:13.498Z,1659975193.498 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220808T142300/Courier0063.lzma
2022-08-08T16:13:21.531Z,1659975201.531 [DataOverHttps](INFO): Moved sent file to Logs/20220808T142300/Courier0063.lzma.bak
2022-08-08T16:13:21.531Z,1659975201.531 [DataOverHttps](INFO): SBD MOMSN=17096667
2022-08-08T16:13:35.639Z,1659975215.639 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-08T16:13:37.734Z,1659975217.734 [DataOverHttps](INFO): Sending 230 bytes from file Logs/20220808T142300/Express0064.lzma
2022-08-08T16:13:38.736Z,1659975218.736 [DataOverHttps](INFO): Moved sent file to Logs/20220808T142300/Express0064.lzma.bak
2022-08-08T16:13:38.736Z,1659975218.736 [DataOverHttps](INFO): SBD MOMSN=17096670
2022-08-08T16:13:40.506Z,1659975220.506 [Default:CheckIn:Read_Iridium] Stopped
2022-08-08T16:13:40.506Z,1659975220.506 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-08T16:13:40.506Z,1659975220.506 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-08T16:18:41.077Z,1659975521.077 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-08T16:18:41.077Z,1659975521.077 [Default:CheckIn:C.Wait] Stopped
2022-08-08T16:18:41.077Z,1659975521.077 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-08T16:18:41.078Z,1659975521.078 [Default:CheckIn:D] Running Loop=1
2022-08-08T16:18:41.473Z,1659975521.473 [Default:CheckIn:D] Stopped
2022-08-08T16:18:41.473Z,1659975521.473 [Default:CheckIn:E] Running Loop=1
2022-08-08T16:18:41.881Z,1659975521.881 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 125.473999 min 
2022-08-08T16:18:41.882Z,1659975521.882 [Default:CheckIn:E] Stopped
2022-08-08T16:18:41.882Z,1659975521.882 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-08T16:18:41.882Z,1659975521.882 [Default:CheckIn] Stopped
2022-08-08T16:18:41.882Z,1659975521.882 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-08T16:18:41.882Z,1659975521.882 [Default:CheckIn](INFO): Running loop #23
2022-08-08T16:18:41.882Z,1659975521.882 [Default:CheckIn] Running Loop=23
2022-08-08T16:18:41.883Z,1659975521.883 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-08T16:18:41.883Z,1659975521.883 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-08T16:19:14.655Z,1659975554.655 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-08-08T16:19:14.738Z,1659975554.738 [NAL9602](ERROR): received: 
+CSQ:0

OK58, 2, 0, 0, 0

OK
2022-08-08T16:23:42.147Z,1659975822.147 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-08-08T16:18:41.9Z
2022-08-08T16:23:42.147Z,1659975822.147 [Default:CheckIn:Read_GPS] Stopped
2022-08-08T16:23:42.148Z,1659975822.148 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-08T16:23:46.148Z,1659975826.148 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-08-08T16:23:49.918Z,1659975829.918 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220808T142300/Courier0066.lzma
2022-08-08T16:23:50.920Z,1659975830.920 [DataOverHttps](INFO): Moved sent file to Logs/20220808T142300/Courier0066.lzma.bak
2022-08-08T16:23:50.920Z,1659975830.920 [DataOverHttps](INFO): SBD MOMSN=17096674
2022-08-08T16:24:06.734Z,1659975846.734 [DataOverHttps](INFO): Sending 162 bytes from file Logs/20220808T142300/Express0067.lzma
2022-08-08T16:24:07.736Z,1659975847.736 [DataOverHttps](INFO): Moved sent file to Logs/20220808T142300/Express0067.lzma.bak
2022-08-08T16:24:07.736Z,1659975847.736 [DataOverHttps](INFO): SBD MOMSN=17096676
2022-08-08T16:24:09.609Z,1659975849.609 [Default:CheckIn:Read_Iridium] Stopped
2022-08-08T16:24:09.609Z,1659975849.609 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-08T16:24:09.610Z,1659975849.610 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-08T16:24:16.864Z,1659975856.864 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-08T16:29:10.227Z,1659976150.227 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-08T16:29:10.227Z,1659976150.227 [Default:CheckIn:C.Wait] Stopped
2022-08-08T16:29:10.227Z,1659976150.227 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-08T16:29:10.227Z,1659976150.227 [Default:CheckIn:D] Running Loop=1
2022-08-08T16:29:10.676Z,1659976150.676 [Default:CheckIn:D] Stopped
2022-08-08T16:29:10.676Z,1659976150.676 [Default:CheckIn:E] Running Loop=1
2022-08-08T16:29:11.023Z,1659976151.023 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 135.960701 min 
2022-08-08T16:29:11.024Z,1659976151.024 [Default:CheckIn:E] Stopped
2022-08-08T16:29:11.024Z,1659976151.024 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-08T16:29:11.024Z,1659976151.024 [Default:CheckIn] Stopped
2022-08-08T16:29:11.024Z,1659976151.024 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-08T16:29:11.024Z,1659976151.024 [Default:CheckIn](INFO): Running loop #24
2022-08-08T16:29:11.024Z,1659976151.024 [Default:CheckIn] Running Loop=24
2022-08-08T16:29:11.024Z,1659976151.024 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-08T16:29:11.024Z,1659976151.024 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-08T16:29:12.630Z,1659976152.630 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-08-08T16:29:12.630Z,1659976152.630 [NAL9602] Data Fault, FailCount= 1
2022-08-08T16:29:12.630Z,1659976152.630 [NAL9602](ERROR): Data Fault
2022-08-08T16:29:12.651Z,1659976152.651 [CBIT](ERROR): Data Fault in component: NAL9602
2022-08-08T16:29:13.186Z,1659976153.186 [NAL9602](INFO): Powering down
2022-08-08T16:29:13.866Z,1659976153.866 [CBIT](INFO): Clearing failed state for component NAL9602
2022-08-08T16:29:13.866Z,1659976153.866 [NAL9602] No Fault, FailCount= 1
2022-08-08T16:29:43.336Z,1659976183.336 [NAL9602](INFO): Powering up NAL9602
2022-08-08T16:29:54.248Z,1659976194.248 [NAL9602](INFO): NAL9602 initialized
2022-08-08T16:34:11.233Z,1659976451.233 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-08-08T16:29:11.0Z
2022-08-08T16:34:11.233Z,1659976451.233 [Default:CheckIn:Read_GPS] Stopped
2022-08-08T16:34:11.233Z,1659976451.233 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-08T16:34:18.262Z,1659976458.262 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220808T142300/Courier0069.lzma
2022-08-08T16:34:19.264Z,1659976459.264 [DataOverHttps](INFO): Moved sent file to Logs/20220808T142300/Courier0069.lzma.bak
2022-08-08T16:34:19.265Z,1659976459.265 [DataOverHttps](INFO): SBD MOMSN=17096679
2022-08-08T16:34:35.939Z,1659976475.939 [DataOverHttps](INFO): Sending 234 bytes from file Logs/20220808T142300/Express0070.lzma
2022-08-08T16:34:36.932Z,1659976476.932 [DataOverHttps](INFO): Moved sent file to Logs/20220808T142300/Express0070.lzma.bak
2022-08-08T16:34:36.932Z,1659976476.932 [DataOverHttps](INFO): SBD MOMSN=17096681
2022-08-08T16:34:38.738Z,1659976478.738 [Default:CheckIn:Read_Iridium] Stopped
2022-08-08T16:34:38.738Z,1659976478.738 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-08T16:34:38.738Z,1659976478.738 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-08T16:34:57.712Z,1659976497.712 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-08-08T16:35:28.411Z,1659976528.411 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-08T16:39:39.358Z,1659976779.358 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-08T16:39:39.358Z,1659976779.358 [Default:CheckIn:C.Wait] Stopped
2022-08-08T16:39:39.358Z,1659976779.358 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-08T16:39:39.358Z,1659976779.358 [Default:CheckIn:D] Running Loop=1
2022-08-08T16:39:39.757Z,1659976779.757 [Default:CheckIn:D] Stopped
2022-08-08T16:39:39.757Z,1659976779.757 [Default:CheckIn:E] Running Loop=1
2022-08-08T16:39:40.173Z,1659976780.173 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 146.445394 min 
2022-08-08T16:39:40.173Z,1659976780.173 [Default:CheckIn:E] Stopped
2022-08-08T16:39:40.174Z,1659976780.174 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-08T16:39:40.174Z,1659976780.174 [Default:CheckIn] Stopped
2022-08-08T16:39:40.174Z,1659976780.174 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-08T16:39:40.174Z,1659976780.174 [Default:CheckIn](INFO): Running loop #25
2022-08-08T16:39:40.174Z,1659976780.174 [Default:CheckIn] Running Loop=25
2022-08-08T16:39:40.174Z,1659976780.174 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-08T16:39:40.174Z,1659976780.174 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-08T16:44:40.390Z,1659977080.390 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-08-08T16:39:40.2Z
2022-08-08T16:44:40.390Z,1659977080.390 [Default:CheckIn:Read_GPS] Stopped
2022-08-08T16:44:40.406Z,1659977080.406 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-08T16:44:43.608Z,1659977083.608 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-08-08T16:44:48.122Z,1659977088.122 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220808T142300/Courier0072.lzma
2022-08-08T16:44:49.124Z,1659977089.124 [DataOverHttps](INFO): Moved sent file to Logs/20220808T142300/Courier0072.lzma.bak
2022-08-08T16:44:49.125Z,1659977089.125 [DataOverHttps](INFO): SBD MOMSN=17096686
2022-08-08T16:45:07.842Z,1659977107.842 [DataOverHttps](INFO): Sending 217 bytes from file Logs/20220808T142300/Express0073.lzma
2022-08-08T16:45:08.837Z,1659977108.837 [DataOverHttps](INFO): Moved sent file to Logs/20220808T142300/Express0073.lzma.bak
2022-08-08T16:45:08.837Z,1659977108.837 [DataOverHttps](INFO): SBD MOMSN=17096688
2022-08-08T16:45:11.178Z,1659977111.178 [Default:CheckIn:Read_Iridium] Stopped
2022-08-08T16:45:11.178Z,1659977111.178 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-08T16:45:11.186Z,1659977111.186 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-08T16:45:14.371Z,1659977114.371 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-08T16:47:44.371Z,1659977264.371 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-08T16:48:45.340Z,1659977325.340 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-08T16:48:51.806Z,1659977331.806 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-08T16:49:32.604Z,1659977372.604 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-08T16:49:45.609Z,1659977385.609 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-08T16:49:58.858Z,1659977398.858 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-08T16:50:11.802Z,1659977411.802 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-08T16:50:11.802Z,1659977411.802 [Default:CheckIn:C.Wait] Stopped
2022-08-08T16:50:11.802Z,1659977411.802 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-08T16:50:11.803Z,1659977411.803 [Default:CheckIn:D] Running Loop=1
2022-08-08T16:50:12.264Z,1659977412.264 [Default:CheckIn:D] Stopped
2022-08-08T16:50:12.264Z,1659977412.264 [Default:CheckIn:E] Running Loop=1
2022-08-08T16:50:12.595Z,1659977412.595 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-08T16:50:12.607Z,1659977412.607 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 156.987174 min 
2022-08-08T16:50:12.607Z,1659977412.607 [Default:CheckIn:E] Stopped
2022-08-08T16:50:12.607Z,1659977412.607 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-08T16:50:12.607Z,1659977412.607 [Default:CheckIn] Stopped
2022-08-08T16:50:12.607Z,1659977412.607 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-08T16:50:12.607Z,1659977412.607 [Default:CheckIn](INFO): Running loop #26
2022-08-08T16:50:12.608Z,1659977412.608 [Default:CheckIn] Running Loop=26
2022-08-08T16:50:12.608Z,1659977412.608 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-08T16:50:12.608Z,1659977412.608 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-08T16:50:14.212Z,1659977414.212 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-08-08T16:50:14.212Z,1659977414.212 [NAL9602] Data Fault, FailCount= 2
2022-08-08T16:50:14.212Z,1659977414.212 [NAL9602](ERROR): Data Fault
2022-08-08T16:50:14.371Z,1659977414.371 [CBIT](ERROR): Data Fault in component: NAL9602
2022-08-08T16:50:14.615Z,1659977414.615 [NAL9602](INFO): Powering down
2022-08-08T16:50:15.463Z,1659977415.463 [CBIT](INFO): Clearing failed state for component NAL9602
2022-08-08T16:50:15.463Z,1659977415.463 [NAL9602] No Fault, FailCount= 2
2022-08-08T16:50:26.332Z,1659977426.332 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-08T16:50:39.722Z,1659977439.722 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-08T16:50:44.915Z,1659977444.915 [NAL9602](INFO