2024-05-01T17:23:51.589Z,1714584231.589 [DataOverHttps](INFO): Received command: restart logs 2024-05-01T17:23:51.626Z,1714584231.626 [CommandExec](IMPORTANT): got command restart logs 2024-05-01T17:23:56.514Z,1714584236.514 [ThrusterHE](ERROR): Zero Speed Commanded. 2024-05-01T17:24:21.617Z,1714584261.617 [ThrusterHE](INFO): Failure count cleared after critical for ThrusterHE 2024-05-01T17:24:28.095Z,1714584268.095 [CommandExec](IMPORTANT): got command maintain clear 2024-05-01T17:24:28.122Z,1714584268.122 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2024-05-01T17:26:32.838Z,1714584392.838 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-01T17:26:32.838Z,1714584392.838 [Default:CheckIn:C.Wait] Stopped 2024-05-01T17:26:32.838Z,1714584392.838 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-01T17:26:32.838Z,1714584392.838 [Default:CheckIn:D] Running Loop=1 2024-05-01T17:26:33.241Z,1714584393.241 [Default:CheckIn:D] Stopped 2024-05-01T17:26:33.241Z,1714584393.241 [Default:CheckIn:E] Running Loop=1 2024-05-01T17:26:33.643Z,1714584393.643 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.180703 min 2024-05-01T17:26:33.643Z,1714584393.643 [Default:CheckIn:E] Stopped 2024-05-01T17:26:33.643Z,1714584393.643 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-01T17:26:33.643Z,1714584393.643 [Default:CheckIn] Stopped 2024-05-01T17:26:33.643Z,1714584393.643 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-01T17:26:33.643Z,1714584393.643 [Default:CheckIn](INFO): Running loop #6 2024-05-01T17:26:33.643Z,1714584393.643 [Default:CheckIn] Running Loop=6 2024-05-01T17:26:33.643Z,1714584393.643 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-01T17:26:33.643Z,1714584393.643 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-01T17:26:35.656Z,1714584395.656 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172631.00,A,3648.33577,N,12150.85658,W,21.577,86.26,010524,,,D*7A 2024-05-01T17:26:35.667Z,1714584395.667 [NAL9602](INFO): GPS fix at 20240501T172631: (36.805596, -121.847610) 2024-05-01T17:26:35.679Z,1714584395.679 [Default:CheckIn:Read_GPS] Stopped 2024-05-01T17:26:35.679Z,1714584395.679 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-01T17:26:46.564Z,1714584406.564 [NAL9602](INFO): SBD MO Status=0, MOMSN=51002, MT Status=0, MTMSN=0 2024-05-01T17:26:46.564Z,1714584406.564 [NAL9602](INFO): No messages in MT queue 2024-05-01T17:27:17.266Z,1714584437.266 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-01T17:27:18.865Z,1714584438.865 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-01T17:27:49.998Z,1714584469.998 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-01T17:28:21.121Z,1714584501.121 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-01T17:28:37.661Z,1714584517.661 [DataOverHttps](INFO): Sending 87 bytes from file Logs/20240501T145537/Courier0073.lzma 2024-05-01T17:28:38.663Z,1714584518.663 [DataOverHttps](INFO): Moved sent file to Logs/20240501T145537/Courier0073.lzma.bak 2024-05-01T17:28:38.663Z,1714584518.663 [DataOverHttps](INFO): SBD MOMSN=19619655 2024-05-01T17:28:57.674Z,1714584537.674 [DataOverHttps](INFO): Sending 268 bytes from file Logs/20240501T172351/Courier0000.lzma 2024-05-01T17:28:58.675Z,1714584538.675 [DataOverHttps](INFO): Moved sent file to Logs/20240501T172351/Courier0000.lzma.bak 2024-05-01T17:28:58.675Z,1714584538.675 [DataOverHttps](INFO): SBD MOMSN=19619658 2024-05-01T17:29:16.562Z,1714584556.562 [DataOverHttps](INFO): Sending 408 bytes from file Logs/20240501T145537/Express0074.lzma 2024-05-01T17:29:17.563Z,1714584557.563 [DataOverHttps](INFO): Moved sent file to Logs/20240501T145537/Express0074.lzma.bak 2024-05-01T17:29:17.563Z,1714584557.563 [DataOverHttps](INFO): SBD MOMSN=19619662 2024-05-01T17:29:33.570Z,1714584573.570 [DataOverHttps](INFO): Sending 477 bytes from file Logs/20240501T172351/Express0001.lzma 2024-05-01T17:29:34.571Z,1714584574.571 [DataOverHttps](INFO): Moved sent file to Logs/20240501T172351/Express0001.lzma.bak 2024-05-01T17:29:34.571Z,1714584574.571 [DataOverHttps](INFO): SBD MOMSN=19619704 2024-05-01T17:29:50.797Z,1714584590.797 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20240501T172351/Express0004.lzma 2024-05-01T17:29:51.799Z,1714584591.799 [DataOverHttps](INFO): Moved sent file to Logs/20240501T172351/Express0004.lzma.bak 2024-05-01T17:29:51.799Z,1714584591.799 [DataOverHttps](INFO): SBD MOMSN=19619709 2024-05-01T17:29:53.282Z,1714584593.282 [Default:CheckIn:Read_Iridium] Stopped 2024-05-01T17:29:53.282Z,1714584593.282 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-01T17:29:53.282Z,1714584593.282 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-01T17:32:29.317Z,1714584749.317 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-01T17:33:00.441Z,1714584780.441 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-01T17:33:31.577Z,1714584811.577 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-01T17:33:42.622Z,1714584822.622 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.055191 2024-05-01T17:34:32.821Z,1714584872.821 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-01T17:34:53.922Z,1714584893.922 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-01T17:34:53.922Z,1714584893.922 [Default:CheckIn:C.Wait] Stopped 2024-05-01T17:34:53.922Z,1714584893.922 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-01T17:34:53.922Z,1714584893.922 [Default:CheckIn:D] Running Loop=1 2024-05-01T17:34:54.323Z,1714584894.323 [Default:CheckIn:D] Stopped 2024-05-01T17:34:54.323Z,1714584894.323 [Default:CheckIn:E] Running Loop=1 2024-05-01T17:34:54.732Z,1714584894.732 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 40.532068 min 2024-05-01T17:34:54.732Z,1714584894.732 [Default:CheckIn:E] Stopped 2024-05-01T17:34:54.732Z,1714584894.732 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-01T17:34:54.732Z,1714584894.732 [Default:CheckIn] Stopped 2024-05-01T17:34:54.732Z,1714584894.732 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-01T17:34:54.732Z,1714584894.732 [Default:CheckIn](INFO): Running loop #7 2024-05-01T17:34:54.732Z,1714584894.732 [Default:CheckIn] Running Loop=7 2024-05-01T17:34:54.732Z,1714584894.732 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-01T17:34:54.732Z,1714584894.732 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-01T17:34:56.736Z,1714584896.736 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173452.00,A,3648.32373,N,12148.11324,W,14.423,107.14,010524,,,D*4A 2024-05-01T17:34:56.747Z,1714584896.747 [NAL9602](INFO): GPS fix at 20240501T173452: (36.805396, -121.801887) 2024-05-01T17:34:56.763Z,1714584896.763 [Default:CheckIn:Read_GPS] Stopped 2024-05-01T17:34:56.763Z,1714584896.763 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-01T17:35:03.946Z,1714584903.946 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-01T17:35:18.604Z,1714584918.604 [NAL9602](INFO): SBD MO Status=1, MOMSN=51003, MT Status=0, MTMSN=0 2024-05-01T17:35:18.669Z,1714584918.669 [NAL9602](INFO): Sent 73 bytes from file Logs/20240501T172351/Courier0006.lzma 2024-05-01T17:35:18.669Z,1714584918.669 [NAL9602](INFO): Packets left to send: 0 2024-05-01T17:35:32.376Z,1714584932.376 [NAL9602](INFO): SBD MO Status=1, MOMSN=51004, MT Status=0, MTMSN=0 2024-05-01T17:35:32.433Z,1714584932.433 [NAL9602](INFO): Sent 155 bytes from file Logs/20240501T172351/Express0007.lzma 2024-05-01T17:35:32.433Z,1714584932.433 [NAL9602](INFO): Packets left to send: 0 2024-05-01T17:35:35.069Z,1714584935.069 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-01T17:35:47.740Z,1714584947.740 [NAL9602](INFO): SBD MO Status=2, MOMSN=51005, MT Status=2, MTMSN=0 2024-05-01T17:35:47.740Z,1714584947.740 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-01T17:36:05.100Z,1714584965.100 [NAL9602](INFO): SBD MO Status=2, MOMSN=51005, MT Status=2, MTMSN=0 2024-05-01T17:36:05.101Z,1714584965.101 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-01T17:36:06.193Z,1714584966.193 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-01T17:36:16.009Z,1714584976.009 [NAL9602](INFO): SBD MO Status=0, MOMSN=51005, MT Status=0, MTMSN=0 2024-05-01T17:36:16.094Z,1714584976.094 [Default:CheckIn:Read_Iridium] Stopped 2024-05-01T17:36:16.094Z,1714584976.094 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-01T17:36:16.094Z,1714584976.094 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-01T17:36:37.318Z,1714584997.318 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-01T17:36:46.718Z,1714585006.718 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-01T17:37:08.441Z,1714585028.441 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-01T17:37:39.567Z,1714585059.567 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-01T17:38:10.689Z,1714585090.689 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-01T17:38:41.821Z,1714585121.821 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-01T17:39:26.286Z,1714585166.286 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003489 2024-05-01T17:41:16.765Z,1714585276.765 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-01T17:41:16.765Z,1714585276.765 [Default:CheckIn:C.Wait] Stopped 2024-05-01T17:41:16.765Z,1714585276.765 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-01T17:41:16.765Z,1714585276.765 [Default:CheckIn:D] Running Loop=1 2024-05-01T17:41:17.170Z,1714585277.170 [Default:CheckIn:D] Stopped 2024-05-01T17:41:17.170Z,1714585277.170 [Default:CheckIn:E] Running Loop=1 2024-05-01T17:41:17.581Z,1714585277.581 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 46.912854 min 2024-05-01T17:41:17.581Z,1714585277.581 [Default:CheckIn:E] Stopped 2024-05-01T17:41:17.581Z,1714585277.581 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-01T17:41:17.582Z,1714585277.582 [Default:CheckIn] Stopped 2024-05-01T17:41:17.582Z,1714585277.582 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-01T17:41:17.582Z,1714585277.582 [Default:CheckIn](INFO): Running loop #8 2024-05-01T17:41:17.582Z,1714585277.582 [Default:CheckIn] Running Loop=8 2024-05-01T17:41:17.582Z,1714585277.582 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-01T17:41:17.582Z,1714585277.582 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-01T17:41:19.584Z,1714585279.584 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174115.00,A,3648.40076,N,12147.12487,W,3.421,181.89,010524,,,D*74 2024-05-01T17:41:19.586Z,1714585279.586 [NAL9602](INFO): GPS fix at 20240501T174115: (36.806679, -121.785415) 2024-05-01T17:41:19.634Z,1714585279.634 [Default:CheckIn:Read_GPS] Stopped 2024-05-01T17:41:19.634Z,1714585279.634 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-01T17:41:25.077Z,1714585285.077 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240501T172351/Courier0009.lzma 2024-05-01T17:41:26.079Z,1714585286.079 [DataOverHttps](INFO): Moved sent file to Logs/20240501T172351/Courier0009.lzma.bak 2024-05-01T17:41:26.079Z,1714585286.079 [DataOverHttps](INFO): SBD MOMSN=19619776 2024-05-01T17:41:41.945Z,1714585301.945 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20240501T172351/Express0010.lzma 2024-05-01T17:41:42.947Z,1714585302.947 [DataOverHttps](INFO): Moved sent file to Logs/20240501T172351/Express0010.lzma.bak 2024-05-01T17:41:42.947Z,1714585302.947 [DataOverHttps](INFO): SBD MOMSN=19619786 2024-05-01T17:41:44.250Z,1714585304.250 [Default:CheckIn:Read_Iridium] Stopped 2024-05-01T17:41:44.250Z,1714585304.250 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-01T17:41:44.250Z,1714585304.250 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-01T17:43:01.452Z,1714585381.452 [NAL9602](INFO): SBD MO Status=0, MOMSN=51006, MT Status=0, MTMSN=0 2024-05-01T17:43:01.452Z,1714585381.452 [NAL9602](INFO): No messages in MT queue 2024-05-01T17:43:32.154Z,1714585412.154 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-01T17:46:45.006Z,1714585605.006 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-01T17:46:45.007Z,1714585605.007 [Default:CheckIn:C.Wait] Stopped 2024-05-01T17:46:45.007Z,1714585605.007 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-01T17:46:45.007Z,1714585605.007 [Default:CheckIn:D] Running Loop=1 2024-05-01T17:46:45.418Z,1714585605.418 [Default:CheckIn:D] Stopped 2024-05-01T17:46:45.418Z,1714585605.418 [Default:CheckIn:E] Running Loop=1 2024-05-01T17:46:45.833Z,1714585605.833 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.383655 min 2024-05-01T17:46:45.833Z,1714585605.833 [Default:CheckIn:E] Stopped 2024-05-01T17:46:45.833Z,1714585605.833 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-01T17:46:45.833Z,1714585605.833 [Default:CheckIn] Stopped 2024-05-01T17:46:45.834Z,1714585605.834 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-01T17:46:45.834Z,1714585605.834 [Default:CheckIn](INFO): Running loop #9 2024-05-01T17:46:45.834Z,1714585605.834 [Default:CheckIn] Running Loop=9 2024-05-01T17:46:45.834Z,1714585605.834 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-01T17:46:45.834Z,1714585605.834 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-01T17:46:47.824Z,1714585607.824 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174643.00,A,3648.14984,N,12147.22062,W,0.253,212.50,010524,,,D*74 2024-05-01T17:46:47.827Z,1714585607.827 [NAL9602](INFO): GPS fix at 20240501T174643: (36.802497, -121.787010) 2024-05-01T17:46:47.838Z,1714585607.838 [Default:CheckIn:Read_GPS] Stopped 2024-05-01T17:46:47.838Z,1714585607.838 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-01T17:46:58.025Z,1714585618.025 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240501T172351/Courier0012.lzma 2024-05-01T17:46:59.028Z,1714585619.028 [DataOverHttps](INFO): Moved sent file to Logs/20240501T172351/Courier0012.lzma.bak 2024-05-01T17:46:59.028Z,1714585619.028 [DataOverHttps](INFO): SBD MOMSN=19619832 2024-05-01T17:47:02.828Z,1714585622.828 [NAL9602](INFO): SBD MO Status=0, MOMSN=51007, MT Status=0, MTMSN=0 2024-05-01T17:47:02.828Z,1714585622.828 [NAL9602](INFO): No messages in MT queue 2024-05-01T17:47:18.941Z,1714585638.941 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20240501T172351/Express0013.lzma 2024-05-01T17:47:19.943Z,1714585639.943 [DataOverHttps](INFO): Moved sent file to Logs/20240501T172351/Express0013.lzma.bak 2024-05-01T17:47:19.943Z,1714585639.943 [DataOverHttps](INFO): SBD MOMSN=19619835 2024-05-01T17:47:21.422Z,1714585641.422 [Default:CheckIn:Read_Iridium] Stopped 2024-05-01T17:47:21.422Z,1714585641.422 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-01T17:47:21.422Z,1714585641.422 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-01T17:47:33.526Z,1714585653.526 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-01T17:52:21.859Z,1714585941.859 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-01T17:52:21.859Z,1714585941.859 [Default:CheckIn:C.Wait] Stopped 2024-05-01T17:52:21.859Z,1714585941.859 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-01T17:52:21.860Z,1714585941.860 [Default:CheckIn:D] Running Loop=1 2024-05-01T17:52:22.219Z,1714585942.219 [Default:CheckIn:D] Stopped 2024-05-01T17:52:22.219Z,1714585942.219 [Default:CheckIn:E] Running Loop=1 2024-05-01T17:52:22.625Z,1714585942.625 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 57.997005 min 2024-05-01T17:52:22.625Z,1714585942.625 [Default:CheckIn:E] Stopped 2024-05-01T17:52:22.626Z,1714585942.626 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-01T17:52:22.626Z,1714585942.626 [Default:CheckIn] Stopped 2024-05-01T17:52:22.626Z,1714585942.626 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-01T17:52:22.626Z,1714585942.626 [Default:CheckIn](INFO): Running loop #10 2024-05-01T17:52:22.626Z,1714585942.626 [Default:CheckIn] Running Loop=10 2024-05-01T17:52:22.626Z,1714585942.626 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-01T17:52:22.626Z,1714585942.626 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-01T17:52:24.633Z,1714585944.633 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175220.00,A,3648.16847,N,12147.26566,W,2.585,285.82,010524,,,D*72 2024-05-01T17:52:24.635Z,1714585944.635 [NAL9602](INFO): GPS fix at 20240501T175220: (36.802808, -121.787761) 2024-05-01T17:52:24.667Z,1714585944.667 [Default:CheckIn:Read_GPS] Stopped 2024-05-01T17:52:24.667Z,1714585944.667 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-01T17:52:34.765Z,1714585954.765 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240501T172351/Courier0015.lzma 2024-05-01T17:52:35.767Z,1714585955.767 [DataOverHttps](INFO): Moved sent file to Logs/20240501T172351/Courier0015.lzma.bak 2024-05-01T17:52:35.767Z,1714585955.767 [DataOverHttps](INFO): SBD MOMSN=19619865 2024-05-01T17:52:46.121Z,1714585966.121 [NAL9602](INFO): SBD MO Status=2, MOMSN=51008, MT Status=2, MTMSN=0 2024-05-01T17:52:46.121Z,1714585966.121 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-01T17:52:52.577Z,1714585972.577 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20240501T172351/Express0016.lzma 2024-05-01T17:52:53.579Z,1714585973.579 [DataOverHttps](INFO): Moved sent file to Logs/20240501T172351/Express0016.lzma.bak 2024-05-01T17:52:53.579Z,1714585973.579 [DataOverHttps](INFO): SBD MOMSN=19619873 2024-05-01T17:52:55.430Z,1714585975.430 [Default:CheckIn:Read_Iridium] Stopped 2024-05-01T17:52:55.430Z,1714585975.430 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-01T17:52:55.430Z,1714585975.430 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-01T17:57:25.356Z,1714586245.356 [NAL9602](INFO): SBD MO Status=2, MOMSN=51008, MT Status=2, MTMSN=0 2024-05-01T17:57:25.356Z,1714586245.356 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-01T17:57:26.964Z,1714586246.964 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-05-01T17:57:56.079Z,1714586276.079 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-01T17:57:56.079Z,1714586276.079 [Default:CheckIn:C.Wait] Stopped 2024-05-01T17:57:56.079Z,1714586276.079 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-01T17:57:56.079Z,1714586276.079 [Default:CheckIn:D] Running Loop=1 2024-05-01T17:57:56.465Z,1714586276.465 [Default:CheckIn:D] Stopped 2024-05-01T17:57:56.465Z,1714586276.465 [Default:CheckIn:E] Running Loop=1 2024-05-01T17:57:56.873Z,1714586276.873 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.567782 min 2024-05-01T17:57:56.873Z,1714586276.873 [Default:CheckIn:E] Stopped 2024-05-01T17:57:56.873Z,1714586276.873 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-01T17:57:56.873Z,1714586276.873 [Default:CheckIn] Stopped 2024-05-01T17:57:56.874Z,1714586276.874 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-01T17:57:56.874Z,1714586276.874 [Default:CheckIn](INFO): Running loop #11 2024-05-01T17:57:56.874Z,1714586276.874 [Default:CheckIn] Running Loop=11 2024-05-01T17:57:56.874Z,1714586276.874 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-01T17:57:56.874Z,1714586276.874 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-01T17:57:58.884Z,1714586278.884 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175754.00,A,3648.16859,N,12147.28200,W,0.292,138.79,010524,,,A*75 2024-05-01T17:57:58.895Z,1714586278.895 [NAL9602](INFO): GPS fix at 20240501T175754: (36.802810, -121.788033) 2024-05-01T17:57:58.927Z,1714586278.927 [Default:CheckIn:Read_GPS] Stopped 2024-05-01T17:57:58.927Z,1714586278.927 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-01T17:58:06.413Z,1714586286.413 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240501T172351/Courier0018.lzma 2024-05-01T17:58:07.415Z,1714586287.415 [DataOverHttps](INFO): Moved sent file to Logs/20240501T172351/Courier0018.lzma.bak 2024-05-01T17:58:07.415Z,1714586287.415 [DataOverHttps](INFO): SBD MOMSN=19619921 2024-05-01T17:58:24.850Z,1714586304.850 [DataOverHttps](INFO): Sending 269 bytes from file Logs/20240501T172351/Express0019.lzma 2024-05-01T17:58:25.851Z,1714586305.851 [DataOverHttps](INFO): Moved sent file to Logs/20240501T172351/Express0019.lzma.bak 2024-05-01T17:58:25.851Z,1714586305.851 [DataOverHttps](INFO): SBD MOMSN=19619928 2024-05-01T17:58:27.602Z,1714586307.602 [Default:CheckIn:Read_Iridium] Stopped 2024-05-01T17:58:27.602Z,1714586307.602 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-01T17:58:27.603Z,1714586307.603 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-01T17:58:31.242Z,1714586311.242 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-01T18:02:31.342Z,1714586551.342 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-01T18:03:02.465Z,1714586582.465 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-01T18:03:28.248Z,1714586608.248 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-01T18:03:28.249Z,1714586608.249 [Default:CheckIn:C.Wait] Stopped 2024-05-01T18:03:28.249Z,1714586608.249 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-01T18:03:28.249Z,1714586608.249 [Default:CheckIn:D] Running Loop=1 2024-05-01T18:03:28.658Z,1714586608.658 [Default:CheckIn:D] Stopped 2024-05-01T18:03:28.658Z,1714586608.658 [Default:CheckIn:E] Running Loop=1 2024-05-01T18:03:29.054Z,1714586609.054 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 69.104321 min 2024-05-01T18:03:29.054Z,1714586609.054 [Default:CheckIn:E] Stopped 2024-05-01T18:03:29.054Z,1714586609.054 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-01T18:03:29.054Z,1714586609.054 [Default:CheckIn] Stopped 2024-05-01T18:03:29.054Z,1714586609.054 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-01T18:03:29.055Z,1714586609.055 [Default:CheckIn](INFO): Running loop #12 2024-05-01T18:03:29.055Z,1714586609.055 [Default:CheckIn] Running Loop=12 2024-05-01T18:03:29.055Z,1714586609.055 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-01T18:03:29.055Z,1714586609.055 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-01T18:03:31.068Z,1714586611.068 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180326.00,A,3648.16397,N,12147.26922,W,0.622,224.92,010524,,,A*76 2024-05-01T18:03:31.070Z,1714586611.070 [NAL9602](INFO): GPS fix at 20240501T180326: (36.802733, -121.787820) 2024-05-01T18:03:31.082Z,1714586611.082 [Default:CheckIn:Read_GPS] Stopped 2024-05-01T18:03:31.082Z,1714586611.082 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-01T18:03:33.602Z,1714586613.602 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-01T18:04:01.771Z,1714586641.771 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2024-05-01T18:04:01.845Z,1714586641.845 [NAL9602](ERROR): received: +SBDI: 2, 51008, 2, 0, 0, 0 OK 2024-05-01T18:04:36.386Z,1714586676.386 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-01T18:04:38.394Z,1714586678.394 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.279602 2024-05-01T18:05:09.522Z,1714586709.522 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-01T18:05:40.647Z,1714586740.647 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-01T18:06:11.778Z,1714586771.778 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-01T18:06:42.901Z,1714586802.901 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-01T18:07:14.037Z,1714586834.037 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-01T18:07:45.169Z,1714586865.169 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-01T18:07:46.029Z,1714586866.029 [CommandExec](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 300 revolution_per_minute 2024-05-01T18:07:46.031Z,1714586866.031 [ComponentRegistry](DEBUG): SyncComponent "Maintain_SpeedControl.propOmegaAction" handled in the control thread. 2024-05-01T18:07:46.431Z,1714586866.431 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_SpeedControl.propOmegaAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2024-05-01T18:07:51.562Z,1714586871.562 [CommandExec](IMPORTANT): got command failComponent 2024-05-01T18:07:51.562Z,1714586871.562 [CommandExec](IMPORTANT): Failed components: 2024-05-01T18:07:51.562Z,1714586871.562 [CommandExec](IMPORTANT): No failed Components. 2024-05-01T18:08:16.298Z,1714586896.298 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-01T18:08:33.667Z,1714586913.667 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-05-01T18:08:47.421Z,1714586927.421 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-01T18:09:18.546Z,1714586958.546 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-01T18:09:40.903Z,1714586980.903 [CommandExec](IMPORTANT): got command maintain clear 2024-05-01T18:09:41.205Z,1714586981.205 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2024-05-01T18:09:49.669Z,1714586989.669 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-01T18:10:05.906Z,1714587005.906 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003983 2024-05-01T18:10:06.385Z,1714587006.385 [CommandExec](IMPORTANT): got command maintain control SpeedControl.propOmegaAction -300 revolution_per_minute 2024-05-01T18:10:06.387Z,1714587006.387 [ComponentRegistry](DEBUG): SyncComponent "Maintain_SpeedControl.propOmegaAction" handled in the control thread. 2024-05-01T18:10:06.554Z,1714587006.554 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_SpeedControl.propOmegaAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2024-05-01T18:10:13.673Z,1714587013.673 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240501T172351/Courier0021.lzma 2024-05-01T18:10:14.675Z,1714587014.675 [DataOverHttps](INFO): Moved sent file to Logs/20240501T172351/Courier0021.lzma.bak 2024-05-01T18:10:14.675Z,1714587014.675 [DataOverHttps](INFO): SBD MOMSN=19619970 2024-05-01T18:10:31.037Z,1714587031.037 [DataOverHttps](INFO): Sending 165 bytes from file Logs/20240501T172351/Express0022.lzma 2024-05-01T18:10:32.039Z,1714587032.039 [DataOverHttps](INFO): Moved sent file to Logs/20240501T172351/Express0022.lzma.bak 2024-05-01T18:10:32.039Z,1714587032.039 [DataOverHttps](INFO): SBD MOMSN=19619973 2024-05-01T18:10:33.838Z,1714587033.838 [Default:CheckIn:Read_Iridium] Stopped 2024-05-01T18:10:33.838Z,1714587033.838 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-01T18:10:33.838Z,1714587033.838 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-01T18:10:37.062Z,1714587037.062 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-01T18:10:59.677Z,1714587059.677 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-01T18:11:06.007Z,1714587066.007 [CommandExec](IMPORTANT): got command maintain clear 2024-05-01T18:11:06.265Z,1714587066.265 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2024-05-01T18:11:11.797Z,1714587071.797 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-01T18:11:24.321Z,1714587084.321 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-01T18:11:37.249Z,1714587097.249 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-01T18:11:49.773Z,1714587109.773 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-01T18:12:02.701Z,1714587122.701 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-01T18:12:14.821Z,1714587134.821 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-01T18:14:54.812Z,1714587294.812 [BPC1](INFO): Calculating totals. Valid battery stick count: 40. Valid reserve battery stick count: 6. 2024-05-01T18:14:54.816Z,1714587294.816 [BPC1](INFO): Received data from all battery sticks. 2024-05-01T18:15:34.410Z,1714587334.410 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-01T18:15:34.410Z,1714587334.410 [Default:CheckIn:C.Wait] Stopped 2024-05-01T18:15:34.410Z,1714587334.410 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-01T18:15:34.411Z,1714587334.411 [Default:CheckIn:D] Running Loop=1 2024-05-01T18:15:34.817Z,1714587334.817 [Default:CheckIn:D] Stopped 2024-05-01T18:15:34.817Z,1714587334.817 [Default:CheckIn:E] Running Loop=1 2024-05-01T18:15:35.225Z,1714587335.225 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 81.206974 min 2024-05-01T18:15:35.225Z,1714587335.225 [Default:CheckIn:E] Stopped 2024-05-01T18:15:35.225Z,1714587335.225 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-01T18:15:35.225Z,1714587335.225 [Default:CheckIn] Stopped 2024-05-01T18:15:35.225Z,1714587335.225 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-01T18:15:35.225Z,1714587335.225 [Default:CheckIn](INFO): Running loop #13 2024-05-01T18:15:35.225Z,1714587335.225 [Default:CheckIn] Running Loop=13 2024-05-01T18:15:35.226Z,1714587335.226 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-01T18:15:35.226Z,1714587335.226 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-01T18:16:25.591Z,1714587385.591 [CommandExec](IMPORTANT): got command maintain control SpeedControl.propOmegaAction -300 revolution_per_minute 2024-05-01T18:16:25.593Z,1714587385.593 [ComponentRegistry](DEBUG): SyncComponent "Maintain_SpeedControl.propOmegaAction" handled in the control thread. 2024-05-01T18:16:25.756Z,1714587385.756 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_SpeedControl.propOmegaAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2024-05-01T18:18:01.108Z,1714587481.108 [CommandExec](IMPORTANT): got command maintain clear 2024-05-01T18:18:01.201Z,1714587481.201 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2024-05-01T18:20:35.390Z,1714587635.390 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-05-01T18:15:35.2Z 2024-05-01T18:20:35.390Z,1714587635.390 [Default:CheckIn:Read_GPS] Stopped 2024-05-01T18:20:35.391Z,1714587635.391 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-01T18:20:39.839Z,1714587639.839 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-05-01T18:20:42.554Z,1714587642.554 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20240501T172351/Courier0024.lzma 2024-05-01T18:20:43.555Z,1714587643.555 [DataOverHttps](INFO): Moved sent file to Logs/20240501T172351/Courier0024.lzma.bak 2024-05-01T18:20:43.555Z,1714587643.555 [DataOverHttps](INFO): SBD MOMSN=19619988 2024-05-01T18:20:41.000Z,1714587642.000 [CBIT](FAULT): Humidity exceeds 150% of running average: Humidity:23 %. Average:11.880000 2024-05-01T18:20:44.077Z,1714587644.077 [RudderServo](FAULT): Overload Error 2024-05-01T18:20:44.077Z,1714587644.077 [RudderServo] Hardware Fault, FailCount= 1 2024-05-01T18:20:44.077Z,1714587644.077 [RudderServo](ERROR): Hardware Fault 2024-05-01T18:20:44.104Z,1714587644.104 [CBIT](ERROR): Hardware Fault in component: RudderServo 2024-05-01T18:20:44.477Z,1714587644.477 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-05-01T18:20:44.477Z,1714587644.477 [RudderServo](INFO): Powering down 2024-05-01T18:20:45.247Z,1714587645.247 [RudderServo](DEBUG): Initializing EZServoServo. 2024-05-01T18:20:45.362Z,1714587645.362 [RudderServo](DEBUG): Initializing RudderServo. 2024-05-01T18:20:45.367Z,1714587645.367 [CBIT](CRITICAL): Environmental Failure. Press:14.676020 PSI. Humidity:23%. Temp:21 C. ABORTING MISSION 2024-05-01T18:20:45.367Z,1714587645.367 [CBIT](INFO): Clearing failed state for component RudderServo 2024-05-01T18:20:45.367Z,1714587645.367 [RudderServo] No Fault, FailCount= 1 2024-05-01T18:20:45.660Z,1714587645.660 [CBIT](INFO): Critical error at 20240501T182045 2024-05-01T18:20:50.508Z,1714587650.508 [RudderServo](FAULT): Overload Error 2024-05-01T18:20:50.508Z,1714587650.508 [RudderServo] Hardware Fault, FailCount= 2 2024-05-01T18:20:50.508Z,1714587650.508 [RudderServo](ERROR): Hardware Fault 2024-05-01T18:20:50.540Z,1714587650.540 [CBIT](ERROR): Hardware Fault in component: RudderServo 2024-05-01T18:20:50.907Z,1714587650.907 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-05-01T18:20:50.907Z,1714587650.907 [RudderServo](INFO): Powering down 2024-05-01T18:20:51.698Z,1714587651.698 [RudderServo](DEBUG): Initializing EZServoServo. 2024-05-01T18:20:51.830Z,1714587651.830 [RudderServo](DEBUG): Initializing RudderServo. 2024-05-01T18:20:51.839Z,1714587651.839 [CBIT](INFO): Clearing failed state for component RudderServo 2024-05-01T18:20:51.839Z,1714587651.839 [RudderServo] No Fault, FailCount= 2 2024-05-01T18:20:56.594Z,1714587656.594 [DataOverHttps](INFO): Sending 418 bytes from file Logs/20240501T172351/Express0025.lzma 2024-05-01T18:20:56.988Z,1714587656.988 [RudderServo](FAULT): Overload Error 2024-05-01T18:20:56.988Z,1714587656.988 [RudderServo] Hardware Fault, FailCount= 3 2024-05-01T18:20:56.988Z,1714587656.988 [RudderServo](ERROR): Hardware Fault 2024-05-01T18:20:57.021Z,1714587657.021 [CBIT](ERROR): Hardware Fault in component: RudderServo 2024-05-01T18:20:57.021Z,1714587657.021 [CBIT](CRITICAL): Hardware Fault in component: RudderServo 2024-05-01T18:20:57.354Z,1714587657.354 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-05-01T18:20:57.354Z,1714587657.354 [RudderServo](INFO): Powering down 2024-05-01T18:20:57.356Z,1714587657.356 [CBIT](INFO): Critical error at 20240501T182057 2024-05-01T18:20:57.578Z,1714587657.578 [DataOverHttps](INFO): Moved sent file to Logs/20240501T172351/Express0025.lzma.bak 2024-05-01T18:20:57.578Z,1714587657.578 [DataOverHttps](INFO): SBD MOMSN=19619990 2024-05-01T18:20:58.174Z,1714587658.174 [RudderServo](DEBUG): Initializing EZServoServo. 2024-05-01T18:20:58.290Z,1714587658.290 [RudderServo](DEBUG): Initializing RudderServo. 2024-05-01T18:20:58.970Z,1714587658.970 [Default:CheckIn:Read_Iridium] Stopped 2024-05-01T18:20:58.970Z,1714587658.970 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-01T18:20:58.970Z,1714587658.970 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-01T18:21:10.262Z,1714587670.262 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-01T18:25:59.540Z,1714587959.540 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-01T18:25:59.540Z,1714587959.540 [Default:CheckIn:C.Wait] Stopped 2024-05-01T18:25:59.540Z,1714587959.540 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-01T18:25:59.541Z,1714587959.541 [Default:CheckIn:D] Running Loop=1 2024-05-01T18:25:59.948Z,1714587959.948 [Default:CheckIn:D] Stopped 2024-05-01T18:25:59.948Z,1714587959.948 [Default:CheckIn:E] Running Loop=1 2024-05-01T18:26:00.356Z,1714587960.356 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 91.625830 min 2024-05-01T18:26:00.356Z,1714587960.356 [Default:CheckIn:E] Stopped 2024-05-01T18:26:00.357Z,1714587960.357 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-01T18:26:00.357Z,1714587960.357 [Default:CheckIn] Stopped 2024-05-01T18:26:00.357Z,1714587960.357 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-01T18:26:00.357Z,1714587960.357 [Default:CheckIn](INFO): Running loop #14 2024-05-01T18:26:00.357Z,1714587960.357 [Default:CheckIn] Running Loop=14 2024-05-01T18:26:00.357Z,1714587960.357 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-01T18:26:00.357Z,1714587960.357 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-01T18:26:00.754Z,1714587960.754 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2024-05-01T18:26:00.754Z,1714587960.754 [DropWeight] Hardware Fault, FailCount= 1 2024-05-01T18:26:00.754Z,1714587960.754 [DropWeight](ERROR): Hardware Fault 2024-05-01T18:26:00.773Z,1714587960.773 [CBIT](INFO): Critical error at 20240501T182600 2024-05-01T18:26:00.776Z,1714587960.776 [CBIT](ERROR): Hardware Fault in component: DropWeight 2024-05-01T18:26:00.776Z,1714587960.776 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2024-05-01T18:26:01.189Z,1714587961.189 [CBIT](INFO): Critical error at 20240501T182600 2024-05-01T18:26:01.961Z,1714587961.961 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-05-01T18:26:01.961Z,1714587961.961 [NAL9602] Data Fault, FailCount= 1 2024-05-01T18:26:01.961Z,1714587961.961 [NAL9602](ERROR): Data Fault 2024-05-01T18:26:01.982Z,1714587961.982 [CBIT](ERROR): Data Fault in component: NAL9602 2024-05-01T18:26:02.357Z,1714587962.357 [NAL9602](INFO): Powering down 2024-05-01T18:26:03.190Z,1714587963.190 [CBIT](INFO): Clearing failed state for component NAL9602 2024-05-01T18:26:03.190Z,1714587963.190 [NAL9602] No Fault, FailCount= 1 2024-05-01T18:26:32.657Z,1714587992.657 [NAL9602](INFO): Powering up NAL9602 2024-05-01T18:26:43.571Z,1714588003.571 [NAL9602](INFO): NAL9602 initialized 2024-05-01T18:31:00.574Z,1714588260.574 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-05-01T18:26:00.4Z 2024-05-01T18:31:00.574Z,1714588260.574 [Default:CheckIn:Read_GPS] Stopped 2024-05-01T18:31:00.574Z,1714588260.574 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-01T18:31:13.465Z,1714588273.465 [DataOverHttps](INFO): Sending 247 bytes from file Logs/20240501T172351/Courier0027.lzma 2024-05-01T18:31:14.466Z,1714588274.466 [DataOverHttps](INFO): Moved sent file to Logs/20240501T172351/Courier0027.lzma.bak 2024-05-01T18:31:14.466Z,1714588274.466 [DataOverHttps](INFO): SBD MOMSN=19620041 2024-05-01T18:31:39.973Z,1714588299.973 [DataOverHttps](INFO): Sending 356 bytes from file Logs/20240501T172351/Express0028.lzma 2024-05-01T18:31:40.974Z,1714588300.974 [DataOverHttps](INFO): Moved sent file to Logs/20240501T172351/Express0028.lzma.bak 2024-05-01T18:31:40.975Z,1714588300.975 [DataOverHttps](INFO): SBD MOMSN=19620047 2024-05-01T18:31:43.065Z,1714588303.065 [Default:CheckIn:Read_Iridium] Stopped 2024-05-01T18:31:43.065Z,1714588303.065 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-01T18:31:43.065Z,1714588303.065 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-01T18:31:47.417Z,1714588307.417 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-05-01T18:32:18.125Z,1714588338.125 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-01T18:35:57.149Z,1714588557.149 [CBIT](INFO): Clearing failed state for component RudderServo 2024-05-01T18:35:57.149Z,1714588557.149 [RudderServo] No Fault, FailCount= 3 2024-05-01T18:36:01.616Z,1714588561.616 [RudderServo](FAULT): Overload Error 2024-05-01T18:36:01.617Z,1714588561.617 [RudderServo] Hardware Fault, FailCount= 1 2024-05-01T18:36:01.617Z,1714588561.617 [RudderServo](ERROR): Hardware Fault 2024-05-01T18:36:01.645Z,1714588561.645 [CBIT](ERROR): Hardware Fault in component: RudderServo 2024-05-01T18:36:02.035Z,1714588562.035 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-05-01T18:36:02.035Z,1714588562.035 [RudderServo](INFO): Powering down 2024-05-01T18:36:02.848Z,1714588562.848 [RudderServo](DEBUG): Initializing EZServoServo. 2024-05-01T18:36:02.965Z,1714588562.965 [RudderServo](DEBUG): Initializing RudderServo. 2024-05-01T18:36:02.969Z,1714588562.969 [CBIT](INFO): Clearing failed state for component RudderServo 2024-05-01T18:36:02.969Z,1714588562.969 [RudderServo] No Fault, FailCount= 1 2024-05-01T18:36:08.109Z,1714588568.109 [RudderServo](FAULT): Overload Error 2024-05-01T18:36:08.109Z,1714588568.109 [RudderServo] Hardware Fault, FailCount= 2 2024-05-01T18:36:08.109Z,1714588568.109 [RudderServo](ERROR): Hardware Fault 2024-05-01T18:36:08.152Z,1714588568.152 [CBIT](ERROR): Hardware Fault in component: RudderServo 2024-05-01T18:36:08.439Z,1714588568.439 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-05-01T18:36:08.439Z,1714588568.439 [RudderServo](INFO): Powering down 2024-05-01T18:36:09.271Z,1714588569.271 [RudderServo](DEBUG): Initializing EZServoServo. 2024-05-01T18:36:09.389Z,1714588569.389 [RudderServo](DEBUG): Initializing RudderServo. 2024-05-01T18:36:09.396Z,1714588569.396 [CBIT](INFO): Clearing failed state for component RudderServo 2024-05-01T18:36:09.396Z,1714588569.396 [RudderServo] No Fault, FailCount= 2 2024-05-01T18:36:14.548Z,1714588574.548 [RudderServo](FAULT): Overload Error 2024-05-01T18:36:14.549Z,1714588574.549 [RudderServo] Hardware Fault, FailCount= 3 2024-05-01T18:36:14.549Z,1714588574.549 [RudderServo](ERROR): Hardware Fault 2024-05-01T18:36:14.582Z,1714588574.582 [CBIT](ERROR): Hardware Fault in component: RudderServo 2024-05-01T18:36:14.582Z,1714588574.582 [CBIT](CRITICAL): Hardware Fault in component: RudderServo 2024-05-01T18:36:14.970Z,1714588574.970 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-05-01T18:36:14.970Z,1714588574.970 [RudderServo](INFO): Powering down 2024-05-01T18:36:15.001Z,1714588575.001 [CBIT](INFO): Critical error at 20240501T183614 2024-05-01T18:36:15.743Z,1714588575.743 [RudderServo](DEBUG): Initializing EZServoServo. 2024-05-01T18:36:15.861Z,1714588575.861 [RudderServo](DEBUG): Initializing RudderServo. 2024-05-01T18:36:43.610Z,1714588603.610 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-01T18:36:43.611Z,1714588603.611 [Default:CheckIn:C.Wait] Stopped 2024-05-01T18:36:43.611Z,1714588603.611 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-01T18:36:43.611Z,1714588603.611 [Default:CheckIn:D] Running Loop=1 2024-05-01T18:36:43.978Z,1714588603.978 [Default:CheckIn:D] Stopped 2024-05-01T18:36:43.978Z,1714588603.978 [Default:CheckIn:E] Running Loop=1 2024-05-01T18:36:44.406Z,1714588604.406 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 102.359660 min 2024-05-01T18:36:44.406Z,1714588604.406 [Default:CheckIn:E] Stopped 2024-05-01T18:36:44.406Z,1714588604.406 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-01T18:36:44.406Z,1714588604.406 [Default:CheckIn] Stopped 2024-05-01T18:36:44.406Z,1714588604.406 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-01T18:36:44.407Z,1714588604.407 [Default:CheckIn](INFO): Running loop #15 2024-05-01T18:36:44.407Z,1714588604.407 [Default:CheckIn] Running Loop=15 2024-05-01T18:36:44.407Z,1714588604.407 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-01T18:36:44.407Z,1714588604.407 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-01T18:36:45.978Z,1714588605.978 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-05-01T18:36:45.978Z,1714588605.978 [NAL9602] Data Fault, FailCount= 2 2024-05-01T18:36:45.978Z,1714588605.978 [NAL9602](ERROR): Data Fault 2024-05-01T18:36:46.060Z,1714588606.060 [CBIT](ERROR): Data Fault in component: NAL9602 2024-05-01T18:36:46.389Z,1714588606.389 [NAL9602](INFO): Powering down 2024-05-01T18:36:47.275Z,1714588607.275 [CBIT](INFO): Clearing failed state for component NAL9602 2024-05-01T18:36:47.275Z,1714588607.275 [NAL9602] No Fault, FailCount= 2 2024-05-01T18:37:16.677Z,1714588636.677 [NAL9602](INFO): Powering up NAL9602 2024-05-01T18:37:27.594Z,1714588647.594 [NAL9602](INFO): NAL9602 initialized 2024-05-01T18:41:00.995Z,1714588860.995 [CBIT](INFO): Clearing failed state for component DropWeight 2024-05-01T18:41:00.995Z,1714588860.995 [DropWeight] No Fault, FailCount= 1 2024-05-01T18:41:44.566Z,1714588904.566 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-05-01T18:36:44.4Z 2024-05-01T18:41:44.566Z,1714588904.566 [Default:CheckIn:Read_GPS] Stopped 2024-05-01T18:41:44.566Z,1714588904.566 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-01T18:41:57.796Z,1714588917.796 [DataOverHttps](INFO): Sending 88 bytes from file Logs/20240501T172351/Courier0030.lzma 2024-05-01T18:41:58.798Z,1714588918.798 [DataOverHttps](INFO): Moved sent file to Logs/20240501T172351/Courier0030.lzma.bak 2024-05-01T18:41:58.798Z,1714588918.798 [DataOverHttps](INFO): SBD MOMSN=19620099 2024-05-01T18:42:24.354Z,1714588944.354 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20240501T172351/Express0031.lzma 2024-05-01T18:42:25.354Z,1714588945.354 [DataOverHttps](INFO): Moved sent file to Logs/20240501T172351/Express0031.lzma.bak 2024-05-01T18:42:25.354Z,1714588945.354 [DataOverHttps](INFO): SBD MOMSN=19620102 2024-05-01T18:42:27.114Z,1714588947.114 [Default:CheckIn:Read_Iridium] Stopped 2024-05-01T18:42:27.114Z,1714588947.114 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-01T18:42:27.114Z,1714588947.114 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-01T18:42:31.130Z,1714588951.130 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-05-01T18:43:01.829Z,1714588981.829 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-01T18:47:27.718Z,1714589247.718 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-01T18:47:27.718Z,1714589247.718 [Default:CheckIn:C.Wait] Stopped 2024-05-01T18:47:27.718Z,1714589247.718 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-01T18:47:27.719Z,1714589247.719 [Default:CheckIn:D] Running Loop=1 2024-05-01T18:47:28.133Z,1714589248.133 [Default:CheckIn:D] Stopped 2024-05-01T18:47:28.133Z,1714589248.133 [Default:CheckIn:E] Running Loop=1 2024-05-01T18:47:28.525Z,1714589248.525 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 113.095581 min 2024-05-01T18:47:28.525Z,1714589248.525 [Default:CheckIn:E] Stopped 2024-05-01T18:47:28.526Z,1714589248.526 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-01T18:47:28.526Z,1714589248.526 [Default:CheckIn] Stopped 2024-05-01T18:47:28.526Z,1714589248.526 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-01T18:47:28.526Z,1714589248.526 [Default:CheckIn](INFO): Running loop #16 2024-05-01T18:47:28.526Z,1714589248.526 [Default:CheckIn] Running Loop=16 2024-05-01T18:47:28.526Z,1714589248.526 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-01T18:47:28.526Z,1714589248.526 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-01T18:47:30.101Z,1714589250.101 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-05-01T18:47:30.101Z,1714589250.101 [NAL9602] Data Fault, FailCount= 3 2024-05-01T18:47:30.101Z,1714589250.101 [NAL9602](ERROR): Data Fault 2024-05-01T18:47:30.227Z,1714589250.227 [CBIT](ERROR): Data Fault in component: NAL9602 2024-05-01T18:47:30.505Z,1714589250.505 [NAL9602](INFO): Powering down 2024-05-01T18:47:31.346Z,1714589251.346 [CBIT](INFO): Clearing failed state for component NAL9602 2024-05-01T18:47:31.346Z,1714589251.346 [NAL9602] No Fault, FailCount= 3 2024-05-01T18:48:00.809Z,1714589280.809 [NAL9602](INFO): Powering up NAL9602 2024-05-01T18:48:11.718Z,1714589291.718 [NAL9602](INFO): NAL9602 initialized 2024-05-01T18:50:33.437Z,1714589433.437 [CommandExec](IMPORTANT): got command maintain control SpeedControl.propOmegaAction -300 revolution_per_minute 2024-05-01T18:50:33.439Z,1714589433.439 [ComponentRegistry](DEBUG): SyncComponent "Maintain_SpeedControl.propOmegaAction" handled in the control thread. 2024-05-01T18:50:33.575Z,1714589433.575 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_SpeedControl.propOmegaAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2024-05-01T18:51:03.082Z,1714589463.082 [CommandExec](IMPORTANT): got command maintain clear 2024-05-01T18:51:03.153Z,1714589463.153 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2024-05-01T18:51:14.814Z,1714589474.814 [CBIT](INFO): Clearing failed state for component RudderServo 2024-05-01T18:51:14.814Z,1714589474.814 [RudderServo] No Fault, FailCount= 3 2024-05-01T18:51:19.240Z,1714589479.240 [RudderServo](FAULT): Overload Error 2024-05-01T18:51:19.241Z,1714589479.241 [RudderServo] Hardware Fault, FailCount= 1 2024-05-01T18:51:19.241Z,1714589479.241 [RudderServo](ERROR): Hardware Fault 2024-05-01T18:51:19.272Z,1714589479.272 [CBIT](ERROR): Hardware Fault in component: RudderServo 2024-05-01T18:51:19.626Z,1714589479.626 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-05-01T18:51:19.627Z,1714589479.627 [RudderServo](INFO): Powering down 2024-05-01T18:51:20.427Z,1714589480.427 [RudderServo](DEBUG): Initializing EZServoServo. 2024-05-01T18:51:20.548Z,1714589480.548 [RudderServo](DEBUG): Initializing RudderServo. 2024-05-01T18:51:20.557Z,1714589480.557 [CBIT](INFO): Clearing failed state for component RudderServo 2024-05-01T18:51:20.557Z,1714589480.557 [RudderServo] No Fault, FailCount= 1 2024-05-01T18:51:25.716Z,1714589485.716 [RudderServo](FAULT): Overload Error 2024-05-01T18:51:25.716Z,1714589485.716 [RudderServo] Hardware Fault, FailCount= 2 2024-05-01T18:51:25.716Z,1714589485.716 [RudderServo](ERROR): Hardware Fault 2024-05-01T18:51:25.744Z,1714589485.744 [CBIT](ERROR): Hardware Fault in component: RudderServo 2024-05-01T18:51:26.093Z,1714589486.093 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-05-01T18:51:26.093Z,1714589486.093 [RudderServo](INFO): Powering down 2024-05-01T18:51:26.879Z,1714589486.879 [RudderServo](DEBUG): Initializing EZServoServo. 2024-05-01T18:51:26.997Z,1714589486.997 [RudderServo](DEBUG): Initializing RudderServo. 2024-05-01T18:51:27.001Z,1714589487.001 [CBIT](INFO): Clearing failed state for component RudderServo 2024-05-01T18:51:27.001Z,1714589487.001 [RudderServo] No Fault, FailCount= 2 2024-05-01T18:51:32.189Z,1714589492.189 [RudderServo](FAULT): Overload Error 2024-05-01T18:51:32.189Z,1714589492.189 [RudderServo] Hardware Fault, FailCount= 3 2024-05-01T18:51:32.189Z,1714589492.189 [RudderServo](ERROR): Hardware Fault 2024-05-01T18:51:32.216Z,1714589492.216 [CBIT](ERROR): Hardware Fault in component: RudderServo 2024-05-01T18:51:32.217Z,1714589492.217 [CBIT](CRITICAL): Hardware Fault in component: RudderServo 2024-05-01T18:51:32.550Z,1714589492.550 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-05-01T18:51:32.551Z,1714589492.551 [RudderServo](INFO): Powering down 2024-05-01T18:51:32.553Z,1714589492.553 [CBIT](INFO): Critical error at 20240501T185132 2024-05-01T18:51:33.387Z,1714589493.387 [RudderServo](DEBUG): Initializing EZServoServo. 2024-05-01T18:51:33.505Z,1714589493.505 [RudderServo](DEBUG): Initializing RudderServo. 2024-05-01T18:52:28.713Z,1714589548.713 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-05-01T18:47:28.5Z 2024-05-01T18:52:28.713Z,1714589548.713 [Default:CheckIn:Read_GPS] Stopped 2024-05-01T18:52:28.714Z,1714589548.714 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-01T18:52:40.945Z,1714589560.945 [DataOverHttps](INFO): Sending 88 bytes from file Logs/20240501T172351/Courier0033.lzma 2024-05-01T18:52:41.946Z,1714589561.946 [DataOverHttps](INFO): Moved sent file to Logs/20240501T172351/Courier0033.lzma.bak 2024-05-01T18:52:41.947Z,1714589561.947 [DataOverHttps](INFO): SBD MOMSN=19620131 2024-05-01T18:53:05.857Z,1714589585.857 [DataOverHttps](INFO): Sending 360 bytes from file Logs/20240501T172351/Express0034.lzma 2024-05-01T18:53:06.858Z,1714589586.858 [DataOverHttps](INFO): Moved sent file to Logs/20240501T172351/Express0034.lzma.bak 2024-05-01T18:53:06.858Z,1714589586.858 [DataOverHttps](INFO): SBD MOMSN=19620138 2024-05-01T18:53:08.510Z,1714589588.510 [Default:CheckIn:Read_Iridium] Stopped 2024-05-01T18:53:08.510Z,1714589588.510 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-01T18:53:08.510Z,1714589588.510 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-01T18:53:15.743Z,1714589595.743 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-05-01T18:53:46.441Z,1714589626.441 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-01T18:58:09.082Z,1714589889.082 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-01T18:58:09.082Z,1714589889.082 [Default:CheckIn:C.Wait] Stopped 2024-05-01T18:58:09.082Z,1714589889.082 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-01T18:58:09.082Z,1714589889.082 [Default:CheckIn:D] Running Loop=1 2024-05-01T18:58:09.494Z,1714589889.494 [Default:CheckIn:D] Stopped 2024-05-01T18:58:09.494Z,1714589889.494 [Default:CheckIn:E] Running Loop=1 2024-05-01T18:58:09.882Z,1714589889.882 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 123.784920 min 2024-05-01T18:58:09.882Z,1714589889.882 [Default:CheckIn:E] Stopped 2024-05-01T18:58:09.882Z,1714589889.882 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-01T18:58:09.882Z,1714589889.882 [Default:CheckIn] Stopped 2024-05-01T18:58:09.882Z,1714589889.882 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-01T18:58:09.882Z,1714589889.882 [Default:CheckIn](INFO): Running loop #17 2024-05-01T18:58:09.882Z,1714589889.882 [Default:CheckIn] Running Loop=17 2024-05-01T18:58:09.883Z,1714589889.883 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-01T18:58:09.883Z,1714589889.883 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-01T18:58:42.586Z,1714589922.586 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2024-05-01T18:58:42.664Z,1714589922.664 [NAL9602](ERROR): received: +CSQ:0 OK008, 2, 0, 0, 0 OK 2024-05-01T19:03:10.069Z,1714590190.069 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-05-01T18:58:09.9Z 2024-05-01T19:03:10.069Z,1714590190.069 [Default:CheckIn:Read_GPS] Stopped 2024-05-01T19:03:10.071Z,1714590190.071 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-01T19:03:14.525Z,1714590194.525 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-05-01T19:03:22.101Z,1714590202.101 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240501T172351/Courier0036.lzma 2024-05-01T19:03:23.102Z,1714590203.102 [DataOverHttps](INFO): Moved sent file to Logs/20240501T172351/Courier0036.lzma.bak 2024-05-01T19:03:23.102Z,1714590203.102 [DataOverHttps](INFO): SBD MOMSN=19620233 2024-05-01T19:03:45.213Z,1714590225.213 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-01T19:03:48.873Z,1714590228.873 [DataOverHttps](INFO): Sending 217 bytes from file Logs/20240501T172351/Express0037.lzma 2024-05-01T19:03:49.874Z,1714590229.874 [DataOverHttps](INFO): Moved sent file to Logs/20240501T172351/Express0037.lzma.bak 2024-05-01T19:03:49.874Z,1714590229.874 [DataOverHttps](INFO): SBD MOMSN=19620235 2024-05-01T19:03:52.524Z,1714590232.524 [Default:CheckIn:Read_Iridium] Stopped 2024-05-01T19:03:52.525Z,1714590232.525 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-01T19:03:52.525Z,1714590232.525 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-01T19:05:19.010Z,1714590319.010 [CBIT](CRITICAL): Environmental Failure. Press:13.950611 PSI. Humidity:6%. Temp:22 C. ABORTING MISSION 2024-05-01T19:05:19.400Z,1714590319.400 [CBIT](INFO): Critical error at 20240501T190519 2024-05-01T19:06:32.535Z,1714590392.535 [CBIT](INFO): Clearing failed state for component RudderServo 2024-05-01T19:06:32.535Z,1714590392.535 [RudderServo] No Fault, FailCount= 3 2024-05-01T19:06:37.019Z,1714590397.019 [RudderServo](FAULT): Overload Error 2024-05-01T19:06:37.019Z,1714590397.019 [RudderServo] Hardware Fault, FailCount= 1 2024-05-01T19:06:37.019Z,1714590397.019 [RudderServo](ERROR): Hardware Fault 2024-05-01T19:06:37.048Z,1714590397.048 [CBIT](ERROR): Hardware Fault in component: RudderServo 2024-05-01T19:06:37.391Z,1714590397.391 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-05-01T19:06:37.391Z,1714590397.391 [RudderServo](INFO): Powering down 2024-05-01T19:06:38.222Z,1714590398.222 [RudderServo](DEBUG): Initializing EZServoServo. 2024-05-01T19:06:38.343Z,1714590398.343 [RudderServo](DEBUG): Initializing RudderServo. 2024-05-01T19:06:38.347Z,1714590398.347 [CBIT](INFO): Clearing failed state for component RudderServo 2024-05-01T19:06:38.347Z,1714590398.347 [RudderServo] No Fault, FailCount= 1 2024-05-01T19:06:43.465Z,1714590403.465 [RudderServo](FAULT): Overload Error 2024-05-01T19:06:43.465Z,1714590403.465 [RudderServo] Hardware Fault, FailCount= 2 2024-05-01T19:06:43.465Z,1714590403.465 [RudderServo](ERROR): Hardware Fault 2024-05-01T19:06:43.501Z,1714590403.501 [CBIT](ERROR): Hardware Fault in component: RudderServo 2024-05-01T19:06:43.857Z,1714590403.857 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-05-01T19:06:43.857Z,1714590403.857 [RudderServo](INFO): Powering down 2024-05-01T19:06:44.693Z,1714590404.693 [RudderServo](DEBUG): Initializing EZServoServo. 2024-05-01T19:06:44.815Z,1714590404.815 [RudderServo](DEBUG): Initializing RudderServo. 2024-05-01T19:06:44.820Z,1714590404.820 [CBIT](INFO): Clearing failed state for component RudderServo 2024-05-01T19:06:44.820Z,1714590404.820 [RudderServo] No Fault, FailCount= 2 2024-05-01T19:06:49.945Z,1714590409.945 [RudderServo](FAULT): Overload Error 2024-05-01T19:06:49.945Z,1714590409.945 [RudderServo] Hardware Fault, FailCount= 3 2024-05-01T19:06:49.945Z,1714590409.945 [RudderServo](ERROR): Hardware Fault 2024-05-01T19:06:49.984Z,1714590409.984 [CBIT](ERROR): Hardware Fault in component: RudderServo 2024-05-01T19:06:49.985Z,1714590409.985 [CBIT](CRITICAL): Hardware Fault in component: RudderServo 2024-05-01T19:06:50.304Z,1714590410.304 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-05-01T19:06:50.304Z,1714590410.304 [RudderServo](INFO): Powering down 2024-05-01T19:06:50.311Z,1714590410.311 [CBIT](INFO): Critical error at 20240501T190649 2024-05-01T19:06:51.091Z,1714590411.091 [RudderServo](DEBUG): Initializing EZServoServo. 2024-05-01T19:06:51.207Z,1714590411.207 [RudderServo](DEBUG): Initializing RudderServo. 2024-05-01T19:08:53.089Z,1714590533.089 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-01T19:08:53.090Z,1714590533.090 [Default:CheckIn:C.Wait] Stopped 2024-05-01T19:08:53.090Z,1714590533.090 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-01T19:08:53.090Z,1714590533.090 [Default:CheckIn:D] Running Loop=1 2024-05-01T19:08:53.503Z,1714590533.503 [Default:CheckIn:D] Stopped 2024-05-01T19:08:53.503Z,1714590533.503 [Default:CheckIn:E] Running Loop=1 2024-05-01T19:08:53.913Z,1714590533.913 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 134.518400 min 2024-05-01T19:08:53.913Z,1714590533.913 [Default:CheckIn:E] Stopped 2024-05-01T19:08:53.913Z,1714590533.913 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-01T19:08:53.913Z,1714590533.913 [Default:CheckIn] Stopped 2024-05-01T19:08:53.914Z,1714590533.914 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-01T19:08:53.914Z,1714590533.914 [Default:CheckIn](INFO): Running loop #18 2024-05-01T19:08:53.914Z,1714590533.914 [Default:CheckIn] Running Loop=18 2024-05-01T19:08:53.914Z,1714590533.914 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-01T19:08:53.914Z,1714590533.914 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-01T19:08:55.494Z,1714590535.494 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-05-01T19:08:55.494Z,1714590535.494 [NAL9602] Data Fault, FailCount= 4 2024-05-01T19:08:55.494Z,1714590535.494 [NAL9602](ERROR): Data Fault 2024-05-01T19:08:55.594Z,1714590535.594 [CBIT](ERROR): Data Fault in component: NAL9602 2024-05-01T19:08:55.897Z,1714590535.897 [NAL9602](INFO): Powering down 2024-05-01T19:08:56.751Z,1714590536.751 [CBIT](INFO): Clearing failed state for component NAL9602 2024-05-01T19:08:56.751Z,1714590536.751 [NAL9602] No Fault, FailCount= 4 2024-05-01T19:09:02.590Z,1714590542.590 [CommandExec](IMPORTANT): got command get Onboard.Pressure 2024-05-01T19:09:02.590Z,1714590542.590 [CommandExec](IMPORTANT): Onboard.Pressure 58441.539062 Pa 2024-05-01T19:09:09.553Z,1714590549.553 [CommandExec](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch 2024-05-01T19:09:09.554Z,1714590549.554 [CommandExec](IMPORTANT): Onboard.Pressure 8.985002 psi 2024-05-01T19:09:25.093Z,1714590565.093 [CommandExec](IMPORTANT): got command get Onboard.Humidity 2024-05-01T19:09:25.094Z,1714590565.094 [CommandExec](IMPORTANT): Onboard.Humidity 12.947136 % 2024-05-01T19:09:26.200Z,1714590566.200 [NAL9602](INFO): Powering up NAL9602 2024-05-01T19:09:37.110Z,1714590577.110 [NAL9602](INFO): NAL9602 initialized 2024-05-01T19:09:38.898Z,1714590578.898 [CommandExec](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch 2024-05-01T19:09:38.898Z,1714590578.898 [CommandExec](IMPORTANT): Onboard.Pressure 8.973157 psi 2024-05-01T19:09:46.988Z,1714590586.989 [CommandExec](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch 2024-05-01T19:09:46.989Z,1714590586.989 [CommandExec](IMPORTANT): Onboard.Pressure 9.001080 psi 2024-05-01T19:09:58.444Z,1714590598.444 [CommandExec](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch 2024-05-01T19:09:58.445Z,1714590598.445 [CommandExec](IMPORTANT): Onboard.Pressure 8.985002 psi 2024-05-01T19:10:07.381Z,1714590607.381 [CommandExec](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch 2024-05-01T19:10:07.382Z,1714590607.382 [CommandExec](IMPORTANT): Onboard.Pressure 9.041762 psi 2024-05-01T19:10:17.509Z,1714590617.509 [CommandExec](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch 2024-05-01T19:10:17.510Z,1714590617.510 [CommandExec](IMPORTANT): Onboard.Pressure 9.468933 psi 2024-05-01T19:10:29.244Z,1714590629.244 [CommandExec](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch 2024-05-01T19:10:29.245Z,1714590629.245 [CommandExec](IMPORTANT): Onboard.Pressure 10.201228 psi 2024-05-01T19:10:57.826Z,1714590657.826 [CommandExec](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch 2024-05-01T19:10:57.826Z,1714590657.826 [CommandExec](IMPORTANT): Onboard.Pressure 10.226404 psi 2024-05-01T19:11:02.505Z,1714590662.505 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2024-05-01T19:11:02.505Z,1714590662.505 [DropWeight] Hardware Fault, FailCount= 1 2024-05-01T19:11:02.505Z,1714590662.505 [DropWeight](ERROR): Hardware Fault 2024-05-01T19:11:02.598Z,1714590662.598 [CBIT](INFO): Critical error at 20240501T191102 2024-05-01T19:11:02.609Z,1714590662.609 [CBIT](ERROR): Hardware Fault in component: DropWeight 2024-05-01T19:11:02.609Z,1714590662.609 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2024-05-01T19:11:02.984Z,1714590662.984 [CBIT](INFO): Critical error at 20240501T191102 2024-05-01T19:11:35.375Z,1714590695.375 [CommandExec](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch 2024-05-01T19:11:35.375Z,1714590695.375 [CommandExec](IMPORTANT): Onboard.Pressure 10.429912 psi 2024-05-01T19:11:41.028Z,1714590701.028 [CommandExec](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch 2024-05-01T19:11:41.029Z,1714590701.029 [CommandExec](IMPORTANT): Onboard.Pressure 10.018154 psi 2024-05-01T19:11:52.943Z,1714590712.943 [CommandExec](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch 2024-05-01T19:11:52.943Z,1714590712.943 [CommandExec](IMPORTANT): Onboard.Pressure 9.554826 psi 2024-05-01T19:11:59.201Z,1714590719.201 [CommandExec](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch 2024-05-01T19:11:59.201Z,1714590719.201 [CommandExec](IMPORTANT): Onboard.Pressure 9.493773 psi 2024-05-01T19:12:06.065Z,1714590726.065 [CommandExec](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch 2024-05-01T19:12:06.065Z,1714590726.065 [CommandExec](IMPORTANT): Onboard.Pressure 9.534473 psi 2024-05-01T19:12:12.339Z,1714590732.339 [CommandExec](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch 2024-05-01T19:12:12.339Z,1714590732.339 [CommandExec](IMPORTANT): Onboard.Pressure 9.550300 psi 2024-05-01T19:12:19.997Z,1714590739.997 [CommandExec](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch 2024-05-01T19:12:19.998Z,1714590739.998 [CommandExec](IMPORTANT): Onboard.Pressure 9.143470 psi 2024-05-01T19:12:26.806Z,1714590746.806 [CommandExec](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch 2024-05-01T19:12:26.806Z,1714590746.806 [CommandExec](IMPORTANT): Onboard.Pressure 9.168159 psi 2024-05-01T19:12:33.377Z,1714590753.377 [CommandExec](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch 2024-05-01T19:12:33.378Z,1714590753.378 [CommandExec](IMPORTANT): Onboard.Pressure 9.233587 psi 2024-05-01T19:12:46.611Z,1714590766.611 [CommandExec](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch 2024-05-01T19:12:46.611Z,1714590766.611 [CommandExec](IMPORTANT): Onboard.Pressure 9.640791 psi 2024-05-01T19:13:03.333Z,1714590783.333 [CommandExec](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch 2024-05-01T19:13:03.334Z,1714590783.334 [CommandExec](IMPORTANT): Onboard.Pressure 9.559351 psi 2024-05-01T19:13:28.939Z,1714590808.939 [CommandExec](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch 2024-05-01T19:13:28.940Z,1714590808.940 [CommandExec](IMPORTANT): Onboard.Pressure 10.145000 psi 2024-05-01T19:13:47.172Z,1714590827.172 [CommandExec](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch 2024-05-01T19:13:47.185Z,1714590827.185 [CommandExec](IMPORTANT): Onboard.Pressure 10.047996 psi 2024-05-01T19:13:54.252Z,1714590834.252 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-05-01T19:08:53.9Z 2024-05-01T19:13:54.252Z,1714590834.252 [Default:CheckIn:Read_GPS] Stopped 2024-05-01T19:13:54.258Z,1714590834.258 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-01T19:14:02.107Z,1714590842.107 [CommandExec](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch 2024-05-01T19:14:02.107Z,1714590842.107 [CommandExec](IMPORTANT): Onboard.Pressure 10.043246 psi 2024-05-01T19:14:07.410Z,1714590847.410 [CommandExec](IMPORTANT): got command restart system 2024-05-01T19:14:07.777Z,1714590847.777 [DataOverHttps](INFO): Sending 217 bytes from file Logs/20240501T172351/Courier0039.lzma 2024-05-01T19:14:08.767Z,1714590848.767 [DataOverHttps](INFO): Moved sent file to Logs/20240501T172351/Courier0039.lzma.bak 2024-05-01T19:14:08.767Z,1714590848.767 [DataOverHttps](INFO): SBD MOMSN=19620301 2024-05-01T19:14:09.577Z,1714590849.577 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-01T19:14:09.577Z,1714590849.577 [CommandExec](INFO): Uninitializing the command executive. 2024-05-01T19:14:09.577Z,1714590849.577 [CommandExec](INFO): Uninitializing the command scheduler. 2024-05-01T19:14:09.578Z,1714590849.578 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-05-01T19:14:09.652Z,1714590849.652 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2024-05-01T19:14:09.652Z,1714590849.652 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2024-05-01T19:14:09.653Z,1714590849.653 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-05-01T19:14:09.654Z,1714590849.654 [NavChartDb](INFO): Join timeout helper Thread ID is 2156 2024-05-01T19:14:09.844Z,1714590849.844 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-01T19:14:09.845Z,1714590849.845 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-05-01T19:14:09.856Z,1714590849.856 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2024-05-01T19:14:09.856Z,1714590849.856 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-05-01T19:14:09.857Z,1714590849.857 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2157 2024-05-01T19:14:10.314Z,1714590850.314 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-01T19:14:10.314Z,1714590850.314 [WetLabsBB2FL](INFO): Powering down 2024-05-01T19:14:10.315Z,1714590850.315 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-05-01T19:14:10.328Z,1714590850.328 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2024-05-01T19:14:10.328Z,1714590850.328 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-05-01T19:14:10.329Z,1714590850.329 [CTD_Seabird](INFO): Join timeout helper Thread ID is 2158 2024-05-01T19:14:10.764Z,1714590850.764 [CTD_Seabird](INFO): Powering down 2024-05-01T19:14:10.777Z,1714590850.777 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-01T19:14:10.777Z,1714590850.777 [CTD_Seabird](INFO): Powering down 2024-05-01T19:14:10.820Z,1714590850.820 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-05-01T19:14:10.840Z,1714590850.840 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2024-05-01T19:14:10.840Z,1714590850.840 [DAT ThreadHandler](INFO): Thread cancelled. 2024-05-01T19:14:10.842Z,1714590850.842 [DAT](INFO): Join timeout helper Thread ID is 2159 2024-05-01T19:14:10.884Z,1714590850.884 [DAT](INFO): Powering down 2024-05-01T19:14:10.957Z,1714590850.957 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-01T19:14:10.957Z,1714590850.957 [DAT](INFO): Powering down 2024-05-01T19:14:10.958Z,1714590850.958 [DAT ThreadHandler](INFO): Thread cancelled. 2024-05-01T19:14:10.961Z,1714590850.961 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2024-05-01T19:14:10.961Z,1714590850.961 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-05-01T19:14:10.962Z,1714590850.962 [Radio_Surface](INFO): Join timeout helper Thread ID is 2160 2024-05-01T19:14:11.097Z,1714590851.097 [Radi