2024-10-24T18:53:38.506Z,1729796018.506 [CommandExec](IMPORTANT): got command restart logs 2024-10-24T18:54:33.916Z,1729796073.916 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-10-24T18:54:33.916Z,1729796073.916 [Default:CheckIn:C.Wait] Stopped 2024-10-24T18:54:33.916Z,1729796073.916 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-10-24T18:54:33.917Z,1729796073.917 [Default:CheckIn:D] Running Loop=1 2024-10-24T18:54:34.308Z,1729796074.308 [Default:CheckIn:D] Stopped 2024-10-24T18:54:34.308Z,1729796074.308 [Default:CheckIn:E] Running Loop=1 2024-10-24T18:54:34.708Z,1729796074.708 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 15.090695 min 2024-10-24T18:54:34.708Z,1729796074.708 [Default:CheckIn:E] Stopped 2024-10-24T18:54:34.709Z,1729796074.709 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-10-24T18:54:34.709Z,1729796074.709 [Default:CheckIn] Stopped 2024-10-24T18:54:34.709Z,1729796074.709 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-10-24T18:54:34.709Z,1729796074.709 [Default:CheckIn](INFO): Running loop #3 2024-10-24T18:54:34.709Z,1729796074.709 [Default:CheckIn] Running Loop=3 2024-10-24T18:54:34.709Z,1729796074.709 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-10-24T18:54:34.709Z,1729796074.709 [Default:CheckIn:Read_GPS] Running Loop=1 2024-10-24T18:54:36.844Z,1729796076.844 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185435.00,A,3647.85911,N,12150.55556,W,1.380,85.46,241024,,,D*40 2024-10-24T18:54:36.846Z,1729796076.846 [NAL9602](INFO): GPS fix at 20241024T185435: (36.797652, -121.842593) 2024-10-24T18:54:36.902Z,1729796076.902 [Default:CheckIn:Read_GPS] Stopped 2024-10-24T18:54:36.902Z,1729796076.902 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-10-24T18:54:39.921Z,1729796079.921 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20241024T134500/Courier0084.lzma 2024-10-24T18:54:40.379Z,1729796080.379 [DataOverHttps](INFO): Moved sent file to Logs/20241024T134500/Courier0084.lzma.bak 2024-10-24T18:54:40.379Z,1729796080.379 [DataOverHttps](INFO): SBD MOMSN=23333678 2024-10-24T18:55:00.305Z,1729796100.305 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20241024T185338/Courier0000.lzma 2024-10-24T18:55:01.307Z,1729796101.307 [DataOverHttps](INFO): Moved sent file to Logs/20241024T185338/Courier0000.lzma.bak 2024-10-24T18:55:01.307Z,1729796101.307 [DataOverHttps](INFO): SBD MOMSN=23333680 2024-10-24T18:55:14.088Z,1729796114.088 [NAL9602](INFO): SBD MO Status=2, MOMSN=59438, MT Status=2, MTMSN=0 2024-10-24T18:55:14.088Z,1729796114.088 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-10-24T18:55:17.425Z,1729796117.425 [DataOverHttps](INFO): Sending 114 bytes from file Logs/20241024T134500/Express0085.lzma 2024-10-24T18:55:18.427Z,1729796118.427 [DataOverHttps](INFO): Moved sent file to Logs/20241024T134500/Express0085.lzma.bak 2024-10-24T18:55:18.427Z,1729796118.427 [DataOverHttps](INFO): SBD MOMSN=23333684 2024-10-24T18:55:27.020Z,1729796127.020 [NAL9602](INFO): SBD MO Status=2, MOMSN=59438, MT Status=2, MTMSN=0 2024-10-24T18:55:27.020Z,1729796127.020 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-10-24T18:55:34.326Z,1729796134.326 [DataOverHttps](INFO): Sending 459 bytes from file Logs/20241024T185338/Express0001.lzma 2024-10-24T18:55:34.672Z,1729796134.672 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T18:55:35.327Z,1729796135.327 [DataOverHttps](INFO): Moved sent file to Logs/20241024T185338/Express0001.lzma.bak 2024-10-24T18:55:35.327Z,1729796135.327 [DataOverHttps](INFO): SBD MOMSN=23333687 2024-10-24T18:55:48.408Z,1729796148.408 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T18:55:52.485Z,1729796152.485 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20241024T185338/Express0004.lzma 2024-10-24T18:55:53.487Z,1729796153.487 [DataOverHttps](INFO): Moved sent file to Logs/20241024T185338/Express0004.lzma.bak 2024-10-24T18:55:53.487Z,1729796153.487 [DataOverHttps](INFO): SBD MOMSN=23333691 2024-10-24T18:55:55.301Z,1729796155.301 [Default:CheckIn:Read_Iridium] Stopped 2024-10-24T18:55:55.301Z,1729796155.301 [Default:CheckIn:C.Wait] Running Loop=1 2024-10-24T18:55:55.301Z,1729796155.301 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-10-24T18:56:01.739Z,1729796161.739 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T18:56:05.788Z,1729796165.788 [NAL9602](INFO): SBD MO Status=2, MOMSN=59438, MT Status=2, MTMSN=0 2024-10-24T18:56:05.788Z,1729796165.788 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-10-24T18:56:15.073Z,1729796175.073 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T18:56:28.807Z,1729796188.807 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T18:56:32.048Z,1729796192.048 [NAL9602](INFO): SBD MO Status=2, MOMSN=59438, MT Status=2, MTMSN=0 2024-10-24T18:56:32.048Z,1729796192.048 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-10-24T18:56:42.139Z,1729796202.139 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T18:56:48.204Z,1729796208.204 [NAL9602](INFO): SBD MO Status=2, MOMSN=59438, MT Status=2, MTMSN=0 2024-10-24T18:56:48.204Z,1729796208.204 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-10-24T18:56:56.684Z,1729796216.684 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T18:57:08.411Z,1729796228.411 [NAL9602](INFO): SBD MO Status=1, MOMSN=59438, MT Status=0, MTMSN=0 2024-10-24T18:57:08.412Z,1729796228.412 [NAL9602](INFO): No messages in MT queue 2024-10-24T18:57:10.824Z,1729796230.824 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T18:57:24.160Z,1729796244.160 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T18:57:37.488Z,1729796257.488 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T18:57:39.213Z,1729796259.213 [NAL9602](INFO): Not Powering down - fast GPS 2024-10-24T19:00:55.960Z,1729796455.960 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-10-24T19:00:55.960Z,1729796455.960 [Default:CheckIn:C.Wait] Stopped 2024-10-24T19:00:55.960Z,1729796455.960 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-10-24T19:00:55.960Z,1729796455.960 [Default:CheckIn:D] Running Loop=1 2024-10-24T19:00:56.360Z,1729796456.360 [Default:CheckIn:D] Stopped 2024-10-24T19:00:56.360Z,1729796456.360 [Default:CheckIn:E] Running Loop=1 2024-10-24T19:00:56.769Z,1729796456.769 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.458221 min 2024-10-24T19:00:56.769Z,1729796456.769 [Default:CheckIn:E] Stopped 2024-10-24T19:00:56.769Z,1729796456.769 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-10-24T19:00:56.769Z,1729796456.769 [Default:CheckIn] Stopped 2024-10-24T19:00:56.769Z,1729796456.769 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-10-24T19:00:56.770Z,1729796456.770 [Default:CheckIn](INFO): Running loop #4 2024-10-24T19:00:56.770Z,1729796456.770 [Default:CheckIn] Running Loop=4 2024-10-24T19:00:56.770Z,1729796456.770 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-10-24T19:00:56.770Z,1729796456.770 [Default:CheckIn:Read_GPS] Running Loop=1 2024-10-24T19:00:58.776Z,1729796458.776 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190057.00,A,3647.76346,N,12148.88115,W,29.255,77.95,241024,,,D*7A 2024-10-24T19:00:58.778Z,1729796458.778 [NAL9602](INFO): GPS fix at 20241024T190057: (36.796058, -121.814686) 2024-10-24T19:00:58.806Z,1729796458.806 [Default:CheckIn:Read_GPS] Stopped 2024-10-24T19:00:58.806Z,1729796458.806 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-10-24T19:01:12.915Z,1729796472.915 [NAL9602](INFO): SBD MO Status=1, MOMSN=59439, MT Status=0, MTMSN=0 2024-10-24T19:01:12.915Z,1729796472.915 [NAL9602](INFO): No messages in MT queue 2024-10-24T19:01:37.865Z,1729796497.865 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241024T185338/Courier0006.lzma 2024-10-24T19:01:38.867Z,1729796498.867 [DataOverHttps](INFO): Moved sent file to Logs/20241024T185338/Courier0006.lzma.bak 2024-10-24T19:01:38.867Z,1729796498.867 [DataOverHttps](INFO): SBD MOMSN=23333703 2024-10-24T19:01:43.618Z,1729796503.618 [NAL9602](INFO): Not Powering down - fast GPS 2024-10-24T19:02:08.601Z,1729796528.601 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20241024T185338/Express0007.lzma 2024-10-24T19:02:09.603Z,1729796529.603 [DataOverHttps](INFO): Moved sent file to Logs/20241024T185338/Express0007.lzma.bak 2024-10-24T19:02:09.603Z,1729796529.603 [DataOverHttps](INFO): SBD MOMSN=23333707 2024-10-24T19:02:11.517Z,1729796531.517 [Default:CheckIn:Read_Iridium] Stopped 2024-10-24T19:02:11.517Z,1729796531.517 [Default:CheckIn:C.Wait] Running Loop=1 2024-10-24T19:02:11.517Z,1729796531.517 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-10-24T19:05:02.929Z,1729796702.929 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:05:16.258Z,1729796716.258 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:05:29.590Z,1729796729.590 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:05:43.270Z,1729796743.270 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:05:56.602Z,1729796756.602 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:06:09.935Z,1729796769.935 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:06:23.351Z,1729796783.351 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:06:36.655Z,1729796796.655 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:06:49.989Z,1729796809.989 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:07:03.760Z,1729796823.760 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:07:12.256Z,1729796832.256 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-10-24T19:07:12.256Z,1729796832.256 [Default:CheckIn:C.Wait] Stopped 2024-10-24T19:07:12.256Z,1729796832.256 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-10-24T19:07:12.257Z,1729796832.257 [Default:CheckIn:D] Running Loop=1 2024-10-24T19:07:12.669Z,1729796832.669 [Default:CheckIn:D] Stopped 2024-10-24T19:07:12.669Z,1729796832.669 [Default:CheckIn:E] Running Loop=1 2024-10-24T19:07:13.083Z,1729796833.083 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 27.730035 min 2024-10-24T19:07:13.083Z,1729796833.083 [Default:CheckIn:E] Stopped 2024-10-24T19:07:13.083Z,1729796833.083 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-10-24T19:07:13.083Z,1729796833.083 [Default:CheckIn] Stopped 2024-10-24T19:07:13.083Z,1729796833.083 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-10-24T19:07:13.083Z,1729796833.083 [Default:CheckIn](INFO): Running loop #5 2024-10-24T19:07:13.084Z,1729796833.084 [Default:CheckIn] Running Loop=5 2024-10-24T19:07:13.084Z,1729796833.084 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-10-24T19:07:13.084Z,1729796833.084 [Default:CheckIn:Read_GPS] Running Loop=1 2024-10-24T19:07:15.076Z,1729796835.076 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190714.00,A,3648.37457,N,12147.12317,W,5.268,173.18,241024,,,D*7B 2024-10-24T19:07:15.078Z,1729796835.078 [NAL9602](INFO): GPS fix at 20241024T190714: (36.806243, -121.785386) 2024-10-24T19:07:15.089Z,1729796835.089 [Default:CheckIn:Read_GPS] Stopped 2024-10-24T19:07:15.089Z,1729796835.089 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-10-24T19:07:22.917Z,1729796842.917 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241024T185338/Courier0009.lzma 2024-10-24T19:07:23.919Z,1729796843.919 [DataOverHttps](INFO): Moved sent file to Logs/20241024T185338/Courier0009.lzma.bak 2024-10-24T19:07:23.919Z,1729796843.919 [DataOverHttps](INFO): SBD MOMSN=23333725 2024-10-24T19:07:39.897Z,1729796859.897 [DataOverHttps](INFO): Sending 177 bytes from file Logs/20241024T185338/Express0010.lzma 2024-10-24T19:07:40.899Z,1729796860.899 [DataOverHttps](INFO): Moved sent file to Logs/20241024T185338/Express0010.lzma.bak 2024-10-24T19:07:40.899Z,1729796860.899 [DataOverHttps](INFO): SBD MOMSN=23333729 2024-10-24T19:07:42.561Z,1729796862.561 [Default:CheckIn:Read_Iridium] Stopped 2024-10-24T19:07:42.562Z,1729796862.562 [Default:CheckIn:C.Wait] Running Loop=1 2024-10-24T19:07:42.562Z,1729796862.562 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-10-24T19:07:57.551Z,1729796877.551 [NAL9602](INFO): SBD MO Status=1, MOMSN=59440, MT Status=0, MTMSN=0 2024-10-24T19:07:57.552Z,1729796877.552 [NAL9602](INFO): No messages in MT queue 2024-10-24T19:08:28.223Z,1729796908.223 [NAL9602](INFO): Not Powering down - fast GPS 2024-10-24T19:12:43.332Z,1729797163.332 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-10-24T19:12:43.333Z,1729797163.333 [Default:CheckIn:C.Wait] Stopped 2024-10-24T19:12:43.333Z,1729797163.333 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-10-24T19:12:43.333Z,1729797163.333 [Default:CheckIn:D] Running Loop=1 2024-10-24T19:12:43.738Z,1729797163.738 [Default:CheckIn:D] Stopped 2024-10-24T19:12:43.738Z,1729797163.738 [Default:CheckIn:E] Running Loop=1 2024-10-24T19:12:44.180Z,1729797164.180 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 33.247854 min 2024-10-24T19:12:44.180Z,1729797164.180 [Default:CheckIn:E] Stopped 2024-10-24T19:12:44.180Z,1729797164.180 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-10-24T19:12:44.180Z,1729797164.180 [Default:CheckIn] Stopped 2024-10-24T19:12:44.180Z,1729797164.180 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-10-24T19:12:44.181Z,1729797164.181 [Default:CheckIn](INFO): Running loop #6 2024-10-24T19:12:44.181Z,1729797164.181 [Default:CheckIn] Running Loop=6 2024-10-24T19:12:44.181Z,1729797164.181 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-10-24T19:12:44.181Z,1729797164.181 [Default:CheckIn:Read_GPS] Running Loop=1 2024-10-24T19:12:46.145Z,1729797166.145 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191245.00,A,3648.15037,N,12147.22212,W,0.019,212.86,241024,,,D*7C 2024-10-24T19:12:46.147Z,1729797166.147 [NAL9602](INFO): GPS fix at 20241024T191245: (36.802506, -121.787035) 2024-10-24T19:12:46.158Z,1729797166.158 [Default:CheckIn:Read_GPS] Stopped 2024-10-24T19:12:46.158Z,1729797166.158 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-10-24T19:12:56.775Z,1729797176.775 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241024T185338/Courier0012.lzma 2024-10-24T19:12:57.303Z,1729797177.303 [DataOverHttps](INFO): Moved sent file to Logs/20241024T185338/Courier0012.lzma.bak 2024-10-24T19:12:57.303Z,1729797177.303 [DataOverHttps](INFO): SBD MOMSN=23333747 2024-10-24T19:13:14.524Z,1729797194.524 [DataOverHttps](INFO): Sending 165 bytes from file Logs/20241024T185338/Express0013.lzma 2024-10-24T19:13:15.523Z,1729797195.523 [DataOverHttps](INFO): Moved sent file to Logs/20241024T185338/Express0013.lzma.bak 2024-10-24T19:13:15.523Z,1729797195.523 [DataOverHttps](INFO): SBD MOMSN=23333751 2024-10-24T19:13:17.424Z,1729797197.424 [Default:CheckIn:Read_Iridium] Stopped 2024-10-24T19:13:17.425Z,1729797197.425 [Default:CheckIn:C.Wait] Running Loop=1 2024-10-24T19:13:17.425Z,1729797197.425 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-10-24T19:13:34.733Z,1729797214.733 [NAL9602](INFO): SBD MO Status=1, MOMSN=59441, MT Status=0, MTMSN=0 2024-10-24T19:13:34.733Z,1729797214.733 [NAL9602](INFO): No messages in MT queue 2024-10-24T19:14:05.425Z,1729797245.425 [NAL9602](INFO): Not Powering down - fast GPS 2024-10-24T19:14:24.182Z,1729797264.182 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-10-24T19:14:30.138Z,1729797270.138 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:14:45.883Z,1729797285.883 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:15:01.688Z,1729797301.688 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:15:17.446Z,1729797317.446 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:15:32.795Z,1729797332.795 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:15:48.955Z,1729797348.955 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:16:04.711Z,1729797364.711 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:16:20.063Z,1729797380.063 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:16:35.874Z,1729797395.874 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:18:18.121Z,1729797498.121 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-10-24T19:18:18.121Z,1729797498.121 [Default:CheckIn:C.Wait] Stopped 2024-10-24T19:18:18.121Z,1729797498.121 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-10-24T19:18:18.122Z,1729797498.122 [Default:CheckIn:D] Running Loop=1 2024-10-24T19:18:18.537Z,1729797498.537 [Default:CheckIn:D] Stopped 2024-10-24T19:18:18.537Z,1729797498.537 [Default:CheckIn:E] Running Loop=1 2024-10-24T19:18:18.945Z,1729797498.945 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 38.827706 min 2024-10-24T19:18:18.946Z,1729797498.946 [Default:CheckIn:E] Stopped 2024-10-24T19:18:18.946Z,1729797498.946 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-10-24T19:18:18.946Z,1729797498.946 [Default:CheckIn] Stopped 2024-10-24T19:18:18.946Z,1729797498.946 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-10-24T19:18:18.946Z,1729797498.946 [Default:CheckIn](INFO): Running loop #7 2024-10-24T19:18:18.946Z,1729797498.946 [Default:CheckIn] Running Loop=7 2024-10-24T19:18:18.946Z,1729797498.946 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-10-24T19:18:18.946Z,1729797498.946 [Default:CheckIn:Read_GPS] Running Loop=1 2024-10-24T19:18:20.924Z,1729797500.924 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191819.00,A,3648.13870,N,12147.23488,W,0.039,223.33,241024,,,D*78 2024-10-24T19:18:20.926Z,1729797500.926 [NAL9602](INFO): GPS fix at 20241024T191819: (36.802312, -121.787248) 2024-10-24T19:18:20.946Z,1729797500.946 [Default:CheckIn:Read_GPS] Stopped 2024-10-24T19:18:20.946Z,1729797500.946 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-10-24T19:18:31.471Z,1729797511.471 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241024T185338/Courier0015.lzma 2024-10-24T19:18:32.243Z,1729797512.243 [DataOverHttps](INFO): Moved sent file to Logs/20241024T185338/Courier0015.lzma.bak 2024-10-24T19:18:32.243Z,1729797512.243 [DataOverHttps](INFO): SBD MOMSN=23333755 2024-10-24T19:18:32.279Z,1729797512.279 [NAL9602](INFO): SBD MO Status=1, MOMSN=59442, MT Status=0, MTMSN=0 2024-10-24T19:18:32.280Z,1729797512.280 [NAL9602](INFO): No messages in MT queue 2024-10-24T19:18:48.065Z,1729797528.065 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20241024T185338/Express0016.lzma 2024-10-24T19:18:49.067Z,1729797529.067 [DataOverHttps](INFO): Moved sent file to Logs/20241024T185338/Express0016.lzma.bak 2024-10-24T19:18:49.067Z,1729797529.067 [DataOverHttps](INFO): SBD MOMSN=23333761 2024-10-24T19:18:51.323Z,1729797531.323 [Default:CheckIn:Read_Iridium] Stopped 2024-10-24T19:18:51.323Z,1729797531.323 [Default:CheckIn:C.Wait] Running Loop=1 2024-10-24T19:18:51.323Z,1729797531.323 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-10-24T19:19:03.059Z,1729797543.059 [NAL9602](INFO): Not Powering down - fast GPS 2024-10-24T19:21:32.532Z,1729797692.532 [BPC1](INFO): Calculating totals. Valid battery stick count: 47. Valid reserve battery stick count: 4. 2024-10-24T19:21:32.536Z,1729797692.536 [BPC1](FAULT): Failed to receive data from 7 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 32, 33, 36, 37, 39, 51, 54. 2024-10-24T19:23:51.926Z,1729797831.926 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-10-24T19:23:51.926Z,1729797831.926 [Default:CheckIn:C.Wait] Stopped 2024-10-24T19:23:51.926Z,1729797831.926 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-10-24T19:23:51.926Z,1729797831.926 [Default:CheckIn:D] Running Loop=1 2024-10-24T19:23:52.338Z,1729797832.338 [Default:CheckIn:D] Stopped 2024-10-24T19:23:52.338Z,1729797832.338 [Default:CheckIn:E] Running Loop=1 2024-10-24T19:23:52.732Z,1729797832.732 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 44.391187 min 2024-10-24T19:23:52.732Z,1729797832.732 [Default:CheckIn:E] Stopped 2024-10-24T19:23:52.732Z,1729797832.732 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-10-24T19:23:52.732Z,1729797832.732 [Default:CheckIn] Stopped 2024-10-24T19:23:52.732Z,1729797832.732 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-10-24T19:23:52.732Z,1729797832.732 [Default:CheckIn](INFO): Running loop #8 2024-10-24T19:23:52.732Z,1729797832.732 [Default:CheckIn] Running Loop=8 2024-10-24T19:23:52.732Z,1729797832.732 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-10-24T19:23:52.732Z,1729797832.732 [Default:CheckIn:Read_GPS] Running Loop=1 2024-10-24T19:23:54.744Z,1729797834.744 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192353.00,A,3648.17525,N,12147.27918,W,0.233,319.37,241024,,,D*73 2024-10-24T19:23:54.746Z,1729797834.746 [NAL9602](INFO): GPS fix at 20241024T192353: (36.802921, -121.787986) 2024-10-24T19:23:54.758Z,1729797834.758 [Default:CheckIn:Read_GPS] Stopped 2024-10-24T19:23:54.758Z,1729797834.758 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-10-24T19:24:02.765Z,1729797842.765 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20241024T185338/Courier0018.lzma 2024-10-24T19:24:03.767Z,1729797843.767 [DataOverHttps](INFO): Moved sent file to Logs/20241024T185338/Courier0018.lzma.bak 2024-10-24T19:24:03.767Z,1729797843.767 [DataOverHttps](INFO): SBD MOMSN=23333764 2024-10-24T19:24:12.124Z,1729797852.124 [NAL9602](INFO): SBD MO Status=1, MOMSN=59443, MT Status=0, MTMSN=0 2024-10-24T19:24:12.124Z,1729797852.124 [NAL9602](INFO): No messages in MT queue 2024-10-24T19:24:21.378Z,1729797861.378 [DataOverHttps](INFO): Sending 300 bytes from file Logs/20241024T185338/Express0019.lzma 2024-10-24T19:24:22.379Z,1729797862.379 [DataOverHttps](INFO): Moved sent file to Logs/20241024T185338/Express0019.lzma.bak 2024-10-24T19:24:22.379Z,1729797862.379 [DataOverHttps](INFO): SBD MOMSN=23333768 2024-10-24T19:24:24.706Z,1729797864.706 [Default:CheckIn:Read_Iridium] Stopped 2024-10-24T19:24:24.706Z,1729797864.706 [Default:CheckIn:C.Wait] Running Loop=1 2024-10-24T19:24:24.706Z,1729797864.706 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-10-24T19:24:42.858Z,1729797882.858 [NAL9602](INFO): Not Powering down - fast GPS 2024-10-24T19:29:25.282Z,1729798165.282 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-10-24T19:29:25.282Z,1729798165.282 [Default:CheckIn:C.Wait] Stopped 2024-10-24T19:29:25.282Z,1729798165.282 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-10-24T19:29:25.282Z,1729798165.282 [Default:CheckIn:D] Running Loop=1 2024-10-24T19:29:25.674Z,1729798165.674 [Default:CheckIn:D] Stopped 2024-10-24T19:29:25.674Z,1729798165.674 [Default:CheckIn:E] Running Loop=1 2024-10-24T19:29:26.077Z,1729798166.077 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 49.946785 min 2024-10-24T19:29:26.077Z,1729798166.077 [Default:CheckIn:E] Stopped 2024-10-24T19:29:26.077Z,1729798166.077 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-10-24T19:29:26.077Z,1729798166.077 [Default:CheckIn] Stopped 2024-10-24T19:29:26.077Z,1729798166.077 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-10-24T19:29:26.078Z,1729798166.078 [Default:CheckIn](INFO): Running loop #9 2024-10-24T19:29:26.078Z,1729798166.078 [Default:CheckIn] Running Loop=9 2024-10-24T19:29:26.078Z,1729798166.078 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-10-24T19:29:26.078Z,1729798166.078 [Default:CheckIn:Read_GPS] Running Loop=1 2024-10-24T19:29:28.086Z,1729798168.086 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192927.00,A,3648.17572,N,12147.27888,W,0.117,319.37,241024,,,D*75 2024-10-24T19:29:28.088Z,1729798168.088 [NAL9602](INFO): GPS fix at 20241024T192927: (36.802929, -121.787981) 2024-10-24T19:29:28.099Z,1729798168.099 [Default:CheckIn:Read_GPS] Stopped 2024-10-24T19:29:28.099Z,1729798168.099 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-10-24T19:29:35.873Z,1729798175.873 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241024T185338/Courier0021.lzma 2024-10-24T19:29:36.875Z,1729798176.875 [DataOverHttps](INFO): Moved sent file to Logs/20241024T185338/Courier0021.lzma.bak 2024-10-24T19:29:36.875Z,1729798176.875 [DataOverHttps](INFO): SBD MOMSN=23333772 2024-10-24T19:29:42.228Z,1729798182.228 [NAL9602](INFO): SBD MO Status=1, MOMSN=59444, MT Status=0, MTMSN=0 2024-10-24T19:29:42.228Z,1729798182.228 [NAL9602](INFO): No messages in MT queue 2024-10-24T19:29:52.749Z,1729798192.749 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20241024T185338/Express0022.lzma 2024-10-24T19:29:53.751Z,1729798193.751 [DataOverHttps](INFO): Moved sent file to Logs/20241024T185338/Express0022.lzma.bak 2024-10-24T19:29:53.751Z,1729798193.751 [DataOverHttps](INFO): SBD MOMSN=23333775 2024-10-24T19:29:55.573Z,1729798195.573 [Default:CheckIn:Read_Iridium] Stopped 2024-10-24T19:29:55.574Z,1729798195.574 [Default:CheckIn:C.Wait] Running Loop=1 2024-10-24T19:29:55.574Z,1729798195.574 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-10-24T19:30:12.930Z,1729798212.930 [NAL9602](INFO): Not Powering down - fast GPS 2024-10-24T19:34:56.233Z,1729798496.233 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-10-24T19:34:56.233Z,1729798496.233 [Default:CheckIn:C.Wait] Stopped 2024-10-24T19:34:56.233Z,1729798496.233 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-10-24T19:34:56.234Z,1729798496.234 [Default:CheckIn:D] Running Loop=1 2024-10-24T19:34:56.568Z,1729798496.568 [Default:CheckIn:D] Stopped 2024-10-24T19:34:56.568Z,1729798496.568 [Default:CheckIn:E] Running Loop=1 2024-10-24T19:34:56.985Z,1729798496.985 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 55.461686 min 2024-10-24T19:34:56.985Z,1729798496.985 [Default:CheckIn:E] Stopped 2024-10-24T19:34:56.985Z,1729798496.985 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-10-24T19:34:56.985Z,1729798496.985 [Default:CheckIn] Stopped 2024-10-24T19:34:56.986Z,1729798496.986 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-10-24T19:34:56.986Z,1729798496.986 [Default:CheckIn](INFO): Running loop #10 2024-10-24T19:34:56.986Z,1729798496.986 [Default:CheckIn] Running Loop=10 2024-10-24T19:34:56.986Z,1729798496.986 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-10-24T19:34:56.986Z,1729798496.986 [Default:CheckIn:Read_GPS] Running Loop=1 2024-10-24T19:34:58.980Z,1729798498.980 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193458.00,A,3648.17429,N,12147.27876,W,0.097,319.37,241024,,,A*73 2024-10-24T19:34:58.993Z,1729798498.993 [NAL9602](INFO): GPS fix at 20241024T193458: (36.802905, -121.787979) 2024-10-24T19:34:59.004Z,1729798499.004 [Default:CheckIn:Read_GPS] Stopped 2024-10-24T19:34:59.004Z,1729798499.004 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-10-24T19:35:06.041Z,1729798506.041 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241024T185338/Courier0024.lzma 2024-10-24T19:35:07.044Z,1729798507.044 [DataOverHttps](INFO): Moved sent file to Logs/20241024T185338/Courier0024.lzma.bak 2024-10-24T19:35:07.045Z,1729798507.045 [DataOverHttps](INFO): SBD MOMSN=23333804 2024-10-24T19:35:23.049Z,1729798523.049 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20241024T185338/Express0025.lzma 2024-10-24T19:35:24.051Z,1729798524.051 [DataOverHttps](INFO): Moved sent file to Logs/20241024T185338/Express0025.lzma.bak 2024-10-24T19:35:24.051Z,1729798524.051 [DataOverHttps](INFO): SBD MOMSN=23333807 2024-10-24T19:35:25.735Z,1729798525.735 [Default:CheckIn:Read_Iridium] Stopped 2024-10-24T19:35:25.735Z,1729798525.735 [Default:CheckIn:C.Wait] Running Loop=1 2024-10-24T19:35:25.735Z,1729798525.735 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-10-24T19:36:36.816Z,1729798596.816 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:36:50.552Z,1729798610.552 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:37:04.691Z,1729798624.691 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:37:18.427Z,1729798638.427 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:37:31.760Z,1729798651.760 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:37:45.092Z,1729798665.092 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:37:58.829Z,1729798678.829 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:38:12.969Z,1729798692.969 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:38:26.300Z,1729798706.300 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:38:36.408Z,1729798716.408 [NAL9602](INFO): SBD MO Status=1, MOMSN=59445, MT Status=0, MTMSN=0 2024-10-24T19:38:36.408Z,1729798716.408 [NAL9602](INFO): No messages in MT queue 2024-10-24T19:38:40.038Z,1729798720.038 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:39:07.126Z,1729798747.126 [NAL9602](INFO): Not Powering down - fast GPS 2024-10-24T19:40:26.302Z,1729798826.302 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-10-24T19:40:26.302Z,1729798826.302 [Default:CheckIn:C.Wait] Stopped 2024-10-24T19:40:26.302Z,1729798826.302 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-10-24T19:40:26.303Z,1729798826.303 [Default:CheckIn:D] Running Loop=1 2024-10-24T19:40:26.710Z,1729798826.710 [Default:CheckIn:D] Stopped 2024-10-24T19:40:26.710Z,1729798826.710 [Default:CheckIn:E] Running Loop=1 2024-10-24T19:40:27.126Z,1729798827.126 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 60.964054 min 2024-10-24T19:40:27.126Z,1729798827.126 [Default:CheckIn:E] Stopped 2024-10-24T19:40:27.126Z,1729798827.126 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-10-24T19:40:27.126Z,1729798827.126 [Default:CheckIn] Stopped 2024-10-24T19:40:27.126Z,1729798827.126 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-10-24T19:40:27.126Z,1729798827.126 [Default:CheckIn](INFO): Running loop #11 2024-10-24T19:40:27.126Z,1729798827.126 [Default:CheckIn] Running Loop=11 2024-10-24T19:40:27.127Z,1729798827.127 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-10-24T19:40:27.127Z,1729798827.127 [Default:CheckIn:Read_GPS] Running Loop=1 2024-10-24T19:40:29.142Z,1729798829.142 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194028.00,A,3648.17617,N,12147.27571,W,0.156,319.37,241024,,,A*7E 2024-10-24T19:40:29.144Z,1729798829.144 [NAL9602](INFO): GPS fix at 20241024T194028: (36.802936, -121.787929) 2024-10-24T19:40:29.155Z,1729798829.155 [Default:CheckIn:Read_GPS] Stopped 2024-10-24T19:40:29.155Z,1729798829.155 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-10-24T19:40:36.145Z,1729798836.145 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20241024T185338/Courier0027.lzma 2024-10-24T19:40:37.147Z,1729798837.147 [DataOverHttps](INFO): Moved sent file to Logs/20241024T185338/Courier0027.lzma.bak 2024-10-24T19:40:37.147Z,1729798837.147 [DataOverHttps](INFO): SBD MOMSN=23333887 2024-10-24T19:40:40.119Z,1729798840.119 [NAL9602](INFO): SBD MO Status=1, MOMSN=59446, MT Status=0, MTMSN=0 2024-10-24T19:40:40.119Z,1729798840.119 [NAL9602](INFO): No messages in MT queue 2024-10-24T19:40:56.761Z,1729798856.761 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20241024T185338/Express0028.lzma 2024-10-24T19:40:57.763Z,1729798857.763 [DataOverHttps](INFO): Moved sent file to Logs/20241024T185338/Express0028.lzma.bak 2024-10-24T19:40:57.763Z,1729798857.763 [DataOverHttps](INFO): SBD MOMSN=23333890 2024-10-24T19:41:00.015Z,1729798860.015 [Default:CheckIn:Read_Iridium] Stopped 2024-10-24T19:41:00.015Z,1729798860.015 [Default:CheckIn:C.Wait] Running Loop=1 2024-10-24T19:41:00.015Z,1729798860.015 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-10-24T19:41:10.882Z,1729798870.882 [NAL9602](INFO): Not Powering down - fast GPS 2024-10-24T19:44:50.041Z,1729799090.041 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2024-10-24T19:46:00.752Z,1729799160.752 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-10-24T19:46:00.752Z,1729799160.752 [Default:CheckIn:C.Wait] Stopped 2024-10-24T19:46:00.752Z,1729799160.752 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-10-24T19:46:00.752Z,1729799160.752 [Default:CheckIn:D] Running Loop=1 2024-10-24T19:46:01.153Z,1729799161.153 [Default:CheckIn:D] Stopped 2024-10-24T19:46:01.153Z,1729799161.153 [Default:CheckIn:E] Running Loop=1 2024-10-24T19:46:01.570Z,1729799161.570 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 66.538114 min 2024-10-24T19:46:01.570Z,1729799161.570 [Default:CheckIn:E] Stopped 2024-10-24T19:46:01.570Z,1729799161.570 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-10-24T19:46:01.570Z,1729799161.570 [Default:CheckIn] Stopped 2024-10-24T19:46:01.570Z,1729799161.570 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-10-24T19:46:01.570Z,1729799161.570 [Default:CheckIn](INFO): Running loop #12 2024-10-24T19:46:01.570Z,1729799161.570 [Default:CheckIn] Running Loop=12 2024-10-24T19:46:01.570Z,1729799161.570 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-10-24T19:46:01.570Z,1729799161.570 [Default:CheckIn:Read_GPS] Running Loop=1 2024-10-24T19:46:03.572Z,1729799163.572 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194602.00,A,3648.17509,N,12147.27684,W,0.136,319.37,241024,,,A*73 2024-10-24T19:46:03.574Z,1729799163.574 [NAL9602](INFO): GPS fix at 20241024T194602: (36.802918, -121.787947) 2024-10-24T19:46:03.586Z,1729799163.586 [Default:CheckIn:Read_GPS] Stopped 2024-10-24T19:46:03.586Z,1729799163.586 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-10-24T19:46:07.200Z,1729799167.200 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:46:11.285Z,1729799171.285 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241024T185338/Courier0030.lzma 2024-10-24T19:46:12.287Z,1729799172.287 [DataOverHttps](INFO): Moved sent file to Logs/20241024T185338/Courier0030.lzma.bak 2024-10-24T19:46:12.287Z,1729799172.287 [DataOverHttps](INFO): SBD MOMSN=23333941 2024-10-24T19:46:18.916Z,1729799178.916 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:46:31.448Z,1729799191.448 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:46:31.557Z,1729799191.557 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20241024T185338/Express0031.lzma 2024-10-24T19:46:32.559Z,1729799192.559 [DataOverHttps](INFO): Moved sent file to Logs/20241024T185338/Express0031.lzma.bak 2024-10-24T19:46:32.559Z,1729799192.559 [DataOverHttps](INFO): SBD MOMSN=23333946 2024-10-24T19:46:34.301Z,1729799194.301 [Default:CheckIn:Read_Iridium] Stopped 2024-10-24T19:46:34.301Z,1729799194.301 [Default:CheckIn:C.Wait] Running Loop=1 2024-10-24T19:46:34.301Z,1729799194.301 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-10-24T19:46:43.569Z,1729799203.569 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:46:54.957Z,1729799214.957 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:47:06.643Z,1729799226.643 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:47:18.777Z,1729799238.777 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:47:31.294Z,1729799251.294 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:47:43.408Z,1729799263.408 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:47:55.157Z,1729799275.157 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2024-10-24T19:47:55.157Z,1729799275.157 [DropWeight] Hardware Fault, FailCount= 1 2024-10-24T19:47:55.157Z,1729799275.157 [DropWeight](ERROR): Hardware Fault 2024-10-24T19:47:55.175Z,1729799275.175 [CommandExec](FAULT): Scheduling is paused 2024-10-24T19:47:55.175Z,1729799275.175 [CBIT](INFO): Critical error at 20241024T194755 2024-10-24T19:47:55.178Z,1729799275.178 [CBIT](ERROR): Hardware Fault in component: DropWeight 2024-10-24T19:47:55.178Z,1729799275.178 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2024-10-24T19:47:55.527Z,1729799275.527 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:47:55.568Z,1729799275.568 [CBIT](INFO): Critical error at 20241024T194755 2024-10-24T19:48:06.443Z,1729799286.443 [NAL9602](INFO): SBD MO Status=2, MOMSN=59447, MT Status=2, MTMSN=0 2024-10-24T19:48:06.444Z,1729799286.444 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-10-24T19:48:07.275Z,1729799287.275 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:48:19.770Z,1729799299.770 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:48:27.854Z,1729799307.854 [NAL9602](INFO): SBD MO Status=1, MOMSN=59447, MT Status=0, MTMSN=0 2024-10-24T19:48:27.855Z,1729799307.855 [NAL9602](INFO): No messages in MT queue 2024-10-24T19:48:58.554Z,1729799338.554 [NAL9602](INFO): Not Powering down - fast GPS 2024-10-24T19:51:34.930Z,1729799494.930 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-10-24T19:51:34.930Z,1729799494.930 [Default:CheckIn:C.Wait] Stopped 2024-10-24T19:51:34.930Z,1729799494.930 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-10-24T19:51:34.931Z,1729799494.931 [Default:CheckIn:D] Running Loop=1 2024-10-24T19:51:35.320Z,1729799495.320 [Default:CheckIn:D] Stopped 2024-10-24T19:51:35.320Z,1729799495.320 [Default:CheckIn:E] Running Loop=1 2024-10-24T19:51:35.724Z,1729799495.724 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 72.107560 min 2024-10-24T19:51:35.724Z,1729799495.724 [Default:CheckIn:E] Stopped 2024-10-24T19:51:35.724Z,1729799495.724 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-10-24T19:51:35.724Z,1729799495.724 [Default:CheckIn] Stopped 2024-10-24T19:51:35.724Z,1729799495.724 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-10-24T19:51:35.725Z,1729799495.725 [Default:CheckIn](INFO): Running loop #13 2024-10-24T19:51:35.725Z,1729799495.725 [Default:CheckIn] Running Loop=13 2024-10-24T19:51:35.725Z,1729799495.725 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-10-24T19:51:35.725Z,1729799495.725 [Default:CheckIn:Read_GPS] Running Loop=1 2024-10-24T19:51:37.733Z,1729799497.733 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195136.00,A,3648.17662,N,12147.27931,W,0.467,344.03,241024,,,A*73 2024-10-24T19:51:37.735Z,1729799497.735 [NAL9602](INFO): GPS fix at 20241024T195136: (36.802944, -121.787988) 2024-10-24T19:51:37.746Z,1729799497.746 [Default:CheckIn:Read_GPS] Stopped 2024-10-24T19:51:37.746Z,1729799497.746 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-10-24T19:51:44.949Z,1729799504.949 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20241024T185338/Courier0033.lzma 2024-10-24T19:51:46.671Z,1729799506.671 [DataOverHttps](INFO): Moved sent file to Logs/20241024T185338/Courier0033.lzma.bak 2024-10-24T19:51:46.671Z,1729799506.671 [DataOverHttps](INFO): SBD MOMSN=23333962 2024-10-24T19:52:01.905Z,1729799521.905 [DataOverHttps](INFO): Sending 167 bytes from file Logs/20241024T185338/Express0034.lzma 2024-10-24T19:52:02.907Z,1729799522.907 [DataOverHttps](INFO): Moved sent file to Logs/20241024T185338/Express0034.lzma.bak 2024-10-24T19:52:02.907Z,1729799522.907 [DataOverHttps](INFO): SBD MOMSN=23333969 2024-10-24T19:52:04.808Z,1729799524.808 [Default:CheckIn:Read_Iridium] Stopped 2024-10-24T19:52:04.808Z,1729799524.808 [Default:CheckIn:C.Wait] Running Loop=1 2024-10-24T19:52:04.808Z,1729799524.808 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-10-24T19:52:15.707Z,1729799535.707 [NAL9602](INFO): SBD MO Status=2, MOMSN=59448, MT Status=2, MTMSN=0 2024-10-24T19:52:15.708Z,1729799535.708 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-10-24T19:52:35.909Z,1729799555.909 [NAL9602](INFO): SBD MO Status=1, MOMSN=59448, MT Status=0, MTMSN=0 2024-10-24T19:52:35.910Z,1729799555.910 [NAL9602](INFO): No messages in MT queue 2024-10-24T19:53:06.612Z,1729799586.612 [NAL9602](INFO): Not Powering down - fast GPS 2024-10-24T19:55:37.704Z,1729799737.704 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:55:51.440Z,1729799751.440 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:56:05.176Z,1729799765.176 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:56:18.508Z,1729799778.508 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:56:31.840Z,1729799791.840 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:56:45.171Z,1729799805.171 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:56:58.503Z,1729799818.503 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:57:05.383Z,1729799825.383 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-10-24T19:57:05.383Z,1729799825.383 [Default:CheckIn:C.Wait] Stopped 2024-10-24T19:57:05.384Z,1729799825.384 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-10-24T19:57:05.384Z,1729799825.384 [Default:CheckIn:D] Running Loop=1 2024-10-24T19:57:05.794Z,1729799825.794 [Default:CheckIn:D] Stopped 2024-10-24T19:57:05.794Z,1729799825.794 [Default:CheckIn:E] Running Loop=1 2024-10-24T19:57:06.196Z,1729799826.196 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 77.615454 min 2024-10-24T19:57:06.196Z,1729799826.196 [Default:CheckIn:E] Stopped 2024-10-24T19:57:06.196Z,1729799826.196 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-10-24T19:57:06.196Z,1729799826.196 [Default:CheckIn] Stopped 2024-10-24T19:57:06.196Z,1729799826.196 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-10-24T19:57:06.196Z,1729799826.196 [Default:CheckIn](INFO): Running loop #14 2024-10-24T19:57:06.196Z,1729799826.196 [Default:CheckIn] Running Loop=14 2024-10-24T19:57:06.196Z,1729799826.196 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-10-24T19:57:06.197Z,1729799826.197 [Default:CheckIn:Read_GPS] Running Loop=1 2024-10-24T19:57:08.204Z,1729799828.204 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195707.00,A,3648.16022,N,12147.28184,W,0.933,321.70,241024,,,A*76 2024-10-24T19:57:08.206Z,1729799828.206 [NAL9602](INFO): GPS fix at 20241024T195707: (36.802670, -121.788031) 2024-10-24T19:57:08.239Z,1729799828.239 [Default:CheckIn:Read_GPS] Stopped 2024-10-24T19:57:08.239Z,1729799828.239 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-10-24T19:57:12.252Z,1729799832.252 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:57:15.185Z,1729799835.185 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241024T185338/Courier0036.lzma 2024-10-24T19:57:16.187Z,1729799836.187 [DataOverHttps](INFO): Moved sent file to Logs/20241024T185338/Courier0036.lzma.bak 2024-10-24T19:57:16.187Z,1729799836.187 [DataOverHttps](INFO): SBD MOMSN=23333993 2024-10-24T19:57:26.391Z,1729799846.391 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:57:32.221Z,1729799852.221 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20241024T185338/Express0037.lzma 2024-10-24T19:57:33.223Z,1729799853.223 [DataOverHttps](INFO): Moved sent file to Logs/20241024T185338/Express0037.lzma.bak 2024-10-24T19:57:33.223Z,1729799853.223 [DataOverHttps](INFO): SBD MOMSN=23333996 2024-10-24T19:57:34.926Z,1729799854.926 [Default:CheckIn:Read_Iridium] Stopped 2024-10-24T19:57:34.926Z,1729799854.926 [Default:CheckIn:C.Wait] Running Loop=1 2024-10-24T19:57:34.926Z,1729799854.926 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-10-24T19:57:39.723Z,1729799859.723 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T19:57:53.459Z,1729799873.459 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-24T20:02:10.410Z,1729800130.410 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-10-24T20:02:34.644Z,1729800154.644 [BPC1](INFO): Calculating totals. Valid battery stick count: 46. Valid reserve battery stick count: 5. 2024-10-24T20:02:34.648Z,1729800154.648 [BPC1](FAULT): Failed to receive data from 7 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 32, 33, 36, 37, 39, 51, 54. 2024-10-24T20:02:35.478Z,1729800155.478 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-10-24T20:02:35.479Z,1729800155.479 [Default:CheckIn:C.Wait] Stopped 2024-10-24T20:02:35.479Z,1729800155.479 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-10-24T20:02:35.479Z,1729800155.479 [Default:CheckIn:D] Running Loop=1 2024-10-24T20:02:35.867Z,1729800155.867 [Default:CheckIn:D] Stopped 2024-10-24T20:02:35.868Z,1729800155.868 [Default:CheckIn:E] Running Loop=1 2024-10-24T20:02:36.280Z,1729800156.280 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 83.116683 min 2024-10-24T20:02:36.280Z,1729800156.280 [Default:CheckIn:E] Stopped 2024-10-24T20:02:36.280Z,1729800156.280 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-10-24T20:02:36.280Z,1729800156.280 [Default:CheckIn] Stopped 2024-10-24T20:02:36.280Z,1729800156.280 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-10-24T20:02:36.281Z,1729800156.281 [Default:CheckIn](INFO): Running loop #15 2024-10-24T20:02:36.281Z,1729800156.281 [Default:CheckIn] Running Loop=15 2024-10-24T20:02:36.281Z,1729800156.281 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-10-24T20:02:36.281Z,1729800156.281 [Default:CheckIn:Read_GPS] Running Loop=1 2024-10-24T20:02:38.284Z,1729800158.284 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200237.00,A,3648.13659,N,12147.32545,W,9.778,222.17,241024,,,A*79 2024-10-24T20:02:38.286Z,1729800158.286 [NAL9602](INFO): GPS fix at 20241024T200237: (36.802276, -121.788758) 2024-10-24T20:02:38.320Z,1729800158.320 [Default:CheckIn:Read_GPS] Stopped 2024-10-24T20:02:38.320Z,1729800158.320 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-10-24T20:02:45.193Z,1729800165.193 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20241024T185338/Courier0039.lzma 2024-10-24T20:02:46.195Z,1729800166.195 [DataOverHttps](INFO): Moved sent file to Logs/20241024T185338/Courier0039.lzma.bak 2024-10-24T20:02:46.195Z,1729800166.195 [DataOverHttps](INFO): SBD MOMSN=23334076 2024-10-24T20:02:55.440Z,1729800175.440 [CBIT](INFO): Clearing failed state for component DropWeight 2024-10-24T20:02:55.440Z,1729800175.440 [DropWeight] No Fault, FailCount= 1 2024-10-24T20:03:05.818Z,1729800185.818 [DataOverHttps](INFO): Sending 341 bytes from file Logs/20241024T185338/Express0040.lzma 2024-10-24T20:03:06.819Z,1729800186.819 [DataOverHttps](INFO): Moved sent file to Logs/20241024T185338/Express0040.lzma.bak 2024-10-24T20:03:06.819Z,1729800186.819 [DataOverHttps](INFO): SBD MOMSN=23334079 2024-10-24T20:03:08.361Z,1729800188.361 [Default:CheckIn:Read_Iridium] Stopped 2024-10-24T20:03:08.361Z,1729800188.361 [Default:CheckIn:C.Wait] Running Loop=1 2024-10-24T20:03:08.361Z,1729800188.361 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-10-24T20:03:10.766Z,1729800190.766 [NAL9602](INFO): Not Powering down - fast GPS 2024-10-24T20:08:08.932Z,1729800488.932 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-10-24T20:08:08.933Z,1729800488.933 [Default:CheckIn:C.Wait] Stopped 2024-10-24T20:08:08.933Z,1729800488.933 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-10-24T20:08:08.933Z,1729800488.933 [Default:CheckIn:D] Running Loop=1 2024-10-24T20:08:09.337Z,1729800489.337 [Default:CheckIn:D] Stopped 2024-10-24T20:08:09.337Z,1729800489.337 [Default:CheckIn:E] Running Loop=1 2024-10-24T20:08:09.753Z,1729800489.753 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 88.674504 min 2024-10-24T20:08:09.753Z,1729800489.753 [Default:CheckIn:E] Stopped 2024-10-24T20:08:09.753Z,1729800489.753 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-10-24T20:08:09.753Z,1729800489.753 [Default:CheckIn] Stopped 2024-10-24T20:08:09.753Z,1729800489.753 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-10-24T20:08:09.753Z,1729800489.753 [Default:CheckIn](INFO): Running loop #16 2024-10-24T20:08:09.754Z,1729800489.754 [Default:CheckIn] Running Loop=16 2024-10-24T20:08:09.754Z,1729800489.754 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-10-24T20:08:09.754Z,1729800489.754 [Default:CheckIn:Read_GPS] Running Loop=1 2024-10-24T20:08:42.472Z,1729800522.472 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2024-10-24T20:08:42.549Z,1729800522.549 [NAL9602](ERROR): received: +CSQ:0 OK448, 0, 0, 0, 0 OK 2024-10-24T20:11:59.590Z,1729800719.590 [CommandExec](IMPORTANT): got command quit 2024-10-24T20:12:00.593Z,1729800720.593 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2024-10-24T20:12:00.594Z,1729800720.594 [CommandExec](INFO): Uninitializing the command executive. 2024-10-24T20:12:00.594Z,1729800720.594 [CommandExec](INFO): Uninitializing the command scheduler. 2024-10-24T20:12:00.594Z,1729800720.594 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-10-24T20:12:00.641Z,1729800720.641 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2024-10-24T20:12:00.642Z,1729800720.642 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2024-10-24T20:12:00.642Z,1729800720.642 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-10-24T20:12:00.643Z,1729800720.643 [NavChartDb](INFO): Join timeout helper Thread ID is 3783 2024-10-24T20:12:00.705Z,1729800720.705 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2024-10-24T20:12:00.706Z,1729800720.706 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-10-24T20:12:00.714Z,1729800720.714 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2024-10-24T20:12:00.714Z,1729800720.714 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-10-24T20:12:00.715Z,1729800720.715 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3784 2024-10-24T20:12:01.085Z,1729800721.085 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2024-10-24T20:12:01.086Z,1729800721.086 [WetLabsBB2FL](INFO): Powering down 2024-10-24T20:12:01.086Z,1729800721.086 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-10-24T20:12:01.093Z,1729800721.093 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2024-10-24T20:12:01.093Z,1729800721.093 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-10-24T20:12:01.094Z,1729800721.094 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3785 2024-10-24T20:12:01.393Z,1729800721.393 [CTD_Seabird](INFO): Powering down 2024-10-24T20:12:01.406Z,1729800721.406 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2024-10-24T20:12:01.406Z,1729800721.406 [CTD_Seabird](INFO): Powering down 2024-10-24T20:12:01.417Z,1729800721.417 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-10-24T20:12:01.421Z,1729800721.421 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2024-10-24T20:12:01.421Z,1729800721.421 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-10-24T20:12:01.422Z,1729800721.422 [Radio_Surface](INFO): Join timeout helper Thread ID is 3786 2024-10-24T20:12:01.809Z,1729800721.809 [Radio_Surface](INFO): Powering down 2024-10-24T20:12:01.810Z,1729800721.810 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2024-10-24T20:12:01.811Z,1729800721.811 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-10-24T20:12:01.829Z,1729800721.829 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2024-10-24T20:12:01.829Z,1729800721.829 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-10-24T20:12:01.830Z,1729800721.830 [Onboard](INFO): Join timeout helper Thread ID is 3787 2024-10-24T20:12:02.198Z,1729800722.198 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2024-10-24T20:12:06.185Z,1729800726.185 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2024-10-24T20:12:06.185Z,1729800726.185 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-10-24T20:12:06.194Z,1729800726.194 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2024-10-24T20:12:06.194Z,1729800726.194 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-10-24T20:12:06.194Z,1729800726.194 [DataOverHttps](INFO): Join timeout helper Thread ID is 3788 2024-10-24T20:12:06.213Z,1729800726.213 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2024-10-24T20:12:06.214Z,1729800726.214 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-10-24T20:12:06.218Z,1729800726.218 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2024-10-24T20:12:06.218Z,1729800726.218 [logger ThreadHandler](INFO): Thread cancelled. 2024-10-24T20:12:06.218Z,1729800726.218 [logger](INFO): Join timeout helper Thread ID is 3789 2024-10-24T20:12:06.243Z,1729800726.243 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2024-10-24T20:12:06.243Z,1729800726.243 [logger ThreadHandler](INFO): Thread cancelled. 2024-10-24T20:12:06.250Z,1729800726.250 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2024-10-24T20:12:06.250Z,1729800726.250 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-10-24T20:12:06.250Z,1729800726.250 [CommandLine](INFO): Join timeout helper Thread ID is 3790 2024-10-24T20:12:06.279Z,1729800726.279 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2024-10-24T20:12:06.280Z,1729800726.280 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-10-24T20:12:06.290Z,1729800726.290 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2024-10-24T20:12:06.290Z,1729800726.290 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-10-24T20:12:06.290Z,1729800726.290 [CommandExec](INFO): Join timeout helper Thread ID is 3791 2024-10-24T20:12:06.291Z,1729800726.291 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2024-10-24T20:12:06.292Z,1729800726.292 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-10-24T20:12:06.292Z,1729800726.292 [controlThread](INFO): Join timeout helper Thread ID is 3792 2024-10-24T20:12:06.509Z,1729800726.509 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2024-10-24T20:12:06.509Z,1729800726.509 [controlThread](DEBUG): Uninitializing ControlThread 2024-10-24T20:12:06.510Z,1729800726.510 [AHRS_M2](INFO): Powering down 2024-10-24T20:12:06.582Z,1729800726.582 [NAL9602](INFO): Powering down 2024-10-24T20:12:06.584Z,1729800726.584 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2024-10-24T20:12:06.584Z,1729800726.584 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2024-10-24T20:12:06.585Z,1729800726.585 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2024-10-24T20:12:06.586Z,1729800726.586 [MissionManager](INFO): Uninitializing Mission Default 2024-10-24T20:12:06.586Z,1729800726.586 [Default] Stopped 2024-10-24T20:12:06.586Z,1729800726.586 [Default](DEBUG): Aggregate::uninitialize Default 2024-10-24T20:12:06.586Z,1729800726.586 [Default:B.GoToSurface] Stopped 2024-10-24T20:12:06.586Z,1729800726.586 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-10-24T20:12:06.586Z,1729800726.586 [Default:CheckIn] Stopped 2024-10-24T20:12:06.586Z,1729800726.586 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-10-24T20:12:06.586Z,1729800726.586 [Default:CheckIn:Read_GPS] Stopped 2024-10-24T20:12:06.589Z,1729800726.589 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2024-10-24T20:12:06.589Z,1729800726.589 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2024-10-24T20:12:06.590Z,1729800726.590 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2024-10-24T20:12:06.590Z,1729800726.590 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2024-10-24T20:12:06.590Z,1729800726.590 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2024-10-24T20:12:06.590Z,1729800726.590 [BuoyancyServo](INFO): Powering down 2024-10-24T20:12:06.605Z,1729800726.605 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2024-10-24T20:12:06.605Z,1729800726.605 [ElevatorServo](INFO): Powering down 2024-10-24T20:12:06.606Z,1729800726.606 [MassServo](DEBUG): Uninitialize Mass Servo. 2024-10-24T20:12:06.606Z,1729800726.606 [MassServo](INFO): Powering down 2024-10-24T20:12:06.607Z,1729800726.607 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-10-24T20:12:06.607Z,1729800726.607 [RudderServo](INFO): Powering down 2024-10-24T20:12:06.608Z,1729800726.608 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2024-10-24T20:12:06.608Z,1729800726.608 [ThrusterHE](INFO): Powering down 2024-10-24T20:12:06.609Z,1729800726.609 [SBIT](DEBUG): Uninitialize SBIT Component. 2024-10-24T20:12:06.609Z,1729800726.609 [IBIT](DEBUG): Uninitialize IBIT Component. 2024-10-24T20:12:06.609Z,1729800726.609 [CBIT](DEBUG): Uninitialize CBIT Component. 2024-10-24T20:12:06.609Z,1729800726.609 [CBIT](DEBUG): Powering off loads. 2024-10-24T20:12:06.621Z,1729800726.621 [CBIT](DEBUG): Disabling WDT. 2024-10-24T20:12:06.633Z,1729800726.633 [CBIT](DEBUG): Opening all GF detection circuits. 2024-10-24T20:12:06.634Z,1729800726.634 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-10-24T20:12:06.693Z,1729800726.693 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-10-24T20:12:06.697Z,1729800726.697 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-10-24T20:12:06.703Z,1729800726.703 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-10-24T20:12:06.773Z,1729800726.773 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-10-24T20:12:06.778Z,1729800726.778 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-10-24T20:12:06.818Z,1729800726.818 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-10-24T20:12:06.930Z,1729800726.930 [logger ThreadHandler](INFO): Thread cancelled.