2023-04-18T16:36:03.277Z,1681835763.277 [CommandExec](IMPORTANT): got command restart logs 2023-04-18T16:37:15.057Z,1681835835.057 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-18T16:37:15.057Z,1681835835.057 [Default:CheckIn:C.Wait] Stopped 2023-04-18T16:37:15.057Z,1681835835.057 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-18T16:37:15.058Z,1681835835.058 [Default:CheckIn:D] Running Loop=1 2023-04-18T16:37:15.466Z,1681835835.466 [Default:CheckIn:D] Stopped 2023-04-18T16:37:15.467Z,1681835835.467 [Default:CheckIn:E] Running Loop=1 2023-04-18T16:37:15.864Z,1681835835.864 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 23.745477 min 2023-04-18T16:37:15.864Z,1681835835.864 [Default:CheckIn:E] Stopped 2023-04-18T16:37:15.864Z,1681835835.864 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-18T16:37:15.864Z,1681835835.864 [Default:CheckIn] Stopped 2023-04-18T16:37:15.864Z,1681835835.864 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-18T16:37:15.864Z,1681835835.864 [Default:CheckIn](INFO): Running loop #5 2023-04-18T16:37:15.864Z,1681835835.864 [Default:CheckIn] Running Loop=5 2023-04-18T16:37:15.865Z,1681835835.865 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-18T16:37:15.865Z,1681835835.865 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-18T16:37:17.874Z,1681835837.874 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163717.00,A,3646.12622,N,12150.99753,W,0.894,153.96,180423,,,D*79 2023-04-18T16:37:17.892Z,1681835837.892 [NAL9602](INFO): GPS fix at 20230418T163717: (36.768770, -121.849959) 2023-04-18T16:37:17.907Z,1681835837.907 [Default:CheckIn:Read_GPS] Stopped 2023-04-18T16:37:17.907Z,1681835837.907 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-18T16:37:25.103Z,1681835845.103 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230418T021906/Courier0148.lzma 2023-04-18T16:37:26.106Z,1681835846.106 [DataOverHttps](INFO): Moved sent file to Logs/20230418T021906/Courier0148.lzma.bak 2023-04-18T16:37:26.106Z,1681835846.106 [DataOverHttps](INFO): SBD MOMSN=18235458 2023-04-18T16:37:36.055Z,1681835856.055 [NAL9602](INFO): SBD MO Status=1, MOMSN=2760, MT Status=0, MTMSN=0 2023-04-18T16:37:36.055Z,1681835856.055 [NAL9602](INFO): No messages in MT queue 2023-04-18T16:37:48.052Z,1681835868.052 [DataOverHttps](INFO): Sending 268 bytes from file Logs/20230418T163603/Courier0000.lzma 2023-04-18T16:37:49.053Z,1681835869.053 [DataOverHttps](INFO): Moved sent file to Logs/20230418T163603/Courier0000.lzma.bak 2023-04-18T16:37:49.054Z,1681835869.054 [DataOverHttps](INFO): SBD MOMSN=18235460 2023-04-18T16:38:06.367Z,1681835886.367 [DataOverHttps](INFO): Sending 117 bytes from file Logs/20230418T021906/Express0149.lzma 2023-04-18T16:38:06.805Z,1681835886.805 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-18T16:38:07.370Z,1681835887.370 [DataOverHttps](INFO): Moved sent file to Logs/20230418T021906/Express0149.lzma.bak 2023-04-18T16:38:07.370Z,1681835887.370 [DataOverHttps](INFO): SBD MOMSN=18235464 2023-04-18T16:38:23.320Z,1681835903.320 [DataOverHttps](INFO): Sending 395 bytes from file Logs/20230418T163603/Express0001.lzma 2023-04-18T16:38:24.322Z,1681835904.322 [DataOverHttps](INFO): Moved sent file to Logs/20230418T163603/Express0001.lzma.bak 2023-04-18T16:38:24.322Z,1681835904.322 [DataOverHttps](INFO): SBD MOMSN=18235467 2023-04-18T16:38:45.563Z,1681835925.563 [DataOverHttps](INFO): Sending 52 bytes from file Logs/20230418T163603/Express0004.lzma 2023-04-18T16:38:46.566Z,1681835926.566 [DataOverHttps](INFO): Moved sent file to Logs/20230418T163603/Express0004.lzma.bak 2023-04-18T16:38:46.566Z,1681835926.566 [DataOverHttps](INFO): SBD MOMSN=18235471 2023-04-18T16:38:48.034Z,1681835928.034 [Default:CheckIn:Read_Iridium] Stopped 2023-04-18T16:38:48.034Z,1681835928.034 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-18T16:38:48.034Z,1681835928.034 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-18T16:41:23.166Z,1681836083.166 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2023-04-18T16:41:23.166Z,1681836083.166 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+12.7,0000.0,148 2023-04-18T16:43:48.601Z,1681836228.601 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-18T16:43:48.601Z,1681836228.601 [Default:CheckIn:C.Wait] Stopped 2023-04-18T16:43:48.601Z,1681836228.601 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-18T16:43:48.602Z,1681836228.602 [Default:CheckIn:D] Running Loop=1 2023-04-18T16:43:49.003Z,1681836229.003 [Default:CheckIn:D] Stopped 2023-04-18T16:43:49.003Z,1681836229.003 [Default:CheckIn:E] Running Loop=1 2023-04-18T16:43:49.413Z,1681836229.413 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 30.304417 min 2023-04-18T16:43:49.413Z,1681836229.413 [Default:CheckIn:E] Stopped 2023-04-18T16:43:49.413Z,1681836229.413 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-18T16:43:49.413Z,1681836229.413 [Default:CheckIn] Stopped 2023-04-18T16:43:49.413Z,1681836229.413 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-18T16:43:49.413Z,1681836229.413 [Default:CheckIn](INFO): Running loop #6 2023-04-18T16:43:49.413Z,1681836229.413 [Default:CheckIn] Running Loop=6 2023-04-18T16:43:49.413Z,1681836229.413 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-18T16:43:49.414Z,1681836229.414 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-18T16:43:51.423Z,1681836231.423 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164350.00,A,3646.08883,N,12150.94887,W,1.419,83.72,180423,,,D*42 2023-04-18T16:43:51.439Z,1681836231.439 [NAL9602](INFO): GPS fix at 20230418T164350: (36.768147, -121.849148) 2023-04-18T16:43:51.449Z,1681836231.449 [Default:CheckIn:Read_GPS] Stopped 2023-04-18T16:43:51.449Z,1681836231.449 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-18T16:44:04.346Z,1681836244.346 [NAL9602](INFO): SBD MO Status=1, MOMSN=2761, MT Status=0, MTMSN=0 2023-04-18T16:44:04.346Z,1681836244.346 [NAL9602](INFO): No messages in MT queue 2023-04-18T16:44:22.352Z,1681836262.352 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-18T16:44:35.054Z,1681836275.054 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-18T16:44:40.711Z,1681836280.711 [DVL_micro](ERROR): Failed to parse: 0000000000,35.0,+12.7,0000.0,1489.0,000 2023-04-18T16:44:53.480Z,1681836293.480 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-18T16:45:24.621Z,1681836324.621 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-18T16:45:55.752Z,1681836355.752 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-18T16:46:26.884Z,1681836386.884 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-18T16:46:31.905Z,1681836391.905 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.755585 2023-04-18T16:46:44.379Z,1681836404.379 [NAL9602](INFO): SBD MO Status=1, MOMSN=2762, MT Status=0, MTMSN=0 2023-04-18T16:46:44.436Z,1681836404.436 [NAL9602](INFO): Sent 73 bytes from file Logs/20230418T163603/Courier0006.lzma 2023-04-18T16:46:44.436Z,1681836404.436 [NAL9602](INFO): Packets left to send: 0 2023-04-18T16:46:54.989Z,1681836414.989 [NAL9602](INFO): SBD MO Status=1, MOMSN=2763, MT Status=0, MTMSN=0 2023-04-18T16:46:55.048Z,1681836415.048 [NAL9602](INFO): Sent 144 bytes from file Logs/20230418T163603/Express0007.lzma 2023-04-18T16:46:55.048Z,1681836415.048 [NAL9602](INFO): Packets left to send: 0 2023-04-18T16:47:03.052Z,1681836423.052 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-18T16:47:05.477Z,1681836425.477 [NAL9602](INFO): SBD MO Status=0, MOMSN=2764, MT Status=0, MTMSN=0 2023-04-18T16:47:05.580Z,1681836425.580 [Default:CheckIn:Read_Iridium] Stopped 2023-04-18T16:47:05.580Z,1681836425.580 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-18T16:47:05.580Z,1681836425.580 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-18T16:47:34.176Z,1681836454.176 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-18T16:47:36.164Z,1681836456.164 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-18T16:48:05.300Z,1681836485.300 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-18T16:48:26.285Z,1681836506.285 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2023-04-18T16:48:26.286Z,1681836506.286 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+12.9,089.0,000 2023-04-18T16:48:36.424Z,1681836516.424 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-18T16:49:07.556Z,1681836547.556 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-18T16:49:38.680Z,1681836578.680 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-18T16:50:09.804Z,1681836609.804 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-18T16:50:40.940Z,1681836640.940 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-18T16:50:55.529Z,1681836655.529 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003671 2023-04-18T16:51:03.430Z,1681836663.430 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2023-04-18T16:51:03.430Z,1681836663.430 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0000.0,1489.0,000 2023-04-18T16:52:06.064Z,1681836726.064 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-18T16:52:06.064Z,1681836726.064 [Default:CheckIn:C.Wait] Stopped 2023-04-18T16:52:06.064Z,1681836726.064 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-18T16:52:06.065Z,1681836726.065 [Default:CheckIn:D] Running Loop=1 2023-04-18T16:52:06.457Z,1681836726.457 [Default:CheckIn:D] Stopped 2023-04-18T16:52:06.457Z,1681836726.457 [Default:CheckIn:E] Running Loop=1 2023-04-18T16:52:06.876Z,1681836726.876 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 38.595321 min 2023-04-18T16:52:06.876Z,1681836726.876 [Default:CheckIn:E] Stopped 2023-04-18T16:52:06.876Z,1681836726.876 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-18T16:52:06.876Z,1681836726.876 [Default:CheckIn] Stopped 2023-04-18T16:52:06.876Z,1681836726.876 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-18T16:52:06.877Z,1681836726.877 [Default:CheckIn](INFO): Running loop #7 2023-04-18T16:52:06.877Z,1681836726.877 [Default:CheckIn] Running Loop=7 2023-04-18T16:52:06.877Z,1681836726.877 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-18T16:52:06.877Z,1681836726.877 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-18T16:52:08.879Z,1681836728.879 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165208.00,A,3647.70329,N,12148.53122,W,21.888,50.90,180423,,,D*7A 2023-04-18T16:52:08.881Z,1681836728.881 [NAL9602](INFO): GPS fix at 20230418T165208: (36.795055, -121.808854) 2023-04-18T16:52:08.892Z,1681836728.892 [Default:CheckIn:Read_GPS] Stopped 2023-04-18T16:52:08.892Z,1681836728.892 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-18T16:52:22.243Z,1681836742.243 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230418T163603/Courier0009.lzma 2023-04-18T16:52:23.246Z,1681836743.246 [DataOverHttps](INFO): Moved sent file to Logs/20230418T163603/Courier0009.lzma.bak 2023-04-18T16:52:23.246Z,1681836743.246 [DataOverHttps](INFO): SBD MOMSN=18235501 2023-04-18T16:52:27.464Z,1681836747.464 [NAL9602](INFO): SBD MO Status=0, MOMSN=2765, MT Status=0, MTMSN=0 2023-04-18T16:52:27.464Z,1681836747.464 [NAL9602](INFO): No messages in MT queue 2023-04-18T16:52:42.496Z,1681836762.496 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20230418T163603/Express0010.lzma 2023-04-18T16:52:43.498Z,1681836763.498 [DataOverHttps](INFO): Moved sent file to Logs/20230418T163603/Express0010.lzma.bak 2023-04-18T16:52:43.498Z,1681836763.498 [DataOverHttps](INFO): SBD MOMSN=18235504 2023-04-18T16:52:44.930Z,1681836764.930 [Default:CheckIn:Read_Iridium] Stopped 2023-04-18T16:52:44.930Z,1681836764.930 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-18T16:52:44.930Z,1681836764.930 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-18T16:52:58.238Z,1681836778.238 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-18T16:53:43.527Z,1681836823.527 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2023-04-18T16:53:43.527Z,1681836823.527 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+12.8,489.0,000 2023-04-18T16:54:13.786Z,1681836853.786 [DVL_micro](ERROR): Failed to parse: :T00000000,35.0,+12.8,0000.0,00 2023-04-18T16:57:45.487Z,1681837065.487 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-18T16:57:45.487Z,1681837065.487 [Default:CheckIn:C.Wait] Stopped 2023-04-18T16:57:45.487Z,1681837065.487 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-18T16:57:45.487Z,1681837065.487 [Default:CheckIn:D] Running Loop=1 2023-04-18T16:57:45.895Z,1681837065.895 [Default:CheckIn:D] Stopped 2023-04-18T16:57:45.896Z,1681837065.896 [Default:CheckIn:E] Running Loop=1 2023-04-18T16:57:46.294Z,1681837066.293 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 44.252625 min 2023-04-18T16:57:46.294Z,1681837066.294 [Default:CheckIn:E] Stopped 2023-04-18T16:57:46.294Z,1681837066.294 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-18T16:57:46.294Z,1681837066.294 [Default:CheckIn] Stopped 2023-04-18T16:57:46.294Z,1681837066.294 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-18T16:57:46.294Z,1681837066.294 [Default:CheckIn](INFO): Running loop #8 2023-04-18T16:57:46.294Z,1681837066.294 [Default:CheckIn] Running Loop=8 2023-04-18T16:57:46.294Z,1681837066.294 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-18T16:57:46.294Z,1681837066.294 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-18T16:57:48.309Z,1681837068.309 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165747.00,A,3648.45140,N,12147.11852,W,6.045,187.72,180423,,,D*7C 2023-04-18T16:57:48.311Z,1681837068.311 [NAL9602](INFO): GPS fix at 20230418T165747: (36.807523, -121.785309) 2023-04-18T16:57:48.321Z,1681837068.321 [Default:CheckIn:Read_GPS] Stopped 2023-04-18T16:57:48.322Z,1681837068.322 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-18T16:57:56.043Z,1681837076.043 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230418T163603/Courier0012.lzma 2023-04-18T16:57:57.046Z,1681837077.046 [DataOverHttps](INFO): Moved sent file to Logs/20230418T163603/Courier0012.lzma.bak 2023-04-18T16:57:57.046Z,1681837077.046 [DataOverHttps](INFO): SBD MOMSN=18235518 2023-04-18T16:58:13.003Z,1681837093.003 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20230418T163603/Express0013.lzma 2023-04-18T16:58:14.006Z,1681837094.006 [DataOverHttps](INFO): Moved sent file to Logs/20230418T163603/Express0013.lzma.bak 2023-04-18T16:58:14.006Z,1681837094.006 [DataOverHttps](INFO): SBD MOMSN=18235521 2023-04-18T16:58:15.397Z,1681837095.397 [Default:CheckIn:Read_Iridium] Stopped 2023-04-18T16:58:15.398Z,1681837095.398 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-18T16:58:15.398Z,1681837095.398 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-18T16:58:30.734Z,1681837110.734 [NAL9602](INFO): SBD MO Status=0, MOMSN=2766, MT Status=0, MTMSN=0 2023-04-18T16:58:30.734Z,1681837110.734 [NAL9602](INFO): No messages in MT queue 2023-04-18T16:59:01.427Z,1681837141.427 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-18T17:03:15.958Z,1681837395.958 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-18T17:03:15.958Z,1681837395.958 [Default:CheckIn:C.Wait] Stopped 2023-04-18T17:03:15.958Z,1681837395.958 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-18T17:03:15.958Z,1681837395.958 [Default:CheckIn:D] Running Loop=1 2023-04-18T17:03:16.443Z,1681837396.443 [Default:CheckIn:D] Stopped 2023-04-18T17:03:16.443Z,1681837396.443 [Default:CheckIn:E] Running Loop=1 2023-04-18T17:03:16.857Z,1681837396.857 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 49.761751 min 2023-04-18T17:03:16.857Z,1681837396.857 [Default:CheckIn:E] Stopped 2023-04-18T17:03:16.858Z,1681837396.858 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-18T17:03:16.858Z,1681837396.858 [Default:CheckIn] Stopped 2023-04-18T17:03:16.858Z,1681837396.858 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-18T17:03:16.858Z,1681837396.858 [Default:CheckIn](INFO): Running loop #9 2023-04-18T17:03:16.858Z,1681837396.858 [Default:CheckIn] Running Loop=9 2023-04-18T17:03:16.858Z,1681837396.858 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-18T17:03:16.858Z,1681837396.858 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-18T17:03:18.795Z,1681837398.795 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170318.00,A,3648.13678,N,12147.20495,W,0.039,231.14,180423,,,D*7F 2023-04-18T17:03:18.798Z,1681837398.798 [NAL9602](INFO): GPS fix at 20230418T170318: (36.802280, -121.786749) 2023-04-18T17:03:18.807Z,1681837398.807 [Default:CheckIn:Read_GPS] Stopped 2023-04-18T17:03:18.808Z,1681837398.808 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-18T17:03:25.951Z,1681837405.951 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230418T163603/Courier0015.lzma 2023-04-18T17:03:26.954Z,1681837406.954 [DataOverHttps](INFO): Moved sent file to Logs/20230418T163603/Courier0015.lzma.bak 2023-04-18T17:03:26.954Z,1681837406.954 [DataOverHttps](INFO): SBD MOMSN=18235531 2023-04-18T17:03:33.774Z,1681837413.774 [NAL9602](INFO): SBD MO Status=0, MOMSN=2767, MT Status=0, MTMSN=0 2023-04-18T17:03:33.774Z,1681837413.774 [NAL9602](INFO): No messages in MT queue 2023-04-18T17:03:44.120Z,1681837424.120 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2023-04-18T17:03:44.120Z,1681837424.120 [DVL_micro](ERROR): Failed to parse: :TS,00000000000,+13.1,0000.0,1489.0,000 2023-04-18T17:03:46.960Z,1681837426.960 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20230418T163603/Express0016.lzma 2023-04-18T17:03:47.962Z,1681837427.962 [DataOverHttps](INFO): Moved sent file to Logs/20230418T163603/Express0016.lzma.bak 2023-04-18T17:03:47.962Z,1681837427.962 [DataOverHttps](INFO): SBD MOMSN=18235534 2023-04-18T17:03:49.162Z,1681837429.162 [Default:CheckIn:Read_Iridium] Stopped 2023-04-18T17:03:49.163Z,1681837429.163 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-18T17:03:49.163Z,1681837429.163 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-18T17:03:51.961Z,1681837431.961 [DVL_micro](ERROR): only read 3 of 4 data items 2023-04-18T17:03:51.961Z,1681837431.961 [DVL_micro](ERROR): Failed to parse: :BI,+05229,+01270,-0000,I 2023-04-18T17:04:04.474Z,1681837444.474 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-18T17:06:25.065Z,1681837585.065 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2023-04-18T17:06:25.067Z,1681837585.067 [BPC1](INFO): Received data from all battery sticks. 2023-04-18T17:06:59.412Z,1681837619.412 [DVL_micro](ERROR): only read 3 of 4 data items 2023-04-18T17:06:59.412Z,1681837619.412 [DVL_micro](ERROR): Failed to parse: :BI,+01098,+00772,-02576I 2023-04-18T17:08:49.703Z,1681837729.703 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-18T17:08:49.704Z,1681837729.704 [Default:CheckIn:C.Wait] Stopped 2023-04-18T17:08:49.704Z,1681837729.704 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-18T17:08:49.705Z,1681837729.705 [Default:CheckIn:D] Running Loop=1 2023-04-18T17:08:50.115Z,1681837730.115 [Default:CheckIn:D] Stopped 2023-04-18T17:08:50.115Z,1681837730.115 [Default:CheckIn:E] Running Loop=1 2023-04-18T17:08:50.513Z,1681837730.513 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 55.322945 min 2023-04-18T17:08:50.513Z,1681837730.513 [Default:CheckIn:E] Stopped 2023-04-18T17:08:50.514Z,1681837730.514 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-18T17:08:50.514Z,1681837730.514 [Default:CheckIn] Stopped 2023-04-18T17:08:50.514Z,1681837730.514 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-18T17:08:50.514Z,1681837730.514 [Default:CheckIn](INFO): Running loop #10 2023-04-18T17:08:50.514Z,1681837730.514 [Default:CheckIn] Running Loop=10 2023-04-18T17:08:50.514Z,1681837730.514 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-18T17:08:50.514Z,1681837730.514 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-18T17:08:52.527Z,1681837732.527 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170851.00,A,3648.14224,N,12147.20813,W,0.019,328.63,180423,,,D*7A 2023-04-18T17:08:52.529Z,1681837732.529 [NAL9602](INFO): GPS fix at 20230418T170851: (36.802371, -121.786802) 2023-04-18T17:08:52.539Z,1681837732.539 [Default:CheckIn:Read_GPS] Stopped 2023-04-18T17:08:52.539Z,1681837732.539 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-18T17:08:59.720Z,1681837739.720 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20230418T163603/Courier0018.lzma 2023-04-18T17:09:00.722Z,1681837740.722 [DataOverHttps](INFO): Moved sent file to Logs/20230418T163603/Courier0018.lzma.bak 2023-04-18T17:09:00.722Z,1681837740.722 [DataOverHttps](INFO): SBD MOMSN=18235549 2023-04-18T17:09:14.743Z,1681837754.743 [NAL9602](INFO): SBD MO Status=2, MOMSN=2768, MT Status=2, MTMSN=0 2023-04-18T17:09:14.743Z,1681837754.743 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-04-18T17:09:16.771Z,1681837756.771 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230418T163603/Express0019.lzma 2023-04-18T17:09:17.774Z,1681837757.774 [DataOverHttps](INFO): Moved sent file to Logs/20230418T163603/Express0019.lzma.bak 2023-04-18T17:09:17.774Z,1681837757.774 [DataOverHttps](INFO): SBD MOMSN=18235552 2023-04-18T17:09:19.223Z,1681837759.223 [Default:CheckIn:Read_Iridium] Stopped 2023-04-18T17:09:19.223Z,1681837759.223 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-18T17:09:19.223Z,1681837759.223 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-18T17:09:43.021Z,1681837783.021 [NAL9602](INFO): SBD MO Status=2, MOMSN=2768, MT Status=2, MTMSN=0 2023-04-18T17:09:43.021Z,1681837783.021 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-04-18T17:10:15.343Z,1681837815.343 [NAL9602](INFO): SBD MO Status=2, MOMSN=2768, MT Status=2, MTMSN=0 2023-04-18T17:10:15.343Z,1681837815.343 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-04-18T17:10:33.938Z,1681837833.938 [NAL9602](INFO): SBD MO Status=0, MOMSN=2768, MT Status=0, MTMSN=0 2023-04-18T17:10:33.938Z,1681837833.938 [NAL9602](INFO): No messages in MT queue 2023-04-18T17:11:04.628Z,1681837864.628 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-18T17:13:32.103Z,1681838012.103 [DVL_micro](ERROR): only read 3 of 4 data items 2023-04-18T17:13:32.108Z,1681838012.108 [DVL_micro](ERROR): Failed to parse: :BI,+0371,-01875,+00000,I 2023-04-18T17:14:19.775Z,1681838059.775 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-18T17:14:19.775Z,1681838059.775 [Default:CheckIn:C.Wait] Stopped 2023-04-18T17:14:19.775Z,1681838059.775 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-18T17:14:19.776Z,1681838059.776 [Default:CheckIn:D] Running Loop=1 2023-04-18T17:14:20.205Z,1681838060.205 [Default:CheckIn:D] Stopped 2023-04-18T17:14:20.205Z,1681838060.205 [Default:CheckIn:E] Running Loop=1 2023-04-18T17:14:20.581Z,1681838060.581 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 60.824451 min 2023-04-18T17:14:20.581Z,1681838060.581 [Default:CheckIn:E] Stopped 2023-04-18T17:14:20.581Z,1681838060.581 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-18T17:14:20.581Z,1681838060.581 [Default:CheckIn] Stopped 2023-04-18T17:14:20.581Z,1681838060.581 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-18T17:14:20.582Z,1681838060.582 [Default:CheckIn](INFO): Running loop #11 2023-04-18T17:14:20.582Z,1681838060.582 [Default:CheckIn] Running Loop=11 2023-04-18T17:14:20.582Z,1681838060.582 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-18T17:14:20.582Z,1681838060.582 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-18T17:14:22.594Z,1681838062.594 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171421.00,A,3648.14190,N,12147.20820,W,0.039,222.37,180423,,,D*74 2023-04-18T17:14:22.596Z,1681838062.596 [NAL9602](INFO): GPS fix at 20230418T171421: (36.802365, -121.786803) 2023-04-18T17:14:22.606Z,1681838062.606 [Default:CheckIn:Read_GPS] Stopped 2023-04-18T17:14:22.606Z,1681838062.606 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-18T17:14:29.995Z,1681838069.995 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230418T163603/Courier0021.lzma 2023-04-18T17:14:30.998Z,1681838070.998 [DataOverHttps](INFO): Moved sent file to Logs/20230418T163603/Courier0021.lzma.bak 2023-04-18T17:14:30.998Z,1681838070.998 [DataOverHttps](INFO): SBD MOMSN=18235563 2023-04-18T17:14:42.843Z,1681838082.843 [NAL9602](INFO): SBD MO Status=2, MOMSN=2769, MT Status=2, MTMSN=0 2023-04-18T17:14:42.843Z,1681838082.843 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-04-18T17:14:49.775Z,1681838089.775 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230418T163603/Express0022.lzma 2023-04-18T17:14:50.778Z,1681838090.778 [DataOverHttps](INFO): Moved sent file to Logs/20230418T163603/Express0022.lzma.bak 2023-04-18T17:14:50.778Z,1681838090.778 [DataOverHttps](INFO): SBD MOMSN=18235566 2023-04-18T17:14:52.152Z,1681838092.152 [Default:CheckIn:Read_Iridium] Stopped 2023-04-18T17:14:52.152Z,1681838092.152 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-18T17:14:52.152Z,1681838092.152 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-18T17:15:09.911Z,1681838109.911 [NAL9602](INFO): SBD MO Status=0, MOMSN=2769, MT Status=0, MTMSN=0 2023-04-18T17:15:09.911Z,1681838109.911 [NAL9602](INFO): No messages in MT queue 2023-04-18T17:15:40.611Z,1681838140.611 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-18T17:18:36.368Z,1681838316.368 [DVL_micro](ERROR): only read 3 of 4 data items 2023-04-18T17:18:36.369Z,1681838316.369 [DVL_micro](ERROR): Failed to parse: :RD,+99999.99,+9999.99,+9999.99 2023-04-18T17:19:52.718Z,1681838392.718 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-18T17:19:52.718Z,1681838392.718 [Default:CheckIn:C.Wait] Stopped 2023-04-18T17:19:52.718Z,1681838392.718 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-18T17:19:52.719Z,1681838392.719 [Default:CheckIn:D] Running Loop=1 2023-04-18T17:19:53.128Z,1681838393.128 [Default:CheckIn:D] Stopped 2023-04-18T17:19:53.128Z,1681838393.128 [Default:CheckIn:E] Running Loop=1 2023-04-18T17:19:53.525Z,1681838393.525 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 66.373161 min 2023-04-18T17:19:53.525Z,1681838393.525 [Default:CheckIn:E] Stopped 2023-04-18T17:19:53.526Z,1681838393.526 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-18T17:19:53.526Z,1681838393.526 [Default:CheckIn] Stopped 2023-04-18T17:19:53.526Z,1681838393.526 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-18T17:19:53.526Z,1681838393.526 [Default:CheckIn](INFO): Running loop #12 2023-04-18T17:19:53.526Z,1681838393.526 [Default:CheckIn] Running Loop=12 2023-04-18T17:19:53.526Z,1681838393.526 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-18T17:19:53.526Z,1681838393.526 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-18T17:19:55.538Z,1681838395.538 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171954.00,A,3648.14122,N,12147.20763,W,0.078,183.42,180423,,,A*70 2023-04-18T17:19:55.540Z,1681838395.540 [NAL9602](INFO): GPS fix at 20230418T171954: (36.802354, -121.786794) 2023-04-18T17:19:55.550Z,1681838395.550 [Default:CheckIn:Read_GPS] Stopped 2023-04-18T17:19:55.550Z,1681838395.550 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-18T17:20:03.323Z,1681838403.323 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230418T163603/Courier0024.lzma 2023-04-18T17:20:04.326Z,1681838404.326 [DataOverHttps](INFO): Moved sent file to Logs/20230418T163603/Courier0024.lzma.bak 2023-04-18T17:20:04.326Z,1681838404.326 [DataOverHttps](INFO): SBD MOMSN=18235587 2023-04-18T17:20:20.167Z,1681838420.167 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230418T163603/Express0025.lzma 2023-04-18T17:20:20.993Z,1681838420.993 [NAL9602](INFO): SBD MO Status=2, MOMSN=2770, MT Status=2, MTMSN=0 2023-04-18T17:20:20.993Z,1681838420.993 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-04-18T17:20:21.170Z,1681838421.170 [DataOverHttps](INFO): Moved sent file to Logs/20230418T163603/Express0025.lzma.bak 2023-04-18T17:20:21.170Z,1681838421.170 [DataOverHttps](INFO): SBD MOMSN=18235590 2023-04-18T17:20:22.643Z,1681838422.643 [Default:CheckIn:Read_Iridium] Stopped 2023-04-18T17:20:22.643Z,1681838422.643 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-18T17:20:22.643Z,1681838422.643 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-18T17:20:34.727Z,1681838434.727 [NAL9602](INFO): SBD MO Status=2, MOMSN=2770, MT Status=2, MTMSN=0 2023-04-18T17:20:34.727Z,1681838434.727 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-04-18T17:21:06.243Z,1681838466.243 [NAL9602](INFO): SBD MO Status=0, MOMSN=2770, MT Status=0, MTMSN=0 2023-04-18T17:21:06.243Z,1681838466.243 [NAL9602](INFO): No messages in MT queue 2023-04-18T17:21:36.941Z,1681838496.941 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-18T17:25:23.198Z,1681838723.198 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-18T17:25:23.198Z,1681838723.198 [Default:CheckIn:C.Wait] Stopped 2023-04-18T17:25:23.198Z,1681838723.198 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-18T17:25:23.198Z,1681838723.198 [Default:CheckIn:D] Running Loop=1 2023-04-18T17:25:23.602Z,1681838723.602 [Default:CheckIn:D] Stopped 2023-04-18T17:25:23.602Z,1681838723.602 [Default:CheckIn:E] Running Loop=1 2023-04-18T17:25:24.011Z,1681838724.011 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 71.881063 min 2023-04-18T17:25:24.011Z,1681838724.011 [Default:CheckIn:E] Stopped 2023-04-18T17:25:24.011Z,1681838724.011 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-18T17:25:24.011Z,1681838724.011 [Default:CheckIn] Stopped 2023-04-18T17:25:24.012Z,1681838724.012 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-18T17:25:24.012Z,1681838724.012 [Default:CheckIn](INFO): Running loop #13 2023-04-18T17:25:24.012Z,1681838724.012 [Default:CheckIn] Running Loop=13 2023-04-18T17:25:24.012Z,1681838724.012 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-18T17:25:24.012Z,1681838724.012 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-18T17:25:26.018Z,1681838726.018 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172525.00,A,3648.14231,N,12147.20865,W,0.097,316.91,180423,,,A*70 2023-04-18T17:25:26.032Z,1681838726.032 [NAL9602](INFO): GPS fix at 20230418T172525: (36.802372, -121.786811) 2023-04-18T17:25:26.050Z,1681838726.050 [Default:CheckIn:Read_GPS] Stopped 2023-04-18T17:25:26.050Z,1681838726.050 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-18T17:25:33.927Z,1681838733.927 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230418T163603/Courier0027.lzma 2023-04-18T17:25:34.930Z,1681838734.930 [DataOverHttps](INFO): Moved sent file to Logs/20230418T163603/Courier0027.lzma.bak 2023-04-18T17:25:34.930Z,1681838734.930 [DataOverHttps](INFO): SBD MOMSN=18235602 2023-04-18T17:25:50.302Z,1681838750.302 [NAL9602](INFO): SBD MO Status=2, MOMSN=2771, MT Status=2, MTMSN=0 2023-04-18T17:25:50.303Z,1681838750.303 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-04-18T17:25:53.983Z,1681838753.983 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230418T163603/Express0028.lzma 2023-04-18T17:25:55.005Z,1681838755.005 [DataOverHttps](INFO): Moved sent file to Logs/20230418T163603/Express0028.lzma.bak 2023-04-18T17:25:55.005Z,1681838755.005 [DataOverHttps](INFO): SBD MOMSN=18235605 2023-04-18T17:25:56.392Z,1681838756.392 [Default:CheckIn:Read_Iridium] Stopped 2023-04-18T17:25:56.392Z,1681838756.392 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-18T17:25:56.392Z,1681838756.392 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-18T17:26:42.018Z,1681838802.018 [NAL9602](INFO): SBD MO Status=2, MOMSN=2771, MT Status=2, MTMSN=0 2023-04-18T17:26:42.018Z,1681838802.018 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-04-18T17:27:23.687Z,1681838843.687 [NAL9602](INFO): SBD MO Status=2, MOMSN=2771, MT Status=2, MTMSN=0 2023-04-18T17:27:23.687Z,1681838843.687 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-04-18T17:27:44.283Z,1681838864.283 [NAL9602](INFO): SBD MO Status=2, MOMSN=2771, MT Status=2, MTMSN=0 2023-04-18T17:27:44.283Z,1681838864.283 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-04-18T17:28:02.867Z,1681838882.867 [NAL9602](INFO): SBD MO Status=0, MOMSN=2771, MT Status=0, MTMSN=0 2023-04-18T17:28:02.867Z,1681838882.867 [NAL9602](INFO): No messages in MT queue 2023-04-18T17:28:33.569Z,1681838913.569 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-18T17:29:55.587Z,1681838995.587 [DVL_micro](ERROR): only read 1 of 4 data items 2023-04-18T17:29:55.587Z,1681838995.587 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99,,+9999.99, 2023-04-18T17:30:57.057Z,1681839057.057 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-18T17:30:57.057Z,1681839057.057 [Default:CheckIn:C.Wait] Stopped 2023-04-18T17:30:57.057Z,1681839057.057 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-18T17:30:57.058Z,1681839057.058 [Default:CheckIn:D] Running Loop=1 2023-04-18T17:30:57.453Z,1681839057.453 [Default:CheckIn:D] Stopped 2023-04-18T17:30:57.453Z,1681839057.453 [Default:CheckIn:E] Running Loop=1 2023-04-18T17:30:57.861Z,1681839057.861 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 77.445256 min 2023-04-18T17:30:57.861Z,1681839057.861 [Default:CheckIn:E] Stopped 2023-04-18T17:30:57.861Z,1681839057.861 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-18T17:30:57.861Z,1681839057.861 [Default:CheckIn] Stopped 2023-04-18T17:30:57.862Z,1681839057.862 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-18T17:30:57.862Z,1681839057.862 [Default:CheckIn](INFO): Running loop #14 2023-04-18T17:30:57.862Z,1681839057.862 [Default:CheckIn] Running Loop=14 2023-04-18T17:30:57.862Z,1681839057.862 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-18T17:30:57.862Z,1681839057.862 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-18T17:30:59.871Z,1681839059.871 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173059.00,A,3648.14084,N,12147.20855,W,0.019,215.75,180423,,,D*7B 2023-04-18T17:30:59.873Z,1681839059.873 [NAL9602](INFO): GPS fix at 20230418T173059: (36.802347, -121.786809) 2023-04-18T17:30:59.883Z,1681839059.883 [Default:CheckIn:Read_GPS] Stopped 2023-04-18T17:30:59.883Z,1681839059.883 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-18T17:31:07.895Z,1681839067.895 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230418T163603/Courier0030.lzma 2023-04-18T17:31:08.898Z,1681839068.898 [DataOverHttps](INFO): Moved sent file to Logs/20230418T163603/Courier0030.lzma.bak 2023-04-18T17:31:08.898Z,1681839068.898 [DataOverHttps](INFO): SBD MOMSN=18235659 2023-04-18T17:31:24.991Z,1681839084.991 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230418T163603/Express0031.lzma 2023-04-18T17:31:25.994Z,1681839085.994 [DataOverHttps](INFO): Moved sent file to Logs/20230418T163603/Express0031.lzma.bak 2023-04-18T17:31:25.994Z,1681839085.994 [DataOverHttps](INFO): SBD MOMSN=18235663 2023-04-18T17:31:26.530Z,1681839086.530 [NAL9602](INFO): SBD MO Status=0, MOMSN=2772, MT Status=0, MTMSN=0 2023-04-18T17:31:26.530Z,1681839086.530 [NAL9602](INFO): No messages in MT queue 2023-04-18T17:31:27.363Z,1681839087.363 [Default:CheckIn:Read_Iridium] Stopped 2023-04-18T17:31:27.363Z,1681839087.363 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-18T17:31:27.363Z,1681839087.363 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-18T17:31:57.245Z,1681839117.245 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-18T17:33:20.914Z,1681839200.914 [DVL_micro](ERROR): Failed to parse: :BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00 2023-04-18T17:36:28.134Z,1681839388.134 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-18T17:36:28.134Z,1681839388.134 [Default:CheckIn:C.Wait] Stopped 2023-04-18T17:36:28.134Z,1681839388.134 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-18T17:36:28.134Z,1681839388.134 [Default:CheckIn:D] Running Loop=1 2023-04-18T17:36:28.521Z,1681839388.521 [Default:CheckIn:D] Stopped 2023-04-18T17:36:28.521Z,1681839388.521 [Default:CheckIn:E] Running Loop=1 2023-04-18T17:36:28.930Z,1681839388.930 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 82.963053 min 2023-04-18T17:36:28.930Z,1681839388.930 [Default:CheckIn:E] Stopped 2023-04-18T17:36:28.930Z,1681839388.930 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-18T17:36:28.930Z,1681839388.930 [Default:CheckIn] Stopped 2023-04-18T17:36:28.930Z,1681839388.930 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-18T17:36:28.930Z,1681839388.930 [Default:CheckIn](INFO): Running loop #15 2023-04-18T17:36:28.930Z,1681839388.930 [Default:CheckIn] Running Loop=15 2023-04-18T17:36:28.930Z,1681839388.930 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-18T17:36:28.930Z,1681839388.930 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-18T17:36:30.969Z,1681839390.969 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173630.00,A,3648.14086,N,12147.20843,W,0.019,215.75,180423,,,D*77 2023-04-18T17:36:30.971Z,1681839390.971 [NAL9602](INFO): GPS fix at 20230418T173630: (36.802348, -121.786807) 2023-04-18T17:36:31.005Z,1681839391.005 [Default:CheckIn:Read_GPS] Stopped 2023-04-18T17:36:31.006Z,1681839391.006 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-18T17:36:41.110Z,1681839401.110 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2023-04-18T17:36:41.110Z,1681839401.110 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+14.3,0000.0,00 2023-04-18T17:36:41.399Z,1681839401.399 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230418T163603/Courier0033.lzma 2023-04-18T17:36:42.402Z,1681839402.402 [DataOverHttps](INFO): Moved sent file to Logs/20230418T163603/Courier0033.lzma.bak 2023-04-18T17:36:42.402Z,1681839402.402 [DataOverHttps](INFO): SBD MOMSN=18235674 2023-04-18T17:36:47.567Z,1681839407.567 [NAL9602](INFO): SBD MO Status=0, MOMSN=2773, MT Status=0, MTMSN=0 2023-04-18T17:36:47.567Z,1681839407.567 [NAL9602](INFO): No messages in MT queue 2023-04-18T17:36:58.435Z,1681839418.435 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230418T163603/Express0034.lzma 2023-04-18T17:36:59.438Z,1681839419.438 [DataOverHttps](INFO): Moved sent file to Logs/20230418T163603/Express0034.lzma.bak 2023-04-18T17:36:59.438Z,1681839419.438 [DataOverHttps](INFO): SBD MOMSN=18235679 2023-04-18T17:37:00.913Z,1681839420.913 [Default:CheckIn:Read_Iridium] Stopped 2023-04-18T17:37:00.913Z,1681839420.913 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-18T17:37:00.913Z,1681839420.913 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-18T17:37:18.266Z,1681839438.266 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-18T17:39:05.499Z,1681839545.499 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2023-04-18T17:39:05.504Z,1681839545.504 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,.4,0000.0,1489.0,000 2023-04-18T17:39:58.466Z,1681839598.466 [DVL_micro](ERROR): Failed to parse::SA,-03.16,-06.58,116.4 2023-04-18T17:40:01.282Z,1681839601.282 [DVL_micro](ERROR): only read 3 of 4 data items 2023-04-18T17:40:01.282Z,1681839601.282 [DVL_micro](ERROR): Failed to parse: :RD,+99999.99,+9999.99,+9999.99 2023-04-18T17:40:03.700Z,1681839603.700 [DVL_micro](ERROR): No DVL communication! Re-initializing 2023-04-18T17:40:03.700Z,1681839603.700 [DVL_micro] Communications Fault, FailCount= 1 2023-04-18T17:40:03.700Z,1681839603.700 [DVL_micro](ERROR): Communications Fault 2023-04-18T17:40:03.723Z,1681839603.723 [CBIT](ERROR): Communications Fault in component: DVL_micro 2023-04-18T17:40:04.184Z,1681839604.184 [DVL_micro](INFO): Powering down 2023-04-18T17:40:04.952Z,1681839604.952 [CBIT](INFO): Clearing failed state for component DVL_micro 2023-04-18T17:40:04.952Z,1681839604.952 [DVL_micro] No Fault, FailCount= 1 2023-04-18T17:41:18.517Z,1681839678.517 [DVL_micro](ERROR): Failed to parse: 03,-00285,-0192,I 2023-04-18T17:42:01.377Z,1681839721.377 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-18T17:42:01.377Z,1681839721.377 [Default:CheckIn:C.Wait] Stopped 2023-04-18T17:42:01.378Z,1681839721.378 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-18T17:42:01.378Z,1681839721.378 [Default:CheckIn:D] Running Loop=1 2023-04-18T17:42:01.791Z,1681839721.791 [Default:CheckIn:D] Stopped 2023-04-18T17:42:01.791Z,1681839721.791 [Default:CheckIn:E] Running Loop=1 2023-04-18T17:42:02.189Z,1681839722.189 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 88.517554 min 2023-04-18T17:42:02.189Z,1681839722.189 [Default:CheckIn:E] Stopped 2023-04-18T17:42:02.189Z,1681839722.189 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-18T17:42:02.189Z,1681839722.189 [Default:CheckIn] Stopped 2023-04-18T17:42:02.189Z,1681839722.189 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-18T17:42:02.189Z,1681839722.189 [Default:CheckIn](INFO): Running loop #16 2023-04-18T17:42:02.189Z,1681839722.189 [Default:CheckIn] Running Loop=16 2023-04-18T17:42:02.190Z,1681839722.190 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-18T17:42:02.190Z,1681839722.190 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-18T17:42:04.215Z,1681839724.215 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174203.00,A,3648.16424,N,12147.28451,W,0.311,159.29,180423,,,D*74 2023-04-18T17:42:04.217Z,1681839724.217 [NAL9602](INFO): GPS fix at 20230418T174203: (36.802737, -121.788075) 2023-04-18T17:42:04.227Z,1681839724.227 [Default:CheckIn:Read_GPS] Stopped 2023-04-18T17:42:04.227Z,1681839724.227 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-18T17:42:11.951Z,1681839731.951 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230418T163603/Courier0036.lzma 2023-04-18T17:42:12.954Z,1681839732.954 [DataOverHttps](INFO): Moved sent file to Logs/20230418T163603/Courier0036.lzma.bak 2023-04-18T17:42:12.954Z,1681839732.954 [DataOverHttps](INFO): SBD MOMSN=18235714 2023-04-18T17:42:28.988Z,1681839748.988 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20230418T163603/Express0037.lzma 2023-04-18T17:42:29.990Z,1681839749.990 [DataOverHttps](INFO): Moved sent file to Logs/20230418T163603/Express0037.lzma.bak 2023-04-18T17:42:29.990Z,1681839749.990 [DataOverHttps](INFO): SBD MOMSN=18235718 2023-04-18T17:42:31.289Z,1681839751.289 [Default:CheckIn:Read_Iridium] Stopped 2023-04-18T17:42:31.289Z,1681839751.289 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-18T17:42:31.289Z,1681839751.289 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-18T17:42:38.635Z,1681839758.635 [NAL9602](INFO): SBD MO Status=2, MOMSN=2774, MT Status=2, MTMSN=0 2023-04-18T17:42:38.635Z,1681839758.635 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-04-18T17:47:06.264Z,1681840026.264 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-04-18T17:47:31.749Z,1681840051.749 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-18T17:47:31.749Z,1681840051.749 [Default:CheckIn:C.Wait] Stopped 2023-04-18T17:47:31.749Z,1681840051.749 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-18T17:47:31.750Z,1681840051.750 [Default:CheckIn:D] Running Loop=1 2023-04-18T17:47:32.158Z,1681840052.158 [Default:CheckIn:D] Stopped 2023-04-18T17:47:32.158Z,1681840052.158 [Default:CheckIn:E] Running Loop=1 2023-04-18T17:47:32.556Z,1681840052.556 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 94.023674 min 2023-04-18T17:47:32.556Z,1681840052.556 [Default:CheckIn:E] Stopped 2023-04-18T17:47:32.556Z,1681840052.556 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-18T17:47:32.556Z,1681840052.556 [Default:CheckIn] Stopped 2023-04-18T17:47:32.556Z,1681840052.556 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-18T17:47:32.557Z,1681840052.557 [Default:CheckIn](INFO): Running loop #17 2023-04-18T17:47:32.557Z,1681840052.557 [Default:CheckIn] Running Loop=17 2023-04-18T17:47:32.557Z,1681840052.557 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-18T17:47:32.557Z,1681840052.557 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-18T17:47:34.566Z,1681840054.566 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174733.00,A,3648.15209,N,12147.27912,W,1.575,19.42,180423,,,D*40 2023-04-18T17:47:34.576Z,1681840054.576 [NAL9602](INFO): GPS fix at 20230418T174733: (36.802535, -121.787985) 2023-04-18T17:47:34.611Z,1681840054.611 [Default:CheckIn:Read_GPS] Stopped 2023-04-18T17:47:34.611Z,1681840054.611 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-18T17:47:47.031Z,1681840067.031 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230418T163603/Courier0039.lzma 2023-04-18T17:47:48.034Z,1681840068.034 [DataOverHttps](INFO): Moved sent file to Logs/20230418T163603/Courier0039.lzma.bak 2023-04-18T17:47:48.034Z,1681840068.034 [DataOverHttps](INFO): SBD MOMSN=18235751 2023-04-18T17:48:04.168Z,1681840084.168 [DataOverHttps](INFO): Sending 239 bytes from file Logs/20230418T163603/Express0040.lzma 2023-04-18T17:48:05.170Z,1681840085.170 [DataOverHttps](INFO): Moved sent file to Logs/20230418T163603/Express0040.lzma.bak 2023-04-18T17:48:05.170Z,1681840085.170 [DataOverHttps](INFO): SBD MOMSN=18235754 2023-04-18T17:48:06.596Z,1681840086.596 [Default:CheckIn:Read_Iridium] Stopped 2023-04-18T17:48:06.596Z,1681840086.596 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-18T17:48:06.596Z,1681840086.596 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-18T17:48:07.325Z,1681840087.325 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-18T17:48:13.597Z,1681840093.597 [DataOverHttps](IMPORTANT): SBD MTMSN=20230418T174812 2023-04-18T17:48:21.119Z,1681840101.119 [DataOverHttps](INFO): Received command: strobe off 2023-04-18T17:48:21.153Z,1681840101.153 [CommandExec](IMPORTANT): got command strobe off 2023-04-18T17:48:21.153Z,1681840101.153 [CommandExec](IMPORTANT): Deactivating strobe 2023-04-18T17:51:11.559Z,1681840271.559 [DVL_micro](ERROR): Failed to parse:00000000000,35.0,+14.9,0000.0,1489.0,000 2023-04-18T17:53:07.144Z,1681840387.144 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-18T17:53:07.144Z,1681840387.144 [Default:CheckIn:C.Wait] Stopped 2023-04-18T17:53:07.144Z,1681840387.144 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-18T17:53:07.144Z,1681840387.144 [Default:CheckIn:D] Running Loop=1 2023-04-18T17:53:07.562Z,1681840387.562 [Default:CheckIn:D] Stopped 2023-04-18T17:53:07.562Z,1681840387.562 [Default:CheckIn:E] Running Loop=1 2023-04-18T17:53:07.943Z,1681840387.943 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 99.613745 min 2023-04-18T17:53:07.943Z,1681840387.943 [Default:CheckIn:E] Stopped 2023-04-18T17:53:07.944Z,1681840387.944 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-18T17:53:07.944Z,1681840387.944 [Default:CheckIn] Stopped 2023-04-18T17:53:07.944Z,1681840387.944 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-18T17:53:07.944Z,1681840387.944 [Default:CheckIn](INFO): Running loop #18 2023-04-18T17:53:07.944Z,1681840387.944 [Default:CheckIn] Running Loop=18 2023-04-18T17:53:07.944Z,1681840387.944 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-18T17:53:07.944Z,1681840387.944 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-18T17:53:09.967Z,1681840389.967 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175309.00,A,3648.16506,N,12147.28139,W,0.797,0.00,180423,,,A*7D 2023-04-18T17:53:09.970Z,1681840389.970 [NAL9602](INFO): GPS fix at 20230418T175309: (36.802751, -121.788023) 2023-04-18T17:53:09.980Z,1681840389.980 [Default:CheckIn:Read_GPS] Stopped 2023-04-18T17:53:09.980Z,1681840389.980 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-18T17:53:27.847Z,1681840407.847 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230418T163603/Courier0042.lzma 2023-04-18T17:53:28.850Z,1681840408.850 [DataOverHttps](INFO): Moved sent file to Logs/20230418T163603/Courier0042.lzma.bak 2023-04-18T17:53:28.850Z,1681840408.850 [DataOverHttps](INFO): SBD MOMSN=18235797 2023-04-18T17:53:40.662Z,1681840420.662 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-04-18T17:53:40.736Z,1681840420.736 [NAL9602](ERROR): received: +CSQ:0 OK74, 2, 0, 0, 0 OK 2023-04-18T17:53:45.044Z,1681840425.044 [DataOverHttps](INFO): Sending 243 bytes from file Logs/20230418T163603/Express0043.lzma 2023-04-18T17:53:46.046Z,1681840426.046 [DataOverHttps](INFO): Moved sent file to Logs/20230418T163603/Express0043.lzma.bak 2023-04-18T17:53:46.046Z,1681840426.046 [DataOverHttps](INFO): SBD MOMSN=18235800 2023-04-18T17:54:02.027Z,1681840442.027 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20230418T163603/Express0046.lzma 2023-04-18T17:54:03.030Z,1681840443.030 [DataOverHttps](INFO): Moved sent file to Logs/20230418T163603/Express0046.lzma.bak 2023-04-18T17:54:03.030Z,1681840443.030 [DataOverHttps](INFO): SBD MOMSN=18235808 2023-04-18T17:54:04.506Z,1681840444.506 [Default:CheckIn:Read_Iridium] Stopped 2023-04-18T17:54:04.506Z,1681840444.506 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-18T17:54:04.506Z,1681840444.506 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-18T17:54:23.693Z,1681840463.693 [CommandExec](IMPORTANT): got command burn off 2023-04-18T17:54:23.693Z,1681840463.693 [CommandExec](IMPORTANT): Deactivating dropweight wire 2023-04-18T17:56:13.385Z,1681840573.385 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2023-04-18T17:56:13.385Z,1681840573.385 [DVL_micro](ERROR): Failed to parse: :TS,000000005.0,+15.2,0000.0,1489.0,000 2023-04-18T17:57:26.912Z,1681840646.912 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2023-04-18T17:57:26.912Z,1681840646.912 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,00.0,1489.0,000 2023-04-18T17:58:12.150Z,1681840692.150 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-04-18T17:58:42.865Z,1681840722.865 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-18T17:59:05.088Z,1681840745.088 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-18T17:59:05.088Z,1681840745.088 [Default:CheckIn:C.Wait] Stopped 2023-04-18T17:59:05.088Z,1681840745.088 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-18T17:59:05.089Z,1681840745.089 [Default:CheckIn:D] Running Loop=1 2023-04-18T17:59:05.503Z,1681840745.503 [Default:CheckIn:D] Stopped 2023-04-18T17:59:05.503Z,1681840745.503 [Default:CheckIn:E] Running Loop=1 2023-04-18T17:59:05.899Z,1681840745.899 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 105.579411 min 2023-04-18T17:59:05.899Z,1681840745.899 [Default:CheckIn:E] Stopped 2023-04-18T17:59:05.899Z,1681840745.899 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-18T17:59:05.899Z,1681840745.899 [Default:CheckIn] Stopped 2023-04-18T17:59:05.899Z,1681840745.899 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-18T17:59:05.899Z,1681840745.899 [Default:CheckIn](INFO): Running loop #19 2023-04-18T17:59:05.900Z,1681840745.900 [Default:CheckIn] Running Loop=19 2023-04-18T17:59:05.900Z,1681840745.900 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-18T17:59:05.901Z,1681840745.901 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-18T17:59:38.608Z,1681840778.608 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-04-18T17:59:38.680Z,1681840778.680 [NAL9602](ERROR): received: +CSQ:0 OK74, 2, 0, 0, 0 OK 2023-04-18T18:00:32.341Z,1681840832.341 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2023-04-18T18:00:32.342Z,1681840832.342 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+15.5,000.0,000 2023-04-18T18:02:36.367Z,1681840956.367 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2023-04-18T18:02:36.367Z,1681840956.367 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0000.0,1489.0,000 2023-04-18T18:02:56.975Z,1681840976.975 [DVL_micro](ERROR): Failed to parse: :000000000,35.0,+15.7,0000.0,1489.0,000 2023-04-18T18:03:37.390Z,1681841017.390 [DVL_micro](ERROR): only read 3 of 4 data items 2023-04-18T18:03:37.390Z,1681841017.390 [DVL_micro](ERROR): Failed to parse: :BI,-02485,+00916,-0252,I 2023-04-18T18:03:57.593Z,1681841037.593 [DVL_micro](ERROR): Failed to parse: :B,+00912,-02357,+00000,I 2023-04-18T18:04:06.103Z,1681841046.103 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-04-18T17:59:05.9Z 2023-04-18T18:04:06.103Z,1681841046.103 [Default:CheckIn:Read_GPS] Stopped 2023-04-18T18:04:06.103Z,1681841046.103 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-18T18:04:07.899Z,1681841047.899 [DVL_micro](ERROR): Failed to parse: :WI,+00053,+00882,-02160,+00000,A 2023-04-18T18:04:10.315Z,1681841050.315 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-04-18T18:04:17.087Z,1681841057.087 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230418T163603/Courier0048.lzma 2023-04-18T18:04:18.090Z,1681841058.090 [DataOverHttps](INFO): Moved sent file to Logs/20230418T163603/Courier0048.lzma.bak 2023-04-18T18:04:18.090Z,1681841058.090 [DataOverHttps](INFO): SBD MOMSN=18235811 2023-04-18T18:04:40.993Z,1681841080.993 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-18T18:04:43.066Z,1681841083.066 [DataOverHttps](INFO): Sending 256 bytes from file Logs/20230418T163603/Express0049.lzma 2023-04-18T18:04:44.047Z,1681841084.047 [DataOverHttps](INFO): Moved sent file to Logs/20230418T163603/Express0049.lzma.bak 2023-04-18T18:04:44.047Z,1681841084.047 [DataOverHttps](INFO): SBD MOMSN=18235813 2023-04-18T18:04:45.453Z,1681841085.453 [Default:CheckIn:Read_Iridium] Stopped 2023-04-18T18:04:45.453Z,1681841085.453 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-18T18:04:45.454Z,1681841085.454 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-18T18:06:49.942Z,1681841209.942 [DVL_micro](ERROR): Failed to parse: :SA,-03.17,-05.37,141.8 2023-04-18T18:07:02.815Z,1681841222.815 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2023-04-18T18:07:02.815Z,1681841222.815 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+15.91489.0,000 2023-04-18T18:07:31.517Z,1681841251.517 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:07:31.551Z,1681841251.551 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:07:42.615Z,1681841262.615 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:07:42.793Z,1681841262.793 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:07:53.598Z,1681841273.598 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:07:54.117Z,1681841274.117 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:08:04.881Z,1681841284.881 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:08:05.019Z,1681841285.019 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:08:15.763Z,1681841295.763 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:08:15.949Z,1681841295.949 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:08:26.721Z,1681841306.721 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:08:26.919Z,1681841306.919 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:08:37.763Z,1681841317.763 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:08:37.949Z,1681841317.949 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:08:48.695Z,1681841328.695 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:08:49.125Z,1681841329.125 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:09:00.003Z,1681841340.003 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:09:00.405Z,1681841340.405 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:09:10.481Z,1681841350.481 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:09:11.299Z,1681841351.299 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:09:21.867Z,1681841361.867 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:09:22.315Z,1681841362.315 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:09:33.147Z,1681841373.147 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:09:33.311Z,1681841373.311 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:09:44.109Z,1681841384.109 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:09:44.265Z,1681841384.265 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:09:45.986Z,1681841385.986 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-18T18:09:45.986Z,1681841385.986 [Default:CheckIn:C.Wait] Stopped 2023-04-18T18:09:45.986Z,1681841385.986 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-18T18:09:45.988Z,1681841385.988 [Default:CheckIn:D] Running Loop=1 2023-04-18T18:09:46.392Z,1681841386.392 [Default:CheckIn:D] Stopped 2023-04-18T18:09:46.392Z,1681841386.392 [Default:CheckIn:E] Running Loop=1 2023-04-18T18:09:46.796Z,1681841386.796 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 116.260824 min 2023-04-18T18:09:46.796Z,1681841386.796 [Default:CheckIn:E] Stopped 2023-04-18T18:09:46.796Z,1681841386.796 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-18T18:09:46.796Z,1681841386.796 [Default:CheckIn] Stopped 2023-04-18T18:09:46.796Z,1681841386.796 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-18T18:09:46.796Z,1681841386.796 [Default:CheckIn](INFO): Running loop #20 2023-04-18T18:09:46.796Z,1681841386.796 [Default:CheckIn] Running Loop=20 2023-04-18T18:09:46.797Z,1681841386.797 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-18T18:09:46.797Z,1681841386.797 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-18T18:09:48.399Z,1681841388.399 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-04-18T18:09:48.399Z,1681841388.399 [NAL9602] Data Fault, FailCount= 1 2023-04-18T18:09:48.400Z,1681841388.400 [NAL9602](ERROR): Data Fault 2023-04-18T18:09:48.486Z,1681841388.486 [CBIT](ERROR): Data Fault in component: NAL9602 2023-04-18T18:09:48.789Z,1681841388.789 [NAL9602](INFO): Powering down 2023-04-18T18:09:49.661Z,1681841389.661 [CBIT](INFO): Clearing failed state for component NAL9602 2023-04-18T18:09:49.661Z,1681841389.661 [NAL9602] No Fault, FailCount= 1 2023-04-18T18:09:55.097Z,1681841395.097 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:09:55.315Z,1681841395.315 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:10:06.153Z,1681841406.153 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:10:06.323Z,1681841406.323 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:10:17.076Z,1681841417.076 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:10:17.242Z,1681841417.242 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:10:18.949Z,1681841418.949 [NAL9602](INFO): Powering up NAL9602 2023-04-18T18:10:28.062Z,1681841428.062 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:10:29.853Z,1681841429.853 [NAL9602](INFO): NAL9602 initialized 2023-04-18T18:10:38.490Z,1681841438.490 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:10:44.425Z,1681841444.425 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:11:11.512Z,1681841471.512 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2023-04-18T18:11:11.512Z,1681841471.512 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+16.2,489.0,000 2023-04-18T18:11:20.919Z,1681841480.919 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:12:02.899Z,1681841522.899 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:12:21.903Z,1681841541.903 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:12:51.366Z,1681841571.366 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:13:02.831Z,1681841582.831 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:13:10.420Z,1681841590.420 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:13:14.107Z,1681841594.107 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:13:21.339Z,1681841601.339 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:13:24.983Z,1681841604.983 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:13:32.395Z,1681841612.395 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:13:35.505Z,1681841615.505 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:13:43.185Z,1681841623.185 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:13:46.040Z,1681841626.040 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:13:54.492Z,1681841634.492 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:13:57.415Z,1681841637.415 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:14:05.409Z,1681841645.409 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:14:08.224Z,1681841648.224 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:14:16.330Z,1681841656.330 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:14:19.144Z,1681841659.144 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:14:27.279Z,1681841667.279 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:14:30.142Z,1681841670.142 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:14:37.734Z,1681841677.734 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:14:40.561Z,1681841680.561 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:14:46.926Z,1681841686.926 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-04-18T18:09:46.8Z 2023-04-18T18:14:46.926Z,1681841686.926 [Default:CheckIn:Read_GPS] Stopped 2023-04-18T18:14:46.932Z,1681841686.932 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-18T18:14:48.414Z,1681841688.414 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:14:51.239Z,1681841691.239 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:14:59.503Z,1681841699.503 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230418T163603/Courier0051.lzma 2023-04-18T18:14:59.703Z,1681841699.703 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:15:00.506Z,1681841700.506 [DataOverHttps](INFO): Moved sent file to Logs/20230418T163603/Courier0051.lzma.bak 2023-04-18T18:15:00.506Z,1681841700.506 [DataOverHttps](INFO): SBD MOMSN=18235832 2023-04-18T18:15:02.115Z,1681841702.115 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:15:11.046Z,1681841711.046 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:15:13.027Z,1681841713.027 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:15:22.023Z,1681841722.023 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:15:24.017Z,1681841724.017 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:15:25.830Z,1681841725.830 [DataOverHttps](INFO): Sending 225 bytes from file Logs/20230418T163603/Express0052.lzma 2023-04-18T18:15:26.832Z,1681841726.832 [DataOverHttps](INFO): Moved sent file to Logs/20230418T163603/Express0052.lzma.bak 2023-04-18T18:15:26.832Z,1681841726.832 [DataOverHttps](INFO): SBD MOMSN=18235834 2023-04-18T18:15:28.254Z,1681841728.254 [Default:CheckIn:Read_Iridium] Stopped 2023-04-18T18:15:28.254Z,1681841728.254 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-18T18:15:28.255Z,1681841728.255 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-18T18:15:32.563Z,1681841732.563 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:15:33.541Z,1681841733.541 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-04-18T18:15:34.947Z,1681841734.947 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:15:43.393Z,1681841743.393 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:15:45.785Z,1681841745.785 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:15:54.687Z,1681841754.687 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:15:56.715Z,1681841756.715 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:16:03.041Z,1681841763.041 [DVL_micro](ERROR): only read 3 of 4 data items 2023-04-18T18:16:03.041Z,1681841763.041 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99,+9999.99,+9999.99,+ 2023-04-18T18:16:04.245Z,1681841764.245 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-18T18:16:06.069Z,1681841766.069 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:16:07.647Z,1681841767.647 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:16:07.911Z,1681841767.911 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2023-04-18T18:16:07.930Z,1681841767.930 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+160,1489.0,000 2023-04-18T18:16:16.954Z,1681841776.954 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:16:18.506Z,1681841778.506 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:16:27.440Z,1681841787.440 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:16:29.482Z,1681841789.482 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:16:38.033Z,1681841798.033 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:16:39.989Z,1681841799.989 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:16:48.481Z,1681841808.481 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:16:51.275Z,1681841811.275 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:16:59.384Z,1681841819.384 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:17:02.642Z,1681841822.642 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:17:10.307Z,1681841830.307 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:17:13.928Z,1681841833.928 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:17:21.174Z,1681841841.174 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:17:25.201Z,1681841845.201 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:17:32.084Z,1681841852.084 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:17:36.141Z,1681841856.141 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:17:43.390Z,1681841863.390 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:17:47.032Z,1681841867.032 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:17:54.305Z,1681841874.305 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:17:57.962Z,1681841877.962 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:17:58.994Z,1681841878.994 [BPC1](ERROR): Failed to receive data from all battery packs. 2023-04-18T18:17:58.994Z,1681841878.994 [BPC1] Communications Fault, FailCount= 1 2023-04-18T18:17:58.994Z,1681841878.994 [BPC1](ERROR): Communications Fault 2023-04-18T18:17:59.076Z,1681841879.076 [CBIT](ERROR): Communications Fault in component: BPC1 2023-04-18T18:19:03.257Z,1681841943.257 [DVL_micro](ERROR): only read 3 of 4 data items 2023-04-18T18:19:03.257Z,1681841943.257 [DVL_micro](ERROR): Failed to parse: :BI,-01358,+1931,+00000,I 2023-04-18T18:20:28.903Z,1681842028.903 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-18T18:20:28.903Z,1681842028.903 [Default:CheckIn:C.Wait] Stopped 2023-04-18T18:20:28.904Z,1681842028.904 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-18T18:20:28.904Z,1681842028.904 [Default:CheckIn:D] Running Loop=1 2023-04-18T18:20:29.321Z,1681842029.321 [Default:CheckIn:D] Stopped 2023-04-18T18:20:29.321Z,1681842029.321 [Default:CheckIn:E] Running Loop=1 2023-04-18T18:20:29.710Z,1681842029.710 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 126.976383 min 2023-04-18T18:20:29.710Z,1681842029.710 [Default:CheckIn:E] Stopped 2023-04-18T18:20:29.710Z,1681842029.710 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-18T18:20:29.710Z,1681842029.710 [Default:CheckIn] Stopped 2023-04-18T18:20:29.711Z,1681842029.711 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-18T18:20:29.711Z,1681842029.711 [Default:CheckIn](INFO): Running loop #21 2023-04-18T18:20:29.711Z,1681842029.711 [Default:CheckIn] Running Loop=21 2023-04-18T18:20:29.711Z,1681842029.711 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-18T18:20:29.711Z,1681842029.711 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-18T18:20:31.314Z,1681842031.314 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-04-18T18:20:31.314Z,1681842031.314 [NAL9602] Data Fault, FailCount= 2 2023-04-18T18:20:31.314Z,1681842031.314 [NAL9602](ERROR): Data Fault 2023-04-18T18:20:31.333Z,1681842031.333 [CBIT](ERROR): Data Fault in component: NAL9602 2023-04-18T18:20:31.717Z,1681842031.717 [NAL9602](INFO): Powering down 2023-04-18T18:20:32.568Z,1681842032.568 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2023-04-18T18:20:32.612Z,1681842032.612 [CBIT](INFO): Clearing failed state for component NAL9602 2023-04-18T18:20:32.612Z,1681842032.612 [NAL9602] No Fault, FailCount= 2 2023-04-18T18:21:02.079Z,1681842062.079 [NAL9602](INFO): Powering up NAL9602 2023-04-18T18:21:12.984Z,1681842072.984 [NAL9602](INFO): NAL9602 initialized 2023-04-18T18:23:59.512Z,1681842239.512 [DVL_micro](ERROR): only read 3 of 4 data items 2023-04-18T18:23:59.512Z,1681842239.512 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99,+9999.99,+99999.99 2023-04-18T18:25:30.017Z,1681842330.017 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-04-18T18:20:29.7Z 2023-04-18T18:25:30.017Z,1681842330.017 [Default:CheckIn:Read_GPS] Stopped 2023-04-18T18:25:30.018Z,1681842330.018 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-18T18:25:37.311Z,1681842337.311 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230418T163603/Courier0054.lzma 2023-04-18T18:25:38.314Z,1681842338.314 [DataOverHttps](INFO): Moved sent file to Logs/20230418T163603/Courier0054.lzma.bak 2023-04-18T18:25:38.314Z,1681842338.314 [DataOverHttps](INFO): SBD MOMSN=18235839 2023-04-18T18:25:54.380Z,1681842354.380 [DataOverHttps](INFO): Sending 234 bytes from file Logs/20230418T163603/Express0055.lzma 2023-04-18T18:25:55.382Z,1681842355.382 [DataOverHttps](INFO): Moved sent file to Logs/20230418T163603/Express0055.lzma.bak 2023-04-18T18:25:55.382Z,1681842355.382 [DataOverHttps](INFO): SBD MOMSN=18235841 2023-04-18T18:25:56.689Z,1681842356.689 [Default:CheckIn:Read_Iridium] Stopped 2023-04-18T18:25:56.689Z,1681842356.689 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-18T18:25:56.689Z,1681842356.689 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-18T18:26:16.872Z,1681842376.872 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-04-18T18:26:47.571Z,1681842407.571 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-18T18:27:59.908Z,1681842479.908 [CBIT](INFO): Clearing failed state for component BPC1 2023-04-18T18:27:59.908Z,1681842479.908 [BPC1] No Fault, FailCount= 1 2023-04-18T18:28:13.635Z,1681842493.635 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2023-04-18T18:28:13.635Z,1681842493.635 [DVL_micro](ERROR): Failed to parse: :TS,000000000000007.2,0000.,000 2023-04-18T18:29:06.155Z,1681842546.155 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:29:06.180Z,1681842546.180 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:29:17.126Z,1681842557.126 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:29:17.194Z,1681842557.194 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:29:28.036Z,1681842568.036 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:29:28.441Z,1681842568.441 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:29:39.346Z,1681842579.346 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:29:39.749Z,1681842579.749 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:29:50.660Z,1681842590.660 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:29:51.061Z,1681842591.061 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:30:01.564Z,1681842601.564 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:30:01.968Z,1681842601.968 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:30:05.539Z,1681842605.539 [DVL_micro](ERROR): only read 0 of 4 data items 2023-04-18T18:30:05.552Z,1681842605.552 [DVL_micro](ERROR): Failed to parse: :RD,++9999.99,+9999.99,+9999.99 2023-04-18T18:30:12.884Z,1681842612.884 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:30:23.782Z,1681842623.782 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:30:34.777Z,1681842634.777 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:30:45.180Z,1681842645.180 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:30:46.016Z,1681842646.016 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:30:57.316Z,1681842657.316 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-18T18:30:57.317Z,1681842657.317 [Default:CheckIn:C.Wait] Stopped 2023-04-18T18:30:57.317Z,1681842657.317 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-18T18:30:57.317Z,1681842657.317 [Default:CheckIn:D] Running Loop=1 2023-04-18T18:30:57.672Z,1681842657.672 [Default:CheckIn:D] Stopped 2023-04-18T18:30:57.672Z,1681842657.672 [Default:CheckIn:E] Running Loop=1 2023-04-18T18:30:58.087Z,1681842658.087 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 137.448893 min 2023-04-18T18:30:58.087Z,1681842658.087 [Default:CheckIn:E] Stopped 2023-04-18T18:30:58.087Z,1681842658.087 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-18T18:30:58.087Z,1681842658.087 [Default:CheckIn] Stopped 2023-04-18T18:30:58.087Z,1681842658.087 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-18T18:30:58.087Z,1681842658.087 [Default:CheckIn](INFO): Running loop #22 2023-04-18T18:30:58.087Z,1681842658.087 [Default:CheckIn] Running Loop=22 2023-04-18T18:30:58.088Z,1681842658.088 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-18T18:30:58.088Z,1681842658.088 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-18T18:31:30.797Z,1681842690.797 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-04-18T18:31:30.880Z,1681842690.880 [NAL9602](ERROR): received: +CSQ:0 OK74, 2, 0, 0, 0 OK 2023-04-18T18:32:30.584Z,1681842750.584 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:32:41.548Z,1681842761.548 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:32:52.462Z,1681842772.462 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:33:03.367Z,1681842783.367 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:33:14.686Z,1681842794.686 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:33:21.529Z,1681842801.529 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:33:25.987Z,1681842805.987 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:33:32.448Z,1681842812.448 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:33:37.305Z,1681842817.305 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:33:43.790Z,1681842823.790 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:33:48.220Z,1681842828.220 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:33:54.673Z,1681842834.673 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:33:59.526Z,1681842839.526 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:34:05.581Z,1681842845.581 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:34:10.486Z,1681842850.486 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:34:16.505Z,1681842856.505 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:34:21.339Z,1681842861.339 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:34:27.400Z,1681842867.400 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:34:32.244Z,1681842872.244 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:34:38.301Z,1681842878.301 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:34:43.157Z,1681842883.157 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:34:49.620Z,1681842889.620 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:34:54.062Z,1681842894.062 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:35:00.532Z,1681842900.532 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:35:04.977Z,1681842904.977 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:35:11.502Z,1681842911.502 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:35:15.887Z,1681842915.887 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:35:22.348Z,1681842922.348 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:35:27.200Z,1681842927.200 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:35:32.858Z,1681842932.858 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:35:38.106Z,1681842938.106 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:35:43.820Z,1681842943.820 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:35:49.422Z,1681842949.422 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:35:54.266Z,1681842954.266 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:35:58.285Z,1681842958.285 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-04-18T18:30:58.1Z 2023-04-18T18:35:58.285Z,1681842958.285 [Default:CheckIn:Read_GPS] Stopped 2023-04-18T18:35:58.285Z,1681842958.285 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-18T18:36:00.328Z,1681842960.328 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:36:01.885Z,1681842961.885 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-04-18T18:36:05.168Z,1681842965.168 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:36:05.859Z,1681842965.859 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230418T163603/Courier0057.lzma 2023-04-18T18:36:06.862Z,1681842966.862 [DataOverHttps](INFO): Moved sent file to Logs/20230418T163603/Courier0057.lzma.bak 2023-04-18T18:36:06.862Z,1681842966.862 [DataOverHttps](INFO): SBD MOMSN=18235849 2023-04-18T18:36:11.716Z,1681842971.716 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:36:16.084Z,1681842976.084 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:36:22.539Z,1681842982.539 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:36:22.973Z,1681842982.973 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20230418T163603/Express0058.lzma 2023-04-18T18:36:23.974Z,1681842983.974 [DataOverHttps](INFO): Moved sent file to Logs/20230418T163603/Express0058.lzma.bak 2023-04-18T18:36:23.974Z,1681842983.974 [DataOverHttps](INFO): SBD MOMSN=18235854 2023-04-18T18:36:25.314Z,1681842985.314 [Default:CheckIn:Read_Iridium] Stopped 2023-04-18T18:36:25.314Z,1681842985.314 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-18T18:36:25.314Z,1681842985.314 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-18T18:36:26.580Z,1681842986.580 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:36:32.576Z,1681842992.576 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-18T18:36:33.457Z,1681842993.457 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:36:37.496Z,1681842997.496 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:36:43.988Z,1681843003.988 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:36:48.816Z,1681843008.816 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:36:54.868Z,1681843014.868 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:36:59.248Z,1681843019.248 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2023-04-18T18:36:59.249Z,1681843019.249 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0000.0,1489:WI,-00906,+0191,+00000-00906,+0191,+0:BD,+0000000000000.00,+000,9999.99,000.00 2023-04-18T18:36:59.772Z,1681843019.772 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:37:05.767Z,1681843025.767 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:37:10.618Z,1681843030.618 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:37:17.081Z,1681843037.081 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:37:21.529Z,1681843041.529 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:37:27.987Z,1681843047.987 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:37:32.434Z,1681843052.434 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:37:38.905Z,1681843058.905 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:37:43.348Z,1681843063.348 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:37:49.810Z,1681843069.810 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:37:54.248Z,1681843074.248 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:38:00.771Z,1681843080.771 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:38:04.760Z,1681843084.760 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:38:10.338Z,1681843090.338 [BPC1](ERROR): Failed to receive data from all battery packs. 2023-04-18T18:38:10.338Z,1681843090.338 [BPC1] Communications Fault, FailCount= 2 2023-04-18T18:38:10.338Z,1681843090.338 [BPC1](ERROR): Communications Fault 2023-04-18T18:38:10.382Z,1681843090.382 [CBIT](ERROR): Communications Fault in component: BPC1 2023-04-18T18:40:01.861Z,1681843201.861 [DVL_micro](ERROR): Failed to parse:99.99,++9999.99,+9999.99 2023-04-18T18:41:25.893Z,1681843285.893 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-18T18:41:25.893Z,1681843285.893 [Default:CheckIn:C.Wait] Stopped 2023-04-18T18:41:25.893Z,1681843285.893 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-18T18:41:25.893Z,1681843285.893 [Default:CheckIn:D] Running Loop=1 2023-04-18T18:41:26.301Z,1681843286.301 [Default:CheckIn:D] Stopped 2023-04-18T18:41:26.301Z,1681843286.301 [Default:CheckIn:E] Running Loop=1 2023-04-18T18:41:26.703Z,1681843286.703 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 147.926042 min 2023-04-18T18:41:26.703Z,1681843286.703 [Default:CheckIn:E] Stopped 2023-04-18T18:41:26.703Z,1681843286.703 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-18T18:41:26.703Z,1681843286.703 [Default:CheckIn] Stopped 2023-04-18T18:41:26.704Z,1681843286.704 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-18T18:41:26.704Z,1681843286.704 [Default:CheckIn](INFO): Running loop #23 2023-04-18T18:41:26.704Z,1681843286.704 [Default:CheckIn] Running Loop=23 2023-04-18T18:41:26.704Z,1681843286.704 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-18T18:41:26.704Z,1681843286.704 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-18T18:41:28.312Z,1681843288.312 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-04-18T18:41:28.312Z,1681843288.312 [NAL9602] Data Fault, FailCount= 3 2023-04-18T18:41:28.312Z,1681843288.312 [NAL9602](ERROR): Data Fault 2023-04-18T18:41:28.332Z,1681843288.332 [CBIT](ERROR): Data Fault in component: NAL9602 2023-04-18T18:41:28.721Z,1681843288.721 [NAL9602](INFO): Powering down 2023-04-18T18:41:29.539Z,1681843289.539 [CBIT](INFO): Clearing failed state for component NAL9602 2023-04-18T18:41:29.539Z,1681843289.539 [NAL9602] No Fault, FailCount= 3 2023-04-18T18:41:59.022Z,1681843319.022 [NAL9602](INFO): Powering up NAL9602 2023-04-18T18:42:09.926Z,1681843329.926 [NAL9602](INFO): NAL9602 initialized 2023-04-18T18:43:14.561Z,1681843394.561 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2023-04-18T18:43:14.562Z,1681843394.562 [DVL_micro](ERROR): Failed to parse: :TS,00000000000,+18.0,0000.0,00 2023-04-18T18:46:26.905Z,1681843586.905 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-04-18T18:41:26.7Z 2023-04-18T18:46:26.905Z,1681843586.905 [Default:CheckIn:Read_GPS] Stopped 2023-04-18T18:46:26.905Z,1681843586.905 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-18T18:46:34.591Z,1681843594.591 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230418T163603/Courier0060.lzma 2023-04-18T18:46:35.594Z,1681843595.594 [DataOverHttps](INFO): Moved sent file to Logs/20230418T163603/Courier0060.lzma.bak 2023-04-18T18:46:35.594Z,1681843595.594 [DataOverHttps](INFO): SBD MOMSN=18235882 2023-04-18T18:46:42.227Z,1681843602.227 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2023-04-18T18:46:42.227Z,1681843602.227 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,32,0000.0,1489.0,000 2023-04-18T18:46:51.552Z,1681843611.552 [DataOverHttps](INFO): Sending 225 bytes from file Logs/20230418T163603/Express0061.lzma 2023-04-18T18:46:52.554Z,1681843612.554 [DataOverHttps](INFO): Moved sent file to Logs/20230418T163603/Express0061.lzma.bak 2023-04-18T18:46:52.554Z,1681843612.554 [DataOverHttps](INFO): SBD MOMSN=18235885 2023-04-18T18:46:53.947Z,1681843613.947 [Default:CheckIn:Read_Iridium] Stopped 2023-04-18T18:46:53.947Z,1681843613.947 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-18T18:46:53.947Z,1681843613.947 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-18T18:47:13.728Z,1681843633.728 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-04-18T18:47:44.457Z,1681843664.457 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-18T18:48:11.519Z,1681843691.519 [CBIT](INFO): Clearing failed state for component BPC1 2023-04-18T18:48:11.519Z,1681843691.519 [BPC1] No Fault, FailCount= 2 2023-04-18T18:49:23.078Z,1681843763.078 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:49:23.148Z,1681843763.148 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:49:33.992Z,1681843773.992 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-04-18T18:49:34.061Z,1681843774.061 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:49:44.898Z,1681843784.898 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:49:49.149Z,1681843789.149 [CommandExec](IMPORTANT): got command restart application 2023-04-18T18:49:50.152Z,1681843790.152 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2023-04-18T18:49:50.152Z,1681843790.152 [CommandExec](INFO): Uninitializing the command executive. 2023-04-18T18:49:50.152Z,1681843790.152 [CommandExec](INFO): Uninitializing the command scheduler. 2023-04-18T18:49:50.153Z,1681843790.153 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-04-18T18:49:50.288Z,1681843790.288 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2023-04-18T18:49:50.288Z,1681843790.288 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2023-04-18T18:49:50.288Z,1681843790.288 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-04-18T18:49:50.289Z,1681843790.289 [NavChartDb](INFO): Join timeout helper Thread ID is 5083 2023-04-18T18:49:50.352Z,1681843790.352 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2023-04-18T18:49:50.352Z,1681843790.352 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-04-18T18:49:50.368Z,1681843790.368 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2023-04-18T18:49:50.368Z,1681843790.368 [DAT ThreadHandler](INFO): Thread cancelled. 2023-04-18T18:49:50.368Z,1681843790.368 [DAT](INFO): Join timeout helper Thread ID is 5084 2023-04-18T18:49:50.376Z,1681843790.376 [DAT](INFO): Powering down 2023-04-18T18:49:50.449Z,1681843790.449 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2023-04-18T18:49:50.449Z,1681843790.449 [DAT](INFO): Powering down 2023-04-18T18:49:50.450Z,1681843790.450 [DAT ThreadHandler](INFO): Thread cancelled. 2023-04-18T18:49:50.472Z,1681843790.472 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2023-04-18T18:49:50.472Z,1681843790.472 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-04-18T18:49:50.472Z,1681843790.472 [Radio_Surface](INFO): Join timeout helper Thread ID is 5085 2023-04-18T18:49:50.636Z,1681843790.636 [Radio_Surface](INFO): Powering down 2023-04-18T18:49:50.637Z,1681843790.637 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2023-04-18T18:49:50.637Z,1681843790.637 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-04-18T18:49:50.640Z,1681843790.640 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2023-04-18T18:49:50.640Z,1681843790.640 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-04-18T18:49:50.640Z,1681843790.640 [Onboard](INFO): Join timeout helper Thread ID is 5086 2023-04-18T18:49:50.920Z,1681843790.920 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2023-04-18T18:49:54.532Z,1681843794.532 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2023-04-18T18:49:54.532Z,1681843794.532 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-04-18T18:49:54.552Z,1681843794.552 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2023-04-18T18:49:54.552Z,1681843794.552 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-04-18T18:49:54.552Z,1681843794.552 [DataOverHttps](INFO): Join timeout helper Thread ID is 5087 2023-04-18T18:49:54.936Z,1681843794.936 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2023-04-18T18:49:54.936Z,1681843794.936 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-04-18T18:49:54.941Z,1681843794.941 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2023-04-18T18:49:54.941Z,1681843794.941 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-04-18T18:49:54.941Z,1681843794.941 [BackseatComponent](INFO): Join timeout helper Thread ID is 5088 2023-04-18T18:49:55.028Z,1681843795.028 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2023-04-18T18:49:55.028Z,1681843795.028 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-04-18T18:49:55.044Z,1681843795.044 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2023-04-18T18:49:55.045Z,1681843795.045 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-04-18T18:49:55.045Z,1681843795.045 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5089 2023-04-18T18:49:55.228Z,1681843795.228 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2023-04-18T18:49:55.229Z,1681843795.229 [WetLabsBB2FL](INFO): Powering down 2023-04-18T18:49:55.229Z,1681843795.229 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-04-18T18:49:55.248Z,1681843795.248 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2023-04-18T18:49:55.249Z,1681843795.249 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-04-18T18:49:55.249Z,1681843795.249 [CTD_Seabird](INFO): Join timeout helper Thread ID is 5090 2023-04-18T18:49:55.700Z,1681843795.700 [CTD_Seabird](INFO): Powering down 2023-04-18T18:49:55.712Z,1681843795.712 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2023-04-18T18:49:55.720Z,1681843795.720 [CTD_Seabird](INFO): Powering down 2023-04-18T18:49:55.732Z,1681843795.732 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-04-18T18:49:55.741Z,1681843795.741 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2023-04-18T18:49:55.741Z,1681843795.741 [logger ThreadHandler](INFO): Thread cancelled. 2023-04-18T18:49:55.741Z,1681843795.741 [logger](INFO): Join timeout helper Thread ID is 5091 2023-04-18T18:49:55.786Z,1681843795.786 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2023-04-18T18:49:55.786Z,1681843795.786 [logger ThreadHandler](INFO): Thread cancelled. 2023-04-18T18:49:55.804Z,1681843795.804 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2023-04-18T18:49:55.805Z,1681843795.805 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-04-18T18:49:55.805Z,1681843795.805 [CommandLine](INFO): Join timeout helper Thread ID is 5092 2023-04-18T18:49:55.836Z,1681843795.836 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2023-04-18T18:49:55.836Z,1681843795.836 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-04-18T18:49:55.840Z,1681843795.840 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2023-04-18T18:49:55.841Z,1681843795.841 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-04-18T18:49:55.841Z,1681843795.841 [CommandExec](INFO): Join timeout helper Thread ID is 5093 2023-04-18T18:49:55.848Z,1681843795.848 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-04-18T18:49:55.853Z,1681843795.853 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2023-04-18T18:49:55.853Z,1681843795.853 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-04-18T18:49:55.853Z,1681843795.853 [controlThread](INFO): Join timeout helper Thread ID is 5094 2023-04-18T18:49:55.876Z,1681843795.876 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2023-04-18T18:49:55.876Z,1681843795.876 [controlThread](DEBUG): Uninitializing ControlThread 2023-04-18T18:49:55.878Z,1681843795.878 [AHRS_M2](INFO): Powering down 2023-04-18T18:49:56.020Z,1681843796.020 [DVL_micro](INFO): Powering down 2023-04-18T18:49:56.021Z,1681843796.021 [NAL9602](INFO): Powering down 2023-04-18T18:49:56.022Z,1681843796.022 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2023-04-18T18:49:56.023Z,1681843796.023 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2023-04-18T18:49:56.024Z,1681843796.024 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2023-04-18T18:49:56.025Z,1681843796.025 [MissionManager](INFO): Uninitializing Mission Default 2023-04-18T18:49:56.025Z,1681843796.025 [Default] Stopped 2023-04-18T18:49:56.025Z,1681843796.025 [Default](DEBUG): Aggregate::uninitialize Default 2023-04-18T18:49:56.025Z,1681843796.025 [Default:B.GoToSurface] Stopped 2023-04-18T18:49:56.025Z,1681843796.025 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-04-18T18:49:56.025Z,1681843796.025 [Default:CheckIn] Stopped 2023-04-18T18:49:56.025Z,1681843796.025 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-18T18:49:56.025Z,1681843796.025 [Default:CheckIn:C.Wait] Stopped 2023-04-18T18:49:56.025Z,1681843796.025 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-18T18:49:56.028Z,1681843796.028 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2023-04-18T18:49:56.028Z,1681843796.028 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2023-04-18T18:49:56.029Z,1681843796.029 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2023-04-18T18:49:56.029Z,1681843796.029 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2023-04-18T18:49:56.029Z,1681843796.029 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2023-04-18T18:49:56.030Z,1681843796.030 [BuoyancyServo](INFO): Powering down 2023-04-18T18:49:56.044Z,1681843796.044 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2023-04-18T18:49:56.044Z,1681843796.044 [ElevatorServo](INFO): Powering down 2023-04-18T18:49:56.045Z,1681843796.045 [MassServo](DEBUG): Uninitialize Mass Servo. 2023-04-18T18:49:56.045Z,1681843796.045 [MassServo](INFO): Powering down 2023-04-18T18:49:56.046Z,1681843796.046 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2023-04-18T18:49:56.046Z,1681843796.046 [RudderServo](INFO): Powering down 2023-04-18T18:49:56.047Z,1681843796.047 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2023-04-18T18:49:56.047Z,1681843796.047 [ThrusterHE](INFO): Powering down 2023-04-18T18:49:56.048Z,1681843796.048 [SBIT](DEBUG): Uninitialize SBIT Component. 2023-04-18T18:49:56.048Z,1681843796.048 [IBIT](DEBUG): Uninitialize IBIT Component. 2023-04-18T18:49:56.048Z,1681843796.048 [CBIT](DEBUG): Uninitialize CBIT Component. 2023-04-18T18:49:56.049Z,1681843796.049 [CBIT](DEBUG): Powering off loads. 2023-04-18T18:49:56.060Z,1681843796.060 [CBIT](DEBUG): Disabling WDT. 2023-04-18T18:49:56.072Z,1681843796.072 [CBIT](DEBUG): Opening all GF detection circuits. 2023-04-18T18:49:56.072Z,1681843796.072 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-04-18T18:49:56.116Z,1681843796.116 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-04-18T18:49:56.122Z,1681843796.122 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-04-18T18:49:56.158Z,1681843796.158 [DAT ThreadHandler](INFO): Thread cancelled. 2023-04-18T18:49:56.162Z,1681843796.162 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-04-18T18:49:56.164Z,1681843796.164 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-04-18T18:49:56.172Z,1681843796.172 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-04-18T18:49:56.216Z,1681843796.216 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-04-18T18:49:56.278Z,1681843796.278 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-04-18T18:49:56.350Z,1681843796.350 [logger ThreadHandler](INFO): Thread cancelled.