2023-12-22T21:02:48.469Z,1703278968.469 [CommandExec](IMPORTANT): got command restart logs
2023-12-22T21:02:49.641Z,1703278969.641 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-22T21:02:49.641Z,1703278969.641 [Default:CheckIn:C.Wait] Stopped
2023-12-22T21:02:49.641Z,1703278969.641 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-22T21:02:49.642Z,1703278969.642 [Default:CheckIn:D] Running Loop=1
2023-12-22T21:02:50.045Z,1703278970.045 [Default:CheckIn:D] Stopped
2023-12-22T21:02:50.045Z,1703278970.045 [Default:CheckIn:E] Running Loop=1
2023-12-22T21:02:50.457Z,1703278970.457 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 13.650778 min 
2023-12-22T21:02:50.457Z,1703278970.457 [Default:CheckIn:E] Stopped
2023-12-22T21:02:50.457Z,1703278970.457 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-22T21:02:50.457Z,1703278970.457 [Default:CheckIn] Stopped
2023-12-22T21:02:50.457Z,1703278970.457 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-22T21:02:50.457Z,1703278970.457 [Default:CheckIn](INFO): Running loop #3
2023-12-22T21:02:50.457Z,1703278970.457 [Default:CheckIn] Running Loop=3
2023-12-22T21:02:50.458Z,1703278970.458 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-22T21:02:50.458Z,1703278970.458 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-22T21:02:52.447Z,1703278972.447 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210251.00,A,3648.27637,N,12149.19250,W,28.555,96.34,221223,,,D*77
2023-12-22T21:02:52.449Z,1703278972.449 [NAL9602](INFO): GPS fix at 20231222T210251: (36.804606, -121.819875)
2023-12-22T21:02:52.481Z,1703278972.481 [Default:CheckIn:Read_GPS] Stopped
2023-12-22T21:02:52.481Z,1703278972.481 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-22T21:03:00.947Z,1703278980.947 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20231222T183119/Courier0040.lzma
2023-12-22T21:03:01.950Z,1703278981.950 [DataOverHttps](INFO): Moved sent file to Logs/20231222T183119/Courier0040.lzma.bak
2023-12-22T21:03:01.950Z,1703278981.950 [DataOverHttps](INFO): SBD MOMSN=19130497
2023-12-22T21:03:26.511Z,1703279006.511 [NAL9602](INFO): SBD MO Status=0, MOMSN=14667, MT Status=0, MTMSN=0
2023-12-22T21:03:26.511Z,1703279006.511 [NAL9602](INFO): No messages in MT queue
2023-12-22T21:03:28.968Z,1703279008.968 [DataOverHttps](INFO): Sending 104 bytes from file Logs/20231222T183119/Express0041.lzma
2023-12-22T21:03:30.974Z,1703279010.974 [DataOverHttps](INFO): Moved sent file to Logs/20231222T183119/Express0041.lzma.bak
2023-12-22T21:03:30.974Z,1703279010.974 [DataOverHttps](INFO): SBD MOMSN=19130499
2023-12-22T21:03:32.201Z,1703279012.201 [Default:CheckIn:Read_Iridium] Stopped
2023-12-22T21:03:32.202Z,1703279012.202 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-22T21:03:32.202Z,1703279012.202 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-22T21:03:57.217Z,1703279037.217 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-22T21:05:48.632Z,1703279148.632 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-12-22T21:05:50.640Z,1703279150.640 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.246758
2023-12-22T21:06:23.772Z,1703279183.772 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-12-22T21:06:58.924Z,1703279218.924 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-12-22T21:07:24.572Z,1703279244.572 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002979
2023-12-22T21:08:32.748Z,1703279312.748 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-22T21:08:32.748Z,1703279312.748 [Default:CheckIn:C.Wait] Stopped
2023-12-22T21:08:32.748Z,1703279312.748 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-22T21:08:32.749Z,1703279312.749 [Default:CheckIn:D] Running Loop=1
2023-12-22T21:08:33.158Z,1703279313.158 [Default:CheckIn:D] Stopped
2023-12-22T21:08:33.158Z,1703279313.158 [Default:CheckIn:E] Running Loop=1
2023-12-22T21:08:33.574Z,1703279313.574 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 19.369334 min 
2023-12-22T21:08:33.574Z,1703279313.574 [Default:CheckIn:E] Stopped
2023-12-22T21:08:33.575Z,1703279313.575 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-22T21:08:33.575Z,1703279313.575 [Default:CheckIn] Stopped
2023-12-22T21:08:33.575Z,1703279313.575 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-22T21:08:33.575Z,1703279313.575 [Default:CheckIn](INFO): Running loop #4
2023-12-22T21:08:33.575Z,1703279313.575 [Default:CheckIn] Running Loop=4
2023-12-22T21:08:33.575Z,1703279313.575 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-22T21:08:33.575Z,1703279313.575 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-22T21:08:35.575Z,1703279315.575 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210834.00,A,3648.50578,N,12147.15353,W,5.793,92.21,221223,,,D*41
2023-12-22T21:08:35.577Z,1703279315.577 [NAL9602](INFO): GPS fix at 20231222T210834: (36.808430, -121.785892)
2023-12-22T21:08:35.609Z,1703279315.609 [Default:CheckIn:Read_GPS] Stopped
2023-12-22T21:08:35.609Z,1703279315.609 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-22T21:08:43.348Z,1703279323.348 [DataOverHttps](INFO): Sending 269 bytes from file Logs/20231222T210248/Courier0006.lzma
2023-12-22T21:08:44.349Z,1703279324.349 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0006.lzma.bak
2023-12-22T21:08:44.350Z,1703279324.350 [DataOverHttps](INFO): SBD MOMSN=19130503
2023-12-22T21:08:56.982Z,1703279336.982 [NAL9602](INFO): SBD MO Status=0, MOMSN=14668, MT Status=0, MTMSN=0
2023-12-22T21:08:56.982Z,1703279336.982 [NAL9602](INFO): No messages in MT queue
2023-12-22T21:09:00.248Z,1703279340.248 [DataOverHttps](INFO): Sending 403 bytes from file Logs/20231222T210248/Express0007.lzma
2023-12-22T21:09:01.249Z,1703279341.249 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0007.lzma.bak
2023-12-22T21:09:01.250Z,1703279341.250 [DataOverHttps](INFO): SBD MOMSN=19130508
2023-12-22T21:09:02.677Z,1703279342.677 [Default:CheckIn:Read_Iridium] Stopped
2023-12-22T21:09:02.678Z,1703279342.678 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-22T21:09:02.678Z,1703279342.678 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-22T21:09:27.689Z,1703279367.689 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-22T21:14:03.256Z,1703279643.256 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-22T21:14:03.257Z,1703279643.257 [Default:CheckIn:C.Wait] Stopped
2023-12-22T21:14:03.257Z,1703279643.257 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-22T21:14:03.257Z,1703279643.257 [Default:CheckIn:D] Running Loop=1
2023-12-22T21:14:03.652Z,1703279643.652 [Default:CheckIn:D] Stopped
2023-12-22T21:14:03.652Z,1703279643.652 [Default:CheckIn:E] Running Loop=1
2023-12-22T21:14:04.051Z,1703279644.051 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 24.877566 min 
2023-12-22T21:14:04.051Z,1703279644.051 [Default:CheckIn:E] Stopped
2023-12-22T21:14:04.052Z,1703279644.052 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-22T21:14:04.052Z,1703279644.052 [Default:CheckIn] Stopped
2023-12-22T21:14:04.052Z,1703279644.052 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-22T21:14:04.052Z,1703279644.052 [Default:CheckIn](INFO): Running loop #5
2023-12-22T21:14:04.052Z,1703279644.052 [Default:CheckIn] Running Loop=5
2023-12-22T21:14:04.052Z,1703279644.052 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-22T21:14:04.052Z,1703279644.052 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-22T21:14:06.064Z,1703279646.064 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211405.00,A,3648.13809,N,12147.20205,W,0.486,225.29,221223,,,D*72
2023-12-22T21:14:06.066Z,1703279646.066 [NAL9602](INFO): GPS fix at 20231222T211405: (36.802301, -121.786701)
2023-12-22T21:14:06.078Z,1703279646.078 [Default:CheckIn:Read_GPS] Stopped
2023-12-22T21:14:06.078Z,1703279646.078 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-22T21:14:13.411Z,1703279653.411 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20231222T210248/Courier0009.lzma
2023-12-22T21:14:14.414Z,1703279654.414 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0009.lzma.bak
2023-12-22T21:14:14.414Z,1703279654.414 [DataOverHttps](INFO): SBD MOMSN=19130514
2023-12-22T21:14:18.603Z,1703279658.603 [NAL9602](INFO): SBD MO Status=0, MOMSN=14669, MT Status=0, MTMSN=0
2023-12-22T21:14:18.603Z,1703279658.603 [NAL9602](INFO): No messages in MT queue
2023-12-22T21:14:30.324Z,1703279670.324 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20231222T210248/Express0010.lzma
2023-12-22T21:14:31.325Z,1703279671.325 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0010.lzma.bak
2023-12-22T21:14:31.326Z,1703279671.326 [DataOverHttps](INFO): SBD MOMSN=19130517
2023-12-22T21:14:32.745Z,1703279672.745 [Default:CheckIn:Read_Iridium] Stopped
2023-12-22T21:14:32.745Z,1703279672.745 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-22T21:14:32.745Z,1703279672.745 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-22T21:14:49.294Z,1703279689.294 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-22T21:19:33.313Z,1703279973.313 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-22T21:19:33.313Z,1703279973.313 [Default:CheckIn:C.Wait] Stopped
2023-12-22T21:19:33.314Z,1703279973.314 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-22T21:19:33.314Z,1703279973.314 [Default:CheckIn:D] Running Loop=1
2023-12-22T21:19:33.725Z,1703279973.725 [Default:CheckIn:D] Stopped
2023-12-22T21:19:33.725Z,1703279973.725 [Default:CheckIn:E] Running Loop=1
2023-12-22T21:19:34.147Z,1703279974.147 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 30.378782 min 
2023-12-22T21:19:34.147Z,1703279974.147 [Default:CheckIn:E] Stopped
2023-12-22T21:19:34.147Z,1703279974.147 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-22T21:19:34.147Z,1703279974.147 [Default:CheckIn] Stopped
2023-12-22T21:19:34.147Z,1703279974.147 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-22T21:19:34.147Z,1703279974.147 [Default:CheckIn](INFO): Running loop #6
2023-12-22T21:19:34.147Z,1703279974.147 [Default:CheckIn] Running Loop=6
2023-12-22T21:19:34.148Z,1703279974.148 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-22T21:19:34.148Z,1703279974.148 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-22T21:19:36.135Z,1703279976.135 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211935.00,A,3648.13765,N,12147.20610,W,0.039,225.29,221223,,,A*7C
2023-12-22T21:19:36.137Z,1703279976.137 [NAL9602](INFO): GPS fix at 20231222T211935: (36.802294, -121.786768)
2023-12-22T21:19:36.148Z,1703279976.148 [Default:CheckIn:Read_GPS] Stopped
2023-12-22T21:19:36.149Z,1703279976.149 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-22T21:19:44.163Z,1703279984.163 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231222T210248/Courier0012.lzma
2023-12-22T21:19:45.165Z,1703279985.165 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0012.lzma.bak
2023-12-22T21:19:45.166Z,1703279985.166 [DataOverHttps](INFO): SBD MOMSN=19130521
2023-12-22T21:19:49.058Z,1703279989.058 [NAL9602](INFO): SBD MO Status=0, MOMSN=14670, MT Status=0, MTMSN=0
2023-12-22T21:19:49.059Z,1703279989.059 [NAL9602](INFO): No messages in MT queue
2023-12-22T21:20:01.088Z,1703280001.088 [DataOverHttps](INFO): Sending 127 bytes from file Logs/20231222T210248/Express0013.lzma
2023-12-22T21:20:02.090Z,1703280002.090 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0013.lzma.bak
2023-12-22T21:20:02.090Z,1703280002.090 [DataOverHttps](INFO): SBD MOMSN=19130524
2023-12-22T21:20:03.616Z,1703280003.616 [Default:CheckIn:Read_Iridium] Stopped
2023-12-22T21:20:03.616Z,1703280003.616 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-22T21:20:03.616Z,1703280003.616 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-22T21:20:19.760Z,1703280019.760 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-22T21:25:04.201Z,1703280304.201 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-22T21:25:04.201Z,1703280304.201 [Default:CheckIn:C.Wait] Stopped
2023-12-22T21:25:04.201Z,1703280304.201 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-22T21:25:04.202Z,1703280304.202 [Default:CheckIn:D] Running Loop=1
2023-12-22T21:25:04.592Z,1703280304.592 [Default:CheckIn:D] Stopped
2023-12-22T21:25:04.592Z,1703280304.592 [Default:CheckIn:E] Running Loop=1
2023-12-22T21:25:05.001Z,1703280305.001 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 35.893221 min 
2023-12-22T21:25:05.001Z,1703280305.001 [Default:CheckIn:E] Stopped
2023-12-22T21:25:05.001Z,1703280305.001 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-22T21:25:05.001Z,1703280305.001 [Default:CheckIn] Stopped
2023-12-22T21:25:05.001Z,1703280305.001 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-22T21:25:05.001Z,1703280305.001 [Default:CheckIn](INFO): Running loop #7
2023-12-22T21:25:05.001Z,1703280305.001 [Default:CheckIn] Running Loop=7
2023-12-22T21:25:05.001Z,1703280305.001 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-22T21:25:05.002Z,1703280305.002 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-22T21:25:07.007Z,1703280307.007 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212506.00,A,3648.13709,N,12147.20966,W,0.078,263.47,221223,,,A*78
2023-12-22T21:25:07.009Z,1703280307.009 [NAL9602](INFO): GPS fix at 20231222T212506: (36.802285, -121.786828)
2023-12-22T21:25:07.021Z,1703280307.021 [Default:CheckIn:Read_GPS] Stopped
2023-12-22T21:25:07.021Z,1703280307.021 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-22T21:25:13.979Z,1703280313.979 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20231222T210248/Courier0015.lzma
2023-12-22T21:25:14.982Z,1703280314.982 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0015.lzma.bak
2023-12-22T21:25:14.982Z,1703280314.982 [DataOverHttps](INFO): SBD MOMSN=19130528
2023-12-22T21:25:30.928Z,1703280330.928 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20231222T210248/Express0016.lzma
2023-12-22T21:25:31.254Z,1703280331.254 [NAL9602](INFO): SBD MO Status=2, MOMSN=14671, MT Status=2, MTMSN=0
2023-12-22T21:25:31.254Z,1703280331.254 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-12-22T21:25:31.930Z,1703280331.930 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0016.lzma.bak
2023-12-22T21:25:31.930Z,1703280331.930 [DataOverHttps](INFO): SBD MOMSN=19130531
2023-12-22T21:25:33.298Z,1703280333.298 [Default:CheckIn:Read_Iridium] Stopped
2023-12-22T21:25:33.298Z,1703280333.298 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-22T21:25:33.299Z,1703280333.299 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-22T21:25:50.238Z,1703280350.238 [NAL9602](INFO): SBD MO Status=2, MOMSN=14671, MT Status=2, MTMSN=0
2023-12-22T21:25:50.238Z,1703280350.238 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-12-22T21:26:22.586Z,1703280382.586 [NAL9602](INFO): SBD MO Status=0, MOMSN=14671, MT Status=0, MTMSN=0
2023-12-22T21:26:22.586Z,1703280382.586 [NAL9602](INFO): No messages in MT queue
2023-12-22T21:26:53.289Z,1703280413.289 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-22T21:30:33.918Z,1703280633.918 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-22T21:30:33.918Z,1703280633.918 [Default:CheckIn:C.Wait] Stopped
2023-12-22T21:30:33.918Z,1703280633.918 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-22T21:30:33.918Z,1703280633.918 [Default:CheckIn:D] Running Loop=1
2023-12-22T21:30:34.312Z,1703280634.312 [Default:CheckIn:D] Stopped
2023-12-22T21:30:34.312Z,1703280634.312 [Default:CheckIn:E] Running Loop=1
2023-12-22T21:30:34.716Z,1703280634.716 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 41.388558 min 
2023-12-22T21:30:34.716Z,1703280634.716 [Default:CheckIn:E] Stopped
2023-12-22T21:30:34.716Z,1703280634.716 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-22T21:30:34.716Z,1703280634.716 [Default:CheckIn] Stopped
2023-12-22T21:30:34.716Z,1703280634.716 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-22T21:30:34.716Z,1703280634.716 [Default:CheckIn](INFO): Running loop #8
2023-12-22T21:30:34.716Z,1703280634.716 [Default:CheckIn] Running Loop=8
2023-12-22T21:30:34.717Z,1703280634.717 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-22T21:30:34.717Z,1703280634.717 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-22T21:30:36.731Z,1703280636.731 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213036.00,A,3648.13818,N,12147.20751,W,0.097,263.47,221223,,,A*7B
2023-12-22T21:30:36.733Z,1703280636.733 [NAL9602](INFO): GPS fix at 20231222T213036: (36.802303, -121.786792)
2023-12-22T21:30:36.744Z,1703280636.744 [Default:CheckIn:Read_GPS] Stopped
2023-12-22T21:30:36.745Z,1703280636.745 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-22T21:30:44.203Z,1703280644.203 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231222T210248/Courier0018.lzma
2023-12-22T21:30:45.205Z,1703280645.205 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0018.lzma.bak
2023-12-22T21:30:45.206Z,1703280645.206 [DataOverHttps](INFO): SBD MOMSN=19130535
2023-12-22T21:31:01.352Z,1703280661.352 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20231222T210248/Express0019.lzma
2023-12-22T21:31:02.350Z,1703280662.350 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0019.lzma.bak
2023-12-22T21:31:02.350Z,1703280662.350 [DataOverHttps](INFO): SBD MOMSN=19130538
2023-12-22T21:31:02.584Z,1703280662.584 [NAL9602](INFO): SBD MO Status=2, MOMSN=14672, MT Status=2, MTMSN=0
2023-12-22T21:31:02.585Z,1703280662.585 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-12-22T21:31:03.810Z,1703280663.810 [Default:CheckIn:Read_Iridium] Stopped
2023-12-22T21:31:03.810Z,1703280663.810 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-22T21:31:03.810Z,1703280663.810 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-22T21:31:22.375Z,1703280682.375 [NAL9602](INFO): SBD MO Status=0, MOMSN=14672, MT Status=0, MTMSN=0
2023-12-22T21:31:22.375Z,1703280682.375 [NAL9602](INFO): No messages in MT queue
2023-12-22T21:31:53.076Z,1703280713.076 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-22T21:32:38.769Z,1703280758.769 [CBIT](IMPORTANT): Beginning ground fault scan
2023-12-22T21:32:49.689Z,1703280769.689 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.005804
CHAN A1 (24V): 0.000024
CHAN A2 (12V): -0.005516
CHAN A3 (5V): -0.002657
CHAN B0 (3.3V): -0.001873
CHAN B1 (3.15aV): -0.001269
CHAN B2 (3.15bV): -0.001491
CHAN B3 (GND): -0.001143
OPEN: 0.005003
Full Scale: +/- 1 mA
2023-12-22T21:36:04.380Z,1703280964.380 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-22T21:36:04.380Z,1703280964.380 [Default:CheckIn:C.Wait] Stopped
2023-12-22T21:36:04.380Z,1703280964.380 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-22T21:36:04.380Z,1703280964.380 [Default:CheckIn:D] Running Loop=1
2023-12-22T21:36:04.788Z,1703280964.788 [Default:CheckIn:D] Stopped
2023-12-22T21:36:04.788Z,1703280964.788 [Default:CheckIn:E] Running Loop=1
2023-12-22T21:36:05.194Z,1703280965.194 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 46.896497 min 
2023-12-22T21:36:05.194Z,1703280965.194 [Default:CheckIn:E] Stopped
2023-12-22T21:36:05.195Z,1703280965.195 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-22T21:36:05.195Z,1703280965.195 [Default:CheckIn] Stopped
2023-12-22T21:36:05.195Z,1703280965.195 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-22T21:36:05.195Z,1703280965.195 [Default:CheckIn](INFO): Running loop #9
2023-12-22T21:36:05.195Z,1703280965.195 [Default:CheckIn] Running Loop=9
2023-12-22T21:36:05.195Z,1703280965.195 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-22T21:36:05.195Z,1703280965.195 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-22T21:36:07.199Z,1703280967.199 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213606.00,A,3648.13734,N,12147.20871,W,0.097,263.47,221223,,,A*72
2023-12-22T21:36:07.202Z,1703280967.202 [NAL9602](INFO): GPS fix at 20231222T213606: (36.802289, -121.786812)
2023-12-22T21:36:07.234Z,1703280967.234 [Default:CheckIn:Read_GPS] Stopped
2023-12-22T21:36:07.234Z,1703280967.234 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-22T21:36:15.075Z,1703280975.075 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20231222T210248/Courier0021.lzma
2023-12-22T21:36:16.077Z,1703280976.077 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0021.lzma.bak
2023-12-22T21:36:16.078Z,1703280976.078 [DataOverHttps](INFO): SBD MOMSN=19130542
2023-12-22T21:36:21.738Z,1703280981.738 [NAL9602](INFO): SBD MO Status=0, MOMSN=14673, MT Status=0, MTMSN=0
2023-12-22T21:36:21.738Z,1703280981.738 [NAL9602](INFO): No messages in MT queue
2023-12-22T21:36:31.876Z,1703280991.876 [DataOverHttps](INFO): Sending 327 bytes from file Logs/20231222T210248/Express0022.lzma
2023-12-22T21:36:32.878Z,1703280992.878 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0022.lzma.bak
2023-12-22T21:36:32.878Z,1703280992.878 [DataOverHttps](INFO): SBD MOMSN=19130545
2023-12-22T21:36:34.282Z,1703280994.282 [Default:CheckIn:Read_Iridium] Stopped
2023-12-22T21:36:34.282Z,1703280994.282 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-22T21:36:34.282Z,1703280994.282 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-22T21:36:52.445Z,1703281012.445 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-22T21:41:34.846Z,1703281294.846 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-22T21:41:34.846Z,1703281294.846 [Default:CheckIn:C.Wait] Stopped
2023-12-22T21:41:34.846Z,1703281294.846 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-22T21:41:34.847Z,1703281294.847 [Default:CheckIn:D] Running Loop=1
2023-12-22T21:41:35.256Z,1703281295.256 [Default:CheckIn:D] Stopped
2023-12-22T21:41:35.256Z,1703281295.256 [Default:CheckIn:E] Running Loop=1
2023-12-22T21:41:35.669Z,1703281295.669 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.404301 min 
2023-12-22T21:41:35.669Z,1703281295.669 [Default:CheckIn:E] Stopped
2023-12-22T21:41:35.669Z,1703281295.669 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-22T21:41:35.669Z,1703281295.669 [Default:CheckIn] Stopped
2023-12-22T21:41:35.669Z,1703281295.669 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-22T21:41:35.669Z,1703281295.669 [Default:CheckIn](INFO): Running loop #10
2023-12-22T21:41:35.669Z,1703281295.669 [Default:CheckIn] Running Loop=10
2023-12-22T21:41:35.669Z,1703281295.669 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-22T21:41:35.669Z,1703281295.669 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-22T21:41:37.671Z,1703281297.671 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214136.00,A,3648.15777,N,12147.24650,W,2.410,8.15,221223,,,A*78
2023-12-22T21:41:37.673Z,1703281297.673 [NAL9602](INFO): GPS fix at 20231222T214136: (36.802630, -121.787442)
2023-12-22T21:41:37.708Z,1703281297.708 [Default:CheckIn:Read_GPS] Stopped
2023-12-22T21:41:37.708Z,1703281297.708 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-22T21:41:45.315Z,1703281305.315 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231222T210248/Courier0024.lzma
2023-12-22T21:41:46.318Z,1703281306.318 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0024.lzma.bak
2023-12-22T21:41:46.318Z,1703281306.318 [DataOverHttps](INFO): SBD MOMSN=19130551
2023-12-22T21:41:59.886Z,1703281319.886 [NAL9602](INFO): SBD MO Status=2, MOMSN=14674, MT Status=2, MTMSN=0
2023-12-22T21:41:59.886Z,1703281319.886 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-12-22T21:42:02.268Z,1703281322.268 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20231222T210248/Express0025.lzma
2023-12-22T21:42:03.272Z,1703281323.272 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0025.lzma.bak
2023-12-22T21:42:03.273Z,1703281323.273 [DataOverHttps](INFO): SBD MOMSN=19130554
2023-12-22T21:42:04.778Z,1703281324.778 [Default:CheckIn:Read_Iridium] Stopped
2023-12-22T21:42:04.778Z,1703281324.778 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-22T21:42:04.778Z,1703281324.778 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-22T21:42:25.754Z,1703281345.754 [NAL9602](INFO): SBD MO Status=2, MOMSN=14674, MT Status=2, MTMSN=0
2023-12-22T21:42:25.755Z,1703281345.755 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-12-22T21:42:39.491Z,1703281359.491 [NAL9602](INFO): SBD MO Status=2, MOMSN=14674, MT Status=2, MTMSN=0
2023-12-22T21:42:39.491Z,1703281359.491 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-12-22T21:45:20.686Z,1703281520.686 [NAL9602](INFO): SBD MO Status=2, MOMSN=14674, MT Status=2, MTMSN=0
2023-12-22T21:45:20.686Z,1703281520.686 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-12-22T21:46:40.293Z,1703281600.293 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-12-22T21:47:05.365Z,1703281625.365 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-22T21:47:05.365Z,1703281625.365 [Default:CheckIn:C.Wait] Stopped
2023-12-22T21:47:05.365Z,1703281625.365 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-22T21:47:05.365Z,1703281625.365 [Default:CheckIn:D] Running Loop=1
2023-12-22T21:47:05.760Z,1703281625.760 [Default:CheckIn:D] Stopped
2023-12-22T21:47:05.760Z,1703281625.760 [Default:CheckIn:E] Running Loop=1
2023-12-22T21:47:06.164Z,1703281626.164 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 57.912703 min 
2023-12-22T21:47:06.164Z,1703281626.164 [Default:CheckIn:E] Stopped
2023-12-22T21:47:06.164Z,1703281626.164 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-22T21:47:06.164Z,1703281626.164 [Default:CheckIn] Stopped
2023-12-22T21:47:06.164Z,1703281626.164 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-22T21:47:06.164Z,1703281626.164 [Default:CheckIn](INFO): Running loop #11
2023-12-22T21:47:06.164Z,1703281626.164 [Default:CheckIn] Running Loop=11
2023-12-22T21:47:06.165Z,1703281626.165 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-22T21:47:06.165Z,1703281626.165 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-22T21:47:08.171Z,1703281628.171 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214707.00,A,3648.17078,N,12147.27666,W,0.603,283.97,221223,,,A*79
2023-12-22T21:47:08.173Z,1703281628.173 [NAL9602](INFO): GPS fix at 20231222T214707: (36.802846, -121.787944)
2023-12-22T21:47:08.185Z,1703281628.185 [Default:CheckIn:Read_GPS] Stopped
2023-12-22T21:47:08.185Z,1703281628.185 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-22T21:47:15.139Z,1703281635.139 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20231222T210248/Courier0027.lzma
2023-12-22T21:47:16.142Z,1703281636.142 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0027.lzma.bak
2023-12-22T21:47:16.142Z,1703281636.142 [DataOverHttps](INFO): SBD MOMSN=19130557
2023-12-22T21:47:32.040Z,1703281652.040 [DataOverHttps](INFO): Sending 230 bytes from file Logs/20231222T210248/Express0028.lzma
2023-12-22T21:47:33.042Z,1703281653.042 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0028.lzma.bak
2023-12-22T21:47:33.042Z,1703281653.042 [DataOverHttps](INFO): SBD MOMSN=19130560
2023-12-22T21:47:34.486Z,1703281654.486 [Default:CheckIn:Read_Iridium] Stopped
2023-12-22T21:47:34.486Z,1703281654.486 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-22T21:47:34.486Z,1703281654.486 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-22T21:47:40.533Z,1703281660.533 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-22T21:52:35.073Z,1703281955.073 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-22T21:52:35.073Z,1703281955.073 [Default:CheckIn:C.Wait] Stopped
2023-12-22T21:52:35.073Z,1703281955.073 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-22T21:52:35.073Z,1703281955.073 [Default:CheckIn:D] Running Loop=1
2023-12-22T21:52:35.464Z,1703281955.464 [Default:CheckIn:D] Stopped
2023-12-22T21:52:35.464Z,1703281955.464 [Default:CheckIn:E] Running Loop=1
2023-12-22T21:52:35.869Z,1703281955.869 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.407764 min 
2023-12-22T21:52:35.869Z,1703281955.869 [Default:CheckIn:E] Stopped
2023-12-22T21:52:35.870Z,1703281955.870 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-22T21:52:35.870Z,1703281955.870 [Default:CheckIn] Stopped
2023-12-22T21:52:35.870Z,1703281955.870 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-22T21:52:35.870Z,1703281955.870 [Default:CheckIn](INFO): Running loop #12
2023-12-22T21:52:35.870Z,1703281955.870 [Default:CheckIn] Running Loop=12
2023-12-22T21:52:35.870Z,1703281955.870 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-22T21:52:35.870Z,1703281955.870 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-22T21:52:37.876Z,1703281957.876 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215237.00,A,3648.17765,N,12147.27806,W,0.292,178.27,221223,,,A*7D
2023-12-22T21:52:37.878Z,1703281957.878 [NAL9602](INFO): GPS fix at 20231222T215237: (36.802961, -121.787968)
2023-12-22T21:52:37.890Z,1703281957.890 [Default:CheckIn:Read_GPS] Stopped
2023-12-22T21:52:37.890Z,1703281957.890 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-22T21:52:45.559Z,1703281965.559 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20231222T210248/Courier0030.lzma
2023-12-22T21:52:46.562Z,1703281966.562 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0030.lzma.bak
2023-12-22T21:52:46.562Z,1703281966.562 [DataOverHttps](INFO): SBD MOMSN=19130606
2023-12-22T21:53:02.564Z,1703281982.564 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20231222T210248/Express0031.lzma
2023-12-22T21:53:03.566Z,1703281983.566 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0031.lzma.bak
2023-12-22T21:53:03.566Z,1703281983.566 [DataOverHttps](INFO): SBD MOMSN=19130609
2023-12-22T21:53:05.013Z,1703281985.013 [Default:CheckIn:Read_Iridium] Stopped
2023-12-22T21:53:05.013Z,1703281985.013 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-22T21:53:05.013Z,1703281985.013 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-22T21:53:08.629Z,1703281988.629 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-12-22T21:53:08.712Z,1703281988.712 [NAL9602](ERROR): received: 
+CSQ:0

OK674, 2, 0, 0, 0

OK
2023-12-22T21:55:13.870Z,1703282113.870 [NAL9602](INFO): SBD MO Status=2, MOMSN=14674, MT Status=2, MTMSN=0
2023-12-22T21:55:13.870Z,1703282113.870 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-12-22T21:57:40.521Z,1703282260.521 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-12-22T21:58:05.483Z,1703282285.483 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-22T21:58:05.484Z,1703282285.484 [Default:CheckIn:C.Wait] Stopped
2023-12-22T21:58:05.484Z,1703282285.484 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-22T21:58:05.484Z,1703282285.484 [Default:CheckIn:D] Running Loop=1
2023-12-22T21:58:05.845Z,1703282285.845 [Default:CheckIn:D] Stopped
2023-12-22T21:58:05.845Z,1703282285.845 [Default:CheckIn:E] Running Loop=1
2023-12-22T21:58:06.228Z,1703282286.228 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 68.914111 min 
2023-12-22T21:58:06.229Z,1703282286.229 [Default:CheckIn:E] Stopped
2023-12-22T21:58:06.229Z,1703282286.229 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-22T21:58:06.229Z,1703282286.229 [Default:CheckIn] Stopped
2023-12-22T21:58:06.229Z,1703282286.229 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-22T21:58:06.229Z,1703282286.229 [Default:CheckIn](INFO): Running loop #13
2023-12-22T21:58:06.229Z,1703282286.229 [Default:CheckIn] Running Loop=13
2023-12-22T21:58:06.229Z,1703282286.229 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-22T21:58:06.229Z,1703282286.229 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-22T21:58:08.211Z,1703282288.211 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215807.00,A,3648.17606,N,12147.27770,W,0.564,322.82,221223,,,A*72
2023-12-22T21:58:08.213Z,1703282288.213 [NAL9602](INFO): GPS fix at 20231222T215807: (36.802934, -121.787962)
2023-12-22T21:58:08.241Z,1703282288.241 [Default:CheckIn:Read_GPS] Stopped
2023-12-22T21:58:08.241Z,1703282288.241 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-22T21:58:15.595Z,1703282295.595 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20231222T210248/Courier0033.lzma
2023-12-22T21:58:16.598Z,1703282296.598 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0033.lzma.bak
2023-12-22T21:58:16.598Z,1703282296.598 [DataOverHttps](INFO): SBD MOMSN=19130612
2023-12-22T21:58:32.464Z,1703282312.464 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20231222T210248/Express0034.lzma
2023-12-22T21:58:33.466Z,1703282313.466 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0034.lzma.bak
2023-12-22T21:58:33.466Z,1703282313.466 [DataOverHttps](INFO): SBD MOMSN=19130615
2023-12-22T21:58:34.890Z,1703282314.890 [Default:CheckIn:Read_Iridium] Stopped
2023-12-22T21:58:34.890Z,1703282314.890 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-22T21:58:34.890Z,1703282314.890 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-22T21:58:40.524Z,1703282320.524 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-22T22:01:13.354Z,1703282473.354 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-12-22T22:01:13.361Z,1703282473.361 [BPC1](INFO): Received data from all battery sticks.
2023-12-22T22:03:35.559Z,1703282615.559 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-22T22:03:35.560Z,1703282615.560 [Default:CheckIn:C.Wait] Stopped
2023-12-22T22:03:35.560Z,1703282615.560 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-22T22:03:35.560Z,1703282615.560 [Default:CheckIn:D] Running Loop=1
2023-12-22T22:03:35.964Z,1703282615.964 [Default:CheckIn:D] Stopped
2023-12-22T22:03:35.964Z,1703282615.964 [Default:CheckIn:E] Running Loop=1
2023-12-22T22:03:36.373Z,1703282616.373 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 74.416097 min 
2023-12-22T22:03:36.373Z,1703282616.373 [Default:CheckIn:E] Stopped
2023-12-22T22:03:36.373Z,1703282616.373 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-22T22:03:36.373Z,1703282616.373 [Default:CheckIn] Stopped
2023-12-22T22:03:36.374Z,1703282616.374 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-22T22:03:36.374Z,1703282616.374 [Default:CheckIn](INFO): Running loop #14
2023-12-22T22:03:36.374Z,1703282616.374 [Default:CheckIn] Running Loop=14
2023-12-22T22:03:36.374Z,1703282616.374 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-22T22:03:36.374Z,1703282616.374 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-22T22:03:38.379Z,1703282618.379 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220337.00,A,3648.17623,N,12147.27524,W,0.292,322.82,221223,,,A*76
2023-12-22T22:03:38.381Z,1703282618.381 [NAL9602](INFO): GPS fix at 20231222T220337: (36.802937, -121.787921)
2023-12-22T22:03:38.413Z,1703282618.413 [Default:CheckIn:Read_GPS] Stopped
2023-12-22T22:03:38.413Z,1703282618.413 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-22T22:03:45.292Z,1703282625.292 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20231222T210248/Courier0036.lzma
2023-12-22T22:03:46.294Z,1703282626.294 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0036.lzma.bak
2023-12-22T22:03:46.294Z,1703282626.294 [DataOverHttps](INFO): SBD MOMSN=19130619
2023-12-22T22:04:02.672Z,1703282642.672 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20231222T210248/Express0037.lzma
2023-12-22T22:04:03.674Z,1703282643.674 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0037.lzma.bak
2023-12-22T22:04:03.674Z,1703282643.674 [DataOverHttps](INFO): SBD MOMSN=19130622
2023-12-22T22:04:05.094Z,1703282645.094 [Default:CheckIn:Read_Iridium] Stopped
2023-12-22T22:04:05.094Z,1703282645.094 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-22T22:04:05.094Z,1703282645.094 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-22T22:08:01.818Z,1703282881.818 [NAL9602](INFO): SBD MO Status=2, MOMSN=14674, MT Status=2, MTMSN=0
2023-12-22T22:08:01.818Z,1703282881.818 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-12-22T22:08:40.606Z,1703282920.606 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-12-22T22:09:05.690Z,1703282945.690 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-22T22:09:05.690Z,1703282945.690 [Default:CheckIn:C.Wait] Stopped
2023-12-22T22:09:05.690Z,1703282945.690 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-22T22:09:05.690Z,1703282945.690 [Default:CheckIn:D] Running Loop=1
2023-12-22T22:09:06.069Z,1703282946.069 [Default:CheckIn:D] Stopped
2023-12-22T22:09:06.069Z,1703282946.069 [Default:CheckIn:E] Running Loop=1
2023-12-22T22:09:06.472Z,1703282946.472 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 79.917822 min 
2023-12-22T22:09:06.472Z,1703282946.472 [Default:CheckIn:E] Stopped
2023-12-22T22:09:06.472Z,1703282946.472 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-22T22:09:06.472Z,1703282946.472 [Default:CheckIn] Stopped
2023-12-22T22:09:06.472Z,1703282946.472 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-22T22:09:06.472Z,1703282946.472 [Default:CheckIn](INFO): Running loop #15
2023-12-22T22:09:06.472Z,1703282946.472 [Default:CheckIn] Running Loop=15
2023-12-22T22:09:06.473Z,1703282946.473 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-22T22:09:06.473Z,1703282946.473 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-22T22:09:08.483Z,1703282948.483 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220907.00,A,3648.17609,N,12147.27733,W,0.058,325.36,221223,,,A*7F
2023-12-22T22:09:08.485Z,1703282948.485 [NAL9602](INFO): GPS fix at 20231222T220907: (36.802935, -121.787955)
2023-12-22T22:09:08.517Z,1703282948.517 [Default:CheckIn:Read_GPS] Stopped
2023-12-22T22:09:08.517Z,1703282948.517 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-22T22:09:16.139Z,1703282956.139 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20231222T210248/Courier0039.lzma
2023-12-22T22:09:17.142Z,1703282957.142 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0039.lzma.bak
2023-12-22T22:09:17.142Z,1703282957.142 [DataOverHttps](INFO): SBD MOMSN=19130630
2023-12-22T22:09:33.116Z,1703282973.116 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20231222T210248/Express0040.lzma
2023-12-22T22:09:34.118Z,1703282974.118 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0040.lzma.bak
2023-12-22T22:09:34.118Z,1703282974.118 [DataOverHttps](INFO): SBD MOMSN=19130633
2023-12-22T22:09:35.578Z,1703282975.578 [Default:CheckIn:Read_Iridium] Stopped
2023-12-22T22:09:35.578Z,1703282975.578 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-22T22:09:35.578Z,1703282975.578 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-22T22:09:40.397Z,1703282980.397 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-22T22:09:49.435Z,1703282989.435 [DAT](INFO): got valid direction response:
 22:09:48.6862 LVL=  1744,  2545,  1906,  2355, AGC= 66, IDX= 219, 0.13,-1.986,-1.886,-1.767,-2.056, PHS= 0.039, 0.143, 0.285, RAW= 234.9, -37.7, CAL= 234.0, -41.1, ROT= 276.0,  41.1

2023-12-22T22:09:49.437Z,1703282989.437 [DAT](INFO): Rx dataTimestamp_ set to:1703282988.686199
2023-12-22T22:09:49.437Z,1703282989.437 [DAT](INFO): received an acoustic signal
2023-12-22T22:09:49.438Z,1703282989.438 [DAT](INFO): Received low SNR in chirp
2023-12-22T22:09:49.452Z,1703282989.452 [DAT](INFO): #Rx 221: Read direction message, but no range.
2023-12-22T22:14:36.163Z,1703283276.163 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-22T22:14:36.163Z,1703283276.163 [Default:CheckIn:C.Wait] Stopped
2023-12-22T22:14:36.163Z,1703283276.163 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-22T22:14:36.163Z,1703283276.163 [Default:CheckIn:D] Running Loop=1
2023-12-22T22:14:36.544Z,1703283276.544 [Default:CheckIn:D] Stopped
2023-12-22T22:14:36.544Z,1703283276.544 [Default:CheckIn:E] Running Loop=1
2023-12-22T22:14:36.948Z,1703283276.948 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 85.425765 min 
2023-12-22T22:14:36.948Z,1703283276.948 [Default:CheckIn:E] Stopped
2023-12-22T22:14:36.948Z,1703283276.948 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-22T22:14:36.948Z,1703283276.948 [Default:CheckIn] Stopped
2023-12-22T22:14:36.948Z,1703283276.948 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-22T22:14:36.949Z,1703283276.949 [Default:CheckIn](INFO): Running loop #16
2023-12-22T22:14:36.949Z,1703283276.949 [Default:CheckIn] Running Loop=16
2023-12-22T22:14:36.949Z,1703283276.949 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-22T22:14:36.949Z,1703283276.949 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-22T22:14:38.959Z,1703283278.959 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221438.00,A,3648.17559,N,12147.27909,W,0.194,296.24,221223,,,A*75
2023-12-22T22:14:38.969Z,1703283278.969 [NAL9602](INFO): GPS fix at 20231222T221438: (36.802926, -121.787985)
2023-12-22T22:14:38.981Z,1703283278.981 [Default:CheckIn:Read_GPS] Stopped
2023-12-22T22:14:38.981Z,1703283278.981 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-22T22:14:46.688Z,1703283286.688 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20231222T210248/Courier0042.lzma
2023-12-22T22:14:47.690Z,1703283287.690 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0042.lzma.bak
2023-12-22T22:14:47.690Z,1703283287.690 [DataOverHttps](INFO): SBD MOMSN=19130673
2023-12-22T22:15:03.620Z,1703283303.620 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20231222T210248/Express0043.lzma
2023-12-22T22:15:04.622Z,1703283304.622 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0043.lzma.bak
2023-12-22T22:15:04.622Z,1703283304.622 [DataOverHttps](INFO): SBD MOMSN=19130724
2023-12-22T22:15:06.045Z,1703283306.045 [Default:CheckIn:Read_Iridium] Stopped
2023-12-22T22:15:06.045Z,1703283306.045 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-22T22:15:06.045Z,1703283306.045 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-22T22:15:09.661Z,1703283309.661 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-12-22T22:15:09.744Z,1703283309.744 [NAL9602](ERROR): received: 
+CSQ:0

OK674, 2, 0, 0, 0

OK
2023-12-22T22:19:27.410Z,1703283567.410 [NAL9602](INFO): SBD MO Status=2, MOMSN=14674, MT Status=2, MTMSN=0
2023-12-22T22:19:27.410Z,1703283567.410 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-12-22T22:19:49.235Z,1703283589.235 [NAL9602](INFO): SBD MO Status=2, MOMSN=14674, MT Status=2, MTMSN=0
2023-12-22T22:19:49.235Z,1703283589.235 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-12-22T22:19:49.629Z,1703283589.629 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-12-22T22:20:06.608Z,1703283606.608 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-22T22:20:06.608Z,1703283606.608 [Default:CheckIn:C.Wait] Stopped
2023-12-22T22:20:06.608Z,1703283606.608 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-22T22:20:06.608Z,1703283606.608 [Default:CheckIn:D] Running Loop=1
2023-12-22T22:20:07.026Z,1703283607.026 [Default:CheckIn:D] Stopped
2023-12-22T22:20:07.026Z,1703283607.026 [Default:CheckIn:E] Running Loop=1
2023-12-22T22:20:07.421Z,1703283607.421 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 90.933797 min 
2023-12-22T22:20:07.421Z,1703283607.421 [Default:CheckIn:E] Stopped
2023-12-22T22:20:07.421Z,1703283607.421 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-22T22:20:07.421Z,1703283607.421 [Default:CheckIn] Stopped
2023-12-22T22:20:07.422Z,1703283607.422 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-22T22:20:07.422Z,1703283607.422 [Default:CheckIn](INFO): Running loop #17
2023-12-22T22:20:07.422Z,1703283607.422 [Default:CheckIn] Running Loop=17
2023-12-22T22:20:07.422Z,1703283607.422 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-22T22:20:07.422Z,1703283607.422 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-22T22:20:09.427Z,1703283609.427 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222008.00,A,3648.17733,N,12147.27033,W,0.253,296.24,221223,,,A*77
2023-12-22T22:20:09.429Z,1703283609.429 [NAL9602](INFO): GPS fix at 20231222T222008: (36.802956, -121.787839)
2023-12-22T22:20:09.442Z,1703283609.442 [Default:CheckIn:Read_GPS] Stopped
2023-12-22T22:20:09.442Z,1703283609.442 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-22T22:20:16.419Z,1703283616.419 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231222T210248/Courier0045.lzma
2023-12-22T22:20:17.422Z,1703283617.422 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0045.lzma.bak
2023-12-22T22:20:17.422Z,1703283617.422 [DataOverHttps](INFO): SBD MOMSN=19130763
2023-12-22T22:20:33.664Z,1703283633.664 [DataOverHttps](INFO): Sending 205 bytes from file Logs/20231222T210248/Express0046.lzma
2023-12-22T22:20:34.666Z,1703283634.666 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0046.lzma.bak
2023-12-22T22:20:34.666Z,1703283634.666 [DataOverHttps](INFO): SBD MOMSN=19130768
2023-12-22T22:20:36.114Z,1703283636.114 [Default:CheckIn:Read_Iridium] Stopped
2023-12-22T22:20:36.114Z,1703283636.114 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-22T22:20:36.114Z,1703283636.114 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-22T22:20:41.746Z,1703283641.746 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-22T22:25:36.689Z,1703283936.689 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-22T22:25:36.689Z,1703283936.689 [Default:CheckIn:C.Wait] Stopped
2023-12-22T22:25:36.690Z,1703283936.690 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-22T22:25:36.690Z,1703283936.690 [Default:CheckIn:D] Running Loop=1
2023-12-22T22:25:37.080Z,1703283937.080 [Default:CheckIn:D] Stopped
2023-12-22T22:25:37.080Z,1703283937.080 [Default:CheckIn:E] Running Loop=1
2023-12-22T22:25:37.484Z,1703283937.484 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 96.434692 min 
2023-12-22T22:25:37.484Z,1703283937.484 [Default:CheckIn:E] Stopped
2023-12-22T22:25:37.484Z,1703283937.484 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-22T22:25:37.484Z,1703283937.484 [Default:CheckIn] Stopped
2023-12-22T22:25:37.484Z,1703283937.484 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-22T22:25:37.484Z,1703283937.484 [Default:CheckIn](INFO): Running loop #18
2023-12-22T22:25:37.484Z,1703283937.484 [Default:CheckIn] Running Loop=18
2023-12-22T22:25:37.485Z,1703283937.485 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-22T22:25:37.485Z,1703283937.485 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-22T22:25:39.503Z,1703283939.503 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222538.00,A,3648.17081,N,12147.27731,W,0.253,296.24,221223,,,A*7A
2023-12-22T22:25:39.505Z,1703283939.505 [NAL9602](INFO): GPS fix at 20231222T222538: (36.802847, -121.787955)
2023-12-22T22:25:39.517Z,1703283939.517 [Default:CheckIn:Read_GPS] Stopped
2023-12-22T22:25:39.517Z,1703283939.517 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-22T22:25:46.508Z,1703283946.508 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20231222T210248/Courier0048.lzma
2023-12-22T22:25:47.510Z,1703283947.510 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0048.lzma.bak
2023-12-22T22:25:47.510Z,1703283947.510 [DataOverHttps](INFO): SBD MOMSN=19130806
2023-12-22T22:26:03.404Z,1703283963.404 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20231222T210248/Express0049.lzma
2023-12-22T22:26:04.398Z,1703283964.398 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0049.lzma.bak
2023-12-22T22:26:04.398Z,1703283964.398 [DataOverHttps](INFO): SBD MOMSN=19130809
2023-12-22T22:26:05.790Z,1703283965.790 [Default:CheckIn:Read_Iridium] Stopped
2023-12-22T22:26:05.790Z,1703283965.790 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-22T22:26:05.790Z,1703283965.790 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-22T22:26:39.714Z,1703283999.714 [NAL9602](INFO): SBD MO Status=0, MOMSN=14674, MT Status=0, MTMSN=0
2023-12-22T22:26:39.714Z,1703283999.714 [NAL9602](INFO): No messages in MT queue
2023-12-22T22:27:10.412Z,1703284030.412 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-22T22:31:06.363Z,1703284266.363 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-22T22:31:06.364Z,1703284266.364 [Default:CheckIn:C.Wait] Stopped
2023-12-22T22:31:06.364Z,1703284266.364 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-22T22:31:06.364Z,1703284266.364 [Default:CheckIn:D] Running Loop=1
2023-12-22T22:31:06.847Z,1703284266.847 [Default:CheckIn:D] Stopped
2023-12-22T22:31:06.848Z,1703284266.848 [Default:CheckIn:E] Running Loop=1
2023-12-22T22:31:07.177Z,1703284267.177 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 101.930819 min 
2023-12-22T22:31:07.177Z,1703284267.177 [Default:CheckIn:E] Stopped
2023-12-22T22:31:07.177Z,1703284267.177 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-22T22:31:07.177Z,1703284267.177 [Default:CheckIn] Stopped
2023-12-22T22:31:07.177Z,1703284267.177 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-22T22:31:07.177Z,1703284267.177 [Default:CheckIn](INFO): Running loop #19
2023-12-22T22:31:07.177Z,1703284267.177 [Default:CheckIn] Running Loop=19
2023-12-22T22:31:07.177Z,1703284267.177 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-22T22:31:07.177Z,1703284267.177 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-22T22:31:09.179Z,1703284269.179 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223108.00,A,3648.17118,N,12147.27774,W,0.097,0.00,221223,,,A*7D
2023-12-22T22:31:09.182Z,1703284269.182 [NAL9602](INFO): GPS fix at 20231222T223108: (36.802853, -121.787962)
2023-12-22T22:31:09.193Z,1703284269.193 [Default:CheckIn:Read_GPS] Stopped
2023-12-22T22:31:09.194Z,1703284269.194 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-22T22:31:16.395Z,1703284276.395 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231222T210248/Courier0051.lzma
2023-12-22T22:31:17.398Z,1703284277.398 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0051.lzma.bak
2023-12-22T22:31:17.398Z,1703284277.398 [DataOverHttps](INFO): SBD MOMSN=19130815
2023-12-22T22:31:33.376Z,1703284293.376 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20231222T210248/Express0052.lzma
2023-12-22T22:31:33.458Z,1703284293.458 [NAL9602](INFO): SBD MO Status=0, MOMSN=14675, MT Status=0, MTMSN=0
2023-12-22T22:31:33.459Z,1703284293.459 [NAL9602](INFO): No messages in MT queue
2023-12-22T22:31:34.379Z,1703284294.379 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0052.lzma.bak
2023-12-22T22:31:34.379Z,1703284294.379 [DataOverHttps](INFO): SBD MOMSN=19130824
2023-12-22T22:31:35.898Z,1703284295.898 [Default:CheckIn:Read_Iridium] Stopped
2023-12-22T22:31:35.898Z,1703284295.898 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-22T22:31:35.898Z,1703284295.898 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-22T22:31:41.535Z,1703284301.535 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2023-12-22T22:31:41.535Z,1703284301.535 [DropWeight] Hardware Fault, FailCount= 1
2023-12-22T22:31:41.535Z,1703284301.535 [DropWeight](ERROR): Hardware Fault
2023-12-22T22:31:41.554Z,1703284301.554 [CommandExec](FAULT): Scheduling is paused
2023-12-22T22:31:41.555Z,1703284301.555 [CBIT](INFO): Critical error at 20231222T223141
2023-12-22T22:31:41.557Z,1703284301.557 [CBIT](ERROR): Hardware Fault in component: DropWeight
2023-12-22T22:31:41.558Z,1703284301.558 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2023-12-22T22:31:41.965Z,1703284301.965 [CBIT](INFO): Critical error at 20231222T223141
2023-12-22T22:32:04.227Z,1703284324.227 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-22T22:32:49.946Z,1703284369.946 [CBIT](IMPORTANT): Beginning ground fault scan
2023-12-22T22:33:00.821Z,1703284380.821 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.005433
CHAN A1 (24V): 0.000639
CHAN A2 (12V): -0.004810
CHAN A3 (5V): -0.003752
CHAN B0 (3.3V): -0.000866
CHAN B1 (3.15aV): -0.000890
CHAN B2 (3.15bV): -0.001735
CHAN B3 (GND): -0.000604
OPEN: 0.004635
Full Scale: +/- 1 mA
2023-12-22T22:36:36.594Z,1703284596.594 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-22T22:36:36.594Z,1703284596.594 [Default:CheckIn:C.Wait] Stopped
2023-12-22T22:36:36.594Z,1703284596.594 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-22T22:36:36.595Z,1703284596.595 [Default:CheckIn:D] Running Loop=1
2023-12-22T22:36:37.014Z,1703284597.014 [Default:CheckIn:D] Stopped
2023-12-22T22:36:37.014Z,1703284597.014 [Default:CheckIn:E] Running Loop=1
2023-12-22T22:36:37.394Z,1703284597.394 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 107.433594 min 
2023-12-22T22:36:37.394Z,1703284597.394 [Default:CheckIn:E] Stopped
2023-12-22T22:36:37.394Z,1703284597.394 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-22T22:36:37.394Z,1703284597.394 [Default:CheckIn] Stopped
2023-12-22T22:36:37.395Z,1703284597.395 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-22T22:36:37.395Z,1703284597.395 [Default:CheckIn](INFO): Running loop #20
2023-12-22T22:36:37.395Z,1703284597.395 [Default:CheckIn] Running Loop=20
2023-12-22T22:36:37.395Z,1703284597.395 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-22T22:36:37.395Z,1703284597.395 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-22T22:36:39.397Z,1703284599.397 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223638.00,A,3648.16871,N,12147.27752,W,0.194,0.00,221223,,,A*78
2023-12-22T22:36:39.399Z,1703284599.399 [NAL9602](INFO): GPS fix at 20231222T223638: (36.802812, -121.787959)
2023-12-22T22:36:39.427Z,1703284599.427 [Default:CheckIn:Read_GPS] Stopped
2023-12-22T22:36:39.427Z,1703284599.427 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-22T22:36:46.720Z,1703284606.720 [DataOverHttps](INFO): Sending 181 bytes from file Logs/20231222T210248/Courier0054.lzma
2023-12-22T22:36:47.722Z,1703284607.722 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0054.lzma.bak
2023-12-22T22:36:47.722Z,1703284607.722 [DataOverHttps](INFO): SBD MOMSN=19130873
2023-12-22T22:36:52.330Z,1703284612.330 [NAL9602](INFO): SBD MO Status=0, MOMSN=14676, MT Status=0, MTMSN=0
2023-12-22T22:36:52.330Z,1703284612.330 [NAL9602](INFO): No messages in MT queue
2023-12-22T22:37:03.653Z,1703284623.653 [DataOverHttps](INFO): Sending 332 bytes from file Logs/20231222T210248/Express0055.lzma
2023-12-22T22:37:04.654Z,1703284624.654 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0055.lzma.bak
2023-12-22T22:37:04.654Z,1703284624.654 [DataOverHttps](INFO): SBD MOMSN=19130878
2023-12-22T22:37:06.093Z,1703284626.093 [Default:CheckIn:Read_Iridium] Stopped
2023-12-22T22:37:06.093Z,1703284626.093 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-22T22:37:06.093Z,1703284626.093 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-22T22:37:23.029Z,1703284643.029 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-22T22:42:06.655Z,1703284926.655 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-22T22:42:06.655Z,1703284926.655 [Default:CheckIn:C.Wait] Stopped
2023-12-22T22:42:06.672Z,1703284926.672 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-22T22:42:06.672Z,1703284926.672 [Default:CheckIn:D] Running Loop=1
2023-12-22T22:42:07.061Z,1703284927.061 [Default:CheckIn:D] Stopped
2023-12-22T22:42:07.061Z,1703284927.061 [Default:CheckIn:E] Running Loop=1
2023-12-22T22:42:07.464Z,1703284927.464 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 112.934375 min 
2023-12-22T22:42:07.465Z,1703284927.465 [Default:CheckIn:E] Stopped
2023-12-22T22:42:07.465Z,1703284927.465 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-22T22:42:07.465Z,1703284927.465 [Default:CheckIn] Stopped
2023-12-22T22:42:07.465Z,1703284927.465 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-22T22:42:07.465Z,1703284927.465 [Default:CheckIn](INFO): Running loop #21
2023-12-22T22:42:07.465Z,1703284927.465 [Default:CheckIn] Running Loop=21
2023-12-22T22:42:07.465Z,1703284927.465 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-22T22:42:07.465Z,1703284927.465 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-22T22:42:09.466Z,1703284929.466 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224208.00,A,3648.17105,N,12147.27847,W,0.544,0.00,221223,,,A*71
2023-12-22T22:42:09.469Z,1703284929.469 [NAL9602](INFO): GPS fix at 20231222T224208: (36.802851, -121.787975)
2023-12-22T22:42:09.502Z,1703284929.502 [Default:CheckIn:Read_GPS] Stopped
2023-12-22T22:42:09.502Z,1703284929.502 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-22T22:42:17.247Z,1703284937.247 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231222T210248/Courier0057.lzma
2023-12-22T22:42:18.250Z,1703284938.250 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0057.lzma.bak
2023-12-22T22:42:18.250Z,1703284938.250 [DataOverHttps](INFO): SBD MOMSN=19131014
2023-12-22T22:42:34.676Z,1703284954.676 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20231222T210248/Express0058.lzma
2023-12-22T22:42:35.678Z,1703284955.678 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0058.lzma.bak
2023-12-22T22:42:35.678Z,1703284955.678 [DataOverHttps](INFO): SBD MOMSN=19131019
2023-12-22T22:42:36.966Z,1703284956.966 [Default:CheckIn:Read_Iridium] Stopped
2023-12-22T22:42:36.966Z,1703284956.966 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-22T22:42:36.966Z,1703284956.966 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-22T22:45:13.690Z,1703285113.690 [NAL9602](INFO): SBD MO Status=0, MOMSN=14677, MT Status=0, MTMSN=0
2023-12-22T22:45:13.690Z,1703285113.690 [NAL9602](INFO): No messages in MT queue
2023-12-22T22:45:44.393Z,1703285144.393 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-22T22:46:41.781Z,1703285201.781 [CBIT](INFO): Clearing failed state for component DropWeight
2023-12-22T22:46:41.781Z,1703285201.781 [DropWeight] No Fault, FailCount= 1
2023-12-22T22:47:37.534Z,1703285257.534 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-22T22:47:37.534Z,1703285257.534 [Default:CheckIn:C.Wait] Stopped
2023-12-22T22:47:37.534Z,1703285257.534 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-22T22:47:37.535Z,1703285257.535 [Default:CheckIn:D] Running Loop=1
2023-12-22T22:47:37.942Z,1703285257.942 [Default:CheckIn:D] Stopped
2023-12-22T22:47:37.942Z,1703285257.942 [Default:CheckIn:E] Running Loop=1
2023-12-22T22:47:38.354Z,1703285258.354 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 118.449064 min 
2023-12-22T22:47:38.354Z,1703285258.354 [Default:CheckIn:E] Stopped
2023-12-22T22:47:38.354Z,1703285258.354 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-22T22:47:38.354Z,1703285258.354 [Default:CheckIn] Stopped
2023-12-22T22:47:38.354Z,1703285258.354 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-22T22:47:38.355Z,1703285258.355 [Default:CheckIn](INFO): Running loop #22
2023-12-22T22:47:38.355Z,1703285258.355 [Default:CheckIn] Running Loop=22
2023-12-22T22:47:38.355Z,1703285258.355 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-22T22:47:38.355Z,1703285258.355 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-22T22:47:40.347Z,1703285260.347 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224739.00,A,3648.16962,N,12147.27060,W,0.194,0.00,221223,,,A*7A
2023-12-22T22:47:40.349Z,1703285260.349 [NAL9602](INFO): GPS fix at 20231222T224739: (36.802827, -121.787843)
2023-12-22T22:47:40.361Z,1703285260.361 [Default:CheckIn:Read_GPS] Stopped
2023-12-22T22:47:40.361Z,1703285260.361 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-22T22:47:47.235Z,1703285267.235 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20231222T210248/Courier0060.lzma
2023-12-22T22:47:48.238Z,1703285268.238 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0060.lzma.bak
2023-12-22T22:47:48.238Z,1703285268.238 [DataOverHttps](INFO): SBD MOMSN=19131109
2023-12-22T22:47:49.675Z,1703285269.675 [NAL9602](INFO): SBD MO Status=0, MOMSN=14678, MT Status=0, MTMSN=0
2023-12-22T22:47:49.675Z,1703285269.675 [NAL9602](INFO): No messages in MT queue
2023-12-22T22:48:04.260Z,1703285284.260 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20231222T210248/Express0061.lzma
2023-12-22T22:48:05.262Z,1703285285.262 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0061.lzma.bak
2023-12-22T22:48:05.262Z,1703285285.262 [DataOverHttps](INFO): SBD MOMSN=19131112
2023-12-22T22:48:06.662Z,1703285286.662 [Default:CheckIn:Read_Iridium] Stopped
2023-12-22T22:48:06.662Z,1703285286.662 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-22T22:48:06.662Z,1703285286.662 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-22T22:48:20.373Z,1703285300.373 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-22T22:53:07.240Z,1703285587.240 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-22T22:53:07.240Z,1703285587.240 [Default:CheckIn:C.Wait] Stopped
2023-12-22T22:53:07.240Z,1703285587.240 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-22T22:53:07.240Z,1703285587.240 [Default:CheckIn:D] Running Loop=1
2023-12-22T22:53:07.624Z,1703285587.624 [Default:CheckIn:D] Stopped
2023-12-22T22:53:07.624Z,1703285587.624 [Default:CheckIn:E] Running Loop=1
2023-12-22T22:53:08.032Z,1703285588.032 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 123.943766 min 
2023-12-22T22:53:08.032Z,1703285588.032 [Default:CheckIn:E] Stopped
2023-12-22T22:53:08.032Z,1703285588.032 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-22T22:53:08.032Z,1703285588.032 [Default:CheckIn] Stopped
2023-12-22T22:53:08.032Z,1703285588.032 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-22T22:53:08.032Z,1703285588.032 [Default:CheckIn](INFO): Running loop #23
2023-12-22T22:53:08.033Z,1703285588.033 [Default:CheckIn] Running Loop=23
2023-12-22T22:53:08.033Z,1703285588.033 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-22T22:53:08.033Z,1703285588.033 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-22T22:53:10.039Z,1703285590.039 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,225309.00,A,3648.16886,N,12147.27565,W,0.058,0.00,221223,,,A*76
2023-12-22T22:53:10.042Z,1703285590.042 [NAL9602](INFO): GPS fix at 20231222T225309: (36.802814, -121.787927)
2023-12-22T22:53:10.053Z,1703285590.053 [Default:CheckIn:Read_GPS] Stopped
2023-12-22T22:53:10.053Z,1703285590.053 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-22T22:53:17.707Z,1703285597.707 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20231222T210248/Courier0063.lzma
2023-12-22T22:53:18.710Z,1703285598.710 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0063.lzma.bak
2023-12-22T22:53:18.710Z,1703285598.710 [DataOverHttps](INFO): SBD MOMSN=19131299
2023-12-22T22:53:34.764Z,1703285614.764 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20231222T210248/Express0064.lzma
2023-12-22T22:53:35.766Z,1703285615.766 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0064.lzma.bak
2023-12-22T22:53:35.766Z,1703285615.766 [DataOverHttps](INFO): SBD MOMSN=19131302
2023-12-22T22:53:37.153Z,1703285617.153 [Default:CheckIn:Read_Iridium] Stopped
2023-12-22T22:53:37.153Z,1703285617.153 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-22T22:53:37.153Z,1703285617.153 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-22T22:54:36.103Z,1703285676.103 [NAL9602](INFO): SBD MO Status=0, MOMSN=14679, MT Status=0, MTMSN=0
2023-12-22T22:54:36.103Z,1703285676.103 [NAL9602](INFO): No messages in MT queue
2023-12-22T22:55:06.797Z,1703285706.797 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-22T22:58:37.693Z,1703285917.693 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-22T22:58:37.693Z,1703285917.693 [Default:CheckIn:C.Wait] Stopped
2023-12-22T22:58:37.694Z,1703285917.694 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-22T22:58:37.694Z,1703285917.694 [Default:CheckIn:D] Running Loop=1
2023-12-22T22:58:38.096Z,1703285918.096 [Default:CheckIn:D] Stopped
2023-12-22T22:58:38.096Z,1703285918.096 [Default:CheckIn:E] Running Loop=1
2023-12-22T22:58:38.511Z,1703285918.511 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 129.451628 min 
2023-12-22T22:58:38.512Z,1703285918.512 [Default:CheckIn:E] Stopped
2023-12-22T22:58:38.512Z,1703285918.512 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-22T22:58:38.512Z,1703285918.512 [Default:CheckIn] Stopped
2023-12-22T22:58:38.512Z,1703285918.512 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-22T22:58:38.512Z,1703285918.512 [Default:CheckIn](INFO): Running loop #24
2023-12-22T22:58:38.512Z,1703285918.512 [Default:CheckIn] Running Loop=24
2023-12-22T22:58:38.512Z,1703285918.512 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-22T22:58:38.512Z,1703285918.512 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-22T22:58:40.509Z,1703285920.509 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,225839.00,A,3648.17073,N,12147.28340,W,0.156,0.00,221223,,,A*7C
2023-12-22T22:58:40.512Z,1703285920.512 [NAL9602](INFO): GPS fix at 20231222T225839: (36.802845, -121.788057)
2023-12-22T22:58:40.540Z,1703285920.540 [Default:CheckIn:Read_GPS] Stopped
2023-12-22T22:58:40.540Z,1703285920.540 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-22T22:58:48.160Z,1703285928.160 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20231222T210248/Courier0066.lzma
2023-12-22T22:58:49.162Z,1703285929.162 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0066.lzma.bak
2023-12-22T22:58:49.162Z,1703285929.162 [DataOverHttps](INFO): SBD MOMSN=19131307
2023-12-22T22:59:05.170Z,1703285945.170 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20231222T210248/Express0067.lzma
2023-12-22T22:59:06.170Z,1703285946.170 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0067.lzma.bak
2023-12-22T22:59:06.170Z,1703285946.170 [DataOverHttps](INFO): SBD MOMSN=19131310
2023-12-22T22:59:07.598Z,1703285947.598 [Default:CheckIn:Read_Iridium] Stopped
2023-12-22T22:59:07.598Z,1703285947.598 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-22T22:59:07.598Z,1703285947.598 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-22T23:03:48.866Z,1703286228.866 [NAL9602](INFO): SBD MO Status=0, MOMSN=14680, MT Status=0, MTMSN=0
2023-12-22T23:03:48.866Z,1703286228.866 [NAL9602](INFO): No messages in MT queue
2023-12-22T23:04:08.300Z,1703286248.300 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-22T23:04:08.300Z,1703286248.300 [Default:CheckIn:C.Wait] Stopped
2023-12-22T23:04:08.301Z,1703286248.301 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-22T23:04:08.301Z,1703286248.301 [Default:CheckIn:D] Running Loop=1
2023-12-22T23:04:08.730Z,1703286248.730 [Default:CheckIn:D] Stopped
2023-12-22T23:04:08.730Z,1703286248.730 [Default:CheckIn:E] Running Loop=1
2023-12-22T23:04:09.103Z,1703286249.103 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 134.962199 min 
2023-12-22T23:04:09.103Z,1703286249.103 [Default:CheckIn:E] Stopped
2023-12-22T23:04:09.103Z,1703286249.103 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-22T23:04:09.103Z,1703286249.103 [Default:CheckIn] Stopped
2023-12-22T23:04:09.103Z,1703286249.103 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-22T23:04:09.108Z,1703286249.108 [Default:CheckIn](INFO): Running loop #25
2023-12-22T23:04:09.108Z,1703286249.108 [Default:CheckIn] Running Loop=25
2023-12-22T23:04:09.108Z,1703286249.108 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-22T23:04:09.108Z,1703286249.108 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-22T23:04:11.087Z,1703286251.087 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230410.00,A,3648.17279,N,12147.27543,W,1.438,299.18,221223,,,A*7A
2023-12-22T23:04:11.089Z,1703286251.089 [NAL9602](INFO): GPS fix at 20231222T230410: (36.802880, -121.787924)
2023-12-22T23:04:11.122Z,1703286251.122 [Default:CheckIn:Read_GPS] Stopped
2023-12-22T23:04:11.122Z,1703286251.122 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-22T23:04:18.295Z,1703286258.295 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20231222T210248/Courier0069.lzma
2023-12-22T23:04:19.300Z,1703286259.300 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0069.lzma.bak
2023-12-22T23:04:19.300Z,1703286259.300 [DataOverHttps](INFO): SBD MOMSN=19131313
2023-12-22T23:04:35.408Z,1703286275.408 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20231222T210248/Express0070.lzma
2023-12-22T23:04:36.410Z,1703286276.410 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0070.lzma.bak
2023-12-22T23:04:36.410Z,1703286276.410 [DataOverHttps](INFO): SBD MOMSN=19131316
2023-12-22T23:04:37.766Z,1703286277.766 [Default:CheckIn:Read_Iridium] Stopped
2023-12-22T23:04:37.766Z,1703286277.766 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-22T23:04:37.767Z,1703286277.767 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-22T23:04:43.401Z,1703286283.401 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-22T23:07:02.375Z,1703286422.375 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-12-22T23:08:37.728Z,1703286517.728 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-12-22T23:08:37.731Z,1703286517.731 [BPC1](INFO): Received data from all battery sticks.
2023-12-22T23:09:38.332Z,1703286578.332 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-22T23:09:38.332Z,1703286578.332 [Default:CheckIn:C.Wait] Stopped
2023-12-22T23:09:38.332Z,1703286578.332 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-22T23:09:38.332Z,1703286578.332 [Default:CheckIn:D] Running Loop=1
2023-12-22T23:09:38.783Z,1703286578.783 [Default:CheckIn:D] Stopped
2023-12-22T23:09:38.783Z,1703286578.783 [Default:CheckIn:E] Running Loop=1
2023-12-22T23:09:39.146Z,1703286579.146 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 140.463070 min 
2023-12-22T23:09:39.146Z,1703286579.146 [Default:CheckIn:E] Stopped
2023-12-22T23:09:39.146Z,1703286579.146 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-22T23:09:39.146Z,1703286579.146 [Default:CheckIn] Stopped
2023-12-22T23:09:39.146Z,1703286579.146 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-22T23:09:39.147Z,1703286579.147 [Default:CheckIn](INFO): Running loop #26
2023-12-22T23:09:39.147Z,1703286579.147 [Default:CheckIn] Running Loop=26
2023-12-22T23:09:39.147Z,1703286579.147 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-22T23:09:39.147Z,1703286579.147 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-22T23:09:41.155Z,1703286581.155 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230940.00,A,3648.17107,N,12147.27320,W,1.050,299.18,221223,,,A*71
2023-12-22T23:09:41.166Z,1703286581.166 [NAL9602](INFO): GPS fix at 20231222T230940: (36.802851, -121.787887)
2023-12-22T23:09:41.179Z,1703286581.179 [Default:CheckIn:Read_GPS] Stopped
2023-12-22T23:09:41.179Z,1703286581.179 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-22T23:09:48.819Z,1703286588.819 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20231222T210248/Courier0072.lzma
2023-12-22T23:09:49.822Z,1703286589.822 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0072.lzma.bak
2023-12-22T23:09:49.822Z,1703286589.822 [DataOverHttps](INFO): SBD MOMSN=19131320
2023-12-22T23:10:05.788Z,1703286605.788 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20231222T210248/Express0073.lzma
2023-12-22T23:10:06.790Z,1703286606.790 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0073.lzma.bak
2023-12-22T23:10:06.790Z,1703286606.790 [DataOverHttps](INFO): SBD MOMSN=19131323
2023-12-22T23:10:08.250Z,1703286608.250 [Default:CheckIn:Read_Iridium] Stopped
2023-12-22T23:10:08.250Z,1703286608.250 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-22T23:10:08.250Z,1703286608.250 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-22T23:14:43.346Z,1703286883.346 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-12-22T23:15:08.815Z,1703286908.815 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-22T23:15:08.815Z,1703286908.815 [Default:CheckIn:C.Wait] Stopped
2023-12-22T23:15:08.815Z,1703286908.815 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-22T23:15:08.815Z,1703286908.815 [Default:CheckIn:D] Running Loop=1
2023-12-22T23:15:09.228Z,1703286909.228 [Default:CheckIn:D] Stopped
2023-12-22T23:15:09.228Z,1703286909.228 [Default:CheckIn:E] Running Loop=1
2023-12-22T23:15:09.612Z,1703286909.612 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 145.970492 min 
2023-12-22T23:15:09.612Z,1703286909.612 [Default:CheckIn:E] Stopped
2023-12-22T23:15:09.612Z,1703286909.612 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-22T23:15:09.612Z,1703286909.612 [Default:CheckIn] Stopped
2023-12-22T23:15:09.612Z,1703286909.612 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-22T23:15:09.612Z,1703286909.612 [Default:CheckIn](INFO): Running loop #27
2023-12-22T23:15:09.613Z,1703286909.613 [Default:CheckIn] Running Loop=27
2023-12-22T23:15:09.613Z,1703286909.613 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-22T23:15:09.613Z,1703286909.613 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-22T23:15:11.622Z,1703286911.622 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,231510.00,A,3648.16960,N,12147.27819,W,0.816,303.09,221223,,,A*79
2023-12-22T23:15:11.624Z,1703286911.624 [NAL9602](INFO): GPS fix at 20231222T231510: (36.802827, -121.787970)
2023-12-22T23:15:11.637Z,1703286911.637 [Default:CheckIn:Read_GPS] Stopped
2023-12-22T23:15:11.637Z,1703286911.637 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-22T23:15:19.263Z,1703286919.263 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20231222T210248/Courier0075.lzma
2023-12-22T23:15:20.266Z,1703286920.266 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0075.lzma.bak
2023-12-22T23:15:20.266Z,1703286920.266 [DataOverHttps](INFO): SBD MOMSN=19131326
2023-12-22T23:15:36.220Z,1703286936.220 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20231222T210248/Express0076.lzma
2023-12-22T23:15:37.222Z,1703286937.222 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0076.lzma.bak
2023-12-22T23:15:37.222Z,1703286937.222 [DataOverHttps](INFO): SBD MOMSN=19131329
2023-12-22T23:15:38.706Z,1703286938.706 [Default:CheckIn:Read_Iridium] Stopped
2023-12-22T23:15:38.706Z,1703286938.706 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-22T23:15:38.706Z,1703286938.706 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-22T23:15:44.353Z,1703286944.353 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-22T23:16:43.336Z,1703287003.336 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2023-12-22T23:16:43.337Z,1703287003.337 [DropWeight] Hardware Fault, FailCount= 1
2023-12-22T23:16:43.337Z,1703287003.337 [DropWeight](ERROR): Hardware Fault
2023-12-22T23:16:43.384Z,1703287003.384 [CBIT](INFO): Critical error at 20231222T231643
2023-12-22T23:16:43.387Z,1703287003.387 [CBIT](ERROR): Hardware Fault in component: DropWeight
2023-12-22T23:16:43.387Z,1703287003.387 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2023-12-22T23:16:43.759Z,1703287003.759 [CBIT](INFO): Critical error at 20231222T231643
2023-12-22T23:20:39.295Z,1703287239.295 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-22T23:20:39.297Z,1703287239.297 [Default:CheckIn:C.Wait] Stopped
2023-12-22T23:20:39.298Z,1703287239.298 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-22T23:20:39.302Z,1703287239.302 [Default:CheckIn:D] Running Loop=1
2023-12-22T23:20:39.696Z,1703287239.696 [Default:CheckIn:D] Stopped
2023-12-22T23:20:39.696Z,1703287239.696 [Default:CheckIn:E] Running Loop=1
2023-12-22T23:20:40.092Z,1703287240.092 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 151.478304 min 
2023-12-22T23:20:40.092Z,1703287240.092 [Default:CheckIn:E] Stopped
2023-12-22T23:20:40.093Z,1703287240.093 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-22T23:20:40.093Z,1703287240.093 [Default:CheckIn] Stopped
2023-12-22T23:20:40.093Z,1703287240.093 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-22T23:20:40.093Z,1703287240.093 [Default:CheckIn](INFO): Running loop #28
2023-12-22T23:20:40.093Z,1703287240.093 [Default:CheckIn] Running Loop=28
2023-12-22T23:20:40.093Z,1703287240.093 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-22T23:20:40.093Z,1703287240.093 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-22T23:20:42.099Z,1703287242.099 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232041.00,A,3648.17017,N,12147.27903,W,0.175,237.72,221223,,,A*7F
2023-12-22T23:20:42.101Z,1703287242.101 [NAL9602](INFO): GPS fix at 20231222T232041: (36.802836, -121.787984)
2023-12-22T23:20:42.118Z,1703287242.118 [Default:CheckIn:Read_GPS] Stopped
2023-12-22T23:20:42.118Z,1703287242.118 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-22T23:20:49.856Z,1703287249.856 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20231222T210248/Courier0078.lzma
2023-12-22T23:20:50.858Z,1703287250.858 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0078.lzma.bak
2023-12-22T23:20:50.858Z,1703287250.858 [DataOverHttps](INFO): SBD MOMSN=19131333
2023-12-22T23:21:06.840Z,1703287266.840 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20231222T210248/Express0079.lzma
2023-12-22T23:21:07.845Z,1703287267.845 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0079.lzma.bak
2023-12-22T23:21:07.845Z,1703287267.845 [DataOverHttps](INFO): SBD MOMSN=19131338
2023-12-22T23:21:09.194Z,1703287269.194 [Default:CheckIn:Read_Iridium] Stopped
2023-12-22T23:21:09.194Z,1703287269.194 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-22T23:21:09.195Z,1703287269.195 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-22T23:21:12.801Z,1703287272.801 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-12-22T23:21:12.876Z,1703287272.876 [NAL9602](ERROR): received: 
+CSQ:0

OK680, 0, 0, 0, 0

OK
2023-12-22T23:23:48.746Z,1703287428.746 [NAL9602](INFO): SBD MO Status=2, MOMSN=14681, MT Status=2, MTMSN=0
2023-12-22T23:23:48.746Z,1703287428.746 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-12-22T23:25:44.289Z,1703287544.289 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-12-22T23:26:09.758Z,1703287569.758 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-22T23:26:09.758Z,1703287569.758 [Default:CheckIn:C.Wait] Stopped
2023-12-22T23:26:09.758Z,1703287569.758 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-22T23:26:09.758Z,1703287569.758 [Default:CheckIn:D] Running Loop=1
2023-12-22T23:26:10.160Z,1703287570.160 [Default:CheckIn:D] Stopped
2023-12-22T23:26:10.160Z,1703287570.160 [Default:CheckIn:E] Running Loop=1
2023-12-22T23:26:10.565Z,1703287570.565 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 156.986019 min 
2023-12-22T23:26:10.565Z,1703287570.565 [Default:CheckIn:E] Stopped
2023-12-22T23:26:10.565Z,1703287570.565 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-22T23:26:10.565Z,1703287570.565 [Default:CheckIn] Stopped
2023-12-22T23:26:10.565Z,1703287570.565 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-22T23:26:10.565Z,1703287570.565 [Default:CheckIn](INFO): Running loop #29
2023-12-22T23:26:10.565Z,1703287570.565 [Default:CheckIn] Running Loop=29
2023-12-22T23:26:10.566Z,1703287570.566 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-22T23:26:10.566Z,1703287570.566 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-22T23:26:12.570Z,1703287572.570 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232611.00,A,3648.17067,N,12147.27912,W,0.661,237.72,221223,,,A*79
2023-12-22T23:26:12.573Z,1703287572.573 [NAL9602](INFO): GPS fix at 20231222T232611: (36.802844, -121.787985)
2023-12-22T23:26:12.601Z,1703287572.601 [Default:CheckIn:Read_GPS] Stopped
2023-12-22T23:26:12.601Z,1703287572.601 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-22T23:26:20.051Z,1703287580.051 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20231222T210248/Courier0081.lzma
2023-12-22T23:26:21.054Z,1703287581.054 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0081.lzma.bak
2023-12-22T23:26:21.054Z,1703287581.054 [DataOverHttps](INFO): SBD MOMSN=19131341
2023-12-22T23:26:36.849Z,1703287596.849 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20231222T210248/Express0082.lzma
2023-12-22T23:26:37.850Z,1703287597.850 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0082.lzma.bak
2023-12-22T23:26:37.850Z,1703287597.850 [DataOverHttps](INFO): SBD MOMSN=19131344
2023-12-22T23:26:39.250Z,1703287599.250 [Default:CheckIn:Read_Iridium] Stopped
2023-12-22T23:26:39.250Z,1703287599.250 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-22T23:26:39.250Z,1703287599.250 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-22T23:26:44.484Z,1703287604.484 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-22T23:31:39.887Z,1703287899.887 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-22T23:31:39.887Z,1703287899.887 [Default:CheckIn:C.Wait] Stopped
2023-12-22T23:31:39.887Z,1703287899.887 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-22T23:31:39.889Z,1703287899.889 [Default:CheckIn:D] Running Loop=1
2023-12-22T23:31:40.254Z,1703287900.254 [Default:CheckIn:D] Stopped
2023-12-22T23:31:40.254Z,1703287900.254 [Default:CheckIn:E] Running Loop=1
2023-12-22T23:31:40.660Z,1703287900.660 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 162.487598 min 
2023-12-22T23:31:40.660Z,1703287900.660 [Default:CheckIn:E] Stopped
2023-12-22T23:31:40.660Z,1703287900.660 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-22T23:31:40.660Z,1703287900.660 [Default:CheckIn] Stopped
2023-12-22T23:31:40.660Z,1703287900.660 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-22T23:31:40.660Z,1703287900.660 [Default:CheckIn](INFO): Running loop #30
2023-12-22T23:31:40.660Z,1703287900.660 [Default:CheckIn] Running Loop=30
2023-12-22T23:31:40.660Z,1703287900.660 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-22T23:31:40.660Z,1703287900.660 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-22T23:31:42.666Z,1703287902.666 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233141.00,A,3648.16615,N,12147.27955,W,0.350,237.72,221223,,,A*7C
2023-12-22T23:31:42.669Z,1703287902.669 [NAL9602](INFO): GPS fix at 20231222T233141: (36.802769, -121.787993)
2023-12-22T23:31:42.705Z,1703287902.705 [Default:CheckIn:Read_GPS] Stopped
2023-12-22T23:31:42.705Z,1703287902.705 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-22T23:31:43.489Z,1703287903.489 [CBIT](INFO): Clearing failed state for component DropWeight
2023-12-22T23:31:43.489Z,1703287903.489 [DropWeight] No Fault, FailCount= 1
2023-12-22T23:31:50.219Z,1703287910.219 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20231222T210248/Courier0084.lzma
2023-12-22T23:31:51.222Z,1703287911.222 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0084.lzma.bak
2023-12-22T23:31:51.222Z,1703287911.222 [DataOverHttps](INFO): SBD MOMSN=19131384
2023-12-22T23:32:07.200Z,1703287927.200 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20231222T210248/Express0085.lzma
2023-12-22T23:32:08.202Z,1703287928.202 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0085.lzma.bak
2023-12-22T23:32:08.202Z,1703287928.202 [DataOverHttps](INFO): SBD MOMSN=19131389
2023-12-22T23:32:09.740Z,1703287929.740 [Default:CheckIn:Read_Iridium] Stopped
2023-12-22T23:32:09.740Z,1703287929.740 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-22T23:32:09.740Z,1703287929.740 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-22T23:32:13.369Z,1703287933.369 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-12-22T23:32:13.444Z,1703287933.444 [NAL9602](ERROR): received: 
+CSQ:0

OK681, 2, 0, 0, 0

OK
2023-12-22T23:33:01.079Z,1703287981.079 [CBIT](IMPORTANT): Beginning ground fault scan
2023-12-22T23:33:11.979Z,1703287991.979 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006281
CHAN A1 (24V): 0.000861
CHAN A2 (12V): -0.005525
CHAN A3 (5V): -0.003039
CHAN B0 (3.3V): -0.001373
CHAN B1 (3.15aV): -0.001320
CHAN B2 (3.15bV): -0.001506
CHAN B3 (GND): -0.000737
OPEN: 0.004993
Full Scale: +/- 1 mA
2023-12-22T23:36:45.337Z,1703288205.337 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-12-22T23:37:10.388Z,1703288230.388 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-22T23:37:10.388Z,1703288230.388 [Default:CheckIn:C.Wait] Stopped
2023-12-22T23:37:10.388Z,1703288230.388 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-22T23:37:10.389Z,1703288230.389 [Default:CheckIn:D] Running Loop=1
2023-12-22T23:37:10.801Z,1703288230.801 [Default:CheckIn:D] Stopped
2023-12-22T23:37:10.801Z,1703288230.801 [Default:CheckIn:E] Running Loop=1
2023-12-22T23:37:11.210Z,1703288231.210 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 167.996729 min 
2023-12-22T23:37:11.210Z,1703288231.210 [Default:CheckIn:E] Stopped
2023-12-22T23:37:11.210Z,1703288231.210 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-22T23:37:11.210Z,1703288231.210 [Default:CheckIn] Stopped
2023-12-22T23:37:11.210Z,1703288231.210 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-22T23:37:11.211Z,1703288231.211 [Default:CheckIn](INFO): Running loop #31
2023-12-22T23:37:11.211Z,1703288231.211 [Default:CheckIn] Running Loop=31
2023-12-22T23:37:11.211Z,1703288231.211 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-22T23:37:11.211Z,1703288231.211 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-22T23:37:13.211Z,1703288233.211 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233712.00,A,3648.19562,N,12147.27776,W,0.875,326.34,221223,,,A*70
2023-12-22T23:37:13.213Z,1703288233.213 [NAL9602](INFO): GPS fix at 20231222T233712: (36.803260, -121.787963)
2023-12-22T23:37:13.225Z,1703288233.225 [Default:CheckIn:Read_GPS] Stopped
2023-12-22T23:37:13.225Z,1703288233.225 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-22T23:37:20.711Z,1703288240.711 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231222T210248/Courier0087.lzma
2023-12-22T23:37:21.714Z,1703288241.714 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0087.lzma.bak
2023-12-22T23:37:21.714Z,1703288241.714 [DataOverHttps](INFO): SBD MOMSN=19131412
2023-12-22T23:37:37.520Z,1703288257.520 [DataOverHttps](INFO): Sending 382 bytes from file Logs/20231222T210248/Express0088.lzma
2023-12-22T23:37:38.522Z,1703288258.522 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0088.lzma.bak
2023-12-22T23:37:38.522Z,1703288258.522 [DataOverHttps](INFO): SBD MOMSN=19131418
2023-12-22T23:37:39.902Z,1703288259.902 [Default:CheckIn:Read_Iridium] Stopped
2023-12-22T23:37:39.902Z,1703288259.902 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-22T23:37:39.902Z,1703288259.902 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-22T23:37:45.529Z,1703288265.529 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-22T23:42:40.460Z,1703288560.460 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-22T23:42:40.460Z,1703288560.460 [Default:CheckIn:C.Wait] Stopped
2023-12-22T23:42:40.460Z,1703288560.460 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-22T23:42:40.460Z,1703288560.460 [Default:CheckIn:D] Running Loop=1
2023-12-22T23:42:40.864Z,1703288560.864 [Default:CheckIn:D] Stopped
2023-12-22T23:42:40.864Z,1703288560.864 [Default:CheckIn:E] Running Loop=1
2023-12-22T23:42:41.270Z,1703288561.270 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 173.497754 min 
2023-12-22T23:42:41.270Z,1703288561.270 [Default:CheckIn:E] Stopped
2023-12-22T23:42:41.270Z,1703288561.270 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-22T23:42:41.270Z,1703288561.270 [Default:CheckIn] Stopped
2023-12-22T23:42:41.270Z,1703288561.270 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-22T23:42:41.271Z,1703288561.271 [Default:CheckIn](INFO): Running loop #32
2023-12-22T23:42:41.271Z,1703288561.271 [Default:CheckIn] Running Loop=32
2023-12-22T23:42:41.271Z,1703288561.271 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-22T23:42:41.271Z,1703288561.271 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-22T23:42:43.273Z,1703288563.273 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234242.00,A,3648.17690,N,12147.25994,W,0.272,326.34,221223,,,A*7A
2023-12-22T23:42:43.276Z,1703288563.276 [NAL9602](INFO): GPS fix at 20231222T234242: (36.802948, -121.787666)
2023-12-22T23:42:43.306Z,1703288563.306 [Default:CheckIn:Read_GPS] Stopped
2023-12-22T23:42:43.306Z,1703288563.306 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-22T23:42:50.843Z,1703288570.843 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20231222T210248/Courier0090.lzma
2023-12-22T23:42:51.846Z,1703288571.846 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0090.lzma.bak
2023-12-22T23:42:51.846Z,1703288571.846 [DataOverHttps](INFO): SBD MOMSN=19131428
2023-12-22T23:43:07.688Z,1703288587.688 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20231222T210248/Express0091.lzma
2023-12-22T23:43:08.690Z,1703288588.690 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0091.lzma.bak
2023-12-22T23:43:08.690Z,1703288588.690 [DataOverHttps](INFO): SBD MOMSN=19131431
2023-12-22T23:43:10.010Z,1703288590.010 [Default:CheckIn:Read_Iridium] Stopped
2023-12-22T23:43:10.010Z,1703288590.010 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-22T23:43:10.010Z,1703288590.010 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-22T23:43:14.038Z,1703288594.038 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-12-22T23:43:14.112Z,1703288594.112 [NAL9602](ERROR): received: 
+CSQ:0

OK681, 2, 0, 0, 0

OK
2023-12-22T23:47:45.521Z,1703288865.521 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-12-22T23:48:10.594Z,1703288890.594 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-22T23:48:10.595Z,1703288890.595 [Default:CheckIn:C.Wait] Stopped
2023-12-22T23:48:10.595Z,1703288890.595 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-22T23:48:10.595Z,1703288890.595 [Default:CheckIn:D] Running Loop=1
2023-12-22T23:48:10.984Z,1703288890.984 [Default:CheckIn:D] Stopped
2023-12-22T23:48:10.984Z,1703288890.984 [Default:CheckIn:E] Running Loop=1
2023-12-22T23:48:11.393Z,1703288891.393 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 178.999772 min 
2023-12-22T23:48:11.393Z,1703288891.393 [Default:CheckIn:E] Stopped
2023-12-22T23:48:11.393Z,1703288891.393 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-22T23:48:11.393Z,1703288891.393 [Default:CheckIn] Stopped
2023-12-22T23:48:11.393Z,1703288891.393 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-22T23:48:11.393Z,1703288891.393 [Default:CheckIn](INFO): Running loop #33
2023-12-22T23:48:11.393Z,1703288891.393 [Default:CheckIn] Running Loop=33
2023-12-22T23:48:11.393Z,1703288891.393 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-22T23:48:11.393Z,1703288891.393 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-22T23:48:13.400Z,1703288893.400 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234812.00,A,3648.17360,N,12147.27439,W,1.225,308.25,221223,,,A*78
2023-12-22T23:48:13.402Z,1703288893.402 [NAL9602](INFO): GPS fix at 20231222T234812: (36.802893, -121.787907)
2023-12-22T23:48:13.414Z,1703288893.414 [Default:CheckIn:Read_GPS] Stopped
2023-12-22T23:48:13.414Z,1703288893.414 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-22T23:48:21.071Z,1703288901.071 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231222T210248/Courier0093.lzma
2023-12-22T23:48:22.074Z,1703288902.074 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0093.lzma.bak
2023-12-22T23:48:22.074Z,1703288902.074 [DataOverHttps](INFO): SBD MOMSN=19131437
2023-12-22T23:48:38.004Z,1703288918.004 [DataOverHttps](INFO): Sending 205 bytes from file Logs/20231222T210248/Express0094.lzma
2023-12-22T23:48:39.006Z,1703288919.006 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0094.lzma.bak
2023-12-22T23:48:39.006Z,1703288919.006 [DataOverHttps](INFO): SBD MOMSN=19131440
2023-12-22T23:48:40.501Z,1703288920.501 [Default:CheckIn:Read_Iridium] Stopped
2023-12-22T23:48:40.501Z,1703288920.501 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-22T23:48:40.501Z,1703288920.501 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-22T23:48:45.341Z,1703288925.341 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-22T23:53:41.108Z,1703289221.108 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-22T23:53:41.108Z,1703289221.108 [Default:CheckIn:C.Wait] Stopped
2023-12-22T23:53:41.108Z,1703289221.108 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-22T23:53:41.109Z,1703289221.109 [Default:CheckIn:D] Running Loop=1
2023-12-22T23:53:41.512Z,1703289221.512 [Default:CheckIn:D] Stopped
2023-12-22T23:53:41.512Z,1703289221.512 [Default:CheckIn:E] Running Loop=1
2023-12-22T23:53:41.920Z,1703289221.920 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 184.508561 min 
2023-12-22T23:53:41.920Z,1703289221.920 [Default:CheckIn:E] Stopped
2023-12-22T23:53:41.921Z,1703289221.921 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-22T23:53:41.921Z,1703289221.921 [Default:CheckIn] Stopped
2023-12-22T23:53:41.921Z,1703289221.921 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-22T23:53:41.921Z,1703289221.921 [Default:CheckIn](INFO): Running loop #34
2023-12-22T23:53:41.921Z,1703289221.921 [Default:CheckIn] Running Loop=34
2023-12-22T23:53:41.921Z,1703289221.921 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-22T23:53:41.921Z,1703289221.921 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-22T23:53:43.927Z,1703289223.927 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235343.00,A,3648.17392,N,12147.24993,W,1.380,130.49,221223,,,A*78
2023-12-22T23:53:43.938Z,1703289223.938 [NAL9602](INFO): GPS fix at 20231222T235343: (36.802899, -121.787499)
2023-12-22T23:53:43.955Z,1703289223.955 [Default:CheckIn:Read_GPS] Stopped
2023-12-22T23:53:43.955Z,1703289223.955 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-22T23:53:51.623Z,1703289231.623 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20231222T210248/Courier0096.lzma
2023-12-22T23:53:52.626Z,1703289232.626 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0096.lzma.bak
2023-12-22T23:53:52.626Z,1703289232.626 [DataOverHttps](INFO): SBD MOMSN=19131454
2023-12-22T23:54:08.612Z,1703289248.612 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20231222T210248/Express0097.lzma
2023-12-22T23:54:09.614Z,1703289249.614 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0097.lzma.bak
2023-12-22T23:54:09.614Z,1703289249.614 [DataOverHttps](INFO): SBD MOMSN=19131457
2023-12-22T23:54:11.023Z,1703289251.023 [Default:CheckIn:Read_Iridium] Stopped
2023-12-22T23:54:11.024Z,1703289251.024 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-22T23:54:11.024Z,1703289251.024 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-22T23:54:14.625Z,1703289254.625 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-12-22T23:54:14.708Z,1703289254.708 [NAL9602](ERROR): received: 
+CSQ:0

OK681, 2, 0, 0, 0

OK
2023-12-22T23:58:46.530Z,1703289526.530 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-12-22T23:59:11.578Z,1703289551.578 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-22T23:59:11.578Z,1703289551.578 [Default:CheckIn:C.Wait] Stopped
2023-12-22T23:59:11.578Z,1703289551.578 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-22T23:59:11.578Z,1703289551.578 [Default:CheckIn:D] Running Loop=1
2023-12-22T23:59:11.985Z,1703289551.985 [Default:CheckIn:D] Stopped
2023-12-22T23:59:11.985Z,1703289551.985 [Default:CheckIn:E] Running Loop=1
2023-12-22T23:59:12.397Z,1703289552.397 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 190.016455 min 
2023-12-22T23:59:12.397Z,1703289552.397 [Default:CheckIn:E] Stopped
2023-12-22T23:59:12.397Z,1703289552.397 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-22T23:59:12.397Z,1703289552.397 [Default:CheckIn] Stopped
2023-12-22T23:59:12.397Z,1703289552.397 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-22T23:59:12.397Z,1703289552.397 [Default:CheckIn](INFO): Running loop #35
2023-12-22T23:59:12.397Z,1703289552.397 [Default:CheckIn] Running Loop=35
2023-12-22T23:59:12.398Z,1703289552.398 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-22T23:59:12.398Z,1703289552.398 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-22T23:59:14.395Z,1703289554.395 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235913.00,A,3648.18688,N,12147.34764,W,8.650,300.73,221223,,,A*78
2023-12-22T23:59:14.397Z,1703289554.397 [NAL9602](INFO): GPS fix at 20231222T235913: (36.803115, -121.789127)
2023-12-22T23:59:14.409Z,1703289554.409 [Default:CheckIn:Read_GPS] Stopped
2023-12-22T23:59:14.409Z,1703289554.409 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-22T23:59:21.947Z,1703289561.947 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20231222T210248/Courier0099.lzma
2023-12-22T23:59:22.950Z,1703289562.950 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0099.lzma.bak
2023-12-22T23:59:22.950Z,1703289562.950 [DataOverHttps](INFO): SBD MOMSN=19131466
2023-12-22T23:59:41.825Z,1703289581.825 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20231222T210248/Express0100.lzma
2023-12-22T23:59:42.826Z,1703289582.826 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0100.lzma.bak
2023-12-22T23:59:42.826Z,1703289582.826 [DataOverHttps](INFO): SBD MOMSN=19131469
2023-12-22T23:59:44.370Z,1703289584.370 [Default:CheckIn:Read_Iridium] Stopped
2023-12-22T23:59:44.370Z,1703289584.370 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-22T23:59:44.370Z,1703289584.370 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-22T23:59:46.365Z,1703289586.365 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-23T00:01:44.737Z,1703289704.737 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2023-12-23T00:01:44.737Z,1703289704.737 [DropWeight] Hardware Fault, FailCount= 1
2023-12-23T00:01:44.737Z,1703289704.737 [DropWeight](ERROR): Hardware Fault
2023-12-23T00:01:44.756Z,1703289704.756 [CBIT](INFO): Critical error at 20231223T000144
2023-12-23T00:01:44.758Z,1703289704.758 [CBIT](ERROR): Hardware Fault in component: DropWeight
2023-12-23T00:01:44.759Z,1703289704.759 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2023-12-23T00:01:45.171Z,1703289705.171 [CBIT](INFO): Critical error at 20231223T000144
2023-12-23T00:04:44.935Z,1703289884.935 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-23T00:04:44.935Z,1703289884.935 [Default:CheckIn:C.Wait] Stopped
2023-12-23T00:04:44.935Z,1703289884.935 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-23T00:04:44.936Z,1703289884.936 [Default:CheckIn:D] Running Loop=1
2023-12-23T00:04:45.340Z,1703289885.340 [Default:CheckIn:D] Stopped
2023-12-23T00:04:45.340Z,1703289885.340 [Default:CheckIn:E] Running Loop=1
2023-12-23T00:04:45.750Z,1703289885.750 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 195.572363 min 
2023-12-23T00:04:45.750Z,1703289885.750 [Default:CheckIn:E] Stopped
2023-12-23T00:04:45.750Z,1703289885.750 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-23T00:04:45.750Z,1703289885.750 [Default:CheckIn] Stopped
2023-12-23T00:04:45.750Z,1703289885.750 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-23T00:04:45.750Z,1703289885.750 [Default:CheckIn](INFO): Running loop #36
2023-12-23T00:04:45.750Z,1703289885.750 [Default:CheckIn] Running Loop=36
2023-12-23T00:04:45.750Z,1703289885.750 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-23T00:04:45.751Z,1703289885.751 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-23T00:04:47.755Z,1703289887.755 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000447.00,A,3648.17831,N,12147.30228,W,1.166,304.76,231223,,,A*71
2023-12-23T00:04:47.757Z,1703289887.757 [NAL9602](INFO): GPS fix at 20231223T000447: (36.802972, -121.788371)
2023-12-23T00:04:47.793Z,1703289887.793 [Default:CheckIn:Read_GPS] Stopped
2023-12-23T00:04:47.793Z,1703289887.793 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-23T00:04:54.940Z,1703289894.940 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20231222T210248/Courier0102.lzma
2023-12-23T00:04:55.943Z,1703289895.943 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0102.lzma.bak
2023-12-23T00:04:55.943Z,1703289895.943 [DataOverHttps](INFO): SBD MOMSN=19131481
2023-12-23T00:05:11.871Z,1703289911.871 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20231222T210248/Express0103.lzma
2023-12-23T00:05:12.874Z,1703289912.874 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0103.lzma.bak
2023-12-23T00:05:12.874Z,1703289912.874 [DataOverHttps](INFO): SBD MOMSN=19131486
2023-12-23T00:05:14.433Z,1703289914.433 [Default:CheckIn:Read_Iridium] Stopped
2023-12-23T00:05:14.433Z,1703289914.433 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-23T00:05:14.433Z,1703289914.433 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-23T00:05:18.453Z,1703289918.453 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-12-23T00:05:18.528Z,1703289918.528 [NAL9602](ERROR): received: 
+CSQ:0

OK681, 2, 0, 0, 0

OK
2023-12-23T00:05:44.320Z,1703289944.320 [NAL9602](INFO): SBD MO Status=2, MOMSN=14681, MT Status=2, MTMSN=0
2023-12-23T00:05:44.320Z,1703289944.320 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-12-23T00:07:16.830Z,1703290036.830 [NAL9602](INFO): SBD MO Status=2, MOMSN=14681, MT Status=2, MTMSN=0
2023-12-23T00:07:16.831Z,1703290036.831 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-12-23T00:07:45.913Z,1703290065.913 [NAL9602](INFO): SBD MO Status=2, MOMSN=14681, MT Status=2, MTMSN=0
2023-12-23T00:07:45.913Z,1703290065.913 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-12-23T00:08:55.003Z,1703290135.003 [NAL9602](INFO): SBD MO Status=0, MOMSN=14681, MT Status=0, MTMSN=0
2023-12-23T00:08:55.003Z,1703290135.003 [NAL9602](INFO): No messages in MT queue
2023-12-23T00:09:25.700Z,1703290165.700 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-23T00:10:15.009Z,1703290215.009 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-23T00:10:15.009Z,1703290215.009 [Default:CheckIn:C.Wait] Stopped
2023-12-23T00:10:15.009Z,1703290215.009 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-23T00:10:15.009Z,1703290215.009 [Default:CheckIn:D] Running Loop=1
2023-12-23T00:10:15.424Z,1703290215.424 [Default:CheckIn:D] Stopped
2023-12-23T00:10:15.424Z,1703290215.424 [Default:CheckIn:E] Running Loop=1
2023-12-23T00:10:15.812Z,1703290215.812 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 201.073486 min 
2023-12-23T00:10:15.812Z,1703290215.812 [Default:CheckIn:E] Stopped
2023-12-23T00:10:15.812Z,1703290215.812 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-23T00:10:15.812Z,1703290215.812 [Default:CheckIn] Stopped
2023-12-23T00:10:15.812Z,1703290215.812 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-23T00:10:15.812Z,1703290215.812 [Default:CheckIn](INFO): Running loop #37
2023-12-23T00:10:15.812Z,1703290215.812 [Default:CheckIn] Running Loop=37
2023-12-23T00:10:15.812Z,1703290215.812 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-23T00:10:15.813Z,1703290215.813 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-23T00:10:17.827Z,1703290217.827 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001017.00,A,3648.18316,N,12147.28182,W,0.758,66.52,231223,,,A*41
2023-12-23T00:10:17.829Z,1703290217.829 [NAL9602](INFO): GPS fix at 20231223T001017: (36.803053, -121.788030)
2023-12-23T00:10:17.841Z,1703290217.841 [Default:CheckIn:Read_GPS] Stopped
2023-12-23T00:10:17.841Z,1703290217.841 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-23T00:10:25.507Z,1703290225.507 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20231222T210248/Courier0105.lzma
2023-12-23T00:10:26.510Z,1703290226.510 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0105.lzma.bak
2023-12-23T00:10:26.510Z,1703290226.510 [DataOverHttps](INFO): SBD MOMSN=19131493
2023-12-23T00:10:42.640Z,1703290242.640 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20231222T210248/Express0106.lzma
2023-12-23T00:10:43.642Z,1703290243.642 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0106.lzma.bak
2023-12-23T00:10:43.642Z,1703290243.642 [DataOverHttps](INFO): SBD MOMSN=19131497
2023-12-23T00:10:44.938Z,1703290244.938 [Default:CheckIn:Read_Iridium] Stopped
2023-12-23T00:10:44.938Z,1703290244.938 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-23T00:10:44.938Z,1703290244.938 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-23T00:15:21.773Z,1703290521.773 [NAL9602](INFO): SBD MO Status=2, MOMSN=14682, MT Status=2, MTMSN=0
2023-12-23T00:15:21.774Z,1703290521.774 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-12-23T00:15:22.240Z,1703290522.240 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-12-23T00:15:45.627Z,1703290545.627 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-23T00:15:45.628Z,1703290545.628 [Default:CheckIn:C.Wait] Stopped
2023-12-23T00:15:45.628Z,1703290545.628 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-23T00:15:45.629Z,1703290545.629 [Default:CheckIn:D] Running Loop=1
2023-12-23T00:15:46.028Z,1703290546.028 [Default:CheckIn:D] Stopped
2023-12-23T00:15:46.028Z,1703290546.028 [Default:CheckIn:E] Running Loop=1
2023-12-23T00:15:46.427Z,1703290546.427 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 206.583838 min 
2023-12-23T00:15:46.427Z,1703290546.427 [Default:CheckIn:E] Stopped
2023-12-23T00:15:46.428Z,1703290546.428 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-23T00:15:46.428Z,1703290546.428 [Default:CheckIn] Stopped
2023-12-23T00:15:46.428Z,1703290546.428 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-23T00:15:46.428Z,1703290546.428 [Default:CheckIn](INFO): Running loop #38
2023-12-23T00:15:46.428Z,1703290546.428 [Default:CheckIn] Running Loop=38
2023-12-23T00:15:46.428Z,1703290546.428 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-23T00:15:46.428Z,1703290546.428 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-23T00:15:48.440Z,1703290548.440 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001547.00,A,3648.16912,N,12147.26543,W,1.769,107.57,231223,,,A*76
2023-12-23T00:15:48.442Z,1703290548.442 [NAL9602](INFO): GPS fix at 20231223T001547: (36.802819, -121.787757)
2023-12-23T00:15:48.455Z,1703290548.455 [Default:CheckIn:Read_GPS] Stopped
2023-12-23T00:15:48.455Z,1703290548.455 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-23T00:15:55.724Z,1703290555.724 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20231222T210248/Courier0108.lzma
2023-12-23T00:15:56.726Z,1703290556.726 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0108.lzma.bak
2023-12-23T00:15:56.726Z,1703290556.726 [DataOverHttps](INFO): SBD MOMSN=19131509
2023-12-23T00:16:03.798Z,1703290563.798 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-12-23T00:16:03.801Z,1703290563.801 [BPC1](INFO): Received data from all battery sticks.
2023-12-23T00:16:12.648Z,1703290572.648 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20231222T210248/Express0109.lzma
2023-12-23T00:16:13.650Z,1703290573.650 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0109.lzma.bak
2023-12-23T00:16:13.650Z,1703290573.650 [DataOverHttps](INFO): SBD MOMSN=19131512
2023-12-23T00:16:15.122Z,1703290575.122 [Default:CheckIn:Read_Iridium] Stopped
2023-12-23T00:16:15.122Z,1703290575.122 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-23T00:16:15.122Z,1703290575.122 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-23T00:16:20.352Z,1703290580.352 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-23T00:16:45.036Z,1703290605.036 [CBIT](INFO): Clearing failed state for component DropWeight
2023-12-23T00:16:45.036Z,1703290605.036 [DropWeight] No Fault, FailCount= 1
2023-12-23T00:21:15.722Z,1703290875.722 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-23T00:21:15.722Z,1703290875.722 [Default:CheckIn:C.Wait] Stopped
2023-12-23T00:21:15.722Z,1703290875.722 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-23T00:21:15.723Z,1703290875.723 [Default:CheckIn:D] Running Loop=1
2023-12-23T00:21:16.116Z,1703290876.116 [Default:CheckIn:D] Stopped
2023-12-23T00:21:16.116Z,1703290876.116 [Default:CheckIn:E] Running Loop=1
2023-12-23T00:21:16.520Z,1703290876.520 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 212.085303 min 
2023-12-23T00:21:16.520Z,1703290876.520 [Default:CheckIn:E] Stopped
2023-12-23T00:21:16.520Z,1703290876.520 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-23T00:21:16.520Z,1703290876.520 [Default:CheckIn] Stopped
2023-12-23T00:21:16.520Z,1703290876.520 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-23T00:21:16.520Z,1703290876.520 [Default:CheckIn](INFO): Running loop #39
2023-12-23T00:21:16.520Z,1703290876.520 [Default:CheckIn] Running Loop=39
2023-12-23T00:21:16.521Z,1703290876.521 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-23T00:21:16.521Z,1703290876.521 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-23T00:21:18.527Z,1703290878.527 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002117.00,A,3648.16930,N,12147.27781,W,0.564,184.30,231223,,,A*7D
2023-12-23T00:21:18.529Z,1703290878.529 [NAL9602](INFO): GPS fix at 20231223T002117: (36.802822, -121.787964)
2023-12-23T00:21:18.541Z,1703290878.541 [Default:CheckIn:Read_GPS] Stopped
2023-12-23T00:21:18.541Z,1703290878.541 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-23T00:21:26.419Z,1703290886.419 [DataOverHttps](INFO): Sending 86 bytes from file Logs/20231222T210248/Courier0111.lzma
2023-12-23T00:21:27.422Z,1703290887.422 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0111.lzma.bak
2023-12-23T00:21:27.422Z,1703290887.422 [DataOverHttps](INFO): SBD MOMSN=19131531
2023-12-23T00:21:43.244Z,1703290903.244 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20231222T210248/Express0112.lzma
2023-12-23T00:21:44.246Z,1703290904.246 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0112.lzma.bak
2023-12-23T00:21:44.246Z,1703290904.246 [DataOverHttps](INFO): SBD MOMSN=19131536
2023-12-23T00:21:45.610Z,1703290905.610 [Default:CheckIn:Read_Iridium] Stopped
2023-12-23T00:21:45.610Z,1703290905.610 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-23T00:21:45.610Z,1703290905.610 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-23T00:26:20.312Z,1703291180.312 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-12-23T00:26:46.179Z,1703291206.179 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-23T00:26:46.179Z,1703291206.179 [Default:CheckIn:C.Wait] Stopped
2023-12-23T00:26:46.179Z,1703291206.179 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-23T00:26:46.180Z,1703291206.180 [Default:CheckIn:D] Running Loop=1
2023-12-23T00:26:46.589Z,1703291206.589 [Default:CheckIn:D] Stopped
2023-12-23T00:26:46.589Z,1703291206.589 [Default:CheckIn:E] Running Loop=1
2023-12-23T00:26:46.992Z,1703291206.992 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 217.593180 min 
2023-12-23T00:26:46.992Z,1703291206.992 [Default:CheckIn:E] Stopped
2023-12-23T00:26:46.992Z,1703291206.992 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-23T00:26:46.993Z,1703291206.993 [Default:CheckIn] Stopped
2023-12-23T00:26:46.993Z,1703291206.993 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-23T00:26:46.993Z,1703291206.993 [Default:CheckIn](INFO): Running loop #40
2023-12-23T00:26:46.993Z,1703291206.993 [Default:CheckIn] Running Loop=40
2023-12-23T00:26:46.993Z,1703291206.993 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-23T00:26:46.993Z,1703291206.993 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-23T00:26:49.003Z,1703291209.003 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002648.00,A,3648.15950,N,12147.27533,W,1.127,0.00,231223,,,A*72
2023-12-23T00:26:49.015Z,1703291209.015 [NAL9602](INFO): GPS fix at 20231223T002648: (36.802658, -121.787922)
2023-12-23T00:26:49.027Z,1703291209.027 [Default:CheckIn:Read_GPS] Stopped
2023-12-23T00:26:49.027Z,1703291209.027 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-23T00:26:56.703Z,1703291216.703 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231222T210248/Courier0114.lzma
2023-12-23T00:26:57.706Z,1703291217.706 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0114.lzma.bak
2023-12-23T00:26:57.706Z,1703291217.706 [DataOverHttps](INFO): SBD MOMSN=19131545
2023-12-23T00:27:13.668Z,1703291233.668 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20231222T210248/Express0115.lzma
2023-12-23T00:27:14.670Z,1703291234.670 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0115.lzma.bak
2023-12-23T00:27:14.670Z,1703291234.670 [DataOverHttps](INFO): SBD MOMSN=19131548
2023-12-23T00:27:16.093Z,1703291236.093 [Default:CheckIn:Read_Iridium] Stopped
2023-12-23T00:27:16.093Z,1703291236.093 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-23T00:27:16.093Z,1703291236.093 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-23T00:27:21.317Z,1703291241.317 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-23T00:32:16.660Z,1703291536.660 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-23T00:32:16.660Z,1703291536.660 [Default:CheckIn:C.Wait] Stopped
2023-12-23T00:32:16.660Z,1703291536.660 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-23T00:32:16.661Z,1703291536.661 [Default:CheckIn:D] Running Loop=1
2023-12-23T00:32:17.062Z,1703291537.062 [Default:CheckIn:D] Stopped
2023-12-23T00:32:17.062Z,1703291537.062 [Default:CheckIn:E] Running Loop=1
2023-12-23T00:32:17.474Z,1703291537.474 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 223.101058 min 
2023-12-23T00:32:17.474Z,1703291537.474 [Default:CheckIn:E] Stopped
2023-12-23T00:32:17.474Z,1703291537.474 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-23T00:32:17.474Z,1703291537.474 [Default:CheckIn] Stopped
2023-12-23T00:32:17.474Z,1703291537.474 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-23T00:32:17.474Z,1703291537.474 [Default:CheckIn](INFO): Running loop #41
2023-12-23T00:32:17.474Z,1703291537.474 [Default:CheckIn] Running Loop=41
2023-12-23T00:32:17.474Z,1703291537.474 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-23T00:32:17.474Z,1703291537.474 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-23T00:32:19.479Z,1703291539.479 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,003218.00,A,3648.16942,N,12147.29373,W,1.225,317.82,231223,,,A*70
2023-12-23T00:32:19.481Z,1703291539.481 [NAL9602](INFO): GPS fix at 20231223T003218: (36.802824, -121.788229)
2023-12-23T00:32:19.517Z,1703291539.517 [Default:CheckIn:Read_GPS] Stopped
2023-12-23T00:32:19.517Z,1703291539.517 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-23T00:32:27.544Z,1703291547.544 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20231222T210248/Courier0117.lzma
2023-12-23T00:32:28.126Z,1703291548.126 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0117.lzma.bak
2023-12-23T00:32:28.126Z,1703291548.126 [DataOverHttps](INFO): SBD MOMSN=19131560
2023-12-23T00:32:44.112Z,1703291564.112 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20231222T210248/Express0118.lzma
2023-12-23T00:32:45.114Z,1703291565.114 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0118.lzma.bak
2023-12-23T00:32:45.115Z,1703291565.115 [DataOverHttps](INFO): SBD MOMSN=19131563
2023-12-23T00:32:46.563Z,1703291566.563 [Default:CheckIn:Read_Iridium] Stopped
2023-12-23T00:32:46.563Z,1703291566.563 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-23T00:32:46.563Z,1703291566.563 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-23T00:32:50.181Z,1703291570.181 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-12-23T00:32:50.256Z,1703291570.256 [NAL9602](ERROR): received: 
+CSQ:0

OK682, 2, 0, 0, 0

OK
2023-12-23T00:33:12.054Z,1703291592.054 [CBIT](IMPORTANT): Beginning ground fault scan
2023-12-23T00:33:22.945Z,1703291602.945 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.005745
CHAN A1 (24V): 0.000395
CHAN A2 (12V): -0.005353
CHAN A3 (5V): -0.002680
CHAN B0 (3.3V): -0.000919
CHAN B1 (3.15aV): -0.001479
CHAN B2 (3.15bV): -0.001082
CHAN B3 (GND): -0.000339
OPEN: 0.005077
Full Scale: +/- 1 mA
2023-12-23T00:37:21.273Z,1703291841.273 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-12-23T00:37:47.155Z,1703291867.155 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-23T00:37:47.155Z,1703291867.155 [Default:CheckIn:C.Wait] Stopped
2023-12-23T00:37:47.155Z,1703291867.155 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-23T00:37:47.155Z,1703291867.155 [Default:CheckIn:D] Running Loop=1
2023-12-23T00:37:47.548Z,1703291867.548 [Default:CheckIn:D] Stopped
2023-12-23T00:37:47.548Z,1703291867.548 [Default:CheckIn:E] Running Loop=1
2023-12-23T00:37:47.954Z,1703291867.954 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 228.609163 min 
2023-12-23T00:37:47.954Z,1703291867.954 [Default:CheckIn:E] Stopped
2023-12-23T00:37:47.954Z,1703291867.954 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-23T00:37:47.954Z,1703291867.954 [Default:CheckIn] Stopped
2023-12-23T00:37:47.954Z,1703291867.954 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-23T00:37:47.954Z,1703291867.954 [Default:CheckIn](INFO): Running loop #42
2023-12-23T00:37:47.955Z,1703291867.955 [Default:CheckIn] Running Loop=42
2023-12-23T00:37:47.955Z,1703291867.955 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-23T00:37:47.955Z,1703291867.955 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-23T00:37:49.955Z,1703291869.955 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,003749.00,A,3648.14347,N,12147.26456,W,1.263,137.37,231223,,,A*7F
2023-12-23T00:37:49.957Z,1703291869.957 [NAL9602](INFO): GPS fix at 20231223T003749: (36.802391, -121.787743)
2023-12-23T00:37:49.997Z,1703291869.997 [Default:CheckIn:Read_GPS] Stopped
2023-12-23T00:37:49.998Z,1703291869.998 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-23T00:37:57.459Z,1703291877.459 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20231222T210248/Courier0120.lzma
2023-12-23T00:37:58.462Z,1703291878.462 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0120.lzma.bak
2023-12-23T00:37:58.462Z,1703291878.462 [DataOverHttps](INFO): SBD MOMSN=19131572
2023-12-23T00:38:14.780Z,1703291894.780 [DataOverHttps](INFO): Sending 381 bytes from file Logs/20231222T210248/Express0121.lzma
2023-12-23T00:38:15.782Z,1703291895.782 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0121.lzma.bak
2023-12-23T00:38:15.782Z,1703291895.782 [DataOverHttps](INFO): SBD MOMSN=19131575
2023-12-23T00:38:17.102Z,1703291897.102 [Default:CheckIn:Read_Iridium] Stopped
2023-12-23T00:38:17.102Z,1703291897.102 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-23T00:38:17.102Z,1703291897.102 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-23T00:38:22.333Z,1703291902.333 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-23T00:43:17.672Z,1703292197.672 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-23T00:43:17.672Z,1703292197.672 [Default:CheckIn:C.Wait] Stopped
2023-12-23T00:43:17.672Z,1703292197.672 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-23T00:43:17.672Z,1703292197.672 [Default:CheckIn:D] Running Loop=1
2023-12-23T00:43:18.076Z,1703292198.076 [Default:CheckIn:D] Stopped
2023-12-23T00:43:18.076Z,1703292198.076 [Default:CheckIn:E] Running Loop=1
2023-12-23T00:43:18.489Z,1703292198.489 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 234.117969 min 
2023-12-23T00:43:18.489Z,1703292198.489 [Default:CheckIn:E] Stopped
2023-12-23T00:43:18.490Z,1703292198.490 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-23T00:43:18.490Z,1703292198.490 [Default:CheckIn] Stopped
2023-12-23T00:43:18.490Z,1703292198.490 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-23T00:43:18.490Z,1703292198.490 [Default:CheckIn](INFO): Running loop #43
2023-12-23T00:43:18.490Z,1703292198.490 [Default:CheckIn] Running Loop=43
2023-12-23T00:43:18.490Z,1703292198.490 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-23T00:43:18.490Z,1703292198.490 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-23T00:43:20.491Z,1703292200.491 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,004319.00,A,3648.15961,N,12147.27950,W,2.060,140.34,231223,,,A*7D
2023-12-23T00:43:20.493Z,1703292200.493 [NAL9602](INFO): GPS fix at 20231223T004319: (36.802660, -121.787992)
2023-12-23T00:43:20.506Z,1703292200.506 [Default:CheckIn:Read_GPS] Stopped
2023-12-23T00:43:20.506Z,1703292200.506 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-23T00:43:27.428Z,1703292207.428 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20231222T210248/Courier0123.lzma
2023-12-23T00:43:28.430Z,1703292208.430 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0123.lzma.bak
2023-12-23T00:43:28.430Z,1703292208.430 [DataOverHttps](INFO): SBD MOMSN=19131588
2023-12-23T00:43:44.788Z,1703292224.788 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20231222T210248/Express0124.lzma
2023-12-23T00:43:45.790Z,1703292225.790 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0124.lzma.bak
2023-12-23T00:43:45.790Z,1703292225.790 [DataOverHttps](INFO): SBD MOMSN=19131591
2023-12-23T00:43:47.210Z,1703292227.210 [Default:CheckIn:Read_Iridium] Stopped
2023-12-23T00:43:47.210Z,1703292227.210 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-23T00:43:47.210Z,1703292227.210 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-23T00:43:51.225Z,1703292231.225 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-12-23T00:43:51.300Z,1703292231.300 [NAL9602](ERROR): received: 
+CSQ:0

OK682, 2, 0, 0, 0

OK
2023-12-23T00:46:46.569Z,1703292406.569 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2023-12-23T00:46:46.569Z,1703292406.569 [DropWeight] Hardware Fault, FailCount= 1
2023-12-23T00:46:46.569Z,1703292406.569 [DropWeight](ERROR): Hardware Fault
2023-12-23T00:46:46.614Z,1703292406.614 [CBIT](INFO): Critical error at 20231223T004646
2023-12-23T00:46:46.616Z,1703292406.616 [CBIT](ERROR): Hardware Fault in component: DropWeight
2023-12-23T00:46:46.617Z,1703292406.617 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2023-12-23T00:46:47.051Z,1703292407.051 [CBIT](INFO): Critical error at 20231223T004646
2023-12-23T00:48:22.317Z,1703292502.317 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-12-23T00:48:47.792Z,1703292527.792 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-23T00:48:47.792Z,1703292527.792 [Default:CheckIn:C.Wait] Stopped
2023-12-23T00:48:47.792Z,1703292527.792 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-23T00:48:47.792Z,1703292527.792 [Default:CheckIn:D] Running Loop=1
2023-12-23T00:48:48.184Z,1703292528.184 [Default:CheckIn:D] Stopped
2023-12-23T00:48:48.184Z,1703292528.184 [Default:CheckIn:E] Running Loop=1
2023-12-23T00:48:48.588Z,1703292528.588 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 239.619759 min 
2023-12-23T00:48:48.588Z,1703292528.588 [Default:CheckIn:E] Stopped
2023-12-23T00:48:48.588Z,1703292528.588 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-23T00:48:48.588Z,1703292528.588 [Default:CheckIn] Stopped
2023-12-23T00:48:48.588Z,1703292528.588 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-23T00:48:48.589Z,1703292528.589 [Default:CheckIn](INFO): Running loop #44
2023-12-23T00:48:48.589Z,1703292528.589 [Default:CheckIn] Running Loop=44
2023-12-23T00:48:48.589Z,1703292528.589 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-23T00:48:48.589Z,1703292528.589 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-23T00:48:50.598Z,1703292530.598 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,004849.00,A,3648.16278,N,12147.27762,W,0.447,254.96,231223,,,A*71
2023-12-23T00:48:50.601Z,1703292530.601 [NAL9602](INFO): GPS fix at 20231223T004849: (36.802713, -121.787960)
2023-12-23T00:48:50.613Z,1703292530.613 [Default:CheckIn:Read_GPS] Stopped
2023-12-23T00:48:50.613Z,1703292530.613 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-23T00:48:57.624Z,1703292537.624 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20231222T210248/Courier0126.lzma
2023-12-23T00:48:58.626Z,1703292538.626 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0126.lzma.bak
2023-12-23T00:48:58.626Z,1703292538.626 [DataOverHttps](INFO): SBD MOMSN=19131601
2023-12-23T00:49:14.452Z,1703292554.452 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20231222T210248/Express0127.lzma
2023-12-23T00:49:15.454Z,1703292555.454 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0127.lzma.bak
2023-12-23T00:49:15.454Z,1703292555.454 [DataOverHttps](INFO): SBD MOMSN=19131606
2023-12-23T00:49:16.910Z,1703292556.910 [Default:CheckIn:Read_Iridium] Stopped
2023-12-23T00:49:16.910Z,1703292556.910 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-23T00:49:16.910Z,1703292556.910 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-23T00:49:22.549Z,1703292562.549 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-23T00:54:17.481Z,1703292857.481 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-23T00:54:17.481Z,1703292857.481 [Default:CheckIn:C.Wait] Stopped
2023-12-23T00:54:17.481Z,1703292857.481 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-23T00:54:17.481Z,1703292857.481 [Default:CheckIn:D] Running Loop=1
2023-12-23T00:54:17.896Z,1703292857.896 [Default:CheckIn:D] Stopped
2023-12-23T00:54:17.896Z,1703292857.896 [Default:CheckIn:E] Running Loop=1
2023-12-23T00:54:18.288Z,1703292858.288 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 245.114974 min 
2023-12-23T00:54:18.289Z,1703292858.289 [Default:CheckIn:E] Stopped
2023-12-23T00:54:18.289Z,1703292858.289 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-23T00:54:18.289Z,1703292858.289 [Default:CheckIn] Stopped
2023-12-23T00:54:18.289Z,1703292858.289 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-23T00:54:18.289Z,1703292858.289 [Default:CheckIn](INFO): Running loop #45
2023-12-23T00:54:18.289Z,1703292858.289 [Default:CheckIn] Running Loop=45
2023-12-23T00:54:18.289Z,1703292858.289 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-23T00:54:18.289Z,1703292858.289 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-23T00:54:20.298Z,1703292860.298 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,005419.00,A,3648.16299,N,12147.27666,W,1.244,141.34,231223,,,A*78
2023-12-23T00:54:20.300Z,1703292860.300 [NAL9602](INFO): GPS fix at 20231223T005419: (36.802717, -121.787944)
2023-12-23T00:54:20.312Z,1703292860.312 [Default:CheckIn:Read_GPS] Stopped
2023-12-23T00:54:20.312Z,1703292860.312 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-23T00:54:27.947Z,1703292867.947 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20231222T210248/Courier0129.lzma
2023-12-23T00:54:28.950Z,1703292868.950 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0129.lzma.bak
2023-12-23T00:54:28.950Z,1703292868.950 [DataOverHttps](INFO): SBD MOMSN=19131617
2023-12-23T00:54:45.044Z,1703292885.044 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20231222T210248/Express0130.lzma
2023-12-23T00:54:46.046Z,1703292886.046 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0130.lzma.bak
2023-12-23T00:54:46.046Z,1703292886.046 [DataOverHttps](INFO): SBD MOMSN=19131620
2023-12-23T00:54:47.382Z,1703292887.382 [Default:CheckIn:Read_Iridium] Stopped
2023-12-23T00:54:47.382Z,1703292887.382 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-23T00:54:47.382Z,1703292887.382 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-23T00:54:51.001Z,1703292891.001 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-12-23T00:54:51.080Z,1703292891.080 [NAL9602](ERROR): received: 
+CSQ:0

OK682, 2, 0, 0, 0

OK
2023-12-23T00:57:47.566Z,1703293067.566 [NAL9602](INFO): SBD MO Status=2, MOMSN=14682, MT Status=2, MTMSN=0
2023-12-23T00:57:47.566Z,1703293067.566 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-12-23T00:59:00.327Z,1703293140.327 [NAL9602](INFO): SBD MO Status=2, MOMSN=14682, MT Status=2, MTMSN=0
2023-12-23T00:59:00.327Z,1703293140.327 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-12-23T00:59:22.545Z,1703293162.545 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-12-23T00:59:48.033Z,1703293188.033 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-23T00:59:48.033Z,1703293188.033 [Default:CheckIn:C.Wait] Stopped
2023-12-23T00:59:48.033Z,1703293188.033 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-23T00:59:48.033Z,1703293188.033 [Default:CheckIn:D] Running Loop=1
2023-12-23T00:59:48.417Z,1703293188.417 [Default:CheckIn:D] Stopped
2023-12-23T00:59:48.417Z,1703293188.417 [Default:CheckIn:E] Running Loop=1
2023-12-23T00:59:48.825Z,1703293188.825 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 250.623649 min 
2023-12-23T00:59:48.825Z,1703293188.825 [Default:CheckIn:E] Stopped
2023-12-23T00:59:48.825Z,1703293188.825 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-23T00:59:48.826Z,1703293188.826 [Default:CheckIn] Stopped
2023-12-23T00:59:48.826Z,1703293188.826 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-23T00:59:48.826Z,1703293188.826 [Default:CheckIn](INFO): Running loop #46
2023-12-23T00:59:48.826Z,1703293188.826 [Default:CheckIn] Running Loop=46
2023-12-23T00:59:48.826Z,1703293188.826 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-23T00:59:48.826Z,1703293188.826 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-23T00:59:50.840Z,1703293190.840 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,005950.00,A,3648.15556,N,12147.27348,W,0.855,144.62,231223,,,A*7B
2023-12-23T00:59:50.842Z,1703293190.842 [NAL9602](INFO): GPS fix at 20231223T005950: (36.802593, -121.787891)
2023-12-23T00:59:50.856Z,1703293190.856 [Default:CheckIn:Read_GPS] Stopped
2023-12-23T00:59:50.856Z,1703293190.856 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-23T00:59:57.803Z,1703293197.803 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20231222T210248/Courier0132.lzma
2023-12-23T00:59:58.806Z,1703293198.806 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0132.lzma.bak
2023-12-23T00:59:58.806Z,1703293198.806 [DataOverHttps](INFO): SBD MOMSN=19131630
2023-12-23T01:00:14.644Z,1703293214.644 [DataOverHttps](INFO): Sending 205 bytes from file Logs/20231222T210248/Express0133.lzma
2023-12-23T01:00:15.646Z,1703293215.646 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0133.lzma.bak
2023-12-23T01:00:15.647Z,1703293215.647 [DataOverHttps](INFO): SBD MOMSN=19131633
2023-12-23T01:00:17.136Z,1703293217.136 [Default:CheckIn:Read_Iridium] Stopped
2023-12-23T01:00:17.136Z,1703293217.136 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-23T01:00:17.136Z,1703293217.136 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-23T01:00:23.586Z,1703293223.586 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-23T01:01:46.829Z,1703293306.829 [CBIT](INFO): Clearing failed state for component DropWeight
2023-12-23T01:01:46.829Z,1703293306.829 [DropWeight] No Fault, FailCount= 1
2023-12-23T01:05:17.719Z,1703293517.719 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-23T01:05:17.719Z,1703293517.719 [Default:CheckIn:C.Wait] Stopped
2023-12-23T01:05:17.719Z,1703293517.719 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-23T01:05:17.720Z,1703293517.720 [Default:CheckIn:D] Running Loop=1
2023-12-23T01:05:18.124Z,1703293518.124 [Default:CheckIn:D] Stopped
2023-12-23T01:05:18.124Z,1703293518.124 [Default:CheckIn:E] Running Loop=1
2023-12-23T01:05:18.538Z,1703293518.538 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 256.118766 min 
2023-12-23T01:05:18.538Z,1703293518.538 [Default:CheckIn:E] Stopped
2023-12-23T01:05:18.538Z,1703293518.538 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-23T01:05:18.539Z,1703293518.539 [Default:CheckIn] Stopped
2023-12-23T01:05:18.539Z,1703293518.539 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-23T01:05:18.539Z,1703293518.539 [Default:CheckIn](INFO): Running loop #47
2023-12-23T01:05:18.539Z,1703293518.539 [Default:CheckIn] Running Loop=47
2023-12-23T01:05:18.539Z,1703293518.539 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-23T01:05:18.539Z,1703293518.539 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-23T01:05:20.539Z,1703293520.539 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,010519.00,A,3648.16760,N,12147.29215,W,1.263,309.76,231223,,,A*7D
2023-12-23T01:05:20.541Z,1703293520.541 [NAL9602](INFO): GPS fix at 20231223T010519: (36.802793, -121.788202)
2023-12-23T01:05:20.553Z,1703293520.553 [Default:CheckIn:Read_GPS] Stopped
2023-12-23T01:05:20.554Z,1703293520.554 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-23T01:05:28.147Z,1703293528.147 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20231222T210248/Courier0135.lzma
2023-12-23T01:05:29.146Z,1703293529.146 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0135.lzma.bak
2023-12-23T01:05:29.146Z,1703293529.146 [DataOverHttps](INFO): SBD MOMSN=19131644
2023-12-23T01:05:45.080Z,1703293545.080 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20231222T210248/Express0136.lzma
2023-12-23T01:05:46.086Z,1703293546.086 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0136.lzma.bak
2023-12-23T01:05:46.087Z,1703293546.087 [DataOverHttps](INFO): SBD MOMSN=19131647
2023-12-23T01:05:47.622Z,1703293547.622 [Default:CheckIn:Read_Iridium] Stopped
2023-12-23T01:05:47.622Z,1703293547.622 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-23T01:05:47.622Z,1703293547.622 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-23T01:05:51.237Z,1703293551.237 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-12-23T01:05:51.312Z,1703293551.312 [NAL9602](ERROR): received: 
+CSQ:1

OK682, 2, 0, 0, 0

OK
2023-12-23T01:07:43.147Z,1703293663.147 [NAL9602](INFO): SBD MO Status=2, MOMSN=14682, MT Status=2, MTMSN=0
2023-12-23T01:07:43.148Z,1703293663.148 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-12-23T01:09:19.718Z,1703293759.718 [NAL9602](INFO): SBD MO Status=0, MOMSN=14682, MT Status=0, MTMSN=0
2023-12-23T01:09:19.718Z,1703293759.718 [NAL9602](INFO): No messages in MT queue
2023-12-23T01:09:50.409Z,1703293790.409 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-23T01:10:48.194Z,1703293848.194 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-23T01:10:48.194Z,1703293848.194 [Default:CheckIn:C.Wait] Stopped
2023-12-23T01:10:48.194Z,1703293848.194 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-23T01:10:48.194Z,1703293848.194 [Default:CheckIn:D] Running Loop=1
2023-12-23T01:10:48.595Z,1703293848.595 [Default:CheckIn:D] Stopped
2023-12-23T01:10:48.595Z,1703293848.595 [Default:CheckIn:E] Running Loop=1
2023-12-23T01:10:49.001Z,1703293849.001 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 261.626628 min 
2023-12-23T01:10:49.001Z,1703293849.001 [Default:CheckIn:E] Stopped
2023-12-23T01:10:49.001Z,1703293849.001 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-23T01:10:49.001Z,1703293849.001 [Default:CheckIn] Stopped
2023-12-23T01:10:49.001Z,1703293849.001 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-23T01:10:49.001Z,1703293849.001 [Default:CheckIn](INFO): Running loop #48
2023-12-23T01:10:49.001Z,1703293849.001 [Default:CheckIn] Running Loop=48
2023-12-23T01:10:49.002Z,1703293849.002 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-23T01:10:49.002Z,1703293849.002 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-23T01:10:51.007Z,1703293851.007 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,011050.00,A,3648.15221,N,12147.25286,W,1.575,124.91,231223,,,A*75
2023-12-23T01:10:51.009Z,1703293851.009 [NAL9602](INFO): GPS fix at 20231223T011050: (36.802537, -121.787548)
2023-12-23T01:10:51.041Z,1703293851.041 [Default:CheckIn:Read_GPS] Stopped
2023-12-23T01:10:51.041Z,1703293851.041 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-23T01:10:58.063Z,1703293858.063 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20231222T210248/Courier0138.lzma
2023-12-23T01:10:59.066Z,1703293859.066 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0138.lzma.bak
2023-12-23T01:10:59.066Z,1703293859.066 [DataOverHttps](INFO): SBD MOMSN=19131657
2023-12-23T01:11:15.180Z,1703293875.180 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20231222T210248/Express0139.lzma
2023-12-23T01:11:16.182Z,1703293876.182 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0139.lzma.bak
2023-12-23T01:11:16.182Z,1703293876.182 [DataOverHttps](INFO): SBD MOMSN=19131660
2023-12-23T01:11:17.716Z,1703293877.716 [Default:CheckIn:Read_Iridium] Stopped
2023-12-23T01:11:17.716Z,1703293877.716 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-23T01:11:17.716Z,1703293877.716 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-23T01:15:53.234Z,1703294153.234 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-12-23T01:16:18.296Z,1703294178.296 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-23T01:16:18.296Z,1703294178.296 [Default:CheckIn:C.Wait] Stopped
2023-12-23T01:16:18.297Z,1703294178.297 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-23T01:16:18.297Z,1703294178.297 [Default:CheckIn:D] Running Loop=1
2023-12-23T01:16:18.710Z,1703294178.710 [Default:CheckIn:D] Stopped
2023-12-23T01:16:18.710Z,1703294178.710 [Default:CheckIn:E] Running Loop=1
2023-12-23T01:16:19.102Z,1703294179.102 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 267.128532 min 
2023-12-23T01:16:19.102Z,1703294179.102 [Default:CheckIn:E] Stopped
2023-12-23T01:16:19.102Z,1703294179.102 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-23T01:16:19.102Z,1703294179.102 [Default:CheckIn] Stopped
2023-12-23T01:16:19.102Z,1703294179.102 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-23T01:16:19.103Z,1703294179.103 [Default:CheckIn](INFO): Running loop #49
2023-12-23T01:16:19.103Z,1703294179.103 [Default:CheckIn] Running Loop=49
2023-12-23T01:16:19.103Z,1703294179.103 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-23T01:16:19.103Z,1703294179.103 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-23T01:16:21.107Z,1703294181.107 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,011620.00,A,3648.16089,N,12147.35651,W,8.009,224.74,231223,,,A*77
2023-12-23T01:16:21.110Z,1703294181.110 [NAL9602](INFO): GPS fix at 20231223T011620: (36.802681, -121.789275)
2023-12-23T01:16:21.122Z,1703294181.122 [Default:CheckIn:Read_GPS] Stopped
2023-12-23T01:16:21.122Z,1703294181.122 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-23T01:16:28.667Z,1703294188.667 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231222T210248/Courier0141.lzma
2023-12-23T01:16:29.670Z,1703294189.670 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0141.lzma.bak
2023-12-23T01:16:29.670Z,1703294189.670 [DataOverHttps](INFO): SBD MOMSN=19131671
2023-12-23T01:16:45.720Z,1703294205.720 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20231222T210248/Express0142.lzma
2023-12-23T01:16:46.722Z,1703294206.722 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0142.lzma.bak
2023-12-23T01:16:46.722Z,1703294206.722 [DataOverHttps](INFO): SBD MOMSN=19131674
2023-12-23T01:16:48.189Z,1703294208.189 [Default:CheckIn:Read_Iridium] Stopped
2023-12-23T01:16:48.189Z,1703294208.189 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-23T01:16:48.190Z,1703294208.190 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-23T01:16:53.429Z,1703294213.429 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-23T01:21:48.867Z,1703294508.867 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-23T01:21:48.867Z,1703294508.867 [Default:CheckIn:C.Wait] Stopped
2023-12-23T01:21:48.867Z,1703294508.867 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-23T01:21:48.867Z,1703294508.867 [Default:CheckIn:D] Running Loop=1
2023-12-23T01:21:49.278Z,1703294509.278 [Default:CheckIn:D] Stopped
2023-12-23T01:21:49.278Z,1703294509.278 [Default:CheckIn:E] Running Loop=1
2023-12-23T01:21:49.689Z,1703294509.689 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 272.638005 min 
2023-12-23T01:21:49.690Z,1703294509.690 [Default:CheckIn:E] Stopped
2023-12-23T01:21:49.690Z,1703294509.690 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-23T01:21:49.690Z,1703294509.690 [Default:CheckIn] Stopped
2023-12-23T01:21:49.690Z,1703294509.690 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-23T01:21:49.690Z,1703294509.690 [Default:CheckIn](INFO): Running loop #50
2023-12-23T01:21:49.690Z,1703294509.690 [Default:CheckIn] Running Loop=50
2023-12-23T01:21:49.690Z,1703294509.690 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-23T01:21:49.690Z,1703294509.690 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-23T01:21:51.743Z,1703294511.743 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,012150.00,A,3648.16569,N,12147.28737,W,1.089,267.58,231223,,,A*7A
2023-12-23T01:21:51.745Z,1703294511.745 [NAL9602](INFO): GPS fix at 20231223T012150: (36.802762, -121.788123)
2023-12-23T01:21:51.782Z,1703294511.782 [Default:CheckIn:Read_GPS] Stopped
2023-12-23T01:21:51.782Z,1703294511.782 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-23T01:21:58.709Z,1703294518.709 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20231222T210248/Courier0144.lzma
2023-12-23T01:21:59.710Z,1703294519.710 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0144.lzma.bak
2023-12-23T01:21:59.710Z,1703294519.710 [DataOverHttps](INFO): SBD MOMSN=19131684
2023-12-23T01:22:15.592Z,1703294535.592 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20231222T210248/Express0145.lzma
2023-12-23T01:22:16.594Z,1703294536.594 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0145.lzma.bak
2023-12-23T01:22:16.594Z,1703294536.594 [DataOverHttps](INFO): SBD MOMSN=19131687
2023-12-23T01:22:18.014Z,1703294538.014 [Default:CheckIn:Read_Iridium] Stopped
2023-12-23T01:22:18.014Z,1703294538.014 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-23T01:22:18.015Z,1703294538.015 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-23T01:22:22.457Z,1703294542.457 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-12-23T01:22:22.532Z,1703294542.532 [NAL9602](ERROR): received: 
+CSQ:0

OK682, 0, 0, 0, 0

OK
2023-12-23T01:23:31.530Z,1703294611.530 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-12-23T01:23:31.533Z,1703294611.533 [BPC1](INFO): Received data from all battery sticks.
2023-12-23T01:26:08.686Z,1703294768.686 [NAL9602](INFO): SBD MO Status=2, MOMSN=14683, MT Status=2, MTMSN=0
2023-12-23T01:26:08.686Z,1703294768.686 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-12-23T01:26:54.337Z,1703294814.337 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-12-23T01:27:18.604Z,1703294838.604 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-23T01:27:18.605Z,1703294838.605 [Default:CheckIn:C.Wait] Stopped
2023-12-23T01:27:18.605Z,1703294838.605 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-23T01:27:18.605Z,1703294838.605 [Default:CheckIn:D] Running Loop=1
2023-12-23T01:27:18.992Z,1703294838.992 [Default:CheckIn:D] Stopped
2023-12-23T01:27:18.992Z,1703294838.992 [Default:CheckIn:E] Running Loop=1
2023-12-23T01:27:19.401Z,1703294839.401 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 278.133236 min 
2023-12-23T01:27:19.401Z,1703294839.401 [Default:CheckIn:E] Stopped
2023-12-23T01:27:19.401Z,1703294839.401 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-23T01:27:19.402Z,1703294839.402 [Default:CheckIn] Stopped
2023-12-23T01:27:19.402Z,1703294839.402 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-23T01:27:19.402Z,1703294839.402 [Default:CheckIn](INFO): Running loop #51
2023-12-23T01:27:19.402Z,1703294839.402 [Default:CheckIn] Running Loop=51
2023-12-23T01:27:19.402Z,1703294839.402 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-23T01:27:19.402Z,1703294839.402 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-23T01:27:21.407Z,1703294841.407 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,012720.00,A,3648.17461,N,12147.27691,W,1.302,0.00,231223,,,A*7F
2023-12-23T01:27:21.409Z,1703294841.409 [NAL9602](INFO): GPS fix at 20231223T012720: (36.802910, -121.787948)
2023-12-23T01:27:21.437Z,1703294841.437 [Default:CheckIn:Read_GPS] Stopped
2023-12-23T01:27:21.437Z,1703294841.437 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-23T01:27:28.339Z,1703294848.339 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20231222T210248/Courier0147.lzma
2023-12-23T01:27:29.342Z,1703294849.342 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0147.lzma.bak
2023-12-23T01:27:29.342Z,1703294849.342 [DataOverHttps](INFO): SBD MOMSN=19131697
2023-12-23T01:27:45.216Z,1703294865.216 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20231222T210248/Express0148.lzma
2023-12-23T01:27:46.218Z,1703294866.218 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0148.lzma.bak
2023-12-23T01:27:46.218Z,1703294866.218 [DataOverHttps](INFO): SBD MOMSN=19131700
2023-12-23T01:27:47.693Z,1703294867.693 [Default:CheckIn:Read_Iridium] Stopped
2023-12-23T01:27:47.693Z,1703294867.693 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-23T01:27:47.693Z,1703294867.693 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-23T01:27:53.321Z,1703294873.321 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-23T01:31:48.039Z,1703295108.039 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2023-12-23T01:31:48.039Z,1703295108.039 [DropWeight] Hardware Fault, FailCount= 1
2023-12-23T01:31:48.039Z,1703295108.039 [DropWeight](ERROR): Hardware Fault
2023-12-23T01:31:48.087Z,1703295108.087 [CBIT](INFO): Critical error at 20231223T013148
2023-12-23T01:31:48.094Z,1703295108.094 [CBIT](ERROR): Hardware Fault in component: DropWeight
2023-12-23T01:31:48.094Z,1703295108.094 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2023-12-23T01:31:48.494Z,1703295108.494 [CBIT](INFO): Critical error at 20231223T013148
2023-12-23T01:32:48.247Z,1703295168.247 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-23T01:32:48.247Z,1703295168.247 [Default:CheckIn:C.Wait] Stopped
2023-12-23T01:32:48.247Z,1703295168.247 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-23T01:32:48.248Z,1703295168.248 [Default:CheckIn:D] Running Loop=1
2023-12-23T01:32:48.656Z,1703295168.656 [Default:CheckIn:D] Stopped
2023-12-23T01:32:48.656Z,1703295168.656 [Default:CheckIn:E] Running Loop=1
2023-12-23T01:32:49.069Z,1703295169.069 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 283.627637 min 
2023-12-23T01:32:49.069Z,1703295169.069 [Default:CheckIn:E] Stopped
2023-12-23T01:32:49.069Z,1703295169.069 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-23T01:32:49.069Z,1703295169.069 [Default:CheckIn] Stopped
2023-12-23T01:32:49.069Z,1703295169.069 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-23T01:32:49.069Z,1703295169.069 [Default:CheckIn](INFO): Running loop #52
2023-12-23T01:32:49.069Z,1703295169.069 [Default:CheckIn] Running Loop=52
2023-12-23T01:32:49.070Z,1703295169.070 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-23T01:32:49.070Z,1703295169.070 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-23T01:32:51.071Z,1703295171.071 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,013250.00,A,3648.17738,N,12147.28059,W,0.661,327.44,231223,,,A*79
2023-12-23T01:32:51.082Z,1703295171.082 [NAL9602](INFO): GPS fix at 20231223T013250: (36.802956, -121.788010)
2023-12-23T01:32:51.094Z,1703295171.094 [Default:CheckIn:Read_GPS] Stopped
2023-12-23T01:32:51.094Z,1703295171.094 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-23T01:32:58.668Z,1703295178.668 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20231222T210248/Courier0150.lzma
2023-12-23T01:32:59.671Z,1703295179.671 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0150.lzma.bak
2023-12-23T01:32:59.672Z,1703295179.672 [DataOverHttps](INFO): SBD MOMSN=19131711
2023-12-23T01:33:15.640Z,1703295195.640 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20231222T210248/Express0151.lzma
2023-12-23T01:33:16.642Z,1703295196.642 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0151.lzma.bak
2023-12-23T01:33:16.642Z,1703295196.642 [DataOverHttps](INFO): SBD MOMSN=19131716
2023-12-23T01:33:18.174Z,1703295198.174 [Default:CheckIn:Read_Iridium] Stopped
2023-12-23T01:33:18.174Z,1703295198.174 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-23T01:33:18.174Z,1703295198.174 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-23T01:33:23.057Z,1703295203.057 [CBIT](IMPORTANT): Beginning ground fault scan
2023-12-23T01:33:34.337Z,1703295214.337 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.005114
CHAN A1 (24V): 0.000292
CHAN A2 (12V): -0.005008
CHAN A3 (5V): -0.003031
CHAN B0 (3.3V): -0.000466
CHAN B1 (3.15aV): -0.000952
CHAN B2 (3.15bV): -0.000805
CHAN B3 (GND): -0.000753
OPEN: 0.004731
Full Scale: +/- 1 mA
2023-12-23T01:37:53.261Z,1703295473.261 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-12-23T01:38:18.720Z,1703295498.720 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-23T01:38:18.720Z,1703295498.720 [Default:CheckIn:C.Wait] Stopped
2023-12-23T01:38:18.720Z,1703295498.720 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-23T01:38:18.720Z,1703295498.720 [Default:CheckIn:D] Running Loop=1
2023-12-23T01:38:19.127Z,1703295499.127 [Default:CheckIn:D] Stopped
2023-12-23T01:38:19.128Z,1703295499.128 [Default:CheckIn:E] Running Loop=1
2023-12-23T01:38:19.544Z,1703295499.544 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 289.135482 min 
2023-12-23T01:38:19.545Z,1703295499.545 [Default:CheckIn:E] Stopped
2023-12-23T01:38:19.545Z,1703295499.545 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-23T01:38:19.545Z,1703295499.545 [Default:CheckIn] Stopped
2023-12-23T01:38:19.545Z,1703295499.545 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-23T01:38:19.545Z,1703295499.545 [Default:CheckIn](INFO): Running loop #53
2023-12-23T01:38:19.545Z,1703295499.545 [Default:CheckIn] Running Loop=53
2023-12-23T01:38:19.545Z,1703295499.545 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-23T01:38:19.545Z,1703295499.545 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-23T01:38:21.539Z,1703295501.539 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,013820.00,A,3648.16573,N,12147.28467,W,1.302,293.28,231223,,,A*74
2023-12-23T01:38:21.541Z,1703295501.541 [NAL9602](INFO): GPS fix at 20231223T013820: (36.802762, -121.788078)
2023-12-23T01:38:21.573Z,1703295501.573 [Default:CheckIn:Read_GPS] Stopped
2023-12-23T01:38:21.573Z,1703295501.573 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-23T01:38:29.683Z,1703295509.683 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231222T210248/Courier0153.lzma
2023-12-23T01:38:30.686Z,1703295510.686 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0153.lzma.bak
2023-12-23T01:38:30.686Z,1703295510.686 [DataOverHttps](INFO): SBD MOMSN=19131726
2023-12-23T01:38:46.540Z,1703295526.540 [DataOverHttps](INFO): Sending 379 bytes from file Logs/20231222T210248/Express0154.lzma
2023-12-23T01:38:47.542Z,1703295527.542 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0154.lzma.bak
2023-12-23T01:38:47.542Z,1703295527.542 [DataOverHttps](INFO): SBD MOMSN=19131729
2023-12-23T01:38:49.029Z,1703295529.029 [Default:CheckIn:Read_Iridium] Stopped
2023-12-23T01:38:49.029Z,1703295529.029 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-23T01:38:49.029Z,1703295529.029 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-23T01:38:53.465Z,1703295533.465 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-23T01:43:49.635Z,1703295829.635 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-23T01:43:49.636Z,1703295829.636 [Default:CheckIn:C.Wait] Stopped
2023-12-23T01:43:49.636Z,1703295829.636 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-23T01:43:49.637Z,1703295829.637 [Default:CheckIn:D] Running Loop=1
2023-12-23T01:43:50.039Z,1703295830.039 [Default:CheckIn:D] Stopped
2023-12-23T01:43:50.039Z,1703295830.039 [Default:CheckIn:E] Running Loop=1
2023-12-23T01:43:50.448Z,1703295830.448 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 294.650684 min 
2023-12-23T01:43:50.448Z,1703295830.448 [Default:CheckIn:E] Stopped
2023-12-23T01:43:50.448Z,1703295830.448 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-23T01:43:50.448Z,1703295830.448 [Default:CheckIn] Stopped
2023-12-23T01:43:50.449Z,1703295830.449 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-23T01:43:50.449Z,1703295830.449 [Default:CheckIn](INFO): Running loop #54
2023-12-23T01:43:50.449Z,1703295830.449 [Default:CheckIn] Running Loop=54
2023-12-23T01:43:50.449Z,1703295830.449 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-23T01:43:50.449Z,1703295830.449 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-23T01:43:52.450Z,1703295832.450 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,014351.00,A,3648.17233,N,12147.28647,W,0.389,164.53,231223,,,A*79
2023-12-23T01:43:52.465Z,1703295832.465 [NAL9602](INFO): GPS fix at 20231223T014351: (36.802872, -121.788108)
2023-12-23T01:43:52.493Z,1703295832.493 [Default:CheckIn:Read_GPS] Stopped
2023-12-23T01:43:52.493Z,1703295832.493 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-23T01:44:00.103Z,1703295840.103 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20231222T210248/Courier0156.lzma
2023-12-23T01:44:01.106Z,1703295841.106 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0156.lzma.bak
2023-12-23T01:44:01.106Z,1703295841.106 [DataOverHttps](INFO): SBD MOMSN=19131741
2023-12-23T01:44:17.092Z,1703295857.092 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20231222T210248/Express0157.lzma
2023-12-23T01:44:18.094Z,1703295858.094 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0157.lzma.bak
2023-12-23T01:44:18.094Z,1703295858.094 [DataOverHttps](INFO): SBD MOMSN=19131744
2023-12-23T01:44:19.541Z,1703295859.541 [Default:CheckIn:Read_Iridium] Stopped
2023-12-23T01:44:19.541Z,1703295859.541 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-23T01:44:19.541Z,1703295859.541 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-23T01:44:23.157Z,1703295863.157 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-12-23T01:44:23.232Z,1703295863.232 [NAL9602](ERROR): received: 
+CSQ:0

OK683, 2, 0, 0, 0

OK
2023-12-23T01:46:48.222Z,1703296008.222 [CBIT](INFO): Clearing failed state for component DropWeight
2023-12-23T01:46:48.222Z,1703296008.222 [DropWeight] No Fault, FailCount= 1
2023-12-23T01:47:35.862Z,1703296055.862 [NAL9602](INFO): SBD MO Status=2, MOMSN=14683, MT Status=2, MTMSN=0
2023-12-23T01:47:35.862Z,1703296055.862 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-12-23T01:48:54.241Z,1703296134.241 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-12-23T01:49:20.103Z,1703296160.103 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-23T01:49:20.103Z,1703296160.103 [Default:CheckIn:C.Wait] Stopped
2023-12-23T01:49:20.103Z,1703296160.103 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-23T01:49:20.103Z,1703296160.103 [Default:CheckIn:D] Running Loop=1
2023-12-23T01:49:20.516Z,1703296160.516 [Default:CheckIn:D] Stopped
2023-12-23T01:49:20.516Z,1703296160.516 [Default:CheckIn:E] Running Loop=1
2023-12-23T01:49:20.924Z,1703296160.924 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 300.158626 min 
2023-12-23T01:49:20.924Z,1703296160.924 [Default:CheckIn:E] Stopped
2023-12-23T01:49:20.925Z,1703296160.925 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-23T01:49:20.925Z,1703296160.925 [Default:CheckIn] Stopped
2023-12-23T01:49:20.925Z,1703296160.925 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-23T01:49:20.925Z,1703296160.925 [Default:CheckIn](INFO): Running loop #55
2023-12-23T01:49:20.925Z,1703296160.925 [Default:CheckIn] Running Loop=55
2023-12-23T01:49:20.925Z,1703296160.925 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-23T01:49:20.925Z,1703296160.925 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-23T01:49:22.927Z,1703296162.927 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,014922.00,A,3648.18030,N,12147.28593,W,1.205,0.00,231223,,,A*72
2023-12-23T01:49:22.929Z,1703296162.929 [NAL9602](INFO): GPS fix at 20231223T014922: (36.803005, -121.788099)
2023-12-23T01:49:22.941Z,1703296162.941 [Default:CheckIn:Read_GPS] Stopped
2023-12-23T01:49:22.941Z,1703296162.941 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-23T01:49:24.538Z,1703296164.538 [NAL9602](FAULT): LCB fault:  Invalid Command. Hardware Overcurrent Shutdown. Current Limiter Activated.
2023-12-23T01:49:24.538Z,1703296164.538 [NAL9602] Hardware Fault, FailCount= 1
2023-12-23T01:49:24.538Z,1703296164.538 [NAL9602](ERROR): Hardware Fault
2023-12-23T01:49:24.596Z,1703296164.596 [CBIT](ERROR): Hardware Fault in component: NAL9602
2023-12-23T01:49:24.945Z,1703296164.945 [NAL9602](INFO): Powering down
2023-12-23T01:49:25.844Z,1703296165.844 [CBIT](INFO): Clearing failed state for component NAL9602
2023-12-23T01:49:25.844Z,1703296165.844 [NAL9602] No Fault, FailCount= 1
2023-12-23T01:49:30.311Z,1703296170.311 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20231222T210248/Courier0159.lzma
2023-12-23T01:49:31.314Z,1703296171.314 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0159.lzma.bak
2023-12-23T01:49:31.314Z,1703296171.314 [DataOverHttps](INFO): SBD MOMSN=19131754
2023-12-23T01:49:47.379Z,1703296187.379 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20231222T210248/Express0160.lzma
2023-12-23T01:49:48.134Z,1703296188.134 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0160.lzma.bak
2023-12-23T01:49:48.134Z,1703296188.134 [DataOverHttps](INFO): SBD MOMSN=19131757
2023-12-23T01:49:49.605Z,1703296189.605 [Default:CheckIn:Read_Iridium] Stopped
2023-12-23T01:49:49.605Z,1703296189.605 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-23T01:49:49.605Z,1703296189.605 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-23T01:49:55.240Z,1703296195.240 [NAL9602](INFO): Powering up NAL9602
2023-12-23T01:50:06.148Z,1703296206.148 [NAL9602](INFO): NAL9602 initialized
2023-12-23T01:50:37.257Z,1703296237.257 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-23T01:54:50.197Z,1703296490.197 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-23T01:54:50.197Z,1703296490.197 [Default:CheckIn:C.Wait] Stopped
2023-12-23T01:54:50.197Z,1703296490.197 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-23T01:54:50.198Z,1703296490.198 [Default:CheckIn:D] Running Loop=1
2023-12-23T01:54:50.600Z,1703296490.600 [Default:CheckIn:D] Stopped
2023-12-23T01:54:50.600Z,1703296490.600 [Default:CheckIn:E] Running Loop=1
2023-12-23T01:54:51.009Z,1703296491.009 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 305.660026 min 
2023-12-23T01:54:51.009Z,1703296491.009 [Default:CheckIn:E] Stopped
2023-12-23T01:54:51.009Z,1703296491.009 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-23T01:54:51.009Z,1703296491.009 [Default:CheckIn] Stopped
2023-12-23T01:54:51.009Z,1703296491.009 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-23T01:54:51.009Z,1703296491.009 [Default:CheckIn](INFO): Running loop #56
2023-12-23T01:54:51.009Z,1703296491.009 [Default:CheckIn] Running Loop=56
2023-12-23T01:54:51.010Z,1703296491.010 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-23T01:54:51.010Z,1703296491.010 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-23T01:55:22.909Z,1703296522.909 [NAL9602](ERROR): parseGSV uart error: serial timeout
2023-12-23T01:59:51.176Z,1703296791.176 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-12-23T01:54:51.0Z
2023-12-23T01:59:51.176Z,1703296791.176 [Default:CheckIn:Read_GPS] Stopped
2023-12-23T01:59:51.177Z,1703296791.177 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-23T01:59:58.427Z,1703296798.427 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20231222T210248/Courier0162.lzma
2023-12-23T01:59:59.430Z,1703296799.430 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0162.lzma.bak
2023-12-23T01:59:59.430Z,1703296799.430 [DataOverHttps](INFO): SBD MOMSN=19131814
2023-12-23T02:00:15.388Z,1703296815.388 [DataOverHttps](INFO): Sending 210 bytes from file Logs/20231222T210248/Express0163.lzma
2023-12-23T02:00:16.390Z,1703296816.390 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0163.lzma.bak
2023-12-23T02:00:16.390Z,1703296816.390 [DataOverHttps](INFO): SBD MOMSN=19131816
2023-12-23T02:00:17.873Z,1703296817.873 [Default:CheckIn:Read_Iridium] Stopped
2023-12-23T02:00:17.873Z,1703296817.873 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-23T02:00:17.873Z,1703296817.873 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-23T02:00:23.491Z,1703296823.491 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-12-23T02:00:54.193Z,1703296854.193 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-23T02:05:18.428Z,1703297118.428 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-23T02:05:18.428Z,1703297118.428 [Default:CheckIn:C.Wait] Stopped
2023-12-23T02:05:18.429Z,1703297118.429 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-23T02:05:18.429Z,1703297118.429 [Default:CheckIn:D] Running Loop=1
2023-12-23T02:05:18.820Z,1703297118.820 [Default:CheckIn:D] Stopped
2023-12-23T02:05:18.820Z,1703297118.820 [Default:CheckIn:E] Running Loop=1
2023-12-23T02:05:19.223Z,1703297119.223 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 316.130371 min 
2023-12-23T02:05:19.223Z,1703297119.223 [Default:CheckIn:E] Stopped
2023-12-23T02:05:19.224Z,1703297119.224 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-23T02:05:19.224Z,1703297119.224 [Default:CheckIn] Stopped
2023-12-23T02:05:19.224Z,1703297119.224 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-23T02:05:19.224Z,1703297119.224 [Default:CheckIn](INFO): Running loop #57
2023-12-23T02:05:19.224Z,1703297119.224 [Default:CheckIn] Running Loop=57
2023-12-23T02:05:19.224Z,1703297119.224 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-23T02:05:19.224Z,1703297119.224 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-23T02:05:20.833Z,1703297120.833 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-12-23T02:05:20.833Z,1703297120.833 [NAL9602] Data Fault, FailCount= 1
2023-12-23T02:05:20.833Z,1703297120.833 [NAL9602](ERROR): Data Fault
2023-12-23T02:05:20.854Z,1703297120.854 [CBIT](ERROR): Data Fault in component: NAL9602
2023-12-23T02:05:21.238Z,1703297121.238 [NAL9602](INFO): Powering down
2023-12-23T02:05:22.092Z,1703297122.092 [CBIT](INFO): Clearing failed state for component NAL9602
2023-12-23T02:05:22.093Z,1703297122.093 [NAL9602] No Fault, FailCount= 1
2023-12-23T02:05:51.533Z,1703297151.533 [NAL9602](INFO): Powering up NAL9602
2023-12-23T02:06:02.440Z,1703297162.440 [NAL9602](INFO): NAL9602 initialized
2023-12-23T02:10:19.438Z,1703297419.438 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-12-23T02:05:19.2Z
2023-12-23T02:10:19.438Z,1703297419.438 [Default:CheckIn:Read_GPS] Stopped
2023-12-23T02:10:19.438Z,1703297419.438 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-23T02:10:26.803Z,1703297426.803 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20231222T210248/Courier0165.lzma
2023-12-23T02:10:27.806Z,1703297427.806 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0165.lzma.bak
2023-12-23T02:10:27.807Z,1703297427.807 [DataOverHttps](INFO): SBD MOMSN=19131827
2023-12-23T02:10:43.636Z,1703297443.636 [DataOverHttps](INFO): Sending 241 bytes from file Logs/20231222T210248/Express0166.lzma
2023-12-23T02:10:44.638Z,1703297444.638 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0166.lzma.bak
2023-12-23T02:10:44.638Z,1703297444.638 [DataOverHttps](INFO): SBD MOMSN=19131832
2023-12-23T02:10:46.078Z,1703297446.078 [Default:CheckIn:Read_Iridium] Stopped
2023-12-23T02:10:46.078Z,1703297446.078 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-23T02:10:46.078Z,1703297446.078 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-23T02:11:05.917Z,1703297465.917 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-12-23T02:11:36.621Z,1703297496.621 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-23T02:15:46.759Z,1703297746.759 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-23T02:15:46.759Z,1703297746.759 [Default:CheckIn:C.Wait] Stopped
2023-12-23T02:15:46.768Z,1703297746.768 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-23T02:15:46.768Z,1703297746.768 [Default:CheckIn:D] Running Loop=1
2023-12-23T02:15:47.140Z,1703297747.140 [Default:CheckIn:D] Stopped
2023-12-23T02:15:47.140Z,1703297747.140 [Default:CheckIn:E] Running Loop=1
2023-12-23T02:15:47.553Z,1703297747.553 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 326.602376 min 
2023-12-23T02:15:47.553Z,1703297747.553 [Default:CheckIn:E] Stopped
2023-12-23T02:15:47.553Z,1703297747.553 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-23T02:15:47.553Z,1703297747.553 [Default:CheckIn] Stopped
2023-12-23T02:15:47.553Z,1703297747.553 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-23T02:15:47.553Z,1703297747.553 [Default:CheckIn](INFO): Running loop #58
2023-12-23T02:15:47.554Z,1703297747.554 [Default:CheckIn] Running Loop=58
2023-12-23T02:15:47.554Z,1703297747.554 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-23T02:15:47.554Z,1703297747.554 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-23T02:16:20.233Z,1703297780.233 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-12-23T02:16:20.316Z,1703297780.316 [NAL9602](ERROR): received: 
+CSQ:0

OK683, 2, 0, 0, 0

OK
2023-12-23T02:16:49.725Z,1703297809.725 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2023-12-23T02:16:49.725Z,1703297809.725 [DropWeight] Hardware Fault, FailCount= 1
2023-12-23T02:16:49.725Z,1703297809.725 [DropWeight](ERROR): Hardware Fault
2023-12-23T02:16:49.820Z,1703297809.820 [CBIT](INFO): Critical error at 20231223T021649
2023-12-23T02:16:49.823Z,1703297809.823 [CBIT](ERROR): Hardware Fault in component: DropWeight
2023-12-23T02:16:49.823Z,1703297809.823 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2023-12-23T02:16:50.182Z,1703297810.182 [CBIT](INFO): Critical error at 20231223T021649
2023-12-23T02:20:47.750Z,1703298047.750 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-12-23T02:15:47.6Z
2023-12-23T02:20:47.750Z,1703298047.750 [Default:CheckIn:Read_GPS] Stopped
2023-12-23T02:20:47.750Z,1703298047.750 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-23T02:20:52.097Z,1703298052.097 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-12-23T02:20:56.083Z,1703298056.083 [DataOverHttps](INFO): Sending 116 bytes from file Logs/20231222T210248/Courier0168.lzma
2023-12-23T02:20:56.846Z,1703298056.846 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0168.lzma.bak
2023-12-23T02:20:56.846Z,1703298056.846 [DataOverHttps](INFO): SBD MOMSN=19131847
2023-12-23T02:21:21.808Z,1703298081.808 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20231222T210248/Express0169.lzma
2023-12-23T02:21:22.805Z,1703298082.805 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-23T02:21:22.810Z,1703298082.810 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0169.lzma.bak
2023-12-23T02:21:22.810Z,1703298082.810 [DataOverHttps](INFO): SBD MOMSN=19131851
2023-12-23T02:21:24.449Z,1703298084.449 [Default:CheckIn:Read_Iridium] Stopped
2023-12-23T02:21:24.449Z,1703298084.449 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-23T02:21:24.449Z,1703298084.449 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-23T02:26:13.749Z,1703298373.749 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-12-23T02:26:25.019Z,1703298385.019 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-23T02:26:25.019Z,1703298385.019 [Default:CheckIn:C.Wait] Stopped
2023-12-23T02:26:25.019Z,1703298385.019 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-23T02:26:25.019Z,1703298385.019 [Default:CheckIn:D] Running Loop=1
2023-12-23T02:26:25.422Z,1703298385.422 [Default:CheckIn:D] Stopped
2023-12-23T02:26:25.422Z,1703298385.422 [Default:CheckIn:E] Running Loop=1
2023-12-23T02:26:25.838Z,1703298385.838 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 337.240397 min 
2023-12-23T02:26:25.838Z,1703298385.838 [Default:CheckIn:E] Stopped
2023-12-23T02:26:25.838Z,1703298385.838 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-23T02:26:25.838Z,1703298385.838 [Default:CheckIn] Stopped
2023-12-23T02:26:25.838Z,1703298385.838 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-23T02:26:25.838Z,1703298385.838 [Default:CheckIn](INFO): Running loop #59
2023-12-23T02:26:25.838Z,1703298385.838 [Default:CheckIn] Running Loop=59
2023-12-23T02:26:25.838Z,1703298385.838 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-23T02:26:25.838Z,1703298385.838 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-23T02:26:27.461Z,1703298387.461 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-12-23T02:26:27.461Z,1703298387.461 [NAL9602] Data Fault, FailCount= 2
2023-12-23T02:26:27.461Z,1703298387.461 [NAL9602](ERROR): Data Fault
2023-12-23T02:26:27.481Z,1703298387.481 [CBIT](ERROR): Data Fault in component: NAL9602
2023-12-23T02:26:27.861Z,1703298387.861 [NAL9602](INFO): Powering down
2023-12-23T02:26:28.661Z,1703298388.661 [CBIT](INFO): Clearing failed state for component NAL9602
2023-12-23T02:26:28.661Z,1703298388.661 [NAL9602] No Fault, FailCount= 2
2023-12-23T02:26:58.142Z,1703298418.142 [NAL9602](INFO): Powering up NAL9602
2023-12-23T02:27:09.041Z,1703298429.041 [NAL9602](INFO): NAL9602 initialized
2023-12-23T02:30:56.901Z,1703298656.901 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-12-23T02:30:56.904Z,1703298656.904 [BPC1](INFO): Received data from all battery sticks.
2023-12-23T02:31:26.000Z,1703298686.000 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-12-23T02:26:25.8Z
2023-12-23T02:31:26.000Z,1703298686.000 [Default:CheckIn:Read_GPS] Stopped
2023-12-23T02:31:26.001Z,1703298686.001 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-23T02:31:33.055Z,1703298693.055 [DataOverHttps](INFO): Sending 49 bytes from file Logs/20231222T210248/Courier0171.lzma
2023-12-23T02:31:34.058Z,1703298694.058 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Courier0171.lzma.bak
2023-12-23T02:31:34.058Z,1703298694.058 [DataOverHttps](INFO): SBD MOMSN=19131855
2023-12-23T02:31:49.863Z,1703298709.863 [CBIT](INFO): Clearing failed state for component DropWeight
2023-12-23T02:31:49.863Z,1703298709.863 [DropWeight] No Fault, FailCount= 1
2023-12-23T02:31:50.336Z,1703298710.336 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20231222T210248/Express0172.lzma
2023-12-23T02:31:51.338Z,1703298711.338 [DataOverHttps](INFO): Moved sent file to Logs/20231222T210248/Express0172.lzma.bak
2023-12-23T02:31:51.338Z,1703298711.338 [DataOverHttps](INFO): SBD MOMSN=19131857
2023-12-23T02:31:52.674Z,1703298712.674 [Default:CheckIn:Read_Iridium] Stopped
2023-12-23T02:31:52.674Z,1703298712.674 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-23T02:31:52.674Z,1703298712.674 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-23T02:32:12.449Z,1703298732.449 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-12-23T02:32:43.149Z,1703298763.149 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-23T02:33:34.482Z,1703298814.482 [CBIT](IMPORTANT): Beginning ground fault scan
2023-12-23T02:33:45.414Z,1703298825.414 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.033555
CHAN A1 (24V): 0.000598
CHAN A2 (12V): -0.004934
CHAN A3 (5V): -0.003454
CHAN B0 (3.3V): -0.001526
CHAN B1 (3.15aV): -0.001911
CHAN B2 (3.15bV): -0.001974
CHAN B3 (GND): -0.000948
OPEN: 0.004585
Full Scale: +/- 1 mA
2023-12-23T02:36:29.132Z,1703298989.132 [CommandExec](IMPORTANT): got command quit
2023-12-23T02:36:30.140Z,1703298990.140 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-12-23T02:36:30.140Z,1703298990.140 [CommandExec](INFO): Uninitializing the command executive.
2023-12-23T02:36:30.140Z,1703298990.140 [CommandExec](INFO): Uninitializing the command scheduler.
2023-12-23T02:36:30.140Z,1703298990.140 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-12-23T02:36:30.244Z,1703298990.244 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-12-23T02:36:30.244Z,1703298990.244 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-12-23T02:36:30.244Z,1703298990.244 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-12-23T02:36:30.245Z,1703298990.245 [NavChartDb](INFO): Join timeout helper Thread ID is 1511
2023-12-23T02:36:30.400Z,1703298990.400 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-12-23T02:36:30.400Z,1703298990.400 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-12-23T02:36:30.420Z,1703298990.420 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-12-23T02:36:30.420Z,1703298990.420 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-12-23T02:36:30.420Z,1703298990.420 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1512
2023-12-23T02:36:30.508Z,1703298990.508 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-12-23T02:36:30.509Z,1703298990.509 [WetLabsBB2FL](INFO): Powering down
2023-12-23T02:36:30.509Z,1703298990.509 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-12-23T02:36:30.524Z,1703298990.524 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-12-23T02:36:30.524Z,1703298990.524 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-12-23T02:36:30.524Z,1703298990.524 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1513
2023-12-23T02:36:31.152Z,1703298991.152 [CTD_Seabird](INFO): Powering down
2023-12-23T02:36:31.164Z,1703298991.164 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-12-23T02:36:31.164Z,1703298991.164 [CTD_Seabird](INFO): Powering down
2023-12-23T02:36:31.176Z,1703298991.176 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-12-23T02:36:31.184Z,1703298991.184 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2023-12-23T02:36:31.184Z,1703298991.184 [DAT ThreadHandler](INFO): Thread cancelled.
2023-12-23T02:36:31.184Z,1703298991.184 [DAT](INFO): Join timeout helper Thread ID is 1514
2023-12-23T02:36:31.308Z,1703298991.308 [DAT](INFO): Powering down
2023-12-23T02:36:31.380Z,1703298991.380 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2023-12-23T02:36:31.381Z,1703298991.381 [DAT](INFO): Powering down
2023-12-23T02:36:31.381Z,1703298991.381 [DAT ThreadHandler](INFO): Thread cancelled.
2023-12-23T02:36:31.388Z,1703298991.388 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-12-23T02:36:31.388Z,1703298991.388 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-12-23T02:36:31.388Z,1703298991.388 [Radio_Surface](INFO): Join timeout helper Thread ID is 1515
2023-12-23T02:36:31.564Z,1703298991.564 [Radio_Surface](INFO): Powering down
2023-12-23T02:36:31.565Z,1703298991.565 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-12-23T02:36:31.565Z,1703298991.565 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-12-23T02:36:31.581Z,1703298991.581 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-12-23T02:36:31.581Z,1703298991.581 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-12-23T02:36:31.581Z,1703298991.581 [Onboard](INFO): Join timeout helper Thread ID is 1516
2023-12-23T02:36:31.668Z,1703298991.668 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2023-12-23T02:36:34.196Z,1703298994.196 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-12-23T02:36:34.196Z,1703298994.196 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-12-23T02:36:34.208Z,1703298994.208 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-12-23T02:36:34.208Z,1703298994.208 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-12-23T02:36:34.209Z,1703298994.209 [DataOverHttps](INFO): Join timeout helper Thread ID is 1517
2023-12-23T02:36:34.680Z,1703298994.680 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-12-23T02:36:34.680Z,1703298994.680 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-12-23T02:36:34.700Z,1703298994.700 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2023-12-23T02:36:34.701Z,1703298994.701 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-12-23T02:36:34.701Z,1703298994.701 [BackseatComponent](INFO): Join timeout helper Thread ID is 1518
2023-12-23T02:36:34.896Z,1703298994.896 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2023-12-23T02:36:34.896Z,1703298994.896 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-12-23T02:36:34.912Z,1703298994.912 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-12-23T02:36:34.912Z,1703298994.912 [logger ThreadHandler](INFO): Thread cancelled.
2023-12-23T02:36:34.913Z,1703298994.913 [logger](INFO): Join timeout helper Thread ID is 1519
2023-12-23T02:36:34.948Z,1703298994.948 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-12-23T02:36:34.948Z,1703298994.948 [logger ThreadHandler](INFO): Thread cancelled.
2023-12-23T02:36:34.952Z,1703298994.952 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-12-23T02:36:34.952Z,1703298994.952 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-12-23T02:36:34.953Z,1703298994.953 [CommandLine](INFO): Join timeout helper Thread ID is 1520
2023-12-23T02:36:35.044Z,1703298995.044 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-12-23T02:36:35.044Z,1703298995.044 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-12-23T02:36:35.053Z,1703298995.053 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-12-23T02:36:35.053Z,1703298995.053 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-12-23T02:36:35.053Z,1703298995.053 [CommandExec](INFO): Join timeout helper Thread ID is 1521
2023-12-23T02:36:35.068Z,1703298995.068 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-12-23T02:36:35.069Z,1703298995.069 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-12-23T02:36:35.069Z,1703298995.069 [controlThread](INFO): Join timeout helper Thread ID is 1522
2023-12-23T02:36:35.072Z,1703298995.072 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-12-23T02:36:35.072Z,1703298995.072 [controlThread](DEBUG): Uninitializing ControlThread
2023-12-23T02:36:35.073Z,1703298995.073 [AHRS_M2](INFO): Powering down
2023-12-23T02:36:35.145Z,1703298995.145 [NAL9602](INFO): Powering down
2023-12-23T02:36:35.146Z,1703298995.146 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-12-23T02:36:35.147Z,1703298995.147 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-12-23T02:36:35.148Z,1703298995.148 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-12-23T02:36:35.148Z,1703298995.148 [MissionManager](INFO): Uninitializing Mission Default
2023-12-23T02:36:35.149Z,1703298995.149 [Default] Stopped
2023-12-23T02:36:35.149Z,1703298995.149 [Default](DEBUG): Aggregate::uninitialize Default
2023-12-23T02:36:35.149Z,1703298995.149 [Default:B.GoToSurface] Stopped
2023-12-23T02:36:35.149Z,1703298995.149 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-12-23T02:36:35.149Z,1703298995.149 [Default:CheckIn] Stopped
2023-12-23T02:36:35.149Z,1703298995.149 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-23T02:36:35.149Z,1703298995.149 [Default:CheckIn:C.Wait] Stopped
2023-12-23T02:36:35.149Z,1703298995.149 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-23T02:36:35.152Z,1703298995.152 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-12-23T02:36:35.152Z,1703298995.152 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-12-23T02:36:35.153Z,1703298995.153 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-12-23T02:36:35.153Z,1703298995.153 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-12-23T02:36:35.153Z,1703298995.153 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-12-23T02:36:35.153Z,1703298995.153 [BuoyancyServo](INFO): Powering down
2023-12-23T02:36:35.168Z,1703298995.168 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-12-23T02:36:35.168Z,1703298995.168 [ElevatorServo](INFO): Powering down
2023-12-23T02:36:35.169Z,1703298995.169 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-12-23T02:36:35.169Z,1703298995.169 [MassServo](INFO): Powering down
2023-12-23T02:36:35.170Z,1703298995.170 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-12-23T02:36:35.170Z,1703298995.170 [RudderServo](INFO): Powering down
2023-12-23T02:36:35.171Z,1703298995.171 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-12-23T02:36:35.171Z,1703298995.171 [ThrusterHE](INFO): Powering down
2023-12-23T02:36:35.172Z,1703298995.172 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-12-23T02:36:35.172Z,1703298995.172 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-12-23T02:36:35.172Z,1703298995.172 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-12-23T02:36:35.172Z,1703298995.172 [CBIT](DEBUG): Powering off loads.
2023-12-23T02:36:35.184Z,1703298995.184 [CBIT](DEBUG): Disabling WDT.
2023-12-23T02:36:35.196Z,1703298995.196 [CBIT](DEBUG): Opening all GF detection circuits.
2023-12-23T02:36:35.197Z,1703298995.197 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-12-23T02:36:35.230Z,1703298995.230 [DAT ThreadHandler](INFO): Thread cancelled.
2023-12-23T02:36:35.235Z,1703298995.235 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-12-23T02:36:35.237Z,1703298995.237 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-12-23T02:36:35.243Z,1703298995.243 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-12-23T02:36:35.288Z,1703298995.288 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-12-23T02:36:35.338Z,1703298995.338 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-12-23T02:36:35.343Z,1703298995.343 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-12-23T02:36:35.393Z,1703298995.393 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-12-23T02:36:35.443Z,1703298995.443 [logger ThreadHandler](INFO): Thread cancelled.