2020-02-10T22:47:34.778Z,1581374854.778 [DataOverHttps](INFO): Sending 174 bytes from file Logs/20200210T202907/Courier0049.lzma
2020-02-10T22:47:34.781Z,1581374854.781 [DataOverHttps](INFO): Received command:Restart logs
2020-02-10T22:47:34.784Z,1581374854.784 [CommandLine](IMPORTANT): got command restart logs
2020-02-10T22:47:38.792Z,1581374858.792 [DataOverHttps](INFO): Moved sent file to Logs/20200210T202907/Courier0049.lzma.bak
2020-02-10T22:47:38.793Z,1581374858.793 [DataOverHttps](INFO): SBD MOMSN=12244082
2020-02-10T22:47:47.991Z,1581374867.991 [BuoyancyServo](FAULT): Reading outside of valid range:0.000000

2020-02-10T22:47:47.991Z,1581374867.991 [BuoyancyServo](FAULT): Buoyancy engine reporting null position
2020-02-10T22:47:47.992Z,1581374867.992 [BuoyancyServo] Hardware Fault, FailCount= 1
2020-02-10T22:47:47.992Z,1581374867.992 [BuoyancyServo](ERROR): Hardware Fault
2020-02-10T22:47:47.996Z,1581374867.996 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
2020-02-10T22:47:49.995Z,1581374869.995 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-02-10T22:47:49.995Z,1581374869.995 [BuoyancyServo](INFO): Powering down
2020-02-10T22:47:50.800Z,1581374870.800 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2020-02-10T22:47:50.800Z,1581374870.800 [BuoyancyServo] No Fault, FailCount= 1
2020-02-10T22:47:51.186Z,1581374871.186 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-02-10T22:47:51.186Z,1581374871.186 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-02-10T22:47:58.955Z,1581374878.955 [BuoyancyServo](FAULT): Reading outside of valid range:0.000000

2020-02-10T22:47:58.955Z,1581374878.955 [BuoyancyServo](FAULT): Buoyancy engine reporting null position
2020-02-10T22:47:58.956Z,1581374878.956 [BuoyancyServo] Hardware Fault, FailCount= 1
2020-02-10T22:47:58.956Z,1581374878.956 [BuoyancyServo](ERROR): Hardware Fault
2020-02-10T22:47:58.960Z,1581374878.960 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
2020-02-10T22:48:00.878Z,1581374880.878 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-02-10T22:48:00.878Z,1581374880.878 [BuoyancyServo](INFO): Powering down
2020-02-10T22:48:01.665Z,1581374881.665 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2020-02-10T22:48:01.665Z,1581374881.665 [BuoyancyServo] No Fault, FailCount= 1
2020-02-10T22:48:01.749Z,1581374881.749 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200210T202907/Courier0052.lzma
2020-02-10T22:48:02.105Z,1581374882.105 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-02-10T22:48:02.112Z,1581374882.112 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-02-10T22:48:04.753Z,1581374884.753 [DataOverHttps](INFO): Moved sent file to Logs/20200210T202907/Courier0052.lzma.bak
2020-02-10T22:48:04.753Z,1581374884.753 [DataOverHttps](INFO): SBD MOMSN=12244085
2020-02-10T22:48:13.109Z,1581374893.109 [DefaultUnder:TrackAcousticContact:A.](INFO): *** querying acoustic contact ***
2020-02-10T22:48:13.424Z,1581374893.424 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2020-02-10T22:48:13.424Z,1581374893.424 [DUSBL_Hydroid](INFO): ****** received ping request ******
2020-02-10T22:48:13.425Z,1581374893.425 [DUSBL_Hydroid](INFO): Arming DUSBL. detectThresh: 75
2020-02-10T22:48:13.425Z,1581374893.425 [DUSBL_Hydroid](INFO): ****** ping requested ******
2020-02-10T22:48:13.829Z,1581374893.829 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,P
80,80,61,64
F,188,80,80,95,FF
2020-02-10T22:48:13.829Z,1581374893.829 [DUSBL_Hydroid](INFO): Command Ack
2020-02-10T22:48:13.829Z,1581374893.829 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2020-02-10T22:48:13.830Z,1581374893.830 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2020-02-10T22:48:14.273Z,1581374894.273 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2020-02-10T22:48:15.095Z,1581374895.095 [BuoyancyServo](ERROR): getPosition uart error serial timeout
2020-02-10T22:48:15.095Z,1581374895.095 [BuoyancyServo](FAULT): Buoyancy getPosition uart error.serial timeout
2020-02-10T22:48:15.095Z,1581374895.095 [BuoyancyServo] Communications Fault, FailCount= 1
2020-02-10T22:48:15.095Z,1581374895.095 [BuoyancyServo](ERROR): Communications Fault
2020-02-10T22:48:15.100Z,1581374895.100 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2020-02-10T22:48:15.158Z,1581374895.158 [Micromodem](INFO): Nmea in: $SNTTA,,,,,224814.35*51

2020-02-10T22:48:16.819Z,1581374896.819 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-02-10T22:48:16.819Z,1581374896.819 [BuoyancyServo](INFO): Powering down
2020-02-10T22:48:17.136Z,1581374897.136 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,N,80,80,61,64
F,188,80,80,95,FF
2020-02-10T22:48:17.136Z,1581374897.136 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,61,64
F,188,80,80,95,FF
2020-02-10T22:48:17.136Z,1581374897.136 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-02-10T22:48:17.639Z,1581374897.639 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2020-02-10T22:48:17.639Z,1581374897.639 [BuoyancyServo] No Fault, FailCount= 1
2020-02-10T22:48:18.010Z,1581374898.010 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-02-10T22:48:18.015Z,1581374898.015 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-02-10T22:48:33.358Z,1581374913.358 [DefaultUnder:Notify:A:A:A.Wait](INFO): Done Waiting.
2020-02-10T22:48:33.358Z,1581374913.358 [DefaultUnder:Notify:A:A:A.Wait] Stopped
2020-02-10T22:48:33.358Z,1581374913.358 [DefaultUnder:Notify:A:A:A.Wait](DEBUG): Uninitialize Wait Component.
2020-02-10T22:48:33.358Z,1581374913.358 [DefaultUnder:Notify:A:A:B] Running Loop=1
2020-02-10T22:48:33.788Z,1581374913.788 [DefaultUnder:Notify:A:A:B] Stopped
2020-02-10T22:48:33.789Z,1581374913.789 [DefaultUnder:Notify:A:A:C] Running Loop=1
2020-02-10T22:48:34.162Z,1581374914.162 [DefaultUnder:Notify:A:A:C](IMPORTANT): Default Under mission has been running for 55.242285 min 
2020-02-10T22:48:34.162Z,1581374914.162 [DefaultUnder:Notify:A:A:C] Stopped
2020-02-10T22:48:34.163Z,1581374914.163 [DefaultUnder:Notify:A:A](INFO): Completed DefaultUnder:Notify:A:A
2020-02-10T22:48:34.179Z,1581374914.179 [DefaultUnder:Notify:A:A] Stopped
2020-02-10T22:48:34.179Z,1581374914.179 [DefaultUnder:Notify:A:A](DEBUG): Aggregate::uninitialize DefaultUnder:Notify:A:A
2020-02-10T22:48:34.179Z,1581374914.179 [DefaultUnder:Notify:A:A](INFO): Running loop #12
2020-02-10T22:48:34.179Z,1581374914.179 [DefaultUnder:Notify:A:A] Running Loop=12
2020-02-10T22:48:34.179Z,1581374914.179 [DefaultUnder:Notify:A:A](DEBUG): Aggregate::initialize DefaultUnder:Notify:A:A
2020-02-10T22:48:34.179Z,1581374914.179 [DefaultUnder:Notify:A:A:A.Wait] Running Loop=1
2020-02-10T22:48:34.180Z,1581374914.180 [DefaultUnder:Notify:A:A:A.Wait](DEBUG): Initialize Wait Component.
2020-02-10T22:48:40.107Z,1581374920.107 [DataOverHttps](INFO): Sending 339 bytes from file Logs/20200210T202907/Express0047.lzma
2020-02-10T22:48:50.143Z,1581374930.143 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2020-02-10T22:48:55.383Z,1581374935.383 [BuoyancyServo](FAULT): Reading outside of valid range:0.000000

2020-02-10T22:48:55.383Z,1581374935.383 [BuoyancyServo](FAULT): Buoyancy engine reporting null position
2020-02-10T22:48:55.383Z,1581374935.383 [BuoyancyServo] Hardware Fault, FailCount= 1
2020-02-10T22:48:55.383Z,1581374935.383 [BuoyancyServo](ERROR): Hardware Fault
2020-02-10T22:48:55.388Z,1581374935.388 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
2020-02-10T22:48:57.383Z,1581374937.383 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-02-10T22:48:57.383Z,1581374937.383 [BuoyancyServo](INFO): Powering down
2020-02-10T22:48:58.186Z,1581374938.186 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2020-02-10T22:48:58.186Z,1581374938.186 [BuoyancyServo] No Fault, FailCount= 1
2020-02-10T22:48:58.582Z,1581374938.582 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-02-10T22:48:58.583Z,1581374938.583 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-02-10T22:49:05.499Z,1581374945.499 [BuoyancyServo](FAULT): Reading outside of valid range:0.000000

2020-02-10T22:49:05.499Z,1581374945.499 [BuoyancyServo](FAULT): Buoyancy engine reporting null position
2020-02-10T22:49:05.499Z,1581374945.499 [BuoyancyServo] Hardware Fault, FailCount= 1
2020-02-10T22:49:05.499Z,1581374945.499 [BuoyancyServo](ERROR): Hardware Fault
2020-02-10T22:49:05.504Z,1581374945.504 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
2020-02-10T22:49:07.501Z,1581374947.501 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-02-10T22:49:07.501Z,1581374947.501 [BuoyancyServo](INFO): Powering down
2020-02-10T22:49:08.373Z,1581374948.373 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2020-02-10T22:49:08.373Z,1581374948.373 [BuoyancyServo] No Fault, FailCount= 1
2020-02-10T22:49:08.786Z,1581374948.786 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-02-10T22:49:08.791Z,1581374948.791 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-02-10T22:49:09.917Z,1581374949.917 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,  80.28,  74.63,  77.62,  75.96
2020-02-10T22:49:11.127Z,1581374951.127 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-10T22:49:11.127Z,1581374951.127 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20021016021572,35.0, -.0,1448.9,  0
2020-02-10T22:49:13.262Z,1581374953.262 [DefaultUnder:TrackAcousticContact:A.](INFO): *** querying acoustic contact ***
2020-02-10T22:49:13.620Z,1581374953.620 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2020-02-10T22:49:13.620Z,1581374953.620 [DUSBL_Hydroid](INFO): ****** received ping request ******
2020-02-10T22:49:13.620Z,1581374953.620 [DUSBL_Hydroid](INFO): Arming DUSBL. detectThresh: 75
2020-02-10T22:49:13.621Z,1581374953.621 [DUSBL_Hydroid](INFO): ****** ping requested ******
2020-02-10T22:49:14.020Z,1581374954.020 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,P
80,80,61,64
F,188,80,80,95,FF
2020-02-10T22:49:14.020Z,1581374954.020 [DUSBL_Hydroid](INFO): Command Ack
2020-02-10T22:49:14.020Z,1581374954.020 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2020-02-10T22:49:14.022Z,1581374954.022 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2020-02-10T22:49:14.427Z,1581374954.427 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2020-02-10T22:49:15.241Z,1581374955.241 [Micromodem](INFO): Nmea in: $SNTTA,,,,,224914.54*57

2020-02-10T22:49:17.660Z,1581374957.660 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,N,80,80,61,64
F,188,80,80,95,FF
2020-02-10T22:49:17.660Z,1581374957.660 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,61,64
F,188,80,80,95,FF
2020-02-10T22:49:17.660Z,1581374957.660 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-02-10T22:49:18.583Z,1581374958.583 [BuoyancyServo](FAULT): Reading outside of valid range:0.000000

2020-02-10T22:49:18.583Z,1581374958.583 [BuoyancyServo](FAULT): Buoyancy engine reporting null position
2020-02-10T22:49:18.583Z,1581374958.583 [BuoyancyServo] Hardware Fault, FailCount= 1
2020-02-10T22:49:18.583Z,1581374958.583 [BuoyancyServo](ERROR): Hardware Fault
2020-02-10T22:49:18.588Z,1581374958.588 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
2020-02-10T22:49:19.276Z,1581374959.276 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.351315
2020-02-10T22:49:20.507Z,1581374960.507 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-02-10T22:49:20.507Z,1581374960.507 [BuoyancyServo](INFO): Powering down
2020-02-10T22:49:21.306Z,1581374961.306 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2020-02-10T22:49:21.306Z,1581374961.306 [BuoyancyServo] No Fault, FailCount= 1
2020-02-10T22:49:21.801Z,1581374961.801 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-02-10T22:49:21.802Z,1581374961.802 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-02-10T22:49:25.763Z,1581374965.763 [RDI_Pathfinder](ERROR): only read 1 of 4 data items
2020-02-10T22:49:25.763Z,1581374965.763 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,    +2    +0,A
2020-02-10T22:49:45.655Z,1581374985.655 [BuoyancyServo](FAULT): Reading outside of valid range:0.000000

2020-02-10T22:49:45.656Z,1581374985.656 [BuoyancyServo](FAULT): Buoyancy engine reporting null position
2020-02-10T22:49:45.656Z,1581374985.656 [BuoyancyServo] Hardware Fault, FailCount= 1
2020-02-10T22:49:45.656Z,1581374985.656 [BuoyancyServo](ERROR): Hardware Fault
2020-02-10T22:49:45.660Z,1581374985.660 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
2020-02-10T22:49:47.608Z,1581374987.608 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-02-10T22:49:47.608Z,1581374987.608 [BuoyancyServo](INFO): Powering down
2020-02-10T22:49:48.592Z,1581374988.592 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2020-02-10T22:49:48.592Z,1581374988.592 [BuoyancyServo] No Fault, FailCount= 1
2020-02-10T22:49:48.876Z,1581374988.876 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-02-10T22:49:48.883Z,1581374988.883 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-02-10T22:50:12.343Z,1581375012.343 [BuoyancyServo](FAULT): Reading outside of valid range:0.000000

2020-02-10T22:50:12.343Z,1581375012.343 [BuoyancyServo](FAULT): Buoyancy engine reporting null position
2020-02-10T22:50:12.343Z,1581375012.343 [BuoyancyServo] Hardware Fault, FailCount= 1
2020-02-10T22:50:12.343Z,1581375012.343 [BuoyancyServo](ERROR): Hardware Fault
2020-02-10T22:50:12.388Z,1581375012.388 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
2020-02-10T22:50:13.528Z,1581375013.528 [DefaultUnder:TrackAcousticContact:A.](INFO): *** querying acoustic contact ***
2020-02-10T22:50:13.882Z,1581375013.882 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2020-02-10T22:50:13.882Z,1581375013.882 [DUSBL_Hydroid](INFO): ****** received ping request ******
2020-02-10T22:50:13.882Z,1581375013.882 [DUSBL_Hydroid](INFO): Arming DUSBL. detectThresh: 75
2020-02-10T22:50:13.882Z,1581375013.882 [DUSBL_Hydroid](INFO): ****** ping requested ******
2020-02-10T22:50:14.288Z,1581375014.288 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,P
80,80,61,64
F,188,80,80,95,FF
2020-02-10T22:50:14.288Z,1581375014.288 [DUSBL_Hydroid](INFO): Command Ack
2020-02-10T22:50:14.288Z,1581375014.288 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2020-02-10T22:50:14.290Z,1581375014.290 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2020-02-10T22:50:14.426Z,1581375014.426 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-02-10T22:50:14.426Z,1581375014.426 [BuoyancyServo](INFO): Powering down
2020-02-10T22:50:14.689Z,1581375014.689 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2020-02-10T22:50:14.761Z,1581375014.761 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-02-10T22:50:14.761Z,1581375014.761 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,   +11,   -1,A
2020-02-10T22:50:15.202Z,1581375015.202 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2020-02-10T22:50:15.203Z,1581375015.203 [BuoyancyServo] No Fault, FailCount= 1
2020-02-10T22:50:15.501Z,1581375015.501 [Micromodem](INFO): Nmea in: $SNTTA,,,,,225014.80*56

2020-02-10T22:50:15.588Z,1581375015.588 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-02-10T22:50:15.611Z,1581375015.611 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-02-10T22:50:17.922Z,1581375017.922 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,N,80,80,61,64
F,188,80,80,95,FF
2020-02-10T22:50:17.922Z,1581375017.922 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,61,64
F,188,80,80,95,FF
2020-02-10T22:50:17.922Z,1581375017.922 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-02-10T22:50:24.840Z,1581375024.840 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-02-10T22:50:24.843Z,1581375024.843 [BPC1](INFO): Received data from all battery sticks.
2020-02-10T22:50:25.271Z,1581375025.271 [BuoyancyServo](FAULT): Reading outside of valid range:0.000000

2020-02-10T22:50:25.272Z,1581375025.272 [BuoyancyServo](FAULT): Buoyancy engine reporting null position
2020-02-10T22:50:25.272Z,1581375025.272 [BuoyancyServo] Hardware Fault, FailCount= 1
2020-02-10T22:50:25.272Z,1581375025.272 [BuoyancyServo](ERROR): Hardware Fault
2020-02-10T22:50:25.276Z,1581375025.276 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
2020-02-10T22:50:26.107Z,1581375026.107 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2020-02-10T22:50:27.251Z,1581375027.251 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-02-10T22:50:27.251Z,1581375027.251 [BuoyancyServo](INFO): Powering down
2020-02-10T22:50:28.079Z,1581375028.079 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2020-02-10T22:50:28.079Z,1581375028.079 [BuoyancyServo] No Fault, FailCount= 1
2020-02-10T22:50:28.452Z,1581375028.452 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-02-10T22:50:28.453Z,1581375028.453 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-02-10T22:50:34.146Z,1581375034.146 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-10T22:50:34.147Z,1581375034.147 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,200210160338720.1,   0.0,1448.9,  0
2020-02-10T22:50:45.883Z,1581375045.883 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003078
2020-02-10T22:50:59.971Z,1581375059.971 [BuoyancyServo](ERROR): getPosition uart error serial timeout
2020-02-10T22:50:59.971Z,1581375059.971 [BuoyancyServo](FAULT): Buoyancy getPosition uart error.serial timeout
2020-02-10T22:50:59.971Z,1581375059.971 [BuoyancyServo] Communications Fault, FailCount= 1
2020-02-10T22:50:59.971Z,1581375059.971 [BuoyancyServo](ERROR): Communications Fault
2020-02-10T22:50:59.976Z,1581375059.976 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2020-02-10T22:51:01.707Z,1581375061.707 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-02-10T22:51:01.707Z,1581375061.707 [BuoyancyServo](INFO): Powering down
2020-02-10T22:51:02.504Z,1581375062.504 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2020-02-10T22:51:02.504Z,1581375062.504 [BuoyancyServo] No Fault, FailCount= 1
2020-02-10T22:51:02.912Z,1581375062.912 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-02-10T22:51:02.913Z,1581375062.913 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-02-10T22:51:13.565Z,1581375073.565 [DefaultUnder:TrackAcousticContact:A.](INFO): *** querying acoustic contact ***
2020-02-10T22:51:13.663Z,1581375073.663 [DataOverHttps](INFO): Sending 339 bytes from file Logs/20200210T202907/Express0047.lzma
2020-02-10T22:51:13.876Z,1581375073.876 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2020-02-10T22:51:13.876Z,1581375073.876 [DUSBL_Hydroid](INFO): ****** received ping request ******
2020-02-10T22:51:13.876Z,1581375073.876 [DUSBL_Hydroid](INFO): Arming DUSBL. detectThresh: 75
2020-02-10T22:51:13.877Z,1581375073.877 [DUSBL_Hydroid](INFO): ****** ping requested ******
2020-02-10T22:51:14.272Z,1581375074.272 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,P
80,80,61,64
F,188,80,80,95,FF
2020-02-10T22:51:14.272Z,1581375074.272 [DUSBL_Hydroid](INFO): Command Ack
2020-02-10T22:51:14.272Z,1581375074.272 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2020-02-10T22:51:14.274Z,1581375074.274 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2020-02-10T22:51:14.687Z,1581375074.687 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2020-02-10T22:51:15.496Z,1581375075.496 [Micromodem](INFO): Nmea in: $SNTTA,,,,,225114.79*51

2020-02-10T22:51:16.674Z,1581375076.674 [DataOverHttps](INFO): Moved sent file to Logs/20200210T202907/Express0047.lzma.bak
2020-02-10T22:51:16.674Z,1581375076.674 [DataOverHttps](INFO): SBD MOMSN=12244087
2020-02-10T22:51:17.904Z,1581375077.904 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,N,80,80,61,64
F,188,80,80,95,FF
2020-02-10T22:51:17.904Z,1581375077.904 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,61,64
F,188,80,80,95,FF
2020-02-10T22:51:17.904Z,1581375077.904 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-02-10T22:51:25.651Z,1581375085.651 [BuoyancyServo](FAULT): Reading outside of valid range:0.000000

2020-02-10T22:51:25.651Z,1581375085.651 [BuoyancyServo](FAULT): Buoyancy engine reporting null position
2020-02-10T22:51:25.651Z,1581375085.651 [BuoyancyServo] Hardware Fault, FailCount= 1
2020-02-10T22:51:25.651Z,1581375085.651 [BuoyancyServo](ERROR): Hardware Fault
2020-02-10T22:51:25.658Z,1581375085.658 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
2020-02-10T22:51:27.641Z,1581375087.641 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-02-10T22:51:27.641Z,1581375087.641 [BuoyancyServo](INFO): Powering down
2020-02-10T22:51:28.445Z,1581375088.445 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2020-02-10T22:51:28.445Z,1581375088.445 [BuoyancyServo] No Fault, FailCount= 1
2020-02-10T22:51:28.864Z,1581375088.864 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-02-10T22:51:28.865Z,1581375088.865 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-02-10T22:51:37.303Z,1581375097.303 [DataOverHttps](INFO): Sending 314 bytes from file Logs/20200210T202907/Express0050.lzma
2020-02-10T22:51:38.575Z,1581375098.575 [BuoyancyServo](FAULT): Reading outside of valid range:0.000000

2020-02-10T22:51:38.575Z,1581375098.575 [BuoyancyServo](FAULT): Buoyancy engine reporting null position
2020-02-10T22:51:38.575Z,1581375098.575 [BuoyancyServo] Hardware Fault, FailCount= 1
2020-02-10T22:51:38.575Z,1581375098.575 [BuoyancyServo](ERROR): Hardware Fault
2020-02-10T22:51:38.619Z,1581375098.619 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
2020-02-10T22:51:39.309Z,1581375099.309 [DataOverHttps](INFO): Moved sent file to Logs/20200210T202907/Express0050.lzma.bak
2020-02-10T22:51:39.310Z,1581375099.310 [DataOverHttps](INFO): SBD MOMSN=12244104
2020-02-10T22:51:40.657Z,1581375100.657 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-02-10T22:51:40.657Z,1581375100.657 [BuoyancyServo](INFO): Powering down
2020-02-10T22:51:41.435Z,1581375101.435 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2020-02-10T22:51:41.435Z,1581375101.435 [BuoyancyServo] No Fault, FailCount= 1
2020-02-10T22:51:41.854Z,1581375101.854 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-02-10T22:51:41.855Z,1581375101.855 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-02-10T22:51:57.787Z,1581375117.787 [DataOverHttps](INFO): Sending 275 bytes from file Logs/20200210T202907/Express0053.lzma
2020-02-10T22:51:59.797Z,1581375119.797 [DataOverHttps](INFO): Moved sent file to Logs/20200210T202907/Express0053.lzma.bak
2020-02-10T22:51:59.797Z,1581375119.797 [DataOverHttps](INFO): SBD MOMSN=12244112
2020-02-10T22:52:01.669Z,1581375121.669 [DefaultUnder:uModemComms:B] Stopped
2020-02-10T22:52:01.669Z,1581375121.669 [DefaultUnder:uModemComms:ResumeTracking] Running Loop=1
2020-02-10T22:52:01.670Z,1581375121.670 [DefaultUnder:uModemComms:ResumeTracking](DEBUG): Aggregate::initialize DefaultUnder:uModemComms:ResumeTracking
2020-02-10T22:52:01.670Z,1581375121.670 [DefaultUnder:uModemComms:ResumeTracking:A] Running Loop=1
2020-02-10T22:52:02.092Z,1581375122.092 [DefaultUnder:uModemComms:ResumeTracking] Stopped
2020-02-10T22:52:02.092Z,1581375122.092 [DefaultUnder:uModemComms:ResumeTracking](DEBUG): Aggregate::uninitialize DefaultUnder:uModemComms:ResumeTracking
2020-02-10T22:52:02.092Z,1581375122.092 [DefaultUnder:uModemComms:ResumeTracking:A] Stopped
2020-02-10T22:52:02.093Z,1581375122.093 [DefaultUnder:uModemComms](INFO): Completed DefaultUnder:uModemComms
2020-02-10T22:52:02.093Z,1581375122.093 [DefaultUnder:uModemComms] Stopped
2020-02-10T22:52:02.093Z,1581375122.093 [DefaultUnder:uModemComms](DEBUG): Aggregate::uninitialize DefaultUnder:uModemComms
2020-02-10T22:52:02.480Z,1581375122.480 [DefaultUnder:Default:Docked:commsWhileDocked:A](INFO): Completed DefaultUnder:Default:Docked:commsWhileDocked:A
2020-02-10T22:52:02.480Z,1581375122.480 [DefaultUnder:Default:Docked:commsWhileDocked:A] Stopped
2020-02-10T22:52:02.480Z,1581375122.480 [DefaultUnder:Default:Docked:commsWhileDocked:A](DEBUG): Aggregate::uninitialize DefaultUnder:Default:Docked:commsWhileDocked:A
2020-02-10T22:52:02.480Z,1581375122.480 [DefaultUnder:Default:Docked:commsWhileDocked](INFO): Completed DefaultUnder:Default:Docked:commsWhileDocked
2020-02-10T22:52:02.480Z,1581375122.480 [DefaultUnder:Default:Docked:commsWhileDocked] Stopped
2020-02-10T22:52:02.480Z,1581375122.480 [DefaultUnder:Default:Docked:commsWhileDocked](DEBUG): Aggregate::uninitialize DefaultUnder:Default:Docked:commsWhileDocked
2020-02-10T22:52:13.805Z,1581375133.805 [DefaultUnder:TrackAcousticContact:A.](INFO): *** querying acoustic contact ***
2020-02-10T22:52:14.162Z,1581375134.162 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2020-02-10T22:52:14.162Z,1581375134.162 [DUSBL_Hydroid](INFO): ****** received ping request ******
2020-02-10T22:52:14.162Z,1581375134.162 [DUSBL_Hydroid](INFO): Arming DUSBL. detectThresh: 75
2020-02-10T22:52:14.163Z,1581375134.163 [DUSBL_Hydroid](INFO): ****** ping requested ******
2020-02-10T22:52:14.584Z,1581375134.584 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,P
80,80,61,64
F,188,80,80,95,FF
2020-02-10T22:52:14.584Z,1581375134.584 [DUSBL_Hydroid](INFO): Command Ack
2020-02-10T22:52:14.585Z,1581375134.585 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2020-02-10T22:52:14.586Z,1581375134.586 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2020-02-10T22:52:14.983Z,1581375134.983 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2020-02-10T22:52:15.793Z,1581375135.793 [Micromodem](INFO): Nmea in: $SNTTA,,,,,225215.10*5C

2020-02-10T22:52:18.202Z,1581375138.202 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,N,80,80,61,64
F,188,80,80,95,FF
2020-02-10T22:52:18.202Z,1581375138.202 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,61,64
F,188,80,80,95,FF
2020-02-10T22:52:18.202Z,1581375138.202 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-02-10T22:52:37.650Z,1581375157.650 [DefaultUnder:Default:Docked:run:C.Docked](INFO): Detected possible state switch to DETACHED.
2020-02-10T22:53:14.136Z,1581375194.136 [DefaultUnder:TrackAcousticContact:A.](INFO): *** querying acoustic contact ***
2020-02-10T22:53:14.496Z,1581375194.496 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2020-02-10T22:53:14.496Z,1581375194.496 [DUSBL_Hydroid](INFO): ****** received ping request ******
2020-02-10T22:53:14.496Z,1581375194.496 [DUSBL_Hydroid](INFO): Arming DUSBL. detectThresh: 75
2020-02-10T22:53:14.497Z,1581375194.497 [DUSBL_Hydroid](INFO): ****** ping requested ******
2020-02-10T22:53:14.915Z,1581375194.915 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,P
80,80,61,64
F,188,80,80,95,FF
2020-02-10T22:53:14.915Z,1581375194.915 [DUSBL_Hydroid](INFO): Command Ack
2020-02-10T22:53:14.915Z,1581375194.915 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2020-02-10T22:53:14.917Z,1581375194.917 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2020-02-10T22:53:15.303Z,1581375195.303 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2020-02-10T22:53:16.125Z,1581375196.125 [Micromodem](INFO): Nmea in: $SNTTA,,,,,225315.43*5B

2020-02-10T22:53:18.532Z,1581375198.532 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,N,80,80,61,64
F,188,80,80,95,FF
2020-02-10T22:53:18.532Z,1581375198.532 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,61,64
F,188,80,80,95,FF
2020-02-10T22:53:18.532Z,1581375198.532 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-02-10T22:53:30.911Z,1581375210.911 [DDM](FAULT): failed to deactivate IR emitter.
2020-02-10T22:53:30.911Z,1581375210.911 [DDM] Hardware Fault, FailCount= 1
2020-02-10T22:53:30.911Z,1581375210.911 [DDM](ERROR): Hardware Fault
2020-02-10T22:53:31.111Z,1581375211.111 [CBIT](ERROR): Hardware Fault in component: DDM
2020-02-10T22:53:31.284Z,1581375211.284 [DDM](INFO): Powering down
2020-02-10T22:53:32.166Z,1581375212.166 [CBIT](INFO): Clearing failed state for component DDM
2020-02-10T22:53:32.166Z,1581375212.166 [DDM] No Fault, FailCount= 1
2020-02-10T22:53:34.635Z,1581375214.635 [DefaultUnder:Notify:A:A:A.Wait](INFO): Done Waiting.
2020-02-10T22:53:34.635Z,1581375214.635 [DefaultUnder:Notify:A:A:A.Wait] Stopped
2020-02-10T22:53:34.635Z,1581375214.635 [DefaultUnder:Notify:A:A:A.Wait](DEBUG): Uninitialize Wait Component.
2020-02-10T22:53:34.635Z,1581375214.635 [DefaultUnder:Notify:A:A:B] Running Loop=1
2020-02-10T22:53:34.997Z,1581375214.997 [DefaultUnder:Notify:A:A:B] Stopped
2020-02-10T22:53:34.997Z,1581375214.997 [DefaultUnder:Notify:A:A:C] Running Loop=1
2020-02-10T22:53:35.398Z,1581375215.398 [DefaultUnder:Notify:A:A:C](IMPORTANT): Default Under mission has been running for 60.262419 min 
2020-02-10T22:53:35.398Z,1581375215.398 [DefaultUnder:Notify:A:A:C] Stopped
2020-02-10T22:53:35.398Z,1581375215.398 [DefaultUnder:Notify:A:A](INFO): Completed DefaultUnder:Notify:A:A
2020-02-10T22:53:35.398Z,1581375215.398 [DefaultUnder:Notify:A:A] Stopped
2020-02-10T22:53:35.398Z,1581375215.398 [DefaultUnder:Notify:A:A](DEBUG): Aggregate::uninitialize DefaultUnder:Notify:A:A
2020-02-10T22:53:35.398Z,1581375215.398 [DefaultUnder:Notify:A:A](INFO): Running loop #13
2020-02-10T22:53:35.399Z,1581375215.399 [DefaultUnder:Notify:A:A] Running Loop=13
2020-02-10T22:53:35.399Z,1581375215.399 [DefaultUnder:Notify:A:A](DEBUG): Aggregate::initialize DefaultUnder:Notify:A:A
2020-02-10T22:53:35.399Z,1581375215.399 [DefaultUnder:Notify:A:A:A.Wait] Running Loop=1
2020-02-10T22:53:35.399Z,1581375215.399 [DefaultUnder:Notify:A:A:A.Wait](DEBUG): Initialize Wait Component.
2020-02-10T22:53:36.570Z,1581375216.570 [DDM](INFO): Powering up
2020-02-10T22:53:36.570Z,1581375216.570 [DDM](DEBUG): Initializing DDM.
2020-02-10T22:54:01.673Z,1581375241.673 [DDM](FAULT): failed to initialize; deviceResponse_ loaded: , available: 0
2020-02-10T22:54:01.673Z,1581375241.673 [DDM] Communications Fault, FailCount= 2
2020-02-10T22:54:01.673Z,1581375241.673 [DDM](ERROR): Communications Fault
2020-02-10T22:54:01.767Z,1581375241.767 [CBIT](ERROR): Communications Fault in component: DDM
2020-02-10T22:54:02.076Z,1581375242.076 [DDM](INFO): Powering down
2020-02-10T22:54:03.012Z,1581375243.012 [CBIT](INFO): Clearing failed state for component DDM
2020-02-10T22:54:03.012Z,1581375243.012 [DDM] No Fault, FailCount= 2
2020-02-10T22:54:07.471Z,1581375247.471 [DDM](INFO): Powering up
2020-02-10T22:54:07.471Z,1581375247.471 [DDM](DEBUG): Initializing DDM.
2020-02-10T22:54:14.376Z,1581375254.376 [DefaultUnder:TrackAcousticContact:A.](INFO): *** querying acoustic contact ***
2020-02-10T22:54:14.764Z,1581375254.764 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2020-02-10T22:54:14.764Z,1581375254.764 [DUSBL_Hydroid](INFO): ****** received ping request ******
2020-02-10T22:54:14.764Z,1581375254.764 [DUSBL_Hydroid](INFO): Arming DUSBL. detectThresh: 75
2020-02-10T22:54:14.803Z,1581375254.803 [DUSBL_Hydroid](INFO): ****** ping requested ******
2020-02-10T22:54:14.803Z,1581375254.803 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,P
80,80,61,64
F,188,80,80,95,FF
2020-02-10T22:54:14.803Z,1581375254.803 [DUSBL_Hydroid](INFO): Command Ack
2020-02-10T22:54:14.803Z,1581375254.803 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2020-02-10T22:54:14.805Z,1581375254.805 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2020-02-10T22:54:15.149Z,1581375255.149 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2020-02-10T22:54:16.361Z,1581375256.361 [Micromodem](INFO): Nmea in: $SNTTA,,,,,225415.31*59

2020-02-10T22:54:18.388Z,1581375258.388 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,N,80,80,61,64
F,188,80,80,95,FF
2020-02-10T22:54:18.388Z,1581375258.388 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,61,64
F,188,80,80,95,FF
2020-02-10T22:54:18.388Z,1581375258.388 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-02-10T22:54:25.754Z,1581375265.754 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-02-10T22:54:25.754Z,1581375265.754 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,    +9,    +0,
2020-02-10T22:54:32.524Z,1581375272.524 [DDM](FAULT): failed to initialize; deviceResponse_ loaded: , available: 0
2020-02-10T22:54:32.524Z,1581375272.524 [DDM] Communications Fault, FailCount= 3
2020-02-10T22:54:32.525Z,1581375272.525 [DDM](ERROR): Communications Fault
2020-02-10T22:54:32.707Z,1581375272.707 [CBIT](ERROR): Communications Fault in component: DDM
2020-02-10T22:54:32.708Z,1581375272.708 [CBIT](CRITICAL): Communications Fault in component: DDM
2020-02-10T22:54:32.925Z,1581375272.925 [DDM](INFO): Powering down
2020-02-10T22:54:32.991Z,1581375272.991 [CBIT](INFO): Critical error at 20200210T225432
2020-02-10T22:54:32.991Z,1581375272.991 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-02-10T22:54:45.007Z,1581375285.007 [BuoyancyServo](ERROR): getPosition uart error serial timeout
2020-02-10T22:54:45.007Z,1581375285.007 [BuoyancyServo](FAULT): Buoyancy getPosition uart error.serial timeout
2020-02-10T22:54:45.007Z,1581375285.007 [BuoyancyServo] Communications Fault, FailCount= 1
2020-02-10T22:54:45.007Z,1581375285.007 [BuoyancyServo](ERROR): Communications Fault
2020-02-10T22:54:45.012Z,1581375285.012 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2020-02-10T22:54:46.691Z,1581375286.691 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-02-10T22:54:46.697Z,1581375286.697 [BuoyancyServo](INFO): Powering down
2020-02-10T22:54:47.534Z,1581375287.534 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2020-02-10T22:54:47.534Z,1581375287.534 [BuoyancyServo] No Fault, FailCount= 1
2020-02-10T22:54:47.932Z,1581375287.932 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-02-10T22:54:47.939Z,1581375287.939 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-02-10T22:55:10.552Z,1581375310.552 [BuoyancyServo](FAULT): Reading outside of valid range:0.000000

2020-02-10T22:55:10.552Z,1581375310.552 [BuoyancyServo](FAULT): Buoyancy engine reporting null position
2020-02-10T22:55:10.552Z,1581375310.552 [BuoyancyServo] Hardware Fault, FailCount= 1
2020-02-10T22:55:10.552Z,1581375310.552 [BuoyancyServo](ERROR): Hardware Fault
2020-02-10T22:55:10.557Z,1581375310.557 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
2020-02-10T22:55:12.563Z,1581375312.563 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-02-10T22:55:12.563Z,1581375312.563 [BuoyancyServo](INFO): Powering down
2020-02-10T22:55:13.378Z,1581375313.378 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2020-02-10T22:55:13.378Z,1581375313.378 [BuoyancyServo] No Fault, FailCount= 1
2020-02-10T22:55:13.805Z,1581375313.805 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-02-10T22:55:13.806Z,1581375313.806 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-02-10T22:55:14.566Z,1581375314.566 [DefaultUnder:TrackAcousticContact:A.](INFO): *** querying acoustic contact ***
2020-02-10T22:55:14.948Z,1581375314.948 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2020-02-10T22:55:14.948Z,1581375314.948 [DUSBL_Hydroid](INFO): ****** received ping request ******
2020-02-10T22:55:14.948Z,1581375314.948 [DUSBL_Hydroid](INFO): Arming DUSBL. detectThresh: 75
2020-02-10T22:55:14.975Z,1581375314.975 [DUSBL_Hydroid](INFO): ****** ping requested ******
2020-02-10T22:55:14.975Z,1581375314.975 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,P
80,80,61,64
F,188,80,80,95,FF
2020-02-10T22:55:14.976Z,1581375314.976 [DUSBL_Hydroid](INFO): Command Ack
2020-02-10T22:55:14.976Z,1581375314.976 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2020-02-10T22:55:14.977Z,1581375314.977 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2020-02-10T22:55:15.349Z,1581375315.349 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2020-02-10T22:55:16.186Z,1581375316.186 [Micromodem](INFO): Nmea in: $SNTTA,,,,,225515.48*56

2020-02-10T22:55:18.633Z,1581375318.633 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,N,80,80,61,64
F,188,80,80,95,FF
2020-02-10T22:55:18.633Z,1581375318.633 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,61,64
F,188,80,80,95,FF
2020-02-10T22:55:18.633Z,1581375318.633 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-02-10T22:55:48.543Z,1581375348.543 [BuoyancyServo](FAULT): Reading outside of valid range:0.000000

2020-02-10T22:55:48.543Z,1581375348.543 [BuoyancyServo](FAULT): Buoyancy engine reporting null position
2020-02-10T22:55:48.543Z,1581375348.543 [BuoyancyServo] Hardware Fault, FailCount= 1
2020-02-10T22:55:48.543Z,1581375348.543 [BuoyancyServo](ERROR): Hardware Fault
2020-02-10T22:55:48.548Z,1581375348.548 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
2020-02-10T22:55:50.533Z,1581375350.533 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-02-10T22:55:50.533Z,1581375350.533 [BuoyancyServo](INFO): Powering down
2020-02-10T22:55:51.365Z,1581375351.365 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2020-02-10T22:55:51.365Z,1581375351.365 [BuoyancyServo] No Fault, FailCount= 1
2020-02-10T22:55:51.799Z,1581375351.799 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-02-10T22:55:51.800Z,1581375351.800 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-02-10T22:56:14.855Z,1581375374.855 [DefaultUnder:TrackAcousticContact:A.](INFO): *** querying acoustic contact ***
2020-02-10T22:56:15.172Z,1581375375.172 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2020-02-10T22:56:15.172Z,1581375375.172 [DUSBL_Hydroid](INFO): ****** received ping request ******
2020-02-10T22:56:15.172Z,1581375375.172 [DUSBL_Hydroid](INFO): Arming DUSBL. detectThresh: 75
2020-02-10T22:56:15.183Z,1581375375.183 [DUSBL_Hydroid](INFO): ****** ping requested ******
2020-02-10T22:56:15.592Z,1581375375.592 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,P
80,80,61,64
F,188,80,80,95,FF
2020-02-10T22:56:15.592Z,1581375375.592 [DUSBL_Hydroid](INFO): Command Ack
2020-02-10T22:56:15.593Z,1581375375.593 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2020-02-10T22:56:15.594Z,1581375375.594 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2020-02-10T22:56:15.991Z,1581375375.991 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2020-02-10T22:56:16.797Z,1581375376.797 [Micromodem](INFO): Nmea in: $SNTTA,,,,,225616.10*5B

2020-02-10T22:56:19.212Z,1581375379.212 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,N,80,80,61,64
F,188,80,80,95,FF
2020-02-10T22:56:19.212Z,1581375379.212 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,61,64
F,188,80,80,95,FF
2020-02-10T22:56:19.212Z,1581375379.212 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-02-10T22:56:21.584Z,1581375381.584 [DataOverHttps](IMPORTANT): SBD MTMSN=20200210T225610
2020-02-10T22:56:27.762Z,1581375387.762 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-02-10T22:56:27.762Z,1581375387.762 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,   -10,   -12,
2020-02-10T22:56:30.514Z,1581375390.514 [DataOverHttps](INFO): Received command:run Default.xml
2020-02-10T22:56:30.583Z,1581375390.583 [CommandLine](IMPORTANT): got command run ./Missions/Default.xml
2020-02-10T22:56:30.584Z,1581375390.584 [MissionManager](INFO): Loading Mission: ./Missions/Default.xml
2020-02-10T22:56:30.632Z,1581375390.632 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-02-10T22:56:30.633Z,1581375390.633 [Default:A.Wait](DEBUG): Construct Wait.
2020-02-10T22:56:30.635Z,1581375390.635 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-02-10T22:56:30.660Z,1581375390.660 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-02-10T22:56:30.662Z,1581375390.662 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-02-10T22:56:30.674Z,1581375390.674 [Default:E.Execute](DEBUG): Construct Execute.
2020-02-10T22:56:30.678Z,1581375390.678 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2020-02-10T22:56:30.679Z,1581375390.679 [CommandLine](IMPORTANT): Running ./Missions/Default.xml
2020-02-10T22:56:30.962Z,1581375390.962 [DefaultUnder] Stopped
2020-02-10T22:56:30.962Z,1581375390.962 [DefaultUnder](DEBUG): Aggregate::uninitialize DefaultUnder
2020-02-10T22:56:30.962Z,1581375390.962 [DefaultUnder:C.PitchEnvelope] Stopped
2020-02-10T22:56:30.962Z,1581375390.962 [DefaultUnder:C.PitchEnvelope](DEBUG): Uninitialize PitchEnvelope.
2020-02-10T22:56:30.962Z,1581375390.962 [DefaultUnder:Notify] Stopped
2020-02-10T22:56:30.962Z,1581375390.962 [DefaultUnder:Notify](DEBUG): Aggregate::uninitialize DefaultUnder:Notify
2020-02-10T22:56:30.962Z,1581375390.962 [DefaultUnder:Notify:A] Stopped
2020-02-10T22:56:30.962Z,1581375390.962 [DefaultUnder:Notify:A](DEBUG): Aggregate::uninitialize DefaultUnder:Notify:A
2020-02-10T22:56:30.963Z,1581375390.963 [DefaultUnder:Notify:A:A] Stopped
2020-02-10T22:56:30.971Z,1581375390.971 [DefaultUnder:Notify:A:A](DEBUG): Aggregate::uninitialize DefaultUnder:Notify:A:A
2020-02-10T22:56:30.971Z,1581375390.971 [DefaultUnder:Notify:A:A:A.Wait] Stopped
2020-02-10T22:56:30.971Z,1581375390.971 [DefaultUnder:Notify:A:A:A.Wait](DEBUG): Uninitialize Wait Component.
2020-02-10T22:56:30.971Z,1581375390.971 [DefaultUnder:TrackAcousticContact] Stopped
2020-02-10T22:56:30.971Z,1581375390.971 [DefaultUnder:TrackAcousticContact](DEBUG): Aggregate::uninitialize DefaultUnder:TrackAcousticContact
2020-02-10T22:56:30.971Z,1581375390.971 [DefaultUnder:TrackAcousticContact:A.] Stopped
2020-02-10T22:56:30.971Z,1581375390.971 [DefaultUnder:Default] Stopped
2020-02-10T22:56:30.971Z,1581375390.971 [DefaultUnder:Default](DEBUG): Aggregate::uninitialize DefaultUnder:Default
2020-02-10T22:56:30.972Z,1581375390.972 [DefaultUnder:Default:Docked] Stopped
2020-02-10T22:56:30.972Z,1581375390.972 [DefaultUnder:Default:Docked](DEBUG): Aggregate::uninitialize DefaultUnder:Default:Docked
2020-02-10T22:56:30.972Z,1581375390.972 [DefaultUnder:Default:Docked:run] Stopped
2020-02-10T22:56:30.972Z,1581375390.972 [DefaultUnder:Default:Docked:run](DEBUG): Aggregate::uninitialize DefaultUnder:Default:Docked:run
2020-02-10T22:56:30.972Z,1581375390.972 [DefaultUnder:Default:Docked:run:C.Docked] Stopped
2020-02-10T22:56:30.972Z,1581375390.972 [MissionManager](IMPORTANT): Started mission Default
2020-02-10T22:56:30.972Z,1581375390.972 [Default] Running Loop=1
2020-02-10T22:56:30.972Z,1581375390.972 [Default](DEBUG): Aggregate::initialize Default
2020-02-10T22:56:30.972Z,1581375390.972 [Default:B.GoToSurface] Running Loop=1
2020-02-10T22:56:30.972Z,1581375390.972 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-02-10T22:56:30.973Z,1581375390.973 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-02-10T22:56:30.973Z,1581375390.973 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-02-10T22:56:30.973Z,1581375390.973 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-02-10T22:56:30.974Z,1581375390.974 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-02-10T22:56:30.974Z,1581375390.974 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-02-10T22:56:30.974Z,1581375390.974 [Default:A.Wait] Running Loop=1
2020-02-10T22:56:30.974Z,1581375390.974 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-02-10T22:56:44.346Z,1581375404.346 [Default:A.Wait](INFO): Done Waiting.
2020-02-10T22:56:44.346Z,1581375404.346 [Default:A.Wait] Stopped
2020-02-10T22:56:44.346Z,1581375404.346 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-02-10T22:56:44.795Z,1581375404.795 [Default:CheckIn] Running Loop=1
2020-02-10T22:56:44.795Z,1581375404.795 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-10T22:56:44.796Z,1581375404.796 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-10T22:56:45.146Z,1581375405.146 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-02-10T22:56:46.799Z,1581375406.799 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,225636.00,A,4450.83587,N,06734.83041,W,0.058,2.98,100220,,,D*7C
2020-02-10T22:56:46.801Z,1581375406.801 [NAL9602](INFO): GPS fix at 20200210T225636: (44.847265, -67.580507)
2020-02-10T22:56:46.859Z,1581375406.859 [Default:CheckIn:Read_GPS] Stopped
2020-02-10T22:56:46.859Z,1581375406.859 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-10T22:56:47.179Z,1581375407.179 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-02-10T22:56:54.759Z,1581375414.759 [DataOverHttps](INFO): Sending 364 bytes from file Logs/20200210T224734/Courier0003.lzma
2020-02-10T22:56:56.767Z,1581375416.767 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Courier0003.lzma.bak
2020-02-10T22:56:56.768Z,1581375416.768 [DataOverHttps](INFO): SBD MOMSN=12244122
2020-02-10T22:57:12.191Z,1581375432.191 [NAL9602](INFO): SBD MO Status=0, MOMSN=10668, MT Status=0, MTMSN=0
2020-02-10T22:57:12.191Z,1581375432.191 [NAL9602](INFO): No messages in MT queue
2020-02-10T22:57:15.532Z,1581375435.532 [DataOverHttps](INFO): Sending 771 bytes from file Logs/20200210T224734/Express0004.lzma
2020-02-10T22:57:17.537Z,1581375437.537 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Express0004.lzma.bak
2020-02-10T22:57:17.537Z,1581375437.537 [DataOverHttps](INFO): SBD MOMSN=12244127
2020-02-10T22:57:35.346Z,1581375455.346 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200210T224734/Express0007.lzma
2020-02-10T22:57:37.352Z,1581375457.352 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Express0007.lzma.bak
2020-02-10T22:57:37.353Z,1581375457.353 [DataOverHttps](INFO): SBD MOMSN=12244166
2020-02-10T22:57:39.307Z,1581375459.307 [Default:CheckIn:Read_Iridium] Stopped
2020-02-10T22:57:39.307Z,1581375459.307 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-10T22:57:39.307Z,1581375459.307 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-10T22:57:42.897Z,1581375462.897 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-10T22:59:32.859Z,1581375572.859 [CBIT](INFO): Clearing failed state for component DDM
2020-02-10T22:59:32.860Z,1581375572.860 [DDM] No Fault, FailCount= 3
2020-02-10T22:59:33.185Z,1581375573.185 [DDM](INFO): Powering up
2020-02-10T22:59:33.185Z,1581375573.185 [DDM](DEBUG): Initializing DDM.
2020-02-10T22:59:58.234Z,1581375598.234 [DDM](FAULT): failed to initialize; deviceResponse_ loaded: !V1,H_RECOVERY_LATCH
!V2,H_RECOVERY_WHISKERS
!V1,H_RECOVERY_LATCH
!V2,H_RECOVERY_WHISKERS
!V1,H_RECOVERY_LATCH
!V2,H_RECOVERY_WHISKERS
!V1,H_RECOVERY_LATCH
!V2,H_RECOVERY_WHISKERS
!V1,H_RECOVERY_LATCH
!V2,H_RECOVERY_WHISKERS
!V1,H_RECOVERY_LATCH
COVERY_WHISKERS
!V1,H_RECOVERY_LATCH
!V2,H_RECOVERY_WHISKERS
!V1,H_RECOVERY_LATCH
!V2,H_RECOVERY_WHISKERS
!V1,H_RECOVERY_LATCH
!V2,H_RECOVERY_WHISKERS
!V1,H_RECOVERY_LATCH
!V2,H_RECOVERY_WHISKERS
!V1,H_RECOVERY_LATCH
!V2,H_RECOVERY_WHISKERS
!V1,H_RECOVERY_LATCH
!V2,H_RECOVERY_WHISKERS
!V1,H_RECOVERY_LATCH
!V2,H_RECOVERY_WHISKERS
!V1,H_RECOVERY_LATCH
!V2,H_RECOVERY_WHISKERS
!V1,H_RECOVERY_LATCH
!V2,H_RECOVERY_WHISKERS
!V1,H_RECOVERY_LATCH
!V2,H_RECOVERY_WHISKERS
!V1,H_RECOVERY_LATCH
!V2,H_RECOVERY_WHISKERS
!V1,H_RECOVERY_LATCH
!V2,H_RECOVERY_WHISKERS
!V1,H_RECOVERY_LATCH
!V2,H_RECOVERY_WHISKERS
!V1,H_RECOVERY_LATCH
!V2,H_RECOVERY_WHISKERS
!V1,H_RECOVERY_LATCH
!V2,H_RECOVERY_WHISKERS
!V1,H_RECOVERY_LATCH
!V2,H_RECOVERY_WHISKERS
!V1,H_RECOVERY_LATCH
!V2,H_RECOVERY_WHISKERS
!V1,H_RECOVERY_LATCH
!V2,H_RECOVERY_WHISKERS
;PŽ?, available: 430
2020-02-10T22:59:58.234Z,1581375598.234 [DDM] Communications Fault, FailCount= 1
2020-02-10T22:59:58.234Z,1581375598.234 [DDM](ERROR): Communications Fault
2020-02-10T22:59:58.343Z,1581375598.343 [CBIT](ERROR): Communications Fault in component: DDM
2020-02-10T22:59:58.639Z,1581375598.639 [DDM](INFO): Powering down
2020-02-10T22:59:59.494Z,1581375599.494 [CBIT](INFO): Clearing failed state for component DDM
2020-02-10T22:59:59.494Z,1581375599.494 [DDM] No Fault, FailCount= 1
2020-02-10T23:00:03.889Z,1581375603.889 [DDM](INFO): Powering up
2020-02-10T23:00:03.889Z,1581375603.889 [DDM](DEBUG): Initializing DDM.
2020-02-10T23:00:18.464Z,1581375618.464 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2020-02-10T23:00:19.645Z,1581375619.645 [DDM](INFO): Dynamic Docking Module:!C REMUS Capture Rev 1.1
2020-02-10T23:01:42.095Z,1581375702.095 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-10T23:01:42.095Z,1581375702.095 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   I,-32768,-32768,-32768,-32768,V
2020-02-10T23:02:39.884Z,1581375759.884 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-10T23:02:39.884Z,1581375759.884 [Default:CheckIn:C.Wait] Stopped
2020-02-10T23:02:39.885Z,1581375759.885 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-10T23:02:39.885Z,1581375759.885 [Default:CheckIn:D] Running Loop=1
2020-02-10T23:02:40.271Z,1581375760.271 [Default:CheckIn:D] Stopped
2020-02-10T23:02:40.271Z,1581375760.271 [Default:CheckIn:E] Running Loop=1
2020-02-10T23:02:40.689Z,1581375760.689 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.154974 min 
2020-02-10T23:02:40.690Z,1581375760.690 [Default:CheckIn:E] Stopped
2020-02-10T23:02:40.690Z,1581375760.690 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-10T23:02:40.690Z,1581375760.690 [Default:CheckIn] Stopped
2020-02-10T23:02:40.690Z,1581375760.690 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-10T23:02:40.691Z,1581375760.691 [Default:CheckIn](INFO): Running loop #2
2020-02-10T23:02:40.692Z,1581375760.692 [Default:CheckIn] Running Loop=2
2020-02-10T23:02:40.692Z,1581375760.692 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-10T23:02:40.692Z,1581375760.692 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-10T23:02:42.667Z,1581375762.667 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230232.00,A,4450.83601,N,06734.83247,W,0.039,2.98,100220,,,D*76
2020-02-10T23:02:42.671Z,1581375762.671 [NAL9602](INFO): GPS fix at 20200210T230232: (44.847267, -67.580541)
2020-02-10T23:02:42.739Z,1581375762.739 [Default:CheckIn:Read_GPS] Stopped
2020-02-10T23:02:42.743Z,1581375762.743 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-10T23:02:50.450Z,1581375770.450 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200210T224734/Courier0009.lzma
2020-02-10T23:02:52.456Z,1581375772.456 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Courier0009.lzma.bak
2020-02-10T23:02:52.457Z,1581375772.457 [DataOverHttps](INFO): SBD MOMSN=12244177
2020-02-10T23:02:52.572Z,1581375772.572 [CommandLine](IMPORTANT): got command failComponent hardware DDM
2020-02-10T23:02:52.572Z,1581375772.572 [DDM] Hardware Fault, FailCount= 2
2020-02-10T23:02:52.572Z,1581375772.572 [DDM](ERROR): Hardware Fault
2020-02-10T23:02:52.572Z,1581375772.572 [CommandLine](IMPORTANT): DDM failureMode is Hardware Fault
2020-02-10T23:02:52.760Z,1581375772.760 [DDM](INFO): Powering down
2020-02-10T23:02:52.914Z,1581375772.914 [CBIT](ERROR): Hardware Fault in component: DDM
2020-02-10T23:02:53.166Z,1581375773.166 [DDM](FAULT): LCB fault:  Software Overcurrent.
2020-02-10T23:02:53.660Z,1581375773.660 [CBIT](INFO): Clearing failed state for component DDM
2020-02-10T23:02:53.660Z,1581375773.660 [DDM] No Fault, FailCount= 2
2020-02-10T23:02:58.014Z,1581375778.014 [DDM](INFO): Powering up
2020-02-10T23:02:58.014Z,1581375778.014 [DDM](DEBUG): Initializing DDM.
2020-02-10T23:03:10.071Z,1581375790.071 [DataOverHttps](INFO): Sending 263 bytes from file Logs/20200210T224734/Express0010.lzma
2020-02-10T23:03:12.076Z,1581375792.076 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Express0010.lzma.bak
2020-02-10T23:03:12.077Z,1581375792.077 [DataOverHttps](INFO): SBD MOMSN=12244180
2020-02-10T23:03:13.769Z,1581375793.769 [DDM](INFO): Dynamic Docking Module:C REMUS Capture Rev 1.1
2020-02-10T23:03:13.947Z,1581375793.947 [Default:CheckIn:Read_Iridium] Stopped
2020-02-10T23:03:13.947Z,1581375793.947 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-10T23:03:13.947Z,1581375793.947 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-10T23:03:33.978Z,1581375813.978 [NAL9602](INFO): SBD MO Status=2, MOMSN=10669, MT Status=2, MTMSN=0
2020-02-10T23:03:33.979Z,1581375813.979 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-02-10T23:03:50.139Z,1581375830.139 [NAL9602](INFO): SBD MO Status=0, MOMSN=10669, MT Status=0, MTMSN=0
2020-02-10T23:03:50.139Z,1581375830.139 [NAL9602](INFO): No messages in MT queue
2020-02-10T23:04:20.841Z,1581375860.841 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-10T23:05:27.123Z,1581375927.123 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-10T23:05:27.123Z,1581375927.123 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,  77.96,  74.30,  78.95
2020-02-10T23:07:29.955Z,1581376049.955 [RDI_Pathfinder](ERROR): Failed to parse:
:B -2.74,      +235.98,      -47.54,   7.60,  0.00
2020-02-10T23:07:30.767Z,1581376050.767 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-02-10T23:07:30.767Z,1581376050.767 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,   +16,    +0,    -,   +16,    +0,    -8,A
2020-02-10T23:08:14.419Z,1581376094.419 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-10T23:08:14.419Z,1581376094.419 [Default:CheckIn:C.Wait] Stopped
2020-02-10T23:08:14.419Z,1581376094.419 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-10T23:08:14.419Z,1581376094.419 [Default:CheckIn:D] Running Loop=1
2020-02-10T23:08:14.842Z,1581376094.842 [Default:CheckIn:D] Stopped
2020-02-10T23:08:14.843Z,1581376094.843 [Default:CheckIn:E] Running Loop=1
2020-02-10T23:08:15.211Z,1581376095.211 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.731170 min 
2020-02-10T23:08:15.212Z,1581376095.212 [Default:CheckIn:E] Stopped
2020-02-10T23:08:15.212Z,1581376095.212 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-10T23:08:15.212Z,1581376095.212 [Default:CheckIn] Stopped
2020-02-10T23:08:15.212Z,1581376095.212 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-10T23:08:15.212Z,1581376095.212 [Default:CheckIn](INFO): Running loop #3
2020-02-10T23:08:15.212Z,1581376095.212 [Default:CheckIn] Running Loop=3
2020-02-10T23:08:15.212Z,1581376095.212 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-10T23:08:15.212Z,1581376095.212 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-10T23:08:17.188Z,1581376097.188 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230806.00,A,4450.83629,N,06734.83092,W,0.039,295.46,100220,,,D*74
2020-02-10T23:08:17.190Z,1581376097.190 [NAL9602](INFO): GPS fix at 20200210T230806: (44.847271, -67.580515)
2020-02-10T23:08:17.256Z,1581376097.256 [Default:CheckIn:Read_GPS] Stopped
2020-02-10T23:08:17.256Z,1581376097.256 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-10T23:08:18.762Z,1581376098.762 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-02-10T23:08:18.762Z,1581376098.762 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,       -2.64,      +23   -47.65,   7.64,  0.00
2020-02-10T23:08:25.374Z,1581376105.374 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200210T224734/Courier0012.lzma
2020-02-10T23:08:27.380Z,1581376107.380 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Courier0012.lzma.bak
2020-02-10T23:08:27.380Z,1581376107.380 [DataOverHttps](INFO): SBD MOMSN=12244186
2020-02-10T23:08:34.086Z,1581376114.086 [NAL9602](INFO): SBD MO Status=0, MOMSN=10670, MT Status=0, MTMSN=0
2020-02-10T23:08:34.087Z,1581376114.087 [NAL9602](INFO): No messages in MT queue
2020-02-10T23:08:46.631Z,1581376126.631 [DataOverHttps](INFO): Sending 219 bytes from file Logs/20200210T224734/Express0013.lzma
2020-02-10T23:08:48.637Z,1581376128.637 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Express0013.lzma.bak
2020-02-10T23:08:48.637Z,1581376128.637 [DataOverHttps](INFO): SBD MOMSN=12244189
2020-02-10T23:08:50.743Z,1581376130.743 [Default:CheckIn:Read_Iridium] Stopped
2020-02-10T23:08:50.743Z,1581376130.743 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-10T23:08:50.744Z,1581376130.744 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-10T23:09:04.789Z,1581376144.789 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-10T23:11:17.781Z,1581376277.781 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,   +99,    -4,   +15,    -8,A
2020-02-10T23:13:16.128Z,1581376396.128 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-10T23:13:16.128Z,1581376396.128 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,200210165.0, -0.1,   0.0,1448.9,  0
2020-02-10T23:13:51.314Z,1581376431.314 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-10T23:13:51.314Z,1581376431.314 [Default:CheckIn:C.Wait] Stopped
2020-02-10T23:13:51.314Z,1581376431.314 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-10T23:13:51.314Z,1581376431.314 [Default:CheckIn:D] Running Loop=1
2020-02-10T23:13:51.705Z,1581376431.705 [Default:CheckIn:D] Stopped
2020-02-10T23:13:51.705Z,1581376431.705 [Default:CheckIn:E] Running Loop=1
2020-02-10T23:13:52.110Z,1581376432.110 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 17.345551 min 
2020-02-10T23:13:52.110Z,1581376432.110 [Default:CheckIn:E] Stopped
2020-02-10T23:13:52.110Z,1581376432.110 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-10T23:13:52.110Z,1581376432.110 [Default:CheckIn] Stopped
2020-02-10T23:13:52.110Z,1581376432.110 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-10T23:13:52.110Z,1581376432.110 [Default:CheckIn](INFO): Running loop #4
2020-02-10T23:13:52.110Z,1581376432.110 [Default:CheckIn] Running Loop=4
2020-02-10T23:13:52.110Z,1581376432.110 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-10T23:13:52.110Z,1581376432.110 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-10T23:13:54.095Z,1581376434.095 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,231343.00,A,4450.83673,N,06734.83185,W,0.136,202.70,100220,,,D*72
2020-02-10T23:13:54.097Z,1581376434.097 [NAL9602](INFO): GPS fix at 20200210T231343: (44.847279, -67.580531)
2020-02-10T23:13:54.148Z,1581376434.148 [Default:CheckIn:Read_GPS] Stopped
2020-02-10T23:13:54.148Z,1581376434.148 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-10T23:14:02.684Z,1581376442.684 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200210T224734/Courier0015.lzma
2020-02-10T23:14:04.819Z,1581376444.819 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Courier0015.lzma.bak
2020-02-10T23:14:04.819Z,1581376444.819 [DataOverHttps](INFO): SBD MOMSN=12244208
2020-02-10T23:14:19.959Z,1581376459.959 [NAL9602](INFO): SBD MO Status=2, MOMSN=10671, MT Status=2, MTMSN=0
2020-02-10T23:14:19.959Z,1581376459.959 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-02-10T23:14:22.778Z,1581376462.778 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20200210T224734/Express0016.lzma
2020-02-10T23:14:24.784Z,1581376464.784 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Express0016.lzma.bak
2020-02-10T23:14:24.785Z,1581376464.785 [DataOverHttps](INFO): SBD MOMSN=12244211
2020-02-10T23:14:26.892Z,1581376466.892 [Default:CheckIn:Read_Iridium] Stopped
2020-02-10T23:14:26.892Z,1581376466.892 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-10T23:14:26.892Z,1581376466.892 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-10T23:14:38.935Z,1581376478.935 [NAL9602](INFO): SBD MO Status=0, MOMSN=10671, MT Status=0, MTMSN=0
2020-02-10T23:14:38.935Z,1581376478.935 [NAL9602](INFO): No messages in MT queue
2020-02-10T23:15:09.650Z,1581376509.650 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-10T23:16:54.761Z,1581376614.761 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-10T23:16:54.761Z,1581376614.761 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,  79.29,  74.29,  76.62
2020-02-10T23:17:14.137Z,1581376634.137 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-02-10T23:17:14.137Z,1581376634.137 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,   -18,  +10,A
2020-02-10T23:19:27.488Z,1581376767.488 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-10T23:19:27.488Z,1581376767.488 [Default:CheckIn:C.Wait] Stopped
2020-02-10T23:19:27.488Z,1581376767.488 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-10T23:19:27.489Z,1581376767.489 [Default:CheckIn:D] Running Loop=1
2020-02-10T23:19:27.889Z,1581376767.889 [Default:CheckIn:D] Stopped
2020-02-10T23:19:27.889Z,1581376767.889 [Default:CheckIn:E] Running Loop=1
2020-02-10T23:19:28.287Z,1581376768.287 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 22.948617 min 
2020-02-10T23:19:28.287Z,1581376768.287 [Default:CheckIn:E] Stopped
2020-02-10T23:19:28.287Z,1581376768.287 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-10T23:19:28.287Z,1581376768.287 [Default:CheckIn] Stopped
2020-02-10T23:19:28.287Z,1581376768.287 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-10T23:19:28.287Z,1581376768.287 [Default:CheckIn](INFO): Running loop #5
2020-02-10T23:19:28.287Z,1581376768.287 [Default:CheckIn] Running Loop=5
2020-02-10T23:19:28.288Z,1581376768.288 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-10T23:19:28.288Z,1581376768.288 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-10T23:19:30.275Z,1581376770.275 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,231919.00,A,4450.83569,N,06734.83153,W,0.156,202.70,100220,,,D*72
2020-02-10T23:19:30.277Z,1581376770.277 [NAL9602](INFO): GPS fix at 20200210T231919: (44.847262, -67.580525)
2020-02-10T23:19:30.320Z,1581376770.320 [Default:CheckIn:Read_GPS] Stopped
2020-02-10T23:19:30.320Z,1581376770.320 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-10T23:19:37.386Z,1581376777.386 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200210T224734/Courier0018.lzma
2020-02-10T23:19:39.392Z,1581376779.392 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Courier0018.lzma.bak
2020-02-10T23:19:39.393Z,1581376779.393 [DataOverHttps](INFO): SBD MOMSN=12244217
2020-02-10T23:19:47.764Z,1581376787.764 [NAL9602](INFO): SBD MO Status=0, MOMSN=10672, MT Status=0, MTMSN=0
2020-02-10T23:19:47.764Z,1581376787.764 [NAL9602](INFO): No messages in MT queue
2020-02-10T23:19:57.559Z,1581376797.559 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20200210T224734/Express0019.lzma
2020-02-10T23:19:59.565Z,1581376799.565 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Express0019.lzma.bak
2020-02-10T23:19:59.565Z,1581376799.565 [DataOverHttps](INFO): SBD MOMSN=12244220
2020-02-10T23:20:01.515Z,1581376801.515 [Default:CheckIn:Read_Iridium] Stopped
2020-02-10T23:20:01.515Z,1581376801.515 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-10T23:20:01.515Z,1581376801.515 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-10T23:20:18.445Z,1581376818.445 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-10T23:25:02.130Z,1581377102.130 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-10T23:25:02.130Z,1581377102.130 [Default:CheckIn:C.Wait] Stopped
2020-02-10T23:25:02.130Z,1581377102.130 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-10T23:25:02.130Z,1581377102.130 [Default:CheckIn:D] Running Loop=1
2020-02-10T23:25:02.507Z,1581377102.507 [Default:CheckIn:D] Stopped
2020-02-10T23:25:02.507Z,1581377102.507 [Default:CheckIn:E] Running Loop=1
2020-02-10T23:25:02.919Z,1581377102.919 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 28.525574 min 
2020-02-10T23:25:02.919Z,1581377102.919 [Default:CheckIn:E] Stopped
2020-02-10T23:25:02.920Z,1581377102.920 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-10T23:25:02.920Z,1581377102.920 [Default:CheckIn] Stopped
2020-02-10T23:25:02.920Z,1581377102.920 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-10T23:25:02.920Z,1581377102.920 [Default:CheckIn](INFO): Running loop #6
2020-02-10T23:25:02.920Z,1581377102.920 [Default:CheckIn] Running Loop=6
2020-02-10T23:25:02.920Z,1581377102.920 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-10T23:25:02.920Z,1581377102.920 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-10T23:25:04.897Z,1581377104.897 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232454.00,A,4450.83505,N,06734.83154,W,0.019,202.70,100220,,,D*72
2020-02-10T23:25:04.899Z,1581377104.899 [NAL9602](INFO): GPS fix at 20200210T232454: (44.847251, -67.580526)
2020-02-10T23:25:04.949Z,1581377104.949 [Default:CheckIn:Read_GPS] Stopped
2020-02-10T23:25:04.949Z,1581377104.949 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-10T23:25:12.744Z,1581377112.744 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200210T224734/Courier0021.lzma
2020-02-10T23:25:14.752Z,1581377114.752 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Courier0021.lzma.bak
2020-02-10T23:25:14.753Z,1581377114.753 [DataOverHttps](INFO): SBD MOMSN=12244235
2020-02-10T23:25:17.839Z,1581377117.839 [NAL9602](INFO): SBD MO Status=0, MOMSN=10673, MT Status=0, MTMSN=0
2020-02-10T23:25:17.839Z,1581377117.839 [NAL9602](INFO): No messages in MT queue
2020-02-10T23:25:32.007Z,1581377132.007 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200210T224734/Express0022.lzma
2020-02-10T23:25:36.021Z,1581377136.021 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Express0022.lzma.bak
2020-02-10T23:25:36.021Z,1581377136.021 [DataOverHttps](INFO): SBD MOMSN=12244238
2020-02-10T23:25:38.128Z,1581377138.128 [Default:CheckIn:Read_Iridium] Stopped
2020-02-10T23:25:38.128Z,1581377138.128 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-10T23:25:38.128Z,1581377138.128 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-10T23:25:48.528Z,1581377148.528 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-10T23:26:06.769Z,1581377166.769 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-10T23:26:06.769Z,1581377166.769 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,  78.69,  75.96,  76.62
2020-02-10T23:27:07.773Z,1581377227.773 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-02-10T23:27:07.773Z,1581377227.773 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,   +5,    -4,A
2020-02-10T23:30:38.632Z,1581377438.632 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-10T23:30:38.632Z,1581377438.632 [Default:CheckIn:C.Wait] Stopped
2020-02-10T23:30:38.632Z,1581377438.632 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-10T23:30:38.632Z,1581377438.632 [Default:CheckIn:D] Running Loop=1
2020-02-10T23:30:39.057Z,1581377439.057 [Default:CheckIn:D] Stopped
2020-02-10T23:30:39.058Z,1581377439.058 [Default:CheckIn:E] Running Loop=1
2020-02-10T23:30:39.467Z,1581377439.467 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 34.134753 min 
2020-02-10T23:30:39.467Z,1581377439.467 [Default:CheckIn:E] Stopped
2020-02-10T23:30:39.467Z,1581377439.467 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-10T23:30:39.467Z,1581377439.467 [Default:CheckIn] Stopped
2020-02-10T23:30:39.468Z,1581377439.468 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-10T23:30:39.468Z,1581377439.468 [Default:CheckIn](INFO): Running loop #7
2020-02-10T23:30:39.468Z,1581377439.468 [Default:CheckIn] Running Loop=7
2020-02-10T23:30:39.468Z,1581377439.468 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-10T23:30:39.468Z,1581377439.468 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-10T23:30:41.443Z,1581377441.443 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233031.00,A,4450.83473,N,06734.83101,W,0.019,202.70,100220,,,D*74
2020-02-10T23:30:41.445Z,1581377441.445 [NAL9602](INFO): GPS fix at 20200210T233031: (44.847245, -67.580517)
2020-02-10T23:30:41.491Z,1581377441.491 [Default:CheckIn:Read_GPS] Stopped
2020-02-10T23:30:41.491Z,1581377441.491 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-10T23:30:49.246Z,1581377449.246 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200210T224734/Courier0024.lzma
2020-02-10T23:30:51.252Z,1581377451.252 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Courier0024.lzma.bak
2020-02-10T23:30:51.253Z,1581377451.253 [DataOverHttps](INFO): SBD MOMSN=12244244
2020-02-10T23:30:54.775Z,1581377454.775 [NAL9602](INFO): SBD MO Status=0, MOMSN=10674, MT Status=0, MTMSN=0
2020-02-10T23:30:54.775Z,1581377454.775 [NAL9602](INFO): No messages in MT queue
2020-02-10T23:31:08.574Z,1581377468.574 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20200210T224734/Express0025.lzma
2020-02-10T23:31:10.580Z,1581377470.580 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Express0025.lzma.bak
2020-02-10T23:31:10.581Z,1581377470.581 [DataOverHttps](INFO): SBD MOMSN=12244247
2020-02-10T23:31:12.578Z,1581377472.578 [Default:CheckIn:Read_Iridium] Stopped
2020-02-10T23:31:12.579Z,1581377472.579 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-10T23:31:12.579Z,1581377472.579 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-10T23:31:25.477Z,1581377485.477 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-10T23:31:50.146Z,1581377510.146 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-10T23:31:50.146Z,1581377510.146 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20021016445472,35.0, -0.1,   0.0,1448.9A,  0.00,  78.95,  75.29,  76.29,  76.62
2020-02-10T23:36:13.170Z,1581377773.170 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-10T23:36:13.171Z,1581377773.171 [Default:CheckIn:C.Wait] Stopped
2020-02-10T23:36:13.171Z,1581377773.171 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-10T23:36:13.171Z,1581377773.171 [Default:CheckIn:D] Running Loop=1
2020-02-10T23:36:13.562Z,1581377773.562 [Default:CheckIn:D] Stopped
2020-02-10T23:36:13.562Z,1581377773.562 [Default:CheckIn:E] Running Loop=1
2020-02-10T23:36:14.074Z,1581377774.074 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 39.709823 min 
2020-02-10T23:36:14.074Z,1581377774.074 [Default:CheckIn:E] Stopped
2020-02-10T23:36:14.074Z,1581377774.074 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-10T23:36:14.074Z,1581377774.074 [Default:CheckIn] Stopped
2020-02-10T23:36:14.074Z,1581377774.074 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-10T23:36:14.074Z,1581377774.074 [Default:CheckIn](INFO): Running loop #8
2020-02-10T23:36:14.074Z,1581377774.074 [Default:CheckIn] Running Loop=8
2020-02-10T23:36:14.074Z,1581377774.074 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-10T23:36:14.075Z,1581377774.075 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-10T23:36:15.955Z,1581377775.955 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233605.00,A,4450.83493,N,06734.83173,W,0.039,202.70,100220,,,D*7C
2020-02-10T23:36:15.957Z,1581377775.957 [NAL9602](INFO): GPS fix at 20200210T233605: (44.847249, -67.580529)
2020-02-10T23:36:16.010Z,1581377776.010 [Default:CheckIn:Read_GPS] Stopped
2020-02-10T23:36:16.010Z,1581377776.010 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-10T23:36:18.764Z,1581377778.764 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-10T23:36:18.764Z,1581377778.764 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,  78.95,  74.96,  76.62
2020-02-10T23:36:24.270Z,1581377784.270 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200210T224734/Courier0027.lzma
2020-02-10T23:36:26.276Z,1581377786.276 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Courier0027.lzma.bak
2020-02-10T23:36:26.277Z,1581377786.277 [DataOverHttps](INFO): SBD MOMSN=12244259
2020-02-10T23:36:40.130Z,1581377800.130 [NAL9602](INFO): SBD MO Status=0, MOMSN=10675, MT Status=0, MTMSN=0
2020-02-10T23:36:40.130Z,1581377800.130 [NAL9602](INFO): No messages in MT queue
2020-02-10T23:36:43.499Z,1581377803.499 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200210T224734/Express0028.lzma
2020-02-10T23:36:45.504Z,1581377805.504 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Express0028.lzma.bak
2020-02-10T23:36:45.505Z,1581377805.505 [DataOverHttps](INFO): SBD MOMSN=12244262
2020-02-10T23:36:47.432Z,1581377807.432 [Default:CheckIn:Read_Iridium] Stopped
2020-02-10T23:36:47.432Z,1581377807.432 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-10T23:36:47.432Z,1581377807.432 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-10T23:37:10.830Z,1581377830.830 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-10T23:39:22.957Z,1581377962.957 [RDI_Pathfinder](ERROR): Failed to parse:
:B -3.88,      +245.50,      -47.83,   7.69,  0.00
2020-02-10T23:40:02.135Z,1581378002.135 [RDI_Pathfinder](ERROR): Failed to parse:.00,  78.95,  75.63,  76.29,  76.96
2020-02-10T23:41:48.006Z,1581378108.006 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-10T23:41:48.006Z,1581378108.006 [Default:CheckIn:C.Wait] Stopped
2020-02-10T23:41:48.006Z,1581378108.006 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-10T23:41:48.007Z,1581378108.007 [Default:CheckIn:D] Running Loop=1
2020-02-10T23:41:48.416Z,1581378108.416 [Default:CheckIn:D] Stopped
2020-02-10T23:41:48.416Z,1581378108.416 [Default:CheckIn:E] Running Loop=1
2020-02-10T23:41:48.824Z,1581378108.824 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 45.290706 min 
2020-02-10T23:41:48.824Z,1581378108.824 [Default:CheckIn:E] Stopped
2020-02-10T23:41:48.825Z,1581378108.825 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-10T23:41:48.825Z,1581378108.825 [Default:CheckIn] Stopped
2020-02-10T23:41:48.825Z,1581378108.825 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-10T23:41:48.825Z,1581378108.825 [Default:CheckIn](INFO): Running loop #9
2020-02-10T23:41:48.825Z,1581378108.825 [Default:CheckIn] Running Loop=9
2020-02-10T23:41:48.825Z,1581378108.825 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-10T23:41:48.825Z,1581378108.825 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-10T23:41:50.807Z,1581378110.807 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234140.00,A,4450.83430,N,06734.83161,W,0.000,202.70,100220,,,D*7D
2020-02-10T23:41:50.809Z,1581378110.809 [NAL9602](INFO): GPS fix at 20200210T234140: (44.847238, -67.580527)
2020-02-10T23:41:50.862Z,1581378110.862 [Default:CheckIn:Read_GPS] Stopped
2020-02-10T23:41:50.862Z,1581378110.862 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-10T23:41:58.242Z,1581378118.242 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200210T224734/Courier0030.lzma
2020-02-10T23:42:00.248Z,1581378120.248 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Courier0030.lzma.bak
2020-02-10T23:42:00.249Z,1581378120.249 [DataOverHttps](INFO): SBD MOMSN=12244269
2020-02-10T23:42:06.971Z,1581378126.971 [NAL9602](INFO): SBD MO Status=0, MOMSN=10676, MT Status=0, MTMSN=0
2020-02-10T23:42:06.971Z,1581378126.971 [NAL9602](INFO): No messages in MT queue
2020-02-10T23:42:22.311Z,1581378142.311 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200210T224734/Express0031.lzma
2020-02-10T23:42:24.316Z,1581378144.316 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Express0031.lzma.bak
2020-02-10T23:42:24.317Z,1581378144.317 [DataOverHttps](INFO): SBD MOMSN=12244272
2020-02-10T23:42:26.448Z,1581378146.448 [Default:CheckIn:Read_Iridium] Stopped
2020-02-10T23:42:26.448Z,1581378146.448 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-10T23:42:26.449Z,1581378146.449 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-10T23:42:37.689Z,1581378157.689 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-10T23:44:21.965Z,1581378261.965 [RDI_Pathfinder](ERROR): Failed to parse:
   -1.18,      +245.54,      -47.47,   7.69,  0.00
2020-02-10T23:45:42.777Z,1581378342.777 [RDI_Pathfinder](ERROR): only read 0 of 4 data items
2020-02-10T23:45:42.777Z,1581378342.777 [RDI_Pathfinder](ERROR): Failed to parse:
:BS    -7,    +3,A
2020-02-10T23:47:12.929Z,1581378432.929 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,  78.95,  75.29,  76.96,  76.62
2020-02-10T23:47:27.101Z,1581378447.101 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-10T23:47:27.101Z,1581378447.101 [Default:CheckIn:C.Wait] Stopped
2020-02-10T23:47:27.101Z,1581378447.101 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-10T23:47:27.101Z,1581378447.101 [Default:CheckIn:D] Running Loop=1
2020-02-10T23:47:27.479Z,1581378447.479 [Default:CheckIn:D] Stopped
2020-02-10T23:47:27.479Z,1581378447.479 [Default:CheckIn:E] Running Loop=1
2020-02-10T23:47:27.908Z,1581378447.908 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 50.941772 min 
2020-02-10T23:47:27.908Z,1581378447.908 [Default:CheckIn:E] Stopped
2020-02-10T23:47:27.908Z,1581378447.908 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-10T23:47:27.908Z,1581378447.908 [Default:CheckIn] Stopped
2020-02-10T23:47:27.908Z,1581378447.908 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-10T23:47:27.909Z,1581378447.909 [Default:CheckIn](INFO): Running loop #10
2020-02-10T23:47:27.909Z,1581378447.909 [Default:CheckIn] Running Loop=10
2020-02-10T23:47:27.909Z,1581378447.909 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-10T23:47:27.909Z,1581378447.909 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-10T23:47:29.902Z,1581378449.902 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234719.00,A,4450.83469,N,06734.83225,W,0.019,202.70,100220,,,D*70
2020-02-10T23:47:29.904Z,1581378449.904 [NAL9602](INFO): GPS fix at 20200210T234719: (44.847245, -67.580538)
2020-02-10T23:47:29.969Z,1581378449.969 [Default:CheckIn:Read_GPS] Stopped
2020-02-10T23:47:29.969Z,1581378449.969 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-10T23:47:33.139Z,1581378453.139 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-10T23:47:33.139Z,1581378453.139 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,  75.29,  76.29,  76.62
2020-02-10T23:47:37.482Z,1581378457.482 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200210T224734/Courier0033.lzma
2020-02-10T23:47:39.488Z,1581378459.488 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Courier0033.lzma.bak
2020-02-10T23:47:39.489Z,1581378459.489 [DataOverHttps](INFO): SBD MOMSN=12244284
2020-02-10T23:47:44.423Z,1581378464.423 [NAL9602](INFO): SBD MO Status=0, MOMSN=10677, MT Status=0, MTMSN=0
2020-02-10T23:47:44.423Z,1581378464.423 [NAL9602](INFO): No messages in MT queue
2020-02-10T23:47:56.715Z,1581378476.715 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200210T224734/Express0034.lzma
2020-02-10T23:47:58.721Z,1581378478.721 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Express0034.lzma.bak
2020-02-10T23:47:58.722Z,1581378478.722 [DataOverHttps](INFO): SBD MOMSN=12244287
2020-02-10T23:48:00.615Z,1581378480.615 [Default:CheckIn:Read_Iridium] Stopped
2020-02-10T23:48:00.615Z,1581378480.615 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-10T23:48:00.615Z,1581378480.615 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-10T23:48:15.145Z,1581378495.145 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-10T23:50:32.126Z,1581378632.126 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-10T23:50:32.127Z,1581378632.127 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,  78.95,  75.29,  76.29,  BI,    -2,   +18,    -4,    +0,A
2020-02-10T23:50:53.148Z,1581378653.148 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-10T23:50:53.148Z,1581378653.148 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20021017035772,35.0  0.0,1448.9,  0
2020-02-10T23:51:12.928Z,1581378672.928 [RDI_Pathfinder](ERROR): Failed to parse:.9,  0
2020-02-10T23:51:52.927Z,1581378712.927 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,  78.95,  75.29,  76.29,  76.29
2020-02-10T23:53:01.388Z,1581378781.388 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-10T23:53:01.388Z,1581378781.388 [Default:CheckIn:C.Wait] Stopped
2020-02-10T23:53:01.388Z,1581378781.388 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-10T23:53:01.389Z,1581378781.389 [Default:CheckIn:D] Running Loop=1
2020-02-10T23:53:01.818Z,1581378781.818 [Default:CheckIn:D] Stopped
2020-02-10T23:53:01.818Z,1581378781.818 [Default:CheckIn:E] Running Loop=1
2020-02-10T23:53:02.206Z,1581378782.206 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 56.514103 min 
2020-02-10T23:53:02.206Z,1581378782.206 [Default:CheckIn:E] Stopped
2020-02-10T23:53:02.207Z,1581378782.207 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-10T23:53:02.207Z,1581378782.207 [Default:CheckIn] Stopped
2020-02-10T23:53:02.207Z,1581378782.207 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-10T23:53:02.207Z,1581378782.207 [Default:CheckIn](INFO): Running loop #11
2020-02-10T23:53:02.207Z,1581378782.207 [Default:CheckIn] Running Loop=11
2020-02-10T23:53:02.207Z,1581378782.207 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-10T23:53:02.207Z,1581378782.207 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-10T23:53:04.195Z,1581378784.195 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235253.00,A,4450.83472,N,06734.83223,W,0.019,202.70,100220,,,D*76
2020-02-10T23:53:04.197Z,1581378784.197 [NAL9602](INFO): GPS fix at 20200210T235253: (44.847245, -67.580537)
2020-02-10T23:53:04.246Z,1581378784.246 [Default:CheckIn:Read_GPS] Stopped
2020-02-10T23:53:04.246Z,1581378784.246 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-10T23:53:12.270Z,1581378792.270 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200210T224734/Courier0036.lzma
2020-02-10T23:53:14.276Z,1581378794.276 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Courier0036.lzma.bak
2020-02-10T23:53:14.276Z,1581378794.276 [DataOverHttps](INFO): SBD MOMSN=12244297
2020-02-10T23:53:21.171Z,1581378801.171 [NAL9602](INFO): SBD MO Status=0, MOMSN=10678, MT Status=0, MTMSN=0
2020-02-10T23:53:21.171Z,1581378801.171 [NAL9602](INFO): No messages in MT queue
2020-02-10T23:53:32.863Z,1581378812.863 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20200210T224734/Express0037.lzma
2020-02-10T23:53:34.868Z,1581378814.868 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Express0037.lzma.bak
2020-02-10T23:53:34.869Z,1581378814.869 [DataOverHttps](INFO): SBD MOMSN=12244305
2020-02-10T23:53:36.987Z,1581378816.987 [Default:CheckIn:Read_Iridium] Stopped
2020-02-10T23:53:36.987Z,1581378816.987 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-10T23:53:36.987Z,1581378816.987 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-10T23:53:51.873Z,1581378831.873 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-10T23:53:55.134Z,1581378835.134 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-10T23:53:55.134Z,1581378835.134 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20021017065972,35.0, -0.1,   .9,  0
2020-02-10T23:54:36.129Z,1581378876.129 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-10T23:54:36.129Z,1581378876.129 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20021017074, -0.1,   0.0,1448.9,  0
2020-02-10T23:55:16.136Z,1581378916.136 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-10T23:55:16.136Z,1581378916.136 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,  78.95,  74.96,  76.62
2020-02-10T23:57:50.229Z,1581379070.229 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-02-10T23:57:50.232Z,1581379070.232 [BPC1](INFO): Received data from all battery sticks.
2020-02-10T23:58:37.525Z,1581379117.525 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-10T23:58:37.525Z,1581379117.525 [Default:CheckIn:C.Wait] Stopped
2020-02-10T23:58:37.525Z,1581379117.525 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-10T23:58:37.525Z,1581379117.525 [Default:CheckIn:D] Running Loop=1
2020-02-10T23:58:37.925Z,1581379117.925 [Default:CheckIn:D] Stopped
2020-02-10T23:58:37.925Z,1581379117.925 [Default:CheckIn:E] Running Loop=1
2020-02-10T23:58:38.328Z,1581379118.328 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 62.115881 min 
2020-02-10T23:58:38.328Z,1581379118.328 [Default:CheckIn:E] Stopped
2020-02-10T23:58:38.328Z,1581379118.328 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-10T23:58:38.328Z,1581379118.328 [Default:CheckIn] Stopped
2020-02-10T23:58:38.328Z,1581379118.328 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-10T23:58:38.328Z,1581379118.328 [Default:CheckIn](INFO): Running loop #12
2020-02-10T23:58:38.328Z,1581379118.328 [Default:CheckIn] Running Loop=12
2020-02-10T23:58:38.329Z,1581379118.329 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-10T23:58:38.329Z,1581379118.329 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-10T23:58:40.311Z,1581379120.311 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235829.00,A,4450.83432,N,06734.83184,W,0.039,0.00,100220,,,D*7E
2020-02-10T23:58:40.313Z,1581379120.313 [NAL9602](INFO): GPS fix at 20200210T235829: (44.847239, -67.580531)
2020-02-10T23:58:40.346Z,1581379120.346 [Default:CheckIn:Read_GPS] Stopped
2020-02-10T23:58:40.346Z,1581379120.346 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-10T23:58:48.114Z,1581379128.114 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20200210T224734/Courier0039.lzma
2020-02-10T23:58:50.120Z,1581379130.120 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Courier0039.lzma.bak
2020-02-10T23:58:50.121Z,1581379130.121 [DataOverHttps](INFO): SBD MOMSN=12244310
2020-02-10T23:59:07.507Z,1581379147.507 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20200210T224734/Express0040.lzma
2020-02-10T23:59:08.597Z,1581379148.597 [NAL9602](INFO): SBD MO Status=0, MOMSN=10679, MT Status=0, MTMSN=0
2020-02-10T23:59:08.597Z,1581379148.597 [NAL9602](INFO): No messages in MT queue
2020-02-10T23:59:09.512Z,1581379149.512 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Express0040.lzma.bak
2020-02-10T23:59:09.513Z,1581379149.513 [DataOverHttps](INFO): SBD MOMSN=12244313
2020-02-10T23:59:11.451Z,1581379151.451 [Default:CheckIn:Read_Iridium] Stopped
2020-02-10T23:59:11.451Z,1581379151.451 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-10T23:59:11.451Z,1581379151.451 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-10T23:59:39.289Z,1581379179.289 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-11T00:01:02.134Z,1581379262.134 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-11T00:01:02.134Z,1581379262.134 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20021017140672,35.0, -0.11448.9,  0
2020-02-11T00:04:12.159Z,1581379452.159 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-11T00:04:12.159Z,1581379452.159 [Default:CheckIn:C.Wait] Stopped
2020-02-11T00:04:12.160Z,1581379452.160 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-11T00:04:12.160Z,1581379452.160 [Default:CheckIn:D] Running Loop=1
2020-02-11T00:04:12.464Z,1581379452.464 [Default:CheckIn:D] Stopped
2020-02-11T00:04:12.464Z,1581379452.464 [Default:CheckIn:E] Running Loop=1
2020-02-11T00:04:12.917Z,1581379452.917 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 67.691528 min 
2020-02-11T00:04:12.917Z,1581379452.917 [Default:CheckIn:E] Stopped
2020-02-11T00:04:12.917Z,1581379452.917 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-11T00:04:12.917Z,1581379452.917 [Default:CheckIn] Stopped
2020-02-11T00:04:12.917Z,1581379452.917 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-11T00:04:12.918Z,1581379452.918 [Default:CheckIn](INFO): Running loop #13
2020-02-11T00:04:12.918Z,1581379452.918 [Default:CheckIn] Running Loop=13
2020-02-11T00:04:12.918Z,1581379452.918 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-11T00:04:12.918Z,1581379452.918 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-11T00:04:14.855Z,1581379454.855 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000404.00,A,4450.83542,N,06734.83179,W,0.019,0.00,110220,,,D*7E
2020-02-11T00:04:14.857Z,1581379454.857 [NAL9602](INFO): GPS fix at 20200211T000404: (44.847257, -67.580530)
2020-02-11T00:04:14.957Z,1581379454.957 [Default:CheckIn:Read_GPS] Stopped
2020-02-11T00:04:14.957Z,1581379454.957 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-11T00:04:23.334Z,1581379463.334 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200210T224734/Courier0042.lzma
2020-02-11T00:04:25.340Z,1581379465.340 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Courier0042.lzma.bak
2020-02-11T00:04:25.341Z,1581379465.341 [DataOverHttps](INFO): SBD MOMSN=12244324
2020-02-11T00:04:28.287Z,1581379468.287 [NAL9602](INFO): SBD MO Status=0, MOMSN=10680, MT Status=0, MTMSN=0
2020-02-11T00:04:28.288Z,1581379468.288 [NAL9602](INFO): No messages in MT queue
2020-02-11T00:04:42.479Z,1581379482.479 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200210T224734/Express0043.lzma
2020-02-11T00:04:44.485Z,1581379484.485 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Express0043.lzma.bak
2020-02-11T00:04:44.485Z,1581379484.485 [DataOverHttps](INFO): SBD MOMSN=12244327
2020-02-11T00:04:46.491Z,1581379486.491 [Default:CheckIn:Read_Iridium] Stopped
2020-02-11T00:04:46.493Z,1581379486.493 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-11T00:04:46.494Z,1581379486.494 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-11T00:04:58.998Z,1581379498.998 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-11T00:06:34.778Z,1581379594.778 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-11T00:06:34.778Z,1581379594.778 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,  78.95,  75.29,  75.92
2020-02-11T00:09:15.135Z,1581379755.135 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-11T00:09:15.135Z,1581379755.135 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20021017221972,31,   0.0,1448.9,  0
2020-02-11T00:09:47.080Z,1581379787.080 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-11T00:09:47.080Z,1581379787.080 [Default:CheckIn:C.Wait] Stopped
2020-02-11T00:09:47.080Z,1581379787.080 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-11T00:09:47.081Z,1581379787.081 [Default:CheckIn:D] Running Loop=1
2020-02-11T00:09:47.480Z,1581379787.480 [Default:CheckIn:D] Stopped
2020-02-11T00:09:47.480Z,1581379787.480 [Default:CheckIn:E] Running Loop=1
2020-02-11T00:09:47.907Z,1581379787.907 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 73.275122 min 
2020-02-11T00:09:47.907Z,1581379787.907 [Default:CheckIn:E] Stopped
2020-02-11T00:09:47.907Z,1581379787.907 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-11T00:09:47.907Z,1581379787.907 [Default:CheckIn] Stopped
2020-02-11T00:09:47.908Z,1581379787.908 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-11T00:09:47.908Z,1581379787.908 [Default:CheckIn](INFO): Running loop #14
2020-02-11T00:09:47.908Z,1581379787.908 [Default:CheckIn] Running Loop=14
2020-02-11T00:09:47.908Z,1581379787.908 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-11T00:09:47.908Z,1581379787.908 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-11T00:09:49.867Z,1581379789.867 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000939.00,A,4450.83572,N,06734.83207,W,0.019,0.00,110220,,,D*74
2020-02-11T00:09:49.869Z,1581379789.869 [NAL9602](INFO): GPS fix at 20200211T000939: (44.847262, -67.580534)
2020-02-11T00:09:49.921Z,1581379789.921 [Default:CheckIn:Read_GPS] Stopped
2020-02-11T00:09:49.921Z,1581379789.921 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-11T00:09:57.586Z,1581379797.586 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200210T224734/Courier0045.lzma
2020-02-11T00:09:59.593Z,1581379799.593 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Courier0045.lzma.bak
2020-02-11T00:09:59.594Z,1581379799.594 [DataOverHttps](INFO): SBD MOMSN=12244332
2020-02-11T00:10:04.811Z,1581379804.811 [NAL9602](INFO): SBD MO Status=0, MOMSN=10681, MT Status=0, MTMSN=0
2020-02-11T00:10:04.811Z,1581379804.811 [NAL9602](INFO): No messages in MT queue
2020-02-11T00:10:20.263Z,1581379820.263 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200210T224734/Express0046.lzma
2020-02-11T00:10:22.269Z,1581379822.269 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Express0046.lzma.bak
2020-02-11T00:10:22.269Z,1581379822.269 [DataOverHttps](INFO): SBD MOMSN=12244335
2020-02-11T00:10:24.676Z,1581379824.676 [Default:CheckIn:Read_Iridium] Stopped
2020-02-11T00:10:24.677Z,1581379824.677 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-11T00:10:24.677Z,1581379824.677 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-11T00:10:35.542Z,1581379835.542 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-11T00:12:14.940Z,1581379934.940 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-11T00:12:14.940Z,1581379934.940 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20021017251872,35.0, -0.1,   0
2020-02-11T00:12:35.153Z,1581379955.153 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-11T00:12:35.153Z,1581379955.153 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20021017253972,35.0, -0.1,   0.0,1448.RA,  0.00,  78.95,  75.29,  76.29,  77.62
2020-02-11T00:15:25.282Z,1581380125.282 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-11T00:15:25.282Z,1581380125.282 [Default:CheckIn:C.Wait] Stopped
2020-02-11T00:15:25.282Z,1581380125.282 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-11T00:15:25.282Z,1581380125.282 [Default:CheckIn:D] Running Loop=1
2020-02-11T00:15:25.686Z,1581380125.686 [Default:CheckIn:D] Stopped
2020-02-11T00:15:25.686Z,1581380125.686 [Default:CheckIn:E] Running Loop=1
2020-02-11T00:15:26.076Z,1581380126.076 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 78.911898 min 
2020-02-11T00:15:26.076Z,1581380126.076 [Default:CheckIn:E] Stopped
2020-02-11T00:15:26.076Z,1581380126.076 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-11T00:15:26.076Z,1581380126.076 [Default:CheckIn] Stopped
2020-02-11T00:15:26.076Z,1581380126.076 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-11T00:15:26.077Z,1581380126.077 [Default:CheckIn](INFO): Running loop #15
2020-02-11T00:15:26.077Z,1581380126.077 [Default:CheckIn] Running Loop=15
2020-02-11T00:15:26.077Z,1581380126.077 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-11T00:15:26.077Z,1581380126.077 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-11T00:15:28.065Z,1581380128.065 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001517.00,A,4450.83581,N,06734.83265,W,0.019,0.00,110220,,,D*7D
2020-02-11T00:15:28.067Z,1581380128.067 [NAL9602](INFO): GPS fix at 20200211T001517: (44.847263, -67.580544)
2020-02-11T00:15:28.118Z,1581380128.118 [Default:CheckIn:Read_GPS] Stopped
2020-02-11T00:15:28.118Z,1581380128.118 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-11T00:15:36.482Z,1581380136.482 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200210T224734/Courier0048.lzma
2020-02-11T00:15:38.489Z,1581380138.489 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Courier0048.lzma.bak
2020-02-11T00:15:38.489Z,1581380138.489 [DataOverHttps](INFO): SBD MOMSN=12244346
2020-02-11T00:15:39.783Z,1581380139.783 [NAL9602](INFO): SBD MO Status=0, MOMSN=10682, MT Status=0, MTMSN=0
2020-02-11T00:15:39.783Z,1581380139.783 [NAL9602](INFO): No messages in MT queue
2020-02-11T00:15:55.146Z,1581380155.146 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-11T00:15:55.146Z,1581380155.146 [RDI_Pathfinder](ERROR): Failed to parse:
:TS7285972,35.0, -0.1,   0.0,1448.9,  0
2020-02-11T00:15:55.647Z,1581380155.647 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200210T224734/Express0049.lzma
2020-02-11T00:15:57.653Z,1581380157.653 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Express0049.lzma.bak
2020-02-11T00:15:57.653Z,1581380157.653 [DataOverHttps](INFO): SBD MOMSN=12244349
2020-02-11T00:15:59.605Z,1581380159.605 [Default:CheckIn:Read_Iridium] Stopped
2020-02-11T00:15:59.605Z,1581380159.605 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-11T00:15:59.605Z,1581380159.605 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-11T00:16:10.481Z,1581380170.481 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-11T00:18:58.985Z,1581380338.985 [RDI_Pathfinder](ERROR): Failed to parse:
  -11.96,      +253.50,      -49.75,   7.68,  0.00
2020-02-11T00:19:17.148Z,1581380357.148 [RDI_Pathfinder](ERROR): Failed to parse:
-2,   -10,    +6,   -14,A
2020-02-11T00:20:16.993Z,1581380416.993 [RDI_Pathfinder](ERROR): Failed to parse:
:SA,   +0.00,   +0.00,  0.00
2020-02-11T00:20:55.779Z,1581380455.779 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-02-11T00:20:55.779Z,1581380455.779 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,    +3,   +4,A
2020-02-11T00:21:00.213Z,1581380460.213 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-11T00:21:00.213Z,1581380460.213 [Default:CheckIn:C.Wait] Stopped
2020-02-11T00:21:00.213Z,1581380460.213 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-11T00:21:00.213Z,1581380460.213 [Default:CheckIn:D] Running Loop=1
2020-02-11T00:21:00.618Z,1581380460.618 [Default:CheckIn:D] Stopped
2020-02-11T00:21:00.618Z,1581380460.618 [Default:CheckIn:E] Running Loop=1
2020-02-11T00:21:01.049Z,1581380461.049 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 84.494100 min 
2020-02-11T00:21:01.049Z,1581380461.049 [Default:CheckIn:E] Stopped
2020-02-11T00:21:01.049Z,1581380461.049 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-11T00:21:01.049Z,1581380461.049 [Default:CheckIn] Stopped
2020-02-11T00:21:01.049Z,1581380461.049 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-11T00:21:01.050Z,1581380461.050 [Default:CheckIn](INFO): Running loop #16
2020-02-11T00:21:01.050Z,1581380461.050 [Default:CheckIn] Running Loop=16
2020-02-11T00:21:01.050Z,1581380461.050 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-11T00:21:01.050Z,1581380461.050 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-11T00:21:03.012Z,1581380463.012 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002052.00,A,4450.83534,N,06734.83194,W,0.019,0.00,110220,,,D*79
2020-02-11T00:21:03.014Z,1581380463.014 [NAL9602](INFO): GPS fix at 20200211T002052: (44.847256, -67.580532)
2020-02-11T00:21:03.048Z,1581380463.048 [Default:CheckIn:Read_GPS] Stopped
2020-02-11T00:21:03.048Z,1581380463.048 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-11T00:21:10.334Z,1581380470.334 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200210T224734/Courier0051.lzma
2020-02-11T00:21:12.341Z,1581380472.341 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Courier0051.lzma.bak
2020-02-11T00:21:12.341Z,1581380472.341 [DataOverHttps](INFO): SBD MOMSN=12244354
2020-02-11T00:21:15.935Z,1581380475.935 [NAL9602](INFO): SBD MO Status=0, MOMSN=10683, MT Status=0, MTMSN=0
2020-02-11T00:21:15.935Z,1581380475.935 [NAL9602](INFO): No messages in MT queue
2020-02-11T00:21:29.977Z,1581380489.977 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200210T224734/Express0052.lzma
2020-02-11T00:21:31.665Z,1581380491.665 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Express0052.lzma.bak
2020-02-11T00:21:31.665Z,1581380491.665 [DataOverHttps](INFO): SBD MOMSN=12244357
2020-02-11T00:21:33.782Z,1581380493.782 [Default:CheckIn:Read_Iridium] Stopped
2020-02-11T00:21:33.782Z,1581380493.782 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-11T00:21:33.782Z,1581380493.782 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-11T00:21:46.633Z,1581380506.633 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-11T00:23:57.150Z,1581380637.150 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-11T00:23:57.150Z,1581380637.150 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20021017370172,35.0, -0.1,   .9,  0
2020-02-11T00:24:57.781Z,1581380697.781 [RDI_Pathfinder](ERROR): Failed to parse:
+4,   -45,    +6,A
2020-02-11T00:25:36.123Z,1581380736.123 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-11T00:25:36.123Z,1581380736.123 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,200210135.0, -0.1,   .9,  0
2020-02-11T00:26:34.346Z,1581380794.346 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-11T00:26:34.346Z,1581380794.346 [Default:CheckIn:C.Wait] Stopped
2020-02-11T00:26:34.346Z,1581380794.346 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-11T00:26:34.346Z,1581380794.346 [Default:CheckIn:D] Running Loop=1
2020-02-11T00:26:34.744Z,1581380794.744 [Default:CheckIn:D] Stopped
2020-02-11T00:26:34.744Z,1581380794.744 [Default:CheckIn:E] Running Loop=1
2020-02-11T00:26:35.138Z,1581380795.138 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 90.062858 min 
2020-02-11T00:26:35.139Z,1581380795.139 [Default:CheckIn:E] Stopped
2020-02-11T00:26:35.139Z,1581380795.139 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-11T00:26:35.139Z,1581380795.139 [Default:CheckIn] Stopped
2020-02-11T00:26:35.139Z,1581380795.139 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-11T00:26:35.139Z,1581380795.139 [Default:CheckIn](INFO): Running loop #17
2020-02-11T00:26:35.139Z,1581380795.139 [Default:CheckIn] Running Loop=17
2020-02-11T00:26:35.139Z,1581380795.139 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-11T00:26:35.139Z,1581380795.139 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-11T00:26:37.119Z,1581380797.119 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002626.00,A,4450.83533,N,06734.83163,W,0.039,0.00,110220,,,D*71
2020-02-11T00:26:37.121Z,1581380797.121 [NAL9602](INFO): GPS fix at 20200211T002626: (44.847256, -67.580527)
2020-02-11T00:26:37.171Z,1581380797.171 [Default:CheckIn:Read_GPS] Stopped
2020-02-11T00:26:37.171Z,1581380797.171 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-11T00:26:45.582Z,1581380805.582 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200210T224734/Courier0054.lzma
2020-02-11T00:26:47.588Z,1581380807.588 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Courier0054.lzma.bak
2020-02-11T00:26:47.589Z,1581380807.589 [DataOverHttps](INFO): SBD MOMSN=12244370
2020-02-11T00:26:52.467Z,1581380812.467 [NAL9602](INFO): SBD MO Status=0, MOMSN=10684, MT Status=0, MTMSN=0
2020-02-11T00:26:52.467Z,1581380812.467 [NAL9602](INFO): No messages in MT queue
2020-02-11T00:27:04.819Z,1581380824.819 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200210T224734/Express0055.lzma
2020-02-11T00:27:06.824Z,1581380826.824 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Express0055.lzma.bak
2020-02-11T00:27:06.825Z,1581380826.825 [DataOverHttps](INFO): SBD MOMSN=12244373
2020-02-11T00:27:08.653Z,1581380828.653 [Default:CheckIn:Read_Iridium] Stopped
2020-02-11T00:27:08.653Z,1581380828.653 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-11T00:27:08.653Z,1581380828.653 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-11T00:27:19.149Z,1581380839.149 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-11T00:27:19.150Z,1581380839.150 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.95,  75.63,  76.29,  76.62
2020-02-11T00:27:23.169Z,1581380843.169 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-11T00:28:20.979Z,1581380900.979 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20021017412572,35.0, -0.1,   0.0,1448.9,  0
2020-02-11T00:28:58.144Z,1581380938.144 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,    -1,    +7,    -3,A
2020-02-11T00:30:35.120Z,1581381035.120 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-11T00:30:35.120Z,1581381035.120 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,  78.95,  75.29,  76.62
2020-02-11T00:31:40.984Z,1581381100.984 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20021017444572,35.0, -0.1,   0.0,1448.9,  0
2020-02-11T00:31:59.148Z,1581381119.148 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-11T00:31:59.148Z,1581381119.148 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  8.95,  74.63,  76.96,  76.62
2020-02-11T00:32:09.259Z,1581381129.259 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-11T00:32:09.259Z,1581381129.259 [Default:CheckIn:C.Wait] Stopped
2020-02-11T00:32:09.259Z,1581381129.259 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-11T00:32:09.260Z,1581381129.260 [Default:CheckIn:D] Running Loop=1
2020-02-11T00:32:09.666Z,1581381129.666 [Default:CheckIn:D] Stopped
2020-02-11T00:32:09.666Z,1581381129.666 [Default:CheckIn:E] Running Loop=1
2020-02-11T00:32:10.082Z,1581381130.082 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 95.644906 min 
2020-02-11T00:32:10.082Z,1581381130.082 [Default:CheckIn:E] Stopped
2020-02-11T00:32:10.082Z,1581381130.082 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-11T00:32:10.082Z,1581381130.082 [Default:CheckIn] Stopped
2020-02-11T00:32:10.082Z,1581381130.082 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-11T00:32:10.083Z,1581381130.083 [Default:CheckIn](INFO): Running loop #18
2020-02-11T00:32:10.083Z,1581381130.083 [Default:CheckIn] Running Loop=18
2020-02-11T00:32:10.083Z,1581381130.083 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-11T00:32:10.083Z,1581381130.083 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-11T00:32:12.063Z,1581381132.063 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,003201.00,A,4450.83493,N,06734.83166,W,0.019,0.00,110220,,,D*7D
2020-02-11T00:32:12.065Z,1581381132.065 [NAL9602](INFO): GPS fix at 20200211T003201: (44.847249, -67.580528)
2020-02-11T00:32:12.151Z,1581381132.151 [Default:CheckIn:Read_GPS] Stopped
2020-02-11T00:32:12.151Z,1581381132.151 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-11T00:32:22.106Z,1581381142.106 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200210T224734/Courier0057.lzma
2020-02-11T00:32:25.117Z,1581381145.117 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Courier0057.lzma.bak
2020-02-11T00:32:25.117Z,1581381145.117 [DataOverHttps](INFO): SBD MOMSN=12244380
2020-02-11T00:32:32.433Z,1581381152.433 [NAL9602](INFO): SBD MO Status=0, MOMSN=10685, MT Status=0, MTMSN=0
2020-02-11T00:32:32.433Z,1581381152.433 [NAL9602](INFO): No messages in MT queue
2020-02-11T00:32:42.556Z,1581381162.556 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200210T224734/Express0058.lzma
2020-02-11T00:32:44.561Z,1581381164.561 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Express0058.lzma.bak
2020-02-11T00:32:44.561Z,1581381164.561 [DataOverHttps](INFO): SBD MOMSN=12244383
2020-02-11T00:32:46.630Z,1581381166.630 [Default:CheckIn:Read_Iridium] Stopped
2020-02-11T00:32:46.630Z,1581381166.630 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-11T00:32:46.630Z,1581381166.630 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-11T00:32:59.142Z,1581381179.142 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-11T00:32:59.142Z,1581381179.142 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20021017460372,35.0, -.0,1448.9,  0
2020-02-11T00:33:03.165Z,1581381183.165 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-11T00:33:40.784Z,1581381220.784 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-02-11T00:33:40.784Z,1581381220.784 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,   -11,   +19,  :BE,   -11,   +19,    -5,A
2020-02-11T00:34:59.149Z,1581381299.149 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-11T00:34:59.149Z,1581381299.149 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,  78.95,  75.29,  76.96
2020-02-11T00:35:17.774Z,1581381317.774 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-11T00:35:17.774Z,1581381317.774 [RDI_Pathfinder](ERROR): Failed to parse:
:TS  -9,   +15,    -7,   +22,A
2020-02-11T00:35:38.777Z,1581381338.777 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-11T00:35:38.777Z,1581381338.777 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,  78.95,  75.96,  76.96
2020-02-11T00:37:47.234Z,1581381467.234 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-11T00:37:47.234Z,1581381467.234 [Default:CheckIn:C.Wait] Stopped
2020-02-11T00:37:47.235Z,1581381467.235 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-11T00:37:47.235Z,1581381467.235 [Default:CheckIn:D] Running Loop=1
2020-02-11T00:37:47.626Z,1581381467.626 [Default:CheckIn:D] Stopped
2020-02-11T00:37:47.626Z,1581381467.626 [Default:CheckIn:E] Running Loop=1
2020-02-11T00:37:48.052Z,1581381468.052 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 101.277563 min 
2020-02-11T00:37:48.052Z,1581381468.052 [Default:CheckIn:E] Stopped
2020-02-11T00:37:48.052Z,1581381468.052 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-11T00:37:48.052Z,1581381468.052 [Default:CheckIn] Stopped
2020-02-11T00:37:48.052Z,1581381468.052 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-11T00:37:48.053Z,1581381468.053 [Default:CheckIn](INFO): Running loop #19
2020-02-11T00:37:48.053Z,1581381468.053 [Default:CheckIn] Running Loop=19
2020-02-11T00:37:48.053Z,1581381468.053 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-11T00:37:48.053Z,1581381468.053 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-11T00:37:50.027Z,1581381470.027 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,003739.00,A,4450.83517,N,06734.83212,W,0.039,0.00,110220,,,D*7C
2020-02-11T00:37:50.029Z,1581381470.029 [NAL9602](INFO): GPS fix at 20200211T003739: (44.847253, -67.580535)
2020-02-11T00:37:50.081Z,1581381470.081 [Default:CheckIn:Read_GPS] Stopped
2020-02-11T00:37:50.081Z,1581381470.081 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-11T00:37:57.846Z,1581381477.846 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20200210T224734/Courier0060.lzma
2020-02-11T00:37:59.853Z,1581381479.853 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Courier0060.lzma.bak
2020-02-11T00:37:59.853Z,1581381479.853 [DataOverHttps](INFO): SBD MOMSN=12244396
2020-02-11T00:38:06.586Z,1581381486.586 [NAL9602](INFO): SBD MO Status=0, MOMSN=10686, MT Status=0, MTMSN=0
2020-02-11T00:38:06.597Z,1581381486.597 [NAL9602](INFO): No messages in MT queue
2020-02-11T00:38:20.267Z,1581381500.267 [DataOverHttps](INFO): Sending 121 bytes from file Logs/20200210T224734/Express0061.lzma
2020-02-11T00:38:22.275Z,1581381502.275 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Express0061.lzma.bak
2020-02-11T00:38:22.275Z,1581381502.275 [DataOverHttps](INFO): SBD MOMSN=12244399
2020-02-11T00:38:24.027Z,1581381504.027 [Default:CheckIn:Read_Iridium] Stopped
2020-02-11T00:38:24.028Z,1581381504.028 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-11T00:38:24.028Z,1581381504.028 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-11T00:38:37.321Z,1581381517.321 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-11T00:41:18.158Z,1581381678.158 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-02-11T00:41:18.158Z,1581381678.158 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,   22,    +6,A
2020-02-11T00:43:24.677Z,1581381804.677 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-11T00:43:24.678Z,1581381804.678 [Default:CheckIn:C.Wait] Stopped
2020-02-11T00:43:24.678Z,1581381804.678 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-11T00:43:24.678Z,1581381804.678 [Default:CheckIn:D] Running Loop=1
2020-02-11T00:43:25.067Z,1581381805.067 [Default:CheckIn:D] Stopped
2020-02-11T00:43:25.067Z,1581381805.067 [Default:CheckIn:E] Running Loop=1
2020-02-11T00:43:25.467Z,1581381805.467 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 106.901579 min 
2020-02-11T00:43:25.467Z,1581381805.467 [Default:CheckIn:E] Stopped
2020-02-11T00:43:25.468Z,1581381805.468 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-11T00:43:25.468Z,1581381805.468 [Default:CheckIn] Stopped
2020-02-11T00:43:25.468Z,1581381805.468 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-11T00:43:25.468Z,1581381805.468 [Default:CheckIn](INFO): Running loop #20
2020-02-11T00:43:25.468Z,1581381805.468 [Default:CheckIn] Running Loop=20
2020-02-11T00:43:25.468Z,1581381805.468 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-11T00:43:25.468Z,1581381805.468 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-11T00:43:27.453Z,1581381807.453 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,004317.00,A,4450.83408,N,06734.83105,W,0.019,0.00,110220,,,D*7B
2020-02-11T00:43:27.455Z,1581381807.455 [NAL9602](INFO): GPS fix at 20200211T004317: (44.847235, -67.580517)
2020-02-11T00:43:27.504Z,1581381807.504 [Default:CheckIn:Read_GPS] Stopped
2020-02-11T00:43:27.504Z,1581381807.504 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-11T00:43:35.162Z,1581381815.162 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200210T224734/Courier0063.lzma
2020-02-11T00:43:37.169Z,1581381817.169 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Courier0063.lzma.bak
2020-02-11T00:43:37.169Z,1581381817.169 [DataOverHttps](INFO): SBD MOMSN=12244405
2020-02-11T00:43:37.959Z,1581381817.959 [NAL9602](INFO): SBD MO Status=0, MOMSN=10687, MT Status=0, MTMSN=0
2020-02-11T00:43:37.959Z,1581381817.959 [NAL9602](INFO): No messages in MT queue
2020-02-11T00:43:54.291Z,1581381834.291 [DataOverHttps](INFO): Sending 121 bytes from file Logs/20200210T224734/Express0064.lzma
2020-02-11T00:43:56.297Z,1581381836.297 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Express0064.lzma.bak
2020-02-11T00:43:56.297Z,1581381836.297 [DataOverHttps](INFO): SBD MOMSN=12244409
2020-02-11T00:43:58.195Z,1581381838.195 [Default:CheckIn:Read_Iridium] Stopped
2020-02-11T00:43:58.195Z,1581381838.195 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-11T00:43:58.195Z,1581381838.195 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-11T00:44:08.665Z,1581381848.665 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-11T00:45:15.794Z,1581381915.794 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-11T00:45:15.794Z,1581381915.794 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20021017582072,35.0, -0.1,   0.0,10
2020-02-11T00:48:58.809Z,1581382138.809 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-11T00:48:58.809Z,1581382138.809 [Default:CheckIn:C.Wait] Stopped
2020-02-11T00:48:58.809Z,1581382138.809 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-11T00:48:58.809Z,1581382138.809 [Default:CheckIn:D] Running Loop=1
2020-02-11T00:48:59.187Z,1581382139.187 [Default:CheckIn:D] Stopped
2020-02-11T00:48:59.187Z,1581382139.187 [Default:CheckIn:E] Running Loop=1
2020-02-11T00:48:59.591Z,1581382139.591 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 112.470239 min 
2020-02-11T00:48:59.591Z,1581382139.591 [Default:CheckIn:E] Stopped
2020-02-11T00:48:59.591Z,1581382139.591 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-11T00:48:59.591Z,1581382139.591 [Default:CheckIn] Stopped
2020-02-11T00:48:59.591Z,1581382139.591 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-11T00:48:59.592Z,1581382139.592 [Default:CheckIn](INFO): Running loop #21
2020-02-11T00:48:59.592Z,1581382139.592 [Default:CheckIn] Running Loop=21
2020-02-11T00:48:59.592Z,1581382139.592 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-11T00:48:59.592Z,1581382139.592 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-11T00:49:01.579Z,1581382141.579 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,004851.00,A,4450.83456,N,06734.83238,W,0.078,0.00,110220,,,D*73
2020-02-11T00:49:01.581Z,1581382141.581 [NAL9602](INFO): GPS fix at 20200211T004851: (44.847243, -67.580540)
2020-02-11T00:49:01.624Z,1581382141.624 [Default:CheckIn:Read_GPS] Stopped
2020-02-11T00:49:01.624Z,1581382141.624 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-11T00:49:09.586Z,1581382149.586 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200210T224734/Courier0066.lzma
2020-02-11T00:49:11.592Z,1581382151.592 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Courier0066.lzma.bak
2020-02-11T00:49:11.593Z,1581382151.593 [DataOverHttps](INFO): SBD MOMSN=12244423
2020-02-11T00:49:15.714Z,1581382155.714 [NAL9602](INFO): SBD MO Status=0, MOMSN=10688, MT Status=0, MTMSN=0
2020-02-11T00:49:15.714Z,1581382155.714 [NAL9602](INFO): No messages in MT queue
2020-02-11T00:49:28.787Z,1581382168.787 [DataOverHttps](INFO): Sending 121 bytes from file Logs/20200210T224734/Express0067.lzma
2020-02-11T00:49:30.793Z,1581382170.793 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Express0067.lzma.bak
2020-02-11T00:49:30.793Z,1581382170.793 [DataOverHttps](INFO): SBD MOMSN=12244426
2020-02-11T00:49:32.723Z,1581382172.723 [Default:CheckIn:Read_Iridium] Stopped
2020-02-11T00:49:32.723Z,1581382172.723 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-11T00:49:32.723Z,1581382172.723 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-11T00:49:46.417Z,1581382186.417 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-11T00:53:56.164Z,1581382436.164 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-11T00:53:56.164Z,1581382436.164 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20021018070072,35.0, -0.1,   .9,  0
2020-02-11T00:54:33.325Z,1581382473.325 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-11T00:54:33.325Z,1581382473.325 [Default:CheckIn:C.Wait] Stopped
2020-02-11T00:54:33.325Z,1581382473.325 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-11T00:54:33.326Z,1581382473.326 [Default:CheckIn:D] Running Loop=1
2020-02-11T00:54:33.740Z,1581382473.740 [Default:CheckIn:D] Stopped
2020-02-11T00:54:33.740Z,1581382473.740 [Default:CheckIn:E] Running Loop=1
2020-02-11T00:54:34.153Z,1581382474.153 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 118.046118 min 
2020-02-11T00:54:34.153Z,1581382474.153 [Default:CheckIn:E] Stopped
2020-02-11T00:54:34.153Z,1581382474.153 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-11T00:54:34.153Z,1581382474.153 [Default:CheckIn] Stopped
2020-02-11T00:54:34.153Z,1581382474.153 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-11T00:54:34.154Z,1581382474.154 [Default:CheckIn](INFO): Running loop #22
2020-02-11T00:54:34.154Z,1581382474.154 [Default:CheckIn] Running Loop=22
2020-02-11T00:54:34.154Z,1581382474.154 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-11T00:54:34.154Z,1581382474.154 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-11T00:54:36.125Z,1581382476.125 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,005425.00,A,4450.83466,N,06734.83193,W,0.039,0.00,110220,,,D*79
2020-02-11T00:54:36.128Z,1581382476.128 [NAL9602](INFO): GPS fix at 20200211T005425: (44.847244, -67.580532)
2020-02-11T00:54:36.152Z,1581382476.152 [Default:CheckIn:Read_GPS] Stopped
2020-02-11T00:54:36.152Z,1581382476.152 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-11T00:54:43.514Z,1581382483.514 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200210T224734/Courier0069.lzma
2020-02-11T00:54:45.520Z,1581382485.520 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Courier0069.lzma.bak
2020-02-11T00:54:45.521Z,1581382485.521 [DataOverHttps](INFO): SBD MOMSN=12244431
2020-02-11T00:54:51.129Z,1581382491.129 [NAL9602](INFO): SBD MO Status=0, MOMSN=10689, MT Status=0, MTMSN=0
2020-02-11T00:54:51.129Z,1581382491.129 [NAL9602](INFO): No messages in MT queue
2020-02-11T00:55:02.835Z,1581382502.835 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200210T224734/Express0070.lzma
2020-02-11T00:55:04.841Z,1581382504.841 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Express0070.lzma.bak
2020-02-11T00:55:04.841Z,1581382504.841 [DataOverHttps](INFO): SBD MOMSN=12244434
2020-02-11T00:55:06.938Z,1581382506.938 [Default:CheckIn:Read_Iridium] Stopped
2020-02-11T00:55:06.943Z,1581382506.943 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-11T00:55:06.943Z,1581382506.943 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-11T00:55:11.797Z,1581382511.797 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,   +11,    +0,    +0,A
2020-02-11T00:55:21.829Z,1581382521.829 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-11T00:55:54.166Z,1581382554.166 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-02-11T00:55:54.166Z,1581382554.166 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,    1,    +2,A
2020-02-11T00:58:54.785Z,1581382734.785 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-11T00:58:54.785Z,1581382734.785 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,  78.95,  75.29,  76.96
2020-02-11T00:59:56.155Z,1581382796.155 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-11T00:59:56.155Z,1581382796.155 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20021018130072,35.0, -0.1,   0.0,1448:RA,  0.00,  78.95,  75.29,  75.96,  76.96
2020-02-11T01:00:07.495Z,1581382807.495 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-11T01:00:07.495Z,1581382807.495 [Default:CheckIn:C.Wait] Stopped
2020-02-11T01:00:07.495Z,1581382807.495 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-11T01:00:07.495Z,1581382807.495 [Default:CheckIn:D] Running Loop=1
2020-02-11T01:00:07.915Z,1581382807.915 [Default:CheckIn:D] Stopped
2020-02-11T01:00:07.915Z,1581382807.915 [Default:CheckIn:E] Running Loop=1
2020-02-11T01:00:08.329Z,1581382808.329 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 123.615715 min 
2020-02-11T01:00:08.329Z,1581382808.329 [Default:CheckIn:E] Stopped
2020-02-11T01:00:08.329Z,1581382808.329 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-11T01:00:08.329Z,1581382808.329 [Default:CheckIn] Stopped
2020-02-11T01:00:08.329Z,1581382808.329 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-11T01:00:08.329Z,1581382808.329 [Default:CheckIn](INFO): Running loop #23
2020-02-11T01:00:08.329Z,1581382808.329 [Default:CheckIn] Running Loop=23
2020-02-11T01:00:08.329Z,1581382808.329 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-11T01:00:08.329Z,1581382808.329 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-11T01:00:10.289Z,1581382810.289 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,005959.00,A,4450.83520,N,06734.83194,W,0.058,0.00,110220,,,D*7C
2020-02-11T01:00:10.291Z,1581382810.291 [NAL9602](INFO): GPS fix at 20200211T005959: (44.847253, -67.580532)
2020-02-11T01:00:10.317Z,1581382810.317 [Default:CheckIn:Read_GPS] Stopped
2020-02-11T01:00:10.317Z,1581382810.317 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-11T01:00:22.610Z,1581382822.610 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200210T224734/Courier0072.lzma
2020-02-11T01:00:24.155Z,1581382824.155 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-11T01:00:24.155Z,1581382824.155 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,  78.95,  75.29,  76.29
2020-02-11T01:00:25.620Z,1581382825.620 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Courier0072.lzma.bak
2020-02-11T01:00:25.621Z,1581382825.621 [DataOverHttps](INFO): SBD MOMSN=12244445
2020-02-11T01:00:32.223Z,1581382832.223 [NAL9602](INFO): SBD MO Status=0, MOMSN=10690, MT Status=0, MTMSN=0
2020-02-11T01:00:32.223Z,1581382832.223 [NAL9602](INFO): No messages in MT queue
2020-02-11T01:00:45.134Z,1581382845.134 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200210T224734/Express0073.lzma
2020-02-11T01:00:47.149Z,1581382847.149 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Express0073.lzma.bak
2020-02-11T01:00:47.149Z,1581382847.149 [DataOverHttps](INFO): SBD MOMSN=12244449
2020-02-11T01:00:49.238Z,1581382849.238 [Default:CheckIn:Read_Iridium] Stopped
2020-02-11T01:00:49.243Z,1581382849.243 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-11T01:00:49.243Z,1581382849.243 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-11T01:01:02.913Z,1581382862.913 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-11T01:02:23.784Z,1581382943.784 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-02-11T01:02:23.784Z,1581382943.784 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,    +7,   -5,A
2020-02-11T01:03:04.151Z,1581382984.151 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-11T01:03:04.151Z,1581382984.151 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,200210181608 -0.1,   0.0,1448.9,  0
2020-02-11T01:05:06.156Z,1581383106.156 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-11T01:05:06.156Z,1581383106.156 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,  78.95,  74.29,  76.29
2020-02-11T01:05:14.642Z,1581383114.642 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-02-11T01:05:14.645Z,1581383114.645 [BPC1](INFO): Received data from all battery sticks.
2020-02-11T01:05:49.841Z,1581383149.841 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-11T01:05:49.841Z,1581383149.841 [Default:CheckIn:C.Wait] Stopped
2020-02-11T01:05:49.841Z,1581383149.841 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-11T01:05:49.842Z,1581383149.842 [Default:CheckIn:D] Running Loop=1
2020-02-11T01:05:50.206Z,1581383150.206 [Default:CheckIn:D] Stopped
2020-02-11T01:05:50.206Z,1581383150.206 [Default:CheckIn:E] Running Loop=1
2020-02-11T01:05:50.607Z,1581383150.607 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 129.320565 min 
2020-02-11T01:05:50.607Z,1581383150.607 [Default:CheckIn:E] Stopped
2020-02-11T01:05:50.607Z,1581383150.607 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-11T01:05:50.607Z,1581383150.607 [Default:CheckIn] Stopped
2020-02-11T01:05:50.607Z,1581383150.607 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-11T01:05:50.608Z,1581383150.608 [Default:CheckIn](INFO): Running loop #24
2020-02-11T01:05:50.608Z,1581383150.608 [Default:CheckIn] Running Loop=24
2020-02-11T01:05:50.608Z,1581383150.608 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-11T01:05:50.608Z,1581383150.608 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-11T01:05:52.603Z,1581383152.603 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,010542.00,A,4450.83557,N,06734.83239,W,0.019,0.00,110220,,,D*7F
2020-02-11T01:05:52.605Z,1581383152.605 [NAL9602](INFO): GPS fix at 20200211T010542: (44.847259, -67.580540)
2020-02-11T01:05:52.645Z,1581383152.645 [Default:CheckIn:Read_GPS] Stopped
2020-02-11T01:05:52.645Z,1581383152.645 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-11T01:06:02.311Z,1581383162.311 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20200210T224734/Courier0075.lzma
2020-02-11T01:06:04.316Z,1581383164.316 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Courier0075.lzma.bak
2020-02-11T01:06:04.317Z,1581383164.317 [DataOverHttps](INFO): SBD MOMSN=12244457
2020-02-11T01:06:23.055Z,1581383183.055 [DataOverHttps](INFO): Sending 121 bytes from file Logs/20200210T224734/Express0076.lzma
2020-02-11T01:06:25.061Z,1581383185.061 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Express0076.lzma.bak
2020-02-11T01:06:25.061Z,1581383185.061 [DataOverHttps](INFO): SBD MOMSN=12244460
2020-02-11T01:06:26.159Z,1581383186.159 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-11T01:06:26.159Z,1581383186.159 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20021018193072,35.0, 0.0,1448.9,  0
2020-02-11T01:06:26.991Z,1581383186.991 [Default:CheckIn:Read_Iridium] Stopped
2020-02-11T01:06:26.992Z,1581383186.992 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-11T01:06:26.992Z,1581383186.992 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-11T01:06:45.587Z,1581383205.587 [NAL9602](INFO): SBD MO Status=2, MOMSN=10691, MT Status=2, MTMSN=0
2020-02-11T01:06:45.587Z,1581383205.587 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-02-11T01:06:55.283Z,1581383215.283 [NAL9602](INFO): SBD MO Status=0, MOMSN=10691, MT Status=0, MTMSN=0
2020-02-11T01:06:55.283Z,1581383215.283 [NAL9602](INFO): No messages in MT queue
2020-02-11T01:07:25.985Z,1581383245.985 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-11T01:09:02.152Z,1581383342.152 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-11T01:09:02.152Z,1581383342.152 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,  78.95,  75.29,  76.29
2020-02-11T01:11:27.597Z,1581383487.597 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-11T01:11:27.597Z,1581383487.597 [Default:CheckIn:C.Wait] Stopped
2020-02-11T01:11:27.597Z,1581383487.597 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-11T01:11:27.597Z,1581383487.597 [Default:CheckIn:D] Running Loop=1
2020-02-11T01:11:28.023Z,1581383488.023 [Default:CheckIn:D] Stopped
2020-02-11T01:11:28.023Z,1581383488.023 [Default:CheckIn:E] Running Loop=1
2020-02-11T01:11:28.417Z,1581383488.417 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 134.950838 min 
2020-02-11T01:11:28.417Z,1581383488.417 [Default:CheckIn:E] Stopped
2020-02-11T01:11:28.417Z,1581383488.417 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-11T01:11:28.417Z,1581383488.417 [Default:CheckIn] Stopped
2020-02-11T01:11:28.417Z,1581383488.417 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-11T01:11:28.417Z,1581383488.417 [Default:CheckIn](INFO): Running loop #25
2020-02-11T01:11:28.418Z,1581383488.418 [Default:CheckIn] Running Loop=25
2020-02-11T01:11:28.418Z,1581383488.418 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-11T01:11:28.418Z,1581383488.418 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-11T01:11:30.401Z,1581383490.401 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,011120.00,A,4450.83582,N,06734.83249,W,0.019,0.00,110220,,,D*71
2020-02-11T01:11:30.403Z,1581383490.403 [NAL9602](INFO): GPS fix at 20200211T011120: (44.847264, -67.580541)
2020-02-11T01:11:30.428Z,1581383490.428 [Default:CheckIn:Read_GPS] Stopped
2020-02-11T01:11:30.428Z,1581383490.428 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-11T01:11:37.846Z,1581383497.846 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200210T224734/Courier0078.lzma
2020-02-11T01:11:39.853Z,1581383499.853 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Courier0078.lzma.bak
2020-02-11T01:11:39.853Z,1581383499.853 [DataOverHttps](INFO): SBD MOMSN=12244471
2020-02-11T01:11:42.931Z,1581383502.931 [NAL9602](INFO): SBD MO Status=0, MOMSN=10692, MT Status=0, MTMSN=0
2020-02-11T01:11:42.931Z,1581383502.931 [NAL9602](INFO): No messages in MT queue
2020-02-11T01:11:43.791Z,1581383503.791 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-02-11T01:11:43.791Z,1581383503.791 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,   +3,    -7,A
2020-02-11T01:11:57.087Z,1581383517.087 [DataOverHttps](INFO): Sending 121 bytes from file Logs/20200210T224734/Express0079.lzma
2020-02-11T01:11:59.095Z,1581383519.095 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Express0079.lzma.bak
2020-02-11T01:11:59.095Z,1581383519.095 [DataOverHttps](INFO): SBD MOMSN=12244474
2020-02-11T01:12:01.161Z,1581383521.161 [Default:CheckIn:Read_Iridium] Stopped
2020-02-11T01:12:01.162Z,1581383521.162 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-11T01:12:01.162Z,1581383521.162 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-11T01:12:13.661Z,1581383533.661 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-11T01:13:42.157Z,1581383622.157 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-11T01:13:42.157Z,1581383622.157 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  8.95,  75.29,  76.29,  76.29
2020-02-11T01:17:01.789Z,1581383821.789 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-11T01:17:01.789Z,1581383821.789 [Default:CheckIn:C.Wait] Stopped
2020-02-11T01:17:01.789Z,1581383821.789 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-11T01:17:01.789Z,1581383821.789 [Default:CheckIn:D] Running Loop=1
2020-02-11T01:17:02.156Z,1581383822.156 [Default:CheckIn:D] Stopped
2020-02-11T01:17:02.156Z,1581383822.156 [Default:CheckIn:E] Running Loop=1
2020-02-11T01:17:02.556Z,1581383822.556 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 140.519743 min 
2020-02-11T01:17:02.556Z,1581383822.556 [Default:CheckIn:E] Stopped
2020-02-11T01:17:02.556Z,1581383822.556 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-11T01:17:02.556Z,1581383822.556 [Default:CheckIn] Stopped
2020-02-11T01:17:02.556Z,1581383822.556 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-11T01:17:02.556Z,1581383822.556 [Default:CheckIn](INFO): Running loop #26
2020-02-11T01:17:02.556Z,1581383822.556 [Default:CheckIn] Running Loop=26
2020-02-11T01:17:02.556Z,1581383822.556 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-11T01:17:02.556Z,1581383822.556 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-11T01:17:04.547Z,1581383824.547 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,011654.00,A,4450.83531,N,06734.83218,W,0.039,0.00,110220,,,D*7B
2020-02-11T01:17:04.549Z,1581383824.549 [NAL9602](INFO): GPS fix at 20200211T011654: (44.847255, -67.580536)
2020-02-11T01:17:04.598Z,1581383824.598 [Default:CheckIn:Read_GPS] Stopped
2020-02-11T01:17:04.598Z,1581383824.598 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-11T01:17:12.862Z,1581383832.862 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200210T224734/Courier0081.lzma
2020-02-11T01:17:14.869Z,1581383834.869 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Courier0081.lzma.bak
2020-02-11T01:17:14.869Z,1581383834.869 [DataOverHttps](INFO): SBD MOMSN=12244483
2020-02-11T01:17:16.790Z,1581383836.790 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-02-11T01:17:16.790Z,1581383836.790 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,    ,      +243.92,      -51.45,   7.67,  0.00
2020-02-11T01:17:24.420Z,1581383844.420 [NAL9602](INFO): SBD MO Status=0, MOMSN=10693, MT Status=0, MTMSN=0
2020-02-11T01:17:24.420Z,1581383844.420 [NAL9602](INFO): No messages in MT queue
2020-02-11T01:17:33.223Z,1581383853.223 [DataOverHttps](INFO): Sending 121 bytes from file Logs/20200210T224734/Express0082.lzma
2020-02-11T01:17:35.229Z,1581383855.229 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Express0082.lzma.bak
2020-02-11T01:17:35.229Z,1581383855.229 [DataOverHttps](INFO): SBD MOMSN=12244489
2020-02-11T01:17:36.966Z,1581383856.966 [Default:CheckIn:Read_Iridium] Stopped
2020-02-11T01:17:36.967Z,1581383856.967 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-11T01:17:36.967Z,1581383856.967 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-11T01:17:55.117Z,1581383875.117 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-11T01:18:36.792Z,1581383916.792 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-02-11T01:18:36.793Z,1581383916.793 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,      -13.57,      +243.8-51.46,   7.68,  0.00
2020-02-11T01:22:31.096Z,1581384151.096 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,  78.95,  74.96,  76.29,  76.29
2020-02-11T01:22:37.570Z,1581384157.570 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-11T01:22:37.570Z,1581384157.570 [Default:CheckIn:C.Wait] Stopped
2020-02-11T01:22:37.570Z,1581384157.570 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-11T01:22:37.570Z,1581384157.570 [Default:CheckIn:D] Running Loop=1
2020-02-11T01:22:37.991Z,1581384157.991 [Default:CheckIn:D] Stopped
2020-02-11T01:22:37.991Z,1581384157.991 [Default:CheckIn:E] Running Loop=1
2020-02-11T01:22:38.411Z,1581384158.411 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 146.116976 min 
2020-02-11T01:22:38.411Z,1581384158.411 [Default:CheckIn:E] Stopped
2020-02-11T01:22:38.411Z,1581384158.411 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-11T01:22:38.412Z,1581384158.412 [Default:CheckIn] Stopped
2020-02-11T01:22:38.412Z,1581384158.412 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-11T01:22:38.412Z,1581384158.412 [Default:CheckIn](INFO): Running loop #27
2020-02-11T01:22:38.412Z,1581384158.412 [Default:CheckIn] Running Loop=27
2020-02-11T01:22:38.412Z,1581384158.412 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-11T01:22:38.412Z,1581384158.412 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-11T01:22:40.375Z,1581384160.375 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,012230.00,A,4450.83516,N,06734.83193,W,0.000,0.00,110220,,,D*71
2020-02-11T01:22:40.377Z,1581384160.377 [NAL9602](INFO): GPS fix at 20200211T012230: (44.847253, -67.580532)
2020-02-11T01:22:40.420Z,1581384160.420 [Default:CheckIn:Read_GPS] Stopped
2020-02-11T01:22:40.420Z,1581384160.420 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-11T01:22:48.346Z,1581384168.346 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200210T224734/Courier0084.lzma
2020-02-11T01:22:50.353Z,1581384170.353 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Courier0084.lzma.bak
2020-02-11T01:22:50.353Z,1581384170.353 [DataOverHttps](INFO): SBD MOMSN=12244495
2020-02-11T01:22:59.043Z,1581384179.043 [RDI_Pathfinder](ERROR): Failed to parse:
  -14.15,      +243.62,      -51.63,   7.68,  0.00
2020-02-11T01:23:08.471Z,1581384188.471 [DataOverHttps](INFO): Sending 121 bytes from file Logs/20200210T224734/Express0085.lzma
2020-02-11T01:23:10.477Z,1581384190.477 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Express0085.lzma.bak
2020-02-11T01:23:10.477Z,1581384190.477 [DataOverHttps](INFO): SBD MOMSN=12244498
2020-02-11T01:23:12.319Z,1581384192.319 [Default:CheckIn:Read_Iridium] Stopped
2020-02-11T01:23:12.319Z,1581384192.319 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-11T01:23:12.319Z,1581384192.319 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-11T01:23:18.876Z,1581384198.876 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2020-02-11T01:23:18.876Z,1581384198.876 [RDI_Pathfinder] Communications Fault, FailCount= 1
2020-02-11T01:23:18.876Z,1581384198.876 [RDI_Pathfinder](ERROR): Communications Fault
2020-02-11T01:23:18.877Z,1581384198.877 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-11T01:23:18.926Z,1581384198.926 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-02-11T01:23:19.243Z,1581384199.243 [RDI_Pathfinder](INFO): Powering down
2020-02-11T01:23:20.003Z,1581384200.003 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-02-11T01:23:20.003Z,1581384200.003 [RDI_Pathfinder] No Fault, FailCount= 1
2020-02-11T01:23:22.387Z,1581384202.387 [NAL9602](INFO): SBD MO Status=0, MOMSN=10694, MT Status=0, MTMSN=0
2020-02-11T01:23:22.387Z,1581384202.387 [NAL9602](INFO): No messages in MT queue
2020-02-11T01:23:33.313Z,1581384213.313 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2020-02-11T01:23:53.089Z,1581384233.089 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-11T01:24:43.612Z,1581384283.612 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-11T01:24:43.612Z,1581384283.612 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,  78.62,  76.29,  76.29
2020-02-11T01:25:44.626Z,1581384344.626 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-11T01:25:44.626Z,1581384344.626 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20021018384918,35.0, -.0,1448.9,  0
2020-02-11T01:28:12.911Z,1581384492.911 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-11T01:28:12.911Z,1581384492.911 [Default:CheckIn:C.Wait] Stopped
2020-02-11T01:28:12.911Z,1581384492.911 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-11T01:28:12.911Z,1581384492.911 [Default:CheckIn:D] Running Loop=1
2020-02-11T01:28:13.331Z,1581384493.331 [Default:CheckIn:D] Stopped
2020-02-11T01:28:13.331Z,1581384493.331 [Default:CheckIn:E] Running Loop=1
2020-02-11T01:28:13.707Z,1581384493.707 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 151.705990 min 
2020-02-11T01:28:13.707Z,1581384493.707 [Default:CheckIn:E] Stopped
2020-02-11T01:28:13.707Z,1581384493.707 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-11T01:28:13.708Z,1581384493.708 [Default:CheckIn] Stopped
2020-02-11T01:28:13.708Z,1581384493.708 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-11T01:28:13.708Z,1581384493.708 [Default:CheckIn](INFO): Running loop #28
2020-02-11T01:28:13.708Z,1581384493.708 [Default:CheckIn] Running Loop=28
2020-02-11T01:28:13.708Z,1581384493.708 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-11T01:28:13.708Z,1581384493.708 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-11T01:28:15.736Z,1581384495.736 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,012805.00,A,4450.83497,N,06734.83177,W,0.000,0.00,110220,,,D*7F
2020-02-11T01:28:15.738Z,1581384495.738 [NAL9602](INFO): GPS fix at 20200211T012805: (44.847249, -67.580529)
2020-02-11T01:28:15.785Z,1581384495.785 [Default:CheckIn:Read_GPS] Stopped
2020-02-11T01:28:15.785Z,1581384495.785 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-11T01:28:23.454Z,1581384503.454 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200210T224734/Courier0087.lzma
2020-02-11T01:28:25.461Z,1581384505.461 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Courier0087.lzma.bak
2020-02-11T01:28:25.461Z,1581384505.461 [DataOverHttps](INFO): SBD MOMSN=12244511
2020-02-11T01:28:30.287Z,1581384510.287 [NAL9602](INFO): SBD MO Status=0, MOMSN=10695, MT Status=0, MTMSN=0
2020-02-11T01:28:30.287Z,1581384510.287 [NAL9602](INFO): No messages in MT queue
2020-02-11T01:28:42.758Z,1581384522.758 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200210T224734/Express0088.lzma
2020-02-11T01:28:43.262Z,1581384523.262 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-02-11T01:28:43.262Z,1581384523.262 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.  -0.69,       -0.54,   7.65,  0.00
2020-02-11T01:28:44.765Z,1581384524.765 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Express0088.lzma.bak
2020-02-11T01:28:44.765Z,1581384524.765 [DataOverHttps](INFO): SBD MOMSN=12244514
2020-02-11T01:28:46.511Z,1581384526.511 [Default:CheckIn:Read_Iridium] Stopped
2020-02-11T01:28:46.511Z,1581384526.511 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-11T01:28:46.511Z,1581384526.511 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-11T01:29:01.001Z,1581384541.001 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-11T01:29:02.259Z,1581384542.259 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-11T01:29:02.259Z,1581384542.259 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,   +13,    -3,    -9   +13,    -3,  :BD,        +0.71,       -0.83,       -0.61,   7.65,  0.00
2020-02-11T01:29:21.252Z,1581384561.252 [RDI_Pathfinder](ERROR): Failed to parse: +3,    +2,    -3,A
2020-02-11T01:30:22.624Z,1581384622.624 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-11T01:30:22.624Z,1581384622.624 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20021018432718,35.0, -0.1,   0.0,1448.9,,  0.00,  78.62,  75.63,  76.29,  76.29
2020-02-11T01:32:01.608Z,1581384721.608 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-11T01:32:01.608Z,1581384721.608 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,2002101845061-0.1,   0.0,1448.9,  0
2020-02-11T01:33:47.052Z,1581384827.052 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-11T01:33:47.052Z,1581384827.052 [Default:CheckIn:C.Wait] Stopped
2020-02-11T01:33:47.052Z,1581384827.052 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-11T01:33:47.052Z,1581384827.052 [Default:CheckIn:D] Running Loop=1
2020-02-11T01:33:47.475Z,1581384827.475 [Default:CheckIn:D] Stopped
2020-02-11T01:33:47.475Z,1581384827.475 [Default:CheckIn:E] Running Loop=1
2020-02-11T01:33:47.872Z,1581384827.872 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 157.275049 min 
2020-02-11T01:33:47.872Z,1581384827.872 [Default:CheckIn:E] Stopped
2020-02-11T01:33:47.872Z,1581384827.872 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-11T01:33:47.873Z,1581384827.873 [Default:CheckIn] Stopped
2020-02-11T01:33:47.873Z,1581384827.873 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-11T01:33:47.873Z,1581384827.873 [Default:CheckIn](INFO): Running loop #29
2020-02-11T01:33:47.873Z,1581384827.873 [Default:CheckIn] Running Loop=29
2020-02-11T01:33:47.873Z,1581384827.873 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-11T01:33:47.873Z,1581384827.873 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-11T01:33:49.863Z,1581384829.863 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,013339.00,A,4450.83503,N,06734.83154,W,0.000,0.00,110220,,,D*77
2020-02-11T01:33:49.865Z,1581384829.865 [NAL9602](INFO): GPS fix at 20200211T013339: (44.847251, -67.580526)
2020-02-11T01:33:49.913Z,1581384829.913 [Default:CheckIn:Read_GPS] Stopped
2020-02-11T01:33:49.913Z,1581384829.913 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-11T01:33:57.874Z,1581384837.874 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200210T224734/Courier0090.lzma
2020-02-11T01:33:59.881Z,1581384839.881 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Courier0090.lzma.bak
2020-02-11T01:33:59.881Z,1581384839.881 [DataOverHttps](INFO): SBD MOMSN=12244519
2020-02-11T01:34:01.983Z,1581384841.983 [NAL9602](INFO): SBD MO Status=0, MOMSN=10696, MT Status=0, MTMSN=0
2020-02-11T01:34:01.983Z,1581384841.983 [NAL9602](INFO): No messages in MT queue
2020-02-11T01:34:20.555Z,1581384860.555 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200210T224734/Express0091.lzma
2020-02-11T01:34:22.562Z,1581384862.562 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Express0091.lzma.bak
2020-02-11T01:34:22.562Z,1581384862.562 [DataOverHttps](INFO): SBD MOMSN=12244522
2020-02-11T01:34:24.670Z,1581384864.670 [Default:CheckIn:Read_Iridium] Stopped
2020-02-11T01:34:24.670Z,1581384864.670 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-11T01:34:24.670Z,1581384864.670 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-11T01:34:32.717Z,1581384872.717 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-11T01:39:25.301Z,1581385165.301 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-11T01:39:25.301Z,1581385165.301 [Default:CheckIn:C.Wait] Stopped
2020-02-11T01:39:25.301Z,1581385165.301 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-11T01:39:25.302Z,1581385165.302 [Default:CheckIn:D] Running Loop=1
2020-02-11T01:39:25.666Z,1581385165.666 [Default:CheckIn:D] Stopped
2020-02-11T01:39:25.666Z,1581385165.666 [Default:CheckIn:E] Running Loop=1
2020-02-11T01:39:26.081Z,1581385166.081 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 162.911572 min 
2020-02-11T01:39:26.082Z,1581385166.082 [Default:CheckIn:E] Stopped
2020-02-11T01:39:26.082Z,1581385166.082 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-11T01:39:26.082Z,1581385166.082 [Default:CheckIn] Stopped
2020-02-11T01:39:26.082Z,1581385166.082 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-11T01:39:26.082Z,1581385166.082 [Default:CheckIn](INFO): Running loop #30
2020-02-11T01:39:26.082Z,1581385166.082 [Default:CheckIn] Running Loop=30
2020-02-11T01:39:26.082Z,1581385166.082 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-11T01:39:26.082Z,1581385166.082 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-11T01:39:28.063Z,1581385168.063 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,013917.00,A,4450.83498,N,06734.83182,W,0.019,0.00,110220,,,D*71
2020-02-11T01:39:28.065Z,1581385168.065 [NAL9602](INFO): GPS fix at 20200211T013917: (44.847250, -67.580530)
2020-02-11T01:39:28.089Z,1581385168.089 [Default:CheckIn:Read_GPS] Stopped
2020-02-11T01:39:28.089Z,1581385168.089 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-11T01:39:35.314Z,1581385175.314 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200210T224734/Courier0093.lzma
2020-02-11T01:39:37.321Z,1581385177.321 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Courier0093.lzma.bak
2020-02-11T01:39:37.321Z,1581385177.321 [DataOverHttps](INFO): SBD MOMSN=12244533
2020-02-11T01:39:38.591Z,1581385178.591 [NAL9602](INFO): SBD MO Status=0, MOMSN=10697, MT Status=0, MTMSN=0
2020-02-11T01:39:38.595Z,1581385178.595 [NAL9602](INFO): No messages in MT queue
2020-02-11T01:39:54.407Z,1581385194.407 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200210T224734/Express0094.lzma
2020-02-11T01:39:56.421Z,1581385196.421 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Express0094.lzma.bak
2020-02-11T01:39:56.421Z,1581385196.421 [DataOverHttps](INFO): SBD MOMSN=12244536
2020-02-11T01:39:58.425Z,1581385198.425 [Default:CheckIn:Read_Iridium] Stopped
2020-02-11T01:39:58.425Z,1581385198.425 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-11T01:39:58.425Z,1581385198.425 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-11T01:40:09.293Z,1581385209.293 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-11T01:40:42.453Z,1581385242.453 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-11T01:40:42.453Z,1581385242.453 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,  78.63,   7.67,  0.00
2020-02-11T01:42:01.258Z,1581385321.258 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-02-11T01:42:01.258Z,1581385321.258 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +2.14,       -    -1.21,   7.69,  0.00
2020-02-11T01:43:21.256Z,1581385401.256 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-11T01:43:21.256Z,1581385401.256 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20021018562618,35.0, -0.1,   0.0, 0
2020-02-11T01:44:18.622Z,1581385458.622 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-11T01:44:18.622Z,1581385458.622 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20021018572318,35.0, -0.1,  8.9,  0
2020-02-11T01:44:41.262Z,1581385481.262 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-02-11T01:44:41.262Z,1581385481.262 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,   -2,    -2,A
2020-02-11T01:44:59.016Z,1581385499.016 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-11T01:44:59.016Z,1581385499.016 [Default:CheckIn:C.Wait] Stopped
2020-02-11T01:44:59.016Z,1581385499.016 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-11T01:44:59.017Z,1581385499.017 [Default:CheckIn:D] Running Loop=1
2020-02-11T01:44:59.418Z,1581385499.418 [Default:CheckIn:D] Stopped
2020-02-11T01:44:59.418Z,1581385499.418 [Default:CheckIn:E] Running Loop=1
2020-02-11T01:44:59.823Z,1581385499.823 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 168.474105 min 
2020-02-11T01:44:59.823Z,1581385499.823 [Default:CheckIn:E] Stopped
2020-02-11T01:44:59.823Z,1581385499.823 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-11T01:44:59.824Z,1581385499.824 [Default:CheckIn] Stopped
2020-02-11T01:44:59.824Z,1581385499.824 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-11T01:44:59.824Z,1581385499.824 [Default:CheckIn](INFO): Running loop #31
2020-02-11T01:44:59.824Z,1581385499.824 [Default:CheckIn] Running Loop=31
2020-02-11T01:44:59.824Z,1581385499.824 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-11T01:44:59.824Z,1581385499.824 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-11T01:45:01.815Z,1581385501.815 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,014451.00,A,4450.83536,N,06734.83191,W,0.019,0.00,110220,,,D*7E
2020-02-11T01:45:01.817Z,1581385501.817 [NAL9602](INFO): GPS fix at 20200211T014451: (44.847256, -67.580532)
2020-02-11T01:45:01.859Z,1581385501.859 [Default:CheckIn:Read_GPS] Stopped
2020-02-11T01:45:01.859Z,1581385501.859 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-11T01:45:09.482Z,1581385509.482 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200210T224734/Courier0096.lzma
2020-02-11T01:45:11.489Z,1581385511.489 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Courier0096.lzma.bak
2020-02-11T01:45:11.489Z,1581385511.489 [DataOverHttps](INFO): SBD MOMSN=12244541
2020-02-11T01:45:29.587Z,1581385529.587 [DataOverHttps](INFO): Sending 121 bytes from file Logs/20200210T224734/Express0097.lzma
2020-02-11T01:45:31.593Z,1581385531.593 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Express0097.lzma.bak
2020-02-11T01:45:31.593Z,1581385531.593 [DataOverHttps](INFO): SBD MOMSN=12244544
2020-02-11T01:45:33.366Z,1581385533.366 [Default:CheckIn:Read_Iridium] Stopped
2020-02-11T01:45:33.366Z,1581385533.366 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-11T01:45:33.366Z,1581385533.366 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-11T01:45:40.198Z,1581385540.198 [NAL9602](INFO): SBD MO Status=0, MOMSN=10698, MT Status=0, MTMSN=0
2020-02-11T01:45:40.198Z,1581385540.198 [NAL9602](INFO): No messages in MT queue
2020-02-11T01:46:10.897Z,1581385570.897 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-11T01:46:58.620Z,1581385618.620 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-11T01:46:58.620Z,1581385618.620 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,  74.63,  76.29,  76.29
2020-02-11T01:47:19.628Z,1581385639.628 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-11T01:47:19.628Z,1581385639.628 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,200210190.0, -0.1,   0.0,1448.9,  0
2020-02-11T01:48:39.668Z,1581385719.668 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-11T01:48:39.668Z,1581385719.668 [RDI_Pathfinder](ERROR): Failed to parse:
:RA, 78.62,  75.29,  75.96,  75.96
2020-02-11T01:50:33.964Z,1581385833.964 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-11T01:50:33.964Z,1581385833.964 [Default:CheckIn:C.Wait] Stopped
2020-02-11T01:50:33.964Z,1581385833.964 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-11T01:50:33.964Z,1581385833.964 [Default:CheckIn:D] Running Loop=1
2020-02-11T01:50:34.384Z,1581385834.384 [Default:CheckIn:D] Stopped
2020-02-11T01:50:34.385Z,1581385834.385 [Default:CheckIn:E] Running Loop=1
2020-02-11T01:50:34.769Z,1581385834.769 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 174.056868 min 
2020-02-11T01:50:34.769Z,1581385834.769 [Default:CheckIn:E] Stopped
2020-02-11T01:50:34.769Z,1581385834.769 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-11T01:50:34.769Z,1581385834.769 [Default:CheckIn] Stopped
2020-02-11T01:50:34.769Z,1581385834.769 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-11T01:50:34.769Z,1581385834.769 [Default:CheckIn](INFO): Running loop #32
2020-02-11T01:50:34.770Z,1581385834.770 [Default:CheckIn] Running Loop=32
2020-02-11T01:50:34.770Z,1581385834.770 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-11T01:50:34.770Z,1581385834.770 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-11T01:50:36.768Z,1581385836.768 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,015026.00,A,4450.83498,N,06734.83203,W,0.019,0.00,110220,,,D*76
2020-02-11T01:50:36.770Z,1581385836.770 [NAL9602](INFO): GPS fix at 20200211T015026: (44.847250, -67.580534)
2020-02-11T01:50:36.795Z,1581385836.795 [Default:CheckIn:Read_GPS] Stopped
2020-02-11T01:50:36.795Z,1581385836.795 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-11T01:50:44.846Z,1581385844.846 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200210T224734/Courier0099.lzma
2020-02-11T01:50:46.853Z,1581385846.853 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Courier0099.lzma.bak
2020-02-11T01:50:46.853Z,1581385846.853 [DataOverHttps](INFO): SBD MOMSN=12244557
2020-02-11T01:50:51.817Z,1581385851.817 [NAL9602](INFO): SBD MO Status=0, MOMSN=10699, MT Status=0, MTMSN=0
2020-02-11T01:50:51.818Z,1581385851.818 [NAL9602](INFO): No messages in MT queue
2020-02-11T01:51:04.970Z,1581385864.970 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20200210T224734/Express0100.lzma
2020-02-11T01:51:06.997Z,1581385866.997 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Express0100.lzma.bak
2020-02-11T01:51:06.997Z,1581385866.997 [DataOverHttps](INFO): SBD MOMSN=12244561
2020-02-11T01:51:08.803Z,1581385868.803 [Default:CheckIn:Read_Iridium] Stopped
2020-02-11T01:51:08.819Z,1581385868.819 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-11T01:51:08.819Z,1581385868.819 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-11T01:51:22.510Z,1581385882.510 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-11T01:53:13.636Z,1581385993.636 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-11T01:53:13.636Z,1581385993.636 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.05,  75.29,  76.96,  76.29
2020-02-11T01:56:09.409Z,1581386169.409 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-11T01:56:09.409Z,1581386169.409 [Default:CheckIn:C.Wait] Stopped
2020-02-11T01:56:09.409Z,1581386169.409 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-11T01:56:09.409Z,1581386169.409 [Default:CheckIn:D] Running Loop=1
2020-02-11T01:56:09.813Z,1581386169.813 [Default:CheckIn:D] Stopped
2020-02-11T01:56:09.813Z,1581386169.813 [Default:CheckIn:E] Running Loop=1
2020-02-11T01:56:10.207Z,1581386170.207 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 179.647347 min 
2020-02-11T01:56:10.207Z,1581386170.207 [Default:CheckIn:E] Stopped
2020-02-11T01:56:10.207Z,1581386170.207 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-11T01:56:10.208Z,1581386170.208 [Default:CheckIn] Stopped
2020-02-11T01:56:10.208Z,1581386170.208 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-11T01:56:10.208Z,1581386170.208 [Default:CheckIn](INFO): Running loop #33
2020-02-11T01:56:10.208Z,1581386170.208 [Default:CheckIn] Running Loop=33
2020-02-11T01:56:10.208Z,1581386170.208 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-11T01:56:10.208Z,1581386170.208 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-11T01:56:12.213Z,1581386172.213 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,015602.00,A,4450.83486,N,06734.83182,W,0.019,0.00,110220,,,D*73
2020-02-11T01:56:12.237Z,1581386172.237 [NAL9602](INFO): GPS fix at 20200211T015602: (44.847248, -67.580530)
2020-02-11T01:56:12.343Z,1581386172.343 [Default:CheckIn:Read_GPS] Stopped
2020-02-11T01:56:12.343Z,1581386172.343 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-11T01:56:21.626Z,1581386181.626 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,  78.62,  75.29,  76.62,  76.29
2020-02-11T01:56:22.334Z,1581386182.334 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200210T224734/Courier0102.lzma
2020-02-11T01:56:24.797Z,1581386184.797 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Courier0102.lzma.bak
2020-02-11T01:56:24.798Z,1581386184.798 [DataOverHttps](INFO): SBD MOMSN=12244566
2020-02-11T01:56:29.276Z,1581386189.276 [NAL9602](INFO): SBD MO Status=0, MOMSN=10700, MT Status=0, MTMSN=0
2020-02-11T01:56:29.276Z,1581386189.276 [NAL9602](INFO): No messages in MT queue
2020-02-11T01:56:41.564Z,1581386201.564 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20200210T224734/Express0103.lzma
2020-02-11T01:56:43.569Z,1581386203.569 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Express0103.lzma.bak
2020-02-11T01:56:43.569Z,1581386203.569 [DataOverHttps](INFO): SBD MOMSN=12244569
2020-02-11T01:56:45.471Z,1581386205.471 [Default:CheckIn:Read_Iridium] Stopped
2020-02-11T01:56:45.471Z,1581386205.471 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-11T01:56:45.471Z,1581386205.471 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-11T01:56:59.985Z,1581386219.985 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-11T02:01:26.260Z,1581386486.260 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-11T02:01:26.260Z,1581386486.260 [RDI_Pathfinder](ERROR): Failed to parse:
:TS

2020-02-11T02:01:46.040Z,1581386506.040 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-11T02:01:46.040Z,1581386506.040 [Default:CheckIn:C.Wait] Stopped
2020-02-11T02:01:46.040Z,1581386506.040 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-11T02:01:46.041Z,1581386506.041 [Default:CheckIn:D] Running Loop=1
2020-02-11T02:01:46.453Z,1581386506.453 [Default:CheckIn:D] Stopped
2020-02-11T02:01:46.453Z,1581386506.453 [Default:CheckIn:E] Running Loop=1
2020-02-11T02:01:46.844Z,1581386506.844 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 185.258008 min 
2020-02-11T02:01:46.844Z,1581386506.844 [Default:CheckIn:E] Stopped
2020-02-11T02:01:46.844Z,1581386506.844 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-11T02:01:46.844Z,1581386506.844 [Default:CheckIn] Stopped
2020-02-11T02:01:46.845Z,1581386506.845 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-11T02:01:46.845Z,1581386506.845 [Default:CheckIn](INFO): Running loop #34
2020-02-11T02:01:46.845Z,1581386506.845 [Default:CheckIn] Running Loop=34
2020-02-11T02:01:46.845Z,1581386506.845 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-11T02:01:46.845Z,1581386506.845 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-11T02:01:48.834Z,1581386508.833 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,020138.00,A,4450.83521,N,06734.83245,W,0.019,0.00,110220,,,D*7F
2020-02-11T02:01:48.847Z,1581386508.847 [NAL9602](INFO): GPS fix at 20200211T020138: (44.847254, -67.580541)
2020-02-11T02:01:48.875Z,1581386508.875 [Default:CheckIn:Read_GPS] Stopped
2020-02-11T02:01:48.875Z,1581386508.875 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-11T02:01:56.614Z,1581386516.614 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200210T224734/Courier0105.lzma
2020-02-11T02:01:58.621Z,1581386518.621 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Courier0105.lzma.bak
2020-02-11T02:01:58.621Z,1581386518.621 [DataOverHttps](INFO): SBD MOMSN=12244580
2020-02-11T02:02:06.261Z,1581386526.261 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,   +12,    +0,    -2,A
2020-02-11T02:02:19.687Z,1581386539.687 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200210T224734/Express0106.lzma
2020-02-11T02:02:21.693Z,1581386541.693 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Express0106.lzma.bak
2020-02-11T02:02:21.693Z,1581386541.693 [DataOverHttps](INFO): SBD MOMSN=12244583
2020-02-11T02:02:23.603Z,1581386543.603 [NAL9602](INFO): SBD MO Status=2, MOMSN=10701, MT Status=2, MTMSN=0
2020-02-11T02:02:23.603Z,1581386543.603 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-02-11T02:02:23.652Z,1581386543.652 [Default:CheckIn:Read_Iridium] Stopped
2020-02-11T02:02:23.652Z,1581386543.652 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-11T02:02:23.652Z,1581386543.652 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-11T02:02:46.249Z,1581386566.249 [NAL9602](INFO): SBD MO Status=0, MOMSN=10701, MT Status=0, MTMSN=0
2020-02-11T02:02:46.249Z,1581386566.249 [NAL9602](INFO): No messages in MT queue
2020-02-11T02:03:03.273Z,1581386583.273 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-11T02:03:03.273Z,1581386583.273 [RDI_Pathfinder](ERROR): Failed to parse:
:TS9160818,35.0, -0.1,   0.0,1448.9,  0
2020-02-11T02:03:16.933Z,1581386596.933 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-11T02:05:43.625Z,1581386743.625 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-11T02:05:43.626Z,1581386743.626 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20021019184818,35.0, -0.1,   0.0,1448.9A,  0.00,  78.95,  74.63,  76.96,  76.96
2020-02-11T02:07:24.257Z,1581386844.257 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-11T02:07:24.257Z,1581386844.257 [Default:CheckIn:C.Wait] Stopped
2020-02-11T02:07:24.257Z,1581386844.257 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-11T02:07:24.258Z,1581386844.258 [Default:CheckIn:D] Running Loop=1
2020-02-11T02:07:24.641Z,1581386844.641 [Default:CheckIn:D] Stopped
2020-02-11T02:07:24.641Z,1581386844.641 [Default:CheckIn:E] Running Loop=1
2020-02-11T02:07:25.047Z,1581386845.047 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 190.894482 min 
2020-02-11T02:07:25.047Z,1581386845.047 [Default:CheckIn:E] Stopped
2020-02-11T02:07:25.047Z,1581386845.047 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-11T02:07:25.048Z,1581386845.048 [Default:CheckIn] Stopped
2020-02-11T02:07:25.048Z,1581386845.048 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-11T02:07:25.048Z,1581386845.048 [Default:CheckIn](INFO): Running loop #35
2020-02-11T02:07:25.048Z,1581386845.048 [Default:CheckIn] Running Loop=35
2020-02-11T02:07:25.048Z,1581386845.048 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-11T02:07:25.048Z,1581386845.048 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-11T02:07:27.025Z,1581386847.025 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,020716.00,A,4450.83487,N,06734.83172,W,0.000,0.00,110220,,,D*77
2020-02-11T02:07:27.028Z,1581386847.028 [NAL9602](INFO): GPS fix at 20200211T020716: (44.847248, -67.580529)
2020-02-11T02:07:27.071Z,1581386847.071 [Default:CheckIn:Read_GPS] Stopped
2020-02-11T02:07:27.071Z,1581386847.071 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-11T02:07:34.778Z,1581386854.778 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200210T224734/Courier0108.lzma
2020-02-11T02:07:36.785Z,1581386856.785 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Courier0108.lzma.bak
2020-02-11T02:07:36.785Z,1581386856.785 [DataOverHttps](INFO): SBD MOMSN=12244590
2020-02-11T02:07:43.995Z,1581386863.995 [NAL9602](INFO): SBD MO Status=0, MOMSN=10702, MT Status=0, MTMSN=0
2020-02-11T02:07:43.995Z,1581386863.995 [NAL9602](INFO): No messages in MT queue
2020-02-11T02:07:54.335Z,1581386874.335 [DataOverHttps](INFO): Sending 121 bytes from file Logs/20200210T224734/Express0109.lzma
2020-02-11T02:07:56.341Z,1581386876.341 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Express0109.lzma.bak
2020-02-11T02:07:56.341Z,1581386876.341 [DataOverHttps](INFO): SBD MOMSN=12244593
2020-02-11T02:07:58.195Z,1581386878.195 [Default:CheckIn:Read_Iridium] Stopped
2020-02-11T02:07:58.195Z,1581386878.195 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-11T02:07:58.195Z,1581386878.195 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-11T02:08:04.632Z,1581386884.632 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-11T02:08:04.632Z,1581386884.632 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20021019210918,35.   0.0,1448.9,  0
2020-02-11T02:08:07.473Z,1581386887.473 [RDI_Pathfinder](ERROR): only read 1 of 4 data items
2020-02-11T02:08:07.473Z,1581386887.473 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,    +5    -1,A
2020-02-11T02:08:14.709Z,1581386894.709 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-11T02:09:05.629Z,1581386945.629 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-11T02:09:05.629Z,1581386945.629 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20021019221018,35.0, -0.1,   0.0,1448.RA,  0.00,  78.95,  74.96,  76.29,  76.62
2020-02-11T02:11:04.420Z,1581387064.420 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,  78.95,  74.63,  76.96,  76.62
2020-02-11T02:11:47.264Z,1581387107.264 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-11T02:11:47.264Z,1581387107.264 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.62,  74.63,  76.96,  76.62
2020-02-11T02:12:21.629Z,1581387141.629 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-11T02:12:21.629Z,1581387141.629 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,200210195.0, -0.1,   0.0,1448.9,  0
2020-02-11T02:12:39.825Z,1581387159.825 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-02-11T02:12:39.828Z,1581387159.828 [BPC1](INFO): Received data from all battery sticks.
2020-02-11T02:12:42.266Z,1581387162.266 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-11T02:12:42.266Z,1581387162.266 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,  75.29,  76.96,  76.62
2020-02-11T02:12:58.812Z,1581387178.812 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-11T02:12:58.812Z,1581387178.812 [Default:CheckIn:C.Wait] Stopped
2020-02-11T02:12:58.812Z,1581387178.812 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-11T02:12:58.813Z,1581387178.813 [Default:CheckIn:D] Running Loop=1
2020-02-11T02:12:59.260Z,1581387179.260 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-11T02:12:59.261Z,1581387179.261 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20021019260418,35.0  0.0,1448.9,  0
2020-02-11T02:12:59.275Z,1581387179.275 [Default:CheckIn:D] Stopped
2020-02-11T02:12:59.275Z,1581387179.275 [Default:CheckIn:E] Running Loop=1
2020-02-11T02:12:59.622Z,1581387179.622 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 196.471712 min 
2020-02-11T02:12:59.623Z,1581387179.623 [Default:CheckIn:E] Stopped
2020-02-11T02:12:59.623Z,1581387179.623 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-11T02:12:59.623Z,1581387179.623 [Default:CheckIn] Stopped
2020-02-11T02:12:59.623Z,1581387179.623 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-11T02:12:59.623Z,1581387179.623 [Default:CheckIn](INFO): Running loop #36
2020-02-11T02:12:59.623Z,1581387179.623 [Default:CheckIn] Running Loop=36
2020-02-11T02:12:59.623Z,1581387179.623 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-11T02:12:59.624Z,1581387179.624 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-11T02:13:01.615Z,1581387181.615 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,021251.00,A,4450.83511,N,06734.83197,W,0.039,0.00,110220,,,D*7F
2020-02-11T02:13:01.617Z,1581387181.617 [NAL9602](INFO): GPS fix at 20200211T021251: (44.847252, -67.580533)
2020-02-11T02:13:01.660Z,1581387181.660 [Default:CheckIn:Read_GPS] Stopped
2020-02-11T02:13:01.660Z,1581387181.660 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-11T02:13:09.606Z,1581387189.606 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20200210T224734/Courier0111.lzma
2020-02-11T02:13:11.613Z,1581387191.613 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Courier0111.lzma.bak
2020-02-11T02:13:11.613Z,1581387191.613 [DataOverHttps](INFO): SBD MOMSN=12244606
2020-02-11T02:13:28.811Z,1581387208.811 [DataOverHttps](INFO): Sending 121 bytes from file Logs/20200210T224734/Express0112.lzma
2020-02-11T02:13:30.817Z,1581387210.817 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Express0112.lzma.bak
2020-02-11T02:13:30.817Z,1581387210.817 [DataOverHttps](INFO): SBD MOMSN=12244609
2020-02-11T02:13:32.754Z,1581387212.754 [Default:CheckIn:Read_Iridium] Stopped
2020-02-11T02:13:32.754Z,1581387212.754 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-11T02:13:32.754Z,1581387212.754 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-11T02:13:39.188Z,1581387219.188 [NAL9602](INFO): SBD MO Status=0, MOMSN=10703, MT Status=0, MTMSN=0
2020-02-11T02:13:39.188Z,1581387219.188 [NAL9602](INFO): No messages in MT queue
2020-02-11T02:14:09.922Z,1581387249.922 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-11T02:14:40.270Z,1581387280.270 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-11T02:14:40.270Z,1581387280.270 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,  78.62,  74.63,  75.96,

2020-02-11T02:14:58.420Z,1581387298.420 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,  78.29,  75.29,  75.96,  76.96  -7,   -12,    +2,    +7,A
2020-02-11T02:16:19.618Z,1581387379.618 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-11T02:16:19.618Z,1581387379.618 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20021019292418,35.0, -0.1, 48.9,  0
2020-02-11T02:16:42.263Z,1581387402.263 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-11T02:16:42.263Z,1581387402.263 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,  78.95,  74.96,  76.96,

2020-02-11T02:17:41.654Z,1581387461.654 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-02-11T02:17:41.655Z,1581387461.655 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,   +15,    4,A
2020-02-11T02:18:33.360Z,1581387513.360 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-11T02:18:33.360Z,1581387513.360 [Default:CheckIn:C.Wait] Stopped
2020-02-11T02:18:33.360Z,1581387513.360 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-11T02:18:33.360Z,1581387513.360 [Default:CheckIn:D] Running Loop=1
2020-02-11T02:18:33.753Z,1581387513.753 [Default:CheckIn:D] Stopped
2020-02-11T02:18:33.753Z,1581387513.753 [Default:CheckIn:E] Running Loop=1
2020-02-11T02:18:34.163Z,1581387514.163 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 202.046338 min 
2020-02-11T02:18:34.163Z,1581387514.163 [Default:CheckIn:E] Stopped
2020-02-11T02:18:34.163Z,1581387514.163 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-11T02:18:34.163Z,1581387514.163 [Default:CheckIn] Stopped
2020-02-11T02:18:34.164Z,1581387514.164 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-11T02:18:34.164Z,1581387514.164 [Default:CheckIn](INFO): Running loop #37
2020-02-11T02:18:34.164Z,1581387514.164 [Default:CheckIn] Running Loop=37
2020-02-11T02:18:34.164Z,1581387514.164 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-11T02:18:34.164Z,1581387514.164 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-11T02:18:36.151Z,1581387516.151 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,021825.00,A,4450.83528,N,06734.83199,W,0.039,0.00,110220,,,D*72
2020-02-11T02:18:36.153Z,1581387516.153 [NAL9602](INFO): GPS fix at 20200211T021825: (44.847255, -67.580533)
2020-02-11T02:18:36.199Z,1581387516.199 [Default:CheckIn:Read_GPS] Stopped
2020-02-11T02:18:36.199Z,1581387516.199 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-11T02:18:43.614Z,1581387523.614 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200210T224734/Courier0114.lzma
2020-02-11T02:18:45.621Z,1581387525.621 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Courier0114.lzma.bak
2020-02-11T02:18:45.621Z,1581387525.621 [DataOverHttps](INFO): SBD MOMSN=12244615
2020-02-11T02:18:48.683Z,1581387528.683 [NAL9602](INFO): SBD MO Status=0, MOMSN=10704, MT Status=0, MTMSN=0
2020-02-11T02:18:48.687Z,1581387528.687 [NAL9602](INFO): No messages in MT queue
2020-02-11T02:19:02.863Z,1581387542.863 [DataOverHttps](INFO): Sending 121 bytes from file Logs/20200210T224734/Express0115.lzma
2020-02-11T02:19:04.869Z,1581387544.869 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Express0115.lzma.bak
2020-02-11T02:19:04.869Z,1581387544.869 [DataOverHttps](INFO): SBD MOMSN=12244618
2020-02-11T02:19:06.920Z,1581387546.920 [Default:CheckIn:Read_Iridium] Stopped
2020-02-11T02:19:06.920Z,1581387546.920 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-11T02:19:06.920Z,1581387546.920 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-11T02:19:19.374Z,1581387559.374 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-11T02:19:43.276Z,1581387583.276 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-11T02:19:43.276Z,1581387583.276 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,  78.95,  74.96,  76.29
2020-02-11T02:20:23.654Z,1581387623.654 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,    +0,   -21,    -5,A
2020-02-11T02:20:44.271Z,1581387644.271 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-02-11T02:20:44.271Z,1581387644.271 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,    +0,   -1,A
2020-02-11T02:21:43.636Z,1581387703.636 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-11T02:21:43.636Z,1581387703.636 [RDI_Pathfinder](ERROR): Failed to parse:
:TS9344818,35.0, -0.1,   0.0,1448.9,  0
2020-02-11T02:22:44.269Z,1581387764.269 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-02-11T02:22:44.269Z,1581387764.269 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +4.0020.46,       -1.69,   7.69,  0.00
2020-02-11T02:23:46.471Z,1581387826.471 [RDI_Pathfinder](ERROR): Failed to parse:
:   +4.35,      -20.39,       -1.74,   7.66,  0.00
2020-02-11T02:24:07.478Z,1581387847.478 [RDI_Pathfinder](ERROR): only read 0 of 4 data items
2020-02-11T02:24:07.478Z,1581387847.478 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,    +,      -20.47,       -1.77,   7.68,  0.00
2020-02-11T02:24:07.504Z,1581387847.504 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-11T02:24:07.504Z,1581387847.504 [Default:CheckIn:C.Wait] Stopped
2020-02-11T02:24:07.504Z,1581387847.504 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-11T02:24:07.505Z,1581387847.505 [Default:CheckIn:D] Running Loop=1
2020-02-11T02:24:07.885Z,1581387847.885 [Default:CheckIn:D] Stopped
2020-02-11T02:24:07.885Z,1581387847.885 [Default:CheckIn:E] Running Loop=1
2020-02-11T02:24:08.310Z,1581387848.310 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 207.615218 min 
2020-02-11T02:24:08.310Z,1581387848.310 [Default:CheckIn:E] Stopped
2020-02-11T02:24:08.310Z,1581387848.310 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-11T02:24:08.310Z,1581387848.310 [Default:CheckIn] Stopped
2020-02-11T02:24:08.310Z,1581387848.310 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-11T02:24:08.310Z,1581387848.310 [Default:CheckIn](INFO): Running loop #38
2020-02-11T02:24:08.310Z,1581387848.310 [Default:CheckIn] Running Loop=38
2020-02-11T02:24:08.311Z,1581387848.311 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-11T02:24:08.311Z,1581387848.311 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-11T02:24:10.282Z,1581387850.282 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,022359.00,A,4450.83520,N,06734.83161,W,0.019,0.00,110220,,,D*7C
2020-02-11T02:24:10.284Z,1581387850.284 [NAL9602](INFO): GPS fix at 20200211T022359: (44.847253, -67.580527)
2020-02-11T02:24:10.317Z,1581387850.317 [Default:CheckIn:Read_GPS] Stopped
2020-02-11T02:24:10.317Z,1581387850.317 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-11T02:24:15.565Z,1581387855.565 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-11T02:24:15.565Z,1581387855.565 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,  78.29,  76.29,  76.29
2020-02-11T02:24:21.621Z,1581387861.621 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-11T02:24:21.621Z,1581387861.621 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20021019372618,35,   0.0,1448.9,  0
2020-02-11T02:24:22.430Z,1581387862.430 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200210T224734/Courier0117.lzma
2020-02-11T02:24:23.213Z,1581387863.213 [NAL9602](INFO): SBD MO Status=0, MOMSN=10705, MT Status=0, MTMSN=0
2020-02-11T02:24:23.213Z,1581387863.213 [NAL9602](INFO): No messages in MT queue
2020-02-11T02:24:23.642Z,1581387863.642 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-11T02:24:23.642Z,1581387863.642 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,200210193728180.1,   0.0,1448.9,  0
2020-02-11T02:24:24.437Z,1581387864.437 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Courier0117.lzma.bak
2020-02-11T02:24:24.437Z,1581387864.437 [DataOverHttps](INFO): SBD MOMSN=12244630
2020-02-11T02:24:41.647Z,1581387881.647 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200210T224734/Express0118.lzma
2020-02-11T02:24:43.653Z,1581387883.653 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Express0118.lzma.bak
2020-02-11T02:24:43.653Z,1581387883.653 [DataOverHttps](INFO): SBD MOMSN=12244633
2020-02-11T02:24:45.470Z,1581387885.470 [Default:CheckIn:Read_Iridium] Stopped
2020-02-11T02:24:45.470Z,1581387885.470 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-11T02:24:45.470Z,1581387885.470 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-11T02:24:53.917Z,1581387893.917 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-11T02:25:43.622Z,1581387943.622 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-11T02:25:43.622Z,1581387943.622 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20021019384, -0.1,   0.0,1448.9,  0
2020-02-11T02:29:46.042Z,1581388186.042 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-11T02:29:46.042Z,1581388186.042 [Default:CheckIn:C.Wait] Stopped
2020-02-11T02:29:46.042Z,1581388186.042 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-11T02:29:46.042Z,1581388186.042 [Default:CheckIn:D] Running Loop=1
2020-02-11T02:29:46.453Z,1581388186.453 [Default:CheckIn:D] Stopped
2020-02-11T02:29:46.453Z,1581388186.453 [Default:CheckIn:E] Running Loop=1
2020-02-11T02:29:46.844Z,1581388186.844 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 213.258008 min 
2020-02-11T02:29:46.844Z,1581388186.844 [Default:CheckIn:E] Stopped
2020-02-11T02:29:46.844Z,1581388186.844 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-11T02:29:46.845Z,1581388186.845 [Default:CheckIn] Stopped
2020-02-11T02:29:46.845Z,1581388186.845 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-11T02:29:46.845Z,1581388186.845 [Default:CheckIn](INFO): Running loop #39
2020-02-11T02:29:46.845Z,1581388186.845 [Default:CheckIn] Running Loop=39
2020-02-11T02:29:46.845Z,1581388186.845 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-11T02:29:46.845Z,1581388186.845 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-11T02:29:48.845Z,1581388188.845 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,022938.00,A,4450.83568,N,06734.83220,W,0.039,0.00,110220,,,D*79
2020-02-11T02:29:48.847Z,1581388188.847 [NAL9602](INFO): GPS fix at 20200211T022938: (44.847261, -67.580537)
2020-02-11T02:29:48.871Z,1581388188.871 [Default:CheckIn:Read_GPS] Stopped
2020-02-11T02:29:48.871Z,1581388188.871 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-11T02:29:56.814Z,1581388196.814 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20200210T224734/Courier0120.lzma
2020-02-11T02:29:58.821Z,1581388198.821 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Courier0120.lzma.bak
2020-02-11T02:29:58.821Z,1581388198.821 [DataOverHttps](INFO): SBD MOMSN=12244639
2020-02-11T02:30:02.575Z,1581388202.575 [NAL9602](INFO): SBD MO Status=0, MOMSN=10706, MT Status=0, MTMSN=0
2020-02-11T02:30:02.575Z,1581388202.575 [NAL9602](INFO): No messages in MT queue
2020-02-11T02:30:20.287Z,1581388220.287 [DataOverHttps](INFO): Sending 121 bytes from file Logs/20200210T224734/Express0121.lzma
2020-02-11T02:30:22.293Z,1581388222.293 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Express0121.lzma.bak
2020-02-11T02:30:22.293Z,1581388222.293 [DataOverHttps](INFO): SBD MOMSN=12244642
2020-02-11T02:30:24.053Z,1581388224.053 [Default:CheckIn:Read_Iridium] Stopped
2020-02-11T02:30:24.053Z,1581388224.053 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-11T02:30:24.053Z,1581388224.053 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-11T02:30:33.305Z,1581388233.305 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-11T02:30:47.470Z,1581388247.470 [RDI_Pathfinder](ERROR): Failed to parse:
:SA,   +0.00,   +0.00,  0.00
2020-02-11T02:34:00.618Z,1581388440.618 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-11T02:34:00.618Z,1581388440.618 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20021019470518,35.0, -0.1,   0.,  0
2020-02-11T02:34:42.270Z,1581388482.270 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-11T02:34:42.270Z,1581388482.270 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20021019474718,35.0, -0.1,  8.9,  0
2020-02-11T02:35:22.671Z,1581388522.671 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-11T02:35:22.671Z,1581388522.671 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,200210135.0, -0.1,   0.0,1448.9,  0
2020-02-11T02:35:24.675Z,1581388524.675 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-11T02:35:24.675Z,1581388524.675 [Default:CheckIn:C.Wait] Stopped
2020-02-11T02:35:24.675Z,1581388524.675 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-11T02:35:24.675Z,1581388524.675 [Default:CheckIn:D] Running Loop=1
2020-02-11T02:35:25.075Z,1581388525.075 [Default:CheckIn:D] Stopped
2020-02-11T02:35:25.075Z,1581388525.075 [Default:CheckIn:E] Running Loop=1
2020-02-11T02:35:25.490Z,1581388525.490 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 218.901709 min 
2020-02-11T02:35:25.490Z,1581388525.490 [Default:CheckIn:E] Stopped
2020-02-11T02:35:25.490Z,1581388525.490 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-11T02:35:25.490Z,1581388525.490 [Default:CheckIn] Stopped
2020-02-11T02:35:25.490Z,1581388525.490 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-11T02:35:25.490Z,1581388525.490 [Default:CheckIn](INFO): Running loop #40
2020-02-11T02:35:25.490Z,1581388525.490 [Default:CheckIn] Running Loop=40
2020-02-11T02:35:25.490Z,1581388525.490 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-11T02:35:25.491Z,1581388525.491 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-11T02:35:27.471Z,1581388527.471 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,023517.00,A,4450.83414,N,06734.83077,W,0.097,0.00,110220,,,D*77
2020-02-11T02:35:27.473Z,1581388527.473 [NAL9602](INFO): GPS fix at 20200211T023517: (44.847236, -67.580513)
2020-02-11T02:35:27.507Z,1581388527.507 [Default:CheckIn:Read_GPS] Stopped
2020-02-11T02:35:27.527Z,1581388527.527 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-11T02:35:34.962Z,1581388534.962 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200210T224734/Courier0123.lzma
2020-02-11T02:35:36.969Z,1581388536.969 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Courier0123.lzma.bak
2020-02-11T02:35:36.969Z,1581388536.969 [DataOverHttps](INFO): SBD MOMSN=12244653
2020-02-11T02:35:39.181Z,1581388539.181 [NAL9602](INFO): SBD MO Status=0, MOMSN=10707, MT Status=0, MTMSN=0
2020-02-11T02:35:39.181Z,1581388539.181 [NAL9602](INFO): No messages in MT queue
2020-02-11T02:35:41.636Z,1581388541.636 [RDI_Pathfinder](ERROR): Failed to parse:
:0,  78.95,  74.63,  76.29,  77.29
2020-02-11T02:35:54.190Z,1581388554.190 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200210T224734/Express0124.lzma
2020-02-11T02:35:56.197Z,1581388556.197 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Express0124.lzma.bak
2020-02-11T02:35:56.197Z,1581388556.197 [DataOverHttps](INFO): SBD MOMSN=12244657
2020-02-11T02:35:58.199Z,1581388558.199 [Default:CheckIn:Read_Iridium] Stopped
2020-02-11T02:35:58.215Z,1581388558.215 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-11T02:35:58.215Z,1581388558.215 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-11T02:36:09.893Z,1581388569.893 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-11T02:37:41.621Z,1581388661.621 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-11T02:37:41.621Z,1581388661.621 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20021019504618,35,   0.0,1448.9,  0
2020-02-11T02:39:36.441Z,1581388776.441 [CBIT](IMPORTANT): Beginning ground fault scan
2020-02-11T02:39:47.340Z,1581388787.340 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.008294
CHAN A1 (24V): -0.028169
CHAN A2 (12V): -0.007445
CHAN A3 (5V): -0.002010
CHAN B0 (3.3V): 0.000387
CHAN B1 (3.15aV): -0.000469
CHAN B2 (3.15bV): 0.000373
CHAN B3 (GND): 0.002084
OPEN: 0.006271
Full Scale Calc: 4.765 mA, -1.589 mA
2020-02-11T02:40:58.802Z,1581388858.802 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-11T02:40:58.802Z,1581388858.802 [Default:CheckIn:C.Wait] Stopped
2020-02-11T02:40:58.802Z,1581388858.802 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-11T02:40:58.802Z,1581388858.802 [Default:CheckIn:D] Running Loop=1
2020-02-11T02:40:59.221Z,1581388859.221 [Default:CheckIn:D] Stopped
2020-02-11T02:40:59.221Z,1581388859.221 [Default:CheckIn:E] Running Loop=1
2020-02-11T02:40:59.619Z,1581388859.619 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 224.470817 min 
2020-02-11T02:40:59.619Z,1581388859.619 [Default:CheckIn:E] Stopped
2020-02-11T02:40:59.619Z,1581388859.619 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-11T02:40:59.620Z,1581388859.620 [Default:CheckIn] Stopped
2020-02-11T02:40:59.620Z,1581388859.620 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-11T02:40:59.620Z,1581388859.620 [Default:CheckIn](INFO): Running loop #41
2020-02-11T02:40:59.620Z,1581388859.620 [Default:CheckIn] Running Loop=41
2020-02-11T02:40:59.620Z,1581388859.620 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-11T02:40:59.620Z,1581388859.620 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-11T02:41:01.601Z,1581388861.601 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,024051.00,A,4450.83600,N,06734.83227,W,0.058,0.00,110220,,,D*74
2020-02-11T02:41:01.615Z,1581388861.615 [NAL9602](INFO): GPS fix at 20200211T024051: (44.847267, -67.580538)
2020-02-11T02:41:01.649Z,1581388861.649 [Default:CheckIn:Read_GPS] Stopped
2020-02-11T02:41:01.649Z,1581388861.649 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-11T02:41:09.938Z,1581388869.938 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200210T224734/Courier0126.lzma
2020-02-11T02:41:11.267Z,1581388871.267 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Courier0126.lzma.bak
2020-02-11T02:41:11.267Z,1581388871.267 [DataOverHttps](INFO): SBD MOMSN=12244663
2020-02-11T02:41:13.726Z,1581388873.726 [NAL9602](INFO): SBD MO Status=0, MOMSN=10708, MT Status=0, MTMSN=0
2020-02-11T02:41:13.727Z,1581388873.727 [NAL9602](INFO): No messages in MT queue
2020-02-11T02:41:24.279Z,1581388884.279 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-11T02:41:24.279Z,1581388884.279 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,  78.62,  75.63,  76.62
2020-02-11T02:41:39.423Z,1581388899.423 [DataOverHttps](INFO): Sending 335 bytes from file Logs/20200210T224734/Express0127.lzma
2020-02-11T02:41:41.429Z,1581388901.429 [DataOverHttps](INFO): Moved sent file to Logs/20200210T224734/Express0127.lzma.bak
2020-02-11T02:41:41.429Z,1581388901.429 [DataOverHttps](INFO): SBD MOMSN=12244673
2020-02-11T02:41:43.272Z,1581388903.272 [Default:CheckIn:Read_Iridium] Stopped
2020-02-11T02:41:43.272Z,1581388903.272 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-11T02:41:43.272Z,1581388903.272 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-11T02:41:44.424Z,1581388904.424 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-11T02:43:24.648Z,1581389004.648 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-02-11T02:43:24.648Z,1581389004.648 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,   +18,   -65,
2020-02-11T02:44:02.620Z,1581389042.620 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,  77.62,  76.29,  78.29,  75.63
2020-02-11T02:45:20.877Z,1581389120.877 [CommandLine](IMPORTANT): got command restart application
2020-02-11T02:45:21.883Z,1581389121.883 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2020-02-11T02:45:21.883Z,1581389121.883 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-11T02:45:21.884Z,1581389121.884 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-02-11T02:45:21.947Z,1581389121.947 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-02-11T02:45:21.947Z,1581389121.947 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-02-11T02:45:21.948Z,1581389121.948 [CommandLine](INFO): Join timeout helper Thread ID is 2984
2020-02-11T02:45:21.955Z,1581389121.955 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-02-11T02:45:21.955Z,1581389121.955 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-02-11T02:45:21.956Z,1581389121.956 [NavChartDb](INFO): Join timeout helper Thread ID is 2985
2020-02-11T02:45:22.262Z,1581389122.262 [MissionManager](INFO): MissionManager is completed.
2020-02-11T02:45:22.262Z,1581389122.262 [MissionManager](INFO): Uninitializing Mission Default
2020-02-11T02:45:22.271Z,1581389122.271 [Default] Stopped
2020-02-11T02:45:22.271Z,1581389122.271 [Default](DEBUG): Aggregate::uninitialize Default
2020-02-11T02:45:22.271Z,1581389122.271 [Default:B.GoToSurface] Stopped
2020-02-11T02:45:22.271Z,1581389122.271 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-02-11T02:45:22.271Z,1581389122.271 [Default:CheckIn] Stopped
2020-02-11T02:45:22.271Z,1581389122.271 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-11T02:45:22.271Z,1581389122.271 [Default:CheckIn:C.Wait] Stopped
2020-02-11T02:45:22.271Z,1581389122.271 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-11T02:45:22.347Z,1581389122.347 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-11T02:45:22.347Z,1581389122.347 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-02-11T02:45:22.359Z,1581389122.359 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2020-02-11T02:45:22.359Z,1581389122.359 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-02-11T02:45:22.359Z,1581389122.359 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 2986
2020-02-11T02:45:22.367Z,1581389122.367 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-11T02:45:22.367Z,1581389122.367 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-02-11T02:45:22.368Z,1581389122.368 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-02-11T02:45:22.371Z,1581389122.371 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-02-11T02:45:22.371Z,1581389122.371 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-02-11T02:45:22.371Z,1581389122.371 [Radio_Surface](INFO): Join timeout helper Thread ID is 2987
2020-02-11T02:45:22.399Z,1581389122.399 [Radio_Surface](INFO): Powering down
2020-02-11T02:45:22.400Z,1581389122.400 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-11T02:45:22.400Z,1581389122.400 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-02-11T02:45:22.407Z,1581389122.407 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-02-11T02:45:22.407Z,1581389122.407 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-02-11T02:45:22.407Z,1581389122.407 [DataOverHttps](INFO): Join timeout helper Thread ID is 2988
2020-02-11T02:45:22.443Z,1581389122.443 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-11T02:45:22.447Z,1581389122.447 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-02-11T02:45:22.464Z,1581389122.464 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-02-11T02:45:22.464Z,1581389122.464 [logger ThreadHandler](INFO): Thread cancelled.
2020-02-11T02:45:22.465Z,1581389122.465 [logger](INFO): Join timeout helper Thread ID is 2989
2020-02-11T02:45:22.487Z,1581389122.487 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-11T02:45:22.487Z,1581389122.487 [logger ThreadHandler](INFO): Thread cancelled.
2020-02-11T02:45:22.504Z,1581389122.504 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-02-11T02:45:22.504Z,1581389122.504 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-02-11T02:45:22.504Z,1581389122.504 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-02-11T02:45:22.504Z,1581389122.504 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-02-11T02:45:22.504Z,1581389122.504 [controlThread](INFO): Join timeout helper Thread ID is 2990
2020-02-11T02:45:22.571Z,1581389122.571 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-11T02:45:22.571Z,1581389122.571 [controlThread](DEBUG): Uninitializing ControlThread
2020-02-11T02:45:22.572Z,1581389122.572 [AHRS_M2](INFO): Powering down
2020-02-11T02:45:22.643Z,1581389122.643 [DDM](INFO): Powering down
2020-02-11T02:45:22.716Z,1581389122.716 [DUSBL_Hydroid](INFO): Powering down
2020-02-11T02:45:22.787Z,1581389122.787 [Micromodem](INFO): Powering down
2020-02-11T02:45:22.883Z,1581389122.883 [NAL9602](INFO): Powering down
2020-02-11T02:45:22.955Z,1581389122.955 [RDI_Pathfinder](INFO): Powering down
2020-02-11T02:45:22.957Z,1581389122.957 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-02-11T02:45:22.957Z,1581389122.957 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-02-11T02:45:22.958Z,1581389122.958 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-02-11T02:45:22.958Z,1581389122.958 [MissionManager](INFO): Uninitializing Mission DefaultUnder
2020-02-11T02:45:22.989Z,1581389122.989 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-02-11T02:45:22.989Z,1581389122.989 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-02-11T02:45:22.989Z,1581389122.989 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-02-11T02:45:22.990Z,1581389122.990 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-02-11T02:45:22.990Z,1581389122.990 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-02-11T02:45:22.990Z,1581389122.990 [BuoyancyServo](INFO): Powering down
2020-02-11T02:45:23.003Z,1581389123.003 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-02-11T02:45:23.003Z,1581389123.003 [ElevatorServo](INFO): Powering down
2020-02-11T02:45:23.004Z,1581389123.004 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-02-11T02:45:23.004Z,1581389123.004 [MassServo](INFO): Powering down
2020-02-11T02:45:23.005Z,1581389123.005 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-02-11T02:45:23.005Z,1581389123.005 [RudderServo](INFO): Powering down
2020-02-11T02:45:23.006Z,1581389123.006 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-02-11T02:45:23.006Z,1581389123.006 [ThrusterServo](INFO): Powering down
2020-02-11T02:45:23.006Z,1581389123.006 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-02-11T02:45:23.007Z,1581389123.007 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-02-11T02:45:23.007Z,1581389123.007 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-02-11T02:45:23.007Z,1581389123.007 [CBIT](DEBUG): Powering off loads.
2020-02-11T02:45:23.019Z,1581389123.019 [CBIT](DEBUG): Disabling WDT.
2020-02-11T02:45:23.031Z,1581389123.031 [CBIT](DEBUG): Opening all GF detection circuits.
2020-02-11T02:45:23.032Z,1581389123.032 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-02-11T02:45:23.084Z,1581389123.084 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-02-11T02:45:23.096Z,1581389123.096 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-02-11T02:45:23.148Z,1581389123.148 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-02-11T02:45:23.224Z,1581389123.224 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-02-11T02:45:23.292Z,1581389123.292 [logger ThreadHandler](INFO): Thread cancelled.