2024-09-25T17:12:10.354Z,1727284330.354 [CommandExec](IMPORTANT): got command restart logs 2024-09-25T17:12:37.538Z,1727284357.538 [NAL9602](INFO): SBD MO Status=2, MOMSN=18336, MT Status=2, MTMSN=0 2024-09-25T17:12:37.538Z,1727284357.538 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-09-25T17:13:06.674Z,1727284386.674 [NAL9602](INFO): SBD MO Status=1, MOMSN=18336, MT Status=0, MTMSN=0 2024-09-25T17:13:06.721Z,1727284386.721 [NAL9602](INFO): Sent 40 bytes from file Logs/20240924T170500/Courier0088.lzma 2024-09-25T17:13:06.721Z,1727284386.721 [NAL9602](INFO): Packets left to send: 0 2024-09-25T17:13:20.954Z,1727284400.954 [NAL9602](INFO): SBD MO Status=1, MOMSN=18337, MT Status=0, MTMSN=0 2024-09-25T17:13:21.009Z,1727284401.009 [NAL9602](INFO): Sent 332 bytes from file Logs/20240924T170500/Express0086.lzma 2024-09-25T17:13:21.009Z,1727284401.009 [NAL9602](INFO): Packets left to send: 1 2024-09-25T17:13:28.790Z,1727284408.790 [NAL9602](INFO): SBD MO Status=1, MOMSN=18338, MT Status=0, MTMSN=0 2024-09-25T17:13:28.841Z,1727284408.841 [NAL9602](INFO): Sent 32 bytes from file Logs/20240924T170500/Express0086.lzma 2024-09-25T17:13:28.841Z,1727284408.841 [NAL9602](INFO): Packets left to send: 0 2024-09-25T17:13:34.955Z,1727284414.955 [NAL9602](INFO): SBD MO Status=1, MOMSN=18339, MT Status=0, MTMSN=0 2024-09-25T17:13:35.015Z,1727284415.015 [NAL9602](INFO): Sent 110 bytes from file Logs/20240924T170500/Express0089.lzma 2024-09-25T17:13:35.015Z,1727284415.015 [NAL9602](INFO): Packets left to send: 0 2024-09-25T17:13:45.461Z,1727284425.461 [NAL9602](INFO): SBD MO Status=0, MOMSN=18340, MT Status=0, MTMSN=0 2024-09-25T17:14:05.715Z,1727284445.715 [NAL9602](INFO): SBD MO Status=1, MOMSN=18341, MT Status=0, MTMSN=0 2024-09-25T17:14:05.765Z,1727284445.765 [NAL9602](INFO): Sent 152 bytes from file Logs/20240925T171210/Courier0000.lzma 2024-09-25T17:14:05.765Z,1727284445.765 [NAL9602](INFO): Packets left to send: 0 2024-09-25T17:14:22.386Z,1727284462.386 [NAL9602](INFO): SBD MO Status=1, MOMSN=18342, MT Status=0, MTMSN=0 2024-09-25T17:14:22.429Z,1727284462.429 [NAL9602](INFO): Sent 259 bytes from file Logs/20240925T171210/Express0001.lzma 2024-09-25T17:14:22.430Z,1727284462.430 [NAL9602](INFO): Packets left to send: 0 2024-09-25T17:14:31.281Z,1727284471.281 [NAL9602](INFO): SBD MO Status=0, MOMSN=18343, MT Status=0, MTMSN=0 2024-09-25T17:14:31.369Z,1727284471.369 [Default:CheckIn:Read_Iridium] Stopped 2024-09-25T17:14:31.369Z,1727284471.369 [Default:CheckIn:C.Wait] Running Loop=1 2024-09-25T17:14:31.369Z,1727284471.369 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-09-25T17:15:01.982Z,1727284501.982 [NAL9602](INFO): Powering down 2024-09-25T17:15:55.742Z,1727284555.742 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2024-09-25T17:19:31.883Z,1727284771.883 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-09-25T17:19:31.883Z,1727284771.883 [Default:CheckIn:C.Wait] Stopped 2024-09-25T17:19:31.883Z,1727284771.883 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-09-25T17:19:31.883Z,1727284771.883 [Default:CheckIn:D] Running Loop=1 2024-09-25T17:19:32.275Z,1727284772.275 [Default:CheckIn:D] Stopped 2024-09-25T17:19:32.275Z,1727284772.275 [Default:CheckIn:E] Running Loop=1 2024-09-25T17:19:32.684Z,1727284772.684 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 41.159383 min 2024-09-25T17:19:32.684Z,1727284772.684 [Default:CheckIn:E] Stopped 2024-09-25T17:19:32.684Z,1727284772.684 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-09-25T17:19:32.684Z,1727284772.684 [Default:CheckIn] Stopped 2024-09-25T17:19:32.684Z,1727284772.684 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-09-25T17:19:32.684Z,1727284772.684 [Default:CheckIn](INFO): Running loop #6 2024-09-25T17:19:32.684Z,1727284772.684 [Default:CheckIn] Running Loop=6 2024-09-25T17:19:32.684Z,1727284772.684 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-09-25T17:19:32.685Z,1727284772.685 [Default:CheckIn:Read_GPS] Running Loop=1 2024-09-25T17:19:33.480Z,1727284773.480 [NAL9602](INFO): Powering up 2024-09-25T17:19:44.390Z,1727284784.390 [NAL9602](INFO): NAL9602 initialized 2024-09-25T17:20:17.524Z,1727284817.524 [NAL9602](INFO): SBD MO Status=1, MOMSN=18345, MT Status=0, MTMSN=0 2024-09-25T17:20:17.524Z,1727284817.524 [NAL9602](INFO): No messages in MT queue 2024-09-25T17:20:18.738Z,1727284818.738 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172014.00,A,3648.29242,N,12150.09422,W,0.428,60.43,250924,,,A*46 2024-09-25T17:20:18.740Z,1727284818.740 [NAL9602](INFO): GPS fix at 20240925T172014: (36.804874, -121.834904) 2024-09-25T17:20:18.750Z,1727284818.750 [Default:CheckIn:Read_GPS] Stopped 2024-09-25T17:20:18.750Z,1727284818.750 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-09-25T17:20:42.209Z,1727284842.209 [NAL9602](INFO): SBD MO Status=2, MOMSN=18346, MT Status=2, MTMSN=0 2024-09-25T17:20:42.209Z,1727284842.209 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-09-25T17:21:08.126Z,1727284868.126 [NAL9602](INFO): SBD MO Status=2, MOMSN=18346, MT Status=2, MTMSN=0 2024-09-25T17:21:08.126Z,1727284868.126 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-09-25T17:21:38.122Z,1727284898.122 [NAL9602](INFO): SBD MO Status=2, MOMSN=18346, MT Status=2, MTMSN=0 2024-09-25T17:21:38.122Z,1727284898.122 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-09-25T17:22:01.946Z,1727284921.946 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.039022 2024-09-25T17:22:07.287Z,1727284927.287 [NAL9602](INFO): SBD MO Status=2, MOMSN=18346, MT Status=2, MTMSN=0 2024-09-25T17:22:07.287Z,1727284927.287 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-09-25T17:22:11.250Z,1727284931.250 [DataOverHttps](INFO): Sending 227 bytes from file Logs/20240925T171210/Courier0003.lzma 2024-09-25T17:22:13.255Z,1727284933.255 [DataOverHttps](INFO): Moved sent file to Logs/20240925T171210/Courier0003.lzma.bak 2024-09-25T17:22:13.255Z,1727284933.255 [DataOverHttps](INFO): SBD MOMSN=23227380 2024-09-25T17:22:32.345Z,1727284952.345 [DataOverHttps](INFO): Sending 172 bytes from file Logs/20240925T171210/Express0004.lzma 2024-09-25T17:22:33.352Z,1727284953.352 [DataOverHttps](INFO): Moved sent file to Logs/20240925T171210/Express0004.lzma.bak 2024-09-25T17:22:33.352Z,1727284953.352 [DataOverHttps](INFO): SBD MOMSN=23227384 2024-09-25T17:22:35.148Z,1727284955.148 [Default:CheckIn:Read_Iridium] Stopped 2024-09-25T17:22:35.148Z,1727284955.148 [Default:CheckIn:C.Wait] Running Loop=1 2024-09-25T17:22:35.148Z,1727284955.148 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-09-25T17:22:37.962Z,1727284957.962 [NAL9602](INFO): Powering down 2024-09-25T17:23:35.377Z,1727285015.377 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-25T17:24:06.501Z,1727285046.501 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-25T17:24:37.625Z,1727285077.625 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-25T17:25:08.750Z,1727285108.750 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-25T17:25:39.873Z,1727285139.873 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-25T17:25:48.910Z,1727285148.910 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.714966 2024-09-25T17:25:55.923Z,1727285155.923 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2024-09-25T17:26:41.125Z,1727285201.125 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-25T17:27:12.249Z,1727285232.249 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-25T17:27:35.726Z,1727285255.726 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-09-25T17:27:35.726Z,1727285255.726 [Default:CheckIn:C.Wait] Stopped 2024-09-25T17:27:35.726Z,1727285255.726 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-09-25T17:27:35.727Z,1727285255.727 [Default:CheckIn:D] Running Loop=1 2024-09-25T17:27:36.129Z,1727285256.129 [Default:CheckIn:D] Stopped 2024-09-25T17:27:36.129Z,1727285256.129 [Default:CheckIn:E] Running Loop=1 2024-09-25T17:27:36.551Z,1727285256.551 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 49.223612 min 2024-09-25T17:27:36.551Z,1727285256.551 [Default:CheckIn:E] Stopped 2024-09-25T17:27:36.551Z,1727285256.551 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-09-25T17:27:36.551Z,1727285256.551 [Default:CheckIn] Stopped 2024-09-25T17:27:36.551Z,1727285256.551 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-09-25T17:27:36.551Z,1727285256.551 [Default:CheckIn](INFO): Running loop #7 2024-09-25T17:27:36.551Z,1727285256.551 [Default:CheckIn] Running Loop=7 2024-09-25T17:27:36.552Z,1727285256.552 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-09-25T17:27:36.552Z,1727285256.552 [Default:CheckIn:Read_GPS] Running Loop=1 2024-09-25T17:27:37.326Z,1727285257.326 [NAL9602](INFO): Powering up 2024-09-25T17:27:43.373Z,1727285263.373 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-25T17:27:48.234Z,1727285268.234 [NAL9602](INFO): NAL9602 initialized 2024-09-25T17:28:03.586Z,1727285283.586 [NAL9602](INFO): SBD MO Status=1, MOMSN=18347, MT Status=0, MTMSN=0 2024-09-25T17:28:03.586Z,1727285283.586 [NAL9602](INFO): No messages in MT queue 2024-09-25T17:28:04.804Z,1727285284.804 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172800.00,A,3648.36926,N,12149.45350,W,17.825,85.68,250924,,,A*7B 2024-09-25T17:28:04.806Z,1727285284.806 [NAL9602](INFO): GPS fix at 20240925T172800: (36.806154, -121.824225) 2024-09-25T17:28:04.816Z,1727285284.816 [Default:CheckIn:Read_GPS] Stopped 2024-09-25T17:28:04.817Z,1727285284.817 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-09-25T17:28:14.497Z,1727285294.497 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-25T17:28:21.023Z,1727285301.023 [NAL9602](INFO): SBD MO Status=1, MOMSN=18348, MT Status=0, MTMSN=0 2024-09-25T17:28:21.073Z,1727285301.073 [NAL9602](INFO): Sent 75 bytes from file Logs/20240925T171210/Courier0006.lzma 2024-09-25T17:28:21.074Z,1727285301.074 [NAL9602](INFO): Packets left to send: 0 2024-09-25T17:28:30.054Z,1727285310.054 [NAL9602](INFO): SBD MO Status=1, MOMSN=18349, MT Status=0, MTMSN=0 2024-09-25T17:28:30.101Z,1727285310.101 [NAL9602](INFO): Sent 138 bytes from file Logs/20240925T171210/Express0007.lzma 2024-09-25T17:28:30.101Z,1727285310.101 [NAL9602](INFO): Packets left to send: 0 2024-09-25T17:28:36.118Z,1727285316.118 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=18350, MT Status=1, MTMSN=4219 2024-09-25T17:28:36.601Z,1727285316.601 [NAL9602](INFO): Received command: strobe off 2024-09-25T17:28:36.666Z,1727285316.666 [CommandExec](IMPORTANT): got command strobe off 2024-09-25T17:28:36.666Z,1727285316.666 [CommandExec](IMPORTANT): Deactivating strobe 2024-09-25T17:28:45.623Z,1727285325.623 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-25T17:28:47.432Z,1727285327.432 [NAL9602](INFO): SBD MO Status=0, MOMSN=18351, MT Status=0, MTMSN=0 2024-09-25T17:29:16.745Z,1727285356.745 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-25T17:29:19.022Z,1727285359.022 [NAL9602](INFO): SBD MO Status=2, MOMSN=18352, MT Status=2, MTMSN=0 2024-09-25T17:29:19.022Z,1727285359.022 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-09-25T17:29:44.522Z,1727285384.522 [NAL9602](INFO): SBD MO Status=2, MOMSN=18352, MT Status=2, MTMSN=0 2024-09-25T17:29:44.522Z,1727285384.522 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-09-25T17:29:47.869Z,1727285387.869 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-25T17:30:10.428Z,1727285410.428 [NAL9602](INFO): SBD MO Status=2, MOMSN=18352, MT Status=2, MTMSN=0 2024-09-25T17:30:10.428Z,1727285410.428 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-09-25T17:30:18.993Z,1727285418.993 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-25T17:30:37.955Z,1727285437.955 [NAL9602](INFO): SBD MO Status=2, MOMSN=18352, MT Status=2, MTMSN=0 2024-09-25T17:30:37.955Z,1727285437.955 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-09-25T17:30:50.117Z,1727285450.117 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-25T17:30:53.751Z,1727285453.751 [NAL9602](INFO): SBD MO Status=2, MOMSN=18352, MT Status=2, MTMSN=0 2024-09-25T17:30:53.751Z,1727285453.751 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-09-25T17:31:21.257Z,1727285481.257 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-25T17:31:27.740Z,1727285487.740 [NAL9602](INFO): SBD MO Status=1, MOMSN=18352, MT Status=0, MTMSN=0 2024-09-25T17:31:27.794Z,1727285487.794 [NAL9602](INFO): Sent 40 bytes from file Logs/20240925T171210/Courier0009.lzma 2024-09-25T17:31:27.794Z,1727285487.794 [NAL9602](INFO): Packets left to send: 0 2024-09-25T17:31:36.766Z,1727285496.766 [NAL9602](INFO): SBD MO Status=1, MOMSN=18353, MT Status=0, MTMSN=0 2024-09-25T17:31:36.817Z,1727285496.817 [NAL9602](INFO): Sent 182 bytes from file Logs/20240925T171210/Express0010.lzma 2024-09-25T17:31:36.817Z,1727285496.817 [NAL9602](INFO): Packets left to send: 0 2024-09-25T17:31:45.656Z,1727285505.656 [NAL9602](INFO): SBD MO Status=0, MOMSN=18354, MT Status=0, MTMSN=0 2024-09-25T17:31:45.740Z,1727285505.740 [Default:CheckIn:Read_Iridium] Stopped 2024-09-25T17:31:45.741Z,1727285505.741 [Default:CheckIn:C.Wait] Running Loop=1 2024-09-25T17:31:45.741Z,1727285505.741 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-09-25T17:31:49.693Z,1727285509.693 [DVL_micro](ERROR): only read 3 of 4 data items for bottom velocity. Device response is::BI,-05884,-01039,-01814,+ 2024-09-25T17:31:49.693Z,1727285509.693 [DVL_micro](ERROR): Failed to parse: :BI,-05884,-01039,-01814,+ 2024-09-25T17:31:52.397Z,1727285512.397 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-25T17:32:16.353Z,1727285536.353 [NAL9602](INFO): Powering down 2024-09-25T17:32:23.538Z,1727285543.538 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-25T17:32:52.982Z,1727285572.982 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003426 2024-09-25T17:35:56.203Z,1727285756.203 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2024-09-25T17:36:46.313Z,1727285806.313 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-09-25T17:36:46.313Z,1727285806.313 [Default:CheckIn:C.Wait] Stopped 2024-09-25T17:36:46.313Z,1727285806.313 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-09-25T17:36:46.313Z,1727285806.313 [Default:CheckIn:D] Running Loop=1 2024-09-25T17:36:46.703Z,1727285806.703 [Default:CheckIn:D] Stopped 2024-09-25T17:36:46.703Z,1727285806.703 [Default:CheckIn:E] Running Loop=1 2024-09-25T17:36:47.112Z,1727285807.112 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 58.399845 min 2024-09-25T17:36:47.112Z,1727285807.112 [Default:CheckIn:E] Stopped 2024-09-25T17:36:47.112Z,1727285807.112 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-09-25T17:36:47.112Z,1727285807.112 [Default:CheckIn] Stopped 2024-09-25T17:36:47.112Z,1727285807.112 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-09-25T17:36:47.113Z,1727285807.113 [Default:CheckIn](INFO): Running loop #8 2024-09-25T17:36:47.113Z,1727285807.113 [Default:CheckIn] Running Loop=8 2024-09-25T17:36:47.113Z,1727285807.113 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-09-25T17:36:47.113Z,1727285807.113 [Default:CheckIn:Read_GPS] Running Loop=1 2024-09-25T17:36:47.902Z,1727285807.902 [NAL9602](INFO): Powering up 2024-09-25T17:36:58.815Z,1727285818.815 [NAL9602](INFO): NAL9602 initialized 2024-09-25T17:39:14.589Z,1727285954.589 [NAL9602](INFO): SBD MO Status=2, MOMSN=18355, MT Status=2, MTMSN=0 2024-09-25T17:39:14.589Z,1727285954.589 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-09-25T17:40:05.098Z,1727286005.098 [NAL9602](INFO): SBD MO Status=0, MOMSN=18355, MT Status=0, MTMSN=0 2024-09-25T17:40:05.099Z,1727286005.099 [NAL9602](INFO): No messages in MT queue 2024-09-25T17:40:06.336Z,1727286006.336 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174005.00,A,3648.16478,N,12147.20909,W,2.294,216.81,250924,,,A*74 2024-09-25T17:40:06.338Z,1727286006.338 [NAL9602](INFO): GPS fix at 20240925T174005: (36.802746, -121.786818) 2024-09-25T17:40:06.403Z,1727286006.403 [Default:CheckIn:Read_GPS] Stopped 2024-09-25T17:40:06.403Z,1727286006.403 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-09-25T17:40:10.216Z,1727286010.216 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20240925T171210/Courier0012.lzma 2024-09-25T17:40:11.219Z,1727286011.219 [DataOverHttps](INFO): Moved sent file to Logs/20240925T171210/Courier0012.lzma.bak 2024-09-25T17:40:11.219Z,1727286011.219 [DataOverHttps](INFO): SBD MOMSN=23227448 2024-09-25T17:40:28.201Z,1727286028.201 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20240925T171210/Express0013.lzma 2024-09-25T17:40:33.220Z,1727286033.220 [DataOverHttps](INFO): Moved sent file to Logs/20240925T171210/Express0013.lzma.bak 2024-09-25T17:40:33.220Z,1727286033.220 [DataOverHttps](INFO): SBD MOMSN=23227452 2024-09-25T17:40:34.833Z,1727286034.833 [Default:CheckIn:Read_Iridium] Stopped 2024-09-25T17:40:34.834Z,1727286034.834 [Default:CheckIn:C.Wait] Running Loop=1 2024-09-25T17:40:34.834Z,1727286034.834 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-09-25T17:40:38.836Z,1727286038.836 [NAL9602](INFO): Powering down 2024-09-25T17:41:05.661Z,1727286065.661 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2024-09-25T17:42:07.909Z,1727286127.909 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-25T17:45:35.446Z,1727286335.446 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-09-25T17:45:35.446Z,1727286335.446 [Default:CheckIn:C.Wait] Stopped 2024-09-25T17:45:35.446Z,1727286335.446 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-09-25T17:45:35.446Z,1727286335.446 [Default:CheckIn:D] Running Loop=1 2024-09-25T17:45:35.811Z,1727286335.811 [Default:CheckIn:D] Stopped 2024-09-25T17:45:35.811Z,1727286335.811 [Default:CheckIn:E] Running Loop=1 2024-09-25T17:45:36.217Z,1727286336.217 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 67.218323 min 2024-09-25T17:45:36.217Z,1727286336.217 [Default:CheckIn:E] Stopped 2024-09-25T17:45:36.217Z,1727286336.217 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-09-25T17:45:36.217Z,1727286336.217 [Default:CheckIn] Stopped 2024-09-25T17:45:36.218Z,1727286336.218 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-09-25T17:45:36.218Z,1727286336.218 [Default:CheckIn](INFO): Running loop #9 2024-09-25T17:45:36.218Z,1727286336.218 [Default:CheckIn] Running Loop=9 2024-09-25T17:45:36.218Z,1727286336.218 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-09-25T17:45:36.218Z,1727286336.218 [Default:CheckIn:Read_GPS] Running Loop=1 2024-09-25T17:45:36.999Z,1727286336.999 [NAL9602](INFO): Powering up 2024-09-25T17:45:47.902Z,1727286347.902 [NAL9602](INFO): NAL9602 initialized 2024-09-25T17:45:56.387Z,1727286356.387 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2024-09-25T17:46:20.649Z,1727286380.649 [NAL9602](INFO): SBD MO Status=2, MOMSN=18356, MT Status=2, MTMSN=0 2024-09-25T17:46:20.649Z,1727286380.649 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-09-25T17:48:22.049Z,1727286502.049 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2024-09-25T17:48:28.308Z,1727286508.308 [NAL9602](INFO): SBD MO Status=2, MOMSN=18356, MT Status=2, MTMSN=0 2024-09-25T17:48:28.308Z,1727286508.308 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-09-25T17:48:54.160Z,1727286534.160 [NAL9602](INFO): SBD MO Status=2, MOMSN=18356, MT Status=2, MTMSN=0 2024-09-25T17:48:54.160Z,1727286534.160 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-09-25T17:49:31.330Z,1727286571.330 [NAL9602](INFO): SBD MO Status=2, MOMSN=18356, MT Status=2, MTMSN=0 2024-09-25T17:49:31.330Z,1727286571.330 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-09-25T17:50:28.702Z,1727286628.702 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99,+9999.99,+9999.99,+9999.99 2024-09-25T17:50:36.402Z,1727286636.402 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-09-25T17:45:36.2Z 2024-09-25T17:50:36.403Z,1727286636.403 [Default:CheckIn:Read_GPS] Stopped 2024-09-25T17:50:36.403Z,1727286636.403 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-09-25T17:50:50.912Z,1727286650.912 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-09-25T17:50:56.598Z,1727286656.598 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-25T17:51:21.617Z,1727286681.617 [NAL9602](INFO): Powering down 2024-09-25T17:51:27.725Z,1727286687.725 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-25T17:52:06.891Z,1727286726.891 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20240925T171210/Courier0015.lzma 2024-09-25T17:52:07.891Z,1727286727.891 [DataOverHttps](INFO): Moved sent file to Logs/20240925T171210/Courier0015.lzma.bak 2024-09-25T17:52:07.891Z,1727286727.891 [DataOverHttps](INFO): SBD MOMSN=23227478 2024-09-25T17:52:23.946Z,1727286743.946 [DataOverHttps](INFO): Sending 123 bytes from file Logs/20240925T171210/Express0016.lzma 2024-09-25T17:52:24.948Z,1727286744.948 [DataOverHttps](INFO): Moved sent file to Logs/20240925T171210/Express0016.lzma.bak 2024-09-25T17:52:24.948Z,1727286744.948 [DataOverHttps](INFO): SBD MOMSN=23227480 2024-09-25T17:52:26.688Z,1727286746.688 [Default:CheckIn:Read_Iridium] Stopped 2024-09-25T17:52:26.688Z,1727286746.688 [Default:CheckIn:C.Wait] Running Loop=1 2024-09-25T17:52:26.688Z,1727286746.688 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-09-25T17:55:56.386Z,1727286956.386 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2024-09-25T17:57:27.367Z,1727287047.367 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-09-25T17:57:27.367Z,1727287047.367 [Default:CheckIn:C.Wait] Stopped 2024-09-25T17:57:27.367Z,1727287047.367 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-09-25T17:57:27.368Z,1727287047.368 [Default:CheckIn:D] Running Loop=1 2024-09-25T17:57:27.772Z,1727287047.772 [Default:CheckIn:D] Stopped 2024-09-25T17:57:27.772Z,1727287047.772 [Default:CheckIn:E] Running Loop=1 2024-09-25T17:57:28.172Z,1727287048.172 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 79.084326 min 2024-09-25T17:57:28.172Z,1727287048.172 [Default:CheckIn:E] Stopped 2024-09-25T17:57:28.172Z,1727287048.172 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-09-25T17:57:28.173Z,1727287048.173 [Default:CheckIn] Stopped 2024-09-25T17:57:28.173Z,1727287048.173 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-09-25T17:57:28.173Z,1727287048.173 [Default:CheckIn](INFO): Running loop #10 2024-09-25T17:57:28.173Z,1727287048.173 [Default:CheckIn] Running Loop=10 2024-09-25T17:57:28.173Z,1727287048.173 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-09-25T17:57:28.173Z,1727287048.173 [Default:CheckIn:Read_GPS] Running Loop=1 2024-09-25T17:57:28.958Z,1727287048.958 [NAL9602](INFO): Powering up 2024-09-25T17:57:39.875Z,1727287059.875 [NAL9602](INFO): NAL9602 initialized 2024-09-25T17:57:40.681Z,1727287060.681 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-09-25T17:57:40.681Z,1727287060.681 [NAL9602] Data Fault, FailCount= 1 2024-09-25T17:57:40.682Z,1727287060.682 [NAL9602](ERROR): Data Fault 2024-09-25T17:57:40.726Z,1727287060.726 [CBIT](ERROR): Data Fault in component: NAL9602 2024-09-25T17:57:41.099Z,1727287061.099 [NAL9602](INFO): Powering down 2024-09-25T17:57:41.909Z,1727287061.909 [CBIT](INFO): Clearing failed state for component NAL9602 2024-09-25T17:57:41.909Z,1727287061.909 [NAL9602] No Fault, FailCount= 1 2024-09-25T17:58:11.380Z,1727287091.380 [NAL9602](INFO): Powering up NAL9602 2024-09-25T17:58:22.319Z,1727287102.319 [NAL9602](INFO): NAL9602 initialized 2024-09-25T17:58:58.652Z,1727287138.652 [NAL9602](INFO): SBD MO Status=0, MOMSN=18356, MT Status=0, MTMSN=0 2024-09-25T17:58:58.653Z,1727287138.653 [NAL9602](INFO): No messages in MT queue 2024-09-25T17:59:32.181Z,1727287172.181 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175931.00,A,3648.14726,N,12147.25548,W,0.136,2.20,250924,,,A*78 2024-09-25T17:59:32.183Z,1727287172.183 [NAL9602](INFO): GPS fix at 20240925T175931: (36.802454, -121.787591) 2024-09-25T17:59:32.198Z,1727287172.198 [Default:CheckIn:Read_GPS] Stopped 2024-09-25T17:59:32.199Z,1727287172.199 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-09-25T17:59:41.241Z,1727287181.241 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20240925T171210/Courier0018.lzma 2024-09-25T17:59:42.524Z,1727287182.524 [DataOverHttps](INFO): Moved sent file to Logs/20240925T171210/Courier0018.lzma.bak 2024-09-25T17:59:42.524Z,1727287182.524 [DataOverHttps](INFO): SBD MOMSN=23227520 2024-09-25T18:00:04.922Z,1727287204.922 [NAL9602](INFO): Powering down 2024-09-25T18:00:47.354Z,1727287247.354 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6. 2024-09-25T18:00:47.357Z,1727287247.357 [BPC1](INFO): Received data from all battery sticks. 2024-09-25T18:01:33.093Z,1727287293.093 [DataOverHttps](INFO): Sending 234 bytes from file Logs/20240925T171210/Express0019.lzma 2024-09-25T18:01:34.095Z,1727287294.095 [DataOverHttps](INFO): Moved sent file to Logs/20240925T171210/Express0019.lzma.bak 2024-09-25T18:01:34.095Z,1727287294.095 [DataOverHttps](INFO): SBD MOMSN=23227540 2024-09-25T18:01:35.859Z,1727287295.859 [Default:CheckIn:Read_Iridium] Stopped 2024-09-25T18:01:35.859Z,1727287295.859 [Default:CheckIn:C.Wait] Running Loop=1 2024-09-25T18:01:35.860Z,1727287295.860 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-09-25T18:05:56.404Z,1727287556.404 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2024-09-25T18:06:36.411Z,1727287596.411 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-09-25T18:06:36.411Z,1727287596.411 [Default:CheckIn:C.Wait] Stopped 2024-09-25T18:06:36.411Z,1727287596.411 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-09-25T18:06:36.412Z,1727287596.412 [Default:CheckIn:D] Running Loop=1 2024-09-25T18:06:36.827Z,1727287596.827 [Default:CheckIn:D] Stopped 2024-09-25T18:06:36.827Z,1727287596.827 [Default:CheckIn:E] Running Loop=1 2024-09-25T18:06:37.218Z,1727287597.218 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 88.235246 min 2024-09-25T18:06:37.218Z,1727287597.218 [Default:CheckIn:E] Stopped 2024-09-25T18:06:37.218Z,1727287597.218 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-09-25T18:06:37.218Z,1727287597.218 [Default:CheckIn] Stopped 2024-09-25T18:06:37.218Z,1727287597.218 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-09-25T18:06:37.218Z,1727287597.218 [Default:CheckIn](INFO): Running loop #11 2024-09-25T18:06:37.219Z,1727287597.219 [Default:CheckIn] Running Loop=11 2024-09-25T18:06:37.219Z,1727287597.219 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-09-25T18:06:37.219Z,1727287597.219 [Default:CheckIn:Read_GPS] Running Loop=1 2024-09-25T18:06:38.031Z,1727287598.031 [NAL9602](INFO): Powering up 2024-09-25T18:06:48.929Z,1727287608.929 [NAL9602](INFO): NAL9602 initialized 2024-09-25T18:07:11.152Z,1727287631.152 [NAL9602](INFO): SBD MO Status=2, MOMSN=18357, MT Status=2, MTMSN=0 2024-09-25T18:07:11.152Z,1727287631.152 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-09-25T18:07:52.787Z,1727287672.787 [NAL9602](INFO): SBD MO Status=2, MOMSN=18357, MT Status=2, MTMSN=0 2024-09-25T18:07:52.787Z,1727287672.787 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-09-25T18:08:06.115Z,1727287686.115 [NAL9602](INFO): SBD MO Status=2, MOMSN=18357, MT Status=2, MTMSN=0 2024-09-25T18:08:06.115Z,1727287686.115 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-09-25T18:09:00.355Z,1727287740.355 [NAL9602](INFO): SBD MO Status=2, MOMSN=18357, MT Status=2, MTMSN=0 2024-09-25T18:09:00.355Z,1727287740.355 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-09-25T18:10:01.659Z,1727287801.659 [NAL9602](INFO): SBD MO Status=0, MOMSN=18357, MT Status=0, MTMSN=0 2024-09-25T18:10:01.659Z,1727287801.659 [NAL9602](INFO): No messages in MT queue 2024-09-25T18:11:37.487Z,1727287897.487 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-09-25T18:06:37.2Z 2024-09-25T18:11:37.487Z,1727287897.487 [Default:CheckIn:Read_GPS] Stopped 2024-09-25T18:11:37.487Z,1727287897.487 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-09-25T18:11:45.169Z,1727287905.169 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20240925T171210/Courier0021.lzma 2024-09-25T18:11:46.171Z,1727287906.171 [DataOverHttps](INFO): Moved sent file to Logs/20240925T171210/Courier0021.lzma.bak 2024-09-25T18:11:46.171Z,1727287906.171 [DataOverHttps](INFO): SBD MOMSN=23227594 2024-09-25T18:12:02.153Z,1727287922.153 [DataOverHttps](INFO): Sending 123 bytes from file Logs/20240925T171210/Express0022.lzma 2024-09-25T18:12:03.155Z,1727287923.155 [DataOverHttps](INFO): Moved sent file to Logs/20240925T171210/Express0022.lzma.bak 2024-09-25T18:12:03.155Z,1727287923.155 [DataOverHttps](INFO): SBD MOMSN=23227599 2024-09-25T18:12:04.958Z,1727287924.958 [Default:CheckIn:Read_Iridium] Stopped 2024-09-25T18:12:04.958Z,1727287924.958 [Default:CheckIn:C.Wait] Running Loop=1 2024-09-25T18:12:04.958Z,1727287924.958 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-09-25T18:12:10.600Z,1727287930.600 [NAL9602](INFO): Powering down 2024-09-25T18:12:17.879Z,1727287937.879 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00000000000000,35.0,+148,+00000,I 2024-09-25T18:12:17.879Z,1727287937.879 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+148,+00000,I 2024-09-25T18:15:56.501Z,1727288156.501 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2024-09-25T18:17:05.565Z,1727288225.565 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-09-25T18:17:05.565Z,1727288225.565 [Default:CheckIn:C.Wait] Stopped 2024-09-25T18:17:05.565Z,1727288225.565 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-09-25T18:17:05.566Z,1727288225.566 [Default:CheckIn:D] Running Loop=1 2024-09-25T18:17:05.972Z,1727288225.972 [Default:CheckIn:D] Stopped 2024-09-25T18:17:05.972Z,1727288225.972 [Default:CheckIn:E] Running Loop=1 2024-09-25T18:17:06.384Z,1727288226.384 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 98.721004 min 2024-09-25T18:17:06.384Z,1727288226.384 [Default:CheckIn:E] Stopped 2024-09-25T18:17:06.384Z,1727288226.384 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-09-25T18:17:06.384Z,1727288226.384 [Default:CheckIn] Stopped 2024-09-25T18:17:06.385Z,1727288226.385 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-09-25T18:17:06.385Z,1727288226.385 [Default:CheckIn](INFO): Running loop #12 2024-09-25T18:17:06.385Z,1727288226.385 [Default:CheckIn] Running Loop=12 2024-09-25T18:17:06.385Z,1727288226.385 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-09-25T18:17:06.385Z,1727288226.385 [Default:CheckIn:Read_GPS] Running Loop=1 2024-09-25T18:17:07.174Z,1727288227.174 [NAL9602](INFO): Powering up 2024-09-25T18:17:18.129Z,1727288238.129 [NAL9602](INFO): NAL9602 initialized 2024-09-25T18:17:18.891Z,1727288238.891 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-09-25T18:17:18.891Z,1727288238.891 [NAL9602] Data Fault, FailCount= 1 2024-09-25T18:17:18.891Z,1727288238.891 [NAL9602](ERROR): Data Fault 2024-09-25T18:17:18.910Z,1727288238.910 [CBIT](ERROR): Data Fault in component: NAL9602 2024-09-25T18:17:19.299Z,1727288239.299 [NAL9602](INFO): Powering down 2024-09-25T18:17:20.118Z,1727288240.118 [CBIT](INFO): Clearing failed state for component NAL9602 2024-09-25T18:17:20.118Z,1727288240.118 [NAL9602] No Fault, FailCount= 1 2024-09-25T18:17:49.604Z,1727288269.604 [NAL9602](INFO): Powering up NAL9602 2024-09-25T18:18:00.502Z,1727288280.502 [NAL9602](INFO): NAL9602 initialized 2024-09-25T18:18:17.876Z,1727288297.876 [NAL9602](INFO): SBD MO Status=2, MOMSN=18358, MT Status=2, MTMSN=0 2024-09-25T18:18:17.876Z,1727288297.876 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-09-25T18:19:01.508Z,1727288341.508 [NAL9602](INFO): SBD MO Status=2, MOMSN=18358, MT Status=2, MTMSN=0 2024-09-25T18:19:01.508Z,1727288341.508 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-09-25T18:20:10.183Z,1727288410.183 [NAL9602](INFO): SBD MO Status=0, MOMSN=18358, MT Status=0, MTMSN=0 2024-09-25T18:20:10.183Z,1727288410.183 [NAL9602](INFO): No messages in MT queue 2024-09-25T18:20:11.399Z,1727288411.399 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182007.00,A,3648.17917,N,12147.28031,W,0.019,252.41,250924,,,D*7C 2024-09-25T18:20:11.401Z,1727288411.401 [NAL9602](INFO): GPS fix at 20240925T182007: (36.802986, -121.788005) 2024-09-25T18:20:11.411Z,1727288411.411 [Default:CheckIn:Read_GPS] Stopped 2024-09-25T18:20:11.411Z,1727288411.411 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-09-25T18:20:19.113Z,1727288419.113 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20240925T171210/Courier0024.lzma 2024-09-25T18:20:20.115Z,1727288420.115 [DataOverHttps](INFO): Moved sent file to Logs/20240925T171210/Courier0024.lzma.bak 2024-09-25T18:20:20.115Z,1727288420.115 [DataOverHttps](INFO): SBD MOMSN=23227717 2024-09-25T18:20:37.975Z,1727288437.975 [DataOverHttps](INFO): Sending 181 bytes from file Logs/20240925T171210/Express0025.lzma 2024-09-25T18:20:38.975Z,1727288438.975 [DataOverHttps](INFO): Moved sent file to Logs/20240925T171210/Express0025.lzma.bak 2024-09-25T18:20:38.975Z,1727288438.975 [DataOverHttps](INFO): SBD MOMSN=23227721 2024-09-25T18:20:40.539Z,1727288440.539 [Default:CheckIn:Read_Iridium] Stopped 2024-09-25T18:20:40.539Z,1727288440.539 [Default:CheckIn:C.Wait] Running Loop=1 2024-09-25T18:20:40.539Z,1727288440.539 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-09-25T18:20:43.741Z,1727288443.741 [NAL9602](INFO): Powering down 2024-09-25T18:22:15.453Z,1727288535.453 [DVL_micro](ERROR): Failed to parse: :BI,-00032,+00525,-01093,+00000,I 2024-09-25T18:25:41.101Z,1727288741.101 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-09-25T18:25:41.101Z,1727288741.101 [Default:CheckIn:C.Wait] Stopped 2024-09-25T18:25:41.101Z,1727288741.101 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-09-25T18:25:41.102Z,1727288741.102 [Default:CheckIn:D] Running Loop=1 2024-09-25T18:25:41.516Z,1727288741.516 [Default:CheckIn:D] Stopped 2024-09-25T18:25:41.516Z,1727288741.516 [Default:CheckIn:E] Running Loop=1 2024-09-25T18:25:41.906Z,1727288741.906 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 107.313403 min 2024-09-25T18:25:41.906Z,1727288741.906 [Default:CheckIn:E] Stopped 2024-09-25T18:25:41.906Z,1727288741.906 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-09-25T18:25:41.906Z,1727288741.906 [Default:CheckIn] Stopped 2024-09-25T18:25:41.906Z,1727288741.906 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-09-25T18:25:41.906Z,1727288741.906 [Default:CheckIn](INFO): Running loop #13 2024-09-25T18:25:41.906Z,1727288741.906 [Default:CheckIn] Running Loop=13 2024-09-25T18:25:41.906Z,1727288741.906 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-09-25T18:25:41.906Z,1727288741.906 [Default:CheckIn:Read_GPS] Running Loop=1 2024-09-25T18:25:42.707Z,1727288742.707 [NAL9602](INFO): Powering up 2024-09-25T18:25:53.613Z,1727288753.613 [NAL9602](INFO): NAL9602 initialized 2024-09-25T18:25:56.859Z,1727288756.859 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2024-09-25T18:30:42.078Z,1727289042.078 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-09-25T18:25:41.9Z 2024-09-25T18:30:42.078Z,1727289042.078 [Default:CheckIn:Read_GPS] Stopped 2024-09-25T18:30:42.078Z,1727289042.078 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-09-25T18:30:49.084Z,1727289049.084 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20240925T171210/Courier0027.lzma 2024-09-25T18:30:50.087Z,1727289050.087 [DataOverHttps](INFO): Moved sent file to Logs/20240925T171210/Courier0027.lzma.bak 2024-09-25T18:30:50.087Z,1727289050.087 [DataOverHttps](INFO): SBD MOMSN=23227736 2024-09-25T18:30:56.623Z,1727289056.623 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-09-25T18:31:05.985Z,1727289065.985 [DataOverHttps](INFO): Sending 123 bytes from file Logs/20240925T171210/Express0028.lzma 2024-09-25T18:31:06.988Z,1727289066.988 [DataOverHttps](INFO): Moved sent file to Logs/20240925T171210/Express0028.lzma.bak 2024-09-25T18:31:06.988Z,1727289066.988 [DataOverHttps](INFO): SBD MOMSN=23227738 2024-09-25T18:31:08.334Z,1727289068.334 [Default:CheckIn:Read_Iridium] Stopped 2024-09-25T18:31:08.334Z,1727289068.334 [Default:CheckIn:C.Wait] Running Loop=1 2024-09-25T18:31:08.334Z,1727289068.334 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-09-25T18:31:27.319Z,1727289087.319 [NAL9602](INFO): Powering down 2024-09-25T18:35:57.197Z,1727289357.197 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2024-09-25T18:36:08.920Z,1727289368.920 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-09-25T18:36:08.920Z,1727289368.920 [Default:CheckIn:C.Wait] Stopped 2024-09-25T18:36:08.920Z,1727289368.920 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-09-25T18:36:08.920Z,1727289368.920 [Default:CheckIn:D] Running Loop=1 2024-09-25T18:36:09.318Z,1727289369.318 [Default:CheckIn:D] Stopped 2024-09-25T18:36:09.318Z,1727289369.318 [Default:CheckIn:E] Running Loop=1 2024-09-25T18:36:09.732Z,1727289369.732 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 117.776758 min 2024-09-25T18:36:09.732Z,1727289369.732 [Default:CheckIn:E] Stopped 2024-09-25T18:36:09.732Z,1727289369.732 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-09-25T18:36:09.732Z,1727289369.732 [Default:CheckIn] Stopped 2024-09-25T18:36:09.732Z,1727289369.732 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-09-25T18:36:09.733Z,1727289369.733 [Default:CheckIn](INFO): Running loop #14 2024-09-25T18:36:09.733Z,1727289369.733 [Default:CheckIn] Running Loop=14 2024-09-25T18:36:09.733Z,1727289369.733 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-09-25T18:36:09.734Z,1727289369.734 [Default:CheckIn:Read_GPS] Running Loop=1 2024-09-25T18:36:10.520Z,1727289370.520 [NAL9602](INFO): Powering up 2024-09-25T18:36:21.430Z,1727289381.430 [NAL9602](INFO): NAL9602 initialized 2024-09-25T18:36:22.243Z,1727289382.243 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-09-25T18:36:22.243Z,1727289382.243 [NAL9602] Data Fault, FailCount= 1 2024-09-25T18:36:22.243Z,1727289382.243 [NAL9602](ERROR): Data Fault 2024-09-25T18:36:22.264Z,1727289382.264 [CBIT](ERROR): Data Fault in component: NAL9602 2024-09-25T18:36:22.640Z,1727289382.640 [NAL9602](INFO): Powering down 2024-09-25T18:36:23.465Z,1727289383.465 [CBIT](INFO): Clearing failed state for component NAL9602 2024-09-25T18:36:23.465Z,1727289383.465 [NAL9602] No Fault, FailCount= 1 2024-09-25T18:36:52.937Z,1727289412.937 [NAL9602](INFO): Powering up NAL9602 2024-09-25T18:37:03.850Z,1727289423.850 [NAL9602](INFO): NAL9602 initialized 2024-09-25T18:41:09.928Z,1727289669.928 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-09-25T18:36:09.7Z 2024-09-25T18:41:09.928Z,1727289669.928 [Default:CheckIn:Read_GPS] Stopped 2024-09-25T18:41:09.928Z,1727289669.928 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-09-25T18:41:17.470Z,1727289677.470 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20240925T171210/Courier0030.lzma 2024-09-25T18:41:18.299Z,1727289678.299 [DataOverHttps](INFO): Moved sent file to Logs/20240925T171210/Courier0030.lzma.bak 2024-09-25T18:41:18.299Z,1727289678.299 [DataOverHttps](INFO): SBD MOMSN=23227747 2024-09-25T18:41:35.085Z,1727289695.085 [DataOverHttps](INFO): Sending 219 bytes from file Logs/20240925T171210/Express0031.lzma 2024-09-25T18:41:36.087Z,1727289696.087 [DataOverHttps](INFO): Moved sent file to Logs/20240925T171210/Express0031.lzma.bak 2024-09-25T18:41:36.087Z,1727289696.087 [DataOverHttps](INFO): SBD MOMSN=23227749 2024-09-25T18:41:37.802Z,1727289697.802 [Default:CheckIn:Read_Iridium] Stopped 2024-09-25T18:41:37.802Z,1727289697.802 [Default:CheckIn:C.Wait] Running Loop=1 2024-09-25T18:41:37.802Z,1727289697.802 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-09-25T18:42:06.865Z,1727289726.865 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-09-25T18:42:37.563Z,1727289757.563 [NAL9602](INFO): Powering down 2024-09-25T18:42:56.010Z,1727289776.010 [CommandExec](IMPORTANT): got command quit 2024-09-25T18:42:57.017Z,1727289777.017 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2024-09-25T18:42:57.017Z,1727289777.017 [CommandExec](INFO): Uninitializing the command executive. 2024-09-25T18:42:57.017Z,1727289777.017 [CommandExec](INFO): Uninitializing the command scheduler. 2024-09-25T18:42:57.018Z,1727289777.018 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-09-25T18:42:57.101Z,1727289777.101 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2024-09-25T18:42:57.102Z,1727289777.102 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2024-09-25T18:42:57.103Z,1727289777.103 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-09-25T18:42:57.106Z,1727289777.106 [NavChartDb](INFO): Join timeout helper Thread ID is 9264 2024-09-25T18:42:57.134Z,1727289777.134 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2024-09-25T18:42:57.134Z,1727289777.134 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-09-25T18:42:57.145Z,1727289777.145 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2024-09-25T18:42:57.145Z,1727289777.145 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-09-25T18:42:57.146Z,1727289777.146 [Radio_Surface](INFO): Join timeout helper Thread ID is 9265 2024-09-25T18:42:57.541Z,1727289777.541 [Radio_Surface](INFO): Powering down 2024-09-25T18:42:57.542Z,1727289777.542 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2024-09-25T18:42:57.542Z,1727289777.542 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-09-25T18:42:57.559Z,1727289777.559 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2024-09-25T18:42:57.559Z,1727289777.559 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-09-25T18:42:57.559Z,1727289777.559 [Onboard](INFO): Join timeout helper Thread ID is 9266 2024-09-25T18:42:58.433Z,1727289778.433 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2024-09-25T18:42:59.505Z,1727289779.505 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2024-09-25T18:42:59.505Z,1727289779.505 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-09-25T18:42:59.513Z,1727289779.513 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2024-09-25T18:42:59.513Z,1727289779.513 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-09-25T18:42:59.514Z,1727289779.514 [DataOverHttps](INFO): Join timeout helper Thread ID is 9267 2024-09-25T18:43:00.441Z,1727289780.441 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2024-09-25T18:43:00.441Z,1727289780.441 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-09-25T18:43:00.457Z,1727289780.457 [ComponentRegistry](INFO): Shutting down DATMMP ThreadHandler 2024-09-25T18:43:00.457Z,1727289780.457 [DATMMP ThreadHandler](INFO): Thread cancelled. 2024-09-25T18:43:00.457Z,1727289780.457 [DATMMP](INFO): Join timeout helper Thread ID is 9268 2024-09-25T18:43:00.662Z,1727289780.662 [DATMMP](INFO): Stop 2024-09-25T18:43:00.662Z,1727289780.662 [DATMMP](INFO): uninitialize 2024-09-25T18:43:00.662Z,1727289780.662 [DATMMP](INFO): Powering down 2024-09-25T18:43:00.734Z,1727289780.734 [DATMMP ThreadHandler](INFO): Uninitializing protected caller thread. 2024-09-25T18:43:00.734Z,1727289780.734 [DATMMP](INFO): uninitialize 2024-09-25T18:43:00.734Z,1727289780.734 [DATMMP](INFO): Powering down 2024-09-25T18:43:00.735Z,1727289780.735 [DATMMP ThreadHandler](INFO): Thread cancelled. 2024-09-25T18:43:00.742Z,1727289780.742 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2024-09-25T18:43:00.742Z,1727289780.742 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-09-25T18:43:00.742Z,1727289780.742 [BackseatComponent](INFO): Join timeout helper Thread ID is 9269 2024-09-25T18:43:00.925Z,1727289780.925 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2024-09-25T18:43:00.926Z,1727289780.926 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-09-25T18:43:00.930Z,1727289780.930 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2024-09-25T18:43:00.930Z,1727289780.930 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-09-25T18:43:00.930Z,1727289780.930 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 9270 2024-09-25T18:43:01.157Z,1727289781.157 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2024-09-25T18:43:01.158Z,1727289781.158 [WetLabsBB2FL](INFO): Powering down 2024-09-25T18:43:01.158Z,1727289781.158 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-09-25T18:43:01.178Z,1727289781.178 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2024-09-25T18:43:01.178Z,1727289781.178 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-09-25T18:43:01.178Z,1727289781.178 [CTD_Seabird](INFO): Join timeout helper Thread ID is 9271 2024-09-25T18:43:01.257Z,1727289781.257 [CTD_Seabird](INFO): Powering down 2024-09-25T18:43:01.269Z,1727289781.269 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2024-09-25T18:43:01.269Z,1727289781.269 [CTD_Seabird](INFO): Powering down 2024-09-25T18:43:01.281Z,1727289781.281 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-09-25T18:43:01.283Z,1727289781.283 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2024-09-25T18:43:01.283Z,1727289781.283 [logger ThreadHandler](INFO): Thread cancelled. 2024-09-25T18:43:01.283Z,1727289781.283 [logger](INFO): Join timeout helper Thread ID is 9272 2024-09-25T18:43:01.301Z,1727289781.301 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2024-09-25T18:43:01.301Z,1727289781.301 [logger ThreadHandler](INFO): Thread cancelled. 2024-09-25T18:43:01.303Z,1727289781.303 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2024-09-25T18:43:01.303Z,1727289781.303 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-09-25T18:43:01.303Z,1727289781.303 [CommandLine](INFO): Join timeout helper Thread ID is 9273 2024-09-25T18:43:01.381Z,1727289781.381 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2024-09-25T18:43:01.381Z,1727289781.381 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-09-25T18:43:01.383Z,1727289781.383 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2024-09-25T18:43:01.383Z,1727289781.383 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-09-25T18:43:01.383Z,1727289781.383 [CommandExec](INFO): Join timeout helper Thread ID is 9274 2024-09-25T18:43:01.406Z,1727289781.406 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2024-09-25T18:43:01.406Z,1727289781.406 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-09-25T18:43:01.406Z,1727289781.406 [controlThread](INFO): Join timeout helper Thread ID is 9275 2024-09-25T18:43:01.431Z,1727289781.431 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2024-09-25T18:43:01.431Z,1727289781.431 [controlThread](DEBUG): Uninitializing ControlThread 2024-09-25T18:43:01.433Z,1727289781.433 [AHRS_M2](INFO): Powering down 2024-09-25T18:43:01.601Z,1727289781.601 [DVL_micro](INFO): Powering down 2024-09-25T18:43:01.602Z,1727289781.602 [NAL9602](INFO): Powering down 2024-09-25T18:43:01.603Z,1727289781.603 [Sonardyne_Nano](INFO): Powering down 2024-09-25T18:43:01.722Z,1727289781.722 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2024-09-25T18:43:01.722Z,1727289781.722 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2024-09-25T18:43:01.723Z,1727289781.723 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2024-09-25T18:43:01.723Z,1727289781.723 [MissionManager](INFO): Uninitializing Mission Default 2024-09-25T18:43:01.723Z,1727289781.723 [Default] Stopped 2024-09-25T18:43:01.724Z,1727289781.724 [Default](DEBUG): Aggregate::uninitialize Default 2024-09-25T18:43:01.724Z,1727289781.724 [Default:B.GoToSurface] Stopped 2024-09-25T18:43:01.724Z,1727289781.724 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-09-25T18:43:01.724Z,1727289781.724 [Default:CheckIn] Stopped 2024-09-25T18:43:01.724Z,1727289781.724 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-09-25T18:43:01.724Z,1727289781.724 [Default:CheckIn:C.Wait] Stopped 2024-09-25T18:43:01.724Z,1727289781.724 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-09-25T18:43:01.727Z,1727289781.727 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2024-09-25T18:43:01.727Z,1727289781.727 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2024-09-25T18:43:01.727Z,1727289781.727 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2024-09-25T18:43:01.728Z,1727289781.728 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2024-09-25T18:43:01.728Z,1727289781.728 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2024-09-25T18:43:01.728Z,1727289781.728 [BuoyancyServo](INFO): Powering down 2024-09-25T18:43:01.741Z,1727289781.741 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2024-09-25T18:43:01.741Z,1727289781.741 [ElevatorServo](INFO): Powering down 2024-09-25T18:43:01.742Z,1727289781.742 [MassServo](DEBUG): Uninitialize Mass Servo. 2024-09-25T18:43:01.742Z,1727289781.742 [MassServo](INFO): Powering down 2024-09-25T18:43:01.743Z,1727289781.743 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-09-25T18:43:01.743Z,1727289781.743 [RudderServo](INFO): Powering down 2024-09-25T18:43:01.744Z,1727289781.744 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2024-09-25T18:43:01.744Z,1727289781.744 [ThrusterHE](INFO): Powering down 2024-09-25T18:43:01.745Z,1727289781.745 [SBIT](DEBUG): Uninitialize SBIT Component. 2024-09-25T18:43:01.745Z,1727289781.745 [IBIT](DEBUG): Uninitialize IBIT Component. 2024-09-25T18:43:01.745Z,1727289781.745 [CBIT](DEBUG): Uninitialize CBIT Component. 2024-09-25T18:43:01.746Z,1727289781.746 [CBIT](DEBUG): Powering off loads. 2024-09-25T18:43:01.757Z,1727289781.757 [CBIT](DEBUG): Disabling WDT. 2024-09-25T18:43:01.769Z,1727289781.769 [CBIT](DEBUG): Opening all GF detection circuits. 2024-09-25T18:43:01.770Z,1727289781.770 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-09-25T18:43:01.805Z,1727289781.805 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-09-25T18:43:01.811Z,1727289781.811 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-09-25T18:43:01.842Z,1727289781.842 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-09-25T18:43:01.844Z,1727289781.844 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-09-25T18:43:01.851Z,1727289781.851 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-09-25T18:43:01.857Z,1727289781.857 [DATMMP ThreadHandler](INFO): Thread cancelled. 2024-09-25T18:43:01.900Z,1727289781.900 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-09-25T18:43:01.953Z,1727289781.953 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-09-25T18:43:02.042Z,1727289782.042 [logger ThreadHandler](INFO): Thread cancelled.