2024-03-27T17:36:06.194Z,1711560966.194 [CommandExec](IMPORTANT): got command restart logs 2024-03-27T17:36:07.233Z,1711560967.233 [DataOverHttps](INFO): Moved sent file to Logs/20240327T154449/Courier0036.lzma.bak 2024-03-27T17:36:07.234Z,1711560967.234 [DataOverHttps](INFO): SBD MOMSN=19358543 2024-03-27T17:36:19.463Z,1711560979.463 [NAL9602](INFO): SBD MO Status=0, MOMSN=49361, MT Status=0, MTMSN=0 2024-03-27T17:36:19.463Z,1711560979.463 [NAL9602](INFO): No messages in MT queue 2024-03-27T17:36:25.981Z,1711560985.981 [DataOverHttps](INFO): Sending 186 bytes from file Logs/20240327T154449/Express0037.lzma 2024-03-27T17:36:26.983Z,1711560986.983 [DataOverHttps](INFO): Moved sent file to Logs/20240327T154449/Express0037.lzma.bak 2024-03-27T17:36:26.983Z,1711560986.983 [DataOverHttps](INFO): SBD MOMSN=19358547 2024-03-27T17:36:42.940Z,1711561002.940 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20240327T154449/Express0040.lzma 2024-03-27T17:36:43.943Z,1711561003.943 [DataOverHttps](INFO): Moved sent file to Logs/20240327T154449/Express0040.lzma.bak 2024-03-27T17:36:43.943Z,1711561003.943 [DataOverHttps](INFO): SBD MOMSN=19358551 2024-03-27T17:36:45.333Z,1711561005.333 [Default:CheckIn:Read_Iridium] Stopped 2024-03-27T17:36:45.334Z,1711561005.334 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-27T17:36:45.334Z,1711561005.334 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-27T17:36:50.202Z,1711561010.202 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-27T17:41:46.053Z,1711561306.053 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-27T17:41:46.053Z,1711561306.053 [Default:CheckIn:C.Wait] Stopped 2024-03-27T17:41:46.053Z,1711561306.053 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-27T17:41:46.054Z,1711561306.054 [Default:CheckIn:D] Running Loop=1 2024-03-27T17:41:46.467Z,1711561306.467 [Default:CheckIn:D] Stopped 2024-03-27T17:41:46.467Z,1711561306.467 [Default:CheckIn:E] Running Loop=1 2024-03-27T17:41:46.852Z,1711561306.852 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 29.378615 min 2024-03-27T17:41:46.852Z,1711561306.852 [Default:CheckIn:E] Stopped 2024-03-27T17:41:46.853Z,1711561306.853 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-27T17:41:46.853Z,1711561306.853 [Default:CheckIn] Stopped 2024-03-27T17:41:46.853Z,1711561306.853 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-27T17:41:46.853Z,1711561306.853 [Default:CheckIn](INFO): Running loop #6 2024-03-27T17:41:46.853Z,1711561306.853 [Default:CheckIn] Running Loop=6 2024-03-27T17:41:46.853Z,1711561306.853 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-27T17:41:46.853Z,1711561306.853 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-27T17:41:48.865Z,1711561308.865 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174147.00,A,3647.89196,N,12150.25382,W,21.227,104.30,270324,,,D*4B 2024-03-27T17:41:48.867Z,1711561308.867 [NAL9602](INFO): GPS fix at 20240327T174147: (36.798199, -121.837564) 2024-03-27T17:41:48.879Z,1711561308.879 [Default:CheckIn:Read_GPS] Stopped 2024-03-27T17:41:48.879Z,1711561308.879 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-27T17:41:56.949Z,1711561316.949 [DataOverHttps](INFO): Sending 269 bytes from file Logs/20240327T173606/Courier0003.lzma 2024-03-27T17:41:57.950Z,1711561317.950 [DataOverHttps](INFO): Moved sent file to Logs/20240327T173606/Courier0003.lzma.bak 2024-03-27T17:41:57.951Z,1711561317.951 [DataOverHttps](INFO): SBD MOMSN=19358556 2024-03-27T17:42:09.059Z,1711561329.059 [NAL9602](INFO): SBD MO Status=0, MOMSN=49362, MT Status=0, MTMSN=0 2024-03-27T17:42:09.059Z,1711561329.059 [NAL9602](INFO): No messages in MT queue 2024-03-27T17:42:16.861Z,1711561336.861 [DataOverHttps](INFO): Sending 466 bytes from file Logs/20240327T173606/Express0004.lzma 2024-03-27T17:42:17.862Z,1711561337.862 [DataOverHttps](INFO): Moved sent file to Logs/20240327T173606/Express0004.lzma.bak 2024-03-27T17:42:17.863Z,1711561337.863 [DataOverHttps](INFO): SBD MOMSN=19358560 2024-03-27T17:42:19.183Z,1711561339.183 [Default:CheckIn:Read_Iridium] Stopped 2024-03-27T17:42:19.184Z,1711561339.184 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-27T17:42:19.184Z,1711561339.184 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-27T17:42:39.770Z,1711561359.770 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-27T17:47:19.893Z,1711561639.893 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-27T17:47:19.893Z,1711561639.893 [Default:CheckIn:C.Wait] Stopped 2024-03-27T17:47:19.893Z,1711561639.893 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-27T17:47:19.893Z,1711561639.893 [Default:CheckIn:D] Running Loop=1 2024-03-27T17:47:20.300Z,1711561640.300 [Default:CheckIn:D] Stopped 2024-03-27T17:47:20.300Z,1711561640.300 [Default:CheckIn:E] Running Loop=1 2024-03-27T17:47:20.704Z,1711561640.704 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 34.942501 min 2024-03-27T17:47:20.705Z,1711561640.705 [Default:CheckIn:E] Stopped 2024-03-27T17:47:20.705Z,1711561640.705 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-27T17:47:20.705Z,1711561640.705 [Default:CheckIn] Stopped 2024-03-27T17:47:20.705Z,1711561640.705 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-27T17:47:20.705Z,1711561640.705 [Default:CheckIn](INFO): Running loop #7 2024-03-27T17:47:20.705Z,1711561640.705 [Default:CheckIn] Running Loop=7 2024-03-27T17:47:20.705Z,1711561640.705 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-27T17:47:20.705Z,1711561640.705 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-27T17:47:22.715Z,1711561642.715 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174721.00,A,3648.17417,N,12147.77609,W,24.667,54.48,270324,,,D*70 2024-03-27T17:47:22.727Z,1711561642.727 [NAL9602](INFO): GPS fix at 20240327T174721: (36.802903, -121.796268) 2024-03-27T17:47:22.738Z,1711561642.738 [Default:CheckIn:Read_GPS] Stopped 2024-03-27T17:47:22.738Z,1711561642.738 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-27T17:47:45.339Z,1711561665.339 [NAL9602](INFO): SBD MO Status=2, MOMSN=49363, MT Status=2, MTMSN=0 2024-03-27T17:47:45.339Z,1711561665.339 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-03-27T17:47:54.200Z,1711561674.200 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240327T173606/Courier0006.lzma 2024-03-27T17:47:55.202Z,1711561675.202 [DataOverHttps](INFO): Moved sent file to Logs/20240327T173606/Courier0006.lzma.bak 2024-03-27T17:47:55.203Z,1711561675.203 [DataOverHttps](INFO): SBD MOMSN=19358565 2024-03-27T17:48:11.156Z,1711561691.156 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20240327T173606/Express0007.lzma 2024-03-27T17:48:12.158Z,1711561692.158 [DataOverHttps](INFO): Moved sent file to Logs/20240327T173606/Express0007.lzma.bak 2024-03-27T17:48:12.159Z,1711561692.159 [DataOverHttps](INFO): SBD MOMSN=19358568 2024-03-27T17:48:13.637Z,1711561693.637 [Default:CheckIn:Read_Iridium] Stopped 2024-03-27T17:48:13.638Z,1711561693.638 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-27T17:48:13.638Z,1711561693.638 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-27T17:48:36.647Z,1711561716.647 [NAL9602](INFO): SBD MO Status=2, MOMSN=49363, MT Status=2, MTMSN=0 2024-03-27T17:48:36.648Z,1711561716.648 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-03-27T17:48:44.333Z,1711561724.333 [NAL9602](INFO): SBD MO Status=0, MOMSN=49363, MT Status=0, MTMSN=0 2024-03-27T17:48:44.333Z,1711561724.333 [NAL9602](INFO): No messages in MT queue 2024-03-27T17:49:15.025Z,1711561755.025 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-27T17:53:14.298Z,1711561994.298 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-27T17:53:14.298Z,1711561994.298 [Default:CheckIn:C.Wait] Stopped 2024-03-27T17:53:14.298Z,1711561994.298 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-27T17:53:14.298Z,1711561994.298 [Default:CheckIn:D] Running Loop=1 2024-03-27T17:53:14.710Z,1711561994.710 [Default:CheckIn:D] Stopped 2024-03-27T17:53:14.711Z,1711561994.711 [Default:CheckIn:E] Running Loop=1 2024-03-27T17:53:15.125Z,1711561995.125 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 40.849341 min 2024-03-27T17:53:15.125Z,1711561995.125 [Default:CheckIn:E] Stopped 2024-03-27T17:53:15.125Z,1711561995.125 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-27T17:53:15.125Z,1711561995.125 [Default:CheckIn] Stopped 2024-03-27T17:53:15.125Z,1711561995.125 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-27T17:53:15.125Z,1711561995.125 [Default:CheckIn](INFO): Running loop #8 2024-03-27T17:53:15.126Z,1711561995.126 [Default:CheckIn] Running Loop=8 2024-03-27T17:53:15.126Z,1711561995.126 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-27T17:53:15.126Z,1711561995.126 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-27T17:53:17.120Z,1711561997.120 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175315.00,A,3648.32104,N,12147.12642,W,4.199,188.73,270324,,,D*72 2024-03-27T17:53:17.122Z,1711561997.122 [NAL9602](INFO): GPS fix at 20240327T175315: (36.805351, -121.785440) 2024-03-27T17:53:17.133Z,1711561997.133 [Default:CheckIn:Read_GPS] Stopped 2024-03-27T17:53:17.133Z,1711561997.133 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-27T17:53:24.700Z,1711562004.700 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240327T173606/Courier0009.lzma 2024-03-27T17:53:25.702Z,1711562005.702 [DataOverHttps](INFO): Moved sent file to Logs/20240327T173606/Courier0009.lzma.bak 2024-03-27T17:53:25.702Z,1711562005.702 [DataOverHttps](INFO): SBD MOMSN=19358573 2024-03-27T17:53:37.319Z,1711562017.319 [NAL9602](INFO): SBD MO Status=0, MOMSN=49364, MT Status=0, MTMSN=0 2024-03-27T17:53:37.319Z,1711562017.319 [NAL9602](INFO): No messages in MT queue 2024-03-27T17:53:41.777Z,1711562021.777 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20240327T173606/Express0010.lzma 2024-03-27T17:53:42.778Z,1711562022.778 [DataOverHttps](INFO): Moved sent file to Logs/20240327T173606/Express0010.lzma.bak 2024-03-27T17:53:42.779Z,1711562022.779 [DataOverHttps](INFO): SBD MOMSN=19358576 2024-03-27T17:53:44.210Z,1711562024.210 [Default:CheckIn:Read_Iridium] Stopped 2024-03-27T17:53:44.210Z,1711562024.210 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-27T17:53:44.210Z,1711562024.210 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-27T17:54:08.018Z,1711562048.018 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-27T17:58:44.848Z,1711562324.848 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-27T17:58:44.848Z,1711562324.848 [Default:CheckIn:C.Wait] Stopped 2024-03-27T17:58:44.849Z,1711562324.849 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-27T17:58:44.849Z,1711562324.849 [Default:CheckIn:D] Running Loop=1 2024-03-27T17:58:45.253Z,1711562325.253 [Default:CheckIn:D] Stopped 2024-03-27T17:58:45.253Z,1711562325.253 [Default:CheckIn:E] Running Loop=1 2024-03-27T17:58:45.662Z,1711562325.662 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 46.358374 min 2024-03-27T17:58:45.662Z,1711562325.662 [Default:CheckIn:E] Stopped 2024-03-27T17:58:45.662Z,1711562325.662 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-27T17:58:45.662Z,1711562325.662 [Default:CheckIn] Stopped 2024-03-27T17:58:45.663Z,1711562325.663 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-27T17:58:45.663Z,1711562325.663 [Default:CheckIn](INFO): Running loop #9 2024-03-27T17:58:45.663Z,1711562325.663 [Default:CheckIn] Running Loop=9 2024-03-27T17:58:45.663Z,1711562325.663 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-27T17:58:45.663Z,1711562325.663 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-27T17:58:47.660Z,1711562327.660 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175846.00,A,3648.14912,N,12147.20957,W,0.369,202.96,270324,,,D*7D 2024-03-27T17:58:47.662Z,1711562327.662 [NAL9602](INFO): GPS fix at 20240327T175846: (36.802485, -121.786826) 2024-03-27T17:58:47.699Z,1711562327.699 [Default:CheckIn:Read_GPS] Stopped 2024-03-27T17:58:47.699Z,1711562327.699 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-27T17:58:58.108Z,1711562338.108 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240327T173606/Courier0012.lzma 2024-03-27T17:58:59.110Z,1711562339.110 [DataOverHttps](INFO): Moved sent file to Logs/20240327T173606/Courier0012.lzma.bak 2024-03-27T17:58:59.111Z,1711562339.111 [DataOverHttps](INFO): SBD MOMSN=19358584 2024-03-27T17:58:59.467Z,1711562339.467 [NAL9602](INFO): SBD MO Status=0, MOMSN=49365, MT Status=0, MTMSN=0 2024-03-27T17:58:59.468Z,1711562339.468 [NAL9602](INFO): No messages in MT queue 2024-03-27T17:59:15.380Z,1711562355.380 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20240327T173606/Express0013.lzma 2024-03-27T17:59:16.382Z,1711562356.382 [DataOverHttps](INFO): Moved sent file to Logs/20240327T173606/Express0013.lzma.bak 2024-03-27T17:59:16.383Z,1711562356.383 [DataOverHttps](INFO): SBD MOMSN=19358587 2024-03-27T17:59:17.659Z,1711562357.659 [Default:CheckIn:Read_Iridium] Stopped 2024-03-27T17:59:17.659Z,1711562357.659 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-27T17:59:17.659Z,1711562357.659 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-27T17:59:30.174Z,1711562370.174 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-27T17:59:34.208Z,1711562374.208 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-27T17:59:46.733Z,1711562386.733 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-27T17:59:59.659Z,1711562399.659 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-27T18:00:12.588Z,1711562412.588 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-27T18:00:25.517Z,1711562425.517 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-27T18:00:38.449Z,1711562438.449 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-27T18:00:50.970Z,1711562450.970 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-27T18:03:33.860Z,1711562613.860 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-27T18:03:33.867Z,1711562613.867 [BPC1](INFO): Calculating totals. Valid battery stick count: 39. Valid reserve battery stick count: 5. 2024-03-27T18:03:33.890Z,1711562613.890 [BPC1](INFO): Received data from all battery sticks. 2024-03-27T18:04:18.320Z,1711562658.320 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-27T18:04:18.320Z,1711562658.320 [Default:CheckIn:C.Wait] Stopped 2024-03-27T18:04:18.320Z,1711562658.320 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-27T18:04:18.351Z,1711562658.351 [Default:CheckIn:D] Running Loop=1 2024-03-27T18:04:18.716Z,1711562658.716 [Default:CheckIn:D] Stopped 2024-03-27T18:04:18.716Z,1711562658.716 [Default:CheckIn:E] Running Loop=1 2024-03-27T18:04:19.122Z,1711562659.122 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 51.916105 min 2024-03-27T18:04:19.122Z,1711562659.122 [Default:CheckIn:E] Stopped 2024-03-27T18:04:19.122Z,1711562659.122 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-27T18:04:19.122Z,1711562659.122 [Default:CheckIn] Stopped 2024-03-27T18:04:19.122Z,1711562659.122 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-27T18:04:19.122Z,1711562659.122 [Default:CheckIn](INFO): Running loop #10 2024-03-27T18:04:19.122Z,1711562659.122 [Default:CheckIn] Running Loop=10 2024-03-27T18:04:19.123Z,1711562659.123 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-27T18:04:19.123Z,1711562659.123 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-27T18:04:21.135Z,1711562661.135 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180419.00,A,3648.14869,N,12147.21041,W,0.078,202.96,270324,,,A*75 2024-03-27T18:04:21.138Z,1711562661.138 [NAL9602](INFO): GPS fix at 20240327T180419: (36.802478, -121.786840) 2024-03-27T18:04:21.169Z,1711562661.169 [Default:CheckIn:Read_GPS] Stopped 2024-03-27T18:04:21.169Z,1711562661.169 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-27T18:04:28.908Z,1711562668.908 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20240327T173606/Courier0015.lzma 2024-03-27T18:04:29.910Z,1711562669.910 [DataOverHttps](INFO): Moved sent file to Logs/20240327T173606/Courier0015.lzma.bak 2024-03-27T18:04:29.911Z,1711562669.911 [DataOverHttps](INFO): SBD MOMSN=19358593 2024-03-27T18:04:35.287Z,1711562675.287 [NAL9602](INFO): SBD MO Status=0, MOMSN=49366, MT Status=0, MTMSN=0 2024-03-27T18:04:35.287Z,1711562675.287 [NAL9602](INFO): No messages in MT queue 2024-03-27T18:04:45.908Z,1711562685.908 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20240327T173606/Express0016.lzma 2024-03-27T18:04:46.910Z,1711562686.910 [DataOverHttps](INFO): Moved sent file to Logs/20240327T173606/Express0016.lzma.bak 2024-03-27T18:04:46.911Z,1711562686.911 [DataOverHttps](INFO): SBD MOMSN=19358596 2024-03-27T18:04:48.253Z,1711562688.253 [Default:CheckIn:Read_Iridium] Stopped 2024-03-27T18:04:48.253Z,1711562688.253 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-27T18:04:48.253Z,1711562688.253 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-27T18:05:06.029Z,1711562706.029 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-27T18:09:48.897Z,1711562988.897 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-27T18:09:48.897Z,1711562988.897 [Default:CheckIn:C.Wait] Stopped 2024-03-27T18:09:48.898Z,1711562988.898 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-27T18:09:48.898Z,1711562988.898 [Default:CheckIn:D] Running Loop=1 2024-03-27T18:09:49.310Z,1711562989.310 [Default:CheckIn:D] Stopped 2024-03-27T18:09:49.310Z,1711562989.310 [Default:CheckIn:E] Running Loop=1 2024-03-27T18:09:49.704Z,1711562989.704 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 57.426009 min 2024-03-27T18:09:49.704Z,1711562989.704 [Default:CheckIn:E] Stopped 2024-03-27T18:09:49.704Z,1711562989.704 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-27T18:09:49.705Z,1711562989.705 [Default:CheckIn] Stopped 2024-03-27T18:09:49.705Z,1711562989.705 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-27T18:09:49.705Z,1711562989.705 [Default:CheckIn](INFO): Running loop #11 2024-03-27T18:09:49.705Z,1711562989.705 [Default:CheckIn] Running Loop=11 2024-03-27T18:09:49.705Z,1711562989.705 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-27T18:09:49.705Z,1711562989.705 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-27T18:09:51.716Z,1711562991.716 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180950.00,A,3648.14819,N,12147.21255,W,0.097,202.96,270324,,,A*74 2024-03-27T18:09:51.718Z,1711562991.718 [NAL9602](INFO): GPS fix at 20240327T180950: (36.802470, -121.786876) 2024-03-27T18:09:51.737Z,1711562991.737 [Default:CheckIn:Read_GPS] Stopped 2024-03-27T18:09:51.737Z,1711562991.737 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-27T18:09:59.408Z,1711562999.408 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240327T173606/Courier0018.lzma 2024-03-27T18:10:00.410Z,1711563000.410 [DataOverHttps](INFO): Moved sent file to Logs/20240327T173606/Courier0018.lzma.bak 2024-03-27T18:10:00.410Z,1711563000.410 [DataOverHttps](INFO): SBD MOMSN=19358603 2024-03-27T18:10:16.416Z,1711563016.416 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20240327T173606/Express0019.lzma 2024-03-27T18:10:17.418Z,1711563017.418 [DataOverHttps](INFO): Moved sent file to Logs/20240327T173606/Express0019.lzma.bak 2024-03-27T18:10:17.419Z,1711563017.419 [DataOverHttps](INFO): SBD MOMSN=19358606 2024-03-27T18:10:18.806Z,1711563018.806 [Default:CheckIn:Read_Iridium] Stopped 2024-03-27T18:10:18.806Z,1711563018.806 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-27T18:10:18.807Z,1711563018.807 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-27T18:10:28.079Z,1711563028.079 [NAL9602](INFO): SBD MO Status=2, MOMSN=49367, MT Status=2, MTMSN=0 2024-03-27T18:10:28.080Z,1711563028.080 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-03-27T18:10:42.620Z,1711563042.620 [NAL9602](INFO): SBD MO Status=2, MOMSN=49367, MT Status=2, MTMSN=0 2024-03-27T18:10:42.620Z,1711563042.620 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-03-27T18:11:46.512Z,1711563106.512 [NAL9602](INFO): SBD MO Status=2, MOMSN=49367, MT Status=2, MTMSN=0 2024-03-27T18:11:46.512Z,1711563106.512 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-03-27T18:12:24.483Z,1711563144.483 [NAL9602](INFO): SBD MO Status=0, MOMSN=49367, MT Status=0, MTMSN=0 2024-03-27T18:12:24.483Z,1711563144.483 [NAL9602](INFO): No messages in MT queue 2024-03-27T18:12:55.218Z,1711563175.218 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-27T18:14:55.690Z,1711563295.690 [CBIT](IMPORTANT): Beginning ground fault scan 2024-03-27T18:15:06.574Z,1711563306.574 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.002520 CHAN A1 (24V): 0.000057 CHAN A2 (12V): -0.002279 CHAN A3 (5V): -0.001560 CHAN B0 (3.3V): -0.000146 CHAN B1 (3.15aV): -0.000060 CHAN B2 (3.15bV): 0.000128 CHAN B3 (GND): -0.000392 OPEN: -0.000415 Full Scale: +/- 1 mA 2024-03-27T18:15:19.490Z,1711563319.490 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-27T18:15:19.490Z,1711563319.490 [Default:CheckIn:C.Wait] Stopped 2024-03-27T18:15:19.490Z,1711563319.490 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-27T18:15:19.490Z,1711563319.490 [Default:CheckIn:D] Running Loop=1 2024-03-27T18:15:19.903Z,1711563319.903 [Default:CheckIn:D] Stopped 2024-03-27T18:15:19.903Z,1711563319.903 [Default:CheckIn:E] Running Loop=1 2024-03-27T18:15:20.298Z,1711563320.298 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 62.935876 min 2024-03-27T18:15:20.298Z,1711563320.298 [Default:CheckIn:E] Stopped 2024-03-27T18:15:20.298Z,1711563320.298 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-27T18:15:20.298Z,1711563320.298 [Default:CheckIn] Stopped 2024-03-27T18:15:20.298Z,1711563320.298 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-27T18:15:20.299Z,1711563320.299 [Default:CheckIn](INFO): Running loop #12 2024-03-27T18:15:20.299Z,1711563320.299 [Default:CheckIn] Running Loop=12 2024-03-27T18:15:20.299Z,1711563320.299 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-27T18:15:20.299Z,1711563320.299 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-27T18:15:22.308Z,1711563322.308 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181520.00,A,3648.15071,N,12147.22073,W,0.097,243.67,270324,,,A*77 2024-03-27T18:15:22.310Z,1711563322.310 [NAL9602](INFO): GPS fix at 20240327T181520: (36.802512, -121.787012) 2024-03-27T18:15:22.325Z,1711563322.325 [Default:CheckIn:Read_GPS] Stopped 2024-03-27T18:15:22.325Z,1711563322.325 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-27T18:15:29.693Z,1711563329.693 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240327T173606/Courier0021.lzma 2024-03-27T18:15:30.694Z,1711563330.694 [DataOverHttps](INFO): Moved sent file to Logs/20240327T173606/Courier0021.lzma.bak 2024-03-27T18:15:30.695Z,1711563330.695 [DataOverHttps](INFO): SBD MOMSN=19358610 2024-03-27T18:15:41.299Z,1711563341.299 [NAL9602](INFO): SBD MO Status=0, MOMSN=49368, MT Status=0, MTMSN=0 2024-03-27T18:15:41.299Z,1711563341.299 [NAL9602](INFO): No messages in MT queue 2024-03-27T18:15:47.049Z,1711563347.049 [DataOverHttps](INFO): Sending 333 bytes from file Logs/20240327T173606/Express0022.lzma 2024-03-27T18:15:48.051Z,1711563348.051 [DataOverHttps](INFO): Moved sent file to Logs/20240327T173606/Express0022.lzma.bak 2024-03-27T18:15:48.051Z,1711563348.051 [DataOverHttps](INFO): SBD MOMSN=19358613 2024-03-27T18:15:49.790Z,1711563349.790 [Default:CheckIn:Read_Iridium] Stopped 2024-03-27T18:15:49.790Z,1711563349.790 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-27T18:15:49.790Z,1711563349.790 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-27T18:16:12.002Z,1711563372.002 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-27T18:20:50.479Z,1711563650.479 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-27T18:20:50.479Z,1711563650.479 [Default:CheckIn:C.Wait] Stopped 2024-03-27T18:20:50.479Z,1711563650.479 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-27T18:20:50.479Z,1711563650.479 [Default:CheckIn:D] Running Loop=1 2024-03-27T18:20:50.890Z,1711563650.890 [Default:CheckIn:D] Stopped 2024-03-27T18:20:50.890Z,1711563650.890 [Default:CheckIn:E] Running Loop=1 2024-03-27T18:20:51.295Z,1711563651.295 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 68.452336 min 2024-03-27T18:20:51.295Z,1711563651.295 [Default:CheckIn:E] Stopped 2024-03-27T18:20:51.295Z,1711563651.295 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-27T18:20:51.295Z,1711563651.295 [Default:CheckIn] Stopped 2024-03-27T18:20:51.295Z,1711563651.295 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-27T18:20:51.296Z,1711563651.296 [Default:CheckIn](INFO): Running loop #13 2024-03-27T18:20:51.296Z,1711563651.296 [Default:CheckIn] Running Loop=13 2024-03-27T18:20:51.296Z,1711563651.296 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-27T18:20:51.296Z,1711563651.296 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-27T18:20:53.289Z,1711563653.289 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182051.00,A,3648.15018,N,12147.22226,W,0.194,277.19,270324,,,D*73 2024-03-27T18:20:53.292Z,1711563653.292 [NAL9602](INFO): GPS fix at 20240327T182051: (36.802503, -121.787038) 2024-03-27T18:20:53.311Z,1711563653.311 [Default:CheckIn:Read_GPS] Stopped 2024-03-27T18:20:53.311Z,1711563653.311 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-27T18:21:01.932Z,1711563661.932 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240327T173606/Courier0024.lzma 2024-03-27T18:21:02.934Z,1711563662.934 [DataOverHttps](INFO): Moved sent file to Logs/20240327T173606/Courier0024.lzma.bak 2024-03-27T18:21:02.935Z,1711563662.935 [DataOverHttps](INFO): SBD MOMSN=19358623 2024-03-27T18:21:18.804Z,1711563678.804 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20240327T173606/Express0025.lzma 2024-03-27T18:21:19.167Z,1711563679.167 [NAL9602](INFO): SBD MO Status=2, MOMSN=49369, MT Status=2, MTMSN=0 2024-03-27T18:21:19.168Z,1711563679.168 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-03-27T18:21:19.852Z,1711563679.852 [DataOverHttps](INFO): Moved sent file to Logs/20240327T173606/Express0025.lzma.bak 2024-03-27T18:21:19.852Z,1711563679.852 [DataOverHttps](INFO): SBD MOMSN=19358626 2024-03-27T18:21:21.197Z,1711563681.197 [Default:CheckIn:Read_Iridium] Stopped 2024-03-27T18:21:21.198Z,1711563681.198 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-27T18:21:21.198Z,1711563681.198 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-27T18:21:28.870Z,1711563688.870 [NAL9602](INFO): SBD MO Status=0, MOMSN=49369, MT Status=0, MTMSN=0 2024-03-27T18:21:28.870Z,1711563688.870 [NAL9602](INFO): No messages in MT queue 2024-03-27T18:21:59.561Z,1711563719.561 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-27T18:26:21.800Z,1711563981.800 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-27T18:26:21.800Z,1711563981.800 [Default:CheckIn:C.Wait] Stopped 2024-03-27T18:26:21.800Z,1711563981.800 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-27T18:26:21.801Z,1711563981.801 [Default:CheckIn:D] Running Loop=1 2024-03-27T18:26:22.205Z,1711563982.205 [Default:CheckIn:D] Stopped 2024-03-27T18:26:22.205Z,1711563982.205 [Default:CheckIn:E] Running Loop=1 2024-03-27T18:26:22.610Z,1711563982.610 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 73.974243 min 2024-03-27T18:26:22.611Z,1711563982.611 [Default:CheckIn:E] Stopped 2024-03-27T18:26:22.611Z,1711563982.611 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-27T18:26:22.611Z,1711563982.611 [Default:CheckIn] Stopped 2024-03-27T18:26:22.611Z,1711563982.611 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-27T18:26:22.611Z,1711563982.611 [Default:CheckIn](INFO): Running loop #14 2024-03-27T18:26:22.611Z,1711563982.611 [Default:CheckIn] Running Loop=14 2024-03-27T18:26:22.611Z,1711563982.611 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-27T18:26:22.611Z,1711563982.611 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-27T18:26:24.616Z,1711563984.616 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182623.00,A,3648.14341,N,12147.22695,W,0.019,210.88,270324,,,D*7F 2024-03-27T18:26:24.618Z,1711563984.618 [NAL9602](INFO): GPS fix at 20240327T182623: (36.802390, -121.787116) 2024-03-27T18:26:24.654Z,1711563984.654 [Default:CheckIn:Read_GPS] Stopped 2024-03-27T18:26:24.654Z,1711563984.654 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-27T18:26:32.316Z,1711563992.316 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240327T173606/Courier0027.lzma 2024-03-27T18:26:33.318Z,1711563993.318 [DataOverHttps](INFO): Moved sent file to Logs/20240327T173606/Courier0027.lzma.bak 2024-03-27T18:26:33.319Z,1711563993.319 [DataOverHttps](INFO): SBD MOMSN=19358631 2024-03-27T18:26:48.071Z,1711564008.071 [NAL9602](INFO): SBD MO Status=2, MOMSN=49370, MT Status=2, MTMSN=0 2024-03-27T18:26:48.072Z,1711564008.072 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-03-27T18:26:49.860Z,1711564009.860 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20240327T173606/Express0028.lzma 2024-03-27T18:26:50.863Z,1711564010.863 [DataOverHttps](INFO): Moved sent file to Logs/20240327T173606/Express0028.lzma.bak 2024-03-27T18:26:50.863Z,1711564010.863 [DataOverHttps](INFO): SBD MOMSN=19358634 2024-03-27T18:26:52.572Z,1711564012.572 [Default:CheckIn:Read_Iridium] Stopped 2024-03-27T18:26:52.572Z,1711564012.572 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-27T18:26:52.572Z,1711564012.572 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-27T18:27:33.775Z,1711564053.775 [NAL9602](INFO): SBD MO Status=2, MOMSN=49370, MT Status=2, MTMSN=0 2024-03-27T18:27:33.775Z,1711564053.775 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-03-27T18:28:02.863Z,1711564082.863 [NAL9602](INFO): SBD MO Status=2, MOMSN=49370, MT Status=2, MTMSN=0 2024-03-27T18:28:02.863Z,1711564082.863 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-03-27T18:28:27.907Z,1711564107.907 [NAL9602](INFO): SBD MO Status=0, MOMSN=49370, MT Status=0, MTMSN=0 2024-03-27T18:28:27.907Z,1711564107.907 [NAL9602](INFO): No messages in MT queue 2024-03-27T18:28:58.646Z,1711564138.646 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-27T18:31:53.187Z,1711564313.187 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-27T18:31:53.187Z,1711564313.187 [Default:CheckIn:C.Wait] Stopped 2024-03-27T18:31:53.187Z,1711564313.187 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-27T18:31:53.188Z,1711564313.188 [Default:CheckIn:D] Running Loop=1 2024-03-27T18:31:53.579Z,1711564313.579 [Default:CheckIn:D] Stopped 2024-03-27T18:31:53.579Z,1711564313.579 [Default:CheckIn:E] Running Loop=1 2024-03-27T18:31:53.983Z,1711564313.983 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 79.497152 min 2024-03-27T18:31:53.983Z,1711564313.983 [Default:CheckIn:E] Stopped 2024-03-27T18:31:53.983Z,1711564313.983 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-27T18:31:53.983Z,1711564313.983 [Default:CheckIn] Stopped 2024-03-27T18:31:53.983Z,1711564313.983 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-27T18:31:53.983Z,1711564313.983 [Default:CheckIn](INFO): Running loop #15 2024-03-27T18:31:53.983Z,1711564313.983 [Default:CheckIn] Running Loop=15 2024-03-27T18:31:53.984Z,1711564313.984 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-27T18:31:53.984Z,1711564313.984 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-27T18:31:55.993Z,1711564315.993 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183154.00,A,3648.17661,N,12147.27669,W,0.117,290.92,270324,,,D*77 2024-03-27T18:31:55.995Z,1711564315.995 [NAL9602](INFO): GPS fix at 20240327T183154: (36.802943, -121.787945) 2024-03-27T18:31:56.007Z,1711564316.007 [Default:CheckIn:Read_GPS] Stopped 2024-03-27T18:31:56.007Z,1711564316.007 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-27T18:32:03.764Z,1711564323.764 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240327T173606/Courier0030.lzma 2024-03-27T18:32:04.855Z,1711564324.855 [DataOverHttps](INFO): Moved sent file to Logs/20240327T173606/Courier0030.lzma.bak 2024-03-27T18:32:04.855Z,1711564324.855 [DataOverHttps](INFO): SBD MOMSN=19358638 2024-03-27T18:32:22.225Z,1711564342.225 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20240327T173606/Express0031.lzma 2024-03-27T18:32:23.227Z,1711564343.227 [DataOverHttps](INFO): Moved sent file to Logs/20240327T173606/Express0031.lzma.bak 2024-03-27T18:32:23.227Z,1711564343.227 [DataOverHttps](INFO): SBD MOMSN=19358641 2024-03-27T18:32:25.186Z,1711564345.186 [Default:CheckIn:Read_Iridium] Stopped 2024-03-27T18:32:25.186Z,1711564345.186 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-27T18:32:25.186Z,1711564345.186 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-27T18:33:57.232Z,1711564437.232 [NAL9602](INFO): SBD MO Status=2, MOMSN=49371, MT Status=2, MTMSN=0 2024-03-27T18:33:57.233Z,1711564437.233 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-03-27T18:34:33.203Z,1711564473.203 [NAL9602](INFO): SBD MO Status=0, MOMSN=49371, MT Status=0, MTMSN=0 2024-03-27T18:34:33.203Z,1711564473.203 [NAL9602](INFO): No messages in MT queue 2024-03-27T18:35:03.890Z,1711564503.890 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-27T18:37:25.708Z,1711564645.708 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-27T18:37:25.709Z,1711564645.709 [Default:CheckIn:C.Wait] Stopped 2024-03-27T18:37:25.709Z,1711564645.709 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-27T18:37:25.709Z,1711564645.709 [Default:CheckIn:D] Running Loop=1 2024-03-27T18:37:26.118Z,1711564646.118 [Default:CheckIn:D] Stopped 2024-03-27T18:37:26.118Z,1711564646.118 [Default:CheckIn:E] Running Loop=1 2024-03-27T18:37:26.513Z,1711564646.513 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 85.039469 min 2024-03-27T18:37:26.513Z,1711564646.513 [Default:CheckIn:E] Stopped 2024-03-27T18:37:26.513Z,1711564646.513 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-27T18:37:26.513Z,1711564646.513 [Default:CheckIn] Stopped 2024-03-27T18:37:26.513Z,1711564646.513 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-27T18:37:26.513Z,1711564646.513 [Default:CheckIn](INFO): Running loop #16 2024-03-27T18:37:26.513Z,1711564646.513 [Default:CheckIn] Running Loop=16 2024-03-27T18:37:26.513Z,1711564646.513 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-27T18:37:26.514Z,1711564646.514 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-27T18:37:28.521Z,1711564648.521 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183727.00,A,3648.17765,N,12147.27862,W,0.058,357.87,270324,,,A*74 2024-03-27T18:37:28.523Z,1711564648.523 [NAL9602](INFO): GPS fix at 20240327T183727: (36.802961, -121.787977) 2024-03-27T18:37:28.534Z,1711564648.534 [Default:CheckIn:Read_GPS] Stopped 2024-03-27T18:37:28.534Z,1711564648.534 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-27T18:37:39.336Z,1711564659.336 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240327T173606/Courier0033.lzma 2024-03-27T18:37:40.338Z,1711564660.338 [DataOverHttps](INFO): Moved sent file to Logs/20240327T173606/Courier0033.lzma.bak 2024-03-27T18:37:40.339Z,1711564660.339 [DataOverHttps](INFO): SBD MOMSN=19358645 2024-03-27T18:37:56.436Z,1711564676.436 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20240327T173606/Express0034.lzma 2024-03-27T18:37:57.439Z,1711564677.439 [DataOverHttps](INFO): Moved sent file to Logs/20240327T173606/Express0034.lzma.bak 2024-03-27T18:37:57.439Z,1711564677.439 [DataOverHttps](INFO): SBD MOMSN=19358648 2024-03-27T18:37:58.841Z,1711564678.841 [Default:CheckIn:Read_Iridium] Stopped 2024-03-27T18:37:58.841Z,1711564678.841 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-27T18:37:58.841Z,1711564678.841 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-27T18:42:31.122Z,1711564951.122 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-03-27T18:42:59.412Z,1711564979.412 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-27T18:42:59.412Z,1711564979.412 [Default:CheckIn:C.Wait] Stopped 2024-03-27T18:42:59.412Z,1711564979.412 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-27T18:42:59.412Z,1711564979.412 [Default:CheckIn:D] Running Loop=1 2024-03-27T18:42:59.816Z,1711564979.816 [Default:CheckIn:D] Stopped 2024-03-27T18:42:59.816Z,1711564979.816 [Default:CheckIn:E] Running Loop=1 2024-03-27T18:43:00.248Z,1711564980.248 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 90.601107 min 2024-03-27T18:43:00.248Z,1711564980.248 [Default:CheckIn:E] Stopped 2024-03-27T18:43:00.249Z,1711564980.249 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-27T18:43:00.249Z,1711564980.249 [Default:CheckIn] Stopped 2024-03-27T18:43:00.249Z,1711564980.249 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-27T18:43:00.249Z,1711564980.249 [Default:CheckIn](INFO): Running loop #17 2024-03-27T18:43:00.249Z,1711564980.249 [Default:CheckIn] Running Loop=17 2024-03-27T18:43:00.249Z,1711564980.249 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-27T18:43:00.249Z,1711564980.249 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-27T18:43:02.228Z,1711564982.228 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184300.00,A,3648.17937,N,12147.27779,W,0.369,125.26,270324,,,A*73 2024-03-27T18:43:02.230Z,1711564982.230 [NAL9602](INFO): GPS fix at 20240327T184300: (36.802990, -121.787963) 2024-03-27T18:43:02.242Z,1711564982.242 [Default:CheckIn:Read_GPS] Stopped 2024-03-27T18:43:02.242Z,1711564982.242 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-27T18:43:09.656Z,1711564989.656 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240327T173606/Courier0036.lzma 2024-03-27T18:43:10.658Z,1711564990.658 [DataOverHttps](INFO): Moved sent file to Logs/20240327T173606/Courier0036.lzma.bak 2024-03-27T18:43:10.659Z,1711564990.659 [DataOverHttps](INFO): SBD MOMSN=19358651 2024-03-27T18:43:27.273Z,1711565007.273 [DataOverHttps](INFO): Sending 240 bytes from file Logs/20240327T173606/Express0037.lzma 2024-03-27T18:43:28.274Z,1711565008.274 [DataOverHttps](INFO): Moved sent file to Logs/20240327T173606/Express0037.lzma.bak 2024-03-27T18:43:28.275Z,1711565008.275 [DataOverHttps](INFO): SBD MOMSN=19358654 2024-03-27T18:43:29.750Z,1711565009.750 [Default:CheckIn:Read_Iridium] Stopped 2024-03-27T18:43:29.750Z,1711565009.750 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-27T18:43:29.750Z,1711565009.750 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-27T18:43:34.170Z,1711565014.170 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-27T18:48:30.388Z,1711565310.388 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-27T18:48:30.388Z,1711565310.388 [Default:CheckIn:C.Wait] Stopped 2024-03-27T18:48:30.388Z,1711565310.388 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-27T18:48:30.389Z,1711565310.389 [Default:CheckIn:D] Running Loop=1 2024-03-27T18:48:30.796Z,1711565310.796 [Default:CheckIn:D] Stopped 2024-03-27T18:48:30.797Z,1711565310.797 [Default:CheckIn:E] Running Loop=1 2024-03-27T18:48:31.216Z,1711565311.216 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 96.117440 min 2024-03-27T18:48:31.225Z,1711565311.225 [Default:CheckIn:E] Stopped 2024-03-27T18:48:31.225Z,1711565311.225 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-27T18:48:31.225Z,1711565311.225 [Default:CheckIn] Stopped 2024-03-27T18:48:31.225Z,1711565311.225 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-27T18:48:31.225Z,1711565311.225 [Default:CheckIn](INFO): Running loop #18 2024-03-27T18:48:31.225Z,1711565311.225 [Default:CheckIn] Running Loop=18 2024-03-27T18:48:31.225Z,1711565311.225 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-27T18:48:31.225Z,1711565311.225 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-27T18:48:33.207Z,1711565313.207 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184831.00,A,3648.17790,N,12147.27539,W,0.019,125.26,270324,,,A*7B 2024-03-27T18:48:33.210Z,1711565313.210 [NAL9602](INFO): GPS fix at 20240327T184831: (36.802965, -121.787923) 2024-03-27T18:48:33.245Z,1711565313.245 [Default:CheckIn:Read_GPS] Stopped 2024-03-27T18:48:33.245Z,1711565313.245 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-27T18:48:41.369Z,1711565321.369 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240327T173606/Courier0039.lzma 2024-03-27T18:48:42.370Z,1711565322.370 [DataOverHttps](INFO): Moved sent file to Logs/20240327T173606/Courier0039.lzma.bak 2024-03-27T18:48:42.371Z,1711565322.371 [DataOverHttps](INFO): SBD MOMSN=19358658 2024-03-27T18:49:01.608Z,1711565341.608 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20240327T173606/Express0040.lzma 2024-03-27T18:49:02.610Z,1711565342.610 [DataOverHttps](INFO): Moved sent file to Logs/20240327T173606/Express0040.lzma.bak 2024-03-27T18:49:02.611Z,1711565342.611 [DataOverHttps](INFO): SBD MOMSN=19358661 2024-03-27T18:49:03.946Z,1711565343.946 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2024-03-27T18:49:04.025Z,1711565344.025 [NAL9602](ERROR): received: +CSQ:0 OK371, 0, 0, 0, 0 OK 2024-03-27T18:49:04.064Z,1711565344.064 [Default:CheckIn:Read_Iridium] Stopped 2024-03-27T18:49:04.065Z,1711565344.065 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-27T18:49:04.065Z,1711565344.065 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-27T18:53:19.688Z,1711565599.688 [NAL9602](INFO): SBD MO Status=2, MOMSN=49372, MT Status=2, MTMSN=0 2024-03-27T18:53:19.688Z,1711565599.688 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-03-27T18:53:35.042Z,1711565615.042 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-03-27T18:54:04.540Z,1711565644.540 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-27T18:54:04.540Z,1711565644.540 [Default:CheckIn:C.Wait] Stopped 2024-03-27T18:54:04.540Z,1711565644.540 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-27T18:54:04.541Z,1711565644.541 [Default:CheckIn:D] Running Loop=1 2024-03-27T18:54:04.950Z,1711565644.950 [Default:CheckIn:D] Stopped 2024-03-27T18:54:04.950Z,1711565644.950 [Default:CheckIn:E] Running Loop=1 2024-03-27T18:54:05.369Z,1711565645.369 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 101.686662 min 2024-03-27T18:54:05.369Z,1711565645.369 [Default:CheckIn:E] Stopped 2024-03-27T18:54:05.369Z,1711565645.369 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-27T18:54:05.369Z,1711565645.369 [Default:CheckIn] Stopped 2024-03-27T18:54:05.370Z,1711565645.370 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-27T18:54:05.370Z,1711565645.370 [Default:CheckIn](INFO): Running loop #19 2024-03-27T18:54:05.370Z,1711565645.370 [Default:CheckIn] Running Loop=19 2024-03-27T18:54:05.370Z,1711565645.370 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-27T18:54:05.370Z,1711565645.370 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-27T18:54:05.775Z,1711565645.775 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-27T18:54:07.367Z,1711565647.367 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185405.00,A,3648.17721,N,12147.27818,W,0.097,43.54,270324,,,A*47 2024-03-27T18:54:07.370Z,1711565647.370 [NAL9602](INFO): GPS fix at 20240327T185405: (36.802954, -121.787970) 2024-03-27T18:54:07.413Z,1711565647.413 [Default:CheckIn:Read_GPS] Stopped 2024-03-27T18:54:07.413Z,1711565647.413 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-27T18:54:14.696Z,1711565654.696 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240327T173606/Courier0042.lzma 2024-03-27T18:54:15.699Z,1711565655.699 [DataOverHttps](INFO): Moved sent file to Logs/20240327T173606/Courier0042.lzma.bak 2024-03-27T18:54:15.699Z,1711565655.699 [DataOverHttps](INFO): SBD MOMSN=19358664 2024-03-27T18:54:31.664Z,1711565671.664 [DataOverHttps](INFO): Sending 216 bytes from file Logs/20240327T173606/Express0043.lzma 2024-03-27T18:54:32.667Z,1711565672.667 [DataOverHttps](INFO): Moved sent file to Logs/20240327T173606/Express0043.lzma.bak 2024-03-27T18:54:32.667Z,1711565672.667 [DataOverHttps](INFO): SBD MOMSN=19358667 2024-03-27T18:54:34.050Z,1711565674.050 [Default:CheckIn:Read_Iridium] Stopped 2024-03-27T18:54:34.050Z,1711565674.050 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-27T18:54:34.050Z,1711565674.050 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-27T18:54:38.062Z,1711565678.062 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2024-03-27T18:54:38.137Z,1711565678.137 [NAL9602](ERROR): received: +CSQ:0 OK372, 2, 0, 0, 0 OK 2024-03-27T18:56:23.515Z,1711565783.515 [NAL9602](INFO): SBD MO Status=2, MOMSN=49372, MT Status=2, MTMSN=0 2024-03-27T18:56:23.515Z,1711565783.515 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-03-27T18:56:32.805Z,1711565792.805 [NAL9602](INFO): SBD MO Status=0, MOMSN=49372, MT Status=0, MTMSN=0 2024-03-27T18:56:32.805Z,1711565792.805 [NAL9602](INFO): No messages in MT queue 2024-03-27T18:57:03.510Z,1711565823.510 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-27T18:59:34.621Z,1711565974.621 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-27T18:59:34.621Z,1711565974.621 [Default:CheckIn:C.Wait] Stopped 2024-03-27T18:59:34.621Z,1711565974.621 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-27T18:59:34.621Z,1711565974.621 [Default:CheckIn:D] Running Loop=1 2024-03-27T18:59:35.029Z,1711565975.029 [Default:CheckIn:D] Stopped 2024-03-27T18:59:35.029Z,1711565975.029 [Default:CheckIn:E] Running Loop=1 2024-03-27T18:59:35.421Z,1711565975.421 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 107.187980 min 2024-03-27T18:59:35.421Z,1711565975.421 [Default:CheckIn:E] Stopped 2024-03-27T18:59:35.421Z,1711565975.421 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-27T18:59:35.421Z,1711565975.421 [Default:CheckIn] Stopped 2024-03-27T18:59:35.421Z,1711565975.421 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-27T18:59:35.421Z,1711565975.421 [Default:CheckIn](INFO): Running loop #20 2024-03-27T18:59:35.422Z,1711565975.422 [Default:CheckIn] Running Loop=20 2024-03-27T18:59:35.422Z,1711565975.422 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-27T18:59:35.422Z,1711565975.422 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-27T18:59:37.431Z,1711565977.431 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185935.00,A,3648.17710,N,12147.27569,W,0.078,43.54,270324,,,A*41 2024-03-27T18:59:37.434Z,1711565977.434 [NAL9602](INFO): GPS fix at 20240327T185935: (36.802952, -121.787928) 2024-03-27T18:59:37.445Z,1711565977.445 [Default:CheckIn:Read_GPS] Stopped 2024-03-27T18:59:37.445Z,1711565977.445 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-27T18:59:44.404Z,1711565984.404 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240327T173606/Courier0045.lzma 2024-03-27T18:59:45.406Z,1711565985.406 [DataOverHttps](INFO): Moved sent file to Logs/20240327T173606/Courier0045.lzma.bak 2024-03-27T18:59:45.407Z,1711565985.407 [DataOverHttps](INFO): SBD MOMSN=19358672 2024-03-27T19:00:01.268Z,1711566001.268 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20240327T173606/Express0046.lzma 2024-03-27T19:00:02.270Z,1711566002.270 [DataOverHttps](INFO): Moved sent file to Logs/20240327T173606/Express0046.lzma.bak 2024-03-27T19:00:02.271Z,1711566002.271 [DataOverHttps](INFO): SBD MOMSN=19358675 2024-03-27T19:00:03.704Z,1711566003.704 [Default:CheckIn:Read_Iridium] Stopped 2024-03-27T19:00:03.704Z,1711566003.704 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-27T19:00:03.704Z,1711566003.704 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-27T19:04:40.030Z,1711566280.030 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-03-27T19:04:40.835Z,1711566280.835 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-27T19:04:53.761Z,1711566293.761 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-27T19:05:04.280Z,1711566304.280 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-27T19:05:04.280Z,1711566304.280 [Default:CheckIn:C.Wait] Stopped 2024-03-27T19:05:04.281Z,1711566304.281 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-27T19:05:04.281Z,1711566304.281 [Default:CheckIn:D] Running Loop=1 2024-03-27T19:05:04.688Z,1711566304.688 [Default:CheckIn:D] Stopped 2024-03-27T19:05:04.688Z,1711566304.688 [Default:CheckIn:E] Running Loop=1 2024-03-27T19:05:05.093Z,1711566305.093 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 112.682308 min 2024-03-27T19:05:05.093Z,1711566305.093 [Default:CheckIn:E] Stopped 2024-03-27T19:05:05.093Z,1711566305.093 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-27T19:05:05.093Z,1711566305.093 [Default:CheckIn] Stopped 2024-03-27T19:05:05.093Z,1711566305.093 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-27T19:05:05.093Z,1711566305.093 [Default:CheckIn](INFO): Running loop #21 2024-03-27T19:05:05.093Z,1711566305.093 [Default:CheckIn] Running Loop=21 2024-03-27T19:05:05.093Z,1711566305.093 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-27T19:05:05.093Z,1711566305.093 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-27T19:05:06.284Z,1711566306.284 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-27T19:05:07.120Z,1711566307.120 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190505.00,A,3648.17710,N,12147.26796,W,0.039,82.04,270324,,,A*44 2024-03-27T19:05:07.122Z,1711566307.122 [NAL9602](INFO): GPS fix at 20240327T190505: (36.802952, -121.787799) 2024-03-27T19:05:07.134Z,1711566307.134 [Default:CheckIn:Read_GPS] Stopped 2024-03-27T19:05:07.134Z,1711566307.134 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-27T19:05:14.356Z,1711566314.356 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240327T173606/Courier0048.lzma 2024-03-27T19:05:15.358Z,1711566315.358 [DataOverHttps](INFO): Moved sent file to Logs/20240327T173606/Courier0048.lzma.bak 2024-03-27T19:05:15.359Z,1711566315.359 [DataOverHttps](INFO): SBD MOMSN=19358678 2024-03-27T19:05:19.212Z,1711566319.212 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-27T19:05:31.736Z,1711566331.736 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-27T19:05:31.869Z,1711566331.869 [DataOverHttps](INFO): Sending 231 bytes from file Logs/20240327T173606/Express0049.lzma 2024-03-27T19:05:32.871Z,1711566332.871 [DataOverHttps](INFO): Moved sent file to Logs/20240327T173606/Express0049.lzma.bak 2024-03-27T19:05:32.871Z,1711566332.871 [DataOverHttps](INFO): SBD MOMSN=19358681 2024-03-27T19:05:34.184Z,1711566334.184 [Default:CheckIn:Read_Iridium] Stopped 2024-03-27T19:05:34.184Z,1711566334.184 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-27T19:05:34.184Z,1711566334.184 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-27T19:05:39.010Z,1711566339.010 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-27T19:05:45.068Z,1711566345.068 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-27T19:05:57.591Z,1711566357.591 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-27T19:08:39.597Z,1711566519.597 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-27T19:08:39.604Z,1711566519.604 [BPC1](INFO): Calculating totals. Valid battery stick count: 39. Valid reserve battery stick count: 5. 2024-03-27T19:08:39.610Z,1711566519.610 [BPC1](INFO): Received data from all battery sticks. 2024-03-27T19:10:34.747Z,1711566634.747 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-27T19:10:34.747Z,1711566634.747 [Default:CheckIn:C.Wait] Stopped 2024-03-27T19:10:34.748Z,1711566634.748 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-27T19:10:34.748Z,1711566634.748 [Default:CheckIn:D] Running Loop=1 2024-03-27T19:10:35.161Z,1711566635.161 [Default:CheckIn:D] Stopped 2024-03-27T19:10:35.161Z,1711566635.161 [Default:CheckIn:E] Running Loop=1 2024-03-27T19:10:35.573Z,1711566635.573 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 118.190186 min 2024-03-27T19:10:35.573Z,1711566635.573 [Default:CheckIn:E] Stopped 2024-03-27T19:10:35.573Z,1711566635.573 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-27T19:10:35.573Z,1711566635.573 [Default:CheckIn] Stopped 2024-03-27T19:10:35.573Z,1711566635.573 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-27T19:10:35.573Z,1711566635.573 [Default:CheckIn](INFO): Running loop #22 2024-03-27T19:10:35.573Z,1711566635.573 [Default:CheckIn] Running Loop=22 2024-03-27T19:10:35.574Z,1711566635.574 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-27T19:10:35.574Z,1711566635.574 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-27T19:10:37.572Z,1711566637.572 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191036.00,A,3648.16825,N,12147.27919,W,0.311,82.04,270324,,,A*49 2024-03-27T19:10:37.574Z,1711566637.574 [NAL9602](INFO): GPS fix at 20240327T191036: (36.802804, -121.787987) 2024-03-27T19:10:37.609Z,1711566637.609 [Default:CheckIn:Read_GPS] Stopped 2024-03-27T19:10:37.609Z,1711566637.609 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-27T19:10:45.044Z,1711566645.044 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20240327T173606/Courier0051.lzma 2024-03-27T19:10:46.046Z,1711566646.046 [DataOverHttps](INFO): Moved sent file to Logs/20240327T173606/Courier0051.lzma.bak 2024-03-27T19:10:46.047Z,1711566646.047 [DataOverHttps](INFO): SBD MOMSN=19358685 2024-03-27T19:11:05.584Z,1711566665.584 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240327T173606/Express0052.lzma 2024-03-27T19:11:06.587Z,1711566666.587 [DataOverHttps](INFO): Moved sent file to Logs/20240327T173606/Express0052.lzma.bak 2024-03-27T19:11:06.587Z,1711566666.587 [DataOverHttps](INFO): SBD MOMSN=19358688 2024-03-27T19:11:07.942Z,1711566667.942 [Default:CheckIn:Read_Iridium] Stopped 2024-03-27T19:11:07.942Z,1711566667.942 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-27T19:11:07.942Z,1711566667.942 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-27T19:11:08.326Z,1711566668.326 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2024-03-27T19:11:08.409Z,1711566668.409 [NAL9602](ERROR): received: +CSQ:0 OK372, 0, 0, 0, 0 OK 2024-03-27T19:13:56.801Z,1711566836.801 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2024-03-27T19:13:56.801Z,1711566836.801 [DropWeight] Hardware Fault, FailCount= 1 2024-03-27T19:13:56.801Z,1711566836.801 [DropWeight](ERROR): Hardware Fault 2024-03-27T19:13:56.891Z,1711566836.891 [CommandExec](FAULT): Scheduling is paused 2024-03-27T19:13:56.891Z,1711566836.891 [CBIT](INFO): Critical error at 20240327T191356 2024-03-27T19:13:56.894Z,1711566836.894 [CBIT](ERROR): Hardware Fault in component: DropWeight 2024-03-27T19:13:56.894Z,1711566836.894 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2024-03-27T19:13:57.248Z,1711566837.248 [CBIT](INFO): Critical error at 20240327T191356 2024-03-27T19:15:40.228Z,1711566940.228 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-03-27T19:16:08.524Z,1711566968.524 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-27T19:16:08.524Z,1711566968.524 [Default:CheckIn:C.Wait] Stopped 2024-03-27T19:16:08.552Z,1711566968.552 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-27T19:16:08.552Z,1711566968.552 [Default:CheckIn:D] Running Loop=1 2024-03-27T19:16:08.924Z,1711566968.924 [Default:CheckIn:D] Stopped 2024-03-27T19:16:08.924Z,1711566968.924 [Default:CheckIn:E] Running Loop=1 2024-03-27T19:16:09.332Z,1711566969.332 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 123.752897 min 2024-03-27T19:16:09.332Z,1711566969.332 [Default:CheckIn:E] Stopped 2024-03-27T19:16:09.333Z,1711566969.333 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-27T19:16:09.333Z,1711566969.333 [Default:CheckIn] Stopped 2024-03-27T19:16:09.333Z,1711566969.333 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-27T19:16:09.333Z,1711566969.333 [Default:CheckIn](INFO): Running loop #23 2024-03-27T19:16:09.333Z,1711566969.333 [Default:CheckIn] Running Loop=23 2024-03-27T19:16:09.333Z,1711566969.333 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-27T19:16:09.333Z,1711566969.333 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-27T19:16:11.339Z,1711566971.339 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191609.00,A,3648.16749,N,12147.28019,W,0.292,202.75,270324,,,A*76 2024-03-27T19:16:11.353Z,1711566971.353 [NAL9602](INFO): GPS fix at 20240327T191609: (36.802791, -121.788003) 2024-03-27T19:16:11.365Z,1711566971.365 [Default:CheckIn:Read_GPS] Stopped 2024-03-27T19:16:11.365Z,1711566971.365 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-27T19:16:18.628Z,1711566978.628 [DataOverHttps](INFO): Sending 180 bytes from file Logs/20240327T173606/Courier0054.lzma 2024-03-27T19:16:19.631Z,1711566979.631 [DataOverHttps](INFO): Moved sent file to Logs/20240327T173606/Courier0054.lzma.bak 2024-03-27T19:16:19.631Z,1711566979.631 [DataOverHttps](INFO): SBD MOMSN=19358691 2024-03-27T19:16:35.583Z,1711566995.583 [DataOverHttps](INFO): Sending 230 bytes from file Logs/20240327T173606/Express0055.lzma 2024-03-27T19:16:36.582Z,1711566996.582 [DataOverHttps](INFO): Moved sent file to Logs/20240327T173606/Express0055.lzma.bak 2024-03-27T19:16:36.583Z,1711566996.583 [DataOverHttps](INFO): SBD MOMSN=19358696 2024-03-27T19:16:38.026Z,1711566998.026 [Default:CheckIn:Read_Iridium] Stopped 2024-03-27T19:16:38.026Z,1711566998.026 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-27T19:16:38.026Z,1711566998.026 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-27T19:16:44.058Z,1711567004.058 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-27T19:21:38.592Z,1711567298.592 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-27T19:21:38.592Z,1711567298.592 [Default:CheckIn:C.Wait] Stopped 2024-03-27T19:21:38.592Z,1711567298.592 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-27T19:21:38.592Z,1711567298.592 [Default:CheckIn:D] Running Loop=1 2024-03-27T19:21:38.000Z,1711567299.000 [Default:CheckIn:D] Stopped 2024-03-27T19:21:39.000Z,1711567299.000 [Default:CheckIn:E] Running Loop=1 2024-03-27T19:21:39.396Z,1711567299.396 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 129.254167 min 2024-03-27T19:21:39.396Z,1711567299.396 [Default:CheckIn:E] Stopped 2024-03-27T19:21:39.396Z,1711567299.396 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-27T19:21:39.396Z,1711567299.396 [Default:CheckIn] Stopped 2024-03-27T19:21:39.396Z,1711567299.396 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-27T19:21:39.396Z,1711567299.396 [Default:CheckIn](INFO): Running loop #24 2024-03-27T19:21:39.396Z,1711567299.396 [Default:CheckIn] Running Loop=24 2024-03-27T19:21:39.397Z,1711567299.397 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-27T19:21:39.397Z,1711567299.397 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-27T19:21:41.403Z,1711567301.403 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192139.00,A,3648.16931,N,12147.27146,W,0.350,81.49,270324,,,A*4D 2024-03-27T19:21:41.406Z,1711567301.406 [NAL9602](INFO): GPS fix at 20240327T192139: (36.802822, -121.787858) 2024-03-27T19:21:41.418Z,1711567301.418 [Default:CheckIn:Read_GPS] Stopped 2024-03-27T19:21:41.418Z,1711567301.418 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-27T19:21:49.092Z,1711567309.092 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240327T173606/Courier0057.lzma 2024-03-27T19:21:50.095Z,1711567310.095 [DataOverHttps](INFO): Moved sent file to Logs/20240327T173606/Courier0057.lzma.bak 2024-03-27T19:21:50.095Z,1711567310.095 [DataOverHttps](INFO): SBD MOMSN=19358703 2024-03-27T19:22:06.172Z,1711567326.172 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240327T173606/Express0058.lzma 2024-03-27T19:22:07.175Z,1711567327.175 [DataOverHttps](INFO): Moved sent file to Logs/20240327T173606/Express0058.lzma.bak 2024-03-27T19:22:07.175Z,1711567327.175 [DataOverHttps](INFO): SBD MOMSN=19358706 2024-03-27T19:22:08.898Z,1711567328.898 [Default:CheckIn:Read_Iridium] Stopped 2024-03-27T19:22:08.898Z,1711567328.898 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-27T19:22:08.898Z,1711567328.898 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-27T19:22:12.115Z,1711567332.115 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2024-03-27T19:22:12.189Z,1711567332.189 [NAL9602](ERROR): received: +CSQ:0 OK372, 0, 0, 0, 0 OK 2024-03-27T19:26:44.006Z,1711567604.006 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-03-27T19:27:09.462Z,1711567629.462 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-27T19:27:09.463Z,1711567629.463 [Default:CheckIn:C.Wait] Stopped 2024-03-27T19:27:09.463Z,1711567629.463 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-27T19:27:09.463Z,1711567629.463 [Default:CheckIn:D] Running Loop=1 2024-03-27T19:27:09.876Z,1711567629.876 [Default:CheckIn:D] Stopped 2024-03-27T19:27:09.876Z,1711567629.876 [Default:CheckIn:E] Running Loop=1 2024-03-27T19:27:10.272Z,1711567630.272 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 134.768766 min 2024-03-27T19:27:10.272Z,1711567630.272 [Default:CheckIn:E] Stopped 2024-03-27T19:27:10.272Z,1711567630.272 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-27T19:27:10.272Z,1711567630.272 [Default:CheckIn] Stopped 2024-03-27T19:27:10.272Z,1711567630.272 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-27T19:27:10.272Z,1711567630.272 [Default:CheckIn](INFO): Running loop #25 2024-03-27T19:27:10.296Z,1711567630.296 [Default:CheckIn] Running Loop=25 2024-03-27T19:27:10.296Z,1711567630.296 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-27T19:27:10.296Z,1711567630.296 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-27T19:27:12.281Z,1711567632.281 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192710.00,A,3648.16346,N,12147.27806,W,1.050,81.49,270324,,,A*45 2024-03-27T19:27:12.283Z,1711567632.283 [NAL9602](INFO): GPS fix at 20240327T192710: (36.802724, -121.787968) 2024-03-27T19:27:12.294Z,1711567632.294 [Default:CheckIn:Read_GPS] Stopped 2024-03-27T19:27:12.294Z,1711567632.294 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-27T19:27:19.956Z,1711567639.956 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240327T173606/Courier0060.lzma 2024-03-27T19:27:20.958Z,1711567640.958 [DataOverHttps](INFO): Moved sent file to Logs/20240327T173606/Courier0060.lzma.bak 2024-03-27T19:27:20.959Z,1711567640.959 [DataOverHttps](INFO): SBD MOMSN=19358710 2024-03-27T19:27:36.852Z,1711567656.852 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20240327T173606/Express0061.lzma 2024-03-27T19:27:37.855Z,1711567657.855 [DataOverHttps](INFO): Moved sent file to Logs/20240327T173606/Express0061.lzma.bak 2024-03-27T19:27:37.855Z,1711567657.855 [DataOverHttps](INFO): SBD MOMSN=19358713 2024-03-27T19:27:39.360Z,1711567659.360 [Default:CheckIn:Read_Iridium] Stopped 2024-03-27T19:27:39.360Z,1711567659.360 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-27T19:27:39.360Z,1711567659.360 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-27T19:27:44.217Z,1711567664.217 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-27T19:28:56.961Z,1711567736.961 [CBIT](INFO): Clearing failed state for component DropWeight 2024-03-27T19:28:56.961Z,1711567736.961 [DropWeight] No Fault, FailCount= 1 2024-03-27T19:32:39.949Z,1711567959.949 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-27T19:32:39.949Z,1711567959.949 [Default:CheckIn:C.Wait] Stopped 2024-03-27T19:32:39.949Z,1711567959.949 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-27T19:32:39.950Z,1711567959.950 [Default:CheckIn:D] Running Loop=1 2024-03-27T19:32:40.345Z,1711567960.345 [Default:CheckIn:D] Stopped 2024-03-27T19:32:40.345Z,1711567960.345 [Default:CheckIn:E] Running Loop=1 2024-03-27T19:32:40.749Z,1711567960.749 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 140.276579 min 2024-03-27T19:32:40.749Z,1711567960.749 [Default:CheckIn:E] Stopped 2024-03-27T19:32:40.749Z,1711567960.749 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-27T19:32:40.749Z,1711567960.749 [Default:CheckIn] Stopped 2024-03-27T19:32:40.749Z,1711567960.749 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-27T19:32:40.749Z,1711567960.749 [Default:CheckIn](INFO): Running loop #26 2024-03-27T19:32:40.749Z,1711567960.749 [Default:CheckIn] Running Loop=26 2024-03-27T19:32:40.749Z,1711567960.749 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-27T19:32:40.750Z,1711567960.750 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-27T19:32:42.768Z,1711567962.768 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193241.00,A,3648.17197,N,12147.27573,W,0.739,0.00,270324,,,A*78 2024-03-27T19:32:42.786Z,1711567962.786 [NAL9602](INFO): GPS fix at 20240327T193241: (36.802866, -121.787929) 2024-03-27T19:32:42.801Z,1711567962.801 [Default:CheckIn:Read_GPS] Stopped 2024-03-27T19:32:42.801Z,1711567962.801 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-27T19:32:50.492Z,1711567970.492 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240327T173606/Courier0063.lzma 2024-03-27T19:32:51.495Z,1711567971.495 [DataOverHttps](INFO): Moved sent file to Logs/20240327T173606/Courier0063.lzma.bak 2024-03-27T19:32:51.495Z,1711567971.495 [DataOverHttps](INFO): SBD MOMSN=19358717 2024-03-27T19:33:07.444Z,1711567987.444 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240327T173606/Express0064.lzma 2024-03-27T19:33:08.447Z,1711567988.447 [DataOverHttps](INFO): Moved sent file to Logs/20240327T173606/Express0064.lzma.bak 2024-03-27T19:33:08.447Z,1711567988.447 [DataOverHttps](INFO): SBD MOMSN=19358720 2024-03-27T19:33:09.842Z,1711567989.842 [Default:CheckIn:Read_Iridium] Stopped 2024-03-27T19:33:09.842Z,1711567989.842 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-27T19:33:09.843Z,1711567989.843 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-27T19:37:45.052Z,1711568265.052 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-03-27T19:38:10.530Z,1711568290.530 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-27T19:38:10.530Z,1711568290.530 [Default:CheckIn:C.Wait] Stopped 2024-03-27T19:38:10.530Z,1711568290.530 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-27T19:38:10.531Z,1711568290.531 [Default:CheckIn:D] Running Loop=1 2024-03-27T19:38:10.919Z,1711568290.919 [Default:CheckIn:D] Stopped 2024-03-27T19:38:10.919Z,1711568290.919 [Default:CheckIn:E] Running Loop=1 2024-03-27T19:38:11.331Z,1711568291.331 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 145.786149 min 2024-03-27T19:38:11.331Z,1711568291.331 [Default:CheckIn:E] Stopped 2024-03-27T19:38:11.332Z,1711568291.332 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-27T19:38:11.332Z,1711568291.332 [Default:CheckIn] Stopped 2024-03-27T19:38:11.332Z,1711568291.332 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-27T19:38:11.332Z,1711568291.332 [Default:CheckIn](INFO): Running loop #27 2024-03-27T19:38:11.332Z,1711568291.332 [Default:CheckIn] Running Loop=27 2024-03-27T19:38:11.332Z,1711568291.332 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-27T19:38:11.332Z,1711568291.332 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-27T19:38:13.338Z,1711568293.338 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193812.00,A,3648.17015,N,12147.27394,W,1.400,0.00,270324,,,A*78 2024-03-27T19:38:13.341Z,1711568293.341 [NAL9602](INFO): GPS fix at 20240327T193812: (36.802836, -121.787899) 2024-03-27T19:38:13.352Z,1711568293.352 [Default:CheckIn:Read_GPS] Stopped 2024-03-27T19:38:13.352Z,1711568293.352 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-27T19:38:20.311Z,1711568300.311 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240327T173606/Courier0066.lzma 2024-03-27T19:38:21.313Z,1711568301.313 [DataOverHttps](INFO): Moved sent file to Logs/20240327T173606/Courier0066.lzma.bak 2024-03-27T19:38:21.313Z,1711568301.313 [DataOverHttps](INFO): SBD MOMSN=19358723 2024-03-27T19:38:37.087Z,1711568317.087 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20240327T173606/Express0067.lzma 2024-03-27T19:38:38.089Z,1711568318.089 [DataOverHttps](INFO): Moved sent file to Logs/20240327T173606/Express0067.lzma.bak 2024-03-27T19:38:38.089Z,1711568318.089 [DataOverHttps](INFO): SBD MOMSN=19358726 2024-03-27T19:38:39.616Z,1711568319.616 [Default:CheckIn:Read_Iridium] Stopped 2024-03-27T19:38:39.616Z,1711568319.616 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-27T19:38:39.617Z,1711568319.617 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-27T19:38:45.656Z,1711568325.656 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-27T19:39:26.404Z,1711568366.404 [CommandExec](IMPORTANT): got command get Onboard.Pressure 2024-03-27T19:39:26.405Z,1711568366.405 [CommandExec](IMPORTANT): Onboard.Pressure 65924.687500 Pa 2024-03-27T19:39:36.041Z,1711568376.041 [CommandExec](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch 2024-03-27T19:39:36.042Z,1711568376.042 [CommandExec](IMPORTANT): Onboard.Pressure 9.581974 psi 2024-03-27T19:39:48.984Z,1711568388.984 [CommandExec](IMPORTANT): got command get Onboard.Humidity 2024-03-27T19:39:48.984Z,1711568388.984 [CommandExec](IMPORTANT): Onboard.Humidity 10.932731 % 2024-03-27T19:40:42.728Z,1711568442.728 [CommandExec](IMPORTANT): got command quit 2024-03-27T19:40:43.731Z,1711568443.731 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2024-03-27T19:40:43.731Z,1711568443.731 [CommandExec](INFO): Uninitializing the command executive. 2024-03-27T19:40:43.732Z,1711568443.732 [CommandExec](INFO): Uninitializing the command scheduler. 2024-03-27T19:40:43.732Z,1711568443.732 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-03-27T19:40:43.915Z,1711568443.915 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2024-03-27T19:40:43.915Z,1711568443.915 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2024-03-27T19:40:43.915Z,1711568443.915 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-03-27T19:40:43.916Z,1711568443.916 [NavChartDb](INFO): Join timeout helper Thread ID is 988 2024-03-27T19:40:43.919Z,1711568443.919 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2024-03-27T19:40:43.920Z,1711568443.920 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-03-27T19:40:43.927Z,1711568443.927 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2024-03-27T19:40:43.927Z,1711568443.927 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-03-27T19:40:43.928Z,1711568443.928 [CTD_Seabird](INFO): Join timeout helper Thread ID is 989 2024-03-27T19:40:44.527Z,1711568444.527 [CTD_Seabird](INFO): Powering down 2024-03-27T19:40:44.539Z,1711568444.539 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2024-03-27T19:40:44.539Z,1711568444.539 [CTD_Seabird](INFO): Powering down 2024-03-27T19:40:44.551Z,1711568444.551 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-03-27T19:40:44.571Z,1711568444.571 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2024-03-27T19:40:44.571Z,1711568444.571 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-03-27T19:40:44.572Z,1711568444.572 [Radio_Surface](INFO): Join timeout helper Thread ID is 990 2024-03-27T19:40:44.651Z,1711568444.651 [Radio_Surface](INFO): Powering down 2024-03-27T19:40:44.652Z,1711568444.652 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2024-03-27T19:40:44.653Z,1711568444.653 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-03-27T19:40:44.663Z,1711568444.663 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2024-03-27T19:40:44.663Z,1711568444.663 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-03-27T19:40:44.664Z,1711568444.664 [Onboard](INFO): Join timeout helper Thread ID is 991 2024-03-27T19:40:47.487Z,1711568447.487 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2024-03-27T19:40:47.488Z,1711568447.488 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-03-27T19:40:47.499Z,1711568447.499 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2024-03-27T19:40:47.499Z,1711568447.499 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-03-27T19:40:47.500Z,1711568447.500 [DataOverHttps](INFO): Join timeout helper Thread ID is 992 2024-03-27T19:40:48.343Z,1711568448.343 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2024-03-27T19:40:48.346Z,1711568448.346 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-03-27T19:40:48.352Z,1711568448.352 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2024-03-27T19:40:48.352Z,1711568448.352 [logger ThreadHandler](INFO): Thread cancelled. 2024-03-27T19:40:48.353Z,1711568448.353 [logger](INFO): Join timeout helper Thread ID is 993 2024-03-27T19:40:48.371Z,1711568448.371 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2024-03-27T19:40:48.372Z,1711568448.372 [logger ThreadHandler](INFO): Thread cancelled. 2024-03-27T19:40:48.393Z,1711568448.393 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2024-03-27T19:40:48.393Z,1711568448.393 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-03-27T19:40:48.394Z,1711568448.394 [CommandLine](INFO): Join timeout helper Thread ID is 994 2024-03-27T19:40:48.483Z,1711568448.483 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2024-03-27T19:40:48.484Z,1711568448.484 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-03-27T19:40:48.492Z,1711568448.492 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2024-03-27T19:40:48.492Z,1711568448.492 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-03-27T19:40:48.492Z,1711568448.492 [CommandExec](INFO): Join timeout helper Thread ID is 995 2024-03-27T19:40:48.494Z,1711568448.494 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2024-03-27T19:40:48.494Z,1711568448.494 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-03-27T19:40:48.494Z,1711568448.494 [controlThread](INFO): Join timeout helper Thread ID is 996 2024-03-27T19:40:48.855Z,1711568448.855 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2024-03-27T19:40:48.856Z,1711568448.856 [controlThread](DEBUG): Uninitializing ControlThread 2024-03-27T19:40:48.856Z,1711568448.856 [AHRS_M2](INFO): Powering down 2024-03-27T19:40:48.928Z,1711568448.928 [NAL9602](INFO): Powering down 2024-03-27T19:40:48.931Z,1711568448.931 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2024-03-27T19:40:48.932Z,1711568448.932 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2024-03-27T19:40:48.932Z,1711568448.932 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2024-03-27T19:40:48.933Z,1711568448.933 [MissionManager](INFO): Uninitializing Mission Default 2024-03-27T19:40:48.933Z,1711568448.933 [Default] Stopped 2024-03-27T19:40:48.933Z,1711568448.933 [Default](DEBUG): Aggregate::uninitialize Default 2024-03-27T19:40:48.933Z,1711568448.933 [Default:B.GoToSurface] Stopped 2024-03-27T19:40:48.933Z,1711568448.933 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-03-27T19:40:48.933Z,1711568448.933 [Default:CheckIn] Stopped 2024-03-27T19:40:48.933Z,1711568448.933 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-27T19:40:48.933Z,1711568448.933 [Default:CheckIn:C.Wait] Stopped 2024-03-27T19:40:48.933Z,1711568448.933 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-27T19:40:48.936Z,1711568448.936 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2024-03-27T19:40:48.936Z,1711568448.936 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2024-03-27T19:40:48.937Z,1711568448.937 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2024-03-27T19:40:48.937Z,1711568448.937 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2024-03-27T19:40:48.937Z,1711568448.937 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2024-03-27T19:40:48.937Z,1711568448.937 [BuoyancyServo](INFO): Powering down 2024-03-27T19:40:48.951Z,1711568448.951 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2024-03-27T19:40:48.951Z,1711568448.951 [ElevatorServo](INFO): Powering down 2024-03-27T19:40:48.952Z,1711568448.952 [MassServo](DEBUG): Uninitialize Mass Servo. 2024-03-27T19:40:48.952Z,1711568448.952 [MassServo](INFO): Powering down 2024-03-27T19:40:48.953Z,1711568448.953 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-03-27T19:40:48.953Z,1711568448.953 [RudderServo](INFO): Powering down 2024-03-27T19:40:48.954Z,1711568448.954 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2024-03-27T19:40:48.954Z,1711568448.954 [ThrusterHE](INFO): Powering down 2024-03-27T19:40:48.955Z,1711568448.955 [SBIT](DEBUG): Uninitialize SBIT Component. 2024-03-27T19:40:48.955Z,1711568448.955 [IBIT](DEBUG): Uninitialize IBIT Component. 2024-03-27T19:40:48.956Z,1711568448.956 [CBIT](DEBUG): Uninitialize CBIT Component. 2024-03-27T19:40:48.956Z,1711568448.956 [CBIT](DEBUG): Powering off loads. 2024-03-27T19:40:48.967Z,1711568448.967 [CBIT](DEBUG): Disabling WDT. 2024-03-27T19:40:48.979Z,1711568448.979 [CBIT](DEBUG): Opening all GF detection circuits. 2024-03-27T19:40:48.980Z,1711568448.980 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-03-27T19:40:49.037Z,1711568449.037 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-03-27T19:40:49.041Z,1711568449.041 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-03-27T19:40:49.048Z,1711568449.048 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-03-27T19:40:49.122Z,1711568449.122 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-03-27T19:40:49.163Z,1711568449.163 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-03-27T19:40:49.252Z,1711568449.252 [logger ThreadHandler](INFO): Thread cancelled.