2023-10-18T18:19:31.220Z,1697653171.220 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20231017T213050/Express0118.lzma 2023-10-18T18:19:31.223Z,1697653171.223 [DataOverHttps](INFO): Received command: restart logs 2023-10-18T18:19:31.238Z,1697653171.238 [CommandExec](IMPORTANT): got command restart logs 2023-10-18T18:19:53.305Z,1697653193.305 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2023-10-18T18:20:24.433Z,1697653224.433 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-18T18:20:55.557Z,1697653255.557 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-18T18:21:26.705Z,1697653286.705 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-18T18:21:57.829Z,1697653317.829 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-18T18:22:21.925Z,1697653341.925 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.435257 2023-10-18T18:22:34.895Z,1697653354.895 [NAL9602](INFO): SBD MO Status=1, MOMSN=45436, MT Status=0, MTMSN=0 2023-10-18T18:22:34.953Z,1697653354.953 [NAL9602](INFO): Sent 39 bytes from file Logs/20231017T213050/Courier0120.lzma 2023-10-18T18:22:34.953Z,1697653354.953 [NAL9602](INFO): Packets left to send: 0 2023-10-18T18:22:45.835Z,1697653365.835 [NAL9602](INFO): SBD MO Status=1, MOMSN=45437, MT Status=0, MTMSN=0 2023-10-18T18:22:45.885Z,1697653365.885 [NAL9602](INFO): Sent 140 bytes from file Logs/20231017T213050/Express0118.lzma 2023-10-18T18:22:45.885Z,1697653365.885 [NAL9602](INFO): Packets left to send: 0 2023-10-18T18:22:53.049Z,1697653373.049 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-18T18:23:03.255Z,1697653383.255 [NAL9602](INFO): SBD MO Status=1, MOMSN=45438, MT Status=0, MTMSN=0 2023-10-18T18:23:03.309Z,1697653383.309 [NAL9602](INFO): Sent 106 bytes from file Logs/20231017T213050/Express0121.lzma 2023-10-18T18:23:03.309Z,1697653383.309 [NAL9602](INFO): Packets left to send: 0 2023-10-18T18:23:10.928Z,1697653390.928 [NAL9602](INFO): SBD MO Status=0, MOMSN=45439, MT Status=0, MTMSN=0 2023-10-18T18:23:11.012Z,1697653391.012 [Default:CheckIn:Read_Iridium] Stopped 2023-10-18T18:23:11.013Z,1697653391.013 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-18T18:23:11.013Z,1697653391.013 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-18T18:23:24.196Z,1697653404.196 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-18T18:23:41.634Z,1697653421.634 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-18T18:23:55.321Z,1697653435.321 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-18T18:24:26.457Z,1697653466.457 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-18T18:24:58.713Z,1697653498.713 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003274 2023-10-18T18:28:11.622Z,1697653691.622 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-18T18:28:11.623Z,1697653691.623 [Default:CheckIn:C.Wait] Stopped 2023-10-18T18:28:11.623Z,1697653691.623 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-18T18:28:11.623Z,1697653691.623 [Default:CheckIn:D] Running Loop=1 2023-10-18T18:28:12.037Z,1697653692.037 [Default:CheckIn:D] Stopped 2023-10-18T18:28:12.037Z,1697653692.037 [Default:CheckIn:E] Running Loop=1 2023-10-18T18:28:12.431Z,1697653692.431 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 22.845516 min 2023-10-18T18:28:12.431Z,1697653692.431 [Default:CheckIn:E] Stopped 2023-10-18T18:28:12.431Z,1697653692.431 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-18T18:28:12.431Z,1697653692.431 [Default:CheckIn] Stopped 2023-10-18T18:28:12.431Z,1697653692.431 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-18T18:28:12.431Z,1697653692.431 [Default:CheckIn](INFO): Running loop #4 2023-10-18T18:28:12.431Z,1697653692.431 [Default:CheckIn] Running Loop=4 2023-10-18T18:28:12.431Z,1697653692.431 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-18T18:28:12.432Z,1697653692.432 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-18T18:28:14.447Z,1697653694.447 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182813.00,A,3648.49417,N,12149.57000,W,1.322,248.20,181023,,,D*74 2023-10-18T18:28:14.450Z,1697653694.450 [NAL9602](INFO): GPS fix at 20231018T182813: (36.808236, -121.826167) 2023-10-18T18:28:14.460Z,1697653694.460 [Default:CheckIn:Read_GPS] Stopped 2023-10-18T18:28:14.460Z,1697653694.460 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-18T18:28:22.128Z,1697653702.128 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20231018T181931/Courier0003.lzma 2023-10-18T18:28:23.130Z,1697653703.130 [DataOverHttps](INFO): Moved sent file to Logs/20231018T181931/Courier0003.lzma.bak 2023-10-18T18:28:23.131Z,1697653703.131 [DataOverHttps](INFO): SBD MOMSN=19011310 2023-10-18T18:28:29.019Z,1697653709.019 [NAL9602](INFO): SBD MO Status=0, MOMSN=45440, MT Status=0, MTMSN=0 2023-10-18T18:28:29.019Z,1697653709.019 [NAL9602](INFO): No messages in MT queue 2023-10-18T18:28:42.685Z,1697653722.685 [DataOverHttps](INFO): Sending 394 bytes from file Logs/20231018T181931/Express0004.lzma 2023-10-18T18:28:43.686Z,1697653723.686 [DataOverHttps](INFO): Moved sent file to Logs/20231018T181931/Express0004.lzma.bak 2023-10-18T18:28:43.686Z,1697653723.686 [DataOverHttps](INFO): SBD MOMSN=19011319 2023-10-18T18:28:44.831Z,1697653724.831 [Default:CheckIn:Read_Iridium] Stopped 2023-10-18T18:28:44.832Z,1697653724.832 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-18T18:28:44.832Z,1697653724.832 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-18T18:28:59.757Z,1697653739.757 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-18T18:33:45.401Z,1697654025.401 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-18T18:33:45.401Z,1697654025.401 [Default:CheckIn:C.Wait] Stopped 2023-10-18T18:33:45.401Z,1697654025.401 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-18T18:33:45.402Z,1697654025.402 [Default:CheckIn:D] Running Loop=1 2023-10-18T18:33:45.807Z,1697654025.807 [Default:CheckIn:D] Stopped 2023-10-18T18:33:45.807Z,1697654025.807 [Default:CheckIn:E] Running Loop=1 2023-10-18T18:33:46.209Z,1697654026.209 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 28.408356 min 2023-10-18T18:33:46.209Z,1697654026.209 [Default:CheckIn:E] Stopped 2023-10-18T18:33:46.209Z,1697654026.209 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-18T18:33:46.209Z,1697654026.209 [Default:CheckIn] Stopped 2023-10-18T18:33:46.210Z,1697654026.210 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-18T18:33:46.210Z,1697654026.210 [Default:CheckIn](INFO): Running loop #5 2023-10-18T18:33:46.210Z,1697654026.210 [Default:CheckIn] Running Loop=5 2023-10-18T18:33:46.210Z,1697654026.210 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-18T18:33:46.210Z,1697654026.210 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-18T18:33:48.218Z,1697654028.218 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183347.00,A,3648.36019,N,12149.42250,W,0.952,222.21,181023,,,D*7F 2023-10-18T18:33:48.220Z,1697654028.220 [NAL9602](INFO): GPS fix at 20231018T183347: (36.806003, -121.823708) 2023-10-18T18:33:48.255Z,1697654028.255 [Default:CheckIn:Read_GPS] Stopped 2023-10-18T18:33:48.255Z,1697654028.255 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-18T18:33:56.756Z,1697654036.756 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231018T181931/Courier0006.lzma 2023-10-18T18:33:57.758Z,1697654037.758 [DataOverHttps](INFO): Moved sent file to Logs/20231018T181931/Courier0006.lzma.bak 2023-10-18T18:33:57.758Z,1697654037.758 [DataOverHttps](INFO): SBD MOMSN=19011330 2023-10-18T18:34:07.207Z,1697654047.207 [NAL9602](INFO): SBD MO Status=0, MOMSN=45441, MT Status=0, MTMSN=0 2023-10-18T18:34:07.207Z,1697654047.207 [NAL9602](INFO): No messages in MT queue 2023-10-18T18:34:20.608Z,1697654060.608 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20231018T181931/Express0007.lzma 2023-10-18T18:34:21.610Z,1697654061.610 [DataOverHttps](INFO): Moved sent file to Logs/20231018T181931/Express0007.lzma.bak 2023-10-18T18:34:21.610Z,1697654061.610 [DataOverHttps](INFO): SBD MOMSN=19011334 2023-10-18T18:34:22.981Z,1697654062.981 [Default:CheckIn:Read_Iridium] Stopped 2023-10-18T18:34:22.981Z,1697654062.981 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-18T18:34:22.981Z,1697654062.981 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-18T18:34:37.909Z,1697654077.909 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-18T18:39:23.632Z,1697654363.632 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-18T18:39:23.632Z,1697654363.632 [Default:CheckIn:C.Wait] Stopped 2023-10-18T18:39:23.632Z,1697654363.632 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-18T18:39:23.633Z,1697654363.633 [Default:CheckIn:D] Running Loop=1 2023-10-18T18:39:24.031Z,1697654364.031 [Default:CheckIn:D] Stopped 2023-10-18T18:39:24.031Z,1697654364.031 [Default:CheckIn:E] Running Loop=1 2023-10-18T18:39:24.435Z,1697654364.435 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 34.045420 min 2023-10-18T18:39:24.435Z,1697654364.435 [Default:CheckIn:E] Stopped 2023-10-18T18:39:24.435Z,1697654364.435 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-18T18:39:24.435Z,1697654364.435 [Default:CheckIn] Stopped 2023-10-18T18:39:24.435Z,1697654364.435 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-18T18:39:24.436Z,1697654364.436 [Default:CheckIn](INFO): Running loop #6 2023-10-18T18:39:24.436Z,1697654364.436 [Default:CheckIn] Running Loop=6 2023-10-18T18:39:24.436Z,1697654364.436 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-18T18:39:24.436Z,1697654364.436 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-18T18:39:26.451Z,1697654366.451 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183925.00,A,3648.35597,N,12149.48888,W,0.467,248.80,181023,,,A*7D 2023-10-18T18:39:26.454Z,1697654366.454 [NAL9602](INFO): GPS fix at 20231018T183925: (36.805933, -121.824815) 2023-10-18T18:39:26.464Z,1697654366.464 [Default:CheckIn:Read_GPS] Stopped 2023-10-18T18:39:26.464Z,1697654366.464 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-18T18:39:33.344Z,1697654373.344 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231018T181931/Courier0009.lzma 2023-10-18T18:39:34.947Z,1697654374.947 [NAL9602](INFO): SBD MO Status=0, MOMSN=45442, MT Status=0, MTMSN=0 2023-10-18T18:39:34.947Z,1697654374.947 [NAL9602](INFO): No messages in MT queue 2023-10-18T18:39:45.390Z,1697654385.390 [DataOverHttps](INFO): Moved sent file to Logs/20231018T181931/Courier0009.lzma.bak 2023-10-18T18:39:45.390Z,1697654385.390 [DataOverHttps](INFO): SBD MOMSN=19011354 2023-10-18T18:40:05.644Z,1697654405.644 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-18T18:40:10.260Z,1697654410.260 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20231018T181931/Express0010.lzma 2023-10-18T18:40:11.262Z,1697654411.262 [DataOverHttps](INFO): Moved sent file to Logs/20231018T181931/Express0010.lzma.bak 2023-10-18T18:40:11.262Z,1697654411.262 [DataOverHttps](INFO): SBD MOMSN=19011357 2023-10-18T18:40:12.533Z,1697654412.533 [Default:CheckIn:Read_Iridium] Stopped 2023-10-18T18:40:12.533Z,1697654412.533 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-18T18:40:12.533Z,1697654412.533 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-18T18:45:13.171Z,1697654713.171 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-18T18:45:13.171Z,1697654713.171 [Default:CheckIn:C.Wait] Stopped 2023-10-18T18:45:13.171Z,1697654713.171 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-18T18:45:13.171Z,1697654713.171 [Default:CheckIn:D] Running Loop=1 2023-10-18T18:45:13.559Z,1697654713.559 [Default:CheckIn:D] Stopped 2023-10-18T18:45:13.559Z,1697654713.559 [Default:CheckIn:E] Running Loop=1 2023-10-18T18:45:13.964Z,1697654713.964 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 39.870882 min 2023-10-18T18:45:13.964Z,1697654713.964 [Default:CheckIn:E] Stopped 2023-10-18T18:45:13.964Z,1697654713.964 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-18T18:45:13.965Z,1697654713.965 [Default:CheckIn] Stopped 2023-10-18T18:45:13.965Z,1697654713.965 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-18T18:45:13.965Z,1697654713.965 [Default:CheckIn](INFO): Running loop #7 2023-10-18T18:45:13.965Z,1697654713.965 [Default:CheckIn] Running Loop=7 2023-10-18T18:45:13.965Z,1697654713.965 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-18T18:45:13.965Z,1697654713.965 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-18T18:45:15.975Z,1697654715.975 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184515.00,A,3648.38948,N,12148.06308,W,3.363,120.15,181023,,,D*7A 2023-10-18T18:45:15.978Z,1697654715.978 [NAL9602](INFO): GPS fix at 20231018T184515: (36.806491, -121.801051) 2023-10-18T18:45:15.989Z,1697654715.989 [Default:CheckIn:Read_GPS] Stopped 2023-10-18T18:45:15.989Z,1697654715.989 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-18T18:45:23.672Z,1697654723.672 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231018T181931/Courier0012.lzma 2023-10-18T18:45:24.674Z,1697654724.674 [DataOverHttps](INFO): Moved sent file to Logs/20231018T181931/Courier0012.lzma.bak 2023-10-18T18:45:24.674Z,1697654724.674 [DataOverHttps](INFO): SBD MOMSN=19011362 2023-10-18T18:45:36.988Z,1697654736.988 [NAL9602](INFO): SBD MO Status=0, MOMSN=45443, MT Status=0, MTMSN=0 2023-10-18T18:45:36.988Z,1697654736.988 [NAL9602](INFO): No messages in MT queue 2023-10-18T18:45:40.700Z,1697654740.700 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20231018T181931/Express0013.lzma 2023-10-18T18:45:41.702Z,1697654741.702 [DataOverHttps](INFO): Moved sent file to Logs/20231018T181931/Express0013.lzma.bak 2023-10-18T18:45:41.702Z,1697654741.702 [DataOverHttps](INFO): SBD MOMSN=19011367 2023-10-18T18:45:43.068Z,1697654743.068 [Default:CheckIn:Read_Iridium] Stopped 2023-10-18T18:45:43.068Z,1697654743.068 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-18T18:45:43.069Z,1697654743.069 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-18T18:46:07.689Z,1697654767.689 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-18T18:50:43.632Z,1697655043.632 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-18T18:50:43.632Z,1697655043.632 [Default:CheckIn:C.Wait] Stopped 2023-10-18T18:50:43.632Z,1697655043.632 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-18T18:50:43.633Z,1697655043.633 [Default:CheckIn:D] Running Loop=1 2023-10-18T18:50:44.051Z,1697655044.051 [Default:CheckIn:D] Stopped 2023-10-18T18:50:44.051Z,1697655044.051 [Default:CheckIn:E] Running Loop=1 2023-10-18T18:50:44.435Z,1697655044.435 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 45.379085 min 2023-10-18T18:50:44.435Z,1697655044.435 [Default:CheckIn:E] Stopped 2023-10-18T18:50:44.435Z,1697655044.435 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-18T18:50:44.435Z,1697655044.435 [Default:CheckIn] Stopped 2023-10-18T18:50:44.435Z,1697655044.435 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-18T18:50:44.435Z,1697655044.435 [Default:CheckIn](INFO): Running loop #8 2023-10-18T18:50:44.435Z,1697655044.435 [Default:CheckIn] Running Loop=8 2023-10-18T18:50:44.436Z,1697655044.436 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-18T18:50:44.436Z,1697655044.436 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-18T18:50:46.451Z,1697655046.451 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185045.00,A,3648.33812,N,12148.06528,W,0.758,187.23,181023,,,D*7D 2023-10-18T18:50:46.453Z,1697655046.453 [NAL9602](INFO): GPS fix at 20231018T185045: (36.805635, -121.801088) 2023-10-18T18:50:46.463Z,1697655046.463 [Default:CheckIn:Read_GPS] Stopped 2023-10-18T18:50:46.464Z,1697655046.464 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-18T18:50:53.760Z,1697655053.760 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231018T181931/Courier0015.lzma 2023-10-18T18:50:54.762Z,1697655054.762 [DataOverHttps](INFO): Moved sent file to Logs/20231018T181931/Courier0015.lzma.bak 2023-10-18T18:50:54.762Z,1697655054.762 [DataOverHttps](INFO): SBD MOMSN=19011391 2023-10-18T18:51:10.588Z,1697655070.588 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20231018T181931/Express0016.lzma 2023-10-18T18:51:11.590Z,1697655071.590 [DataOverHttps](INFO): Moved sent file to Logs/20231018T181931/Express0016.lzma.bak 2023-10-18T18:51:11.590Z,1697655071.590 [DataOverHttps](INFO): SBD MOMSN=19011394 2023-10-18T18:51:12.717Z,1697655072.717 [Default:CheckIn:Read_Iridium] Stopped 2023-10-18T18:51:12.717Z,1697655072.717 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-18T18:51:12.717Z,1697655072.717 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-18T18:51:30.483Z,1697655090.483 [NAL9602](INFO): SBD MO Status=0, MOMSN=45444, MT Status=0, MTMSN=0 2023-10-18T18:51:30.483Z,1697655090.483 [NAL9602](INFO): No messages in MT queue 2023-10-18T18:52:01.189Z,1697655121.189 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-18T18:56:13.295Z,1697655373.295 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-18T18:56:13.295Z,1697655373.295 [Default:CheckIn:C.Wait] Stopped 2023-10-18T18:56:13.295Z,1697655373.295 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-18T18:56:13.295Z,1697655373.295 [Default:CheckIn:D] Running Loop=1 2023-10-18T18:56:13.699Z,1697655373.699 [Default:CheckIn:D] Stopped 2023-10-18T18:56:13.699Z,1697655373.699 [Default:CheckIn:E] Running Loop=1 2023-10-18T18:56:14.100Z,1697655374.100 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 50.873218 min 2023-10-18T18:56:14.100Z,1697655374.100 [Default:CheckIn:E] Stopped 2023-10-18T18:56:14.100Z,1697655374.100 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-18T18:56:14.101Z,1697655374.101 [Default:CheckIn] Stopped 2023-10-18T18:56:14.101Z,1697655374.101 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-18T18:56:14.101Z,1697655374.101 [Default:CheckIn](INFO): Running loop #9 2023-10-18T18:56:14.101Z,1697655374.101 [Default:CheckIn] Running Loop=9 2023-10-18T18:56:14.101Z,1697655374.101 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-18T18:56:14.101Z,1697655374.101 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-18T18:56:16.119Z,1697655376.119 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185615.00,A,3648.27596,N,12148.09489,W,6.609,147.80,181023,,,A*7C 2023-10-18T18:56:16.121Z,1697655376.121 [NAL9602](INFO): GPS fix at 20231018T185615: (36.804599, -121.801581) 2023-10-18T18:56:16.132Z,1697655376.132 [Default:CheckIn:Read_GPS] Stopped 2023-10-18T18:56:16.132Z,1697655376.132 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-18T18:56:24.560Z,1697655384.560 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231018T181931/Courier0018.lzma 2023-10-18T18:56:25.564Z,1697655385.564 [DataOverHttps](INFO): Moved sent file to Logs/20231018T181931/Courier0018.lzma.bak 2023-10-18T18:56:25.564Z,1697655385.564 [DataOverHttps](INFO): SBD MOMSN=19011407 2023-10-18T18:56:31.099Z,1697655391.099 [NAL9602](INFO): SBD MO Status=0, MOMSN=45445, MT Status=0, MTMSN=0 2023-10-18T18:56:31.099Z,1697655391.099 [NAL9602](INFO): No messages in MT queue 2023-10-18T18:56:42.908Z,1697655402.908 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20231018T181931/Express0019.lzma 2023-10-18T18:56:43.910Z,1697655403.910 [DataOverHttps](INFO): Moved sent file to Logs/20231018T181931/Express0019.lzma.bak 2023-10-18T18:56:43.910Z,1697655403.910 [DataOverHttps](INFO): SBD MOMSN=19011410 2023-10-18T18:56:45.267Z,1697655405.267 [Default:CheckIn:Read_Iridium] Stopped 2023-10-18T18:56:45.267Z,1697655405.267 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-18T18:56:45.267Z,1697655405.267 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-18T18:57:01.797Z,1697655421.797 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-18T19:01:45.823Z,1697655705.823 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-18T19:01:45.823Z,1697655705.823 [Default:CheckIn:C.Wait] Stopped 2023-10-18T19:01:45.823Z,1697655705.823 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-18T19:01:45.824Z,1697655705.824 [Default:CheckIn:D] Running Loop=1 2023-10-18T19:01:46.224Z,1697655706.224 [Default:CheckIn:D] Stopped 2023-10-18T19:01:46.224Z,1697655706.224 [Default:CheckIn:E] Running Loop=1 2023-10-18T19:01:46.640Z,1697655706.640 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 56.415299 min 2023-10-18T19:01:46.640Z,1697655706.640 [Default:CheckIn:E] Stopped 2023-10-18T19:01:46.640Z,1697655706.640 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-18T19:01:46.640Z,1697655706.640 [Default:CheckIn] Stopped 2023-10-18T19:01:46.640Z,1697655706.640 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-18T19:01:46.641Z,1697655706.641 [Default:CheckIn](INFO): Running loop #10 2023-10-18T19:01:46.641Z,1697655706.641 [Default:CheckIn] Running Loop=10 2023-10-18T19:01:46.641Z,1697655706.641 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-18T19:01:46.641Z,1697655706.641 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-18T19:01:48.643Z,1697655708.643 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190147.00,A,3648.31094,N,12147.49193,W,8.436,50.79,181023,,,D*49 2023-10-18T19:01:48.646Z,1697655708.646 [NAL9602](INFO): GPS fix at 20231018T190147: (36.805182, -121.791532) 2023-10-18T19:01:48.656Z,1697655708.656 [Default:CheckIn:Read_GPS] Stopped 2023-10-18T19:01:48.656Z,1697655708.656 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-18T19:01:56.144Z,1697655716.144 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231018T181931/Courier0021.lzma 2023-10-18T19:01:57.146Z,1697655717.146 [DataOverHttps](INFO): Moved sent file to Logs/20231018T181931/Courier0021.lzma.bak 2023-10-18T19:01:57.146Z,1697655717.146 [DataOverHttps](INFO): SBD MOMSN=19011427 2023-10-18T19:02:12.968Z,1697655732.968 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20231018T181931/Express0022.lzma 2023-10-18T19:02:13.970Z,1697655733.970 [DataOverHttps](INFO): Moved sent file to Logs/20231018T181931/Express0022.lzma.bak 2023-10-18T19:02:13.970Z,1697655733.970 [DataOverHttps](INFO): SBD MOMSN=19011430 2023-10-18T19:02:15.321Z,1697655735.321 [Default:CheckIn:Read_Iridium] Stopped 2023-10-18T19:02:15.321Z,1697655735.321 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-18T19:02:15.321Z,1697655735.321 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-18T19:02:20.958Z,1697655740.958 [NAL9602](INFO): SBD MO Status=0, MOMSN=45446, MT Status=0, MTMSN=0 2023-10-18T19:02:20.958Z,1697655740.958 [NAL9602](INFO): No messages in MT queue 2023-10-18T19:02:51.663Z,1697655771.663 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-18T19:07:15.950Z,1697656035.950 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-18T19:07:15.950Z,1697656035.950 [Default:CheckIn:C.Wait] Stopped 2023-10-18T19:07:15.950Z,1697656035.950 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-18T19:07:15.951Z,1697656035.951 [Default:CheckIn:D] Running Loop=1 2023-10-18T19:07:16.370Z,1697656036.370 [Default:CheckIn:D] Stopped 2023-10-18T19:07:16.370Z,1697656036.370 [Default:CheckIn:E] Running Loop=1 2023-10-18T19:07:16.754Z,1697656036.754 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 61.917737 min 2023-10-18T19:07:16.755Z,1697656036.755 [Default:CheckIn:E] Stopped 2023-10-18T19:07:16.755Z,1697656036.755 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-18T19:07:16.755Z,1697656036.755 [Default:CheckIn] Stopped 2023-10-18T19:07:16.755Z,1697656036.755 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-18T19:07:16.755Z,1697656036.755 [Default:CheckIn](INFO): Running loop #11 2023-10-18T19:07:16.755Z,1697656036.755 [Default:CheckIn] Running Loop=11 2023-10-18T19:07:16.755Z,1697656036.755 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-18T19:07:16.755Z,1697656036.755 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-18T19:07:18.771Z,1697656038.771 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190718.00,A,3648.28075,N,12147.13857,W,4.315,180.65,181023,,,D*77 2023-10-18T19:07:18.774Z,1697656038.774 [NAL9602](INFO): GPS fix at 20231018T190718: (36.804679, -121.785643) 2023-10-18T19:07:18.784Z,1697656038.784 [Default:CheckIn:Read_GPS] Stopped 2023-10-18T19:07:18.784Z,1697656038.784 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-18T19:07:25.900Z,1697656045.900 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231018T181931/Courier0024.lzma 2023-10-18T19:07:26.902Z,1697656046.902 [DataOverHttps](INFO): Moved sent file to Logs/20231018T181931/Courier0024.lzma.bak 2023-10-18T19:07:26.902Z,1697656046.902 [DataOverHttps](INFO): SBD MOMSN=19011444 2023-10-18T19:07:30.475Z,1697656050.475 [NAL9602](INFO): SBD MO Status=0, MOMSN=45447, MT Status=0, MTMSN=0 2023-10-18T19:07:30.475Z,1697656050.475 [NAL9602](INFO): No messages in MT queue 2023-10-18T19:07:42.576Z,1697656062.576 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20231018T181931/Express0025.lzma 2023-10-18T19:07:43.578Z,1697656063.578 [DataOverHttps](INFO): Moved sent file to Logs/20231018T181931/Express0025.lzma.bak 2023-10-18T19:07:43.578Z,1697656063.578 [DataOverHttps](INFO): SBD MOMSN=19011447 2023-10-18T19:07:45.035Z,1697656065.035 [Default:CheckIn:Read_Iridium] Stopped 2023-10-18T19:07:45.035Z,1697656065.035 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-18T19:07:45.035Z,1697656065.035 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-18T19:08:01.177Z,1697656081.177 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-18T19:10:31.995Z,1697656231.995 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-10-18T19:10:46.134Z,1697656246.134 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-10-18T19:11:00.275Z,1697656260.275 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-10-18T19:11:13.607Z,1697656273.607 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-10-18T19:11:27.342Z,1697656287.342 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-10-18T19:11:41.079Z,1697656301.079 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-10-18T19:11:54.816Z,1697656314.816 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-10-18T19:12:08.957Z,1697656328.957 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-10-18T19:12:23.132Z,1697656343.132 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-10-18T19:12:37.319Z,1697656357.319 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-10-18T19:12:45.819Z,1697656365.819 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-18T19:12:45.819Z,1697656365.819 [Default:CheckIn:C.Wait] Stopped 2023-10-18T19:12:45.820Z,1697656365.820 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-18T19:12:45.820Z,1697656365.820 [Default:CheckIn:D] Running Loop=1 2023-10-18T19:12:46.233Z,1697656366.233 [Default:CheckIn:D] Stopped 2023-10-18T19:12:46.233Z,1697656366.233 [Default:CheckIn:E] Running Loop=1 2023-10-18T19:12:46.630Z,1697656366.630 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 67.415458 min 2023-10-18T19:12:46.630Z,1697656366.630 [Default:CheckIn:E] Stopped 2023-10-18T19:12:46.630Z,1697656366.630 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-18T19:12:46.631Z,1697656366.631 [Default:CheckIn] Stopped 2023-10-18T19:12:46.631Z,1697656366.631 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-18T19:12:46.631Z,1697656366.631 [Default:CheckIn](INFO): Running loop #12 2023-10-18T19:12:46.631Z,1697656366.631 [Default:CheckIn] Running Loop=12 2023-10-18T19:12:46.631Z,1697656366.631 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-18T19:12:46.631Z,1697656366.631 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-18T19:12:48.647Z,1697656368.647 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191247.00,A,3648.15157,N,12147.22344,W,0.039,199.36,181023,,,D*7A 2023-10-18T19:12:48.650Z,1697656368.650 [NAL9602](INFO): GPS fix at 20231018T191247: (36.802526, -121.787057) 2023-10-18T19:12:48.660Z,1697656368.660 [Default:CheckIn:Read_GPS] Stopped 2023-10-18T19:12:48.660Z,1697656368.660 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-18T19:12:55.908Z,1697656375.908 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231018T181931/Courier0027.lzma 2023-10-18T19:12:56.910Z,1697656376.910 [DataOverHttps](INFO): Moved sent file to Logs/20231018T181931/Courier0027.lzma.bak 2023-10-18T19:12:56.910Z,1697656376.910 [DataOverHttps](INFO): SBD MOMSN=19011466 2023-10-18T19:13:04.799Z,1697656384.799 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2023-10-18T19:13:12.908Z,1697656392.908 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20231018T181931/Express0028.lzma 2023-10-18T19:13:13.910Z,1697656393.910 [DataOverHttps](INFO): Moved sent file to Logs/20231018T181931/Express0028.lzma.bak 2023-10-18T19:13:13.910Z,1697656393.910 [DataOverHttps](INFO): SBD MOMSN=19011470 2023-10-18T19:13:15.317Z,1697656395.317 [Default:CheckIn:Read_Iridium] Stopped 2023-10-18T19:13:15.317Z,1697656395.317 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-18T19:13:15.317Z,1697656395.317 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-18T19:13:16.517Z,1697656396.517 [NAL9602](INFO): SBD MO Status=0, MOMSN=45448, MT Status=0, MTMSN=0 2023-10-18T19:13:16.517Z,1697656396.517 [NAL9602](INFO): No messages in MT queue 2023-10-18T19:13:47.213Z,1697656427.213 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-18T19:15:05.739Z,1697656505.739 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-10-18T19:15:12.111Z,1697656512.111 [BPC1](INFO): Calculating totals. Valid battery stick count: 40. Valid reserve battery stick count: 5. 2023-10-18T19:15:12.114Z,1697656512.114 [BPC1](INFO): Received data from all battery sticks. 2023-10-18T19:18:16.011Z,1697656696.011 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-18T19:18:16.011Z,1697656696.011 [Default:CheckIn:C.Wait] Stopped 2023-10-18T19:18:16.011Z,1697656696.011 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-18T19:18:16.011Z,1697656696.011 [Default:CheckIn:D] Running Loop=1 2023-10-18T19:18:16.415Z,1697656696.415 [Default:CheckIn:D] Stopped 2023-10-18T19:18:16.415Z,1697656696.415 [Default:CheckIn:E] Running Loop=1 2023-10-18T19:18:16.817Z,1697656696.817 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 72.918490 min 2023-10-18T19:18:16.817Z,1697656696.817 [Default:CheckIn:E] Stopped 2023-10-18T19:18:16.817Z,1697656696.817 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-18T19:18:16.817Z,1697656696.817 [Default:CheckIn] Stopped 2023-10-18T19:18:16.818Z,1697656696.818 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-18T19:18:16.818Z,1697656696.818 [Default:CheckIn](INFO): Running loop #13 2023-10-18T19:18:16.818Z,1697656696.818 [Default:CheckIn] Running Loop=13 2023-10-18T19:18:16.818Z,1697656696.818 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-18T19:18:16.818Z,1697656696.818 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-18T19:18:18.828Z,1697656698.828 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191818.00,A,3648.17536,N,12147.28244,W,0.097,297.44,181023,,,A*79 2023-10-18T19:18:18.830Z,1697656698.830 [NAL9602](INFO): GPS fix at 20231018T191818: (36.802923, -121.788041) 2023-10-18T19:18:18.877Z,1697656698.877 [Default:CheckIn:Read_GPS] Stopped 2023-10-18T19:18:18.877Z,1697656698.877 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-18T19:18:29.448Z,1697656709.448 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20231018T181931/Courier0030.lzma 2023-10-18T19:18:30.450Z,1697656710.450 [DataOverHttps](INFO): Moved sent file to Logs/20231018T181931/Courier0030.lzma.bak 2023-10-18T19:18:30.450Z,1697656710.450 [DataOverHttps](INFO): SBD MOMSN=19011485 2023-10-18T19:18:50.468Z,1697656730.468 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20231018T181931/Express0031.lzma 2023-10-18T19:18:51.470Z,1697656731.470 [DataOverHttps](INFO): Moved sent file to Logs/20231018T181931/Express0031.lzma.bak 2023-10-18T19:18:51.470Z,1697656731.470 [DataOverHttps](INFO): SBD MOMSN=19011488 2023-10-18T19:18:52.935Z,1697656732.935 [Default:CheckIn:Read_Iridium] Stopped 2023-10-18T19:18:52.936Z,1697656732.936 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-18T19:18:52.936Z,1697656732.936 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-18T19:20:19.775Z,1697656819.775 [NAL9602](INFO): SBD MO Status=2, MOMSN=45449, MT Status=2, MTMSN=0 2023-10-18T19:20:19.775Z,1697656819.775 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-10-18T19:21:09.147Z,1697656869.147 [NAL9602](INFO): SBD MO Status=2, MOMSN=45449, MT Status=2, MTMSN=0 2023-10-18T19:21:09.147Z,1697656869.147 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-10-18T19:22:00.859Z,1697656920.859 [NAL9602](INFO): SBD MO Status=0, MOMSN=45449, MT Status=0, MTMSN=0 2023-10-18T19:22:00.859Z,1697656920.859 [NAL9602](INFO): No messages in MT queue 2023-10-18T19:22:31.571Z,1697656951.571 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-18T19:23:53.591Z,1697657033.591 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-18T19:23:53.591Z,1697657033.591 [Default:CheckIn:C.Wait] Stopped 2023-10-18T19:23:53.591Z,1697657033.591 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-18T19:23:53.592Z,1697657033.592 [Default:CheckIn:D] Running Loop=1 2023-10-18T19:23:53.996Z,1697657033.996 [Default:CheckIn:D] Stopped 2023-10-18T19:23:53.996Z,1697657033.996 [Default:CheckIn:E] Running Loop=1 2023-10-18T19:23:54.411Z,1697657034.411 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 78.544832 min 2023-10-18T19:23:54.411Z,1697657034.411 [Default:CheckIn:E] Stopped 2023-10-18T19:23:54.411Z,1697657034.411 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-18T19:23:54.411Z,1697657034.411 [Default:CheckIn] Stopped 2023-10-18T19:23:54.411Z,1697657034.411 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-18T19:23:54.411Z,1697657034.411 [Default:CheckIn](INFO): Running loop #14 2023-10-18T19:23:54.411Z,1697657034.411 [Default:CheckIn] Running Loop=14 2023-10-18T19:23:54.412Z,1697657034.412 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-18T19:23:54.412Z,1697657034.412 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-18T19:23:56.406Z,1697657036.406 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192355.00,A,3648.17485,N,12147.28113,W,0.078,297.44,181023,,,D*74 2023-10-18T19:23:56.409Z,1697657036.409 [NAL9602](INFO): GPS fix at 20231018T192355: (36.802914, -121.788019) 2023-10-18T19:23:56.419Z,1697657036.419 [Default:CheckIn:Read_GPS] Stopped 2023-10-18T19:23:56.419Z,1697657036.419 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-18T19:24:03.408Z,1697657043.408 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231018T181931/Courier0033.lzma 2023-10-18T19:24:04.410Z,1697657044.410 [DataOverHttps](INFO): Moved sent file to Logs/20231018T181931/Courier0033.lzma.bak 2023-10-18T19:24:04.410Z,1697657044.410 [DataOverHttps](INFO): SBD MOMSN=19011503 2023-10-18T19:24:22.664Z,1697657062.664 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20231018T181931/Express0034.lzma 2023-10-18T19:24:23.666Z,1697657063.666 [DataOverHttps](INFO): Moved sent file to Logs/20231018T181931/Express0034.lzma.bak 2023-10-18T19:24:23.666Z,1697657063.666 [DataOverHttps](INFO): SBD MOMSN=19011506 2023-10-18T19:24:24.812Z,1697657064.812 [Default:CheckIn:Read_Iridium] Stopped 2023-10-18T19:24:24.813Z,1697657064.813 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-18T19:24:24.813Z,1697657064.813 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-18T19:28:30.137Z,1697657310.137 [NAL9602](INFO): SBD MO Status=2, MOMSN=45450, MT Status=2, MTMSN=0 2023-10-18T19:28:30.137Z,1697657310.137 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-10-18T19:28:58.093Z,1697657338.093 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-10-18T19:29:25.166Z,1697657365.166 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-18T19:29:25.166Z,1697657365.166 [Default:CheckIn:C.Wait] Stopped 2023-10-18T19:29:25.166Z,1697657365.166 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-18T19:29:25.167Z,1697657365.167 [Default:CheckIn:D] Running Loop=1 2023-10-18T19:29:25.561Z,1697657365.561 [Default:CheckIn:D] Stopped 2023-10-18T19:29:25.561Z,1697657365.561 [Default:CheckIn:E] Running Loop=1 2023-10-18T19:29:25.959Z,1697657365.959 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 84.070923 min 2023-10-18T19:29:25.959Z,1697657365.959 [Default:CheckIn:E] Stopped 2023-10-18T19:29:25.959Z,1697657365.959 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-18T19:29:25.959Z,1697657365.959 [Default:CheckIn] Stopped 2023-10-18T19:29:25.959Z,1697657365.959 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-18T19:29:25.959Z,1697657365.959 [Default:CheckIn](INFO): Running loop #15 2023-10-18T19:29:25.959Z,1697657365.959 [Default:CheckIn] Running Loop=15 2023-10-18T19:29:25.959Z,1697657365.959 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-18T19:29:25.960Z,1697657365.960 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-18T19:29:27.971Z,1697657367.971 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192927.00,A,3648.17409,N,12147.28082,W,0.350,297.44,181023,,,A*7A 2023-10-18T19:29:27.973Z,1697657367.973 [NAL9602](INFO): GPS fix at 20231018T192927: (36.802901, -121.788014) 2023-10-18T19:29:28.017Z,1697657368.017 [Default:CheckIn:Read_GPS] Stopped 2023-10-18T19:29:28.017Z,1697657368.017 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-18T19:29:35.152Z,1697657375.152 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231018T181931/Courier0036.lzma 2023-10-18T19:29:36.154Z,1697657376.154 [DataOverHttps](INFO): Moved sent file to Logs/20231018T181931/Courier0036.lzma.bak 2023-10-18T19:29:36.154Z,1697657376.154 [DataOverHttps](INFO): SBD MOMSN=19011517 2023-10-18T19:29:51.793Z,1697657391.793 [DataOverHttps](INFO): Sending 230 bytes from file Logs/20231018T181931/Express0037.lzma 2023-10-18T19:29:52.794Z,1697657392.794 [DataOverHttps](INFO): Moved sent file to Logs/20231018T181931/Express0037.lzma.bak 2023-10-18T19:29:52.795Z,1697657392.795 [DataOverHttps](INFO): SBD MOMSN=19011520 2023-10-18T19:29:53.907Z,1697657393.907 [Default:CheckIn:Read_Iridium] Stopped 2023-10-18T19:29:53.907Z,1697657393.907 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-18T19:29:53.908Z,1697657393.908 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-18T19:30:00.705Z,1697657400.705 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-18T19:34:54.275Z,1697657694.275 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-18T19:34:54.275Z,1697657694.275 [Default:CheckIn:C.Wait] Stopped 2023-10-18T19:34:54.275Z,1697657694.275 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-18T19:34:54.275Z,1697657694.275 [Default:CheckIn:D] Running Loop=1 2023-10-18T19:34:54.659Z,1697657694.659 [Default:CheckIn:D] Stopped 2023-10-18T19:34:54.659Z,1697657694.659 [Default:CheckIn:E] Running Loop=1 2023-10-18T19:34:55.063Z,1697657695.063 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 89.555892 min 2023-10-18T19:34:55.063Z,1697657695.063 [Default:CheckIn:E] Stopped 2023-10-18T19:34:55.063Z,1697657695.063 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-18T19:34:55.063Z,1697657695.063 [Default:CheckIn] Stopped 2023-10-18T19:34:55.063Z,1697657695.063 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-18T19:34:55.064Z,1697657695.063 [Default:CheckIn](INFO): Running loop #16 2023-10-18T19:34:55.064Z,1697657695.064 [Default:CheckIn] Running Loop=16 2023-10-18T19:34:55.064Z,1697657695.064 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-18T19:34:55.064Z,1697657695.064 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-18T19:34:57.079Z,1697657697.079 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193456.00,A,3648.16973,N,12147.28012,W,0.156,297.44,181023,,,A*7C 2023-10-18T19:34:57.082Z,1697657697.082 [NAL9602](INFO): GPS fix at 20231018T193456: (36.802829, -121.788002) 2023-10-18T19:34:57.112Z,1697657697.112 [Default:CheckIn:Read_GPS] Stopped 2023-10-18T19:34:57.112Z,1697657697.112 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-18T19:35:04.880Z,1697657704.880 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231018T181931/Courier0039.lzma 2023-10-18T19:35:05.882Z,1697657705.882 [DataOverHttps](INFO): Moved sent file to Logs/20231018T181931/Courier0039.lzma.bak 2023-10-18T19:35:05.882Z,1697657705.882 [DataOverHttps](INFO): SBD MOMSN=19011537 2023-10-18T19:35:21.748Z,1697657721.748 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20231018T181931/Express0040.lzma 2023-10-18T19:35:22.750Z,1697657722.750 [DataOverHttps](INFO): Moved sent file to Logs/20231018T181931/Express0040.lzma.bak 2023-10-18T19:35:22.750Z,1697657722.750 [DataOverHttps](INFO): SBD MOMSN=19011540 2023-10-18T19:35:24.157Z,1697657724.157 [Default:CheckIn:Read_Iridium] Stopped 2023-10-18T19:35:24.157Z,1697657724.157 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-18T19:35:24.157Z,1697657724.157 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-18T19:35:27.778Z,1697657727.778 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-10-18T19:35:27.860Z,1697657727.860 [NAL9602](ERROR): received: +CSQ:0 OK450, 2, 0, 0, 0 OK 2023-10-18T19:36:18.279Z,1697657778.279 [NAL9602](INFO): SBD MO Status=2, MOMSN=45450, MT Status=2, MTMSN=0 2023-10-18T19:36:18.279Z,1697657778.279 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-10-18T19:37:15.319Z,1697657835.319 [NAL9602](INFO): SBD MO Status=2, MOMSN=45450, MT Status=2, MTMSN=0 2023-10-18T19:37:15.319Z,1697657835.319 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-10-18T19:37:29.863Z,1697657849.863 [NAL9602](INFO): SBD MO Status=2, MOMSN=45450, MT Status=2, MTMSN=0 2023-10-18T19:37:29.863Z,1697657849.863 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-10-18T19:37:47.640Z,1697657867.640 [NAL9602](INFO): SBD MO Status=2, MOMSN=45450, MT Status=2, MTMSN=0 2023-10-18T19:37:47.640Z,1697657867.640 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-10-18T19:37:58.543Z,1697657878.543 [NAL9602](INFO): SBD MO Status=0, MOMSN=45450, MT Status=0, MTMSN=0 2023-10-18T19:37:58.543Z,1697657878.543 [NAL9602](INFO): No messages in MT queue 2023-10-18T19:38:29.282Z,1697657909.282 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-18T19:40:24.881Z,1697658024.881 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-18T19:40:24.881Z,1697658024.881 [Default:CheckIn:C.Wait] Stopped 2023-10-18T19:40:24.881Z,1697658024.881 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-18T19:40:24.882Z,1697658024.882 [Default:CheckIn:D] Running Loop=1 2023-10-18T19:40:25.296Z,1697658025.296 [Default:CheckIn:D] Stopped 2023-10-18T19:40:25.296Z,1697658025.296 [Default:CheckIn:E] Running Loop=1 2023-10-18T19:40:25.670Z,1697658025.670 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 95.066496 min 2023-10-18T19:40:25.670Z,1697658025.670 [Default:CheckIn:E] Stopped 2023-10-18T19:40:25.670Z,1697658025.670 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-18T19:40:25.670Z,1697658025.670 [Default:CheckIn] Stopped 2023-10-18T19:40:25.670Z,1697658025.670 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-18T19:40:25.671Z,1697658025.671 [Default:CheckIn](INFO): Running loop #17 2023-10-18T19:40:25.671Z,1697658025.671 [Default:CheckIn] Running Loop=17 2023-10-18T19:40:25.671Z,1697658025.671 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-18T19:40:25.671Z,1697658025.671 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-18T19:40:27.667Z,1697658027.667 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194026.00,A,3648.17476,N,12147.28151,W,0.311,0.00,181023,,,A*7A 2023-10-18T19:40:27.678Z,1697658027.678 [NAL9602](INFO): GPS fix at 20231018T194026: (36.802913, -121.788025) 2023-10-18T19:40:27.688Z,1697658027.688 [Default:CheckIn:Read_GPS] Stopped 2023-10-18T19:40:27.688Z,1697658027.688 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-18T19:40:35.148Z,1697658035.148 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231018T181931/Courier0042.lzma 2023-10-18T19:40:36.150Z,1697658036.150 [DataOverHttps](INFO): Moved sent file to Logs/20231018T181931/Courier0042.lzma.bak 2023-10-18T19:40:36.150Z,1697658036.150 [DataOverHttps](INFO): SBD MOMSN=19011552 2023-10-18T19:40:44.743Z,1697658044.743 [NAL9602](INFO): SBD MO Status=0, MOMSN=45451, MT Status=0, MTMSN=0 2023-10-18T19:40:44.743Z,1697658044.743 [NAL9602](INFO): No messages in MT queue 2023-10-18T19:40:54.300Z,1697658054.300 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20231018T181931/Express0043.lzma 2023-10-18T19:40:55.303Z,1697658055.303 [DataOverHttps](INFO): Moved sent file to Logs/20231018T181931/Express0043.lzma.bak 2023-10-18T19:40:55.303Z,1697658055.303 [DataOverHttps](INFO): SBD MOMSN=19011555 2023-10-18T19:40:56.875Z,1697658056.875 [Default:CheckIn:Read_Iridium] Stopped 2023-10-18T19:40:56.875Z,1697658056.875 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-18T19:40:56.875Z,1697658056.875 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-18T19:41:15.436Z,1697658075.436 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-18T19:45:57.557Z,1697658357.557 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-18T19:45:57.557Z,1697658357.557 [Default:CheckIn:C.Wait] Stopped 2023-10-18T19:45:57.557Z,1697658357.557 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-18T19:45:57.558Z,1697658357.558 [Default:CheckIn:D] Running Loop=1 2023-10-18T19:45:57.976Z,1697658357.976 [Default:CheckIn:D] Stopped 2023-10-18T19:45:57.976Z,1697658357.976 [Default:CheckIn:E] Running Loop=1 2023-10-18T19:45:58.358Z,1697658358.358 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 100.611165 min 2023-10-18T19:45:58.358Z,1697658358.358 [Default:CheckIn:E] Stopped 2023-10-18T19:45:58.359Z,1697658358.359 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-18T19:45:58.359Z,1697658358.359 [Default:CheckIn] Stopped 2023-10-18T19:45:58.359Z,1697658358.359 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-18T19:45:58.359Z,1697658358.359 [Default:CheckIn](INFO): Running loop #18 2023-10-18T19:45:58.359Z,1697658358.359 [Default:CheckIn] Running Loop=18 2023-10-18T19:45:58.359Z,1697658358.359 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-18T19:45:58.359Z,1697658358.359 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-18T19:46:00.397Z,1697658360.397 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194559.00,A,3648.17117,N,12147.28406,W,0.369,0.00,181023,,,A*7D 2023-10-18T19:46:00.399Z,1697658360.399 [NAL9602](INFO): GPS fix at 20231018T194559: (36.802853, -121.788068) 2023-10-18T19:46:00.409Z,1697658360.409 [Default:CheckIn:Read_GPS] Stopped 2023-10-18T19:46:00.409Z,1697658360.409 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-18T19:46:07.636Z,1697658367.636 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231018T181931/Courier0045.lzma 2023-10-18T19:46:08.638Z,1697658368.638 [DataOverHttps](INFO): Moved sent file to Logs/20231018T181931/Courier0045.lzma.bak 2023-10-18T19:46:08.638Z,1697658368.638 [DataOverHttps](INFO): SBD MOMSN=19011578 2023-10-18T19:46:25.895Z,1697658385.895 [NAL9602](INFO): SBD MO Status=0, MOMSN=45452, MT Status=0, MTMSN=0 2023-10-18T19:46:25.895Z,1697658385.895 [NAL9602](INFO): No messages in MT queue 2023-10-18T19:46:27.184Z,1697658387.184 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20231018T181931/Express0046.lzma 2023-10-18T19:46:28.186Z,1697658388.186 [DataOverHttps](INFO): Moved sent file to Logs/20231018T181931/Express0046.lzma.bak 2023-10-18T19:46:28.186Z,1697658388.186 [DataOverHttps](INFO): SBD MOMSN=19011581 2023-10-18T19:46:29.584Z,1697658389.584 [Default:CheckIn:Read_Iridium] Stopped 2023-10-18T19:46:29.584Z,1697658389.584 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-18T19:46:29.584Z,1697658389.584 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-18T19:46:56.589Z,1697658416.589 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-18T19:51:30.137Z,1697658690.137 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-18T19:51:30.137Z,1697658690.137 [Default:CheckIn:C.Wait] Stopped 2023-10-18T19:51:30.137Z,1697658690.137 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-18T19:51:30.138Z,1697658690.138 [Default:CheckIn:D] Running Loop=1 2023-10-18T19:51:30.546Z,1697658690.546 [Default:CheckIn:D] Stopped 2023-10-18T19:51:30.546Z,1697658690.546 [Default:CheckIn:E] Running Loop=1 2023-10-18T19:51:30.949Z,1697658690.949 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 106.154004 min 2023-10-18T19:51:30.949Z,1697658690.949 [Default:CheckIn:E] Stopped 2023-10-18T19:51:30.949Z,1697658690.949 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-18T19:51:30.949Z,1697658690.949 [Default:CheckIn] Stopped 2023-10-18T19:51:30.949Z,1697658690.949 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-18T19:51:30.950Z,1697658690.950 [Default:CheckIn](INFO): Running loop #19 2023-10-18T19:51:30.950Z,1697658690.950 [Default:CheckIn] Running Loop=19 2023-10-18T19:51:30.950Z,1697658690.950 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-18T19:51:30.950Z,1697658690.950 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-18T19:51:32.956Z,1697658692.956 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195132.00,A,3648.17336,N,12147.27802,W,0.078,0.00,181023,,,A*70 2023-10-18T19:51:32.958Z,1697658692.958 [NAL9602](INFO): GPS fix at 20231018T195132: (36.802889, -121.787967) 2023-10-18T19:51:32.969Z,1697658692.969 [Default:CheckIn:Read_GPS] Stopped 2023-10-18T19:51:32.969Z,1697658692.969 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-18T19:51:40.044Z,1697658700.044 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231018T181931/Courier0048.lzma 2023-10-18T19:51:41.046Z,1697658701.046 [DataOverHttps](INFO): Moved sent file to Logs/20231018T181931/Courier0048.lzma.bak 2023-10-18T19:51:41.046Z,1697658701.046 [DataOverHttps](INFO): SBD MOMSN=19011590 2023-10-18T19:51:56.781Z,1697658716.781 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20231018T181931/Express0049.lzma 2023-10-18T19:51:57.782Z,1697658717.782 [DataOverHttps](INFO): Moved sent file to Logs/20231018T181931/Express0049.lzma.bak 2023-10-18T19:51:57.782Z,1697658717.782 [DataOverHttps](INFO): SBD MOMSN=19011596 2023-10-18T19:51:59.236Z,1697658719.236 [Default:CheckIn:Read_Iridium] Stopped 2023-10-18T19:51:59.236Z,1697658719.236 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-18T19:51:59.236Z,1697658719.236 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-18T19:54:37.994Z,1697658877.994 [NAL9602](INFO): SBD MO Status=0, MOMSN=45453, MT Status=0, MTMSN=0 2023-10-18T19:54:37.994Z,1697658877.994 [NAL9602](INFO): No messages in MT queue 2023-10-18T19:55:08.698Z,1697658908.698 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-18T19:56:59.815Z,1697659019.815 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-18T19:56:59.815Z,1697659019.815 [Default:CheckIn:C.Wait] Stopped 2023-10-18T19:56:59.815Z,1697659019.815 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-18T19:56:59.815Z,1697659019.815 [Default:CheckIn:D] Running Loop=1 2023-10-18T19:57:00.211Z,1697659020.211 [Default:CheckIn:D] Stopped 2023-10-18T19:57:00.211Z,1697659020.211 [Default:CheckIn:E] Running Loop=1 2023-10-18T19:57:00.622Z,1697659020.622 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 111.648413 min 2023-10-18T19:57:00.622Z,1697659020.622 [Default:CheckIn:E] Stopped 2023-10-18T19:57:00.622Z,1697659020.622 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-18T19:57:00.622Z,1697659020.622 [Default:CheckIn] Stopped 2023-10-18T19:57:00.622Z,1697659020.622 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-18T19:57:00.622Z,1697659020.622 [Default:CheckIn](INFO): Running loop #20 2023-10-18T19:57:00.622Z,1697659020.622 [Default:CheckIn] Running Loop=20 2023-10-18T19:57:00.623Z,1697659020.623 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-18T19:57:00.623Z,1697659020.623 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-18T19:57:02.635Z,1697659022.635 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195701.00,A,3648.17269,N,12147.27608,W,0.058,0.00,181023,,,A*7B 2023-10-18T19:57:02.638Z,1697659022.638 [NAL9602](INFO): GPS fix at 20231018T195701: (36.802878, -121.787935) 2023-10-18T19:57:02.648Z,1697659022.648 [Default:CheckIn:Read_GPS] Stopped 2023-10-18T19:57:02.648Z,1697659022.648 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-18T19:57:09.580Z,1697659029.580 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231018T181931/Courier0051.lzma 2023-10-18T19:57:10.582Z,1697659030.582 [DataOverHttps](INFO): Moved sent file to Logs/20231018T181931/Courier0051.lzma.bak 2023-10-18T19:57:10.582Z,1697659030.582 [DataOverHttps](INFO): SBD MOMSN=19011606 2023-10-18T19:57:13.543Z,1697659033.543 [NAL9602](INFO): SBD MO Status=0, MOMSN=45454, MT Status=0, MTMSN=0 2023-10-18T19:57:13.544Z,1697659033.544 [NAL9602](INFO): No messages in MT queue 2023-10-18T19:57:26.228Z,1697659046.228 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20231018T181931/Express0052.lzma 2023-10-18T19:57:27.230Z,1697659047.230 [DataOverHttps](INFO): Moved sent file to Logs/20231018T181931/Express0052.lzma.bak 2023-10-18T19:57:27.230Z,1697659047.230 [DataOverHttps](INFO): SBD MOMSN=19011614 2023-10-18T19:57:28.505Z,1697659048.505 [Default:CheckIn:Read_Iridium] Stopped 2023-10-18T19:57:28.505Z,1697659048.505 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-18T19:57:28.505Z,1697659048.505 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-18T19:57:44.246Z,1697659064.246 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-18T19:58:12.537Z,1697659092.537 [CBIT](IMPORTANT): Beginning ground fault scan 2023-10-18T19:58:15.369Z,1697659095.369 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes. 2023-10-18T19:58:23.472Z,1697659103.472 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.002367 CHAN A1 (24V) N/A reporting off CHAN A2 (12V): -0.002097 CHAN A3 (5V): -0.001604 CHAN B0 (3.3V): -0.000074 CHAN B1 (3.15aV): -0.000037 CHAN B2 (3.15bV): 0.000007 CHAN B3 (GND): 0.000070 OPEN: -0.000324 Full Scale: +/- 1 mA 2023-10-18T20:02:29.107Z,1697659349.107 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-18T20:02:29.107Z,1697659349.107 [Default:CheckIn:C.Wait] Stopped 2023-10-18T20:02:29.107Z,1697659349.107 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-18T20:02:29.107Z,1697659349.107 [Default:CheckIn:D] Running Loop=1 2023-10-18T20:02:29.511Z,1697659349.511 [Default:CheckIn:D] Stopped 2023-10-18T20:02:29.511Z,1697659349.511 [Default:CheckIn:E] Running Loop=1 2023-10-18T20:02:29.912Z,1697659349.912 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 117.136759 min 2023-10-18T20:02:29.912Z,1697659349.912 [Default:CheckIn:E] Stopped 2023-10-18T20:02:29.912Z,1697659349.912 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-18T20:02:29.912Z,1697659349.912 [Default:CheckIn] Stopped 2023-10-18T20:02:29.912Z,1697659349.912 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-18T20:02:29.913Z,1697659349.913 [Default:CheckIn](INFO): Running loop #21 2023-10-18T20:02:29.913Z,1697659349.913 [Default:CheckIn] Running Loop=21 2023-10-18T20:02:29.913Z,1697659349.913 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-18T20:02:29.913Z,1697659349.913 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-18T20:02:31.923Z,1697659351.923 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200231.00,A,3648.17598,N,12147.27664,W,0.350,0.00,181023,,,A*7A 2023-10-18T20:02:31.926Z,1697659351.926 [NAL9602](INFO): GPS fix at 20231018T200231: (36.802933, -121.787944) 2023-10-18T20:02:31.957Z,1697659351.957 [Default:CheckIn:Read_GPS] Stopped 2023-10-18T20:02:31.957Z,1697659351.957 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-18T20:02:38.960Z,1697659358.960 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231018T181931/Courier0054.lzma 2023-10-18T20:02:39.962Z,1697659359.962 [DataOverHttps](INFO): Moved sent file to Logs/20231018T181931/Courier0054.lzma.bak 2023-10-18T20:02:39.962Z,1697659359.962 [DataOverHttps](INFO): SBD MOMSN=19011625 2023-10-18T20:02:45.663Z,1697659365.663 [NAL9602](INFO): SBD MO Status=2, MOMSN=45455, MT Status=2, MTMSN=0 2023-10-18T20:02:45.663Z,1697659365.663 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-10-18T20:02:55.011Z,1697659375.011 [NAL9602](INFO): SBD MO Status=0, MOMSN=45455, MT Status=0, MTMSN=0 2023-10-18T20:02:55.011Z,1697659375.011 [NAL9602](INFO): No messages in MT queue 2023-10-18T20:02:58.693Z,1697659378.693 [DataOverHttps](INFO): Sending 337 bytes from file Logs/20231018T181931/Express0055.lzma 2023-10-18T20:02:59.686Z,1697659379.686 [DataOverHttps](INFO): Moved sent file to Logs/20231018T181931/Express0055.lzma.bak 2023-10-18T20:02:59.687Z,1697659379.687 [DataOverHttps](INFO): SBD MOMSN=19011630 2023-10-18T20:03:01.091Z,1697659381.091 [Default:CheckIn:Read_Iridium] Stopped 2023-10-18T20:03:01.091Z,1697659381.091 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-18T20:03:01.091Z,1697659381.091 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-18T20:03:25.716Z,1697659405.716 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-18T20:08:01.667Z,1697659681.667 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-18T20:08:01.667Z,1697659681.667 [Default:CheckIn:C.Wait] Stopped 2023-10-18T20:08:01.667Z,1697659681.667 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-18T20:08:01.668Z,1697659681.668 [Default:CheckIn:D] Running Loop=1 2023-10-18T20:08:02.073Z,1697659682.073 [Default:CheckIn:D] Stopped 2023-10-18T20:08:02.073Z,1697659682.073 [Default:CheckIn:E] Running Loop=1 2023-10-18T20:08:02.488Z,1697659682.488 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 122.679460 min 2023-10-18T20:08:02.489Z,1697659682.489 [Default:CheckIn:E] Stopped 2023-10-18T20:08:02.489Z,1697659682.489 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-18T20:08:02.489Z,1697659682.489 [Default:CheckIn] Stopped 2023-10-18T20:08:02.489Z,1697659682.489 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-18T20:08:02.489Z,1697659682.489 [Default:CheckIn](INFO): Running loop #22 2023-10-18T20:08:02.489Z,1697659682.489 [Default:CheckIn] Running Loop=22 2023-10-18T20:08:02.489Z,1697659682.489 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-18T20:08:02.489Z,1697659682.489 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-18T20:08:04.482Z,1697659684.482 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200803.00,A,3648.18077,N,12147.28820,W,0.233,302.48,181023,,,A*72 2023-10-18T20:08:04.485Z,1697659684.485 [NAL9602](INFO): GPS fix at 20231018T200803: (36.803013, -121.788137) 2023-10-18T20:08:04.495Z,1697659684.495 [Default:CheckIn:Read_GPS] Stopped 2023-10-18T20:08:04.495Z,1697659684.495 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-18T20:08:11.388Z,1697659691.388 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231018T181931/Courier0057.lzma 2023-10-18T20:08:12.390Z,1697659692.390 [DataOverHttps](INFO): Moved sent file to Logs/20231018T181931/Courier0057.lzma.bak 2023-10-18T20:08:12.390Z,1697659692.390 [DataOverHttps](INFO): SBD MOMSN=19011642 2023-10-18T20:08:31.700Z,1697659711.700 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20231018T181931/Express0058.lzma 2023-10-18T20:08:32.702Z,1697659712.702 [DataOverHttps](INFO): Moved sent file to Logs/20231018T181931/Express0058.lzma.bak 2023-10-18T20:08:32.702Z,1697659712.702 [DataOverHttps](INFO): SBD MOMSN=19011645 2023-10-18T20:08:34.021Z,1697659714.021 [Default:CheckIn:Read_Iridium] Stopped 2023-10-18T20:08:34.021Z,1697659714.021 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-18T20:08:34.022Z,1697659714.022 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-18T20:11:30.155Z,1697659890.155 [NAL9602](INFO): SBD MO Status=0, MOMSN=45456, MT Status=0, MTMSN=0 2023-10-18T20:11:30.155Z,1697659890.155 [NAL9602](INFO): No messages in MT queue 2023-10-18T20:12:00.856Z,1697659920.856 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-18T20:13:34.601Z,1697660014.601 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-18T20:13:34.601Z,1697660014.601 [Default:CheckIn:C.Wait] Stopped 2023-10-18T20:13:34.601Z,1697660014.601 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-18T20:13:34.602Z,1697660014.602 [Default:CheckIn:D] Running Loop=1 2023-10-18T20:13:35.001Z,1697660015.001 [Default:CheckIn:D] Stopped 2023-10-18T20:13:35.001Z,1697660015.001 [Default:CheckIn:E] Running Loop=1 2023-10-18T20:13:35.416Z,1697660015.416 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 128.228247 min 2023-10-18T20:13:35.416Z,1697660015.416 [Default:CheckIn:E] Stopped 2023-10-18T20:13:35.416Z,1697660015.416 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-18T20:13:35.416Z,1697660015.416 [Default:CheckIn] Stopped 2023-10-18T20:13:35.416Z,1697660015.416 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-18T20:13:35.417Z,1697660015.417 [Default:CheckIn](INFO): Running loop #23 2023-10-18T20:13:35.417Z,1697660015.417 [Default:CheckIn] Running Loop=23 2023-10-18T20:13:35.417Z,1697660015.417 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-18T20:13:35.417Z,1697660015.417 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-18T20:13:37.419Z,1697660017.419 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201336.00,A,3648.17393,N,12147.28372,W,0.330,302.48,181023,,,A*76 2023-10-18T20:13:37.422Z,1697660017.422 [NAL9602](INFO): GPS fix at 20231018T201336: (36.802899, -121.788062) 2023-10-18T20:13:37.452Z,1697660017.452 [Default:CheckIn:Read_GPS] Stopped 2023-10-18T20:13:37.452Z,1697660017.452 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-18T20:13:44.960Z,1697660024.960 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231018T181931/Courier0060.lzma 2023-10-18T20:13:45.962Z,1697660025.962 [DataOverHttps](INFO): Moved sent file to Logs/20231018T181931/Courier0060.lzma.bak 2023-10-18T20:13:45.962Z,1697660025.962 [DataOverHttps](INFO): SBD MOMSN=19011663 2023-10-18T20:13:51.159Z,1697660031.159 [NAL9602](INFO): SBD MO Status=0, MOMSN=45457, MT Status=0, MTMSN=0 2023-10-18T20:13:51.159Z,1697660031.159 [NAL9602](INFO): No messages in MT queue 2023-10-18T20:14:01.652Z,1697660041.652 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20231018T181931/Express0061.lzma 2023-10-18T20:14:02.655Z,1697660042.655 [DataOverHttps](INFO): Moved sent file to Logs/20231018T181931/Express0061.lzma.bak 2023-10-18T20:14:02.655Z,1697660042.655 [DataOverHttps](INFO): SBD MOMSN=19011667 2023-10-18T20:14:04.134Z,1697660044.134 [Default:CheckIn:Read_Iridium] Stopped 2023-10-18T20:14:04.134Z,1697660044.134 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-18T20:14:04.135Z,1697660044.135 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-18T20:14:21.577Z,1697660061.577 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-18T20:17:48.329Z,1697660268.329 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-10-18T20:18:02.873Z,1697660282.873 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-10-18T20:18:17.425Z,1697660297.425 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-10-18T20:18:31.151Z,1697660311.151 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-10-18T20:18:44.886Z,1697660324.886 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-10-18T20:18:59.028Z,1697660339.028 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-10-18T20:19:04.695Z,1697660344.695 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-18T20:19:04.695Z,1697660344.695 [Default:CheckIn:C.Wait] Stopped 2023-10-18T20:19:04.695Z,1697660344.695 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-18T20:19:04.695Z,1697660344.695 [Default:CheckIn:D] Running Loop=1 2023-10-18T20:19:05.103Z,1697660345.103 [Default:CheckIn:D] Stopped 2023-10-18T20:19:05.103Z,1697660345.103 [Default:CheckIn:E] Running Loop=1 2023-10-18T20:19:05.507Z,1697660345.507 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 133.729956 min 2023-10-18T20:19:05.507Z,1697660345.507 [Default:CheckIn:E] Stopped 2023-10-18T20:19:05.507Z,1697660345.507 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-18T20:19:05.507Z,1697660345.507 [Default:CheckIn] Stopped 2023-10-18T20:19:05.507Z,1697660345.507 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-18T20:19:05.508Z,1697660345.508 [Default:CheckIn](INFO): Running loop #24 2023-10-18T20:19:05.508Z,1697660345.508 [Default:CheckIn] Running Loop=24 2023-10-18T20:19:05.508Z,1697660345.508 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-18T20:19:05.508Z,1697660345.508 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-18T20:19:07.516Z,1697660347.516 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201906.00,A,3648.17387,N,12147.27746,W,0.136,301.06,181023,,,A*7B 2023-10-18T20:19:07.519Z,1697660347.519 [NAL9602](INFO): GPS fix at 20231018T201906: (36.802898, -121.787958) 2023-10-18T20:19:07.529Z,1697660347.529 [Default:CheckIn:Read_GPS] Stopped 2023-10-18T20:19:07.529Z,1697660347.529 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-18T20:19:13.167Z,1697660353.167 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-10-18T20:19:15.464Z,1697660355.464 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231018T181931/Courier0063.lzma 2023-10-18T20:19:16.466Z,1697660356.466 [DataOverHttps](INFO): Moved sent file to Logs/20231018T181931/Courier0063.lzma.bak 2023-10-18T20:19:16.466Z,1697660356.466 [DataOverHttps](INFO): SBD MOMSN=19011673 2023-10-18T20:19:26.903Z,1697660366.903 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-10-18T20:19:32.196Z,1697660372.196 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20231018T181931/Express0064.lzma 2023-10-18T20:19:33.198Z,1697660373.198 [DataOverHttps](INFO): Moved sent file to Logs/20231018T181931/Express0064.lzma.bak 2023-10-18T20:19:33.198Z,1697660373.198 [DataOverHttps](INFO): SBD MOMSN=19011681 2023-10-18T20:19:34.600Z,1697660374.600 [Default:CheckIn:Read_Iridium] Stopped 2023-10-18T20:19:34.600Z,1697660374.600 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-18T20:19:34.600Z,1697660374.600 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-18T20:19:40.640Z,1697660380.640 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-10-18T20:19:54.780Z,1697660394.780 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-10-18T20:20:21.495Z,1697660421.495 [NAL9602](INFO): SBD MO Status=0, MOMSN=45458, MT Status=0, MTMSN=0 2023-10-18T20:20:21.495Z,1697660421.495 [NAL9602](INFO): No messages in MT queue 2023-10-18T20:20:52.229Z,1697660452.229 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-18T20:22:30.811Z,1697660550.811 [BPC1](INFO): Calculating totals. Valid battery stick count: 40. Valid reserve battery stick count: 5. 2023-10-18T20:22:30.814Z,1697660550.814 [BPC1](INFO): Received data from all battery sticks. 2023-10-18T20:24:35.260Z,1697660675.260 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-18T20:24:35.260Z,1697660675.260 [Default:CheckIn:C.Wait] Stopped 2023-10-18T20:24:35.260Z,1697660675.260 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-18T20:24:35.260Z,1697660675.260 [Default:CheckIn:D] Running Loop=1 2023-10-18T20:24:35.675Z,1697660675.675 [Default:CheckIn:D] Stopped 2023-10-18T20:24:35.675Z,1697660675.675 [Default:CheckIn:E] Running Loop=1 2023-10-18T20:24:36.058Z,1697660676.058 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 139.239486 min 2023-10-18T20:24:36.059Z,1697660676.059 [Default:CheckIn:E] Stopped 2023-10-18T20:24:36.059Z,1697660676.059 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-18T20:24:36.059Z,1697660676.059 [Default:CheckIn] Stopped 2023-10-18T20:24:36.059Z,1697660676.059 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-18T20:24:36.059Z,1697660676.059 [Default:CheckIn](INFO): Running loop #25 2023-10-18T20:24:36.059Z,1697660676.059 [Default:CheckIn] Running Loop=25 2023-10-18T20:24:36.059Z,1697660676.059 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-18T20:24:36.059Z,1697660676.059 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-18T20:29:36.240Z,1697660976.240 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-10-18T20:24:36.1Z 2023-10-18T20:29:36.240Z,1697660976.240 [Default:CheckIn:Read_GPS] Stopped 2023-10-18T20:29:36.240Z,1697660976.240 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-18T20:29:40.270Z,1697660980.270 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-10-18T20:29:43.200Z,1697660983.200 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20231018T181931/Courier0066.lzma 2023-10-18T20:29:44.202Z,1697660984.202 [DataOverHttps](INFO): Moved sent file to Logs/20231018T181931/Courier0066.lzma.bak 2023-10-18T20:29:44.202Z,1697660984.202 [DataOverHttps](INFO): SBD MOMSN=19011691 2023-10-18T20:29:59.956Z,1697660999.956 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20231018T181931/Express0067.lzma 2023-10-18T20:30:00.958Z,1697661000.958 [DataOverHttps](INFO): Moved sent file to Logs/20231018T181931/Express0067.lzma.bak 2023-10-18T20:30:00.958Z,1697661000.958 [DataOverHttps](INFO): SBD MOMSN=19011695 2023-10-18T20:30:02.099Z,1697661002.099 [Default:CheckIn:Read_Iridium] Stopped 2023-10-18T20:30:02.099Z,1697661002.099 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-18T20:30:02.099Z,1697661002.099 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-18T20:30:08.121Z,1697661008.121 [CommandExec](IMPORTANT): got command failComponent 2023-10-18T20:30:08.121Z,1697661008.121 [CommandExec](IMPORTANT): Failed components: 2023-10-18T20:30:08.121Z,1697661008.121 [CommandExec](IMPORTANT): No failed Components. 2023-10-18T20:30:10.973Z,1697661010.973 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-18T20:30:11.254Z,1697661011.254 [CommandExec](IMPORTANT): got command burn off 2023-10-18T20:30:11.254Z,1697661011.254 [CommandExec](IMPORTANT): Deactivating dropweight wire 2023-10-18T20:30:17.150Z,1697661017.150 [CommandExec](IMPORTANT): got command get Onboard.Pressure 2023-10-18T20:30:17.150Z,1697661017.150 [CommandExec](IMPORTANT): Onboard.Pressure 66346.796875 Pa 2023-10-18T20:30:24.489Z,1697661024.489 [CommandExec](IMPORTANT): got command get Onboard.Humidity 2023-10-18T20:30:24.489Z,1697661024.489 [CommandExec](IMPORTANT): Onboard.Humidity 11.030399 % 2023-10-18T20:30:27.721Z,1697661027.721 [CommandExec](IMPORTANT): got command quit 2023-10-18T20:30:28.724Z,1697661028.724 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2023-10-18T20:30:28.724Z,1697661028.724 [CommandExec](INFO): Uninitializing the command executive. 2023-10-18T20:30:28.725Z,1697661028.725 [CommandExec](INFO): Uninitializing the command scheduler. 2023-10-18T20:30:28.725Z,1697661028.725 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-10-18T20:30:28.896Z,1697661028.896 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2023-10-18T20:30:28.896Z,1697661028.896 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2023-10-18T20:30:28.896Z,1697661028.896 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-10-18T20:30:28.897Z,1697661028.897 [NavChartDb](INFO): Join timeout helper Thread ID is 3450 2023-10-18T20:30:28.984Z,1697661028.984 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2023-10-18T20:30:28.985Z,1697661028.985 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-10-18T20:30:28.996Z,1697661028.996 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2023-10-18T20:30:28.996Z,1697661028.996 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-10-18T20:30:28.997Z,1697661028.997 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3451 2023-10-18T20:30:29.213Z,1697661029.213 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2023-10-18T20:30:29.213Z,1697661029.213 [WetLabsBB2FL](INFO): Powering down 2023-10-18T20:30:29.214Z,1697661029.214 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-10-18T20:30:29.220Z,1697661029.220 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2023-10-18T20:30:29.220Z,1697661029.220 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-10-18T20:30:29.221Z,1697661029.221 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3452 2023-10-18T20:30:29.640Z,1697661029.640 [CTD_Seabird](INFO): Powering down 2023-10-18T20:30:29.652Z,1697661029.652 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2023-10-18T20:30:29.652Z,1697661029.652 [CTD_Seabird](INFO): Powering down 2023-10-18T20:30:29.664Z,1697661029.664 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-10-18T20:30:29.676Z,1697661029.676 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2023-10-18T20:30:29.676Z,1697661029.676 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-10-18T20:30:29.677Z,1697661029.677 [Radio_Surface](INFO): Join timeout helper Thread ID is 3453 2023-10-18T20:30:29.857Z,1697661029.857 [Radio_Surface](INFO): Powering down 2023-10-18T20:30:29.858Z,1697661029.858 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2023-10-18T20:30:29.858Z,1697661029.858 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-10-18T20:30:29.864Z,1697661029.864 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2023-10-18T20:30:29.864Z,1697661029.864 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-10-18T20:30:29.865Z,1697661029.865 [Onboard](INFO): Join timeout helper Thread ID is 3454 2023-10-18T20:30:30.769Z,1697661030.769 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2023-10-18T20:30:32.616Z,1697661032.616 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2023-10-18T20:30:32.617Z,1697661032.617 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-10-18T20:30:32.629Z,1697661032.629 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2023-10-18T20:30:32.629Z,1697661032.629 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-10-18T20:30:32.630Z,1697661032.630 [DataOverHttps](INFO): Join timeout helper Thread ID is 3455 2023-10-18T20:30:32.776Z,1697661032.776 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2023-10-18T20:30:32.777Z,1697661032.777 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-10-18T20:30:32.789Z,1697661032.789 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2023-10-18T20:30:32.789Z,1697661032.789 [logger ThreadHandler](INFO): Thread cancelled. 2023-10-18T20:30:32.790Z,1697661032.790 [logger](INFO): Join timeout helper Thread ID is 3456 2023-10-18T20:30:32.808Z,1697661032.808 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2023-10-18T20:30:32.809Z,1697661032.809 [logger ThreadHandler](INFO): Thread cancelled. 2023-10-18T20:30:32.813Z,1697661032.813 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2023-10-18T20:30:32.813Z,1697661032.813 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-10-18T20:30:32.813Z,1697661032.813 [CommandLine](INFO): Join timeout helper Thread ID is 3457 2023-10-18T20:30:32.876Z,1697661032.876 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2023-10-18T20:30:32.877Z,1697661032.877 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-10-18T20:30:32.893Z,1697661032.893 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2023-10-18T20:30:32.893Z,1697661032.893 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-10-18T20:30:32.894Z,1697661032.894 [CommandExec](INFO): Join timeout helper Thread ID is 3458 2023-10-18T20:30:32.895Z,1697661032.895 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2023-10-18T20:30:32.895Z,1697661032.895 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-10-18T20:30:32.896Z,1697661032.896 [controlThread](INFO): Join timeout helper Thread ID is 3459 2023-10-18T20:30:33.176Z,1697661033.176 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2023-10-18T20:30:33.177Z,1697661033.177 [controlThread](DEBUG): Uninitializing ControlThread 2023-10-18T20:30:33.177Z,1697661033.177 [AHRS_M2](INFO): Powering down 2023-10-18T20:30:33.249Z,1697661033.249 [NAL9602](INFO): Powering down 2023-10-18T20:30:33.252Z,1697661033.252 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2023-10-18T20:30:33.253Z,1697661033.253 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2023-10-18T20:30:33.253Z,1697661033.253 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2023-10-18T20:30:33.254Z,1697661033.254 [MissionManager](INFO): Uninitializing Mission Default 2023-10-18T20:30:33.254Z,1697661033.254 [Default] Stopped 2023-10-18T20:30:33.254Z,1697661033.254 [Default](DEBUG): Aggregate::uninitialize Default 2023-10-18T20:30:33.254Z,1697661033.254 [Default:B.GoToSurface] Stopped 2023-10-18T20:30:33.254Z,1697661033.254 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-10-18T20:30:33.254Z,1697661033.254 [Default:CheckIn] Stopped 2023-10-18T20:30:33.254Z,1697661033.254 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-18T20:30:33.254Z,1697661033.254 [Default:CheckIn:C.Wait] Stopped 2023-10-18T20:30:33.254Z,1697661033.254 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-18T20:30:33.257Z,1697661033.257 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2023-10-18T20:30:33.258Z,1697661033.258 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2023-10-18T20:30:33.258Z,1697661033.258 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2023-10-18T20:30:33.258Z,1697661033.258 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2023-10-18T20:30:33.258Z,1697661033.258 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2023-10-18T20:30:33.258Z,1697661033.258 [BuoyancyServo](INFO): Powering down 2023-10-18T20:30:33.272Z,1697661033.272 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2023-10-18T20:30:33.273Z,1697661033.273 [ElevatorServo](INFO): Powering down 2023-10-18T20:30:33.273Z,1697661033.273 [MassServo](DEBUG): Uninitialize Mass Servo. 2023-10-18T20:30:33.274Z,1697661033.274 [MassServo](INFO): Powering down 2023-10-18T20:30:33.274Z,1697661033.274 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2023-10-18T20:30:33.274Z,1697661033.274 [RudderServo](INFO): Powering down 2023-10-18T20:30:33.275Z,1697661033.275 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2023-10-18T20:30:33.275Z,1697661033.275 [ThrusterHE](INFO): Powering down 2023-10-18T20:30:33.276Z,1697661033.276 [SBIT](DEBUG): Uninitialize SBIT Component. 2023-10-18T20:30:33.277Z,1697661033.277 [IBIT](DEBUG): Uninitialize IBIT Component. 2023-10-18T20:30:33.277Z,1697661033.277 [CBIT](DEBUG): Uninitialize CBIT Component. 2023-10-18T20:30:33.277Z,1697661033.277 [CBIT](DEBUG): Powering off loads. 2023-10-18T20:30:33.288Z,1697661033.288 [CBIT](DEBUG): Disabling WDT. 2023-10-18T20:30:33.300Z,1697661033.300 [CBIT](DEBUG): Opening all GF detection circuits. 2023-10-18T20:30:33.301Z,1697661033.301 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-10-18T20:30:33.354Z,1697661033.354 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-10-18T20:30:33.357Z,1697661033.357 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-10-18T20:30:33.363Z,1697661033.363 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-10-18T20:30:33.434Z,1697661033.434 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-10-18T20:30:33.438Z,1697661033.438 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-10-18T20:30:33.478Z,1697661033.478 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-10-18T20:30:33.585Z,1697661033.585 [logger ThreadHandler](INFO): Thread cancelled.