2023-04-26T17:38:10.086Z,1682530690.086 [CommandExec](IMPORTANT): got command restart logs
2023-04-26T17:38:19.983Z,1682530699.983 [NAL9602](INFO): SBD MO Status=1, MOMSN=11797, MT Status=0, MTMSN=0
2023-04-26T17:38:20.041Z,1682530700.041 [NAL9602](INFO): Sent 39 bytes from file Logs/20230423T180818/Courier0216.lzma
2023-04-26T17:38:20.041Z,1682530700.041 [NAL9602](INFO): Packets left to send: 0
2023-04-26T17:38:29.806Z,1682530709.806 [NAL9602](INFO): SBD MO Status=1, MOMSN=11798, MT Status=0, MTMSN=0
2023-04-26T17:38:29.853Z,1682530709.853 [NAL9602](INFO): Sent 140 bytes from file Logs/20230423T180818/Express0214.lzma
2023-04-26T17:38:29.853Z,1682530709.853 [NAL9602](INFO): Packets left to send: 0
2023-04-26T17:38:38.338Z,1682530718.338 [NAL9602](INFO): SBD MO Status=1, MOMSN=11799, MT Status=0, MTMSN=0
2023-04-26T17:38:38.389Z,1682530718.389 [NAL9602](INFO): Sent 117 bytes from file Logs/20230423T180818/Express0217.lzma
2023-04-26T17:38:38.389Z,1682530718.389 [NAL9602](INFO): Packets left to send: 0
2023-04-26T17:38:42.065Z,1682530722.065 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-26T17:38:46.418Z,1682530726.418 [NAL9602](INFO): SBD MO Status=0, MOMSN=11800, MT Status=0, MTMSN=0
2023-04-26T17:39:10.707Z,1682530750.707 [NAL9602](INFO): SBD MO Status=1, MOMSN=11801, MT Status=0, MTMSN=0
2023-04-26T17:39:10.761Z,1682530750.761 [NAL9602](INFO): Sent 150 bytes from file Logs/20230426T173810/Courier0000.lzma
2023-04-26T17:39:10.761Z,1682530750.761 [NAL9602](INFO): Packets left to send: 0
2023-04-26T17:39:17.229Z,1682530757.229 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-26T17:39:22.567Z,1682530762.567 [NAL9602](INFO): SBD MO Status=1, MOMSN=11802, MT Status=0, MTMSN=0
2023-04-26T17:39:22.617Z,1682530762.617 [NAL9602](INFO): Sent 259 bytes from file Logs/20230426T173810/Express0001.lzma
2023-04-26T17:39:22.617Z,1682530762.617 [NAL9602](INFO): Packets left to send: 0
2023-04-26T17:39:29.835Z,1682530769.835 [NAL9602](INFO): SBD MO Status=0, MOMSN=11803, MT Status=0, MTMSN=0
2023-04-26T17:39:29.916Z,1682530769.916 [Default:CheckIn:Read_Iridium] Stopped
2023-04-26T17:39:29.917Z,1682530769.917 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-26T17:39:29.917Z,1682530769.917 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-26T17:39:52.381Z,1682530792.381 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-26T17:40:00.542Z,1682530800.542 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-26T17:40:27.549Z,1682530827.549 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-26T17:41:02.693Z,1682530862.693 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-26T17:41:37.833Z,1682530897.833 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-26T17:42:12.973Z,1682530932.973 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-26T17:42:26.345Z,1682530946.345 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003071
2023-04-26T17:44:30.429Z,1682531070.429 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-26T17:44:30.429Z,1682531070.429 [Default:CheckIn:C.Wait] Stopped
2023-04-26T17:44:30.429Z,1682531070.429 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-26T17:44:30.429Z,1682531070.429 [Default:CheckIn:D] Running Loop=1
2023-04-26T17:44:30.837Z,1682531070.837 [Default:CheckIn:D] Stopped
2023-04-26T17:44:30.837Z,1682531070.837 [Default:CheckIn:E] Running Loop=1
2023-04-26T17:44:31.235Z,1682531071.235 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 22.257385 min 
2023-04-26T17:44:31.235Z,1682531071.235 [Default:CheckIn:E] Stopped
2023-04-26T17:44:31.235Z,1682531071.235 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-26T17:44:31.235Z,1682531071.235 [Default:CheckIn] Stopped
2023-04-26T17:44:31.236Z,1682531071.236 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-26T17:44:31.236Z,1682531071.236 [Default:CheckIn](INFO): Running loop #4
2023-04-26T17:44:31.236Z,1682531071.236 [Default:CheckIn] Running Loop=4
2023-04-26T17:44:31.236Z,1682531071.236 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-26T17:44:31.236Z,1682531071.236 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-26T17:44:33.243Z,1682531073.243 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174435.00,A,3648.08897,N,12147.80753,W,4.471,52.65,260423,,,D*45
2023-04-26T17:44:33.246Z,1682531073.246 [NAL9602](INFO): GPS fix at 20230426T174435: (36.801483, -121.796792)
2023-04-26T17:44:33.259Z,1682531073.259 [Default:CheckIn:Read_GPS] Stopped
2023-04-26T17:44:33.259Z,1682531073.259 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-26T17:44:40.388Z,1682531080.388 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20230426T173810/Courier0003.lzma
2023-04-26T17:44:41.390Z,1682531081.390 [DataOverHttps](INFO): Moved sent file to Logs/20230426T173810/Courier0003.lzma.bak
2023-04-26T17:44:41.391Z,1682531081.391 [DataOverHttps](INFO): SBD MOMSN=18269254
2023-04-26T17:44:49.415Z,1682531089.415 [NAL9602](INFO): SBD MO Status=0, MOMSN=11804, MT Status=0, MTMSN=0
2023-04-26T17:44:49.415Z,1682531089.415 [NAL9602](INFO): No messages in MT queue
2023-04-26T17:44:57.132Z,1682531097.132 [DataOverHttps](INFO): Sending 272 bytes from file Logs/20230426T173810/Express0004.lzma
2023-04-26T17:44:58.134Z,1682531098.134 [DataOverHttps](INFO): Moved sent file to Logs/20230426T173810/Express0004.lzma.bak
2023-04-26T17:44:58.134Z,1682531098.134 [DataOverHttps](INFO): SBD MOMSN=18269260
2023-04-26T17:44:59.558Z,1682531099.558 [Default:CheckIn:Read_Iridium] Stopped
2023-04-26T17:44:59.558Z,1682531099.558 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-26T17:44:59.558Z,1682531099.558 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-26T17:45:20.100Z,1682531120.100 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-26T17:46:42.118Z,1682531202.118 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-04-26T17:46:42.133Z,1682531202.133 [BPC1](INFO): Received data from all battery sticks.
2023-04-26T17:50:00.093Z,1682531400.093 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-26T17:50:00.093Z,1682531400.093 [Default:CheckIn:C.Wait] Stopped
2023-04-26T17:50:00.093Z,1682531400.093 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-26T17:50:00.094Z,1682531400.094 [Default:CheckIn:D] Running Loop=1
2023-04-26T17:50:00.502Z,1682531400.502 [Default:CheckIn:D] Stopped
2023-04-26T17:50:00.502Z,1682531400.502 [Default:CheckIn:E] Running Loop=1
2023-04-26T17:50:00.897Z,1682531400.897 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 27.751792 min 
2023-04-26T17:50:00.897Z,1682531400.897 [Default:CheckIn:E] Stopped
2023-04-26T17:50:00.897Z,1682531400.897 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-26T17:50:00.897Z,1682531400.897 [Default:CheckIn] Stopped
2023-04-26T17:50:00.898Z,1682531400.898 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-26T17:50:00.898Z,1682531400.898 [Default:CheckIn](INFO): Running loop #5
2023-04-26T17:50:00.898Z,1682531400.898 [Default:CheckIn] Running Loop=5
2023-04-26T17:50:00.898Z,1682531400.898 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-26T17:50:00.898Z,1682531400.898 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-26T17:50:02.907Z,1682531402.907 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175005.00,A,3648.44392,N,12147.31833,W,8.397,49.55,260423,,,D*4C
2023-04-26T17:50:02.910Z,1682531402.910 [NAL9602](INFO): GPS fix at 20230426T175005: (36.807399, -121.788639)
2023-04-26T17:50:02.923Z,1682531402.923 [Default:CheckIn:Read_GPS] Stopped
2023-04-26T17:50:02.923Z,1682531402.923 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-26T17:50:12.160Z,1682531412.160 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20230426T173810/Courier0006.lzma
2023-04-26T17:50:18.291Z,1682531418.291 [NAL9602](INFO): SBD MO Status=0, MOMSN=11805, MT Status=0, MTMSN=0
2023-04-26T17:50:18.291Z,1682531418.291 [NAL9602](INFO): No messages in MT queue
2023-04-26T17:50:22.198Z,1682531422.198 [DataOverHttps](INFO): Moved sent file to Logs/20230426T173810/Courier0006.lzma.bak
2023-04-26T17:50:22.199Z,1682531422.199 [DataOverHttps](INFO): SBD MOMSN=18269275
2023-04-26T17:50:38.032Z,1682531438.032 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20230426T173810/Express0007.lzma
2023-04-26T17:50:39.034Z,1682531439.034 [DataOverHttps](INFO): Moved sent file to Logs/20230426T173810/Express0007.lzma.bak
2023-04-26T17:50:39.035Z,1682531439.035 [DataOverHttps](INFO): SBD MOMSN=18269283
2023-04-26T17:50:40.545Z,1682531440.545 [Default:CheckIn:Read_Iridium] Stopped
2023-04-26T17:50:40.545Z,1682531440.545 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-26T17:50:40.545Z,1682531440.545 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-26T17:50:48.988Z,1682531448.988 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-26T17:55:41.095Z,1682531741.095 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-26T17:55:41.095Z,1682531741.095 [Default:CheckIn:C.Wait] Stopped
2023-04-26T17:55:41.095Z,1682531741.095 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-26T17:55:41.096Z,1682531741.096 [Default:CheckIn:D] Running Loop=1
2023-04-26T17:55:41.511Z,1682531741.511 [Default:CheckIn:D] Stopped
2023-04-26T17:55:41.511Z,1682531741.511 [Default:CheckIn:E] Running Loop=1
2023-04-26T17:55:41.900Z,1682531741.900 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 33.435276 min 
2023-04-26T17:55:41.901Z,1682531741.901 [Default:CheckIn:E] Stopped
2023-04-26T17:55:41.901Z,1682531741.901 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-26T17:55:41.901Z,1682531741.901 [Default:CheckIn] Stopped
2023-04-26T17:55:41.901Z,1682531741.901 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-26T17:55:41.901Z,1682531741.901 [Default:CheckIn](INFO): Running loop #6
2023-04-26T17:55:41.901Z,1682531741.901 [Default:CheckIn] Running Loop=6
2023-04-26T17:55:41.901Z,1682531741.901 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-26T17:55:41.901Z,1682531741.901 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-26T17:55:43.916Z,1682531743.916 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175546.00,A,3648.25432,N,12147.13870,W,4.568,199.74,260423,,,D*76
2023-04-26T17:55:43.918Z,1682531743.918 [NAL9602](INFO): GPS fix at 20230426T175546: (36.804239, -121.785645)
2023-04-26T17:55:43.930Z,1682531743.930 [Default:CheckIn:Read_GPS] Stopped
2023-04-26T17:55:43.931Z,1682531743.931 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-26T17:55:51.148Z,1682531751.148 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230426T173810/Courier0009.lzma
2023-04-26T17:55:52.150Z,1682531752.150 [DataOverHttps](INFO): Moved sent file to Logs/20230426T173810/Courier0009.lzma.bak
2023-04-26T17:55:52.151Z,1682531752.151 [DataOverHttps](INFO): SBD MOMSN=18269321
2023-04-26T17:56:07.860Z,1682531767.860 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230426T173810/Express0010.lzma
2023-04-26T17:56:08.862Z,1682531768.862 [DataOverHttps](INFO): Moved sent file to Logs/20230426T173810/Express0010.lzma.bak
2023-04-26T17:56:08.863Z,1682531768.863 [DataOverHttps](INFO): SBD MOMSN=18269327
2023-04-26T17:56:10.203Z,1682531770.203 [Default:CheckIn:Read_Iridium] Stopped
2023-04-26T17:56:10.203Z,1682531770.203 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-26T17:56:10.203Z,1682531770.203 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-26T17:57:11.578Z,1682531831.578 [NAL9602](INFO): SBD MO Status=0, MOMSN=11806, MT Status=0, MTMSN=0
2023-04-26T17:57:11.578Z,1682531831.578 [NAL9602](INFO): No messages in MT queue
2023-04-26T17:57:42.282Z,1682531862.282 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-26T18:01:10.762Z,1682532070.762 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-26T18:01:10.762Z,1682532070.762 [Default:CheckIn:C.Wait] Stopped
2023-04-26T18:01:10.762Z,1682532070.762 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-26T18:01:10.762Z,1682532070.762 [Default:CheckIn:D] Running Loop=1
2023-04-26T18:01:11.165Z,1682532071.165 [Default:CheckIn:D] Stopped
2023-04-26T18:01:11.165Z,1682532071.165 [Default:CheckIn:E] Running Loop=1
2023-04-26T18:01:11.570Z,1682532071.570 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 38.929513 min 
2023-04-26T18:01:11.570Z,1682532071.570 [Default:CheckIn:E] Stopped
2023-04-26T18:01:11.570Z,1682532071.570 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-26T18:01:11.570Z,1682532071.570 [Default:CheckIn] Stopped
2023-04-26T18:01:11.570Z,1682532071.570 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-26T18:01:11.570Z,1682532071.570 [Default:CheckIn](INFO): Running loop #7
2023-04-26T18:01:11.570Z,1682532071.570 [Default:CheckIn] Running Loop=7
2023-04-26T18:01:11.570Z,1682532071.570 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-26T18:01:11.571Z,1682532071.571 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-26T18:01:13.575Z,1682532073.575 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180115.00,A,3648.14695,N,12147.22377,W,0.039,203.12,260423,,,D*78
2023-04-26T18:01:13.578Z,1682532073.578 [NAL9602](INFO): GPS fix at 20230426T180115: (36.802449, -121.787063)
2023-04-26T18:01:13.606Z,1682532073.606 [Default:CheckIn:Read_GPS] Stopped
2023-04-26T18:01:13.606Z,1682532073.606 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-26T18:01:21.104Z,1682532081.104 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230426T173810/Courier0012.lzma
2023-04-26T18:01:22.106Z,1682532082.106 [DataOverHttps](INFO): Moved sent file to Logs/20230426T173810/Courier0012.lzma.bak
2023-04-26T18:01:22.107Z,1682532082.107 [DataOverHttps](INFO): SBD MOMSN=18269337
2023-04-26T18:01:30.951Z,1682532090.951 [NAL9602](INFO): SBD MO Status=0, MOMSN=11807, MT Status=0, MTMSN=0
2023-04-26T18:01:30.951Z,1682532090.951 [NAL9602](INFO): No messages in MT queue
2023-04-26T18:01:37.880Z,1682532097.880 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230426T173810/Express0013.lzma
2023-04-26T18:01:38.883Z,1682532098.883 [DataOverHttps](INFO): Moved sent file to Logs/20230426T173810/Express0013.lzma.bak
2023-04-26T18:01:38.883Z,1682532098.883 [DataOverHttps](INFO): SBD MOMSN=18269343
2023-04-26T18:01:40.256Z,1682532100.256 [Default:CheckIn:Read_Iridium] Stopped
2023-04-26T18:01:40.256Z,1682532100.256 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-26T18:01:40.257Z,1682532100.257 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-26T18:02:01.648Z,1682532121.648 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-26T18:06:40.869Z,1682532400.869 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-26T18:06:40.869Z,1682532400.869 [Default:CheckIn:C.Wait] Stopped
2023-04-26T18:06:40.869Z,1682532400.869 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-26T18:06:40.870Z,1682532400.870 [Default:CheckIn:D] Running Loop=1
2023-04-26T18:06:41.260Z,1682532401.260 [Default:CheckIn:D] Stopped
2023-04-26T18:06:41.260Z,1682532401.260 [Default:CheckIn:E] Running Loop=1
2023-04-26T18:06:41.666Z,1682532401.666 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 44.431091 min 
2023-04-26T18:06:41.666Z,1682532401.666 [Default:CheckIn:E] Stopped
2023-04-26T18:06:41.666Z,1682532401.666 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-26T18:06:41.666Z,1682532401.666 [Default:CheckIn] Stopped
2023-04-26T18:06:41.667Z,1682532401.667 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-26T18:06:41.667Z,1682532401.667 [Default:CheckIn](INFO): Running loop #8
2023-04-26T18:06:41.667Z,1682532401.667 [Default:CheckIn] Running Loop=8
2023-04-26T18:06:41.667Z,1682532401.667 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-26T18:06:41.667Z,1682532401.667 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-26T18:06:43.679Z,1682532403.679 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180646.00,A,3648.14812,N,12147.21631,W,0.097,0.00,260423,,,D*7A
2023-04-26T18:06:43.682Z,1682532403.682 [NAL9602](INFO): GPS fix at 20230426T180646: (36.802469, -121.786939)
2023-04-26T18:06:43.693Z,1682532403.693 [Default:CheckIn:Read_GPS] Stopped
2023-04-26T18:06:43.694Z,1682532403.694 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-26T18:06:50.992Z,1682532410.992 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230426T173810/Courier0015.lzma
2023-04-26T18:06:51.994Z,1682532411.994 [DataOverHttps](INFO): Moved sent file to Logs/20230426T173810/Courier0015.lzma.bak
2023-04-26T18:06:51.995Z,1682532411.995 [DataOverHttps](INFO): SBD MOMSN=18269352
2023-04-26T18:07:06.299Z,1682532426.299 [NAL9602](INFO): SBD MO Status=2, MOMSN=11808, MT Status=2, MTMSN=0
2023-04-26T18:07:06.300Z,1682532426.300 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-04-26T18:07:08.200Z,1682532428.200 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230426T173810/Express0016.lzma
2023-04-26T18:07:09.202Z,1682532429.202 [DataOverHttps](INFO): Moved sent file to Logs/20230426T173810/Express0016.lzma.bak
2023-04-26T18:07:09.203Z,1682532429.203 [DataOverHttps](INFO): SBD MOMSN=18269356
2023-04-26T18:07:10.778Z,1682532430.778 [Default:CheckIn:Read_Iridium] Stopped
2023-04-26T18:07:10.778Z,1682532430.778 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-26T18:07:10.778Z,1682532430.778 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-26T18:07:43.875Z,1682532463.875 [NAL9602](INFO): SBD MO Status=0, MOMSN=11808, MT Status=0, MTMSN=0
2023-04-26T18:07:43.875Z,1682532463.875 [NAL9602](INFO): No messages in MT queue
2023-04-26T18:08:14.577Z,1682532494.577 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-26T18:12:11.332Z,1682532731.332 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-26T18:12:11.332Z,1682532731.332 [Default:CheckIn:C.Wait] Stopped
2023-04-26T18:12:11.333Z,1682532731.333 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-26T18:12:11.333Z,1682532731.333 [Default:CheckIn:D] Running Loop=1
2023-04-26T18:12:11.747Z,1682532731.747 [Default:CheckIn:D] Stopped
2023-04-26T18:12:11.747Z,1682532731.747 [Default:CheckIn:E] Running Loop=1
2023-04-26T18:12:12.140Z,1682532732.140 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 49.939221 min 
2023-04-26T18:12:12.140Z,1682532732.140 [Default:CheckIn:E] Stopped
2023-04-26T18:12:12.164Z,1682532732.164 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-26T18:12:12.164Z,1682532732.164 [Default:CheckIn] Stopped
2023-04-26T18:12:12.164Z,1682532732.164 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-26T18:12:12.164Z,1682532732.164 [Default:CheckIn](INFO): Running loop #9
2023-04-26T18:12:12.165Z,1682532732.165 [Default:CheckIn] Running Loop=9
2023-04-26T18:12:12.165Z,1682532732.165 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-26T18:12:12.165Z,1682532732.165 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-26T18:12:14.155Z,1682532734.155 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181216.00,A,3648.14628,N,12147.22588,W,1.186,206.14,260423,,,D*7E
2023-04-26T18:12:14.158Z,1682532734.158 [NAL9602](INFO): GPS fix at 20230426T181216: (36.802438, -121.787098)
2023-04-26T18:12:14.170Z,1682532734.170 [Default:CheckIn:Read_GPS] Stopped
2023-04-26T18:12:14.170Z,1682532734.170 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-26T18:12:21.404Z,1682532741.404 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230426T173810/Courier0018.lzma
2023-04-26T18:12:22.407Z,1682532742.407 [DataOverHttps](INFO): Moved sent file to Logs/20230426T173810/Courier0018.lzma.bak
2023-04-26T18:12:22.407Z,1682532742.407 [DataOverHttps](INFO): SBD MOMSN=18269376
2023-04-26T18:12:38.109Z,1682532758.109 [DataOverHttps](INFO): Sending 127 bytes from file Logs/20230426T173810/Express0019.lzma
2023-04-26T18:12:39.110Z,1682532759.110 [DataOverHttps](INFO): Moved sent file to Logs/20230426T173810/Express0019.lzma.bak
2023-04-26T18:12:39.110Z,1682532759.110 [DataOverHttps](INFO): SBD MOMSN=18269382
2023-04-26T18:12:40.431Z,1682532760.431 [Default:CheckIn:Read_Iridium] Stopped
2023-04-26T18:12:40.431Z,1682532760.431 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-26T18:12:40.431Z,1682532760.431 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-26T18:13:11.119Z,1682532791.119 [NAL9602](INFO): SBD MO Status=2, MOMSN=11809, MT Status=2, MTMSN=0
2023-04-26T18:13:11.120Z,1682532791.120 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-04-26T18:14:28.279Z,1682532868.279 [NAL9602](INFO): SBD MO Status=2, MOMSN=11809, MT Status=2, MTMSN=0
2023-04-26T18:14:28.279Z,1682532868.279 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-04-26T18:15:11.911Z,1682532911.911 [NAL9602](INFO): SBD MO Status=0, MOMSN=11809, MT Status=0, MTMSN=0
2023-04-26T18:15:11.911Z,1682532911.911 [NAL9602](INFO): No messages in MT queue
2023-04-26T18:15:42.614Z,1682532942.614 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-26T18:17:40.996Z,1682533060.996 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-26T18:17:40.996Z,1682533060.996 [Default:CheckIn:C.Wait] Stopped
2023-04-26T18:17:40.996Z,1682533060.996 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-26T18:17:40.996Z,1682533060.996 [Default:CheckIn:D] Running Loop=1
2023-04-26T18:17:41.407Z,1682533061.407 [Default:CheckIn:D] Stopped
2023-04-26T18:17:41.407Z,1682533061.407 [Default:CheckIn:E] Running Loop=1
2023-04-26T18:17:41.809Z,1682533061.809 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 55.433549 min 
2023-04-26T18:17:41.809Z,1682533061.809 [Default:CheckIn:E] Stopped
2023-04-26T18:17:41.810Z,1682533061.810 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-26T18:17:41.810Z,1682533061.810 [Default:CheckIn] Stopped
2023-04-26T18:17:41.810Z,1682533061.810 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-26T18:17:41.810Z,1682533061.810 [Default:CheckIn](INFO): Running loop #10
2023-04-26T18:17:41.810Z,1682533061.810 [Default:CheckIn] Running Loop=10
2023-04-26T18:17:41.810Z,1682533061.810 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-26T18:17:41.810Z,1682533061.810 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-26T18:17:43.820Z,1682533063.820 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181746.00,A,3648.17591,N,12147.28066,W,0.292,250.09,260423,,,D*7B
2023-04-26T18:17:43.831Z,1682533063.831 [NAL9602](INFO): GPS fix at 20230426T181746: (36.802932, -121.788011)
2023-04-26T18:17:43.843Z,1682533063.843 [Default:CheckIn:Read_GPS] Stopped
2023-04-26T18:17:43.843Z,1682533063.843 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-26T18:17:50.672Z,1682533070.672 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230426T173810/Courier0021.lzma
2023-04-26T18:17:51.674Z,1682533071.674 [DataOverHttps](INFO): Moved sent file to Logs/20230426T173810/Courier0021.lzma.bak
2023-04-26T18:17:51.675Z,1682533071.675 [DataOverHttps](INFO): SBD MOMSN=18269405
2023-04-26T18:18:07.368Z,1682533087.368 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230426T173810/Express0022.lzma
2023-04-26T18:18:08.370Z,1682533088.370 [DataOverHttps](INFO): Moved sent file to Logs/20230426T173810/Express0022.lzma.bak
2023-04-26T18:18:08.371Z,1682533088.371 [DataOverHttps](INFO): SBD MOMSN=18269411
2023-04-26T18:18:09.694Z,1682533089.694 [Default:CheckIn:Read_Iridium] Stopped
2023-04-26T18:18:09.694Z,1682533089.694 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-26T18:18:09.694Z,1682533089.694 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-26T18:22:46.005Z,1682533366.005 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-04-26T18:23:10.256Z,1682533390.256 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-26T18:23:10.257Z,1682533390.257 [Default:CheckIn:C.Wait] Stopped
2023-04-26T18:23:10.257Z,1682533390.257 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-26T18:23:10.257Z,1682533390.257 [Default:CheckIn:D] Running Loop=1
2023-04-26T18:23:10.676Z,1682533390.676 [Default:CheckIn:D] Stopped
2023-04-26T18:23:10.676Z,1682533390.676 [Default:CheckIn:E] Running Loop=1
2023-04-26T18:23:11.069Z,1682533391.069 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 60.921362 min 
2023-04-26T18:23:11.069Z,1682533391.069 [Default:CheckIn:E] Stopped
2023-04-26T18:23:11.069Z,1682533391.069 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-26T18:23:11.069Z,1682533391.069 [Default:CheckIn] Stopped
2023-04-26T18:23:11.070Z,1682533391.070 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-26T18:23:11.070Z,1682533391.070 [Default:CheckIn](INFO): Running loop #11
2023-04-26T18:23:11.070Z,1682533391.070 [Default:CheckIn] Running Loop=11
2023-04-26T18:23:11.070Z,1682533391.070 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-26T18:23:11.070Z,1682533391.070 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-26T18:23:13.080Z,1682533393.080 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182315.00,A,3648.16816,N,12147.28310,W,0.525,250.09,260423,,,D*70
2023-04-26T18:23:13.082Z,1682533393.082 [NAL9602](INFO): GPS fix at 20230426T182315: (36.802803, -121.788052)
2023-04-26T18:23:13.094Z,1682533393.094 [Default:CheckIn:Read_GPS] Stopped
2023-04-26T18:23:13.095Z,1682533393.095 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-26T18:23:20.412Z,1682533400.412 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230426T173810/Courier0024.lzma
2023-04-26T18:23:21.415Z,1682533401.415 [DataOverHttps](INFO): Moved sent file to Logs/20230426T173810/Courier0024.lzma.bak
2023-04-26T18:23:21.415Z,1682533401.415 [DataOverHttps](INFO): SBD MOMSN=18269426
2023-04-26T18:23:37.352Z,1682533417.352 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20230426T173810/Express0025.lzma
2023-04-26T18:23:38.355Z,1682533418.355 [DataOverHttps](INFO): Moved sent file to Logs/20230426T173810/Express0025.lzma.bak
2023-04-26T18:23:38.355Z,1682533418.355 [DataOverHttps](INFO): SBD MOMSN=18269432
2023-04-26T18:23:39.755Z,1682533419.755 [Default:CheckIn:Read_Iridium] Stopped
2023-04-26T18:23:39.755Z,1682533419.755 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-26T18:23:39.755Z,1682533419.755 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-26T18:23:45.402Z,1682533425.402 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-26T18:28:40.339Z,1682533720.339 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-26T18:28:40.339Z,1682533720.339 [Default:CheckIn:C.Wait] Stopped
2023-04-26T18:28:40.339Z,1682533720.339 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-26T18:28:40.340Z,1682533720.340 [Default:CheckIn:D] Running Loop=1
2023-04-26T18:28:40.733Z,1682533720.733 [Default:CheckIn:D] Stopped
2023-04-26T18:28:40.733Z,1682533720.733 [Default:CheckIn:E] Running Loop=1
2023-04-26T18:28:41.132Z,1682533721.132 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 66.422314 min 
2023-04-26T18:28:41.132Z,1682533721.132 [Default:CheckIn:E] Stopped
2023-04-26T18:28:41.132Z,1682533721.132 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-26T18:28:41.132Z,1682533721.132 [Default:CheckIn] Stopped
2023-04-26T18:28:41.133Z,1682533721.133 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-26T18:28:41.133Z,1682533721.133 [Default:CheckIn](INFO): Running loop #12
2023-04-26T18:28:41.133Z,1682533721.133 [Default:CheckIn] Running Loop=12
2023-04-26T18:28:41.133Z,1682533721.133 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-26T18:28:41.133Z,1682533721.133 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-26T18:28:43.151Z,1682533723.151 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182845.00,A,3648.17237,N,12147.28250,W,0.389,250.09,260423,,,A*76
2023-04-26T18:28:43.154Z,1682533723.154 [NAL9602](INFO): GPS fix at 20230426T182845: (36.802873, -121.788042)
2023-04-26T18:28:43.166Z,1682533723.166 [Default:CheckIn:Read_GPS] Stopped
2023-04-26T18:28:43.166Z,1682533723.166 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-26T18:28:50.528Z,1682533730.528 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230426T173810/Courier0027.lzma
2023-04-26T18:28:51.530Z,1682533731.530 [DataOverHttps](INFO): Moved sent file to Logs/20230426T173810/Courier0027.lzma.bak
2023-04-26T18:28:51.531Z,1682533731.531 [DataOverHttps](INFO): SBD MOMSN=18269444
2023-04-26T18:29:07.648Z,1682533747.648 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230426T173810/Express0028.lzma
2023-04-26T18:29:08.650Z,1682533748.650 [DataOverHttps](INFO): Moved sent file to Logs/20230426T173810/Express0028.lzma.bak
2023-04-26T18:29:08.651Z,1682533748.651 [DataOverHttps](INFO): SBD MOMSN=18269450
2023-04-26T18:29:10.220Z,1682533750.220 [Default:CheckIn:Read_Iridium] Stopped
2023-04-26T18:29:10.220Z,1682533750.220 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-26T18:29:10.220Z,1682533750.220 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-26T18:29:13.846Z,1682533753.846 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-04-26T18:29:13.929Z,1682533753.929 [NAL9602](ERROR): received: 
+CSQ:0

OK809, 0, 0, 0, 0

OK
2023-04-26T18:33:45.340Z,1682534025.340 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-04-26T18:34:10.803Z,1682534050.803 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-26T18:34:10.803Z,1682534050.803 [Default:CheckIn:C.Wait] Stopped
2023-04-26T18:34:10.803Z,1682534050.803 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-26T18:34:10.804Z,1682534050.804 [Default:CheckIn:D] Running Loop=1
2023-04-26T18:34:11.213Z,1682534051.213 [Default:CheckIn:D] Stopped
2023-04-26T18:34:11.213Z,1682534051.213 [Default:CheckIn:E] Running Loop=1
2023-04-26T18:34:11.623Z,1682534051.623 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 71.930322 min 
2023-04-26T18:34:11.623Z,1682534051.623 [Default:CheckIn:E] Stopped
2023-04-26T18:34:11.623Z,1682534051.623 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-26T18:34:11.623Z,1682534051.623 [Default:CheckIn] Stopped
2023-04-26T18:34:11.624Z,1682534051.624 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-26T18:34:11.624Z,1682534051.624 [Default:CheckIn](INFO): Running loop #13
2023-04-26T18:34:11.624Z,1682534051.624 [Default:CheckIn] Running Loop=13
2023-04-26T18:34:11.624Z,1682534051.624 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-26T18:34:11.624Z,1682534051.624 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-26T18:34:13.622Z,1682534053.622 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183416.00,A,3648.16864,N,12147.27862,W,0.330,0.00,260423,,,A*78
2023-04-26T18:34:13.625Z,1682534053.625 [NAL9602](INFO): GPS fix at 20230426T183416: (36.802811, -121.787977)
2023-04-26T18:34:13.661Z,1682534053.661 [Default:CheckIn:Read_GPS] Stopped
2023-04-26T18:34:13.662Z,1682534053.662 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-26T18:34:21.029Z,1682534061.029 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230426T173810/Courier0030.lzma
2023-04-26T18:34:22.030Z,1682534062.030 [DataOverHttps](INFO): Moved sent file to Logs/20230426T173810/Courier0030.lzma.bak
2023-04-26T18:34:22.031Z,1682534062.031 [DataOverHttps](INFO): SBD MOMSN=18269458
2023-04-26T18:34:37.800Z,1682534077.800 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20230426T173810/Express0031.lzma
2023-04-26T18:34:38.802Z,1682534078.802 [DataOverHttps](INFO): Moved sent file to Logs/20230426T173810/Express0031.lzma.bak
2023-04-26T18:34:38.803Z,1682534078.803 [DataOverHttps](INFO): SBD MOMSN=18269465
2023-04-26T18:34:40.304Z,1682534080.304 [Default:CheckIn:Read_Iridium] Stopped
2023-04-26T18:34:40.304Z,1682534080.304 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-26T18:34:40.304Z,1682534080.304 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-26T18:34:46.345Z,1682534086.345 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-26T18:39:40.873Z,1682534380.873 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-26T18:39:40.873Z,1682534380.873 [Default:CheckIn:C.Wait] Stopped
2023-04-26T18:39:40.873Z,1682534380.873 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-26T18:39:40.874Z,1682534380.874 [Default:CheckIn:D] Running Loop=1
2023-04-26T18:39:41.289Z,1682534381.289 [Default:CheckIn:D] Stopped
2023-04-26T18:39:41.289Z,1682534381.289 [Default:CheckIn:E] Running Loop=1
2023-04-26T18:39:41.685Z,1682534381.685 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 77.431576 min 
2023-04-26T18:39:41.685Z,1682534381.685 [Default:CheckIn:E] Stopped
2023-04-26T18:39:41.685Z,1682534381.685 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-26T18:39:41.685Z,1682534381.685 [Default:CheckIn] Stopped
2023-04-26T18:39:41.686Z,1682534381.686 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-26T18:39:41.686Z,1682534381.686 [Default:CheckIn](INFO): Running loop #14
2023-04-26T18:39:41.686Z,1682534381.686 [Default:CheckIn] Running Loop=14
2023-04-26T18:39:41.686Z,1682534381.686 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-26T18:39:41.686Z,1682534381.686 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-26T18:39:43.702Z,1682534383.702 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183946.00,A,3648.16953,N,12147.28084,W,0.097,0.00,260423,,,A*74
2023-04-26T18:39:43.705Z,1682534383.705 [NAL9602](INFO): GPS fix at 20230426T183946: (36.802825, -121.788014)
2023-04-26T18:39:43.717Z,1682534383.717 [Default:CheckIn:Read_GPS] Stopped
2023-04-26T18:39:43.717Z,1682534383.717 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-26T18:39:50.604Z,1682534390.604 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230426T173810/Courier0033.lzma
2023-04-26T18:39:51.606Z,1682534391.606 [DataOverHttps](INFO): Moved sent file to Logs/20230426T173810/Courier0033.lzma.bak
2023-04-26T18:39:51.607Z,1682534391.607 [DataOverHttps](INFO): SBD MOMSN=18269478
2023-04-26T18:40:07.268Z,1682534407.268 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230426T173810/Express0034.lzma
2023-04-26T18:40:08.270Z,1682534408.270 [DataOverHttps](INFO): Moved sent file to Logs/20230426T173810/Express0034.lzma.bak
2023-04-26T18:40:08.271Z,1682534408.271 [DataOverHttps](INFO): SBD MOMSN=18269485
2023-04-26T18:40:09.572Z,1682534409.572 [Default:CheckIn:Read_Iridium] Stopped
2023-04-26T18:40:09.572Z,1682534409.572 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-26T18:40:09.572Z,1682534409.572 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-26T18:44:46.286Z,1682534686.286 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-04-26T18:45:10.137Z,1682534710.137 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-26T18:45:10.137Z,1682534710.137 [Default:CheckIn:C.Wait] Stopped
2023-04-26T18:45:10.137Z,1682534710.137 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-26T18:45:10.137Z,1682534710.137 [Default:CheckIn:D] Running Loop=1
2023-04-26T18:45:10.537Z,1682534710.537 [Default:CheckIn:D] Stopped
2023-04-26T18:45:10.538Z,1682534710.538 [Default:CheckIn:E] Running Loop=1
2023-04-26T18:45:10.953Z,1682534710.953 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 82.919059 min 
2023-04-26T18:45:10.953Z,1682534710.953 [Default:CheckIn:E] Stopped
2023-04-26T18:45:10.953Z,1682534710.953 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-26T18:45:10.953Z,1682534710.953 [Default:CheckIn] Stopped
2023-04-26T18:45:10.953Z,1682534710.953 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-26T18:45:10.953Z,1682534710.953 [Default:CheckIn](INFO): Running loop #15
2023-04-26T18:45:10.954Z,1682534710.954 [Default:CheckIn] Running Loop=15
2023-04-26T18:45:10.954Z,1682534710.954 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-26T18:45:10.954Z,1682534710.954 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-26T18:45:12.955Z,1682534712.955 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184515.00,A,3648.17102,N,12147.27852,W,0.330,0.00,260423,,,A*76
2023-04-26T18:45:12.958Z,1682534712.958 [NAL9602](INFO): GPS fix at 20230426T184515: (36.802850, -121.787975)
2023-04-26T18:45:12.991Z,1682534712.991 [Default:CheckIn:Read_GPS] Stopped
2023-04-26T18:45:12.991Z,1682534712.991 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-26T18:45:20.580Z,1682534720.580 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230426T173810/Courier0036.lzma
2023-04-26T18:45:21.582Z,1682534721.582 [DataOverHttps](INFO): Moved sent file to Logs/20230426T173810/Courier0036.lzma.bak
2023-04-26T18:45:21.583Z,1682534721.583 [DataOverHttps](INFO): SBD MOMSN=18269502
2023-04-26T18:45:37.293Z,1682534737.293 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20230426T173810/Express0037.lzma
2023-04-26T18:45:38.295Z,1682534738.295 [DataOverHttps](INFO): Moved sent file to Logs/20230426T173810/Express0037.lzma.bak
2023-04-26T18:45:38.295Z,1682534738.295 [DataOverHttps](INFO): SBD MOMSN=18269508
2023-04-26T18:45:39.627Z,1682534739.627 [Default:CheckIn:Read_Iridium] Stopped
2023-04-26T18:45:39.627Z,1682534739.627 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-26T18:45:39.627Z,1682534739.627 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-26T18:45:45.268Z,1682534745.268 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-26T18:50:40.214Z,1682535040.214 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-26T18:50:40.214Z,1682535040.214 [Default:CheckIn:C.Wait] Stopped
2023-04-26T18:50:40.214Z,1682535040.214 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-26T18:50:40.215Z,1682535040.215 [Default:CheckIn:D] Running Loop=1
2023-04-26T18:50:40.611Z,1682535040.611 [Default:CheckIn:D] Stopped
2023-04-26T18:50:40.611Z,1682535040.611 [Default:CheckIn:E] Running Loop=1
2023-04-26T18:50:41.013Z,1682535041.013 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 88.420288 min 
2023-04-26T18:50:41.013Z,1682535041.013 [Default:CheckIn:E] Stopped
2023-04-26T18:50:41.013Z,1682535041.013 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-26T18:50:41.014Z,1682535041.014 [Default:CheckIn] Stopped
2023-04-26T18:50:41.014Z,1682535041.014 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-26T18:50:41.014Z,1682535041.014 [Default:CheckIn](INFO): Running loop #16
2023-04-26T18:50:41.014Z,1682535041.014 [Default:CheckIn] Running Loop=16
2023-04-26T18:50:41.014Z,1682535041.014 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-26T18:50:41.014Z,1682535041.014 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-26T18:50:43.020Z,1682535043.020 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185045.00,A,3648.16462,N,12147.28238,W,0.739,200.90,260423,,,A*7A
2023-04-26T18:50:43.022Z,1682535043.022 [NAL9602](INFO): GPS fix at 20230426T185045: (36.802744, -121.788040)
2023-04-26T18:50:43.034Z,1682535043.034 [Default:CheckIn:Read_GPS] Stopped
2023-04-26T18:50:43.034Z,1682535043.034 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-26T18:50:49.960Z,1682535049.960 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230426T173810/Courier0039.lzma
2023-04-26T18:50:50.963Z,1682535050.963 [DataOverHttps](INFO): Moved sent file to Logs/20230426T173810/Courier0039.lzma.bak
2023-04-26T18:50:50.963Z,1682535050.963 [DataOverHttps](INFO): SBD MOMSN=18269518
2023-04-26T18:51:06.672Z,1682535066.672 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20230426T173810/Express0040.lzma
2023-04-26T18:51:07.674Z,1682535067.674 [DataOverHttps](INFO): Moved sent file to Logs/20230426T173810/Express0040.lzma.bak
2023-04-26T18:51:07.675Z,1682535067.675 [DataOverHttps](INFO): SBD MOMSN=18269526
2023-04-26T18:51:09.310Z,1682535069.310 [Default:CheckIn:Read_Iridium] Stopped
2023-04-26T18:51:09.310Z,1682535069.310 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-26T18:51:09.310Z,1682535069.310 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-26T18:51:13.722Z,1682535073.722 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-04-26T18:51:13.797Z,1682535073.797 [NAL9602](ERROR): received: 
+CSQ:0

OK809, 0, 0, 0, 0

OK
2023-04-26T18:53:53.416Z,1682535233.416 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2023-04-26T18:54:06.637Z,1682535246.637 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-04-26T18:54:06.640Z,1682535246.640 [BPC1](INFO): Received data from all battery sticks.
2023-04-26T18:55:45.214Z,1682535345.214 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-04-26T18:56:09.867Z,1682535369.867 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-26T18:56:09.867Z,1682535369.867 [Default:CheckIn:C.Wait] Stopped
2023-04-26T18:56:09.868Z,1682535369.868 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-26T18:56:09.868Z,1682535369.868 [Default:CheckIn:D] Running Loop=1
2023-04-26T18:56:10.278Z,1682535370.278 [Default:CheckIn:D] Stopped
2023-04-26T18:56:10.278Z,1682535370.278 [Default:CheckIn:E] Running Loop=1
2023-04-26T18:56:10.674Z,1682535370.674 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 93.914722 min 
2023-04-26T18:56:10.675Z,1682535370.675 [Default:CheckIn:E] Stopped
2023-04-26T18:56:10.675Z,1682535370.675 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-26T18:56:10.675Z,1682535370.675 [Default:CheckIn] Stopped
2023-04-26T18:56:10.675Z,1682535370.675 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-26T18:56:10.675Z,1682535370.675 [Default:CheckIn](INFO): Running loop #17
2023-04-26T18:56:10.675Z,1682535370.675 [Default:CheckIn] Running Loop=17
2023-04-26T18:56:10.675Z,1682535370.675 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-26T18:56:10.675Z,1682535370.675 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-26T18:56:12.688Z,1682535372.688 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185615.00,A,3648.16541,N,12147.28464,W,0.233,200.90,260423,,,A*79
2023-04-26T18:56:12.690Z,1682535372.690 [NAL9602](INFO): GPS fix at 20230426T185615: (36.802757, -121.788077)
2023-04-26T18:56:12.702Z,1682535372.702 [Default:CheckIn:Read_GPS] Stopped
2023-04-26T18:56:12.702Z,1682535372.702 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-26T18:56:19.584Z,1682535379.584 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20230426T173810/Courier0042.lzma
2023-04-26T18:56:20.586Z,1682535380.586 [DataOverHttps](INFO): Moved sent file to Logs/20230426T173810/Courier0042.lzma.bak
2023-04-26T18:56:20.587Z,1682535380.587 [DataOverHttps](INFO): SBD MOMSN=18269535
2023-04-26T18:56:36.500Z,1682535396.500 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20230426T173810/Express0043.lzma
2023-04-26T18:56:37.503Z,1682535397.503 [DataOverHttps](INFO): Moved sent file to Logs/20230426T173810/Express0043.lzma.bak
2023-04-26T18:56:37.503Z,1682535397.503 [DataOverHttps](INFO): SBD MOMSN=18269541
2023-04-26T18:56:38.970Z,1682535398.970 [Default:CheckIn:Read_Iridium] Stopped
2023-04-26T18:56:38.970Z,1682535398.970 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-26T18:56:38.971Z,1682535398.971 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-26T18:56:45.410Z,1682535405.410 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-26T19:01:39.544Z,1682535699.544 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-26T19:01:39.544Z,1682535699.544 [Default:CheckIn:C.Wait] Stopped
2023-04-26T19:01:39.544Z,1682535699.544 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-26T19:01:39.549Z,1682535699.549 [Default:CheckIn:D] Running Loop=1
2023-04-26T19:01:39.962Z,1682535699.962 [Default:CheckIn:D] Stopped
2023-04-26T19:01:39.962Z,1682535699.962 [Default:CheckIn:E] Running Loop=1
2023-04-26T19:01:40.345Z,1682535700.345 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 99.409465 min 
2023-04-26T19:01:40.345Z,1682535700.345 [Default:CheckIn:E] Stopped
2023-04-26T19:01:40.345Z,1682535700.345 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-26T19:01:40.345Z,1682535700.345 [Default:CheckIn] Stopped
2023-04-26T19:01:40.346Z,1682535700.346 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-26T19:01:40.346Z,1682535700.346 [Default:CheckIn](INFO): Running loop #18
2023-04-26T19:01:40.346Z,1682535700.346 [Default:CheckIn] Running Loop=18
2023-04-26T19:01:40.346Z,1682535700.346 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-26T19:01:40.346Z,1682535700.346 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-26T19:02:13.048Z,1682535733.048 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-04-26T19:02:13.121Z,1682535733.121 [NAL9602](ERROR): received: 
+CSQ:0

OK809, 0, 0, 0, 0

OK
2023-04-26T19:04:17.853Z,1682535857.853 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.239246
2023-04-26T19:04:48.977Z,1682535888.977 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-26T19:05:37.853Z,1682535937.853 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002999
2023-04-26T19:06:35.077Z,1682535995.077 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-26T19:06:38.089Z,1682535998.089 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.239456
2023-04-26T19:06:40.543Z,1682536000.543 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-04-26T19:01:40.3Z
2023-04-26T19:06:40.543Z,1682536000.543 [Default:CheckIn:Read_GPS] Stopped
2023-04-26T19:06:40.549Z,1682536000.549 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-26T19:06:45.354Z,1682536005.354 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-04-26T19:07:10.217Z,1682536030.217 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-26T19:07:45.357Z,1682536065.357 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-26T19:08:20.497Z,1682536100.497 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-26T19:08:55.677Z,1682536135.677 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-26T19:09:30.817Z,1682536170.817 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-26T19:09:51.485Z,1682536191.485 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2023-04-26T19:09:51.486Z,1682536191.486 [DropWeight] Hardware Fault, FailCount= 1
2023-04-26T19:09:51.486Z,1682536191.486 [DropWeight](ERROR): Hardware Fault
2023-04-26T19:09:51.563Z,1682536191.563 [CommandExec](FAULT): Scheduling is paused for 1 commands
2023-04-26T19:09:51.563Z,1682536191.563 [CBIT](INFO): Critical error at 20230426T190951
2023-04-26T19:09:51.563Z,1682536191.563 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2023-04-26T19:09:51.566Z,1682536191.566 [CBIT](ERROR): Hardware Fault in component: DropWeight
2023-04-26T19:09:51.566Z,1682536191.566 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2023-04-26T19:09:51.902Z,1682536191.902 [CBIT](INFO): Critical error at 20230426T190951
2023-04-26T19:10:05.957Z,1682536205.957 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-26T19:10:41.109Z,1682536241.109 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-26T19:11:16.249Z,1682536276.249 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-26T19:11:51.389Z,1682536311.389 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-26T19:12:03.877Z,1682536323.877 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003453
2023-04-26T19:12:11.148Z,1682536331.148 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230426T173810/Courier0045.lzma
2023-04-26T19:12:12.150Z,1682536332.150 [DataOverHttps](INFO): Moved sent file to Logs/20230426T173810/Courier0045.lzma.bak
2023-04-26T19:12:12.151Z,1682536332.151 [DataOverHttps](INFO): SBD MOMSN=18269588
2023-04-26T19:12:28.120Z,1682536348.120 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230426T173810/Express0046.lzma
2023-04-26T19:12:29.123Z,1682536349.123 [DataOverHttps](INFO): Moved sent file to Logs/20230426T173810/Express0046.lzma.bak
2023-04-26T19:12:29.123Z,1682536349.123 [DataOverHttps](INFO): SBD MOMSN=18269590
2023-04-26T19:12:30.670Z,1682536350.670 [Default:CheckIn:Read_Iridium] Stopped
2023-04-26T19:12:30.670Z,1682536350.670 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-26T19:12:30.670Z,1682536350.670 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-26T19:12:34.686Z,1682536354.686 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-26T19:17:31.237Z,1682536651.237 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-26T19:17:31.237Z,1682536651.237 [Default:CheckIn:C.Wait] Stopped
2023-04-26T19:17:31.237Z,1682536651.237 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-26T19:17:31.237Z,1682536651.237 [Default:CheckIn:D] Running Loop=1
2023-04-26T19:17:31.635Z,1682536651.635 [Default:CheckIn:D] Stopped
2023-04-26T19:17:31.636Z,1682536651.636 [Default:CheckIn:E] Running Loop=1
2023-04-26T19:17:32.046Z,1682536652.046 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 115.270687 min 
2023-04-26T19:17:32.046Z,1682536652.046 [Default:CheckIn:E] Stopped
2023-04-26T19:17:32.046Z,1682536652.046 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-26T19:17:32.046Z,1682536652.046 [Default:CheckIn] Stopped
2023-04-26T19:17:32.046Z,1682536652.046 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-26T19:17:32.046Z,1682536652.046 [Default:CheckIn](INFO): Running loop #19
2023-04-26T19:17:32.047Z,1682536652.047 [Default:CheckIn] Running Loop=19
2023-04-26T19:17:32.047Z,1682536652.047 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-26T19:17:32.047Z,1682536652.047 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-26T19:17:33.650Z,1682536653.650 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-04-26T19:17:33.650Z,1682536653.650 [NAL9602] Data Fault, FailCount= 1
2023-04-26T19:17:33.650Z,1682536653.650 [NAL9602](ERROR): Data Fault
2023-04-26T19:17:33.672Z,1682536653.672 [CBIT](ERROR): Data Fault in component: NAL9602
2023-04-26T19:17:34.050Z,1682536654.050 [NAL9602](INFO): Powering down
2023-04-26T19:17:34.876Z,1682536654.876 [CBIT](INFO): Clearing failed state for component NAL9602
2023-04-26T19:17:34.876Z,1682536654.876 [NAL9602] No Fault, FailCount= 1
2023-04-26T19:18:04.353Z,1682536684.353 [NAL9602](INFO): Powering up NAL9602
2023-04-26T19:18:15.262Z,1682536695.262 [NAL9602](INFO): NAL9602 initialized
2023-04-26T19:20:00.541Z,1682536800.541 [CommandExec](IMPORTANT): got command quit
2023-04-26T19:20:01.545Z,1682536801.545 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-26T19:20:01.545Z,1682536801.545 [CommandExec](INFO): Uninitializing the command executive.
2023-04-26T19:20:01.545Z,1682536801.545 [CommandExec](INFO): Uninitializing the command scheduler.
2023-04-26T19:20:01.545Z,1682536801.545 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-04-26T19:20:01.572Z,1682536801.572 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-04-26T19:20:01.573Z,1682536801.573 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-04-26T19:20:01.573Z,1682536801.573 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-04-26T19:20:01.573Z,1682536801.573 [NavChartDb](INFO): Join timeout helper Thread ID is 9443
2023-04-26T19:20:01.813Z,1682536801.813 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-26T19:20:01.813Z,1682536801.813 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-04-26T19:20:01.817Z,1682536801.817 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-04-26T19:20:01.817Z,1682536801.817 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-04-26T19:20:01.817Z,1682536801.817 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 9444
2023-04-26T19:20:02.005Z,1682536802.005 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-26T19:20:02.005Z,1682536802.005 [WetLabsBB2FL](INFO): Powering down
2023-04-26T19:20:02.006Z,1682536802.006 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-04-26T19:20:02.021Z,1682536802.021 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-04-26T19:20:02.021Z,1682536802.021 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-04-26T19:20:02.021Z,1682536802.021 [CTD_Seabird](INFO): Join timeout helper Thread ID is 9445
2023-04-26T19:20:02.433Z,1682536802.433 [CTD_Seabird](INFO): Powering down
2023-04-26T19:20:02.445Z,1682536802.445 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-26T19:20:02.445Z,1682536802.445 [CTD_Seabird](INFO): Powering down
2023-04-26T19:20:02.458Z,1682536802.458 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-04-26T19:20:02.477Z,1682536802.477 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-04-26T19:20:02.477Z,1682536802.477 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-04-26T19:20:02.477Z,1682536802.477 [Radio_Surface](INFO): Join timeout helper Thread ID is 9446
2023-04-26T19:20:02.565Z,1682536802.565 [Radio_Surface](INFO): Powering down
2023-04-26T19:20:02.565Z,1682536802.565 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-26T19:20:02.566Z,1682536802.566 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-04-26T19:20:02.577Z,1682536802.577 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-04-26T19:20:02.577Z,1682536802.577 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-04-26T19:20:02.577Z,1682536802.577 [Onboard](INFO): Join timeout helper Thread ID is 9447
2023-04-26T19:20:03.477Z,1682536803.477 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2023-04-26T19:20:05.653Z,1682536805.653 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-26T19:20:05.653Z,1682536805.653 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-04-26T19:20:05.679Z,1682536805.679 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-04-26T19:20:05.679Z,1682536805.679 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-04-26T19:20:05.680Z,1682536805.680 [DataOverHttps](INFO): Join timeout helper Thread ID is 9448
2023-04-26T19:20:06.489Z,1682536806.489 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-26T19:20:06.489Z,1682536806.489 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-04-26T19:20:06.493Z,1682536806.493 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2023-04-26T19:20:06.493Z,1682536806.493 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-04-26T19:20:06.494Z,1682536806.494 [BackseatComponent](INFO): Join timeout helper Thread ID is 9449
2023-04-26T19:20:06.609Z,1682536806.609 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-26T19:20:06.609Z,1682536806.609 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-04-26T19:20:06.621Z,1682536806.621 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-04-26T19:20:06.621Z,1682536806.621 [logger ThreadHandler](INFO): Thread cancelled.
2023-04-26T19:20:06.622Z,1682536806.622 [logger](INFO): Join timeout helper Thread ID is 9450
2023-04-26T19:20:06.653Z,1682536806.653 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-26T19:20:06.653Z,1682536806.653 [logger ThreadHandler](INFO): Thread cancelled.
2023-04-26T19:20:06.661Z,1682536806.661 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-04-26T19:20:06.661Z,1682536806.661 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-04-26T19:20:06.662Z,1682536806.662 [CommandLine](INFO): Join timeout helper Thread ID is 9451
2023-04-26T19:20:06.757Z,1682536806.757 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-26T19:20:06.757Z,1682536806.757 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-04-26T19:20:06.761Z,1682536806.761 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-04-26T19:20:06.762Z,1682536806.762 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-04-26T19:20:06.762Z,1682536806.762 [CommandExec](INFO): Join timeout helper Thread ID is 9452
2023-04-26T19:20:06.765Z,1682536806.765 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-04-26T19:20:06.766Z,1682536806.766 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-04-26T19:20:06.766Z,1682536806.766 [controlThread](INFO): Join timeout helper Thread ID is 9453
2023-04-26T19:20:06.788Z,1682536806.788 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-26T19:20:06.789Z,1682536806.789 [controlThread](DEBUG): Uninitializing ControlThread
2023-04-26T19:20:06.789Z,1682536806.789 [AHRS_M2](INFO): Powering down
2023-04-26T19:20:06.861Z,1682536806.861 [NAL9602](INFO): Powering down
2023-04-26T19:20:06.863Z,1682536806.863 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-04-26T19:20:06.864Z,1682536806.864 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-04-26T19:20:06.865Z,1682536806.865 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-04-26T19:20:06.866Z,1682536806.866 [MissionManager](INFO): Uninitializing Mission Default
2023-04-26T19:20:06.866Z,1682536806.866 [Default] Stopped
2023-04-26T19:20:06.866Z,1682536806.866 [Default](DEBUG): Aggregate::uninitialize Default
2023-04-26T19:20:06.866Z,1682536806.866 [Default:B.GoToSurface] Stopped
2023-04-26T19:20:06.866Z,1682536806.866 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-04-26T19:20:06.866Z,1682536806.866 [Default:CheckIn] Stopped
2023-04-26T19:20:06.866Z,1682536806.866 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-26T19:20:06.866Z,1682536806.866 [Default:CheckIn:Read_GPS] Stopped
2023-04-26T19:20:06.869Z,1682536806.869 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-04-26T19:20:06.869Z,1682536806.869 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-04-26T19:20:06.870Z,1682536806.870 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-04-26T19:20:06.870Z,1682536806.870 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-04-26T19:20:06.870Z,1682536806.870 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-04-26T19:20:06.870Z,1682536806.870 [BuoyancyServo](INFO): Powering down
2023-04-26T19:20:06.885Z,1682536806.885 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-04-26T19:20:06.885Z,1682536806.885 [ElevatorServo](INFO): Powering down
2023-04-26T19:20:06.886Z,1682536806.886 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-04-26T19:20:06.886Z,1682536806.886 [MassServo](INFO): Powering down
2023-04-26T19:20:06.887Z,1682536806.887 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-04-26T19:20:06.887Z,1682536806.887 [RudderServo](INFO): Powering down
2023-04-26T19:20:06.887Z,1682536806.887 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-04-26T19:20:06.888Z,1682536806.888 [ThrusterHE](INFO): Powering down
2023-04-26T19:20:06.889Z,1682536806.889 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-04-26T19:20:06.889Z,1682536806.889 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-04-26T19:20:06.889Z,1682536806.889 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-04-26T19:20:06.889Z,1682536806.889 [CBIT](DEBUG): Powering off loads.
2023-04-26T19:20:06.900Z,1682536806.900 [CBIT](DEBUG): Disabling WDT.
2023-04-26T19:20:06.912Z,1682536806.912 [CBIT](DEBUG): Opening all GF detection circuits.
2023-04-26T19:20:06.914Z,1682536806.914 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-04-26T19:20:06.947Z,1682536806.947 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-04-26T19:20:06.951Z,1682536806.951 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-04-26T19:20:06.958Z,1682536806.958 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-04-26T19:20:07.001Z,1682536807.001 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-04-26T19:20:07.058Z,1682536807.058 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-04-26T19:20:07.061Z,1682536807.061 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-04-26T19:20:07.112Z,1682536807.112 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-04-26T19:20:07.161Z,1682536807.161 [logger ThreadHandler](INFO): Thread cancelled.