2025-09-05T23:31:22.635Z,1757115082.635 [CommandExec](IMPORTANT): got command restart logs 2025-09-05T23:34:43.988Z,1757115283.988 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-05T23:34:43.988Z,1757115283.988 [Default:CheckIn:C.Wait] Stopped 2025-09-05T23:34:43.988Z,1757115283.988 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-05T23:34:43.989Z,1757115283.989 [Default:CheckIn:D] Running Loop=1 2025-09-05T23:34:44.392Z,1757115284.392 [Default:CheckIn:D] Stopped 2025-09-05T23:34:44.392Z,1757115284.392 [Default:CheckIn:E] Running Loop=1 2025-09-05T23:34:44.796Z,1757115284.796 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 1065.862630 min 2025-09-05T23:34:44.796Z,1757115284.796 [Default:CheckIn:E] Stopped 2025-09-05T23:34:44.797Z,1757115284.797 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-05T23:34:44.797Z,1757115284.797 [Default:CheckIn] Stopped 2025-09-05T23:34:44.797Z,1757115284.797 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-05T23:34:44.797Z,1757115284.797 [Default:CheckIn](INFO): Running loop #12 2025-09-05T23:34:44.797Z,1757115284.797 [Default:CheckIn] Running Loop=12 2025-09-05T23:34:44.797Z,1757115284.797 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-05T23:34:44.797Z,1757115284.797 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-05T23:34:46.803Z,1757115286.803 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233446.00,A,3648.40026,N,12147.38478,W,17.670,57.85,050925,,,D*76 2025-09-05T23:34:46.805Z,1757115286.805 [NAL9602](INFO): GPS fix at 20250905T233446: (36.806671, -121.789746) 2025-09-05T23:34:46.815Z,1757115286.815 [Default:CheckIn:Read_GPS] Stopped 2025-09-05T23:34:46.815Z,1757115286.815 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-05T23:34:54.126Z,1757115294.126 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20250904T164447/Courier0183.lzma 2025-09-05T23:35:05.170Z,1757115305.170 [DataOverHttps](INFO): Moved sent file to Logs/20250904T164447/Courier0183.lzma.bak 2025-09-05T23:35:05.170Z,1757115305.170 [DataOverHttps](INFO): SBD MOMSN=25935126 2025-09-05T23:35:22.757Z,1757115322.757 [NAL9602](INFO): SBD MO Status=0, MOMSN=10853, MT Status=0, MTMSN=0 2025-09-05T23:35:22.757Z,1757115322.757 [NAL9602](INFO): No messages in MT queue 2025-09-05T23:35:31.091Z,1757115331.091 [DataOverHttps](INFO): Sending 269 bytes from file Logs/20250905T233122/Courier0000.lzma 2025-09-05T23:35:42.144Z,1757115342.144 [DataOverHttps](INFO): Moved sent file to Logs/20250905T233122/Courier0000.lzma.bak 2025-09-05T23:35:42.144Z,1757115342.144 [DataOverHttps](INFO): SBD MOMSN=25935128 2025-09-05T23:35:53.463Z,1757115353.463 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-05T23:36:08.162Z,1757115368.162 [DataOverHttps](INFO): Sending 103 bytes from file Logs/20250904T164447/Express0184.lzma 2025-09-05T23:36:19.204Z,1757115379.204 [DataOverHttps](INFO): Moved sent file to Logs/20250904T164447/Express0184.lzma.bak 2025-09-05T23:36:19.204Z,1757115379.204 [DataOverHttps](INFO): SBD MOMSN=25935133 2025-09-05T23:36:45.343Z,1757115405.343 [DataOverHttps](INFO): Sending 504 bytes from file Logs/20250905T233122/Express0001.lzma 2025-09-05T23:36:56.384Z,1757115416.384 [DataOverHttps](INFO): Moved sent file to Logs/20250905T233122/Express0001.lzma.bak 2025-09-05T23:36:56.384Z,1757115416.384 [DataOverHttps](INFO): SBD MOMSN=25935136 2025-09-05T23:37:22.674Z,1757115442.674 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20250905T233122/Express0004.lzma 2025-09-05T23:37:33.716Z,1757115453.716 [DataOverHttps](INFO): Moved sent file to Logs/20250905T233122/Express0004.lzma.bak 2025-09-05T23:37:33.716Z,1757115453.716 [DataOverHttps](INFO): SBD MOMSN=25935140 2025-09-05T23:37:34.884Z,1757115454.884 [Default:CheckIn:Read_Iridium] Stopped 2025-09-05T23:37:34.885Z,1757115454.885 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-05T23:37:34.885Z,1757115454.885 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-05T23:41:19.110Z,1757115679.110 [CBIT](INFO): Clearing failed state for component DropWeight 2025-09-05T23:41:19.110Z,1757115679.110 [DropWeight] No Fault, FailCount= 1 2025-09-05T23:41:20.707Z,1757115680.707 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-09-05T23:41:20.707Z,1757115680.707 [DropWeight] Hardware Fault, FailCount= 1 2025-09-05T23:41:20.707Z,1757115680.707 [DropWeight](ERROR): Hardware Fault 2025-09-05T23:41:20.724Z,1757115680.724 [CBIT](INFO): Critical error at 20250905T234120 2025-09-05T23:41:20.726Z,1757115680.726 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-09-05T23:41:20.727Z,1757115680.727 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-09-05T23:41:21.124Z,1757115681.124 [CBIT](INFO): Critical error at 20250905T234120 2025-09-05T23:42:35.460Z,1757115755.460 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-05T23:42:35.460Z,1757115755.460 [Default:CheckIn:C.Wait] Stopped 2025-09-05T23:42:35.460Z,1757115755.460 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-05T23:42:35.461Z,1757115755.461 [Default:CheckIn:D] Running Loop=1 2025-09-05T23:42:35.856Z,1757115755.856 [Default:CheckIn:D] Stopped 2025-09-05T23:42:35.856Z,1757115755.856 [Default:CheckIn:E] Running Loop=1 2025-09-05T23:42:36.258Z,1757115756.258 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 1073.720378 min 2025-09-05T23:42:36.258Z,1757115756.258 [Default:CheckIn:E] Stopped 2025-09-05T23:42:36.259Z,1757115756.259 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-05T23:42:36.259Z,1757115756.259 [Default:CheckIn] Stopped 2025-09-05T23:42:36.259Z,1757115756.259 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-05T23:42:36.259Z,1757115756.259 [Default:CheckIn](INFO): Running loop #13 2025-09-05T23:42:36.259Z,1757115756.259 [Default:CheckIn] Running Loop=13 2025-09-05T23:42:36.259Z,1757115756.259 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-05T23:42:36.259Z,1757115756.259 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-05T23:42:38.273Z,1757115758.273 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234237.00,A,3648.14899,N,12147.21840,W,0.078,212.63,050925,,,D*70 2025-09-05T23:42:38.275Z,1757115758.275 [NAL9602](INFO): GPS fix at 20250905T234237: (36.802483, -121.786973) 2025-09-05T23:42:38.285Z,1757115758.285 [Default:CheckIn:Read_GPS] Stopped 2025-09-05T23:42:38.285Z,1757115758.285 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-05T23:42:45.826Z,1757115765.826 [DataOverHttps](INFO): Sending 181 bytes from file Logs/20250905T233122/Courier0006.lzma 2025-09-05T23:42:56.868Z,1757115776.868 [DataOverHttps](INFO): Moved sent file to Logs/20250905T233122/Courier0006.lzma.bak 2025-09-05T23:42:56.868Z,1757115776.868 [DataOverHttps](INFO): SBD MOMSN=25935148 2025-09-05T23:42:58.469Z,1757115778.469 [NAL9602](INFO): SBD MO Status=0, MOMSN=10854, MT Status=0, MTMSN=0 2025-09-05T23:42:58.469Z,1757115778.469 [NAL9602](INFO): No messages in MT queue 2025-09-05T23:43:22.803Z,1757115802.803 [DataOverHttps](INFO): Sending 171 bytes from file Logs/20250905T233122/Express0007.lzma 2025-09-05T23:43:29.172Z,1757115809.172 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-05T23:43:33.856Z,1757115813.856 [DataOverHttps](INFO): Moved sent file to Logs/20250905T233122/Express0007.lzma.bak 2025-09-05T23:43:33.856Z,1757115813.856 [DataOverHttps](INFO): SBD MOMSN=25935154 2025-09-05T23:43:35.241Z,1757115815.241 [Default:CheckIn:Read_Iridium] Stopped 2025-09-05T23:43:35.241Z,1757115815.241 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-05T23:43:35.241Z,1757115815.241 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-05T23:48:35.817Z,1757116115.817 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-05T23:48:35.817Z,1757116115.817 [Default:CheckIn:C.Wait] Stopped 2025-09-05T23:48:35.817Z,1757116115.817 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-05T23:48:35.818Z,1757116115.818 [Default:CheckIn:D] Running Loop=1 2025-09-05T23:48:36.255Z,1757116116.255 [Default:CheckIn:D] Stopped 2025-09-05T23:48:36.255Z,1757116116.255 [Default:CheckIn:E] Running Loop=1 2025-09-05T23:48:36.643Z,1757116116.643 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 1079.727018 min 2025-09-05T23:48:36.643Z,1757116116.643 [Default:CheckIn:E] Stopped 2025-09-05T23:48:36.643Z,1757116116.643 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-05T23:48:36.644Z,1757116116.644 [Default:CheckIn] Stopped 2025-09-05T23:48:36.644Z,1757116116.644 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-05T23:48:36.644Z,1757116116.644 [Default:CheckIn](INFO): Running loop #14 2025-09-05T23:48:36.644Z,1757116116.644 [Default:CheckIn] Running Loop=14 2025-09-05T23:48:36.644Z,1757116116.644 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-05T23:48:36.644Z,1757116116.644 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-05T23:48:38.643Z,1757116118.643 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234837.00,A,3648.14774,N,12147.22238,W,0.019,248.96,050925,,,D*72 2025-09-05T23:48:38.646Z,1757116118.646 [NAL9602](INFO): GPS fix at 20250905T234837: (36.802462, -121.787040) 2025-09-05T23:48:38.673Z,1757116118.673 [Default:CheckIn:Read_GPS] Stopped 2025-09-05T23:48:38.673Z,1757116118.673 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-05T23:48:46.026Z,1757116126.026 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250905T233122/Courier0009.lzma 2025-09-05T23:48:55.202Z,1757116135.202 [NAL9602](INFO): SBD MO Status=0, MOMSN=10855, MT Status=0, MTMSN=0 2025-09-05T23:48:55.203Z,1757116135.203 [NAL9602](INFO): No messages in MT queue 2025-09-05T23:48:57.069Z,1757116137.069 [DataOverHttps](INFO): Moved sent file to Logs/20250905T233122/Courier0009.lzma.bak 2025-09-05T23:48:57.069Z,1757116137.069 [DataOverHttps](INFO): SBD MOMSN=25935158 2025-09-05T23:49:22.954Z,1757116162.954 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250905T233122/Express0010.lzma 2025-09-05T23:49:25.903Z,1757116165.903 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-05T23:49:33.996Z,1757116173.996 [DataOverHttps](INFO): Moved sent file to Logs/20250905T233122/Express0010.lzma.bak 2025-09-05T23:49:33.996Z,1757116173.996 [DataOverHttps](INFO): SBD MOMSN=25935162 2025-09-05T23:49:35.220Z,1757116175.220 [Default:CheckIn:Read_Iridium] Stopped 2025-09-05T23:49:35.220Z,1757116175.220 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-05T23:49:35.220Z,1757116175.220 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-05T23:54:35.822Z,1757116475.822 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-05T23:54:35.822Z,1757116475.822 [Default:CheckIn:C.Wait] Stopped 2025-09-05T23:54:35.822Z,1757116475.822 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-05T23:54:35.823Z,1757116475.823 [Default:CheckIn:D] Running Loop=1 2025-09-05T23:54:36.233Z,1757116476.233 [Default:CheckIn:D] Stopped 2025-09-05T23:54:36.233Z,1757116476.233 [Default:CheckIn:E] Running Loop=1 2025-09-05T23:54:36.652Z,1757116476.652 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 1085.726693 min 2025-09-05T23:54:36.652Z,1757116476.652 [Default:CheckIn:E] Stopped 2025-09-05T23:54:36.652Z,1757116476.652 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-05T23:54:36.652Z,1757116476.652 [Default:CheckIn] Stopped 2025-09-05T23:54:36.652Z,1757116476.652 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-05T23:54:36.652Z,1757116476.652 [Default:CheckIn](INFO): Running loop #15 2025-09-05T23:54:36.652Z,1757116476.652 [Default:CheckIn] Running Loop=15 2025-09-05T23:54:36.652Z,1757116476.652 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-05T23:54:36.653Z,1757116476.653 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-05T23:54:38.642Z,1757116478.642 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235437.00,A,3648.14833,N,12147.21513,W,0.078,248.96,050925,,,D*79 2025-09-05T23:54:38.644Z,1757116478.644 [NAL9602](INFO): GPS fix at 20250905T235437: (36.802472, -121.786919) 2025-09-05T23:54:38.655Z,1757116478.655 [Default:CheckIn:Read_GPS] Stopped 2025-09-05T23:54:38.655Z,1757116478.655 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-05T23:54:46.434Z,1757116486.434 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250905T233122/Courier0012.lzma 2025-09-05T23:54:57.484Z,1757116497.484 [DataOverHttps](INFO): Moved sent file to Logs/20250905T233122/Courier0012.lzma.bak 2025-09-05T23:54:57.484Z,1757116497.484 [DataOverHttps](INFO): SBD MOMSN=25935184 2025-09-05T23:55:04.102Z,1757116504.102 [NAL9602](INFO): SBD MO Status=0, MOMSN=10856, MT Status=0, MTMSN=0 2025-09-05T23:55:04.102Z,1757116504.102 [NAL9602](INFO): No messages in MT queue 2025-09-05T23:55:23.398Z,1757116523.398 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20250905T233122/Express0013.lzma 2025-09-05T23:55:34.448Z,1757116534.448 [DataOverHttps](INFO): Moved sent file to Logs/20250905T233122/Express0013.lzma.bak 2025-09-05T23:55:34.448Z,1757116534.448 [DataOverHttps](INFO): SBD MOMSN=25935187 2025-09-05T23:55:34.799Z,1757116534.799 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-05T23:55:35.621Z,1757116535.621 [Default:CheckIn:Read_Iridium] Stopped 2025-09-05T23:55:35.621Z,1757116535.621 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-05T23:55:35.621Z,1757116535.621 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-05T23:56:20.869Z,1757116580.869 [CBIT](INFO): Clearing failed state for component DropWeight 2025-09-05T23:56:20.869Z,1757116580.869 [DropWeight] No Fault, FailCount= 1 2025-09-05T23:56:22.467Z,1757116582.467 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-09-05T23:56:22.467Z,1757116582.467 [DropWeight] Hardware Fault, FailCount= 1 2025-09-05T23:56:22.467Z,1757116582.467 [DropWeight](ERROR): Hardware Fault 2025-09-05T23:56:22.486Z,1757116582.486 [CBIT](INFO): Critical error at 20250905T235622 2025-09-05T23:56:22.488Z,1757116582.488 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-09-05T23:56:22.489Z,1757116582.489 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-09-05T23:56:22.896Z,1757116582.896 [CBIT](INFO): Critical error at 20250905T235622 2025-09-06T00:00:00.062Z,1757116800.062 [CommandExec](FAULT): Schedule is paused. Ignoring time-scheduled command: set circle_acoustic_contact:BackseatDriver.EnableBackseat 1 bool 2025-09-06T00:00:36.230Z,1757116836.230 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-06T00:00:36.230Z,1757116836.230 [Default:CheckIn:C.Wait] Stopped 2025-09-06T00:00:36.230Z,1757116836.230 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-06T00:00:36.230Z,1757116836.230 [Default:CheckIn:D] Running Loop=1 2025-09-06T00:00:36.616Z,1757116836.616 [Default:CheckIn:D] Stopped 2025-09-06T00:00:36.616Z,1757116836.616 [Default:CheckIn:E] Running Loop=1 2025-09-06T00:00:37.024Z,1757116837.024 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 1091.733073 min 2025-09-06T00:00:37.025Z,1757116837.025 [Default:CheckIn:E] Stopped 2025-09-06T00:00:37.025Z,1757116837.025 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-06T00:00:37.025Z,1757116837.025 [Default:CheckIn] Stopped 2025-09-06T00:00:37.025Z,1757116837.025 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-06T00:00:37.025Z,1757116837.025 [Default:CheckIn](INFO): Running loop #16 2025-09-06T00:00:37.025Z,1757116837.025 [Default:CheckIn] Running Loop=16 2025-09-06T00:00:37.025Z,1757116837.025 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-06T00:00:37.025Z,1757116837.025 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-06T00:00:39.038Z,1757116839.038 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000038.00,A,3648.14923,N,12147.22025,W,0.058,248.96,060925,,,D*74 2025-09-06T00:00:39.040Z,1757116839.040 [NAL9602](INFO): GPS fix at 20250906T000038: (36.802487, -121.787004) 2025-09-06T00:00:39.051Z,1757116839.051 [Default:CheckIn:Read_GPS] Stopped 2025-09-06T00:00:39.051Z,1757116839.051 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-06T00:00:46.550Z,1757116846.550 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20250905T233122/Courier0015.lzma 2025-09-06T00:00:57.607Z,1757116857.607 [DataOverHttps](INFO): Moved sent file to Logs/20250905T233122/Courier0015.lzma.bak 2025-09-06T00:00:57.607Z,1757116857.607 [DataOverHttps](INFO): SBD MOMSN=25935283 2025-09-06T00:01:11.358Z,1757116871.358 [NAL9602](INFO): SBD MO Status=0, MOMSN=10857, MT Status=0, MTMSN=0 2025-09-06T00:01:11.358Z,1757116871.358 [NAL9602](INFO): No messages in MT queue 2025-09-06T00:01:23.483Z,1757116883.483 [DataOverHttps](INFO): Sending 252 bytes from file Logs/20250905T233122/Express0016.lzma 2025-09-06T00:01:34.524Z,1757116894.524 [DataOverHttps](INFO): Moved sent file to Logs/20250905T233122/Express0016.lzma.bak 2025-09-06T00:01:34.524Z,1757116894.524 [DataOverHttps](INFO): SBD MOMSN=25935294 2025-09-06T00:01:35.601Z,1757116895.601 [Default:CheckIn:Read_Iridium] Stopped 2025-09-06T00:01:35.602Z,1757116895.602 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-06T00:01:35.602Z,1757116895.602 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-06T00:01:42.059Z,1757116902.059 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-06T00:06:36.173Z,1757117196.173 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-06T00:06:36.173Z,1757117196.173 [Default:CheckIn:C.Wait] Stopped 2025-09-06T00:06:36.174Z,1757117196.174 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-06T00:06:36.174Z,1757117196.174 [Default:CheckIn:D] Running Loop=1 2025-09-06T00:06:36.578Z,1757117196.578 [Default:CheckIn:D] Stopped 2025-09-06T00:06:36.578Z,1757117196.578 [Default:CheckIn:E] Running Loop=1 2025-09-06T00:06:36.992Z,1757117196.992 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 1097.732422 min 2025-09-06T00:06:36.992Z,1757117196.992 [Default:CheckIn:E] Stopped 2025-09-06T00:06:36.993Z,1757117196.993 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-06T00:06:36.993Z,1757117196.993 [Default:CheckIn] Stopped 2025-09-06T00:06:36.993Z,1757117196.993 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-06T00:06:36.993Z,1757117196.993 [Default:CheckIn](INFO): Running loop #17 2025-09-06T00:06:36.993Z,1757117196.993 [Default:CheckIn] Running Loop=17 2025-09-06T00:06:36.993Z,1757117196.993 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-06T00:06:36.993Z,1757117196.993 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-06T00:06:38.997Z,1757117198.997 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000638.00,A,3648.14571,N,12147.21716,W,0.058,248.96,060925,,,A*78 2025-09-06T00:06:38.999Z,1757117198.999 [NAL9602](INFO): GPS fix at 20250906T000638: (36.802428, -121.786953) 2025-09-06T00:06:39.030Z,1757117199.030 [Default:CheckIn:Read_GPS] Stopped 2025-09-06T00:06:39.030Z,1757117199.030 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-06T00:06:46.018Z,1757117206.018 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250905T233122/Courier0018.lzma 2025-09-06T00:06:54.352Z,1757117214.352 [NAL9602](INFO): SBD MO Status=0, MOMSN=10858, MT Status=0, MTMSN=0 2025-09-06T00:06:54.353Z,1757117214.353 [NAL9602](INFO): No messages in MT queue 2025-09-06T00:06:57.060Z,1757117217.060 [DataOverHttps](INFO): Moved sent file to Logs/20250905T233122/Courier0018.lzma.bak 2025-09-06T00:06:57.060Z,1757117217.060 [DataOverHttps](INFO): SBD MOMSN=25935387 2025-09-06T00:07:23.062Z,1757117243.062 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20250905T233122/Express0019.lzma 2025-09-06T00:07:25.055Z,1757117245.055 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-06T00:07:34.108Z,1757117254.108 [DataOverHttps](INFO): Moved sent file to Logs/20250905T233122/Express0019.lzma.bak 2025-09-06T00:07:34.108Z,1757117254.108 [DataOverHttps](INFO): SBD MOMSN=25935395 2025-09-06T00:07:35.192Z,1757117255.192 [Default:CheckIn:Read_Iridium] Stopped 2025-09-06T00:07:35.192Z,1757117255.192 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-06T00:07:35.192Z,1757117255.192 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-06T00:11:22.669Z,1757117482.669 [CBIT](INFO): Clearing failed state for component DropWeight 2025-09-06T00:11:22.669Z,1757117482.669 [DropWeight] No Fault, FailCount= 1 2025-09-06T00:11:24.247Z,1757117484.247 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-09-06T00:11:24.247Z,1757117484.247 [DropWeight] Hardware Fault, FailCount= 1 2025-09-06T00:11:24.247Z,1757117484.247 [DropWeight](ERROR): Hardware Fault 2025-09-06T00:11:24.289Z,1757117484.289 [CBIT](INFO): Critical error at 20250906T001124 2025-09-06T00:11:24.296Z,1757117484.296 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-09-06T00:11:24.296Z,1757117484.296 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-09-06T00:11:24.662Z,1757117484.662 [CBIT](INFO): Critical error at 20250906T001124 2025-09-06T00:12:35.761Z,1757117555.761 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-06T00:12:35.761Z,1757117555.761 [Default:CheckIn:C.Wait] Stopped 2025-09-06T00:12:35.761Z,1757117555.761 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-06T00:12:35.761Z,1757117555.761 [Default:CheckIn:D] Running Loop=1 2025-09-06T00:12:36.161Z,1757117556.161 [Default:CheckIn:D] Stopped 2025-09-06T00:12:36.161Z,1757117556.161 [Default:CheckIn:E] Running Loop=1 2025-09-06T00:12:36.561Z,1757117556.561 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 1103.725521 min 2025-09-06T00:12:36.562Z,1757117556.562 [Default:CheckIn:E] Stopped 2025-09-06T00:12:36.562Z,1757117556.562 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-06T00:12:36.562Z,1757117556.562 [Default:CheckIn] Stopped 2025-09-06T00:12:36.562Z,1757117556.562 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-06T00:12:36.562Z,1757117556.562 [Default:CheckIn](INFO): Running loop #18 2025-09-06T00:12:36.562Z,1757117556.562 [Default:CheckIn] Running Loop=18 2025-09-06T00:12:36.562Z,1757117556.562 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-06T00:12:36.563Z,1757117556.563 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-06T00:12:38.575Z,1757117558.575 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001237.00,A,3648.14718,N,12147.22092,W,0.078,171.47,060925,,,D*75 2025-09-06T00:12:38.577Z,1757117558.577 [NAL9602](INFO): GPS fix at 20250906T001237: (36.802453, -121.787015) 2025-09-06T00:12:38.587Z,1757117558.587 [Default:CheckIn:Read_GPS] Stopped 2025-09-06T00:12:38.587Z,1757117558.587 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-06T00:12:45.858Z,1757117565.858 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20250905T233122/Courier0021.lzma 2025-09-06T00:12:50.697Z,1757117570.697 [NAL9602](INFO): SBD MO Status=0, MOMSN=10859, MT Status=0, MTMSN=0 2025-09-06T00:12:50.697Z,1757117570.697 [NAL9602](INFO): No messages in MT queue 2025-09-06T00:12:56.900Z,1757117576.900 [DataOverHttps](INFO): Moved sent file to Logs/20250905T233122/Courier0021.lzma.bak 2025-09-06T00:12:56.900Z,1757117576.900 [DataOverHttps](INFO): SBD MOMSN=25935496 2025-09-06T00:13:21.395Z,1757117601.395 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-06T00:13:22.910Z,1757117602.910 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250905T233122/Express0022.lzma 2025-09-06T00:13:33.968Z,1757117613.968 [DataOverHttps](INFO): Moved sent file to Logs/20250905T233122/Express0022.lzma.bak 2025-09-06T00:13:33.968Z,1757117613.968 [DataOverHttps](INFO): SBD MOMSN=25935527 2025-09-06T00:13:35.150Z,1757117615.150 [Default:CheckIn:Read_Iridium] Stopped 2025-09-06T00:13:35.151Z,1757117615.151 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-06T00:13:35.151Z,1757117615.151 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-06T00:18:35.725Z,1757117915.725 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-06T00:18:35.725Z,1757117915.725 [Default:CheckIn:C.Wait] Stopped 2025-09-06T00:18:35.725Z,1757117915.725 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-06T00:18:35.725Z,1757117915.725 [Default:CheckIn:D] Running Loop=1 2025-09-06T00:18:36.124Z,1757117916.124 [Default:CheckIn:D] Stopped 2025-09-06T00:18:36.124Z,1757117916.124 [Default:CheckIn:E] Running Loop=1 2025-09-06T00:18:36.525Z,1757117916.525 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 1109.724870 min 2025-09-06T00:18:36.525Z,1757117916.525 [Default:CheckIn:E] Stopped 2025-09-06T00:18:36.525Z,1757117916.525 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-06T00:18:36.525Z,1757117916.525 [Default:CheckIn] Stopped 2025-09-06T00:18:36.525Z,1757117916.525 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-06T00:18:36.526Z,1757117916.526 [Default:CheckIn](INFO): Running loop #19 2025-09-06T00:18:36.526Z,1757117916.526 [Default:CheckIn] Running Loop=19 2025-09-06T00:18:36.526Z,1757117916.526 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-06T00:18:36.526Z,1757117916.526 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-06T00:18:38.539Z,1757117918.539 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001837.00,A,3648.14600,N,12147.22176,W,0.019,171.47,060925,,,D*7B 2025-09-06T00:18:38.541Z,1757117918.541 [NAL9602](INFO): GPS fix at 20250906T001837: (36.802433, -121.787029) 2025-09-06T00:18:38.551Z,1757117918.551 [Default:CheckIn:Read_GPS] Stopped 2025-09-06T00:18:38.551Z,1757117918.551 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-06T00:18:45.466Z,1757117925.466 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250905T233122/Courier0024.lzma 2025-09-06T00:18:56.508Z,1757117936.508 [DataOverHttps](INFO): Moved sent file to Logs/20250905T233122/Courier0024.lzma.bak 2025-09-06T00:18:56.508Z,1757117936.508 [DataOverHttps](INFO): SBD MOMSN=25935534 2025-09-06T00:18:58.741Z,1757117938.741 [NAL9602](INFO): SBD MO Status=0, MOMSN=10860, MT Status=0, MTMSN=0 2025-09-06T00:18:58.741Z,1757117938.741 [NAL9602](INFO): No messages in MT queue 2025-09-06T00:19:22.994Z,1757117962.994 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250905T233122/Express0025.lzma 2025-09-06T00:19:29.439Z,1757117969.439 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-06T00:19:34.036Z,1757117974.036 [DataOverHttps](INFO): Moved sent file to Logs/20250905T233122/Express0025.lzma.bak 2025-09-06T00:19:34.036Z,1757117974.036 [DataOverHttps](INFO): SBD MOMSN=25935538 2025-09-06T00:19:35.125Z,1757117975.125 [Default:CheckIn:Read_Iridium] Stopped 2025-09-06T00:19:35.125Z,1757117975.125 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-06T00:19:35.125Z,1757117975.125 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-06T00:24:35.741Z,1757118275.741 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-06T00:24:35.741Z,1757118275.741 [Default:CheckIn:C.Wait] Stopped 2025-09-06T00:24:35.741Z,1757118275.741 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-06T00:24:35.742Z,1757118275.742 [Default:CheckIn:D] Running Loop=1 2025-09-06T00:24:36.126Z,1757118276.126 [Default:CheckIn:D] Stopped 2025-09-06T00:24:36.126Z,1757118276.126 [Default:CheckIn:E] Running Loop=1 2025-09-06T00:24:36.531Z,1757118276.531 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 1115.724870 min 2025-09-06T00:24:36.531Z,1757118276.531 [Default:CheckIn:E] Stopped 2025-09-06T00:24:36.531Z,1757118276.531 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-06T00:24:36.532Z,1757118276.532 [Default:CheckIn] Stopped 2025-09-06T00:24:36.532Z,1757118276.532 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-06T00:24:36.532Z,1757118276.532 [Default:CheckIn](INFO): Running loop #20 2025-09-06T00:24:36.532Z,1757118276.532 [Default:CheckIn] Running Loop=20 2025-09-06T00:24:36.532Z,1757118276.532 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-06T00:24:36.532Z,1757118276.532 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-06T00:24:38.542Z,1757118278.542 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002437.00,A,3648.14543,N,12147.22270,W,0.039,171.47,060925,,,D*77 2025-09-06T00:24:38.544Z,1757118278.544 [NAL9602](INFO): GPS fix at 20250906T002437: (36.802424, -121.787045) 2025-09-06T00:24:38.566Z,1757118278.566 [Default:CheckIn:Read_GPS] Stopped 2025-09-06T00:24:38.566Z,1757118278.566 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-06T00:24:45.454Z,1757118285.454 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250905T233122/Courier0027.lzma 2025-09-06T00:24:56.500Z,1757118296.500 [DataOverHttps](INFO): Moved sent file to Logs/20250905T233122/Courier0027.lzma.bak 2025-09-06T00:24:56.501Z,1757118296.501 [DataOverHttps](INFO): SBD MOMSN=25935551 2025-09-06T00:25:23.014Z,1757118323.014 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250905T233122/Express0028.lzma 2025-09-06T00:25:34.066Z,1757118334.066 [DataOverHttps](INFO): Moved sent file to Logs/20250905T233122/Express0028.lzma.bak 2025-09-06T00:25:34.066Z,1757118334.066 [DataOverHttps](INFO): SBD MOMSN=25935554 2025-09-06T00:25:35.550Z,1757118335.550 [Default:CheckIn:Read_Iridium] Stopped 2025-09-06T00:25:35.550Z,1757118335.550 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-06T00:25:35.550Z,1757118335.550 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-06T00:25:56.117Z,1757118356.117 [NAL9602](INFO): SBD MO Status=2, MOMSN=10861, MT Status=2, MTMSN=0 2025-09-06T00:25:56.117Z,1757118356.117 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-06T00:26:24.425Z,1757118384.425 [CBIT](INFO): Clearing failed state for component DropWeight 2025-09-06T00:26:24.425Z,1757118384.425 [DropWeight] No Fault, FailCount= 1 2025-09-06T00:26:28.038Z,1757118388.038 [NAL9602](INFO): SBD MO Status=0, MOMSN=10861, MT Status=0, MTMSN=0 2025-09-06T00:26:28.038Z,1757118388.038 [NAL9602](INFO): No messages in MT queue 2025-09-06T00:26:58.730Z,1757118418.730 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-06T00:28:23.163Z,1757118503.163 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-06T00:28:26.798Z,1757118506.798 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-09-06T00:28:30.033Z,1757118510.033 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-06T00:28:41.747Z,1757118521.747 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-09-06T00:28:45.385Z,1757118525.385 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-06T00:28:57.100Z,1757118537.100 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-09-06T00:29:00.332Z,1757118540.332 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-06T00:29:12.450Z,1757118552.450 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-09-06T00:29:15.278Z,1757118555.278 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-06T00:29:28.215Z,1757118568.215 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-09-06T00:29:30.227Z,1757118570.227 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-06T00:29:43.154Z,1757118583.154 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-09-06T00:29:45.982Z,1757118585.982 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-06T00:29:58.509Z,1757118598.509 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-09-06T00:30:01.334Z,1757118601.334 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-06T00:30:13.858Z,1757118613.858 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-09-06T00:30:13.863Z,1757118613.863 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2025-09-06T00:30:15.878Z,1757118615.878 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-06T00:30:29.213Z,1757118629.213 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-09-06T00:30:30.828Z,1757118630.828 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-06T00:30:36.098Z,1757118636.098 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-06T00:30:36.099Z,1757118636.099 [Default:CheckIn:C.Wait] Stopped 2025-09-06T00:30:36.099Z,1757118636.099 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-06T00:30:36.099Z,1757118636.099 [Default:CheckIn:D] Running Loop=1 2025-09-06T00:30:36.493Z,1757118636.493 [Default:CheckIn:D] Stopped 2025-09-06T00:30:36.493Z,1757118636.493 [Default:CheckIn:E] Running Loop=1 2025-09-06T00:30:36.905Z,1757118636.905 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 1121.730990 min 2025-09-06T00:30:36.905Z,1757118636.905 [Default:CheckIn:E] Stopped 2025-09-06T00:30:36.905Z,1757118636.905 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-06T00:30:36.905Z,1757118636.905 [Default:CheckIn] Stopped 2025-09-06T00:30:36.905Z,1757118636.905 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-06T00:30:36.905Z,1757118636.905 [Default:CheckIn](INFO): Running loop #21 2025-09-06T00:30:36.905Z,1757118636.905 [Default:CheckIn] Running Loop=21 2025-09-06T00:30:36.905Z,1757118636.905 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-06T00:30:36.905Z,1757118636.905 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-06T00:30:38.911Z,1757118638.911 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,003038.00,A,3648.14772,N,12147.21896,W,0.039,171.47,060925,,,D*7C 2025-09-06T00:30:38.913Z,1757118638.913 [NAL9602](INFO): GPS fix at 20250906T003038: (36.802462, -121.786983) 2025-09-06T00:30:38.924Z,1757118638.924 [Default:CheckIn:Read_GPS] Stopped 2025-09-06T00:30:38.924Z,1757118638.924 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-06T00:30:43.350Z,1757118643.350 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2025-09-06T00:30:45.797Z,1757118645.797 [BPC1](INFO): Calculating totals. Valid battery stick count: 48. Valid reserve battery stick count: 5. 2025-09-06T00:30:45.799Z,1757118645.799 [BPC1](INFO): Received data from all battery sticks. 2025-09-06T00:30:46.754Z,1757118646.754 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250905T233122/Courier0030.lzma 2025-09-06T00:30:49.013Z,1757118649.013 [NAL9602](INFO): SBD MO Status=0, MOMSN=10862, MT Status=0, MTMSN=0 2025-09-06T00:30:49.013Z,1757118649.013 [NAL9602](INFO): No messages in MT queue 2025-09-06T00:30:57.796Z,1757118657.796 [DataOverHttps](INFO): Moved sent file to Logs/20250905T233122/Courier0030.lzma.bak 2025-09-06T00:30:57.796Z,1757118657.796 [DataOverHttps](INFO): SBD MOMSN=25935558 2025-09-06T00:31:00.723Z,1757118660.723 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-09-06T00:31:00.723Z,1757118660.723 [DropWeight] Hardware Fault, FailCount= 1 2025-09-06T00:31:00.723Z,1757118660.723 [DropWeight](ERROR): Hardware Fault 2025-09-06T00:31:00.773Z,1757118660.773 [CBIT](INFO): Critical error at 20250906T003100 2025-09-06T00:31:00.780Z,1757118660.780 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-09-06T00:31:00.780Z,1757118660.780 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-09-06T00:31:01.177Z,1757118661.177 [CBIT](INFO): Critical error at 20250906T003100 2025-09-06T00:31:19.711Z,1757118679.711 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-06T00:31:23.798Z,1757118683.798 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250905T233122/Express0031.lzma 2025-09-06T00:31:34.848Z,1757118694.848 [DataOverHttps](INFO): Moved sent file to Logs/20250905T233122/Express0031.lzma.bak 2025-09-06T00:31:34.848Z,1757118694.848 [DataOverHttps](INFO): SBD MOMSN=25935562 2025-09-06T00:31:36.284Z,1757118696.284 [Default:CheckIn:Read_Iridium] Stopped 2025-09-06T00:31:36.284Z,1757118696.284 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-06T00:31:36.284Z,1757118696.284 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-06T00:36:36.865Z,1757118996.865 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-06T00:36:36.865Z,1757118996.865 [Default:CheckIn:C.Wait] Stopped 2025-09-06T00:36:36.865Z,1757118996.865 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-06T00:36:36.866Z,1757118996.866 [Default:CheckIn:D] Running Loop=1 2025-09-06T00:36:37.268Z,1757118997.268 [Default:CheckIn:D] Stopped 2025-09-06T00:36:37.269Z,1757118997.269 [Default:CheckIn:E] Running Loop=1 2025-09-06T00:36:37.670Z,1757118997.670 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 1127.743880 min 2025-09-06T00:36:37.670Z,1757118997.670 [Default:CheckIn:E] Stopped 2025-09-06T00:36:37.671Z,1757118997.671 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-06T00:36:37.671Z,1757118997.671 [Default:CheckIn] Stopped 2025-09-06T00:36:37.671Z,1757118997.671 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-06T00:36:37.671Z,1757118997.671 [Default:CheckIn](INFO): Running loop #22 2025-09-06T00:36:37.671Z,1757118997.671 [Default:CheckIn] Running Loop=22 2025-09-06T00:36:37.671Z,1757118997.671 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-06T00:36:37.671Z,1757118997.671 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-06T00:36:39.681Z,1757118999.681 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,003638.00,A,3648.14937,N,12147.22091,W,0.039,171.47,060925,,,D*79 2025-09-06T00:36:39.692Z,1757118999.692 [NAL9602](INFO): GPS fix at 20250906T003638: (36.802490, -121.787015) 2025-09-06T00:36:39.702Z,1757118999.702 [Default:CheckIn:Read_GPS] Stopped 2025-09-06T00:36:39.703Z,1757118999.703 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-06T00:36:47.447Z,1757119007.447 [DataOverHttps](INFO): Sending 270 bytes from file Logs/20250905T233122/Courier0033.lzma 2025-09-06T00:36:58.488Z,1757119018.488 [DataOverHttps](INFO): Moved sent file to Logs/20250905T233122/Courier0033.lzma.bak 2025-09-06T00:36:58.488Z,1757119018.488 [DataOverHttps](INFO): SBD MOMSN=25935575 2025-09-06T00:37:03.113Z,1757119023.113 [NAL9602](INFO): SBD MO Status=2, MOMSN=10863, MT Status=2, MTMSN=0 2025-09-06T00:37:03.113Z,1757119023.113 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-06T00:37:20.489Z,1757119040.489 [NAL9602](INFO): SBD MO Status=2, MOMSN=10863, MT Status=2, MTMSN=0 2025-09-06T00:37:20.489Z,1757119040.489 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-06T00:37:24.554Z,1757119044.554 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250905T233122/Express0034.lzma 2025-09-06T00:37:32.209Z,1757119052.209 [NAL9602](INFO): SBD MO Status=0, MOMSN=10863, MT Status=0, MTMSN=0 2025-09-06T00:37:32.209Z,1757119052.209 [NAL9602](INFO): No messages in MT queue 2025-09-06T00:37:35.596Z,1757119055.596 [DataOverHttps](INFO): Moved sent file to Logs/20250905T233122/Express0034.lzma.bak 2025-09-06T00:37:35.596Z,1757119055.596 [DataOverHttps](INFO): SBD MOMSN=25935580 2025-09-06T00:37:37.057Z,1757119057.057 [Default:CheckIn:Read_Iridium] Stopped 2025-09-06T00:37:37.057Z,1757119057.057 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-06T00:37:37.058Z,1757119057.058 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-06T00:38:02.911Z,1757119082.911 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-06T00:42:37.640Z,1757119357.640 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-06T00:42:37.640Z,1757119357.640 [Default:CheckIn:C.Wait] Stopped 2025-09-06T00:42:37.640Z,1757119357.640 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-06T00:42:37.640Z,1757119357.640 [Default:CheckIn:D] Running Loop=1 2025-09-06T00:42:38.048Z,1757119358.048 [Default:CheckIn:D] Stopped 2025-09-06T00:42:38.048Z,1757119358.048 [Default:CheckIn:E] Running Loop=1 2025-09-06T00:42:38.449Z,1757119358.449 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 1133.756901 min 2025-09-06T00:42:38.449Z,1757119358.449 [Default:CheckIn:E] Stopped 2025-09-06T00:42:38.450Z,1757119358.450 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-06T00:42:38.450Z,1757119358.450 [Default:CheckIn] Stopped 2025-09-06T00:42:38.450Z,1757119358.450 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-06T00:42:38.466Z,1757119358.466 [Default:CheckIn](INFO): Running loop #23 2025-09-06T00:42:38.467Z,1757119358.467 [Default:CheckIn] Running Loop=23 2025-09-06T00:42:38.467Z,1757119358.467 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-06T00:42:38.467Z,1757119358.467 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-06T00:42:40.457Z,1757119360.457 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,004239.00,A,3648.14632,N,12147.22191,W,0.078,55.43,060925,,,D*46 2025-09-06T00:42:40.460Z,1757119360.460 [NAL9602](INFO): GPS fix at 20250906T004239: (36.802439, -121.787032) 2025-09-06T00:42:40.470Z,1757119360.470 [Default:CheckIn:Read_GPS] Stopped 2025-09-06T00:42:40.470Z,1757119360.470 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-06T00:42:48.022Z,1757119368.022 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250905T233122/Courier0036.lzma 2025-09-06T00:42:54.193Z,1757119374.193 [NAL9602](INFO): SBD MO Status=0, MOMSN=10864, MT Status=0, MTMSN=0 2025-09-06T00:42:54.193Z,1757119374.193 [NAL9602](INFO): No messages in MT queue 2025-09-06T00:42:59.064Z,1757119379.064 [DataOverHttps](INFO): Moved sent file to Logs/20250905T233122/Courier0036.lzma.bak 2025-09-06T00:42:59.064Z,1757119379.064 [DataOverHttps](INFO): SBD MOMSN=25935584 2025-09-06T00:43:24.895Z,1757119404.895 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-06T00:43:24.938Z,1757119404.938 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250905T233122/Express0037.lzma 2025-09-06T00:43:35.981Z,1757119415.981 [DataOverHttps](INFO): Moved sent file to Logs/20250905T233122/Express0037.lzma.bak 2025-09-06T00:43:35.981Z,1757119415.981 [DataOverHttps](INFO): SBD MOMSN=25935589 2025-09-06T00:43:37.433Z,1757119417.433 [Default:CheckIn:Read_Iridium] Stopped 2025-09-06T00:43:37.433Z,1757119417.433 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-06T00:43:37.433Z,1757119417.433 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-06T00:46:00.873Z,1757119560.873 [CBIT](INFO): Clearing failed state for component DropWeight 2025-09-06T00:46:00.874Z,1757119560.874 [DropWeight] No Fault, FailCount= 1 2025-09-06T00:48:03.319Z,1757119683.319 [DataOverHttps](IMPORTANT): SBD MTMSN=20250906T004802 2025-09-06T00:48:10.694Z,1757119690.694 [DataOverHttps](INFO): Received command: burn on 2025-09-06T00:48:10.771Z,1757119690.771 [CommandExec](IMPORTANT): got command burn on 2025-09-06T00:48:10.771Z,1757119690.771 [CommandExec](IMPORTANT): Activating dropweight wire 2025-09-06T00:48:38.006Z,1757119718.006 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-06T00:48:38.007Z,1757119718.007 [Default:CheckIn:C.Wait] Stopped 2025-09-06T00:48:38.007Z,1757119718.007 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-06T00:48:38.007Z,1757119718.007 [Default:CheckIn:D] Running Loop=1 2025-09-06T00:48:38.420Z,1757119718.420 [Default:CheckIn:D] Stopped 2025-09-06T00:48:38.420Z,1757119718.420 [Default:CheckIn:E] Running Loop=1 2025-09-06T00:48:38.817Z,1757119718.817 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 1139.763151 min 2025-09-06T00:48:38.817Z,1757119718.817 [Default:CheckIn:E] Stopped 2025-09-06T00:48:38.817Z,1757119718.817 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-06T00:48:38.817Z,1757119718.817 [Default:CheckIn] Stopped 2025-09-06T00:48:38.817Z,1757119718.817 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-06T00:48:38.817Z,1757119718.817 [Default:CheckIn](INFO): Running loop #24 2025-09-06T00:48:38.818Z,1757119718.818 [Default:CheckIn] Running Loop=24 2025-09-06T00:48:38.818Z,1757119718.818 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-06T00:48:38.818Z,1757119718.818 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-06T00:48:40.830Z,1757119720.830 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,004840.00,A,3648.14542,N,12147.22322,W,0.136,13.97,060925,,,D*4C 2025-09-06T00:48:40.832Z,1757119720.832 [NAL9602](INFO): GPS fix at 20250906T004840: (36.802424, -121.787054) 2025-09-06T00:48:40.842Z,1757119720.842 [Default:CheckIn:Read_GPS] Stopped 2025-09-06T00:48:40.842Z,1757119720.842 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-06T00:48:46.514Z,1757119726.514 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20250905T233122/Courier0039.lzma 2025-09-06T00:48:57.556Z,1757119737.556 [DataOverHttps](INFO): Moved sent file to Logs/20250905T233122/Courier0039.lzma.bak 2025-09-06T00:48:57.556Z,1757119737.556 [DataOverHttps](INFO): SBD MOMSN=25935599 2025-09-06T00:49:01.849Z,1757119741.849 [NAL9602](INFO): SBD MO Status=2, MOMSN=10865, MT Status=2, MTMSN=0 2025-09-06T00:49:01.849Z,1757119741.849 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-06T00:49:13.550Z,1757119753.550 [NAL9602](INFO): SBD MO Status=0, MOMSN=10865, MT Status=0, MTMSN=0 2025-09-06T00:49:13.550Z,1757119753.550 [NAL9602](INFO): No messages in MT queue 2025-09-06T00:49:16.123Z,1757119756.123 [DataOverHttps](IMPORTANT): SBD MTMSN=20250906T004915 2025-09-06T00:49:23.599Z,1757119763.599 [DataOverHttps](INFO): Sending 265 bytes from file Logs/20250905T233122/Express0040.lzma 2025-09-06T00:49:23.601Z,1757119763.601 [DataOverHttps](INFO): Received command: burn off 2025-09-06T00:49:23.683Z,1757119763.683 [CommandExec](IMPORTANT): got command burn off 2025-09-06T00:49:23.683Z,1757119763.683 [CommandExec](IMPORTANT): Deactivating dropweight wire 2025-09-06T00:49:34.640Z,1757119774.640 [DataOverHttps](INFO): Moved sent file to Logs/20250905T233122/Express0040.lzma.bak 2025-09-06T00:49:34.640Z,1757119774.640 [DataOverHttps](INFO): SBD MOMSN=25935603 2025-09-06T00:49:44.253Z,1757119784.253 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-06T00:49:57.706Z,1757119797.706 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20250905T233122/Courier0045.lzma 2025-09-06T00:50:08.748Z,1757119808.748 [DataOverHttps](INFO): Moved sent file to Logs/20250905T233122/Courier0045.lzma.bak 2025-09-06T00:50:08.749Z,1757119808.749 [DataOverHttps](INFO): SBD MOMSN=25935611 2025-09-06T00:50:34.526Z,1757119834.526 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20250905T233122/Express0043.lzma 2025-09-06T00:50:45.569Z,1757119845.569 [DataOverHttps](INFO): Moved sent file to Logs/20250905T233122/Express0043.lzma.bak 2025-09-06T00:50:45.569Z,1757119845.569 [DataOverHttps](INFO): SBD MOMSN=25935613 2025-09-06T00:51:11.430Z,1757119871.430 [DataOverHttps](INFO): Sending 161 bytes from file Logs/20250905T233122/Express0046.lzma 2025-09-06T00:51:21.468Z,1757119881.468 [DataOverHttps](INFO): Moved sent file to Logs/20250905T233122/Express0046.lzma.bak 2025-09-06T00:51:21.468Z,1757119881.468 [DataOverHttps](INFO): SBD MOMSN=25935615 2025-09-06T00:51:22.853Z,1757119882.853 [Default:CheckIn:Read_Iridium] Stopped 2025-09-06T00:51:22.854Z,1757119882.854 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-06T00:51:22.854Z,1757119882.854 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-06T00:55:13.216Z,1757120113.216 [DataOverHttps](IMPORTANT): SBD MTMSN=20250906T005512 2025-09-06T00:55:20.754Z,1757120120.754 [DataOverHttps](INFO): Received command: configSet VerticalControl.massDefault 6.594093 mm persist 2025-09-06T00:55:21.154Z,1757120121.154 [CommandExec](IMPORTANT): got command configSet VerticalControl.massDefault 6.594093 millimeter persist 2025-09-06T00:56:07.456Z,1757120167.455 [DataOverHttps](IMPORTANT): SBD MTMSN=20250906T005606 2025-09-06T00:56:14.886Z,1757120174.886 [DataOverHttps](INFO): Received command: get platform_mass_position cm 2025-09-06T00:56:16.360Z,1757120176.360 [CommandExec](IMPORTANT): got command get platform_mass_position centimeter 2025-09-06T00:56:16.361Z,1757120176.361 [CommandExec](IMPORTANT): platform_mass_position 0.657001 cm 2025-09-06T00:56:23.441Z,1757120183.441 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-06T00:56:23.441Z,1757120183.441 [Default:CheckIn:C.Wait] Stopped 2025-09-06T00:56:23.441Z,1757120183.441 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-06T00:56:23.441Z,1757120183.441 [Default:CheckIn:D] Running Loop=1 2025-09-06T00:56:23.840Z,1757120183.840 [Default:CheckIn:D] Stopped 2025-09-06T00:56:23.841Z,1757120183.841 [Default:CheckIn:E] Running Loop=1 2025-09-06T00:56:24.241Z,1757120184.241 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 1147.520182 min 2025-09-06T00:56:24.241Z,1757120184.241 [Default:CheckIn:E] Stopped 2025-09-06T00:56:24.241Z,1757120184.241 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-06T00:56:24.241Z,1757120184.241 [Default:CheckIn] Stopped 2025-09-06T00:56:24.241Z,1757120184.241 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-06T00:56:24.241Z,1757120184.241 [Default:CheckIn](INFO): Running loop #25 2025-09-06T00:56:24.241Z,1757120184.241 [Default:CheckIn] Running Loop=25 2025-09-06T00:56:24.241Z,1757120184.241 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-06T00:56:24.241Z,1757120184.241 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-06T00:56:26.255Z,1757120186.255 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,005625.00,A,3648.18306,N,12147.18327,W,4.568,45.05,060925,,,D*45 2025-09-06T00:56:26.257Z,1757120186.257 [NAL9602](INFO): GPS fix at 20250906T005625: (36.803051, -121.786388) 2025-09-06T00:56:26.268Z,1757120186.268 [Default:CheckIn:Read_GPS] Stopped 2025-09-06T00:56:26.268Z,1757120186.268 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-06T00:56:33.370Z,1757120193.370 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250905T233122/Courier0048.lzma 2025-09-06T00:56:39.587Z,1757120199.587 [NAL9602](INFO): SBD MO Status=0, MOMSN=10866, MT Status=0, MTMSN=0 2025-09-06T00:56:39.587Z,1757120199.587 [NAL9602](INFO): No messages in MT queue 2025-09-06T00:56:44.412Z,1757120204.412 [DataOverHttps](INFO): Moved sent file to Logs/20250905T233122/Courier0048.lzma.bak 2025-09-06T00:56:44.412Z,1757120204.412 [DataOverHttps](INFO): SBD MOMSN=25935637 2025-09-06T00:57:10.226Z,1757120230.226 [DataOverHttps](INFO): Sending 327 bytes from file Logs/20250905T233122/Express0049.lzma 2025-09-06T00:57:10.289Z,1757120230.289 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-06T00:57:21.268Z,1757120241.268 [DataOverHttps](INFO): Moved sent file to Logs/20250905T233122/Express0049.lzma.bak 2025-09-06T00:57:21.268Z,1757120241.268 [DataOverHttps](INFO): SBD MOMSN=25935641 2025-09-06T00:57:47.122Z,1757120267.122 [DataOverHttps](INFO): Sending 51 bytes from file Logs/20250905T233122/Express0052.lzma 2025-09-06T00:57:58.164Z,1757120278.164 [DataOverHttps](INFO): Moved sent file to Logs/20250905T233122/Express0052.lzma.bak 2025-09-06T00:57:58.164Z,1757120278.164 [DataOverHttps](INFO): SBD MOMSN=25935649 2025-09-06T00:57:59.234Z,1757120279.234 [Default:CheckIn:Read_Iridium] Stopped 2025-09-06T00:57:59.234Z,1757120279.234 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-06T00:57:59.234Z,1757120279.234 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-06T01:02:59.756Z,1757120579.756 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-06T01:02:59.756Z,1757120579.756 [Default:CheckIn:C.Wait] Stopped 2025-09-06T01:02:59.756Z,1757120579.756 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-06T01:02:59.757Z,1757120579.757 [Default:CheckIn:D] Running Loop=1 2025-09-06T01:03:00.161Z,1757120580.161 [Default:CheckIn:D] Stopped 2025-09-06T01:03:00.161Z,1757120580.161 [Default:CheckIn:E] Running Loop=1 2025-09-06T01:03:00.567Z,1757120580.567 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 1154.125521 min 2025-09-06T01:03:00.567Z,1757120580.567 [Default:CheckIn:E] Stopped 2025-09-06T01:03:00.567Z,1757120580.567 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-06T01:03:00.567Z,1757120580.567 [Default:CheckIn] Stopped 2025-09-06T01:03:00.567Z,1757120580.567 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-06T01:03:00.567Z,1757120580.567 [Default:CheckIn](INFO): Running loop #26 2025-09-06T01:03:00.567Z,1757120580.567 [Default:CheckIn] Running Loop=26 2025-09-06T01:03:00.567Z,1757120580.567 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-06T01:03:00.568Z,1757120580.568 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-06T01:03:02.577Z,1757120582.577 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,010301.00,A,3648.41402,N,12147.39509,W,4.451,239.74,060925,,,D*70 2025-09-06T01:03:02.587Z,1757120582.587 [NAL9602](INFO): GPS fix at 20250906T010301: (36.806900, -121.789918) 2025-09-06T01:03:02.597Z,1757120582.597 [Default:CheckIn:Read_GPS] Stopped 2025-09-06T01:03:02.598Z,1757120582.598 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-06T01:03:09.562Z,1757120589.562 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250905T233122/Courier0054.lzma 2025-09-06T01:03:20.604Z,1757120600.604 [DataOverHttps](INFO): Moved sent file to Logs/20250905T233122/Courier0054.lzma.bak 2025-09-06T01:03:20.604Z,1757120600.604 [DataOverHttps](INFO): SBD MOMSN=25935651 2025-09-06T01:03:29.648Z,1757120609.648 [NAL9602](INFO): SBD MO Status=2, MOMSN=10867, MT Status=2, MTMSN=0 2025-09-06T01:03:29.648Z,1757120609.648 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-06T01:03:39.754Z,1757120619.754 [NAL9602](INFO): SBD MO Status=0, MOMSN=10867, MT Status=0, MTMSN=0 2025-09-06T01:03:39.754Z,1757120619.754 [NAL9602](INFO): No messages in MT queue 2025-09-06T01:03:47.034Z,1757120627.034 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20250905T233122/Express0055.lzma 2025-09-06T01:03:58.076Z,1757120638.076 [DataOverHttps](INFO): Moved sent file to Logs/20250905T233122/Express0055.lzma.bak 2025-09-06T01:03:58.076Z,1757120638.076 [DataOverHttps](INFO): SBD MOMSN=25935657 2025-09-06T01:03:59.163Z,1757120639.163 [Default:CheckIn:Read_Iridium] Stopped 2025-09-06T01:03:59.164Z,1757120639.164 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-06T01:03:59.164Z,1757120639.164 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-06T01:04:10.447Z,1757120650.447 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-06T01:07:14.320Z,1757120834.320 [DataOverHttps](IMPORTANT): SBD MTMSN=20250906T010713 2025-09-06T01:07:21.742Z,1757120841.742 [DataOverHttps](INFO): Received command: ibit 2025-09-06T01:07:21.747Z,1757120841.747 [CommandExec](IMPORTANT): got command ibit 2025-09-06T01:07:21.967Z,1757120841.967 [IBIT](IMPORTANT): Beginning Initiated BIT 2025-09-06T01:07:21.967Z,1757120841.967 [IBIT](IMPORTANT): Beginning control surface checks. 2025-09-06T01:07:21.971Z,1757120841.971 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-09-06T01:07:23.561Z,1757120843.561 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,010722.00,A,3648.27400,N,12148.05854,W,5.948,253.55,060925,,,D*79 2025-09-06T01:07:23.573Z,1757120843.573 [NAL9602](INFO): GPS fix at 20250906T010722: (36.804567, -121.800976) 2025-09-06T01:07:26.478Z,1757120846.478 [GFScanner](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.033885 CHAN A1 (24V): 0.052629 CHAN A2 (12V): 0.024169 CHAN A3 (5V): 0.007437 CHAN B0 (3.3V): 0.005423 CHAN B1 (3.15aV): 0.004874 CHAN B2 (3.15bV): 0.005730 CHAN B3 (GND): -0.002117 OPEN: 0.006778 Full Scale: +/- 1 mA 2025-09-06T01:07:45.783Z,1757120865.783 [NAL9602](INFO): SBD MO Status=2, MOMSN=10868, MT Status=2, MTMSN=0 2025-09-06T01:07:45.783Z,1757120865.783 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-06T01:07:59.923Z,1757120879.923 [NAL9602](INFO): SBD MO Status=0, MOMSN=10868, MT Status=0, MTMSN=0 2025-09-06T01:07:59.923Z,1757120879.923 [NAL9602](INFO): No messages in MT queue 2025-09-06T01:08:01.137Z,1757120881.137 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,010800.00,A,3648.24479,N,12148.17087,W,8.670,257.62,060925,,,D*77 2025-09-06T01:08:01.139Z,1757120881.139 [NAL9602](INFO): GPS fix at 20250906T010800: (36.804080, -121.802848) 2025-09-06T01:08:03.971Z,1757120883.971 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,010803.00,A,3648.24286,N,12148.17926,W,8.184,253.36,060925,,,D*79 2025-09-06T01:08:03.973Z,1757120883.973 [NAL9602](INFO): GPS fix at 20250906T010803: (36.804048, -121.802988) 2025-09-06T01:08:07.193Z,1757120887.193 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,010806.00,A,3648.24081,N,12148.18765,W,8.825,253.32,060925,,,D*79 2025-09-06T01:08:07.196Z,1757120887.196 [NAL9602](INFO): GPS fix at 20250906T010806: (36.804014, -121.803128) 2025-09-06T01:08:08.422Z,1757120888.422 [IBIT](IMPORTANT): Communications Status: Fix Status: 1 Iridium Signal Strength: 5 Latitude: 36.804012 Longitude: -121.803131 2025-09-06T01:08:08.840Z,1757120888.840 [IBIT](IMPORTANT): Battery Status: Battery Charge (AH): 0.000000 Voltage: 16.372999 2025-09-06T01:08:08.841Z,1757120888.841 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah 2025-09-06T01:08:08.841Z,1757120888.841 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V 2025-09-06T01:08:09.247Z,1757120889.247 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 % 2025-09-06T01:08:09.248Z,1757120889.248 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi 2025-09-06T01:08:09.248Z,1757120889.248 [IBIT](IMPORTANT): Pressure:9.597905 PSI 2025-09-06T01:08:09.248Z,1757120889.248 [IBIT](IMPORTANT): Humidity:16.963741 % 2025-09-06T01:08:09.639Z,1757120889.639 [IBIT](IMPORTANT): Vehicle Pitch:5.367312 degrees 2025-09-06T01:08:09.639Z,1757120889.639 [IBIT](IMPORTANT): Vehicle Roll:-3.674272 degrees 2025-09-06T01:08:09.640Z,1757120889.640 [IBIT](IMPORTANT): Vehicle Heading:252.737030 degrees 2025-09-06T01:08:10.036Z,1757120890.036 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m 2025-09-06T01:08:10.036Z,1757120890.036 [IBIT](IMPORTANT): buoyancyNeutral: 205.507782 cc 2025-09-06T01:08:10.037Z,1757120890.037 [IBIT](IMPORTANT): massDefault: 0.659409 cm 2025-09-06T01:08:10.037Z,1757120890.037 [IBIT](IMPORTANT): stopDepth: 275.000000 m 2025-09-06T01:08:10.037Z,1757120890.037 [IBIT](IMPORTANT): abortDepth: 300.000000 m 2025-09-06T01:08:10.037Z,1757120890.037 [IBIT](IMPORTANT): IBIT PASSED 2025-09-06T01:08:39.513Z,1757120919.513 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-06T01:08:59.747Z,1757120939.747 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-06T01:08:59.748Z,1757120939.748 [Default:CheckIn:C.Wait] Stopped 2025-09-06T01:08:59.748Z,1757120939.748 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-06T01:08:59.748Z,1757120939.748 [Default:CheckIn:D] Running Loop=1 2025-09-06T01:09:00.129Z,1757120940.129 [Default:CheckIn:D] Stopped 2025-09-06T01:09:00.129Z,1757120940.129 [Default:CheckIn:E] Running Loop=1 2025-09-06T01:09:00.545Z,1757120940.545 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 1160.125000 min 2025-09-06T01:09:00.545Z,1757120940.545 [Default:CheckIn:E] Stopped 2025-09-06T01:09:00.545Z,1757120940.545 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-06T01:09:00.545Z,1757120940.545 [Default:CheckIn] Stopped 2025-09-06T01:09:00.545Z,1757120940.545 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-06T01:09:00.545Z,1757120940.545 [Default:CheckIn](INFO): Running loop #27 2025-09-06T01:09:00.545Z,1757120940.545 [Default:CheckIn] Running Loop=27 2025-09-06T01:09:00.545Z,1757120940.545 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-06T01:09:00.545Z,1757120940.545 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-06T01:09:02.555Z,1757120942.555 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,010901.00,A,3648.21310,N,12148.34461,W,6.181,255.69,060925,,,D*79 2025-09-06T01:09:02.557Z,1757120942.557 [NAL9602](INFO): GPS fix at 20250906T010901: (36.803552, -121.805744) 2025-09-06T01:09:02.567Z,1757120942.567 [Default:CheckIn:Read_GPS] Stopped 2025-09-06T01:09:02.567Z,1757120942.567 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-06T01:09:08.526Z,1757120948.526 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250905T233122/Courier0057.lzma 2025-09-06T01:09:20.584Z,1757120960.584 [DataOverHttps](INFO): Moved sent file to Logs/20250905T233122/Courier0057.lzma.bak 2025-09-06T01:09:20.585Z,1757120960.585 [DataOverHttps](INFO): SBD MOMSN=25935688 2025-09-06T01:09:28.477Z,1757120968.477 [NAL9602](INFO): SBD MO Status=0, MOMSN=10869, MT Status=0, MTMSN=0 2025-09-06T01:09:28.477Z,1757120968.477 [NAL9602](INFO): No messages in MT queue 2025-09-06T01:09:47.480Z,1757120987.480 [DataOverHttps](INFO): Sending 968 bytes from file Logs/20250905T233122/Express0058.lzma 2025-09-06T01:09:59.109Z,1757120999.109 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-06T01:09:59.524Z,1757120999.524 [DataOverHttps](INFO): Moved sent file to Logs/20250905T233122/Express0058.lzma.bak 2025-09-06T01:09:59.524Z,1757120999.524 [DataOverHttps](INFO): SBD MOMSN=25935691 2025-09-06T01:10:00.734Z,1757121000.734 [Default:CheckIn:Read_Iridium] Stopped 2025-09-06T01:10:00.735Z,1757121000.735 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-06T01:10:00.735Z,1757121000.735 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-06T01:14:03.831Z,1757121243.831 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2025-09-06T01:14:20.226Z,1757121260.226 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.886505 2025-09-06T01:15:01.320Z,1757121301.320 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-06T01:15:01.320Z,1757121301.320 [Default:CheckIn:C.Wait] Stopped 2025-09-06T01:15:01.320Z,1757121301.320 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-06T01:15:01.320Z,1757121301.320 [Default:CheckIn:D] Running Loop=1 2025-09-06T01:15:01.720Z,1757121301.720 [Default:CheckIn:D] Stopped 2025-09-06T01:15:01.720Z,1757121301.720 [Default:CheckIn:E] Running Loop=1 2025-09-06T01:15:02.121Z,1757121302.121 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 1166.151432 min 2025-09-06T01:15:02.121Z,1757121302.121 [Default:CheckIn:E] Stopped 2025-09-06T01:15:02.121Z,1757121302.121 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-06T01:15:02.121Z,1757121302.121 [Default:CheckIn] Stopped 2025-09-06T01:15:02.122Z,1757121302.122 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-06T01:15:02.122Z,1757121302.122 [Default:CheckIn](INFO): Running loop #28 2025-09-06T01:15:02.122Z,1757121302.122 [Default:CheckIn] Running Loop=28 2025-09-06T01:15:02.122Z,1757121302.122 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-06T01:15:02.122Z,1757121302.122 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-06T01:15:04.131Z,1757121304.131 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,011503.00,A,3648.14224,N,12148.83730,W,1.924,291.20,060925,,,D*78 2025-09-06T01:15:04.133Z,1757121304.133 [NAL9602](INFO): GPS fix at 20250906T011503: (36.802371, -121.813955) 2025-09-06T01:15:04.143Z,1757121304.143 [Default:CheckIn:Read_GPS] Stopped 2025-09-06T01:15:04.143Z,1757121304.143 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-06T01:15:14.826Z,1757121314.826 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2025-09-06T01:15:27.202Z,1757121327.202 [NAL9602](INFO): SBD MO Status=1, MOMSN=10870, MT Status=0, MTMSN=0 2025-09-06T01:15:27.262Z,1757121327.262 [NAL9602](INFO): Sent 72 bytes from file Logs/20250905T233122/Courier0060.lzma 2025-09-06T01:15:27.262Z,1757121327.262 [NAL9602](INFO): Packets left to send: 0 2025-09-06T01:15:37.454Z,1757121337.454 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003159 2025-09-06T01:15:45.833Z,1757121345.833 [NAL9602](INFO): SBD MO Status=2, MOMSN=10871, MT Status=2, MTMSN=0 2025-09-06T01:15:45.833Z,1757121345.833 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-06T01:15:50.834Z,1757121350.834 [DataOverHttps](INFO): Sending 170 bytes from file Logs/20250905T233122/Express0062.lzma 2025-09-06T01:16:01.876Z,1757121361.876 [DataOverHttps](INFO): Moved sent file to Logs/20250905T233122/Express0062.lzma.bak 2025-09-06T01:16:01.876Z,1757121361.876 [DataOverHttps](INFO): SBD MOMSN=25935735 2025-09-06T01:16:03.212Z,1757121363.212 [Default:CheckIn:Read_Iridium] Stopped 2025-09-06T01:16:03.212Z,1757121363.212 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-06T01:16:03.212Z,1757121363.212 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-06T01:16:16.536Z,1757121376.536 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-06T01:19:22.028Z,1757121562.028 [DataOverHttps](IMPORTANT): SBD MTMSN=20250906T011917 2025-09-06T01:19:33.414Z,1757121573.414 [DataOverHttps](INFO): Received command: restart logs