2025-10-10T17:56:00.176Z,1760118960.176 [CommandExec](IMPORTANT): got command restart logs 2025-10-10T17:58:26.143Z,1760119106.143 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-10-10T17:59:28.407Z,1760119168.407 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-10-10T17:59:59.531Z,1760119199.531 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-10-10T18:00:15.414Z,1760119215.414 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-10T18:00:15.414Z,1760119215.414 [Default:CheckIn:C.Wait] Stopped 2025-10-10T18:00:15.414Z,1760119215.414 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-10T18:00:15.415Z,1760119215.415 [Default:CheckIn:D] Running Loop=1 2025-10-10T18:00:15.832Z,1760119215.832 [Default:CheckIn:D] Stopped 2025-10-10T18:00:15.832Z,1760119215.832 [Default:CheckIn:E] Running Loop=1 2025-10-10T18:00:16.226Z,1760119216.226 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 14.254376 min 2025-10-10T18:00:16.227Z,1760119216.227 [Default:CheckIn:E] Stopped 2025-10-10T18:00:16.227Z,1760119216.227 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-10T18:00:16.227Z,1760119216.227 [Default:CheckIn] Stopped 2025-10-10T18:00:16.227Z,1760119216.227 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T18:00:16.227Z,1760119216.227 [Default:CheckIn](INFO): Running loop #3 2025-10-10T18:00:16.227Z,1760119216.227 [Default:CheckIn] Running Loop=3 2025-10-10T18:00:16.227Z,1760119216.227 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-10T18:00:16.228Z,1760119216.228 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-10T18:00:18.230Z,1760119218.230 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180017.00,A,3648.19430,N,12150.01421,W,26.553,81.21,101025,,,D*7B 2025-10-10T18:00:18.232Z,1760119218.232 [NAL9602](INFO): GPS fix at 20251010T180017: (36.803238, -121.833570) 2025-10-10T18:00:18.268Z,1760119218.268 [Default:CheckIn:Read_GPS] Stopped 2025-10-10T18:00:18.268Z,1760119218.268 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-10T18:00:30.655Z,1760119230.655 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-10-10T18:00:33.668Z,1760119233.668 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.735519 2025-10-10T18:00:49.750Z,1760119249.750 [NAL9602](INFO): SBD MO Status=0, MOMSN=65236, MT Status=0, MTMSN=0 2025-10-10T18:00:49.750Z,1760119249.750 [NAL9602](INFO): No messages in MT queue 2025-10-10T18:01:04.791Z,1760119264.791 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-10-10T18:01:08.362Z,1760119268.362 [NAL9602](INFO): SBD MO Status=1, MOMSN=65237, MT Status=0, MTMSN=0 2025-10-10T18:01:08.415Z,1760119268.415 [NAL9602](INFO): Sent 39 bytes from file Logs/20251009T194131/Courier0141.lzma 2025-10-10T18:01:08.415Z,1760119268.415 [NAL9602](INFO): Packets left to send: 0 2025-10-10T18:01:23.758Z,1760119283.758 [NAL9602](INFO): SBD MO Status=1, MOMSN=65238, MT Status=0, MTMSN=0 2025-10-10T18:01:23.807Z,1760119283.807 [NAL9602](INFO): Sent 268 bytes from file Logs/20251010T175600/Courier0000.lzma 2025-10-10T18:01:23.808Z,1760119283.808 [NAL9602](INFO): Packets left to send: 0 2025-10-10T18:01:34.708Z,1760119294.708 [NAL9602](INFO): SBD MO Status=1, MOMSN=65239, MT Status=0, MTMSN=0 2025-10-10T18:01:34.756Z,1760119294.756 [NAL9602](INFO): Sent 103 bytes from file Logs/20251009T194131/Express0142.lzma 2025-10-10T18:01:34.756Z,1760119294.756 [NAL9602](INFO): Packets left to send: 0 2025-10-10T18:01:35.949Z,1760119295.949 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-10-10T18:01:45.660Z,1760119305.660 [NAL9602](INFO): SBD MO Status=1, MOMSN=65240, MT Status=0, MTMSN=0 2025-10-10T18:01:45.715Z,1760119305.715 [NAL9602](INFO): Sent 332 bytes from file Logs/20251010T175600/Express0001.lzma 2025-10-10T18:01:45.715Z,1760119305.715 [NAL9602](INFO): Packets left to send: 1 2025-10-10T18:01:56.222Z,1760119316.222 [NAL9602](INFO): SBD MO Status=1, MOMSN=65241, MT Status=0, MTMSN=0 2025-10-10T18:01:56.279Z,1760119316.279 [NAL9602](INFO): Sent 161 bytes from file Logs/20251010T175600/Express0001.lzma 2025-10-10T18:01:56.279Z,1760119316.279 [NAL9602](INFO): Packets left to send: 0 2025-10-10T18:02:06.776Z,1760119326.776 [NAL9602](INFO): SBD MO Status=1, MOMSN=65242, MT Status=0, MTMSN=0 2025-10-10T18:02:06.825Z,1760119326.825 [NAL9602](INFO): Sent 61 bytes from file Logs/20251010T175600/Express0004.lzma 2025-10-10T18:02:06.826Z,1760119326.826 [NAL9602](INFO): Packets left to send: 0 2025-10-10T18:02:07.081Z,1760119327.081 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-10-10T18:02:17.270Z,1760119337.270 [NAL9602](INFO): SBD MO Status=0, MOMSN=65243, MT Status=0, MTMSN=0 2025-10-10T18:02:17.381Z,1760119337.381 [Default:CheckIn:Read_Iridium] Stopped 2025-10-10T18:02:17.381Z,1760119337.381 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-10T18:02:17.381Z,1760119337.381 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-10T18:02:38.203Z,1760119358.203 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-10-10T18:02:47.977Z,1760119367.977 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-10T18:03:09.327Z,1760119389.327 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-10-10T18:03:31.610Z,1760119411.610 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-10-10T18:03:40.452Z,1760119420.452 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-10-10T18:04:11.575Z,1760119451.575 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-10-10T18:04:42.700Z,1760119482.700 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-10-10T18:05:13.823Z,1760119513.823 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-10-10T18:05:44.947Z,1760119544.947 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-10-10T18:06:02.527Z,1760119562.527 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003255 2025-10-10T18:07:17.922Z,1760119637.922 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-10T18:07:17.923Z,1760119637.923 [Default:CheckIn:C.Wait] Stopped 2025-10-10T18:07:17.923Z,1760119637.923 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-10T18:07:17.939Z,1760119637.939 [Default:CheckIn:D] Running Loop=1 2025-10-10T18:07:18.320Z,1760119638.320 [Default:CheckIn:D] Stopped 2025-10-10T18:07:18.320Z,1760119638.320 [Default:CheckIn:E] Running Loop=1 2025-10-10T18:07:18.770Z,1760119638.770 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.295844 min 2025-10-10T18:07:18.770Z,1760119638.770 [Default:CheckIn:E] Stopped 2025-10-10T18:07:18.771Z,1760119638.771 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-10T18:07:18.771Z,1760119638.771 [Default:CheckIn] Stopped 2025-10-10T18:07:18.771Z,1760119638.771 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T18:07:18.771Z,1760119638.771 [Default:CheckIn](INFO): Running loop #4 2025-10-10T18:07:18.771Z,1760119638.771 [Default:CheckIn] Running Loop=4 2025-10-10T18:07:18.771Z,1760119638.771 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-10T18:07:18.772Z,1760119638.772 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-10T18:07:20.744Z,1760119640.744 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180720.00,A,3648.43011,N,12147.33254,W,8.553,50.86,101025,,,D*4E 2025-10-10T18:07:20.746Z,1760119640.746 [NAL9602](INFO): GPS fix at 20251010T180720: (36.807169, -121.788876) 2025-10-10T18:07:20.776Z,1760119640.776 [Default:CheckIn:Read_GPS] Stopped 2025-10-10T18:07:20.776Z,1760119640.776 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-10T18:07:24.995Z,1760119644.995 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20251010T175600/Courier0006.lzma 2025-10-10T18:07:25.997Z,1760119645.997 [DataOverHttps](INFO): Moved sent file to Logs/20251010T175600/Courier0006.lzma.bak 2025-10-10T18:07:25.997Z,1760119645.997 [DataOverHttps](INFO): SBD MOMSN=26169126 2025-10-10T18:07:36.576Z,1760119656.576 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-10-10T18:07:41.987Z,1760119661.987 [DataOverHttps](INFO): Sending 167 bytes from file Logs/20251010T175600/Express0007.lzma 2025-10-10T18:07:42.989Z,1760119662.989 [DataOverHttps](INFO): Moved sent file to Logs/20251010T175600/Express0007.lzma.bak 2025-10-10T18:07:42.989Z,1760119662.989 [DataOverHttps](INFO): SBD MOMSN=26169129 2025-10-10T18:07:44.252Z,1760119664.252 [Default:CheckIn:Read_Iridium] Stopped 2025-10-10T18:07:44.252Z,1760119664.252 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-10T18:07:44.252Z,1760119664.252 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-10T18:07:48.318Z,1760119668.318 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-10-10T18:07:54.335Z,1760119674.335 [NAL9602](INFO): SBD MO Status=2, MOMSN=65244, MT Status=2, MTMSN=0 2025-10-10T18:07:54.336Z,1760119674.336 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-10-10T18:08:28.111Z,1760119708.111 [NAL9602](INFO): SBD MO Status=0, MOMSN=65244, MT Status=0, MTMSN=0 2025-10-10T18:08:28.116Z,1760119708.116 [NAL9602](INFO): No messages in MT queue 2025-10-10T18:08:58.714Z,1760119738.714 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-10T18:10:06.923Z,1760119806.923 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T18:10:13.387Z,1760119813.387 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T18:10:26.799Z,1760119826.799 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T18:10:39.727Z,1760119839.727 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T18:10:52.656Z,1760119852.656 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T18:11:05.993Z,1760119865.993 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T18:11:18.915Z,1760119878.915 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T18:11:31.438Z,1760119891.438 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T18:11:44.770Z,1760119904.770 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T18:11:57.295Z,1760119917.295 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T18:12:10.222Z,1760119930.222 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T18:12:23.584Z,1760119943.584 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T18:12:30.494Z,1760119950.494 [BPC1](INFO): Calculating totals. Valid battery stick count: 48. Valid reserve battery stick count: 4. 2025-10-10T18:12:30.496Z,1760119950.496 [BPC1](INFO): Received data from all battery sticks. 2025-10-10T18:12:45.054Z,1760119965.054 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-10T18:12:45.054Z,1760119965.054 [Default:CheckIn:C.Wait] Stopped 2025-10-10T18:12:45.055Z,1760119965.055 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-10T18:12:45.055Z,1760119965.055 [Default:CheckIn:D] Running Loop=1 2025-10-10T18:12:45.453Z,1760119965.453 [Default:CheckIn:D] Stopped 2025-10-10T18:12:45.453Z,1760119965.453 [Default:CheckIn:E] Running Loop=1 2025-10-10T18:12:45.864Z,1760119965.864 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 26.748051 min 2025-10-10T18:12:45.864Z,1760119965.864 [Default:CheckIn:E] Stopped 2025-10-10T18:12:45.865Z,1760119965.865 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-10T18:12:45.865Z,1760119965.865 [Default:CheckIn] Stopped 2025-10-10T18:12:45.865Z,1760119965.865 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T18:12:45.865Z,1760119965.865 [Default:CheckIn](INFO): Running loop #5 2025-10-10T18:12:45.865Z,1760119965.865 [Default:CheckIn] Running Loop=5 2025-10-10T18:12:45.865Z,1760119965.865 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-10T18:12:45.865Z,1760119965.865 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-10T18:12:47.871Z,1760119967.871 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181247.00,A,3648.22541,N,12147.15614,W,3.538,214.51,101025,,,D*76 2025-10-10T18:12:47.873Z,1760119967.873 [NAL9602](INFO): GPS fix at 20251010T181247: (36.803757, -121.785936) 2025-10-10T18:12:47.905Z,1760119967.905 [Default:CheckIn:Read_GPS] Stopped 2025-10-10T18:12:47.905Z,1760119967.905 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-10T18:12:54.963Z,1760119974.963 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20251010T175600/Courier0009.lzma 2025-10-10T18:12:55.965Z,1760119975.965 [DataOverHttps](INFO): Moved sent file to Logs/20251010T175600/Courier0009.lzma.bak 2025-10-10T18:12:55.965Z,1760119975.965 [DataOverHttps](INFO): SBD MOMSN=26169172 2025-10-10T18:13:11.790Z,1760119991.790 [NAL9602](INFO): SBD MO Status=0, MOMSN=65245, MT Status=0, MTMSN=0 2025-10-10T18:13:11.790Z,1760119991.790 [NAL9602](INFO): No messages in MT queue 2025-10-10T18:13:12.219Z,1760119992.219 [DataOverHttps](INFO): Sending 167 bytes from file Logs/20251010T175600/Express0010.lzma 2025-10-10T18:13:13.221Z,1760119993.221 [DataOverHttps](INFO): Moved sent file to Logs/20251010T175600/Express0010.lzma.bak 2025-10-10T18:13:13.221Z,1760119993.221 [DataOverHttps](INFO): SBD MOMSN=26169175 2025-10-10T18:13:14.627Z,1760119994.627 [Default:CheckIn:Read_Iridium] Stopped 2025-10-10T18:13:14.627Z,1760119994.627 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-10T18:13:14.627Z,1760119994.627 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-10T18:13:31.991Z,1760120011.991 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-10-10T18:13:42.492Z,1760120022.492 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-10T18:18:15.326Z,1760120295.326 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-10T18:18:15.326Z,1760120295.326 [Default:CheckIn:C.Wait] Stopped 2025-10-10T18:18:15.326Z,1760120295.326 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-10T18:18:15.327Z,1760120295.327 [Default:CheckIn:D] Running Loop=1 2025-10-10T18:18:15.733Z,1760120295.733 [Default:CheckIn:D] Stopped 2025-10-10T18:18:15.733Z,1760120295.733 [Default:CheckIn:E] Running Loop=1 2025-10-10T18:18:16.146Z,1760120296.146 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.252728 min 2025-10-10T18:18:16.146Z,1760120296.146 [Default:CheckIn:E] Stopped 2025-10-10T18:18:16.147Z,1760120296.147 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-10T18:18:16.147Z,1760120296.147 [Default:CheckIn] Stopped 2025-10-10T18:18:16.147Z,1760120296.147 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T18:18:16.147Z,1760120296.147 [Default:CheckIn](INFO): Running loop #6 2025-10-10T18:18:16.147Z,1760120296.147 [Default:CheckIn] Running Loop=6 2025-10-10T18:18:16.147Z,1760120296.147 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-10T18:18:16.147Z,1760120296.147 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-10T18:18:18.147Z,1760120298.147 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181817.00,A,3648.15274,N,12147.21928,W,0.078,203.86,101025,,,D*75 2025-10-10T18:18:18.149Z,1760120298.149 [NAL9602](INFO): GPS fix at 20251010T181817: (36.802546, -121.786988) 2025-10-10T18:18:18.190Z,1760120298.190 [Default:CheckIn:Read_GPS] Stopped 2025-10-10T18:18:18.190Z,1760120298.190 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-10T18:18:28.179Z,1760120308.179 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20251010T175600/Courier0012.lzma 2025-10-10T18:18:29.181Z,1760120309.181 [DataOverHttps](INFO): Moved sent file to Logs/20251010T175600/Courier0012.lzma.bak 2025-10-10T18:18:29.181Z,1760120309.181 [DataOverHttps](INFO): SBD MOMSN=26169215 2025-10-10T18:18:45.141Z,1760120325.141 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20251010T175600/Express0013.lzma 2025-10-10T18:18:46.141Z,1760120326.141 [DataOverHttps](INFO): Moved sent file to Logs/20251010T175600/Express0013.lzma.bak 2025-10-10T18:18:46.141Z,1760120326.141 [DataOverHttps](INFO): SBD MOMSN=26169218 2025-10-10T18:18:47.544Z,1760120327.544 [Default:CheckIn:Read_Iridium] Stopped 2025-10-10T18:18:47.544Z,1760120327.544 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-10T18:18:47.544Z,1760120327.544 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-10T18:19:26.325Z,1760120366.325 [NAL9602](INFO): SBD MO Status=0, MOMSN=65246, MT Status=0, MTMSN=0 2025-10-10T18:19:26.326Z,1760120366.326 [NAL9602](INFO): No messages in MT queue 2025-10-10T18:19:57.017Z,1760120397.017 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-10T18:23:32.051Z,1760120612.051 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-10-10T18:23:48.219Z,1760120628.219 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-10T18:23:48.219Z,1760120628.219 [Default:CheckIn:C.Wait] Stopped 2025-10-10T18:23:48.219Z,1760120628.219 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-10T18:23:48.219Z,1760120628.219 [Default:CheckIn:D] Running Loop=1 2025-10-10T18:23:48.625Z,1760120628.625 [Default:CheckIn:D] Stopped 2025-10-10T18:23:48.625Z,1760120628.625 [Default:CheckIn:E] Running Loop=1 2025-10-10T18:23:49.044Z,1760120629.044 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 37.800928 min 2025-10-10T18:23:49.044Z,1760120629.044 [Default:CheckIn:E] Stopped 2025-10-10T18:23:49.045Z,1760120629.045 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-10T18:23:49.045Z,1760120629.045 [Default:CheckIn] Stopped 2025-10-10T18:23:49.045Z,1760120629.045 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T18:23:49.045Z,1760120629.045 [Default:CheckIn](INFO): Running loop #7 2025-10-10T18:23:49.045Z,1760120629.045 [Default:CheckIn] Running Loop=7 2025-10-10T18:23:49.045Z,1760120629.045 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-10T18:23:49.045Z,1760120629.045 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-10T18:23:51.038Z,1760120631.038 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182350.00,A,3648.15654,N,12147.22527,W,0.117,20.37,101025,,,D*49 2025-10-10T18:23:51.040Z,1760120631.040 [NAL9602](INFO): GPS fix at 20251010T182350: (36.802609, -121.787088) 2025-10-10T18:23:51.051Z,1760120631.051 [Default:CheckIn:Read_GPS] Stopped 2025-10-10T18:23:51.051Z,1760120631.051 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-10T18:23:58.442Z,1760120638.442 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20251010T175600/Courier0015.lzma 2025-10-10T18:23:59.445Z,1760120639.445 [DataOverHttps](INFO): Moved sent file to Logs/20251010T175600/Courier0015.lzma.bak 2025-10-10T18:23:59.445Z,1760120639.445 [DataOverHttps](INFO): SBD MOMSN=26169257 2025-10-10T18:24:15.415Z,1760120655.415 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20251010T175600/Express0016.lzma 2025-10-10T18:24:16.417Z,1760120656.417 [DataOverHttps](INFO): Moved sent file to Logs/20251010T175600/Express0016.lzma.bak 2025-10-10T18:24:16.417Z,1760120656.417 [DataOverHttps](INFO): SBD MOMSN=26169260 2025-10-10T18:24:17.740Z,1760120657.740 [Default:CheckIn:Read_Iridium] Stopped 2025-10-10T18:24:17.740Z,1760120657.740 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-10T18:24:17.740Z,1760120657.740 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-10T18:24:34.726Z,1760120674.726 [NAL9602](INFO): SBD MO Status=2, MOMSN=65247, MT Status=2, MTMSN=0 2025-10-10T18:24:34.726Z,1760120674.726 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-10-10T18:28:53.338Z,1760120933.338 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-10-10T18:29:18.402Z,1760120958.402 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-10T18:29:18.402Z,1760120958.402 [Default:CheckIn:C.Wait] Stopped 2025-10-10T18:29:18.402Z,1760120958.402 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-10T18:29:18.402Z,1760120958.402 [Default:CheckIn:D] Running Loop=1 2025-10-10T18:29:18.804Z,1760120958.804 [Default:CheckIn:D] Stopped 2025-10-10T18:29:18.804Z,1760120958.804 [Default:CheckIn:E] Running Loop=1 2025-10-10T18:29:19.223Z,1760120959.223 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 43.303902 min 2025-10-10T18:29:19.223Z,1760120959.223 [Default:CheckIn:E] Stopped 2025-10-10T18:29:19.223Z,1760120959.223 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-10T18:29:19.223Z,1760120959.223 [Default:CheckIn] Stopped 2025-10-10T18:29:19.223Z,1760120959.223 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T18:29:19.224Z,1760120959.224 [Default:CheckIn](INFO): Running loop #8 2025-10-10T18:29:19.224Z,1760120959.224 [Default:CheckIn] Running Loop=8 2025-10-10T18:29:19.224Z,1760120959.224 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-10T18:29:19.224Z,1760120959.224 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-10T18:29:21.218Z,1760120961.218 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182920.00,A,3648.15701,N,12147.22499,W,0.039,20.37,101025,,,A*49 2025-10-10T18:29:21.220Z,1760120961.220 [NAL9602](INFO): GPS fix at 20251010T182920: (36.802617, -121.787083) 2025-10-10T18:29:21.257Z,1760120961.257 [Default:CheckIn:Read_GPS] Stopped 2025-10-10T18:29:21.257Z,1760120961.257 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-10T18:29:28.987Z,1760120968.987 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20251010T175600/Courier0018.lzma 2025-10-10T18:29:29.990Z,1760120969.990 [DataOverHttps](INFO): Moved sent file to Logs/20251010T175600/Courier0018.lzma.bak 2025-10-10T18:29:29.990Z,1760120969.990 [DataOverHttps](INFO): SBD MOMSN=26169298 2025-10-10T18:29:45.943Z,1760120985.943 [DataOverHttps](INFO): Sending 250 bytes from file Logs/20251010T175600/Express0019.lzma 2025-10-10T18:29:46.945Z,1760120986.945 [DataOverHttps](INFO): Moved sent file to Logs/20251010T175600/Express0019.lzma.bak 2025-10-10T18:29:46.945Z,1760120986.945 [DataOverHttps](INFO): SBD MOMSN=26169301 2025-10-10T18:29:48.300Z,1760120988.300 [Default:CheckIn:Read_Iridium] Stopped 2025-10-10T18:29:48.300Z,1760120988.300 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-10T18:29:48.300Z,1760120988.300 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-10T18:29:53.540Z,1760120993.540 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-10T18:33:32.186Z,1760121212.186 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-10-10T18:34:48.971Z,1760121288.971 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-10T18:34:48.972Z,1760121288.972 [Default:CheckIn:C.Wait] Stopped 2025-10-10T18:34:48.972Z,1760121288.972 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-10T18:34:48.972Z,1760121288.972 [Default:CheckIn:D] Running Loop=1 2025-10-10T18:34:49.359Z,1760121289.359 [Default:CheckIn:D] Stopped 2025-10-10T18:34:49.359Z,1760121289.359 [Default:CheckIn:E] Running Loop=1 2025-10-10T18:34:49.772Z,1760121289.772 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 48.813155 min 2025-10-10T18:34:49.772Z,1760121289.772 [Default:CheckIn:E] Stopped 2025-10-10T18:34:49.772Z,1760121289.772 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-10T18:34:49.772Z,1760121289.772 [Default:CheckIn] Stopped 2025-10-10T18:34:49.773Z,1760121289.773 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T18:34:49.773Z,1760121289.773 [Default:CheckIn](INFO): Running loop #9 2025-10-10T18:34:49.773Z,1760121289.773 [Default:CheckIn] Running Loop=9 2025-10-10T18:34:49.773Z,1760121289.773 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-10T18:34:49.773Z,1760121289.773 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-10T18:34:51.770Z,1760121291.770 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183451.00,A,3648.15538,N,12147.22317,W,0.019,20.37,101025,,,A*48 2025-10-10T18:34:51.772Z,1760121291.772 [NAL9602](INFO): GPS fix at 20251010T183451: (36.802590, -121.787053) 2025-10-10T18:34:51.800Z,1760121291.800 [Default:CheckIn:Read_GPS] Stopped 2025-10-10T18:34:51.800Z,1760121291.800 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-10T18:34:58.951Z,1760121298.951 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20251010T175600/Courier0021.lzma 2025-10-10T18:34:59.953Z,1760121299.953 [DataOverHttps](INFO): Moved sent file to Logs/20251010T175600/Courier0021.lzma.bak 2025-10-10T18:34:59.953Z,1760121299.953 [DataOverHttps](INFO): SBD MOMSN=26169341 2025-10-10T18:35:16.051Z,1760121316.051 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20251010T175600/Express0022.lzma 2025-10-10T18:35:17.045Z,1760121317.045 [DataOverHttps](INFO): Moved sent file to Logs/20251010T175600/Express0022.lzma.bak 2025-10-10T18:35:17.045Z,1760121317.045 [DataOverHttps](INFO): SBD MOMSN=26169344 2025-10-10T18:35:18.461Z,1760121318.461 [Default:CheckIn:Read_Iridium] Stopped 2025-10-10T18:35:18.461Z,1760121318.461 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-10T18:35:18.461Z,1760121318.461 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-10T18:35:22.477Z,1760121322.477 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-10-10T18:35:22.551Z,1760121322.551 [NAL9602](ERROR): received: +CSQ:0 OK247, 2, 0, 0, 0 OK 2025-10-10T18:37:22.120Z,1760121442.120 [NAL9602](INFO): SBD MO Status=2, MOMSN=65247, MT Status=2, MTMSN=0 2025-10-10T18:37:22.120Z,1760121442.120 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-10-10T18:38:57.474Z,1760121537.474 [NAL9602](INFO): SBD MO Status=0, MOMSN=65247, MT Status=0, MTMSN=0 2025-10-10T18:38:57.474Z,1760121537.474 [NAL9602](INFO): No messages in MT queue 2025-10-10T18:39:28.188Z,1760121568.188 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-10T18:40:19.121Z,1760121619.121 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-10T18:40:19.121Z,1760121619.121 [Default:CheckIn:C.Wait] Stopped 2025-10-10T18:40:19.121Z,1760121619.121 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-10T18:40:19.121Z,1760121619.121 [Default:CheckIn:D] Running Loop=1 2025-10-10T18:40:19.506Z,1760121619.506 [Default:CheckIn:D] Stopped 2025-10-10T18:40:19.507Z,1760121619.507 [Default:CheckIn:E] Running Loop=1 2025-10-10T18:40:19.910Z,1760121619.910 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 54.315617 min 2025-10-10T18:40:19.910Z,1760121619.910 [Default:CheckIn:E] Stopped 2025-10-10T18:40:19.910Z,1760121619.910 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-10T18:40:19.910Z,1760121619.910 [Default:CheckIn] Stopped 2025-10-10T18:40:19.910Z,1760121619.910 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T18:40:19.911Z,1760121619.911 [Default:CheckIn](INFO): Running loop #10 2025-10-10T18:40:19.911Z,1760121619.911 [Default:CheckIn] Running Loop=10 2025-10-10T18:40:19.911Z,1760121619.911 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-10T18:40:19.911Z,1760121619.911 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-10T18:40:21.914Z,1760121621.914 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184021.00,A,3648.16587,N,12147.25011,W,1.477,307.70,101025,,,A*71 2025-10-10T18:40:21.925Z,1760121621.925 [NAL9602](INFO): GPS fix at 20251010T184021: (36.802765, -121.787502) 2025-10-10T18:40:21.936Z,1760121621.936 [Default:CheckIn:Read_GPS] Stopped 2025-10-10T18:40:21.937Z,1760121621.937 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-10T18:40:31.282Z,1760121631.282 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20251010T175600/Courier0024.lzma 2025-10-10T18:40:32.285Z,1760121632.285 [DataOverHttps](INFO): Moved sent file to Logs/20251010T175600/Courier0024.lzma.bak 2025-10-10T18:40:32.285Z,1760121632.285 [DataOverHttps](INFO): SBD MOMSN=26169383 2025-10-10T18:40:48.219Z,1760121648.219 [DataOverHttps](INFO): Sending 159 bytes from file Logs/20251010T175600/Express0025.lzma 2025-10-10T18:40:49.221Z,1760121649.221 [DataOverHttps](INFO): Moved sent file to Logs/20251010T175600/Express0025.lzma.bak 2025-10-10T18:40:49.221Z,1760121649.221 [DataOverHttps](INFO): SBD MOMSN=26169386 2025-10-10T18:40:50.644Z,1760121650.644 [Default:CheckIn:Read_Iridium] Stopped 2025-10-10T18:40:50.644Z,1760121650.644 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-10T18:40:50.644Z,1760121650.644 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-10T18:40:53.062Z,1760121653.062 [NAL9602](INFO): SBD MO Status=0, MOMSN=65248, MT Status=0, MTMSN=0 2025-10-10T18:40:53.062Z,1760121653.062 [NAL9602](INFO): No messages in MT queue 2025-10-10T18:41:23.856Z,1760121683.856 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-10T18:43:32.266Z,1760121812.266 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-10-10T18:45:51.246Z,1760121951.246 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-10T18:45:51.246Z,1760121951.246 [Default:CheckIn:C.Wait] Stopped 2025-10-10T18:45:51.246Z,1760121951.246 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-10T18:45:51.247Z,1760121951.247 [Default:CheckIn:D] Running Loop=1 2025-10-10T18:45:51.652Z,1760121951.652 [Default:CheckIn:D] Stopped 2025-10-10T18:45:51.652Z,1760121951.652 [Default:CheckIn:E] Running Loop=1 2025-10-10T18:45:52.053Z,1760121952.053 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 59.851379 min 2025-10-10T18:45:52.053Z,1760121952.053 [Default:CheckIn:E] Stopped 2025-10-10T18:45:52.053Z,1760121952.053 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-10T18:45:52.053Z,1760121952.053 [Default:CheckIn] Stopped 2025-10-10T18:45:52.053Z,1760121952.053 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T18:45:52.053Z,1760121952.053 [Default:CheckIn](INFO): Running loop #11 2025-10-10T18:45:52.053Z,1760121952.053 [Default:CheckIn] Running Loop=11 2025-10-10T18:45:52.053Z,1760121952.053 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-10T18:45:52.053Z,1760121952.053 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-10T18:45:54.066Z,1760121954.066 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184553.00,A,3648.17824,N,12147.27791,W,0.175,247.50,101025,,,A*78 2025-10-10T18:45:54.068Z,1760121954.068 [NAL9602](INFO): GPS fix at 20251010T184553: (36.802971, -121.787965) 2025-10-10T18:45:54.100Z,1760121954.100 [Default:CheckIn:Read_GPS] Stopped 2025-10-10T18:45:54.100Z,1760121954.100 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-10T18:46:06.851Z,1760121966.851 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20251010T175600/Courier0027.lzma 2025-10-10T18:46:07.853Z,1760121967.853 [DataOverHttps](INFO): Moved sent file to Logs/20251010T175600/Courier0027.lzma.bak 2025-10-10T18:46:07.853Z,1760121967.853 [DataOverHttps](INFO): SBD MOMSN=26169428 2025-10-10T18:46:09.413Z,1760121969.413 [NAL9602](INFO): SBD MO Status=0, MOMSN=65249, MT Status=0, MTMSN=0 2025-10-10T18:46:09.413Z,1760121969.413 [NAL9602](INFO): No messages in MT queue 2025-10-10T18:46:31.405Z,1760121991.405 [DataOverHttps](INFO): Sending 167 bytes from file Logs/20251010T175600/Express0028.lzma 2025-10-10T18:46:33.401Z,1760121993.401 [DataOverHttps](INFO): Moved sent file to Logs/20251010T175600/Express0028.lzma.bak 2025-10-10T18:46:33.401Z,1760121993.401 [DataOverHttps](INFO): SBD MOMSN=26169431 2025-10-10T18:46:34.539Z,1760121994.539 [Default:CheckIn:Read_Iridium] Stopped 2025-10-10T18:46:34.539Z,1760121994.539 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-10T18:46:34.539Z,1760121994.539 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-10T18:46:40.194Z,1760122000.194 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-10T18:51:35.115Z,1760122295.115 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-10T18:51:35.115Z,1760122295.115 [Default:CheckIn:C.Wait] Stopped 2025-10-10T18:51:35.115Z,1760122295.115 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-10T18:51:35.116Z,1760122295.116 [Default:CheckIn:D] Running Loop=1 2025-10-10T18:51:35.522Z,1760122295.522 [Default:CheckIn:D] Stopped 2025-10-10T18:51:35.522Z,1760122295.522 [Default:CheckIn:E] Running Loop=1 2025-10-10T18:51:35.932Z,1760122295.932 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 65.582544 min 2025-10-10T18:51:35.932Z,1760122295.932 [Default:CheckIn:E] Stopped 2025-10-10T18:51:35.932Z,1760122295.932 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-10T18:51:35.933Z,1760122295.933 [Default:CheckIn] Stopped 2025-10-10T18:51:35.933Z,1760122295.933 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T18:51:35.933Z,1760122295.933 [Default:CheckIn](INFO): Running loop #12 2025-10-10T18:51:35.933Z,1760122295.933 [Default:CheckIn] Running Loop=12 2025-10-10T18:51:35.933Z,1760122295.933 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-10T18:51:35.933Z,1760122295.933 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-10T18:51:37.934Z,1760122297.934 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185137.00,A,3648.17750,N,12147.28073,W,0.097,247.50,101025,,,A*7A 2025-10-10T18:51:37.945Z,1760122297.945 [NAL9602](INFO): GPS fix at 20251010T185137: (36.802958, -121.788012) 2025-10-10T18:51:37.957Z,1760122297.957 [Default:CheckIn:Read_GPS] Stopped 2025-10-10T18:51:37.957Z,1760122297.957 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-10T18:51:48.603Z,1760122308.603 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20251010T175600/Courier0030.lzma 2025-10-10T18:51:49.605Z,1760122309.605 [DataOverHttps](INFO): Moved sent file to Logs/20251010T175600/Courier0030.lzma.bak 2025-10-10T18:51:49.605Z,1760122309.605 [DataOverHttps](INFO): SBD MOMSN=26169470 2025-10-10T18:52:08.043Z,1760122328.043 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20251010T175600/Express0031.lzma 2025-10-10T18:52:09.045Z,1760122329.045 [DataOverHttps](INFO): Moved sent file to Logs/20251010T175600/Express0031.lzma.bak 2025-10-10T18:52:09.045Z,1760122329.045 [DataOverHttps](INFO): SBD MOMSN=26169473 2025-10-10T18:52:10.323Z,1760122330.323 [Default:CheckIn:Read_Iridium] Stopped 2025-10-10T18:52:10.323Z,1760122330.323 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-10T18:52:10.327Z,1760122330.327 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-10T18:53:14.894Z,1760122394.894 [NAL9602](INFO): SBD MO Status=2, MOMSN=65250, MT Status=2, MTMSN=0 2025-10-10T18:53:14.894Z,1760122394.894 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-10-10T18:53:32.670Z,1760122412.670 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-10-10T18:53:34.690Z,1760122414.690 [NAL9602](INFO): SBD MO Status=0, MOMSN=65250, MT Status=0, MTMSN=0 2025-10-10T18:53:34.690Z,1760122414.690 [NAL9602](INFO): No messages in MT queue 2025-10-10T18:54:05.416Z,1760122445.416 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-10T18:57:10.930Z,1760122630.930 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-10T18:57:10.930Z,1760122630.930 [Default:CheckIn:C.Wait] Stopped 2025-10-10T18:57:10.930Z,1760122630.930 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-10T18:57:10.933Z,1760122630.933 [Default:CheckIn:D] Running Loop=1 2025-10-10T18:57:11.326Z,1760122631.326 [Default:CheckIn:D] Stopped 2025-10-10T18:57:11.326Z,1760122631.326 [Default:CheckIn:E] Running Loop=1 2025-10-10T18:57:11.727Z,1760122631.727 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 71.179281 min 2025-10-10T18:57:11.727Z,1760122631.727 [Default:CheckIn:E] Stopped 2025-10-10T18:57:11.727Z,1760122631.727 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-10T18:57:11.727Z,1760122631.727 [Default:CheckIn] Stopped 2025-10-10T18:57:11.728Z,1760122631.728 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T18:57:11.728Z,1760122631.728 [Default:CheckIn](INFO): Running loop #13 2025-10-10T18:57:11.728Z,1760122631.728 [Default:CheckIn] Running Loop=13 2025-10-10T18:57:11.728Z,1760122631.728 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-10T18:57:11.728Z,1760122631.728 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-10T18:57:13.741Z,1760122633.741 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185713.00,A,3648.17973,N,12147.27759,W,0.019,247.50,101025,,,A*73 2025-10-10T18:57:13.743Z,1760122633.743 [NAL9602](INFO): GPS fix at 20251010T185713: (36.802996, -121.787960) 2025-10-10T18:57:13.753Z,1760122633.753 [Default:CheckIn:Read_GPS] Stopped 2025-10-10T18:57:13.753Z,1760122633.753 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-10T18:57:21.667Z,1760122641.667 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20251010T175600/Courier0033.lzma 2025-10-10T18:57:22.669Z,1760122642.669 [DataOverHttps](INFO): Moved sent file to Logs/20251010T175600/Courier0033.lzma.bak 2025-10-10T18:57:22.669Z,1760122642.669 [DataOverHttps](INFO): SBD MOMSN=26169517 2025-10-10T18:57:38.768Z,1760122658.768 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20251010T175600/Express0034.lzma 2025-10-10T18:57:39.769Z,1760122659.769 [DataOverHttps](INFO): Moved sent file to Logs/20251010T175600/Express0034.lzma.bak 2025-10-10T18:57:39.769Z,1760122659.769 [DataOverHttps](INFO): SBD MOMSN=26169520 2025-10-10T18:57:41.250Z,1760122661.250 [Default:CheckIn:Read_Iridium] Stopped 2025-10-10T18:57:41.250Z,1760122661.250 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-10T18:57:41.250Z,1760122661.250 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-10T19:02:16.417Z,1760122936.417 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-10-10T19:02:41.910Z,1760122961.910 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-10T19:02:41.911Z,1760122961.911 [Default:CheckIn:C.Wait] Stopped 2025-10-10T19:02:41.911Z,1760122961.911 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-10T19:02:41.911Z,1760122961.911 [Default:CheckIn:D] Running Loop=1 2025-10-10T19:02:42.320Z,1760122962.320 [Default:CheckIn:D] Stopped 2025-10-10T19:02:42.320Z,1760122962.320 [Default:CheckIn:E] Running Loop=1 2025-10-10T19:02:42.736Z,1760122962.736 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 76.695825 min 2025-10-10T19:02:42.737Z,1760122962.737 [Default:CheckIn:E] Stopped 2025-10-10T19:02:42.737Z,1760122962.737 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-10T19:02:42.737Z,1760122962.737 [Default:CheckIn] Stopped 2025-10-10T19:02:42.737Z,1760122962.737 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T19:02:42.737Z,1760122962.737 [Default:CheckIn](INFO): Running loop #14 2025-10-10T19:02:42.737Z,1760122962.737 [Default:CheckIn] Running Loop=14 2025-10-10T19:02:42.737Z,1760122962.737 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-10T19:02:42.737Z,1760122962.737 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-10T19:02:44.727Z,1760122964.727 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190244.00,A,3648.17569,N,12147.27743,W,0.058,0.00,101025,,,A*7D 2025-10-10T19:02:44.730Z,1760122964.730 [NAL9602](INFO): GPS fix at 20251010T190244: (36.802928, -121.787957) 2025-10-10T19:02:44.740Z,1760122964.740 [Default:CheckIn:Read_GPS] Stopped 2025-10-10T19:02:44.741Z,1760122964.741 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-10T19:02:45.550Z,1760122965.550 [Default:CheckIn:Read_Iridium] Stopped 2025-10-10T19:02:45.550Z,1760122965.550 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-10T19:02:45.550Z,1760122965.550 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-10T19:03:17.457Z,1760122997.457 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-10T19:03:32.813Z,1760123012.813 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-10-10T19:07:46.217Z,1760123266.217 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-10T19:07:46.217Z,1760123266.217 [Default:CheckIn:C.Wait] Stopped 2025-10-10T19:07:46.217Z,1760123266.217 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-10T19:07:46.217Z,1760123266.217 [Default:CheckIn:D] Running Loop=1 2025-10-10T19:07:46.630Z,1760123266.630 [Default:CheckIn:D] Stopped 2025-10-10T19:07:46.630Z,1760123266.630 [Default:CheckIn:E] Running Loop=1 2025-10-10T19:07:47.024Z,1760123267.024 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 81.767684 min 2025-10-10T19:07:47.024Z,1760123267.024 [Default:CheckIn:E] Stopped 2025-10-10T19:07:47.025Z,1760123267.025 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-10T19:07:47.025Z,1760123267.025 [Default:CheckIn] Stopped 2025-10-10T19:07:47.025Z,1760123267.025 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T19:07:47.025Z,1760123267.025 [Default:CheckIn](INFO): Running loop #15 2025-10-10T19:07:47.025Z,1760123267.025 [Default:CheckIn] Running Loop=15 2025-10-10T19:07:47.025Z,1760123267.025 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-10T19:07:47.025Z,1760123267.025 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-10T19:07:49.042Z,1760123269.042 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190748.00,A,3648.18083,N,12147.27171,W,0.078,0.00,101025,,,A*7F 2025-10-10T19:07:49.044Z,1760123269.044 [NAL9602](INFO): GPS fix at 20251010T190748: (36.803014, -121.787862) 2025-10-10T19:07:49.056Z,1760123269.056 [Default:CheckIn:Read_GPS] Stopped 2025-10-10T19:07:49.056Z,1760123269.056 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-10T19:07:59.639Z,1760123279.639 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20251010T175600/Courier0036.lzma 2025-10-10T19:08:00.641Z,1760123280.641 [DataOverHttps](INFO): Moved sent file to Logs/20251010T175600/Courier0036.lzma.bak 2025-10-10T19:08:00.641Z,1760123280.641 [DataOverHttps](INFO): SBD MOMSN=26169593 2025-10-10T19:08:21.023Z,1760123301.023 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20251010T175600/Courier0039.lzma 2025-10-10T19:08:22.025Z,1760123302.025 [DataOverHttps](INFO): Moved sent file to Logs/20251010T175600/Courier0039.lzma.bak 2025-10-10T19:08:22.025Z,1760123302.025 [DataOverHttps](INFO): SBD MOMSN=26169596 2025-10-10T19:08:40.755Z,1760123320.755 [DataOverHttps](INFO): Sending 227 bytes from file Logs/20251010T175600/Express0037.lzma 2025-10-10T19:08:41.757Z,1760123321.757 [DataOverHttps](INFO): Moved sent file to Logs/20251010T175600/Express0037.lzma.bak 2025-10-10T19:08:41.757Z,1760123321.757 [DataOverHttps](INFO): SBD MOMSN=26169599 2025-10-10T19:08:58.295Z,1760123338.295 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20251010T175600/Express0040.lzma 2025-10-10T19:08:59.297Z,1760123339.297 [DataOverHttps](INFO): Moved sent file to Logs/20251010T175600/Express0040.lzma.bak 2025-10-10T19:08:59.298Z,1760123339.298 [DataOverHttps](INFO): SBD MOMSN=26169603 2025-10-10T19:09:00.624Z,1760123340.624 [Default:CheckIn:Read_Iridium] Stopped 2025-10-10T19:09:00.624Z,1760123340.624 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-10T19:09:00.625Z,1760123340.625 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-10T19:11:22.398Z,1760123482.398 [NAL9602](INFO): SBD MO Status=0, MOMSN=65251, MT Status=0, MTMSN=0 2025-10-10T19:11:22.398Z,1760123482.398 [NAL9602](INFO): No messages in MT queue 2025-10-10T19:11:53.110Z,1760123513.110 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-10T19:13:32.891Z,1760123612.891 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-10-10T19:13:36.523Z,1760123616.523 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-10-10T19:13:48.646Z,1760123628.646 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-10-10T19:14:00.359Z,1760123640.359 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-10-10T19:14:01.181Z,1760123641.181 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-10T19:14:01.181Z,1760123641.181 [Default:CheckIn:C.Wait] Stopped 2025-10-10T19:14:01.181Z,1760123641.181 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-10T19:14:01.181Z,1760123641.181 [Default:CheckIn:D] Running Loop=1 2025-10-10T19:14:01.607Z,1760123641.607 [Default:CheckIn:D] Stopped 2025-10-10T19:14:01.607Z,1760123641.607 [Default:CheckIn:E] Running Loop=1 2025-10-10T19:14:02.001Z,1760123642.001 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 88.017285 min 2025-10-10T19:14:02.001Z,1760123642.001 [Default:CheckIn:E] Stopped 2025-10-10T19:14:02.001Z,1760123642.001 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-10T19:14:02.001Z,1760123642.001 [Default:CheckIn] Stopped 2025-10-10T19:14:02.002Z,1760123642.002 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T19:14:02.002Z,1760123642.002 [Default:CheckIn](INFO): Running loop #16 2025-10-10T19:14:02.002Z,1760123642.002 [Default:CheckIn] Running Loop=16 2025-10-10T19:14:02.002Z,1760123642.002 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-10T19:14:02.002Z,1760123642.002 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-10T19:14:03.999Z,1760123643.999 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191403.00,A,3648.18236,N,12147.27318,W,0.019,0.00,101025,,,A*74 2025-10-10T19:14:04.001Z,1760123644.001 [NAL9602](INFO): GPS fix at 20251010T191403: (36.803039, -121.787886) 2025-10-10T19:14:04.013Z,1760123644.013 [Default:CheckIn:Read_GPS] Stopped 2025-10-10T19:14:04.013Z,1760123644.013 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-10T19:14:11.115Z,1760123651.115 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20251010T175600/Courier0042.lzma 2025-10-10T19:14:12.117Z,1760123652.117 [DataOverHttps](INFO): Moved sent file to Logs/20251010T175600/Courier0042.lzma.bak 2025-10-10T19:14:12.117Z,1760123652.117 [DataOverHttps](INFO): SBD MOMSN=26169643 2025-10-10T19:14:12.563Z,1760123652.563 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-10-10T19:14:24.297Z,1760123664.297 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-10-10T19:14:26.330Z,1760123666.330 [NAL9602](INFO): SBD MO Status=0, MOMSN=65252, MT Status=0, MTMSN=0 2025-10-10T19:14:26.330Z,1760123666.330 [NAL9602](INFO): No messages in MT queue 2025-10-10T19:14:31.635Z,1760123671.635 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20251010T175600/Express0043.lzma 2025-10-10T19:14:32.637Z,1760123672.637 [DataOverHttps](INFO): Moved sent file to Logs/20251010T175600/Express0043.lzma.bak 2025-10-10T19:14:32.637Z,1760123672.637 [DataOverHttps](INFO): SBD MOMSN=26169646 2025-10-10T19:14:34.036Z,1760123674.036 [Default:CheckIn:Read_Iridium] Stopped 2025-10-10T19:14:34.036Z,1760123674.036 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-10T19:14:34.036Z,1760123674.036 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-10T19:14:36.424Z,1760123676.424 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-10-10T19:14:47.749Z,1760123687.749 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-10-10T19:14:57.036Z,1760123697.036 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-10T19:14:59.451Z,1760123699.451 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-10-10T19:16:13.788Z,1760123773.788 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2025-10-10T19:17:33.375Z,1760123853.375 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T19:17:46.713Z,1760123866.713 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T19:18:00.039Z,1760123880.039 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T19:18:13.371Z,1760123893.371 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T19:18:26.702Z,1760123906.702 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T19:18:39.635Z,1760123919.635 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T19:18:52.964Z,1760123932.964 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T19:19:05.893Z,1760123945.893 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T19:19:19.223Z,1760123959.223 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T19:19:32.151Z,1760123972.151 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T19:19:34.593Z,1760123974.593 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-10T19:19:34.593Z,1760123974.593 [Default:CheckIn:C.Wait] Stopped 2025-10-10T19:19:34.593Z,1760123974.593 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-10T19:19:34.594Z,1760123974.594 [Default:CheckIn:D] Running Loop=1 2025-10-10T19:19:34.998Z,1760123974.998 [Default:CheckIn:D] Stopped 2025-10-10T19:19:34.998Z,1760123974.998 [Default:CheckIn:E] Running Loop=1 2025-10-10T19:19:35.407Z,1760123975.407 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 93.573804 min 2025-10-10T19:19:35.408Z,1760123975.408 [Default:CheckIn:E] Stopped 2025-10-10T19:19:35.408Z,1760123975.408 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-10T19:19:35.408Z,1760123975.408 [Default:CheckIn] Stopped 2025-10-10T19:19:35.408Z,1760123975.408 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T19:19:35.408Z,1760123975.408 [Default:CheckIn](INFO): Running loop #17 2025-10-10T19:19:35.408Z,1760123975.408 [Default:CheckIn] Running Loop=17 2025-10-10T19:19:35.408Z,1760123975.408 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-10T19:19:35.408Z,1760123975.408 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-10T19:19:37.407Z,1760123977.407 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191936.00,A,3648.18800,N,12147.27800,W,0.194,0.00,101025,,,A*76 2025-10-10T19:19:37.409Z,1760123977.409 [NAL9602](INFO): GPS fix at 20251010T191936: (36.803133, -121.787967) 2025-10-10T19:19:37.420Z,1760123977.420 [Default:CheckIn:Read_GPS] Stopped 2025-10-10T19:19:37.421Z,1760123977.421 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-10T19:19:45.079Z,1760123985.079 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T19:19:45.399Z,1760123985.399 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20251010T175600/Courier0045.lzma 2025-10-10T19:19:46.401Z,1760123986.401 [DataOverHttps](INFO): Moved sent file to Logs/20251010T175600/Courier0045.lzma.bak 2025-10-10T19:19:46.401Z,1760123986.401 [DataOverHttps](INFO): SBD MOMSN=26169693 2025-10-10T19:19:57.618Z,1760123997.618 [BPC1](INFO): Calculating totals. Valid battery stick count: 51. Valid reserve battery stick count: 5. 2025-10-10T19:19:57.626Z,1760123997.626 [BPC1](INFO): Received data from all battery sticks. 2025-10-10T19:20:02.491Z,1760124002.491 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20251010T175600/Express0046.lzma 2025-10-10T19:20:03.493Z,1760124003.493 [DataOverHttps](INFO): Moved sent file to Logs/20251010T175600/Express0046.lzma.bak 2025-10-10T19:20:03.493Z,1760124003.493 [DataOverHttps](INFO): SBD MOMSN=26169696 2025-10-10T19:20:04.891Z,1760124004.891 [Default:CheckIn:Read_Iridium] Stopped 2025-10-10T19:20:04.891Z,1760124004.891 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-10T19:20:04.891Z,1760124004.891 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-10T19:20:39.230Z,1760124039.230 [NAL9602](INFO): SBD MO Status=2, MOMSN=65253, MT Status=2, MTMSN=0 2025-10-10T19:20:39.230Z,1760124039.230 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-10-10T19:20:46.913Z,1760124046.913 [NAL9602](INFO): SBD MO Status=0, MOMSN=65253, MT Status=0, MTMSN=0 2025-10-10T19:20:46.914Z,1760124046.914 [NAL9602](INFO): No messages in MT queue 2025-10-10T19:21:17.596Z,1760124077.596 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-10T19:22:37.589Z,1760124157.589 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-10-10T19:22:37.589Z,1760124157.589 [DropWeight] Hardware Fault, FailCount= 1 2025-10-10T19:22:37.589Z,1760124157.589 [DropWeight](ERROR): Hardware Fault 2025-10-10T19:22:37.608Z,1760124157.608 [CommandExec](FAULT): Scheduling is paused 2025-10-10T19:22:37.609Z,1760124157.609 [CBIT](INFO): Critical error at 20251010T192237 2025-10-10T19:22:37.611Z,1760124157.611 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-10-10T19:22:37.612Z,1760124157.612 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-10-10T19:22:37.656Z,1760124157.656 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2025-10-10T19:22:38.031Z,1760124158.031 [CBIT](INFO): Critical error at 20251010T192237 2025-10-10T19:23:32.942Z,1760124212.942 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-10-10T19:25:05.473Z,1760124305.473 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-10T19:25:05.473Z,1760124305.473 [Default:CheckIn:C.Wait] Stopped 2025-10-10T19:25:05.473Z,1760124305.473 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-10T19:25:05.473Z,1760124305.473 [Default:CheckIn:D] Running Loop=1 2025-10-10T19:25:05.881Z,1760124305.881 [Default:CheckIn:D] Stopped 2025-10-10T19:25:05.881Z,1760124305.881 [Default:CheckIn:E] Running Loop=1 2025-10-10T19:25:06.278Z,1760124306.278 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 99.088517 min 2025-10-10T19:25:06.278Z,1760124306.278 [Default:CheckIn:E] Stopped 2025-10-10T19:25:06.278Z,1760124306.278 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-10T19:25:06.278Z,1760124306.278 [Default:CheckIn] Stopped 2025-10-10T19:25:06.279Z,1760124306.279 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T19:25:06.295Z,1760124306.295 [Default:CheckIn](INFO): Running loop #18 2025-10-10T19:25:06.295Z,1760124306.295 [Default:CheckIn] Running Loop=18 2025-10-10T19:25:06.295Z,1760124306.295 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-10T19:25:06.295Z,1760124306.295 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-10T19:25:08.294Z,1760124308.294 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192507.00,A,3648.19415,N,12147.27173,W,0.019,0.00,101025,,,A*7B 2025-10-10T19:25:08.296Z,1760124308.296 [NAL9602](INFO): GPS fix at 20251010T192507: (36.803236, -121.787862) 2025-10-10T19:25:08.307Z,1760124308.307 [Default:CheckIn:Read_GPS] Stopped 2025-10-10T19:25:08.307Z,1760124308.307 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-10T19:25:17.509Z,1760124317.509 [DataOverHttps](INFO): Sending 192 bytes from file Logs/20251010T175600/Courier0048.lzma 2025-10-10T19:25:19.513Z,1760124319.513 [DataOverHttps](INFO): Moved sent file to Logs/20251010T175600/Courier0048.lzma.bak 2025-10-10T19:25:19.513Z,1760124319.513 [DataOverHttps](INFO): SBD MOMSN=26169745 2025-10-10T19:25:30.909Z,1760124330.909 [NAL9602](INFO): SBD MO Status=0, MOMSN=65254, MT Status=0, MTMSN=0 2025-10-10T19:25:30.910Z,1760124330.910 [NAL9602](INFO): No messages in MT queue 2025-10-10T19:25:37.937Z,1760124337.937 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20251010T175600/Express0049.lzma 2025-10-10T19:25:38.937Z,1760124338.937 [DataOverHttps](INFO): Moved sent file to Logs/20251010T175600/Express0049.lzma.bak 2025-10-10T19:25:38.937Z,1760124338.937 [DataOverHttps](INFO): SBD MOMSN=26169750 2025-10-10T19:25:40.217Z,1760124340.217 [Default:CheckIn:Read_Iridium] Stopped 2025-10-10T19:25:40.217Z,1760124340.217 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-10T19:25:40.217Z,1760124340.217 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-10T19:26:01.616Z,1760124361.616 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-10T19:30:40.871Z,1760124640.871 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-10T19:30:40.871Z,1760124640.871 [Default:CheckIn:C.Wait] Stopped 2025-10-10T19:30:40.871Z,1760124640.871 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-10T19:30:40.871Z,1760124640.871 [Default:CheckIn:D] Running Loop=1 2025-10-10T19:30:41.259Z,1760124641.259 [Default:CheckIn:D] Stopped 2025-10-10T19:30:41.260Z,1760124641.260 [Default:CheckIn:E] Running Loop=1 2025-10-10T19:30:41.667Z,1760124641.667 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 104.678166 min 2025-10-10T19:30:41.667Z,1760124641.667 [Default:CheckIn:E] Stopped 2025-10-10T19:30:41.667Z,1760124641.667 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-10T19:30:41.667Z,1760124641.667 [Default:CheckIn] Stopped 2025-10-10T19:30:41.668Z,1760124641.668 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T19:30:41.668Z,1760124641.668 [Default:CheckIn](INFO): Running loop #19 2025-10-10T19:30:41.668Z,1760124641.668 [Default:CheckIn] Running Loop=19 2025-10-10T19:30:41.668Z,1760124641.668 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-10T19:30:41.668Z,1760124641.668 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-10T19:30:43.683Z,1760124643.683 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193042.00,A,3648.18925,N,12147.27110,W,0.253,0.00,101025,,,A*78 2025-10-10T19:30:43.685Z,1760124643.685 [NAL9602](INFO): GPS fix at 20251010T193042: (36.803154, -121.787852) 2025-10-10T19:30:43.697Z,1760124643.697 [Default:CheckIn:Read_GPS] Stopped 2025-10-10T19:30:43.697Z,1760124643.697 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-10T19:30:52.851Z,1760124652.851 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20251010T175600/Courier0051.lzma 2025-10-10T19:30:53.853Z,1760124653.853 [DataOverHttps](INFO): Moved sent file to Logs/20251010T175600/Courier0051.lzma.bak 2025-10-10T19:30:53.854Z,1760124653.854 [DataOverHttps](INFO): SBD MOMSN=26169792 2025-10-10T19:30:55.393Z,1760124655.393 [NAL9602](INFO): SBD MO Status=0, MOMSN=65255, MT Status=0, MTMSN=0 2025-10-10T19:30:55.393Z,1760124655.393 [NAL9602](INFO): No messages in MT queue 2025-10-10T19:31:12.899Z,1760124672.899 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20251010T175600/Express0052.lzma 2025-10-10T19:31:13.902Z,1760124673.902 [DataOverHttps](INFO): Moved sent file to Logs/20251010T175600/Express0052.lzma.bak 2025-10-10T19:31:13.902Z,1760124673.902 [DataOverHttps](INFO): SBD MOMSN=26169795 2025-10-10T19:31:15.198Z,1760124675.198 [Default:CheckIn:Read_Iridium] Stopped 2025-10-10T19:31:15.198Z,1760124675.198 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-10T19:31:15.198Z,1760124675.198 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-10T19:31:26.092Z,1760124686.092 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-10T19:33:32.996Z,1760124812.996 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-10-10T19:36:15.871Z,1760124975.871 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-10T19:36:15.871Z,1760124975.871 [Default:CheckIn:C.Wait] Stopped 2025-10-10T19:36:15.871Z,1760124975.871 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-10T19:36:15.871Z,1760124975.871 [Default:CheckIn:D] Running Loop=1 2025-10-10T19:36:16.266Z,1760124976.266 [Default:CheckIn:D] Stopped 2025-10-10T19:36:16.266Z,1760124976.266 [Default:CheckIn:E] Running Loop=1 2025-10-10T19:36:16.666Z,1760124976.666 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 110.261613 min 2025-10-10T19:36:16.666Z,1760124976.666 [Default:CheckIn:E] Stopped 2025-10-10T19:36:16.666Z,1760124976.666 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-10T19:36:16.667Z,1760124976.667 [Default:CheckIn] Stopped 2025-10-10T19:36:16.667Z,1760124976.667 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T19:36:16.667Z,1760124976.667 [Default:CheckIn](INFO): Running loop #20 2025-10-10T19:36:16.667Z,1760124976.667 [Default:CheckIn] Running Loop=20 2025-10-10T19:36:16.667Z,1760124976.667 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-10T19:36:16.667Z,1760124976.667 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-10T19:36:18.694Z,1760124978.694 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193617.00,A,3648.18725,N,12147.27322,W,0.058,0.00,101025,,,A*7A 2025-10-10T19:36:18.696Z,1760124978.696 [NAL9602](INFO): GPS fix at 20251010T193617: (36.803121, -121.787887) 2025-10-10T19:36:18.717Z,1760124978.717 [Default:CheckIn:Read_GPS] Stopped 2025-10-10T19:36:18.717Z,1760124978.717 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-10T19:36:30.203Z,1760124990.203 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20251010T175600/Courier0054.lzma 2025-10-10T19:36:31.205Z,1760124991.205 [DataOverHttps](INFO): Moved sent file to Logs/20251010T175600/Courier0054.lzma.bak 2025-10-10T19:36:31.205Z,1760124991.205 [DataOverHttps](INFO): SBD MOMSN=26169812 2025-10-10T19:36:47.411Z,1760125007.411 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20251010T175600/Express0055.lzma 2025-10-10T19:36:48.413Z,1760125008.413 [DataOverHttps](INFO): Moved sent file to Logs/20251010T175600/Express0055.lzma.bak 2025-10-10T19:36:48.413Z,1760125008.413 [DataOverHttps](INFO): SBD MOMSN=26169815 2025-10-10T19:36:49.894Z,1760125009.894 [Default:CheckIn:Read_Iridium] Stopped 2025-10-10T19:36:49.894Z,1760125009.894 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-10T19:36:49.894Z,1760125009.894 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-10T19:37:37.973Z,1760125057.973 [CBIT](INFO): Clearing failed state for component DropWeight 2025-10-10T19:37:37.973Z,1760125057.973 [DropWeight] No Fault, FailCount= 1 2025-10-10T19:39:10.490Z,1760125150.490 [NAL9602](INFO): SBD MO Status=0, MOMSN=65256, MT Status=0, MTMSN=0 2025-10-10T19:39:10.490Z,1760125150.490 [NAL9602](INFO): No messages in MT queue 2025-10-10T19:39:41.201Z,1760125181.201 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-10T19:41:50.491Z,1760125310.491 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-10T19:41:50.491Z,1760125310.491 [Default:CheckIn:C.Wait] Stopped 2025-10-10T19:41:50.491Z,1760125310.491 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-10T19:41:50.491Z,1760125310.491 [Default:CheckIn:D] Running Loop=1 2025-10-10T19:41:50.902Z,1760125310.902 [Default:CheckIn:D] Stopped 2025-10-10T19:41:50.902Z,1760125310.902 [Default:CheckIn:E] Running Loop=1 2025-10-10T19:41:51.318Z,1760125311.318 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 115.838875 min 2025-10-10T19:41:51.318Z,1760125311.318 [Default:CheckIn:E] Stopped 2025-10-10T19:41:51.318Z,1760125311.318 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-10T19:41:51.318Z,1760125311.318 [Default:CheckIn] Stopped 2025-10-10T19:41:51.319Z,1760125311.319 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T19:41:51.319Z,1760125311.319 [Default:CheckIn](INFO): Running loop #21 2025-10-10T19:41:51.319Z,1760125311.319 [Default:CheckIn] Running Loop=21 2025-10-10T19:41:51.319Z,1760125311.319 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-10T19:41:51.319Z,1760125311.319 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-10T19:41:53.316Z,1760125313.316 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194152.00,A,3648.17306,N,12147.28103,W,0.914,311.86,101025,,,A*73 2025-10-10T19:41:53.318Z,1760125313.318 [NAL9602](INFO): GPS fix at 20251010T194152: (36.802884, -121.788017) 2025-10-10T19:41:53.345Z,1760125313.345 [Default:CheckIn:Read_GPS] Stopped 2025-10-10T19:41:53.345Z,1760125313.345 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-10T19:41:55.010Z,1760125315.010 [NAL9602](FAULT): LCB fault: Invalid Command. Hardware Overcurrent Shutdown. Current Limiter Activated. 2025-10-10T19:41:55.010Z,1760125315.010 [NAL9602] Hardware Fault, FailCount= 1 2025-10-10T19:41:55.011Z,1760125315.011 [NAL9602](ERROR): Hardware Fault 2025-10-10T19:41:55.031Z,1760125315.031 [CBIT](ERROR): Hardware Fault in component: NAL9602 2025-10-10T19:41:55.421Z,1760125315.421 [NAL9602](INFO): Powering down 2025-10-10T19:41:56.280Z,1760125316.280 [CBIT](INFO): Clearing failed state for component NAL9602 2025-10-10T19:41:56.280Z,1760125316.280 [NAL9602] No Fault, FailCount= 1 2025-10-10T19:42:00.315Z,1760125320.315 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20251010T175600/Courier0057.lzma 2025-10-10T19:42:01.317Z,1760125321.317 [DataOverHttps](INFO): Moved sent file to Logs/20251010T175600/Courier0057.lzma.bak 2025-10-10T19:42:01.317Z,1760125321.317 [DataOverHttps](INFO): SBD MOMSN=26169869 2025-10-10T19:42:17.327Z,1760125337.327 [DataOverHttps](INFO): Sending 162 bytes from file Logs/20251010T175600/Express0058.lzma 2025-10-10T19:42:18.329Z,1760125338.329 [DataOverHttps](INFO): Moved sent file to Logs/20251010T175600/Express0058.lzma.bak 2025-10-10T19:42:18.329Z,1760125338.329 [DataOverHttps](INFO): SBD MOMSN=26169872 2025-10-10T19:42:19.694Z,1760125339.694 [Default:CheckIn:Read_Iridium] Stopped 2025-10-10T19:42:19.694Z,1760125339.694 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-10T19:42:19.694Z,1760125339.694 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-10T19:42:25.776Z,1760125345.776 [NAL9602](INFO): Powering up NAL9602 2025-10-10T19:42:36.694Z,1760125356.694 [NAL9602](INFO): NAL9602 initialized 2025-10-10T19:43:07.800Z,1760125387.800 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-10T19:43:33.258Z,1760125413.258 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-10-10T19:47:20.333Z,1760125640.333 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-10T19:47:20.334Z,1760125640.334 [Default:CheckIn:C.Wait] Stopped 2025-10-10T19:47:20.334Z,1760125640.334 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-10T19:47:20.334Z,1760125640.334 [Default:CheckIn:D] Running Loop=1 2025-10-10T19:47:20.745Z,1760125640.745 [Default:CheckIn:D] Stopped 2025-10-10T19:47:20.745Z,1760125640.745 [Default:CheckIn:E] Running Loop=1 2025-10-10T19:47:21.168Z,1760125641.168 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 121.336255 min 2025-10-10T19:47:21.168Z,1760125641.168 [Default:CheckIn:E] Stopped 2025-10-10T19:47:21.168Z,1760125641.168 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-10T19:47:21.168Z,1760125641.168 [Default:CheckIn] Stopped 2025-10-10T19:47:21.168Z,1760125641.168 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T19:47:21.168Z,1760125641.168 [Default:CheckIn](INFO): Running loop #22 2025-10-10T19:47:21.168Z,1760125641.168 [Default:CheckIn] Running Loop=22 2025-10-10T19:47:21.169Z,1760125641.169 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-10T19:47:21.169Z,1760125641.169 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-10T19:48:40.728Z,1760125720.728 [NAL9602](INFO): SBD MO Status=2, MOMSN=65257, MT Status=2, MTMSN=0 2025-10-10T19:48:40.728Z,1760125720.728 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-10-10T19:52:21.400Z,1760125941.400 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-10-10T19:47:21.2Z 2025-10-10T19:52:21.400Z,1760125941.400 [Default:CheckIn:Read_GPS] Stopped 2025-10-10T19:52:21.401Z,1760125941.401 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-10T19:52:25.564Z,1760125945.564 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-10-10T19:52:33.799Z,1760125953.799 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20251010T175600/Courier0060.lzma 2025-10-10T19:52:34.801Z,1760125954.801 [DataOverHttps](INFO): Moved sent file to Logs/20251010T175600/Courier0060.lzma.bak 2025-10-10T19:52:34.801Z,1760125954.801 [DataOverHttps](INFO): SBD MOMSN=26169946 2025-10-10T19:52:53.979Z,1760125973.979 [DataOverHttps](INFO): Sending 218 bytes from file Logs/20251010T175600/Express0061.lzma 2025-10-10T19:52:55.985Z,1760125975.985 [DataOverHttps](INFO): Moved sent file to Logs/20251010T175600/Express0061.lzma.bak 2025-10-10T19:52:55.985Z,1760125975.985 [DataOverHttps](INFO): SBD MOMSN=26169948 2025-10-10T19:52:56.306Z,1760125976.306 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-10T19:52:57.120Z,1760125977.120 [Default:CheckIn:Read_Iridium] Stopped 2025-10-10T19:52:57.120Z,1760125977.120 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-10T19:52:57.120Z,1760125977.120 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-10T19:53:33.498Z,1760126013.498 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-10-10T19:57:57.694Z,1760126277.694 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-10T19:57:57.694Z,1760126277.694 [Default:CheckIn:C.Wait] Stopped 2025-10-10T19:57:57.694Z,1760126277.694 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-10T19:57:57.695Z,1760126277.695 [Default:CheckIn:D] Running Loop=1 2025-10-10T19:57:58.101Z,1760126278.101 [Default:CheckIn:D] Stopped 2025-10-10T19:57:58.101Z,1760126278.101 [Default:CheckIn:E] Running Loop=1 2025-10-10T19:57:58.514Z,1760126278.514 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 131.958854 min 2025-10-10T19:57:58.515Z,1760126278.515 [Default:CheckIn:E] Stopped 2025-10-10T19:57:58.515Z,1760126278.515 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-10T19:57:58.515Z,1760126278.515 [Default:CheckIn] Stopped 2025-10-10T19:57:58.515Z,1760126278.515 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T19:57:58.515Z,1760126278.515 [Default:CheckIn](INFO): Running loop #23 2025-10-10T19:57:58.515Z,1760126278.515 [Default:CheckIn] Running Loop=23 2025-10-10T19:57:58.515Z,1760126278.515 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-10T19:57:58.516Z,1760126278.516 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-10T19:58:00.108Z,1760126280.108 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-10-10T19:58:00.108Z,1760126280.108 [NAL9602] Data Fault, FailCount= 1 2025-10-10T19:58:00.108Z,1760126280.108 [NAL9602](ERROR): Data Fault 2025-10-10T19:58:00.129Z,1760126280.129 [CBIT](ERROR): Data Fault in component: NAL9602 2025-10-10T19:58:00.510Z,1760126280.510 [NAL9602](INFO): Powering down 2025-10-10T19:58:01.365Z,1760126281.365 [CBIT](INFO): Clearing failed state for component NAL9602 2025-10-10T19:58:01.365Z,1760126281.365 [NAL9602] No Fault, FailCount= 1 2025-10-10T19:58:30.808Z,1760126310.808 [NAL9602](INFO): Powering up NAL9602 2025-10-10T19:58:41.731Z,1760126321.731 [NAL9602](INFO): NAL9602 initialized 2025-10-10T20:00:52.940Z,1760126452.940 [CommandExec](IMPORTANT): got command quit 2025-10-10T20:00:53.943Z,1760126453.943 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2025-10-10T20:00:53.943Z,1760126453.943 [CommandExec](INFO): Uninitializing the command executive. 2025-10-10T20:00:53.944Z,1760126453.944 [CommandExec](INFO): Uninitializing the command scheduler. 2025-10-10T20:00:53.944Z,1760126453.944 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:00:54.019Z,1760126454.019 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2025-10-10T20:00:54.019Z,1760126454.019 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2025-10-10T20:00:54.020Z,1760126454.020 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:00:54.020Z,1760126454.020 [NavChartDb](INFO): Join timeout helper Thread ID is 3121 2025-10-10T20:00:54.191Z,1760126454.191 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2025-10-10T20:00:54.192Z,1760126454.192 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:00:54.195Z,1760126454.195 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2025-10-10T20:00:54.195Z,1760126454.195 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:00:54.196Z,1760126454.196 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3122 2025-10-10T20:00:54.463Z,1760126454.463 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2025-10-10T20:00:54.464Z,1760126454.464 [WetLabsBB2FL](INFO): Powering down 2025-10-10T20:00:54.464Z,1760126454.464 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:00:54.475Z,1760126454.475 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2025-10-10T20:00:54.475Z,1760126454.475 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:00:54.476Z,1760126454.476 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3123 2025-10-10T20:00:54.864Z,1760126454.864 [CTD_Seabird](INFO): Powering down 2025-10-10T20:00:54.879Z,1760126454.879 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2025-10-10T20:00:54.879Z,1760126454.879 [CTD_Seabird](INFO): Powering down 2025-10-10T20:00:54.891Z,1760126454.891 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:00:54.903Z,1760126454.903 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2025-10-10T20:00:54.903Z,1760126454.903 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:00:54.904Z,1760126454.904 [Radio_Surface](INFO): Join timeout helper Thread ID is 3124 2025-10-10T20:00:55.235Z,1760126455.235 [Radio_Surface](INFO): Powering down 2025-10-10T20:00:55.236Z,1760126455.236 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2025-10-10T20:00:55.236Z,1760126455.236 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:00:55.247Z,1760126455.247 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2025-10-10T20:00:55.247Z,1760126455.247 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:00:55.248Z,1760126455.248 [Onboard](INFO): Join timeout helper Thread ID is 3125 2025-10-10T20:00:55.776Z,1760126455.776 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-10-10T20:00:58.867Z,1760126458.867 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2025-10-10T20:00:58.867Z,1760126458.867 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:00:58.872Z,1760126458.872 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2025-10-10T20:00:58.872Z,1760126458.872 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:00:58.873Z,1760126458.873 [DataOverHttps](INFO): Join timeout helper Thread ID is 3126 2025-10-10T20:00:59.791Z,1760126459.791 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2025-10-10T20:00:59.792Z,1760126459.792 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:00:59.804Z,1760126459.804 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2025-10-10T20:00:59.804Z,1760126459.804 [DAT ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:00:59.804Z,1760126459.804 [DAT](INFO): Join timeout helper Thread ID is 3127 2025-10-10T20:00:59.903Z,1760126459.903 [DAT](INFO): Powering down 2025-10-10T20:00:59.980Z,1760126459.980 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2025-10-10T20:00:59.980Z,1760126459.980 [DAT](INFO): Powering down 2025-10-10T20:00:59.981Z,1760126459.981 [DAT ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:00:59.996Z,1760126459.996 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2025-10-10T20:00:59.996Z,1760126459.996 [logger ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:00:59.996Z,1760126459.996 [logger](INFO): Join timeout helper Thread ID is 3128 2025-10-10T20:00:59.000Z,1760126460.000 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2025-10-10T20:01:00.000Z,1760126460.000 [logger ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:01:00.002Z,1760126460.002 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2025-10-10T20:01:00.002Z,1760126460.002 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:01:00.002Z,1760126460.002 [CommandLine](INFO): Join timeout helper Thread ID is 3129 2025-10-10T20:01:00.083Z,1760126460.083 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2025-10-10T20:01:00.084Z,1760126460.084 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:01:00.100Z,1760126460.100 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2025-10-10T20:01:00.100Z,1760126460.100 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:01:00.100Z,1760126460.100 [CommandExec](INFO): Join timeout helper Thread ID is 3130 2025-10-10T20:01:00.102Z,1760126460.102 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2025-10-10T20:01:00.102Z,1760126460.102 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:01:00.102Z,1760126460.102 [controlThread](INFO): Join timeout helper Thread ID is 3131 2025-10-10T20:01:00.319Z,1760126460.319 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2025-10-10T20:01:00.320Z,1760126460.320 [controlThread](DEBUG): Uninitializing ControlThread 2025-10-10T20:01:00.320Z,1760126460.320 [AHRS_M2](INFO): Powering down 2025-10-10T20:01:00.399Z,1760126460.399 [NAL9602](INFO): Powering down 2025-10-10T20:01:00.401Z,1760126460.401 [Sonardyne_Nano](INFO): Powering down 2025-10-10T20:01:00.603Z,1760126460.603 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2025-10-10T20:01:00.604Z,1760126460.604 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2025-10-10T20:01:00.605Z,1760126460.605 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2025-10-10T20:01:00.605Z,1760126460.605 [MissionManager](INFO): Uninitializing Mission Default 2025-10-10T20:01:00.605Z,1760126460.605 [Default] Stopped 2025-10-10T20:01:00.605Z,1760126460.605 [Default](DEBUG): Aggregate::uninitialize Default 2025-10-10T20:01:00.605Z,1760126460.605 [Default:B.GoToSurface] Stopped 2025-10-10T20:01:00.605Z,1760126460.605 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-10-10T20:01:00.605Z,1760126460.605 [Default:CheckIn] Stopped 2025-10-10T20:01:00.606Z,1760126460.606 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T20:01:00.606Z,1760126460.606 [Default:CheckIn:Read_GPS] Stopped 2025-10-10T20:01:00.609Z,1760126460.609 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2025-10-10T20:01:00.609Z,1760126460.609 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2025-10-10T20:01:00.609Z,1760126460.609 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2025-10-10T20:01:00.610Z,1760126460.610 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2025-10-10T20:01:00.610Z,1760126460.610 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2025-10-10T20:01:00.610Z,1760126460.610 [BuoyancyServo](INFO): Powering down 2025-10-10T20:01:00.623Z,1760126460.623 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2025-10-10T20:01:00.623Z,1760126460.623 [ElevatorServo](INFO): Powering down 2025-10-10T20:01:00.624Z,1760126460.624 [MassServo](DEBUG): Uninitialize Mass Servo. 2025-10-10T20:01:00.624Z,1760126460.624 [MassServo](INFO): Powering down 2025-10-10T20:01:00.625Z,1760126460.625 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2025-10-10T20:01:00.625Z,1760126460.625 [RudderServo](INFO): Powering down 2025-10-10T20:01:00.626Z,1760126460.626 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-10-10T20:01:00.626Z,1760126460.626 [ThrusterHE](INFO): Powering down 2025-10-10T20:01:00.627Z,1760126460.627 [SBIT](DEBUG): Uninitialize SBIT Component. 2025-10-10T20:01:00.627Z,1760126460.627 [IBIT](DEBUG): Uninitialize IBIT Component. 2025-10-10T20:01:00.628Z,1760126460.628 [CBIT](DEBUG): Uninitialize CBIT Component. 2025-10-10T20:01:00.628Z,1760126460.628 [CBIT](DEBUG): Powering off loads. 2025-10-10T20:01:00.639Z,1760126460.639 [CBIT](DEBUG): Disabling WDT. 2025-10-10T20:01:00.651Z,1760126460.651 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-10-10T20:01:00.651Z,1760126460.651 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-10-10T20:01:00.652Z,1760126460.652 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:01:00.771Z,1760126460.771 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:01:00.775Z,1760126460.775 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:01:00.782Z,1760126460.782 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:01:00.792Z,1760126460.792 [DAT ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:01:00.866Z,1760126460.866 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:01:00.871Z,1760126460.871 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:01:00.895Z,1760126460.895 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-10-10T20:01:00.895Z,1760126460.895 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-10-10T20:01:00.916Z,1760126460.916 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:01:01.027Z,1760126461.027 [logger ThreadHandler](INFO): Thread cancelled.