2022-07-22T19:02:04.266Z,1658516524.266 [CommandExec](IMPORTANT): got command restart logs
2022-07-22T19:02:34.823Z,1658516554.823 [NAL9602](INFO): Not Powering down - fast GPS
2022-07-22T19:03:02.005Z,1658516582.005 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2022-07-22T19:07:02.382Z,1658516822.382 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-07-22T19:07:02.382Z,1658516822.382 [Default:CheckIn:C.Wait] Stopped
2022-07-22T19:07:02.382Z,1658516822.382 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-07-22T19:07:02.382Z,1658516822.382 [Default:CheckIn:D] Running Loop=1
2022-07-22T19:07:02.773Z,1658516822.773 [Default:CheckIn:D] Stopped
2022-07-22T19:07:02.773Z,1658516822.773 [Default:CheckIn:E] Running Loop=1
2022-07-22T19:07:03.183Z,1658516823.183 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 222.484017 min 
2022-07-22T19:07:03.183Z,1658516823.183 [Default:CheckIn:E] Stopped
2022-07-22T19:07:03.183Z,1658516823.183 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-07-22T19:07:03.183Z,1658516823.183 [Default:CheckIn] Stopped
2022-07-22T19:07:03.183Z,1658516823.183 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-07-22T19:07:03.184Z,1658516823.184 [Default:CheckIn](INFO): Running loop #25
2022-07-22T19:07:03.184Z,1658516823.184 [Default:CheckIn] Running Loop=25
2022-07-22T19:07:03.184Z,1658516823.184 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-07-22T19:07:03.184Z,1658516823.184 [Default:CheckIn:Read_GPS] Running Loop=1
2022-07-22T19:07:05.195Z,1658516825.195 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190704.00,A,3632.58829,N,12209.93977,W,1.380,196.33,220722,,,A*75
2022-07-22T19:07:05.198Z,1658516825.198 [NAL9602](INFO): GPS fix at 20220722T190704: (36.543138, -122.165663)
2022-07-22T19:07:05.208Z,1658516825.208 [Default:CheckIn:Read_GPS] Stopped
2022-07-22T19:07:05.208Z,1658516825.208 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-07-22T19:07:13.408Z,1658516833.408 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220722T040154/Courier0343.lzma
2022-07-22T19:07:14.411Z,1658516834.411 [DataOverHttps](INFO): Moved sent file to Logs/20220722T040154/Courier0343.lzma.bak
2022-07-22T19:07:14.411Z,1658516834.411 [DataOverHttps](INFO): SBD MOMSN=17074650
2022-07-22T19:07:30.243Z,1658516850.243 [NAL9602](INFO): SBD MO Status=1, MOMSN=35491, MT Status=0, MTMSN=0
2022-07-22T19:07:30.243Z,1658516850.243 [NAL9602](INFO): No messages in MT queue
2022-07-22T19:07:43.165Z,1658516863.165 [DataOverHttps](INFO): Sending 269 bytes from file Logs/20220722T190204/Courier0000.lzma
2022-07-22T19:07:45.170Z,1658516865.170 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Courier0000.lzma.bak
2022-07-22T19:07:45.171Z,1658516865.171 [DataOverHttps](INFO): SBD MOMSN=17074654
2022-07-22T19:08:00.817Z,1658516880.817 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20220722T040154/Express0344.lzma
2022-07-22T19:08:00.943Z,1658516880.943 [NAL9602](INFO): Not Powering down - fast GPS
2022-07-22T19:08:01.819Z,1658516881.819 [DataOverHttps](INFO): Moved sent file to Logs/20220722T040154/Express0344.lzma.bak
2022-07-22T19:08:01.819Z,1658516881.819 [DataOverHttps](INFO): SBD MOMSN=17074658
2022-07-22T19:08:17.677Z,1658516897.677 [DataOverHttps](INFO): Sending 445 bytes from file Logs/20220722T190204/Express0001.lzma
2022-07-22T19:08:21.691Z,1658516901.691 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Express0001.lzma.bak
2022-07-22T19:08:21.691Z,1658516901.691 [DataOverHttps](INFO): SBD MOMSN=17074661
2022-07-22T19:08:23.209Z,1658516903.209 [Default:CheckIn:Read_Iridium] Stopped
2022-07-22T19:08:23.209Z,1658516903.209 [Default:CheckIn:C.Wait] Running Loop=1
2022-07-22T19:08:23.209Z,1658516903.209 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-07-22T19:09:23.613Z,1658516963.613 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-07-22T19:09:54.761Z,1658516994.761 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-07-22T19:11:26.721Z,1658517086.721 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-07-22T19:11:57.865Z,1658517117.865 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-07-22T19:12:28.997Z,1658517148.997 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-07-22T19:13:00.121Z,1658517180.121 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-07-22T19:13:23.755Z,1658517203.755 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-07-22T19:13:23.755Z,1658517203.755 [Default:CheckIn:C.Wait] Stopped
2022-07-22T19:13:23.755Z,1658517203.755 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-07-22T19:13:23.755Z,1658517203.755 [Default:CheckIn:D] Running Loop=1
2022-07-22T19:13:24.157Z,1658517204.157 [Default:CheckIn:D] Stopped
2022-07-22T19:13:24.158Z,1658517204.158 [Default:CheckIn:E] Running Loop=1
2022-07-22T19:13:24.566Z,1658517204.566 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 228.840413 min 
2022-07-22T19:13:24.566Z,1658517204.566 [Default:CheckIn:E] Stopped
2022-07-22T19:13:24.566Z,1658517204.566 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-07-22T19:13:24.566Z,1658517204.566 [Default:CheckIn] Stopped
2022-07-22T19:13:24.566Z,1658517204.566 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-07-22T19:13:24.566Z,1658517204.566 [Default:CheckIn](INFO): Running loop #26
2022-07-22T19:13:24.566Z,1658517204.566 [Default:CheckIn] Running Loop=26
2022-07-22T19:13:24.566Z,1658517204.566 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-07-22T19:13:24.566Z,1658517204.566 [Default:CheckIn:Read_GPS] Running Loop=1
2022-07-22T19:13:26.572Z,1658517206.572 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191325.00,A,3632.38478,N,12209.65451,W,5.734,79.27,220722,,,D*42
2022-07-22T19:13:26.574Z,1658517206.574 [NAL9602](INFO): GPS fix at 20220722T191325: (36.539746, -122.160909)
2022-07-22T19:13:26.588Z,1658517206.588 [Default:CheckIn:Read_GPS] Stopped
2022-07-22T19:13:26.588Z,1658517206.588 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-07-22T19:13:31.257Z,1658517211.257 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-07-22T19:13:34.269Z,1658517214.269 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.775665
2022-07-22T19:13:50.010Z,1658517230.010 [NAL9602](INFO): SBD MO Status=2, MOMSN=35492, MT Status=2, MTMSN=0
2022-07-22T19:13:50.010Z,1658517230.010 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-07-22T19:14:05.393Z,1658517245.393 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-07-22T19:14:15.459Z,1658517255.459 [NAL9602](INFO): SBD MO Status=2, MOMSN=35492, MT Status=2, MTMSN=0
2022-07-22T19:14:15.459Z,1658517255.459 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-07-22T19:14:35.255Z,1658517275.255 [NAL9602](INFO): SBD MO Status=2, MOMSN=35492, MT Status=2, MTMSN=0
2022-07-22T19:14:35.255Z,1658517275.255 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-07-22T19:14:36.517Z,1658517276.517 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-07-22T19:14:57.068Z,1658517297.068 [NAL9602](INFO): SBD MO Status=2, MOMSN=35492, MT Status=2, MTMSN=0
2022-07-22T19:14:57.068Z,1658517297.068 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-07-22T19:15:07.645Z,1658517307.645 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-07-22T19:15:33.022Z,1658517333.022 [NAL9602](INFO): SBD MO Status=2, MOMSN=35492, MT Status=2, MTMSN=0
2022-07-22T19:15:33.022Z,1658517333.022 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-07-22T19:15:38.769Z,1658517338.769 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-07-22T19:16:09.901Z,1658517369.901 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-07-22T19:16:17.470Z,1658517377.470 [NAL9602](INFO): SBD MO Status=2, MOMSN=35492, MT Status=2, MTMSN=0
2022-07-22T19:16:17.470Z,1658517377.470 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-07-22T19:16:41.033Z,1658517401.033 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-07-22T19:16:46.955Z,1658517406.955 [NAL9602](INFO): SBD MO Status=1, MOMSN=35492, MT Status=0, MTMSN=0
2022-07-22T19:16:46.955Z,1658517406.955 [NAL9602](INFO): No messages in MT queue
2022-07-22T19:17:12.169Z,1658517432.169 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-07-22T19:17:30.231Z,1658517450.231 [NAL9602](INFO): SBD MO Status=1, MOMSN=35493, MT Status=0, MTMSN=0
2022-07-22T19:17:30.285Z,1658517450.285 [NAL9602](INFO): Sent 73 bytes from file Logs/20220722T190204/Courier0003.lzma
2022-07-22T19:17:30.285Z,1658517450.285 [NAL9602](INFO): Packets left to send: 0
2022-07-22T19:17:42.023Z,1658517462.023 [NAL9602](INFO): SBD MO Status=1, MOMSN=35494, MT Status=0, MTMSN=0
2022-07-22T19:17:42.085Z,1658517462.085 [NAL9602](INFO): Sent 160 bytes from file Logs/20220722T190204/Express0004.lzma
2022-07-22T19:17:42.085Z,1658517462.085 [NAL9602](INFO): Packets left to send: 0
2022-07-22T19:17:43.293Z,1658517463.293 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-07-22T19:18:01.003Z,1658517481.003 [NAL9602](INFO): SBD MO Status=2, MOMSN=35495, MT Status=2, MTMSN=0
2022-07-22T19:18:01.003Z,1658517481.003 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-07-22T19:18:14.433Z,1658517494.433 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-07-22T19:18:19.187Z,1658517499.187 [NAL9602](INFO): SBD MO Status=2, MOMSN=35495, MT Status=2, MTMSN=0
2022-07-22T19:18:19.187Z,1658517499.187 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-07-22T19:18:35.749Z,1658517515.749 [NAL9602](INFO): SBD MO Status=2, MOMSN=35495, MT Status=2, MTMSN=0
2022-07-22T19:18:35.749Z,1658517515.749 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-07-22T19:18:40.731Z,1658517520.731 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2022-07-22T19:18:42.770Z,1658517522.770 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2022-07-22T19:18:45.557Z,1658517525.557 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-07-22T19:18:51.647Z,1658517531.647 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2022-07-22T19:18:55.684Z,1658517535.684 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2022-07-22T19:19:14.009Z,1658517554.009 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003139
2022-07-22T19:19:15.376Z,1658517555.376 [Default:CheckIn:Read_Iridium] Stopped
2022-07-22T19:19:15.377Z,1658517555.377 [Default:CheckIn:C.Wait] Running Loop=1
2022-07-22T19:19:15.377Z,1658517555.377 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-07-22T19:19:18.571Z,1658517558.571 [NAL9602](INFO): SBD MO Status=2, MOMSN=35495, MT Status=2, MTMSN=0
2022-07-22T19:19:18.571Z,1658517558.571 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-07-22T19:19:49.314Z,1658517589.314 [NAL9602](INFO): Not Powering down - fast GPS
2022-07-22T19:23:17.953Z,1658517797.953 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-07-22T19:23:49.077Z,1658517829.077 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-07-22T19:24:16.039Z,1658517856.039 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-07-22T19:24:16.039Z,1658517856.039 [Default:CheckIn:C.Wait] Stopped
2022-07-22T19:24:16.040Z,1658517856.040 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-07-22T19:24:16.040Z,1658517856.040 [Default:CheckIn:D] Running Loop=1
2022-07-22T19:24:16.447Z,1658517856.447 [Default:CheckIn:D] Stopped
2022-07-22T19:24:16.447Z,1658517856.447 [Default:CheckIn:E] Running Loop=1
2022-07-22T19:24:16.848Z,1658517856.848 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 239.711914 min 
2022-07-22T19:24:16.848Z,1658517856.848 [Default:CheckIn:E] Stopped
2022-07-22T19:24:16.848Z,1658517856.848 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-07-22T19:24:16.848Z,1658517856.848 [Default:CheckIn] Stopped
2022-07-22T19:24:16.848Z,1658517856.848 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-07-22T19:24:16.848Z,1658517856.848 [Default:CheckIn](INFO): Running loop #27
2022-07-22T19:24:16.848Z,1658517856.848 [Default:CheckIn] Running Loop=27
2022-07-22T19:24:16.848Z,1658517856.848 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-07-22T19:24:16.848Z,1658517856.848 [Default:CheckIn:Read_GPS] Running Loop=1
2022-07-22T19:24:18.868Z,1658517858.868 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192418.00,A,3632.87447,N,12207.76273,W,9.019,37.88,220722,,,D*41
2022-07-22T19:24:18.870Z,1658517858.870 [NAL9602](INFO): GPS fix at 20220722T192418: (36.547908, -122.129379)
2022-07-22T19:24:18.880Z,1658517858.880 [Default:CheckIn:Read_GPS] Stopped
2022-07-22T19:24:18.880Z,1658517858.880 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-07-22T19:24:43.513Z,1658517883.513 [NAL9602](INFO): SBD MO Status=2, MOMSN=35495, MT Status=2, MTMSN=0
2022-07-22T19:24:43.514Z,1658517883.514 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-07-22T19:25:01.889Z,1658517901.889 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220722T190204/Courier0006.lzma
2022-07-22T19:25:02.890Z,1658517902.890 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Courier0006.lzma.bak
2022-07-22T19:25:02.891Z,1658517902.891 [DataOverHttps](INFO): SBD MOMSN=17074678
2022-07-22T19:25:18.849Z,1658517918.849 [DataOverHttps](INFO): Sending 217 bytes from file Logs/20220722T190204/Express0007.lzma
2022-07-22T19:25:23.866Z,1658517923.866 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Express0007.lzma.bak
2022-07-22T19:25:23.867Z,1658517923.867 [DataOverHttps](INFO): SBD MOMSN=17074682
2022-07-22T19:25:25.214Z,1658517925.214 [Default:CheckIn:Read_Iridium] Stopped
2022-07-22T19:25:25.214Z,1658517925.214 [Default:CheckIn:C.Wait] Running Loop=1
2022-07-22T19:25:25.214Z,1658517925.214 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-07-22T19:25:48.187Z,1658517948.187 [NAL9602](INFO): SBD MO Status=2, MOMSN=35495, MT Status=2, MTMSN=0
2022-07-22T19:25:48.187Z,1658517948.187 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-07-22T19:26:41.115Z,1658518001.115 [NAL9602](INFO): SBD MO Status=2, MOMSN=35495, MT Status=2, MTMSN=0
2022-07-22T19:26:41.115Z,1658518001.115 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-07-22T19:27:19.139Z,1658518039.139 [NAL9602](INFO): SBD MO Status=2, MOMSN=35495, MT Status=2, MTMSN=0
2022-07-22T19:27:19.140Z,1658518039.140 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-07-22T19:28:59.335Z,1658518139.335 [NAL9602](INFO): SBD MO Status=0, MOMSN=35495, MT Status=0, MTMSN=0
2022-07-22T19:28:59.335Z,1658518139.335 [NAL9602](INFO): No messages in MT queue
2022-07-22T19:29:30.042Z,1658518170.042 [NAL9602](INFO): Not Powering down - fast GPS
2022-07-22T19:30:25.905Z,1658518225.905 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-07-22T19:30:25.905Z,1658518225.905 [Default:CheckIn:C.Wait] Stopped
2022-07-22T19:30:25.905Z,1658518225.905 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-07-22T19:30:25.905Z,1658518225.905 [Default:CheckIn:D] Running Loop=1
2022-07-22T19:30:26.290Z,1658518226.290 [Default:CheckIn:D] Stopped
2022-07-22T19:30:26.290Z,1658518226.290 [Default:CheckIn:E] Running Loop=1
2022-07-22T19:30:26.698Z,1658518226.698 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 245.875960 min 
2022-07-22T19:30:26.698Z,1658518226.698 [Default:CheckIn:E] Stopped
2022-07-22T19:30:26.698Z,1658518226.698 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-07-22T19:30:26.698Z,1658518226.698 [Default:CheckIn] Stopped
2022-07-22T19:30:26.698Z,1658518226.698 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-07-22T19:30:26.698Z,1658518226.698 [Default:CheckIn](INFO): Running loop #28
2022-07-22T19:30:26.698Z,1658518226.698 [Default:CheckIn] Running Loop=28
2022-07-22T19:30:26.698Z,1658518226.698 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-07-22T19:30:26.698Z,1658518226.698 [Default:CheckIn:Read_GPS] Running Loop=1
2022-07-22T19:30:28.675Z,1658518228.675 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193027.00,A,3633.37045,N,12206.66644,W,7.970,55.53,220722,,,D*4E
2022-07-22T19:30:28.690Z,1658518228.690 [NAL9602](INFO): GPS fix at 20220722T193027: (36.556174, -122.111107)
2022-07-22T19:30:28.716Z,1658518228.716 [Default:CheckIn:Read_GPS] Stopped
2022-07-22T19:30:28.749Z,1658518228.749 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-07-22T19:30:39.176Z,1658518239.176 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220722T190204/Courier0009.lzma
2022-07-22T19:30:40.178Z,1658518240.178 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Courier0009.lzma.bak
2022-07-22T19:30:40.179Z,1658518240.179 [DataOverHttps](INFO): SBD MOMSN=17074723
2022-07-22T19:30:49.673Z,1658518249.673 [NAL9602](INFO): SBD MO Status=0, MOMSN=35496, MT Status=0, MTMSN=0
2022-07-22T19:30:49.674Z,1658518249.674 [NAL9602](INFO): No messages in MT queue
2022-07-22T19:31:17.329Z,1658518277.329 [DataOverHttps](INFO): Sending 189 bytes from file Logs/20220722T190204/Express0010.lzma
2022-07-22T19:31:20.339Z,1658518280.339 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Express0010.lzma.bak
2022-07-22T19:31:20.339Z,1658518280.339 [DataOverHttps](INFO): SBD MOMSN=17074733
2022-07-22T19:31:20.390Z,1658518280.390 [NAL9602](INFO): Not Powering down - fast GPS
2022-07-22T19:31:21.625Z,1658518281.625 [Default:CheckIn:Read_Iridium] Stopped
2022-07-22T19:31:21.625Z,1658518281.625 [Default:CheckIn:C.Wait] Running Loop=1
2022-07-22T19:31:21.625Z,1658518281.625 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-07-22T19:36:22.204Z,1658518582.204 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-07-22T19:36:22.204Z,1658518582.204 [Default:CheckIn:C.Wait] Stopped
2022-07-22T19:36:22.205Z,1658518582.205 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-07-22T19:36:22.205Z,1658518582.205 [Default:CheckIn:D] Running Loop=1
2022-07-22T19:36:22.593Z,1658518582.593 [Default:CheckIn:D] Stopped
2022-07-22T19:36:22.593Z,1658518582.593 [Default:CheckIn:E] Running Loop=1
2022-07-22T19:36:22.997Z,1658518582.997 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 251.814355 min 
2022-07-22T19:36:22.998Z,1658518582.998 [Default:CheckIn:E] Stopped
2022-07-22T19:36:22.998Z,1658518582.998 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-07-22T19:36:22.998Z,1658518582.998 [Default:CheckIn] Stopped
2022-07-22T19:36:22.998Z,1658518582.998 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-07-22T19:36:22.998Z,1658518582.998 [Default:CheckIn](INFO): Running loop #29
2022-07-22T19:36:22.998Z,1658518582.998 [Default:CheckIn] Running Loop=29
2022-07-22T19:36:22.998Z,1658518582.998 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-07-22T19:36:22.998Z,1658518582.998 [Default:CheckIn:Read_GPS] Running Loop=1
2022-07-22T19:36:25.011Z,1658518585.011 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193624.00,A,3633.96915,N,12205.47409,W,15.590,56.47,220722,,,D*70
2022-07-22T19:36:25.014Z,1658518585.014 [NAL9602](INFO): GPS fix at 20220722T193624: (36.566153, -122.091235)
2022-07-22T19:36:25.041Z,1658518585.041 [Default:CheckIn:Read_GPS] Stopped
2022-07-22T19:36:25.041Z,1658518585.041 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-07-22T19:36:56.033Z,1658518616.033 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-07-22T19:37:27.157Z,1658518647.157 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-07-22T19:37:41.520Z,1658518661.520 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220722T190204/Courier0012.lzma
2022-07-22T19:37:42.522Z,1658518662.522 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Courier0012.lzma.bak
2022-07-22T19:37:42.522Z,1658518662.522 [DataOverHttps](INFO): SBD MOMSN=17074756
2022-07-22T19:37:53.488Z,1658518673.488 [NAL9602](INFO): SBD MO Status=0, MOMSN=35497, MT Status=0, MTMSN=0
2022-07-22T19:37:53.488Z,1658518673.488 [NAL9602](INFO): No messages in MT queue
2022-07-22T19:38:00.257Z,1658518680.257 [DataOverHttps](INFO): Sending 165 bytes from file Logs/20220722T190204/Express0013.lzma
2022-07-22T19:38:02.262Z,1658518682.262 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Express0013.lzma.bak
2022-07-22T19:38:02.263Z,1658518682.263 [DataOverHttps](INFO): SBD MOMSN=17074759
2022-07-22T19:38:03.601Z,1658518683.601 [Default:CheckIn:Read_Iridium] Stopped
2022-07-22T19:38:03.602Z,1658518683.602 [Default:CheckIn:C.Wait] Running Loop=1
2022-07-22T19:38:03.602Z,1658518683.602 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-07-22T19:38:24.187Z,1658518704.187 [NAL9602](INFO): Not Powering down - fast GPS
2022-07-22T19:38:33.397Z,1658518713.397 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-07-22T19:39:04.521Z,1658518744.521 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-07-22T19:43:04.178Z,1658518984.178 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-07-22T19:43:04.178Z,1658518984.178 [Default:CheckIn:C.Wait] Stopped
2022-07-22T19:43:04.178Z,1658518984.178 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-07-22T19:43:04.178Z,1658518984.178 [Default:CheckIn:D] Running Loop=1
2022-07-22T19:43:04.579Z,1658518984.579 [Default:CheckIn:D] Stopped
2022-07-22T19:43:04.579Z,1658518984.579 [Default:CheckIn:E] Running Loop=1
2022-07-22T19:43:04.983Z,1658518984.983 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 258.514111 min 
2022-07-22T19:43:04.983Z,1658518984.983 [Default:CheckIn:E] Stopped
2022-07-22T19:43:04.983Z,1658518984.983 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-07-22T19:43:04.984Z,1658518984.984 [Default:CheckIn] Stopped
2022-07-22T19:43:04.984Z,1658518984.984 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-07-22T19:43:04.984Z,1658518984.984 [Default:CheckIn](INFO): Running loop #30
2022-07-22T19:43:04.984Z,1658518984.984 [Default:CheckIn] Running Loop=30
2022-07-22T19:43:04.984Z,1658518984.984 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-07-22T19:43:04.984Z,1658518984.984 [Default:CheckIn:Read_GPS] Running Loop=1
2022-07-22T19:43:06.992Z,1658518986.992 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194306.00,A,3634.63691,N,12204.31911,W,16.173,81.57,220722,,,D*79
2022-07-22T19:43:06.994Z,1658518986.994 [NAL9602](INFO): GPS fix at 20220722T194306: (36.577282, -122.071985)
2022-07-22T19:43:07.005Z,1658518987.005 [Default:CheckIn:Read_GPS] Stopped
2022-07-22T19:43:07.005Z,1658518987.005 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-07-22T19:43:14.696Z,1658518994.696 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220722T190204/Courier0015.lzma
2022-07-22T19:43:15.698Z,1658518995.698 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Courier0015.lzma.bak
2022-07-22T19:43:15.698Z,1658518995.698 [DataOverHttps](INFO): SBD MOMSN=17074763
2022-07-22T19:43:26.791Z,1658519006.791 [NAL9602](INFO): SBD MO Status=2, MOMSN=35498, MT Status=2, MTMSN=0
2022-07-22T19:43:26.792Z,1658519006.792 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-07-22T19:43:31.540Z,1658519011.540 [DataOverHttps](INFO): Sending 162 bytes from file Logs/20220722T190204/Express0016.lzma
2022-07-22T19:43:32.543Z,1658519012.543 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Express0016.lzma.bak
2022-07-22T19:43:32.543Z,1658519012.543 [DataOverHttps](INFO): SBD MOMSN=17074767
2022-07-22T19:43:34.085Z,1658519014.085 [Default:CheckIn:Read_Iridium] Stopped
2022-07-22T19:43:34.085Z,1658519014.085 [Default:CheckIn:C.Wait] Running Loop=1
2022-07-22T19:43:34.085Z,1658519014.085 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-07-22T19:44:29.013Z,1658519069.013 [NAL9602](INFO): SBD MO Status=2, MOMSN=35498, MT Status=2, MTMSN=0
2022-07-22T19:44:29.014Z,1658519069.014 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-07-22T19:45:18.324Z,1658519118.324 [NAL9602](INFO): SBD MO Status=2, MOMSN=35498, MT Status=2, MTMSN=0
2022-07-22T19:45:18.324Z,1658519118.324 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-07-22T19:45:54.283Z,1658519154.283 [NAL9602](INFO): SBD MO Status=2, MOMSN=35498, MT Status=2, MTMSN=0
2022-07-22T19:45:54.283Z,1658519154.283 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-07-22T19:46:11.239Z,1658519171.239 [NAL9602](INFO): SBD MO Status=2, MOMSN=35498, MT Status=2, MTMSN=0
2022-07-22T19:46:11.239Z,1658519171.239 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-07-22T19:46:33.059Z,1658519193.059 [NAL9602](INFO): SBD MO Status=2, MOMSN=35498, MT Status=2, MTMSN=0
2022-07-22T19:46:33.059Z,1658519193.059 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-07-22T19:47:07.399Z,1658519227.399 [NAL9602](INFO): SBD MO Status=2, MOMSN=35498, MT Status=2, MTMSN=0
2022-07-22T19:47:07.399Z,1658519227.399 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-07-22T19:47:35.674Z,1658519255.674 [NAL9602](INFO): SBD MO Status=0, MOMSN=35498, MT Status=0, MTMSN=0
2022-07-22T19:47:35.674Z,1658519255.674 [NAL9602](INFO): No messages in MT queue
2022-07-22T19:48:06.382Z,1658519286.382 [NAL9602](INFO): Not Powering down - fast GPS
2022-07-22T19:48:34.673Z,1658519314.673 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-07-22T19:48:34.674Z,1658519314.674 [Default:CheckIn:C.Wait] Stopped
2022-07-22T19:48:34.674Z,1658519314.674 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-07-22T19:48:34.674Z,1658519314.674 [Default:CheckIn:D] Running Loop=1
2022-07-22T19:48:35.084Z,1658519315.084 [Default:CheckIn:D] Stopped
2022-07-22T19:48:35.084Z,1658519315.084 [Default:CheckIn:E] Running Loop=1
2022-07-22T19:48:35.493Z,1658519315.493 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 264.022526 min 
2022-07-22T19:48:35.493Z,1658519315.493 [Default:CheckIn:E] Stopped
2022-07-22T19:48:35.493Z,1658519315.493 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-07-22T19:48:35.493Z,1658519315.493 [Default:CheckIn] Stopped
2022-07-22T19:48:35.493Z,1658519315.493 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-07-22T19:48:35.493Z,1658519315.493 [Default:CheckIn](INFO): Running loop #31
2022-07-22T19:48:35.494Z,1658519315.494 [Default:CheckIn] Running Loop=31
2022-07-22T19:48:35.494Z,1658519315.494 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-07-22T19:48:35.494Z,1658519315.494 [Default:CheckIn:Read_GPS] Running Loop=1
2022-07-22T19:48:37.520Z,1658519317.520 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194836.00,A,3635.24489,N,12203.39538,W,11.819,73.65,220722,,,D*7E
2022-07-22T19:48:37.522Z,1658519317.522 [NAL9602](INFO): GPS fix at 20220722T194836: (36.587415, -122.056590)
2022-07-22T19:48:37.538Z,1658519317.538 [Default:CheckIn:Read_GPS] Stopped
2022-07-22T19:48:37.538Z,1658519317.538 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-07-22T19:48:45.673Z,1658519325.673 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220722T190204/Courier0018.lzma
2022-07-22T19:48:46.674Z,1658519326.674 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Courier0018.lzma.bak
2022-07-22T19:48:46.675Z,1658519326.675 [DataOverHttps](INFO): SBD MOMSN=17074774
2022-07-22T19:48:50.555Z,1658519330.555 [NAL9602](INFO): SBD MO Status=0, MOMSN=35499, MT Status=0, MTMSN=0
2022-07-22T19:48:50.555Z,1658519330.555 [NAL9602](INFO): No messages in MT queue
2022-07-22T19:49:05.709Z,1658519345.709 [DataOverHttps](INFO): Sending 200 bytes from file Logs/20220722T190204/Express0019.lzma
2022-07-22T19:49:06.710Z,1658519346.710 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Express0019.lzma.bak
2022-07-22T19:49:06.711Z,1658519346.711 [DataOverHttps](INFO): SBD MOMSN=17074777
2022-07-22T19:49:07.937Z,1658519347.937 [Default:CheckIn:Read_Iridium] Stopped
2022-07-22T19:49:07.937Z,1658519347.937 [Default:CheckIn:C.Wait] Running Loop=1
2022-07-22T19:49:07.938Z,1658519347.938 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-07-22T19:49:21.252Z,1658519361.252 [NAL9602](INFO): Not Powering down - fast GPS
2022-07-22T19:51:04.674Z,1658519464.674 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-07-22T19:51:19.640Z,1658519479.640 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-07-22T19:51:34.978Z,1658519494.978 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-07-22T19:51:50.359Z,1658519510.359 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-07-22T19:52:05.309Z,1658519525.309 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-07-22T19:52:20.254Z,1658519540.254 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-07-22T19:52:35.606Z,1658519555.606 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-07-22T19:52:50.555Z,1658519570.555 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-07-22T19:53:05.503Z,1658519585.503 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-07-22T19:53:20.451Z,1658519600.451 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-07-22T19:53:32.176Z,1658519612.176 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2022-07-22T19:53:32.178Z,1658519612.178 [BPC1](INFO): Received data from all battery sticks.
2022-07-22T19:54:08.538Z,1658519648.538 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-07-22T19:54:08.538Z,1658519648.538 [Default:CheckIn:C.Wait] Stopped
2022-07-22T19:54:08.538Z,1658519648.538 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-07-22T19:54:08.538Z,1658519648.538 [Default:CheckIn:D] Running Loop=1
2022-07-22T19:54:08.950Z,1658519648.950 [Default:CheckIn:D] Stopped
2022-07-22T19:54:08.950Z,1658519648.950 [Default:CheckIn:E] Running Loop=1
2022-07-22T19:54:09.352Z,1658519649.352 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 269.586963 min 
2022-07-22T19:54:09.352Z,1658519649.352 [Default:CheckIn:E] Stopped
2022-07-22T19:54:09.352Z,1658519649.352 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-07-22T19:54:09.352Z,1658519649.352 [Default:CheckIn] Stopped
2022-07-22T19:54:09.352Z,1658519649.352 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-07-22T19:54:09.352Z,1658519649.352 [Default:CheckIn](INFO): Running loop #32
2022-07-22T19:54:09.352Z,1658519649.352 [Default:CheckIn] Running Loop=32
2022-07-22T19:54:09.352Z,1658519649.352 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-07-22T19:54:09.353Z,1658519649.353 [Default:CheckIn:Read_GPS] Running Loop=1
2022-07-22T19:54:11.363Z,1658519651.363 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195410.00,A,3636.14857,N,12202.17757,W,17.728,51.46,220722,,,D*74
2022-07-22T19:54:11.376Z,1658519651.376 [NAL9602](INFO): GPS fix at 20220722T195410: (36.602476, -122.036293)
2022-07-22T19:54:11.387Z,1658519651.387 [Default:CheckIn:Read_GPS] Stopped
2022-07-22T19:54:11.387Z,1658519651.387 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-07-22T19:54:21.737Z,1658519661.737 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20220722T190204/Courier0021.lzma
2022-07-22T19:54:22.738Z,1658519662.738 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Courier0021.lzma.bak
2022-07-22T19:54:22.739Z,1658519662.739 [DataOverHttps](INFO): SBD MOMSN=17074808
2022-07-22T19:54:40.741Z,1658519680.741 [DataOverHttps](INFO): Sending 166 bytes from file Logs/20220722T190204/Express0022.lzma
2022-07-22T19:54:41.742Z,1658519681.742 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Express0022.lzma.bak
2022-07-22T19:54:41.743Z,1658519681.743 [DataOverHttps](INFO): SBD MOMSN=17074811
2022-07-22T19:54:42.941Z,1658519682.941 [Default:CheckIn:Read_Iridium] Stopped
2022-07-22T19:54:42.941Z,1658519682.941 [Default:CheckIn:C.Wait] Running Loop=1
2022-07-22T19:54:42.942Z,1658519682.942 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-07-22T19:57:06.479Z,1658519826.479 [NAL9602](INFO): SBD MO Status=2, MOMSN=35500, MT Status=2, MTMSN=0
2022-07-22T19:57:06.479Z,1658519826.479 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-07-22T19:57:29.099Z,1658519849.099 [NAL9602](INFO): SBD MO Status=0, MOMSN=35500, MT Status=0, MTMSN=0
2022-07-22T19:57:29.099Z,1658519849.099 [NAL9602](INFO): No messages in MT queue
2022-07-22T19:57:59.805Z,1658519879.805 [NAL9602](INFO): Not Powering down - fast GPS
2022-07-22T19:59:43.367Z,1658519983.367 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-07-22T19:59:43.367Z,1658519983.367 [Default:CheckIn:C.Wait] Stopped
2022-07-22T19:59:43.367Z,1658519983.367 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-07-22T19:59:43.368Z,1658519983.368 [Default:CheckIn:D] Running Loop=1
2022-07-22T19:59:43.767Z,1658519983.767 [Default:CheckIn:D] Stopped
2022-07-22T19:59:43.767Z,1658519983.767 [Default:CheckIn:E] Running Loop=1
2022-07-22T19:59:44.183Z,1658519984.183 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 275.167253 min 
2022-07-22T19:59:44.183Z,1658519984.183 [Default:CheckIn:E] Stopped
2022-07-22T19:59:44.184Z,1658519984.184 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-07-22T19:59:44.184Z,1658519984.184 [Default:CheckIn] Stopped
2022-07-22T19:59:44.184Z,1658519984.184 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-07-22T19:59:44.184Z,1658519984.184 [Default:CheckIn](INFO): Running loop #33
2022-07-22T19:59:44.184Z,1658519984.184 [Default:CheckIn] Running Loop=33
2022-07-22T19:59:44.184Z,1658519984.184 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-07-22T19:59:44.184Z,1658519984.184 [Default:CheckIn:Read_GPS] Running Loop=1
2022-07-22T19:59:46.188Z,1658519986.188 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195945.00,A,3637.34141,N,12200.81598,W,20.702,48.28,220722,,,D*74
2022-07-22T19:59:46.200Z,1658519986.200 [NAL9602](INFO): GPS fix at 20220722T195945: (36.622357, -122.013600)
2022-07-22T19:59:46.210Z,1658519986.210 [Default:CheckIn:Read_GPS] Stopped
2022-07-22T19:59:46.210Z,1658519986.210 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-07-22T19:59:53.508Z,1658519993.508 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220722T190204/Courier0024.lzma
2022-07-22T19:59:54.510Z,1658519994.510 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Courier0024.lzma.bak
2022-07-22T19:59:54.511Z,1658519994.511 [DataOverHttps](INFO): SBD MOMSN=17074815
2022-07-22T20:00:10.537Z,1658520010.537 [DataOverHttps](INFO): Sending 171 bytes from file Logs/20220722T190204/Express0025.lzma
2022-07-22T20:00:11.538Z,1658520011.538 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Express0025.lzma.bak
2022-07-22T20:00:11.539Z,1658520011.539 [DataOverHttps](INFO): SBD MOMSN=17074818
2022-07-22T20:00:12.877Z,1658520012.877 [Default:CheckIn:Read_Iridium] Stopped
2022-07-22T20:00:12.878Z,1658520012.878 [Default:CheckIn:C.Wait] Running Loop=1
2022-07-22T20:00:12.878Z,1658520012.878 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-07-22T20:00:16.095Z,1658520016.095 [NAL9602](INFO): SBD MO Status=0, MOMSN=35501, MT Status=0, MTMSN=0
2022-07-22T20:00:16.095Z,1658520016.095 [NAL9602](INFO): No messages in MT queue
2022-07-22T20:00:46.794Z,1658520046.794 [NAL9602](INFO): Not Powering down - fast GPS
2022-07-22T20:05:13.639Z,1658520313.639 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-07-22T20:05:13.639Z,1658520313.639 [Default:CheckIn:C.Wait] Stopped
2022-07-22T20:05:13.639Z,1658520313.639 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-07-22T20:05:13.640Z,1658520313.640 [Default:CheckIn:D] Running Loop=1
2022-07-22T20:05:14.030Z,1658520314.030 [Default:CheckIn:D] Stopped
2022-07-22T20:05:14.030Z,1658520314.030 [Default:CheckIn:E] Running Loop=1
2022-07-22T20:05:14.443Z,1658520314.443 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 280.671615 min 
2022-07-22T20:05:14.443Z,1658520314.443 [Default:CheckIn:E] Stopped
2022-07-22T20:05:14.443Z,1658520314.443 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-07-22T20:05:14.443Z,1658520314.443 [Default:CheckIn] Stopped
2022-07-22T20:05:14.444Z,1658520314.444 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-07-22T20:05:14.444Z,1658520314.444 [Default:CheckIn](INFO): Running loop #34
2022-07-22T20:05:14.444Z,1658520314.444 [Default:CheckIn] Running Loop=34
2022-07-22T20:05:14.445Z,1658520314.445 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-07-22T20:05:14.445Z,1658520314.445 [Default:CheckIn:Read_GPS] Running Loop=1
2022-07-22T20:05:16.451Z,1658520316.451 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200515.00,A,3638.36021,N,12159.31801,W,20.566,47.55,220722,,,D*74
2022-07-22T20:05:16.453Z,1658520316.453 [NAL9602](INFO): GPS fix at 20220722T200515: (36.639337, -121.988634)
2022-07-22T20:05:16.463Z,1658520316.463 [Default:CheckIn:Read_GPS] Stopped
2022-07-22T20:05:16.463Z,1658520316.463 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-07-22T20:05:23.996Z,1658520323.996 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220722T190204/Courier0027.lzma
2022-07-22T20:05:24.998Z,1658520324.998 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Courier0027.lzma.bak
2022-07-22T20:05:24.999Z,1658520324.999 [DataOverHttps](INFO): SBD MOMSN=17074822
2022-07-22T20:05:40.761Z,1658520340.761 [DataOverHttps](INFO): Sending 163 bytes from file Logs/20220722T190204/Express0028.lzma
2022-07-22T20:05:41.090Z,1658520341.090 [NAL9602](INFO): SBD MO Status=2, MOMSN=35502, MT Status=2, MTMSN=0
2022-07-22T20:05:41.090Z,1658520341.090 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-07-22T20:05:41.763Z,1658520341.763 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Express0028.lzma.bak
2022-07-22T20:05:41.763Z,1658520341.763 [DataOverHttps](INFO): SBD MOMSN=17074825
2022-07-22T20:05:43.136Z,1658520343.136 [Default:CheckIn:Read_Iridium] Stopped
2022-07-22T20:05:43.136Z,1658520343.136 [Default:CheckIn:C.Wait] Running Loop=1
2022-07-22T20:05:43.136Z,1658520343.136 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-07-22T20:06:03.722Z,1658520363.722 [NAL9602](INFO): SBD MO Status=2, MOMSN=35502, MT Status=2, MTMSN=0
2022-07-22T20:06:03.722Z,1658520363.722 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-07-22T20:07:06.031Z,1658520426.031 [NAL9602](INFO): SBD MO Status=2, MOMSN=35502, MT Status=2, MTMSN=0
2022-07-22T20:07:06.031Z,1658520426.031 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-07-22T20:08:51.579Z,1658520531.579 [NAL9602](INFO): SBD MO Status=2, MOMSN=35502, MT Status=2, MTMSN=0
2022-07-22T20:08:51.579Z,1658520531.579 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-07-22T20:09:58.247Z,1658520598.247 [NAL9602](INFO): SBD MO Status=2, MOMSN=35502, MT Status=2, MTMSN=0
2022-07-22T20:09:58.247Z,1658520598.247 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-07-22T20:10:10.367Z,1658520610.367 [NAL9602](INFO): SBD MO Status=0, MOMSN=35502, MT Status=0, MTMSN=0
2022-07-22T20:10:10.367Z,1658520610.367 [NAL9602](INFO): No messages in MT queue
2022-07-22T20:10:41.074Z,1658520641.074 [NAL9602](INFO): Not Powering down - fast GPS
2022-07-22T20:10:43.901Z,1658520643.901 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-07-22T20:10:43.901Z,1658520643.901 [Default:CheckIn:C.Wait] Stopped
2022-07-22T20:10:43.901Z,1658520643.901 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-07-22T20:10:43.901Z,1658520643.901 [Default:CheckIn:D] Running Loop=1
2022-07-22T20:10:44.309Z,1658520644.309 [Default:CheckIn:D] Stopped
2022-07-22T20:10:44.309Z,1658520644.309 [Default:CheckIn:E] Running Loop=1
2022-07-22T20:10:44.724Z,1658520644.724 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 286.176270 min 
2022-07-22T20:10:44.725Z,1658520644.725 [Default:CheckIn:E] Stopped
2022-07-22T20:10:44.725Z,1658520644.725 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-07-22T20:10:44.725Z,1658520644.725 [Default:CheckIn] Stopped
2022-07-22T20:10:44.725Z,1658520644.725 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-07-22T20:10:44.725Z,1658520644.725 [Default:CheckIn](INFO): Running loop #35
2022-07-22T20:10:44.725Z,1658520644.725 [Default:CheckIn] Running Loop=35
2022-07-22T20:10:44.725Z,1658520644.725 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-07-22T20:10:44.725Z,1658520644.725 [Default:CheckIn:Read_GPS] Running Loop=1
2022-07-22T20:10:46.728Z,1658520646.728 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201045.00,A,3639.36707,N,12157.61985,W,17.572,79.39,220722,,,D*77
2022-07-22T20:10:46.730Z,1658520646.730 [NAL9602](INFO): GPS fix at 20220722T201045: (36.656118, -121.960331)
2022-07-22T20:10:46.760Z,1658520646.760 [Default:CheckIn:Read_GPS] Stopped
2022-07-22T20:10:46.761Z,1658520646.761 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-07-22T20:10:47.767Z,1658520647.767 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-07-22T20:10:57.861Z,1658520657.861 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220722T190204/Courier0030.lzma
2022-07-22T20:10:58.862Z,1658520658.862 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Courier0030.lzma.bak
2022-07-22T20:10:58.863Z,1658520658.863 [DataOverHttps](INFO): SBD MOMSN=17074828
2022-07-22T20:11:15.081Z,1658520675.081 [DataOverHttps](INFO): Sending 193 bytes from file Logs/20220722T190204/Express0031.lzma
2022-07-22T20:11:16.083Z,1658520676.083 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Express0031.lzma.bak
2022-07-22T20:11:16.083Z,1658520676.083 [DataOverHttps](INFO): SBD MOMSN=17074831
2022-07-22T20:11:17.547Z,1658520677.547 [Default:CheckIn:Read_Iridium] Stopped
2022-07-22T20:11:17.547Z,1658520677.547 [Default:CheckIn:C.Wait] Running Loop=1
2022-07-22T20:11:17.547Z,1658520677.547 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-07-22T20:11:28.023Z,1658520688.023 [NAL9602](INFO): SBD MO Status=0, MOMSN=35503, MT Status=0, MTMSN=0
2022-07-22T20:11:28.023Z,1658520688.023 [NAL9602](INFO): No messages in MT queue
2022-07-22T20:11:58.722Z,1658520718.722 [NAL9602](INFO): Not Powering down - fast GPS
2022-07-22T20:16:18.270Z,1658520978.270 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-07-22T20:16:18.270Z,1658520978.270 [Default:CheckIn:C.Wait] Stopped
2022-07-22T20:16:18.270Z,1658520978.270 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-07-22T20:16:18.270Z,1658520978.270 [Default:CheckIn:D] Running Loop=1
2022-07-22T20:16:18.653Z,1658520978.653 [Default:CheckIn:D] Stopped
2022-07-22T20:16:18.653Z,1658520978.653 [Default:CheckIn:E] Running Loop=1
2022-07-22T20:16:19.064Z,1658520979.064 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 291.748665 min 
2022-07-22T20:16:19.064Z,1658520979.064 [Default:CheckIn:E] Stopped
2022-07-22T20:16:19.064Z,1658520979.064 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-07-22T20:16:19.064Z,1658520979.064 [Default:CheckIn] Stopped
2022-07-22T20:16:19.064Z,1658520979.064 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-07-22T20:16:19.064Z,1658520979.064 [Default:CheckIn](INFO): Running loop #36
2022-07-22T20:16:19.064Z,1658520979.064 [Default:CheckIn] Running Loop=36
2022-07-22T20:16:19.065Z,1658520979.065 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-07-22T20:16:19.065Z,1658520979.065 [Default:CheckIn:Read_GPS] Running Loop=1
2022-07-22T20:16:21.071Z,1658520981.071 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201620.00,A,3640.01489,N,12155.62062,W,20.333,73.08,220722,,,D*73
2022-07-22T20:16:21.074Z,1658520981.074 [NAL9602](INFO): GPS fix at 20220722T201620: (36.666915, -121.927010)
2022-07-22T20:16:21.107Z,1658520981.107 [Default:CheckIn:Read_GPS] Stopped
2022-07-22T20:16:21.107Z,1658520981.107 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-07-22T20:16:28.844Z,1658520988.844 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220722T190204/Courier0033.lzma
2022-07-22T20:16:30.850Z,1658520990.850 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Courier0033.lzma.bak
2022-07-22T20:16:30.851Z,1658520990.851 [DataOverHttps](INFO): SBD MOMSN=17074843
2022-07-22T20:16:46.605Z,1658521006.605 [DataOverHttps](INFO): Sending 164 bytes from file Logs/20220722T190204/Express0034.lzma
2022-07-22T20:16:47.606Z,1658521007.606 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Express0034.lzma.bak
2022-07-22T20:16:47.607Z,1658521007.607 [DataOverHttps](INFO): SBD MOMSN=17074846
2022-07-22T20:16:49.013Z,1658521009.013 [Default:CheckIn:Read_Iridium] Stopped
2022-07-22T20:16:49.013Z,1658521009.013 [Default:CheckIn:C.Wait] Running Loop=1
2022-07-22T20:16:49.013Z,1658521009.013 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-07-22T20:17:38.715Z,1658521058.715 [NAL9602](INFO): SBD MO Status=2, MOMSN=35504, MT Status=2, MTMSN=0
2022-07-22T20:17:38.715Z,1658521058.715 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-07-22T20:19:25.035Z,1658521165.035 [NAL9602](INFO): SBD MO Status=2, MOMSN=35504, MT Status=2, MTMSN=0
2022-07-22T20:19:25.035Z,1658521165.035 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-07-22T20:19:43.216Z,1658521183.216 [NAL9602](INFO): SBD MO Status=0, MOMSN=35504, MT Status=0, MTMSN=0
2022-07-22T20:19:43.216Z,1658521183.216 [NAL9602](INFO): No messages in MT queue
2022-07-22T20:20:13.915Z,1658521213.915 [NAL9602](INFO): Not Powering down - fast GPS
2022-07-22T20:21:49.695Z,1658521309.695 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-07-22T20:21:49.695Z,1658521309.695 [Default:CheckIn:C.Wait] Stopped
2022-07-22T20:21:49.695Z,1658521309.695 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-07-22T20:21:49.695Z,1658521309.695 [Default:CheckIn:D] Running Loop=1
2022-07-22T20:21:50.097Z,1658521310.097 [Default:CheckIn:D] Stopped
2022-07-22T20:21:50.097Z,1658521310.097 [Default:CheckIn:E] Running Loop=1
2022-07-22T20:21:50.516Z,1658521310.516 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 297.272721 min 
2022-07-22T20:21:50.516Z,1658521310.516 [Default:CheckIn:E] Stopped
2022-07-22T20:21:50.516Z,1658521310.516 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-07-22T20:21:50.516Z,1658521310.516 [Default:CheckIn] Stopped
2022-07-22T20:21:50.516Z,1658521310.516 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-07-22T20:21:50.516Z,1658521310.516 [Default:CheckIn](INFO): Running loop #37
2022-07-22T20:21:50.516Z,1658521310.516 [Default:CheckIn] Running Loop=37
2022-07-22T20:21:50.517Z,1658521310.517 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-07-22T20:21:50.517Z,1658521310.517 [Default:CheckIn:Read_GPS] Running Loop=1
2022-07-22T20:21:52.510Z,1658521312.510 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202151.00,A,3641.02720,N,12153.84348,W,16.775,20.83,220722,,,D*70
2022-07-22T20:21:52.512Z,1658521312.512 [NAL9602](INFO): GPS fix at 20220722T202151: (36.683787, -121.897391)
2022-07-22T20:21:52.522Z,1658521312.522 [Default:CheckIn:Read_GPS] Stopped
2022-07-22T20:21:52.522Z,1658521312.522 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-07-22T20:22:00.360Z,1658521320.360 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220722T190204/Courier0036.lzma
2022-07-22T20:22:01.362Z,1658521321.362 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Courier0036.lzma.bak
2022-07-22T20:22:01.363Z,1658521321.363 [DataOverHttps](INFO): SBD MOMSN=17074850
2022-07-22T20:22:17.093Z,1658521337.093 [DataOverHttps](INFO): Sending 174 bytes from file Logs/20220722T190204/Express0037.lzma
2022-07-22T20:22:18.095Z,1658521338.095 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Express0037.lzma.bak
2022-07-22T20:22:18.095Z,1658521338.095 [DataOverHttps](INFO): SBD MOMSN=17074853
2022-07-22T20:22:19.624Z,1658521339.624 [Default:CheckIn:Read_Iridium] Stopped
2022-07-22T20:22:19.624Z,1658521339.624 [Default:CheckIn:C.Wait] Running Loop=1
2022-07-22T20:22:19.624Z,1658521339.624 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-07-22T20:22:26.529Z,1658521346.529 [NAL9602](INFO): SBD MO Status=2, MOMSN=35505, MT Status=2, MTMSN=0
2022-07-22T20:22:26.530Z,1658521346.530 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-07-22T20:23:32.427Z,1658521412.427 [NAL9602](INFO): SBD MO Status=2, MOMSN=35505, MT Status=2, MTMSN=0
2022-07-22T20:23:32.427Z,1658521412.427 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-07-22T20:23:59.087Z,1658521439.087 [NAL9602](INFO): SBD MO Status=0, MOMSN=35505, MT Status=0, MTMSN=0
2022-07-22T20:23:59.087Z,1658521439.087 [NAL9602](INFO): No messages in MT queue
2022-07-22T20:24:29.803Z,1658521469.803 [NAL9602](INFO): Not Powering down - fast GPS
2022-07-22T20:27:20.321Z,1658521640.321 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-07-22T20:27:20.321Z,1658521640.321 [Default:CheckIn:C.Wait] Stopped
2022-07-22T20:27:20.321Z,1658521640.321 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-07-22T20:27:20.321Z,1658521640.321 [Default:CheckIn:D] Running Loop=1
2022-07-22T20:27:20.732Z,1658521640.732 [Default:CheckIn:D] Stopped
2022-07-22T20:27:20.732Z,1658521640.732 [Default:CheckIn:E] Running Loop=1
2022-07-22T20:27:21.128Z,1658521641.128 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 302.783333 min 
2022-07-22T20:27:21.128Z,1658521641.128 [Default:CheckIn:E] Stopped
2022-07-22T20:27:21.128Z,1658521641.128 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-07-22T20:27:21.128Z,1658521641.128 [Default:CheckIn] Stopped
2022-07-22T20:27:21.128Z,1658521641.128 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-07-22T20:27:21.128Z,1658521641.128 [Default:CheckIn](INFO): Running loop #38
2022-07-22T20:27:21.128Z,1658521641.128 [Default:CheckIn] Running Loop=38
2022-07-22T20:27:21.137Z,1658521641.137 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-07-22T20:27:21.137Z,1658521641.137 [Default:CheckIn:Read_GPS] Running Loop=1
2022-07-22T20:27:23.140Z,1658521643.140 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202722.00,A,3641.31467,N,12153.40173,W,1.400,116.58,220722,,,D*75
2022-07-22T20:27:23.142Z,1658521643.142 [NAL9602](INFO): GPS fix at 20220722T202722: (36.688578, -121.890029)
2022-07-22T20:27:23.185Z,1658521643.185 [Default:CheckIn:Read_GPS] Stopped
2022-07-22T20:27:23.185Z,1658521643.185 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-07-22T20:27:30.017Z,1658521650.017 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220722T190204/Courier0039.lzma
2022-07-22T20:27:31.018Z,1658521651.018 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Courier0039.lzma.bak
2022-07-22T20:27:31.019Z,1658521651.019 [DataOverHttps](INFO): SBD MOMSN=17074857
2022-07-22T20:27:46.869Z,1658521666.869 [DataOverHttps](INFO): Sending 175 bytes from file Logs/20220722T190204/Express0040.lzma
2022-07-22T20:27:47.871Z,1658521667.871 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Express0040.lzma.bak
2022-07-22T20:27:47.871Z,1658521667.871 [DataOverHttps](INFO): SBD MOMSN=17074860
2022-07-22T20:27:49.412Z,1658521669.412 [Default:CheckIn:Read_Iridium] Stopped
2022-07-22T20:27:49.412Z,1658521669.412 [Default:CheckIn:C.Wait] Running Loop=1
2022-07-22T20:27:49.412Z,1658521669.412 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-07-22T20:31:37.770Z,1658521897.770 [NAL9602](INFO): SBD MO Status=0, MOMSN=35506, MT Status=0, MTMSN=0
2022-07-22T20:31:37.771Z,1658521897.771 [NAL9602](INFO): No messages in MT queue
2022-07-22T20:32:08.462Z,1658521928.462 [NAL9602](INFO): Not Powering down - fast GPS
2022-07-22T20:32:50.092Z,1658521970.092 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-07-22T20:32:50.092Z,1658521970.092 [Default:CheckIn:C.Wait] Stopped
2022-07-22T20:32:50.092Z,1658521970.092 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-07-22T20:32:50.092Z,1658521970.092 [Default:CheckIn:D] Running Loop=1
2022-07-22T20:32:50.494Z,1658521970.494 [Default:CheckIn:D] Stopped
2022-07-22T20:32:50.494Z,1658521970.494 [Default:CheckIn:E] Running Loop=1
2022-07-22T20:32:50.890Z,1658521970.890 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 308.279362 min 
2022-07-22T20:32:50.890Z,1658521970.890 [Default:CheckIn:E] Stopped
2022-07-22T20:32:50.890Z,1658521970.890 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-07-22T20:32:50.890Z,1658521970.890 [Default:CheckIn] Stopped
2022-07-22T20:32:50.890Z,1658521970.890 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-07-22T20:32:50.891Z,1658521970.891 [Default:CheckIn](INFO): Running loop #39
2022-07-22T20:32:50.891Z,1658521970.891 [Default:CheckIn] Running Loop=39
2022-07-22T20:32:50.891Z,1658521970.891 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-07-22T20:32:50.891Z,1658521970.891 [Default:CheckIn:Read_GPS] Running Loop=1
2022-07-22T20:32:52.903Z,1658521972.903 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203252.00,A,3641.32760,N,12153.21386,W,10.691,31.57,220722,,,D*7F
2022-07-22T20:32:52.906Z,1658521972.906 [NAL9602](INFO): GPS fix at 20220722T203252: (36.688793, -121.886898)
2022-07-22T20:32:52.922Z,1658521972.922 [Default:CheckIn:Read_GPS] Stopped
2022-07-22T20:32:52.922Z,1658521972.922 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-07-22T20:33:00.416Z,1658521980.416 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220722T190204/Courier0042.lzma
2022-07-22T20:33:01.418Z,1658521981.418 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Courier0042.lzma.bak
2022-07-22T20:33:01.418Z,1658521981.418 [DataOverHttps](INFO): SBD MOMSN=17074863
2022-07-22T20:33:17.177Z,1658521997.177 [DataOverHttps](INFO): Sending 161 bytes from file Logs/20220722T190204/Express0043.lzma
2022-07-22T20:33:18.178Z,1658521998.178 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Express0043.lzma.bak
2022-07-22T20:33:18.179Z,1658521998.179 [DataOverHttps](INFO): SBD MOMSN=17074866
2022-07-22T20:33:19.620Z,1658521999.620 [Default:CheckIn:Read_Iridium] Stopped
2022-07-22T20:33:19.621Z,1658521999.621 [Default:CheckIn:C.Wait] Running Loop=1
2022-07-22T20:33:19.621Z,1658521999.621 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-07-22T20:36:11.043Z,1658522171.043 [NAL9602](INFO): SBD MO Status=0, MOMSN=35507, MT Status=0, MTMSN=0
2022-07-22T20:36:11.043Z,1658522171.043 [NAL9602](INFO): No messages in MT queue
2022-07-22T20:36:41.766Z,1658522201.766 [NAL9602](INFO): Not Powering down - fast GPS
2022-07-22T20:38:20.349Z,1658522300.349 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-07-22T20:38:20.349Z,1658522300.349 [Default:CheckIn:C.Wait] Stopped
2022-07-22T20:38:20.349Z,1658522300.349 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-07-22T20:38:20.349Z,1658522300.349 [Default:CheckIn:D] Running Loop=1
2022-07-22T20:38:20.760Z,1658522300.760 [Default:CheckIn:D] Stopped
2022-07-22T20:38:20.760Z,1658522300.760 [Default:CheckIn:E] Running Loop=1
2022-07-22T20:38:21.163Z,1658522301.163 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 313.783789 min 
2022-07-22T20:38:21.163Z,1658522301.163 [Default:CheckIn:E] Stopped
2022-07-22T20:38:21.163Z,1658522301.163 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-07-22T20:38:21.164Z,1658522301.164 [Default:CheckIn] Stopped
2022-07-22T20:38:21.164Z,1658522301.164 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-07-22T20:38:21.164Z,1658522301.164 [Default:CheckIn](INFO): Running loop #40
2022-07-22T20:38:21.164Z,1658522301.164 [Default:CheckIn] Running Loop=40
2022-07-22T20:38:21.164Z,1658522301.164 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-07-22T20:38:21.164Z,1658522301.164 [Default:CheckIn:Read_GPS] Running Loop=1
2022-07-22T20:38:23.172Z,1658522303.172 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203822.00,A,3642.58104,N,12152.38483,W,21.557,36.12,220722,,,D*7F
2022-07-22T20:38:23.175Z,1658522303.175 [NAL9602](INFO): GPS fix at 20220722T203822: (36.709684, -121.873081)
2022-07-22T20:38:23.185Z,1658522303.185 [Default:CheckIn:Read_GPS] Stopped
2022-07-22T20:38:23.185Z,1658522303.185 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-07-22T20:38:30.336Z,1658522310.336 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220722T190204/Courier0045.lzma
2022-07-22T20:38:31.338Z,1658522311.338 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Courier0045.lzma.bak
2022-07-22T20:38:31.339Z,1658522311.339 [DataOverHttps](INFO): SBD MOMSN=17074904
2022-07-22T20:38:32.479Z,1658522312.479 [NAL9602](INFO): SBD MO Status=0, MOMSN=35508, MT Status=0, MTMSN=0
2022-07-22T20:38:32.479Z,1658522312.479 [NAL9602](INFO): No messages in MT queue
2022-07-22T20:38:47.913Z,1658522327.913 [DataOverHttps](INFO): Sending 161 bytes from file Logs/20220722T190204/Express0046.lzma
2022-07-22T20:38:48.915Z,1658522328.915 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Express0046.lzma.bak
2022-07-22T20:38:48.915Z,1658522328.915 [DataOverHttps](INFO): SBD MOMSN=17074929
2022-07-22T20:38:50.266Z,1658522330.266 [Default:CheckIn:Read_Iridium] Stopped
2022-07-22T20:38:50.266Z,1658522330.266 [Default:CheckIn:C.Wait] Running Loop=1
2022-07-22T20:38:50.266Z,1658522330.266 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-07-22T20:39:03.208Z,1658522343.208 [NAL9602](INFO): Not Powering down - fast GPS
2022-07-22T20:43:50.958Z,1658522630.958 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-07-22T20:43:50.958Z,1658522630.958 [Default:CheckIn:C.Wait] Stopped
2022-07-22T20:43:50.958Z,1658522630.958 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-07-22T20:43:50.958Z,1658522630.958 [Default:CheckIn:D] Running Loop=1
2022-07-22T20:43:51.362Z,1658522631.362 [Default:CheckIn:D] Stopped
2022-07-22T20:43:51.362Z,1658522631.362 [Default:CheckIn:E] Running Loop=1
2022-07-22T20:43:51.776Z,1658522631.776 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 319.293815 min 
2022-07-22T20:43:51.776Z,1658522631.776 [Default:CheckIn:E] Stopped
2022-07-22T20:43:51.776Z,1658522631.776 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-07-22T20:43:51.776Z,1658522631.776 [Default:CheckIn] Stopped
2022-07-22T20:43:51.776Z,1658522631.776 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-07-22T20:43:51.776Z,1658522631.776 [Default:CheckIn](INFO): Running loop #41
2022-07-22T20:43:51.777Z,1658522631.777 [Default:CheckIn] Running Loop=41
2022-07-22T20:43:51.777Z,1658522631.777 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-07-22T20:43:51.777Z,1658522631.777 [Default:CheckIn:Read_GPS] Running Loop=1
2022-07-22T20:43:53.780Z,1658522633.780 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204353.00,A,3644.30190,N,12151.07681,W,16.017,18.93,220722,,,D*7F
2022-07-22T20:43:53.782Z,1658522633.782 [NAL9602](INFO): GPS fix at 20220722T204353: (36.738365, -121.851280)
2022-07-22T20:43:53.817Z,1658522633.817 [Default:CheckIn:Read_GPS] Stopped
2022-07-22T20:43:53.817Z,1658522633.817 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-07-22T20:44:26.093Z,1658522666.093 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-07-22T20:44:33.820Z,1658522673.820 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220722T190204/Courier0048.lzma
2022-07-22T20:44:34.822Z,1658522674.822 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Courier0048.lzma.bak
2022-07-22T20:44:34.823Z,1658522674.823 [DataOverHttps](INFO): SBD MOMSN=17074942
2022-07-22T20:44:52.873Z,1658522692.873 [DataOverHttps](INFO): Sending 167 bytes from file Logs/20220722T190204/Express0049.lzma
2022-07-22T20:44:53.875Z,1658522693.875 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Express0049.lzma.bak
2022-07-22T20:44:53.875Z,1658522693.875 [DataOverHttps](INFO): SBD MOMSN=17074947
2022-07-22T20:44:55.241Z,1658522695.241 [Default:CheckIn:Read_Iridium] Stopped
2022-07-22T20:44:55.241Z,1658522695.241 [Default:CheckIn:C.Wait] Running Loop=1
2022-07-22T20:44:55.241Z,1658522695.241 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-07-22T20:45:51.791Z,1658522751.791 [NAL9602](INFO): SBD MO Status=2, MOMSN=35509, MT Status=2, MTMSN=0
2022-07-22T20:45:51.791Z,1658522751.791 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-07-22T20:46:26.093Z,1658522786.093 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-07-22T20:46:26.131Z,1658522786.131 [NAL9602](INFO): SBD MO Status=0, MOMSN=35509, MT Status=0, MTMSN=0
2022-07-22T20:46:26.131Z,1658522786.131 [NAL9602](INFO): No messages in MT queue
2022-07-22T20:46:56.876Z,1658522816.876 [NAL9602](INFO): Not Powering down - fast GPS
2022-07-22T20:49:55.873Z,1658522995.873 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-07-22T20:49:55.873Z,1658522995.873 [Default:CheckIn:C.Wait] Stopped
2022-07-22T20:49:55.873Z,1658522995.873 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-07-22T20:49:55.873Z,1658522995.873 [Default:CheckIn:D] Running Loop=1
2022-07-22T20:49:56.275Z,1658522996.275 [Default:CheckIn:D] Stopped
2022-07-22T20:49:56.275Z,1658522996.275 [Default:CheckIn:E] Running Loop=1
2022-07-22T20:49:56.682Z,1658522996.682 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 325.375716 min 
2022-07-22T20:49:56.682Z,1658522996.682 [Default:CheckIn:E] Stopped
2022-07-22T20:49:56.682Z,1658522996.682 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-07-22T20:49:56.682Z,1658522996.682 [Default:CheckIn] Stopped
2022-07-22T20:49:56.682Z,1658522996.682 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-07-22T20:49:56.682Z,1658522996.682 [Default:CheckIn](INFO): Running loop #42
2022-07-22T20:49:56.682Z,1658522996.682 [Default:CheckIn] Running Loop=42
2022-07-22T20:49:56.682Z,1658522996.682 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-07-22T20:49:56.682Z,1658522996.682 [Default:CheckIn:Read_GPS] Running Loop=1
2022-07-22T20:49:58.691Z,1658522998.691 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204957.00,A,3646.25639,N,12150.13658,W,17.164,29.49,220722,,,D*72
2022-07-22T20:49:58.694Z,1658522998.694 [NAL9602](INFO): GPS fix at 20220722T204957: (36.770940, -121.835610)
2022-07-22T20:49:58.738Z,1658522998.738 [Default:CheckIn:Read_GPS] Stopped
2022-07-22T20:49:58.738Z,1658522998.738 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-07-22T20:50:06.340Z,1658523006.340 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220722T190204/Courier0051.lzma
2022-07-22T20:50:07.427Z,1658523007.427 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Courier0051.lzma.bak
2022-07-22T20:50:07.427Z,1658523007.427 [DataOverHttps](INFO): SBD MOMSN=17074953
2022-07-22T20:50:23.245Z,1658523023.245 [DataOverHttps](INFO): Sending 166 bytes from file Logs/20220722T190204/Express0052.lzma
2022-07-22T20:50:24.246Z,1658523024.246 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Express0052.lzma.bak
2022-07-22T20:50:24.247Z,1658523024.247 [DataOverHttps](INFO): SBD MOMSN=17074957
2022-07-22T20:50:25.351Z,1658523025.351 [NAL9602](INFO): SBD MO Status=2, MOMSN=35510, MT Status=2, MTMSN=0
2022-07-22T20:50:25.351Z,1658523025.351 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-07-22T20:50:25.789Z,1658523025.789 [Default:CheckIn:Read_Iridium] Stopped
2022-07-22T20:50:25.790Z,1658523025.790 [Default:CheckIn:C.Wait] Running Loop=1
2022-07-22T20:50:25.790Z,1658523025.790 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-07-22T20:53:22.303Z,1658523202.303 [NAL9602](INFO): SBD MO Status=2, MOMSN=35510, MT Status=2, MTMSN=0
2022-07-22T20:53:22.304Z,1658523202.304 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-07-22T20:53:50.186Z,1658523230.186 [NAL9602](INFO): SBD MO Status=2, MOMSN=35510, MT Status=2, MTMSN=0
2022-07-22T20:53:50.186Z,1658523230.186 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-07-22T20:54:35.030Z,1658523275.030 [NAL9602](INFO): SBD MO Status=2, MOMSN=35510, MT Status=2, MTMSN=0
2022-07-22T20:54:35.031Z,1658523275.031 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-07-22T20:55:01.686Z,1658523301.686 [NAL9602](INFO): SBD MO Status=2, MOMSN=35510, MT Status=2, MTMSN=0
2022-07-22T20:55:01.687Z,1658523301.687 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-07-22T20:55:02.098Z,1658523302.098 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-07-22T20:55:26.338Z,1658523326.338 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-07-22T20:55:26.338Z,1658523326.338 [Default:CheckIn:C.Wait] Stopped
2022-07-22T20:55:26.338Z,1658523326.338 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-07-22T20:55:26.338Z,1658523326.338 [Default:CheckIn:D] Running Loop=1
2022-07-22T20:55:26.757Z,1658523326.757 [Default:CheckIn:D] Stopped
2022-07-22T20:55:26.757Z,1658523326.757 [Default:CheckIn:E] Running Loop=1
2022-07-22T20:55:27.169Z,1658523327.169 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 330.883757 min 
2022-07-22T20:55:27.169Z,1658523327.169 [Default:CheckIn:E] Stopped
2022-07-22T20:55:27.170Z,1658523327.170 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-07-22T20:55:27.170Z,1658523327.170 [Default:CheckIn] Stopped
2022-07-22T20:55:27.170Z,1658523327.170 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-07-22T20:55:27.170Z,1658523327.170 [Default:CheckIn](INFO): Running loop #43
2022-07-22T20:55:27.170Z,1658523327.170 [Default:CheckIn] Running Loop=43
2022-07-22T20:55:27.170Z,1658523327.170 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-07-22T20:55:27.170Z,1658523327.170 [Default:CheckIn:Read_GPS] Running Loop=1
2022-07-22T20:55:29.158Z,1658523329.158 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205528.00,A,3647.53691,N,12150.25142,W,9.253,284.04,220722,,,D*78
2022-07-22T20:55:29.161Z,1658523329.161 [NAL9602](INFO): GPS fix at 20220722T205528: (36.792282, -121.837524)
2022-07-22T20:55:29.171Z,1658523329.171 [Default:CheckIn:Read_GPS] Stopped
2022-07-22T20:55:29.171Z,1658523329.171 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-07-22T20:55:56.376Z,1658523356.376 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220722T190204/Courier0054.lzma
2022-07-22T20:55:59.387Z,1658523359.387 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Courier0054.lzma.bak
2022-07-22T20:55:59.387Z,1658523359.387 [DataOverHttps](INFO): SBD MOMSN=17074960
2022-07-22T20:56:01.534Z,1658523361.534 [NAL9602](INFO): Not Powering down - fast GPS
2022-07-22T20:57:10.589Z,1658523430.589 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-07-22T20:57:41.713Z,1658523461.713 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-07-22T20:58:12.849Z,1658523492.849 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-07-22T20:58:27.342Z,1658523507.342 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-07-22T20:58:42.293Z,1658523522.293 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-07-22T20:58:43.973Z,1658523523.973 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-07-22T20:58:57.642Z,1658523537.642 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-07-22T20:59:08.069Z,1658523548.069 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.167297
2022-07-22T20:59:13.802Z,1658523553.802 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-07-22T20:59:28.806Z,1658523568.806 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-07-22T20:59:30.021Z,1658523570.021 [NAL9602](INFO): SBD MO Status=2, MOMSN=35510, MT Status=2, MTMSN=0
2022-07-22T20:59:30.021Z,1658523570.021 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-07-22T20:59:39.193Z,1658523579.193 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-07-22T20:59:43.806Z,1658523583.806 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-07-22T20:59:51.892Z,1658523591.892 [NAL9602](INFO): SBD MO Status=1, MOMSN=35510, MT Status=0, MTMSN=0
2022-07-22T20:59:51.945Z,1658523591.945 [NAL9602](INFO): Sent 292 bytes from file Logs/20220722T190204/Express0055.lzma
2022-07-22T20:59:51.945Z,1658523591.945 [NAL9602](INFO): Packets left to send: 0
2022-07-22T20:59:59.158Z,1658523599.158 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-07-22T21:00:00.779Z,1658523600.779 [NAL9602](INFO): SBD MO Status=0, MOMSN=35511, MT Status=0, MTMSN=0
2022-07-22T21:00:00.863Z,1658523600.863 [Default:CheckIn:Read_Iridium] Stopped
2022-07-22T21:00:00.863Z,1658523600.863 [Default:CheckIn:C.Wait] Running Loop=1
2022-07-22T21:00:00.864Z,1658523600.864 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-07-22T21:00:10.325Z,1658523610.325 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-07-22T21:00:14.109Z,1658523614.109 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-07-22T21:00:29.458Z,1658523629.458 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-07-22T21:00:31.486Z,1658523631.486 [NAL9602](INFO): Not Powering down - fast GPS
2022-07-22T21:00:41.449Z,1658523641.449 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-07-22T21:00:44.811Z,1658523644.811 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-07-22T21:00:55.727Z,1658523655.727 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2022-07-22T21:00:55.730Z,1658523655.730 [BPC1](INFO): Received data from all battery sticks.
2022-07-22T21:01:12.573Z,1658523672.573 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-07-22T21:01:21.505Z,1658523681.505 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003170
2022-07-22T21:05:01.581Z,1658523901.581 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-07-22T21:05:01.581Z,1658523901.581 [Default:CheckIn:C.Wait] Stopped
2022-07-22T21:05:01.581Z,1658523901.581 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-07-22T21:05:01.581Z,1658523901.581 [Default:CheckIn:D] Running Loop=1
2022-07-22T21:05:01.983Z,1658523901.983 [Default:CheckIn:D] Stopped
2022-07-22T21:05:01.983Z,1658523901.983 [Default:CheckIn:E] Running Loop=1
2022-07-22T21:05:02.398Z,1658523902.398 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 340.470866 min 
2022-07-22T21:05:02.398Z,1658523902.398 [Default:CheckIn:E] Stopped
2022-07-22T21:05:02.398Z,1658523902.398 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-07-22T21:05:02.398Z,1658523902.398 [Default:CheckIn] Stopped
2022-07-22T21:05:02.398Z,1658523902.398 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-07-22T21:05:02.398Z,1658523902.398 [Default:CheckIn](INFO): Running loop #44
2022-07-22T21:05:02.398Z,1658523902.398 [Default:CheckIn] Running Loop=44
2022-07-22T21:05:02.398Z,1658523902.398 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-07-22T21:05:02.398Z,1658523902.398 [Default:CheckIn:Read_GPS] Running Loop=1
2022-07-22T21:05:04.399Z,1658523904.399 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210503.00,A,3647.75442,N,12150.54497,W,0.952,100.11,220722,,,D*7E
2022-07-22T21:05:04.402Z,1658523904.402 [NAL9602](INFO): GPS fix at 20220722T210503: (36.795907, -121.842416)
2022-07-22T21:05:04.411Z,1658523904.411 [Default:CheckIn:Read_GPS] Stopped
2022-07-22T21:05:04.412Z,1658523904.412 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-07-22T21:05:11.664Z,1658523911.664 [DataOverHttps](INFO): Sending 86 bytes from file Logs/20220722T190204/Courier0057.lzma
2022-07-22T21:05:12.667Z,1658523912.667 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Courier0057.lzma.bak
2022-07-22T21:05:12.667Z,1658523912.667 [DataOverHttps](INFO): SBD MOMSN=17074968
2022-07-22T21:05:28.812Z,1658523928.812 [DataOverHttps](INFO): Sending 161 bytes from file Logs/20220722T190204/Express0058.lzma
2022-07-22T21:05:29.815Z,1658523929.815 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Express0058.lzma.bak
2022-07-22T21:05:29.815Z,1658523929.815 [DataOverHttps](INFO): SBD MOMSN=17074971
2022-07-22T21:05:31.109Z,1658523931.109 [Default:CheckIn:Read_Iridium] Stopped
2022-07-22T21:05:31.109Z,1658523931.109 [Default:CheckIn:C.Wait] Running Loop=1
2022-07-22T21:05:31.109Z,1658523931.109 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-07-22T21:06:13.111Z,1658523973.111 [NAL9602](INFO): SBD MO Status=0, MOMSN=35512, MT Status=0, MTMSN=0
2022-07-22T21:06:13.111Z,1658523973.111 [NAL9602](INFO): No messages in MT queue
2022-07-22T21:06:43.857Z,1658524003.857 [NAL9602](INFO): Not Powering down - fast GPS
2022-07-22T21:10:31.719Z,1658524231.719 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-07-22T21:10:31.719Z,1658524231.719 [Default:CheckIn:C.Wait] Stopped
2022-07-22T21:10:31.719Z,1658524231.719 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-07-22T21:10:31.719Z,1658524231.719 [Default:CheckIn:D] Running Loop=1
2022-07-22T21:10:32.120Z,1658524232.120 [Default:CheckIn:D] Stopped
2022-07-22T21:10:32.120Z,1658524232.120 [Default:CheckIn:E] Running Loop=1
2022-07-22T21:10:32.536Z,1658524232.536 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 345.973112 min 
2022-07-22T21:10:32.536Z,1658524232.536 [Default:CheckIn:E] Stopped
2022-07-22T21:10:32.536Z,1658524232.536 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-07-22T21:10:32.537Z,1658524232.537 [Default:CheckIn] Stopped
2022-07-22T21:10:32.537Z,1658524232.537 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-07-22T21:10:32.537Z,1658524232.537 [Default:CheckIn](INFO): Running loop #45
2022-07-22T21:10:32.537Z,1658524232.537 [Default:CheckIn] Running Loop=45
2022-07-22T21:10:32.537Z,1658524232.537 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-07-22T21:10:32.537Z,1658524232.537 [Default:CheckIn:Read_GPS] Running Loop=1
2022-07-22T21:10:34.536Z,1658524234.536 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211033.00,A,3647.78419,N,12150.44175,W,5.948,280.43,220722,,,A*75
2022-07-22T21:10:34.538Z,1658524234.538 [NAL9602](INFO): GPS fix at 20220722T211033: (36.796403, -121.840696)
2022-07-22T21:10:34.548Z,1658524234.548 [Default:CheckIn:Read_GPS] Stopped
2022-07-22T21:10:34.548Z,1658524234.548 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-07-22T21:10:42.352Z,1658524242.352 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220722T190204/Courier0060.lzma
2022-07-22T21:10:43.355Z,1658524243.355 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Courier0060.lzma.bak
2022-07-22T21:10:43.355Z,1658524243.355 [DataOverHttps](INFO): SBD MOMSN=17074991
2022-07-22T21:11:02.477Z,1658524262.477 [DataOverHttps](INFO): Sending 165 bytes from file Logs/20220722T190204/Express0061.lzma
2022-07-22T21:11:03.383Z,1658524263.383 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Express0061.lzma.bak
2022-07-22T21:11:03.383Z,1658524263.383 [DataOverHttps](INFO): SBD MOMSN=17074994
2022-07-22T21:11:04.898Z,1658524264.898 [Default:CheckIn:Read_Iridium] Stopped
2022-07-22T21:11:04.898Z,1658524264.898 [Default:CheckIn:C.Wait] Running Loop=1
2022-07-22T21:11:04.898Z,1658524264.898 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-07-22T21:11:19.027Z,1658524279.027 [NAL9602](INFO): SBD MO Status=0, MOMSN=35513, MT Status=0, MTMSN=0
2022-07-22T21:11:19.028Z,1658524279.028 [NAL9602](INFO): No messages in MT queue
2022-07-22T21:11:49.734Z,1658524309.734 [NAL9602](INFO): Not Powering down - fast GPS
2022-07-22T21:16:05.504Z,1658524565.504 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-07-22T21:16:05.504Z,1658524565.504 [Default:CheckIn:C.Wait] Stopped
2022-07-22T21:16:05.504Z,1658524565.504 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-07-22T21:16:05.505Z,1658524565.505 [Default:CheckIn:D] Running Loop=1
2022-07-22T21:16:05.887Z,1658524565.887 [Default:CheckIn:D] Stopped
2022-07-22T21:16:05.887Z,1658524565.887 [Default:CheckIn:E] Running Loop=1
2022-07-22T21:16:06.290Z,1658524566.290 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 351.535905 min 
2022-07-22T21:16:06.290Z,1658524566.290 [Default:CheckIn:E] Stopped
2022-07-22T21:16:06.290Z,1658524566.290 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-07-22T21:16:06.290Z,1658524566.290 [Default:CheckIn] Stopped
2022-07-22T21:16:06.290Z,1658524566.290 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-07-22T21:16:06.290Z,1658524566.290 [Default:CheckIn](INFO): Running loop #46
2022-07-22T21:16:06.291Z,1658524566.291 [Default:CheckIn] Running Loop=46
2022-07-22T21:16:06.291Z,1658524566.291 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-07-22T21:16:06.291Z,1658524566.291 [Default:CheckIn:Read_GPS] Running Loop=1
2022-07-22T21:16:08.311Z,1658524568.311 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211607.00,A,3647.73535,N,12150.72723,W,0.194,288.07,220722,,,D*71
2022-07-22T21:16:08.314Z,1658524568.314 [NAL9602](INFO): GPS fix at 20220722T211607: (36.795589, -121.845454)
2022-07-22T21:16:08.324Z,1658524568.324 [Default:CheckIn:Read_GPS] Stopped
2022-07-22T21:16:08.324Z,1658524568.324 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-07-22T21:16:17.177Z,1658524577.177 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220722T190204/Courier0063.lzma
2022-07-22T21:16:18.175Z,1658524578.175 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Courier0063.lzma.bak
2022-07-22T21:16:18.175Z,1658524578.175 [DataOverHttps](INFO): SBD MOMSN=17075005
2022-07-22T21:16:34.211Z,1658524594.211 [NAL9602](INFO): SBD MO Status=2, MOMSN=35514, MT Status=2, MTMSN=0
2022-07-22T21:16:34.211Z,1658524594.211 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-07-22T21:16:36.970Z,1658524596.970 [DataOverHttps](INFO): Sending 161 bytes from file Logs/20220722T190204/Express0064.lzma
2022-07-22T21:16:37.971Z,1658524597.971 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Express0064.lzma.bak
2022-07-22T21:16:37.971Z,1658524597.971 [DataOverHttps](INFO): SBD MOMSN=17075008
2022-07-22T21:16:39.472Z,1658524599.472 [Default:CheckIn:Read_Iridium] Stopped
2022-07-22T21:16:39.472Z,1658524599.472 [Default:CheckIn:C.Wait] Running Loop=1
2022-07-22T21:16:39.472Z,1658524599.472 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-07-22T21:16:58.463Z,1658524618.463 [NAL9602](INFO): SBD MO Status=0, MOMSN=35514, MT Status=0, MTMSN=0
2022-07-22T21:16:58.463Z,1658524618.463 [NAL9602](INFO): No messages in MT queue
2022-07-22T21:17:29.164Z,1658524649.164 [NAL9602](INFO): Not Powering down - fast GPS
2022-07-22T21:21:40.085Z,1658524900.085 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-07-22T21:21:40.085Z,1658524900.085 [Default:CheckIn:C.Wait] Stopped
2022-07-22T21:21:40.085Z,1658524900.085 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-07-22T21:21:40.086Z,1658524900.086 [Default:CheckIn:D] Running Loop=1
2022-07-22T21:21:40.497Z,1658524900.497 [Default:CheckIn:D] Stopped
2022-07-22T21:21:40.497Z,1658524900.497 [Default:CheckIn:E] Running Loop=1
2022-07-22T21:21:40.911Z,1658524900.911 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 357.112760 min 
2022-07-22T21:21:40.911Z,1658524900.911 [Default:CheckIn:E] Stopped
2022-07-22T21:21:40.912Z,1658524900.912 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-07-22T21:21:40.912Z,1658524900.912 [Default:CheckIn] Stopped
2022-07-22T21:21:40.912Z,1658524900.912 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-07-22T21:21:40.912Z,1658524900.912 [Default:CheckIn](INFO): Running loop #47
2022-07-22T21:21:40.912Z,1658524900.912 [Default:CheckIn] Running Loop=47
2022-07-22T21:21:40.912Z,1658524900.912 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-07-22T21:21:40.912Z,1658524900.912 [Default:CheckIn:Read_GPS] Running Loop=1
2022-07-22T21:21:42.911Z,1658524902.911 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212142.00,A,3647.71591,N,12150.70326,W,0.311,124.03,220722,,,A*70
2022-07-22T21:21:42.924Z,1658524902.924 [NAL9602](INFO): GPS fix at 20220722T212142: (36.795265, -121.845054)
2022-07-22T21:21:42.935Z,1658524902.935 [Default:CheckIn:Read_GPS] Stopped
2022-07-22T21:21:42.935Z,1658524902.935 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-07-22T21:21:50.832Z,1658524910.832 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220722T190204/Courier0066.lzma
2022-07-22T21:21:51.835Z,1658524911.835 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Courier0066.lzma.bak
2022-07-22T21:21:51.835Z,1658524911.835 [DataOverHttps](INFO): SBD MOMSN=17075021
2022-07-22T21:22:07.609Z,1658524927.609 [DataOverHttps](INFO): Sending 166 bytes from file Logs/20220722T190204/Express0067.lzma
2022-07-22T21:22:08.611Z,1658524928.611 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Express0067.lzma.bak
2022-07-22T21:22:08.611Z,1658524928.611 [DataOverHttps](INFO): SBD MOMSN=17075024
2022-07-22T21:22:10.000Z,1658524930.000 [Default:CheckIn:Read_Iridium] Stopped
2022-07-22T21:22:10.000Z,1658524930.000 [Default:CheckIn:C.Wait] Running Loop=1
2022-07-22T21:22:10.000Z,1658524930.000 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-07-22T21:22:14.823Z,1658524934.823 [NAL9602](INFO): SBD MO Status=2, MOMSN=35515, MT Status=2, MTMSN=0
2022-07-22T21:22:14.823Z,1658524934.823 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-07-22T21:22:38.255Z,1658524958.255 [NAL9602](INFO): SBD MO Status=2, MOMSN=35515, MT Status=2, MTMSN=0
2022-07-22T21:22:38.255Z,1658524958.255 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-07-22T21:22:47.951Z,1658524967.951 [NAL9602](INFO): SBD MO Status=0, MOMSN=35515, MT Status=0, MTMSN=0
2022-07-22T21:22:47.951Z,1658524967.951 [NAL9602](INFO): No messages in MT queue
2022-07-22T21:23:18.658Z,1658524998.658 [NAL9602](INFO): Not Powering down - fast GPS
2022-07-22T21:27:10.661Z,1658525230.661 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-07-22T21:27:10.661Z,1658525230.661 [Default:CheckIn:C.Wait] Stopped
2022-07-22T21:27:10.661Z,1658525230.661 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-07-22T21:27:10.661Z,1658525230.661 [Default:CheckIn:D] Running Loop=1
2022-07-22T21:27:11.045Z,1658525231.045 [Default:CheckIn:D] Stopped
2022-07-22T21:27:11.045Z,1658525231.045 [Default:CheckIn:E] Running Loop=1
2022-07-22T21:27:11.456Z,1658525231.456 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 362.621875 min 
2022-07-22T21:27:11.456Z,1658525231.456 [Default:CheckIn:E] Stopped
2022-07-22T21:27:11.456Z,1658525231.456 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-07-22T21:27:11.456Z,1658525231.456 [Default:CheckIn] Stopped
2022-07-22T21:27:11.456Z,1658525231.456 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-07-22T21:27:11.456Z,1658525231.456 [Default:CheckIn](INFO): Running loop #48
2022-07-22T21:27:11.456Z,1658525231.456 [Default:CheckIn] Running Loop=48
2022-07-22T21:27:11.457Z,1658525231.457 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-07-22T21:27:11.457Z,1658525231.457 [Default:CheckIn:Read_GPS] Running Loop=1
2022-07-22T21:27:13.465Z,1658525233.465 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212712.00,A,3647.70712,N,12150.58382,W,1.050,95.09,220722,,,D*4C
2022-07-22T21:27:13.468Z,1658525233.468 [NAL9602](INFO): GPS fix at 20220722T212712: (36.795119, -121.843064)
2022-07-22T21:27:13.482Z,1658525233.482 [Default:CheckIn:Read_GPS] Stopped
2022-07-22T21:27:13.482Z,1658525233.482 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-07-22T21:27:20.892Z,1658525240.892 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220722T190204/Courier0069.lzma
2022-07-22T21:27:22.898Z,1658525242.898 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Courier0069.lzma.bak
2022-07-22T21:27:22.899Z,1658525242.899 [DataOverHttps](INFO): SBD MOMSN=17075039
2022-07-22T21:27:31.649Z,1658525251.649 [NAL9602](INFO): SBD MO Status=0, MOMSN=35516, MT Status=0, MTMSN=0
2022-07-22T21:27:31.650Z,1658525251.650 [NAL9602](INFO): No messages in MT queue
2022-07-22T21:27:38.937Z,1658525258.937 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20220722T190204/Express0070.lzma
2022-07-22T21:27:39.939Z,1658525259.939 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Express0070.lzma.bak
2022-07-22T21:27:39.939Z,1658525259.939 [DataOverHttps](INFO): SBD MOMSN=17075042
2022-07-22T21:27:41.357Z,1658525261.357 [Default:CheckIn:Read_Iridium] Stopped
2022-07-22T21:27:41.358Z,1658525261.358 [Default:CheckIn:C.Wait] Running Loop=1
2022-07-22T21:27:41.358Z,1658525261.358 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-07-22T21:28:02.349Z,1658525282.349 [NAL9602](INFO): Not Powering down - fast GPS
2022-07-22T21:32:42.098Z,1658525562.098 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-07-22T21:32:42.098Z,1658525562.098 [Default:CheckIn:C.Wait] Stopped
2022-07-22T21:32:42.098Z,1658525562.098 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-07-22T21:32:42.098Z,1658525562.098 [Default:CheckIn:D] Running Loop=1
2022-07-22T21:32:42.514Z,1658525562.514 [Default:CheckIn:D] Stopped
2022-07-22T21:32:42.514Z,1658525562.514 [Default:CheckIn:E] Running Loop=1
2022-07-22T21:32:42.915Z,1658525562.915 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 368.146354 min 
2022-07-22T21:32:42.915Z,1658525562.915 [Default:CheckIn:E] Stopped
2022-07-22T21:32:42.915Z,1658525562.915 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-07-22T21:32:42.916Z,1658525562.916 [Default:CheckIn] Stopped
2022-07-22T21:32:42.916Z,1658525562.916 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-07-22T21:32:42.916Z,1658525562.916 [Default:CheckIn](INFO): Running loop #49
2022-07-22T21:32:42.916Z,1658525562.916 [Default:CheckIn] Running Loop=49
2022-07-22T21:32:42.916Z,1658525562.916 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-07-22T21:32:42.916Z,1658525562.916 [Default:CheckIn:Read_GPS] Running Loop=1
2022-07-22T21:32:44.920Z,1658525564.920 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213244.00,A,3647.99084,N,12148.45076,W,17.067,83.15,220722,,,D*70
2022-07-22T21:32:44.922Z,1658525564.922 [NAL9602](INFO): GPS fix at 20220722T213244: (36.799847, -121.807513)
2022-07-22T21:32:44.984Z,1658525564.984 [Default:CheckIn:Read_GPS] Stopped
2022-07-22T21:32:44.984Z,1658525564.984 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-07-22T21:32:54.768Z,1658525574.768 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220722T190204/Courier0072.lzma
2022-07-22T21:32:55.771Z,1658525575.771 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Courier0072.lzma.bak
2022-07-22T21:32:55.771Z,1658525575.771 [DataOverHttps](INFO): SBD MOMSN=17075097
2022-07-22T21:32:57.091Z,1658525577.091 [NAL9602](INFO): SBD MO Status=0, MOMSN=35517, MT Status=0, MTMSN=0
2022-07-22T21:32:57.091Z,1658525577.091 [NAL9602](INFO): No messages in MT queue
2022-07-22T21:33:12.521Z,1658525592.521 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20220722T190204/Express0073.lzma
2022-07-22T21:33:13.523Z,1658525593.523 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Express0073.lzma.bak
2022-07-22T21:33:13.523Z,1658525593.523 [DataOverHttps](INFO): SBD MOMSN=17075101
2022-07-22T21:33:14.880Z,1658525594.880 [Default:CheckIn:Read_Iridium] Stopped
2022-07-22T21:33:14.880Z,1658525594.880 [Default:CheckIn:C.Wait] Running Loop=1
2022-07-22T21:33:14.880Z,1658525594.880 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-07-22T21:33:27.794Z,1658525607.794 [NAL9602](INFO): Not Powering down - fast GPS
2022-07-22T21:38:15.291Z,1658525895.291 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-07-22T21:38:15.291Z,1658525895.291 [Default:CheckIn:C.Wait] Stopped
2022-07-22T21:38:15.291Z,1658525895.291 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-07-22T21:38:15.291Z,1658525895.291 [Default:CheckIn:D] Running Loop=1
2022-07-22T21:38:15.688Z,1658525895.688 [Default:CheckIn:D] Stopped
2022-07-22T21:38:15.688Z,1658525895.688 [Default:CheckIn:E] Running Loop=1
2022-07-22T21:38:16.088Z,1658525896.088 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 373.699251 min 
2022-07-22T21:38:16.088Z,1658525896.088 [Default:CheckIn:E] Stopped
2022-07-22T21:38:16.088Z,1658525896.088 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-07-22T21:38:16.089Z,1658525896.089 [Default:CheckIn] Stopped
2022-07-22T21:38:16.089Z,1658525896.089 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-07-22T21:38:16.089Z,1658525896.089 [Default:CheckIn](INFO): Running loop #50
2022-07-22T21:38:16.089Z,1658525896.089 [Default:CheckIn] Running Loop=50
2022-07-22T21:38:16.089Z,1658525896.089 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-07-22T21:38:16.089Z,1658525896.089 [Default:CheckIn:Read_GPS] Running Loop=1
2022-07-22T21:38:18.090Z,1658525898.090 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213817.00,A,3648.47120,N,12147.10884,W,3.829,172.79,220722,,,D*77
2022-07-22T21:38:18.092Z,1658525898.092 [NAL9602](INFO): GPS fix at 20220722T213817: (36.807853, -121.785147)
2022-07-22T21:38:18.123Z,1658525898.123 [Default:CheckIn:Read_GPS] Stopped
2022-07-22T21:38:18.123Z,1658525898.123 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-07-22T21:38:28.768Z,1658525908.768 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20220722T190204/Courier0075.lzma
2022-07-22T21:38:29.770Z,1658525909.770 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Courier0075.lzma.bak
2022-07-22T21:38:29.771Z,1658525909.771 [DataOverHttps](INFO): SBD MOMSN=17075151
2022-07-22T21:38:45.558Z,1658525925.558 [DataOverHttps](INFO): Sending 165 bytes from file Logs/20220722T190204/Express0076.lzma
2022-07-22T21:38:46.559Z,1658525926.559 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Express0076.lzma.bak
2022-07-22T21:38:46.559Z,1658525926.559 [DataOverHttps](INFO): SBD MOMSN=17075154
2022-07-22T21:38:48.074Z,1658525928.074 [Default:CheckIn:Read_Iridium] Stopped
2022-07-22T21:38:48.074Z,1658525928.074 [Default:CheckIn:C.Wait] Running Loop=1
2022-07-22T21:38:48.075Z,1658525928.075 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-07-22T21:39:23.259Z,1658525963.259 [NAL9602](INFO): SBD MO Status=2, MOMSN=35518, MT Status=2, MTMSN=0
2022-07-22T21:39:23.259Z,1658525963.259 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-07-22T21:40:26.741Z,1658526026.741 [NAL9602](INFO): SBD MO Status=0, MOMSN=35518, MT Status=0, MTMSN=0
2022-07-22T21:40:26.741Z,1658526026.741 [NAL9602](INFO): No messages in MT queue
2022-07-22T21:40:57.470Z,1658526057.470 [NAL9602](INFO): Not Powering down - fast GPS
2022-07-22T21:43:48.510Z,1658526228.510 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-07-22T21:43:48.510Z,1658526228.510 [Default:CheckIn:C.Wait] Stopped
2022-07-22T21:43:48.510Z,1658526228.510 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-07-22T21:43:48.510Z,1658526228.510 [Default:CheckIn:D] Running Loop=1
2022-07-22T21:43:48.917Z,1658526228.917 [Default:CheckIn:D] Stopped
2022-07-22T21:43:48.917Z,1658526228.917 [Default:CheckIn:E] Running Loop=1
2022-07-22T21:43:49.310Z,1658526229.310 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 379.253092 min 
2022-07-22T21:43:49.310Z,1658526229.310 [Default:CheckIn:E] Stopped
2022-07-22T21:43:49.310Z,1658526229.310 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-07-22T21:43:49.310Z,1658526229.310 [Default:CheckIn] Stopped
2022-07-22T21:43:49.310Z,1658526229.310 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-07-22T21:43:49.310Z,1658526229.310 [Default:CheckIn](INFO): Running loop #51
2022-07-22T21:43:49.310Z,1658526229.310 [Default:CheckIn] Running Loop=51
2022-07-22T21:43:49.310Z,1658526229.310 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-07-22T21:43:49.310Z,1658526229.310 [Default:CheckIn:Read_GPS] Running Loop=1
2022-07-22T21:43:51.328Z,1658526231.328 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214350.00,A,3648.16879,N,12147.19611,W,1.438,246.91,220722,,,D*7E
2022-07-22T21:43:51.331Z,1658526231.331 [NAL9602](INFO): GPS fix at 20220722T214350: (36.802813, -121.786602)
2022-07-22T21:43:51.341Z,1658526231.341 [Default:CheckIn:Read_GPS] Stopped
2022-07-22T21:43:51.341Z,1658526231.341 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-07-22T21:43:57.812Z,1658526237.812 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220722T190204/Courier0078.lzma
2022-07-22T21:43:58.814Z,1658526238.814 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Courier0078.lzma.bak
2022-07-22T21:43:58.815Z,1658526238.815 [DataOverHttps](INFO): SBD MOMSN=17075188
2022-07-22T21:44:14.701Z,1658526254.701 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20220722T190204/Express0079.lzma
2022-07-22T21:44:15.703Z,1658526255.703 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Express0079.lzma.bak
2022-07-22T21:44:15.703Z,1658526255.703 [DataOverHttps](INFO): SBD MOMSN=17075193
2022-07-22T21:44:17.188Z,1658526257.188 [Default:CheckIn:Read_Iridium] Stopped
2022-07-22T21:44:17.188Z,1658526257.188 [Default:CheckIn:C.Wait] Running Loop=1
2022-07-22T21:44:17.188Z,1658526257.188 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-07-22T21:44:18.803Z,1658526258.803 [NAL9602](INFO): SBD MO Status=2, MOMSN=35519, MT Status=2, MTMSN=0
2022-07-22T21:44:18.803Z,1658526258.803 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-07-22T21:44:38.643Z,1658526278.643 [NAL9602](INFO): SBD MO Status=0, MOMSN=35519, MT Status=0, MTMSN=0
2022-07-22T21:44:38.643Z,1658526278.643 [NAL9602](INFO): No messages in MT queue
2022-07-22T21:45:09.399Z,1658526309.399 [NAL9602](INFO): Not Powering down - fast GPS
2022-07-22T21:49:17.604Z,1658526557.604 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-07-22T21:49:17.604Z,1658526557.604 [Default:CheckIn:C.Wait] Stopped
2022-07-22T21:49:17.604Z,1658526557.604 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-07-22T21:49:17.604Z,1658526557.604 [Default:CheckIn:D] Running Loop=1
2022-07-22T21:49:17.998Z,1658526557.998 [Default:CheckIn:D] Stopped
2022-07-22T21:49:17.998Z,1658526557.998 [Default:CheckIn:E] Running Loop=1
2022-07-22T21:49:18.414Z,1658526558.414 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 384.737760 min 
2022-07-22T21:49:18.414Z,1658526558.414 [Default:CheckIn:E] Stopped
2022-07-22T21:49:18.414Z,1658526558.414 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-07-22T21:49:18.414Z,1658526558.414 [Default:CheckIn] Stopped
2022-07-22T21:49:18.414Z,1658526558.414 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-07-22T21:49:18.414Z,1658526558.414 [Default:CheckIn](INFO): Running loop #52
2022-07-22T21:49:18.414Z,1658526558.414 [Default:CheckIn] Running Loop=52
2022-07-22T21:49:18.414Z,1658526558.414 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-07-22T21:49:18.415Z,1658526558.415 [Default:CheckIn:Read_GPS] Running Loop=1
2022-07-22T21:49:20.414Z,1658526560.414 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214919.00,A,3648.16663,N,12147.21469,W,0.078,288.90,220722,,,D*78
2022-07-22T21:49:20.417Z,1658526560.417 [NAL9602](INFO): GPS fix at 20220722T214919: (36.802777, -121.786912)
2022-07-22T21:49:20.427Z,1658526560.427 [Default:CheckIn:Read_GPS] Stopped
2022-07-22T21:49:20.427Z,1658526560.427 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-07-22T21:49:28.257Z,1658526568.257 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20220722T190204/Courier0081.lzma
2022-07-22T21:49:29.259Z,1658526569.259 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Courier0081.lzma.bak
2022-07-22T21:49:29.259Z,1658526569.259 [DataOverHttps](INFO): SBD MOMSN=17075203
2022-07-22T21:49:45.558Z,1658526585.558 [DataOverHttps](INFO): Sending 166 bytes from file Logs/20220722T190204/Express0082.lzma
2022-07-22T21:49:46.559Z,1658526586.559 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Express0082.lzma.bak
2022-07-22T21:49:46.559Z,1658526586.559 [DataOverHttps](INFO): SBD MOMSN=17075206
2022-07-22T21:49:47.904Z,1658526587.904 [Default:CheckIn:Read_Iridium] Stopped
2022-07-22T21:49:47.904Z,1658526587.904 [Default:CheckIn:C.Wait] Running Loop=1
2022-07-22T21:49:47.904Z,1658526587.904 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-07-22T21:50:13.343Z,1658526613.343 [NAL9602](INFO): SBD MO Status=0, MOMSN=35520, MT Status=0, MTMSN=0
2022-07-22T21:50:13.343Z,1658526613.343 [NAL9602](INFO): No messages in MT queue
2022-07-22T21:50:44.087Z,1658526644.087 [NAL9602](INFO): Not Powering down - fast GPS
2022-07-22T21:54:48.658Z,1658526888.658 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-07-22T21:54:48.658Z,1658526888.658 [Default:CheckIn:C.Wait] Stopped
2022-07-22T21:54:48.658Z,1658526888.658 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-07-22T21:54:48.659Z,1658526888.659 [Default:CheckIn:D] Running Loop=1
2022-07-22T21:54:49.054Z,1658526889.054 [Default:CheckIn:D] Stopped
2022-07-22T21:54:49.054Z,1658526889.054 [Default:CheckIn:E] Running Loop=1
2022-07-22T21:54:49.468Z,1658526889.468 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 390.255371 min 
2022-07-22T21:54:49.468Z,1658526889.468 [Default:CheckIn:E] Stopped
2022-07-22T21:54:49.468Z,1658526889.468 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-07-22T21:54:49.468Z,1658526889.468 [Default:CheckIn] Stopped
2022-07-22T21:54:49.468Z,1658526889.468 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-07-22T21:54:49.469Z,1658526889.469 [Default:CheckIn](INFO): Running loop #53
2022-07-22T21:54:49.469Z,1658526889.469 [Default:CheckIn] Running Loop=53
2022-07-22T21:54:49.469Z,1658526889.469 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-07-22T21:54:49.469Z,1658526889.469 [Default:CheckIn:Read_GPS] Running Loop=1
2022-07-22T21:54:51.471Z,1658526891.471 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215450.00,A,3648.16315,N,12147.21798,W,0.039,195.71,220722,,,A*70
2022-07-22T21:54:51.474Z,1658526891.474 [NAL9602](INFO): GPS fix at 20220722T215450: (36.802719, -121.786966)
2022-07-22T21:54:51.493Z,1658526891.493 [Default:CheckIn:Read_GPS] Stopped
2022-07-22T21:54:51.493Z,1658526891.493 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-07-22T21:54:59.968Z,1658526899.968 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220722T190204/Courier0084.lzma
2022-07-22T21:55:00.970Z,1658526900.970 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Courier0084.lzma.bak
2022-07-22T21:55:00.971Z,1658526900.971 [DataOverHttps](INFO): SBD MOMSN=17075218
2022-07-22T21:55:17.025Z,1658526917.025 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20220722T190204/Express0085.lzma
2022-07-22T21:55:18.027Z,1658526918.027 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Express0085.lzma.bak
2022-07-22T21:55:18.027Z,1658526918.027 [DataOverHttps](INFO): SBD MOMSN=17075221
2022-07-22T21:55:19.356Z,1658526919.356 [Default:CheckIn:Read_Iridium] Stopped
2022-07-22T21:55:19.356Z,1658526919.356 [Default:CheckIn:C.Wait] Running Loop=1
2022-07-22T21:55:19.356Z,1658526919.356 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-07-22T21:55:50.863Z,1658526950.863 [NAL9602](INFO): SBD MO Status=0, MOMSN=35521, MT Status=0, MTMSN=0
2022-07-22T21:55:50.863Z,1658526950.863 [NAL9602](INFO): No messages in MT queue
2022-07-22T21:56:21.566Z,1658526981.566 [NAL9602](INFO): Not Powering down - fast GPS
2022-07-22T22:00:19.961Z,1658527219.961 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-07-22T22:00:19.961Z,1658527219.961 [Default:CheckIn:C.Wait] Stopped
2022-07-22T22:00:19.961Z,1658527219.961 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-07-22T22:00:19.962Z,1658527219.962 [Default:CheckIn:D] Running Loop=1
2022-07-22T22:00:20.372Z,1658527220.372 [Default:CheckIn:D] Stopped
2022-07-22T22:00:20.372Z,1658527220.372 [Default:CheckIn:E] Running Loop=1
2022-07-22T22:00:20.781Z,1658527220.781 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 395.777344 min 
2022-07-22T22:00:20.781Z,1658527220.781 [Default:CheckIn:E] Stopped
2022-07-22T22:00:20.781Z,1658527220.781 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-07-22T22:00:20.782Z,1658527220.782 [Default:CheckIn] Stopped
2022-07-22T22:00:20.782Z,1658527220.782 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-07-22T22:00:20.782Z,1658527220.782 [Default:CheckIn](INFO): Running loop #54
2022-07-22T22:00:20.782Z,1658527220.782 [Default:CheckIn] Running Loop=54
2022-07-22T22:00:20.782Z,1658527220.782 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-07-22T22:00:20.782Z,1658527220.782 [Default:CheckIn:Read_GPS] Running Loop=1
2022-07-22T22:00:22.788Z,1658527222.788 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220022.00,A,3648.14306,N,12147.22901,W,0.039,200.42,220722,,,A*75
2022-07-22T22:00:22.790Z,1658527222.790 [NAL9602](INFO): GPS fix at 20220722T220022: (36.802384, -121.787150)
2022-07-22T22:00:22.800Z,1658527222.800 [Default:CheckIn:Read_GPS] Stopped
2022-07-22T22:00:22.821Z,1658527222.821 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-07-22T22:00:30.380Z,1658527230.380 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220722T190204/Courier0087.lzma
2022-07-22T22:00:31.383Z,1658527231.383 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Courier0087.lzma.bak
2022-07-22T22:00:31.383Z,1658527231.383 [DataOverHttps](INFO): SBD MOMSN=17075231
2022-07-22T22:00:47.137Z,1658527247.137 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20220722T190204/Express0088.lzma
2022-07-22T22:00:48.139Z,1658527248.139 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Express0088.lzma.bak
2022-07-22T22:00:48.139Z,1658527248.139 [DataOverHttps](INFO): SBD MOMSN=17075235
2022-07-22T22:00:49.499Z,1658527249.499 [Default:CheckIn:Read_Iridium] Stopped
2022-07-22T22:00:49.499Z,1658527249.499 [Default:CheckIn:C.Wait] Running Loop=1
2022-07-22T22:00:49.499Z,1658527249.499 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-07-22T22:01:10.123Z,1658527270.123 [NAL9602](INFO): SBD MO Status=0, MOMSN=35522, MT Status=0, MTMSN=0
2022-07-22T22:01:10.123Z,1658527270.123 [NAL9602](INFO): No messages in MT queue
2022-07-22T22:01:40.822Z,1658527300.822 [NAL9602](INFO): Not Powering down - fast GPS
2022-07-22T22:04:08.381Z,1658527448.381 [CBIT](IMPORTANT): Beginning ground fault scan
2022-07-22T22:04:11.225Z,1658527451.225 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2022-07-22T22:04:19.313Z,1658527459.313 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002227
CHAN A1 (24V) N/A reporting off
CHAN A2 (12V): -0.002900
CHAN A3 (5V): -0.001389
CHAN B0 (3.3V): -0.000466
CHAN B1 (3.15aV): 0.000136
CHAN B2 (3.15bV): -0.000182
CHAN B3 (GND): -0.000306
OPEN: -0.000485
Full Scale: +/- 1 mA
2022-07-22T22:05:50.176Z,1658527550.176 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-07-22T22:05:50.176Z,1658527550.176 [Default:CheckIn:C.Wait] Stopped
2022-07-22T22:05:50.176Z,1658527550.176 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-07-22T22:05:50.176Z,1658527550.176 [Default:CheckIn:D] Running Loop=1
2022-07-22T22:05:50.586Z,1658527550.586 [Default:CheckIn:D] Stopped
2022-07-22T22:05:50.586Z,1658527550.586 [Default:CheckIn:E] Running Loop=1
2022-07-22T22:05:50.984Z,1658527550.984 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 401.280892 min 
2022-07-22T22:05:50.984Z,1658527550.984 [Default:CheckIn:E] Stopped
2022-07-22T22:05:50.984Z,1658527550.984 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-07-22T22:05:50.984Z,1658527550.984 [Default:CheckIn] Stopped
2022-07-22T22:05:50.984Z,1658527550.984 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-07-22T22:05:51.005Z,1658527551.005 [Default:CheckIn](INFO): Running loop #55
2022-07-22T22:05:51.005Z,1658527551.005 [Default:CheckIn] Running Loop=55
2022-07-22T22:05:51.005Z,1658527551.005 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-07-22T22:05:51.005Z,1658527551.005 [Default:CheckIn:Read_GPS] Running Loop=1
2022-07-22T22:05:53.064Z,1658527553.064 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220552.00,A,3648.16268,N,12147.24680,W,3.285,1.62,220722,,,D*7E
2022-07-22T22:05:53.066Z,1658527553.066 [NAL9602](INFO): GPS fix at 20220722T220552: (36.802711, -121.787447)
2022-07-22T22:05:53.094Z,1658527553.094 [Default:CheckIn:Read_GPS] Stopped
2022-07-22T22:05:53.094Z,1658527553.094 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-07-22T22:05:53.395Z,1658527553.395 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-07-22T22:05:59.948Z,1658527559.948 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220722T190204/Courier0090.lzma
2022-07-22T22:06:00.951Z,1658527560.951 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Courier0090.lzma.bak
2022-07-22T22:06:00.951Z,1658527560.951 [DataOverHttps](INFO): SBD MOMSN=17075246
2022-07-22T22:06:08.815Z,1658527568.815 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-07-22T22:06:17.221Z,1658527577.221 [DataOverHttps](INFO): Sending 375 bytes from file Logs/20220722T190204/Express0091.lzma
2022-07-22T22:06:18.223Z,1658527578.223 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Express0091.lzma.bak
2022-07-22T22:06:18.223Z,1658527578.223 [DataOverHttps](INFO): SBD MOMSN=17075249
2022-07-22T22:06:19.757Z,1658527579.757 [Default:CheckIn:Read_Iridium] Stopped
2022-07-22T22:06:19.757Z,1658527579.757 [Default:CheckIn:C.Wait] Running Loop=1
2022-07-22T22:06:19.758Z,1658527579.758 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-07-22T22:06:24.194Z,1658527584.194 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-07-22T22:06:39.178Z,1658527599.178 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-07-22T22:06:54.154Z,1658527614.154 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-07-22T22:07:09.542Z,1658527629.542 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-07-22T22:07:25.298Z,1658527645.298 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-07-22T22:07:41.458Z,1658527661.458 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-07-22T22:07:56.810Z,1658527676.810 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-07-22T22:08:11.758Z,1658527691.758 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2022-07-22T22:08:19.475Z,1658527699.475 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2022-07-22T22:08:19.477Z,1658527699.477 [BPC1](INFO): Received data from all battery sticks.
2022-07-22T22:10:55.430Z,1658527855.430 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-07-22T22:11:20.485Z,1658527880.485 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-07-22T22:11:20.485Z,1658527880.485 [Default:CheckIn:C.Wait] Stopped
2022-07-22T22:11:20.485Z,1658527880.485 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-07-22T22:11:20.486Z,1658527880.486 [Default:CheckIn:D] Running Loop=1
2022-07-22T22:11:20.900Z,1658527880.900 [Default:CheckIn:D] Stopped
2022-07-22T22:11:20.900Z,1658527880.900 [Default:CheckIn:E] Running Loop=1
2022-07-22T22:11:21.309Z,1658527881.309 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 406.786133 min 
2022-07-22T22:11:21.309Z,1658527881.309 [Default:CheckIn:E] Stopped
2022-07-22T22:11:21.309Z,1658527881.309 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-07-22T22:11:21.309Z,1658527881.309 [Default:CheckIn] Stopped
2022-07-22T22:11:21.309Z,1658527881.309 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-07-22T22:11:21.309Z,1658527881.309 [Default:CheckIn](INFO): Running loop #56
2022-07-22T22:11:21.309Z,1658527881.309 [Default:CheckIn] Running Loop=56
2022-07-22T22:11:21.310Z,1658527881.310 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-07-22T22:11:21.310Z,1658527881.310 [Default:CheckIn:Read_GPS] Running Loop=1
2022-07-22T22:11:23.306Z,1658527883.306 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221122.00,A,3648.16388,N,12147.27803,W,0.875,305.14,220722,,,A*70
2022-07-22T22:11:23.308Z,1658527883.308 [NAL9602](INFO): GPS fix at 20220722T221122: (36.802731, -121.787967)
2022-07-22T22:11:23.318Z,1658527883.318 [Default:CheckIn:Read_GPS] Stopped
2022-07-22T22:11:23.318Z,1658527883.318 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-07-22T22:11:30.296Z,1658527890.296 [DataOverHttps](INFO): Sending 86 bytes from file Logs/20220722T190204/Courier0093.lzma
2022-07-22T22:11:31.299Z,1658527891.299 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Courier0093.lzma.bak
2022-07-22T22:11:31.299Z,1658527891.299 [DataOverHttps](INFO): SBD MOMSN=17075260
2022-07-22T22:11:47.105Z,1658527907.105 [DataOverHttps](INFO): Sending 227 bytes from file Logs/20220722T190204/Express0094.lzma
2022-07-22T22:11:48.107Z,1658527908.107 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Express0094.lzma.bak
2022-07-22T22:11:48.107Z,1658527908.107 [DataOverHttps](INFO): SBD MOMSN=17075263
2022-07-22T22:11:49.592Z,1658527909.592 [Default:CheckIn:Read_Iridium] Stopped
2022-07-22T22:11:49.592Z,1658527909.592 [Default:CheckIn:C.Wait] Running Loop=1
2022-07-22T22:11:49.592Z,1658527909.592 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-07-22T22:11:55.219Z,1658527915.219 [NAL9602](INFO): Not Powering down - fast GPS
2022-07-22T22:16:50.292Z,1658528210.292 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-07-22T22:16:50.292Z,1658528210.292 [Default:CheckIn:C.Wait] Stopped
2022-07-22T22:16:50.292Z,1658528210.292 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-07-22T22:16:50.292Z,1658528210.292 [Default:CheckIn:D] Running Loop=1
2022-07-22T22:16:50.702Z,1658528210.702 [Default:CheckIn:D] Stopped
2022-07-22T22:16:50.702Z,1658528210.702 [Default:CheckIn:E] Running Loop=1
2022-07-22T22:16:51.099Z,1658528211.099 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 412.282845 min 
2022-07-22T22:16:51.099Z,1658528211.099 [Default:CheckIn:E] Stopped
2022-07-22T22:16:51.099Z,1658528211.099 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-07-22T22:16:51.100Z,1658528211.100 [Default:CheckIn] Stopped
2022-07-22T22:16:51.100Z,1658528211.100 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-07-22T22:16:51.100Z,1658528211.100 [Default:CheckIn](INFO): Running loop #57
2022-07-22T22:16:51.100Z,1658528211.100 [Default:CheckIn] Running Loop=57
2022-07-22T22:16:51.100Z,1658528211.100 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-07-22T22:16:51.100Z,1658528211.100 [Default:CheckIn:Read_GPS] Running Loop=1
2022-07-22T22:16:53.118Z,1658528213.118 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221652.00,A,3648.16784,N,12147.27972,W,0.117,308.99,220722,,,A*7A
2022-07-22T22:16:53.120Z,1658528213.120 [NAL9602](INFO): GPS fix at 20220722T221652: (36.802797, -121.787995)
2022-07-22T22:16:53.138Z,1658528213.138 [Default:CheckIn:Read_GPS] Stopped
2022-07-22T22:16:53.138Z,1658528213.138 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-07-22T22:17:01.252Z,1658528221.252 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220722T190204/Courier0096.lzma
2022-07-22T22:17:02.255Z,1658528222.255 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Courier0096.lzma.bak
2022-07-22T22:17:02.255Z,1658528222.255 [DataOverHttps](INFO): SBD MOMSN=17075274
2022-07-22T22:17:18.128Z,1658528238.128 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20220722T190204/Express0097.lzma
2022-07-22T22:17:19.131Z,1658528239.131 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Express0097.lzma.bak
2022-07-22T22:17:19.131Z,1658528239.131 [DataOverHttps](INFO): SBD MOMSN=17075277
2022-07-22T22:17:20.640Z,1658528240.640 [Default:CheckIn:Read_Iridium] Stopped
2022-07-22T22:17:20.641Z,1658528240.641 [Default:CheckIn:C.Wait] Running Loop=1
2022-07-22T22:17:20.641Z,1658528240.641 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-07-22T22:17:23.830Z,1658528243.830 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-07-22T22:17:23.905Z,1658528243.905 [NAL9602](ERROR): received: 
+CSQ:0

OK522, 0, 0, 0, 0

OK
2022-07-22T22:21:55.358Z,1658528515.358 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-07-22T22:22:21.257Z,1658528541.257 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-07-22T22:22:21.257Z,1658528541.257 [Default:CheckIn:C.Wait] Stopped
2022-07-22T22:22:21.258Z,1658528541.258 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-07-22T22:22:21.258Z,1658528541.258 [Default:CheckIn:D] Running Loop=1
2022-07-22T22:22:21.626Z,1658528541.626 [Default:CheckIn:D] Stopped
2022-07-22T22:22:21.626Z,1658528541.626 [Default:CheckIn:E] Running Loop=1
2022-07-22T22:22:22.040Z,1658528542.040 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 417.798242 min 
2022-07-22T22:22:22.040Z,1658528542.040 [Default:CheckIn:E] Stopped
2022-07-22T22:22:22.040Z,1658528542.040 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-07-22T22:22:22.040Z,1658528542.040 [Default:CheckIn] Stopped
2022-07-22T22:22:22.040Z,1658528542.040 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-07-22T22:22:22.040Z,1658528542.040 [Default:CheckIn](INFO): Running loop #58
2022-07-22T22:22:22.040Z,1658528542.040 [Default:CheckIn] Running Loop=58
2022-07-22T22:22:22.040Z,1658528542.040 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-07-22T22:22:22.041Z,1658528542.041 [Default:CheckIn:Read_GPS] Running Loop=1
2022-07-22T22:22:24.043Z,1658528544.043 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222223.00,A,3648.16638,N,12147.28017,W,0.544,308.99,220722,,,A*7A
2022-07-22T22:22:24.046Z,1658528544.046 [NAL9602](INFO): GPS fix at 20220722T222223: (36.802773, -121.788003)
2022-07-22T22:22:24.072Z,1658528544.072 [Default:CheckIn:Read_GPS] Stopped
2022-07-22T22:22:24.072Z,1658528544.072 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-07-22T22:22:31.484Z,1658528551.484 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220722T190204/Courier0099.lzma
2022-07-22T22:22:32.487Z,1658528552.487 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Courier0099.lzma.bak
2022-07-22T22:22:32.487Z,1658528552.487 [DataOverHttps](INFO): SBD MOMSN=17075288
2022-07-22T22:22:49.165Z,1658528569.165 [DataOverHttps](INFO): Sending 219 bytes from file Logs/20220722T190204/Express0100.lzma
2022-07-22T22:22:50.167Z,1658528570.167 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Express0100.lzma.bak
2022-07-22T22:22:50.167Z,1658528570.167 [DataOverHttps](INFO): SBD MOMSN=17075291
2022-07-22T22:22:51.585Z,1658528571.585 [Default:CheckIn:Read_Iridium] Stopped
2022-07-22T22:22:51.585Z,1658528571.585 [Default:CheckIn:C.Wait] Running Loop=1
2022-07-22T22:22:51.585Z,1658528571.585 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-07-22T22:22:56.426Z,1658528576.426 [NAL9602](INFO): Not Powering down - fast GPS
2022-07-22T22:27:52.175Z,1658528872.175 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-07-22T22:27:52.175Z,1658528872.175 [Default:CheckIn:C.Wait] Stopped
2022-07-22T22:27:52.175Z,1658528872.175 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-07-22T22:27:52.175Z,1658528872.175 [Default:CheckIn:D] Running Loop=1
2022-07-22T22:27:52.574Z,1658528872.574 [Default:CheckIn:D] Stopped
2022-07-22T22:27:52.574Z,1658528872.574 [Default:CheckIn:E] Running Loop=1
2022-07-22T22:27:52.987Z,1658528872.987 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 423.314030 min 
2022-07-22T22:27:52.988Z,1658528872.988 [Default:CheckIn:E] Stopped
2022-07-22T22:27:52.988Z,1658528872.988 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-07-22T22:27:52.988Z,1658528872.988 [Default:CheckIn] Stopped
2022-07-22T22:27:52.988Z,1658528872.988 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-07-22T22:27:52.988Z,1658528872.988 [Default:CheckIn](INFO): Running loop #59
2022-07-22T22:27:52.988Z,1658528872.988 [Default:CheckIn] Running Loop=59
2022-07-22T22:27:52.988Z,1658528872.988 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-07-22T22:27:52.988Z,1658528872.988 [Default:CheckIn:Read_GPS] Running Loop=1
2022-07-22T22:27:54.990Z,1658528874.990 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222754.00,A,3648.16712,N,12147.27459,W,0.525,15.42,220722,,,A*49
2022-07-22T22:27:54.992Z,1658528874.992 [NAL9602](INFO): GPS fix at 20220722T222754: (36.802785, -121.787910)
2022-07-22T22:27:55.022Z,1658528875.022 [Default:CheckIn:Read_GPS] Stopped
2022-07-22T22:27:55.022Z,1658528875.022 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-07-22T22:28:02.304Z,1658528882.304 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220722T190204/Courier0102.lzma
2022-07-22T22:28:03.307Z,1658528883.307 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Courier0102.lzma.bak
2022-07-22T22:28:03.307Z,1658528883.307 [DataOverHttps](INFO): SBD MOMSN=17075302
2022-07-22T22:28:20.557Z,1658528900.557 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20220722T190204/Express0103.lzma
2022-07-22T22:28:21.559Z,1658528901.559 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Express0103.lzma.bak
2022-07-22T22:28:21.559Z,1658528901.559 [DataOverHttps](INFO): SBD MOMSN=17075305
2022-07-22T22:28:22.909Z,1658528902.909 [Default:CheckIn:Read_Iridium] Stopped
2022-07-22T22:28:22.909Z,1658528902.909 [Default:CheckIn:C.Wait] Running Loop=1
2022-07-22T22:28:22.909Z,1658528902.909 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-07-22T22:28:25.730Z,1658528905.730 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-07-22T22:28:25.805Z,1658528905.805 [NAL9602](ERROR): received: 
+CSQ:0

OK522, 0, 0, 0, 0

OK
2022-07-22T22:32:57.228Z,1658529177.228 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-07-22T22:33:23.506Z,1658529203.506 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-07-22T22:33:23.506Z,1658529203.506 [Default:CheckIn:C.Wait] Stopped
2022-07-22T22:33:23.506Z,1658529203.506 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-07-22T22:33:23.507Z,1658529203.507 [Default:CheckIn:D] Running Loop=1
2022-07-22T22:33:23.903Z,1658529203.903 [Default:CheckIn:D] Stopped
2022-07-22T22:33:23.903Z,1658529203.903 [Default:CheckIn:E] Running Loop=1
2022-07-22T22:33:24.311Z,1658529204.311 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 428.836198 min 
2022-07-22T22:33:24.311Z,1658529204.311 [Default:CheckIn:E] Stopped
2022-07-22T22:33:24.311Z,1658529204.311 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-07-22T22:33:24.311Z,1658529204.311 [Default:CheckIn] Stopped
2022-07-22T22:33:24.312Z,1658529204.312 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-07-22T22:33:24.312Z,1658529204.312 [Default:CheckIn](INFO): Running loop #60
2022-07-22T22:33:24.312Z,1658529204.312 [Default:CheckIn] Running Loop=60
2022-07-22T22:33:24.312Z,1658529204.312 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-07-22T22:33:24.312Z,1658529204.312 [Default:CheckIn:Read_GPS] Running Loop=1
2022-07-22T22:33:26.326Z,1658529206.326 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223325.00,A,3648.16675,N,12147.27722,W,0.544,15.42,220722,,,A*42
2022-07-22T22:33:26.328Z,1658529206.328 [NAL9602](INFO): GPS fix at 20220722T223325: (36.802779, -121.787954)
2022-07-22T22:33:26.338Z,1658529206.338 [Default:CheckIn:Read_GPS] Stopped
2022-07-22T22:33:26.338Z,1658529206.338 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-07-22T22:33:33.180Z,1658529213.180 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220722T190204/Courier0105.lzma
2022-07-22T22:33:34.183Z,1658529214.183 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Courier0105.lzma.bak
2022-07-22T22:33:34.183Z,1658529214.183 [DataOverHttps](INFO): SBD MOMSN=17075314
2022-07-22T22:33:49.965Z,1658529229.965 [DataOverHttps](INFO): Sending 219 bytes from file Logs/20220722T190204/Express0106.lzma
2022-07-22T22:33:50.967Z,1658529230.967 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Express0106.lzma.bak
2022-07-22T22:33:50.967Z,1658529230.967 [DataOverHttps](INFO): SBD MOMSN=17075317
2022-07-22T22:33:52.198Z,1658529232.198 [Default:CheckIn:Read_Iridium] Stopped
2022-07-22T22:33:52.198Z,1658529232.198 [Default:CheckIn:C.Wait] Running Loop=1
2022-07-22T22:33:52.198Z,1658529232.198 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-07-22T22:33:58.232Z,1658529238.232 [NAL9602](INFO): Not Powering down - fast GPS
2022-07-22T22:38:52.873Z,1658529532.873 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-07-22T22:38:52.873Z,1658529532.873 [Default:CheckIn:C.Wait] Stopped
2022-07-22T22:38:52.873Z,1658529532.873 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-07-22T22:38:52.873Z,1658529532.873 [Default:CheckIn:D] Running Loop=1
2022-07-22T22:38:53.208Z,1658529533.208 [Default:CheckIn:D] Stopped
2022-07-22T22:38:53.208Z,1658529533.208 [Default:CheckIn:E] Running Loop=1
2022-07-22T22:38:53.661Z,1658529533.661 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 434.324577 min 
2022-07-22T22:38:53.661Z,1658529533.661 [Default:CheckIn:E] Stopped
2022-07-22T22:38:53.661Z,1658529533.661 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-07-22T22:38:53.661Z,1658529533.661 [Default:CheckIn] Stopped
2022-07-22T22:38:53.662Z,1658529533.662 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-07-22T22:38:53.662Z,1658529533.662 [Default:CheckIn](INFO): Running loop #61
2022-07-22T22:38:53.662Z,1658529533.662 [Default:CheckIn] Running Loop=61
2022-07-22T22:38:53.662Z,1658529533.662 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-07-22T22:38:53.662Z,1658529533.662 [Default:CheckIn:Read_GPS] Running Loop=1
2022-07-22T22:38:55.616Z,1658529535.616 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223855.00,A,3648.16860,N,12147.28389,W,0.583,122.21,220722,,,A*75
2022-07-22T22:38:55.618Z,1658529535.618 [NAL9602](INFO): GPS fix at 20220722T223855: (36.802810, -121.788065)
2022-07-22T22:38:55.644Z,1658529535.644 [Default:CheckIn:Read_GPS] Stopped
2022-07-22T22:38:55.644Z,1658529535.644 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-07-22T22:39:02.856Z,1658529542.856 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220722T190204/Courier0108.lzma
2022-07-22T22:39:03.859Z,1658529543.859 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Courier0108.lzma.bak
2022-07-22T22:39:03.859Z,1658529543.859 [DataOverHttps](INFO): SBD MOMSN=17075328
2022-07-22T22:39:19.736Z,1658529559.736 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20220722T190204/Express0109.lzma
2022-07-22T22:39:20.739Z,1658529560.739 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Express0109.lzma.bak
2022-07-22T22:39:20.739Z,1658529560.739 [DataOverHttps](INFO): SBD MOMSN=17075331
2022-07-22T22:39:22.285Z,1658529562.285 [Default:CheckIn:Read_Iridium] Stopped
2022-07-22T22:39:22.285Z,1658529562.285 [Default:CheckIn:C.Wait] Running Loop=1
2022-07-22T22:39:22.286Z,1658529562.286 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-07-22T22:39:26.318Z,1658529566.318 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-07-22T22:39:26.401Z,1658529566.401 [NAL9602](ERROR): received: 
+CSQ:0

OK522, 0, 0, 0, 0

OK
2022-07-22T22:43:58.230Z,1658529838.230 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-07-22T22:44:22.877Z,1658529862.877 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-07-22T22:44:22.877Z,1658529862.877 [Default:CheckIn:C.Wait] Stopped
2022-07-22T22:44:22.877Z,1658529862.877 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-07-22T22:44:22.878Z,1658529862.878 [Default:CheckIn:D] Running Loop=1
2022-07-22T22:44:23.288Z,1658529863.288 [Default:CheckIn:D] Stopped
2022-07-22T22:44:23.288Z,1658529863.288 [Default:CheckIn:E] Running Loop=1
2022-07-22T22:44:23.691Z,1658529863.691 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 439.825911 min 
2022-07-22T22:44:23.691Z,1658529863.691 [Default:CheckIn:E] Stopped
2022-07-22T22:44:23.691Z,1658529863.691 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-07-22T22:44:23.691Z,1658529863.691 [Default:CheckIn] Stopped
2022-07-22T22:44:23.692Z,1658529863.692 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-07-22T22:44:23.692Z,1658529863.692 [Default:CheckIn](INFO): Running loop #62
2022-07-22T22:44:23.692Z,1658529863.692 [Default:CheckIn] Running Loop=62
2022-07-22T22:44:23.692Z,1658529863.692 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-07-22T22:44:23.692Z,1658529863.692 [Default:CheckIn:Read_GPS] Running Loop=1
2022-07-22T22:44:25.710Z,1658529865.710 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224425.00,A,3648.16652,N,12147.28247,W,0.156,127.47,220722,,,A*7C
2022-07-22T22:44:25.712Z,1658529865.712 [NAL9602](INFO): GPS fix at 20220722T224425: (36.802775, -121.788041)
2022-07-22T22:44:25.722Z,1658529865.722 [Default:CheckIn:Read_GPS] Stopped
2022-07-22T22:44:25.722Z,1658529865.722 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-07-22T22:44:32.648Z,1658529872.648 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220722T190204/Courier0111.lzma
2022-07-22T22:44:33.651Z,1658529873.651 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Courier0111.lzma.bak
2022-07-22T22:44:33.651Z,1658529873.651 [DataOverHttps](INFO): SBD MOMSN=17075341
2022-07-22T22:44:50.853Z,1658529890.853 [DataOverHttps](INFO): Sending 215 bytes from file Logs/20220722T190204/Express0112.lzma
2022-07-22T22:44:51.855Z,1658529891.855 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Express0112.lzma.bak
2022-07-22T22:44:51.855Z,1658529891.855 [DataOverHttps](INFO): SBD MOMSN=17075344
2022-07-22T22:44:53.199Z,1658529893.199 [Default:CheckIn:Read_Iridium] Stopped
2022-07-22T22:44:53.200Z,1658529893.200 [Default:CheckIn:C.Wait] Running Loop=1
2022-07-22T22:44:53.200Z,1658529893.200 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-07-22T22:44:58.462Z,1658529898.462 [NAL9602](INFO): Not Powering down - fast GPS
2022-07-22T22:49:53.831Z,1658530193.831 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-07-22T22:49:53.831Z,1658530193.831 [Default:CheckIn:C.Wait] Stopped
2022-07-22T22:49:53.831Z,1658530193.831 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-07-22T22:49:53.832Z,1658530193.832 [Default:CheckIn:D] Running Loop=1
2022-07-22T22:49:54.244Z,1658530194.244 [Default:CheckIn:D] Stopped
2022-07-22T22:49:54.244Z,1658530194.244 [Default:CheckIn:E] Running Loop=1
2022-07-22T22:49:54.657Z,1658530194.657 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 445.341862 min 
2022-07-22T22:49:54.657Z,1658530194.657 [Default:CheckIn:E] Stopped
2022-07-22T22:49:54.658Z,1658530194.658 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-07-22T22:49:54.658Z,1658530194.658 [Default:CheckIn] Stopped
2022-07-22T22:49:54.658Z,1658530194.658 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-07-22T22:49:54.658Z,1658530194.658 [Default:CheckIn](INFO): Running loop #63
2022-07-22T22:49:54.658Z,1658530194.658 [Default:CheckIn] Running Loop=63
2022-07-22T22:49:54.658Z,1658530194.658 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-07-22T22:49:54.658Z,1658530194.658 [Default:CheckIn:Read_GPS] Running Loop=1
2022-07-22T22:49:56.652Z,1658530196.652 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224956.00,A,3648.16540,N,12147.28319,W,0.097,127.47,220722,,,A*73
2022-07-22T22:49:56.654Z,1658530196.654 [NAL9602](INFO): GPS fix at 20220722T224956: (36.802757, -121.788053)
2022-07-22T22:49:56.664Z,1658530196.664 [Default:CheckIn:Read_GPS] Stopped
2022-07-22T22:49:56.665Z,1658530196.665 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-07-22T22:50:03.664Z,1658530203.664 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220722T190204/Courier0114.lzma
2022-07-22T22:50:04.667Z,1658530204.667 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Courier0114.lzma.bak
2022-07-22T22:50:04.667Z,1658530204.667 [DataOverHttps](INFO): SBD MOMSN=17075378
2022-07-22T22:50:20.609Z,1658530220.609 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20220722T190204/Express0115.lzma
2022-07-22T22:50:21.611Z,1658530221.611 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Express0115.lzma.bak
2022-07-22T22:50:21.611Z,1658530221.611 [DataOverHttps](INFO): SBD MOMSN=17075381
2022-07-22T22:50:22.940Z,1658530222.940 [Default:CheckIn:Read_Iridium] Stopped
2022-07-22T22:50:22.940Z,1658530222.940 [Default:CheckIn:C.Wait] Running Loop=1
2022-07-22T22:50:22.940Z,1658530222.940 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-07-22T22:50:27.381Z,1658530227.381 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-07-22T22:50:27.457Z,1658530227.457 [NAL9602](ERROR): received: 
+CSQ:0

OK522, 0, 0, 0, 0

OK
2022-07-22T22:54:59.304Z,1658530499.304 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-07-22T22:55:23.559Z,1658530523.559 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-07-22T22:55:23.560Z,1658530523.560 [Default:CheckIn:C.Wait] Stopped
2022-07-22T22:55:23.560Z,1658530523.560 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-07-22T22:55:23.560Z,1658530523.560 [Default:CheckIn:D] Running Loop=1
2022-07-22T22:55:23.969Z,1658530523.969 [Default:CheckIn:D] Stopped
2022-07-22T22:55:23.969Z,1658530523.969 [Default:CheckIn:E] Running Loop=1
2022-07-22T22:55:24.361Z,1658530524.361 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 450.837272 min 
2022-07-22T22:55:24.362Z,1658530524.362 [Default:CheckIn:E] Stopped
2022-07-22T22:55:24.362Z,1658530524.362 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-07-22T22:55:24.362Z,1658530524.362 [Default:CheckIn] Stopped
2022-07-22T22:55:24.362Z,1658530524.362 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-07-22T22:55:24.362Z,1658530524.362 [Default:CheckIn](INFO): Running loop #64
2022-07-22T22:55:24.362Z,1658530524.362 [Default:CheckIn] Running Loop=64
2022-07-22T22:55:24.362Z,1658530524.362 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-07-22T22:55:24.362Z,1658530524.362 [Default:CheckIn:Read_GPS] Running Loop=1
2022-07-22T22:55:26.379Z,1658530526.379 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,225525.00,A,3648.16298,N,12147.28130,W,0.194,0.00,220722,,,A*74
2022-07-22T22:55:26.382Z,1658530526.382 [NAL9602](INFO): GPS fix at 20220722T225525: (36.802716, -121.788022)
2022-07-22T22:55:26.392Z,1658530526.392 [Default:CheckIn:Read_GPS] Stopped
2022-07-22T22:55:26.392Z,1658530526.392 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-07-22T22:55:33.260Z,1658530533.260 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220722T190204/Courier0117.lzma
2022-07-22T22:55:34.263Z,1658530534.263 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Courier0117.lzma.bak
2022-07-22T22:55:34.263Z,1658530534.263 [DataOverHttps](INFO): SBD MOMSN=17075390
2022-07-22T22:55:50.245Z,1658530550.245 [DataOverHttps](INFO): Sending 217 bytes from file Logs/20220722T190204/Express0118.lzma
2022-07-22T22:55:51.247Z,1658530551.247 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Express0118.lzma.bak
2022-07-22T22:55:51.247Z,1658530551.247 [DataOverHttps](INFO): SBD MOMSN=17075393
2022-07-22T22:55:52.758Z,1658530552.758 [Default:CheckIn:Read_Iridium] Stopped
2022-07-22T22:55:52.758Z,1658530552.758 [Default:CheckIn:C.Wait] Running Loop=1
2022-07-22T22:55:52.758Z,1658530552.758 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-07-22T22:55:58.395Z,1658530558.395 [NAL9602](INFO): Not Powering down - fast GPS
2022-07-22T23:00:53.330Z,1658530853.330 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-07-22T23:00:53.331Z,1658530853.331 [Default:CheckIn:C.Wait] Stopped
2022-07-22T23:00:53.331Z,1658530853.331 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-07-22T23:00:53.331Z,1658530853.331 [Default:CheckIn:D] Running Loop=1
2022-07-22T23:00:53.772Z,1658530853.772 [Default:CheckIn:D] Stopped
2022-07-22T23:00:53.772Z,1658530853.772 [Default:CheckIn:E] Running Loop=1
2022-07-22T23:00:54.169Z,1658530854.169 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 456.333984 min 
2022-07-22T23:00:54.170Z,1658530854.170 [Default:CheckIn:E] Stopped
2022-07-22T23:00:54.170Z,1658530854.170 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-07-22T23:00:54.170Z,1658530854.170 [Default:CheckIn] Stopped
2022-07-22T23:00:54.170Z,1658530854.170 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-07-22T23:00:54.170Z,1658530854.170 [Default:CheckIn](INFO): Running loop #65
2022-07-22T23:00:54.170Z,1658530854.170 [Default:CheckIn] Running Loop=65
2022-07-22T23:00:54.170Z,1658530854.170 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-07-22T23:00:54.170Z,1658530854.170 [Default:CheckIn:Read_GPS] Running Loop=1
2022-07-22T23:00:56.156Z,1658530856.156 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230055.00,A,3648.16405,N,12147.28365,W,0.078,102.97,220722,,,A*7C
2022-07-22T23:00:56.158Z,1658530856.158 [NAL9602](INFO): GPS fix at 20220722T230055: (36.802734, -121.788061)
2022-07-22T23:00:56.177Z,1658530856.177 [Default:CheckIn:Read_GPS] Stopped
2022-07-22T23:00:56.177Z,1658530856.177 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-07-22T23:01:06.056Z,1658530866.056 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220722T190204/Courier0120.lzma
2022-07-22T23:01:07.059Z,1658530867.059 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Courier0120.lzma.bak
2022-07-22T23:01:07.059Z,1658530867.059 [DataOverHttps](INFO): SBD MOMSN=17075404
2022-07-22T23:01:23.188Z,1658530883.188 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20220722T190204/Express0121.lzma
2022-07-22T23:01:24.191Z,1658530884.191 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Express0121.lzma.bak
2022-07-22T23:01:24.191Z,1658530884.191 [DataOverHttps](INFO): SBD MOMSN=17075407
2022-07-22T23:01:25.747Z,1658530885.747 [Default:CheckIn:Read_Iridium] Stopped
2022-07-22T23:01:25.747Z,1658530885.747 [Default:CheckIn:C.Wait] Running Loop=1
2022-07-22T23:01:25.747Z,1658530885.747 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-07-22T23:01:26.918Z,1658530886.918 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-07-22T23:01:27.001Z,1658530887.001 [NAL9602](ERROR): received: 
+CSQ:0

OK522, 0, 0, 0, 0

OK
2022-07-22T23:03:01.557Z,1658530981.557 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2022-07-22T23:03:01.557Z,1658530981.557 [DropWeight] Hardware Fault, FailCount= 1
2022-07-22T23:03:01.558Z,1658530981.558 [DropWeight](ERROR): Hardware Fault
2022-07-22T23:03:01.683Z,1658530981.683 [CommandExec](FAULT): Scheduling is paused
2022-07-22T23:03:01.683Z,1658530981.683 [CBIT](INFO): Critical error at 20220722T230301
2022-07-22T23:03:01.685Z,1658530981.685 [CBIT](ERROR): Hardware Fault in component: DropWeight
2022-07-22T23:03:01.686Z,1658530981.686 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2022-07-22T23:03:02.038Z,1658530982.038 [CBIT](INFO): Critical error at 20220722T230301
2022-07-22T23:05:58.509Z,1658531158.509 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-07-22T23:06:26.401Z,1658531186.401 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-07-22T23:06:26.401Z,1658531186.401 [Default:CheckIn:C.Wait] Stopped
2022-07-22T23:06:26.401Z,1658531186.401 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-07-22T23:06:26.401Z,1658531186.401 [Default:CheckIn:D] Running Loop=1
2022-07-22T23:06:26.815Z,1658531186.815 [Default:CheckIn:D] Stopped
2022-07-22T23:06:26.816Z,1658531186.816 [Default:CheckIn:E] Running Loop=1
2022-07-22T23:06:27.215Z,1658531187.215 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 461.884733 min 
2022-07-22T23:06:27.215Z,1658531187.215 [Default:CheckIn:E] Stopped
2022-07-22T23:06:27.215Z,1658531187.215 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-07-22T23:06:27.215Z,1658531187.215 [Default:CheckIn] Stopped
2022-07-22T23:06:27.215Z,1658531187.215 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-07-22T23:06:27.216Z,1658531187.216 [Default:CheckIn](INFO): Running loop #66
2022-07-22T23:06:27.216Z,1658531187.216 [Default:CheckIn] Running Loop=66
2022-07-22T23:06:27.216Z,1658531187.216 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-07-22T23:06:27.216Z,1658531187.216 [Default:CheckIn:Read_GPS] Running Loop=1
2022-07-22T23:06:29.222Z,1658531189.222 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230628.00,A,3648.16696,N,12147.27938,W,0.330,102.97,220722,,,A*7A
2022-07-22T23:06:29.224Z,1658531189.224 [NAL9602](INFO): GPS fix at 20220722T230628: (36.802783, -121.787990)
2022-07-22T23:06:29.234Z,1658531189.234 [Default:CheckIn:Read_GPS] Stopped
2022-07-22T23:06:29.234Z,1658531189.234 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-07-22T23:06:36.792Z,1658531196.792 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20220722T190204/Courier0123.lzma
2022-07-22T23:06:45.077Z,1658531205.077 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20220722T190204/Courier0123.lzma
2022-07-22T23:06:56.221Z,1658531216.221 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20220722T190204/Courier0123.lzma
2022-07-22T23:07:01.181Z,1658531221.181 [NAL9602](INFO): Not Powering down - fast GPS
2022-07-22T23:07:04.861Z,1658531224.861 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20220722T190204/Courier0123.lzma
2022-07-22T23:07:13.225Z,1658531233.225 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20220722T190204/Courier0123.lzma
2022-07-22T23:07:21.825Z,1658531241.825 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20220722T190204/Courier0123.lzma
2022-07-22T23:07:30.185Z,1658531250.185 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20220722T190204/Courier0123.lzma
2022-07-22T23:07:38.753Z,1658531258.753 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20220722T190204/Courier0123.lzma
2022-07-22T23:07:47.137Z,1658531267.137 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20220722T190204/Courier0123.lzma
2022-07-22T23:07:55.821Z,1658531275.821 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20220722T190204/Courier0123.lzma
2022-07-22T23:08:04.157Z,1658531284.157 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20220722T190204/Courier0123.lzma
2022-07-22T23:08:12.749Z,1658531292.749 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20220722T190204/Courier0123.lzma
2022-07-22T23:08:23.733Z,1658531303.733 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20220722T190204/Courier0123.lzma
2022-07-22T23:08:32.989Z,1658531312.989 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20220722T190204/Courier0123.lzma
2022-07-22T23:08:41.345Z,1658531321.345 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20220722T190204/Courier0123.lzma
2022-07-22T23:08:51.709Z,1658531331.709 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20220722T190204/Courier0123.lzma
2022-07-22T23:09:01.001Z,1658531341.001 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20220722T190204/Courier0123.lzma
2022-07-22T23:09:09.521Z,1658531349.521 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20220722T190204/Courier0123.lzma
2022-07-22T23:09:10.523Z,1658531350.523 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Courier0123.lzma.bak
2022-07-22T23:09:10.523Z,1658531350.523 [DataOverHttps](INFO): SBD MOMSN=17075416
2022-07-22T23:09:26.233Z,1658531366.233 [DataOverHttps](INFO): Sending 240 bytes from file Logs/20220722T190204/Express0124.lzma
2022-07-22T23:09:27.235Z,1658531367.235 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Express0124.lzma.bak
2022-07-22T23:09:27.235Z,1658531367.235 [DataOverHttps](INFO): SBD MOMSN=17075425
2022-07-22T23:09:28.749Z,1658531368.749 [Default:CheckIn:Read_Iridium] Stopped
2022-07-22T23:09:28.750Z,1658531368.750 [Default:CheckIn:C.Wait] Running Loop=1
2022-07-22T23:09:28.750Z,1658531368.750 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-07-22T23:13:18.206Z,1658531598.206 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-07-22T23:13:33.966Z,1658531613.966 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-07-22T23:13:49.718Z,1658531629.718 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-07-22T23:14:05.074Z,1658531645.074 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-07-22T23:14:20.846Z,1658531660.846 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-07-22T23:14:29.353Z,1658531669.353 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-07-22T23:14:29.353Z,1658531669.353 [Default:CheckIn:C.Wait] Stopped
2022-07-22T23:14:29.353Z,1658531669.353 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-07-22T23:14:29.353Z,1658531669.353 [Default:CheckIn:D] Running Loop=1
2022-07-22T23:14:29.769Z,1658531669.769 [Default:CheckIn:D] Stopped
2022-07-22T23:14:29.769Z,1658531669.769 [Default:CheckIn:E] Running Loop=1
2022-07-22T23:14:30.157Z,1658531670.157 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 469.933952 min 
2022-07-22T23:14:30.158Z,1658531670.158 [Default:CheckIn:E] Stopped
2022-07-22T23:14:30.158Z,1658531670.158 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-07-22T23:14:30.158Z,1658531670.158 [Default:CheckIn] Stopped
2022-07-22T23:14:30.158Z,1658531670.158 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-07-22T23:14:30.158Z,1658531670.158 [Default:CheckIn](INFO): Running loop #67
2022-07-22T23:14:30.158Z,1658531670.158 [Default:CheckIn] Running Loop=67
2022-07-22T23:14:30.158Z,1658531670.158 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-07-22T23:14:30.158Z,1658531670.158 [Default:CheckIn:Read_GPS] Running Loop=1
2022-07-22T23:14:32.171Z,1658531672.171 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,231431.00,A,3648.16403,N,12147.28152,W,0.156,137.19,220722,,,D*73
2022-07-22T23:14:32.173Z,1658531672.173 [NAL9602](INFO): GPS fix at 20220722T231431: (36.802734, -121.788025)
2022-07-22T23:14:32.183Z,1658531672.183 [Default:CheckIn:Read_GPS] Stopped
2022-07-22T23:14:32.183Z,1658531672.183 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-07-22T23:14:35.802Z,1658531675.802 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-07-22T23:14:39.968Z,1658531679.968 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220722T190204/Courier0126.lzma
2022-07-22T23:14:40.971Z,1658531680.971 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Courier0126.lzma.bak
2022-07-22T23:14:40.971Z,1658531680.971 [DataOverHttps](INFO): SBD MOMSN=17075435
2022-07-22T23:14:50.769Z,1658531690.769 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-07-22T23:15:00.209Z,1658531700.209 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20220722T190204/Express0127.lzma
2022-07-22T23:15:01.211Z,1658531701.211 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Express0127.lzma.bak
2022-07-22T23:15:01.211Z,1658531701.211 [DataOverHttps](INFO): SBD MOMSN=17075443
2022-07-22T23:15:02.524Z,1658531702.524 [Default:CheckIn:Read_Iridium] Stopped
2022-07-22T23:15:02.525Z,1658531702.525 [Default:CheckIn:C.Wait] Running Loop=1
2022-07-22T23:15:02.525Z,1658531702.525 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-07-22T23:15:02.906Z,1658531702.906 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-07-22T23:15:02.981Z,1658531702.981 [NAL9602](ERROR): received: 
+CSQ:0

OK522, 0, 0, 0, 0

OK
2022-07-22T23:15:05.730Z,1658531705.730 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-07-22T23:15:21.082Z,1658531721.082 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-07-22T23:15:35.224Z,1658531735.224 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2022-07-22T23:15:45.733Z,1658531745.733 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2022-07-22T23:15:45.735Z,1658531745.735 [BPC1](INFO): Received data from all battery sticks.
2022-07-22T23:18:01.905Z,1658531881.905 [CBIT](INFO): Clearing failed state for component DropWeight
2022-07-22T23:18:01.905Z,1658531881.905 [DropWeight] No Fault, FailCount= 1
2022-07-22T23:19:34.411Z,1658531974.411 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-07-22T23:20:03.112Z,1658532003.112 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-07-22T23:20:03.113Z,1658532003.113 [Default:CheckIn:C.Wait] Stopped
2022-07-22T23:20:03.113Z,1658532003.113 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-07-22T23:20:03.113Z,1658532003.113 [Default:CheckIn:D] Running Loop=1
2022-07-22T23:20:03.521Z,1658532003.521 [Default:CheckIn:D] Stopped
2022-07-22T23:20:03.522Z,1658532003.522 [Default:CheckIn:E] Running Loop=1
2022-07-22T23:20:03.909Z,1658532003.909 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 475.496484 min 
2022-07-22T23:20:03.909Z,1658532003.909 [Default:CheckIn:E] Stopped
2022-07-22T23:20:03.910Z,1658532003.910 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-07-22T23:20:03.910Z,1658532003.910 [Default:CheckIn] Stopped
2022-07-22T23:20:03.910Z,1658532003.910 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-07-22T23:20:03.910Z,1658532003.910 [Default:CheckIn](INFO): Running loop #68
2022-07-22T23:20:03.910Z,1658532003.910 [Default:CheckIn] Running Loop=68
2022-07-22T23:20:03.910Z,1658532003.910 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-07-22T23:20:03.910Z,1658532003.910 [Default:CheckIn:Read_GPS] Running Loop=1
2022-07-22T23:24:27.493Z,1658532267.493 [CommandExec](IMPORTANT): got command quit
2022-07-22T23:24:28.497Z,1658532268.497 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2022-07-22T23:24:28.497Z,1658532268.497 [CommandExec](INFO): Uninitializing the command executive.
2022-07-22T23:24:28.497Z,1658532268.497 [CommandExec](INFO): Uninitializing the command scheduler.
2022-07-22T23:24:28.497Z,1658532268.497 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-07-22T23:24:28.621Z,1658532268.621 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2022-07-22T23:24:28.621Z,1658532268.621 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2022-07-22T23:24:28.621Z,1658532268.621 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-07-22T23:24:28.622Z,1658532268.622 [NavChartDb](INFO): Join timeout helper Thread ID is 5863
2022-07-22T23:24:28.637Z,1658532268.637 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2022-07-22T23:24:28.637Z,1658532268.637 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-07-22T23:24:28.665Z,1658532268.665 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2022-07-22T23:24:28.665Z,1658532268.665 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-07-22T23:24:28.665Z,1658532268.665 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5864
2022-07-22T23:24:28.765Z,1658532268.765 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2022-07-22T23:24:28.765Z,1658532268.765 [WetLabsBB2FL](INFO): Powering down
2022-07-22T23:24:28.766Z,1658532268.766 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-07-22T23:24:28.777Z,1658532268.777 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2022-07-22T23:24:28.777Z,1658532268.777 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-07-22T23:24:28.777Z,1658532268.777 [CTD_Seabird](INFO): Join timeout helper Thread ID is 5865
2022-07-22T23:24:28.825Z,1658532268.825 [CTD_Seabird](INFO): Powering down
2022-07-22T23:24:28.837Z,1658532268.837 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2022-07-22T23:24:28.837Z,1658532268.837 [CTD_Seabird](INFO): Powering down
2022-07-22T23:24:28.849Z,1658532268.849 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-07-22T23:24:28.865Z,1658532268.865 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2022-07-22T23:24:28.865Z,1658532268.865 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-07-22T23:24:28.865Z,1658532268.865 [Radio_Surface](INFO): Join timeout helper Thread ID is 5866
2022-07-22T23:24:29.093Z,1658532269.093 [Radio_Surface](INFO): Powering down
2022-07-22T23:24:29.094Z,1658532269.094 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2022-07-22T23:24:29.094Z,1658532269.094 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-07-22T23:24:29.113Z,1658532269.113 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2022-07-22T23:24:29.113Z,1658532269.113 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-07-22T23:24:29.113Z,1658532269.113 [Onboard](INFO): Join timeout helper Thread ID is 5867
2022-07-22T23:24:29.605Z,1658532269.605 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2022-07-22T23:24:30.933Z,1658532270.933 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2022-07-22T23:24:30.933Z,1658532270.933 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-07-22T23:24:30.954Z,1658532270.954 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2022-07-22T23:24:30.954Z,1658532270.954 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-07-22T23:24:30.954Z,1658532270.954 [DataOverHttps](INFO): Join timeout helper Thread ID is 5868
2022-07-22T23:24:31.613Z,1658532271.613 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2022-07-22T23:24:31.613Z,1658532271.613 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-07-22T23:24:31.621Z,1658532271.621 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2022-07-22T23:24:31.622Z,1658532271.622 [logger ThreadHandler](INFO): Thread cancelled.
2022-07-22T23:24:31.622Z,1658532271.622 [logger](INFO): Join timeout helper Thread ID is 5869
2022-07-22T23:24:31.633Z,1658532271.633 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2022-07-22T23:24:31.633Z,1658532271.633 [logger ThreadHandler](INFO): Thread cancelled.
2022-07-22T23:24:31.641Z,1658532271.641 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2022-07-22T23:24:31.641Z,1658532271.641 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-07-22T23:24:31.642Z,1658532271.642 [CommandLine](INFO): Join timeout helper Thread ID is 5870
2022-07-22T23:24:31.729Z,1658532271.729 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2022-07-22T23:24:31.729Z,1658532271.729 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-07-22T23:24:31.741Z,1658532271.741 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2022-07-22T23:24:31.741Z,1658532271.741 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-07-22T23:24:31.742Z,1658532271.742 [CommandExec](INFO): Join timeout helper Thread ID is 5871
2022-07-22T23:24:31.743Z,1658532271.743 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2022-07-22T23:24:31.743Z,1658532271.743 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-07-22T23:24:31.743Z,1658532271.743 [controlThread](INFO): Join timeout helper Thread ID is 5872
2022-07-22T23:24:31.745Z,1658532271.745 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2022-07-22T23:24:31.745Z,1658532271.745 [controlThread](DEBUG): Uninitializing ControlThread
2022-07-22T23:24:31.745Z,1658532271.745 [AHRS_M2](INFO): Powering down
2022-07-22T23:24:31.817Z,1658532271.817 [NAL9602](INFO): Powering down
2022-07-22T23:24:31.820Z,1658532271.820 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2022-07-22T23:24:31.821Z,1658532271.821 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2022-07-22T23:24:31.821Z,1658532271.821 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2022-07-22T23:24:31.822Z,1658532271.822 [MissionManager](INFO): Uninitializing Mission Default
2022-07-22T23:24:31.822Z,1658532271.822 [Default] Stopped
2022-07-22T23:24:31.822Z,1658532271.822 [Default](DEBUG): Aggregate::uninitialize Default
2022-07-22T23:24:31.822Z,1658532271.822 [Default:B.GoToSurface] Stopped
2022-07-22T23:24:31.822Z,1658532271.822 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-07-22T23:24:31.822Z,1658532271.822 [Default:CheckIn] Stopped
2022-07-22T23:24:31.822Z,1658532271.822 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-07-22T23:24:31.823Z,1658532271.823 [Default:CheckIn:Read_GPS] Stopped
2022-07-22T23:24:31.825Z,1658532271.825 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2022-07-22T23:24:31.826Z,1658532271.826 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2022-07-22T23:24:31.826Z,1658532271.826 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2022-07-22T23:24:31.826Z,1658532271.826 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2022-07-22T23:24:31.827Z,1658532271.827 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2022-07-22T23:24:31.827Z,1658532271.827 [BuoyancyServo](INFO): Powering down
2022-07-22T23:24:31.841Z,1658532271.841 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2022-07-22T23:24:31.841Z,1658532271.841 [ElevatorServo](INFO): Powering down
2022-07-22T23:24:31.842Z,1658532271.842 [MassServo](DEBUG): Uninitialize Mass Servo.
2022-07-22T23:24:31.842Z,1658532271.842 [MassServo](INFO): Powering down
2022-07-22T23:24:31.843Z,1658532271.843 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-07-22T23:24:31.843Z,1658532271.843 [RudderServo](INFO): Powering down
2022-07-22T23:24:31.844Z,1658532271.844 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2022-07-22T23:24:31.844Z,1658532271.844 [ThrusterServo](INFO): Powering down
2022-07-22T23:24:31.845Z,1658532271.845 [SBIT](DEBUG): Uninitialize SBIT Component.
2022-07-22T23:24:31.845Z,1658532271.845 [IBIT](DEBUG): Uninitialize IBIT Component.
2022-07-22T23:24:31.845Z,1658532271.845 [CBIT](DEBUG): Uninitialize CBIT Component.
2022-07-22T23:24:31.845Z,1658532271.845 [CBIT](DEBUG): Powering off loads.
2022-07-22T23:24:31.856Z,1658532271.856 [CBIT](DEBUG): Disabling WDT.
2022-07-22T23:24:31.868Z,1658532271.868 [CBIT](DEBUG): Opening all GF detection circuits.
2022-07-22T23:24:31.869Z,1658532271.869 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-07-22T23:24:31.918Z,1658532271.918 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-07-22T23:24:31.921Z,1658532271.921 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-07-22T23:24:31.927Z,1658532271.927 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-07-22T23:24:31.998Z,1658532271.998 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-07-22T23:24:32.001Z,1658532272.001 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-07-22T23:24:32.041Z,1658532272.041 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-07-22T23:24:32.146Z,1658532272.146 [logger ThreadHandler](INFO): Thread cancelled.