2023-09-13T17:52:13.768Z,1694627533.768 [CommandExec](IMPORTANT): got command stop 2023-09-13T17:52:13.768Z,1694627533.768 [CommandExec](IMPORTANT): Scheduling is paused 2023-09-13T17:52:13.768Z,1694627533.768 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop 2023-09-13T17:52:13.768Z,1694627533.768 [CommandExec](IMPORTANT): got command restart logs 2023-09-13T17:52:14.160Z,1694627534.160 [MissionManager](INFO): MissionManager is completed. 2023-09-13T17:52:14.160Z,1694627534.160 [MissionManager](INFO): Uninitializing Mission ballast_and_trim 2023-09-13T17:52:14.160Z,1694627534.160 [ballast_and_trim] Stopped 2023-09-13T17:52:14.161Z,1694627534.161 [ballast_and_trim](DEBUG): Aggregate::uninitialize ballast_and_trim 2023-09-13T17:52:14.161Z,1694627534.161 [ballast_and_trim:Science] Stopped 2023-09-13T17:52:14.161Z,1694627534.161 [ballast_and_trim:Science](DEBUG): Aggregate::uninitialize ballast_and_trim:Science 2023-09-13T17:52:14.161Z,1694627534.161 [ballast_and_trim:Science:B] Stopped 2023-09-13T17:52:14.161Z,1694627534.161 [ballast_and_trim:Science:C] Stopped 2023-09-13T17:52:14.161Z,1694627534.161 [ballast_and_trim:Science:E] Stopped 2023-09-13T17:52:14.161Z,1694627534.161 [ballast_and_trim:Science:OceanCurrent] Stopped 2023-09-13T17:52:14.161Z,1694627534.161 [ballast_and_trim:Science:OceanCurrent](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:OceanCurrent 2023-09-13T17:52:14.161Z,1694627534.161 [ballast_and_trim:Science:OceanCurrent:A.] Stopped 2023-09-13T17:52:14.161Z,1694627534.161 [ballast_and_trim:BackseatDriver] Stopped 2023-09-13T17:52:14.161Z,1694627534.161 [ballast_and_trim:BackseatDriver](DEBUG): Aggregate::uninitialize ballast_and_trim:BackseatDriver 2023-09-13T17:52:14.161Z,1694627534.161 [ballast_and_trim:BackseatDriver:A.BackseatDriver] Stopped 2023-09-13T17:52:14.161Z,1694627534.161 [ballast_and_trim:RunBallastAndTrim] Stopped 2023-09-13T17:52:14.162Z,1694627534.162 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim 2023-09-13T17:52:14.162Z,1694627534.162 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim] Stopped 2023-09-13T17:52:14.162Z,1694627534.162 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim 2023-09-13T17:52:14.162Z,1694627534.162 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Stopped 2023-09-13T17:52:14.162Z,1694627534.162 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Stopped 2023-09-13T17:52:14.162Z,1694627534.162 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Stopped 2023-09-13T17:52:14.162Z,1694627534.162 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Stopped 2023-09-13T17:52:14.162Z,1694627534.162 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Stopped 2023-09-13T17:52:14.162Z,1694627534.162 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth] Stopped 2023-09-13T17:52:14.162Z,1694627534.162 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth 2023-09-13T17:52:14.162Z,1694627534.162 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Stopped 2023-09-13T17:52:14.162Z,1694627534.162 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Uninitialize. 2023-09-13T17:52:14.162Z,1694627534.162 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Stopped 2023-09-13T17:52:14.162Z,1694627534.162 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.] Stopped 2023-09-13T17:52:14.163Z,1694627534.163 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](INFO): Uninitializing BallastAndTrim. 2023-09-13T17:52:14.163Z,1694627534.163 [ballast_and_trim:RunBallastAndTrim:Depth1] Stopped 2023-09-13T17:52:14.163Z,1694627534.163 [ballast_and_trim:RunBallastAndTrim:Depth1](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1 2023-09-13T17:52:14.163Z,1694627534.163 [ballast_and_trim:RunBallastAndTrim:Depth1:B] Stopped 2023-09-13T17:52:14.163Z,1694627534.163 [ballast_and_trim:RunBallastAndTrim:Depth1:B](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1:B 2023-09-13T17:52:14.529Z,1694627534.529 [MissionManager](IMPORTANT): Started mission Default 2023-09-13T17:52:14.529Z,1694627534.529 [Default] Running Loop=1 2023-09-13T17:52:14.529Z,1694627534.529 [Default](DEBUG): Aggregate::initialize Default 2023-09-13T17:52:14.529Z,1694627534.529 [Default:B.GoToSurface] Running Loop=1 2023-09-13T17:52:14.534Z,1694627534.534 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-09-13T17:52:14.534Z,1694627534.534 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-09-13T17:52:14.534Z,1694627534.534 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-09-13T17:52:14.535Z,1694627534.535 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-09-13T17:52:14.535Z,1694627534.535 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-09-13T17:52:14.535Z,1694627534.535 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-09-13T17:52:14.536Z,1694627534.536 [Default:A.Wait] Running Loop=1 2023-09-13T17:52:14.536Z,1694627534.536 [Default:A.Wait](DEBUG): Initialize Wait Component. 2023-09-13T17:52:14.730Z,1694627534.730 [WetLabsBB2FL](INFO): Powering down 2023-09-13T17:52:27.861Z,1694627547.861 [Default:A.Wait](INFO): Done Waiting. 2023-09-13T17:52:27.861Z,1694627547.861 [Default:A.Wait] Stopped 2023-09-13T17:52:27.861Z,1694627547.861 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2023-09-13T17:52:28.286Z,1694627548.286 [Default:CheckIn] Running Loop=1 2023-09-13T17:52:28.286Z,1694627548.286 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-13T17:52:28.287Z,1694627548.287 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-13T17:52:31.889Z,1694627551.889 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175230.00,A,3648.16280,N,12147.28786,W,0.175,247.41,130923,,,A*75 2023-09-13T17:52:31.893Z,1694627551.893 [NAL9602](INFO): GPS fix at 20230913T175230: (36.802713, -121.788131) 2023-09-13T17:52:31.924Z,1694627551.924 [Default:CheckIn:Read_GPS] Stopped 2023-09-13T17:52:31.924Z,1694627551.924 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-13T17:52:40.441Z,1694627560.441 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230913T171222/Courier0009.lzma 2023-09-13T17:52:41.444Z,1694627561.444 [DataOverHttps](INFO): Moved sent file to Logs/20230913T171222/Courier0009.lzma.bak 2023-09-13T17:52:41.444Z,1694627561.444 [DataOverHttps](INFO): SBD MOMSN=18766386 2023-09-13T17:52:58.210Z,1694627578.210 [DataOverHttps](INFO): Sending 268 bytes from file Logs/20230913T175213/Courier0000.lzma 2023-09-13T17:52:59.211Z,1694627579.211 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Courier0000.lzma.bak 2023-09-13T17:52:59.212Z,1694627579.212 [DataOverHttps](INFO): SBD MOMSN=18766388 2023-09-13T17:53:04.217Z,1694627584.217 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-13T17:53:19.999Z,1694627599.999 [DataOverHttps](INFO): Sending 920 bytes from file Logs/20230913T171222/Express0008.lzma 2023-09-13T17:53:20.000Z,1694627601.000 [DataOverHttps](INFO): Moved sent file to Logs/20230913T171222/Express0008.lzma.bak 2023-09-13T17:53:20.000Z,1694627601.000 [DataOverHttps](INFO): SBD MOMSN=18766392 2023-09-13T17:53:43.850Z,1694627623.850 [DataOverHttps](INFO): Sending 380 bytes from file Logs/20230913T175213/Express0001.lzma 2023-09-13T17:53:44.851Z,1694627624.851 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0001.lzma.bak 2023-09-13T17:53:44.852Z,1694627624.852 [DataOverHttps](INFO): SBD MOMSN=18766429 2023-09-13T17:54:04.117Z,1694627644.117 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20230913T175213/Express0004.lzma 2023-09-13T17:54:05.120Z,1694627645.120 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0004.lzma.bak 2023-09-13T17:54:05.120Z,1694627645.120 [DataOverHttps](INFO): SBD MOMSN=18766435 2023-09-13T17:54:08.546Z,1694627648.546 [Default:CheckIn:Read_Iridium] Stopped 2023-09-13T17:54:08.547Z,1694627648.547 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-13T17:54:08.547Z,1694627648.547 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-13T17:59:09.133Z,1694627949.133 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-13T17:59:09.134Z,1694627949.134 [Default:CheckIn:C.Wait] Stopped 2023-09-13T17:59:09.134Z,1694627949.134 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-13T17:59:09.134Z,1694627949.134 [Default:CheckIn:D] Running Loop=1 2023-09-13T17:59:09.538Z,1694627949.538 [Default:CheckIn:D] Stopped 2023-09-13T17:59:09.538Z,1694627949.538 [Default:CheckIn:E] Running Loop=1 2023-09-13T17:59:09.948Z,1694627949.948 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.916813 min 2023-09-13T17:59:09.948Z,1694627949.948 [Default:CheckIn:E] Stopped 2023-09-13T17:59:09.948Z,1694627949.948 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-13T17:59:09.948Z,1694627949.948 [Default:CheckIn] Stopped 2023-09-13T17:59:09.948Z,1694627949.948 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-13T17:59:09.948Z,1694627949.948 [Default:CheckIn](INFO): Running loop #2 2023-09-13T17:59:09.948Z,1694627949.948 [Default:CheckIn] Running Loop=2 2023-09-13T17:59:09.948Z,1694627949.948 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-13T17:59:09.948Z,1694627949.948 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-13T17:59:11.957Z,1694627951.957 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175911.00,A,3648.15927,N,12147.27439,W,0.603,247.41,130923,,,A*76 2023-09-13T17:59:11.967Z,1694627951.967 [NAL9602](INFO): GPS fix at 20230913T175911: (36.802655, -121.787907) 2023-09-13T17:59:11.980Z,1694627951.980 [Default:CheckIn:Read_GPS] Stopped 2023-09-13T17:59:11.980Z,1694627951.980 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-13T17:59:21.581Z,1694627961.581 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230913T175213/Courier0006.lzma 2023-09-13T17:59:22.584Z,1694627962.584 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Courier0006.lzma.bak 2023-09-13T17:59:22.584Z,1694627962.584 [DataOverHttps](INFO): SBD MOMSN=18766448 2023-09-13T17:59:45.085Z,1694627985.085 [DataOverHttps](INFO): Sending 162 bytes from file Logs/20230913T175213/Express0007.lzma 2023-09-13T17:59:46.088Z,1694627986.088 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0007.lzma.bak 2023-09-13T17:59:46.088Z,1694627986.088 [DataOverHttps](INFO): SBD MOMSN=18766456 2023-09-13T17:59:49.188Z,1694627989.188 [Default:CheckIn:Read_Iridium] Stopped 2023-09-13T17:59:49.188Z,1694627989.188 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-13T17:59:49.188Z,1694627989.188 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-13T18:04:14.199Z,1694628254.199 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-13T18:04:44.899Z,1694628284.899 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-13T18:04:49.762Z,1694628289.762 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-13T18:04:49.762Z,1694628289.762 [Default:CheckIn:C.Wait] Stopped 2023-09-13T18:04:49.762Z,1694628289.762 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-13T18:04:49.762Z,1694628289.762 [Default:CheckIn:D] Running Loop=1 2023-09-13T18:04:50.183Z,1694628290.183 [Default:CheckIn:D] Stopped 2023-09-13T18:04:50.183Z,1694628290.183 [Default:CheckIn:E] Running Loop=1 2023-09-13T18:04:50.567Z,1694628290.567 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.594219 min 2023-09-13T18:04:50.567Z,1694628290.567 [Default:CheckIn:E] Stopped 2023-09-13T18:04:50.567Z,1694628290.567 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-13T18:04:50.567Z,1694628290.567 [Default:CheckIn] Stopped 2023-09-13T18:04:50.567Z,1694628290.567 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-13T18:04:50.568Z,1694628290.568 [Default:CheckIn](INFO): Running loop #3 2023-09-13T18:04:50.568Z,1694628290.568 [Default:CheckIn] Running Loop=3 2023-09-13T18:04:50.568Z,1694628290.568 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-13T18:04:50.568Z,1694628290.568 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-13T18:04:52.581Z,1694628292.581 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180451.00,A,3648.17107,N,12147.27861,W,0.389,0.00,130923,,,A*7F 2023-09-13T18:04:52.583Z,1694628292.583 [NAL9602](INFO): GPS fix at 20230913T180451: (36.802851, -121.787977) 2023-09-13T18:04:52.596Z,1694628292.596 [Default:CheckIn:Read_GPS] Stopped 2023-09-13T18:04:52.596Z,1694628292.596 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-13T18:05:01.369Z,1694628301.369 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230913T175213/Courier0009.lzma 2023-09-13T18:05:02.372Z,1694628302.372 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Courier0009.lzma.bak 2023-09-13T18:05:02.372Z,1694628302.372 [DataOverHttps](INFO): SBD MOMSN=18766465 2023-09-13T18:05:20.102Z,1694628320.102 [DataOverHttps](INFO): Sending 229 bytes from file Logs/20230913T175213/Express0010.lzma 2023-09-13T18:05:21.103Z,1694628321.103 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0010.lzma.bak 2023-09-13T18:05:21.104Z,1694628321.104 [DataOverHttps](INFO): SBD MOMSN=18766469 2023-09-13T18:05:23.279Z,1694628323.279 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-09-13T18:05:23.354Z,1694628323.354 [NAL9602](ERROR): received: +CSQ:0 OK500, 2, 0, 0, 0 OK 2023-09-13T18:05:25.761Z,1694628325.761 [Default:CheckIn:Read_Iridium] Stopped 2023-09-13T18:05:25.761Z,1694628325.761 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-13T18:05:25.761Z,1694628325.761 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-13T18:06:01.775Z,1694628361.775 [NAL9602](INFO): SBD MO Status=2, MOMSN=13500, MT Status=2, MTMSN=0 2023-09-13T18:06:01.775Z,1694628361.776 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T18:09:54.484Z,1694628594.484 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-13T18:10:25.183Z,1694628625.183 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-13T18:10:26.447Z,1694628626.447 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-13T18:10:26.448Z,1694628626.448 [Default:CheckIn:C.Wait] Stopped 2023-09-13T18:10:26.448Z,1694628626.448 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-13T18:10:26.448Z,1694628626.448 [Default:CheckIn:D] Running Loop=1 2023-09-13T18:10:26.875Z,1694628626.875 [Default:CheckIn:D] Stopped 2023-09-13T18:10:26.875Z,1694628626.875 [Default:CheckIn:E] Running Loop=1 2023-09-13T18:10:27.260Z,1694628627.260 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 18.205756 min 2023-09-13T18:10:27.260Z,1694628627.260 [Default:CheckIn:E] Stopped 2023-09-13T18:10:27.260Z,1694628627.260 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-13T18:10:27.260Z,1694628627.260 [Default:CheckIn] Stopped 2023-09-13T18:10:27.260Z,1694628627.260 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-13T18:10:27.261Z,1694628627.261 [Default:CheckIn](INFO): Running loop #4 2023-09-13T18:10:27.261Z,1694628627.261 [Default:CheckIn] Running Loop=4 2023-09-13T18:10:27.261Z,1694628627.261 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-13T18:10:27.261Z,1694628627.261 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-13T18:10:29.257Z,1694628629.257 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181028.00,A,3648.16775,N,12147.27808,W,0.311,0.00,130923,,,A*78 2023-09-13T18:10:29.259Z,1694628629.259 [NAL9602](INFO): GPS fix at 20230913T181028: (36.802796, -121.787968) 2023-09-13T18:10:29.330Z,1694628629.330 [Default:CheckIn:Read_GPS] Stopped 2023-09-13T18:10:29.330Z,1694628629.330 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-13T18:10:31.444Z,1694628631.444 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230913T175213/Courier0012.lzma 2023-09-13T18:10:32.455Z,1694628632.455 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Courier0012.lzma.bak 2023-09-13T18:10:32.456Z,1694628632.456 [DataOverHttps](INFO): SBD MOMSN=18766482 2023-09-13T18:10:50.786Z,1694628650.786 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20230913T175213/Express0013.lzma 2023-09-13T18:10:51.787Z,1694628651.787 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0013.lzma.bak 2023-09-13T18:10:51.788Z,1694628651.788 [DataOverHttps](INFO): SBD MOMSN=18766485 2023-09-13T18:10:55.142Z,1694628655.142 [Default:CheckIn:Read_Iridium] Stopped 2023-09-13T18:10:55.142Z,1694628655.142 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-13T18:10:55.142Z,1694628655.142 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-13T18:10:59.955Z,1694628659.955 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-09-13T18:11:00.038Z,1694628660.038 [NAL9602](ERROR): received: +CSQ:0 OK500, 2, 0, 0, 0 OK 2023-09-13T18:14:30.040Z,1694628870.040 [NAL9602](INFO): SBD MO Status=2, MOMSN=13500, MT Status=2, MTMSN=0 2023-09-13T18:14:30.040Z,1694628870.040 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T18:15:16.096Z,1694628916.096 [NAL9602](INFO): SBD MO Status=2, MOMSN=13500, MT Status=2, MTMSN=0 2023-09-13T18:15:16.096Z,1694628916.096 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T18:15:31.444Z,1694628931.444 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-13T18:15:55.791Z,1694628955.791 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-13T18:15:55.791Z,1694628955.791 [Default:CheckIn:C.Wait] Stopped 2023-09-13T18:15:55.791Z,1694628955.791 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-13T18:15:56.609Z,1694628956.609 [Default:CheckIn:D] Running Loop=1 2023-09-13T18:15:56.764Z,1694628956.764 [Default:CheckIn:D] Stopped 2023-09-13T18:15:56.764Z,1694628956.764 [Default:CheckIn:E] Running Loop=1 2023-09-13T18:15:57.071Z,1694628957.071 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 23.703902 min 2023-09-13T18:15:57.071Z,1694628957.071 [Default:CheckIn:E] Stopped 2023-09-13T18:15:57.071Z,1694628957.071 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-13T18:15:57.071Z,1694628957.071 [Default:CheckIn] Stopped 2023-09-13T18:15:57.072Z,1694628957.072 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-13T18:15:57.072Z,1694628957.072 [Default:CheckIn](INFO): Running loop #5 2023-09-13T18:15:57.072Z,1694628957.072 [Default:CheckIn] Running Loop=5 2023-09-13T18:15:57.072Z,1694628957.072 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-13T18:15:57.072Z,1694628957.072 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-13T18:15:59.065Z,1694628959.065 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181558.00,A,3648.16932,N,12147.27938,W,0.641,0.00,130923,,,A*75 2023-09-13T18:15:59.067Z,1694628959.067 [NAL9602](INFO): GPS fix at 20230913T181558: (36.802822, -121.787990) 2023-09-13T18:15:59.079Z,1694628959.079 [Default:CheckIn:Read_GPS] Stopped 2023-09-13T18:15:59.079Z,1694628959.079 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-13T18:16:07.769Z,1694628967.769 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20230913T175213/Courier0015.lzma 2023-09-13T18:16:08.772Z,1694628968.772 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Courier0015.lzma.bak 2023-09-13T18:16:08.772Z,1694628968.772 [DataOverHttps](INFO): SBD MOMSN=18766498 2023-09-13T18:16:26.401Z,1694628986.401 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20230913T175213/Express0016.lzma 2023-09-13T18:16:27.403Z,1694628987.404 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0016.lzma.bak 2023-09-13T18:16:27.404Z,1694628987.404 [DataOverHttps](INFO): SBD MOMSN=18766502 2023-09-13T18:16:30.639Z,1694628990.639 [Default:CheckIn:Read_Iridium] Stopped 2023-09-13T18:16:30.639Z,1694628990.639 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-13T18:16:30.639Z,1694628990.639 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-13T18:16:31.379Z,1694628991.379 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-13T18:21:31.197Z,1694629291.197 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-13T18:21:31.197Z,1694629291.197 [Default:CheckIn:C.Wait] Stopped 2023-09-13T18:21:31.197Z,1694629291.197 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-13T18:21:31.197Z,1694629291.197 [Default:CheckIn:D] Running Loop=1 2023-09-13T18:21:31.590Z,1694629291.590 [Default:CheckIn:D] Stopped 2023-09-13T18:21:31.590Z,1694629291.590 [Default:CheckIn:E] Running Loop=1 2023-09-13T18:21:31.995Z,1694629291.995 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 29.284342 min 2023-09-13T18:21:31.995Z,1694629291.995 [Default:CheckIn:E] Stopped 2023-09-13T18:21:31.995Z,1694629291.995 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-13T18:21:31.995Z,1694629291.995 [Default:CheckIn] Stopped 2023-09-13T18:21:31.995Z,1694629291.995 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-13T18:21:31.995Z,1694629291.995 [Default:CheckIn](INFO): Running loop #6 2023-09-13T18:21:31.996Z,1694629291.996 [Default:CheckIn] Running Loop=6 2023-09-13T18:21:31.996Z,1694629291.996 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-13T18:21:31.996Z,1694629291.996 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-13T18:21:34.002Z,1694629294.002 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182133.00,A,3648.16684,N,12147.29394,W,0.505,0.00,130923,,,A*7C 2023-09-13T18:21:34.004Z,1694629294.004 [NAL9602](INFO): GPS fix at 20230913T182133: (36.802781, -121.788232) 2023-09-13T18:21:34.016Z,1694629294.016 [Default:CheckIn:Read_GPS] Stopped 2023-09-13T18:21:34.017Z,1694629294.017 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-13T18:21:42.777Z,1694629302.777 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230913T175213/Courier0018.lzma 2023-09-13T18:21:43.780Z,1694629303.780 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Courier0018.lzma.bak 2023-09-13T18:21:43.780Z,1694629303.780 [DataOverHttps](INFO): SBD MOMSN=18766580 2023-09-13T18:22:03.861Z,1694629323.861 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230913T175213/Express0019.lzma 2023-09-13T18:22:04.780Z,1694629324.780 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-09-13T18:22:04.858Z,1694629324.858 [NAL9602](ERROR): received: +CSQ:0 OK500, 2, 0, 0, 0 OK 2023-09-13T18:22:04.864Z,1694629324.864 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0019.lzma.bak 2023-09-13T18:22:04.864Z,1694629324.864 [DataOverHttps](INFO): SBD MOMSN=18766588 2023-09-13T18:22:08.409Z,1694629328.409 [Default:CheckIn:Read_Iridium] Stopped 2023-09-13T18:22:08.409Z,1694629328.409 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-13T18:22:08.409Z,1694629328.409 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-13T18:23:49.746Z,1694629429.746 [NAL9602](INFO): SBD MO Status=2, MOMSN=13500, MT Status=2, MTMSN=0 2023-09-13T18:23:49.746Z,1694629429.746 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T18:26:17.247Z,1694629577.247 [NAL9602](INFO): SBD MO Status=2, MOMSN=13500, MT Status=2, MTMSN=0 2023-09-13T18:26:17.247Z,1694629577.247 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T18:26:36.251Z,1694629596.251 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-13T18:27:06.935Z,1694629626.935 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-13T18:27:08.977Z,1694629628.977 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-13T18:27:08.977Z,1694629628.977 [Default:CheckIn:C.Wait] Stopped 2023-09-13T18:27:08.977Z,1694629628.977 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-13T18:27:08.978Z,1694629628.978 [Default:CheckIn:D] Running Loop=1 2023-09-13T18:27:09.395Z,1694629629.395 [Default:CheckIn:D] Stopped 2023-09-13T18:27:09.395Z,1694629629.395 [Default:CheckIn:E] Running Loop=1 2023-09-13T18:27:09.775Z,1694629629.775 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 34.914433 min 2023-09-13T18:27:09.775Z,1694629629.775 [Default:CheckIn:E] Stopped 2023-09-13T18:27:09.775Z,1694629629.775 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-13T18:27:09.775Z,1694629629.775 [Default:CheckIn] Stopped 2023-09-13T18:27:09.775Z,1694629629.775 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-13T18:27:09.775Z,1694629629.775 [Default:CheckIn](INFO): Running loop #7 2023-09-13T18:27:09.776Z,1694629629.776 [Default:CheckIn] Running Loop=7 2023-09-13T18:27:09.776Z,1694629629.776 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-13T18:27:09.776Z,1694629629.776 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-13T18:27:11.807Z,1694629631.807 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182711.00,A,3648.17066,N,12147.27984,W,0.428,0.00,130923,,,A*7A 2023-09-13T18:27:11.809Z,1694629631.809 [NAL9602](INFO): GPS fix at 20230913T182711: (36.802844, -121.787997) 2023-09-13T18:27:11.845Z,1694629631.845 [Default:CheckIn:Read_GPS] Stopped 2023-09-13T18:27:11.845Z,1694629631.845 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-13T18:27:21.749Z,1694629641.749 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230913T175213/Courier0021.lzma 2023-09-13T18:27:22.756Z,1694629642.756 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Courier0021.lzma.bak 2023-09-13T18:27:22.756Z,1694629642.756 [DataOverHttps](INFO): SBD MOMSN=18766599 2023-09-13T18:27:28.791Z,1694629648.791 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2023-09-13T18:27:28.830Z,1694629648.830 [BPC1](INFO): Received data from all battery sticks. 2023-09-13T18:27:29.625Z,1694629649.625 [CBIT](IMPORTANT): Beginning ground fault scan 2023-09-13T18:27:32.502Z,1694629652.502 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes. 2023-09-13T18:27:40.555Z,1694629660.555 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.006293 CHAN A1 (24V) N/A reporting off CHAN A2 (12V): -0.005479 CHAN A3 (5V): -0.002452 CHAN B0 (3.3V): -0.000857 CHAN B1 (3.15aV): -0.000799 CHAN B2 (3.15bV): -0.001069 CHAN B3 (GND): -0.000580 OPEN: 0.005130 Full Scale: +/- 1 mA 2023-09-13T18:27:42.539Z,1694629662.539 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-09-13T18:27:42.622Z,1694629662.622 [NAL9602](ERROR): received: +CSQ:0 OK500, 2, 0, 0, 0 OK 2023-09-13T18:27:45.682Z,1694629665.682 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20230913T175213/Express0022.lzma 2023-09-13T18:27:46.684Z,1694629666.684 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0022.lzma.bak 2023-09-13T18:27:46.684Z,1694629666.684 [DataOverHttps](INFO): SBD MOMSN=18766602 2023-09-13T18:27:49.831Z,1694629669.831 [Default:CheckIn:Read_Iridium] Stopped 2023-09-13T18:27:49.831Z,1694629669.831 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-13T18:27:49.832Z,1694629669.832 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-13T18:31:38.904Z,1694629898.904 [NAL9602](INFO): SBD MO Status=2, MOMSN=13500, MT Status=2, MTMSN=0 2023-09-13T18:31:38.905Z,1694629898.905 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T18:32:24.952Z,1694629944.952 [NAL9602](INFO): SBD MO Status=2, MOMSN=13500, MT Status=2, MTMSN=0 2023-09-13T18:32:24.952Z,1694629944.952 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T18:32:25.355Z,1694629945.355 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-13T18:32:50.489Z,1694629970.489 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-13T18:32:50.489Z,1694629970.489 [Default:CheckIn:C.Wait] Stopped 2023-09-13T18:32:50.489Z,1694629970.489 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-13T18:32:50.489Z,1694629970.489 [Default:CheckIn:D] Running Loop=1 2023-09-13T18:32:50.841Z,1694629970.841 [Default:CheckIn:D] Stopped 2023-09-13T18:32:50.841Z,1694629970.841 [Default:CheckIn:E] Running Loop=1 2023-09-13T18:32:51.261Z,1694629971.261 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 40.605192 min 2023-09-13T18:32:51.261Z,1694629971.261 [Default:CheckIn:E] Stopped 2023-09-13T18:32:51.261Z,1694629971.261 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-13T18:32:51.261Z,1694629971.261 [Default:CheckIn] Stopped 2023-09-13T18:32:51.320Z,1694629971.320 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-13T18:32:51.320Z,1694629971.320 [Default:CheckIn](INFO): Running loop #8 2023-09-13T18:32:51.320Z,1694629971.320 [Default:CheckIn] Running Loop=8 2023-09-13T18:32:51.320Z,1694629971.320 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-13T18:32:51.320Z,1694629971.320 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-13T18:32:53.265Z,1694629973.265 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183252.00,A,3648.17024,N,12147.27751,W,0.019,0.00,130923,,,A*7F 2023-09-13T18:32:53.267Z,1694629973.267 [NAL9602](INFO): GPS fix at 20230913T183252: (36.802837, -121.787959) 2023-09-13T18:32:53.293Z,1694629973.293 [Default:CheckIn:Read_GPS] Stopped 2023-09-13T18:32:53.293Z,1694629973.293 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-13T18:32:58.115Z,1694629978.115 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20230913T175213/Courier0024.lzma 2023-09-13T18:33:00.152Z,1694629980.152 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Courier0024.lzma.bak 2023-09-13T18:33:00.154Z,1694629980.154 [DataOverHttps](INFO): SBD MOMSN=18766611 2023-09-13T18:33:25.231Z,1694630005.231 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-13T18:33:29.302Z,1694630009.302 [DataOverHttps](INFO): Sending 413 bytes from file Logs/20230913T175213/Express0025.lzma 2023-09-13T18:33:30.304Z,1694630010.304 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0025.lzma.bak 2023-09-13T18:33:30.304Z,1694630010.304 [DataOverHttps](INFO): SBD MOMSN=18766617 2023-09-13T18:33:35.795Z,1694630015.795 [Default:CheckIn:Read_Iridium] Stopped 2023-09-13T18:33:35.795Z,1694630015.795 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-13T18:33:35.795Z,1694630015.795 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-13T18:38:36.413Z,1694630316.413 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-13T18:38:36.413Z,1694630316.413 [Default:CheckIn:C.Wait] Stopped 2023-09-13T18:38:36.413Z,1694630316.413 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-13T18:38:36.413Z,1694630316.413 [Default:CheckIn:D] Running Loop=1 2023-09-13T18:38:36.818Z,1694630316.818 [Default:CheckIn:D] Stopped 2023-09-13T18:38:36.818Z,1694630316.818 [Default:CheckIn:E] Running Loop=1 2023-09-13T18:38:37.228Z,1694630317.228 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 46.371472 min 2023-09-13T18:38:37.228Z,1694630317.228 [Default:CheckIn:E] Stopped 2023-09-13T18:38:37.228Z,1694630317.228 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-13T18:38:37.228Z,1694630317.228 [Default:CheckIn] Stopped 2023-09-13T18:38:37.229Z,1694630317.229 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-13T18:38:37.229Z,1694630317.229 [Default:CheckIn](INFO): Running loop #9 2023-09-13T18:38:37.229Z,1694630317.229 [Default:CheckIn] Running Loop=9 2023-09-13T18:38:37.229Z,1694630317.229 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-13T18:38:37.229Z,1694630317.229 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-13T18:38:39.233Z,1694630319.233 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183838.00,A,3648.17159,N,12147.28243,W,0.233,0.00,130923,,,A*71 2023-09-13T18:38:39.236Z,1694630319.236 [NAL9602](INFO): GPS fix at 20230913T183838: (36.802860, -121.788040) 2023-09-13T18:38:39.248Z,1694630319.248 [Default:CheckIn:Read_GPS] Stopped 2023-09-13T18:38:39.248Z,1694630319.248 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-13T18:38:48.081Z,1694630328.081 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230913T175213/Courier0027.lzma 2023-09-13T18:38:49.084Z,1694630329.084 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Courier0027.lzma.bak 2023-09-13T18:38:49.084Z,1694630329.084 [DataOverHttps](INFO): SBD MOMSN=18766633 2023-09-13T18:39:06.874Z,1694630346.874 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230913T175213/Express0028.lzma 2023-09-13T18:39:07.876Z,1694630347.876 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0028.lzma.bak 2023-09-13T18:39:07.876Z,1694630347.876 [DataOverHttps](INFO): SBD MOMSN=18766636 2023-09-13T18:39:11.197Z,1694630351.197 [Default:CheckIn:Read_Iridium] Stopped 2023-09-13T18:39:11.197Z,1694630351.197 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-13T18:39:11.197Z,1694630351.197 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-13T18:40:46.936Z,1694630446.936 [NAL9602](INFO): SBD MO Status=0, MOMSN=13500, MT Status=0, MTMSN=0 2023-09-13T18:40:46.937Z,1694630446.937 [NAL9602](INFO): No messages in MT queue 2023-09-13T18:41:17.639Z,1694630477.639 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-13T18:44:11.778Z,1694630651.778 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-13T18:44:11.778Z,1694630651.778 [Default:CheckIn:C.Wait] Stopped 2023-09-13T18:44:11.778Z,1694630651.778 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-13T18:44:11.778Z,1694630651.778 [Default:CheckIn:D] Running Loop=1 2023-09-13T18:44:12.173Z,1694630652.173 [Default:CheckIn:D] Stopped 2023-09-13T18:44:12.173Z,1694630652.173 [Default:CheckIn:E] Running Loop=1 2023-09-13T18:44:12.583Z,1694630652.583 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 51.960726 min 2023-09-13T18:44:12.583Z,1694630652.583 [Default:CheckIn:E] Stopped 2023-09-13T18:44:12.583Z,1694630652.583 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-13T18:44:12.583Z,1694630652.583 [Default:CheckIn] Stopped 2023-09-13T18:44:12.583Z,1694630652.583 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-13T18:44:12.583Z,1694630652.583 [Default:CheckIn](INFO): Running loop #10 2023-09-13T18:44:12.584Z,1694630652.584 [Default:CheckIn] Running Loop=10 2023-09-13T18:44:12.584Z,1694630652.584 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-13T18:44:12.584Z,1694630652.584 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-13T18:44:14.589Z,1694630654.589 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184413.00,A,3648.16589,N,12147.28056,W,0.156,0.00,130923,,,A*7D 2023-09-13T18:44:14.591Z,1694630654.591 [NAL9602](INFO): GPS fix at 20230913T184413: (36.802765, -121.788009) 2023-09-13T18:44:14.605Z,1694630654.605 [Default:CheckIn:Read_GPS] Stopped 2023-09-13T18:44:14.605Z,1694630654.605 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-13T18:44:23.357Z,1694630663.357 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230913T175213/Courier0030.lzma 2023-09-13T18:44:24.360Z,1694630664.360 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Courier0030.lzma.bak 2023-09-13T18:44:24.360Z,1694630664.360 [DataOverHttps](INFO): SBD MOMSN=18766704 2023-09-13T18:44:43.757Z,1694630683.757 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230913T175213/Express0031.lzma 2023-09-13T18:44:44.760Z,1694630684.760 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0031.lzma.bak 2023-09-13T18:44:44.760Z,1694630684.760 [DataOverHttps](INFO): SBD MOMSN=18766707 2023-09-13T18:44:48.152Z,1694630688.152 [Default:CheckIn:Read_Iridium] Stopped 2023-09-13T18:44:48.153Z,1694630688.153 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-13T18:44:48.153Z,1694630688.153 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-13T18:48:29.148Z,1694630909.148 [NAL9602](INFO): SBD MO Status=2, MOMSN=13501, MT Status=2, MTMSN=0 2023-09-13T18:48:29.148Z,1694630909.148 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T18:48:48.136Z,1694630928.136 [NAL9602](INFO): SBD MO Status=2, MOMSN=13501, MT Status=2, MTMSN=0 2023-09-13T18:48:48.137Z,1694630928.137 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T18:49:04.297Z,1694630944.297 [NAL9602](INFO): SBD MO Status=2, MOMSN=13501, MT Status=2, MTMSN=0 2023-09-13T18:49:04.297Z,1694630944.297 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T18:49:16.410Z,1694630956.410 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-13T18:49:47.120Z,1694630987.120 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-13T18:49:48.748Z,1694630988.748 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-13T18:49:48.748Z,1694630988.748 [Default:CheckIn:C.Wait] Stopped 2023-09-13T18:49:48.748Z,1694630988.748 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-13T18:49:48.748Z,1694630988.748 [Default:CheckIn:D] Running Loop=1 2023-09-13T18:49:49.145Z,1694630989.145 [Default:CheckIn:D] Stopped 2023-09-13T18:49:49.145Z,1694630989.145 [Default:CheckIn:E] Running Loop=1 2023-09-13T18:49:49.550Z,1694630989.550 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 57.576925 min 2023-09-13T18:49:49.550Z,1694630989.550 [Default:CheckIn:E] Stopped 2023-09-13T18:49:49.550Z,1694630989.550 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-13T18:49:49.550Z,1694630989.550 [Default:CheckIn] Stopped 2023-09-13T18:49:49.550Z,1694630989.550 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-13T18:49:49.550Z,1694630989.550 [Default:CheckIn](INFO): Running loop #11 2023-09-13T18:49:49.550Z,1694630989.550 [Default:CheckIn] Running Loop=11 2023-09-13T18:49:49.551Z,1694630989.551 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-13T18:49:49.551Z,1694630989.551 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-13T18:49:51.565Z,1694630991.565 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184950.00,A,3648.16981,N,12147.28341,W,0.097,0.00,130923,,,A*7A 2023-09-13T18:49:51.567Z,1694630991.567 [NAL9602](INFO): GPS fix at 20230913T184950: (36.802830, -121.788057) 2023-09-13T18:49:51.579Z,1694630991.579 [Default:CheckIn:Read_GPS] Stopped 2023-09-13T18:49:51.579Z,1694630991.579 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-13T18:49:59.625Z,1694630999.625 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230913T175213/Courier0033.lzma 2023-09-13T18:50:00.628Z,1694631000.628 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Courier0033.lzma.bak 2023-09-13T18:50:00.628Z,1694631000.628 [DataOverHttps](INFO): SBD MOMSN=18766736 2023-09-13T18:50:18.397Z,1694631018.397 [DataOverHttps](INFO): Sending 205 bytes from file Logs/20230913T175213/Express0034.lzma 2023-09-13T18:50:19.400Z,1694631019.400 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0034.lzma.bak 2023-09-13T18:50:19.400Z,1694631019.400 [DataOverHttps](INFO): SBD MOMSN=18766739 2023-09-13T18:50:22.319Z,1694631022.319 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-09-13T18:50:22.394Z,1694631022.394 [NAL9602](ERROR): received: +CSQ:1 OK501, 2, 0, 0, 0 OK 2023-09-13T18:50:22.757Z,1694631022.757 [Default:CheckIn:Read_Iridium] Stopped 2023-09-13T18:50:22.757Z,1694631022.757 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-13T18:50:22.757Z,1694631022.757 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-13T18:50:44.545Z,1694631044.545 [NAL9602](INFO): SBD MO Status=2, MOMSN=13501, MT Status=2, MTMSN=0 2023-09-13T18:50:44.545Z,1694631044.545 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T18:51:09.588Z,1694631069.588 [NAL9602](INFO): SBD MO Status=2, MOMSN=13501, MT Status=2, MTMSN=0 2023-09-13T18:51:09.588Z,1694631069.588 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T18:51:31.404Z,1694631091.404 [NAL9602](INFO): SBD MO Status=2, MOMSN=13501, MT Status=2, MTMSN=0 2023-09-13T18:51:31.404Z,1694631091.404 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T18:51:47.968Z,1694631107.968 [NAL9602](INFO): SBD MO Status=2, MOMSN=13501, MT Status=2, MTMSN=0 2023-09-13T18:51:47.969Z,1694631107.969 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T18:52:14.640Z,1694631134.640 [NAL9602](INFO): SBD MO Status=2, MOMSN=13501, MT Status=2, MTMSN=0 2023-09-13T18:52:14.641Z,1694631134.641 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T18:53:21.696Z,1694631201.696 [NAL9602](INFO): SBD MO Status=2, MOMSN=13501, MT Status=2, MTMSN=0 2023-09-13T18:53:21.696Z,1694631201.696 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T18:54:53.403Z,1694631293.403 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-13T18:55:23.339Z,1694631323.339 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-13T18:55:23.339Z,1694631323.339 [Default:CheckIn:C.Wait] Stopped 2023-09-13T18:55:23.339Z,1694631323.339 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-13T18:55:23.339Z,1694631323.339 [Default:CheckIn:D] Running Loop=1 2023-09-13T18:55:23.713Z,1694631323.713 [Default:CheckIn:D] Stopped 2023-09-13T18:55:23.713Z,1694631323.713 [Default:CheckIn:E] Running Loop=1 2023-09-13T18:55:24.108Z,1694631324.108 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-13T18:55:24.130Z,1694631324.130 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.153060 min 2023-09-13T18:55:24.130Z,1694631324.130 [Default:CheckIn:E] Stopped 2023-09-13T18:55:24.130Z,1694631324.130 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-13T18:55:24.130Z,1694631324.130 [Default:CheckIn] Stopped 2023-09-13T18:55:24.131Z,1694631324.131 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-13T18:55:24.131Z,1694631324.131 [Default:CheckIn](INFO): Running loop #12 2023-09-13T18:55:24.131Z,1694631324.131 [Default:CheckIn] Running Loop=12 2023-09-13T18:55:24.131Z,1694631324.131 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-13T18:55:24.131Z,1694631324.131 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-13T18:55:26.129Z,1694631326.129 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185525.00,A,3648.16766,N,12147.28125,W,0.194,0.00,130923,,,A*70 2023-09-13T18:55:26.131Z,1694631326.131 [NAL9602](INFO): GPS fix at 20230913T185525: (36.802794, -121.788021) 2023-09-13T18:55:26.187Z,1694631326.187 [Default:CheckIn:Read_GPS] Stopped 2023-09-13T18:55:26.187Z,1694631326.187 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-13T18:55:38.349Z,1694631338.349 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20230913T175213/Courier0036.lzma 2023-09-13T18:55:39.352Z,1694631339.352 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Courier0036.lzma.bak 2023-09-13T18:55:39.352Z,1694631339.352 [DataOverHttps](INFO): SBD MOMSN=18766750 2023-09-13T18:55:56.840Z,1694631356.840 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-09-13T18:55:56.918Z,1694631356.918 [NAL9602](ERROR): received: +CSQ:0 OK501, 2, 0, 0, 0 OK 2023-09-13T18:55:57.221Z,1694631357.221 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20230913T175213/Express0037.lzma 2023-09-13T18:55:58.224Z,1694631358.224 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0037.lzma.bak 2023-09-13T18:55:58.224Z,1694631358.224 [DataOverHttps](INFO): SBD MOMSN=18766753 2023-09-13T18:56:03.355Z,1694631363.355 [Default:CheckIn:Read_Iridium] Stopped 2023-09-13T18:56:03.355Z,1694631363.355 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-13T18:56:03.355Z,1694631363.355 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-13T18:57:11.585Z,1694631431.585 [NAL9602](INFO): SBD MO Status=2, MOMSN=13501, MT Status=2, MTMSN=0 2023-09-13T18:57:11.585Z,1694631431.585 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T18:57:28.549Z,1694631448.549 [NAL9602](INFO): SBD MO Status=2, MOMSN=13501, MT Status=2, MTMSN=0 2023-09-13T18:57:28.549Z,1694631448.549 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T18:59:10.360Z,1694631550.360 [NAL9602](INFO): SBD MO Status=2, MOMSN=13501, MT Status=2, MTMSN=0 2023-09-13T18:59:10.361Z,1694631550.361 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T18:59:33.384Z,1694631573.384 [NAL9602](INFO): SBD MO Status=2, MOMSN=13501, MT Status=2, MTMSN=0 2023-09-13T18:59:33.385Z,1694631573.385 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T18:59:45.105Z,1694631585.105 [NAL9602](INFO): SBD MO Status=0, MOMSN=13501, MT Status=0, MTMSN=0 2023-09-13T18:59:45.105Z,1694631585.105 [NAL9602](INFO): No messages in MT queue 2023-09-13T19:00:15.807Z,1694631615.807 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-13T19:01:03.898Z,1694631663.898 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-13T19:01:03.898Z,1694631663.898 [Default:CheckIn:C.Wait] Stopped 2023-09-13T19:01:03.898Z,1694631663.898 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-13T19:01:03.898Z,1694631663.898 [Default:CheckIn:D] Running Loop=1 2023-09-13T19:01:04.293Z,1694631664.293 [Default:CheckIn:D] Stopped 2023-09-13T19:01:04.293Z,1694631664.293 [Default:CheckIn:E] Running Loop=1 2023-09-13T19:01:04.698Z,1694631664.698 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 68.829395 min 2023-09-13T19:01:04.698Z,1694631664.698 [Default:CheckIn:E] Stopped 2023-09-13T19:01:04.699Z,1694631664.699 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-13T19:01:04.699Z,1694631664.699 [Default:CheckIn] Stopped 2023-09-13T19:01:04.699Z,1694631664.699 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-13T19:01:04.699Z,1694631664.699 [Default:CheckIn](INFO): Running loop #13 2023-09-13T19:01:04.699Z,1694631664.699 [Default:CheckIn] Running Loop=13 2023-09-13T19:01:04.699Z,1694631664.699 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-13T19:01:04.699Z,1694631664.699 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-13T19:01:06.717Z,1694631666.717 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190106.00,A,3648.16318,N,12147.28458,W,0.447,0.00,130923,,,A*78 2023-09-13T19:01:06.719Z,1694631666.719 [NAL9602](INFO): GPS fix at 20230913T190106: (36.802720, -121.788076) 2023-09-13T19:01:06.734Z,1694631666.734 [Default:CheckIn:Read_GPS] Stopped 2023-09-13T19:01:06.734Z,1694631666.734 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-13T19:01:15.597Z,1694631675.597 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230913T175213/Courier0039.lzma 2023-09-13T19:01:16.600Z,1694631676.600 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Courier0039.lzma.bak 2023-09-13T19:01:16.600Z,1694631676.600 [DataOverHttps](INFO): SBD MOMSN=18766764 2023-09-13T19:01:26.123Z,1694631686.123 [NAL9602](INFO): SBD MO Status=2, MOMSN=13502, MT Status=2, MTMSN=0 2023-09-13T19:01:26.123Z,1694631686.123 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T19:01:37.778Z,1694631697.778 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230913T175213/Express0040.lzma 2023-09-13T19:01:38.780Z,1694631698.780 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0040.lzma.bak 2023-09-13T19:01:38.780Z,1694631698.780 [DataOverHttps](INFO): SBD MOMSN=18766767 2023-09-13T19:01:41.940Z,1694631701.940 [Default:CheckIn:Read_Iridium] Stopped 2023-09-13T19:01:41.996Z,1694631701.996 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-13T19:01:41.996Z,1694631701.996 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-13T19:02:03.715Z,1694631723.715 [NAL9602](INFO): SBD MO Status=2, MOMSN=13502, MT Status=2, MTMSN=0 2023-09-13T19:02:03.715Z,1694631723.715 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T19:02:29.573Z,1694631749.573 [NAL9602](INFO): SBD MO Status=2, MOMSN=13502, MT Status=2, MTMSN=0 2023-09-13T19:02:29.573Z,1694631749.573 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T19:02:46.540Z,1694631766.540 [NAL9602](INFO): SBD MO Status=2, MOMSN=13502, MT Status=2, MTMSN=0 2023-09-13T19:02:46.540Z,1694631766.540 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T19:06:09.411Z,1694631969.411 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-13T19:06:40.111Z,1694632000.111 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-13T19:06:42.548Z,1694632002.548 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-13T19:06:42.548Z,1694632002.548 [Default:CheckIn:C.Wait] Stopped 2023-09-13T19:06:42.548Z,1694632002.548 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-13T19:06:42.548Z,1694632002.548 [Default:CheckIn:D] Running Loop=1 2023-09-13T19:06:42.962Z,1694632002.962 [Default:CheckIn:D] Stopped 2023-09-13T19:06:42.962Z,1694632002.962 [Default:CheckIn:E] Running Loop=1 2023-09-13T19:06:43.381Z,1694632003.381 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 74.473877 min 2023-09-13T19:06:43.381Z,1694632003.381 [Default:CheckIn:E] Stopped 2023-09-13T19:06:43.381Z,1694632003.381 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-13T19:06:43.381Z,1694632003.381 [Default:CheckIn] Stopped 2023-09-13T19:06:43.381Z,1694632003.381 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-13T19:06:43.381Z,1694632003.381 [Default:CheckIn](INFO): Running loop #14 2023-09-13T19:06:43.381Z,1694632003.381 [Default:CheckIn] Running Loop=14 2023-09-13T19:06:43.381Z,1694632003.381 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-13T19:06:43.381Z,1694632003.381 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-13T19:06:45.364Z,1694632005.364 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190644.00,A,3648.16656,N,12147.28343,W,0.350,308.95,130923,,,A*7D 2023-09-13T19:06:45.367Z,1694632005.367 [NAL9602](INFO): GPS fix at 20230913T190644: (36.802776, -121.788057) 2023-09-13T19:06:45.379Z,1694632005.379 [Default:CheckIn:Read_GPS] Stopped 2023-09-13T19:06:45.379Z,1694632005.379 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-13T19:06:53.825Z,1694632013.825 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230913T175213/Courier0042.lzma 2023-09-13T19:06:54.828Z,1694632014.828 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Courier0042.lzma.bak 2023-09-13T19:06:54.828Z,1694632014.828 [DataOverHttps](INFO): SBD MOMSN=18766778 2023-09-13T19:07:12.694Z,1694632032.694 [DataOverHttps](INFO): Sending 205 bytes from file Logs/20230913T175213/Express0043.lzma 2023-09-13T19:07:13.696Z,1694632033.696 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0043.lzma.bak 2023-09-13T19:07:13.696Z,1694632033.696 [DataOverHttps](INFO): SBD MOMSN=18766781 2023-09-13T19:07:16.067Z,1694632036.067 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-09-13T19:07:16.150Z,1694632036.150 [NAL9602](ERROR): received: +CSQ:0 OK502, 2, 0, 0, 0 OK 2023-09-13T19:07:16.895Z,1694632036.895 [Default:CheckIn:Read_Iridium] Stopped 2023-09-13T19:07:16.895Z,1694632036.895 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-13T19:07:16.895Z,1694632036.895 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-13T19:08:39.756Z,1694632119.756 [NAL9602](INFO): SBD MO Status=2, MOMSN=13502, MT Status=2, MTMSN=0 2023-09-13T19:08:39.757Z,1694632119.757 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T19:09:07.628Z,1694632147.628 [NAL9602](INFO): SBD MO Status=0, MOMSN=13502, MT Status=0, MTMSN=0 2023-09-13T19:09:07.628Z,1694632147.628 [NAL9602](INFO): No messages in MT queue 2023-09-13T19:09:38.331Z,1694632178.331 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-13T19:12:17.528Z,1694632337.528 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-13T19:12:17.528Z,1694632337.528 [Default:CheckIn:C.Wait] Stopped 2023-09-13T19:12:17.529Z,1694632337.529 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-13T19:12:17.529Z,1694632337.529 [Default:CheckIn:D] Running Loop=1 2023-09-13T19:12:17.943Z,1694632337.943 [Default:CheckIn:D] Stopped 2023-09-13T19:12:17.943Z,1694632337.943 [Default:CheckIn:E] Running Loop=1 2023-09-13T19:12:18.325Z,1694632338.325 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 80.056901 min 2023-09-13T19:12:18.325Z,1694632338.325 [Default:CheckIn:E] Stopped 2023-09-13T19:12:18.325Z,1694632338.325 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-13T19:12:18.325Z,1694632338.325 [Default:CheckIn] Stopped 2023-09-13T19:12:18.326Z,1694632338.326 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-13T19:12:18.326Z,1694632338.326 [Default:CheckIn](INFO): Running loop #15 2023-09-13T19:12:18.326Z,1694632338.326 [Default:CheckIn] Running Loop=15 2023-09-13T19:12:18.326Z,1694632338.326 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-13T19:12:18.326Z,1694632338.326 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-13T19:12:20.341Z,1694632340.341 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191219.00,A,3648.16952,N,12147.28162,W,0.719,308.95,130923,,,A*73 2023-09-13T19:12:20.343Z,1694632340.343 [NAL9602](INFO): GPS fix at 20230913T191219: (36.802825, -121.788027) 2023-09-13T19:12:20.356Z,1694632340.356 [Default:CheckIn:Read_GPS] Stopped 2023-09-13T19:12:20.356Z,1694632340.356 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-13T19:12:28.377Z,1694632348.377 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230913T175213/Courier0045.lzma 2023-09-13T19:12:29.380Z,1694632349.380 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Courier0045.lzma.bak 2023-09-13T19:12:29.380Z,1694632349.380 [DataOverHttps](INFO): SBD MOMSN=18766793 2023-09-13T19:12:48.893Z,1694632368.893 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230913T175213/Express0046.lzma 2023-09-13T19:12:49.896Z,1694632369.896 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0046.lzma.bak 2023-09-13T19:12:49.896Z,1694632369.896 [DataOverHttps](INFO): SBD MOMSN=18766796 2023-09-13T19:12:53.098Z,1694632373.098 [Default:CheckIn:Read_Iridium] Stopped 2023-09-13T19:12:53.098Z,1694632373.098 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-13T19:12:53.098Z,1694632373.098 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-13T19:14:53.048Z,1694632493.048 [NAL9602](INFO): SBD MO Status=2, MOMSN=13503, MT Status=2, MTMSN=0 2023-09-13T19:14:53.049Z,1694632493.049 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T19:15:46.013Z,1694632546.013 [NAL9602](INFO): SBD MO Status=2, MOMSN=13503, MT Status=2, MTMSN=0 2023-09-13T19:15:46.013Z,1694632546.013 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T19:16:38.936Z,1694632598.936 [NAL9602](INFO): SBD MO Status=2, MOMSN=13503, MT Status=2, MTMSN=0 2023-09-13T19:16:38.936Z,1694632598.936 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T19:17:17.316Z,1694632637.316 [NAL9602](INFO): SBD MO Status=2, MOMSN=13503, MT Status=2, MTMSN=0 2023-09-13T19:17:17.317Z,1694632637.317 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T19:17:32.264Z,1694632652.264 [NAL9602](INFO): SBD MO Status=2, MOMSN=13503, MT Status=2, MTMSN=0 2023-09-13T19:17:32.265Z,1694632652.265 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T19:17:32.679Z,1694632652.679 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-13T19:17:53.694Z,1694632673.694 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-13T19:17:53.695Z,1694632673.695 [Default:CheckIn:C.Wait] Stopped 2023-09-13T19:17:53.695Z,1694632673.695 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-13T19:17:53.695Z,1694632673.695 [Default:CheckIn:D] Running Loop=1 2023-09-13T19:17:54.102Z,1694632674.102 [Default:CheckIn:D] Stopped 2023-09-13T19:17:54.102Z,1694632674.102 [Default:CheckIn:E] Running Loop=1 2023-09-13T19:17:54.494Z,1694632674.494 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 85.659538 min 2023-09-13T19:17:54.494Z,1694632674.494 [Default:CheckIn:E] Stopped 2023-09-13T19:17:54.494Z,1694632674.494 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-13T19:17:54.494Z,1694632674.494 [Default:CheckIn] Stopped 2023-09-13T19:17:54.494Z,1694632674.494 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-13T19:17:54.494Z,1694632674.494 [Default:CheckIn](INFO): Running loop #16 2023-09-13T19:17:54.495Z,1694632674.495 [Default:CheckIn] Running Loop=16 2023-09-13T19:17:54.495Z,1694632674.495 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-13T19:17:54.495Z,1694632674.495 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-13T19:17:56.504Z,1694632676.504 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191755.00,A,3648.16964,N,12147.28705,W,0.369,0.00,130923,,,A*78 2023-09-13T19:17:56.506Z,1694632676.506 [NAL9602](INFO): GPS fix at 20230913T191755: (36.802827, -121.788117) 2023-09-13T19:17:56.518Z,1694632676.518 [Default:CheckIn:Read_GPS] Stopped 2023-09-13T19:17:56.518Z,1694632676.518 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-13T19:18:04.537Z,1694632684.537 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230913T175213/Courier0048.lzma 2023-09-13T19:18:05.540Z,1694632685.540 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Courier0048.lzma.bak 2023-09-13T19:18:05.540Z,1694632685.540 [DataOverHttps](INFO): SBD MOMSN=18766806 2023-09-13T19:18:23.210Z,1694632703.210 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20230913T175213/Express0049.lzma 2023-09-13T19:18:24.212Z,1694632704.212 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0049.lzma.bak 2023-09-13T19:18:24.212Z,1694632704.212 [DataOverHttps](INFO): SBD MOMSN=18766809 2023-09-13T19:18:27.244Z,1694632707.244 [Default:CheckIn:Read_Iridium] Stopped 2023-09-13T19:18:27.244Z,1694632707.244 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-13T19:18:27.244Z,1694632707.244 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-13T19:18:28.419Z,1694632708.419 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-13T19:23:27.848Z,1694633007.848 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-13T19:23:27.848Z,1694633007.848 [Default:CheckIn:C.Wait] Stopped 2023-09-13T19:23:27.848Z,1694633007.848 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-13T19:23:27.848Z,1694633007.848 [Default:CheckIn:D] Running Loop=1 2023-09-13T19:23:28.261Z,1694633008.261 [Default:CheckIn:D] Stopped 2023-09-13T19:23:28.261Z,1694633008.261 [Default:CheckIn:E] Running Loop=1 2023-09-13T19:23:28.622Z,1694633008.622 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 91.228857 min 2023-09-13T19:23:28.622Z,1694633008.622 [Default:CheckIn:E] Stopped 2023-09-13T19:23:28.622Z,1694633008.622 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-13T19:23:28.623Z,1694633008.623 [Default:CheckIn] Stopped 2023-09-13T19:23:28.623Z,1694633008.623 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-13T19:23:28.623Z,1694633008.623 [Default:CheckIn](INFO): Running loop #17 2023-09-13T19:23:28.623Z,1694633008.623 [Default:CheckIn] Running Loop=17 2023-09-13T19:23:28.623Z,1694633008.623 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-13T19:23:28.623Z,1694633008.623 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-13T19:23:30.633Z,1694633010.633 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192329.00,A,3648.16928,N,12147.28115,W,0.875,0.00,130923,,,A*7D 2023-09-13T19:23:30.636Z,1694633010.636 [NAL9602](INFO): GPS fix at 20230913T192329: (36.802821, -121.788019) 2023-09-13T19:23:30.648Z,1694633010.648 [Default:CheckIn:Read_GPS] Stopped 2023-09-13T19:23:30.648Z,1694633010.648 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-13T19:23:41.535Z,1694633021.535 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230913T175213/Courier0051.lzma 2023-09-13T19:23:42.540Z,1694633022.540 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Courier0051.lzma.bak 2023-09-13T19:23:42.540Z,1694633022.540 [DataOverHttps](INFO): SBD MOMSN=18766819 2023-09-13T19:24:00.489Z,1694633040.489 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230913T175213/Express0052.lzma 2023-09-13T19:24:01.492Z,1694633041.492 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0052.lzma.bak 2023-09-13T19:24:01.492Z,1694633041.492 [DataOverHttps](INFO): SBD MOMSN=18766822 2023-09-13T19:24:04.987Z,1694633044.987 [Default:CheckIn:Read_Iridium] Stopped 2023-09-13T19:24:04.987Z,1694633044.987 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-13T19:24:04.987Z,1694633044.987 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-13T19:25:34.658Z,1694633134.658 [NAL9602](INFO): SBD MO Status=2, MOMSN=13503, MT Status=2, MTMSN=0 2023-09-13T19:25:34.658Z,1694633134.658 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T19:26:48.597Z,1694633208.597 [NAL9602](INFO): SBD MO Status=2, MOMSN=13503, MT Status=2, MTMSN=0 2023-09-13T19:26:48.597Z,1694633208.597 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T19:27:06.782Z,1694633226.782 [NAL9602](INFO): SBD MO Status=2, MOMSN=13503, MT Status=2, MTMSN=0 2023-09-13T19:27:06.782Z,1694633226.782 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T19:27:40.772Z,1694633260.772 [CBIT](IMPORTANT): Beginning ground fault scan 2023-09-13T19:27:43.564Z,1694633263.564 [NAL9602](INFO): SBD MO Status=0, MOMSN=13503, MT Status=0, MTMSN=0 2023-09-13T19:27:43.565Z,1694633263.565 [NAL9602](INFO): No messages in MT queue 2023-09-13T19:27:43.586Z,1694633263.586 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes. 2023-09-13T19:27:51.662Z,1694633271.662 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.007340 CHAN A1 (24V) N/A reporting off CHAN A2 (12V): -0.004975 CHAN A3 (5V): -0.002911 CHAN B0 (3.3V): -0.001419 CHAN B1 (3.15aV): -0.001479 CHAN B2 (3.15bV): -0.001499 CHAN B3 (GND): -0.000795 OPEN: 0.004826 Full Scale: +/- 1 mA 2023-09-13T19:28:14.263Z,1694633294.263 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-13T19:29:05.595Z,1694633345.595 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-13T19:29:05.596Z,1694633345.596 [Default:CheckIn:C.Wait] Stopped 2023-09-13T19:29:05.596Z,1694633345.596 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-13T19:29:05.596Z,1694633345.596 [Default:CheckIn:D] Running Loop=1 2023-09-13T19:29:05.987Z,1694633345.987 [Default:CheckIn:D] Stopped 2023-09-13T19:29:05.987Z,1694633345.987 [Default:CheckIn:E] Running Loop=1 2023-09-13T19:29:06.395Z,1694633346.395 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 96.857625 min 2023-09-13T19:29:06.395Z,1694633346.395 [Default:CheckIn:E] Stopped 2023-09-13T19:29:06.395Z,1694633346.395 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-13T19:29:06.395Z,1694633346.395 [Default:CheckIn] Stopped 2023-09-13T19:29:06.395Z,1694633346.395 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-13T19:29:06.395Z,1694633346.395 [Default:CheckIn](INFO): Running loop #18 2023-09-13T19:29:06.395Z,1694633346.395 [Default:CheckIn] Running Loop=18 2023-09-13T19:29:06.396Z,1694633346.396 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-13T19:29:06.396Z,1694633346.396 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-13T19:29:08.401Z,1694633348.401 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192907.00,A,3648.18969,N,12147.27749,W,1.613,136.31,130923,,,A*79 2023-09-13T19:29:08.404Z,1694633348.404 [NAL9602](INFO): GPS fix at 20230913T192907: (36.803162, -121.787958) 2023-09-13T19:29:08.416Z,1694633348.416 [Default:CheckIn:Read_GPS] Stopped 2023-09-13T19:29:08.416Z,1694633348.416 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-13T19:29:17.301Z,1694633357.301 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230913T175213/Courier0054.lzma 2023-09-13T19:29:18.304Z,1694633358.304 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Courier0054.lzma.bak 2023-09-13T19:29:18.304Z,1694633358.304 [DataOverHttps](INFO): SBD MOMSN=18766833 2023-09-13T19:29:36.043Z,1694633376.043 [NAL9602](INFO): SBD MO Status=2, MOMSN=13504, MT Status=2, MTMSN=0 2023-09-13T19:29:36.043Z,1694633376.043 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T19:29:43.642Z,1694633383.642 [DataOverHttps](INFO): Sending 316 bytes from file Logs/20230913T175213/Express0055.lzma 2023-09-13T19:29:44.644Z,1694633384.644 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0055.lzma.bak 2023-09-13T19:29:44.644Z,1694633384.644 [DataOverHttps](INFO): SBD MOMSN=18766836 2023-09-13T19:29:47.769Z,1694633387.769 [Default:CheckIn:Read_Iridium] Stopped 2023-09-13T19:29:47.769Z,1694633387.769 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-13T19:29:47.769Z,1694633387.769 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-13T19:31:44.500Z,1694633504.500 [NAL9602](INFO): SBD MO Status=2, MOMSN=13504, MT Status=2, MTMSN=0 2023-09-13T19:31:44.500Z,1694633504.500 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T19:34:10.062Z,1694633650.062 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-13T19:34:40.643Z,1694633680.643 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-13T19:34:48.338Z,1694633688.338 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-13T19:34:48.338Z,1694633688.338 [Default:CheckIn:C.Wait] Stopped 2023-09-13T19:34:48.338Z,1694633688.338 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-13T19:34:48.339Z,1694633688.339 [Default:CheckIn:D] Running Loop=1 2023-09-13T19:34:48.746Z,1694633688.746 [Default:CheckIn:D] Stopped 2023-09-13T19:34:48.746Z,1694633688.746 [Default:CheckIn:E] Running Loop=1 2023-09-13T19:34:49.144Z,1694633689.144 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 102.570272 min 2023-09-13T19:34:49.144Z,1694633689.144 [Default:CheckIn:E] Stopped 2023-09-13T19:34:49.144Z,1694633689.144 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-13T19:34:49.144Z,1694633689.144 [Default:CheckIn] Stopped 2023-09-13T19:34:49.145Z,1694633689.145 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-13T19:34:49.145Z,1694633689.145 [Default:CheckIn](INFO): Running loop #19 2023-09-13T19:34:49.145Z,1694633689.145 [Default:CheckIn] Running Loop=19 2023-09-13T19:34:49.145Z,1694633689.145 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-13T19:34:49.145Z,1694633689.145 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-13T19:34:51.148Z,1694633691.148 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193450.00,A,3648.16675,N,12147.28040,W,0.603,136.31,130923,,,A*7A 2023-09-13T19:34:51.151Z,1694633691.151 [NAL9602](INFO): GPS fix at 20230913T193450: (36.802779, -121.788007) 2023-09-13T19:34:51.164Z,1694633691.164 [Default:CheckIn:Read_GPS] Stopped 2023-09-13T19:34:51.164Z,1694633691.164 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-13T19:34:54.385Z,1694633694.385 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2023-09-13T19:34:54.388Z,1694633694.388 [BPC1](INFO): Received data from all battery sticks. 2023-09-13T19:34:59.316Z,1694633699.316 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230913T175213/Courier0057.lzma 2023-09-13T19:35:00.316Z,1694633700.316 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Courier0057.lzma.bak 2023-09-13T19:35:00.316Z,1694633700.316 [DataOverHttps](INFO): SBD MOMSN=18766848 2023-09-13T19:35:18.250Z,1694633718.250 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20230913T175213/Express0058.lzma 2023-09-13T19:35:19.252Z,1694633719.252 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0058.lzma.bak 2023-09-13T19:35:19.252Z,1694633719.252 [DataOverHttps](INFO): SBD MOMSN=18766851 2023-09-13T19:35:21.855Z,1694633721.855 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-09-13T19:35:21.938Z,1694633721.938 [NAL9602](ERROR): received: +CSQ:0 OK504, 2, 0, 0, 0 OK 2023-09-13T19:35:23.900Z,1694633723.900 [Default:CheckIn:Read_Iridium] Stopped 2023-09-13T19:35:23.900Z,1694633723.900 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-13T19:35:23.900Z,1694633723.900 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-13T19:39:53.475Z,1694633993.475 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-13T19:40:24.179Z,1694634024.179 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-13T19:40:24.607Z,1694634024.607 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-13T19:40:24.607Z,1694634024.607 [Default:CheckIn:C.Wait] Stopped 2023-09-13T19:40:24.607Z,1694634024.607 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-13T19:40:24.608Z,1694634024.608 [Default:CheckIn:D] Running Loop=1 2023-09-13T19:40:25.023Z,1694634025.023 [Default:CheckIn:D] Stopped 2023-09-13T19:40:25.023Z,1694634025.023 [Default:CheckIn:E] Running Loop=1 2023-09-13T19:40:25.427Z,1694634025.427 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 108.174894 min 2023-09-13T19:40:25.427Z,1694634025.427 [Default:CheckIn:E] Stopped 2023-09-13T19:40:25.427Z,1694634025.427 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-13T19:40:25.427Z,1694634025.427 [Default:CheckIn] Stopped 2023-09-13T19:40:25.427Z,1694634025.427 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-13T19:40:25.427Z,1694634025.427 [Default:CheckIn](INFO): Running loop #20 2023-09-13T19:40:25.428Z,1694634025.428 [Default:CheckIn] Running Loop=20 2023-09-13T19:40:25.428Z,1694634025.428 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-13T19:40:25.428Z,1694634025.428 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-13T19:40:27.417Z,1694634027.417 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194026.00,A,3648.17199,N,12147.27930,W,0.447,0.00,130923,,,A*79 2023-09-13T19:40:27.419Z,1694634027.419 [NAL9602](INFO): GPS fix at 20230913T194026: (36.802867, -121.787988) 2023-09-13T19:40:27.431Z,1694634027.431 [Default:CheckIn:Read_GPS] Stopped 2023-09-13T19:40:27.431Z,1694634027.431 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-13T19:40:29.191Z,1694634029.191 [DataOverHttps](INFO): Sending 86 bytes from file Logs/20230913T175213/Courier0060.lzma 2023-09-13T19:40:30.196Z,1694634030.196 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Courier0060.lzma.bak 2023-09-13T19:40:30.196Z,1694634030.196 [DataOverHttps](INFO): SBD MOMSN=18766862 2023-09-13T19:40:47.994Z,1694634047.994 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20230913T175213/Express0061.lzma 2023-09-13T19:40:48.996Z,1694634048.996 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0061.lzma.bak 2023-09-13T19:40:48.996Z,1694634048.996 [DataOverHttps](INFO): SBD MOMSN=18766865 2023-09-13T19:40:52.511Z,1694634052.511 [Default:CheckIn:Read_Iridium] Stopped 2023-09-13T19:40:52.511Z,1694634052.511 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-13T19:40:52.511Z,1694634052.511 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-13T19:40:58.127Z,1694634058.127 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-09-13T19:40:58.202Z,1694634058.202 [NAL9602](ERROR): received: +CSQ:0 OK504, 2, 0, 0, 0 OK 2023-09-13T19:45:29.272Z,1694634329.272 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-13T19:45:53.125Z,1694634353.125 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-13T19:45:53.130Z,1694634353.130 [Default:CheckIn:C.Wait] Stopped 2023-09-13T19:45:53.130Z,1694634353.130 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-13T19:45:53.130Z,1694634353.130 [Default:CheckIn:D] Running Loop=1 2023-09-13T19:45:53.522Z,1694634353.522 [Default:CheckIn:D] Stopped 2023-09-13T19:45:53.522Z,1694634353.522 [Default:CheckIn:E] Running Loop=1 2023-09-13T19:45:53.923Z,1694634353.923 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 113.649886 min 2023-09-13T19:45:53.923Z,1694634353.923 [Default:CheckIn:E] Stopped 2023-09-13T19:45:53.923Z,1694634353.923 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-13T19:45:53.923Z,1694634353.923 [Default:CheckIn] Stopped 2023-09-13T19:45:53.923Z,1694634353.923 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-13T19:45:53.923Z,1694634353.923 [Default:CheckIn](INFO): Running loop #21 2023-09-13T19:45:53.923Z,1694634353.923 [Default:CheckIn] Running Loop=21 2023-09-13T19:45:53.923Z,1694634353.923 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-13T19:45:53.923Z,1694634353.923 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-13T19:45:55.937Z,1694634355.937 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194555.00,A,3648.17239,N,12147.28193,W,0.972,354.29,130923,,,A*7D 2023-09-13T19:45:55.939Z,1694634355.939 [NAL9602](INFO): GPS fix at 20230913T194555: (36.802873, -121.788032) 2023-09-13T19:45:55.951Z,1694634355.951 [Default:CheckIn:Read_GPS] Stopped 2023-09-13T19:45:55.951Z,1694634355.951 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-13T19:46:04.573Z,1694634364.573 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230913T175213/Courier0063.lzma 2023-09-13T19:46:05.576Z,1694634365.576 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Courier0063.lzma.bak 2023-09-13T19:46:05.576Z,1694634365.576 [DataOverHttps](INFO): SBD MOMSN=18766876 2023-09-13T19:46:23.202Z,1694634383.202 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20230913T175213/Express0064.lzma 2023-09-13T19:46:24.204Z,1694634384.204 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0064.lzma.bak 2023-09-13T19:46:24.204Z,1694634384.204 [DataOverHttps](INFO): SBD MOMSN=18766881 2023-09-13T19:46:27.454Z,1694634387.454 [Default:CheckIn:Read_Iridium] Stopped 2023-09-13T19:46:27.454Z,1694634387.454 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-13T19:46:27.455Z,1694634387.455 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-13T19:46:28.251Z,1694634388.251 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-13T19:51:28.108Z,1694634688.108 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-13T19:51:28.108Z,1694634688.108 [Default:CheckIn:C.Wait] Stopped 2023-09-13T19:51:28.108Z,1694634688.108 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-13T19:51:28.109Z,1694634688.109 [Default:CheckIn:D] Running Loop=1 2023-09-13T19:51:28.481Z,1694634688.481 [Default:CheckIn:D] Stopped 2023-09-13T19:51:28.481Z,1694634688.481 [Default:CheckIn:E] Running Loop=1 2023-09-13T19:51:28.897Z,1694634688.897 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 119.232528 min 2023-09-13T19:51:28.902Z,1694634688.902 [Default:CheckIn:E] Stopped 2023-09-13T19:51:28.903Z,1694634688.903 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-13T19:51:28.903Z,1694634688.903 [Default:CheckIn] Stopped 2023-09-13T19:51:28.903Z,1694634688.903 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-13T19:51:28.903Z,1694634688.903 [Default:CheckIn](INFO): Running loop #22 2023-09-13T19:51:28.903Z,1694634688.903 [Default:CheckIn] Running Loop=22 2023-09-13T19:51:28.903Z,1694634688.903 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-13T19:51:28.903Z,1694634688.903 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-13T19:51:30.897Z,1694634690.897 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195130.00,A,3648.17237,N,12147.28158,W,0.233,354.29,130923,,,A*7C 2023-09-13T19:51:30.899Z,1694634690.899 [NAL9602](INFO): GPS fix at 20230913T195130: (36.802873, -121.788026) 2023-09-13T19:51:30.983Z,1694634690.983 [Default:CheckIn:Read_GPS] Stopped 2023-09-13T19:51:30.983Z,1694634690.983 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-13T19:51:39.285Z,1694634699.285 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230913T175213/Courier0066.lzma 2023-09-13T19:51:40.288Z,1694634700.288 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Courier0066.lzma.bak 2023-09-13T19:51:40.288Z,1694634700.288 [DataOverHttps](INFO): SBD MOMSN=18766892 2023-09-13T19:51:58.029Z,1694634718.029 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20230913T175213/Express0067.lzma 2023-09-13T19:51:59.032Z,1694634719.032 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0067.lzma.bak 2023-09-13T19:51:59.032Z,1694634719.032 [DataOverHttps](INFO): SBD MOMSN=18766895 2023-09-13T19:52:01.343Z,1694634721.343 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-09-13T19:52:01.418Z,1694634721.418 [NAL9602](ERROR): received: +CSQ:0 OK504, 2, 0, 0, 0 OK 2023-09-13T19:52:02.607Z,1694634722.607 [Default:CheckIn:Read_Iridium] Stopped 2023-09-13T19:52:02.608Z,1694634722.608 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-13T19:52:02.608Z,1694634722.608 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-13T19:56:33.287Z,1694634993.287 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-13T19:57:03.195Z,1694635023.195 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-13T19:57:03.195Z,1694635023.195 [Default:CheckIn:C.Wait] Stopped 2023-09-13T19:57:03.195Z,1694635023.195 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-13T19:57:03.195Z,1694635023.195 [Default:CheckIn:D] Running Loop=1 2023-09-13T19:57:03.597Z,1694635023.597 [Default:CheckIn:D] Stopped 2023-09-13T19:57:03.597Z,1694635023.597 [Default:CheckIn:E] Running Loop=1 2023-09-13T19:57:03.991Z,1694635023.991 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-13T19:57:04.005Z,1694635024.005 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 124.817790 min 2023-09-13T19:57:04.005Z,1694635024.005 [Default:CheckIn:E] Stopped 2023-09-13T19:57:04.005Z,1694635024.005 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-13T19:57:04.005Z,1694635024.005 [Default:CheckIn] Stopped 2023-09-13T19:57:04.005Z,1694635024.005 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-13T19:57:04.005Z,1694635024.005 [Default:CheckIn](INFO): Running loop #23 2023-09-13T19:57:04.005Z,1694635024.005 [Default:CheckIn] Running Loop=23 2023-09-13T19:57:04.005Z,1694635024.005 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-13T19:57:04.006Z,1694635024.006 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-13T19:57:06.008Z,1694635026.008 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195705.00,A,3648.16436,N,12147.27858,W,0.097,354.29,130923,,,A*70 2023-09-13T19:57:06.019Z,1694635026.019 [NAL9602](INFO): GPS fix at 20230913T195705: (36.802739, -121.787976) 2023-09-13T19:57:06.031Z,1694635026.031 [Default:CheckIn:Read_GPS] Stopped 2023-09-13T19:57:06.032Z,1694635026.032 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-13T19:57:14.329Z,1694635034.329 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230913T175213/Courier0069.lzma 2023-09-13T19:57:15.348Z,1694635035.348 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Courier0069.lzma.bak 2023-09-13T19:57:15.348Z,1694635035.348 [DataOverHttps](INFO): SBD MOMSN=18766905 2023-09-13T19:57:36.967Z,1694635056.967 [DataOverHttps](INFO): Sending 205 bytes from file Logs/20230913T175213/Express0070.lzma 2023-09-13T19:57:37.968Z,1694635057.968 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0070.lzma.bak 2023-09-13T19:57:37.968Z,1694635057.968 [DataOverHttps](INFO): SBD MOMSN=18766909 2023-09-13T19:57:41.258Z,1694635061.258 [Default:CheckIn:Read_Iridium] Stopped 2023-09-13T19:57:41.258Z,1694635061.258 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-13T19:57:41.258Z,1694635061.258 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-13T19:58:30.489Z,1694635110.489 [NAL9602](INFO): SBD MO Status=0, MOMSN=13504, MT Status=0, MTMSN=0 2023-09-13T19:58:30.489Z,1694635110.489 [NAL9602](INFO): No messages in MT queue 2023-09-13T19:59:01.191Z,1694635141.191 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-13T20:02:41.844Z,1694635361.844 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-13T20:02:41.844Z,1694635361.844 [Default:CheckIn:C.Wait] Stopped 2023-09-13T20:02:41.844Z,1694635361.844 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-13T20:02:41.845Z,1694635361.845 [Default:CheckIn:D] Running Loop=1 2023-09-13T20:02:42.258Z,1694635362.258 [Default:CheckIn:D] Stopped 2023-09-13T20:02:42.258Z,1694635362.258 [Default:CheckIn:E] Running Loop=1 2023-09-13T20:02:42.645Z,1694635362.645 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 130.462142 min 2023-09-13T20:02:42.646Z,1694635362.646 [Default:CheckIn:E] Stopped 2023-09-13T20:02:42.646Z,1694635362.646 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-13T20:02:42.646Z,1694635362.646 [Default:CheckIn] Stopped 2023-09-13T20:02:42.646Z,1694635362.646 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-13T20:02:42.646Z,1694635362.646 [Default:CheckIn](INFO): Running loop #24 2023-09-13T20:02:42.646Z,1694635362.646 [Default:CheckIn] Running Loop=24 2023-09-13T20:02:42.646Z,1694635362.646 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-13T20:02:42.647Z,1694635362.647 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-13T20:02:44.661Z,1694635364.661 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200243.00,A,3648.17384,N,12147.28283,W,1.263,0.00,130923,,,A*75 2023-09-13T20:02:44.663Z,1694635364.663 [NAL9602](INFO): GPS fix at 20230913T200243: (36.802897, -121.788047) 2023-09-13T20:02:44.675Z,1694635364.675 [Default:CheckIn:Read_GPS] Stopped 2023-09-13T20:02:44.675Z,1694635364.675 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-13T20:02:53.005Z,1694635373.005 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230913T175213/Courier0072.lzma 2023-09-13T20:02:54.008Z,1694635374.008 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Courier0072.lzma.bak 2023-09-13T20:02:54.008Z,1694635374.008 [DataOverHttps](INFO): SBD MOMSN=18766915 2023-09-13T20:03:11.766Z,1694635391.766 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230913T175213/Express0073.lzma 2023-09-13T20:03:12.768Z,1694635392.768 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0073.lzma.bak 2023-09-13T20:03:12.768Z,1694635392.768 [DataOverHttps](INFO): SBD MOMSN=18766918 2023-09-13T20:03:16.221Z,1694635396.221 [Default:CheckIn:Read_Iridium] Stopped 2023-09-13T20:03:16.221Z,1694635396.221 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-13T20:03:16.221Z,1694635396.221 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-13T20:05:56.220Z,1694635556.220 [NAL9602](INFO): SBD MO Status=2, MOMSN=13505, MT Status=2, MTMSN=0 2023-09-13T20:05:56.221Z,1694635556.221 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T20:06:42.684Z,1694635602.684 [NAL9602](INFO): SBD MO Status=0, MOMSN=13505, MT Status=0, MTMSN=0 2023-09-13T20:06:42.685Z,1694635602.685 [NAL9602](INFO): No messages in MT queue 2023-09-13T20:07:13.383Z,1694635633.383 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-13T20:08:16.845Z,1694635696.845 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-13T20:08:16.845Z,1694635696.845 [Default:CheckIn:C.Wait] Stopped 2023-09-13T20:08:16.845Z,1694635696.845 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-13T20:08:16.846Z,1694635696.846 [Default:CheckIn:D] Running Loop=1 2023-09-13T20:08:17.235Z,1694635697.235 [Default:CheckIn:D] Stopped 2023-09-13T20:08:17.235Z,1694635697.235 [Default:CheckIn:E] Running Loop=1 2023-09-13T20:08:17.643Z,1694635697.643 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 136.045068 min 2023-09-13T20:08:17.643Z,1694635697.643 [Default:CheckIn:E] Stopped 2023-09-13T20:08:17.643Z,1694635697.643 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-13T20:08:17.643Z,1694635697.643 [Default:CheckIn] Stopped 2023-09-13T20:08:17.643Z,1694635697.643 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-13T20:08:17.643Z,1694635697.643 [Default:CheckIn](INFO): Running loop #25 2023-09-13T20:08:17.644Z,1694635697.644 [Default:CheckIn] Running Loop=25 2023-09-13T20:08:17.644Z,1694635697.644 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-13T20:08:17.644Z,1694635697.644 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-13T20:08:19.649Z,1694635699.649 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200818.00,A,3648.16715,N,12147.28294,W,0.680,211.75,130923,,,A*72 2023-09-13T20:08:19.651Z,1694635699.651 [NAL9602](INFO): GPS fix at 20230913T200818: (36.802786, -121.788049) 2023-09-13T20:08:19.664Z,1694635699.664 [Default:CheckIn:Read_GPS] Stopped 2023-09-13T20:08:19.664Z,1694635699.664 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-13T20:08:28.501Z,1694635708.501 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230913T175213/Courier0075.lzma 2023-09-13T20:08:31.253Z,1694635711.253 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Courier0075.lzma.bak 2023-09-13T20:08:31.253Z,1694635711.253 [DataOverHttps](INFO): SBD MOMSN=18766935 2023-09-13T20:08:48.245Z,1694635728.245 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230913T175213/Express0076.lzma 2023-09-13T20:08:48.740Z,1694635728.740 [NAL9602](INFO): SBD MO Status=2, MOMSN=13506, MT Status=2, MTMSN=0 2023-09-13T20:08:48.741Z,1694635728.741 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T20:08:49.248Z,1694635729.248 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0076.lzma.bak 2023-09-13T20:08:49.248Z,1694635729.248 [DataOverHttps](INFO): SBD MOMSN=18766938 2023-09-13T20:08:52.403Z,1694635732.403 [Default:CheckIn:Read_Iridium] Stopped 2023-09-13T20:08:52.404Z,1694635732.404 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-13T20:08:52.404Z,1694635732.404 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-13T20:09:02.881Z,1694635742.881 [NAL9602](INFO): SBD MO Status=2, MOMSN=13506, MT Status=2, MTMSN=0 2023-09-13T20:09:02.881Z,1694635742.881 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T20:13:22.307Z,1694636002.307 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-13T20:13:53.014Z,1694636033.014 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-13T20:13:53.031Z,1694636033.031 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-13T20:13:53.031Z,1694636033.031 [Default:CheckIn:C.Wait] Stopped 2023-09-13T20:13:53.031Z,1694636033.031 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-13T20:13:53.032Z,1694636033.032 [Default:CheckIn:D] Running Loop=1 2023-09-13T20:13:53.435Z,1694636033.435 [Default:CheckIn:D] Stopped 2023-09-13T20:13:53.435Z,1694636033.435 [Default:CheckIn:E] Running Loop=1 2023-09-13T20:13:53.849Z,1694636033.849 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 141.648421 min 2023-09-13T20:13:53.849Z,1694636033.849 [Default:CheckIn:E] Stopped 2023-09-13T20:13:53.850Z,1694636033.850 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-13T20:13:53.850Z,1694636033.850 [Default:CheckIn] Stopped 2023-09-13T20:13:53.850Z,1694636033.850 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-13T20:13:53.850Z,1694636033.850 [Default:CheckIn](INFO): Running loop #26 2023-09-13T20:13:53.850Z,1694636033.850 [Default:CheckIn] Running Loop=26 2023-09-13T20:13:53.850Z,1694636033.850 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-13T20:13:53.850Z,1694636033.850 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-13T20:13:55.845Z,1694636035.845 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201355.00,A,3648.17168,N,12147.28356,W,1.400,196.93,130923,,,A*7C 2023-09-13T20:13:55.847Z,1694636035.847 [NAL9602](INFO): GPS fix at 20230913T201355: (36.802861, -121.788059) 2023-09-13T20:13:55.859Z,1694636035.859 [Default:CheckIn:Read_GPS] Stopped 2023-09-13T20:13:55.859Z,1694636035.859 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-13T20:14:04.469Z,1694636044.469 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230913T175213/Courier0078.lzma 2023-09-13T20:14:05.468Z,1694636045.468 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Courier0078.lzma.bak 2023-09-13T20:14:05.468Z,1694636045.468 [DataOverHttps](INFO): SBD MOMSN=18766983 2023-09-13T20:14:23.257Z,1694636063.257 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20230913T175213/Express0079.lzma 2023-09-13T20:14:24.260Z,1694636064.260 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0079.lzma.bak 2023-09-13T20:14:24.260Z,1694636064.260 [DataOverHttps](INFO): SBD MOMSN=18766986 2023-09-13T20:14:26.547Z,1694636066.547 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-09-13T20:14:26.630Z,1694636066.630 [NAL9602](ERROR): received: +CSQ:0 OK506, 2, 0, 0, 0 OK 2023-09-13T20:14:27.796Z,1694636067.796 [Default:CheckIn:Read_Iridium] Stopped 2023-09-13T20:14:27.796Z,1694636067.796 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-13T20:14:27.796Z,1694636067.796 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-13T20:15:02.912Z,1694636102.912 [NAL9602](INFO): SBD MO Status=2, MOMSN=13506, MT Status=2, MTMSN=0 2023-09-13T20:15:02.912Z,1694636102.912 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T20:15:56.240Z,1694636156.240 [NAL9602](INFO): SBD MO Status=0, MOMSN=13506, MT Status=0, MTMSN=0 2023-09-13T20:15:56.241Z,1694636156.241 [NAL9602](INFO): No messages in MT queue 2023-09-13T20:16:26.939Z,1694636186.939 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-13T20:19:28.345Z,1694636368.345 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-13T20:19:28.345Z,1694636368.345 [Default:CheckIn:C.Wait] Stopped 2023-09-13T20:19:28.345Z,1694636368.345 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-13T20:19:28.346Z,1694636368.346 [Default:CheckIn:D] Running Loop=1 2023-09-13T20:19:28.751Z,1694636368.751 [Default:CheckIn:D] Stopped 2023-09-13T20:19:28.751Z,1694636368.751 [Default:CheckIn:E] Running Loop=1 2023-09-13T20:19:29.175Z,1694636369.175 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 147.237028 min 2023-09-13T20:19:29.175Z,1694636369.175 [Default:CheckIn:E] Stopped 2023-09-13T20:19:29.175Z,1694636369.175 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-13T20:19:29.175Z,1694636369.175 [Default:CheckIn] Stopped 2023-09-13T20:19:29.176Z,1694636369.176 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-13T20:19:29.176Z,1694636369.176 [Default:CheckIn](INFO): Running loop #27 2023-09-13T20:19:29.176Z,1694636369.176 [Default:CheckIn] Running Loop=27 2023-09-13T20:19:29.176Z,1694636369.176 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-13T20:19:29.176Z,1694636369.176 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-13T20:19:31.164Z,1694636371.164 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201930.00,A,3648.16336,N,12147.28883,W,0.836,176.01,130923,,,A*73 2023-09-13T20:19:31.166Z,1694636371.166 [NAL9602](INFO): GPS fix at 20230913T201930: (36.802723, -121.788147) 2023-09-13T20:19:31.200Z,1694636371.200 [Default:CheckIn:Read_GPS] Stopped 2023-09-13T20:19:31.200Z,1694636371.200 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-13T20:19:42.857Z,1694636382.857 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230913T175213/Courier0081.lzma 2023-09-13T20:19:43.860Z,1694636383.860 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Courier0081.lzma.bak 2023-09-13T20:19:43.860Z,1694636383.860 [DataOverHttps](INFO): SBD MOMSN=18767005 2023-09-13T20:20:03.213Z,1694636403.213 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230913T175213/Express0082.lzma 2023-09-13T20:20:04.216Z,1694636404.216 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0082.lzma.bak 2023-09-13T20:20:04.216Z,1694636404.216 [DataOverHttps](INFO): SBD MOMSN=18767008 2023-09-13T20:20:07.561Z,1694636407.561 [Default:CheckIn:Read_Iridium] Stopped 2023-09-13T20:20:07.561Z,1694636407.561 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-13T20:20:07.561Z,1694636407.561 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-13T20:23:36.904Z,1694636616.904 [NAL9602](INFO): SBD MO Status=2, MOMSN=13507, MT Status=2, MTMSN=0 2023-09-13T20:23:36.905Z,1694636616.905 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T20:24:42.347Z,1694636682.347 [NAL9602](INFO): SBD MO Status=2, MOMSN=13507, MT Status=2, MTMSN=0 2023-09-13T20:24:42.348Z,1694636682.348 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T20:24:42.755Z,1694636682.755 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-13T20:25:08.214Z,1694636708.214 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-13T20:25:08.214Z,1694636708.214 [Default:CheckIn:C.Wait] Stopped 2023-09-13T20:25:08.214Z,1694636708.214 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-13T20:25:08.214Z,1694636708.214 [Default:CheckIn:D] Running Loop=1 2023-09-13T20:25:08.628Z,1694636708.628 [Default:CheckIn:D] Stopped 2023-09-13T20:25:08.629Z,1694636708.629 [Default:CheckIn:E] Running Loop=1 2023-09-13T20:25:09.039Z,1694636709.039 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 152.901644 min 2023-09-13T20:25:09.039Z,1694636709.039 [Default:CheckIn:E] Stopped 2023-09-13T20:25:09.039Z,1694636709.039 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-13T20:25:09.039Z,1694636709.039 [Default:CheckIn] Stopped 2023-09-13T20:25:09.039Z,1694636709.039 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-13T20:25:09.039Z,1694636709.039 [Default:CheckIn](INFO): Running loop #28 2023-09-13T20:25:09.039Z,1694636709.039 [Default:CheckIn] Running Loop=28 2023-09-13T20:25:09.039Z,1694636709.039 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-13T20:25:09.040Z,1694636709.040 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-13T20:25:11.037Z,1694636711.037 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202510.00,A,3648.16957,N,12147.28058,W,0.194,176.01,130923,,,A*7C 2023-09-13T20:25:11.039Z,1694636711.039 [NAL9602](INFO): GPS fix at 20230913T202510: (36.802826, -121.788010) 2023-09-13T20:25:11.075Z,1694636711.075 [Default:CheckIn:Read_GPS] Stopped 2023-09-13T20:25:11.076Z,1694636711.076 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-13T20:25:19.125Z,1694636719.125 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230913T175213/Courier0084.lzma 2023-09-13T20:25:20.128Z,1694636720.128 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Courier0084.lzma.bak 2023-09-13T20:25:20.128Z,1694636720.128 [DataOverHttps](INFO): SBD MOMSN=18767027 2023-09-13T20:25:42.569Z,1694636742.569 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20230913T175213/Express0085.lzma 2023-09-13T20:25:43.429Z,1694636743.429 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-13T20:25:43.572Z,1694636743.572 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0085.lzma.bak 2023-09-13T20:25:43.572Z,1694636743.572 [DataOverHttps](INFO): SBD MOMSN=18767030 2023-09-13T20:25:47.091Z,1694636747.091 [Default:CheckIn:Read_Iridium] Stopped 2023-09-13T20:25:47.091Z,1694636747.091 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-13T20:25:47.091Z,1694636747.091 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-13T20:27:51.939Z,1694636871.939 [CBIT](IMPORTANT): Beginning ground fault scan 2023-09-13T20:27:54.824Z,1694636874.824 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes. 2023-09-13T20:28:02.834Z,1694636882.834 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.006718 CHAN A1 (24V) N/A reporting off CHAN A2 (12V): -0.005125 CHAN A3 (5V): -0.003576 CHAN B0 (3.3V): -0.001360 CHAN B1 (3.15aV): -0.001761 CHAN B2 (3.15bV): -0.001664 CHAN B3 (GND): -0.000445 OPEN: 0.004956 Full Scale: +/- 1 mA 2023-09-13T20:30:47.665Z,1694637047.665 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-13T20:30:47.666Z,1694637047.666 [Default:CheckIn:C.Wait] Stopped 2023-09-13T20:30:47.666Z,1694637047.666 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-13T20:30:47.666Z,1694637047.666 [Default:CheckIn:D] Running Loop=1 2023-09-13T20:30:48.076Z,1694637048.076 [Default:CheckIn:D] Stopped 2023-09-13T20:30:48.076Z,1694637048.076 [Default:CheckIn:E] Running Loop=1 2023-09-13T20:30:48.475Z,1694637048.475 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 158.559115 min 2023-09-13T20:30:48.475Z,1694637048.475 [Default:CheckIn:E] Stopped 2023-09-13T20:30:48.475Z,1694637048.475 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-13T20:30:48.475Z,1694637048.475 [Default:CheckIn] Stopped 2023-09-13T20:30:48.475Z,1694637048.475 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-13T20:30:48.475Z,1694637048.475 [Default:CheckIn](INFO): Running loop #29 2023-09-13T20:30:48.475Z,1694637048.475 [Default:CheckIn] Running Loop=29 2023-09-13T20:30:48.476Z,1694637048.476 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-13T20:30:48.476Z,1694637048.476 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-13T20:30:50.485Z,1694637050.485 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203049.00,A,3648.16775,N,12147.27791,W,0.447,186.91,130923,,,A*7A 2023-09-13T20:30:50.487Z,1694637050.487 [NAL9602](INFO): GPS fix at 20230913T203049: (36.802796, -121.787965) 2023-09-13T20:30:50.515Z,1694637050.515 [Default:CheckIn:Read_GPS] Stopped 2023-09-13T20:30:50.515Z,1694637050.515 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-13T20:30:58.667Z,1694637058.667 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230913T175213/Courier0087.lzma 2023-09-13T20:30:59.640Z,1694637059.640 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Courier0087.lzma.bak 2023-09-13T20:30:59.640Z,1694637059.640 [DataOverHttps](INFO): SBD MOMSN=18767034 2023-09-13T20:31:17.482Z,1694637077.482 [DataOverHttps](INFO): Sending 320 bytes from file Logs/20230913T175213/Express0088.lzma 2023-09-13T20:31:18.485Z,1694637078.485 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0088.lzma.bak 2023-09-13T20:31:18.485Z,1694637078.485 [DataOverHttps](INFO): SBD MOMSN=18767037 2023-09-13T20:31:22.877Z,1694637082.877 [Default:CheckIn:Read_Iridium] Stopped 2023-09-13T20:31:22.877Z,1694637082.877 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-13T20:31:22.877Z,1694637082.877 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-13T20:31:25.677Z,1694637085.677 [NAL9602](INFO): SBD MO Status=2, MOMSN=13507, MT Status=2, MTMSN=0 2023-09-13T20:31:25.677Z,1694637085.677 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T20:32:39.260Z,1694637159.260 [NAL9602](INFO): SBD MO Status=2, MOMSN=13507, MT Status=2, MTMSN=0 2023-09-13T20:32:39.261Z,1694637159.261 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T20:33:52.396Z,1694637232.396 [NAL9602](INFO): SBD MO Status=0, MOMSN=13507, MT Status=0, MTMSN=0 2023-09-13T20:33:52.397Z,1694637232.397 [NAL9602](INFO): No messages in MT queue 2023-09-13T20:34:23.100Z,1694637263.100 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-13T20:36:23.522Z,1694637383.522 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-13T20:36:23.522Z,1694637383.522 [Default:CheckIn:C.Wait] Stopped 2023-09-13T20:36:23.522Z,1694637383.522 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-13T20:36:23.523Z,1694637383.523 [Default:CheckIn:D] Running Loop=1 2023-09-13T20:36:23.939Z,1694637383.939 [Default:CheckIn:D] Stopped 2023-09-13T20:36:23.939Z,1694637383.939 [Default:CheckIn:E] Running Loop=1 2023-09-13T20:36:24.321Z,1694637384.321 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 164.156820 min 2023-09-13T20:36:24.321Z,1694637384.321 [Default:CheckIn:E] Stopped 2023-09-13T20:36:24.321Z,1694637384.321 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-13T20:36:24.321Z,1694637384.321 [Default:CheckIn] Stopped 2023-09-13T20:36:24.322Z,1694637384.322 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-13T20:36:24.322Z,1694637384.322 [Default:CheckIn](INFO): Running loop #30 2023-09-13T20:36:24.322Z,1694637384.322 [Default:CheckIn] Running Loop=30 2023-09-13T20:36:24.322Z,1694637384.322 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-13T20:36:24.322Z,1694637384.322 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-13T20:36:26.318Z,1694637386.318 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203625.00,A,3648.16765,N,12147.27534,W,0.428,37.07,130923,,,A*47 2023-09-13T20:36:26.320Z,1694637386.320 [NAL9602](INFO): GPS fix at 20230913T203625: (36.802794, -121.787922) 2023-09-13T20:36:26.332Z,1694637386.332 [Default:CheckIn:Read_GPS] Stopped 2023-09-13T20:36:26.332Z,1694637386.332 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-13T20:36:35.365Z,1694637395.365 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230913T175213/Courier0090.lzma 2023-09-13T20:36:36.368Z,1694637396.368 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Courier0090.lzma.bak 2023-09-13T20:36:36.368Z,1694637396.368 [DataOverHttps](INFO): SBD MOMSN=18767043 2023-09-13T20:36:54.477Z,1694637414.477 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20230913T175213/Express0091.lzma 2023-09-13T20:36:55.480Z,1694637415.480 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0091.lzma.bak 2023-09-13T20:36:55.480Z,1694637415.480 [DataOverHttps](INFO): SBD MOMSN=18767047 2023-09-13T20:36:59.073Z,1694637419.073 [Default:CheckIn:Read_Iridium] Stopped 2023-09-13T20:36:59.074Z,1694637419.074 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-13T20:36:59.074Z,1694637419.074 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-13T20:38:19.840Z,1694637499.840 [NAL9602](INFO): SBD MO Status=2, MOMSN=13508, MT Status=2, MTMSN=0 2023-09-13T20:38:19.840Z,1694637499.840 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T20:39:12.369Z,1694637552.369 [NAL9602](INFO): SBD MO Status=2, MOMSN=13508, MT Status=2, MTMSN=0 2023-09-13T20:39:12.369Z,1694637552.369 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T20:39:38.237Z,1694637578.237 [NAL9602](INFO): SBD MO Status=2, MOMSN=13508, MT Status=2, MTMSN=0 2023-09-13T20:39:38.237Z,1694637578.237 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T20:39:53.164Z,1694637593.164 [NAL9602](INFO): SBD MO Status=2, MOMSN=13508, MT Status=2, MTMSN=0 2023-09-13T20:39:53.164Z,1694637593.164 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T20:40:07.708Z,1694637607.708 [NAL9602](INFO): SBD MO Status=2, MOMSN=13508, MT Status=2, MTMSN=0 2023-09-13T20:40:07.709Z,1694637607.709 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T20:40:36.396Z,1694637636.396 [NAL9602](INFO): SBD MO Status=2, MOMSN=13508, MT Status=2, MTMSN=0 2023-09-13T20:40:36.396Z,1694637636.396 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T20:41:34.577Z,1694637694.577 [NAL9602](INFO): SBD MO Status=2, MOMSN=13508, MT Status=2, MTMSN=0 2023-09-13T20:41:34.577Z,1694637694.577 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T20:41:34.975Z,1694637694.975 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-13T20:41:59.626Z,1694637719.626 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-13T20:41:59.626Z,1694637719.626 [Default:CheckIn:C.Wait] Stopped 2023-09-13T20:41:59.626Z,1694637719.626 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-13T20:41:59.626Z,1694637719.626 [Default:CheckIn:D] Running Loop=1 2023-09-13T20:42:00.034Z,1694637720.034 [Default:CheckIn:D] Stopped 2023-09-13T20:42:00.034Z,1694637720.034 [Default:CheckIn:E] Running Loop=1 2023-09-13T20:42:00.436Z,1694637720.436 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 169.758415 min 2023-09-13T20:42:00.436Z,1694637720.436 [Default:CheckIn:E] Stopped 2023-09-13T20:42:00.436Z,1694637720.436 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-13T20:42:00.436Z,1694637720.436 [Default:CheckIn] Stopped 2023-09-13T20:42:00.436Z,1694637720.436 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-13T20:42:00.436Z,1694637720.436 [Default:CheckIn](INFO): Running loop #31 2023-09-13T20:42:00.436Z,1694637720.436 [Default:CheckIn] Running Loop=31 2023-09-13T20:42:00.436Z,1694637720.436 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-13T20:42:00.436Z,1694637720.436 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-13T20:42:02.449Z,1694637722.449 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204201.00,A,3648.16477,N,12147.27959,W,0.486,37.07,130923,,,A*41 2023-09-13T20:42:02.460Z,1694637722.460 [NAL9602](INFO): GPS fix at 20230913T204201: (36.802746, -121.787993) 2023-09-13T20:42:02.472Z,1694637722.472 [Default:CheckIn:Read_GPS] Stopped 2023-09-13T20:42:02.472Z,1694637722.472 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-13T20:42:10.673Z,1694637730.673 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230913T175213/Courier0093.lzma 2023-09-13T20:42:11.676Z,1694637731.676 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Courier0093.lzma.bak 2023-09-13T20:42:11.676Z,1694637731.676 [DataOverHttps](INFO): SBD MOMSN=18767050 2023-09-13T20:42:19.464Z,1694637739.464 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2023-09-13T20:42:19.467Z,1694637739.467 [BPC1](INFO): Received data from all battery sticks. 2023-09-13T20:42:29.422Z,1694637749.422 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20230913T175213/Express0094.lzma 2023-09-13T20:42:30.424Z,1694637750.424 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0094.lzma.bak 2023-09-13T20:42:30.424Z,1694637750.424 [DataOverHttps](INFO): SBD MOMSN=18767053 2023-09-13T20:42:33.610Z,1694637753.610 [Default:CheckIn:Read_Iridium] Stopped 2023-09-13T20:42:33.610Z,1694637753.610 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-13T20:42:33.610Z,1694637753.610 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-13T20:42:34.407Z,1694637754.407 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-13T20:47:34.192Z,1694638054.192 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-13T20:47:34.192Z,1694638054.192 [Default:CheckIn:C.Wait] Stopped 2023-09-13T20:47:34.192Z,1694638054.192 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-13T20:47:34.192Z,1694638054.192 [Default:CheckIn:D] Running Loop=1 2023-09-13T20:47:34.588Z,1694638054.588 [Default:CheckIn:D] Stopped 2023-09-13T20:47:34.589Z,1694638054.589 [Default:CheckIn:E] Running Loop=1 2023-09-13T20:47:34.999Z,1694638054.999 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 175.334310 min 2023-09-13T20:47:34.999Z,1694638054.999 [Default:CheckIn:E] Stopped 2023-09-13T20:47:34.999Z,1694638054.999 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-13T20:47:34.999Z,1694638054.999 [Default:CheckIn] Stopped 2023-09-13T20:47:34.000Z,1694638055.000 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-13T20:47:34.000Z,1694638055.000 [Default:CheckIn](INFO): Running loop #32 2023-09-13T20:47:34.000Z,1694638055.000 [Default:CheckIn] Running Loop=32 2023-09-13T20:47:34.000Z,1694638055.000 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-13T20:47:35.000Z,1694638055.000 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-13T20:47:37.009Z,1694638057.009 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204736.00,A,3648.16843,N,12147.28260,W,0.214,37.07,130923,,,A*48 2023-09-13T20:47:37.011Z,1694638057.011 [NAL9602](INFO): GPS fix at 20230913T204736: (36.802807, -121.788043) 2023-09-13T20:47:37.023Z,1694638057.023 [Default:CheckIn:Read_GPS] Stopped 2023-09-13T20:47:37.023Z,1694638057.023 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-13T20:47:45.609Z,1694638065.609 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20230913T175213/Courier0096.lzma 2023-09-13T20:47:46.612Z,1694638066.612 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Courier0096.lzma.bak 2023-09-13T20:47:46.612Z,1694638066.612 [DataOverHttps](INFO): SBD MOMSN=18767057 2023-09-13T20:48:09.792Z,1694638089.792 [NAL9602](INFO): SBD MO Status=2, MOMSN=13508, MT Status=2, MTMSN=0 2023-09-13T20:48:09.793Z,1694638089.793 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T20:48:10.181Z,1694638090.181 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230913T175213/Express0097.lzma 2023-09-13T20:48:11.184Z,1694638091.184 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0097.lzma.bak 2023-09-13T20:48:11.184Z,1694638091.184 [DataOverHttps](INFO): SBD MOMSN=18767060 2023-09-13T20:48:14.672Z,1694638094.672 [Default:CheckIn:Read_Iridium] Stopped 2023-09-13T20:48:14.672Z,1694638094.672 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-13T20:48:14.672Z,1694638094.672 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-13T20:48:41.708Z,1694638121.708 [NAL9602](INFO): SBD MO Status=2, MOMSN=13508, MT Status=2, MTMSN=0 2023-09-13T20:48:41.709Z,1694638121.709 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T20:48:58.676Z,1694638138.676 [NAL9602](INFO): SBD MO Status=2, MOMSN=13508, MT Status=2, MTMSN=0 2023-09-13T20:48:58.677Z,1694638138.677 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T20:49:03.524Z,1694638143.524 [NAL9602](INFO): SBD MO Status=2, MOMSN=13508, MT Status=2, MTMSN=0 2023-09-13T20:49:03.524Z,1694638143.524 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T20:49:07.565Z,1694638147.565 [NAL9602](INFO): SBD MO Status=2, MOMSN=13508, MT Status=2, MTMSN=0 2023-09-13T20:49:07.565Z,1694638147.565 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T20:49:27.759Z,1694638167.759 [NAL9602](INFO): SBD MO Status=2, MOMSN=13508, MT Status=2, MTMSN=0 2023-09-13T20:49:27.760Z,1694638167.760 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T20:49:47.156Z,1694638187.156 [NAL9602](INFO): SBD MO Status=2, MOMSN=13508, MT Status=2, MTMSN=0 2023-09-13T20:49:47.157Z,1694638187.157 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T20:50:04.528Z,1694638204.528 [NAL9602](INFO): SBD MO Status=2, MOMSN=13508, MT Status=2, MTMSN=0 2023-09-13T20:50:04.528Z,1694638204.528 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T20:52:39.287Z,1694638359.287 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-13T20:53:09.999Z,1694638389.999 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-13T20:53:15.269Z,1694638395.269 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-13T20:53:15.269Z,1694638395.269 [Default:CheckIn:C.Wait] Stopped 2023-09-13T20:53:15.269Z,1694638395.269 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-13T20:53:15.270Z,1694638395.270 [Default:CheckIn:D] Running Loop=1 2023-09-13T20:53:15.658Z,1694638395.658 [Default:CheckIn:D] Stopped 2023-09-13T20:53:15.658Z,1694638395.658 [Default:CheckIn:E] Running Loop=1 2023-09-13T20:53:16.066Z,1694638396.066 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 181.018799 min 2023-09-13T20:53:16.066Z,1694638396.066 [Default:CheckIn:E] Stopped 2023-09-13T20:53:16.067Z,1694638396.067 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-13T20:53:16.067Z,1694638396.067 [Default:CheckIn] Stopped 2023-09-13T20:53:16.067Z,1694638396.067 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-13T20:53:16.067Z,1694638396.067 [Default:CheckIn](INFO): Running loop #33 2023-09-13T20:53:16.067Z,1694638396.067 [Default:CheckIn] Running Loop=33 2023-09-13T20:53:16.067Z,1694638396.067 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-13T20:53:16.067Z,1694638396.067 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-13T20:53:18.077Z,1694638398.077 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205317.00,A,3648.16510,N,12147.28439,W,0.175,0.00,130923,,,A*78 2023-09-13T20:53:18.079Z,1694638398.079 [NAL9602](INFO): GPS fix at 20230913T205317: (36.802752, -121.788073) 2023-09-13T20:53:18.092Z,1694638398.092 [Default:CheckIn:Read_GPS] Stopped 2023-09-13T20:53:18.092Z,1694638398.092 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-13T20:53:26.621Z,1694638406.621 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230913T175213/Courier0099.lzma 2023-09-13T20:53:27.624Z,1694638407.624 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Courier0099.lzma.bak 2023-09-13T20:53:27.624Z,1694638407.624 [DataOverHttps](INFO): SBD MOMSN=18767063 2023-09-13T20:53:48.831Z,1694638428.831 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-09-13T20:53:48.914Z,1694638428.914 [NAL9602](ERROR): received: +CSQ:0 OK508, 2, 0, 0, 0 OK 2023-09-13T20:53:49.666Z,1694638429.666 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20230913T175213/Express0100.lzma 2023-09-13T20:53:50.668Z,1694638430.668 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0100.lzma.bak 2023-09-13T20:53:50.668Z,1694638430.668 [DataOverHttps](INFO): SBD MOMSN=18767066 2023-09-13T20:53:56.132Z,1694638436.132 [Default:CheckIn:Read_Iridium] Stopped 2023-09-13T20:53:56.132Z,1694638436.132 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-13T20:53:56.132Z,1694638436.132 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-13T20:57:47.196Z,1694638667.196 [NAL9602](INFO): SBD MO Status=2, MOMSN=13508, MT Status=2, MTMSN=0 2023-09-13T20:57:47.197Z,1694638667.197 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T20:57:52.044Z,1694638672.044 [NAL9602](INFO): SBD MO Status=2, MOMSN=13508, MT Status=2, MTMSN=0 2023-09-13T20:57:52.045Z,1694638672.045 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T20:57:54.877Z,1694638674.877 [NAL9602](INFO): SBD MO Status=2, MOMSN=13508, MT Status=2, MTMSN=0 2023-09-13T20:57:54.877Z,1694638674.877 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T20:58:03.357Z,1694638683.357 [NAL9602](INFO): SBD MO Status=2, MOMSN=13508, MT Status=2, MTMSN=0 2023-09-13T20:58:03.357Z,1694638683.357 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T20:58:05.376Z,1694638685.376 [NAL9602](INFO): SBD MO Status=2, MOMSN=13508, MT Status=2, MTMSN=0 2023-09-13T20:58:05.377Z,1694638685.377 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T20:58:26.789Z,1694638706.789 [NAL9602](INFO): SBD MO Status=2, MOMSN=13508, MT Status=2, MTMSN=0 2023-09-13T20:58:26.789Z,1694638706.789 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T20:58:27.186Z,1694638707.186 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-13T20:58:56.694Z,1694638736.694 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-13T20:58:56.695Z,1694638736.695 [Default:CheckIn:C.Wait] Stopped 2023-09-13T20:58:56.695Z,1694638736.695 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-13T20:58:56.695Z,1694638736.695 [Default:CheckIn:D] Running Loop=1 2023-09-13T20:58:57.106Z,1694638737.106 [Default:CheckIn:D] Stopped 2023-09-13T20:58:57.106Z,1694638737.106 [Default:CheckIn:E] Running Loop=1 2023-09-13T20:58:57.498Z,1694638737.498 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 186.709603 min 2023-09-13T20:58:57.498Z,1694638737.498 [Default:CheckIn:E] Stopped 2023-09-13T20:58:57.498Z,1694638737.498 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-13T20:58:57.498Z,1694638737.498 [Default:CheckIn] Stopped 2023-09-13T20:58:57.498Z,1694638737.498 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-13T20:58:57.499Z,1694638737.499 [Default:CheckIn](INFO): Running loop #34 2023-09-13T20:58:57.499Z,1694638737.499 [Default:CheckIn] Running Loop=34 2023-09-13T20:58:57.499Z,1694638737.499 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-13T20:58:57.499Z,1694638737.499 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-13T20:58:57.895Z,1694638737.895 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-13T20:58:59.513Z,1694638739.513 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205858.00,A,3648.16916,N,12147.27816,W,0.350,0.00,130923,,,A*79 2023-09-13T20:58:59.515Z,1694638739.515 [NAL9602](INFO): GPS fix at 20230913T205858: (36.802819, -121.787969) 2023-09-13T20:58:59.527Z,1694638739.527 [Default:CheckIn:Read_GPS] Stopped 2023-09-13T20:58:59.527Z,1694638739.527 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-13T20:59:07.705Z,1694638747.705 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230913T175213/Courier0102.lzma 2023-09-13T20:59:08.708Z,1694638748.708 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Courier0102.lzma.bak 2023-09-13T20:59:08.708Z,1694638748.708 [DataOverHttps](INFO): SBD MOMSN=18767070 2023-09-13T20:59:26.475Z,1694638766.475 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20230913T175213/Express0103.lzma 2023-09-13T20:59:27.476Z,1694638767.476 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0103.lzma.bak 2023-09-13T20:59:27.476Z,1694638767.476 [DataOverHttps](INFO): SBD MOMSN=18767073 2023-09-13T20:59:30.689Z,1694638770.689 [Default:CheckIn:Read_Iridium] Stopped 2023-09-13T20:59:30.689Z,1694638770.689 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-13T20:59:30.689Z,1694638770.689 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-13T21:04:01.347Z,1694639041.347 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-13T21:04:31.298Z,1694639071.298 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-13T21:04:31.299Z,1694639071.299 [Default:CheckIn:C.Wait] Stopped 2023-09-13T21:04:31.299Z,1694639071.299 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-13T21:04:31.299Z,1694639071.299 [Default:CheckIn:D] Running Loop=1 2023-09-13T21:04:31.670Z,1694639071.670 [Default:CheckIn:D] Stopped 2023-09-13T21:04:31.670Z,1694639071.670 [Default:CheckIn:E] Running Loop=1 2023-09-13T21:04:32.056Z,1694639072.056 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-13T21:04:32.088Z,1694639072.088 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 192.285677 min 2023-09-13T21:04:32.088Z,1694639072.088 [Default:CheckIn:E] Stopped 2023-09-13T21:04:32.088Z,1694639072.088 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-13T21:04:32.088Z,1694639072.088 [Default:CheckIn] Stopped 2023-09-13T21:04:32.088Z,1694639072.088 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-13T21:04:32.088Z,1694639072.088 [Default:CheckIn](INFO): Running loop #35 2023-09-13T21:04:32.089Z,1694639072.089 [Default:CheckIn] Running Loop=35 2023-09-13T21:04:32.089Z,1694639072.089 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-13T21:04:32.089Z,1694639072.089 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-13T21:04:34.077Z,1694639074.077 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210433.00,A,3648.17501,N,12147.28211,W,0.816,0.00,130923,,,A*7C 2023-09-13T21:04:34.079Z,1694639074.079 [NAL9602](INFO): GPS fix at 20230913T210433: (36.802917, -121.788035) 2023-09-13T21:04:34.106Z,1694639074.106 [Default:CheckIn:Read_GPS] Stopped 2023-09-13T21:04:34.106Z,1694639074.106 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-13T21:04:42.177Z,1694639082.177 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230913T175213/Courier0105.lzma 2023-09-13T21:04:43.180Z,1694639083.180 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Courier0105.lzma.bak 2023-09-13T21:04:43.180Z,1694639083.180 [DataOverHttps](INFO): SBD MOMSN=18767079 2023-09-13T21:05:02.246Z,1694639102.246 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20230913T175213/Express0106.lzma 2023-09-13T21:05:03.248Z,1694639103.248 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0106.lzma.bak 2023-09-13T21:05:03.248Z,1694639103.248 [DataOverHttps](INFO): SBD MOMSN=18767082 2023-09-13T21:05:04.775Z,1694639104.775 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-09-13T21:05:04.850Z,1694639104.850 [NAL9602](ERROR): received: +CSQ:0 OK508, 2, 0, 0, 0 OK 2023-09-13T21:05:06.417Z,1694639106.417 [Default:CheckIn:Read_Iridium] Stopped 2023-09-13T21:05:06.417Z,1694639106.417 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-13T21:05:06.417Z,1694639106.417 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-13T21:07:08.405Z,1694639228.405 [NAL9602](INFO): SBD MO Status=2, MOMSN=13508, MT Status=2, MTMSN=0 2023-09-13T21:07:08.405Z,1694639228.405 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T21:07:25.777Z,1694639245.777 [NAL9602](INFO): SBD MO Status=2, MOMSN=13508, MT Status=2, MTMSN=0 2023-09-13T21:07:25.777Z,1694639245.777 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T21:07:42.740Z,1694639262.740 [NAL9602](INFO): SBD MO Status=2, MOMSN=13508, MT Status=2, MTMSN=0 2023-09-13T21:07:42.741Z,1694639262.741 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T21:09:36.267Z,1694639376.267 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-13T21:10:06.971Z,1694639406.971 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-13T21:10:06.984Z,1694639406.984 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-13T21:10:06.984Z,1694639406.984 [Default:CheckIn:C.Wait] Stopped 2023-09-13T21:10:06.984Z,1694639406.984 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-13T21:10:06.984Z,1694639406.984 [Default:CheckIn:D] Running Loop=1 2023-09-13T21:10:07.381Z,1694639407.381 [Default:CheckIn:D] Stopped 2023-09-13T21:10:07.381Z,1694639407.381 [Default:CheckIn:E] Running Loop=1 2023-09-13T21:10:07.787Z,1694639407.787 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 197.880859 min 2023-09-13T21:10:07.787Z,1694639407.787 [Default:CheckIn:E] Stopped 2023-09-13T21:10:07.787Z,1694639407.787 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-13T21:10:07.787Z,1694639407.787 [Default:CheckIn] Stopped 2023-09-13T21:10:07.787Z,1694639407.787 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-13T21:10:07.788Z,1694639407.788 [Default:CheckIn](INFO): Running loop #36 2023-09-13T21:10:07.788Z,1694639407.788 [Default:CheckIn] Running Loop=36 2023-09-13T21:10:07.788Z,1694639407.788 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-13T21:10:07.788Z,1694639407.788 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-13T21:10:09.796Z,1694639409.796 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211009.00,A,3648.16986,N,12147.28352,W,0.117,0.00,130923,,,A*7C 2023-09-13T21:10:09.798Z,1694639409.798 [NAL9602](INFO): GPS fix at 20230913T211009: (36.802831, -121.788059) 2023-09-13T21:10:09.833Z,1694639409.833 [Default:CheckIn:Read_GPS] Stopped 2023-09-13T21:10:09.833Z,1694639409.833 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-13T21:10:18.653Z,1694639418.653 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230913T175213/Courier0108.lzma 2023-09-13T21:10:19.656Z,1694639419.656 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Courier0108.lzma.bak 2023-09-13T21:10:19.656Z,1694639419.656 [DataOverHttps](INFO): SBD MOMSN=18767088 2023-09-13T21:10:38.821Z,1694639438.821 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20230913T175213/Express0109.lzma 2023-09-13T21:10:39.824Z,1694639439.824 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0109.lzma.bak 2023-09-13T21:10:39.824Z,1694639439.824 [DataOverHttps](INFO): SBD MOMSN=18767091 2023-09-13T21:10:40.498Z,1694639440.498 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-09-13T21:10:40.582Z,1694639440.582 [NAL9602](ERROR): received: +CSQ:0 OK508, 2, 0, 0, 0 OK 2023-09-13T21:10:42.961Z,1694639442.961 [Default:CheckIn:Read_Iridium] Stopped 2023-09-13T21:10:42.961Z,1694639442.961 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-13T21:10:42.961Z,1694639442.961 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-13T21:14:15.024Z,1694639655.024 [NAL9602](INFO): SBD MO Status=2, MOMSN=13508, MT Status=2, MTMSN=0 2023-09-13T21:14:15.024Z,1694639655.024 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T21:15:12.395Z,1694639712.395 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-13T21:15:43.095Z,1694639743.095 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-13T21:15:43.543Z,1694639743.543 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-13T21:15:43.543Z,1694639743.543 [Default:CheckIn:C.Wait] Stopped 2023-09-13T21:15:43.543Z,1694639743.543 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-13T21:15:43.543Z,1694639743.543 [Default:CheckIn:D] Running Loop=1 2023-09-13T21:15:43.920Z,1694639743.920 [Default:CheckIn:D] Stopped 2023-09-13T21:15:43.920Z,1694639743.920 [Default:CheckIn:E] Running Loop=1 2023-09-13T21:15:44.326Z,1694639744.326 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 203.489844 min 2023-09-13T21:15:44.326Z,1694639744.326 [Default:CheckIn:E] Stopped 2023-09-13T21:15:44.326Z,1694639744.326 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-13T21:15:44.326Z,1694639744.326 [Default:CheckIn] Stopped 2023-09-13T21:15:44.326Z,1694639744.326 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-13T21:15:44.327Z,1694639744.327 [Default:CheckIn](INFO): Running loop #37 2023-09-13T21:15:44.327Z,1694639744.327 [Default:CheckIn] Running Loop=37 2023-09-13T21:15:44.327Z,1694639744.327 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-13T21:15:44.327Z,1694639744.327 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-13T21:15:46.329Z,1694639746.329 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211545.00,A,3648.16562,N,12147.28244,W,0.292,0.00,130923,,,A*7F 2023-09-13T21:15:46.331Z,1694639746.331 [NAL9602](INFO): GPS fix at 20230913T211545: (36.802760, -121.788041) 2023-09-13T21:15:46.343Z,1694639746.343 [Default:CheckIn:Read_GPS] Stopped 2023-09-13T21:15:46.344Z,1694639746.344 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-13T21:15:56.235Z,1694639756.235 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230913T175213/Courier0111.lzma 2023-09-13T21:15:57.240Z,1694639757.240 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Courier0111.lzma.bak 2023-09-13T21:15:57.240Z,1694639757.240 [DataOverHttps](INFO): SBD MOMSN=18767095 2023-09-13T21:16:15.090Z,1694639775.090 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20230913T175213/Express0112.lzma 2023-09-13T21:16:16.092Z,1694639776.092 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0112.lzma.bak 2023-09-13T21:16:16.092Z,1694639776.092 [DataOverHttps](INFO): SBD MOMSN=18767098 2023-09-13T21:16:17.030Z,1694639777.030 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-09-13T21:16:17.106Z,1694639777.106 [NAL9602](ERROR): received: +CSQ:0 OK508, 2, 0, 0, 0 OK 2023-09-13T21:16:19.483Z,1694639779.483 [Default:CheckIn:Read_Iridium] Stopped 2023-09-13T21:16:19.484Z,1694639779.484 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-13T21:16:19.484Z,1694639779.484 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-13T21:20:48.518Z,1694640048.518 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-13T21:21:19.221Z,1694640079.221 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-13T21:21:20.041Z,1694640080.041 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-13T21:21:20.041Z,1694640080.041 [Default:CheckIn:C.Wait] Stopped 2023-09-13T21:21:20.041Z,1694640080.041 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-13T21:21:20.042Z,1694640080.042 [Default:CheckIn:D] Running Loop=1 2023-09-13T21:21:20.463Z,1694640080.463 [Default:CheckIn:D] Stopped 2023-09-13T21:21:20.463Z,1694640080.463 [Default:CheckIn:E] Running Loop=1 2023-09-13T21:21:20.862Z,1694640080.862 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 209.098893 min 2023-09-13T21:21:20.862Z,1694640080.862 [Default:CheckIn:E] Stopped 2023-09-13T21:21:20.862Z,1694640080.862 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-13T21:21:20.862Z,1694640080.862 [Default:CheckIn] Stopped 2023-09-13T21:21:20.862Z,1694640080.862 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-13T21:21:20.862Z,1694640080.862 [Default:CheckIn](INFO): Running loop #38 2023-09-13T21:21:20.863Z,1694640080.863 [Default:CheckIn] Running Loop=38 2023-09-13T21:21:20.863Z,1694640080.863 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-13T21:21:20.863Z,1694640080.863 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-13T21:21:22.865Z,1694640082.865 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212122.00,A,3648.17736,N,12147.28433,W,0.622,0.00,130923,,,A*72 2023-09-13T21:21:22.867Z,1694640082.867 [NAL9602](INFO): GPS fix at 20230913T212122: (36.802956, -121.788072) 2023-09-13T21:21:22.900Z,1694640082.900 [Default:CheckIn:Read_GPS] Stopped 2023-09-13T21:21:22.900Z,1694640082.900 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-13T21:21:33.161Z,1694640093.161 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230913T175213/Courier0114.lzma 2023-09-13T21:21:34.164Z,1694640094.164 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Courier0114.lzma.bak 2023-09-13T21:21:34.164Z,1694640094.164 [DataOverHttps](INFO): SBD MOMSN=18767133 2023-09-13T21:21:53.628Z,1694640113.628 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-09-13T21:21:53.702Z,1694640113.702 [NAL9602](ERROR): received: +CSQ:0 OK508, 2, 0, 0, 0 OK 2023-09-13T21:21:58.070Z,1694640118.070 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20230913T175213/Express0115.lzma 2023-09-13T21:21:59.072Z,1694640119.072 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0115.lzma.bak 2023-09-13T21:21:59.072Z,1694640119.072 [DataOverHttps](INFO): SBD MOMSN=18767136 2023-09-13T21:22:02.576Z,1694640122.576 [Default:CheckIn:Read_Iridium] Stopped 2023-09-13T21:22:02.576Z,1694640122.576 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-13T21:22:02.577Z,1694640122.577 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-13T21:26:25.523Z,1694640385.523 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-13T21:26:56.228Z,1694640416.228 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-13T21:27:03.118Z,1694640423.118 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-13T21:27:03.118Z,1694640423.118 [Default:CheckIn:C.Wait] Stopped 2023-09-13T21:27:03.118Z,1694640423.118 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-13T21:27:03.118Z,1694640423.118 [Default:CheckIn:D] Running Loop=1 2023-09-13T21:27:03.505Z,1694640423.505 [Default:CheckIn:D] Stopped 2023-09-13T21:27:03.505Z,1694640423.505 [Default:CheckIn:E] Running Loop=1 2023-09-13T21:27:03.912Z,1694640423.912 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 214.816260 min 2023-09-13T21:27:03.912Z,1694640423.912 [Default:CheckIn:E] Stopped 2023-09-13T21:27:03.912Z,1694640423.912 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-13T21:27:03.912Z,1694640423.912 [Default:CheckIn] Stopped 2023-09-13T21:27:03.912Z,1694640423.912 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-13T21:27:03.912Z,1694640423.912 [Default:CheckIn](INFO): Running loop #39 2023-09-13T21:27:03.912Z,1694640423.912 [Default:CheckIn] Running Loop=39 2023-09-13T21:27:03.912Z,1694640423.912 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-13T21:27:03.913Z,1694640423.913 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-13T21:27:05.921Z,1694640425.921 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212705.00,A,3648.16372,N,12147.28669,W,0.136,0.00,130923,,,A*7B 2023-09-13T21:27:05.924Z,1694640425.924 [NAL9602](INFO): GPS fix at 20230913T212705: (36.802729, -121.788111) 2023-09-13T21:27:05.936Z,1694640425.936 [Default:CheckIn:Read_GPS] Stopped 2023-09-13T21:27:05.936Z,1694640425.936 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-13T21:27:13.957Z,1694640433.957 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230913T175213/Courier0117.lzma 2023-09-13T21:27:14.961Z,1694640434.961 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Courier0117.lzma.bak 2023-09-13T21:27:14.962Z,1694640434.962 [DataOverHttps](INFO): SBD MOMSN=18767140 2023-09-13T21:27:35.534Z,1694640455.534 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20230913T175213/Express0118.lzma 2023-09-13T21:27:36.536Z,1694640456.536 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0118.lzma.bak 2023-09-13T21:27:36.536Z,1694640456.536 [DataOverHttps](INFO): SBD MOMSN=18767143 2023-09-13T21:27:36.719Z,1694640456.719 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-09-13T21:27:36.798Z,1694640456.798 [NAL9602](ERROR): received: +CSQ:0 OK508, 2, 0, 0, 0 OK 2023-09-13T21:27:41.620Z,1694640461.620 [Default:CheckIn:Read_Iridium] Stopped 2023-09-13T21:27:41.621Z,1694640461.621 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-13T21:27:41.621Z,1694640461.621 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-13T21:28:03.024Z,1694640483.024 [CBIT](IMPORTANT): Beginning ground fault scan 2023-09-13T21:28:05.853Z,1694640485.853 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes. 2023-09-13T21:28:13.924Z,1694640493.924 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.005158 CHAN A1 (24V) N/A reporting off CHAN A2 (12V): -0.005438 CHAN A3 (5V): -0.002437 CHAN B0 (3.3V): -0.001020 CHAN B1 (3.15aV): -0.001924 CHAN B2 (3.15bV): -0.001356 CHAN B3 (GND): -0.000928 OPEN: 0.004747 Full Scale: +/- 1 mA 2023-09-13T21:32:08.627Z,1694640728.627 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-13T21:32:39.323Z,1694640759.323 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-13T21:32:42.166Z,1694640762.166 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-13T21:32:42.166Z,1694640762.166 [Default:CheckIn:C.Wait] Stopped 2023-09-13T21:32:42.166Z,1694640762.166 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-13T21:32:42.166Z,1694640762.166 [Default:CheckIn:D] Running Loop=1 2023-09-13T21:32:42.571Z,1694640762.571 [Default:CheckIn:D] Stopped 2023-09-13T21:32:42.571Z,1694640762.571 [Default:CheckIn:E] Running Loop=1 2023-09-13T21:32:42.987Z,1694640762.987 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 220.467350 min 2023-09-13T21:32:42.987Z,1694640762.987 [Default:CheckIn:E] Stopped 2023-09-13T21:32:42.987Z,1694640762.987 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-13T21:32:42.987Z,1694640762.987 [Default:CheckIn] Stopped 2023-09-13T21:32:42.987Z,1694640762.987 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-13T21:32:42.987Z,1694640762.987 [Default:CheckIn](INFO): Running loop #40 2023-09-13T21:32:42.987Z,1694640762.987 [Default:CheckIn] Running Loop=40 2023-09-13T21:32:42.987Z,1694640762.987 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-13T21:32:42.988Z,1694640762.988 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-13T21:32:44.981Z,1694640764.981 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213244.00,A,3648.16849,N,12147.27752,W,0.408,0.00,130923,,,A*77 2023-09-13T21:32:44.983Z,1694640764.983 [NAL9602](INFO): GPS fix at 20230913T213244: (36.802808, -121.787959) 2023-09-13T21:32:44.995Z,1694640764.995 [Default:CheckIn:Read_GPS] Stopped 2023-09-13T21:32:44.996Z,1694640764.996 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-13T21:32:53.021Z,1694640773.021 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230913T175213/Courier0120.lzma 2023-09-13T21:32:54.025Z,1694640774.025 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Courier0120.lzma.bak 2023-09-13T21:32:54.025Z,1694640774.025 [DataOverHttps](INFO): SBD MOMSN=18767149 2023-09-13T21:33:11.718Z,1694640791.718 [DataOverHttps](INFO): Sending 392 bytes from file Logs/20230913T175213/Express0121.lzma 2023-09-13T21:33:12.720Z,1694640792.720 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0121.lzma.bak 2023-09-13T21:33:12.720Z,1694640792.720 [DataOverHttps](INFO): SBD MOMSN=18767152 2023-09-13T21:33:15.759Z,1694640795.759 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-09-13T21:33:15.834Z,1694640795.834 [NAL9602](ERROR): received: +CSQ:0 OK508, 2, 0, 0, 0 OK 2023-09-13T21:33:16.212Z,1694640796.212 [Default:CheckIn:Read_Iridium] Stopped 2023-09-13T21:33:16.212Z,1694640796.212 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-13T21:33:16.212Z,1694640796.212 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-13T21:33:37.176Z,1694640817.176 [NAL9602](INFO): SBD MO Status=2, MOMSN=13508, MT Status=2, MTMSN=0 2023-09-13T21:33:37.177Z,1694640817.177 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T21:34:31.309Z,1694640871.309 [NAL9602](INFO): SBD MO Status=2, MOMSN=13508, MT Status=2, MTMSN=0 2023-09-13T21:34:31.309Z,1694640871.309 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T21:35:48.880Z,1694640948.880 [NAL9602](INFO): SBD MO Status=2, MOMSN=13508, MT Status=2, MTMSN=0 2023-09-13T21:35:48.881Z,1694640948.881 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T21:36:31.705Z,1694640991.705 [NAL9602](INFO): SBD MO Status=2, MOMSN=13508, MT Status=2, MTMSN=0 2023-09-13T21:36:31.705Z,1694640991.705 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T21:37:10.485Z,1694641030.485 [NAL9602](INFO): SBD MO Status=2, MOMSN=13508, MT Status=2, MTMSN=0 2023-09-13T21:37:10.485Z,1694641030.485 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T21:37:47.308Z,1694641067.308 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-13T21:38:16.818Z,1694641096.818 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-13T21:38:16.818Z,1694641096.818 [Default:CheckIn:C.Wait] Stopped 2023-09-13T21:38:16.818Z,1694641096.818 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-13T21:38:16.819Z,1694641096.819 [Default:CheckIn:D] Running Loop=1 2023-09-13T21:38:17.210Z,1694641097.210 [Default:CheckIn:D] Stopped 2023-09-13T21:38:17.210Z,1694641097.210 [Default:CheckIn:E] Running Loop=1 2023-09-13T21:38:17.615Z,1694641097.615 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 226.044678 min 2023-09-13T21:38:17.615Z,1694641097.615 [Default:CheckIn:E] Stopped 2023-09-13T21:38:17.615Z,1694641097.615 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-13T21:38:17.615Z,1694641097.615 [Default:CheckIn] Stopped 2023-09-13T21:38:17.615Z,1694641097.615 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-13T21:38:17.615Z,1694641097.615 [Default:CheckIn](INFO): Running loop #41 2023-09-13T21:38:17.615Z,1694641097.615 [Default:CheckIn] Running Loop=41 2023-09-13T21:38:17.615Z,1694641097.615 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-13T21:38:17.615Z,1694641097.615 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-13T21:38:18.012Z,1694641098.012 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-13T21:38:19.625Z,1694641099.625 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213818.00,A,3648.16646,N,12147.28405,W,0.311,37.07,130923,,,A*47 2023-09-13T21:38:19.635Z,1694641099.635 [NAL9602](INFO): GPS fix at 20230913T213818: (36.802774, -121.788067) 2023-09-13T21:38:19.647Z,1694641099.647 [Default:CheckIn:Read_GPS] Stopped 2023-09-13T21:38:19.647Z,1694641099.647 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-13T21:38:27.693Z,1694641107.693 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230913T175213/Courier0123.lzma 2023-09-13T21:38:28.696Z,1694641108.696 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Courier0123.lzma.bak 2023-09-13T21:38:28.696Z,1694641108.696 [DataOverHttps](INFO): SBD MOMSN=18767158 2023-09-13T21:38:48.489Z,1694641128.489 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20230913T175213/Express0124.lzma 2023-09-13T21:38:49.492Z,1694641129.492 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0124.lzma.bak 2023-09-13T21:38:49.492Z,1694641129.492 [DataOverHttps](INFO): SBD MOMSN=18767163 2023-09-13T21:38:50.382Z,1694641130.382 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-09-13T21:38:50.466Z,1694641130.466 [NAL9602](ERROR): received: +CSQ:0 OK508, 2, 0, 0, 0 OK 2023-09-13T21:38:52.840Z,1694641132.840 [Default:CheckIn:Read_Iridium] Stopped 2023-09-13T21:38:52.840Z,1694641132.840 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-13T21:38:52.840Z,1694641132.840 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-13T21:41:23.909Z,1694641283.909 [NAL9602](INFO): SBD MO Status=2, MOMSN=13508, MT Status=2, MTMSN=0 2023-09-13T21:41:23.909Z,1694641283.909 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T21:41:42.084Z,1694641302.084 [NAL9602](INFO): SBD MO Status=0, MOMSN=13508, MT Status=0, MTMSN=0 2023-09-13T21:41:42.084Z,1694641302.084 [NAL9602](INFO): No messages in MT queue 2023-09-13T21:42:12.791Z,1694641332.791 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-13T21:43:53.459Z,1694641433.459 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-13T21:43:53.459Z,1694641433.459 [Default:CheckIn:C.Wait] Stopped 2023-09-13T21:43:53.459Z,1694641433.459 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-13T21:43:53.460Z,1694641433.460 [Default:CheckIn:D] Running Loop=1 2023-09-13T21:43:53.789Z,1694641433.789 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2023-09-13T21:43:53.888Z,1694641433.888 [Default:CheckIn:D] Stopped 2023-09-13T21:43:53.888Z,1694641433.888 [Default:CheckIn:E] Running Loop=1 2023-09-13T21:43:54.246Z,1694641434.246 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 231.655973 min 2023-09-13T21:43:54.246Z,1694641434.246 [Default:CheckIn:E] Stopped 2023-09-13T21:43:54.246Z,1694641434.246 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-13T21:43:54.246Z,1694641434.246 [Default:CheckIn] Stopped 2023-09-13T21:43:54.246Z,1694641434.246 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-13T21:43:54.246Z,1694641434.246 [Default:CheckIn](INFO): Running loop #42 2023-09-13T21:43:54.246Z,1694641434.246 [Default:CheckIn] Running Loop=42 2023-09-13T21:43:54.247Z,1694641434.247 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-13T21:43:54.247Z,1694641434.247 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-13T21:43:56.213Z,1694641436.213 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214355.00,A,3648.16634,N,12147.28210,W,0.117,37.07,130923,,,A*41 2023-09-13T21:43:56.215Z,1694641436.215 [NAL9602](INFO): GPS fix at 20230913T214355: (36.802772, -121.788035) 2023-09-13T21:43:56.227Z,1694641436.227 [Default:CheckIn:Read_GPS] Stopped 2023-09-13T21:43:56.228Z,1694641436.228 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-13T21:44:04.393Z,1694641444.393 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230913T175213/Courier0126.lzma 2023-09-13T21:44:05.396Z,1694641445.396 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Courier0126.lzma.bak 2023-09-13T21:44:05.396Z,1694641445.396 [DataOverHttps](INFO): SBD MOMSN=18767175 2023-09-13T21:44:17.625Z,1694641457.625 [NAL9602](INFO): SBD MO Status=2, MOMSN=13509, MT Status=2, MTMSN=0 2023-09-13T21:44:17.625Z,1694641457.625 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T21:44:23.413Z,1694641463.413 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230913T175213/Express0127.lzma 2023-09-13T21:44:24.416Z,1694641464.416 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0127.lzma.bak 2023-09-13T21:44:24.416Z,1694641464.416 [DataOverHttps](INFO): SBD MOMSN=18767179 2023-09-13T21:44:27.749Z,1694641467.749 [Default:CheckIn:Read_Iridium] Stopped 2023-09-13T21:44:27.749Z,1694641467.749 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-13T21:44:27.749Z,1694641467.749 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-13T21:44:58.028Z,1694641498.028 [NAL9602](INFO): SBD MO Status=2, MOMSN=13509, MT Status=2, MTMSN=0 2023-09-13T21:44:58.029Z,1694641498.029 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T21:45:23.881Z,1694641523.881 [NAL9602](INFO): SBD MO Status=2, MOMSN=13509, MT Status=2, MTMSN=0 2023-09-13T21:45:23.881Z,1694641523.881 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T21:48:58.474Z,1694641738.474 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-13T21:49:28.396Z,1694641768.396 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-13T21:49:28.396Z,1694641768.396 [Default:CheckIn:C.Wait] Stopped 2023-09-13T21:49:28.396Z,1694641768.396 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-13T21:49:28.396Z,1694641768.396 [Default:CheckIn:D] Running Loop=1 2023-09-13T21:49:28.803Z,1694641768.803 [Default:CheckIn:D] Stopped 2023-09-13T21:49:28.803Z,1694641768.803 [Default:CheckIn:E] Running Loop=1 2023-09-13T21:49:29.175Z,1694641769.175 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-13T21:49:29.189Z,1694641769.189 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 237.237907 min 2023-09-13T21:49:29.189Z,1694641769.189 [Default:CheckIn:E] Stopped 2023-09-13T21:49:29.189Z,1694641769.189 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-13T21:49:29.189Z,1694641769.189 [Default:CheckIn] Stopped 2023-09-13T21:49:29.189Z,1694641769.189 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-13T21:49:29.189Z,1694641769.189 [Default:CheckIn](INFO): Running loop #43 2023-09-13T21:49:29.189Z,1694641769.189 [Default:CheckIn] Running Loop=43 2023-09-13T21:49:29.190Z,1694641769.190 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-13T21:49:29.190Z,1694641769.190 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-13T21:49:31.255Z,1694641771.255 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214930.00,A,3648.16644,N,12147.27902,W,0.369,138.70,130923,,,A*7D 2023-09-13T21:49:31.257Z,1694641771.257 [NAL9602](INFO): GPS fix at 20230913T214930: (36.802774, -121.787984) 2023-09-13T21:49:31.269Z,1694641771.269 [Default:CheckIn:Read_GPS] Stopped 2023-09-13T21:49:31.270Z,1694641771.270 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-13T21:49:42.658Z,1694641782.658 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230913T175213/Courier0129.lzma 2023-09-13T21:49:43.661Z,1694641783.661 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Courier0129.lzma.bak 2023-09-13T21:49:43.661Z,1694641783.661 [DataOverHttps](INFO): SBD MOMSN=18767182 2023-09-13T21:49:47.032Z,1694641787.032 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2023-09-13T21:49:47.035Z,1694641787.035 [BPC1](INFO): Received data from all battery sticks. 2023-09-13T21:50:01.967Z,1694641801.967 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-09-13T21:50:02.046Z,1694641802.046 [NAL9602](ERROR): received: +CSQ:0 OK509, 2, 0, 0, 0 OK 2023-09-13T21:50:05.989Z,1694641805.989 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20230913T175213/Express0130.lzma 2023-09-13T21:50:06.992Z,1694641806.992 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0130.lzma.bak 2023-09-13T21:50:06.992Z,1694641806.992 [DataOverHttps](INFO): SBD MOMSN=18767185 2023-09-13T21:50:10.495Z,1694641810.495 [Default:CheckIn:Read_Iridium] Stopped 2023-09-13T21:50:10.496Z,1694641810.496 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-13T21:50:10.496Z,1694641810.496 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-13T21:50:30.657Z,1694641830.657 [NAL9602](INFO): SBD MO Status=0, MOMSN=13509, MT Status=0, MTMSN=0 2023-09-13T21:50:30.657Z,1694641830.657 [NAL9602](INFO): No messages in MT queue 2023-09-13T21:51:01.355Z,1694641861.355 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-13T21:55:11.082Z,1694642111.082 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-13T21:55:11.082Z,1694642111.082 [Default:CheckIn:C.Wait] Stopped 2023-09-13T21:55:11.082Z,1694642111.082 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-13T21:55:11.083Z,1694642111.083 [Default:CheckIn:D] Running Loop=1 2023-09-13T21:55:11.490Z,1694642111.490 [Default:CheckIn:D] Stopped 2023-09-13T21:55:11.490Z,1694642111.490 [Default:CheckIn:E] Running Loop=1 2023-09-13T21:55:11.913Z,1694642111.913 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 242.949349 min 2023-09-13T21:55:11.913Z,1694642111.913 [Default:CheckIn:E] Stopped 2023-09-13T21:55:11.913Z,1694642111.913 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-13T21:55:11.913Z,1694642111.913 [Default:CheckIn] Stopped 2023-09-13T21:55:11.913Z,1694642111.913 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-13T21:55:11.913Z,1694642111.913 [Default:CheckIn](INFO): Running loop #44 2023-09-13T21:55:11.913Z,1694642111.913 [Default:CheckIn] Running Loop=44 2023-09-13T21:55:11.913Z,1694642111.913 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-13T21:55:11.914Z,1694642111.914 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-13T21:55:13.909Z,1694642113.909 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215513.00,A,3648.16835,N,12147.28089,W,0.058,138.70,130923,,,A*7D 2023-09-13T21:55:13.911Z,1694642113.911 [NAL9602](INFO): GPS fix at 20230913T215513: (36.802806, -121.788015) 2023-09-13T21:55:13.938Z,1694642113.938 [Default:CheckIn:Read_GPS] Stopped 2023-09-13T21:55:13.939Z,1694642113.939 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-13T21:55:22.417Z,1694642122.417 [DataOverHttps](INFO): Sending 86 bytes from file Logs/20230913T175213/Courier0132.lzma 2023-09-13T21:55:23.420Z,1694642123.420 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Courier0132.lzma.bak 2023-09-13T21:55:23.420Z,1694642123.420 [DataOverHttps](INFO): SBD MOMSN=18767190 2023-09-13T21:55:41.322Z,1694642141.322 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230913T175213/Express0133.lzma 2023-09-13T21:55:42.324Z,1694642142.324 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0133.lzma.bak 2023-09-13T21:55:42.324Z,1694642142.324 [DataOverHttps](INFO): SBD MOMSN=18767193 2023-09-13T21:55:45.825Z,1694642145.825 [Default:CheckIn:Read_Iridium] Stopped 2023-09-13T21:55:45.826Z,1694642145.826 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-13T21:55:45.826Z,1694642145.826 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-13T22:00:16.495Z,1694642416.495 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-13T22:00:46.401Z,1694642446.401 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-13T22:00:46.401Z,1694642446.401 [Default:CheckIn:C.Wait] Stopped 2023-09-13T22:00:46.401Z,1694642446.401 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-13T22:00:46.401Z,1694642446.401 [Default:CheckIn:D] Running Loop=1 2023-09-13T22:00:46.811Z,1694642446.811 [Default:CheckIn:D] Stopped 2023-09-13T22:00:46.811Z,1694642446.811 [Default:CheckIn:E] Running Loop=1 2023-09-13T22:00:47.209Z,1694642447.209 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-13T22:00:47.223Z,1694642447.223 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 248.538021 min 2023-09-13T22:00:47.223Z,1694642447.223 [Default:CheckIn:E] Stopped 2023-09-13T22:00:47.223Z,1694642447.223 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-13T22:00:47.223Z,1694642447.223 [Default:CheckIn] Stopped 2023-09-13T22:00:47.223Z,1694642447.223 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-13T22:00:47.223Z,1694642447.223 [Default:CheckIn](INFO): Running loop #45 2023-09-13T22:00:47.224Z,1694642447.224 [Default:CheckIn] Running Loop=45 2023-09-13T22:00:47.224Z,1694642447.224 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-13T22:00:47.224Z,1694642447.224 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-13T22:00:49.225Z,1694642449.225 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220048.00,A,3648.17029,N,12147.28202,W,0.564,0.00,130923,,,A*72 2023-09-13T22:00:49.227Z,1694642449.227 [NAL9602](INFO): GPS fix at 20230913T220048: (36.802838, -121.788034) 2023-09-13T22:00:49.240Z,1694642449.240 [Default:CheckIn:Read_GPS] Stopped 2023-09-13T22:00:49.240Z,1694642449.240 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-13T22:00:57.621Z,1694642457.621 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230913T175213/Courier0135.lzma 2023-09-13T22:00:58.624Z,1694642458.624 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Courier0135.lzma.bak 2023-09-13T22:00:58.624Z,1694642458.624 [DataOverHttps](INFO): SBD MOMSN=18767196 2023-09-13T22:01:16.385Z,1694642476.385 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20230913T175213/Express0136.lzma 2023-09-13T22:01:17.388Z,1694642477.388 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0136.lzma.bak 2023-09-13T22:01:17.388Z,1694642477.388 [DataOverHttps](INFO): SBD MOMSN=18767199 2023-09-13T22:01:19.923Z,1694642479.923 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-09-13T22:01:20.002Z,1694642480.002 [NAL9602](ERROR): received: +CSQ:0 OK509, 0, 0, 0, 0 OK 2023-09-13T22:01:20.760Z,1694642480.760 [Default:CheckIn:Read_Iridium] Stopped 2023-09-13T22:01:20.760Z,1694642480.760 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-13T22:01:20.760Z,1694642480.760 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-13T22:01:52.668Z,1694642512.668 [NAL9602](INFO): SBD MO Status=2, MOMSN=13510, MT Status=2, MTMSN=0 2023-09-13T22:01:52.668Z,1694642512.668 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T22:02:13.231Z,1694642533.231 [DataOverHttps](IMPORTANT): SBD MTMSN=20230913T220212 2023-09-13T22:02:20.566Z,1694642540.566 [DataOverHttps](INFO): Received command: configSet VerticalControl.massDeadband 1 mm persist 2023-09-13T22:02:20.912Z,1694642540.912 [CommandExec](IMPORTANT): got command configSet VerticalControl.massDeadband 2023-09-13T22:02:20.913Z,1694642540.913 [CommandExec](FAULT): Incomplete syntax. Try: help configSet 2023-09-13T22:02:28.220Z,1694642548.220 [NAL9602](INFO): SBD MO Status=2, MOMSN=13510, MT Status=2, MTMSN=0 2023-09-13T22:02:28.220Z,1694642548.220 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T22:03:09.428Z,1694642589.428 [NAL9602](INFO): SBD MO Status=2, MOMSN=13510, MT Status=2, MTMSN=0 2023-09-13T22:03:09.428Z,1694642589.428 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T22:03:22.761Z,1694642602.761 [NAL9602](INFO): SBD MO Status=2, MOMSN=13510, MT Status=2, MTMSN=0 2023-09-13T22:03:22.761Z,1694642602.761 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T22:03:48.626Z,1694642628.626 [NAL9602](INFO): SBD MO Status=2, MOMSN=13510, MT Status=2, MTMSN=0 2023-09-13T22:03:48.626Z,1694642628.626 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T22:04:02.764Z,1694642642.764 [NAL9602](INFO): SBD MO Status=2, MOMSN=13510, MT Status=2, MTMSN=0 2023-09-13T22:04:02.765Z,1694642642.765 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T22:05:51.435Z,1694642751.435 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-13T22:06:21.346Z,1694642781.346 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-13T22:06:21.346Z,1694642781.346 [Default:CheckIn:C.Wait] Stopped 2023-09-13T22:06:21.346Z,1694642781.346 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-13T22:06:21.347Z,1694642781.347 [Default:CheckIn:D] Running Loop=1 2023-09-13T22:06:21.762Z,1694642781.762 [Default:CheckIn:D] Stopped 2023-09-13T22:06:21.762Z,1694642781.762 [Default:CheckIn:E] Running Loop=1 2023-09-13T22:06:22.139Z,1694642782.139 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-13T22:06:22.152Z,1694642782.152 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 254.120540 min 2023-09-13T22:06:22.152Z,1694642782.152 [Default:CheckIn:E] Stopped 2023-09-13T22:06:22.153Z,1694642782.153 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-13T22:06:22.153Z,1694642782.153 [Default:CheckIn] Stopped 2023-09-13T22:06:22.153Z,1694642782.153 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-13T22:06:22.153Z,1694642782.153 [Default:CheckIn](INFO): Running loop #46 2023-09-13T22:06:22.153Z,1694642782.153 [Default:CheckIn] Running Loop=46 2023-09-13T22:06:22.153Z,1694642782.153 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-13T22:06:22.153Z,1694642782.153 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-13T22:06:24.165Z,1694642784.165 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220623.00,A,3648.16839,N,12147.27852,W,0.117,0.00,130923,,,A*71 2023-09-13T22:06:24.168Z,1694642784.168 [NAL9602](INFO): GPS fix at 20230913T220623: (36.802807, -121.787975) 2023-09-13T22:06:24.180Z,1694642784.180 [Default:CheckIn:Read_GPS] Stopped 2023-09-13T22:06:24.180Z,1694642784.180 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-13T22:06:30.529Z,1694642790.529 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230913T175213/Courier0138.lzma 2023-09-13T22:06:31.537Z,1694642791.537 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Courier0138.lzma.bak 2023-09-13T22:06:31.537Z,1694642791.537 [DataOverHttps](INFO): SBD MOMSN=18767208 2023-09-13T22:06:50.442Z,1694642810.442 [DataOverHttps](INFO): Sending 343 bytes from file Logs/20230913T175213/Express0139.lzma 2023-09-13T22:06:51.444Z,1694642811.444 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0139.lzma.bak 2023-09-13T22:06:51.444Z,1694642811.444 [DataOverHttps](INFO): SBD MOMSN=18767211 2023-09-13T22:06:54.895Z,1694642814.895 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-09-13T22:06:54.970Z,1694642814.970 [NAL9602](ERROR): received: +CSQ:0 OK510, 2, 0, 0, 0 OK 2023-09-13T22:07:09.337Z,1694642829.337 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230913T175213/Express0142.lzma 2023-09-13T22:07:10.340Z,1694642830.340 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0142.lzma.bak 2023-09-13T22:07:10.340Z,1694642830.340 [DataOverHttps](INFO): SBD MOMSN=18767218 2023-09-13T22:07:13.521Z,1694642833.521 [Default:CheckIn:Read_Iridium] Stopped 2023-09-13T22:07:13.521Z,1694642833.521 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-13T22:07:13.521Z,1694642833.521 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-13T22:10:50.048Z,1694643050.048 [NAL9602](INFO): SBD MO Status=2, MOMSN=13510, MT Status=2, MTMSN=0 2023-09-13T22:10:50.049Z,1694643050.049 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T22:10:58.933Z,1694643058.933 [NAL9602](INFO): SBD MO Status=0, MOMSN=13510, MT Status=0, MTMSN=0 2023-09-13T22:10:58.933Z,1694643058.933 [NAL9602](INFO): No messages in MT queue 2023-09-13T22:11:29.639Z,1694643089.639 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-13T22:12:14.166Z,1694643134.166 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-13T22:12:14.167Z,1694643134.167 [Default:CheckIn:C.Wait] Stopped 2023-09-13T22:12:14.167Z,1694643134.167 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-13T22:12:14.167Z,1694643134.167 [Default:CheckIn:D] Running Loop=1 2023-09-13T22:12:14.574Z,1694643134.574 [Default:CheckIn:D] Stopped 2023-09-13T22:12:14.574Z,1694643134.574 [Default:CheckIn:E] Running Loop=1 2023-09-13T22:12:14.978Z,1694643134.978 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 260.000749 min 2023-09-13T22:12:14.978Z,1694643134.978 [Default:CheckIn:E] Stopped 2023-09-13T22:12:14.979Z,1694643134.979 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-13T22:12:14.979Z,1694643134.979 [Default:CheckIn] Stopped 2023-09-13T22:12:14.979Z,1694643134.979 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-13T22:12:14.979Z,1694643134.979 [Default:CheckIn](INFO): Running loop #47 2023-09-13T22:12:14.979Z,1694643134.979 [Default:CheckIn] Running Loop=47 2023-09-13T22:12:14.979Z,1694643134.979 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-13T22:12:14.979Z,1694643134.979 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-13T22:12:16.993Z,1694643136.993 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221216.00,A,3648.16784,N,12147.27908,W,0.156,0.00,130923,,,A*70 2023-09-13T22:12:16.995Z,1694643136.995 [NAL9602](INFO): GPS fix at 20230913T221216: (36.802797, -121.787985) 2023-09-13T22:12:17.008Z,1694643137.008 [Default:CheckIn:Read_GPS] Stopped 2023-09-13T22:12:17.008Z,1694643137.008 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-13T22:12:25.585Z,1694643145.585 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230913T175213/Courier0144.lzma 2023-09-13T22:12:26.588Z,1694643146.588 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Courier0144.lzma.bak 2023-09-13T22:12:26.588Z,1694643146.588 [DataOverHttps](INFO): SBD MOMSN=18767220 2023-09-13T22:12:44.856Z,1694643164.856 [NAL9602](INFO): SBD MO Status=0, MOMSN=13511, MT Status=0, MTMSN=0 2023-09-13T22:12:44.856Z,1694643164.856 [NAL9602](INFO): No messages in MT queue 2023-09-13T22:12:45.633Z,1694643165.633 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230913T175213/Express0145.lzma 2023-09-13T22:12:46.636Z,1694643166.636 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0145.lzma.bak 2023-09-13T22:12:46.636Z,1694643166.636 [DataOverHttps](INFO): SBD MOMSN=18767223 2023-09-13T22:12:50.130Z,1694643170.130 [Default:CheckIn:Read_Iridium] Stopped 2023-09-13T22:12:50.131Z,1694643170.131 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-13T22:12:50.131Z,1694643170.131 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-13T22:13:15.557Z,1694643195.557 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-13T22:17:50.711Z,1694643470.711 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-13T22:17:50.711Z,1694643470.711 [Default:CheckIn:C.Wait] Stopped 2023-09-13T22:17:50.711Z,1694643470.711 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-13T22:17:50.713Z,1694643470.713 [Default:CheckIn:D] Running Loop=1 2023-09-13T22:17:51.101Z,1694643471.101 [Default:CheckIn:D] Stopped 2023-09-13T22:17:51.102Z,1694643471.102 [Default:CheckIn:E] Running Loop=1 2023-09-13T22:17:51.512Z,1694643471.512 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 265.609538 min 2023-09-13T22:17:51.512Z,1694643471.512 [Default:CheckIn:E] Stopped 2023-09-13T22:17:51.512Z,1694643471.512 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-13T22:17:51.512Z,1694643471.512 [Default:CheckIn] Stopped 2023-09-13T22:17:51.512Z,1694643471.512 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-13T22:17:51.512Z,1694643471.512 [Default:CheckIn](INFO): Running loop #48 2023-09-13T22:17:51.512Z,1694643471.512 [Default:CheckIn] Running Loop=48 2023-09-13T22:17:51.512Z,1694643471.512 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-13T22:17:51.512Z,1694643471.512 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-13T22:17:53.513Z,1694643473.513 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221752.00,A,3648.16748,N,12147.28298,W,0.194,0.00,130923,,,A*76 2023-09-13T22:17:53.516Z,1694643473.516 [NAL9602](INFO): GPS fix at 20230913T221752: (36.802791, -121.788050) 2023-09-13T22:17:53.528Z,1694643473.528 [Default:CheckIn:Read_GPS] Stopped 2023-09-13T22:17:53.528Z,1694643473.528 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-13T22:18:01.897Z,1694643481.897 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230913T175213/Courier0147.lzma 2023-09-13T22:18:02.900Z,1694643482.900 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Courier0147.lzma.bak 2023-09-13T22:18:02.900Z,1694643482.900 [DataOverHttps](INFO): SBD MOMSN=18767228 2023-09-13T22:18:20.745Z,1694643500.745 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230913T175213/Express0148.lzma 2023-09-13T22:18:21.748Z,1694643501.748 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0148.lzma.bak 2023-09-13T22:18:21.748Z,1694643501.748 [DataOverHttps](INFO): SBD MOMSN=18767231 2023-09-13T22:18:25.483Z,1694643505.483 [Default:CheckIn:Read_Iridium] Stopped 2023-09-13T22:18:25.483Z,1694643505.483 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-13T22:18:25.483Z,1694643505.483 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-13T22:19:53.912Z,1694643593.912 [NAL9602](INFO): SBD MO Status=0, MOMSN=13512, MT Status=0, MTMSN=0 2023-09-13T22:19:53.913Z,1694643593.913 [NAL9602](INFO): No messages in MT queue 2023-09-13T22:20:24.606Z,1694643624.606 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-13T22:20:53.562Z,1694643653.562 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.619392 2023-09-13T22:21:06.874Z,1694643666.874 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002950 2023-09-13T22:23:26.089Z,1694643806.089 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-13T22:23:26.089Z,1694643806.089 [Default:CheckIn:C.Wait] Stopped 2023-09-13T22:23:26.090Z,1694643806.090 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-13T22:23:26.090Z,1694643806.090 [Default:CheckIn:D] Running Loop=1 2023-09-13T22:23:26.481Z,1694643806.481 [Default:CheckIn:D] Stopped 2023-09-13T22:23:26.481Z,1694643806.481 [Default:CheckIn:E] Running Loop=1 2023-09-13T22:23:26.891Z,1694643806.891 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 271.199186 min 2023-09-13T22:23:26.891Z,1694643806.891 [Default:CheckIn:E] Stopped 2023-09-13T22:23:26.892Z,1694643806.892 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-13T22:23:26.892Z,1694643806.892 [Default:CheckIn] Stopped 2023-09-13T22:23:26.892Z,1694643806.892 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-13T22:23:26.892Z,1694643806.892 [Default:CheckIn](INFO): Running loop #49 2023-09-13T22:23:26.892Z,1694643806.892 [Default:CheckIn] Running Loop=49 2023-09-13T22:23:26.892Z,1694643806.892 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-13T22:23:26.892Z,1694643806.892 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-13T22:23:28.901Z,1694643808.901 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222328.00,A,3648.16592,N,12147.28277,W,0.389,0.00,130923,,,A*76 2023-09-13T22:23:28.903Z,1694643808.903 [NAL9602](INFO): GPS fix at 20230913T222328: (36.802765, -121.788046) 2023-09-13T22:23:28.915Z,1694643808.915 [Default:CheckIn:Read_GPS] Stopped 2023-09-13T22:23:28.915Z,1694643808.915 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-13T22:23:40.609Z,1694643820.609 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230913T175213/Courier0150.lzma 2023-09-13T22:23:41.612Z,1694643821.612 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Courier0150.lzma.bak 2023-09-13T22:23:41.612Z,1694643821.612 [DataOverHttps](INFO): SBD MOMSN=18767236 2023-09-13T22:23:47.025Z,1694643827.025 [NAL9602](INFO): SBD MO Status=2, MOMSN=13513, MT Status=2, MTMSN=0 2023-09-13T22:23:47.025Z,1694643827.025 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-13T22:24:01.901Z,1694643841.901 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20230913T175213/Express0151.lzma 2023-09-13T22:24:02.904Z,1694643842.904 [DataOverHttps](INFO): Moved sent file to Logs/20230913T175213/Express0151.lzma.bak 2023-09-13T22:24:02.904Z,1694643842.904 [DataOverHttps](IMPORTANT): SBD MOMSN=18767240, MTMSN=20230913T222402 2023-09-13T22:24:06.467Z,1694643846.467 [Default:CheckIn:Read_Iridium] Stopped 2023-09-13T22:24:06.467Z,1694643846.467 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-13T22:24:06.467Z,1694643846.467 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.