2025-03-14T19:29:08.622Z,1741980548.622 [CommandExec](IMPORTANT): got command restart logs 2025-03-14T19:31:32.936Z,1741980692.936 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-03-14T19:31:32.936Z,1741980692.936 [Default:CheckIn:C.Wait] Stopped 2025-03-14T19:31:32.936Z,1741980692.936 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-03-14T19:31:32.936Z,1741980692.936 [Default:CheckIn:D] Running Loop=1 2025-03-14T19:31:33.328Z,1741980693.328 [Default:CheckIn:D] Stopped 2025-03-14T19:31:33.328Z,1741980693.328 [Default:CheckIn:E] Running Loop=1 2025-03-14T19:31:33.731Z,1741980693.731 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.513554 min 2025-03-14T19:31:33.732Z,1741980693.732 [Default:CheckIn:E] Stopped 2025-03-14T19:31:33.732Z,1741980693.732 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-03-14T19:31:33.732Z,1741980693.732 [Default:CheckIn] Stopped 2025-03-14T19:31:33.732Z,1741980693.732 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-14T19:31:33.732Z,1741980693.732 [Default:CheckIn](INFO): Running loop #6 2025-03-14T19:31:33.732Z,1741980693.732 [Default:CheckIn] Running Loop=6 2025-03-14T19:31:33.732Z,1741980693.732 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-14T19:31:33.732Z,1741980693.732 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-14T19:31:35.743Z,1741980695.743 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193135.00,A,3648.15008,N,12147.22683,W,0.019,352.61,140325,,,D*76 2025-03-14T19:31:35.745Z,1741980695.745 [NAL9602](INFO): GPS fix at 20250314T193135: (36.802501, -121.787114) 2025-03-14T19:31:35.755Z,1741980695.755 [Default:CheckIn:Read_GPS] Stopped 2025-03-14T19:31:35.755Z,1741980695.755 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-14T19:31:43.401Z,1741980703.401 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250314T132711/Courier0075.lzma 2025-03-14T19:31:44.403Z,1741980704.403 [DataOverHttps](INFO): Moved sent file to Logs/20250314T132711/Courier0075.lzma.bak 2025-03-14T19:31:44.403Z,1741980704.403 [DataOverHttps](INFO): SBD MOMSN=24229815 2025-03-14T19:32:03.197Z,1741980723.197 [DataOverHttps](INFO): Sending 268 bytes from file Logs/20250314T192908/Courier0000.lzma 2025-03-14T19:32:04.199Z,1741980724.199 [DataOverHttps](INFO): Moved sent file to Logs/20250314T192908/Courier0000.lzma.bak 2025-03-14T19:32:04.199Z,1741980724.199 [DataOverHttps](INFO): SBD MOMSN=24229817 2025-03-14T19:32:20.556Z,1741980740.556 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250314T192908/Courier0003.lzma 2025-03-14T19:32:21.559Z,1741980741.559 [DataOverHttps](INFO): Moved sent file to Logs/20250314T192908/Courier0003.lzma.bak 2025-03-14T19:32:21.559Z,1741980741.559 [DataOverHttps](INFO): SBD MOMSN=24229821 2025-03-14T19:32:38.681Z,1741980758.681 [DataOverHttps](INFO): Sending 106 bytes from file Logs/20250314T132711/Express0076.lzma 2025-03-14T19:32:39.683Z,1741980759.683 [DataOverHttps](INFO): Moved sent file to Logs/20250314T132711/Express0076.lzma.bak 2025-03-14T19:32:39.683Z,1741980759.683 [DataOverHttps](INFO): SBD MOMSN=24229823 2025-03-14T19:32:56.098Z,1741980776.098 [DataOverHttps](INFO): Sending 475 bytes from file Logs/20250314T192908/Express0001.lzma 2025-03-14T19:32:57.099Z,1741980777.099 [DataOverHttps](INFO): Moved sent file to Logs/20250314T192908/Express0001.lzma.bak 2025-03-14T19:32:57.099Z,1741980777.099 [DataOverHttps](INFO): SBD MOMSN=24229826 2025-03-14T19:33:06.304Z,1741980786.304 [NAL9602](INFO): SBD MO Status=2, MOMSN=18740, MT Status=2, MTMSN=0 2025-03-14T19:33:06.305Z,1741980786.305 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-03-14T19:33:13.189Z,1741980793.189 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20250314T192908/Express0004.lzma 2025-03-14T19:33:14.191Z,1741980794.191 [DataOverHttps](INFO): Moved sent file to Logs/20250314T192908/Express0004.lzma.bak 2025-03-14T19:33:14.191Z,1741980794.191 [DataOverHttps](INFO): SBD MOMSN=24229833 2025-03-14T19:33:15.676Z,1741980795.676 [Default:CheckIn:Read_Iridium] Stopped 2025-03-14T19:33:15.676Z,1741980795.676 [Default:CheckIn:C.Wait] Running Loop=1 2025-03-14T19:33:15.676Z,1741980795.676 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-03-14T19:33:56.412Z,1741980836.412 [NAL9602](INFO): SBD MO Status=2, MOMSN=18740, MT Status=2, MTMSN=0 2025-03-14T19:33:56.412Z,1741980836.412 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-03-14T19:34:55.385Z,1741980895.385 [NAL9602](INFO): SBD MO Status=2, MOMSN=18740, MT Status=2, MTMSN=0 2025-03-14T19:34:55.386Z,1741980895.386 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-03-14T19:35:26.904Z,1741980926.904 [NAL9602](INFO): SBD MO Status=0, MOMSN=18740, MT Status=0, MTMSN=0 2025-03-14T19:35:26.904Z,1741980926.904 [NAL9602](INFO): No messages in MT queue 2025-03-14T19:35:57.619Z,1741980957.619 [NAL9602](INFO): Not Powering down - fast GPS 2025-03-14T19:38:16.191Z,1741981096.191 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-03-14T19:38:16.191Z,1741981096.191 [Default:CheckIn:C.Wait] Stopped 2025-03-14T19:38:16.191Z,1741981096.191 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-03-14T19:38:16.192Z,1741981096.192 [Default:CheckIn:D] Running Loop=1 2025-03-14T19:38:16.604Z,1741981096.604 [Default:CheckIn:D] Stopped 2025-03-14T19:38:16.604Z,1741981096.604 [Default:CheckIn:E] Running Loop=1 2025-03-14T19:38:17.000Z,1741981097.000 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 38.234819 min 2025-03-14T19:38:17.001Z,1741981097.001 [Default:CheckIn:E] Stopped 2025-03-14T19:38:17.001Z,1741981097.001 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-03-14T19:38:17.001Z,1741981097.001 [Default:CheckIn] Stopped 2025-03-14T19:38:17.001Z,1741981097.001 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-14T19:38:17.001Z,1741981097.001 [Default:CheckIn](INFO): Running loop #7 2025-03-14T19:38:17.001Z,1741981097.001 [Default:CheckIn] Running Loop=7 2025-03-14T19:38:17.002Z,1741981097.002 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-14T19:38:17.002Z,1741981097.002 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-14T19:38:19.014Z,1741981099.014 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193818.00,A,3648.17447,N,12147.28239,W,0.019,198.48,140325,,,D*7D 2025-03-14T19:38:19.016Z,1741981099.016 [NAL9602](INFO): GPS fix at 20250314T193818: (36.802908, -121.788040) 2025-03-14T19:38:19.027Z,1741981099.027 [Default:CheckIn:Read_GPS] Stopped 2025-03-14T19:38:19.027Z,1741981099.027 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-14T19:38:26.774Z,1741981106.774 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250314T192908/Courier0006.lzma 2025-03-14T19:38:27.777Z,1741981107.777 [DataOverHttps](INFO): Moved sent file to Logs/20250314T192908/Courier0006.lzma.bak 2025-03-14T19:38:27.777Z,1741981107.777 [DataOverHttps](INFO): SBD MOMSN=24229842 2025-03-14T19:38:43.730Z,1741981123.730 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250314T192908/Express0007.lzma 2025-03-14T19:38:44.731Z,1741981124.731 [DataOverHttps](INFO): Moved sent file to Logs/20250314T192908/Express0007.lzma.bak 2025-03-14T19:38:44.731Z,1741981124.731 [DataOverHttps](INFO): SBD MOMSN=24229848 2025-03-14T19:38:46.092Z,1741981126.092 [Default:CheckIn:Read_Iridium] Stopped 2025-03-14T19:38:46.092Z,1741981126.092 [Default:CheckIn:C.Wait] Running Loop=1 2025-03-14T19:38:46.092Z,1741981126.092 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-03-14T19:41:06.280Z,1741981266.280 [NAL9602](INFO): SBD MO Status=0, MOMSN=18741, MT Status=0, MTMSN=0 2025-03-14T19:41:06.280Z,1741981266.280 [NAL9602](INFO): No messages in MT queue 2025-03-14T19:41:36.962Z,1741981296.962 [NAL9602](INFO): Not Powering down - fast GPS 2025-03-14T19:43:46.732Z,1741981426.732 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-03-14T19:43:46.732Z,1741981426.732 [Default:CheckIn:C.Wait] Stopped 2025-03-14T19:43:46.732Z,1741981426.732 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-03-14T19:43:46.732Z,1741981426.732 [Default:CheckIn:D] Running Loop=1 2025-03-14T19:43:47.125Z,1741981427.125 [Default:CheckIn:D] Stopped 2025-03-14T19:43:47.125Z,1741981427.125 [Default:CheckIn:E] Running Loop=1 2025-03-14T19:43:47.536Z,1741981427.536 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 43.743506 min 2025-03-14T19:43:47.536Z,1741981427.536 [Default:CheckIn:E] Stopped 2025-03-14T19:43:47.536Z,1741981427.536 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-03-14T19:43:47.537Z,1741981427.537 [Default:CheckIn] Stopped 2025-03-14T19:43:47.545Z,1741981427.545 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-14T19:43:47.545Z,1741981427.545 [Default:CheckIn](INFO): Running loop #8 2025-03-14T19:43:47.545Z,1741981427.545 [Default:CheckIn] Running Loop=8 2025-03-14T19:43:47.545Z,1741981427.545 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-14T19:43:47.545Z,1741981427.545 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-14T19:43:49.531Z,1741981429.531 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194348.00,A,3648.17315,N,12147.28272,W,0.136,198.48,140325,,,A*72 2025-03-14T19:43:49.541Z,1741981429.541 [NAL9602](INFO): GPS fix at 20250314T194348: (36.802886, -121.788045) 2025-03-14T19:43:49.551Z,1741981429.551 [Default:CheckIn:Read_GPS] Stopped 2025-03-14T19:43:49.551Z,1741981429.551 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-14T19:43:57.361Z,1741981437.361 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250314T192908/Courier0009.lzma 2025-03-14T19:43:58.364Z,1741981438.364 [DataOverHttps](INFO): Moved sent file to Logs/20250314T192908/Courier0009.lzma.bak 2025-03-14T19:43:58.364Z,1741981438.364 [DataOverHttps](INFO): SBD MOMSN=24229858 2025-03-14T19:44:14.809Z,1741981454.809 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250314T192908/Express0010.lzma 2025-03-14T19:44:15.811Z,1741981455.811 [DataOverHttps](INFO): Moved sent file to Logs/20250314T192908/Express0010.lzma.bak 2025-03-14T19:44:15.811Z,1741981455.811 [DataOverHttps](INFO): SBD MOMSN=24229861 2025-03-14T19:44:17.025Z,1741981457.025 [Default:CheckIn:Read_Iridium] Stopped 2025-03-14T19:44:17.025Z,1741981457.025 [Default:CheckIn:C.Wait] Running Loop=1 2025-03-14T19:44:17.025Z,1741981457.025 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-03-14T19:47:57.996Z,1741981677.996 [NAL9602](INFO): SBD MO Status=2, MOMSN=18742, MT Status=2, MTMSN=0 2025-03-14T19:47:57.996Z,1741981677.996 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-03-14T19:49:03.856Z,1741981743.856 [NAL9602](INFO): SBD MO Status=2, MOMSN=18742, MT Status=2, MTMSN=0 2025-03-14T19:49:03.856Z,1741981743.856 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-03-14T19:49:04.248Z,1741981744.248 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-03-14T19:49:17.604Z,1741981757.604 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-03-14T19:49:17.604Z,1741981757.604 [Default:CheckIn:C.Wait] Stopped 2025-03-14T19:49:17.604Z,1741981757.604 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-03-14T19:49:17.604Z,1741981757.604 [Default:CheckIn:D] Running Loop=1 2025-03-14T19:49:18.009Z,1741981758.009 [Default:CheckIn:D] Stopped 2025-03-14T19:49:18.009Z,1741981758.009 [Default:CheckIn:E] Running Loop=1 2025-03-14T19:49:18.424Z,1741981758.424 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 49.258240 min 2025-03-14T19:49:18.424Z,1741981758.424 [Default:CheckIn:E] Stopped 2025-03-14T19:49:18.424Z,1741981758.424 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-03-14T19:49:18.424Z,1741981758.424 [Default:CheckIn] Stopped 2025-03-14T19:49:18.424Z,1741981758.424 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-14T19:49:18.424Z,1741981758.424 [Default:CheckIn](INFO): Running loop #9 2025-03-14T19:49:18.424Z,1741981758.424 [Default:CheckIn] Running Loop=9 2025-03-14T19:49:18.424Z,1741981758.424 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-14T19:49:18.424Z,1741981758.424 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-14T19:49:20.416Z,1741981760.416 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194919.00,A,3648.17548,N,12147.28041,W,0.641,198.48,140325,,,A*77 2025-03-14T19:49:20.418Z,1741981760.418 [NAL9602](INFO): GPS fix at 20250314T194919: (36.802925, -121.788007) 2025-03-14T19:49:20.428Z,1741981760.428 [Default:CheckIn:Read_GPS] Stopped 2025-03-14T19:49:20.428Z,1741981760.428 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-14T19:49:27.701Z,1741981767.701 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250314T192908/Courier0012.lzma 2025-03-14T19:49:28.703Z,1741981768.703 [DataOverHttps](INFO): Moved sent file to Logs/20250314T192908/Courier0012.lzma.bak 2025-03-14T19:49:28.703Z,1741981768.703 [DataOverHttps](INFO): SBD MOMSN=24229870 2025-03-14T19:49:45.497Z,1741981785.497 [DataOverHttps](INFO): Sending 251 bytes from file Logs/20250314T192908/Express0013.lzma 2025-03-14T19:49:46.499Z,1741981786.499 [DataOverHttps](INFO): Moved sent file to Logs/20250314T192908/Express0013.lzma.bak 2025-03-14T19:49:46.499Z,1741981786.499 [DataOverHttps](INFO): SBD MOMSN=24229873 2025-03-14T19:49:47.918Z,1741981787.918 [Default:CheckIn:Read_Iridium] Stopped 2025-03-14T19:49:47.918Z,1741981787.918 [Default:CheckIn:C.Wait] Running Loop=1 2025-03-14T19:49:47.918Z,1741981787.918 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-03-14T19:49:52.367Z,1741981792.367 [NAL9602](INFO): Not Powering down - fast GPS 2025-03-14T19:54:48.492Z,1741982088.492 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-03-14T19:54:48.492Z,1741982088.492 [Default:CheckIn:C.Wait] Stopped 2025-03-14T19:54:48.492Z,1741982088.492 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-03-14T19:54:48.492Z,1741982088.492 [Default:CheckIn:D] Running Loop=1 2025-03-14T19:54:48.904Z,1741982088.904 [Default:CheckIn:D] Stopped 2025-03-14T19:54:48.904Z,1741982088.904 [Default:CheckIn:E] Running Loop=1 2025-03-14T19:54:49.308Z,1741982089.308 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 54.773157 min 2025-03-14T19:54:49.308Z,1741982089.308 [Default:CheckIn:E] Stopped 2025-03-14T19:54:49.308Z,1741982089.308 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-03-14T19:54:49.309Z,1741982089.309 [Default:CheckIn] Stopped 2025-03-14T19:54:49.309Z,1741982089.309 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-14T19:54:49.309Z,1741982089.309 [Default:CheckIn](INFO): Running loop #10 2025-03-14T19:54:49.309Z,1741982089.309 [Default:CheckIn] Running Loop=10 2025-03-14T19:54:49.309Z,1741982089.309 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-14T19:54:49.309Z,1741982089.309 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-14T19:54:51.332Z,1741982091.332 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195450.00,A,3648.17911,N,12147.28251,W,0.039,0.00,140325,,,A*70 2025-03-14T19:54:51.335Z,1741982091.335 [NAL9602](INFO): GPS fix at 20250314T195450: (36.802985, -121.788042) 2025-03-14T19:54:51.345Z,1741982091.345 [Default:CheckIn:Read_GPS] Stopped 2025-03-14T19:54:51.345Z,1741982091.345 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-14T19:54:59.057Z,1741982099.057 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250314T192908/Courier0015.lzma 2025-03-14T19:55:00.059Z,1741982100.059 [DataOverHttps](INFO): Moved sent file to Logs/20250314T192908/Courier0015.lzma.bak 2025-03-14T19:55:00.059Z,1741982100.059 [DataOverHttps](INFO): SBD MOMSN=24229883 2025-03-14T19:55:16.129Z,1741982116.129 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250314T192908/Express0016.lzma 2025-03-14T19:55:17.131Z,1741982117.131 [DataOverHttps](INFO): Moved sent file to Logs/20250314T192908/Express0016.lzma.bak 2025-03-14T19:55:17.131Z,1741982117.131 [DataOverHttps](INFO): SBD MOMSN=24229886 2025-03-14T19:55:18.409Z,1741982118.409 [Default:CheckIn:Read_Iridium] Stopped 2025-03-14T19:55:18.409Z,1741982118.409 [Default:CheckIn:C.Wait] Running Loop=1 2025-03-14T19:55:18.409Z,1741982118.409 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-03-14T19:55:43.931Z,1741982143.931 [NAL9602](INFO): SBD MO Status=0, MOMSN=18742, MT Status=0, MTMSN=0 2025-03-14T19:55:43.931Z,1741982143.931 [NAL9602](INFO): No messages in MT queue 2025-03-14T19:56:14.538Z,1741982174.538 [NAL9602](INFO): Not Powering down - fast GPS 2025-03-14T20:00:18.968Z,1741982418.968 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-03-14T20:00:18.968Z,1741982418.968 [Default:CheckIn:C.Wait] Stopped 2025-03-14T20:00:18.968Z,1741982418.968 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-03-14T20:00:18.968Z,1741982418.968 [Default:CheckIn:D] Running Loop=1 2025-03-14T20:00:19.376Z,1741982419.376 [Default:CheckIn:D] Stopped 2025-03-14T20:00:19.376Z,1741982419.376 [Default:CheckIn:E] Running Loop=1 2025-03-14T20:00:19.785Z,1741982419.785 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 60.281022 min 2025-03-14T20:00:19.785Z,1741982419.785 [Default:CheckIn:E] Stopped 2025-03-14T20:00:19.785Z,1741982419.785 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-03-14T20:00:19.785Z,1741982419.785 [Default:CheckIn] Stopped 2025-03-14T20:00:19.785Z,1741982419.785 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-14T20:00:19.786Z,1741982419.786 [Default:CheckIn](INFO): Running loop #11 2025-03-14T20:00:19.786Z,1741982419.786 [Default:CheckIn] Running Loop=11 2025-03-14T20:00:19.786Z,1741982419.786 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-14T20:00:19.786Z,1741982419.786 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-14T20:00:21.791Z,1741982421.791 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200021.00,A,3648.17582,N,12147.27818,W,0.097,0.00,140325,,,A*77 2025-03-14T20:00:21.794Z,1741982421.794 [NAL9602](INFO): GPS fix at 20250314T200021: (36.802930, -121.787970) 2025-03-14T20:00:21.804Z,1741982421.804 [Default:CheckIn:Read_GPS] Stopped 2025-03-14T20:00:21.804Z,1741982421.804 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-14T20:00:29.971Z,1741982429.971 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250314T192908/Courier0018.lzma 2025-03-14T20:00:30.971Z,1741982430.971 [DataOverHttps](INFO): Moved sent file to Logs/20250314T192908/Courier0018.lzma.bak 2025-03-14T20:00:30.971Z,1741982430.971 [DataOverHttps](INFO): SBD MOMSN=24229896 2025-03-14T20:00:46.829Z,1741982446.829 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250314T192908/Express0019.lzma 2025-03-14T20:00:47.831Z,1741982447.831 [DataOverHttps](INFO): Moved sent file to Logs/20250314T192908/Express0019.lzma.bak 2025-03-14T20:00:47.831Z,1741982447.831 [DataOverHttps](INFO): SBD MOMSN=24229899 2025-03-14T20:00:49.286Z,1741982449.286 [Default:CheckIn:Read_Iridium] Stopped 2025-03-14T20:00:49.286Z,1741982449.286 [Default:CheckIn:C.Wait] Running Loop=1 2025-03-14T20:00:49.286Z,1741982449.286 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-03-14T20:01:37.747Z,1741982497.747 [NAL9602](INFO): SBD MO Status=2, MOMSN=18743, MT Status=2, MTMSN=0 2025-03-14T20:01:37.747Z,1741982497.747 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-03-14T20:02:03.594Z,1741982523.594 [BPC1](INFO): Calculating totals. Valid battery stick count: 50. Valid reserve battery stick count: 5. 2025-03-14T20:02:03.597Z,1741982523.597 [BPC1](FAULT): Failed to receive data from 4 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 17, 20, 21, 24. 2025-03-14T20:04:48.024Z,1741982688.024 [NAL9602](INFO): SBD MO Status=0, MOMSN=18743, MT Status=0, MTMSN=0 2025-03-14T20:04:48.024Z,1741982688.024 [NAL9602](INFO): No messages in MT queue 2025-03-14T20:05:18.734Z,1741982718.734 [NAL9602](INFO): Not Powering down - fast GPS 2025-03-14T20:05:49.854Z,1741982749.854 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-03-14T20:05:49.854Z,1741982749.854 [Default:CheckIn:C.Wait] Stopped 2025-03-14T20:05:49.854Z,1741982749.854 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-03-14T20:05:49.855Z,1741982749.855 [Default:CheckIn:D] Running Loop=1 2025-03-14T20:05:50.266Z,1741982750.266 [Default:CheckIn:D] Stopped 2025-03-14T20:05:50.266Z,1741982750.266 [Default:CheckIn:E] Running Loop=1 2025-03-14T20:05:50.656Z,1741982750.656 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 65.795862 min 2025-03-14T20:05:50.656Z,1741982750.656 [Default:CheckIn:E] Stopped 2025-03-14T20:05:50.656Z,1741982750.656 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-03-14T20:05:50.656Z,1741982750.656 [Default:CheckIn] Stopped 2025-03-14T20:05:50.656Z,1741982750.656 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-14T20:05:50.681Z,1741982750.681 [Default:CheckIn](INFO): Running loop #12 2025-03-14T20:05:50.681Z,1741982750.681 [Default:CheckIn] Running Loop=12 2025-03-14T20:05:50.682Z,1741982750.682 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-14T20:05:50.682Z,1741982750.682 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-14T20:05:52.664Z,1741982752.664 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200552.00,A,3648.17636,N,12147.27938,W,0.097,0.00,140325,,,A*79 2025-03-14T20:05:52.667Z,1741982752.667 [NAL9602](INFO): GPS fix at 20250314T200552: (36.802939, -121.787990) 2025-03-14T20:05:52.677Z,1741982752.677 [Default:CheckIn:Read_GPS] Stopped 2025-03-14T20:05:52.677Z,1741982752.677 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-14T20:05:59.777Z,1741982759.777 [DataOverHttps](INFO): Sending 201 bytes from file Logs/20250314T192908/Courier0021.lzma 2025-03-14T20:06:00.779Z,1741982760.779 [DataOverHttps](INFO): Moved sent file to Logs/20250314T192908/Courier0021.lzma.bak 2025-03-14T20:06:00.779Z,1741982760.779 [DataOverHttps](INFO): SBD MOMSN=24229910 2025-03-14T20:06:16.549Z,1741982776.549 [DataOverHttps](INFO): Sending 281 bytes from file Logs/20250314T192908/Express0022.lzma 2025-03-14T20:06:17.551Z,1741982777.551 [DataOverHttps](INFO): Moved sent file to Logs/20250314T192908/Express0022.lzma.bak 2025-03-14T20:06:17.551Z,1741982777.551 [DataOverHttps](INFO): SBD MOMSN=24229913 2025-03-14T20:06:18.944Z,1741982778.944 [Default:CheckIn:Read_Iridium] Stopped 2025-03-14T20:06:18.944Z,1741982778.944 [Default:CheckIn:C.Wait] Running Loop=1 2025-03-14T20:06:18.945Z,1741982778.945 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-03-14T20:07:07.406Z,1741982827.406 [NAL9602](INFO): SBD MO Status=2, MOMSN=18744, MT Status=2, MTMSN=0 2025-03-14T20:07:07.406Z,1741982827.406 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-03-14T20:09:42.952Z,1741982982.952 [NAL9602](INFO): SBD MO Status=0, MOMSN=18744, MT Status=0, MTMSN=0 2025-03-14T20:09:42.952Z,1741982982.952 [NAL9602](INFO): No messages in MT queue 2025-03-14T20:10:13.650Z,1741983013.650 [NAL9602](INFO): Not Powering down - fast GPS 2025-03-14T20:11:19.516Z,1741983079.516 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-03-14T20:11:19.516Z,1741983079.516 [Default:CheckIn:C.Wait] Stopped 2025-03-14T20:11:19.516Z,1741983079.516 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-03-14T20:11:19.516Z,1741983079.516 [Default:CheckIn:D] Running Loop=1 2025-03-14T20:11:19.924Z,1741983079.924 [Default:CheckIn:D] Stopped 2025-03-14T20:11:19.924Z,1741983079.924 [Default:CheckIn:E] Running Loop=1 2025-03-14T20:11:20.332Z,1741983080.332 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 71.290153 min 2025-03-14T20:11:20.332Z,1741983080.332 [Default:CheckIn:E] Stopped 2025-03-14T20:11:20.332Z,1741983080.332 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-03-14T20:11:20.332Z,1741983080.332 [Default:CheckIn] Stopped 2025-03-14T20:11:20.332Z,1741983080.332 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-14T20:11:20.333Z,1741983080.333 [Default:CheckIn](INFO): Running loop #13 2025-03-14T20:11:20.333Z,1741983080.333 [Default:CheckIn] Running Loop=13 2025-03-14T20:11:20.333Z,1741983080.333 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-14T20:11:20.333Z,1741983080.333 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-14T20:11:22.332Z,1741983082.332 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201121.00,A,3648.18088,N,12147.27627,W,0.292,350.78,140325,,,A*7B 2025-03-14T20:11:22.334Z,1741983082.334 [NAL9602](INFO): GPS fix at 20250314T201121: (36.803015, -121.787938) 2025-03-14T20:11:22.344Z,1741983082.344 [Default:CheckIn:Read_GPS] Stopped 2025-03-14T20:11:22.344Z,1741983082.344 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-14T20:11:29.965Z,1741983089.965 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250314T192908/Courier0024.lzma 2025-03-14T20:11:30.967Z,1741983090.967 [DataOverHttps](INFO): Moved sent file to Logs/20250314T192908/Courier0024.lzma.bak 2025-03-14T20:11:30.967Z,1741983090.967 [DataOverHttps](INFO): SBD MOMSN=24229924 2025-03-14T20:11:49.797Z,1741983109.797 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250314T192908/Express0025.lzma 2025-03-14T20:11:50.802Z,1741983110.802 [DataOverHttps](INFO): Moved sent file to Logs/20250314T192908/Express0025.lzma.bak 2025-03-14T20:11:50.802Z,1741983110.802 [DataOverHttps](INFO): SBD MOMSN=24229927 2025-03-14T20:11:52.296Z,1741983112.296 [Default:CheckIn:Read_Iridium] Stopped 2025-03-14T20:11:52.296Z,1741983112.296 [Default:CheckIn:C.Wait] Running Loop=1 2025-03-14T20:11:52.296Z,1741983112.296 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-03-14T20:14:11.268Z,1741983251.268 [NAL9602](INFO): SBD MO Status=0, MOMSN=18745, MT Status=0, MTMSN=0 2025-03-14T20:14:11.268Z,1741983251.268 [NAL9602](INFO): No messages in MT queue 2025-03-14T20:14:41.967Z,1741983281.967 [NAL9602](INFO): Not Powering down - fast GPS 2025-03-14T20:15:01.781Z,1741983301.781 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2025-03-14T20:16:52.873Z,1741983412.873 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-03-14T20:16:52.873Z,1741983412.873 [Default:CheckIn:C.Wait] Stopped 2025-03-14T20:16:52.873Z,1741983412.873 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-03-14T20:16:52.873Z,1741983412.873 [Default:CheckIn:D] Running Loop=1 2025-03-14T20:16:53.278Z,1741983413.278 [Default:CheckIn:D] Stopped 2025-03-14T20:16:53.278Z,1741983413.278 [Default:CheckIn:E] Running Loop=1 2025-03-14T20:16:53.697Z,1741983413.697 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 76.846061 min 2025-03-14T20:16:53.697Z,1741983413.697 [Default:CheckIn:E] Stopped 2025-03-14T20:16:53.698Z,1741983413.698 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-03-14T20:16:53.698Z,1741983413.698 [Default:CheckIn] Stopped 2025-03-14T20:16:53.698Z,1741983413.698 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-14T20:16:53.698Z,1741983413.698 [Default:CheckIn](INFO): Running loop #14 2025-03-14T20:16:53.698Z,1741983413.698 [Default:CheckIn] Running Loop=14 2025-03-14T20:16:53.698Z,1741983413.698 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-14T20:16:53.698Z,1741983413.698 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-14T20:16:55.701Z,1741983415.701 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201655.00,A,3648.17630,N,12147.28038,W,0.156,350.78,140325,,,A*79 2025-03-14T20:16:55.703Z,1741983415.703 [NAL9602](INFO): GPS fix at 20250314T201655: (36.802938, -121.788006) 2025-03-14T20:16:55.733Z,1741983415.733 [Default:CheckIn:Read_GPS] Stopped 2025-03-14T20:16:55.734Z,1741983415.734 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-14T20:17:03.277Z,1741983423.277 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250314T192908/Courier0027.lzma 2025-03-14T20:17:04.279Z,1741983424.279 [DataOverHttps](INFO): Moved sent file to Logs/20250314T192908/Courier0027.lzma.bak 2025-03-14T20:17:04.279Z,1741983424.279 [DataOverHttps](INFO): SBD MOMSN=24229937 2025-03-14T20:17:20.289Z,1741983440.289 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250314T192908/Express0028.lzma 2025-03-14T20:17:21.152Z,1741983441.152 [NAL9602](INFO): SBD MO Status=0, MOMSN=18746, MT Status=0, MTMSN=0 2025-03-14T20:17:21.152Z,1741983441.152 [NAL9602](INFO): No messages in MT queue 2025-03-14T20:17:21.291Z,1741983441.291 [DataOverHttps](INFO): Moved sent file to Logs/20250314T192908/Express0028.lzma.bak 2025-03-14T20:17:21.291Z,1741983441.291 [DataOverHttps](INFO): SBD MOMSN=24229940 2025-03-14T20:17:22.788Z,1741983442.788 [Default:CheckIn:Read_Iridium] Stopped 2025-03-14T20:17:22.789Z,1741983442.789 [Default:CheckIn:C.Wait] Running Loop=1 2025-03-14T20:17:22.790Z,1741983442.790 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-03-14T20:17:51.855Z,1741983471.855 [NAL9602](INFO): Not Powering down - fast GPS 2025-03-14T20:21:26.707Z,1741983686.707 [DAT](INFO): DAT read: 20:21:24.1139 LVL= 3392, 3889, 3138, 4771, AGC= 72, IDX= 48,-0.15, 0.574, 1.638, 2.109, 0.566, PHS=-0.024, 1.045, 1.538, RAW= 252.0, -33.2, CAL= 249.9, -38.3, ROT= 260.1, 38.3 2025-03-14T20:21:26.708Z,1741983686.708 [DAT](INFO): got valid direction response: 20:21:24.1139 LVL= 3392, 3889, 3138, 4771, AGC= 72, IDX= 48,-0.15, 0.574, 1.638, 2.109, 0.566, PHS=-0.024, 1.045, 1.538, RAW= 252.0, -33.2, CAL= 249.9, -38.3, ROT= 260.1, 38.3 2025-03-14T20:21:26.709Z,1741983686.709 [DAT](INFO): DAT read: Rx Time:20:21:24.1139 2025-03-14T20:21:26.710Z,1741983686.710 [DAT](INFO): Rx dataTimestamp_ set to:1741983686.709530 2025-03-14T20:21:26.711Z,1741983686.711 [DAT](INFO): DAT read: $Low SNR acquisition 2025-03-14T20:21:26.711Z,1741983686.711 [DAT](INFO): Received low SNR in chirp 2025-03-14T20:21:26.725Z,1741983686.725 [DAT](INFO): #Rx 1: Read direction message, but no range. 2025-03-14T20:21:26.726Z,1741983686.726 [DAT](INFO): direction in FSK: [-0.134926,-0.773091,-0.619779] 2025-03-14T20:22:23.418Z,1741983743.418 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-03-14T20:22:23.418Z,1741983743.418 [Default:CheckIn:C.Wait] Stopped 2025-03-14T20:22:23.418Z,1741983743.418 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-03-14T20:22:23.418Z,1741983743.418 [Default:CheckIn:D] Running Loop=1 2025-03-14T20:22:23.812Z,1741983743.812 [Default:CheckIn:D] Stopped 2025-03-14T20:22:23.812Z,1741983743.812 [Default:CheckIn:E] Running Loop=1 2025-03-14T20:22:24.202Z,1741983744.202 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 82.354956 min 2025-03-14T20:22:24.202Z,1741983744.202 [Default:CheckIn:E] Stopped 2025-03-14T20:22:24.202Z,1741983744.202 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-03-14T20:22:24.203Z,1741983744.203 [Default:CheckIn] Stopped 2025-03-14T20:22:24.203Z,1741983744.203 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-14T20:22:24.203Z,1741983744.203 [Default:CheckIn](INFO): Running loop #15 2025-03-14T20:22:24.203Z,1741983744.203 [Default:CheckIn] Running Loop=15 2025-03-14T20:22:24.203Z,1741983744.203 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-14T20:22:24.203Z,1741983744.203 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-14T20:22:26.211Z,1741983746.211 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202225.00,A,3648.17622,N,12147.27932,W,0.486,350.78,140325,,,D*7B 2025-03-14T20:22:26.225Z,1741983746.225 [NAL9602](INFO): GPS fix at 20250314T202225: (36.802937, -121.787989) 2025-03-14T20:22:26.235Z,1741983746.235 [Default:CheckIn:Read_GPS] Stopped 2025-03-14T20:22:26.235Z,1741983746.235 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-14T20:22:33.871Z,1741983753.871 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250314T192908/Courier0030.lzma 2025-03-14T20:22:34.867Z,1741983754.867 [DataOverHttps](INFO): Moved sent file to Logs/20250314T192908/Courier0030.lzma.bak 2025-03-14T20:22:34.867Z,1741983754.867 [DataOverHttps](INFO): SBD MOMSN=24229950 2025-03-14T20:22:51.245Z,1741983771.245 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250314T192908/Express0031.lzma 2025-03-14T20:22:52.247Z,1741983772.247 [DataOverHttps](INFO): Moved sent file to Logs/20250314T192908/Express0031.lzma.bak 2025-03-14T20:22:52.247Z,1741983772.247 [DataOverHttps](INFO): SBD MOMSN=24229953 2025-03-14T20:22:53.702Z,1741983773.702 [Default:CheckIn:Read_Iridium] Stopped 2025-03-14T20:22:53.703Z,1741983773.703 [Default:CheckIn:C.Wait] Running Loop=1 2025-03-14T20:22:53.703Z,1741983773.703 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-03-14T20:23:59.532Z,1741983839.532 [NAL9602](INFO): SBD MO Status=2, MOMSN=18747, MT Status=2, MTMSN=0 2025-03-14T20:23:59.532Z,1741983839.532 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-03-14T20:24:25.800Z,1741983865.800 [NAL9602](INFO): SBD MO Status=2, MOMSN=18747, MT Status=2, MTMSN=0 2025-03-14T20:24:25.800Z,1741983865.800 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-03-14T20:25:05.796Z,1741983905.796 [NAL9602](INFO): SBD MO Status=2, MOMSN=18747, MT Status=2, MTMSN=0 2025-03-14T20:25:05.796Z,1741983905.796 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-03-14T20:25:17.103Z,1741983917.103 [NAL9602](INFO): SBD MO Status=0, MOMSN=18747, MT Status=0, MTMSN=0 2025-03-14T20:25:17.103Z,1741983917.103 [NAL9602](INFO): No messages in MT queue 2025-03-14T20:25:47.804Z,1741983947.804 [NAL9602](INFO): Not Powering down - fast GPS 2025-03-14T20:27:54.270Z,1741984074.270 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-03-14T20:27:54.270Z,1741984074.270 [Default:CheckIn:C.Wait] Stopped 2025-03-14T20:27:54.270Z,1741984074.270 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-03-14T20:27:54.271Z,1741984074.271 [Default:CheckIn:D] Running Loop=1 2025-03-14T20:27:54.673Z,1741984074.673 [Default:CheckIn:D] Stopped 2025-03-14T20:27:54.673Z,1741984074.673 [Default:CheckIn:E] Running Loop=1 2025-03-14T20:27:55.079Z,1741984075.079 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 87.869303 min 2025-03-14T20:27:55.079Z,1741984075.079 [Default:CheckIn:E] Stopped 2025-03-14T20:27:55.079Z,1741984075.079 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-03-14T20:27:55.079Z,1741984075.079 [Default:CheckIn] Stopped 2025-03-14T20:27:55.079Z,1741984075.079 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-14T20:27:55.080Z,1741984075.080 [Default:CheckIn](INFO): Running loop #16 2025-03-14T20:27:55.080Z,1741984075.080 [Default:CheckIn] Running Loop=16 2025-03-14T20:27:55.080Z,1741984075.080 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-14T20:27:55.080Z,1741984075.080 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-14T20:27:57.101Z,1741984077.101 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202756.00,A,3648.17119,N,12147.27305,W,0.097,0.00,140325,,,D*76 2025-03-14T20:27:57.103Z,1741984077.103 [NAL9602](INFO): GPS fix at 20250314T202756: (36.802853, -121.787884) 2025-03-14T20:27:57.113Z,1741984077.113 [Default:CheckIn:Read_GPS] Stopped 2025-03-14T20:27:57.113Z,1741984077.113 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-14T20:28:04.789Z,1741984084.789 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250314T192908/Courier0033.lzma 2025-03-14T20:28:05.791Z,1741984085.791 [DataOverHttps](INFO): Moved sent file to Logs/20250314T192908/Courier0033.lzma.bak 2025-03-14T20:28:05.791Z,1741984085.791 [DataOverHttps](INFO): SBD MOMSN=24229963 2025-03-14T20:28:21.833Z,1741984101.833 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250314T192908/Express0034.lzma 2025-03-14T20:28:22.835Z,1741984102.835 [DataOverHttps](INFO): Moved sent file to Logs/20250314T192908/Express0034.lzma.bak 2025-03-14T20:28:22.835Z,1741984102.835 [DataOverHttps](INFO): SBD MOMSN=24229966 2025-03-14T20:28:24.197Z,1741984104.197 [Default:CheckIn:Read_Iridium] Stopped 2025-03-14T20:28:24.197Z,1741984104.197 [Default:CheckIn:C.Wait] Running Loop=1 2025-03-14T20:28:24.197Z,1741984104.197 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-03-14T20:32:59.287Z,1741984379.287 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-03-14T20:33:24.757Z,1741984404.757 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-03-14T20:33:24.757Z,1741984404.757 [Default:CheckIn:C.Wait] Stopped 2025-03-14T20:33:24.757Z,1741984404.757 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-03-14T20:33:24.757Z,1741984404.757 [Default:CheckIn:D] Running Loop=1 2025-03-14T20:33:25.155Z,1741984405.155 [Default:CheckIn:D] Stopped 2025-03-14T20:33:25.155Z,1741984405.155 [Default:CheckIn:E] Running Loop=1 2025-03-14T20:33:25.558Z,1741984405.558 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 93.377352 min 2025-03-14T20:33:25.558Z,1741984405.558 [Default:CheckIn:E] Stopped 2025-03-14T20:33:25.558Z,1741984405.558 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-03-14T20:33:25.559Z,1741984405.559 [Default:CheckIn] Stopped 2025-03-14T20:33:25.559Z,1741984405.559 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-14T20:33:25.559Z,1741984405.559 [Default:CheckIn](INFO): Running loop #17 2025-03-14T20:33:25.559Z,1741984405.559 [Default:CheckIn] Running Loop=17 2025-03-14T20:33:25.559Z,1741984405.559 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-14T20:33:25.559Z,1741984405.559 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-14T20:33:27.585Z,1741984407.585 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203327.00,A,3648.17929,N,12147.26913,W,0.117,102.49,140325,,,A*70 2025-03-14T20:33:27.587Z,1741984407.587 [NAL9602](INFO): GPS fix at 20250314T203327: (36.802988, -121.787819) 2025-03-14T20:33:27.598Z,1741984407.598 [Default:CheckIn:Read_GPS] Stopped 2025-03-14T20:33:27.598Z,1741984407.598 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-14T20:33:36.565Z,1741984416.565 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250314T192908/Courier0036.lzma 2025-03-14T20:33:37.567Z,1741984417.567 [DataOverHttps](INFO): Moved sent file to Logs/20250314T192908/Courier0036.lzma.bak 2025-03-14T20:33:37.567Z,1741984417.567 [DataOverHttps](INFO): SBD MOMSN=24229975 2025-03-14T20:33:56.913Z,1741984436.913 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20250314T192908/Express0037.lzma 2025-03-14T20:33:57.915Z,1741984437.915 [DataOverHttps](INFO): Moved sent file to Logs/20250314T192908/Express0037.lzma.bak 2025-03-14T20:33:57.915Z,1741984437.915 [DataOverHttps](INFO): SBD MOMSN=24229978 2025-03-14T20:33:59.119Z,1741984439.119 [Default:CheckIn:Read_Iridium] Stopped 2025-03-14T20:33:59.119Z,1741984439.119 [Default:CheckIn:C.Wait] Running Loop=1 2025-03-14T20:33:59.119Z,1741984439.119 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-03-14T20:34:00.319Z,1741984440.319 [NAL9602](INFO): Not Powering down - fast GPS 2025-03-14T20:38:59.742Z,1741984739.742 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-03-14T20:38:59.742Z,1741984739.742 [Default:CheckIn:C.Wait] Stopped 2025-03-14T20:38:59.742Z,1741984739.742 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-03-14T20:38:59.742Z,1741984739.742 [Default:CheckIn:D] Running Loop=1 2025-03-14T20:39:00.140Z,1741984740.140 [Default:CheckIn:D] Stopped 2025-03-14T20:39:00.140Z,1741984740.140 [Default:CheckIn:E] Running Loop=1 2025-03-14T20:39:00.592Z,1741984740.592 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 98.960425 min 2025-03-14T20:39:00.593Z,1741984740.593 [Default:CheckIn:E] Stopped 2025-03-14T20:39:00.593Z,1741984740.593 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-03-14T20:39:00.593Z,1741984740.593 [Default:CheckIn] Stopped 2025-03-14T20:39:00.593Z,1741984740.593 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-14T20:39:00.593Z,1741984740.593 [Default:CheckIn](INFO): Running loop #18 2025-03-14T20:39:00.593Z,1741984740.593 [Default:CheckIn] Running Loop=18 2025-03-14T20:39:00.593Z,1741984740.593 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-14T20:39:00.594Z,1741984740.594 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-14T20:39:02.571Z,1741984742.571 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203902.00,A,3648.17741,N,12147.27569,W,0.253,102.49,140325,,,A*7E 2025-03-14T20:39:02.573Z,1741984742.573 [NAL9602](INFO): GPS fix at 20250314T203902: (36.802957, -121.787928) 2025-03-14T20:39:02.584Z,1741984742.584 [Default:CheckIn:Read_GPS] Stopped 2025-03-14T20:39:02.584Z,1741984742.584 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-14T20:39:11.305Z,1741984751.305 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250314T192908/Courier0039.lzma 2025-03-14T20:39:12.307Z,1741984752.307 [DataOverHttps](INFO): Moved sent file to Logs/20250314T192908/Courier0039.lzma.bak 2025-03-14T20:39:12.308Z,1741984752.308 [DataOverHttps](INFO): SBD MOMSN=24229988 2025-03-14T20:39:18.306Z,1741984758.306 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-03-14T20:39:18.306Z,1741984758.306 [DropWeight] Hardware Fault, FailCount= 1 2025-03-14T20:39:18.306Z,1741984758.306 [DropWeight](ERROR): Hardware Fault 2025-03-14T20:39:18.358Z,1741984758.358 [CBIT](INFO): Critical error at 20250314T203918 2025-03-14T20:39:18.381Z,1741984758.381 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-03-14T20:39:18.381Z,1741984758.381 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-03-14T20:39:18.759Z,1741984758.759 [CBIT](INFO): Critical error at 20250314T203918 2025-03-14T20:39:29.417Z,1741984769.417 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250314T192908/Express0040.lzma 2025-03-14T20:39:30.419Z,1741984770.419 [DataOverHttps](INFO): Moved sent file to Logs/20250314T192908/Express0040.lzma.bak 2025-03-14T20:39:30.419Z,1741984770.419 [DataOverHttps](INFO): SBD MOMSN=24229991 2025-03-14T20:39:31.653Z,1741984771.653 [Default:CheckIn:Read_Iridium] Stopped 2025-03-14T20:39:31.653Z,1741984771.653 [Default:CheckIn:C.Wait] Running Loop=1 2025-03-14T20:39:31.653Z,1741984771.653 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-03-14T20:39:33.252Z,1741984773.252 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-03-14T20:39:33.333Z,1741984773.333 [NAL9602](ERROR): received: +CSQ:0 OK747, 0, 0, 0, 0 OK 2025-03-14T20:42:57.279Z,1741984977.279 [NAL9602](INFO): SBD MO Status=2, MOMSN=18748, MT Status=2, MTMSN=0 2025-03-14T20:42:57.279Z,1741984977.279 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-03-14T20:43:05.758Z,1741984985.758 [BPC1](INFO): Calculating totals. Valid battery stick count: 50. Valid reserve battery stick count: 5. 2025-03-14T20:43:05.773Z,1741984985.773 [BPC1](FAULT): Failed to receive data from 3 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 17, 21, 24. 2025-03-14T20:44:05.223Z,1741985045.223 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-03-14T20:44:32.312Z,1741985072.312 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-03-14T20:44:32.312Z,1741985072.312 [Default:CheckIn:C.Wait] Stopped 2025-03-14T20:44:32.312Z,1741985072.312 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-03-14T20:44:32.312Z,1741985072.312 [Default:CheckIn:D] Running Loop=1 2025-03-14T20:44:32.707Z,1741985072.707 [Default:CheckIn:D] Stopped 2025-03-14T20:44:32.707Z,1741985072.707 [Default:CheckIn:E] Running Loop=1 2025-03-14T20:44:33.112Z,1741985073.112 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 104.503215 min 2025-03-14T20:44:33.112Z,1741985073.112 [Default:CheckIn:E] Stopped 2025-03-14T20:44:33.112Z,1741985073.112 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-03-14T20:44:33.112Z,1741985073.112 [Default:CheckIn] Stopped 2025-03-14T20:44:33.112Z,1741985073.112 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-14T20:44:33.112Z,1741985073.112 [Default:CheckIn](INFO): Running loop #19 2025-03-14T20:44:33.112Z,1741985073.112 [Default:CheckIn] Running Loop=19 2025-03-14T20:44:33.112Z,1741985073.112 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-14T20:44:33.112Z,1741985073.112 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-14T20:44:35.121Z,1741985075.121 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204434.00,A,3648.17587,N,12147.27599,W,0.739,102.49,140325,,,A*7F 2025-03-14T20:44:35.123Z,1741985075.123 [NAL9602](INFO): GPS fix at 20250314T204434: (36.802931, -121.787933) 2025-03-14T20:44:35.133Z,1741985075.133 [Default:CheckIn:Read_GPS] Stopped 2025-03-14T20:44:35.134Z,1741985075.134 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-14T20:44:42.089Z,1741985082.089 [DataOverHttps](INFO): Sending 190 bytes from file Logs/20250314T192908/Courier0042.lzma 2025-03-14T20:44:43.091Z,1741985083.091 [DataOverHttps](INFO): Moved sent file to Logs/20250314T192908/Courier0042.lzma.bak 2025-03-14T20:44:43.091Z,1741985083.091 [DataOverHttps](INFO): SBD MOMSN=24230000 2025-03-14T20:44:59.106Z,1741985099.106 [DataOverHttps](INFO): Sending 304 bytes from file Logs/20250314T192908/Express0043.lzma 2025-03-14T20:45:00.103Z,1741985100.103 [DataOverHttps](INFO): Moved sent file to Logs/20250314T192908/Express0043.lzma.bak 2025-03-14T20:45:00.103Z,1741985100.103 [DataOverHttps](INFO): SBD MOMSN=24230005 2025-03-14T20:45:01.473Z,1741985101.473 [Default:CheckIn:Read_Iridium] Stopped 2025-03-14T20:45:01.473Z,1741985101.473 [Default:CheckIn:C.Wait] Running Loop=1 2025-03-14T20:45:01.473Z,1741985101.473 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-03-14T20:45:07.522Z,1741985107.522 [NAL9602](INFO): Not Powering down - fast GPS 2025-03-14T20:50:02.205Z,1741985402.205 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-03-14T20:50:02.205Z,1741985402.205 [Default:CheckIn:C.Wait] Stopped 2025-03-14T20:50:02.206Z,1741985402.206 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-03-14T20:50:02.206Z,1741985402.206 [Default:CheckIn:D] Running Loop=1 2025-03-14T20:50:02.620Z,1741985402.620 [Default:CheckIn:D] Stopped 2025-03-14T20:50:02.620Z,1741985402.620 [Default:CheckIn:E] Running Loop=1 2025-03-14T20:50:03.012Z,1741985403.012 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 110.001766 min 2025-03-14T20:50:03.012Z,1741985403.012 [Default:CheckIn:E] Stopped 2025-03-14T20:50:03.012Z,1741985403.012 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-03-14T20:50:03.012Z,1741985403.012 [Default:CheckIn] Stopped 2025-03-14T20:50:03.013Z,1741985403.013 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-14T20:50:03.013Z,1741985403.013 [Default:CheckIn](INFO): Running loop #20 2025-03-14T20:50:03.013Z,1741985403.013 [Default:CheckIn] Running Loop=20 2025-03-14T20:50:03.013Z,1741985403.013 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-14T20:50:03.013Z,1741985403.013 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-14T20:50:05.024Z,1741985405.024 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205004.00,A,3648.16732,N,12147.28043,W,0.233,0.00,140325,,,A*78 2025-03-14T20:50:05.026Z,1741985405.026 [NAL9602](INFO): GPS fix at 20250314T205004: (36.802789, -121.788007) 2025-03-14T20:50:05.036Z,1741985405.036 [Default:CheckIn:Read_GPS] Stopped 2025-03-14T20:50:05.036Z,1741985405.036 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-14T20:50:11.956Z,1741985411.956 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250314T192908/Courier0045.lzma 2025-03-14T20:50:12.959Z,1741985412.959 [DataOverHttps](INFO): Moved sent file to Logs/20250314T192908/Courier0045.lzma.bak 2025-03-14T20:50:12.959Z,1741985412.959 [DataOverHttps](INFO): SBD MOMSN=24230016 2025-03-14T20:50:29.085Z,1741985429.085 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250314T192908/Express0046.lzma 2025-03-14T20:50:30.087Z,1741985430.087 [DataOverHttps](INFO): Moved sent file to Logs/20250314T192908/Express0046.lzma.bak 2025-03-14T20:50:30.087Z,1741985430.087 [DataOverHttps](INFO): SBD MOMSN=24230019 2025-03-14T20:50:31.403Z,1741985431.403 [Default:CheckIn:Read_Iridium] Stopped 2025-03-14T20:50:31.403Z,1741985431.403 [Default:CheckIn:C.Wait] Running Loop=1 2025-03-14T20:50:31.403Z,1741985431.403 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-03-14T20:50:35.827Z,1741985435.827 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-03-14T20:50:35.901Z,1741985435.901 [NAL9602](ERROR): received: +CSQ:0 OK748, 2, 0, 0, 0 OK 2025-03-14T20:54:18.528Z,1741985658.528 [CBIT](INFO): Clearing failed state for component DropWeight 2025-03-14T20:54:18.528Z,1741985658.528 [DropWeight] No Fault, FailCount= 1 2025-03-14T20:55:07.488Z,1741985707.488 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-03-14T20:55:32.015Z,1741985732.015 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-03-14T20:55:32.015Z,1741985732.015 [Default:CheckIn:C.Wait] Stopped 2025-03-14T20:55:32.015Z,1741985732.015 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-03-14T20:55:32.015Z,1741985732.015 [Default:CheckIn:D] Running Loop=1 2025-03-14T20:55:32.426Z,1741985732.426 [Default:CheckIn:D] Stopped 2025-03-14T20:55:32.426Z,1741985732.426 [Default:CheckIn:E] Running Loop=1 2025-03-14T20:55:32.842Z,1741985732.842 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 115.498519 min 2025-03-14T20:55:32.842Z,1741985732.842 [Default:CheckIn:E] Stopped 2025-03-14T20:55:32.842Z,1741985732.842 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-03-14T20:55:32.842Z,1741985732.842 [Default:CheckIn] Stopped 2025-03-14T20:55:32.842Z,1741985732.842 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-14T20:55:32.842Z,1741985732.842 [Default:CheckIn](INFO): Running loop #21 2025-03-14T20:55:32.842Z,1741985732.842 [Default:CheckIn] Running Loop=21 2025-03-14T20:55:32.842Z,1741985732.842 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-14T20:55:32.843Z,1741985732.843 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-14T20:55:34.869Z,1741985734.869 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205534.00,A,3648.15758,N,12147.27501,W,0.719,0.00,140325,,,A*70 2025-03-14T20:55:34.871Z,1741985734.871 [NAL9602](INFO): GPS fix at 20250314T205534: (36.802626, -121.787917) 2025-03-14T20:55:34.882Z,1741985734.882 [Default:CheckIn:Read_GPS] Stopped 2025-03-14T20:55:34.882Z,1741985734.882 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-14T20:55:41.997Z,1741985741.997 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250314T192908/Courier0048.lzma 2025-03-14T20:55:42.999Z,1741985742.999 [DataOverHttps](INFO): Moved sent file to Logs/20250314T192908/Courier0048.lzma.bak 2025-03-14T20:55:42.999Z,1741985742.999 [DataOverHttps](INFO): SBD MOMSN=24230028 2025-03-14T20:56:02.054Z,1741985762.054 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20250314T192908/Express0049.lzma 2025-03-14T20:56:03.055Z,1741985763.055 [DataOverHttps](INFO): Moved sent file to Logs/20250314T192908/Express0049.lzma.bak 2025-03-14T20:56:03.055Z,1741985763.055 [DataOverHttps](INFO): SBD MOMSN=24230031 2025-03-14T20:56:04.488Z,1741985764.488 [Default:CheckIn:Read_Iridium] Stopped 2025-03-14T20:56:04.488Z,1741985764.488 [Default:CheckIn:C.Wait] Running Loop=1 2025-03-14T20:56:04.489Z,1741985764.489 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-03-14T20:56:06.900Z,1741985766.900 [NAL9602](INFO): Not Powering down - fast GPS 2025-03-14T21:00:08.345Z,1741986008.345 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2025-03-14T21:00:10.353Z,1741986010.353 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.654957 2025-03-14T21:00:20.838Z,1741986020.838 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003908 2025-03-14T21:01:05.071Z,1741986065.071 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-03-14T21:01:05.071Z,1741986065.071 [Default:CheckIn:C.Wait] Stopped 2025-03-14T21:01:05.071Z,1741986065.071 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-03-14T21:01:05.071Z,1741986065.071 [Default:CheckIn:D] Running Loop=1 2025-03-14T21:01:05.486Z,1741986065.486 [Default:CheckIn:D] Stopped 2025-03-14T21:01:05.486Z,1741986065.486 [Default:CheckIn:E] Running Loop=1 2025-03-14T21:01:05.876Z,1741986065.876 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 121.049520 min 2025-03-14T21:01:05.877Z,1741986065.877 [Default:CheckIn:E] Stopped 2025-03-14T21:01:05.877Z,1741986065.877 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-03-14T21:01:05.877Z,1741986065.877 [Default:CheckIn] Stopped 2025-03-14T21:01:05.877Z,1741986065.877 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-14T21:01:05.877Z,1741986065.877 [Default:CheckIn](INFO): Running loop #22 2025-03-14T21:01:05.877Z,1741986065.877 [Default:CheckIn] Running Loop=22 2025-03-14T21:01:05.877Z,1741986065.877 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-14T21:01:05.878Z,1741986065.878 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-14T21:01:07.896Z,1741986067.896 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210107.00,A,3648.16250,N,12147.27474,W,0.272,346.66,140325,,,A*74 2025-03-14T21:01:07.898Z,1741986067.898 [NAL9602](INFO): GPS fix at 20250314T210107: (36.802708, -121.787912) 2025-03-14T21:01:07.909Z,1741986067.909 [Default:CheckIn:Read_GPS] Stopped 2025-03-14T21:01:07.909Z,1741986067.909 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-14T21:01:14.866Z,1741986074.866 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250314T192908/Courier0051.lzma 2025-03-14T21:01:15.867Z,1741986075.867 [DataOverHttps](INFO): Moved sent file to Logs/20250314T192908/Courier0051.lzma.bak 2025-03-14T21:01:15.867Z,1741986075.867 [DataOverHttps](INFO): SBD MOMSN=24230041 2025-03-14T21:01:34.869Z,1741986094.869 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20250314T192908/Express0052.lzma 2025-03-14T21:01:35.871Z,1741986095.871 [DataOverHttps](INFO): Moved sent file to Logs/20250314T192908/Express0052.lzma.bak 2025-03-14T21:01:35.871Z,1741986095.871 [DataOverHttps](INFO): SBD MOMSN=24230044 2025-03-14T21:01:37.563Z,1741986097.563 [Default:CheckIn:Read_Iridium] Stopped 2025-03-14T21:01:37.563Z,1741986097.563 [Default:CheckIn:C.Wait] Running Loop=1 2025-03-14T21:01:37.563Z,1741986097.563 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-03-14T21:01:38.738Z,1741986098.738 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-03-14T21:01:38.813Z,1741986098.813 [NAL9602](ERROR): received: +CSQ:0 OK748, 2, 0, 0, 0 OK 2025-03-14T21:02:13.314Z,1741986133.314 [CommandExec](IMPORTANT): got command quit 2025-03-14T21:02:14.337Z,1741986134.337 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-14T21:02:14.337Z,1741986134.337 [CommandExec](INFO): Uninitializing the command executive. 2025-03-14T21:02:14.338Z,1741986134.338 [CommandExec](INFO): Uninitializing the command scheduler. 2025-03-14T21:02:14.338Z,1741986134.338 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-03-14T21:02:14.381Z,1741986134.381 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2025-03-14T21:02:14.381Z,1741986134.381 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2025-03-14T21:02:14.381Z,1741986134.381 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-03-14T21:02:14.382Z,1741986134.382 [NavChartDb](INFO): Join timeout helper Thread ID is 6552 2025-03-14T21:02:14.782Z,1741986134.782 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-14T21:02:14.782Z,1741986134.782 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-03-14T21:02:14.782Z,1741986134.782 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2025-03-14T21:02:14.782Z,1741986134.782 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-03-14T21:02:14.783Z,1741986134.783 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 6553 2025-03-14T21:02:14.989Z,1741986134.989 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-14T21:02:14.989Z,1741986134.989 [WetLabsBB2FL](INFO): Powering down 2025-03-14T21:02:14.990Z,1741986134.990 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-03-14T21:02:14.997Z,1741986134.997 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2025-03-14T21:02:14.997Z,1741986134.997 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-03-14T21:02:14.998Z,1741986134.998 [CTD_Seabird](INFO): Join timeout helper Thread ID is 6554 2025-03-14T21:02:15.765Z,1741986135.765 [CTD_Seabird](INFO): Powering down 2025-03-14T21:02:15.777Z,1741986135.777 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-14T21:02:15.777Z,1741986135.777 [CTD_Seabird](INFO): Powering down 2025-03-14T21:02:15.789Z,1741986135.789 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-03-14T21:02:15.801Z,1741986135.801 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2025-03-14T21:02:15.801Z,1741986135.801 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-03-14T21:02:15.802Z,1741986135.802 [Radio_Surface](INFO): Join timeout helper Thread ID is 6555 2025-03-14T21:02:15.993Z,1741986135.993 [Radio_Surface](INFO): Powering down 2025-03-14T21:02:15.995Z,1741986135.995 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-14T21:02:15.996Z,1741986135.996 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-03-14T21:02:16.005Z,1741986136.005 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2025-03-14T21:02:16.005Z,1741986136.005 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-03-14T21:02:16.006Z,1741986136.006 [Onboard](INFO): Join timeout helper Thread ID is 6556 2025-03-14T21:02:16.133Z,1741986136.133 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-14T21:02:16.133Z,1741986136.133 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-03-14T21:02:16.142Z,1741986136.142 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2025-03-14T21:02:16.142Z,1741986136.142 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-03-14T21:02:16.143Z,1741986136.143 [DataOverHttps](INFO): Join timeout helper Thread ID is 6557 2025-03-14T21:02:20.053Z,1741986140.053 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-14T21:02:20.056Z,1741986140.056 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-03-14T21:02:20.062Z,1741986140.062 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2025-03-14T21:02:20.062Z,1741986140.062 [DAT ThreadHandler](INFO): Thread cancelled. 2025-03-14T21:02:20.062Z,1741986140.062 [DAT](INFO): Join timeout helper Thread ID is 6558 2025-03-14T21:02:20.097Z,1741986140.097 [DAT](INFO): Powering down 2025-03-14T21:02:20.170Z,1741986140.170 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-14T21:02:20.170Z,1741986140.170 [DAT](INFO): Powering down 2025-03-14T21:02:20.171Z,1741986140.171 [DAT ThreadHandler](INFO): Thread cancelled. 2025-03-14T21:02:20.182Z,1741986140.182 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2025-03-14T21:02:20.182Z,1741986140.182 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-03-14T21:02:20.182Z,1741986140.182 [BackseatComponent](INFO): Join timeout helper Thread ID is 6559 2025-03-14T21:02:20.273Z,1741986140.273 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-14T21:02:20.273Z,1741986140.273 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-03-14T21:02:20.286Z,1741986140.286 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2025-03-14T21:02:20.286Z,1741986140.286 [logger ThreadHandler](INFO): Thread cancelled. 2025-03-14T21:02:20.286Z,1741986140.286 [logger](INFO): Join timeout helper Thread ID is 6560 2025-03-14T21:02:20.302Z,1741986140.302 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-14T21:02:20.302Z,1741986140.302 [logger ThreadHandler](INFO): Thread cancelled. 2025-03-14T21:02:20.306Z,1741986140.306 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2025-03-14T21:02:20.306Z,1741986140.306 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-03-14T21:02:20.307Z,1741986140.307 [CommandLine](INFO): Join timeout helper Thread ID is 6561 2025-03-14T21:02:20.341Z,1741986140.341 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-14T21:02:20.341Z,1741986140.341 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-03-14T21:02:20.350Z,1741986140.350 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2025-03-14T21:02:20.350Z,1741986140.350 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-03-14T21:02:20.350Z,1741986140.350 [CommandExec](INFO): Join timeout helper Thread ID is 6562 2025-03-14T21:02:20.358Z,1741986140.358 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2025-03-14T21:02:20.358Z,1741986140.358 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-03-14T21:02:20.358Z,1741986140.358 [controlThread](INFO): Join timeout helper Thread ID is 6563 2025-03-14T21:02:20.385Z,1741986140.385 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-14T21:02:20.385Z,1741986140.385 [controlThread](DEBUG): Uninitializing ControlThread 2025-03-14T21:02:20.385Z,1741986140.385 [AHRS_M2](INFO): Powering down 2025-03-14T21:02:20.458Z,1741986140.458 [MultiRay](INFO): Powering down. 2025-03-14T21:02:20.459Z,1741986140.459 [NAL9602](INFO): Powering down 2025-03-14T21:02:20.460Z,1741986140.460 [Waterlinked](INFO): Powering down 2025-03-14T21:02:20.462Z,1741986140.462 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2025-03-14T21:02:20.462Z,1741986140.462 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2025-03-14T21:02:20.463Z,1741986140.463 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2025-03-14T21:02:20.463Z,1741986140.463 [MissionManager](INFO): Uninitializing Mission Default 2025-03-14T21:02:20.464Z,1741986140.464 [Default] Stopped 2025-03-14T21:02:20.464Z,1741986140.464 [Default](DEBUG): Aggregate::uninitialize Default 2025-03-14T21:02:20.464Z,1741986140.464 [Default:B.GoToSurface] Stopped 2025-03-14T21:02:20.464Z,1741986140.464 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-03-14T21:02:20.464Z,1741986140.464 [Default:CheckIn] Stopped 2025-03-14T21:02:20.464Z,1741986140.464 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-14T21:02:20.464Z,1741986140.464 [Default:CheckIn:C.Wait] Stopped 2025-03-14T21:02:20.464Z,1741986140.464 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-03-14T21:02:20.467Z,1741986140.467 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2025-03-14T21:02:20.468Z,1741986140.468 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2025-03-14T21:02:20.468Z,1741986140.468 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2025-03-14T21:02:20.468Z,1741986140.468 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2025-03-14T21:02:20.469Z,1741986140.469 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2025-03-14T21:02:20.469Z,1741986140.469 [BuoyancyServo](INFO): Powering down 2025-03-14T21:02:20.481Z,1741986140.481 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2025-03-14T21:02:20.481Z,1741986140.481 [ElevatorServo](INFO): Powering down 2025-03-14T21:02:20.482Z,1741986140.482 [MassServo](DEBUG): Uninitialize Mass Servo. 2025-03-14T21:02:20.482Z,1741986140.482 [MassServo](INFO): Powering down 2025-03-14T21:02:20.483Z,1741986140.483 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2025-03-14T21:02:20.483Z,1741986140.483 [RudderServo](INFO): Powering down 2025-03-14T21:02:20.484Z,1741986140.484 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-03-14T21:02:20.484Z,1741986140.484 [ThrusterHE](INFO): Powering down 2025-03-14T21:02:20.485Z,1741986140.485 [SBIT](DEBUG): Uninitialize SBIT Component. 2025-03-14T21:02:20.485Z,1741986140.485 [IBIT](DEBUG): Uninitialize IBIT Component. 2025-03-14T21:02:20.485Z,1741986140.485 [CBIT](DEBUG): Uninitialize CBIT Component. 2025-03-14T21:02:20.486Z,1741986140.486 [CBIT](DEBUG): Powering off loads. 2025-03-14T21:02:20.497Z,1741986140.497 [CBIT](DEBUG): Disabling WDT. 2025-03-14T21:02:20.509Z,1741986140.509 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-03-14T21:02:20.509Z,1741986140.509 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-03-14T21:02:20.510Z,1741986140.510 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-03-14T21:02:20.548Z,1741986140.548 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-03-14T21:02:20.552Z,1741986140.552 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-03-14T21:02:20.559Z,1741986140.559 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-03-14T21:02:20.566Z,1741986140.566 [DAT ThreadHandler](INFO): Thread cancelled. 2025-03-14T21:02:20.609Z,1741986140.609 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-03-14T21:02:20.645Z,1741986140.645 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-03-14T21:02:20.645Z,1741986140.645 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-03-14T21:02:20.660Z,1741986140.660 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-03-14T21:02:20.665Z,1741986140.665 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-03-14T21:02:20.719Z,1741986140.719 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-03-14T21:02:20.769Z,1741986140.769 [logger ThreadHandler](INFO): Thread cancelled.