2024-04-26T20:05:47.388Z,1714161947.388 [CommandExec](IMPORTANT): got command restart logs 2024-04-26T20:05:57.935Z,1714161957.935 [CBIT](INFO): Clearing failed state for component DropWeight 2024-04-26T20:05:57.936Z,1714161957.936 [DropWeight] No Fault, FailCount= 1 2024-04-26T20:06:00.710Z,1714161960.710 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2024-04-26T20:06:00.710Z,1714161960.710 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-26T20:06:00.711Z,1714161960.711 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5 2024-04-26T20:06:00.711Z,1714161960.711 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2024-04-26T20:06:00.742Z,1714161960.742 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-26T20:06:00.742Z,1714161960.742 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2024-04-26T20:06:01.160Z,1714161961.160 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-26T20:06:01.160Z,1714161961.160 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2024-04-26T20:06:01.161Z,1714161961.161 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2024-04-26T20:06:01.161Z,1714161961.161 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5 2024-04-26T20:06:01.523Z,1714161961.523 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-04-26T20:06:02.131Z,1714161962.131 [CommandExec](IMPORTANT): got command get latitude 2024-04-26T20:06:02.131Z,1714161962.131 [CommandExec](FAULT): Element has no value 2024-04-26T20:06:21.783Z,1714161981.783 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 36.8 degree 2024-04-26T20:06:21.787Z,1714161981.787 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.latitude_fix" handled in the control thread. 2024-04-26T20:06:21.788Z,1714161981.788 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -121.9 degree 2024-04-26T20:06:21.807Z,1714161981.807 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.longitude_fix" handled in the control thread. 2024-04-26T20:06:22.141Z,1714161982.141 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,MultiRay,NAL9602,PowerOnly,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,Maintain_NAL9602.latitude_fix,Maintain_NAL9602.longitude_fix,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2024-04-26T20:06:34.329Z,1714161994.329 [CommandExec](IMPORTANT): got command set NAL9602.time_fix 1714161981 second 2024-04-26T20:06:34.677Z,1714161994.677 [Default:CheckIn:Read_GPS] Stopped 2024-04-26T20:06:34.678Z,1714161994.678 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-26T20:06:35.072Z,1714161995.072 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-04-26T20:06:42.360Z,1714162002.360 [CommandExec](IMPORTANT): got command get latitude 2024-04-26T20:06:42.361Z,1714162002.361 [CommandExec](IMPORTANT): latitude 36.799999 arcdeg 2024-04-26T20:06:43.669Z,1714162003.669 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240426T193003/Courier0007.lzma 2024-04-26T20:06:44.673Z,1714162004.673 [DataOverHttps](INFO): Moved sent file to Logs/20240426T193003/Courier0007.lzma.bak 2024-04-26T20:06:44.673Z,1714162004.673 [DataOverHttps](INFO): SBD MOMSN=19597174 2024-04-26T20:07:00.617Z,1714162020.617 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240426T193003/Courier0010.lzma 2024-04-26T20:07:01.620Z,1714162021.620 [DataOverHttps](INFO): Moved sent file to Logs/20240426T193003/Courier0010.lzma.bak 2024-04-26T20:07:01.620Z,1714162021.620 [DataOverHttps](INFO): SBD MOMSN=19597176 2024-04-26T20:07:08.163Z,1714162028.163 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-26T20:07:17.781Z,1714162037.781 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240426T194812/Courier0000.lzma 2024-04-26T20:07:18.784Z,1714162038.784 [DataOverHttps](INFO): Moved sent file to Logs/20240426T194812/Courier0000.lzma.bak 2024-04-26T20:07:18.784Z,1714162038.784 [DataOverHttps](INFO): SBD MOMSN=19597178 2024-04-26T20:07:34.766Z,1714162054.766 [DataOverHttps](INFO): Sending 243 bytes from file Logs/20240426T195045/Courier0000.lzma 2024-04-26T20:07:35.768Z,1714162055.768 [DataOverHttps](INFO): Moved sent file to Logs/20240426T195045/Courier0000.lzma.bak 2024-04-26T20:07:35.768Z,1714162055.768 [DataOverHttps](INFO): SBD MOMSN=19597181 2024-04-26T20:07:51.818Z,1714162071.818 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240426T195045/Courier0004.lzma 2024-04-26T20:07:52.820Z,1714162072.820 [DataOverHttps](INFO): Moved sent file to Logs/20240426T195045/Courier0004.lzma.bak 2024-04-26T20:07:52.820Z,1714162072.820 [DataOverHttps](INFO): SBD MOMSN=19597186 2024-04-26T20:08:08.894Z,1714162088.894 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240426T195714/Courier0000.lzma 2024-04-26T20:08:09.896Z,1714162089.896 [DataOverHttps](INFO): Moved sent file to Logs/20240426T195714/Courier0000.lzma.bak 2024-04-26T20:08:09.896Z,1714162089.896 [DataOverHttps](INFO): SBD MOMSN=19597189 2024-04-26T20:08:26.762Z,1714162106.762 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20240426T200547/Courier0000.lzma 2024-04-26T20:08:27.764Z,1714162107.764 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Courier0000.lzma.bak 2024-04-26T20:08:27.764Z,1714162107.764 [DataOverHttps](INFO): SBD MOMSN=19597192 2024-04-26T20:08:44.793Z,1714162124.793 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20240426T193003/Express0008.lzma 2024-04-26T20:08:45.796Z,1714162125.796 [DataOverHttps](INFO): Moved sent file to Logs/20240426T193003/Express0008.lzma.bak 2024-04-26T20:08:45.796Z,1714162125.796 [DataOverHttps](INFO): SBD MOMSN=19597195 2024-04-26T20:09:01.799Z,1714162141.799 [DataOverHttps](INFO): Sending 802 bytes from file Logs/20240426T193003/Express0011.lzma 2024-04-26T20:09:02.800Z,1714162142.800 [DataOverHttps](INFO): Moved sent file to Logs/20240426T193003/Express0011.lzma.bak 2024-04-26T20:09:02.800Z,1714162142.800 [DataOverHttps](INFO): SBD MOMSN=19597199 2024-04-26T20:09:20.010Z,1714162160.010 [DataOverHttps](INFO): Sending 287 bytes from file Logs/20240426T194812/Express0001.lzma 2024-04-26T20:09:21.012Z,1714162161.012 [DataOverHttps](INFO): Moved sent file to Logs/20240426T194812/Express0001.lzma.bak 2024-04-26T20:09:21.012Z,1714162161.012 [DataOverHttps](INFO): SBD MOMSN=19597218 2024-04-26T20:09:36.919Z,1714162176.919 [DataOverHttps](INFO): Sending 959 bytes from file Logs/20240426T195045/Express0001.lzma 2024-04-26T20:09:37.920Z,1714162177.920 [DataOverHttps](INFO): Moved sent file to Logs/20240426T195045/Express0001.lzma.bak 2024-04-26T20:09:37.920Z,1714162177.920 [DataOverHttps](INFO): SBD MOMSN=19597224 2024-04-26T20:09:55.546Z,1714162195.546 [DataOverHttps](INFO): Sending 407 bytes from file Logs/20240426T195045/Express0005.lzma 2024-04-26T20:09:56.548Z,1714162196.548 [DataOverHttps](INFO): Moved sent file to Logs/20240426T195045/Express0005.lzma.bak 2024-04-26T20:09:56.548Z,1714162196.548 [DataOverHttps](INFO): SBD MOMSN=19597248 2024-04-26T20:10:12.451Z,1714162212.451 [DataOverHttps](INFO): Sending 963 bytes from file Logs/20240426T195714/Express0001.lzma 2024-04-26T20:10:13.452Z,1714162213.452 [DataOverHttps](INFO): Moved sent file to Logs/20240426T195714/Express0001.lzma.bak 2024-04-26T20:10:13.452Z,1714162213.452 [DataOverHttps](INFO): SBD MOMSN=19597272 2024-04-26T20:10:31.074Z,1714162231.074 [DataOverHttps](INFO): Sending 441 bytes from file Logs/20240426T200547/Express0001.lzma 2024-04-26T20:10:32.076Z,1714162232.076 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Express0001.lzma.bak 2024-04-26T20:10:32.076Z,1714162232.076 [DataOverHttps](INFO): SBD MOMSN=19597293 2024-04-26T20:10:34.249Z,1714162234.249 [Default:CheckIn:Read_Iridium] Stopped 2024-04-26T20:10:34.249Z,1714162234.249 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-26T20:10:34.249Z,1714162234.249 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-26T20:11:33.434Z,1714162293.434 [CommandExec](IMPORTANT): got command failComponent 2024-04-26T20:11:33.434Z,1714162293.434 [CommandExec](IMPORTANT): Failed components: 2024-04-26T20:11:33.435Z,1714162293.435 [CommandExec](IMPORTANT): No failed Components. 2024-04-26T20:12:29.347Z,1714162349.347 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T20:12:36.216Z,1714162356.216 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T20:12:51.170Z,1714162371.170 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T20:13:06.513Z,1714162386.513 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T20:13:21.468Z,1714162401.468 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T20:13:36.813Z,1714162416.813 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T20:13:52.234Z,1714162432.234 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T20:14:07.517Z,1714162447.517 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T20:14:22.881Z,1714162462.881 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T20:14:38.221Z,1714162478.221 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T20:14:53.175Z,1714162493.175 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T20:15:34.796Z,1714162534.796 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-26T20:15:34.796Z,1714162534.796 [Default:CheckIn:C.Wait] Stopped 2024-04-26T20:15:34.796Z,1714162534.796 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-26T20:15:34.797Z,1714162534.797 [Default:CheckIn:D] Running Loop=1 2024-04-26T20:15:35.201Z,1714162535.201 [Default:CheckIn:D] Stopped 2024-04-26T20:15:35.201Z,1714162535.201 [Default:CheckIn:E] Running Loop=1 2024-04-26T20:15:35.616Z,1714162535.616 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.155203 min 2024-04-26T20:15:35.616Z,1714162535.616 [Default:CheckIn:E] Stopped 2024-04-26T20:15:35.616Z,1714162535.616 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-26T20:15:35.616Z,1714162535.616 [Default:CheckIn] Stopped 2024-04-26T20:15:35.617Z,1714162535.617 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-26T20:15:35.617Z,1714162535.617 [Default:CheckIn](INFO): Running loop #2 2024-04-26T20:15:35.617Z,1714162535.617 [Default:CheckIn] Running Loop=2 2024-04-26T20:15:35.617Z,1714162535.617 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-26T20:15:35.617Z,1714162535.617 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-26T20:15:37.217Z,1714162537.217 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-04-26T20:15:37.217Z,1714162537.217 [NAL9602] Data Fault, FailCount= 2 2024-04-26T20:15:37.217Z,1714162537.217 [NAL9602](ERROR): Data Fault 2024-04-26T20:15:37.240Z,1714162537.240 [CBIT](ERROR): Data Fault in component: NAL9602 2024-04-26T20:15:37.615Z,1714162537.615 [NAL9602](INFO): Powering down 2024-04-26T20:15:38.443Z,1714162538.443 [CBIT](INFO): Clearing failed state for component NAL9602 2024-04-26T20:15:38.443Z,1714162538.443 [NAL9602] No Fault, FailCount= 2 2024-04-26T20:16:07.946Z,1714162567.946 [NAL9602](INFO): Powering up NAL9602 2024-04-26T20:16:18.827Z,1714162578.827 [NAL9602](INFO): NAL9602 initialized 2024-04-26T20:20:35.797Z,1714162835.797 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-26T20:15:35.6Z 2024-04-26T20:20:35.797Z,1714162835.797 [Default:CheckIn:Read_GPS] Stopped 2024-04-26T20:20:35.802Z,1714162835.802 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-26T20:20:46.530Z,1714162846.530 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240426T200547/Courier0006.lzma 2024-04-26T20:20:47.532Z,1714162847.532 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Courier0006.lzma.bak 2024-04-26T20:20:47.532Z,1714162847.532 [DataOverHttps](INFO): SBD MOMSN=19597313 2024-04-26T20:21:10.570Z,1714162870.570 [DataOverHttps](INFO): Sending 301 bytes from file Logs/20240426T200547/Express0007.lzma 2024-04-26T20:21:11.572Z,1714162871.572 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Express0007.lzma.bak 2024-04-26T20:21:11.572Z,1714162871.572 [DataOverHttps](INFO): SBD MOMSN=19597315 2024-04-26T20:21:15.144Z,1714162875.144 [Default:CheckIn:Read_Iridium] Stopped 2024-04-26T20:21:15.144Z,1714162875.144 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-26T20:21:15.144Z,1714162875.144 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-26T20:21:22.747Z,1714162882.747 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-04-26T20:21:53.451Z,1714162913.451 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-26T20:21:57.491Z,1714162917.491 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5. 2024-04-26T20:21:57.494Z,1714162917.494 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle. 2024-04-26T20:26:15.707Z,1714163175.707 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-26T20:26:15.707Z,1714163175.707 [Default:CheckIn:C.Wait] Stopped 2024-04-26T20:26:15.708Z,1714163175.708 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-26T20:26:15.708Z,1714163175.708 [Default:CheckIn:D] Running Loop=1 2024-04-26T20:26:16.092Z,1714163176.092 [Default:CheckIn:D] Stopped 2024-04-26T20:26:16.092Z,1714163176.092 [Default:CheckIn:E] Running Loop=1 2024-04-26T20:26:16.500Z,1714163176.500 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 20.836717 min 2024-04-26T20:26:16.500Z,1714163176.500 [Default:CheckIn:E] Stopped 2024-04-26T20:26:16.500Z,1714163176.500 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-26T20:26:16.500Z,1714163176.500 [Default:CheckIn] Stopped 2024-04-26T20:26:16.500Z,1714163176.500 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-26T20:26:16.500Z,1714163176.500 [Default:CheckIn](INFO): Running loop #3 2024-04-26T20:26:16.500Z,1714163176.500 [Default:CheckIn] Running Loop=3 2024-04-26T20:26:16.500Z,1714163176.500 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-26T20:26:16.501Z,1714163176.501 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-26T20:26:49.203Z,1714163209.203 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2024-04-26T20:26:49.278Z,1714163209.278 [NAL9602](ERROR): received: +CSQ:0 OK 2024-04-26T20:31:16.688Z,1714163476.688 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-26T20:26:16.5Z 2024-04-26T20:31:16.688Z,1714163476.688 [Default:CheckIn:Read_GPS] Stopped 2024-04-26T20:31:16.688Z,1714163476.688 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-26T20:31:20.691Z,1714163480.691 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-04-26T20:31:24.301Z,1714163484.301 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20240426T200547/Courier0009.lzma 2024-04-26T20:31:25.303Z,1714163485.303 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Courier0009.lzma.bak 2024-04-26T20:31:25.304Z,1714163485.304 [DataOverHttps](INFO): SBD MOMSN=19597357 2024-04-26T20:31:42.110Z,1714163502.110 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20240426T200547/Express0010.lzma 2024-04-26T20:31:43.111Z,1714163503.111 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Express0010.lzma.bak 2024-04-26T20:31:43.112Z,1714163503.112 [DataOverHttps](INFO): SBD MOMSN=19597359 2024-04-26T20:31:45.361Z,1714163505.361 [Default:CheckIn:Read_Iridium] Stopped 2024-04-26T20:31:45.361Z,1714163505.361 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-26T20:31:45.361Z,1714163505.361 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-26T20:31:51.395Z,1714163511.395 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-26T20:35:59.478Z,1714163759.478 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2024-04-26T20:35:59.479Z,1714163759.479 [DropWeight] Hardware Fault, FailCount= 1 2024-04-26T20:35:59.479Z,1714163759.479 [DropWeight](ERROR): Hardware Fault 2024-04-26T20:35:59.499Z,1714163759.499 [CommandExec](FAULT): Scheduling is paused 2024-04-26T20:35:59.500Z,1714163759.500 [CBIT](INFO): Critical error at 20240426T203559 2024-04-26T20:35:59.502Z,1714163759.502 [CBIT](ERROR): Hardware Fault in component: DropWeight 2024-04-26T20:35:59.503Z,1714163759.503 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2024-04-26T20:35:59.903Z,1714163759.903 [CBIT](INFO): Critical error at 20240426T203559 2024-04-26T20:36:45.943Z,1714163805.943 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-26T20:36:45.943Z,1714163805.943 [Default:CheckIn:C.Wait] Stopped 2024-04-26T20:36:45.943Z,1714163805.943 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-26T20:36:45.944Z,1714163805.944 [Default:CheckIn:D] Running Loop=1 2024-04-26T20:36:46.350Z,1714163806.350 [Default:CheckIn:D] Stopped 2024-04-26T20:36:46.350Z,1714163806.350 [Default:CheckIn:E] Running Loop=1 2024-04-26T20:36:46.759Z,1714163806.759 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.341012 min 2024-04-26T20:36:46.760Z,1714163806.760 [Default:CheckIn:E] Stopped 2024-04-26T20:36:46.760Z,1714163806.760 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-26T20:36:46.760Z,1714163806.760 [Default:CheckIn] Stopped 2024-04-26T20:36:46.760Z,1714163806.760 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-26T20:36:46.760Z,1714163806.760 [Default:CheckIn](INFO): Running loop #4 2024-04-26T20:36:46.760Z,1714163806.760 [Default:CheckIn] Running Loop=4 2024-04-26T20:36:46.760Z,1714163806.760 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-26T20:36:46.760Z,1714163806.760 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-26T20:36:48.359Z,1714163808.359 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-04-26T20:36:48.359Z,1714163808.359 [NAL9602] Data Fault, FailCount= 3 2024-04-26T20:36:48.359Z,1714163808.359 [NAL9602](ERROR): Data Fault 2024-04-26T20:36:48.422Z,1714163808.422 [CBIT](ERROR): Data Fault in component: NAL9602 2024-04-26T20:36:48.759Z,1714163808.759 [NAL9602](INFO): Powering down 2024-04-26T20:36:49.596Z,1714163809.596 [CBIT](INFO): Clearing failed state for component NAL9602 2024-04-26T20:36:49.596Z,1714163809.596 [NAL9602] No Fault, FailCount= 3 2024-04-26T20:37:19.059Z,1714163839.059 [NAL9602](INFO): Powering up NAL9602 2024-04-26T20:37:29.968Z,1714163849.968 [NAL9602](INFO): NAL9602 initialized 2024-04-26T20:41:46.937Z,1714164106.937 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-26T20:36:46.8Z 2024-04-26T20:41:46.937Z,1714164106.937 [Default:CheckIn:Read_GPS] Stopped 2024-04-26T20:41:46.937Z,1714164106.937 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-26T20:41:54.701Z,1714164114.701 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20240426T200547/Courier0012.lzma 2024-04-26T20:41:55.704Z,1714164115.704 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Courier0012.lzma.bak 2024-04-26T20:41:55.704Z,1714164115.704 [DataOverHttps](INFO): SBD MOMSN=19597448 2024-04-26T20:42:11.230Z,1714164131.230 [DataOverHttps](INFO): Sending 249 bytes from file Logs/20240426T200547/Express0013.lzma 2024-04-26T20:42:12.228Z,1714164132.228 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Express0013.lzma.bak 2024-04-26T20:42:12.228Z,1714164132.228 [DataOverHttps](INFO): SBD MOMSN=19597452 2024-04-26T20:42:14.442Z,1714164134.442 [Default:CheckIn:Read_Iridium] Stopped 2024-04-26T20:42:14.442Z,1714164134.442 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-26T20:42:14.442Z,1714164134.442 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-26T20:42:33.791Z,1714164153.791 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-04-26T20:43:04.491Z,1714164184.491 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-26T20:47:15.024Z,1714164435.024 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-26T20:47:15.025Z,1714164435.025 [Default:CheckIn:C.Wait] Stopped 2024-04-26T20:47:15.025Z,1714164435.025 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-26T20:47:15.026Z,1714164435.026 [Default:CheckIn:D] Running Loop=1 2024-04-26T20:47:15.408Z,1714164435.408 [Default:CheckIn:D] Stopped 2024-04-26T20:47:15.408Z,1714164435.408 [Default:CheckIn:E] Running Loop=1 2024-04-26T20:47:15.816Z,1714164435.816 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 41.825317 min 2024-04-26T20:47:15.817Z,1714164435.817 [Default:CheckIn:E] Stopped 2024-04-26T20:47:15.817Z,1714164435.817 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-26T20:47:15.817Z,1714164435.817 [Default:CheckIn] Stopped 2024-04-26T20:47:15.817Z,1714164435.817 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-26T20:47:15.817Z,1714164435.817 [Default:CheckIn](INFO): Running loop #5 2024-04-26T20:47:15.817Z,1714164435.817 [Default:CheckIn] Running Loop=5 2024-04-26T20:47:15.817Z,1714164435.817 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-26T20:47:15.817Z,1714164435.817 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-26T20:47:48.527Z,1714164468.527 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2024-04-26T20:47:48.610Z,1714164468.610 [NAL9602](ERROR): received: +CSQ:0 OK 2024-04-26T20:50:59.658Z,1714164659.658 [CBIT](INFO): Clearing failed state for component DropWeight 2024-04-26T20:50:59.658Z,1714164659.658 [DropWeight] No Fault, FailCount= 1 2024-04-26T20:52:16.017Z,1714164736.017 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-26T20:47:15.8Z 2024-04-26T20:52:16.017Z,1714164736.017 [Default:CheckIn:Read_GPS] Stopped 2024-04-26T20:52:16.017Z,1714164736.017 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-26T20:52:20.015Z,1714164740.015 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-04-26T20:52:23.677Z,1714164743.677 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240426T200547/Courier0015.lzma 2024-04-26T20:52:24.680Z,1714164744.680 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Courier0015.lzma.bak 2024-04-26T20:52:24.680Z,1714164744.680 [DataOverHttps](INFO): SBD MOMSN=19597471 2024-04-26T20:52:40.574Z,1714164760.574 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20240426T200547/Express0016.lzma 2024-04-26T20:52:41.576Z,1714164761.576 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Express0016.lzma.bak 2024-04-26T20:52:41.576Z,1714164761.576 [DataOverHttps](INFO): SBD MOMSN=19597475 2024-04-26T20:52:43.461Z,1714164763.461 [Default:CheckIn:Read_Iridium] Stopped 2024-04-26T20:52:43.461Z,1714164763.461 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-26T20:52:43.461Z,1714164763.461 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-26T20:52:50.728Z,1714164770.728 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-26T20:57:44.043Z,1714165064.043 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-26T20:57:44.043Z,1714165064.043 [Default:CheckIn:C.Wait] Stopped 2024-04-26T20:57:44.043Z,1714165064.043 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-26T20:57:44.044Z,1714165064.044 [Default:CheckIn:D] Running Loop=1 2024-04-26T20:57:44.440Z,1714165064.440 [Default:CheckIn:D] Stopped 2024-04-26T20:57:44.440Z,1714165064.440 [Default:CheckIn:E] Running Loop=1 2024-04-26T20:57:44.844Z,1714165064.844 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.309184 min 2024-04-26T20:57:44.844Z,1714165064.844 [Default:CheckIn:E] Stopped 2024-04-26T20:57:44.844Z,1714165064.844 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-26T20:57:44.844Z,1714165064.844 [Default:CheckIn] Stopped 2024-04-26T20:57:44.844Z,1714165064.844 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-26T20:57:44.844Z,1714165064.844 [Default:CheckIn](INFO): Running loop #6 2024-04-26T20:57:44.844Z,1714165064.844 [Default:CheckIn] Running Loop=6 2024-04-26T20:57:44.845Z,1714165064.845 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-26T20:57:44.845Z,1714165064.845 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-26T20:57:46.444Z,1714165066.444 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-04-26T20:57:46.444Z,1714165066.444 [NAL9602] Data Fault, FailCount= 4 2024-04-26T20:57:46.444Z,1714165066.444 [NAL9602](ERROR): Data Fault 2024-04-26T20:57:46.526Z,1714165066.526 [CBIT](ERROR): Data Fault in component: NAL9602 2024-04-26T20:57:46.852Z,1714165066.852 [NAL9602](INFO): Powering down 2024-04-26T20:57:47.678Z,1714165067.678 [CBIT](INFO): Clearing failed state for component NAL9602 2024-04-26T20:57:47.678Z,1714165067.678 [NAL9602] No Fault, FailCount= 4 2024-04-26T20:58:17.151Z,1714165097.151 [NAL9602](INFO): Powering up NAL9602 2024-04-26T20:58:28.056Z,1714165108.056 [NAL9602](INFO): NAL9602 initialized 2024-04-26T21:02:45.092Z,1714165365.092 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-26T20:57:44.8Z 2024-04-26T21:02:45.092Z,1714165365.092 [Default:CheckIn:Read_GPS] Stopped 2024-04-26T21:02:45.092Z,1714165365.092 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-26T21:02:52.473Z,1714165372.473 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240426T200547/Courier0018.lzma 2024-04-26T21:02:53.476Z,1714165373.476 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Courier0018.lzma.bak 2024-04-26T21:02:53.476Z,1714165373.476 [DataOverHttps](INFO): SBD MOMSN=19597563 2024-04-26T21:03:09.382Z,1714165389.382 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20240426T200547/Express0019.lzma 2024-04-26T21:03:10.384Z,1714165390.384 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Express0019.lzma.bak 2024-04-26T21:03:10.385Z,1714165390.385 [DataOverHttps](INFO): SBD MOMSN=19597565 2024-04-26T21:03:12.581Z,1714165392.581 [Default:CheckIn:Read_Iridium] Stopped 2024-04-26T21:03:12.581Z,1714165392.581 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-26T21:03:12.581Z,1714165392.581 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-26T21:03:31.945Z,1714165411.945 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-04-26T21:04:02.648Z,1714165442.648 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-26T21:08:13.151Z,1714165693.151 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-26T21:08:13.151Z,1714165693.151 [Default:CheckIn:C.Wait] Stopped 2024-04-26T21:08:13.151Z,1714165693.151 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-26T21:08:13.151Z,1714165693.151 [Default:CheckIn:D] Running Loop=1 2024-04-26T21:08:13.568Z,1714165693.568 [Default:CheckIn:D] Stopped 2024-04-26T21:08:13.568Z,1714165693.568 [Default:CheckIn:E] Running Loop=1 2024-04-26T21:08:13.952Z,1714165693.952 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 62.794649 min 2024-04-26T21:08:13.952Z,1714165693.952 [Default:CheckIn:E] Stopped 2024-04-26T21:08:13.952Z,1714165693.952 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-26T21:08:13.952Z,1714165693.952 [Default:CheckIn] Stopped 2024-04-26T21:08:13.952Z,1714165693.952 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-26T21:08:13.952Z,1714165693.952 [Default:CheckIn](INFO): Running loop #7 2024-04-26T21:08:13.952Z,1714165693.952 [Default:CheckIn] Running Loop=7 2024-04-26T21:08:13.952Z,1714165693.952 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-26T21:08:13.953Z,1714165693.953 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-26T21:08:46.687Z,1714165726.687 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2024-04-26T21:08:46.770Z,1714165726.770 [NAL9602](ERROR): received: +CSQ:0 OK 2024-04-26T21:13:14.176Z,1714165994.176 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-26T21:08:13.0Z 2024-04-26T21:13:14.176Z,1714165994.176 [Default:CheckIn:Read_GPS] Stopped 2024-04-26T21:13:14.176Z,1714165994.176 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-26T21:13:17.779Z,1714165997.779 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-04-26T21:13:21.493Z,1714166001.493 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240426T200547/Courier0021.lzma 2024-04-26T21:13:22.495Z,1714166002.495 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Courier0021.lzma.bak 2024-04-26T21:13:22.496Z,1714166002.496 [DataOverHttps](INFO): SBD MOMSN=19597589 2024-04-26T21:13:38.670Z,1714166018.670 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20240426T200547/Express0022.lzma 2024-04-26T21:13:39.696Z,1714166019.696 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Express0022.lzma.bak 2024-04-26T21:13:39.697Z,1714166019.697 [DataOverHttps](INFO): SBD MOMSN=19597591 2024-04-26T21:13:41.636Z,1714166021.636 [Default:CheckIn:Read_Iridium] Stopped 2024-04-26T21:13:41.636Z,1714166021.636 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-26T21:13:41.636Z,1714166021.636 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-26T21:13:48.483Z,1714166028.483 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-26T21:18:42.215Z,1714166322.215 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-26T21:18:42.215Z,1714166322.215 [Default:CheckIn:C.Wait] Stopped 2024-04-26T21:18:42.215Z,1714166322.215 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-26T21:18:42.215Z,1714166322.215 [Default:CheckIn:D] Running Loop=1 2024-04-26T21:18:42.609Z,1714166322.609 [Default:CheckIn:D] Stopped 2024-04-26T21:18:42.609Z,1714166322.609 [Default:CheckIn:E] Running Loop=1 2024-04-26T21:18:43.023Z,1714166323.023 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 73.278670 min 2024-04-26T21:18:43.024Z,1714166323.024 [Default:CheckIn:E] Stopped 2024-04-26T21:18:43.024Z,1714166323.024 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-26T21:18:43.024Z,1714166323.024 [Default:CheckIn] Stopped 2024-04-26T21:18:43.024Z,1714166323.024 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-26T21:18:43.024Z,1714166323.024 [Default:CheckIn](INFO): Running loop #8 2024-04-26T21:18:43.024Z,1714166323.024 [Default:CheckIn] Running Loop=8 2024-04-26T21:18:43.024Z,1714166323.024 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-26T21:18:43.024Z,1714166323.024 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-26T21:18:44.625Z,1714166324.625 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-04-26T21:18:44.625Z,1714166324.625 [NAL9602] Data Fault, FailCount= 5 2024-04-26T21:18:44.625Z,1714166324.625 [NAL9602](ERROR): Data Fault 2024-04-26T21:18:44.647Z,1714166324.647 [CBIT](ERROR): Data Fault in component: NAL9602 2024-04-26T21:18:44.647Z,1714166324.647 [CBIT](CRITICAL): Data Fault in component: NAL9602 2024-04-26T21:18:45.019Z,1714166325.019 [NAL9602](INFO): Powering down 2024-04-26T21:18:45.064Z,1714166325.064 [CBIT](INFO): Critical error at 20240426T211844 2024-04-26T21:20:44.691Z,1714166444.691 [CBIT](INFO): Clearing failed state for component NAL9602 2024-04-26T21:20:44.691Z,1714166444.691 [NAL9602] No Fault, FailCount= 5 2024-04-26T21:20:45.007Z,1714166445.007 [NAL9602](INFO): Powering up NAL9602 2024-04-26T21:20:55.915Z,1714166455.915 [NAL9602](INFO): NAL9602 initialized 2024-04-26T21:21:01.198Z,1714166461.198 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2024-04-26T21:21:01.198Z,1714166461.198 [DropWeight] Hardware Fault, FailCount= 1 2024-04-26T21:21:01.198Z,1714166461.198 [DropWeight](ERROR): Hardware Fault 2024-04-26T21:21:01.219Z,1714166461.219 [CBIT](INFO): Critical error at 20240426T212101 2024-04-26T21:21:01.222Z,1714166461.222 [CBIT](ERROR): Hardware Fault in component: DropWeight 2024-04-26T21:21:01.222Z,1714166461.222 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2024-04-26T21:21:01.597Z,1714166461.597 [CBIT](INFO): Critical error at 20240426T212101 2024-04-26T21:23:43.216Z,1714166623.216 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-26T21:18:43.0Z 2024-04-26T21:23:43.216Z,1714166623.216 [Default:CheckIn:Read_GPS] Stopped 2024-04-26T21:23:43.216Z,1714166623.216 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-26T21:23:50.949Z,1714166630.949 [DataOverHttps](INFO): Sending 136 bytes from file Logs/20240426T200547/Courier0024.lzma 2024-04-26T21:23:51.952Z,1714166631.952 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Courier0024.lzma.bak 2024-04-26T21:23:51.952Z,1714166631.952 [DataOverHttps](INFO): SBD MOMSN=19597619 2024-04-26T21:24:07.878Z,1714166647.878 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20240426T200547/Express0025.lzma 2024-04-26T21:24:08.872Z,1714166648.872 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Express0025.lzma.bak 2024-04-26T21:24:08.872Z,1714166648.872 [DataOverHttps](INFO): SBD MOMSN=19597624 2024-04-26T21:24:10.665Z,1714166650.665 [Default:CheckIn:Read_Iridium] Stopped 2024-04-26T21:24:10.665Z,1714166650.665 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-26T21:24:10.665Z,1714166650.665 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-26T21:24:27.610Z,1714166667.610 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T21:24:34.477Z,1714166674.477 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T21:24:49.426Z,1714166689.426 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T21:25:04.779Z,1714166704.779 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T21:25:20.136Z,1714166720.136 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T21:25:35.887Z,1714166735.887 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T21:25:51.642Z,1714166751.642 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T21:25:59.727Z,1714166759.727 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-04-26T21:26:06.592Z,1714166766.592 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T21:26:21.941Z,1714166781.941 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T21:26:30.431Z,1714166790.431 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-26T21:26:36.889Z,1714166796.889 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T21:28:53.040Z,1714166933.040 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2024-04-26T21:29:11.231Z,1714166951.231 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-26T21:29:11.231Z,1714166951.231 [Default:CheckIn:C.Wait] Stopped 2024-04-26T21:29:11.231Z,1714166951.231 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-26T21:29:11.232Z,1714166951.232 [Default:CheckIn:D] Running Loop=1 2024-04-26T21:29:11.639Z,1714166951.639 [Default:CheckIn:D] Stopped 2024-04-26T21:29:11.639Z,1714166951.639 [Default:CheckIn:E] Running Loop=1 2024-04-26T21:29:12.047Z,1714166952.047 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 83.762500 min 2024-04-26T21:29:12.047Z,1714166952.047 [Default:CheckIn:E] Stopped 2024-04-26T21:29:12.048Z,1714166952.048 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-26T21:29:12.048Z,1714166952.048 [Default:CheckIn] Stopped 2024-04-26T21:29:12.048Z,1714166952.048 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-26T21:29:12.048Z,1714166952.048 [Default:CheckIn](INFO): Running loop #9 2024-04-26T21:29:12.048Z,1714166952.048 [Default:CheckIn] Running Loop=9 2024-04-26T21:29:12.048Z,1714166952.048 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-26T21:29:12.048Z,1714166952.048 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-26T21:29:44.760Z,1714166984.760 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2024-04-26T21:29:44.839Z,1714166984.839 [NAL9602](ERROR): received: +CSQ:0 OK 2024-04-26T21:34:01.693Z,1714167241.693 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T21:34:12.245Z,1714167252.245 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-26T21:29:12.0Z 2024-04-26T21:34:12.245Z,1714167252.245 [Default:CheckIn:Read_GPS] Stopped 2024-04-26T21:34:12.245Z,1714167252.245 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-26T21:34:15.435Z,1714167255.435 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-04-26T21:34:17.046Z,1714167257.046 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T21:34:19.993Z,1714167259.993 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240426T200547/Courier0027.lzma 2024-04-26T21:34:20.995Z,1714167260.995 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Courier0027.lzma.bak 2024-04-26T21:34:20.996Z,1714167260.996 [DataOverHttps](INFO): SBD MOMSN=19597673 2024-04-26T21:34:31.993Z,1714167271.993 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T21:34:36.911Z,1714167276.911 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20240426T200547/Express0028.lzma 2024-04-26T21:34:37.912Z,1714167277.912 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Express0028.lzma.bak 2024-04-26T21:34:37.912Z,1714167277.912 [DataOverHttps](INFO): SBD MOMSN=19597675 2024-04-26T21:34:39.711Z,1714167279.711 [Default:CheckIn:Read_Iridium] Stopped 2024-04-26T21:34:39.711Z,1714167279.711 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-26T21:34:39.711Z,1714167279.711 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-26T21:34:46.199Z,1714167286.199 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-26T21:34:46.954Z,1714167286.954 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T21:35:02.293Z,1714167302.293 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T21:35:17.662Z,1714167317.662 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T21:35:33.013Z,1714167333.013 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T21:35:47.961Z,1714167347.961 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T21:36:01.334Z,1714167361.334 [CBIT](INFO): Clearing failed state for component DropWeight 2024-04-26T21:36:01.334Z,1714167361.334 [DropWeight] No Fault, FailCount= 1 2024-04-26T21:36:02.909Z,1714167362.909 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T21:36:18.263Z,1714167378.263 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T21:39:40.280Z,1714167580.280 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-26T21:39:40.280Z,1714167580.280 [Default:CheckIn:C.Wait] Stopped 2024-04-26T21:39:40.280Z,1714167580.280 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-26T21:39:40.280Z,1714167580.280 [Default:CheckIn:D] Running Loop=1 2024-04-26T21:39:40.736Z,1714167580.736 [Default:CheckIn:D] Stopped 2024-04-26T21:39:40.737Z,1714167580.737 [Default:CheckIn:E] Running Loop=1 2024-04-26T21:39:41.106Z,1714167581.106 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 94.247461 min 2024-04-26T21:39:41.106Z,1714167581.106 [Default:CheckIn:E] Stopped 2024-04-26T21:39:41.106Z,1714167581.106 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-26T21:39:41.106Z,1714167581.106 [Default:CheckIn] Stopped 2024-04-26T21:39:41.107Z,1714167581.107 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-26T21:39:41.107Z,1714167581.107 [Default:CheckIn](INFO): Running loop #10 2024-04-26T21:39:41.107Z,1714167581.107 [Default:CheckIn] Running Loop=10 2024-04-26T21:39:41.107Z,1714167581.107 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-26T21:39:41.107Z,1714167581.107 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-26T21:39:42.687Z,1714167582.687 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-04-26T21:39:42.687Z,1714167582.687 [NAL9602] Data Fault, FailCount= 1 2024-04-26T21:39:42.687Z,1714167582.687 [NAL9602](ERROR): Data Fault 2024-04-26T21:39:42.742Z,1714167582.742 [CBIT](ERROR): Data Fault in component: NAL9602 2024-04-26T21:39:43.091Z,1714167583.091 [NAL9602](INFO): Powering down 2024-04-26T21:39:43.978Z,1714167583.978 [CBIT](INFO): Clearing failed state for component NAL9602 2024-04-26T21:39:43.978Z,1714167583.978 [NAL9602] No Fault, FailCount= 1 2024-04-26T21:40:13.399Z,1714167613.399 [NAL9602](INFO): Powering up NAL9602 2024-04-26T21:40:24.299Z,1714167624.299 [NAL9602](INFO): NAL9602 initialized 2024-04-26T21:43:28.969Z,1714167808.969 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T21:43:35.841Z,1714167815.841 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T21:43:51.193Z,1714167831.193 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T21:44:06.546Z,1714167846.546 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T21:44:22.706Z,1714167862.706 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T21:44:37.655Z,1714167877.655 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T21:44:41.307Z,1714167881.307 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-26T21:39:41.1Z 2024-04-26T21:44:41.307Z,1714167881.307 [Default:CheckIn:Read_GPS] Stopped 2024-04-26T21:44:41.307Z,1714167881.307 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-26T21:44:49.105Z,1714167889.105 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240426T200547/Courier0030.lzma 2024-04-26T21:44:50.108Z,1714167890.108 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Courier0030.lzma.bak 2024-04-26T21:44:50.108Z,1714167890.108 [DataOverHttps](INFO): SBD MOMSN=19597811 2024-04-26T21:44:53.070Z,1714167893.070 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T21:45:06.550Z,1714167906.550 [DataOverHttps](INFO): Sending 225 bytes from file Logs/20240426T200547/Express0031.lzma 2024-04-26T21:45:07.552Z,1714167907.552 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Express0031.lzma.bak 2024-04-26T21:45:07.552Z,1714167907.552 [DataOverHttps](INFO): SBD MOMSN=19597814 2024-04-26T21:45:08.357Z,1714167908.357 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T21:45:09.617Z,1714167909.617 [Default:CheckIn:Read_Iridium] Stopped 2024-04-26T21:45:09.617Z,1714167909.617 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-26T21:45:09.617Z,1714167909.617 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-26T21:45:23.307Z,1714167923.307 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T21:45:28.163Z,1714167928.163 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-04-26T21:45:38.253Z,1714167938.253 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T21:45:53.607Z,1714167953.607 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T21:45:58.860Z,1714167958.860 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-26T21:50:10.191Z,1714168210.191 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-26T21:50:10.191Z,1714168210.191 [Default:CheckIn:C.Wait] Stopped 2024-04-26T21:50:10.191Z,1714168210.191 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-26T21:50:10.192Z,1714168210.192 [Default:CheckIn:D] Running Loop=1 2024-04-26T21:50:10.589Z,1714168210.589 [Default:CheckIn:D] Stopped 2024-04-26T21:50:10.589Z,1714168210.589 [Default:CheckIn:E] Running Loop=1 2024-04-26T21:50:10.980Z,1714168210.980 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 104.744995 min 2024-04-26T21:50:10.980Z,1714168210.980 [Default:CheckIn:E] Stopped 2024-04-26T21:50:10.980Z,1714168210.980 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-26T21:50:10.981Z,1714168210.981 [Default:CheckIn] Stopped 2024-04-26T21:50:10.981Z,1714168210.981 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-26T21:50:10.981Z,1714168210.981 [Default:CheckIn](INFO): Running loop #11 2024-04-26T21:50:10.981Z,1714168210.981 [Default:CheckIn] Running Loop=11 2024-04-26T21:50:10.981Z,1714168210.981 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-26T21:50:10.981Z,1714168210.981 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-26T21:50:43.743Z,1714168243.743 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2024-04-26T21:50:43.834Z,1714168243.834 [NAL9602](ERROR): received: +CSQ:0 OK 2024-04-26T21:52:58.674Z,1714168378.674 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5. 2024-04-26T21:52:58.677Z,1714168378.677 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle. 2024-04-26T21:55:11.235Z,1714168511.235 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-26T21:50:10.0Z 2024-04-26T21:55:11.235Z,1714168511.235 [Default:CheckIn:Read_GPS] Stopped 2024-04-26T21:55:11.235Z,1714168511.235 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-26T21:55:14.827Z,1714168514.827 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-04-26T21:55:18.777Z,1714168518.777 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20240426T200547/Courier0033.lzma 2024-04-26T21:55:19.780Z,1714168519.780 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Courier0033.lzma.bak 2024-04-26T21:55:19.780Z,1714168519.780 [DataOverHttps](INFO): SBD MOMSN=19597878 2024-04-26T21:55:35.734Z,1714168535.734 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20240426T200547/Express0034.lzma 2024-04-26T21:55:36.736Z,1714168536.736 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Express0034.lzma.bak 2024-04-26T21:55:36.736Z,1714168536.736 [DataOverHttps](INFO): SBD MOMSN=19597880 2024-04-26T21:55:38.677Z,1714168538.677 [Default:CheckIn:Read_Iridium] Stopped 2024-04-26T21:55:38.677Z,1714168538.677 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-26T21:55:38.677Z,1714168538.677 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-26T21:55:45.535Z,1714168545.535 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-26T22:00:39.256Z,1714168839.256 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-26T22:00:39.257Z,1714168839.257 [Default:CheckIn:C.Wait] Stopped 2024-04-26T22:00:39.257Z,1714168839.257 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-26T22:00:39.257Z,1714168839.257 [Default:CheckIn:D] Running Loop=1 2024-04-26T22:00:39.685Z,1714168839.685 [Default:CheckIn:D] Stopped 2024-04-26T22:00:39.685Z,1714168839.685 [Default:CheckIn:E] Running Loop=1 2024-04-26T22:00:40.065Z,1714168840.065 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 115.229932 min 2024-04-26T22:00:40.065Z,1714168840.065 [Default:CheckIn:E] Stopped 2024-04-26T22:00:40.065Z,1714168840.065 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-26T22:00:40.065Z,1714168840.065 [Default:CheckIn] Stopped 2024-04-26T22:00:40.065Z,1714168840.065 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-26T22:00:40.066Z,1714168840.066 [Default:CheckIn](INFO): Running loop #12 2024-04-26T22:00:40.066Z,1714168840.066 [Default:CheckIn] Running Loop=12 2024-04-26T22:00:40.066Z,1714168840.066 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-26T22:00:40.066Z,1714168840.066 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-26T22:00:41.659Z,1714168841.659 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-04-26T22:00:41.659Z,1714168841.659 [NAL9602] Data Fault, FailCount= 2 2024-04-26T22:00:41.659Z,1714168841.659 [NAL9602](ERROR): Data Fault 2024-04-26T22:00:41.750Z,1714168841.750 [CBIT](ERROR): Data Fault in component: NAL9602 2024-04-26T22:00:42.067Z,1714168842.067 [NAL9602](INFO): Powering down 2024-04-26T22:00:42.936Z,1714168842.936 [CBIT](INFO): Clearing failed state for component NAL9602 2024-04-26T22:00:42.936Z,1714168842.936 [NAL9602] No Fault, FailCount= 2 2024-04-26T22:01:12.373Z,1714168872.373 [NAL9602](INFO): Powering up NAL9602 2024-04-26T22:01:23.273Z,1714168883.273 [NAL9602](INFO): NAL9602 initialized 2024-04-26T22:05:40.232Z,1714169140.232 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-26T22:00:40.1Z 2024-04-26T22:05:40.233Z,1714169140.233 [Default:CheckIn:Read_GPS] Stopped 2024-04-26T22:05:40.233Z,1714169140.233 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-26T22:05:48.029Z,1714169148.029 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240426T200547/Courier0036.lzma 2024-04-26T22:05:49.032Z,1714169149.032 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Courier0036.lzma.bak 2024-04-26T22:05:49.032Z,1714169149.032 [DataOverHttps](INFO): SBD MOMSN=19597925 2024-04-26T22:06:02.843Z,1714169162.843 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2024-04-26T22:06:02.843Z,1714169162.843 [DropWeight] Hardware Fault, FailCount= 1 2024-04-26T22:06:02.843Z,1714169162.843 [DropWeight](ERROR): Hardware Fault 2024-04-26T22:06:02.917Z,1714169162.917 [CBIT](INFO): Critical error at 20240426T220602 2024-04-26T22:06:02.927Z,1714169162.927 [CBIT](ERROR): Hardware Fault in component: DropWeight 2024-04-26T22:06:02.930Z,1714169162.930 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2024-04-26T22:06:03.292Z,1714169163.292 [CBIT](INFO): Critical error at 20240426T220602 2024-04-26T22:06:04.994Z,1714169164.994 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20240426T200547/Express0037.lzma 2024-04-26T22:06:05.996Z,1714169165.996 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Express0037.lzma.bak 2024-04-26T22:06:05.996Z,1714169165.996 [DataOverHttps](INFO): SBD MOMSN=19597927 2024-04-26T22:06:08.132Z,1714169168.132 [Default:CheckIn:Read_Iridium] Stopped 2024-04-26T22:06:08.132Z,1714169168.132 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-26T22:06:08.132Z,1714169168.132 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-26T22:06:27.079Z,1714169187.079 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-04-26T22:06:57.795Z,1714169217.795 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-26T22:11:08.749Z,1714169468.749 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-26T22:11:08.749Z,1714169468.749 [Default:CheckIn:C.Wait] Stopped 2024-04-26T22:11:08.749Z,1714169468.749 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-26T22:11:08.749Z,1714169468.749 [Default:CheckIn:D] Running Loop=1 2024-04-26T22:11:09.159Z,1714169469.159 [Default:CheckIn:D] Stopped 2024-04-26T22:11:09.159Z,1714169469.159 [Default:CheckIn:E] Running Loop=1 2024-04-26T22:11:09.565Z,1714169469.565 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 125.721175 min 2024-04-26T22:11:09.565Z,1714169469.565 [Default:CheckIn:E] Stopped 2024-04-26T22:11:09.565Z,1714169469.565 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-26T22:11:09.565Z,1714169469.565 [Default:CheckIn] Stopped 2024-04-26T22:11:09.565Z,1714169469.565 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-26T22:11:09.565Z,1714169469.565 [Default:CheckIn](INFO): Running loop #13 2024-04-26T22:11:09.565Z,1714169469.565 [Default:CheckIn] Running Loop=13 2024-04-26T22:11:09.565Z,1714169469.565 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-26T22:11:09.566Z,1714169469.566 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-26T22:16:09.739Z,1714169769.739 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-26T22:11:09.6Z 2024-04-26T22:16:09.739Z,1714169769.739 [Default:CheckIn:Read_GPS] Stopped 2024-04-26T22:16:09.740Z,1714169769.740 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-26T22:16:13.355Z,1714169773.355 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-04-26T22:16:17.201Z,1714169777.201 [DataOverHttps](INFO): Sending 115 bytes from file Logs/20240426T200547/Courier0039.lzma 2024-04-26T22:16:18.204Z,1714169778.204 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Courier0039.lzma.bak 2024-04-26T22:16:18.204Z,1714169778.204 [DataOverHttps](INFO): SBD MOMSN=19597995 2024-04-26T22:16:35.542Z,1714169795.542 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20240426T200547/Express0040.lzma 2024-04-26T22:16:36.544Z,1714169796.544 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Express0040.lzma.bak 2024-04-26T22:16:36.544Z,1714169796.544 [DataOverHttps](INFO): SBD MOMSN=19597999 2024-04-26T22:16:38.421Z,1714169798.421 [Default:CheckIn:Read_Iridium] Stopped 2024-04-26T22:16:38.421Z,1714169798.421 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-26T22:16:38.421Z,1714169798.421 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-26T22:16:44.071Z,1714169804.071 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-26T22:21:03.049Z,1714170063.049 [CBIT](INFO): Clearing failed state for component DropWeight 2024-04-26T22:21:03.049Z,1714170063.049 [DropWeight] No Fault, FailCount= 1 2024-04-26T22:21:39.002Z,1714170099.002 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-26T22:21:39.002Z,1714170099.002 [Default:CheckIn:C.Wait] Stopped 2024-04-26T22:21:39.003Z,1714170099.003 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-26T22:21:39.003Z,1714170099.003 [Default:CheckIn:D] Running Loop=1 2024-04-26T22:21:39.395Z,1714170099.395 [Default:CheckIn:D] Stopped 2024-04-26T22:21:39.395Z,1714170099.395 [Default:CheckIn:E] Running Loop=1 2024-04-26T22:21:39.800Z,1714170099.800 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 136.225106 min 2024-04-26T22:21:39.800Z,1714170099.800 [Default:CheckIn:E] Stopped 2024-04-26T22:21:39.801Z,1714170099.801 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-26T22:21:39.801Z,1714170099.801 [Default:CheckIn] Stopped 2024-04-26T22:21:39.801Z,1714170099.801 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-26T22:21:39.801Z,1714170099.801 [Default:CheckIn](INFO): Running loop #14 2024-04-26T22:21:39.801Z,1714170099.801 [Default:CheckIn] Running Loop=14 2024-04-26T22:21:39.801Z,1714170099.801 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-26T22:21:39.801Z,1714170099.801 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-26T22:21:41.400Z,1714170101.400 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-04-26T22:21:41.400Z,1714170101.400 [NAL9602] Data Fault, FailCount= 3 2024-04-26T22:21:41.400Z,1714170101.400 [NAL9602](ERROR): Data Fault 2024-04-26T22:21:41.427Z,1714170101.427 [CBIT](ERROR): Data Fault in component: NAL9602 2024-04-26T22:21:41.812Z,1714170101.812 [NAL9602](INFO): Powering down 2024-04-26T22:21:42.657Z,1714170102.657 [CBIT](INFO): Clearing failed state for component NAL9602 2024-04-26T22:21:42.657Z,1714170102.657 [NAL9602] No Fault, FailCount= 3 2024-04-26T22:22:12.104Z,1714170132.104 [NAL9602](INFO): Powering up NAL9602 2024-04-26T22:22:23.011Z,1714170143.011 [NAL9602](INFO): NAL9602 initialized 2024-04-26T22:26:39.969Z,1714170399.969 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-26T22:21:39.8Z 2024-04-26T22:26:39.969Z,1714170399.969 [Default:CheckIn:Read_GPS] Stopped 2024-04-26T22:26:39.969Z,1714170399.969 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-26T22:26:47.129Z,1714170407.129 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240426T200547/Courier0042.lzma 2024-04-26T22:26:48.131Z,1714170408.131 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Courier0042.lzma.bak 2024-04-26T22:26:48.132Z,1714170408.132 [DataOverHttps](INFO): SBD MOMSN=19598016 2024-04-26T22:27:03.070Z,1714170423.070 [DataOverHttps](INFO): Sending 227 bytes from file Logs/20240426T200547/Express0043.lzma 2024-04-26T22:27:04.060Z,1714170424.060 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Express0043.lzma.bak 2024-04-26T22:27:04.060Z,1714170424.060 [DataOverHttps](INFO): SBD MOMSN=19598018 2024-04-26T22:27:05.913Z,1714170425.913 [Default:CheckIn:Read_Iridium] Stopped 2024-04-26T22:27:05.913Z,1714170425.913 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-26T22:27:05.913Z,1714170425.913 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-26T22:27:26.883Z,1714170446.883 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-04-26T22:27:57.584Z,1714170477.584 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-26T22:32:06.485Z,1714170726.485 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-26T22:32:06.485Z,1714170726.485 [Default:CheckIn:C.Wait] Stopped 2024-04-26T22:32:06.485Z,1714170726.485 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-26T22:32:06.485Z,1714170726.485 [Default:CheckIn:D] Running Loop=1 2024-04-26T22:32:06.876Z,1714170726.876 [Default:CheckIn:D] Stopped 2024-04-26T22:32:06.876Z,1714170726.876 [Default:CheckIn:E] Running Loop=1 2024-04-26T22:32:07.280Z,1714170727.280 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 146.683122 min 2024-04-26T22:32:07.280Z,1714170727.280 [Default:CheckIn:E] Stopped 2024-04-26T22:32:07.280Z,1714170727.280 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-26T22:32:07.280Z,1714170727.280 [Default:CheckIn] Stopped 2024-04-26T22:32:07.280Z,1714170727.280 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-26T22:32:07.281Z,1714170727.281 [Default:CheckIn](INFO): Running loop #15 2024-04-26T22:32:07.281Z,1714170727.281 [Default:CheckIn] Running Loop=15 2024-04-26T22:32:07.281Z,1714170727.281 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-26T22:32:07.281Z,1714170727.281 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-26T22:37:07.455Z,1714171027.455 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-26T22:32:07.3Z 2024-04-26T22:37:07.455Z,1714171027.455 [Default:CheckIn:Read_GPS] Stopped 2024-04-26T22:37:07.456Z,1714171027.456 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-26T22:37:11.096Z,1714171031.096 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-04-26T22:37:15.173Z,1714171035.173 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240426T200547/Courier0045.lzma 2024-04-26T22:37:16.176Z,1714171036.176 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Courier0045.lzma.bak 2024-04-26T22:37:16.176Z,1714171036.176 [DataOverHttps](INFO): SBD MOMSN=19598035 2024-04-26T22:37:32.110Z,1714171052.110 [DataOverHttps](INFO): Sending 210 bytes from file Logs/20240426T200547/Express0046.lzma 2024-04-26T22:37:33.112Z,1714171053.112 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Express0046.lzma.bak 2024-04-26T22:37:33.112Z,1714171053.112 [DataOverHttps](INFO): SBD MOMSN=19598037 2024-04-26T22:37:34.948Z,1714171054.948 [Default:CheckIn:Read_Iridium] Stopped 2024-04-26T22:37:34.948Z,1714171054.948 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-26T22:37:34.948Z,1714171054.948 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-26T22:37:41.800Z,1714171061.800 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-26T22:42:35.544Z,1714171355.544 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-26T22:42:35.544Z,1714171355.544 [Default:CheckIn:C.Wait] Stopped 2024-04-26T22:42:35.544Z,1714171355.544 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-26T22:42:35.544Z,1714171355.544 [Default:CheckIn:D] Running Loop=1 2024-04-26T22:42:35.931Z,1714171355.931 [Default:CheckIn:D] Stopped 2024-04-26T22:42:35.932Z,1714171355.932 [Default:CheckIn:E] Running Loop=1 2024-04-26T22:42:36.333Z,1714171356.333 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 157.167383 min 2024-04-26T22:42:36.333Z,1714171356.333 [Default:CheckIn:E] Stopped 2024-04-26T22:42:36.333Z,1714171356.333 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-26T22:42:36.333Z,1714171356.333 [Default:CheckIn] Stopped 2024-04-26T22:42:36.333Z,1714171356.333 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-26T22:42:36.334Z,1714171356.334 [Default:CheckIn](INFO): Running loop #16 2024-04-26T22:42:36.334Z,1714171356.334 [Default:CheckIn] Running Loop=16 2024-04-26T22:42:36.334Z,1714171356.334 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-26T22:42:36.334Z,1714171356.334 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-26T22:42:37.936Z,1714171357.936 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-04-26T22:42:37.936Z,1714171357.936 [NAL9602] Data Fault, FailCount= 4 2024-04-26T22:42:37.936Z,1714171357.936 [NAL9602](ERROR): Data Fault 2024-04-26T22:42:37.960Z,1714171357.960 [CBIT](ERROR): Data Fault in component: NAL9602 2024-04-26T22:42:38.339Z,1714171358.339 [NAL9602](INFO): Powering down 2024-04-26T22:42:39.202Z,1714171359.202 [CBIT](INFO): Clearing failed state for component NAL9602 2024-04-26T22:42:39.203Z,1714171359.203 [NAL9602] No Fault, FailCount= 4 2024-04-26T22:43:08.643Z,1714171388.643 [NAL9602](INFO): Powering up NAL9602 2024-04-26T22:43:19.547Z,1714171399.547 [NAL9602](INFO): NAL9602 initialized 2024-04-26T22:47:36.505Z,1714171656.505 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-26T22:42:36.3Z 2024-04-26T22:47:36.505Z,1714171656.505 [Default:CheckIn:Read_GPS] Stopped 2024-04-26T22:47:36.505Z,1714171656.505 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-26T22:47:44.229Z,1714171664.229 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240426T200547/Courier0048.lzma 2024-04-26T22:47:45.232Z,1714171665.232 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Courier0048.lzma.bak 2024-04-26T22:47:45.232Z,1714171665.232 [DataOverHttps](INFO): SBD MOMSN=19598093 2024-04-26T22:48:02.278Z,1714171682.278 [DataOverHttps](INFO): Sending 225 bytes from file Logs/20240426T200547/Express0049.lzma 2024-04-26T22:48:03.280Z,1714171683.280 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Express0049.lzma.bak 2024-04-26T22:48:03.280Z,1714171683.280 [DataOverHttps](INFO): SBD MOMSN=19598095 2024-04-26T22:48:05.201Z,1714171685.201 [Default:CheckIn:Read_Iridium] Stopped 2024-04-26T22:48:05.202Z,1714171685.202 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-26T22:48:05.202Z,1714171685.202 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-26T22:48:23.355Z,1714171703.355 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-04-26T22:48:54.063Z,1714171734.063 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-26T22:51:04.603Z,1714171864.603 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2024-04-26T22:51:04.604Z,1714171864.604 [DropWeight] Hardware Fault, FailCount= 1 2024-04-26T22:51:04.604Z,1714171864.604 [DropWeight](ERROR): Hardware Fault 2024-04-26T22:51:04.684Z,1714171864.684 [CBIT](INFO): Critical error at 20240426T225104 2024-04-26T22:51:04.694Z,1714171864.694 [CBIT](ERROR): Hardware Fault in component: DropWeight 2024-04-26T22:51:04.695Z,1714171864.695 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2024-04-26T22:51:05.047Z,1714171865.047 [CBIT](INFO): Critical error at 20240426T225104 2024-04-26T22:53:05.839Z,1714171985.839 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-26T22:53:05.839Z,1714171985.839 [Default:CheckIn:C.Wait] Stopped 2024-04-26T22:53:05.839Z,1714171985.839 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-26T22:53:05.840Z,1714171985.840 [Default:CheckIn:D] Running Loop=1 2024-04-26T22:53:06.236Z,1714171986.236 [Default:CheckIn:D] Stopped 2024-04-26T22:53:06.236Z,1714171986.236 [Default:CheckIn:E] Running Loop=1 2024-04-26T22:53:06.619Z,1714171986.619 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 167.672445 min 2024-04-26T22:53:06.619Z,1714171986.619 [Default:CheckIn:E] Stopped 2024-04-26T22:53:06.620Z,1714171986.620 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-26T22:53:06.620Z,1714171986.620 [Default:CheckIn] Stopped 2024-04-26T22:53:06.620Z,1714171986.620 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-26T22:53:06.620Z,1714171986.620 [Default:CheckIn](INFO): Running loop #17 2024-04-26T22:53:06.620Z,1714171986.620 [Default:CheckIn] Running Loop=17 2024-04-26T22:53:06.620Z,1714171986.620 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-26T22:53:06.620Z,1714171986.620 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-26T22:53:39.328Z,1714172019.328 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2024-04-26T22:53:39.406Z,1714172019.406 [NAL9602](ERROR): received: +CSQ:0 OK 2024-04-26T22:55:31.233Z,1714172131.233 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T22:55:38.102Z,1714172138.102 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T22:55:53.522Z,1714172153.522 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T22:56:09.209Z,1714172169.209 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T22:56:24.562Z,1714172184.562 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T22:56:40.722Z,1714172200.722 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T22:56:55.669Z,1714172215.669 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T22:57:10.620Z,1714172230.620 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T22:57:25.571Z,1714172245.571 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T22:57:40.520Z,1714172260.520 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T22:58:06.827Z,1714172286.827 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-26T22:53:06.6Z 2024-04-26T22:58:06.827Z,1714172286.827 [Default:CheckIn:Read_GPS] Stopped 2024-04-26T22:58:06.828Z,1714172286.828 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-26T22:58:11.431Z,1714172291.431 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-04-26T22:58:13.889Z,1714172293.889 [DataOverHttps](INFO): Sending 116 bytes from file Logs/20240426T200547/Courier0051.lzma 2024-04-26T22:58:14.892Z,1714172294.892 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Courier0051.lzma.bak 2024-04-26T22:58:14.892Z,1714172294.892 [DataOverHttps](INFO): SBD MOMSN=19598114 2024-04-26T22:58:30.950Z,1714172310.950 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20240426T200547/Express0052.lzma 2024-04-26T22:58:31.952Z,1714172311.952 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Express0052.lzma.bak 2024-04-26T22:58:31.952Z,1714172311.952 [DataOverHttps](INFO): SBD MOMSN=19598121 2024-04-26T22:58:34.187Z,1714172314.187 [Default:CheckIn:Read_Iridium] Stopped 2024-04-26T22:58:34.188Z,1714172314.188 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-26T22:58:34.188Z,1714172314.188 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-26T22:58:42.131Z,1714172322.131 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-26T23:03:12.424Z,1714172592.424 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2024-04-26T23:03:34.694Z,1714172614.694 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-26T23:03:34.694Z,1714172614.694 [Default:CheckIn:C.Wait] Stopped 2024-04-26T23:03:34.694Z,1714172614.694 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-26T23:03:34.695Z,1714172614.695 [Default:CheckIn:D] Running Loop=1 2024-04-26T23:03:35.085Z,1714172615.085 [Default:CheckIn:D] Stopped 2024-04-26T23:03:35.085Z,1714172615.085 [Default:CheckIn:E] Running Loop=1 2024-04-26T23:03:35.496Z,1714172615.496 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 178.153271 min 2024-04-26T23:03:35.496Z,1714172615.496 [Default:CheckIn:E] Stopped 2024-04-26T23:03:35.496Z,1714172615.496 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-26T23:03:35.496Z,1714172615.496 [Default:CheckIn] Stopped 2024-04-26T23:03:35.497Z,1714172615.497 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-26T23:03:35.497Z,1714172615.497 [Default:CheckIn](INFO): Running loop #18 2024-04-26T23:03:35.497Z,1714172615.497 [Default:CheckIn] Running Loop=18 2024-04-26T23:03:35.497Z,1714172615.497 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-26T23:03:35.497Z,1714172615.497 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-26T23:03:37.067Z,1714172617.067 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-04-26T23:03:37.067Z,1714172617.067 [NAL9602] Data Fault, FailCount= 5 2024-04-26T23:03:37.067Z,1714172617.067 [NAL9602](ERROR): Data Fault 2024-04-26T23:03:37.110Z,1714172617.110 [CBIT](ERROR): Data Fault in component: NAL9602 2024-04-26T23:03:37.111Z,1714172617.111 [CBIT](CRITICAL): Data Fault in component: NAL9602 2024-04-26T23:03:37.475Z,1714172617.475 [NAL9602](INFO): Powering down 2024-04-26T23:03:37.496Z,1714172617.496 [CBIT](INFO): Critical error at 20240426T230337 2024-04-26T23:04:59.481Z,1714172699.481 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T23:05:06.349Z,1714172706.349 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T23:05:10.643Z,1714172710.643 [DAT](INFO): DAT read: user:6>Rx Time:23:05:09.9609 2024-04-26T23:05:10.643Z,1714172710.643 [DAT](INFO): Rx dataTimestamp_ set to:1714172710.642898 2024-04-26T23:05:11.147Z,1714172711.147 [DAT](INFO): DAT read: 2024-04-26T23:05:11.148Z,1714172711.148 [DAT](INFO): DAT read: $Packet for address 50 2024-04-26T23:05:11.148Z,1714172711.148 [DAT](INFO): received a packet notification 2024-04-26T23:05:21.715Z,1714172721.715 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T23:05:23.015Z,1714172723.015 [DAT](INFO): DAT read: Rx Time:23:05:22.4108 2024-04-26T23:05:23.015Z,1714172723.015 [DAT](INFO): Rx dataTimestamp_ set to:1714172723.014806 2024-04-26T23:05:23.518Z,1714172723.518 [DAT](INFO): DAT read: 2024-04-26T23:05:23.519Z,1714172723.519 [DAT](INFO): DAT read: $Packet for address 50 2024-04-26T23:05:23.519Z,1714172723.519 [DAT](INFO): received a packet notification 2024-04-26T23:05:35.647Z,1714172735.647 [DAT](INFO): DAT read: Rx Time:23:05:34.8608 2024-04-26T23:05:35.647Z,1714172735.647 [DAT](INFO): Rx dataTimestamp_ set to:1714172735.646819 2024-04-26T23:05:36.150Z,1714172736.150 [DAT](INFO): DAT read: 2024-04-26T23:05:36.151Z,1714172736.151 [DAT](INFO): DAT read: $Packet for address 50 2024-04-26T23:05:36.151Z,1714172736.151 [DAT](INFO): received a packet notification 2024-04-26T23:05:37.122Z,1714172737.122 [CBIT](INFO): Clearing failed state for component NAL9602 2024-04-26T23:05:37.123Z,1714172737.123 [NAL9602] No Fault, FailCount= 5 2024-04-26T23:05:37.457Z,1714172737.457 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T23:05:37.465Z,1714172737.465 [NAL9602](INFO): Powering up NAL9602 2024-04-26T23:05:47.999Z,1714172747.999 [DAT](INFO): DAT read: Rx Time:23:05:47.3108 2024-04-26T23:05:47.999Z,1714172747.999 [DAT](INFO): Rx dataTimestamp_ set to:1714172747.998715 2024-04-26T23:05:48.388Z,1714172748.388 [NAL9602](INFO): NAL9602 initialized 2024-04-26T23:05:48.502Z,1714172748.502 [DAT](INFO): DAT read: 2024-04-26T23:05:48.503Z,1714172748.503 [DAT](INFO): DAT read: $Packet for address 50 2024-04-26T23:05:48.503Z,1714172748.503 [DAT](INFO): received a packet notification 2024-04-26T23:05:53.213Z,1714172753.213 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T23:06:00.355Z,1714172760.355 [DAT](INFO): DAT read: Rx Time:23:05:59.7608 2024-04-26T23:06:00.355Z,1714172760.355 [DAT](INFO): Rx dataTimestamp_ set to:1714172760.354898 2024-04-26T23:06:01.110Z,1714172761.110 [DAT](INFO): DAT read: 2024-04-26T23:06:01.111Z,1714172761.111 [DAT](INFO): DAT read: $Packet for address 50 2024-04-26T23:06:01.111Z,1714172761.111 [DAT](INFO): received a packet notification 2024-04-26T23:06:04.953Z,1714172764.953 [CBIT](INFO): Clearing failed state for component DropWeight 2024-04-26T23:06:04.953Z,1714172764.953 [DropWeight] No Fault, FailCount= 1 2024-04-26T23:06:08.567Z,1714172768.567 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T23:06:12.983Z,1714172772.983 [DAT](INFO): DAT read: Rx Time:23:06:12.2107 2024-04-26T23:06:12.983Z,1714172772.983 [DAT](INFO): Rx dataTimestamp_ set to:1714172772.982800 2024-04-26T23:06:13.486Z,1714172773.486 [DAT](INFO): DAT read: 2024-04-26T23:06:13.487Z,1714172773.487 [DAT](INFO): DAT read: $Packet for address 50 2024-04-26T23:06:13.487Z,1714172773.487 [DAT](INFO): received a packet notification 2024-04-26T23:06:23.514Z,1714172783.514 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T23:06:25.339Z,1714172785.339 [DAT](INFO): DAT read: Rx Time:23:06:24.6607 2024-04-26T23:06:25.339Z,1714172785.339 [DAT](INFO): Rx dataTimestamp_ set to:1714172785.338839 2024-04-26T23:06:25.842Z,1714172785.842 [DAT](INFO): DAT read: 2024-04-26T23:06:25.843Z,1714172785.843 [DAT](INFO): DAT read: $Packet for address 50 2024-04-26T23:06:25.843Z,1714172785.843 [DAT](INFO): received a packet notification 2024-04-26T23:06:37.711Z,1714172797.711 [DAT](INFO): DAT read: Rx Time:23:06:37.1107 2024-04-26T23:06:37.712Z,1714172797.712 [DAT](INFO): Rx dataTimestamp_ set to:1714172797.711272 2024-04-26T23:06:38.215Z,1714172798.215 [DAT](INFO): DAT read: 2024-04-26T23:06:38.467Z,1714172798.467 [DAT](INFO): DAT read: $Packet for address 50 2024-04-26T23:06:38.467Z,1714172798.467 [DAT](INFO): received a packet notification 2024-04-26T23:06:38.865Z,1714172798.865 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T23:06:50.323Z,1714172810.323 [DAT](INFO): DAT read: Rx Time:23:06:49.5607 2024-04-26T23:06:50.323Z,1714172810.323 [DAT](INFO): Rx dataTimestamp_ set to:1714172810.322842 2024-04-26T23:06:50.826Z,1714172810.826 [DAT](INFO): DAT read: 2024-04-26T23:06:50.827Z,1714172810.827 [DAT](INFO): DAT read: $Packet for address 50 2024-04-26T23:06:50.827Z,1714172810.827 [DAT](INFO): received a packet notification 2024-04-26T23:06:53.816Z,1714172813.816 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T23:07:02.691Z,1714172822.691 [DAT](INFO): DAT read: Rx Time:23:07:02.0107 2024-04-26T23:07:02.691Z,1714172822.691 [DAT](INFO): Rx dataTimestamp_ set to:1714172822.690881 2024-04-26T23:07:03.195Z,1714172823.195 [DAT](INFO): DAT read: 2024-04-26T23:07:03.195Z,1714172823.195 [DAT](INFO): DAT read: $Packet for address 50 2024-04-26T23:07:03.196Z,1714172823.196 [DAT](INFO): received a packet notification 2024-04-26T23:07:09.178Z,1714172829.178 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T23:07:15.291Z,1714172835.291 [DAT](INFO): DAT read: Rx Time:23:07:14.4607 2024-04-26T23:07:15.291Z,1714172835.291 [DAT](INFO): Rx dataTimestamp_ set to:1714172835.290756 2024-04-26T23:07:15.794Z,1714172835.794 [DAT](INFO): DAT read: 2024-04-26T23:07:15.795Z,1714172835.795 [DAT](INFO): DAT read: $Packet for address 50 2024-04-26T23:07:15.795Z,1714172835.795 [DAT](INFO): received a packet notification 2024-04-26T23:07:27.659Z,1714172847.659 [DAT](INFO): DAT read: Rx Time:23:07:26.9105 2024-04-26T23:07:27.659Z,1714172847.659 [DAT](INFO): Rx dataTimestamp_ set to:1714172847.658783 2024-04-26T23:07:28.167Z,1714172848.167 [DAT](INFO): DAT read: 2024-04-26T23:07:28.168Z,1714172848.168 [DAT](INFO): DAT read: $Packet for address 50 2024-04-26T23:07:28.168Z,1714172848.168 [DAT](INFO): received a packet notification 2024-04-26T23:07:40.035Z,1714172860.035 [DAT](INFO): DAT read: Rx Time:23:07:39.3605 2024-04-26T23:07:40.035Z,1714172860.035 [DAT](INFO): Rx dataTimestamp_ set to:1714172860.034861 2024-04-26T23:07:40.538Z,1714172860.538 [DAT](INFO): DAT read: 2024-04-26T23:07:40.539Z,1714172860.539 [DAT](INFO): DAT read: $Packet for address 50 2024-04-26T23:07:40.540Z,1714172860.540 [DAT](INFO): received a packet notification 2024-04-26T23:07:52.639Z,1714172872.639 [DAT](INFO): DAT read: Rx Time:23:07:51.8105 2024-04-26T23:07:52.639Z,1714172872.639 [DAT](INFO): Rx dataTimestamp_ set to:1714172872.638774 2024-04-26T23:07:53.143Z,1714172873.143 [DAT](INFO): DAT read: 2024-04-26T23:07:53.143Z,1714172873.143 [DAT](INFO): DAT read: $Packet for address 50 2024-04-26T23:07:53.144Z,1714172873.144 [DAT](INFO): received a packet notification 2024-04-26T23:08:05.011Z,1714172885.011 [DAT](INFO): DAT read: Rx Time:23:08:04.2605 2024-04-26T23:08:05.011Z,1714172885.011 [DAT](INFO): Rx dataTimestamp_ set to:1714172885.010888 2024-04-26T23:08:05.514Z,1714172885.514 [DAT](INFO): DAT read: 2024-04-26T23:08:05.515Z,1714172885.515 [DAT](INFO): DAT read: $Packet for address 50 2024-04-26T23:08:05.515Z,1714172885.515 [DAT](INFO): received a packet notification 2024-04-26T23:08:17.359Z,1714172897.359 [DAT](INFO): DAT read: Rx Time:23:08:16.7105 2024-04-26T23:08:17.359Z,1714172897.359 [DAT](INFO): Rx dataTimestamp_ set to:1714172897.358867 2024-04-26T23:08:17.862Z,1714172897.862 [DAT](INFO): DAT read: 2024-04-26T23:08:17.863Z,1714172897.863 [DAT](INFO): DAT read: $Packet for address 50 2024-04-26T23:08:17.863Z,1714172897.863 [DAT](INFO): received a packet notification 2024-04-26T23:08:29.161Z,1714172909.161 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2024-04-26T23:08:29.960Z,1714172909.960 [DAT](INFO): DAT read: Rx Time:23:08:29.1605 2024-04-26T23:08:29.961Z,1714172909.961 [DAT](INFO): Rx dataTimestamp_ set to:1714172909.960251 2024-04-26T23:08:30.462Z,1714172910.462 [DAT](INFO): DAT read: 2024-04-26T23:08:30.463Z,1714172910.463 [DAT](INFO): DAT read: $Packet for address 50 2024-04-26T23:08:30.463Z,1714172910.463 [DAT](INFO): received a packet notification 2024-04-26T23:08:35.649Z,1714172915.649 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-26T23:03:35.5Z 2024-04-26T23:08:35.650Z,1714172915.650 [Default:CheckIn:Read_GPS] Stopped 2024-04-26T23:08:35.650Z,1714172915.650 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-26T23:08:42.323Z,1714172922.323 [DAT](INFO): DAT read: Rx Time:23:08:41.6105 2024-04-26T23:08:42.323Z,1714172922.323 [DAT](INFO): Rx dataTimestamp_ set to:1714172922.322838 2024-04-26T23:08:42.609Z,1714172922.609 [DataOverHttps](INFO): Sending 81 bytes from file Logs/20240426T200547/Courier0054.lzma 2024-04-26T23:08:42.826Z,1714172922.826 [DAT](INFO): DAT read: 2024-04-26T23:08:42.827Z,1714172922.827 [DAT](INFO): DAT read: $Packet for address 50 2024-04-26T23:08:42.827Z,1714172922.827 [DAT](INFO): received a packet notification 2024-04-26T23:08:43.612Z,1714172923.612 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Courier0054.lzma.bak 2024-04-26T23:08:43.612Z,1714172923.612 [DataOverHttps](INFO): SBD MOMSN=19598188 2024-04-26T23:08:59.694Z,1714172939.694 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20240426T200547/Express0055.lzma 2024-04-26T23:09:00.696Z,1714172940.696 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Express0055.lzma.bak 2024-04-26T23:09:00.696Z,1714172940.696 [DataOverHttps](INFO): SBD MOMSN=19598191 2024-04-26T23:09:02.793Z,1714172942.793 [Default:CheckIn:Read_Iridium] Stopped 2024-04-26T23:09:02.793Z,1714172942.793 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-26T23:09:02.793Z,1714172942.793 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-26T23:10:52.287Z,1714173052.287 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-04-26T23:11:22.995Z,1714173082.995 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-26T23:14:03.380Z,1714173243.380 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-26T23:14:03.380Z,1714173243.380 [Default:CheckIn:C.Wait] Stopped 2024-04-26T23:14:03.380Z,1714173243.380 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-26T23:14:03.380Z,1714173243.380 [Default:CheckIn:D] Running Loop=1 2024-04-26T23:14:03.791Z,1714173243.791 [Default:CheckIn:D] Stopped 2024-04-26T23:14:03.792Z,1714173243.792 [Default:CheckIn:E] Running Loop=1 2024-04-26T23:14:04.201Z,1714173244.201 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 188.631706 min 2024-04-26T23:14:04.201Z,1714173244.201 [Default:CheckIn:E] Stopped 2024-04-26T23:14:04.201Z,1714173244.201 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-26T23:14:04.201Z,1714173244.201 [Default:CheckIn] Stopped 2024-04-26T23:14:04.201Z,1714173244.201 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-26T23:14:04.201Z,1714173244.201 [Default:CheckIn](INFO): Running loop #19 2024-04-26T23:14:04.201Z,1714173244.201 [Default:CheckIn] Running Loop=19 2024-04-26T23:14:04.202Z,1714173244.202 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-26T23:14:04.202Z,1714173244.202 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-26T23:14:36.903Z,1714173276.903 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2024-04-26T23:14:36.986Z,1714173276.986 [NAL9602](ERROR): received: +CSQ:0 OK 2024-04-26T23:14:43.766Z,1714173283.766 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T23:14:59.130Z,1714173299.130 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T23:15:14.065Z,1714173314.065 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T23:15:29.418Z,1714173329.418 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T23:15:44.770Z,1714173344.770 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T23:15:59.718Z,1714173359.718 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T23:16:15.072Z,1714173375.072 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T23:16:30.421Z,1714173390.421 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T23:16:45.782Z,1714173405.782 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-26T23:19:04.371Z,1714173544.371 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-26T23:14:04.2Z 2024-04-26T23:19:04.372Z,1714173544.372 [Default:CheckIn:Read_GPS] Stopped 2024-04-26T23:19:04.372Z,1714173544.372 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-26T23:19:07.983Z,1714173547.983 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-04-26T23:19:11.605Z,1714173551.605 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240426T200547/Courier0057.lzma 2024-04-26T23:19:12.608Z,1714173552.608 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Courier0057.lzma.bak 2024-04-26T23:19:12.608Z,1714173552.608 [DataOverHttps](INFO): SBD MOMSN=19598207 2024-04-26T23:19:28.590Z,1714173568.590 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20240426T200547/Express0058.lzma 2024-04-26T23:19:29.592Z,1714173569.592 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Express0058.lzma.bak 2024-04-26T23:19:29.592Z,1714173569.592 [DataOverHttps](INFO): SBD MOMSN=19598209 2024-04-26T23:19:31.467Z,1714173571.467 [Default:CheckIn:Read_Iridium] Stopped 2024-04-26T23:19:31.467Z,1714173571.467 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-26T23:19:31.467Z,1714173571.467 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-26T23:19:38.713Z,1714173578.713 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-26T23:23:59.726Z,1714173839.726 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5. 2024-04-26T23:23:59.728Z,1714173839.728 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle. 2024-04-26T23:24:32.064Z,1714173872.064 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-26T23:24:32.064Z,1714173872.064 [Default:CheckIn:C.Wait] Stopped 2024-04-26T23:24:32.064Z,1714173872.064 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-26T23:24:32.064Z,1714173872.064 [Default:CheckIn:D] Running Loop=1 2024-04-26T23:24:32.477Z,1714173872.477 [Default:CheckIn:D] Stopped 2024-04-26T23:24:32.478Z,1714173872.478 [Default:CheckIn:E] Running Loop=1 2024-04-26T23:24:32.872Z,1714173872.872 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 199.109814 min 2024-04-26T23:24:32.872Z,1714173872.872 [Default:CheckIn:E] Stopped 2024-04-26T23:24:32.872Z,1714173872.872 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-26T23:24:32.872Z,1714173872.872 [Default:CheckIn] Stopped 2024-04-26T23:24:32.872Z,1714173872.872 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-26T23:24:32.872Z,1714173872.872 [Default:CheckIn](INFO): Running loop #20 2024-04-26T23:24:32.872Z,1714173872.872 [Default:CheckIn] Running Loop=20 2024-04-26T23:24:32.872Z,1714173872.872 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-26T23:24:32.873Z,1714173872.873 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-26T23:24:34.475Z,1714173874.475 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-04-26T23:24:34.475Z,1714173874.475 [NAL9602] Data Fault, FailCount= 1 2024-04-26T23:24:34.475Z,1714173874.475 [NAL9602](ERROR): Data Fault 2024-04-26T23:24:34.521Z,1714173874.521 [CBIT](ERROR): Data Fault in component: NAL9602 2024-04-26T23:24:34.877Z,1714173874.877 [NAL9602](INFO): Powering down 2024-04-26T23:24:35.732Z,1714173875.732 [CBIT](INFO): Clearing failed state for component NAL9602 2024-04-26T23:24:35.732Z,1714173875.732 [NAL9602] No Fault, FailCount= 1 2024-04-26T23:25:05.175Z,1714173905.175 [NAL9602](INFO): Powering up NAL9602 2024-04-26T23:25:16.083Z,1714173916.083 [NAL9602](INFO): NAL9602 initialized 2024-04-26T23:29:33.064Z,1714174173.064 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-26T23:24:32.9Z 2024-04-26T23:29:33.064Z,1714174173.064 [Default:CheckIn:Read_GPS] Stopped 2024-04-26T23:29:33.064Z,1714174173.064 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-26T23:29:40.873Z,1714174180.873 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20240426T200547/Courier0060.lzma 2024-04-26T23:29:41.876Z,1714174181.876 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Courier0060.lzma.bak 2024-04-26T23:29:41.876Z,1714174181.876 [DataOverHttps](INFO): SBD MOMSN=19598229 2024-04-26T23:29:57.734Z,1714174197.734 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20240426T200547/Express0061.lzma 2024-04-26T23:29:58.737Z,1714174198.737 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Express0061.lzma.bak 2024-04-26T23:29:58.737Z,1714174198.737 [DataOverHttps](INFO): SBD MOMSN=19598231 2024-04-26T23:30:00.944Z,1714174200.944 [Default:CheckIn:Read_Iridium] Stopped 2024-04-26T23:30:00.944Z,1714174200.944 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-26T23:30:00.944Z,1714174200.944 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-26T23:30:19.901Z,1714174219.901 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-04-26T23:30:50.611Z,1714174250.611 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-26T23:35:01.509Z,1714174501.509 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-26T23:35:01.509Z,1714174501.509 [Default:CheckIn:C.Wait] Stopped 2024-04-26T23:35:01.509Z,1714174501.509 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-26T23:35:01.510Z,1714174501.510 [Default:CheckIn:D] Running Loop=1 2024-04-26T23:35:01.924Z,1714174501.924 [Default:CheckIn:D] Stopped 2024-04-26T23:35:01.924Z,1714174501.924 [Default:CheckIn:E] Running Loop=1 2024-04-26T23:35:02.339Z,1714174502.339 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 209.600586 min 2024-04-26T23:35:02.340Z,1714174502.340 [Default:CheckIn:E] Stopped 2024-04-26T23:35:02.340Z,1714174502.340 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-26T23:35:02.340Z,1714174502.340 [Default:CheckIn] Stopped 2024-04-26T23:35:02.340Z,1714174502.340 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-26T23:35:02.340Z,1714174502.340 [Default:CheckIn](INFO): Running loop #21 2024-04-26T23:35:02.340Z,1714174502.340 [Default:CheckIn] Running Loop=21 2024-04-26T23:35:02.340Z,1714174502.340 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-26T23:35:02.340Z,1714174502.340 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-26T23:35:35.031Z,1714174535.031 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2024-04-26T23:35:35.110Z,1714174535.110 [NAL9602](ERROR): received: +CSQ:0 OK 2024-04-26T23:36:06.539Z,1714174566.539 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2024-04-26T23:36:06.539Z,1714174566.539 [DropWeight] Hardware Fault, FailCount= 1 2024-04-26T23:36:06.539Z,1714174566.539 [DropWeight](ERROR): Hardware Fault 2024-04-26T23:36:06.624Z,1714174566.624 [CBIT](INFO): Critical error at 20240426T233606 2024-04-26T23:36:06.635Z,1714174566.635 [CBIT](ERROR): Hardware Fault in component: DropWeight 2024-04-26T23:36:06.635Z,1714174566.635 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2024-04-26T23:36:07.000Z,1714174567.000 [CBIT](INFO): Critical error at 20240426T233606 2024-04-26T23:39:13.967Z,1714174753.967 [DAT](INFO): DAT read: Rx Time:23:39:13.1977 2024-04-26T23:39:13.967Z,1714174753.967 [DAT](INFO): Rx dataTimestamp_ set to:1714174753.966832 2024-04-26T23:39:14.472Z,1714174754.472 [DAT](INFO): DAT read: Tx time:23:39:14.2796 2024-04-26T23:39:14.472Z,1714174754.472 [DAT](INFO): Ping request sent. 2024-04-26T23:39:14.472Z,1714174754.472 [DAT](INFO): transmitted an acoustic signal 2024-04-26T23:39:14.473Z,1714174754.473 [DAT](INFO): publishing transmit ping time 2024-04-26T23:39:14.473Z,1714174754.473 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000900 2024-04-26T23:39:14.723Z,1714174754.723 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250065 2024-04-26T23:39:14.975Z,1714174754.975 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502249 2024-04-26T23:39:15.226Z,1714174755.226 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754007 2024-04-26T23:39:15.479Z,1714174755.479 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006097 2024-04-26T23:39:15.731Z,1714174755.731 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258109 2024-04-26T23:39:15.983Z,1714174755.983 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510077 2024-04-26T23:39:16.235Z,1714174756.235 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762234 2024-04-26T23:39:16.487Z,1714174756.487 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014092 2024-04-26T23:39:16.739Z,1714174756.739 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266044 2024-04-26T23:39:16.991Z,1714174756.991 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518085 2024-04-26T23:39:17.243Z,1714174757.243 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770244 2024-04-26T23:39:17.495Z,1714174757.495 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022093 2024-04-26T23:39:17.747Z,1714174757.747 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274075 2024-04-26T23:39:17.999Z,1714174757.999 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.526115 2024-04-26T23:39:18.251Z,1714174758.251 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.778047 2024-04-26T23:39:18.503Z,1714174758.503 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.030477 2024-04-26T23:39:18.755Z,1714174758.755 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.282156 2024-04-26T23:39:19.006Z,1714174759.006 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.534006 2024-04-26T23:39:19.259Z,1714174759.259 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.786204 2024-04-26T23:39:19.511Z,1714174759.511 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.038113 2024-04-26T23:39:19.763Z,1714174759.763 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.290046 2024-04-26T23:39:20.015Z,1714174760.015 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.542073 2024-04-26T23:39:20.267Z,1714174760.267 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.794136 2024-04-26T23:39:20.535Z,1714174760.535 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.062109 2024-04-26T23:39:20.787Z,1714174760.787 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.314089 2024-04-26T23:39:21.039Z,1714174761.039 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.566064 2024-04-26T23:39:21.290Z,1714174761.290 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.817976 2024-04-26T23:39:21.543Z,1714174761.543 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.070316 2024-04-26T23:39:21.795Z,1714174761.795 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.322129 2024-04-26T23:39:22.047Z,1714174762.047 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.574052 2024-04-26T23:39:22.299Z,1714174762.299 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.826343 2024-04-26T23:39:22.551Z,1714174762.551 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.078165 2024-04-26T23:39:22.803Z,1714174762.803 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.330062 2024-04-26T23:39:23.055Z,1714174763.055 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.582317 2024-04-26T23:39:23.307Z,1714174763.307 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.834199 2024-04-26T23:39:23.559Z,1714174763.559 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.086058 2024-04-26T23:39:23.811Z,1714174763.811 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.338342 2024-04-26T23:39:24.063Z,1714174764.063 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.590124 2024-04-26T23:39:24.315Z,1714174764.315 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.842058 2024-04-26T23:40:02.517Z,1714174802.517 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-26T23:35:02.3Z 2024-04-26T23:40:02.517Z,1714174802.517 [Default:CheckIn:Read_GPS] Stopped 2024-04-26T23:40:02.517Z,1714174802.517 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-26T23:40:06.919Z,1714174806.919 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-04-26T23:40:09.709Z,1714174809.709 [DataOverHttps](INFO): Sending 115 bytes from file Logs/20240426T200547/Courier0063.lzma 2024-04-26T23:40:10.712Z,1714174810.712 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Courier0063.lzma.bak 2024-04-26T23:40:10.712Z,1714174810.712 [DataOverHttps](INFO): SBD MOMSN=19598257 2024-04-26T23:40:26.638Z,1714174826.638 [DataOverHttps](INFO): Sending 210 bytes from file Logs/20240426T200547/Express0064.lzma 2024-04-26T23:40:27.640Z,1714174827.640 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Express0064.lzma.bak 2024-04-26T23:40:27.640Z,1714174827.640 [DataOverHttps](INFO): SBD MOMSN=19598261 2024-04-26T23:40:29.577Z,1714174829.577 [Default:CheckIn:Read_Iridium] Stopped 2024-04-26T23:40:29.577Z,1714174829.577 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-26T23:40:29.577Z,1714174829.577 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-26T23:40:37.627Z,1714174837.627 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-26T23:45:30.143Z,1714175130.143 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-26T23:45:30.144Z,1714175130.144 [Default:CheckIn:C.Wait] Stopped 2024-04-26T23:45:30.144Z,1714175130.144 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-26T23:45:30.144Z,1714175130.144 [Default:CheckIn:D] Running Loop=1 2024-04-26T23:45:30.553Z,1714175130.553 [Default:CheckIn:D] Stopped 2024-04-26T23:45:30.553Z,1714175130.553 [Default:CheckIn:E] Running Loop=1 2024-04-26T23:45:30.966Z,1714175130.966 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 220.077734 min 2024-04-26T23:45:30.966Z,1714175130.966 [Default:CheckIn:E] Stopped 2024-04-26T23:45:30.966Z,1714175130.966 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-26T23:45:30.966Z,1714175130.966 [Default:CheckIn] Stopped 2024-04-26T23:45:30.966Z,1714175130.966 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-26T23:45:30.966Z,1714175130.966 [Default:CheckIn](INFO): Running loop #22 2024-04-26T23:45:30.967Z,1714175130.967 [Default:CheckIn] Running Loop=22 2024-04-26T23:45:30.967Z,1714175130.967 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-26T23:45:30.967Z,1714175130.967 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-26T23:45:32.551Z,1714175132.551 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-04-26T23:45:32.552Z,1714175132.552 [NAL9602] Data Fault, FailCount= 2 2024-04-26T23:45:32.552Z,1714175132.552 [NAL9602](ERROR): Data Fault 2024-04-26T23:45:32.606Z,1714175132.606 [CBIT](ERROR): Data Fault in component: NAL9602 2024-04-26T23:45:32.955Z,1714175132.955 [NAL9602](INFO): Powering down 2024-04-26T23:45:33.791Z,1714175133.791 [CBIT](INFO): Clearing failed state for component NAL9602 2024-04-26T23:45:33.791Z,1714175133.791 [NAL9602] No Fault, FailCount= 2 2024-04-26T23:46:03.251Z,1714175163.251 [NAL9602](INFO): Powering up NAL9602 2024-04-26T23:46:14.163Z,1714175174.163 [NAL9602](INFO): NAL9602 initialized 2024-04-26T23:48:41.966Z,1714175321.966 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-26T23:48:43.974Z,1714175323.974 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.243252 2024-04-26T23:48:48.930Z,1714175328.930 [DAT](INFO): DAT read: 2024-04-26T23:48:48.931Z,1714175328.931 [DAT](INFO): DAT read: >>> Watchdog: 0x0010 [ PM ] 2024-04-26T23:48:48.932Z,1714175328.932 [DAT](DEBUG): Re-entering command mode due to deviceResponse_: >>> Watchdog: 0x0010 [ PM ] 2024-04-26T23:48:54.990Z,1714175334.990 [DAT](INFO): DAT read: 2024-04-26T23:48:54.991Z,1714175334.991 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2024-04-26T23:48:56.759Z,1714175336.759 [DAT](INFO): DAT read: MF Frequency Band 2024-04-26T23:48:56.760Z,1714175336.760 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2024-04-26T23:48:56.761Z,1714175336.761 [DAT](INFO): DAT read: Apr 26 2024 23:48:51 2024-04-26T23:48:58.023Z,1714175338.023 [DAT](INFO): DAT read: Features enabled [Bearing] 2024-04-26T23:48:58.024Z,1714175338.024 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2024-04-26T23:48:58.024Z,1714175338.024 [DAT](INFO): commRate: 800 2024-04-26T23:49:17.110Z,1714175357.110 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-26T23:49:52.250Z,1714175392.250 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-26T23:50:27.390Z,1714175427.390 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-26T23:50:31.123Z,1714175431.123 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-26T23:45:30.0Z 2024-04-26T23:50:31.124Z,1714175431.124 [Default:CheckIn:Read_GPS] Stopped 2024-04-26T23:50:31.124Z,1714175431.124 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-26T23:51:02.530Z,1714175462.530 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-26T23:51:06.722Z,1714175466.722 [CBIT](INFO): Clearing failed state for component DropWeight 2024-04-26T23:51:06.722Z,1714175466.722 [DropWeight] No Fault, FailCount= 1 2024-04-26T23:51:17.970Z,1714175477.970 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-04-26T23:51:37.670Z,1714175497.670 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-26T23:52:12.814Z,1714175532.814 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-26T23:52:47.954Z,1714175567.954 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-26T23:53:23.094Z,1714175603.094 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-26T23:53:58.243Z,1714175638.243 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-26T23:54:33.382Z,1714175673.382 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-26T23:55:08.522Z,1714175708.522 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-26T23:55:43.662Z,1714175743.662 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-26T23:56:18.818Z,1714175778.818 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-26T23:56:53.958Z,1714175813.958 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-26T23:57:29.098Z,1714175849.098 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-26T23:58:04.238Z,1714175884.238 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-26T23:58:39.382Z,1714175919.382 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-26T23:59:14.522Z,1714175954.522 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-26T23:59:49.662Z,1714175989.662 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:00:24.802Z,1714176024.802 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:00:59.942Z,1714176059.942 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:01:35.086Z,1714176095.086 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:02:10.250Z,1714176130.250 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:02:45.390Z,1714176165.390 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:03:20.546Z,1714176200.546 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:03:55.686Z,1714176235.686 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:04:30.826Z,1714176270.826 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:05:05.974Z,1714176305.974 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:05:41.114Z,1714176341.114 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:06:16.254Z,1714176376.254 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:06:51.394Z,1714176411.394 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:07:26.534Z,1714176446.534 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:08:01.690Z,1714176481.690 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:08:36.842Z,1714176516.842 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:09:12.007Z,1714176552.007 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:09:47.146Z,1714176587.146 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:10:22.286Z,1714176622.286 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:10:57.426Z,1714176657.426 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:11:32.586Z,1714176692.586 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:12:07.726Z,1714176727.726 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:12:42.866Z,1714176762.866 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:13:18.006Z,1714176798.006 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:13:53.150Z,1714176833.150 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:14:28.314Z,1714176868.314 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:15:03.458Z,1714176903.458 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:15:38.598Z,1714176938.598 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:16:13.742Z,1714176973.742 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:16:48.882Z,1714177008.882 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:17:24.022Z,1714177044.022 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:17:59.182Z,1714177079.182 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:18:34.322Z,1714177114.322 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:19:09.478Z,1714177149.478 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:19:44.630Z,1714177184.630 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:20:19.770Z,1714177219.770 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:20:54.910Z,1714177254.910 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:21:08.042Z,1714177268.042 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2024-04-27T00:21:08.042Z,1714177268.042 [DropWeight] Hardware Fault, FailCount= 1 2024-04-27T00:21:08.042Z,1714177268.042 [DropWeight](ERROR): Hardware Fault 2024-04-27T00:21:08.063Z,1714177268.063 [CBIT](INFO): Critical error at 20240427T002108 2024-04-27T00:21:08.065Z,1714177268.065 [CBIT](ERROR): Hardware Fault in component: DropWeight 2024-04-27T00:21:08.086Z,1714177268.086 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2024-04-27T00:21:08.520Z,1714177268.520 [CBIT](INFO): Critical error at 20240427T002108 2024-04-27T00:21:30.054Z,1714177290.054 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:22:05.194Z,1714177325.194 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:22:40.338Z,1714177360.338 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:23:15.478Z,1714177395.478 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:23:50.646Z,1714177430.646 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:24:25.786Z,1714177465.786 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:25:00.931Z,1714177500.931 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:25:36.086Z,1714177536.086 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:26:11.226Z,1714177571.226 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:26:29.615Z,1714177589.615 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-27T00:26:36.485Z,1714177596.485 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-27T00:26:46.366Z,1714177606.366 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:26:51.610Z,1714177611.610 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-27T00:27:06.661Z,1714177626.661 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-27T00:27:21.506Z,1714177641.506 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:27:22.106Z,1714177642.106 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-27T00:27:38.315Z,1714177658.315 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-27T00:27:53.765Z,1714177673.765 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-27T00:27:56.663Z,1714177676.663 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:28:09.853Z,1714177689.853 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-27T00:28:25.048Z,1714177705.048 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-27T00:28:31.822Z,1714177711.822 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:28:41.254Z,1714177721.254 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-27T00:29:06.970Z,1714177746.970 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:29:42.114Z,1714177782.114 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:30:17.254Z,1714177817.254 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:30:52.394Z,1714177852.394 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:31:27.534Z,1714177887.534 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:32:02.682Z,1714177922.682 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:32:37.823Z,1714177957.823 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:33:12.962Z,1714177992.962 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:33:48.102Z,1714178028.102 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:34:23.242Z,1714178063.242 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:34:58.390Z,1714178098.390 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:35:33.538Z,1714178133.538 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-27T00:35:58.878Z,1714178158.878 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003154 2024-04-27T00:36:00.886Z,1714178160.886 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-27T00:36:07.753Z,1714178167.753 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240426T200547/Courier0066.lzma 2024-04-27T00:36:08.191Z,1714178168.191 [CBIT](INFO): Clearing failed state for component DropWeight 2024-04-27T00:36:08.191Z,1714178168.191 [DropWeight] No Fault, FailCount= 1 2024-04-27T00:36:08.557Z,1714178168.557 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-27T00:36:08.756Z,1714178168.756 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Courier0066.lzma.bak 2024-04-27T00:36:08.756Z,1714178168.756 [DataOverHttps](INFO): SBD MOMSN=19599750 2024-04-27T00:36:24.720Z,1714178184.720 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-27T00:36:24.750Z,1714178184.750 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20240426T200547/Express0067.lzma 2024-04-27T00:36:25.752Z,1714178185.752 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Express0067.lzma.bak 2024-04-27T00:36:25.752Z,1714178185.752 [DataOverHttps](INFO): SBD MOMSN=19599752 2024-04-27T00:36:27.981Z,1714178187.981 [Default:CheckIn:Read_Iridium] Stopped 2024-04-27T00:36:27.981Z,1714178187.981 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-27T00:36:27.981Z,1714178187.981 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-27T00:36:29.571Z,1714178189.571 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-27T00:36:40.069Z,1714178200.069 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-27T00:36:55.019Z,1714178215.019 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-27T00:37:10.773Z,1714178230.773 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-27T00:37:25.721Z,1714178245.721 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-27T00:37:41.881Z,1714178261.881 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-27T00:37:58.042Z,1714178278.042 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-27T00:38:13.396Z,1714178293.396 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-27T00:41:28.551Z,1714178488.551 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-27T00:41:28.551Z,1714178488.551 [Default:CheckIn:C.Wait] Stopped 2024-04-27T00:41:28.551Z,1714178488.551 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-27T00:41:28.551Z,1714178488.551 [Default:CheckIn:D] Running Loop=1 2024-04-27T00:41:28.969Z,1714178488.969 [Default:CheckIn:D] Stopped 2024-04-27T00:41:28.969Z,1714178488.969 [Default:CheckIn:E] Running Loop=1 2024-04-27T00:41:29.349Z,1714178489.349 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 276.051335 min 2024-04-27T00:41:29.349Z,1714178489.349 [Default:CheckIn:E] Stopped 2024-04-27T00:41:29.349Z,1714178489.349 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-27T00:41:29.349Z,1714178489.349 [Default:CheckIn] Stopped 2024-04-27T00:41:29.349Z,1714178489.349 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-27T00:41:29.349Z,1714178489.349 [Default:CheckIn](INFO): Running loop #23 2024-04-27T00:41:29.349Z,1714178489.349 [Default:CheckIn] Running Loop=23 2024-04-27T00:41:29.349Z,1714178489.349 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-27T00:41:29.349Z,1714178489.349 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-27T00:41:31.007Z,1714178491.007 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-04-27T00:41:31.008Z,1714178491.008 [NAL9602] Data Fault, FailCount= 3 2024-04-27T00:41:31.008Z,1714178491.008 [NAL9602](ERROR): Data Fault 2024-04-27T00:41:31.030Z,1714178491.030 [CBIT](ERROR): Data Fault in component: NAL9602 2024-04-27T00:41:31.367Z,1714178491.367 [NAL9602](INFO): Powering down 2024-04-27T00:41:32.209Z,1714178492.209 [CBIT](INFO): Clearing failed state for component NAL9602 2024-04-27T00:41:32.209Z,1714178492.209 [NAL9602] No Fault, FailCount= 3 2024-04-27T00:42:01.675Z,1714178521.675 [NAL9602](INFO): Powering up NAL9602 2024-04-27T00:42:12.564Z,1714178532.564 [NAL9602](INFO): NAL9602 initialized 2024-04-27T00:45:27.774Z,1714178727.774 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.879276 2024-04-27T00:45:30.118Z,1714178730.118 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-27T00:45:36.985Z,1714178736.985 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-27T00:45:44.322Z,1714178744.322 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003133 2024-04-27T00:45:52.339Z,1714178752.339 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-27T00:46:08.093Z,1714178768.093 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-27T00:46:23.043Z,1714178783.043 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-27T00:46:29.529Z,1714178789.529 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-27T00:41:29.3Z 2024-04-27T00:46:29.529Z,1714178789.529 [Default:CheckIn:Read_GPS] Stopped 2024-04-27T00:46:29.529Z,1714178789.529 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-27T00:46:38.053Z,1714178798.053 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-27T00:46:45.589Z,1714178805.589 [DataOverHttps](INFO): Sending 115 bytes from file Logs/20240426T200547/Courier0069.lzma 2024-04-27T00:46:47.596Z,1714178807.596 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Courier0069.lzma.bak 2024-04-27T00:46:47.596Z,1714178807.596 [DataOverHttps](INFO): SBD MOMSN=19599823 2024-04-27T00:46:53.526Z,1714178813.526 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-27T00:47:06.766Z,1714178826.766 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20240426T200547/Express0070.lzma 2024-04-27T00:47:07.768Z,1714178827.768 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Express0070.lzma.bak 2024-04-27T00:47:07.768Z,1714178827.768 [DataOverHttps](INFO): SBD MOMSN=19599828 2024-04-27T00:47:08.758Z,1714178828.758 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-27T00:47:10.016Z,1714178830.016 [Default:CheckIn:Read_Iridium] Stopped 2024-04-27T00:47:10.016Z,1714178830.016 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-27T00:47:10.016Z,1714178830.016 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-27T00:47:16.436Z,1714178836.436 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-04-27T00:47:24.111Z,1714178844.111 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-27T00:47:39.057Z,1714178859.057 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-27T00:47:47.252Z,1714178867.252 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-27T00:47:54.007Z,1714178874.007 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-27T00:52:10.579Z,1714179130.579 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-27T00:52:10.579Z,1714179130.579 [Default:CheckIn:C.Wait] Stopped 2024-04-27T00:52:10.580Z,1714179130.580 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-27T00:52:10.580Z,1714179130.580 [Default:CheckIn:D] Running Loop=1 2024-04-27T00:52:10.988Z,1714179130.988 [Default:CheckIn:D] Stopped 2024-04-27T00:52:10.988Z,1714179130.988 [Default:CheckIn:E] Running Loop=1 2024-04-27T00:52:11.372Z,1714179131.372 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 286.751660 min 2024-04-27T00:52:11.372Z,1714179131.372 [Default:CheckIn:E] Stopped 2024-04-27T00:52:11.372Z,1714179131.372 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-27T00:52:11.372Z,1714179131.372 [Default:CheckIn] Stopped 2024-04-27T00:52:11.372Z,1714179131.372 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-27T00:52:11.373Z,1714179131.373 [Default:CheckIn](INFO): Running loop #24 2024-04-27T00:52:11.373Z,1714179131.373 [Default:CheckIn] Running Loop=24 2024-04-27T00:52:11.373Z,1714179131.373 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-27T00:52:11.373Z,1714179131.373 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-27T00:52:44.079Z,1714179164.079 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2024-04-27T00:52:44.154Z,1714179164.154 [NAL9602](ERROR): received: +CSQ:0 OK 2024-04-27T00:55:00.647Z,1714179300.647 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5. 2024-04-27T00:55:00.649Z,1714179300.649 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle. 2024-04-27T00:57:11.568Z,1714179431.568 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-27T00:52:11.4Z 2024-04-27T00:57:11.568Z,1714179431.568 [Default:CheckIn:Read_GPS] Stopped 2024-04-27T00:57:11.569Z,1714179431.569 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-27T00:57:15.175Z,1714179435.175 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-04-27T00:57:22.177Z,1714179442.177 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20240426T200547/Courier0072.lzma 2024-04-27T00:57:23.180Z,1714179443.180 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Courier0072.lzma.bak 2024-04-27T00:57:23.180Z,1714179443.180 [DataOverHttps](INFO): SBD MOMSN=19599915 2024-04-27T00:57:40.321Z,1714179460.321 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20240426T200547/Express0073.lzma 2024-04-27T00:57:41.176Z,1714179461.176 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Express0073.lzma.bak 2024-04-27T00:57:41.176Z,1714179461.176 [DataOverHttps](INFO): SBD MOMSN=19599917 2024-04-27T00:57:43.075Z,1714179463.075 [Default:CheckIn:Read_Iridium] Stopped 2024-04-27T00:57:43.075Z,1714179463.075 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-27T00:57:43.075Z,1714179463.075 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-27T00:57:45.879Z,1714179465.879 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-27T01:02:43.640Z,1714179763.640 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-27T01:02:43.640Z,1714179763.640 [Default:CheckIn:C.Wait] Stopped 2024-04-27T01:02:43.640Z,1714179763.640 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-27T01:02:43.640Z,1714179763.640 [Default:CheckIn:D] Running Loop=1 2024-04-27T01:02:44.048Z,1714179764.048 [Default:CheckIn:D] Stopped 2024-04-27T01:02:44.048Z,1714179764.048 [Default:CheckIn:E] Running Loop=1 2024-04-27T01:02:44.450Z,1714179764.450 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 297.302637 min 2024-04-27T01:02:44.450Z,1714179764.450 [Default:CheckIn:E] Stopped 2024-04-27T01:02:44.450Z,1714179764.450 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-27T01:02:44.450Z,1714179764.450 [Default:CheckIn] Stopped 2024-04-27T01:02:44.450Z,1714179764.450 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-27T01:02:44.450Z,1714179764.450 [Default:CheckIn](INFO): Running loop #25 2024-04-27T01:02:44.450Z,1714179764.450 [Default:CheckIn] Running Loop=25 2024-04-27T01:02:44.450Z,1714179764.450 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-27T01:02:44.451Z,1714179764.451 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-27T01:02:46.052Z,1714179766.052 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-04-27T01:02:46.052Z,1714179766.052 [NAL9602] Data Fault, FailCount= 4 2024-04-27T01:02:46.052Z,1714179766.052 [NAL9602](ERROR): Data Fault 2024-04-27T01:02:46.074Z,1714179766.074 [CBIT](ERROR): Data Fault in component: NAL9602 2024-04-27T01:02:46.460Z,1714179766.460 [NAL9602](INFO): Powering down 2024-04-27T01:02:47.318Z,1714179767.318 [CBIT](INFO): Clearing failed state for component NAL9602 2024-04-27T01:02:47.318Z,1714179767.318 [NAL9602] No Fault, FailCount= 4 2024-04-27T01:03:16.756Z,1714179796.756 [NAL9602](INFO): Powering up NAL9602 2024-04-27T01:03:27.667Z,1714179807.667 [NAL9602](INFO): NAL9602 initialized 2024-04-27T01:04:46.041Z,1714179886.041 [NAL9602](INFO): SBD MO Status=2, MOMSN=16197, MT Status=2, MTMSN=0 2024-04-27T01:04:46.041Z,1714179886.041 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-04-27T01:05:10.684Z,1714179910.684 [NAL9602](INFO): SBD MO Status=2, MOMSN=16197, MT Status=2, MTMSN=0 2024-04-27T01:05:10.684Z,1714179910.684 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-04-27T01:05:38.562Z,1714179938.562 [NAL9602](INFO): SBD MO Status=2, MOMSN=16197, MT Status=2, MTMSN=0 2024-04-27T01:05:38.563Z,1714179938.563 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-04-27T01:07:44.669Z,1714180064.669 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-27T01:02:44.5Z 2024-04-27T01:07:44.682Z,1714180064.682 [Default:CheckIn:Read_GPS] Stopped 2024-04-27T01:07:44.682Z,1714180064.682 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-27T01:07:51.865Z,1714180071.865 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240426T200547/Courier0075.lzma 2024-04-27T01:07:52.868Z,1714180072.868 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Courier0075.lzma.bak 2024-04-27T01:07:52.868Z,1714180072.868 [DataOverHttps](INFO): SBD MOMSN=19599934 2024-04-27T01:08:09.746Z,1714180089.746 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20240426T200547/Express0076.lzma 2024-04-27T01:08:10.748Z,1714180090.748 [DataOverHttps](INFO): Moved sent file to Logs/20240426T200547/Express0076.lzma.bak 2024-04-27T01:08:10.748Z,1714180090.748 [DataOverHttps](INFO): SBD MOMSN=19599936 2024-04-27T01:08:12.917Z,1714180092.917 [Default:CheckIn:Read_Iridium] Stopped 2024-04-27T01:08:12.917Z,1714180092.917 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-27T01:08:12.917Z,1714180092.917 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-27T01:08:31.481Z,1714180111.481 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-04-27T01:09:02.184Z,1714180142.184 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-27T01:10:33.250Z,1714180233.250 [CommandExec](IMPORTANT): got command quit 2024-04-27T01:10:34.254Z,1714180234.254 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-27T01:10:34.254Z,1714180234.254 [CommandExec](INFO): Uninitializing the command executive. 2024-04-27T01:10:34.254Z,1714180234.254 [CommandExec](INFO): Uninitializing the command scheduler. 2024-04-27T01:10:34.254Z,1714180234.254 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-04-27T01:10:34.418Z,1714180234.418 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2024-04-27T01:10:34.418Z,1714180234.418 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2024-04-27T01:10:34.418Z,1714180234.418 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-04-27T01:10:34.419Z,1714180234.419 [NavChartDb](INFO): Join timeout helper Thread ID is 2487 2024-04-27T01:10:34.438Z,1714180234.438 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-27T01:10:34.438Z,1714180234.438 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-04-27T01:10:34.447Z,1714180234.447 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2024-04-27T01:10:34.447Z,1714180234.447 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-04-27T01:10:34.448Z,1714180234.448 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2488 2024-04-27T01:10:34.834Z,1714180234.834 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-27T01:10:34.834Z,1714180234.834 [WetLabsBB2FL](INFO): Powering down 2024-04-27T01:10:34.835Z,1714180234.835 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-04-27T01:10:34.838Z,1714180234.838 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2024-04-27T01:10:34.838Z,1714180234.838 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-04-27T01:10:34.838Z,1714180234.838 [CTD_Seabird](INFO): Join timeout helper Thread ID is 2489 2024-04-27T01:10:35.618Z,1714180235.618 [CTD_Seabird](INFO): Powering down 2024-04-27T01:10:35.630Z,1714180235.630 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-27T01:10:35.630Z,1714180235.630 [CTD_Seabird](INFO): Powering down 2024-04-27T01:10:35.648Z,1714180235.648 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-04-27T01:10:35.648Z,1714180235.648 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2024-04-27T01:10:35.648Z,1714180235.648 [DAT ThreadHandler](INFO): Thread cancelled. 2024-04-27T01:10:35.649Z,1714180235.649 [DAT](INFO): Join timeout helper Thread ID is 2490 2024-04-27T01:10:35.862Z,1714180235.862 [DAT](INFO): Powering down 2024-04-27T01:10:35.942Z,1714180235.942 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-27T01:10:35.942Z,1714180235.942 [DAT](INFO): Powering down 2024-04-27T01:10:35.943Z,1714180235.943 [DAT ThreadHandler](INFO): Thread cancelled. 2024-04-27T01:10:35.958Z,1714180235.958 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2024-04-27T01:10:35.958Z,1714180235.958 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-04-27T01:10:35.959Z,1714180235.959 [Radio_Surface](INFO): Join timeout helper Thread ID is 2492 2024-04-27T01:10:36.074Z,1714180236.074 [Radio_Surface](INFO): Powering down 2024-04-27T01:10:36.075Z,1714180236.075 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-27T01:10:36.075Z,1714180236.075 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-04-27T01:10:36.087Z,1714180236.087 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2024-04-27T01:10:36.087Z,1714180236.087 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-04-27T01:10:36.087Z,1714180236.087 [Onboard](INFO): Join timeout helper Thread ID is 2493 2024-04-27T01:10:36.978Z,1714180236.978 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2024-04-27T01:10:37.714Z,1714180237.714 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-27T01:10:37.714Z,1714180237.714 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-04-27T01:10:37.718Z,1714180237.718 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2024-04-27T01:10:37.719Z,1714180237.719 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-04-27T01:10:37.719Z,1714180237.719 [DataOverHttps](INFO): Join timeout helper Thread ID is 2496 2024-04-27T01:10:37.982Z,1714180237.982 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-27T01:10:37.982Z,1714180237.982 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-04-27T01:10:37.990Z,1714180237.990 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2024-04-27T01:10:37.991Z,1714180237.991 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-04-27T01:10:37.991Z,1714180237.991 [BackseatComponent](INFO): Join timeout helper Thread ID is 2497 2024-04-27T01:10:37.998Z,1714180237.998 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-27T01:10:37.998Z,1714180237.998 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-04-27T01:10:38.005Z,1714180238.005 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2024-04-27T01:10:38.005Z,1714180238.005 [logger ThreadHandler](INFO): Thread cancelled. 2024-04-27T01:10:38.006Z,1714180238.006 [logger](INFO): Join timeout helper Thread ID is 2498 2024-04-27T01:10:38.014Z,1714180238.014 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-27T01:10:38.015Z,1714180238.015 [logger ThreadHandler](INFO): Thread cancelled. 2024-04-27T01:10:38.016Z,1714180238.016 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2024-04-27T01:10:38.016Z,1714180238.016 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-04-27T01:10:38.017Z,1714180238.017 [CommandLine](INFO): Join timeout helper Thread ID is 2499 2024-04-27T01:10:38.038Z,1714180238.038 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-27T01:10:38.038Z,1714180238.038 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-04-27T01:10:38.058Z,1714180238.058 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2024-04-27T01:10:38.058Z,1714180238.058 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-04-27T01:10:38.059Z,1714180238.059 [CommandExec](INFO): Join timeout helper Thread ID is 2500 2024-04-27T01:10:38.060Z,1714180238.060 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2024-04-27T01:10:38.060Z,1714180238.060 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-04-27T01:10:38.060Z,1714180238.060 [controlThread](INFO): Join timeout helper Thread ID is 2501 2024-04-27T01:10:38.314Z,1714180238.314 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-27T01:10:38.314Z,1714180238.314 [controlThread](DEBUG): Uninitializing ControlThread 2024-04-27T01:10:38.315Z,1714180238.315 [AHRS_M2](INFO): Powering down 2024-04-27T01:10:38.387Z,1714180238.387 [MultiRay](INFO): Powering down. 2024-04-27T01:10:38.387Z,1714180238.387 [NAL9602](INFO): Powering down 2024-04-27T01:10:38.390Z,1714180238.390 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2024-04-27T01:10:38.391Z,1714180238.391 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2024-04-27T01:10:38.391Z,1714180238.391 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2024-04-27T01:10:38.392Z,1714180238.392 [MissionManager](INFO): Uninitializing Mission Default 2024-04-27T01:10:38.392Z,1714180238.392 [Default] Stopped 2024-04-27T01:10:38.392Z,1714180238.392 [Default](DEBUG): Aggregate::uninitialize Default 2024-04-27T01:10:38.392Z,1714180238.392 [Default:B.GoToSurface] Stopped 2024-04-27T01:10:38.392Z,1714180238.392 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-04-27T01:10:38.392Z,1714180238.392 [Default:CheckIn] Stopped 2024-04-27T01:10:38.392Z,1714180238.392 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-27T01:10:38.392Z,1714180238.392 [Default:CheckIn:C.Wait] Stopped 2024-04-27T01:10:38.392Z,1714180238.392 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-27T01:10:38.395Z,1714180238.395 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2024-04-27T01:10:38.395Z,1714180238.395 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2024-04-27T01:10:38.396Z,1714180238.396 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2024-04-27T01:10:38.396Z,1714180238.396 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2024-04-27T01:10:38.396Z,1714180238.396 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2024-04-27T01:10:38.396Z,1714180238.396 [BuoyancyServo](INFO): Powering down 2024-04-27T01:10:38.410Z,1714180238.410 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2024-04-27T01:10:38.410Z,1714180238.410 [ElevatorServo](INFO): Powering down 2024-04-27T01:10:38.411Z,1714180238.411 [MassServo](DEBUG): Uninitialize Mass Servo. 2024-04-27T01:10:38.411Z,1714180238.411 [MassServo](INFO): Powering down 2024-04-27T01:10:38.412Z,1714180238.412 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-04-27T01:10:38.412Z,1714180238.412 [RudderServo](INFO): Powering down 2024-04-27T01:10:38.413Z,1714180238.413 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2024-04-27T01:10:38.413Z,1714180238.413 [ThrusterHE](INFO): Powering down 2024-04-27T01:10:38.414Z,1714180238.414 [SBIT](DEBUG): Uninitialize SBIT Component. 2024-04-27T01:10:38.414Z,1714180238.414 [IBIT](DEBUG): Uninitialize IBIT Component. 2024-04-27T01:10:38.414Z,1714180238.414 [CBIT](DEBUG): Uninitialize CBIT Component. 2024-04-27T01:10:38.414Z,1714180238.414 [CBIT](DEBUG): Powering off loads. 2024-04-27T01:10:38.426Z,1714180238.426 [CBIT](DEBUG): Disabling WDT. 2024-04-27T01:10:38.438Z,1714180238.438 [CBIT](DEBUG): Opening all GF detection circuits. 2024-04-27T01:10:38.439Z,1714180238.439 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-04-27T01:10:38.473Z,1714180238.473 [DAT ThreadHandler](INFO): Thread cancelled. 2024-04-27T01:10:38.478Z,1714180238.478 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-04-27T01:10:38.481Z,1714180238.481 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-04-27T01:10:38.487Z,1714180238.487 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-04-27T01:10:38.530Z,1714180238.530 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-04-27T01:10:38.587Z,1714180238.587 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-04-27T01:10:38.599Z,1714180238.599 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-04-27T01:10:38.662Z,1714180238.662 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-04-27T01:10:38.710Z,1714180238.710 [logger ThreadHandler](INFO): Thread cancelled.