2023-09-13T17:53:40.317Z,1694627620.317 [CommandExec](IMPORTANT): got command restart logs
2023-09-13T17:55:18.451Z,1694627718.451 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-13T17:55:18.451Z,1694627718.451 [Default:CheckIn:C.Wait] Stopped
2023-09-13T17:55:18.451Z,1694627718.451 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-13T17:55:18.451Z,1694627718.451 [Default:CheckIn:D] Running Loop=1
2023-09-13T17:55:18.863Z,1694627718.863 [Default:CheckIn:D] Stopped
2023-09-13T17:55:18.863Z,1694627718.863 [Default:CheckIn:E] Running Loop=1
2023-09-13T17:55:19.277Z,1694627719.277 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 29.450022 min 
2023-09-13T17:55:19.277Z,1694627719.277 [Default:CheckIn:E] Stopped
2023-09-13T17:55:19.278Z,1694627719.278 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-13T17:55:19.278Z,1694627719.278 [Default:CheckIn] Stopped
2023-09-13T17:55:19.278Z,1694627719.278 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-13T17:55:19.278Z,1694627719.278 [Default:CheckIn](INFO): Running loop #6
2023-09-13T17:55:19.278Z,1694627719.278 [Default:CheckIn] Running Loop=6
2023-09-13T17:55:19.278Z,1694627719.278 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-13T17:55:19.278Z,1694627719.278 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-13T17:55:21.275Z,1694627721.275 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175520.00,A,3648.45057,N,12149.38643,W,10.283,87.08,130923,,,D*77
2023-09-13T17:55:21.277Z,1694627721.277 [NAL9602](INFO): GPS fix at 20230913T175520: (36.807510, -121.823107)
2023-09-13T17:55:21.287Z,1694627721.287 [Default:CheckIn:Read_GPS] Stopped
2023-09-13T17:55:21.287Z,1694627721.287 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-13T17:55:46.318Z,1694627746.318 [NAL9602](INFO): SBD MO Status=2, MOMSN=45045, MT Status=2, MTMSN=0
2023-09-13T17:55:46.319Z,1694627746.319 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-13T17:55:53.212Z,1694627753.212 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-13T17:56:03.755Z,1694627763.755 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230912T215823/Courier0132.lzma
2023-09-13T17:56:04.497Z,1694627764.497 [NAL9602](INFO): SBD MO Status=0, MOMSN=45045, MT Status=0, MTMSN=0
2023-09-13T17:56:04.497Z,1694627764.497 [NAL9602](INFO): No messages in MT queue
2023-09-13T17:56:04.758Z,1694627764.758 [DataOverHttps](INFO): Moved sent file to Logs/20230912T215823/Courier0132.lzma.bak
2023-09-13T17:56:04.758Z,1694627764.758 [DataOverHttps](INFO): SBD MOMSN=18766438
2023-09-13T17:56:21.568Z,1694627781.568 [DataOverHttps](INFO): Sending 266 bytes from file Logs/20230913T175340/Courier0000.lzma
2023-09-13T17:56:22.570Z,1694627782.570 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175340/Courier0000.lzma.bak
2023-09-13T17:56:22.570Z,1694627782.570 [DataOverHttps](INFO): SBD MOMSN=18766440
2023-09-13T17:56:35.213Z,1694627795.213 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-13T17:57:25.216Z,1694627845.216 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-13T17:57:56.340Z,1694627876.340 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-13T17:58:27.472Z,1694627907.472 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-13T17:59:07.095Z,1694627947.095 [DataOverHttps](INFO): Sending 106 bytes from file Logs/20230912T215823/Express0133.lzma
2023-09-13T17:59:08.098Z,1694627948.098 [DataOverHttps](INFO): Moved sent file to Logs/20230912T215823/Express0133.lzma.bak
2023-09-13T17:59:08.098Z,1694627948.098 [DataOverHttps](INFO): SBD MOMSN=18766445
2023-09-13T17:59:23.756Z,1694627963.756 [DataOverHttps](INFO): Sending 390 bytes from file Logs/20230913T175340/Express0001.lzma
2023-09-13T17:59:24.757Z,1694627964.757 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175340/Express0001.lzma.bak
2023-09-13T17:59:24.757Z,1694627964.757 [DataOverHttps](INFO): SBD MOMSN=18766451
2023-09-13T17:59:26.172Z,1694627966.172 [Default:CheckIn:Read_Iridium] Stopped
2023-09-13T17:59:26.172Z,1694627966.172 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-13T17:59:26.172Z,1694627966.172 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-13T18:04:26.750Z,1694628266.750 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-13T18:04:26.751Z,1694628266.751 [Default:CheckIn:C.Wait] Stopped
2023-09-13T18:04:26.751Z,1694628266.751 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-13T18:04:26.751Z,1694628266.751 [Default:CheckIn:D] Running Loop=1
2023-09-13T18:04:27.152Z,1694628267.152 [Default:CheckIn:D] Stopped
2023-09-13T18:04:27.152Z,1694628267.152 [Default:CheckIn:E] Running Loop=1
2023-09-13T18:04:27.562Z,1694628267.562 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 38.588159 min 
2023-09-13T18:04:27.562Z,1694628267.562 [Default:CheckIn:E] Stopped
2023-09-13T18:04:27.562Z,1694628267.562 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-13T18:04:27.562Z,1694628267.562 [Default:CheckIn] Stopped
2023-09-13T18:04:27.562Z,1694628267.562 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-13T18:04:27.562Z,1694628267.562 [Default:CheckIn](INFO): Running loop #7
2023-09-13T18:04:27.563Z,1694628267.563 [Default:CheckIn] Running Loop=7
2023-09-13T18:04:27.563Z,1694628267.563 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-13T18:04:27.563Z,1694628267.563 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-13T18:04:29.571Z,1694628269.571 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180428.00,A,3648.41378,N,12147.12044,W,4.801,184.49,130923,,,D*7B
2023-09-13T18:04:29.573Z,1694628269.573 [NAL9602](INFO): GPS fix at 20230913T180428: (36.806896, -121.785341)
2023-09-13T18:04:29.606Z,1694628269.606 [Default:CheckIn:Read_GPS] Stopped
2023-09-13T18:04:29.606Z,1694628269.606 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-13T18:04:37.275Z,1694628277.275 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230913T175340/Courier0006.lzma
2023-09-13T18:04:38.277Z,1694628278.277 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175340/Courier0006.lzma.bak
2023-09-13T18:04:38.278Z,1694628278.278 [DataOverHttps](INFO): SBD MOMSN=18766459
2023-09-13T18:04:42.951Z,1694628282.951 [NAL9602](INFO): SBD MO Status=0, MOMSN=45046, MT Status=0, MTMSN=0
2023-09-13T18:04:42.951Z,1694628282.951 [NAL9602](INFO): No messages in MT queue
2023-09-13T18:04:57.076Z,1694628297.076 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20230913T175340/Express0007.lzma
2023-09-13T18:04:58.078Z,1694628298.078 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175340/Express0007.lzma.bak
2023-09-13T18:04:58.078Z,1694628298.078 [DataOverHttps](INFO): SBD MOMSN=18766462
2023-09-13T18:04:59.595Z,1694628299.595 [Default:CheckIn:Read_Iridium] Stopped
2023-09-13T18:04:59.595Z,1694628299.595 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-13T18:04:59.595Z,1694628299.595 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-13T18:05:13.715Z,1694628313.715 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-13T18:10:00.176Z,1694628600.176 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-13T18:10:00.176Z,1694628600.176 [Default:CheckIn:C.Wait] Stopped
2023-09-13T18:10:00.176Z,1694628600.176 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-13T18:10:00.176Z,1694628600.176 [Default:CheckIn:D] Running Loop=1
2023-09-13T18:10:00.592Z,1694628600.592 [Default:CheckIn:D] Stopped
2023-09-13T18:10:00.592Z,1694628600.592 [Default:CheckIn:E] Running Loop=1
2023-09-13T18:10:00.978Z,1694628600.978 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 44.145504 min 
2023-09-13T18:10:00.978Z,1694628600.978 [Default:CheckIn:E] Stopped
2023-09-13T18:10:00.978Z,1694628600.978 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-13T18:10:00.978Z,1694628600.978 [Default:CheckIn] Stopped
2023-09-13T18:10:00.978Z,1694628600.978 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-13T18:10:00.979Z,1694628600.979 [Default:CheckIn](INFO): Running loop #8
2023-09-13T18:10:00.979Z,1694628600.979 [Default:CheckIn] Running Loop=8
2023-09-13T18:10:00.979Z,1694628600.979 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-13T18:10:00.979Z,1694628600.979 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-13T18:10:02.994Z,1694628602.994 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181002.00,A,3648.13523,N,12147.20669,W,0.175,234.33,130923,,,D*7A
2023-09-13T18:10:02.997Z,1694628602.997 [NAL9602](INFO): GPS fix at 20230913T181002: (36.802254, -121.786778)
2023-09-13T18:10:03.007Z,1694628603.007 [Default:CheckIn:Read_GPS] Stopped
2023-09-13T18:10:03.008Z,1694628603.008 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-13T18:10:10.343Z,1694628610.343 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230913T175340/Courier0009.lzma
2023-09-13T18:10:11.345Z,1694628611.345 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175340/Courier0009.lzma.bak
2023-09-13T18:10:11.346Z,1694628611.346 [DataOverHttps](INFO): SBD MOMSN=18766476
2023-09-13T18:10:26.830Z,1694628626.830 [NAL9602](INFO): SBD MO Status=2, MOMSN=45047, MT Status=2, MTMSN=0
2023-09-13T18:10:26.830Z,1694628626.830 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-13T18:10:27.104Z,1694628627.104 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20230913T175340/Express0010.lzma
2023-09-13T18:10:28.105Z,1694628628.105 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175340/Express0010.lzma.bak
2023-09-13T18:10:28.106Z,1694628628.106 [DataOverHttps](INFO): SBD MOMSN=18766479
2023-09-13T18:10:29.258Z,1694628629.258 [Default:CheckIn:Read_Iridium] Stopped
2023-09-13T18:10:29.259Z,1694628629.259 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-13T18:10:29.259Z,1694628629.259 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-13T18:12:01.771Z,1694628721.771 [NAL9602](INFO): SBD MO Status=0, MOMSN=45047, MT Status=0, MTMSN=0
2023-09-13T18:12:01.771Z,1694628721.771 [NAL9602](INFO): No messages in MT queue
2023-09-13T18:12:32.467Z,1694628752.467 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-13T18:15:29.840Z,1694628929.840 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-13T18:15:29.840Z,1694628929.840 [Default:CheckIn:C.Wait] Stopped
2023-09-13T18:15:29.840Z,1694628929.840 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-13T18:15:29.841Z,1694628929.841 [Default:CheckIn:D] Running Loop=1
2023-09-13T18:15:30.246Z,1694628930.246 [Default:CheckIn:D] Stopped
2023-09-13T18:15:30.246Z,1694628930.246 [Default:CheckIn:E] Running Loop=1
2023-09-13T18:15:30.654Z,1694628930.654 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 49.639738 min 
2023-09-13T18:15:30.654Z,1694628930.654 [Default:CheckIn:E] Stopped
2023-09-13T18:15:30.654Z,1694628930.654 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-13T18:15:30.654Z,1694628930.654 [Default:CheckIn] Stopped
2023-09-13T18:15:30.654Z,1694628930.654 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-13T18:15:30.654Z,1694628930.654 [Default:CheckIn](INFO): Running loop #9
2023-09-13T18:15:30.654Z,1694628930.654 [Default:CheckIn] Running Loop=9
2023-09-13T18:15:30.654Z,1694628930.654 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-13T18:15:30.655Z,1694628930.655 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-13T18:15:32.655Z,1694628932.655 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181531.00,A,3648.13787,N,12147.20480,W,0.117,234.33,130923,,,D*72
2023-09-13T18:15:32.657Z,1694628932.657 [NAL9602](INFO): GPS fix at 20230913T181531: (36.802298, -121.786747)
2023-09-13T18:15:32.693Z,1694628932.693 [Default:CheckIn:Read_GPS] Stopped
2023-09-13T18:15:32.693Z,1694628932.693 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-13T18:15:40.451Z,1694628940.451 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230913T175340/Courier0012.lzma
2023-09-13T18:15:41.453Z,1694628941.453 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175340/Courier0012.lzma.bak
2023-09-13T18:15:41.454Z,1694628941.454 [DataOverHttps](INFO): SBD MOMSN=18766492
2023-09-13T18:15:57.267Z,1694628957.267 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20230913T175340/Express0013.lzma
2023-09-13T18:15:58.270Z,1694628958.270 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175340/Express0013.lzma.bak
2023-09-13T18:15:58.270Z,1694628958.270 [DataOverHttps](INFO): SBD MOMSN=18766495
2023-09-13T18:15:59.378Z,1694628959.378 [Default:CheckIn:Read_Iridium] Stopped
2023-09-13T18:15:59.378Z,1694628959.378 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-13T18:15:59.378Z,1694628959.378 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-13T18:16:02.550Z,1694628962.550 [NAL9602](INFO): SBD MO Status=2, MOMSN=45048, MT Status=2, MTMSN=0
2023-09-13T18:16:02.551Z,1694628962.551 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-13T18:16:25.990Z,1694628985.990 [NAL9602](INFO): SBD MO Status=0, MOMSN=45048, MT Status=0, MTMSN=0
2023-09-13T18:16:25.990Z,1694628985.990 [NAL9602](INFO): No messages in MT queue
2023-09-13T18:16:56.693Z,1694629016.693 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-13T18:20:59.918Z,1694629259.918 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-13T18:20:59.918Z,1694629259.918 [Default:CheckIn:C.Wait] Stopped
2023-09-13T18:20:59.918Z,1694629259.918 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-13T18:20:59.919Z,1694629259.919 [Default:CheckIn:D] Running Loop=1
2023-09-13T18:21:00.306Z,1694629260.306 [Default:CheckIn:D] Stopped
2023-09-13T18:21:00.306Z,1694629260.306 [Default:CheckIn:E] Running Loop=1
2023-09-13T18:21:00.714Z,1694629260.714 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 55.140735 min 
2023-09-13T18:21:00.715Z,1694629260.715 [Default:CheckIn:E] Stopped
2023-09-13T18:21:00.715Z,1694629260.715 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-13T18:21:00.715Z,1694629260.715 [Default:CheckIn] Stopped
2023-09-13T18:21:00.715Z,1694629260.715 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-13T18:21:00.715Z,1694629260.715 [Default:CheckIn](INFO): Running loop #10
2023-09-13T18:21:00.715Z,1694629260.715 [Default:CheckIn] Running Loop=10
2023-09-13T18:21:00.715Z,1694629260.715 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-13T18:21:00.715Z,1694629260.715 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-13T18:21:02.724Z,1694629262.724 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182101.00,A,3648.14150,N,12147.20744,W,0.039,279.41,130923,,,D*77
2023-09-13T18:21:02.726Z,1694629262.726 [NAL9602](INFO): GPS fix at 20230913T182101: (36.802358, -121.786791)
2023-09-13T18:21:02.737Z,1694629262.737 [Default:CheckIn:Read_GPS] Stopped
2023-09-13T18:21:02.737Z,1694629262.737 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-13T18:21:10.815Z,1694629270.815 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230913T175340/Courier0015.lzma
2023-09-13T18:21:11.818Z,1694629271.818 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175340/Courier0015.lzma.bak
2023-09-13T18:21:11.818Z,1694629271.818 [DataOverHttps](INFO): SBD MOMSN=18766572
2023-09-13T18:21:20.918Z,1694629280.918 [NAL9602](INFO): SBD MO Status=0, MOMSN=45049, MT Status=0, MTMSN=0
2023-09-13T18:21:20.918Z,1694629280.918 [NAL9602](INFO): No messages in MT queue
2023-09-13T18:21:27.719Z,1694629287.719 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230913T175340/Express0016.lzma
2023-09-13T18:21:28.722Z,1694629288.722 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175340/Express0016.lzma.bak
2023-09-13T18:21:28.722Z,1694629288.722 [DataOverHttps](INFO): SBD MOMSN=18766575
2023-09-13T18:21:30.210Z,1694629290.210 [Default:CheckIn:Read_Iridium] Stopped
2023-09-13T18:21:30.210Z,1694629290.210 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-13T18:21:30.210Z,1694629290.210 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-13T18:21:51.609Z,1694629311.609 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-13T18:22:24.326Z,1694629344.326 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-09-13T18:22:37.659Z,1694629357.659 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-09-13T18:22:50.990Z,1694629370.990 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-09-13T18:23:04.726Z,1694629384.726 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-09-13T18:23:18.462Z,1694629398.462 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-09-13T18:23:32.198Z,1694629412.198 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-09-13T18:23:45.530Z,1694629425.530 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-09-13T18:23:58.862Z,1694629438.862 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-09-13T18:24:12.599Z,1694629452.599 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-09-13T18:24:25.931Z,1694629465.931 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-09-13T18:24:39.669Z,1694629479.669 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-09-13T18:24:48.958Z,1694629488.958 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2023-09-13T18:24:48.972Z,1694629488.972 [BPC1](INFO): Calculating totals. Valid battery stick count: 36. Valid reserve battery stick count: 6.
2023-09-13T18:24:48.974Z,1694629488.974 [BPC1](INFO): Received data from all battery sticks.
2023-09-13T18:26:30.787Z,1694629590.787 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-13T18:26:30.787Z,1694629590.787 [Default:CheckIn:C.Wait] Stopped
2023-09-13T18:26:30.787Z,1694629590.787 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-13T18:26:30.787Z,1694629590.787 [Default:CheckIn:D] Running Loop=1
2023-09-13T18:26:31.193Z,1694629591.193 [Default:CheckIn:D] Stopped
2023-09-13T18:26:31.193Z,1694629591.193 [Default:CheckIn:E] Running Loop=1
2023-09-13T18:26:31.590Z,1694629591.590 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 60.655522 min 
2023-09-13T18:26:31.591Z,1694629591.591 [Default:CheckIn:E] Stopped
2023-09-13T18:26:31.591Z,1694629591.591 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-13T18:26:31.591Z,1694629591.591 [Default:CheckIn] Stopped
2023-09-13T18:26:31.591Z,1694629591.591 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-13T18:26:31.591Z,1694629591.591 [Default:CheckIn](INFO): Running loop #11
2023-09-13T18:26:31.591Z,1694629591.591 [Default:CheckIn] Running Loop=11
2023-09-13T18:26:31.591Z,1694629591.591 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-13T18:26:31.591Z,1694629591.591 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-13T18:26:33.605Z,1694629593.605 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182632.00,A,3648.14152,N,12147.20871,W,0.019,279.41,130923,,,D*79
2023-09-13T18:26:33.607Z,1694629593.607 [NAL9602](INFO): GPS fix at 20230913T182632: (36.802359, -121.786812)
2023-09-13T18:26:33.625Z,1694629593.625 [Default:CheckIn:Read_GPS] Stopped
2023-09-13T18:26:33.625Z,1694629593.625 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-13T18:26:41.419Z,1694629601.419 [DataOverHttps](INFO): Sending 201 bytes from file Logs/20230913T175340/Courier0018.lzma
2023-09-13T18:26:42.421Z,1694629602.421 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175340/Courier0018.lzma.bak
2023-09-13T18:26:42.422Z,1694629602.422 [DataOverHttps](INFO): SBD MOMSN=18766593
2023-09-13T18:27:01.235Z,1694629621.235 [DataOverHttps](INFO): Sending 127 bytes from file Logs/20230913T175340/Express0019.lzma
2023-09-13T18:27:02.237Z,1694629622.237 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175340/Express0019.lzma.bak
2023-09-13T18:27:02.238Z,1694629622.238 [DataOverHttps](INFO): SBD MOMSN=18766596
2023-09-13T18:27:03.564Z,1694629623.564 [Default:CheckIn:Read_Iridium] Stopped
2023-09-13T18:27:03.564Z,1694629623.564 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-13T18:27:03.564Z,1694629623.564 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-13T18:27:06.782Z,1694629626.782 [NAL9602](INFO): SBD MO Status=0, MOMSN=45050, MT Status=0, MTMSN=0
2023-09-13T18:27:06.782Z,1694629626.782 [NAL9602](INFO): No messages in MT queue
2023-09-13T18:27:37.487Z,1694629657.487 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-13T18:32:04.134Z,1694629924.134 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-13T18:32:04.134Z,1694629924.134 [Default:CheckIn:C.Wait] Stopped
2023-09-13T18:32:04.134Z,1694629924.134 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-13T18:32:04.135Z,1694629924.135 [Default:CheckIn:D] Running Loop=1
2023-09-13T18:32:04.533Z,1694629924.533 [Default:CheckIn:D] Stopped
2023-09-13T18:32:04.534Z,1694629924.534 [Default:CheckIn:E] Running Loop=1
2023-09-13T18:32:04.948Z,1694629924.948 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 66.211194 min 
2023-09-13T18:32:04.949Z,1694629924.949 [Default:CheckIn:E] Stopped
2023-09-13T18:32:04.949Z,1694629924.949 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-13T18:32:04.949Z,1694629924.949 [Default:CheckIn] Stopped
2023-09-13T18:32:04.949Z,1694629924.949 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-13T18:32:04.949Z,1694629924.949 [Default:CheckIn](INFO): Running loop #12
2023-09-13T18:32:04.949Z,1694629924.949 [Default:CheckIn] Running Loop=12
2023-09-13T18:32:04.949Z,1694629924.949 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-13T18:32:04.949Z,1694629924.949 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-13T18:32:06.955Z,1694629926.955 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183206.00,A,3648.17086,N,12147.28074,W,0.117,250.90,130923,,,D*7D
2023-09-13T18:32:06.966Z,1694629926.966 [NAL9602](INFO): GPS fix at 20230913T183206: (36.802848, -121.788012)
2023-09-13T18:32:06.977Z,1694629926.977 [Default:CheckIn:Read_GPS] Stopped
2023-09-13T18:32:06.978Z,1694629926.978 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-13T18:32:13.975Z,1694629933.975 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230913T175340/Courier0021.lzma
2023-09-13T18:32:21.898Z,1694629941.898 [NAL9602](INFO): SBD MO Status=0, MOMSN=45051, MT Status=0, MTMSN=0
2023-09-13T18:32:21.899Z,1694629941.899 [NAL9602](INFO): No messages in MT queue
2023-09-13T18:32:27.026Z,1694629947.026 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175340/Courier0021.lzma.bak
2023-09-13T18:32:27.026Z,1694629947.026 [DataOverHttps](INFO): SBD MOMSN=18766609
2023-09-13T18:32:52.654Z,1694629972.654 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-13T18:33:11.131Z,1694629991.131 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20230913T175340/Express0022.lzma
2023-09-13T18:33:16.150Z,1694629996.150 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175340/Express0022.lzma.bak
2023-09-13T18:33:16.150Z,1694629996.150 [DataOverHttps](INFO): SBD MOMSN=18766614
2023-09-13T18:33:17.292Z,1694629997.292 [Default:CheckIn:Read_Iridium] Stopped
2023-09-13T18:33:17.293Z,1694629997.293 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-13T18:33:17.293Z,1694629997.293 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-13T18:38:17.963Z,1694630297.963 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-13T18:38:17.963Z,1694630297.963 [Default:CheckIn:C.Wait] Stopped
2023-09-13T18:38:17.963Z,1694630297.963 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-13T18:38:17.964Z,1694630297.964 [Default:CheckIn:D] Running Loop=1
2023-09-13T18:38:18.379Z,1694630298.379 [Default:CheckIn:D] Stopped
2023-09-13T18:38:18.379Z,1694630298.379 [Default:CheckIn:E] Running Loop=1
2023-09-13T18:38:18.771Z,1694630298.771 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 72.441951 min 
2023-09-13T18:38:18.771Z,1694630298.771 [Default:CheckIn:E] Stopped
2023-09-13T18:38:18.771Z,1694630298.771 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-13T18:38:18.771Z,1694630298.771 [Default:CheckIn] Stopped
2023-09-13T18:38:18.771Z,1694630298.771 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-13T18:38:18.771Z,1694630298.771 [Default:CheckIn](INFO): Running loop #13
2023-09-13T18:38:18.771Z,1694630298.771 [Default:CheckIn] Running Loop=13
2023-09-13T18:38:18.772Z,1694630298.772 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-13T18:38:18.772Z,1694630298.772 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-13T18:38:20.783Z,1694630300.783 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183820.00,A,3648.18082,N,12147.27994,W,0.292,184.72,130923,,,A*7D
2023-09-13T18:38:20.786Z,1694630300.786 [NAL9602](INFO): GPS fix at 20230913T183820: (36.803014, -121.787999)
2023-09-13T18:38:20.796Z,1694630300.796 [Default:CheckIn:Read_GPS] Stopped
2023-09-13T18:38:20.796Z,1694630300.796 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-13T18:38:28.511Z,1694630308.511 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230913T175340/Courier0024.lzma
2023-09-13T18:38:29.513Z,1694630309.513 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175340/Courier0024.lzma.bak
2023-09-13T18:38:29.514Z,1694630309.514 [DataOverHttps](INFO): SBD MOMSN=18766627
2023-09-13T18:38:45.523Z,1694630325.523 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230913T175340/Express0025.lzma
2023-09-13T18:38:46.526Z,1694630326.526 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175340/Express0025.lzma.bak
2023-09-13T18:38:46.526Z,1694630326.526 [DataOverHttps](INFO): SBD MOMSN=18766630
2023-09-13T18:38:47.896Z,1694630327.896 [Default:CheckIn:Read_Iridium] Stopped
2023-09-13T18:38:47.896Z,1694630327.896 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-13T18:38:47.897Z,1694630327.897 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-13T18:40:36.982Z,1694630436.982 [NAL9602](INFO): SBD MO Status=0, MOMSN=45052, MT Status=0, MTMSN=0
2023-09-13T18:40:36.983Z,1694630436.983 [NAL9602](INFO): No messages in MT queue
2023-09-13T18:41:07.739Z,1694630467.739 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-13T18:43:48.579Z,1694630628.579 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-13T18:43:48.579Z,1694630628.579 [Default:CheckIn:C.Wait] Stopped
2023-09-13T18:43:48.579Z,1694630628.579 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-13T18:43:48.579Z,1694630628.579 [Default:CheckIn:D] Running Loop=1
2023-09-13T18:43:48.987Z,1694630628.987 [Default:CheckIn:D] Stopped
2023-09-13T18:43:48.987Z,1694630628.987 [Default:CheckIn:E] Running Loop=1
2023-09-13T18:43:49.396Z,1694630629.396 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 77.952075 min 
2023-09-13T18:43:49.396Z,1694630629.396 [Default:CheckIn:E] Stopped
2023-09-13T18:43:49.396Z,1694630629.396 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-13T18:43:49.396Z,1694630629.396 [Default:CheckIn] Stopped
2023-09-13T18:43:49.396Z,1694630629.396 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-13T18:43:49.397Z,1694630629.397 [Default:CheckIn](INFO): Running loop #14
2023-09-13T18:43:49.397Z,1694630629.397 [Default:CheckIn] Running Loop=14
2023-09-13T18:43:49.397Z,1694630629.397 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-13T18:43:49.397Z,1694630629.397 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-13T18:43:51.395Z,1694630631.395 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184350.00,A,3648.19030,N,12147.27504,W,0.467,3.70,130923,,,A*7B
2023-09-13T18:43:51.397Z,1694630631.397 [NAL9602](INFO): GPS fix at 20230913T184350: (36.803172, -121.787917)
2023-09-13T18:43:51.408Z,1694630631.408 [Default:CheckIn:Read_GPS] Stopped
2023-09-13T18:43:51.408Z,1694630631.408 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-13T18:43:58.807Z,1694630638.807 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230913T175340/Courier0027.lzma
2023-09-13T18:43:59.809Z,1694630639.809 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175340/Courier0027.lzma.bak
2023-09-13T18:43:59.810Z,1694630639.810 [DataOverHttps](INFO): SBD MOMSN=18766698
2023-09-13T18:44:15.963Z,1694630655.963 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230913T175340/Express0028.lzma
2023-09-13T18:44:16.966Z,1694630656.966 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175340/Express0028.lzma.bak
2023-09-13T18:44:16.966Z,1694630656.966 [DataOverHttps](INFO): SBD MOMSN=18766701
2023-09-13T18:44:18.081Z,1694630658.081 [Default:CheckIn:Read_Iridium] Stopped
2023-09-13T18:44:18.081Z,1694630658.081 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-13T18:44:18.081Z,1694630658.081 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-13T18:44:19.678Z,1694630659.678 [NAL9602](INFO): SBD MO Status=2, MOMSN=45053, MT Status=2, MTMSN=0
2023-09-13T18:44:19.679Z,1694630659.679 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-13T18:49:06.601Z,1694630946.601 [NAL9602](INFO): SBD MO Status=0, MOMSN=45053, MT Status=0, MTMSN=0
2023-09-13T18:49:06.601Z,1694630946.601 [NAL9602](INFO): No messages in MT queue
2023-09-13T18:49:18.738Z,1694630958.738 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-13T18:49:18.738Z,1694630958.738 [Default:CheckIn:C.Wait] Stopped
2023-09-13T18:49:18.738Z,1694630958.738 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-13T18:49:18.739Z,1694630958.739 [Default:CheckIn:D] Running Loop=1
2023-09-13T18:49:19.138Z,1694630959.138 [Default:CheckIn:D] Stopped
2023-09-13T18:49:19.138Z,1694630959.138 [Default:CheckIn:E] Running Loop=1
2023-09-13T18:49:19.539Z,1694630959.539 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 83.454606 min 
2023-09-13T18:49:19.539Z,1694630959.539 [Default:CheckIn:E] Stopped
2023-09-13T18:49:19.539Z,1694630959.539 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-13T18:49:19.539Z,1694630959.539 [Default:CheckIn] Stopped
2023-09-13T18:49:19.539Z,1694630959.539 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-13T18:49:19.540Z,1694630959.540 [Default:CheckIn](INFO): Running loop #15
2023-09-13T18:49:19.540Z,1694630959.540 [Default:CheckIn] Running Loop=15
2023-09-13T18:49:19.540Z,1694630959.540 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-13T18:49:19.540Z,1694630959.540 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-13T18:49:21.551Z,1694630961.551 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184920.00,A,3648.17242,N,12147.27465,W,0.369,169.23,130923,,,A*7B
2023-09-13T18:49:21.553Z,1694630961.553 [NAL9602](INFO): GPS fix at 20230913T184920: (36.802874, -121.787911)
2023-09-13T18:49:21.564Z,1694630961.564 [Default:CheckIn:Read_GPS] Stopped
2023-09-13T18:49:21.564Z,1694630961.564 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-13T18:49:28.891Z,1694630968.891 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230913T175340/Courier0030.lzma
2023-09-13T18:49:29.894Z,1694630969.894 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175340/Courier0030.lzma.bak
2023-09-13T18:49:29.894Z,1694630969.894 [DataOverHttps](INFO): SBD MOMSN=18766730
2023-09-13T18:49:45.707Z,1694630985.707 [DataOverHttps](INFO): Sending 138 bytes from file Logs/20230913T175340/Express0031.lzma
2023-09-13T18:49:46.710Z,1694630986.710 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175340/Express0031.lzma.bak
2023-09-13T18:49:46.710Z,1694630986.710 [DataOverHttps](INFO): SBD MOMSN=18766733
2023-09-13T18:49:47.819Z,1694630987.819 [Default:CheckIn:Read_Iridium] Stopped
2023-09-13T18:49:47.819Z,1694630987.819 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-13T18:49:47.819Z,1694630987.819 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-13T18:49:53.465Z,1694630993.465 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-13T18:54:48.467Z,1694631288.467 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-13T18:54:48.468Z,1694631288.468 [Default:CheckIn:C.Wait] Stopped
2023-09-13T18:54:48.468Z,1694631288.468 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-13T18:54:48.468Z,1694631288.468 [Default:CheckIn:D] Running Loop=1
2023-09-13T18:54:48.887Z,1694631288.887 [Default:CheckIn:D] Stopped
2023-09-13T18:54:48.887Z,1694631288.887 [Default:CheckIn:E] Running Loop=1
2023-09-13T18:54:49.275Z,1694631289.275 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 88.950423 min 
2023-09-13T18:54:49.275Z,1694631289.275 [Default:CheckIn:E] Stopped
2023-09-13T18:54:49.275Z,1694631289.275 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-13T18:54:49.275Z,1694631289.275 [Default:CheckIn] Stopped
2023-09-13T18:54:49.275Z,1694631289.275 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-13T18:54:49.275Z,1694631289.275 [Default:CheckIn](INFO): Running loop #16
2023-09-13T18:54:49.275Z,1694631289.275 [Default:CheckIn] Running Loop=16
2023-09-13T18:54:49.275Z,1694631289.275 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-13T18:54:49.276Z,1694631289.276 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-13T18:54:51.292Z,1694631291.292 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185450.00,A,3648.17562,N,12147.27666,W,0.058,169.23,130923,,,A*75
2023-09-13T18:54:51.294Z,1694631291.294 [NAL9602](INFO): GPS fix at 20230913T185450: (36.802927, -121.787944)
2023-09-13T18:54:51.309Z,1694631291.309 [Default:CheckIn:Read_GPS] Stopped
2023-09-13T18:54:51.310Z,1694631291.310 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-13T18:55:00.263Z,1694631300.263 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230913T175340/Courier0033.lzma
2023-09-13T18:55:01.266Z,1694631301.266 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175340/Courier0033.lzma.bak
2023-09-13T18:55:01.266Z,1694631301.266 [DataOverHttps](INFO): SBD MOMSN=18766744
2023-09-13T18:55:16.991Z,1694631316.991 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230913T175340/Express0034.lzma
2023-09-13T18:55:17.994Z,1694631317.994 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175340/Express0034.lzma.bak
2023-09-13T18:55:17.994Z,1694631317.994 [DataOverHttps](INFO): SBD MOMSN=18766747
2023-09-13T18:55:19.348Z,1694631319.348 [Default:CheckIn:Read_Iridium] Stopped
2023-09-13T18:55:19.349Z,1694631319.349 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-13T18:55:19.349Z,1694631319.349 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-13T18:58:00.578Z,1694631480.578 [NAL9602](INFO): SBD MO Status=0, MOMSN=45054, MT Status=0, MTMSN=0
2023-09-13T18:58:00.578Z,1694631480.578 [NAL9602](INFO): No messages in MT queue
2023-09-13T18:58:31.281Z,1694631511.281 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-13T19:00:19.967Z,1694631619.967 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-13T19:00:19.967Z,1694631619.967 [Default:CheckIn:C.Wait] Stopped
2023-09-13T19:00:19.967Z,1694631619.967 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-13T19:00:19.967Z,1694631619.967 [Default:CheckIn:D] Running Loop=1
2023-09-13T19:00:20.381Z,1694631620.381 [Default:CheckIn:D] Stopped
2023-09-13T19:00:20.381Z,1694631620.381 [Default:CheckIn:E] Running Loop=1
2023-09-13T19:00:20.770Z,1694631620.770 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 94.475309 min 
2023-09-13T19:00:20.770Z,1694631620.770 [Default:CheckIn:E] Stopped
2023-09-13T19:00:20.771Z,1694631620.771 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-13T19:00:20.771Z,1694631620.771 [Default:CheckIn] Stopped
2023-09-13T19:00:20.771Z,1694631620.771 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-13T19:00:20.771Z,1694631620.771 [Default:CheckIn](INFO): Running loop #17
2023-09-13T19:00:20.771Z,1694631620.771 [Default:CheckIn] Running Loop=17
2023-09-13T19:00:20.771Z,1694631620.771 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-13T19:00:20.771Z,1694631620.771 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-13T19:00:22.787Z,1694631622.787 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190022.00,A,3648.17646,N,12147.27929,W,0.078,169.23,130923,,,A*73
2023-09-13T19:00:22.789Z,1694631622.789 [NAL9602](INFO): GPS fix at 20230913T190022: (36.802941, -121.787988)
2023-09-13T19:00:22.799Z,1694631622.799 [Default:CheckIn:Read_GPS] Stopped
2023-09-13T19:00:22.799Z,1694631622.799 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-13T19:00:30.211Z,1694631630.211 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230913T175340/Courier0036.lzma
2023-09-13T19:00:31.214Z,1694631631.214 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175340/Courier0036.lzma.bak
2023-09-13T19:00:31.214Z,1694631631.214 [DataOverHttps](INFO): SBD MOMSN=18766758
2023-09-13T19:00:40.153Z,1694631640.153 [NAL9602](INFO): SBD MO Status=0, MOMSN=45055, MT Status=0, MTMSN=0
2023-09-13T19:00:40.154Z,1694631640.154 [NAL9602](INFO): No messages in MT queue
2023-09-13T19:00:46.959Z,1694631646.959 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230913T175340/Express0037.lzma
2023-09-13T19:00:47.962Z,1694631647.962 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175340/Express0037.lzma.bak
2023-09-13T19:00:47.962Z,1694631647.962 [DataOverHttps](INFO): SBD MOMSN=18766761
2023-09-13T19:00:49.108Z,1694631649.108 [Default:CheckIn:Read_Iridium] Stopped
2023-09-13T19:00:49.109Z,1694631649.109 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-13T19:00:49.109Z,1694631649.109 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-13T19:01:10.925Z,1694631670.925 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-13T19:05:49.722Z,1694631949.722 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-13T19:05:49.722Z,1694631949.722 [Default:CheckIn:C.Wait] Stopped
2023-09-13T19:05:49.722Z,1694631949.722 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-13T19:05:49.723Z,1694631949.723 [Default:CheckIn:D] Running Loop=1
2023-09-13T19:05:50.126Z,1694631950.126 [Default:CheckIn:D] Stopped
2023-09-13T19:05:50.126Z,1694631950.126 [Default:CheckIn:E] Running Loop=1
2023-09-13T19:05:50.527Z,1694631950.527 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 99.971077 min 
2023-09-13T19:05:50.527Z,1694631950.527 [Default:CheckIn:E] Stopped
2023-09-13T19:05:50.527Z,1694631950.527 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-13T19:05:50.528Z,1694631950.528 [Default:CheckIn] Stopped
2023-09-13T19:05:50.528Z,1694631950.528 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-13T19:05:50.528Z,1694631950.528 [Default:CheckIn](INFO): Running loop #18
2023-09-13T19:05:50.528Z,1694631950.528 [Default:CheckIn] Running Loop=18
2023-09-13T19:05:50.528Z,1694631950.528 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-13T19:05:50.528Z,1694631950.528 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-13T19:05:52.539Z,1694631952.539 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190551.00,A,3648.17586,N,12147.27899,W,0.019,169.23,130923,,,A*70
2023-09-13T19:05:52.541Z,1694631952.541 [NAL9602](INFO): GPS fix at 20230913T190551: (36.802931, -121.787983)
2023-09-13T19:05:52.573Z,1694631952.573 [Default:CheckIn:Read_GPS] Stopped
2023-09-13T19:05:52.574Z,1694631952.574 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-13T19:06:00.283Z,1694631960.283 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230913T175340/Courier0039.lzma
2023-09-13T19:06:01.285Z,1694631961.285 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175340/Courier0039.lzma.bak
2023-09-13T19:06:01.286Z,1694631961.286 [DataOverHttps](INFO): SBD MOMSN=18766772
2023-09-13T19:06:17.151Z,1694631977.151 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230913T175340/Express0040.lzma
2023-09-13T19:06:18.153Z,1694631978.153 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175340/Express0040.lzma.bak
2023-09-13T19:06:18.154Z,1694631978.154 [DataOverHttps](INFO): SBD MOMSN=18766775
2023-09-13T19:06:19.632Z,1694631979.632 [Default:CheckIn:Read_Iridium] Stopped
2023-09-13T19:06:19.633Z,1694631979.633 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-13T19:06:19.633Z,1694631979.633 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-13T19:07:39.642Z,1694632059.642 [NAL9602](INFO): SBD MO Status=0, MOMSN=45056, MT Status=0, MTMSN=0
2023-09-13T19:07:39.642Z,1694632059.642 [NAL9602](INFO): No messages in MT queue
2023-09-13T19:08:10.345Z,1694632090.345 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-13T19:11:20.253Z,1694632280.253 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-13T19:11:20.253Z,1694632280.253 [Default:CheckIn:C.Wait] Stopped
2023-09-13T19:11:20.253Z,1694632280.253 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-13T19:11:20.254Z,1694632280.254 [Default:CheckIn:D] Running Loop=1
2023-09-13T19:11:20.664Z,1694632280.664 [Default:CheckIn:D] Stopped
2023-09-13T19:11:20.664Z,1694632280.664 [Default:CheckIn:E] Running Loop=1
2023-09-13T19:11:21.063Z,1694632281.063 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 105.480029 min 
2023-09-13T19:11:21.063Z,1694632281.063 [Default:CheckIn:E] Stopped
2023-09-13T19:11:21.063Z,1694632281.063 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-13T19:11:21.063Z,1694632281.063 [Default:CheckIn] Stopped
2023-09-13T19:11:21.063Z,1694632281.063 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-13T19:11:21.063Z,1694632281.063 [Default:CheckIn](INFO): Running loop #19
2023-09-13T19:11:21.094Z,1694632281.094 [Default:CheckIn] Running Loop=19
2023-09-13T19:11:21.094Z,1694632281.094 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-13T19:11:21.094Z,1694632281.094 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-13T19:11:23.074Z,1694632283.074 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191122.00,A,3648.17658,N,12147.27860,W,0.194,169.23,130923,,,A*73
2023-09-13T19:11:23.076Z,1694632283.076 [NAL9602](INFO): GPS fix at 20230913T191122: (36.802943, -121.787977)
2023-09-13T19:11:23.086Z,1694632283.086 [Default:CheckIn:Read_GPS] Stopped
2023-09-13T19:11:23.086Z,1694632283.086 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-13T19:11:30.771Z,1694632290.771 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230913T175340/Courier0042.lzma
2023-09-13T19:11:31.774Z,1694632291.774 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175340/Courier0042.lzma.bak
2023-09-13T19:11:31.774Z,1694632291.774 [DataOverHttps](INFO): SBD MOMSN=18766787
2023-09-13T19:11:47.663Z,1694632307.663 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20230913T175340/Express0043.lzma
2023-09-13T19:11:48.666Z,1694632308.666 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175340/Express0043.lzma.bak
2023-09-13T19:11:48.666Z,1694632308.666 [DataOverHttps](INFO): SBD MOMSN=18766790
2023-09-13T19:11:50.164Z,1694632310.164 [Default:CheckIn:Read_Iridium] Stopped
2023-09-13T19:11:50.164Z,1694632310.164 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-13T19:11:50.164Z,1694632310.164 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-13T19:12:12.770Z,1694632332.770 [NAL9602](INFO): SBD MO Status=2, MOMSN=45057, MT Status=2, MTMSN=0
2023-09-13T19:12:12.770Z,1694632332.770 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-13T19:13:13.001Z,1694632393.001 [NAL9602](INFO): SBD MO Status=2, MOMSN=45057, MT Status=2, MTMSN=0
2023-09-13T19:13:13.001Z,1694632393.001 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-13T19:16:25.313Z,1694632585.313 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-09-13T19:16:50.777Z,1694632610.777 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-13T19:16:50.777Z,1694632610.777 [Default:CheckIn:C.Wait] Stopped
2023-09-13T19:16:50.777Z,1694632610.777 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-13T19:16:50.778Z,1694632610.778 [Default:CheckIn:D] Running Loop=1
2023-09-13T19:16:51.195Z,1694632611.195 [Default:CheckIn:D] Stopped
2023-09-13T19:16:51.195Z,1694632611.195 [Default:CheckIn:E] Running Loop=1
2023-09-13T19:16:51.592Z,1694632611.592 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 110.988883 min 
2023-09-13T19:16:51.592Z,1694632611.592 [Default:CheckIn:E] Stopped
2023-09-13T19:16:51.592Z,1694632611.592 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-13T19:16:51.592Z,1694632611.592 [Default:CheckIn] Stopped
2023-09-13T19:16:51.592Z,1694632611.592 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-13T19:16:51.592Z,1694632611.592 [Default:CheckIn](INFO): Running loop #20
2023-09-13T19:16:51.593Z,1694632611.593 [Default:CheckIn] Running Loop=20
2023-09-13T19:16:51.593Z,1694632611.593 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-13T19:16:51.593Z,1694632611.593 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-13T19:16:53.599Z,1694632613.599 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191652.00,A,3648.17683,N,12147.27804,W,0.078,190.82,130923,,,A*79
2023-09-13T19:16:53.601Z,1694632613.601 [NAL9602](INFO): GPS fix at 20230913T191652: (36.802947, -121.787967)
2023-09-13T19:16:53.620Z,1694632613.620 [Default:CheckIn:Read_GPS] Stopped
2023-09-13T19:16:53.620Z,1694632613.620 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-13T19:17:01.055Z,1694632621.055 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230913T175340/Courier0045.lzma
2023-09-13T19:17:02.058Z,1694632622.058 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175340/Courier0045.lzma.bak
2023-09-13T19:17:02.058Z,1694632622.058 [DataOverHttps](INFO): SBD MOMSN=18766799
2023-09-13T19:17:17.712Z,1694632637.712 [DataOverHttps](INFO): Sending 230 bytes from file Logs/20230913T175340/Express0046.lzma
2023-09-13T19:17:18.714Z,1694632638.714 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175340/Express0046.lzma.bak
2023-09-13T19:17:18.714Z,1694632638.714 [DataOverHttps](INFO): SBD MOMSN=18766802
2023-09-13T19:17:20.099Z,1694632640.099 [Default:CheckIn:Read_Iridium] Stopped
2023-09-13T19:17:20.099Z,1694632640.099 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-13T19:17:20.099Z,1694632640.099 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-13T19:17:25.338Z,1694632645.338 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-13T19:22:20.742Z,1694632940.742 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-13T19:22:20.742Z,1694632940.742 [Default:CheckIn:C.Wait] Stopped
2023-09-13T19:22:20.742Z,1694632940.742 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-13T19:22:20.743Z,1694632940.743 [Default:CheckIn:D] Running Loop=1
2023-09-13T19:22:21.149Z,1694632941.149 [Default:CheckIn:D] Stopped
2023-09-13T19:22:21.149Z,1694632941.149 [Default:CheckIn:E] Running Loop=1
2023-09-13T19:22:21.547Z,1694632941.547 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 116.488110 min 
2023-09-13T19:22:21.547Z,1694632941.547 [Default:CheckIn:E] Stopped
2023-09-13T19:22:21.547Z,1694632941.547 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-13T19:22:21.547Z,1694632941.547 [Default:CheckIn] Stopped
2023-09-13T19:22:21.548Z,1694632941.548 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-13T19:22:21.548Z,1694632941.548 [Default:CheckIn](INFO): Running loop #21
2023-09-13T19:22:21.548Z,1694632941.548 [Default:CheckIn] Running Loop=21
2023-09-13T19:22:21.548Z,1694632941.548 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-13T19:22:21.548Z,1694632941.548 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-13T19:22:23.559Z,1694632943.559 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192222.00,A,3648.18777,N,12147.27165,W,0.156,190.82,130923,,,A*7F
2023-09-13T19:22:23.561Z,1694632943.561 [NAL9602](INFO): GPS fix at 20230913T192222: (36.803129, -121.787861)
2023-09-13T19:22:23.572Z,1694632943.572 [Default:CheckIn:Read_GPS] Stopped
2023-09-13T19:22:23.572Z,1694632943.572 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-13T19:22:31.099Z,1694632951.099 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230913T175340/Courier0048.lzma
2023-09-13T19:22:32.101Z,1694632952.101 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175340/Courier0048.lzma.bak
2023-09-13T19:22:32.102Z,1694632952.102 [DataOverHttps](INFO): SBD MOMSN=18766813
2023-09-13T19:22:47.839Z,1694632967.839 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230913T175340/Express0049.lzma
2023-09-13T19:22:48.842Z,1694632968.842 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175340/Express0049.lzma.bak
2023-09-13T19:22:48.842Z,1694632968.842 [DataOverHttps](INFO): SBD MOMSN=18766816
2023-09-13T19:22:50.242Z,1694632970.242 [Default:CheckIn:Read_Iridium] Stopped
2023-09-13T19:22:50.242Z,1694632970.242 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-13T19:22:50.242Z,1694632970.242 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-13T19:22:54.303Z,1694632974.303 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-09-13T19:22:54.380Z,1694632974.380 [NAL9602](ERROR): received: 
+CSQ:0

OK057, 2, 0, 0, 0

OK
2023-09-13T19:27:25.397Z,1694633245.397 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-09-13T19:27:27.006Z,1694633247.006 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-09-13T19:27:40.338Z,1694633260.338 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-09-13T19:27:50.863Z,1694633270.863 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-13T19:27:50.863Z,1694633270.863 [Default:CheckIn:C.Wait] Stopped
2023-09-13T19:27:50.863Z,1694633270.863 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-13T19:27:50.863Z,1694633270.863 [Default:CheckIn:D] Running Loop=1
2023-09-13T19:27:51.271Z,1694633271.271 [Default:CheckIn:D] Stopped
2023-09-13T19:27:51.271Z,1694633271.271 [Default:CheckIn:E] Running Loop=1
2023-09-13T19:27:51.667Z,1694633271.667 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 121.990153 min 
2023-09-13T19:27:51.667Z,1694633271.667 [Default:CheckIn:E] Stopped
2023-09-13T19:27:51.667Z,1694633271.667 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-13T19:27:51.667Z,1694633271.667 [Default:CheckIn] Stopped
2023-09-13T19:27:51.667Z,1694633271.667 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-13T19:27:51.667Z,1694633271.667 [Default:CheckIn](INFO): Running loop #22
2023-09-13T19:27:51.668Z,1694633271.668 [Default:CheckIn] Running Loop=22
2023-09-13T19:27:51.668Z,1694633271.668 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-13T19:27:51.668Z,1694633271.668 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-13T19:27:53.670Z,1694633273.670 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-09-13T19:27:53.680Z,1694633273.680 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192753.00,A,3648.18259,N,12147.27561,W,1.361,23.51,130923,,,A*45
2023-09-13T19:27:53.682Z,1694633273.682 [NAL9602](INFO): GPS fix at 20230913T192753: (36.803043, -121.787927)
2023-09-13T19:27:53.694Z,1694633273.694 [Default:CheckIn:Read_GPS] Stopped
2023-09-13T19:27:53.694Z,1694633273.694 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-13T19:28:00.983Z,1694633280.983 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230913T175340/Courier0051.lzma
2023-09-13T19:28:01.986Z,1694633281.986 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175340/Courier0051.lzma.bak
2023-09-13T19:28:01.986Z,1694633281.986 [DataOverHttps](INFO): SBD MOMSN=18766825
2023-09-13T19:28:07.406Z,1694633287.406 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-09-13T19:28:17.948Z,1694633297.948 [DataOverHttps](INFO): Sending 205 bytes from file Logs/20230913T175340/Express0052.lzma
2023-09-13T19:28:18.942Z,1694633298.942 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175340/Express0052.lzma.bak
2023-09-13T19:28:18.942Z,1694633298.942 [DataOverHttps](INFO): SBD MOMSN=18766829
2023-09-13T19:28:20.352Z,1694633300.352 [Default:CheckIn:Read_Iridium] Stopped
2023-09-13T19:28:20.352Z,1694633300.352 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-13T19:28:20.352Z,1694633300.352 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-13T19:28:21.546Z,1694633301.546 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-09-13T19:28:26.401Z,1694633306.401 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-13T19:28:35.688Z,1694633315.688 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-09-13T19:28:49.826Z,1694633329.826 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-09-13T19:29:04.010Z,1694633344.010 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-09-13T19:29:18.150Z,1694633358.150 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-09-13T19:29:32.292Z,1694633372.292 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-09-13T19:29:49.664Z,1694633389.664 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2023-09-13T19:29:49.669Z,1694633389.669 [BPC1](INFO): Calculating totals. Valid battery stick count: 36. Valid reserve battery stick count: 6.
2023-09-13T19:29:49.672Z,1694633389.672 [BPC1](INFO): Received data from all battery sticks.
2023-09-13T19:33:20.996Z,1694633600.996 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-13T19:33:20.996Z,1694633600.996 [Default:CheckIn:C.Wait] Stopped
2023-09-13T19:33:20.996Z,1694633600.996 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-13T19:33:20.997Z,1694633600.997 [Default:CheckIn:D] Running Loop=1
2023-09-13T19:33:21.388Z,1694633601.388 [Default:CheckIn:D] Stopped
2023-09-13T19:33:21.388Z,1694633601.388 [Default:CheckIn:E] Running Loop=1
2023-09-13T19:33:21.786Z,1694633601.786 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 127.492106 min 
2023-09-13T19:33:21.786Z,1694633601.786 [Default:CheckIn:E] Stopped
2023-09-13T19:33:21.786Z,1694633601.786 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-13T19:33:21.786Z,1694633601.786 [Default:CheckIn] Stopped
2023-09-13T19:33:21.786Z,1694633601.786 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-13T19:33:21.786Z,1694633601.786 [Default:CheckIn](INFO): Running loop #23
2023-09-13T19:33:21.786Z,1694633601.786 [Default:CheckIn] Running Loop=23
2023-09-13T19:33:21.786Z,1694633601.786 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-13T19:33:21.786Z,1694633601.786 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-13T19:33:23.811Z,1694633603.811 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193323.00,A,3648.17591,N,12147.26769,W,0.583,14.31,130923,,,A*49
2023-09-13T19:33:23.813Z,1694633603.813 [NAL9602](INFO): GPS fix at 20230913T193323: (36.802932, -121.787795)
2023-09-13T19:33:23.823Z,1694633603.823 [Default:CheckIn:Read_GPS] Stopped
2023-09-13T19:33:23.823Z,1694633603.823 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-13T19:33:31.044Z,1694633611.044 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20230913T175340/Courier0054.lzma
2023-09-13T19:33:32.046Z,1694633612.046 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175340/Courier0054.lzma.bak
2023-09-13T19:33:32.046Z,1694633612.046 [DataOverHttps](INFO): SBD MOMSN=18766842
2023-09-13T19:33:47.971Z,1694633627.971 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230913T175340/Express0055.lzma
2023-09-13T19:33:48.974Z,1694633628.974 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175340/Express0055.lzma.bak
2023-09-13T19:33:48.974Z,1694633628.974 [DataOverHttps](INFO): SBD MOMSN=18766845
2023-09-13T19:33:50.497Z,1694633630.497 [Default:CheckIn:Read_Iridium] Stopped
2023-09-13T19:33:50.497Z,1694633630.497 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-13T19:33:50.498Z,1694633630.498 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-13T19:33:54.501Z,1694633634.501 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-09-13T19:33:54.580Z,1694633634.580 [NAL9602](ERROR): received: 
+CSQ:0

OK057, 2, 0, 0, 0

OK
2023-09-13T19:37:28.266Z,1694633848.266 [NAL9602](INFO): SBD MO Status=2, MOMSN=45057, MT Status=2, MTMSN=0
2023-09-13T19:37:28.266Z,1694633848.266 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-13T19:37:35.533Z,1694633855.533 [NAL9602](INFO): SBD MO Status=0, MOMSN=45057, MT Status=0, MTMSN=0
2023-09-13T19:37:35.533Z,1694633855.533 [NAL9602](INFO): No messages in MT queue
2023-09-13T19:38:06.241Z,1694633886.241 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-13T19:38:51.115Z,1694633931.115 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-13T19:38:51.115Z,1694633931.115 [Default:CheckIn:C.Wait] Stopped
2023-09-13T19:38:51.115Z,1694633931.115 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-13T19:38:51.115Z,1694633931.115 [Default:CheckIn:D] Running Loop=1
2023-09-13T19:38:51.494Z,1694633931.494 [Default:CheckIn:D] Stopped
2023-09-13T19:38:51.494Z,1694633931.494 [Default:CheckIn:E] Running Loop=1
2023-09-13T19:38:51.911Z,1694633931.911 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 132.993864 min 
2023-09-13T19:38:51.911Z,1694633931.911 [Default:CheckIn:E] Stopped
2023-09-13T19:38:51.911Z,1694633931.911 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-13T19:38:51.912Z,1694633931.912 [Default:CheckIn] Stopped
2023-09-13T19:38:51.912Z,1694633931.912 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-13T19:38:51.912Z,1694633931.912 [Default:CheckIn](INFO): Running loop #24
2023-09-13T19:38:51.912Z,1694633931.912 [Default:CheckIn] Running Loop=24
2023-09-13T19:38:51.912Z,1694633931.912 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-13T19:38:51.912Z,1694633931.912 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-13T19:38:53.911Z,1694633933.911 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193853.00,A,3648.18365,N,12147.26824,W,0.136,14.31,130923,,,A*4B
2023-09-13T19:38:53.913Z,1694633933.913 [NAL9602](INFO): GPS fix at 20230913T193853: (36.803061, -121.787804)
2023-09-13T19:38:53.963Z,1694633933.963 [Default:CheckIn:Read_GPS] Stopped
2023-09-13T19:38:53.963Z,1694633933.963 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-13T19:39:01.867Z,1694633941.867 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230913T175340/Courier0057.lzma
2023-09-13T19:39:02.870Z,1694633942.870 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175340/Courier0057.lzma.bak
2023-09-13T19:39:02.870Z,1694633942.870 [DataOverHttps](INFO): SBD MOMSN=18766855
2023-09-13T19:39:17.757Z,1694633957.757 [NAL9602](INFO): SBD MO Status=2, MOMSN=45058, MT Status=2, MTMSN=0
2023-09-13T19:39:17.757Z,1694633957.757 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-13T19:39:18.659Z,1694633958.659 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230913T175340/Express0058.lzma
2023-09-13T19:39:19.662Z,1694633959.662 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175340/Express0058.lzma.bak
2023-09-13T19:39:19.662Z,1694633959.662 [DataOverHttps](INFO): SBD MOMSN=18766858
2023-09-13T19:39:20.999Z,1694633960.999 [Default:CheckIn:Read_Iridium] Stopped
2023-09-13T19:39:20.999Z,1694633960.999 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-13T19:39:20.999Z,1694633960.999 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-13T19:39:37.144Z,1694633977.144 [NAL9602](INFO): SBD MO Status=0, MOMSN=45058, MT Status=0, MTMSN=0
2023-09-13T19:39:37.145Z,1694633977.145 [NAL9602](INFO): No messages in MT queue
2023-09-13T19:40:07.839Z,1694634007.839 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-13T19:44:21.575Z,1694634261.575 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-13T19:44:21.575Z,1694634261.575 [Default:CheckIn:C.Wait] Stopped
2023-09-13T19:44:21.575Z,1694634261.575 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-13T19:44:21.576Z,1694634261.576 [Default:CheckIn:D] Running Loop=1
2023-09-13T19:44:21.970Z,1694634261.970 [Default:CheckIn:D] Stopped
2023-09-13T19:44:21.970Z,1694634261.970 [Default:CheckIn:E] Running Loop=1
2023-09-13T19:44:22.370Z,1694634262.370 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 138.501807 min 
2023-09-13T19:44:22.371Z,1694634262.371 [Default:CheckIn:E] Stopped
2023-09-13T19:44:22.371Z,1694634262.371 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-13T19:44:22.371Z,1694634262.371 [Default:CheckIn] Stopped
2023-09-13T19:44:22.371Z,1694634262.371 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-13T19:44:22.371Z,1694634262.371 [Default:CheckIn](INFO): Running loop #25
2023-09-13T19:44:22.371Z,1694634262.371 [Default:CheckIn] Running Loop=25
2023-09-13T19:44:22.371Z,1694634262.371 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-13T19:44:22.372Z,1694634262.372 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-13T19:44:24.391Z,1694634264.391 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194423.00,A,3648.16862,N,12147.25209,W,0.117,14.31,130923,,,A*40
2023-09-13T19:44:24.393Z,1694634264.393 [NAL9602](INFO): GPS fix at 20230913T194423: (36.802810, -121.787535)
2023-09-13T19:44:24.404Z,1694634264.404 [Default:CheckIn:Read_GPS] Stopped
2023-09-13T19:44:24.404Z,1694634264.404 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-13T19:44:32.179Z,1694634272.179 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230913T175340/Courier0060.lzma
2023-09-13T19:44:33.182Z,1694634273.182 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175340/Courier0060.lzma.bak
2023-09-13T19:44:33.182Z,1694634273.182 [DataOverHttps](INFO): SBD MOMSN=18766870
2023-09-13T19:44:52.232Z,1694634292.232 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20230913T175340/Express0061.lzma
2023-09-13T19:44:53.234Z,1694634293.234 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175340/Express0061.lzma.bak
2023-09-13T19:44:53.234Z,1694634293.234 [DataOverHttps](INFO): SBD MOMSN=18766873
2023-09-13T19:44:54.761Z,1694634294.761 [Default:CheckIn:Read_Iridium] Stopped
2023-09-13T19:44:54.761Z,1694634294.761 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-13T19:44:54.762Z,1694634294.762 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-13T19:46:25.624Z,1694634385.624 [NAL9602](INFO): SBD MO Status=2, MOMSN=45059, MT Status=2, MTMSN=0
2023-09-13T19:46:25.624Z,1694634385.624 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-13T19:49:41.578Z,1694634581.578 [NAL9602](INFO): SBD MO Status=2, MOMSN=45059, MT Status=2, MTMSN=0
2023-09-13T19:49:41.578Z,1694634581.578 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-13T19:49:41.985Z,1694634581.985 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-09-13T19:49:55.332Z,1694634595.332 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-13T19:49:55.332Z,1694634595.332 [Default:CheckIn:C.Wait] Stopped
2023-09-13T19:49:55.332Z,1694634595.332 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-13T19:49:55.332Z,1694634595.332 [Default:CheckIn:D] Running Loop=1
2023-09-13T19:49:55.721Z,1694634595.721 [Default:CheckIn:D] Stopped
2023-09-13T19:49:55.722Z,1694634595.722 [Default:CheckIn:E] Running Loop=1
2023-09-13T19:49:56.133Z,1694634596.133 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 144.064323 min 
2023-09-13T19:49:56.133Z,1694634596.133 [Default:CheckIn:E] Stopped
2023-09-13T19:49:56.133Z,1694634596.133 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-13T19:49:56.133Z,1694634596.133 [Default:CheckIn] Stopped
2023-09-13T19:49:56.133Z,1694634596.133 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-13T19:49:56.133Z,1694634596.133 [Default:CheckIn](INFO): Running loop #26
2023-09-13T19:49:56.133Z,1694634596.133 [Default:CheckIn] Running Loop=26
2023-09-13T19:49:56.134Z,1694634596.134 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-13T19:49:56.134Z,1694634596.134 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-13T19:49:58.139Z,1694634598.139 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194957.00,A,3648.16376,N,12147.28323,W,0.292,358.46,130923,,,A*71
2023-09-13T19:49:58.142Z,1694634598.142 [NAL9602](INFO): GPS fix at 20230913T194957: (36.802729, -121.788054)
2023-09-13T19:49:58.153Z,1694634598.153 [Default:CheckIn:Read_GPS] Stopped
2023-09-13T19:49:58.153Z,1694634598.153 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-13T19:50:05.103Z,1694634605.103 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230913T175340/Courier0063.lzma
2023-09-13T19:50:06.105Z,1694634606.105 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175340/Courier0063.lzma.bak
2023-09-13T19:50:06.106Z,1694634606.106 [DataOverHttps](INFO): SBD MOMSN=18766885
2023-09-13T19:50:21.868Z,1694634621.868 [DataOverHttps](INFO): Sending 217 bytes from file Logs/20230913T175340/Express0064.lzma
2023-09-13T19:50:22.870Z,1694634622.870 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175340/Express0064.lzma.bak
2023-09-13T19:50:22.870Z,1694634622.870 [DataOverHttps](INFO): SBD MOMSN=18766888
2023-09-13T19:50:24.012Z,1694634624.012 [Default:CheckIn:Read_Iridium] Stopped
2023-09-13T19:50:24.012Z,1694634624.012 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-13T19:50:24.012Z,1694634624.012 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-13T19:50:30.461Z,1694634630.461 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-13T19:55:24.579Z,1694634924.579 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-13T19:55:24.579Z,1694634924.579 [Default:CheckIn:C.Wait] Stopped
2023-09-13T19:55:24.579Z,1694634924.579 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-13T19:55:24.579Z,1694634924.579 [Default:CheckIn:D] Running Loop=1
2023-09-13T19:55:24.988Z,1694634924.988 [Default:CheckIn:D] Stopped
2023-09-13T19:55:24.988Z,1694634924.988 [Default:CheckIn:E] Running Loop=1
2023-09-13T19:55:25.388Z,1694634925.388 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 149.552116 min 
2023-09-13T19:55:25.388Z,1694634925.388 [Default:CheckIn:E] Stopped
2023-09-13T19:55:25.388Z,1694634925.388 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-13T19:55:25.389Z,1694634925.389 [Default:CheckIn] Stopped
2023-09-13T19:55:25.389Z,1694634925.389 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-13T19:55:25.389Z,1694634925.389 [Default:CheckIn](INFO): Running loop #27
2023-09-13T19:55:25.389Z,1694634925.389 [Default:CheckIn] Running Loop=27
2023-09-13T19:55:25.389Z,1694634925.389 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-13T19:55:25.389Z,1694634925.389 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-13T19:55:27.397Z,1694634927.397 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195526.00,A,3648.16863,N,12147.28289,W,0.505,358.46,130923,,,A*7D
2023-09-13T19:55:27.411Z,1694634927.411 [NAL9602](INFO): GPS fix at 20230913T195526: (36.802810, -121.788048)
2023-09-13T19:55:27.421Z,1694634927.421 [Default:CheckIn:Read_GPS] Stopped
2023-09-13T19:55:27.422Z,1694634927.422 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-13T19:55:37.951Z,1694634937.951 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230913T175340/Courier0066.lzma
2023-09-13T19:55:38.954Z,1694634938.954 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175340/Courier0066.lzma.bak
2023-09-13T19:55:38.954Z,1694634938.954 [DataOverHttps](INFO): SBD MOMSN=18766899
2023-09-13T19:55:54.819Z,1694634954.819 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230913T175340/Express0067.lzma
2023-09-13T19:55:55.822Z,1694634955.822 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175340/Express0067.lzma.bak
2023-09-13T19:55:55.822Z,1694634955.822 [DataOverHttps](INFO): SBD MOMSN=18766902
2023-09-13T19:55:57.323Z,1694634957.323 [Default:CheckIn:Read_Iridium] Stopped
2023-09-13T19:55:57.324Z,1694634957.324 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-13T19:55:57.324Z,1694634957.324 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-13T19:56:35.291Z,1694634995.291 [Depth_Keller](ERROR): Pressure or depth reading out of range: -0.593597 decibar, -0.589218 m
2023-09-13T20:00:29.201Z,1694635229.201 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-09-13T20:00:57.942Z,1694635257.942 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-13T20:00:57.942Z,1694635257.942 [Default:CheckIn:C.Wait] Stopped
2023-09-13T20:00:57.942Z,1694635257.942 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-13T20:00:57.942Z,1694635257.942 [Default:CheckIn:D] Running Loop=1
2023-09-13T20:00:58.318Z,1694635258.318 [Default:CheckIn:D] Stopped
2023-09-13T20:00:58.318Z,1694635258.318 [Default:CheckIn:E] Running Loop=1
2023-09-13T20:00:58.727Z,1694635258.727 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 155.107601 min 
2023-09-13T20:00:58.727Z,1694635258.727 [Default:CheckIn:E] Stopped
2023-09-13T20:00:58.727Z,1694635258.727 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-13T20:00:58.727Z,1694635258.727 [Default:CheckIn] Stopped
2023-09-13T20:00:58.727Z,1694635258.727 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-13T20:00:58.732Z,1694635258.732 [Default:CheckIn](INFO): Running loop #28
2023-09-13T20:00:58.732Z,1694635258.732 [Default:CheckIn] Running Loop=28
2023-09-13T20:00:58.732Z,1694635258.732 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-13T20:00:58.732Z,1694635258.732 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-13T20:05:58.926Z,1694635558.926 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-09-13T20:00:58.7Z
2023-09-13T20:05:58.926Z,1694635558.926 [Default:CheckIn:Read_GPS] Stopped
2023-09-13T20:05:58.926Z,1694635558.926 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-13T20:06:09.740Z,1694635569.740 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230913T175340/Courier0069.lzma
2023-09-13T20:06:10.738Z,1694635570.738 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175340/Courier0069.lzma.bak
2023-09-13T20:06:10.738Z,1694635570.738 [DataOverHttps](INFO): SBD MOMSN=18766926
2023-09-13T20:06:30.513Z,1694635590.513 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-13T20:06:35.189Z,1694635595.189 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20230913T175340/Express0070.lzma
2023-09-13T20:06:36.190Z,1694635596.190 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175340/Express0070.lzma.bak
2023-09-13T20:06:36.190Z,1694635596.190 [DataOverHttps](INFO): SBD MOMSN=18766929
2023-09-13T20:06:37.418Z,1694635597.418 [Default:CheckIn:Read_Iridium] Stopped
2023-09-13T20:06:37.418Z,1694635597.418 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-13T20:06:37.418Z,1694635597.418 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-13T20:11:37.974Z,1694635897.974 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-13T20:11:37.974Z,1694635897.974 [Default:CheckIn:C.Wait] Stopped
2023-09-13T20:11:37.974Z,1694635897.974 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-13T20:11:37.975Z,1694635897.975 [Default:CheckIn:D] Running Loop=1
2023-09-13T20:11:38.380Z,1694635898.380 [Default:CheckIn:D] Stopped
2023-09-13T20:11:38.380Z,1694635898.380 [Default:CheckIn:E] Running Loop=1
2023-09-13T20:11:38.795Z,1694635898.795 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 165.775293 min 
2023-09-13T20:11:38.795Z,1694635898.795 [Default:CheckIn:E] Stopped
2023-09-13T20:11:38.796Z,1694635898.796 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-13T20:11:38.796Z,1694635898.796 [Default:CheckIn] Stopped
2023-09-13T20:11:38.796Z,1694635898.796 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-13T20:11:38.796Z,1694635898.796 [Default:CheckIn](INFO): Running loop #29
2023-09-13T20:11:38.796Z,1694635898.796 [Default:CheckIn] Running Loop=29
2023-09-13T20:11:38.796Z,1694635898.796 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-13T20:11:38.796Z,1694635898.796 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-13T20:11:40.388Z,1694635900.388 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-09-13T20:11:40.389Z,1694635900.389 [NAL9602] Data Fault, FailCount= 1
2023-09-13T20:11:40.389Z,1694635900.389 [NAL9602](ERROR): Data Fault
2023-09-13T20:11:40.429Z,1694635900.429 [CBIT](ERROR): Data Fault in component: NAL9602
2023-09-13T20:11:40.793Z,1694635900.793 [NAL9602](INFO): Powering down
2023-09-13T20:11:41.621Z,1694635901.621 [CBIT](INFO): Clearing failed state for component NAL9602
2023-09-13T20:11:41.621Z,1694635901.621 [NAL9602] No Fault, FailCount= 1
2023-09-13T20:12:11.101Z,1694635931.101 [NAL9602](INFO): Powering up NAL9602
2023-09-13T20:12:22.030Z,1694635942.030 [NAL9602](INFO): NAL9602 initialized
2023-09-13T20:12:56.984Z,1694635976.984 [CommandExec](IMPORTANT): got command quit
2023-09-13T20:12:57.992Z,1694635977.992 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2023-09-13T20:12:57.992Z,1694635977.992 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-13T20:12:57.992Z,1694635977.992 [CommandExec](INFO): Uninitializing the command executive.
2023-09-13T20:12:57.992Z,1694635977.992 [CommandExec](INFO): Uninitializing the command scheduler.
2023-09-13T20:12:57.992Z,1694635977.992 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-09-13T20:12:58.136Z,1694635978.136 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-09-13T20:12:58.136Z,1694635978.136 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-09-13T20:12:58.136Z,1694635978.136 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-09-13T20:12:58.137Z,1694635978.137 [NavChartDb](INFO): Join timeout helper Thread ID is 6334
2023-09-13T20:12:58.188Z,1694635978.188 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-13T20:12:58.188Z,1694635978.188 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-09-13T20:12:58.200Z,1694635978.200 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-09-13T20:12:58.200Z,1694635978.200 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-09-13T20:12:58.200Z,1694635978.200 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 6335
2023-09-13T20:12:58.548Z,1694635978.548 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-13T20:12:58.548Z,1694635978.548 [WetLabsBB2FL](INFO): Powering down
2023-09-13T20:12:58.549Z,1694635978.549 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-09-13T20:12:58.564Z,1694635978.564 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-09-13T20:12:58.564Z,1694635978.564 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-09-13T20:12:58.564Z,1694635978.564 [CTD_Seabird](INFO): Join timeout helper Thread ID is 6336
2023-09-13T20:12:59.172Z,1694635979.172 [CTD_Seabird](INFO): Powering down
2023-09-13T20:12:59.184Z,1694635979.184 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-13T20:12:59.184Z,1694635979.184 [CTD_Seabird](INFO): Powering down
2023-09-13T20:12:59.196Z,1694635979.196 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-09-13T20:12:59.214Z,1694635979.214 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-09-13T20:12:59.215Z,1694635979.215 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-09-13T20:12:59.215Z,1694635979.215 [Radio_Surface](INFO): Join timeout helper Thread ID is 6337
2023-09-13T20:12:59.420Z,1694635979.420 [Radio_Surface](INFO): Powering down
2023-09-13T20:12:59.421Z,1694635979.421 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-13T20:12:59.421Z,1694635979.421 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-09-13T20:12:59.440Z,1694635979.440 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-09-13T20:12:59.440Z,1694635979.440 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-09-13T20:12:59.440Z,1694635979.440 [Onboard](INFO): Join timeout helper Thread ID is 6338
2023-09-13T20:12:59.520Z,1694635979.520 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2023-09-13T20:13:02.252Z,1694635982.252 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-13T20:13:02.253Z,1694635982.253 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-09-13T20:13:02.261Z,1694635982.261 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-09-13T20:13:02.261Z,1694635982.261 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-09-13T20:13:02.261Z,1694635982.261 [DataOverHttps](INFO): Join timeout helper Thread ID is 6339
2023-09-13T20:13:02.532Z,1694635982.532 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-13T20:13:02.532Z,1694635982.532 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-09-13T20:13:02.552Z,1694635982.552 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2023-09-13T20:13:02.553Z,1694635982.553 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-09-13T20:13:02.553Z,1694635982.553 [BackseatComponent](INFO): Join timeout helper Thread ID is 6340
2023-09-13T20:13:02.660Z,1694635982.660 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-13T20:13:02.660Z,1694635982.660 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-09-13T20:13:02.665Z,1694635982.665 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-09-13T20:13:02.665Z,1694635982.665 [logger ThreadHandler](INFO): Thread cancelled.
2023-09-13T20:13:02.665Z,1694635982.665 [logger](INFO): Join timeout helper Thread ID is 6341
2023-09-13T20:13:02.696Z,1694635982.696 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-13T20:13:02.696Z,1694635982.696 [logger ThreadHandler](INFO): Thread cancelled.
2023-09-13T20:13:02.704Z,1694635982.704 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-09-13T20:13:02.704Z,1694635982.704 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-09-13T20:13:02.705Z,1694635982.705 [CommandLine](INFO): Join timeout helper Thread ID is 6342
2023-09-13T20:13:02.796Z,1694635982.796 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-13T20:13:02.796Z,1694635982.796 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-09-13T20:13:02.804Z,1694635982.804 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-09-13T20:13:02.805Z,1694635982.805 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-09-13T20:13:02.805Z,1694635982.805 [CommandExec](INFO): Join timeout helper Thread ID is 6343
2023-09-13T20:13:02.808Z,1694635982.808 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-09-13T20:13:02.808Z,1694635982.808 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-09-13T20:13:02.808Z,1694635982.808 [controlThread](INFO): Join timeout helper Thread ID is 6344
2023-09-13T20:13:02.839Z,1694635982.839 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-13T20:13:02.840Z,1694635982.840 [controlThread](DEBUG): Uninitializing ControlThread
2023-09-13T20:13:02.840Z,1694635982.840 [AHRS_M2](INFO): Powering down
2023-09-13T20:13:02.913Z,1694635982.913 [NAL9602](INFO): Powering down
2023-09-13T20:13:02.915Z,1694635982.915 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-09-13T20:13:02.916Z,1694635982.916 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-09-13T20:13:02.916Z,1694635982.916 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-09-13T20:13:02.917Z,1694635982.917 [MissionManager](INFO): Uninitializing Mission Default
2023-09-13T20:13:02.917Z,1694635982.917 [Default] Stopped
2023-09-13T20:13:02.917Z,1694635982.917 [Default](DEBUG): Aggregate::uninitialize Default
2023-09-13T20:13:02.917Z,1694635982.917 [Default:B.GoToSurface] Stopped
2023-09-13T20:13:02.917Z,1694635982.917 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-09-13T20:13:02.917Z,1694635982.917 [Default:CheckIn] Stopped
2023-09-13T20:13:02.918Z,1694635982.918 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-13T20:13:02.918Z,1694635982.918 [Default:CheckIn:Read_GPS] Stopped
2023-09-13T20:13:02.920Z,1694635982.920 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-09-13T20:13:02.921Z,1694635982.921 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-09-13T20:13:02.921Z,1694635982.921 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-09-13T20:13:02.921Z,1694635982.921 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-09-13T20:13:02.922Z,1694635982.922 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-09-13T20:13:02.922Z,1694635982.922 [BuoyancyServo](INFO): Powering down
2023-09-13T20:13:02.936Z,1694635982.936 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-09-13T20:13:02.936Z,1694635982.936 [ElevatorServo](INFO): Powering down
2023-09-13T20:13:02.937Z,1694635982.937 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-09-13T20:13:02.937Z,1694635982.937 [MassServo](INFO): Powering down
2023-09-13T20:13:02.938Z,1694635982.938 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-09-13T20:13:02.938Z,1694635982.938 [RudderServo](INFO): Powering down
2023-09-13T20:13:02.939Z,1694635982.939 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-09-13T20:13:02.939Z,1694635982.939 [ThrusterHE](INFO): Powering down
2023-09-13T20:13:02.940Z,1694635982.940 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-09-13T20:13:02.940Z,1694635982.940 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-09-13T20:13:02.940Z,1694635982.940 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-09-13T20:13:02.940Z,1694635982.940 [CBIT](DEBUG): Powering off loads.
2023-09-13T20:13:02.952Z,1694635982.952 [CBIT](DEBUG): Disabling WDT.
2023-09-13T20:13:02.964Z,1694635982.964 [CBIT](DEBUG): Opening all GF detection circuits.
2023-09-13T20:13:02.965Z,1694635982.965 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-09-13T20:13:03.013Z,1694635983.013 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-09-13T20:13:03.016Z,1694635983.016 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-09-13T20:13:03.022Z,1694635983.022 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-09-13T20:13:03.066Z,1694635983.066 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-09-13T20:13:03.106Z,1694635983.106 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-09-13T20:13:03.110Z,1694635983.110 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-09-13T20:13:03.153Z,1694635983.153 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-09-13T20:13:03.261Z,1694635983.261 [logger ThreadHandler](INFO): Thread cancelled.