2023-04-26T17:24:50.772Z,1682529890.772 [DataOverHttps](INFO): Received command: restart logs 2023-04-26T17:24:50.774Z,1682529890.774 [CommandExec](IMPORTANT): got command restart logs 2023-04-26T17:24:51.777Z,1682529891.777 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002995 2023-04-26T17:24:55.290Z,1682529895.290 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-26T17:29:25.275Z,1682530165.275 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-26T17:29:25.275Z,1682530165.275 [Default:CheckIn:C.Wait] Stopped 2023-04-26T17:29:25.275Z,1682530165.275 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-26T17:29:25.276Z,1682530165.276 [Default:CheckIn:D] Running Loop=1 2023-04-26T17:29:25.716Z,1682530165.716 [Default:CheckIn:D] Stopped 2023-04-26T17:29:25.717Z,1682530165.717 [Default:CheckIn:E] Running Loop=1 2023-04-26T17:29:26.088Z,1682530166.088 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 44.908142 min 2023-04-26T17:29:26.088Z,1682530166.088 [Default:CheckIn:E] Stopped 2023-04-26T17:29:26.088Z,1682530166.088 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-26T17:29:26.088Z,1682530166.088 [Default:CheckIn] Stopped 2023-04-26T17:29:26.088Z,1682530166.088 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-26T17:29:26.112Z,1682530166.112 [Default:CheckIn](INFO): Running loop #8 2023-04-26T17:29:26.113Z,1682530166.113 [Default:CheckIn] Running Loop=8 2023-04-26T17:29:26.113Z,1682530166.113 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-26T17:29:26.113Z,1682530166.113 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-26T17:29:42.710Z,1682530182.710 [NAL9602](INFO): SBD MO Status=0, MOMSN=3645, MT Status=0, MTMSN=0 2023-04-26T17:29:42.710Z,1682530182.710 [NAL9602](INFO): No messages in MT queue 2023-04-26T17:29:53.206Z,1682530193.206 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172952.00,A,3648.26841,N,12150.41321,W,0.914,0.00,260423,,,A*79 2023-04-26T17:29:53.209Z,1682530193.209 [NAL9602](INFO): GPS fix at 20230426T172952: (36.804474, -121.840220) 2023-04-26T17:29:53.218Z,1682530193.218 [Default:CheckIn:Read_GPS] Stopped 2023-04-26T17:29:53.219Z,1682530193.219 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-26T17:30:01.596Z,1682530201.596 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20230425T194627/Courier0121.lzma 2023-04-26T17:30:02.599Z,1682530202.599 [DataOverHttps](INFO): Moved sent file to Logs/20230425T194627/Courier0121.lzma.bak 2023-04-26T17:30:02.599Z,1682530202.599 [DataOverHttps](INFO): SBD MOMSN=18269182 2023-04-26T17:30:18.524Z,1682530218.524 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20230426T172450/Courier0000.lzma 2023-04-26T17:30:19.526Z,1682530219.526 [DataOverHttps](INFO): Moved sent file to Logs/20230426T172450/Courier0000.lzma.bak 2023-04-26T17:30:19.526Z,1682530219.526 [DataOverHttps](INFO): SBD MOMSN=18269186 2023-04-26T17:30:25.522Z,1682530225.522 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-26T17:30:34.413Z,1682530234.413 [DVL_micro](ERROR): only read 2 of 4 data items 2023-04-26T17:30:34.413Z,1682530234.413 [DVL_micro](ERROR): Failed to parse: :BI,+0169,+00865,+ 2023-04-26T17:30:35.596Z,1682530235.596 [DataOverHttps](INFO): Sending 106 bytes from file Logs/20230425T194627/Express0122.lzma 2023-04-26T17:30:36.599Z,1682530236.599 [DataOverHttps](INFO): Moved sent file to Logs/20230425T194627/Express0122.lzma.bak 2023-04-26T17:30:36.599Z,1682530236.599 [DataOverHttps](INFO): SBD MOMSN=18269190 2023-04-26T17:31:01.477Z,1682530261.477 [DataOverHttps](INFO): Sending 392 bytes from file Logs/20230426T172450/Express0001.lzma 2023-04-26T17:31:02.478Z,1682530262.478 [DataOverHttps](INFO): Moved sent file to Logs/20230426T172450/Express0001.lzma.bak 2023-04-26T17:31:02.478Z,1682530262.478 [DataOverHttps](INFO): SBD MOMSN=18269193 2023-04-26T17:31:18.460Z,1682530278.460 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20230426T172450/Express0004.lzma 2023-04-26T17:31:19.462Z,1682530279.462 [DataOverHttps](INFO): Moved sent file to Logs/20230426T172450/Express0004.lzma.bak 2023-04-26T17:31:19.462Z,1682530279.462 [DataOverHttps](INFO): SBD MOMSN=18269197 2023-04-26T17:31:20.902Z,1682530280.902 [Default:CheckIn:Read_Iridium] Stopped 2023-04-26T17:31:20.903Z,1682530280.903 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-26T17:31:20.903Z,1682530280.903 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-26T17:31:47.950Z,1682530307.950 [DVL_micro](ERROR): Failed to parse: :WI,+04583,-02094,-01020,+00000,A 2023-04-26T17:32:59.064Z,1682530379.064 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2023-04-26T17:32:59.065Z,1682530379.065 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+1.0,1489.0,000 2023-04-26T17:35:24.073Z,1682530524.073 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-26T17:35:55.197Z,1682530555.197 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-26T17:36:21.463Z,1682530581.463 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-26T17:36:21.463Z,1682530581.463 [Default:CheckIn:C.Wait] Stopped 2023-04-26T17:36:21.463Z,1682530581.463 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-26T17:36:21.463Z,1682530581.463 [Default:CheckIn:D] Running Loop=1 2023-04-26T17:36:21.888Z,1682530581.888 [Default:CheckIn:D] Stopped 2023-04-26T17:36:21.888Z,1682530581.888 [Default:CheckIn:E] Running Loop=1 2023-04-26T17:36:22.266Z,1682530582.266 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 51.844332 min 2023-04-26T17:36:22.266Z,1682530582.266 [Default:CheckIn:E] Stopped 2023-04-26T17:36:22.266Z,1682530582.266 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-26T17:36:22.266Z,1682530582.266 [Default:CheckIn] Stopped 2023-04-26T17:36:22.266Z,1682530582.266 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-26T17:36:22.266Z,1682530582.266 [Default:CheckIn](INFO): Running loop #9 2023-04-26T17:36:22.267Z,1682530582.267 [Default:CheckIn] Running Loop=9 2023-04-26T17:36:22.267Z,1682530582.267 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-26T17:36:22.267Z,1682530582.267 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-26T17:36:24.289Z,1682530584.289 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173623.00,A,3648.37543,N,12149.56544,W,23.268,70.60,260423,,,D*70 2023-04-26T17:36:24.291Z,1682530584.291 [NAL9602](INFO): GPS fix at 20230426T173623: (36.806257, -121.826091) 2023-04-26T17:36:24.301Z,1682530584.301 [Default:CheckIn:Read_GPS] Stopped 2023-04-26T17:36:24.301Z,1682530584.301 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-26T17:36:26.337Z,1682530586.337 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-26T17:36:48.930Z,1682530608.930 [NAL9602](INFO): SBD MO Status=0, MOMSN=3646, MT Status=0, MTMSN=0 2023-04-26T17:36:48.931Z,1682530608.931 [NAL9602](INFO): No messages in MT queue 2023-04-26T17:36:57.461Z,1682530617.461 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-26T17:37:19.624Z,1682530639.624 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-26T17:37:28.585Z,1682530648.585 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-26T17:37:32.601Z,1682530652.601 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.735703 2023-04-26T17:37:46.751Z,1682530666.751 [NAL9602](INFO): SBD MO Status=1, MOMSN=3647, MT Status=0, MTMSN=0 2023-04-26T17:37:46.809Z,1682530666.809 [NAL9602](INFO): Sent 71 bytes from file Logs/20230426T172450/Courier0006.lzma 2023-04-26T17:37:46.809Z,1682530666.809 [NAL9602](INFO): Packets left to send: 0 2023-04-26T17:38:03.041Z,1682530683.041 [NAL9602](INFO): SBD MO Status=2, MOMSN=3648, MT Status=2, MTMSN=0 2023-04-26T17:38:03.041Z,1682530683.041 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-04-26T17:38:03.725Z,1682530683.725 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-26T17:38:05.856Z,1682530685.856 [DVL_micro](ERROR): DVL BIT error. See manual. Result code: 3684 2023-04-26T17:38:05.858Z,1682530685.858 [DVL_micro](ERROR): Failed to parse: :BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00 2023-04-26T17:38:31.504Z,1682530711.504 [NAL9602](INFO): SBD MO Status=2, MOMSN=3648, MT Status=2, MTMSN=0 2023-04-26T17:38:31.504Z,1682530711.504 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-04-26T17:38:34.853Z,1682530714.853 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-26T17:38:50.280Z,1682530730.280 [NAL9602](INFO): SBD MO Status=1, MOMSN=3648, MT Status=0, MTMSN=0 2023-04-26T17:38:50.326Z,1682530730.326 [NAL9602](INFO): Sent 141 bytes from file Logs/20230426T172450/Express0007.lzma 2023-04-26T17:38:50.326Z,1682530730.326 [NAL9602](INFO): Packets left to send: 0 2023-04-26T17:39:05.981Z,1682530745.981 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-26T17:39:07.646Z,1682530747.646 [NAL9602](INFO): SBD MO Status=2, MOMSN=3649, MT Status=2, MTMSN=0 2023-04-26T17:39:07.646Z,1682530747.646 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-04-26T17:39:29.537Z,1682530769.537 [NAL9602](INFO): SBD MO Status=2, MOMSN=3649, MT Status=2, MTMSN=0 2023-04-26T17:39:29.537Z,1682530769.537 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-04-26T17:39:34.521Z,1682530774.521 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003002 2023-04-26T17:39:36.292Z,1682530776.292 [Default:CheckIn:Read_Iridium] Stopped 2023-04-26T17:39:36.292Z,1682530776.292 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-26T17:39:36.292Z,1682530776.292 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-26T17:39:38.749Z,1682530778.749 [NAL9602](INFO): SBD MO Status=0, MOMSN=3649, MT Status=0, MTMSN=0 2023-04-26T17:39:57.333Z,1682530797.333 [DVL_micro](ERROR): Failed to parse: :WI,+08950,-0291,+00000,A 2023-04-26T17:40:09.446Z,1682530809.446 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-26T17:42:39.125Z,1682530959.125 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-26T17:43:10.269Z,1682530990.269 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-04-26T17:44:36.970Z,1682531076.970 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-26T17:44:36.970Z,1682531076.970 [Default:CheckIn:C.Wait] Stopped 2023-04-26T17:44:36.970Z,1682531076.970 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-26T17:44:36.970Z,1682531076.970 [Default:CheckIn:D] Running Loop=1 2023-04-26T17:44:37.362Z,1682531077.362 [Default:CheckIn:D] Stopped 2023-04-26T17:44:37.362Z,1682531077.362 [Default:CheckIn:E] Running Loop=1 2023-04-26T17:44:37.777Z,1682531077.777 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 60.102234 min 2023-04-26T17:44:37.777Z,1682531077.777 [Default:CheckIn:E] Stopped 2023-04-26T17:44:37.777Z,1682531077.777 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-26T17:44:37.777Z,1682531077.777 [Default:CheckIn] Stopped 2023-04-26T17:44:37.777Z,1682531077.777 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-26T17:44:37.777Z,1682531077.777 [Default:CheckIn](INFO): Running loop #10 2023-04-26T17:44:37.777Z,1682531077.777 [Default:CheckIn] Running Loop=10 2023-04-26T17:44:37.778Z,1682531077.778 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-26T17:44:37.778Z,1682531077.778 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-26T17:44:39.780Z,1682531079.780 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174438.00,A,3648.09197,N,12147.80523,W,4.898,55.56,260423,,,D*49 2023-04-26T17:44:39.782Z,1682531079.782 [NAL9602](INFO): GPS fix at 20230426T174438: (36.801533, -121.796754) 2023-04-26T17:44:39.881Z,1682531079.881 [Default:CheckIn:Read_GPS] Stopped 2023-04-26T17:44:39.881Z,1682531079.881 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-26T17:44:47.940Z,1682531087.940 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230426T172450/Courier0009.lzma 2023-04-26T17:44:48.942Z,1682531088.942 [DataOverHttps](INFO): Moved sent file to Logs/20230426T172450/Courier0009.lzma.bak 2023-04-26T17:44:48.942Z,1682531088.942 [DataOverHttps](INFO): SBD MOMSN=18269257 2023-04-26T17:44:51.505Z,1682531091.505 [NAL9602](INFO): SBD MO Status=0, MOMSN=3650, MT Status=0, MTMSN=0 2023-04-26T17:44:51.505Z,1682531091.505 [NAL9602](INFO): No messages in MT queue 2023-04-26T17:45:04.996Z,1682531104.996 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20230426T172450/Express0010.lzma 2023-04-26T17:45:05.998Z,1682531105.998 [DataOverHttps](INFO): Moved sent file to Logs/20230426T172450/Express0010.lzma.bak 2023-04-26T17:45:05.998Z,1682531105.998 [DataOverHttps](INFO): SBD MOMSN=18269263 2023-04-26T17:45:07.668Z,1682531107.668 [Default:CheckIn:Read_Iridium] Stopped 2023-04-26T17:45:07.669Z,1682531107.669 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-26T17:45:07.669Z,1682531107.669 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-26T17:45:22.199Z,1682531122.199 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-26T17:46:22.804Z,1682531182.804 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2023-04-26T17:46:22.805Z,1682531182.805 [DVL_micro](ERROR): Failed to parse: :TS,000000000000+13.3,0000.,000 2023-04-26T17:49:37.932Z,1682531377.932 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2023-04-26T17:49:37.932Z,1682531377.932 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+13.4,000.0,000 2023-04-26T17:49:53.309Z,1682531393.309 [CBIT](IMPORTANT): Beginning ground fault scan 2023-04-26T17:50:04.215Z,1682531404.215 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.018014 CHAN A1 (24V): 0.017206 CHAN A2 (12V): -0.012186 CHAN A3 (5V): -0.021856 CHAN B0 (3.3V): -0.009442 CHAN B1 (3.15aV): -0.003454 CHAN B2 (3.15bV): -0.002426 CHAN B3 (GND): -0.012651 OPEN: 0.009882 Full Scale: +/- 1 mA 2023-04-26T17:50:08.253Z,1682531408.253 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-26T17:50:08.253Z,1682531408.253 [Default:CheckIn:C.Wait] Stopped 2023-04-26T17:50:08.253Z,1682531408.253 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-26T17:50:08.253Z,1682531408.253 [Default:CheckIn:D] Running Loop=1 2023-04-26T17:50:08.643Z,1682531408.643 [Default:CheckIn:D] Stopped 2023-04-26T17:50:08.643Z,1682531408.643 [Default:CheckIn:E] Running Loop=1 2023-04-26T17:50:09.051Z,1682531409.051 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 65.623584 min 2023-04-26T17:50:09.051Z,1682531409.051 [Default:CheckIn:E] Stopped 2023-04-26T17:50:09.052Z,1682531409.052 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-26T17:50:09.052Z,1682531409.052 [Default:CheckIn] Stopped 2023-04-26T17:50:09.052Z,1682531409.052 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-26T17:50:09.052Z,1682531409.052 [Default:CheckIn](INFO): Running loop #11 2023-04-26T17:50:09.052Z,1682531409.052 [Default:CheckIn] Running Loop=11 2023-04-26T17:50:09.052Z,1682531409.052 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-26T17:50:09.053Z,1682531409.053 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-26T17:50:11.071Z,1682531411.071 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175010.00,A,3648.45226,N,12147.30765,W,8.572,50.97,260423,,,D*41 2023-04-26T17:50:11.074Z,1682531411.074 [NAL9602](INFO): GPS fix at 20230426T175010: (36.807538, -121.788461) 2023-04-26T17:50:11.084Z,1682531411.084 [Default:CheckIn:Read_GPS] Stopped 2023-04-26T17:50:11.084Z,1682531411.084 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-26T17:50:18.368Z,1682531418.368 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230426T172450/Courier0012.lzma 2023-04-26T17:50:19.370Z,1682531419.370 [DataOverHttps](INFO): Moved sent file to Logs/20230426T172450/Courier0012.lzma.bak 2023-04-26T17:50:19.370Z,1682531419.370 [DataOverHttps](INFO): SBD MOMSN=18269272 2023-04-26T17:50:35.264Z,1682531435.264 [DataOverHttps](INFO): Sending 341 bytes from file Logs/20230426T172450/Express0013.lzma 2023-04-26T17:50:36.266Z,1682531436.266 [DataOverHttps](INFO): Moved sent file to Logs/20230426T172450/Express0013.lzma.bak 2023-04-26T17:50:36.266Z,1682531436.266 [DataOverHttps](INFO): SBD MOMSN=18269278 2023-04-26T17:50:37.740Z,1682531437.740 [Default:CheckIn:Read_Iridium] Stopped 2023-04-26T17:50:37.740Z,1682531437.740 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-26T17:50:37.740Z,1682531437.740 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-26T17:50:44.188Z,1682531444.188 [NAL9602](INFO): SBD MO Status=0, MOMSN=3651, MT Status=0, MTMSN=0 2023-04-26T17:50:44.188Z,1682531444.188 [NAL9602](INFO): No messages in MT queue 2023-04-26T17:51:14.889Z,1682531474.889 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-26T17:52:50.278Z,1682531570.278 [DVL_micro](ERROR): only read 3 of 4 data items 2023-04-26T17:52:50.278Z,1682531570.278 [DVL_micro](ERROR): Failed to parse: :BI,-02700,+03483,-02209,+0:BD,+00000000.00,+00000000.0000.09,000.00 2023-04-26T17:55:38.322Z,1682531738.322 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-26T17:55:38.322Z,1682531738.322 [Default:CheckIn:C.Wait] Stopped 2023-04-26T17:55:38.322Z,1682531738.322 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-26T17:55:38.323Z,1682531738.323 [Default:CheckIn:D] Running Loop=1 2023-04-26T17:55:38.718Z,1682531738.718 [Default:CheckIn:D] Stopped 2023-04-26T17:55:38.718Z,1682531738.718 [Default:CheckIn:E] Running Loop=1 2023-04-26T17:55:39.123Z,1682531739.123 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 71.124837 min 2023-04-26T17:55:39.123Z,1682531739.123 [Default:CheckIn:E] Stopped 2023-04-26T17:55:39.123Z,1682531739.123 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-26T17:55:39.123Z,1682531739.123 [Default:CheckIn] Stopped 2023-04-26T17:55:39.123Z,1682531739.123 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-26T17:55:39.123Z,1682531739.123 [Default:CheckIn](INFO): Running loop #12 2023-04-26T17:55:39.123Z,1682531739.123 [Default:CheckIn] Running Loop=12 2023-04-26T17:55:39.123Z,1682531739.123 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-26T17:55:39.124Z,1682531739.124 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-26T17:55:41.140Z,1682531741.140 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175540.00,A,3648.26172,N,12147.13419,W,4.704,200.94,260423,,,D*74 2023-04-26T17:55:41.150Z,1682531741.150 [NAL9602](INFO): GPS fix at 20230426T175540: (36.804362, -121.785570) 2023-04-26T17:55:41.185Z,1682531741.185 [Default:CheckIn:Read_GPS] Stopped 2023-04-26T17:55:41.185Z,1682531741.185 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-26T17:55:48.752Z,1682531748.752 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230426T172450/Courier0015.lzma 2023-04-26T17:55:49.754Z,1682531749.754 [DataOverHttps](INFO): Moved sent file to Logs/20230426T172450/Courier0015.lzma.bak 2023-04-26T17:55:49.754Z,1682531749.754 [DataOverHttps](INFO): SBD MOMSN=18269318 2023-04-26T17:56:00.603Z,1682531760.603 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2023-04-26T17:56:00.603Z,1682531760.603 [DVL_micro](ERROR): Failed to parse: :TS0000000,35.0000.0,148 2023-04-26T17:56:02.983Z,1682531762.983 [NAL9602](INFO): SBD MO Status=2, MOMSN=3652, MT Status=2, MTMSN=0 2023-04-26T17:56:02.983Z,1682531762.983 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-04-26T17:56:05.700Z,1682531765.700 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230426T172450/Express0016.lzma 2023-04-26T17:56:06.702Z,1682531766.702 [DataOverHttps](INFO): Moved sent file to Logs/20230426T172450/Express0016.lzma.bak 2023-04-26T17:56:06.702Z,1682531766.702 [DataOverHttps](INFO): SBD MOMSN=18269324 2023-04-26T17:56:08.229Z,1682531768.229 [Default:CheckIn:Read_Iridium] Stopped 2023-04-26T17:56:08.229Z,1682531768.229 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-26T17:56:08.229Z,1682531768.229 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-26T17:56:40.925Z,1682531800.925 [NAL9602](INFO): SBD MO Status=0, MOMSN=3652, MT Status=0, MTMSN=0 2023-04-26T17:56:40.925Z,1682531800.925 [NAL9602](INFO): No messages in MT queue 2023-04-26T17:56:51.445Z,1682531811.445 [DVL_micro](ERROR): only read 0 of 4 data items 2023-04-26T17:56:51.445Z,1682531811.445 [DVL_micro](ERROR): Failed to parse: :RD9,+9999.99,+9999.99,+9999.99 2023-04-26T17:57:11.635Z,1682531831.635 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-26T18:00:26.771Z,1682532026.771 [DVL_micro](ERROR): Failed to parse: :.99,+9999.99,+9999.99,+9999.99 2023-04-26T18:01:08.783Z,1682532068.783 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-26T18:01:08.783Z,1682532068.783 [Default:CheckIn:C.Wait] Stopped 2023-04-26T18:01:08.783Z,1682532068.783 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-26T18:01:08.783Z,1682532068.783 [Default:CheckIn:D] Running Loop=1 2023-04-26T18:01:09.182Z,1682532069.182 [Default:CheckIn:D] Stopped 2023-04-26T18:01:09.182Z,1682532069.182 [Default:CheckIn:E] Running Loop=1 2023-04-26T18:01:09.588Z,1682532069.588 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 76.632568 min 2023-04-26T18:01:09.588Z,1682532069.588 [Default:CheckIn:E] Stopped 2023-04-26T18:01:09.588Z,1682532069.588 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-26T18:01:09.588Z,1682532069.588 [Default:CheckIn] Stopped 2023-04-26T18:01:09.588Z,1682532069.588 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-26T18:01:09.589Z,1682532069.589 [Default:CheckIn](INFO): Running loop #13 2023-04-26T18:01:09.589Z,1682532069.589 [Default:CheckIn] Running Loop=13 2023-04-26T18:01:09.589Z,1682532069.589 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-26T18:01:09.589Z,1682532069.589 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-26T18:01:11.599Z,1682532071.599 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180110.00,A,3648.14769,N,12147.22317,W,0.058,191.05,260423,,,D*70 2023-04-26T18:01:11.601Z,1682532071.601 [NAL9602](INFO): GPS fix at 20230426T180110: (36.802461, -121.787053) 2023-04-26T18:01:11.628Z,1682532071.628 [Default:CheckIn:Read_GPS] Stopped 2023-04-26T18:01:11.628Z,1682532071.628 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-26T18:01:19.340Z,1682532079.340 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230426T172450/Courier0018.lzma 2023-04-26T18:01:20.342Z,1682532080.342 [DataOverHttps](INFO): Moved sent file to Logs/20230426T172450/Courier0018.lzma.bak 2023-04-26T18:01:20.342Z,1682532080.342 [DataOverHttps](INFO): SBD MOMSN=18269334 2023-04-26T18:01:25.740Z,1682532085.740 [NAL9602](INFO): SBD MO Status=0, MOMSN=3653, MT Status=0, MTMSN=0 2023-04-26T18:01:25.740Z,1682532085.740 [NAL9602](INFO): No messages in MT queue 2023-04-26T18:01:39.376Z,1682532099.376 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230426T172450/Express0019.lzma 2023-04-26T18:01:40.378Z,1682532100.378 [DataOverHttps](INFO): Moved sent file to Logs/20230426T172450/Express0019.lzma.bak 2023-04-26T18:01:40.378Z,1682532100.378 [DataOverHttps](INFO): SBD MOMSN=18269340 2023-04-26T18:01:42.383Z,1682532102.383 [Default:CheckIn:Read_Iridium] Stopped 2023-04-26T18:01:42.383Z,1682532102.383 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-26T18:01:42.384Z,1682532102.384 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-26T18:01:56.508Z,1682532116.508 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-26T18:02:20.753Z,1682532140.753 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2023-04-26T18:02:20.753Z,1682532140.753 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+13.8,0000.0,1489:WI,+01912,+00930,-02260,+00000,A 2023-04-26T18:05:18.525Z,1682532318.525 [DVL_micro](ERROR): Failed to parse: :BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00 2023-04-26T18:06:42.963Z,1682532402.963 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-26T18:06:42.963Z,1682532402.963 [Default:CheckIn:C.Wait] Stopped 2023-04-26T18:06:42.963Z,1682532402.963 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-26T18:06:42.963Z,1682532402.963 [Default:CheckIn:D] Running Loop=1 2023-04-26T18:06:43.354Z,1682532403.354 [Default:CheckIn:D] Stopped 2023-04-26T18:06:43.354Z,1682532403.354 [Default:CheckIn:E] Running Loop=1 2023-04-26T18:06:43.758Z,1682532403.758 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 82.202108 min 2023-04-26T18:06:43.758Z,1682532403.758 [Default:CheckIn:E] Stopped 2023-04-26T18:06:43.758Z,1682532403.758 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-26T18:06:43.758Z,1682532403.758 [Default:CheckIn] Stopped 2023-04-26T18:06:43.758Z,1682532403.758 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-26T18:06:43.759Z,1682532403.759 [Default:CheckIn](INFO): Running loop #14 2023-04-26T18:06:43.759Z,1682532403.759 [Default:CheckIn] Running Loop=14 2023-04-26T18:06:43.759Z,1682532403.759 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-26T18:06:43.759Z,1682532403.759 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-26T18:06:45.777Z,1682532405.777 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180645.00,A,3648.15104,N,12147.22243,W,0.039,85.58,260423,,,A*45 2023-04-26T18:06:45.779Z,1682532405.779 [NAL9602](INFO): GPS fix at 20230426T180645: (36.802517, -121.787041) 2023-04-26T18:06:45.789Z,1682532405.789 [Default:CheckIn:Read_GPS] Stopped 2023-04-26T18:06:45.790Z,1682532405.790 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-26T18:06:53.608Z,1682532413.608 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230426T172450/Courier0021.lzma 2023-04-26T18:06:54.610Z,1682532414.610 [DataOverHttps](INFO): Moved sent file to Logs/20230426T172450/Courier0021.lzma.bak 2023-04-26T18:06:54.610Z,1682532414.610 [DataOverHttps](INFO): SBD MOMSN=18269350 2023-04-26T18:07:00.316Z,1682532420.316 [NAL9602](INFO): SBD MO Status=0, MOMSN=3654, MT Status=0, MTMSN=0 2023-04-26T18:07:00.316Z,1682532420.316 [NAL9602](INFO): No messages in MT queue 2023-04-26T18:07:02.354Z,1682532422.354 [DVL_micro](ERROR): only read 3 of 4 data items 2023-04-26T18:07:02.354Z,1682532422.354 [DVL_micro](ERROR): Failed to parse: :BI,+00842,-00002,-02242,+ 2023-04-26T18:07:13.528Z,1682532433.528 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230426T172450/Express0022.lzma 2023-04-26T18:07:14.530Z,1682532434.530 [DataOverHttps](INFO): Moved sent file to Logs/20230426T172450/Express0022.lzma.bak 2023-04-26T18:07:14.530Z,1682532434.530 [DataOverHttps](INFO): SBD MOMSN=18269359 2023-04-26T18:07:16.492Z,1682532436.492 [Default:CheckIn:Read_Iridium] Stopped 2023-04-26T18:07:16.508Z,1682532436.508 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-26T18:07:16.508Z,1682532436.508 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-26T18:07:31.016Z,1682532451.016 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-26T18:09:09.192Z,1682532549.192 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2023-04-26T18:09:09.192Z,1682532549.192 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+14.3,0000.0,1489.0,,+00286,+00278,-02033,+00000,A 2023-04-26T18:12:06.565Z,1682532726.565 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2023-04-26T18:12:06.565Z,1682532726.565 [DVL_micro](ERROR): Failed to parse: :TS0000000,35.0,+14.5,0000.0,1489.0,000 2023-04-26T18:12:17.071Z,1682532737.071 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-26T18:12:17.072Z,1682532737.072 [Default:CheckIn:C.Wait] Stopped 2023-04-26T18:12:17.072Z,1682532737.072 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-26T18:12:17.072Z,1682532737.072 [Default:CheckIn:D] Running Loop=1 2023-04-26T18:12:17.479Z,1682532737.479 [Default:CheckIn:D] Stopped 2023-04-26T18:12:17.479Z,1682532737.479 [Default:CheckIn:E] Running Loop=1 2023-04-26T18:12:17.887Z,1682532737.887 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 87.770841 min 2023-04-26T18:12:17.887Z,1682532737.887 [Default:CheckIn:E] Stopped 2023-04-26T18:12:17.887Z,1682532737.887 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-26T18:12:17.888Z,1682532737.888 [Default:CheckIn] Stopped 2023-04-26T18:12:17.888Z,1682532737.888 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-26T18:12:17.888Z,1682532737.888 [Default:CheckIn](INFO): Running loop #15 2023-04-26T18:12:17.888Z,1682532737.888 [Default:CheckIn] Running Loop=15 2023-04-26T18:12:17.888Z,1682532737.888 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-26T18:12:17.888Z,1682532737.888 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-26T18:12:19.899Z,1682532739.899 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181219.00,A,3648.14959,N,12147.22153,W,0.117,85.58,260423,,,A*47 2023-04-26T18:12:19.902Z,1682532739.902 [NAL9602](INFO): GPS fix at 20230426T181219: (36.802493, -121.787025) 2023-04-26T18:12:19.912Z,1682532739.912 [Default:CheckIn:Read_GPS] Stopped 2023-04-26T18:12:19.912Z,1682532739.912 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-26T18:12:27.959Z,1682532747.959 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230426T172450/Courier0024.lzma 2023-04-26T18:12:28.962Z,1682532748.962 [DataOverHttps](INFO): Moved sent file to Logs/20230426T172450/Courier0024.lzma.bak 2023-04-26T18:12:28.962Z,1682532748.962 [DataOverHttps](INFO): SBD MOMSN=18269379 2023-04-26T18:12:44.900Z,1682532764.900 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230426T172450/Express0025.lzma 2023-04-26T18:12:45.902Z,1682532765.902 [DataOverHttps](INFO): Moved sent file to Logs/20230426T172450/Express0025.lzma.bak 2023-04-26T18:12:45.902Z,1682532765.902 [DataOverHttps](INFO): SBD MOMSN=18269385 2023-04-26T18:12:47.382Z,1682532767.382 [Default:CheckIn:Read_Iridium] Stopped 2023-04-26T18:12:47.382Z,1682532767.382 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-26T18:12:47.382Z,1682532767.382 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-26T18:13:15.242Z,1682532795.242 [NAL9602](INFO): SBD MO Status=0, MOMSN=3655, MT Status=0, MTMSN=0 2023-04-26T18:13:15.242Z,1682532795.242 [NAL9602](INFO): No messages in MT queue 2023-04-26T18:13:45.944Z,1682532825.944 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-26T18:17:15.625Z,1682533035.625 [DVL_micro](ERROR): Failed to parse: :SA,-03.49,+06.40,190.3 2023-04-26T18:17:47.962Z,1682533067.962 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-26T18:17:47.962Z,1682533067.962 [Default:CheckIn:C.Wait] Stopped 2023-04-26T18:17:47.962Z,1682533067.962 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-26T18:17:47.962Z,1682533067.962 [Default:CheckIn:D] Running Loop=1 2023-04-26T18:17:48.387Z,1682533068.387 [Default:CheckIn:D] Stopped 2023-04-26T18:17:48.387Z,1682533068.387 [Default:CheckIn:E] Running Loop=1 2023-04-26T18:17:48.758Z,1682533068.758 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 93.285986 min 2023-04-26T18:17:48.758Z,1682533068.758 [Default:CheckIn:E] Stopped 2023-04-26T18:17:48.759Z,1682533068.759 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-26T18:17:48.759Z,1682533068.759 [Default:CheckIn] Stopped 2023-04-26T18:17:48.759Z,1682533068.759 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-26T18:17:48.759Z,1682533068.759 [Default:CheckIn](INFO): Running loop #16 2023-04-26T18:17:48.759Z,1682533068.759 [Default:CheckIn] Running Loop=16 2023-04-26T18:17:48.759Z,1682533068.759 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-26T18:17:48.759Z,1682533068.759 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-26T18:17:50.775Z,1682533070.775 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181750.00,A,3648.16991,N,12147.28583,W,0.505,218.86,260423,,,D*7D 2023-04-26T18:17:50.778Z,1682533070.778 [NAL9602](INFO): GPS fix at 20230426T181750: (36.802832, -121.788097) 2023-04-26T18:17:50.789Z,1682533070.789 [Default:CheckIn:Read_GPS] Stopped 2023-04-26T18:17:50.789Z,1682533070.789 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-26T18:17:57.852Z,1682533077.852 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230426T172450/Courier0027.lzma 2023-04-26T18:17:58.854Z,1682533078.854 [DataOverHttps](INFO): Moved sent file to Logs/20230426T172450/Courier0027.lzma.bak 2023-04-26T18:17:58.854Z,1682533078.854 [DataOverHttps](INFO): SBD MOMSN=18269408 2023-04-26T18:18:14.708Z,1682533094.708 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20230426T172450/Express0028.lzma 2023-04-26T18:18:15.711Z,1682533095.711 [DataOverHttps](INFO): Moved sent file to Logs/20230426T172450/Express0028.lzma.bak 2023-04-26T18:18:15.711Z,1682533095.711 [DataOverHttps](INFO): SBD MOMSN=18269414 2023-04-26T18:18:17.111Z,1682533097.111 [Default:CheckIn:Read_Iridium] Stopped 2023-04-26T18:18:17.111Z,1682533097.111 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-26T18:18:17.112Z,1682533097.112 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-26T18:21:49.202Z,1682533309.202 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2023-04-26T18:21:49.202Z,1682533309.202 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+15.2,0000.0,1489:WI,+01516,-06872,-00043,+00000,A 2023-04-26T18:22:01.755Z,1682533321.755 [DVL_micro](ERROR): only read 3 of 4 data items 2023-04-26T18:22:01.755Z,1682533321.755 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99,+9999.99,+99999.99 2023-04-26T18:22:53.457Z,1682533373.457 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-04-26T18:23:17.728Z,1682533397.728 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-26T18:23:17.728Z,1682533397.728 [Default:CheckIn:C.Wait] Stopped 2023-04-26T18:23:17.728Z,1682533397.728 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-26T18:23:17.728Z,1682533397.728 [Default:CheckIn:D] Running Loop=1 2023-04-26T18:23:18.129Z,1682533398.129 [Default:CheckIn:D] Stopped 2023-04-26T18:23:18.129Z,1682533398.129 [Default:CheckIn:E] Running Loop=1 2023-04-26T18:23:18.515Z,1682533398.515 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 98.781689 min 2023-04-26T18:23:18.515Z,1682533398.515 [Default:CheckIn:E] Stopped 2023-04-26T18:23:18.515Z,1682533398.515 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-26T18:23:18.515Z,1682533398.515 [Default:CheckIn] Stopped 2023-04-26T18:23:18.515Z,1682533398.515 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-26T18:23:18.516Z,1682533398.516 [Default:CheckIn](INFO): Running loop #17 2023-04-26T18:23:18.516Z,1682533398.516 [Default:CheckIn] Running Loop=17 2023-04-26T18:23:18.516Z,1682533398.516 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-26T18:23:18.516Z,1682533398.516 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-26T18:23:20.540Z,1682533400.540 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182319.00,A,3648.16438,N,12147.28441,W,0.914,55.74,260423,,,A*49 2023-04-26T18:23:20.543Z,1682533400.543 [NAL9602](INFO): GPS fix at 20230426T182319: (36.802740, -121.788073) 2023-04-26T18:23:20.553Z,1682533400.553 [Default:CheckIn:Read_GPS] Stopped 2023-04-26T18:23:20.553Z,1682533400.553 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-26T18:23:27.912Z,1682533407.912 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230426T172450/Courier0030.lzma 2023-04-26T18:23:28.914Z,1682533408.914 [DataOverHttps](INFO): Moved sent file to Logs/20230426T172450/Courier0030.lzma.bak 2023-04-26T18:23:28.914Z,1682533408.914 [DataOverHttps](INFO): SBD MOMSN=18269429 2023-04-26T18:23:48.950Z,1682533428.950 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20230426T172450/Express0031.lzma 2023-04-26T18:23:49.950Z,1682533429.950 [DataOverHttps](INFO): Moved sent file to Logs/20230426T172450/Express0031.lzma.bak 2023-04-26T18:23:49.950Z,1682533429.950 [DataOverHttps](INFO): SBD MOMSN=18269436 2023-04-26T18:23:51.307Z,1682533431.307 [Default:CheckIn:Read_Iridium] Stopped 2023-04-26T18:23:51.307Z,1682533431.307 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-26T18:23:51.307Z,1682533431.307 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-26T18:23:52.562Z,1682533432.562 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-26T18:25:24.615Z,1682533524.615 [DVL_micro](ERROR): only read 3 of 4 data items 2023-04-26T18:25:24.616Z,1682533524.616 [DVL_micro](ERROR): Failed to parse: :BI,+02418,+01113,-01471I 2023-04-26T18:25:50.066Z,1682533550.066 [DVL_micro](ERROR): only read 3 of 4 data items 2023-04-26T18:25:50.066Z,1682533550.066 [DVL_micro](ERROR): Failed to parse: :BI,+01075,+0011,+00000,I 2023-04-26T18:27:41.203Z,1682533661.203 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2023-04-26T18:27:41.231Z,1682533661.231 [BPC1](INFO): Received data from all battery sticks. 2023-04-26T18:28:51.871Z,1682533731.871 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-26T18:28:51.871Z,1682533731.871 [Default:CheckIn:C.Wait] Stopped 2023-04-26T18:28:51.871Z,1682533731.871 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-26T18:28:51.871Z,1682533731.871 [Default:CheckIn:D] Running Loop=1 2023-04-26T18:28:52.289Z,1682533732.289 [Default:CheckIn:D] Stopped 2023-04-26T18:28:52.289Z,1682533732.289 [Default:CheckIn:E] Running Loop=1 2023-04-26T18:28:52.676Z,1682533732.676 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 104.351017 min 2023-04-26T18:28:52.676Z,1682533732.676 [Default:CheckIn:E] Stopped 2023-04-26T18:28:52.676Z,1682533732.676 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-26T18:28:52.676Z,1682533732.676 [Default:CheckIn] Stopped 2023-04-26T18:28:52.676Z,1682533732.676 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-26T18:28:52.677Z,1682533732.677 [Default:CheckIn](INFO): Running loop #18 2023-04-26T18:28:52.677Z,1682533732.677 [Default:CheckIn] Running Loop=18 2023-04-26T18:28:52.677Z,1682533732.677 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-26T18:28:52.677Z,1682533732.677 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-26T18:28:54.699Z,1682533734.699 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182853.00,A,3648.16540,N,12147.28538,W,0.078,27.55,260423,,,A*48 2023-04-26T18:28:54.702Z,1682533734.702 [NAL9602](INFO): GPS fix at 20230426T182853: (36.802757, -121.788090) 2023-04-26T18:28:54.733Z,1682533734.733 [Default:CheckIn:Read_GPS] Stopped 2023-04-26T18:28:54.733Z,1682533734.733 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-26T18:29:02.420Z,1682533742.420 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20230426T172450/Courier0033.lzma 2023-04-26T18:29:03.422Z,1682533743.422 [DataOverHttps](INFO): Moved sent file to Logs/20230426T172450/Courier0033.lzma.bak 2023-04-26T18:29:03.422Z,1682533743.422 [DataOverHttps](INFO): SBD MOMSN=18269447 2023-04-26T18:29:19.252Z,1682533759.252 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230426T172450/Express0034.lzma 2023-04-26T18:29:20.254Z,1682533760.254 [DataOverHttps](INFO): Moved sent file to Logs/20230426T172450/Express0034.lzma.bak 2023-04-26T18:29:20.254Z,1682533760.254 [DataOverHttps](INFO): SBD MOMSN=18269453 2023-04-26T18:29:21.771Z,1682533761.771 [Default:CheckIn:Read_Iridium] Stopped 2023-04-26T18:29:21.771Z,1682533761.771 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-26T18:29:21.772Z,1682533761.772 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-26T18:29:25.413Z,1682533765.413 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-04-26T18:29:25.488Z,1682533765.488 [NAL9602](ERROR): received: +CSQ:0 OK55, 0, 0, 0, 0 OK 2023-04-26T18:33:13.280Z,1682533993.280 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2023-04-26T18:33:13.280Z,1682533993.280 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+15.,1489.0,000 2023-04-26T18:33:57.314Z,1682534037.314 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-04-26T18:34:22.367Z,1682534062.367 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-26T18:34:22.367Z,1682534062.367 [Default:CheckIn:C.Wait] Stopped 2023-04-26T18:34:22.367Z,1682534062.367 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-26T18:34:22.368Z,1682534062.368 [Default:CheckIn:D] Running Loop=1 2023-04-26T18:34:22.754Z,1682534062.754 [Default:CheckIn:D] Stopped 2023-04-26T18:34:22.754Z,1682534062.754 [Default:CheckIn:E] Running Loop=1 2023-04-26T18:34:23.159Z,1682534063.159 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 109.858765 min 2023-04-26T18:34:23.159Z,1682534063.159 [Default:CheckIn:E] Stopped 2023-04-26T18:34:23.159Z,1682534063.159 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-26T18:34:23.159Z,1682534063.159 [Default:CheckIn] Stopped 2023-04-26T18:34:23.159Z,1682534063.159 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-26T18:34:23.160Z,1682534063.160 [Default:CheckIn](INFO): Running loop #19 2023-04-26T18:34:23.160Z,1682534063.160 [Default:CheckIn] Running Loop=19 2023-04-26T18:34:23.160Z,1682534063.160 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-26T18:34:23.160Z,1682534063.160 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-26T18:34:25.181Z,1682534065.181 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183424.00,A,3648.16433,N,12147.28086,W,0.253,27.55,260423,,,A*4B 2023-04-26T18:34:25.183Z,1682534065.183 [NAL9602](INFO): GPS fix at 20230426T183424: (36.802739, -121.788014) 2023-04-26T18:34:25.193Z,1682534065.193 [Default:CheckIn:Read_GPS] Stopped 2023-04-26T18:34:25.193Z,1682534065.193 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-26T18:34:32.408Z,1682534072.408 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230426T172450/Courier0036.lzma 2023-04-26T18:34:33.411Z,1682534073.411 [DataOverHttps](INFO): Moved sent file to Logs/20230426T172450/Courier0036.lzma.bak 2023-04-26T18:34:33.411Z,1682534073.411 [DataOverHttps](INFO): SBD MOMSN=18269462 2023-04-26T18:34:49.297Z,1682534089.297 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20230426T172450/Express0037.lzma 2023-04-26T18:34:50.298Z,1682534090.298 [DataOverHttps](INFO): Moved sent file to Logs/20230426T172450/Express0037.lzma.bak 2023-04-26T18:34:50.298Z,1682534090.298 [DataOverHttps](INFO): SBD MOMSN=18269469 2023-04-26T18:34:51.881Z,1682534091.881 [Default:CheckIn:Read_Iridium] Stopped 2023-04-26T18:34:51.881Z,1682534091.881 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-26T18:34:51.881Z,1682534091.881 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-26T18:34:57.489Z,1682534097.489 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-26T18:35:32.661Z,1682534132.661 [DVL_micro](ERROR): only read 3 of 4 data items 2023-04-26T18:35:32.662Z,1682534132.662 [DVL_micro](ERROR): Failed to parse: :BI,-01247,+01541,-02120,+00000+00000000.00,+0000000000000.00,9999.99,000.00 2023-04-26T18:38:27.221Z,1682534307.221 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2023-04-26T18:38:27.222Z,1682534307.222 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+16.0,0000.0,1489.0I,-00264,+01361,-01855,+00000,A 2023-04-26T18:39:52.422Z,1682534392.422 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-26T18:39:52.422Z,1682534392.422 [Default:CheckIn:C.Wait] Stopped 2023-04-26T18:39:52.422Z,1682534392.422 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-26T18:39:52.422Z,1682534392.422 [Default:CheckIn:D] Running Loop=1 2023-04-26T18:39:52.824Z,1682534392.824 [Default:CheckIn:D] Stopped 2023-04-26T18:39:52.824Z,1682534392.824 [Default:CheckIn:E] Running Loop=1 2023-04-26T18:39:53.254Z,1682534393.254 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 115.359928 min 2023-04-26T18:39:53.254Z,1682534393.254 [Default:CheckIn:E] Stopped 2023-04-26T18:39:53.255Z,1682534393.255 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-26T18:39:53.255Z,1682534393.255 [Default:CheckIn] Stopped 2023-04-26T18:39:53.255Z,1682534393.255 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-26T18:39:53.255Z,1682534393.255 [Default:CheckIn](INFO): Running loop #20 2023-04-26T18:39:53.255Z,1682534393.255 [Default:CheckIn] Running Loop=20 2023-04-26T18:39:53.255Z,1682534393.255 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-26T18:39:53.255Z,1682534393.255 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-26T18:39:55.238Z,1682534395.238 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183954.00,A,3648.16714,N,12147.28199,W,0.058,27.55,260423,,,A*41 2023-04-26T18:39:55.241Z,1682534395.241 [NAL9602](INFO): GPS fix at 20230426T183954: (36.802786, -121.788033) 2023-04-26T18:39:55.250Z,1682534395.250 [Default:CheckIn:Read_GPS] Stopped 2023-04-26T18:39:55.250Z,1682534395.250 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-26T18:40:02.244Z,1682534402.244 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230426T172450/Courier0039.lzma 2023-04-26T18:40:03.246Z,1682534403.246 [DataOverHttps](INFO): Moved sent file to Logs/20230426T172450/Courier0039.lzma.bak 2023-04-26T18:40:03.246Z,1682534403.246 [DataOverHttps](INFO): SBD MOMSN=18269482 2023-04-26T18:40:19.076Z,1682534419.076 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230426T172450/Express0040.lzma 2023-04-26T18:40:20.078Z,1682534420.078 [DataOverHttps](INFO): Moved sent file to Logs/20230426T172450/Express0040.lzma.bak 2023-04-26T18:40:20.078Z,1682534420.078 [DataOverHttps](INFO): SBD MOMSN=18269488 2023-04-26T18:40:21.930Z,1682534421.930 [Default:CheckIn:Read_Iridium] Stopped 2023-04-26T18:40:21.930Z,1682534421.930 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-26T18:40:21.930Z,1682534421.930 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-26T18:40:25.954Z,1682534425.954 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-04-26T18:40:26.028Z,1682534426.028 [NAL9602](ERROR): received: +CSQ:0 OK55, 0, 0, 0, 0 OK 2023-04-26T18:42:30.410Z,1682534550.410 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2023-04-26T18:42:30.410Z,1682534550.410 [DVL_micro](ERROR): Failed to parse: :TS,00000000000,+16.2,0000.0,1489.0,000 2023-04-26T18:44:57.461Z,1682534697.461 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-04-26T18:45:22.538Z,1682534722.538 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-26T18:45:22.539Z,1682534722.539 [Default:CheckIn:C.Wait] Stopped 2023-04-26T18:45:22.539Z,1682534722.539 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-26T18:45:22.539Z,1682534722.539 [Default:CheckIn:D] Running Loop=1 2023-04-26T18:45:22.926Z,1682534722.926 [Default:CheckIn:D] Stopped 2023-04-26T18:45:22.927Z,1682534722.927 [Default:CheckIn:E] Running Loop=1 2023-04-26T18:45:23.326Z,1682534723.326 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 120.861646 min 2023-04-26T18:45:23.326Z,1682534723.326 [Default:CheckIn:E] Stopped 2023-04-26T18:45:23.326Z,1682534723.326 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-26T18:45:23.326Z,1682534723.326 [Default:CheckIn] Stopped 2023-04-26T18:45:23.326Z,1682534723.326 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-26T18:45:23.327Z,1682534723.327 [Default:CheckIn](INFO): Running loop #21 2023-04-26T18:45:23.327Z,1682534723.327 [Default:CheckIn] Running Loop=21 2023-04-26T18:45:23.327Z,1682534723.327 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-26T18:45:23.327Z,1682534723.327 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-26T18:45:25.348Z,1682534725.348 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184524.00,A,3648.16718,N,12147.28385,W,0.078,0.00,260423,,,D*7C 2023-04-26T18:45:25.350Z,1682534725.350 [NAL9602](INFO): GPS fix at 20230426T184524: (36.802786, -121.788064) 2023-04-26T18:45:25.361Z,1682534725.361 [Default:CheckIn:Read_GPS] Stopped 2023-04-26T18:45:25.361Z,1682534725.361 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-26T18:45:33.124Z,1682534733.124 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230426T172450/Courier0042.lzma 2023-04-26T18:45:34.126Z,1682534734.126 [DataOverHttps](INFO): Moved sent file to Logs/20230426T172450/Courier0042.lzma.bak 2023-04-26T18:45:34.126Z,1682534734.126 [DataOverHttps](INFO): SBD MOMSN=18269505 2023-04-26T18:45:53.169Z,1682534753.169 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20230426T172450/Express0043.lzma 2023-04-26T18:45:54.170Z,1682534754.170 [DataOverHttps](INFO): Moved sent file to Logs/20230426T172450/Express0043.lzma.bak 2023-04-26T18:45:54.170Z,1682534754.170 [DataOverHttps](INFO): SBD MOMSN=18269512 2023-04-26T18:45:55.714Z,1682534755.714 [Default:CheckIn:Read_Iridium] Stopped 2023-04-26T18:45:55.714Z,1682534755.714 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-26T18:45:55.714Z,1682534755.714 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-26T18:45:57.710Z,1682534757.710 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-26T18:47:44.364Z,1682534864.364 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2023-04-26T18:47:44.364Z,1682534864.364 [DropWeight] Hardware Fault, FailCount= 1 2023-04-26T18:47:44.364Z,1682534864.364 [DropWeight](ERROR): Hardware Fault 2023-04-26T18:47:44.421Z,1682534864.421 [CommandExec](FAULT): Scheduling is paused 2023-04-26T18:47:44.422Z,1682534864.422 [CBIT](INFO): Critical error at 20230426T184744 2023-04-26T18:47:44.422Z,1682534864.422 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2023-04-26T18:47:44.428Z,1682534864.428 [CBIT](ERROR): Hardware Fault in component: DropWeight 2023-04-26T18:47:44.429Z,1682534864.429 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2023-04-26T18:47:44.806Z,1682534864.806 [CBIT](INFO): Critical error at 20230426T184744 2023-04-26T18:49:30.649Z,1682534970.649 [DVL_micro](ERROR): Failed to parse: 9.99,+99999.99,+9999.99 2023-04-26T18:50:56.383Z,1682535056.383 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-26T18:50:56.383Z,1682535056.383 [Default:CheckIn:C.Wait] Stopped 2023-04-26T18:50:56.383Z,1682535056.383 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-26T18:50:56.383Z,1682535056.383 [Default:CheckIn:D] Running Loop=1 2023-04-26T18:50:56.807Z,1682535056.807 [Default:CheckIn:D] Stopped 2023-04-26T18:50:56.807Z,1682535056.807 [Default:CheckIn:E] Running Loop=1 2023-04-26T18:50:57.178Z,1682535057.178 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 126.426318 min 2023-04-26T18:50:57.178Z,1682535057.178 [Default:CheckIn:E] Stopped 2023-04-26T18:50:57.178Z,1682535057.178 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-26T18:50:57.179Z,1682535057.179 [Default:CheckIn] Stopped 2023-04-26T18:50:57.179Z,1682535057.179 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-26T18:50:57.179Z,1682535057.179 [Default:CheckIn](INFO): Running loop #22 2023-04-26T18:50:57.179Z,1682535057.179 [Default:CheckIn] Running Loop=22 2023-04-26T18:50:57.179Z,1682535057.179 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-26T18:50:57.179Z,1682535057.179 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-26T18:50:59.195Z,1682535059.195 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185058.00,A,3648.16614,N,12147.28077,W,0.117,194.61,260423,,,D*73 2023-04-26T18:50:59.198Z,1682535059.198 [NAL9602](INFO): GPS fix at 20230426T185058: (36.802769, -121.788013) 2023-04-26T18:50:59.208Z,1682535059.208 [Default:CheckIn:Read_GPS] Stopped 2023-04-26T18:50:59.208Z,1682535059.208 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-26T18:51:07.253Z,1682535067.253 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20230426T172450/Courier0045.lzma 2023-04-26T18:51:08.250Z,1682535068.250 [DataOverHttps](INFO): Moved sent file to Logs/20230426T172450/Courier0045.lzma.bak 2023-04-26T18:51:08.250Z,1682535068.250 [DataOverHttps](INFO): SBD MOMSN=18269521 2023-04-26T18:51:24.228Z,1682535084.228 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20230426T172450/Express0046.lzma 2023-04-26T18:51:25.231Z,1682535085.231 [DataOverHttps](INFO): Moved sent file to Logs/20230426T172450/Express0046.lzma.bak 2023-04-26T18:51:25.231Z,1682535085.231 [DataOverHttps](INFO): SBD MOMSN=18269529 2023-04-26T18:51:26.673Z,1682535086.673 [Default:CheckIn:Read_Iridium] Stopped 2023-04-26T18:51:26.673Z,1682535086.673 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-26T18:51:26.674Z,1682535086.674 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-26T18:51:29.903Z,1682535089.903 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-04-26T18:51:29.980Z,1682535089.980 [NAL9602](ERROR): received: +CSQ:0 OK55, 0, 0, 0, 0 OK 2023-04-26T18:52:30.496Z,1682535150.496 [DVL_micro](ERROR): Failed to parse: :WI,+00549,+01404,-01655,+00000,A 2023-04-26T18:54:32.116Z,1682535272.116 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2023-04-26T18:54:32.116Z,1682535272.116 [DVL_micro](ERROR): Failed to parse: :TS,000000005.0,+16.6,0000.0,1489.0,000 2023-04-26T18:56:01.461Z,1682535361.461 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-04-26T18:56:27.329Z,1682535387.329 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-26T18:56:27.329Z,1682535387.329 [Default:CheckIn:C.Wait] Stopped 2023-04-26T18:56:27.329Z,1682535387.329 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-26T18:56:27.330Z,1682535387.330 [Default:CheckIn:D] Running Loop=1 2023-04-26T18:56:27.736Z,1682535387.736 [Default:CheckIn:D] Stopped 2023-04-26T18:56:27.736Z,1682535387.736 [Default:CheckIn:E] Running Loop=1 2023-04-26T18:56:28.134Z,1682535388.134 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 131.941805 min 2023-04-26T18:56:28.134Z,1682535388.134 [Default:CheckIn:E] Stopped 2023-04-26T18:56:28.134Z,1682535388.134 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-26T18:56:28.134Z,1682535388.134 [Default:CheckIn] Stopped 2023-04-26T18:56:28.134Z,1682535388.134 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-26T18:56:28.135Z,1682535388.135 [Default:CheckIn](INFO): Running loop #23 2023-04-26T18:56:28.135Z,1682535388.135 [Default:CheckIn] Running Loop=23 2023-04-26T18:56:28.135Z,1682535388.135 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-26T18:56:28.135Z,1682535388.135 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-26T18:56:30.150Z,1682535390.150 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185629.00,A,3648.16406,N,12147.28072,W,0.097,88.26,260423,,,D*41 2023-04-26T18:56:30.152Z,1682535390.152 [NAL9602](INFO): GPS fix at 20230426T185629: (36.802734, -121.788012) 2023-04-26T18:56:30.162Z,1682535390.162 [Default:CheckIn:Read_GPS] Stopped 2023-04-26T18:56:30.162Z,1682535390.162 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-26T18:56:33.786Z,1682535393.786 [DVL_micro](ERROR): only read 3 of 4 data items 2023-04-26T18:56:33.786Z,1682535393.786 [DVL_micro](ERROR): Failed to parse: :BI,+01316,+01389,-01453I 2023-04-26T18:56:37.020Z,1682535397.020 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230426T172450/Courier0048.lzma 2023-04-26T18:56:38.022Z,1682535398.022 [DataOverHttps](INFO): Moved sent file to Logs/20230426T172450/Courier0048.lzma.bak 2023-04-26T18:56:38.022Z,1682535398.022 [DataOverHttps](INFO): SBD MOMSN=18269538 2023-04-26T18:56:53.948Z,1682535413.948 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20230426T172450/Express0049.lzma 2023-04-26T18:56:54.951Z,1682535414.951 [DataOverHttps](INFO): Moved sent file to Logs/20230426T172450/Express0049.lzma.bak 2023-04-26T18:56:54.951Z,1682535414.951 [DataOverHttps](INFO): SBD MOMSN=18269545 2023-04-26T18:56:56.471Z,1682535416.471 [Default:CheckIn:Read_Iridium] Stopped 2023-04-26T18:56:56.471Z,1682535416.471 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-26T18:56:56.471Z,1682535416.471 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-26T18:57:02.497Z,1682535422.497 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-26T18:57:39.676Z,1682535459.676 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2023-04-26T18:57:39.676Z,1682535459.676 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+16.7,0000.0,148 2023-04-26T19:01:57.105Z,1682535717.105 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-26T19:01:57.105Z,1682535717.105 [Default:CheckIn:C.Wait] Stopped 2023-04-26T19:01:57.106Z,1682535717.106 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-26T19:01:57.106Z,1682535717.106 [Default:CheckIn:D] Running Loop=1 2023-04-26T19:01:57.522Z,1682535717.522 [Default:CheckIn:D] Stopped 2023-04-26T19:01:57.522Z,1682535717.522 [Default:CheckIn:E] Running Loop=1 2023-04-26T19:01:57.922Z,1682535717.922 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 137.438232 min 2023-04-26T19:01:57.922Z,1682535717.922 [Default:CheckIn:E] Stopped 2023-04-26T19:01:57.922Z,1682535717.922 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-26T19:01:57.922Z,1682535717.922 [Default:CheckIn] Stopped 2023-04-26T19:01:57.923Z,1682535717.923 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-26T19:01:57.923Z,1682535717.923 [Default:CheckIn](INFO): Running loop #24 2023-04-26T19:01:57.923Z,1682535717.923 [Default:CheckIn] Running Loop=24 2023-04-26T19:01:57.923Z,1682535717.923 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-26T19:01:57.923Z,1682535717.923 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-26T19:01:59.940Z,1682535719.940 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190159.00,A,3648.16491,N,12147.28172,W,0.369,284.05,260423,,,D*77 2023-04-26T19:01:59.958Z,1682535719.958 [NAL9602](INFO): GPS fix at 20230426T190159: (36.802748, -121.788029) 2023-04-26T19:01:59.968Z,1682535719.968 [Default:CheckIn:Read_GPS] Stopped 2023-04-26T19:01:59.968Z,1682535719.968 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-26T19:02:07.684Z,1682535727.684 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230426T172450/Courier0051.lzma 2023-04-26T19:02:08.686Z,1682535728.686 [DataOverHttps](INFO): Moved sent file to Logs/20230426T172450/Courier0051.lzma.bak 2023-04-26T19:02:08.686Z,1682535728.686 [DataOverHttps](INFO): SBD MOMSN=18269552 2023-04-26T19:02:24.544Z,1682535744.544 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230426T172450/Express0052.lzma 2023-04-26T19:02:25.546Z,1682535745.546 [DataOverHttps](INFO): Moved sent file to Logs/20230426T172450/Express0052.lzma.bak 2023-04-26T19:02:25.546Z,1682535745.546 [DataOverHttps](INFO): SBD MOMSN=18269555 2023-04-26T19:02:27.014Z,1682535747.014 [Default:CheckIn:Read_Iridium] Stopped 2023-04-26T19:02:27.015Z,1682535747.015 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-26T19:02:27.015Z,1682535747.015 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-26T19:02:30.649Z,1682535750.649 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-04-26T19:02:30.724Z,1682535750.724 [NAL9602](ERROR): received: +CSQ:0 OK55, 0, 0, 0, 0 OK 2023-04-26T19:02:44.403Z,1682535764.403 [CBIT](INFO): Clearing failed state for component DropWeight 2023-04-26T19:02:44.403Z,1682535764.403 [DropWeight] No Fault, FailCount= 1 2023-04-26T19:07:02.577Z,1682536022.577 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-04-26T19:07:27.632Z,1682536047.632 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-04-26T19:07:27.632Z,1682536047.632 [Default:CheckIn:C.Wait] Stopped 2023-04-26T19:07:27.632Z,1682536047.632 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-26T19:07:27.633Z,1682536047.633 [Default:CheckIn:D] Running Loop=1 2023-04-26T19:07:28.039Z,1682536048.039 [Default:CheckIn:D] Stopped 2023-04-26T19:07:28.039Z,1682536048.039 [Default:CheckIn:E] Running Loop=1 2023-04-26T19:07:28.438Z,1682536048.438 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 142.946859 min 2023-04-26T19:07:28.438Z,1682536048.438 [Default:CheckIn:E] Stopped 2023-04-26T19:07:28.438Z,1682536048.438 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-04-26T19:07:28.438Z,1682536048.438 [Default:CheckIn] Stopped 2023-04-26T19:07:28.439Z,1682536048.439 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-26T19:07:28.439Z,1682536048.439 [Default:CheckIn](INFO): Running loop #25 2023-04-26T19:07:28.439Z,1682536048.439 [Default:CheckIn] Running Loop=25 2023-04-26T19:07:28.439Z,1682536048.439 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-04-26T19:07:28.439Z,1682536048.439 [Default:CheckIn:Read_GPS] Running Loop=1 2023-04-26T19:07:30.460Z,1682536050.460 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190729.00,A,3648.15574,N,12147.30293,W,2.002,261.34,260423,,,A*7A 2023-04-26T19:07:30.462Z,1682536050.462 [NAL9602](INFO): GPS fix at 20230426T190729: (36.802596, -121.788382) 2023-04-26T19:07:30.472Z,1682536050.472 [Default:CheckIn:Read_GPS] Stopped 2023-04-26T19:07:30.472Z,1682536050.472 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-04-26T19:07:40.992Z,1682536060.992 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230426T172450/Courier0054.lzma 2023-04-26T19:07:41.994Z,1682536061.994 [DataOverHttps](INFO): Moved sent file to Logs/20230426T172450/Courier0054.lzma.bak 2023-04-26T19:07:41.995Z,1682536061.995 [DataOverHttps](INFO): SBD MOMSN=18269577 2023-04-26T19:07:58.448Z,1682536078.448 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20230426T172450/Express0055.lzma 2023-04-26T19:07:59.450Z,1682536079.450 [DataOverHttps](INFO): Moved sent file to Logs/20230426T172450/Express0055.lzma.bak 2023-04-26T19:07:59.450Z,1682536079.450 [DataOverHttps](INFO): SBD MOMSN=18269582 2023-04-26T19:08:00.854Z,1682536080.854 [Default:CheckIn:Read_Iridium] Stopped 2023-04-26T19:08:00.854Z,1682536080.854 [Default:CheckIn:C.Wait] Running Loop=1 2023-04-26T19:08:00.854Z,1682536080.854 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-04-26T19:08:02.455Z,1682536082.455 [NAL9602](INFO): Not Powering down - fast GPS 2023-04-26T19:08:04.079Z,1682536084.079 [DVL_micro](ERROR): Failed to parse: :BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00 2023-04-26T19:10:08.429Z,1682536208.429 [CommandExec](IMPORTANT): got command quit 2023-04-26T19:10:09.436Z,1682536209.436 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2023-04-26T19:10:09.437Z,1682536209.437 [CommandExec](INFO): Uninitializing the command executive. 2023-04-26T19:10:09.437Z,1682536209.437 [CommandExec](INFO): Uninitializing the command scheduler. 2023-04-26T19:10:09.437Z,1682536209.437 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-04-26T19:10:09.568Z,1682536209.568 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2023-04-26T19:10:09.568Z,1682536209.568 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2023-04-26T19:10:09.569Z,1682536209.569 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-04-26T19:10:09.569Z,1682536209.569 [NavChartDb](INFO): Join timeout helper Thread ID is 3787 2023-04-26T19:10:09.929Z,1682536209.929 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2023-04-26T19:10:09.929Z,1682536209.929 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-04-26T19:10:09.948Z,1682536209.948 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2023-04-26T19:10:09.948Z,1682536209.948 [DAT ThreadHandler](INFO): Thread cancelled. 2023-04-26T19:10:09.949Z,1682536209.949 [DAT](INFO): Join timeout helper Thread ID is 3788 2023-04-26T19:10:10.053Z,1682536210.053 [DAT](INFO): Powering down 2023-04-26T19:10:10.129Z,1682536210.129 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2023-04-26T19:10:10.129Z,1682536210.129 [DAT](INFO): Powering down 2023-04-26T19:10:10.130Z,1682536210.130 [DAT ThreadHandler](INFO): Thread cancelled. 2023-04-26T19:10:10.144Z,1682536210.144 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2023-04-26T19:10:10.144Z,1682536210.144 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-04-26T19:10:10.145Z,1682536210.145 [Radio_Surface](INFO): Join timeout helper Thread ID is 3789 2023-04-26T19:10:10.236Z,1682536210.236 [Radio_Surface](INFO): Powering down 2023-04-26T19:10:10.237Z,1682536210.237 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2023-04-26T19:10:10.238Z,1682536210.238 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-04-26T19:10:10.244Z,1682536210.244 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2023-04-26T19:10:10.244Z,1682536210.244 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-04-26T19:10:10.245Z,1682536210.245 [Onboard](INFO): Join timeout helper Thread ID is 3790 2023-04-26T19:10:11.197Z,1682536211.197 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2023-04-26T19:10:14.112Z,1682536214.112 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2023-04-26T19:10:14.113Z,1682536214.113 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-04-26T19:10:14.129Z,1682536214.129 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2023-04-26T19:10:14.130Z,1682536214.130 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-04-26T19:10:14.130Z,1682536214.130 [DataOverHttps](INFO): Join timeout helper Thread ID is 3791 2023-04-26T19:10:14.208Z,1682536214.208 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2023-04-26T19:10:14.209Z,1682536214.209 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-04-26T19:10:14.229Z,1682536214.229 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2023-04-26T19:10:14.229Z,1682536214.229 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-04-26T19:10:14.230Z,1682536214.230 [BackseatComponent](INFO): Join timeout helper Thread ID is 3792 2023-04-26T19:10:14.276Z,1682536214.276 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2023-04-26T19:10:14.277Z,1682536214.277 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-04-26T19:10:14.289Z,1682536214.289 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2023-04-26T19:10:14.289Z,1682536214.289 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-04-26T19:10:14.290Z,1682536214.290 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3793 2023-04-26T19:10:14.764Z,1682536214.764 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2023-04-26T19:10:14.765Z,1682536214.765 [WetLabsBB2FL](INFO): Powering down 2023-04-26T19:10:14.765Z,1682536214.765 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-04-26T19:10:14.773Z,1682536214.773 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2023-04-26T19:10:14.773Z,1682536214.773 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-04-26T19:10:14.773Z,1682536214.773 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3794 2023-04-26T19:10:15.560Z,1682536215.560 [CTD_Seabird](INFO): Powering down 2023-04-26T19:10:15.572Z,1682536215.572 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2023-04-26T19:10:15.572Z,1682536215.572 [CTD_Seabird](INFO): Powering down 2023-04-26T19:10:15.584Z,1682536215.584 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-04-26T19:10:15.586Z,1682536215.586 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2023-04-26T19:10:15.586Z,1682536215.586 [logger ThreadHandler](INFO): Thread cancelled. 2023-04-26T19:10:15.586Z,1682536215.586 [logger](INFO): Join timeout helper Thread ID is 3795 2023-04-26T19:10:15.587Z,1682536215.587 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2023-04-26T19:10:15.587Z,1682536215.587 [logger ThreadHandler](INFO): Thread cancelled. 2023-04-26T19:10:15.588Z,1682536215.588 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2023-04-26T19:10:15.588Z,1682536215.588 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-04-26T19:10:15.589Z,1682536215.589 [CommandLine](INFO): Join timeout helper Thread ID is 3796 2023-04-26T19:10:15.636Z,1682536215.636 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2023-04-26T19:10:15.637Z,1682536215.637 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-04-26T19:10:15.649Z,1682536215.649 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2023-04-26T19:10:15.649Z,1682536215.649 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-04-26T19:10:15.649Z,1682536215.649 [CommandExec](INFO): Join timeout helper Thread ID is 3797 2023-04-26T19:10:15.651Z,1682536215.651 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2023-04-26T19:10:15.651Z,1682536215.651 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-04-26T19:10:15.651Z,1682536215.651 [controlThread](INFO): Join timeout helper Thread ID is 3798 2023-04-26T19:10:15.748Z,1682536215.748 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2023-04-26T19:10:15.749Z,1682536215.749 [controlThread](DEBUG): Uninitializing ControlThread 2023-04-26T19:10:15.751Z,1682536215.751 [AHRS_M2](INFO): Powering down 2023-04-26T19:10:15.892Z,1682536215.892 [DVL_micro](INFO): Powering down 2023-04-26T19:10:15.893Z,1682536215.893 [NAL9602](INFO): Powering down 2023-04-26T19:10:15.895Z,1682536215.895 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2023-04-26T19:10:15.896Z,1682536215.896 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2023-04-26T19:10:15.897Z,1682536215.897 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2023-04-26T19:10:15.897Z,1682536215.897 [MissionManager](INFO): Uninitializing Mission Default 2023-04-26T19:10:15.897Z,1682536215.897 [Default] Stopped 2023-04-26T19:10:15.897Z,1682536215.897 [Default](DEBUG): Aggregate::uninitialize Default 2023-04-26T19:10:15.897Z,1682536215.897 [Default:B.GoToSurface] Stopped 2023-04-26T19:10:15.898Z,1682536215.898 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-04-26T19:10:15.898Z,1682536215.898 [Default:CheckIn] Stopped 2023-04-26T19:10:15.898Z,1682536215.898 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-04-26T19:10:15.898Z,1682536215.898 [Default:CheckIn:C.Wait] Stopped 2023-04-26T19:10:15.898Z,1682536215.898 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-04-26T19:10:15.901Z,1682536215.901 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2023-04-26T19:10:15.901Z,1682536215.901 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2023-04-26T19:10:15.901Z,1682536215.901 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2023-04-26T19:10:15.901Z,1682536215.901 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2023-04-26T19:10:15.902Z,1682536215.902 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2023-04-26T19:10:15.902Z,1682536215.902 [BuoyancyServo](INFO): Powering down 2023-04-26T19:10:15.916Z,1682536215.916 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2023-04-26T19:10:15.917Z,1682536215.917 [ElevatorServo](INFO): Powering down 2023-04-26T19:10:15.917Z,1682536215.917 [MassServo](DEBUG): Uninitialize Mass Servo. 2023-04-26T19:10:15.917Z,1682536215.917 [MassServo](INFO): Powering down 2023-04-26T19:10:15.918Z,1682536215.918 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2023-04-26T19:10:15.918Z,1682536215.918 [RudderServo](INFO): Powering down 2023-04-26T19:10:15.919Z,1682536215.919 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2023-04-26T19:10:15.919Z,1682536215.919 [ThrusterHE](INFO): Powering down 2023-04-26T19:10:15.920Z,1682536215.920 [SBIT](DEBUG): Uninitialize SBIT Component. 2023-04-26T19:10:15.921Z,1682536215.921 [IBIT](DEBUG): Uninitialize IBIT Component. 2023-04-26T19:10:15.921Z,1682536215.921 [CBIT](DEBUG): Uninitialize CBIT Component. 2023-04-26T19:10:15.921Z,1682536215.921 [CBIT](DEBUG): Powering off loads. 2023-04-26T19:10:15.932Z,1682536215.932 [CBIT](DEBUG): Disabling WDT. 2023-04-26T19:10:15.944Z,1682536215.944 [CBIT](DEBUG): Opening all GF detection circuits. 2023-04-26T19:10:15.945Z,1682536215.945 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-04-26T19:10:15.981Z,1682536215.981 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-04-26T19:10:16.062Z,1682536216.062 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-04-26T19:10:16.092Z,1682536216.092 [DAT ThreadHandler](INFO): Thread cancelled. 2023-04-26T19:10:16.096Z,1682536216.096 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-04-26T19:10:16.098Z,1682536216.098 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-04-26T19:10:16.105Z,1682536216.105 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-04-26T19:10:16.148Z,1682536216.148 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-04-26T19:10:16.200Z,1682536216.200 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-04-26T19:10:16.275Z,1682536216.275 [logger ThreadHandler](INFO): Thread cancelled.