2020-02-06T16:42:11.819Z,1581007331.819 [CommandLine](IMPORTANT): got command restart logs 2020-02-06T16:42:17.513Z,1581007337.513 [DefaultUnder:TrackAcousticContact:A.](INFO): *** querying acoustic contact *** 2020-02-06T16:42:18.259Z,1581007338.259 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E 2020-02-06T16:42:18.664Z,1581007338.664 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23 2020-02-06T16:42:19.479Z,1581007339.479 [Micromodem](INFO): Nmea in: $SNTTA,,,,,164219.38*5C 2020-02-06T16:42:21.901Z,1581007341.901 [DUSBL_Hydroid](ERROR): No response from remote modem. 2020-02-06T16:42:24.748Z,1581007344.748 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200206T130832/Courier0036.lzma 2020-02-06T16:42:26.756Z,1581007346.756 [DataOverHttps](INFO): Moved sent file to Logs/20200206T130832/Courier0036.lzma.bak 2020-02-06T16:42:26.757Z,1581007346.757 [DataOverHttps](INFO): SBD MOMSN=12230283 2020-02-06T16:42:44.756Z,1581007364.756 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200206T130832/Courier0039.lzma 2020-02-06T16:42:46.762Z,1581007366.762 [DataOverHttps](INFO): Moved sent file to Logs/20200206T130832/Courier0039.lzma.bak 2020-02-06T16:42:46.762Z,1581007366.762 [DataOverHttps](INFO): SBD MOMSN=12230285 2020-02-06T16:43:04.248Z,1581007384.248 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20200206T130832/Courier0042.lzma 2020-02-06T16:43:06.254Z,1581007386.254 [DataOverHttps](INFO): Moved sent file to Logs/20200206T130832/Courier0042.lzma.bak 2020-02-06T16:43:06.254Z,1581007386.254 [DataOverHttps](INFO): SBD MOMSN=12230287 2020-02-06T16:43:17.682Z,1581007397.682 [DefaultUnder:TrackAcousticContact:A.](INFO): *** querying acoustic contact *** 2020-02-06T16:43:18.455Z,1581007398.455 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E 2020-02-06T16:43:18.865Z,1581007398.865 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23 2020-02-06T16:43:19.683Z,1581007399.683 [Micromodem](INFO): Nmea in: $SNTTA,,,,,164319.58*5B 2020-02-06T16:43:22.094Z,1581007402.094 [DUSBL_Hydroid](ERROR): No response from remote modem. 2020-02-06T16:43:23.840Z,1581007403.840 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20200206T130832/Courier0045.lzma 2020-02-06T16:43:25.847Z,1581007405.847 [DataOverHttps](INFO): Moved sent file to Logs/20200206T130832/Courier0045.lzma.bak 2020-02-06T16:43:25.847Z,1581007405.847 [DataOverHttps](INFO): SBD MOMSN=12230289 2020-02-06T16:43:43.299Z,1581007423.299 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200206T130832/Courier0048.lzma 2020-02-06T16:43:45.298Z,1581007425.298 [DataOverHttps](INFO): Moved sent file to Logs/20200206T130832/Courier0048.lzma.bak 2020-02-06T16:43:45.298Z,1581007425.298 [DataOverHttps](INFO): SBD MOMSN=12230291 2020-02-06T16:44:02.174Z,1581007442.174 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2020-02-06T16:44:02.175Z,1581007442.175 [RDI_Pathfinder](ERROR): Failed to parse: :BD, +12.62, +1463.8356.00 2020-02-06T16:44:04.060Z,1581007444.060 [DataOverHttps](INFO): Sending 49 bytes from file Logs/20200206T130832/Courier0051.lzma 2020-02-06T16:44:06.066Z,1581007446.066 [DataOverHttps](INFO): Moved sent file to Logs/20200206T130832/Courier0051.lzma.bak 2020-02-06T16:44:06.066Z,1581007446.066 [DataOverHttps](INFO): SBD MOMSN=12230293 2020-02-06T16:44:17.957Z,1581007457.957 [DefaultUnder:TrackAcousticContact:A.](INFO): *** querying acoustic contact *** 2020-02-06T16:44:18.663Z,1581007458.663 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E 2020-02-06T16:44:19.058Z,1581007459.058 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23 2020-02-06T16:44:19.883Z,1581007459.883 [Micromodem](INFO): Nmea in: $SNTTA,,,,,164419.79*5F 2020-02-06T16:44:22.290Z,1581007462.290 [DUSBL_Hydroid](ERROR): No response from remote modem. 2020-02-06T16:44:24.580Z,1581007464.580 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200206T130832/Courier0054.lzma 2020-02-06T16:44:26.587Z,1581007466.587 [DataOverHttps](INFO): Moved sent file to Logs/20200206T130832/Courier0054.lzma.bak 2020-02-06T16:44:26.587Z,1581007466.587 [DataOverHttps](INFO): SBD MOMSN=12230295 2020-02-06T16:44:44.193Z,1581007484.193 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200206T130832/Courier0057.lzma 2020-02-06T16:44:46.194Z,1581007486.194 [DataOverHttps](INFO): Moved sent file to Logs/20200206T130832/Courier0057.lzma.bak 2020-02-06T16:44:46.194Z,1581007486.194 [DataOverHttps](INFO): SBD MOMSN=12230297 2020-02-06T16:45:02.358Z,1581007502.358 [DefaultUnder](INFO): Timed out from 2020-02-06T13:45:02.2Z 2020-02-06T16:45:02.358Z,1581007502.358 [MissionManager](INFO): DefaultUnder is completed. 2020-02-06T16:45:02.358Z,1581007502.358 [MissionManager](INFO): Uninitializing Mission DefaultUnder 2020-02-06T16:45:02.359Z,1581007502.359 [DefaultUnder] Stopped 2020-02-06T16:45:02.359Z,1581007502.359 [DefaultUnder](DEBUG): Aggregate::uninitialize DefaultUnder 2020-02-06T16:45:02.359Z,1581007502.359 [DefaultUnder:Notify] Stopped 2020-02-06T16:45:02.359Z,1581007502.359 [DefaultUnder:Notify](DEBUG): Aggregate::uninitialize DefaultUnder:Notify 2020-02-06T16:45:02.359Z,1581007502.359 [DefaultUnder:Notify:A] Stopped 2020-02-06T16:45:02.359Z,1581007502.359 [DefaultUnder:Notify:A](DEBUG): Aggregate::uninitialize DefaultUnder:Notify:A 2020-02-06T16:45:02.359Z,1581007502.359 [DefaultUnder:Notify:A:A] Stopped 2020-02-06T16:45:02.359Z,1581007502.359 [DefaultUnder:Notify:A:A](DEBUG): Aggregate::uninitialize DefaultUnder:Notify:A:A 2020-02-06T16:45:02.359Z,1581007502.359 [DefaultUnder:Notify:A:A:A.Wait] Stopped 2020-02-06T16:45:02.359Z,1581007502.359 [DefaultUnder:Notify:A:A:A.Wait](DEBUG): Uninitialize Wait Component. 2020-02-06T16:45:02.359Z,1581007502.359 [DefaultUnder:TrackAcousticContact] Stopped 2020-02-06T16:45:02.359Z,1581007502.359 [DefaultUnder:TrackAcousticContact](DEBUG): Aggregate::uninitialize DefaultUnder:TrackAcousticContact 2020-02-06T16:45:02.360Z,1581007502.360 [DefaultUnder:TrackAcousticContact:A.] Stopped 2020-02-06T16:45:02.360Z,1581007502.360 [DefaultUnder:Default] Stopped 2020-02-06T16:45:02.360Z,1581007502.360 [DefaultUnder:Default](DEBUG): Aggregate::uninitialize DefaultUnder:Default 2020-02-06T16:45:02.360Z,1581007502.360 [DefaultUnder:Default:Docked] Stopped 2020-02-06T16:45:02.360Z,1581007502.360 [DefaultUnder:Default:Docked](DEBUG): Aggregate::uninitialize DefaultUnder:Default:Docked 2020-02-06T16:45:02.360Z,1581007502.360 [DefaultUnder:Default:Home] Stopped 2020-02-06T16:45:02.360Z,1581007502.360 [DefaultUnder:Default:Home](DEBUG): Aggregate::uninitialize DefaultUnder:Default:Home 2020-02-06T16:45:02.360Z,1581007502.360 [DefaultUnder:Default:Surface] Stopped 2020-02-06T16:45:02.360Z,1581007502.360 [DefaultUnder:Default:Surface](DEBUG): Aggregate::uninitialize DefaultUnder:Default:Surface 2020-02-06T16:45:02.369Z,1581007502.369 [DefaultUnder:Default:Surface:run] Stopped 2020-02-06T16:45:02.369Z,1581007502.369 [DefaultUnder:Default:Surface:run](DEBUG): Aggregate::uninitialize DefaultUnder:Default:Surface:run 2020-02-06T16:45:02.369Z,1581007502.369 [DefaultUnder:Default:Surface:run:C.GoToSurface] Stopped 2020-02-06T16:45:02.369Z,1581007502.369 [DefaultUnder:Default:Surface:run:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-02-06T16:45:02.369Z,1581007502.369 [DefaultUnder:Default:Surface:run:SurfaceComms] Stopped 2020-02-06T16:45:02.369Z,1581007502.369 [DefaultUnder:Default:Surface:run:SurfaceComms](DEBUG): Aggregate::uninitialize DefaultUnder:Default:Surface:run:SurfaceComms 2020-02-06T16:45:02.369Z,1581007502.369 [DefaultUnder:Default:Surface:run:SurfaceComms:Read_Iridium_uModem] Stopped 2020-02-06T16:45:02.736Z,1581007502.736 [MissionManager](IMPORTANT): Started mission DefaultWithUndock 2020-02-06T16:45:02.740Z,1581007502.740 [DefaultWithUndock] Running Loop=1 2020-02-06T16:45:02.741Z,1581007502.741 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock 2020-02-06T16:45:02.741Z,1581007502.741 [DefaultWithUndock:C.GoToSurface] Running Loop=1 2020-02-06T16:45:02.741Z,1581007502.741 [DefaultWithUndock:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-02-06T16:45:02.741Z,1581007502.741 [DefaultWithUndock:C.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-02-06T16:45:02.741Z,1581007502.741 [DefaultWithUndock:C.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-02-06T16:45:02.742Z,1581007502.742 [DefaultWithUndock:C.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-02-06T16:45:02.742Z,1581007502.742 [DefaultWithUndock:C.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-02-06T16:45:02.742Z,1581007502.742 [DefaultWithUndock:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-02-06T16:45:02.743Z,1581007502.743 [DefaultWithUndock:A.Wait] Running Loop=1 2020-02-06T16:45:02.743Z,1581007502.743 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component. 2020-02-06T16:45:02.786Z,1581007502.786 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range no_value 2020-02-06T16:45:33.028Z,1581007533.028 [DefaultWithUndock:A.Wait](INFO): Done Waiting. 2020-02-06T16:45:33.028Z,1581007533.028 [DefaultWithUndock:A.Wait] Stopped 2020-02-06T16:45:33.028Z,1581007533.028 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component. 2020-02-06T16:45:33.029Z,1581007533.029 [DefaultWithUndock:B.Undock] Running Loop=1 2020-02-06T16:45:33.029Z,1581007533.029 [DefaultWithUndock:B.Undock](DEBUG): Initialize. 2020-02-06T16:45:33.818Z,1581007533.818 [PowerOnly](INFO): Powering up 2020-02-06T16:45:45.605Z,1581007545.605 [DefaultWithUndock:B.Undock](INFO): Detaching from dock. 2020-02-06T16:45:46.015Z,1581007546.015 [DefaultWithUndock:B.Undock](INFO): Detached at range: nan m. Transitioning DDM to standby. 2020-02-06T16:45:57.724Z,1581007557.724 [DefaultWithUndock:B.Undock](INFO): DDM at standby. 2020-02-06T16:45:58.118Z,1581007558.118 [DefaultWithUndock:B.Undock](IMPORTANT): Undocking sequence complete. 2020-02-06T16:45:58.118Z,1581007558.118 [DefaultWithUndock:B.Undock] Stopped 2020-02-06T16:45:58.476Z,1581007558.476 [DefaultWithUndock:CheckIn] Running Loop=1 2020-02-06T16:45:58.476Z,1581007558.476 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn 2020-02-06T16:45:58.476Z,1581007558.476 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1 2020-02-06T16:46:00.479Z,1581007560.479 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164559.00,A,4131.73306,N,07041.63716,W,21.654,167.28,060220,,,A*4F 2020-02-06T16:46:00.481Z,1581007560.481 [NAL9602](INFO): GPS fix at 20200206T164559: (41.528884, -70.693953) 2020-02-06T16:46:00.506Z,1581007560.506 [DefaultWithUndock:CheckIn:Read_GPS] Stopped 2020-02-06T16:46:00.506Z,1581007560.506 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1 2020-02-06T16:46:08.868Z,1581007568.868 [DataOverHttps](INFO): Sending 232 bytes from file Logs/20200206T130832/Courier0060.lzma 2020-02-06T16:46:10.874Z,1581007570.874 [DataOverHttps](INFO): Moved sent file to Logs/20200206T130832/Courier0060.lzma.bak 2020-02-06T16:46:10.874Z,1581007570.874 [DataOverHttps](INFO): SBD MOMSN=12230299 2020-02-06T16:46:21.080Z,1581007581.080 [NAL9602](INFO): SBD MO Status=2, MOMSN=10456, MT Status=2, MTMSN=0 2020-02-06T16:46:21.080Z,1581007581.080 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-02-06T16:46:28.492Z,1581007588.492 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200206T130832/Courier0063.lzma 2020-02-06T16:46:30.498Z,1581007590.498 [DataOverHttps](INFO): Moved sent file to Logs/20200206T130832/Courier0063.lzma.bak 2020-02-06T16:46:30.498Z,1581007590.498 [DataOverHttps](INFO): SBD MOMSN=12230303 2020-02-06T16:46:30.769Z,1581007590.769 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=10456, MT Status=1, MTMSN=465 2020-02-06T16:46:30.769Z,1581007590.769 [NAL9602](INFO): Data available in MT queue 2020-02-06T16:46:31.261Z,1581007591.261 [NAL9602](INFO): Received command:stop 2020-02-06T16:46:31.305Z,1581007591.305 [CommandLine](IMPORTANT): got command stop 2020-02-06T16:46:31.305Z,1581007591.305 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop 2020-02-06T16:46:48.164Z,1581007608.164 [DataOverHttps](INFO): Sending 269 bytes from file Logs/20200206T164211/Courier0003.lzma 2020-02-06T16:46:50.171Z,1581007610.171 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Courier0003.lzma.bak 2020-02-06T16:46:50.171Z,1581007610.171 [DataOverHttps](INFO): SBD MOMSN=12230305 2020-02-06T16:47:01.874Z,1581007621.874 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-06T16:47:05.121Z,1581007625.121 [PowerOnly](INFO): Powering down 2020-02-06T16:47:07.704Z,1581007627.704 [DataOverHttps](INFO): Sending 219 bytes from file Logs/20200206T130832/Express0011.lzma 2020-02-06T16:47:09.710Z,1581007629.710 [DataOverHttps](INFO): Moved sent file to Logs/20200206T130832/Express0011.lzma.bak 2020-02-06T16:47:09.710Z,1581007629.710 [DataOverHttps](INFO): SBD MOMSN=12230310 2020-02-06T16:47:26.972Z,1581007646.972 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2020-02-06T16:47:26.972Z,1581007646.972 [RDI_Pathfinder](ERROR): Failed to parse: :BD, +12.62, , +129.10, 0.00,460.99 2020-02-06T16:47:27.353Z,1581007647.353 [DataOverHttps](INFO): Sending 196 bytes from file Logs/20200206T130832/Express0016.lzma 2020-02-06T16:47:29.359Z,1581007649.359 [DataOverHttps](INFO): Moved sent file to Logs/20200206T130832/Express0016.lzma.bak 2020-02-06T16:47:29.359Z,1581007649.359 [DataOverHttps](INFO): SBD MOMSN=12230315 2020-02-06T16:47:46.339Z,1581007666.339 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-02-06T16:47:46.339Z,1581007666.339 [RDI_Pathfinder](ERROR): Failed to parse: :TS0005986,35.0, -0.1, 0.0,1448.9, 0 2020-02-06T16:47:48.828Z,1581007668.828 [DataOverHttps](INFO): Sending 170 bytes from file Logs/20200206T130832/Express0019.lzma 2020-02-06T16:47:50.834Z,1581007670.834 [DataOverHttps](INFO): Moved sent file to Logs/20200206T130832/Express0019.lzma.bak 2020-02-06T16:47:50.834Z,1581007670.834 [DataOverHttps](INFO): SBD MOMSN=12230319 2020-02-06T16:48:11.557Z,1581007691.557 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20200206T130832/Express0022.lzma 2020-02-06T16:48:13.562Z,1581007693.562 [DataOverHttps](INFO): Moved sent file to Logs/20200206T130832/Express0022.lzma.bak 2020-02-06T16:48:13.563Z,1581007693.563 [DataOverHttps](INFO): SBD MOMSN=12230324 2020-02-06T16:48:31.232Z,1581007711.232 [DataOverHttps](INFO): Sending 165 bytes from file Logs/20200206T130832/Express0025.lzma 2020-02-06T16:48:32.497Z,1581007712.497 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2020-02-06T16:48:33.238Z,1581007713.238 [DataOverHttps](INFO): Moved sent file to Logs/20200206T130832/Express0025.lzma.bak 2020-02-06T16:48:33.238Z,1581007713.238 [DataOverHttps](INFO): SBD MOMSN=12230328 2020-02-06T16:48:49.409Z,1581007729.409 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2020-02-06T16:48:49.412Z,1581007729.412 [BPC1](INFO): Received data from all battery sticks. 2020-02-06T16:48:50.656Z,1581007730.656 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20200206T130832/Express0028.lzma 2020-02-06T16:48:52.662Z,1581007732.662 [DataOverHttps](INFO): Moved sent file to Logs/20200206T130832/Express0028.lzma.bak 2020-02-06T16:48:52.662Z,1581007732.662 [DataOverHttps](INFO): SBD MOMSN=12230332 2020-02-06T16:49:17.942Z,1581007757.942 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20200206T130832/Express0031.lzma 2020-02-06T16:49:19.945Z,1581007759.945 [DataOverHttps](INFO): Moved sent file to Logs/20200206T130832/Express0031.lzma.bak 2020-02-06T16:49:19.946Z,1581007759.946 [DataOverHttps](INFO): SBD MOMSN=12230336 2020-02-06T16:49:37.760Z,1581007777.760 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20200206T130832/Express0034.lzma 2020-02-06T16:49:39.766Z,1581007779.766 [DataOverHttps](INFO): Moved sent file to Logs/20200206T130832/Express0034.lzma.bak 2020-02-06T16:49:39.766Z,1581007779.766 [DataOverHttps](INFO): SBD MOMSN=12230340 2020-02-06T16:49:45.971Z,1581007785.971 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-02-06T16:49:45.971Z,1581007785.971 [RDI_Pathfinder](ERROR): Failed to parse: :TS,20020610025986,35.0, -00,1448.9, 0 2020-02-06T16:49:57.129Z,1581007797.129 [DataOverHttps](INFO): Sending 389 bytes from file Logs/20200206T130832/Express0037.lzma 2020-02-06T16:49:59.134Z,1581007799.134 [DataOverHttps](INFO): Moved sent file to Logs/20200206T130832/Express0037.lzma.bak 2020-02-06T16:49:59.134Z,1581007799.134 [DataOverHttps](INFO): SBD MOMSN=12230344 2020-02-06T16:50:11.410Z,1581007811.410 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-02-06T16:50:11.410Z,1581007811.410 [RDI_Pathfinder](ERROR): Failed to parse: :TS,200206100.0, -0.1, 0.0,1448.9, 0 2020-02-06T16:50:18.405Z,1581007818.405 [DataOverHttps](INFO): Sending 241 bytes from file Logs/20200206T130832/Express0040.lzma 2020-02-06T16:50:20.410Z,1581007820.410 [DataOverHttps](INFO): Moved sent file to Logs/20200206T130832/Express0040.lzma.bak 2020-02-06T16:50:20.410Z,1581007820.410 [DataOverHttps](INFO): SBD MOMSN=12230350 2020-02-06T16:50:39.702Z,1581007839.702 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20200206T130832/Express0043.lzma 2020-02-06T16:50:41.706Z,1581007841.706 [DataOverHttps](INFO): Moved sent file to Logs/20200206T130832/Express0043.lzma.bak 2020-02-06T16:50:41.706Z,1581007841.706 [DataOverHttps](INFO): SBD MOMSN=12230355 2020-02-06T16:51:10.349Z,1581007870.349 [DataOverHttps](INFO): Sending 186 bytes from file Logs/20200206T130832/Express0046.lzma 2020-02-06T16:51:12.354Z,1581007872.354 [DataOverHttps](INFO): Moved sent file to Logs/20200206T130832/Express0046.lzma.bak 2020-02-06T16:51:12.354Z,1581007872.354 [DataOverHttps](INFO): SBD MOMSN=12230359 2020-02-06T16:51:33.073Z,1581007893.073 [DataOverHttps](INFO): Sending 162 bytes from file Logs/20200206T130832/Express0049.lzma 2020-02-06T16:51:35.075Z,1581007895.075 [DataOverHttps](INFO): Moved sent file to Logs/20200206T130832/Express0049.lzma.bak 2020-02-06T16:51:35.075Z,1581007895.075 [DataOverHttps](INFO): SBD MOMSN=12230363 2020-02-06T16:51:53.744Z,1581007913.744 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20200206T130832/Express0052.lzma 2020-02-06T16:51:55.750Z,1581007915.750 [DataOverHttps](INFO): Moved sent file to Logs/20200206T130832/Express0052.lzma.bak 2020-02-06T16:51:55.751Z,1581007915.751 [DataOverHttps](INFO): SBD MOMSN=12230367 2020-02-06T16:52:00.090Z,1581007920.090 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-02-06T16:52:00.090Z,1581007920.090 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2020-02-06T16:52:05.348Z,1581007925.348 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-02-06T16:52:05.348Z,1581007925.348 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2020-02-06T16:52:18.600Z,1581007938.600 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20200206T130832/Express0055.lzma 2020-02-06T16:52:20.606Z,1581007940.606 [DataOverHttps](INFO): Moved sent file to Logs/20200206T130832/Express0055.lzma.bak 2020-02-06T16:52:20.607Z,1581007940.607 [DataOverHttps](INFO): SBD MOMSN=12230374 2020-02-06T16:52:40.155Z,1581007960.155 [DataOverHttps](INFO): Sending 231 bytes from file Logs/20200206T130832/Express0058.lzma 2020-02-06T16:52:42.158Z,1581007962.158 [DataOverHttps](INFO): Moved sent file to Logs/20200206T130832/Express0058.lzma.bak 2020-02-06T16:52:42.158Z,1581007962.158 [DataOverHttps](INFO): SBD MOMSN=12230381 2020-02-06T16:52:51.027Z,1581007971.027 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-02-06T16:52:51.027Z,1581007971.027 [RDI_Pathfinder](ERROR): Failed to parse: :TS,20020610060486,35.0, -0.1, 8.9, 0 2020-02-06T16:53:02.818Z,1581007982.818 [DataOverHttps](INFO): Sending 605 bytes from file Logs/20200206T130832/Express0061.lzma 2020-02-06T16:53:04.822Z,1581007984.822 [DataOverHttps](INFO): Moved sent file to Logs/20200206T130832/Express0061.lzma.bak 2020-02-06T16:53:04.822Z,1581007984.822 [DataOverHttps](INFO): SBD MOMSN=12230386 2020-02-06T16:53:23.772Z,1581008003.772 [DataOverHttps](INFO): Sending 165 bytes from file Logs/20200206T130832/Express0064.lzma 2020-02-06T16:53:25.778Z,1581008005.778 [DataOverHttps](INFO): Moved sent file to Logs/20200206T130832/Express0064.lzma.bak 2020-02-06T16:53:25.778Z,1581008005.778 [DataOverHttps](INFO): SBD MOMSN=12230397 2020-02-06T16:53:31.429Z,1581008011.429 [RDI_Pathfinder](ERROR): Failed to parse: :0, 0.00, 0.00, 0.00, 0.00 2020-02-06T16:53:48.501Z,1581008028.501 [DataOverHttps](INFO): Sending 359 bytes from file Logs/20200206T164211/Express0004.lzma 2020-02-06T16:53:50.506Z,1581008030.506 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Express0004.lzma.bak 2020-02-06T16:53:50.507Z,1581008030.507 [DataOverHttps](INFO): SBD MOMSN=12230401 2020-02-06T16:53:52.030Z,1581008032.030 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped 2020-02-06T16:53:52.030Z,1581008032.030 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1 2020-02-06T16:53:52.031Z,1581008032.031 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-06T16:58:06.184Z,1581008286.184 [RDI_Pathfinder](ERROR): Failed to parse: :SA, +0.00, +0.00, 0.00 2020-02-06T16:58:52.605Z,1581008332.605 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-06T16:58:52.606Z,1581008332.606 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-02-06T16:58:52.606Z,1581008332.606 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-06T16:58:52.606Z,1581008332.606 [DefaultWithUndock:CheckIn:D] Running Loop=1 2020-02-06T16:58:53.030Z,1581008333.030 [DefaultWithUndock:CheckIn:D] Stopped 2020-02-06T16:58:53.030Z,1581008333.030 [DefaultWithUndock:CheckIn:E] Running Loop=1 2020-02-06T16:58:53.442Z,1581008333.442 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 13.838219 min 2020-02-06T16:58:53.442Z,1581008333.442 [DefaultWithUndock:CheckIn:E] Stopped 2020-02-06T16:58:53.443Z,1581008333.443 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn 2020-02-06T16:58:53.443Z,1581008333.443 [DefaultWithUndock:CheckIn] Stopped 2020-02-06T16:58:53.443Z,1581008333.443 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-02-06T16:58:53.443Z,1581008333.443 [DefaultWithUndock:CheckIn](INFO): Running loop #2 2020-02-06T16:58:53.443Z,1581008333.443 [DefaultWithUndock:CheckIn] Running Loop=2 2020-02-06T16:58:53.443Z,1581008333.443 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn 2020-02-06T16:58:53.443Z,1581008333.443 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1 2020-02-06T16:58:55.414Z,1581008335.414 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165854.00,A,4131.45928,N,07040.31615,W,0.156,145.82,060220,,,D*7A 2020-02-06T16:58:55.417Z,1581008335.417 [NAL9602](INFO): GPS fix at 20200206T165854: (41.524321, -70.671936) 2020-02-06T16:58:55.491Z,1581008335.491 [DefaultWithUndock:CheckIn:Read_GPS] Stopped 2020-02-06T16:58:55.491Z,1581008335.491 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1 2020-02-06T16:59:04.352Z,1581008344.352 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20200206T164211/Courier0006.lzma 2020-02-06T16:59:06.358Z,1581008346.358 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Courier0006.lzma.bak 2020-02-06T16:59:06.359Z,1581008346.359 [DataOverHttps](INFO): SBD MOMSN=12230406 2020-02-06T16:59:25.797Z,1581008365.797 [DataOverHttps](INFO): Sending 251 bytes from file Logs/20200206T164211/Express0007.lzma 2020-02-06T16:59:27.802Z,1581008367.802 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Express0007.lzma.bak 2020-02-06T16:59:27.802Z,1581008367.802 [DataOverHttps](INFO): SBD MOMSN=12230410 2020-02-06T16:59:29.400Z,1581008369.400 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped 2020-02-06T16:59:29.401Z,1581008369.401 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1 2020-02-06T16:59:29.401Z,1581008369.401 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-06T17:00:02.521Z,1581008402.521 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-02-06T17:00:02.521Z,1581008402.521 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2020-02-06T17:00:12.990Z,1581008412.990 [NAL9602](INFO): SBD MO Status=0, MOMSN=10457, MT Status=0, MTMSN=0 2020-02-06T17:00:12.990Z,1581008412.990 [NAL9602](INFO): No messages in MT queue 2020-02-06T17:00:25.970Z,1581008425.970 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2020-02-06T17:00:25.970Z,1581008425.970 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-2768,-32768,V 2020-02-06T17:00:43.706Z,1581008443.706 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-06T17:02:59.120Z,1581008579.120 [CommandLine](IMPORTANT): got command strobe off 2020-02-06T17:02:59.120Z,1581008579.120 [CommandLine](IMPORTANT): Deactivating strobe 2020-02-06T17:04:05.333Z,1581008645.333 [RDI_Pathfinder](ERROR): Failed to parse: :BE,-32768,-32768,-32768,V 2020-02-06T17:04:06.970Z,1581008646.970 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-02-06T17:04:06.970Z,1581008646.970 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-32768,-32768,-32768-32768,-32768,-32768,V 2020-02-06T17:04:29.988Z,1581008669.988 [RDI_Pathfinder](ERROR): Failed to parse: :BI,-32768,-32768,-32768,-32768,V 2020-02-06T17:04:29.997Z,1581008669.997 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-06T17:04:29.997Z,1581008669.997 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-02-06T17:04:29.997Z,1581008669.997 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-06T17:04:29.997Z,1581008669.997 [DefaultWithUndock:CheckIn:D] Running Loop=1 2020-02-06T17:04:30.410Z,1581008670.410 [DefaultWithUndock:CheckIn:D] Stopped 2020-02-06T17:04:30.410Z,1581008670.410 [DefaultWithUndock:CheckIn:E] Running Loop=1 2020-02-06T17:04:30.785Z,1581008670.785 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 19.461233 min 2020-02-06T17:04:30.785Z,1581008670.785 [DefaultWithUndock:CheckIn:E] Stopped 2020-02-06T17:04:30.785Z,1581008670.785 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn 2020-02-06T17:04:30.785Z,1581008670.785 [DefaultWithUndock:CheckIn] Stopped 2020-02-06T17:04:30.785Z,1581008670.785 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-02-06T17:04:30.785Z,1581008670.785 [DefaultWithUndock:CheckIn](INFO): Running loop #3 2020-02-06T17:04:30.785Z,1581008670.785 [DefaultWithUndock:CheckIn] Running Loop=3 2020-02-06T17:04:30.785Z,1581008670.785 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn 2020-02-06T17:04:30.785Z,1581008670.785 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1 2020-02-06T17:04:32.771Z,1581008672.771 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170432.00,A,4131.47058,N,07040.28161,W,0.136,130.30,060220,,,A*7A 2020-02-06T17:04:32.773Z,1581008672.773 [NAL9602](INFO): GPS fix at 20200206T170432: (41.524510, -70.671360) 2020-02-06T17:04:32.825Z,1581008672.825 [DefaultWithUndock:CheckIn:Read_GPS] Stopped 2020-02-06T17:04:32.825Z,1581008672.825 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1 2020-02-06T17:04:41.156Z,1581008681.156 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20200206T164211/Courier0009.lzma 2020-02-06T17:04:43.162Z,1581008683.162 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Courier0009.lzma.bak 2020-02-06T17:04:43.162Z,1581008683.162 [DataOverHttps](INFO): SBD MOMSN=12230424 2020-02-06T17:05:02.748Z,1581008702.748 [DataOverHttps](INFO): Sending 162 bytes from file Logs/20200206T164211/Express0010.lzma 2020-02-06T17:05:04.754Z,1581008704.754 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Express0010.lzma.bak 2020-02-06T17:05:04.754Z,1581008704.754 [DataOverHttps](INFO): SBD MOMSN=12230428 2020-02-06T17:05:06.345Z,1581008706.345 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped 2020-02-06T17:05:06.345Z,1581008706.345 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1 2020-02-06T17:05:06.345Z,1581008706.345 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-06T17:06:24.327Z,1581008784.327 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2020-02-06T17:06:24.327Z,1581008784.327 [RDI_Pathfinder](ERROR): Failed to parse: :BD, +0.00 +0.00, +0.00, 0.00,1597.89 2020-02-06T17:06:28.339Z,1581008788.339 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-02-06T17:06:28.340Z,1581008788.340 [RDI_Pathfinder](ERROR): Failed to parse: :TS,20020610194186,35.0, -0.1, 0., 0 2020-02-06T17:07:09.986Z,1581008829.986 [RDI_Pathfinder](ERROR): Failed to parse: :BE,-32768,-32768,-32768,V 2020-02-06T17:08:30.343Z,1581008910.343 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-02-06T17:08:30.343Z,1581008910.343 [RDI_Pathfinder](ERROR): Failed to parse: :TS,20020610210, -0.1, 0.0,1448.9, 0 2020-02-06T17:09:35.372Z,1581008975.372 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-02-06T17:10:06.070Z,1581009006.070 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-06T17:10:06.940Z,1581009006.940 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-06T17:10:06.941Z,1581009006.941 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-02-06T17:10:06.941Z,1581009006.941 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-06T17:10:06.942Z,1581009006.942 [DefaultWithUndock:CheckIn:D] Running Loop=1 2020-02-06T17:10:07.343Z,1581009007.343 [DefaultWithUndock:CheckIn:D] Stopped 2020-02-06T17:10:07.343Z,1581009007.343 [DefaultWithUndock:CheckIn:E] Running Loop=1 2020-02-06T17:10:07.724Z,1581009007.724 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 25.076782 min 2020-02-06T17:10:07.724Z,1581009007.724 [DefaultWithUndock:CheckIn:E] Stopped 2020-02-06T17:10:07.724Z,1581009007.724 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn 2020-02-06T17:10:07.729Z,1581009007.729 [DefaultWithUndock:CheckIn] Stopped 2020-02-06T17:10:07.729Z,1581009007.729 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-02-06T17:10:07.729Z,1581009007.729 [DefaultWithUndock:CheckIn](INFO): Running loop #4 2020-02-06T17:10:07.729Z,1581009007.729 [DefaultWithUndock:CheckIn] Running Loop=4 2020-02-06T17:10:07.729Z,1581009007.729 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn 2020-02-06T17:10:07.729Z,1581009007.729 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1 2020-02-06T17:10:09.708Z,1581009009.708 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171008.00,A,4131.46951,N,07040.28370,W,0.467,2.60,060220,,,A*71 2020-02-06T17:10:09.710Z,1581009009.710 [NAL9602](INFO): GPS fix at 20200206T171008: (41.524492, -70.671395) 2020-02-06T17:10:09.752Z,1581009009.752 [DefaultWithUndock:CheckIn:Read_GPS] Stopped 2020-02-06T17:10:09.752Z,1581009009.752 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1 2020-02-06T17:10:17.405Z,1581009017.405 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20200206T164211/Courier0012.lzma 2020-02-06T17:10:19.410Z,1581009019.410 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Courier0012.lzma.bak 2020-02-06T17:10:19.411Z,1581009019.411 [DataOverHttps](INFO): SBD MOMSN=12230433 2020-02-06T17:10:35.559Z,1581009035.559 [NAL9602](INFO): SBD MO Status=0, MOMSN=10458, MT Status=0, MTMSN=0 2020-02-06T17:10:35.559Z,1581009035.559 [NAL9602](INFO): No messages in MT queue 2020-02-06T17:10:41.816Z,1581009041.816 [DataOverHttps](INFO): Sending 196 bytes from file Logs/20200206T164211/Express0013.lzma 2020-02-06T17:10:43.825Z,1581009043.825 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Express0013.lzma.bak 2020-02-06T17:10:43.825Z,1581009043.825 [DataOverHttps](INFO): SBD MOMSN=12230436 2020-02-06T17:10:45.300Z,1581009045.300 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped 2020-02-06T17:10:45.301Z,1581009045.301 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1 2020-02-06T17:10:45.301Z,1581009045.301 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-06T17:11:06.301Z,1581009066.301 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-06T17:13:29.322Z,1581009209.322 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-02-06T17:13:29.322Z,1581009209.322 [RDI_Pathfinder](ERROR): Failed to parse: :TS,20020610264286,35.0, 0.0,1448.9, 0 2020-02-06T17:15:45.886Z,1581009345.886 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-06T17:15:45.886Z,1581009345.886 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-02-06T17:15:45.886Z,1581009345.886 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-06T17:15:45.887Z,1581009345.887 [DefaultWithUndock:CheckIn:D] Running Loop=1 2020-02-06T17:15:46.309Z,1581009346.309 [DefaultWithUndock:CheckIn:D] Stopped 2020-02-06T17:15:46.309Z,1581009346.309 [DefaultWithUndock:CheckIn:E] Running Loop=1 2020-02-06T17:15:46.689Z,1581009346.689 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 30.726133 min 2020-02-06T17:15:46.689Z,1581009346.689 [DefaultWithUndock:CheckIn:E] Stopped 2020-02-06T17:15:46.689Z,1581009346.689 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn 2020-02-06T17:15:46.689Z,1581009346.689 [DefaultWithUndock:CheckIn] Stopped 2020-02-06T17:15:46.689Z,1581009346.689 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-02-06T17:15:46.689Z,1581009346.689 [DefaultWithUndock:CheckIn](INFO): Running loop #5 2020-02-06T17:15:46.689Z,1581009346.689 [DefaultWithUndock:CheckIn] Running Loop=5 2020-02-06T17:15:46.689Z,1581009346.689 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn 2020-02-06T17:15:46.690Z,1581009346.690 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1 2020-02-06T17:15:48.689Z,1581009348.689 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171547.00,A,4131.47248,N,07040.28213,W,0.369,141.06,060220,,,A*76 2020-02-06T17:15:48.691Z,1581009348.691 [NAL9602](INFO): GPS fix at 20200206T171547: (41.524541, -70.671369) 2020-02-06T17:15:48.720Z,1581009348.720 [DefaultWithUndock:CheckIn:Read_GPS] Stopped 2020-02-06T17:15:48.721Z,1581009348.721 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1 2020-02-06T17:15:56.116Z,1581009356.116 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20200206T164211/Courier0015.lzma 2020-02-06T17:15:58.119Z,1581009358.119 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Courier0015.lzma.bak 2020-02-06T17:15:58.119Z,1581009358.119 [DataOverHttps](INFO): SBD MOMSN=12230451 2020-02-06T17:16:17.476Z,1581009377.476 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200206T164211/Express0016.lzma 2020-02-06T17:16:19.482Z,1581009379.482 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Express0016.lzma.bak 2020-02-06T17:16:19.482Z,1581009379.482 [DataOverHttps](INFO): SBD MOMSN=12230454 2020-02-06T17:16:21.084Z,1581009381.084 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped 2020-02-06T17:16:21.085Z,1581009381.085 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1 2020-02-06T17:16:21.085Z,1581009381.085 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-06T17:19:01.032Z,1581009541.032 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-02-06T17:19:01.032Z,1581009541.032 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2020-02-06T17:19:26.440Z,1581009566.440 [NAL9602](INFO): SBD MO Status=0, MOMSN=10459, MT Status=0, MTMSN=0 2020-02-06T17:19:26.448Z,1581009566.448 [NAL9602](INFO): No messages in MT queue 2020-02-06T17:19:34.980Z,1581009574.980 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2020-02-06T17:19:34.980Z,1581009574.980 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-32768,-3278,V 2020-02-06T17:19:57.142Z,1581009597.142 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-06T17:21:21.609Z,1581009681.609 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-06T17:21:21.609Z,1581009681.609 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-02-06T17:21:21.609Z,1581009681.609 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-06T17:21:21.610Z,1581009681.610 [DefaultWithUndock:CheckIn:D] Running Loop=1 2020-02-06T17:21:22.016Z,1581009682.016 [DefaultWithUndock:CheckIn:D] Stopped 2020-02-06T17:21:22.016Z,1581009682.016 [DefaultWithUndock:CheckIn:E] Running Loop=1 2020-02-06T17:21:22.446Z,1581009682.446 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 36.321318 min 2020-02-06T17:21:22.446Z,1581009682.446 [DefaultWithUndock:CheckIn:E] Stopped 2020-02-06T17:21:22.446Z,1581009682.446 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn 2020-02-06T17:21:22.446Z,1581009682.446 [DefaultWithUndock:CheckIn] Stopped 2020-02-06T17:21:22.446Z,1581009682.446 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-02-06T17:21:22.446Z,1581009682.446 [DefaultWithUndock:CheckIn](INFO): Running loop #6 2020-02-06T17:21:22.446Z,1581009682.446 [DefaultWithUndock:CheckIn] Running Loop=6 2020-02-06T17:21:22.446Z,1581009682.446 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn 2020-02-06T17:21:22.446Z,1581009682.446 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1 2020-02-06T17:21:24.408Z,1581009684.408 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172123.00,A,4131.47195,N,07040.28332,W,0.039,158.46,060220,,,A*78 2020-02-06T17:21:24.410Z,1581009684.410 [NAL9602](INFO): GPS fix at 20200206T172123: (41.524532, -70.671389) 2020-02-06T17:21:24.445Z,1581009684.445 [DefaultWithUndock:CheckIn:Read_GPS] Stopped 2020-02-06T17:21:24.445Z,1581009684.445 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1 2020-02-06T17:21:31.760Z,1581009691.760 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200206T164211/Courier0018.lzma 2020-02-06T17:21:34.770Z,1581009694.770 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Courier0018.lzma.bak 2020-02-06T17:21:34.770Z,1581009694.770 [DataOverHttps](INFO): SBD MOMSN=12230458 2020-02-06T17:21:52.800Z,1581009712.800 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200206T164211/Express0019.lzma 2020-02-06T17:21:54.806Z,1581009714.806 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Express0019.lzma.bak 2020-02-06T17:21:54.806Z,1581009714.806 [DataOverHttps](INFO): SBD MOMSN=12230462 2020-02-06T17:21:56.372Z,1581009716.372 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped 2020-02-06T17:21:56.373Z,1581009716.373 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1 2020-02-06T17:21:56.373Z,1581009716.373 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-06T17:22:52.140Z,1581009772.140 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2020-02-06T17:22:52.140Z,1581009772.140 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-3268,-32768,V 2020-02-06T17:23:32.146Z,1581009812.146 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2020-02-06T17:23:32.147Z,1581009812.147 [RDI_Pathfinder](ERROR): Failed to parse: :BD, +0.00, +0.00, +5.69 2020-02-06T17:24:52.132Z,1581009892.132 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-02-06T17:24:52.133Z,1581009892.133 [RDI_Pathfinder](ERROR): Failed to parse: :TS,20020610380, -0.1, 0.0,1448.9, 0 2020-02-06T17:25:35.353Z,1581009935.353 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-02-06T17:25:35.353Z,1581009935.353 [RDI_Pathfinder](ERROR): Failed to parse: :TS,20020610384886,35, 0.0,1448.9, 0 2020-02-06T17:26:26.223Z,1581009986.223 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-02-06T17:26:56.934Z,1581010016.934 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-06T17:26:57.014Z,1581010017.014 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-06T17:26:57.014Z,1581010017.014 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-02-06T17:26:57.015Z,1581010017.015 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-06T17:26:57.015Z,1581010017.015 [DefaultWithUndock:CheckIn:D] Running Loop=1 2020-02-06T17:26:57.361Z,1581010017.361 [DefaultWithUndock:CheckIn:D] Stopped 2020-02-06T17:26:57.361Z,1581010017.361 [DefaultWithUndock:CheckIn:E] Running Loop=1 2020-02-06T17:26:57.765Z,1581010017.765 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 41.910413 min 2020-02-06T17:26:57.765Z,1581010017.765 [DefaultWithUndock:CheckIn:E] Stopped 2020-02-06T17:26:57.765Z,1581010017.765 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn 2020-02-06T17:26:57.765Z,1581010017.765 [DefaultWithUndock:CheckIn] Stopped 2020-02-06T17:26:57.765Z,1581010017.765 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-02-06T17:26:57.765Z,1581010017.765 [DefaultWithUndock:CheckIn](INFO): Running loop #7 2020-02-06T17:26:57.765Z,1581010017.765 [DefaultWithUndock:CheckIn] Running Loop=7 2020-02-06T17:26:57.765Z,1581010017.765 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn 2020-02-06T17:26:57.765Z,1581010017.765 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1 2020-02-06T17:26:59.757Z,1581010019.757 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172659.00,A,4131.46713,N,07040.28111,W,0.175,158.46,060220,,,A*71 2020-02-06T17:26:59.759Z,1581010019.759 [NAL9602](INFO): GPS fix at 20200206T172659: (41.524452, -70.671352) 2020-02-06T17:26:59.813Z,1581010019.813 [DefaultWithUndock:CheckIn:Read_GPS] Stopped 2020-02-06T17:26:59.813Z,1581010019.813 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1 2020-02-06T17:27:07.048Z,1581010027.048 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200206T164211/Courier0021.lzma 2020-02-06T17:27:09.054Z,1581010029.054 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Courier0021.lzma.bak 2020-02-06T17:27:09.055Z,1581010029.055 [DataOverHttps](INFO): SBD MOMSN=12230473 2020-02-06T17:27:26.476Z,1581010046.476 [DataOverHttps](INFO): Sending 196 bytes from file Logs/20200206T164211/Express0022.lzma 2020-02-06T17:27:28.482Z,1581010048.482 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Express0022.lzma.bak 2020-02-06T17:27:28.482Z,1581010048.482 [DataOverHttps](INFO): SBD MOMSN=12230476 2020-02-06T17:27:30.085Z,1581010050.085 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped 2020-02-06T17:27:30.086Z,1581010050.086 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1 2020-02-06T17:27:30.086Z,1581010050.086 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-06T17:27:30.465Z,1581010050.465 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2020-02-06T17:27:30.540Z,1581010050.540 [NAL9602](FAULT): received: +CSQ:0 OK459, 0, 0, 0, 0 OK 2020-02-06T17:27:30.541Z,1581010050.541 [NAL9602] Data Fault, FailCount= 1 2020-02-06T17:27:30.541Z,1581010050.541 [NAL9602](ERROR): Data Fault 2020-02-06T17:27:30.601Z,1581010050.601 [CBIT](ERROR): Data Fault in component: NAL9602 2020-02-06T17:27:30.863Z,1581010050.863 [NAL9602](INFO): Powering down 2020-02-06T17:27:31.737Z,1581010051.737 [CBIT](INFO): Clearing failed state for component NAL9602 2020-02-06T17:27:31.737Z,1581010051.737 [NAL9602] No Fault, FailCount= 1 2020-02-06T17:27:32.501Z,1581010052.501 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-02-06T17:27:32.501Z,1581010052.501 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2020-02-06T17:28:01.172Z,1581010081.172 [NAL9602](INFO): Powering up NAL9602 2020-02-06T17:28:12.075Z,1581010092.075 [NAL9602](INFO): NAL9602 initialized 2020-02-06T17:28:43.218Z,1581010123.218 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-06T17:28:54.996Z,1581010134.996 [RDI_Pathfinder](ERROR): only read 1 of 4 data items 2020-02-06T17:28:54.996Z,1581010134.996 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-32768-32768,V 2020-02-06T17:32:30.707Z,1581010350.707 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-06T17:32:30.707Z,1581010350.707 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-02-06T17:32:30.707Z,1581010350.707 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-06T17:32:30.708Z,1581010350.708 [DefaultWithUndock:CheckIn:D] Running Loop=1 2020-02-06T17:32:31.116Z,1581010351.116 [DefaultWithUndock:CheckIn:D] Stopped 2020-02-06T17:32:31.116Z,1581010351.116 [DefaultWithUndock:CheckIn:E] Running Loop=1 2020-02-06T17:32:31.591Z,1581010351.591 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 47.472990 min 2020-02-06T17:32:31.591Z,1581010351.591 [DefaultWithUndock:CheckIn:E] Stopped 2020-02-06T17:32:31.591Z,1581010351.591 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn 2020-02-06T17:32:31.591Z,1581010351.591 [DefaultWithUndock:CheckIn] Stopped 2020-02-06T17:32:31.591Z,1581010351.591 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-02-06T17:32:31.591Z,1581010351.591 [DefaultWithUndock:CheckIn](INFO): Running loop #8 2020-02-06T17:32:31.591Z,1581010351.591 [DefaultWithUndock:CheckIn] Running Loop=8 2020-02-06T17:32:31.592Z,1581010351.592 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn 2020-02-06T17:32:31.592Z,1581010351.592 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1 2020-02-06T17:32:35.111Z,1581010355.111 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-02-06T17:32:52.522Z,1581010372.522 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-02-06T17:32:52.522Z,1581010372.522 [RDI_Pathfinder](ERROR): Failed to parse: :TS,200206104605 -0.1, 0.0,1448.9, 0.00, 0.00, 0.00, 0.00, 0.00 2020-02-06T17:33:55.966Z,1581010435.966 [RDI_Pathfinder](ERROR): Failed to parse: :0, 0.00, 0.00, 0.00, 0.00 2020-02-06T17:34:36.349Z,1581010476.349 [RDI_Pathfinder](ERROR): Failed to parse: 00, 0.00, 0.00, 0.00, 0.00 2020-02-06T17:35:16.336Z,1581010516.336 [RDI_Pathfinder](ERROR): Failed to parse: :R, 0.00, 0.00, 0.00, 0.00 2020-02-06T17:37:14.336Z,1581010634.336 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2020-02-06T17:37:14.336Z,1581010634.336 [RDI_Pathfinder](ERROR): Failed to parse: :BD, +0.00, +0.00, , 0.00,3447.49 2020-02-06T17:37:31.709Z,1581010651.709 [DefaultWithUndock:CheckIn:Read_GPS](INFO): Timed out from 2020-02-06T17:32:31.6Z 2020-02-06T17:37:31.709Z,1581010651.709 [DefaultWithUndock:CheckIn:Read_GPS] Stopped 2020-02-06T17:37:31.709Z,1581010651.709 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1 2020-02-06T17:37:33.697Z,1581010653.697 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-02-06T17:37:33.697Z,1581010653.697 [RDI_Pathfinder](ERROR): Failed to parse: :TS,20020610504686,35.0, -0.1, .9, 0 2020-02-06T17:37:38.976Z,1581010658.976 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200206T164211/Courier0024.lzma 2020-02-06T17:37:40.982Z,1581010660.982 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Courier0024.lzma.bak 2020-02-06T17:37:40.982Z,1581010660.982 [DataOverHttps](INFO): SBD MOMSN=12230487 2020-02-06T17:38:04.370Z,1581010684.370 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-06T17:39:05.632Z,1581010745.632 [DataOverHttps](INFO): Sending 236 bytes from file Logs/20200206T164211/Express0025.lzma 2020-02-06T17:39:07.654Z,1581010747.654 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Express0025.lzma.bak 2020-02-06T17:39:07.654Z,1581010747.654 [DataOverHttps](INFO): SBD MOMSN=12230489 2020-02-06T17:39:09.055Z,1581010749.055 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped 2020-02-06T17:39:09.055Z,1581010749.055 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1 2020-02-06T17:39:09.055Z,1581010749.055 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-06T17:44:09.632Z,1581011049.632 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-06T17:44:09.632Z,1581011049.632 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-02-06T17:44:09.633Z,1581011049.633 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-06T17:44:09.633Z,1581011049.633 [DefaultWithUndock:CheckIn:D] Running Loop=1 2020-02-06T17:44:10.042Z,1581011050.042 [DefaultWithUndock:CheckIn:D] Stopped 2020-02-06T17:44:10.042Z,1581011050.042 [DefaultWithUndock:CheckIn:E] Running Loop=1 2020-02-06T17:44:10.445Z,1581011050.445 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 59.121757 min 2020-02-06T17:44:10.445Z,1581011050.445 [DefaultWithUndock:CheckIn:E] Stopped 2020-02-06T17:44:10.445Z,1581011050.445 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn 2020-02-06T17:44:10.445Z,1581011050.445 [DefaultWithUndock:CheckIn] Stopped 2020-02-06T17:44:10.445Z,1581011050.445 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-02-06T17:44:10.445Z,1581011050.445 [DefaultWithUndock:CheckIn](INFO): Running loop #9 2020-02-06T17:44:10.445Z,1581011050.445 [DefaultWithUndock:CheckIn] Running Loop=9 2020-02-06T17:44:10.446Z,1581011050.446 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn 2020-02-06T17:44:10.446Z,1581011050.446 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1 2020-02-06T17:44:12.023Z,1581011052.023 [NAL9602](FAULT): GPS failed to acquire within timeout. 2020-02-06T17:44:12.023Z,1581011052.023 [NAL9602] Data Fault, FailCount= 1 2020-02-06T17:44:12.023Z,1581011052.023 [NAL9602](ERROR): Data Fault 2020-02-06T17:44:12.105Z,1581011052.105 [CBIT](ERROR): Data Fault in component: NAL9602 2020-02-06T17:44:12.430Z,1581011052.430 [NAL9602](INFO): Powering down 2020-02-06T17:44:13.318Z,1581011053.318 [CBIT](INFO): Clearing failed state for component NAL9602 2020-02-06T17:44:13.321Z,1581011053.321 [NAL9602] No Fault, FailCount= 1 2020-02-06T17:44:42.723Z,1581011082.723 [NAL9602](INFO): Powering up NAL9602 2020-02-06T17:44:53.635Z,1581011093.635 [NAL9602](INFO): NAL9602 initialized 2020-02-06T17:47:53.012Z,1581011273.012 [NAL9602](INFO): SBD MO Status=2, MOMSN=10460, MT Status=2, MTMSN=0 2020-02-06T17:47:53.012Z,1581011273.012 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-02-06T17:48:14.437Z,1581011294.437 [NAL9602](INFO): SBD MO Status=2, MOMSN=10460, MT Status=2, MTMSN=0 2020-02-06T17:48:14.437Z,1581011294.437 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-02-06T17:48:24.985Z,1581011304.985 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-02-06T17:48:24.985Z,1581011304.985 [RDI_Pathfinder](ERROR): Failed to parse::BS,-32768,-32768,-3:BE,-32768,-32768,-32768,V 2020-02-06T17:48:29.385Z,1581011309.385 [NAL9602](INFO): SBD MO Status=2, MOMSN=10460, MT Status=2, MTMSN=0 2020-02-06T17:48:29.385Z,1581011309.385 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-02-06T17:49:10.635Z,1581011350.635 [DefaultWithUndock:CheckIn:Read_GPS](INFO): Timed out from 2020-02-06T17:44:10.4Z 2020-02-06T17:49:10.635Z,1581011350.635 [DefaultWithUndock:CheckIn:Read_GPS] Stopped 2020-02-06T17:49:10.637Z,1581011350.637 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1 2020-02-06T17:49:18.264Z,1581011358.264 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200206T164211/Courier0027.lzma 2020-02-06T17:49:20.290Z,1581011360.290 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Courier0027.lzma.bak 2020-02-06T17:49:20.290Z,1581011360.290 [DataOverHttps](INFO): SBD MOMSN=12230500 2020-02-06T17:49:37.700Z,1581011377.700 [DataOverHttps](INFO): Sending 166 bytes from file Logs/20200206T164211/Express0028.lzma 2020-02-06T17:49:39.707Z,1581011379.707 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Express0028.lzma.bak 2020-02-06T17:49:39.707Z,1581011379.707 [DataOverHttps](INFO): SBD MOMSN=12230502 2020-02-06T17:49:40.921Z,1581011380.921 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped 2020-02-06T17:49:40.921Z,1581011380.921 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1 2020-02-06T17:49:40.921Z,1581011380.921 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-06T17:49:57.050Z,1581011397.050 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-02-06T17:49:57.077Z,1581011397.077 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file). 2020-02-06T17:50:27.753Z,1581011427.753 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-06T17:51:04.979Z,1581011464.979 [RDI_Pathfinder](ERROR): Failed to parse: :8,-32768,-32768,V 2020-02-06T17:53:51.031Z,1581011631.031 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-02-06T17:53:51.031Z,1581011631.031 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2020-02-06T17:54:41.512Z,1581011681.512 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-06T17:54:41.513Z,1581011681.513 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-02-06T17:54:41.513Z,1581011681.513 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-06T17:54:41.513Z,1581011681.513 [DefaultWithUndock:CheckIn:D] Running Loop=1 2020-02-06T17:54:41.914Z,1581011681.914 [DefaultWithUndock:CheckIn:D] Stopped 2020-02-06T17:54:41.914Z,1581011681.914 [DefaultWithUndock:CheckIn:E] Running Loop=1 2020-02-06T17:54:42.338Z,1581011682.338 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 69.652962 min 2020-02-06T17:54:42.338Z,1581011682.338 [DefaultWithUndock:CheckIn:E] Stopped 2020-02-06T17:54:42.338Z,1581011682.338 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn 2020-02-06T17:54:42.338Z,1581011682.338 [DefaultWithUndock:CheckIn] Stopped 2020-02-06T17:54:42.338Z,1581011682.338 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-02-06T17:54:42.338Z,1581011682.338 [DefaultWithUndock:CheckIn](INFO): Running loop #10 2020-02-06T17:54:42.338Z,1581011682.338 [DefaultWithUndock:CheckIn] Running Loop=10 2020-02-06T17:54:42.338Z,1581011682.338 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn 2020-02-06T17:54:42.338Z,1581011682.338 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1 2020-02-06T17:55:03.349Z,1581011703.349 [RDI_Pathfinder](ERROR): Failed to parse: :SA, +0.00, +0.00, 0.00 2020-02-06T17:55:12.231Z,1581011712.231 [RDI_Pathfinder](ERROR): Failed to parse: :SA, +0.00, +0.00, 0.00 2020-02-06T17:55:15.017Z,1581011715.017 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2020-02-06T17:55:15.092Z,1581011715.092 [NAL9602](FAULT): received: +CSQ:0 OK460, 2, 0, 0, 0 OK 2020-02-06T17:55:15.093Z,1581011715.093 [NAL9602] Data Fault, FailCount= 2 2020-02-06T17:55:15.093Z,1581011715.093 [NAL9602](ERROR): Data Fault 2020-02-06T17:55:15.149Z,1581011715.149 [CBIT](ERROR): Data Fault in component: NAL9602 2020-02-06T17:55:15.423Z,1581011715.423 [NAL9602](INFO): Powering down 2020-02-06T17:55:16.279Z,1581011716.279 [CBIT](INFO): Clearing failed state for component NAL9602 2020-02-06T17:55:16.279Z,1581011716.279 [NAL9602] No Fault, FailCount= 2 2020-02-06T17:55:45.723Z,1581011745.723 [NAL9602](INFO): Powering up NAL9602 2020-02-06T17:55:56.631Z,1581011756.631 [NAL9602](INFO): NAL9602 initialized 2020-02-06T17:55:57.439Z,1581011757.439 [NAL9602](FAULT): GPS failed to acquire within timeout. 2020-02-06T17:55:57.439Z,1581011757.439 [NAL9602] Data Fault, FailCount= 3 2020-02-06T17:55:57.439Z,1581011757.439 [NAL9602](ERROR): Data Fault 2020-02-06T17:55:57.493Z,1581011757.493 [CBIT](ERROR): Data Fault in component: NAL9602 2020-02-06T17:55:57.849Z,1581011757.849 [NAL9602](INFO): Powering down 2020-02-06T17:55:58.709Z,1581011758.709 [CBIT](INFO): Clearing failed state for component NAL9602 2020-02-06T17:55:58.709Z,1581011758.709 [NAL9602] No Fault, FailCount= 3 2020-02-06T17:56:05.987Z,1581011765.987 [RDI_Pathfinder](ERROR): Failed to parse: :B,-32768,-32768,V 2020-02-06T17:56:13.226Z,1581011773.226 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2020-02-06T17:56:13.229Z,1581011773.229 [BPC1](INFO): Received data from all battery sticks. 2020-02-06T17:56:28.143Z,1581011788.143 [NAL9602](INFO): Powering up NAL9602 2020-02-06T17:56:39.057Z,1581011799.057 [NAL9602](INFO): NAL9602 initialized 2020-02-06T17:57:06.524Z,1581011826.524 [NAL9602](INFO): SBD MO Status=0, MOMSN=10460, MT Status=0, MTMSN=0 2020-02-06T17:57:06.525Z,1581011826.525 [NAL9602](INFO): No messages in MT queue 2020-02-06T17:59:42.541Z,1581011982.541 [DefaultWithUndock:CheckIn:Read_GPS](INFO): Timed out from 2020-02-06T17:54:42.3Z 2020-02-06T17:59:42.541Z,1581011982.541 [DefaultWithUndock:CheckIn:Read_GPS] Stopped 2020-02-06T17:59:42.541Z,1581011982.541 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1 2020-02-06T17:59:52.832Z,1581011992.832 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20200206T164211/Courier0030.lzma 2020-02-06T17:59:54.838Z,1581011994.838 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Courier0030.lzma.bak 2020-02-06T17:59:54.838Z,1581011994.838 [DataOverHttps](INFO): SBD MOMSN=12230515 2020-02-06T18:00:09.322Z,1581012009.322 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-02-06T18:00:09.322Z,1581012009.322 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2020-02-06T18:00:15.072Z,1581012015.072 [DataOverHttps](INFO): Sending 255 bytes from file Logs/20200206T164211/Express0031.lzma 2020-02-06T18:00:15.354Z,1581012015.354 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-06T18:00:17.078Z,1581012017.078 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Express0031.lzma.bak 2020-02-06T18:00:17.078Z,1581012017.078 [DataOverHttps](INFO): SBD MOMSN=12230517 2020-02-06T18:00:18.664Z,1581012018.664 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped 2020-02-06T18:00:18.664Z,1581012018.664 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1 2020-02-06T18:00:18.664Z,1581012018.664 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-06T18:05:01.038Z,1581012301.038 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2020-02-06T18:05:01.038Z,1581012301.038 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-3268,-32768,V 2020-02-06T18:05:19.224Z,1581012319.224 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-06T18:05:19.228Z,1581012319.228 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-02-06T18:05:19.228Z,1581012319.228 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-06T18:05:19.229Z,1581012319.229 [DefaultWithUndock:CheckIn:D] Running Loop=1 2020-02-06T18:05:19.605Z,1581012319.605 [DefaultWithUndock:CheckIn:D] Stopped 2020-02-06T18:05:19.605Z,1581012319.605 [DefaultWithUndock:CheckIn:E] Running Loop=1 2020-02-06T18:05:20.026Z,1581012320.026 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 80.281136 min 2020-02-06T18:05:20.026Z,1581012320.026 [DefaultWithUndock:CheckIn:E] Stopped 2020-02-06T18:05:20.026Z,1581012320.026 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn 2020-02-06T18:05:20.026Z,1581012320.026 [DefaultWithUndock:CheckIn] Stopped 2020-02-06T18:05:20.026Z,1581012320.026 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-02-06T18:05:20.026Z,1581012320.026 [DefaultWithUndock:CheckIn](INFO): Running loop #11 2020-02-06T18:05:20.026Z,1581012320.026 [DefaultWithUndock:CheckIn] Running Loop=11 2020-02-06T18:05:20.026Z,1581012320.026 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn 2020-02-06T18:05:20.026Z,1581012320.026 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1 2020-02-06T18:05:57.193Z,1581012357.193 [RDI_Pathfinder](ERROR): Failed to parse: +0.00, +0.00, +0.00, 0.00,5168.81 2020-02-06T18:06:12.127Z,1581012372.127 [NAL9602](INFO): SBD MO Status=0, MOMSN=10461, MT Status=0, MTMSN=0 2020-02-06T18:06:12.127Z,1581012372.127 [NAL9602](INFO): No messages in MT queue 2020-02-06T18:06:33.925Z,1581012393.925 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180633.00,A,4131.46178,N,07040.28080,W,1.905,283.75,060220,,,A*79 2020-02-06T18:06:33.927Z,1581012393.927 [NAL9602](INFO): GPS fix at 20200206T180633: (41.524363, -70.671347) 2020-02-06T18:06:33.955Z,1581012393.955 [DefaultWithUndock:CheckIn:Read_GPS] Stopped 2020-02-06T18:06:33.955Z,1581012393.955 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1 2020-02-06T18:06:41.256Z,1581012401.256 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200206T164211/Courier0033.lzma 2020-02-06T18:06:43.262Z,1581012403.262 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Courier0033.lzma.bak 2020-02-06T18:06:43.262Z,1581012403.262 [DataOverHttps](INFO): SBD MOMSN=12230537 2020-02-06T18:06:51.031Z,1581012411.031 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-02-06T18:06:51.031Z,1581012411.031 [RDI_Pathfinder](ERROR): Failed to parse: :TS,20020611200486,35.0, -0.1, 0.0,1448.RA, 0.00, 0.00, 0.00, 0.00, 0.00 2020-02-06T18:07:00.604Z,1581012420.604 [DataOverHttps](INFO): Sending 121 bytes from file Logs/20200206T164211/Express0034.lzma 2020-02-06T18:07:02.610Z,1581012422.610 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Express0034.lzma.bak 2020-02-06T18:07:02.610Z,1581012422.610 [DataOverHttps](INFO): SBD MOMSN=12230540 2020-02-06T18:07:03.932Z,1581012423.932 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped 2020-02-06T18:07:03.953Z,1581012423.953 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1 2020-02-06T18:07:03.953Z,1581012423.953 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-06T18:07:05.980Z,1581012425.980 [RDI_Pathfinder](ERROR): Failed to parse: :BD, +0.00, +0.00, +0.00, 0.00,5238.77 2020-02-06T18:07:06.326Z,1581012426.326 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-06T18:09:22.503Z,1581012562.503 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2020-02-06T18:09:22.503Z,1581012562.503 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-32768,-32768, 2020-02-06T18:12:03.333Z,1581012723.333 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2020-02-06T18:12:03.333Z,1581012723.333 [RDI_Pathfinder](ERROR): Failed to parse: :BD, +0.00, +0.00 +0.00, 0.00,5535.63 2020-02-06T18:12:04.632Z,1581012724.632 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-06T18:12:04.633Z,1581012724.633 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-02-06T18:12:04.633Z,1581012724.633 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-06T18:12:04.633Z,1581012724.633 [DefaultWithUndock:CheckIn:D] Running Loop=1 2020-02-06T18:12:04.928Z,1581012724.928 [DefaultWithUndock:CheckIn:D] Stopped 2020-02-06T18:12:04.928Z,1581012724.928 [DefaultWithUndock:CheckIn:E] Running Loop=1 2020-02-06T18:12:05.383Z,1581012725.383 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 87.036523 min 2020-02-06T18:12:05.383Z,1581012725.383 [DefaultWithUndock:CheckIn:E] Stopped 2020-02-06T18:12:05.383Z,1581012725.383 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn 2020-02-06T18:12:05.383Z,1581012725.383 [DefaultWithUndock:CheckIn] Stopped 2020-02-06T18:12:05.383Z,1581012725.383 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-02-06T18:12:05.383Z,1581012725.383 [DefaultWithUndock:CheckIn](INFO): Running loop #12 2020-02-06T18:12:05.384Z,1581012725.384 [DefaultWithUndock:CheckIn] Running Loop=12 2020-02-06T18:12:05.384Z,1581012725.384 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn 2020-02-06T18:12:05.384Z,1581012725.384 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1 2020-02-06T18:12:07.322Z,1581012727.322 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181206.00,A,4131.46213,N,07040.26779,W,0.311,283.75,060220,,,A*75 2020-02-06T18:12:07.324Z,1581012727.324 [NAL9602](INFO): GPS fix at 20200206T181206: (41.524369, -70.671130) 2020-02-06T18:12:07.349Z,1581012727.349 [DefaultWithUndock:CheckIn:Read_GPS] Stopped 2020-02-06T18:12:07.349Z,1581012727.349 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1 2020-02-06T18:12:15.168Z,1581012735.168 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20200206T164211/Courier0036.lzma 2020-02-06T18:12:17.175Z,1581012737.175 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Courier0036.lzma.bak 2020-02-06T18:12:17.176Z,1581012737.176 [DataOverHttps](INFO): SBD MOMSN=12230545 2020-02-06T18:12:22.510Z,1581012742.510 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-02-06T18:12:22.510Z,1581012742.510 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00, 2020-02-06T18:12:34.592Z,1581012754.592 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20200206T164211/Express0037.lzma 2020-02-06T18:12:36.598Z,1581012756.598 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Express0037.lzma.bak 2020-02-06T18:12:36.598Z,1581012756.598 [DataOverHttps](INFO): SBD MOMSN=12230548 2020-02-06T18:12:37.872Z,1581012757.872 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped 2020-02-06T18:12:37.873Z,1581012757.873 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1 2020-02-06T18:12:37.873Z,1581012757.873 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-06T18:13:42.139Z,1581012822.139 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-02-06T18:13:42.140Z,1581012822.140 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2020-02-06T18:13:45.327Z,1581012825.327 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-02-06T18:13:45.327Z,1581012825.327 [RDI_Pathfinder](ERROR): Failed to parse: :TS1265886,35.0, -0.1, 0.0,1448.9, 0 2020-02-06T18:13:51.400Z,1581012831.400 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-02-06T18:13:51.400Z,1581012831.400 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2020-02-06T18:15:02.500Z,1581012902.500 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-02-06T18:15:02.500Z,1581012902.500 [RDI_Pathfinder](ERROR): Failed to parse: :TS,20020611281586,35.0, -0.1,448.9, 0 2020-02-06T18:16:23.684Z,1581012983.684 [NAL9602](INFO): SBD MO Status=2, MOMSN=10462, MT Status=2, MTMSN=0 2020-02-06T18:16:23.684Z,1581012983.684 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-02-06T18:17:09.331Z,1581013029.331 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-02-06T18:17:38.446Z,1581013058.446 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-06T18:17:38.446Z,1581013058.446 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-02-06T18:17:38.446Z,1581013058.446 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-06T18:17:38.447Z,1581013058.447 [DefaultWithUndock:CheckIn:D] Running Loop=1 2020-02-06T18:17:38.856Z,1581013058.856 [DefaultWithUndock:CheckIn:D] Stopped 2020-02-06T18:17:38.856Z,1581013058.856 [DefaultWithUndock:CheckIn:E] Running Loop=1 2020-02-06T18:17:39.275Z,1581013059.275 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 92.601986 min 2020-02-06T18:17:39.275Z,1581013059.275 [DefaultWithUndock:CheckIn:E] Stopped 2020-02-06T18:17:39.275Z,1581013059.275 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn 2020-02-06T18:17:39.275Z,1581013059.275 [DefaultWithUndock:CheckIn] Stopped 2020-02-06T18:17:39.275Z,1581013059.275 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-02-06T18:17:39.275Z,1581013059.275 [DefaultWithUndock:CheckIn](INFO): Running loop #13 2020-02-06T18:17:39.275Z,1581013059.275 [DefaultWithUndock:CheckIn] Running Loop=13 2020-02-06T18:17:39.275Z,1581013059.275 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn 2020-02-06T18:17:39.275Z,1581013059.275 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1 2020-02-06T18:17:41.254Z,1581013061.254 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181740.00,A,4131.47164,N,07040.27851,W,0.194,39.62,060220,,,A*4E 2020-02-06T18:17:41.256Z,1581013061.256 [NAL9602](INFO): GPS fix at 20200206T181740: (41.524527, -70.671308) 2020-02-06T18:17:41.313Z,1581013061.313 [DefaultWithUndock:CheckIn:Read_GPS] Stopped 2020-02-06T18:17:41.313Z,1581013061.313 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1 2020-02-06T18:17:49.224Z,1581013069.224 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20200206T164211/Courier0039.lzma 2020-02-06T18:17:51.230Z,1581013071.230 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Courier0039.lzma.bak 2020-02-06T18:17:51.230Z,1581013071.230 [DataOverHttps](INFO): SBD MOMSN=12230560 2020-02-06T18:18:11.540Z,1581013091.540 [DataOverHttps](INFO): Sending 195 bytes from file Logs/20200206T164211/Express0040.lzma 2020-02-06T18:18:13.546Z,1581013093.546 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Express0040.lzma.bak 2020-02-06T18:18:13.546Z,1581013093.546 [DataOverHttps](INFO): SBD MOMSN=12230563 2020-02-06T18:18:13.578Z,1581013093.578 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-06T18:18:14.836Z,1581013094.836 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped 2020-02-06T18:18:14.853Z,1581013094.853 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1 2020-02-06T18:18:14.853Z,1581013094.853 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-06T18:18:26.965Z,1581013106.965 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2020-02-06T18:18:26.966Z,1581013106.966 [RDI_Pathfinder](ERROR): Failed to parse: :BD, +0.00, +0.00 +0.00, 0.00,5919.44 2020-02-06T18:18:31.372Z,1581013111.372 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-02-06T18:18:31.372Z,1581013111.372 [RDI_Pathfinder](ERROR): Failed to parse: :TS,200206113144860.1, 0.0,1448.9, 0 2020-02-06T18:19:43.324Z,1581013183.324 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2020-02-06T18:19:43.324Z,1581013183.324 [RDI_Pathfinder](ERROR): Failed to parse: :BD, +0. +0.00, +0.00, 0.00,5995.41 2020-02-06T18:21:06.961Z,1581013266.961 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-02-06T18:21:06.962Z,1581013266.962 [RDI_Pathfinder](ERROR): Failed to parse: :TS768,-32768,-32768,V 2020-02-06T18:21:27.961Z,1581013287.961 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-02-06T18:21:27.961Z,1581013287.961 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-32768,-32768,-32BE,-32768,-32768,-32768,V 2020-02-06T18:21:46.961Z,1581013306.961 [RDI_Pathfinder](ERROR): Failed to parse: :BE,-32768,-32768,-32768,V 2020-02-06T18:23:15.402Z,1581013395.402 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-06T18:23:15.402Z,1581013395.402 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-02-06T18:23:15.402Z,1581013395.402 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-06T18:23:15.403Z,1581013395.403 [DefaultWithUndock:CheckIn:D] Running Loop=1 2020-02-06T18:23:15.809Z,1581013395.809 [DefaultWithUndock:CheckIn:D] Stopped 2020-02-06T18:23:15.809Z,1581013395.809 [DefaultWithUndock:CheckIn:E] Running Loop=1 2020-02-06T18:23:16.227Z,1581013396.227 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 98.217879 min 2020-02-06T18:23:16.227Z,1581013396.227 [DefaultWithUndock:CheckIn:E] Stopped 2020-02-06T18:23:16.227Z,1581013396.227 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn 2020-02-06T18:23:16.227Z,1581013396.227 [DefaultWithUndock:CheckIn] Stopped 2020-02-06T18:23:16.227Z,1581013396.227 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-02-06T18:23:16.227Z,1581013396.227 [DefaultWithUndock:CheckIn](INFO): Running loop #14 2020-02-06T18:23:16.227Z,1581013396.227 [DefaultWithUndock:CheckIn] Running Loop=14 2020-02-06T18:23:16.227Z,1581013396.227 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn 2020-02-06T18:23:16.227Z,1581013396.227 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1 2020-02-06T18:23:18.199Z,1581013398.199 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182317.00,A,4131.46495,N,07040.28134,W,0.156,283.02,060220,,,A*7F 2020-02-06T18:23:18.201Z,1581013398.201 [NAL9602](INFO): GPS fix at 20200206T182317: (41.524416, -70.671356) 2020-02-06T18:23:18.254Z,1581013398.254 [DefaultWithUndock:CheckIn:Read_GPS] Stopped 2020-02-06T18:23:18.254Z,1581013398.254 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1 2020-02-06T18:23:25.756Z,1581013405.756 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200206T164211/Courier0042.lzma 2020-02-06T18:23:27.762Z,1581013407.762 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Courier0042.lzma.bak 2020-02-06T18:23:27.762Z,1581013407.762 [DataOverHttps](INFO): SBD MOMSN=12230567 2020-02-06T18:23:45.168Z,1581013425.168 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200206T164211/Express0043.lzma 2020-02-06T18:23:47.174Z,1581013427.174 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Express0043.lzma.bak 2020-02-06T18:23:47.175Z,1581013427.175 [DataOverHttps](INFO): SBD MOMSN=12230570 2020-02-06T18:23:48.543Z,1581013428.543 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped 2020-02-06T18:23:48.543Z,1581013428.543 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1 2020-02-06T18:23:48.543Z,1581013428.543 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-06T18:23:48.907Z,1581013428.907 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2020-02-06T18:23:48.988Z,1581013428.988 [NAL9602](FAULT): received: +CSQ:0 OK462, 2, 0, 0, 0 OK 2020-02-06T18:23:48.989Z,1581013428.989 [NAL9602] Data Fault, FailCount= 1 2020-02-06T18:23:48.989Z,1581013428.989 [NAL9602](ERROR): Data Fault 2020-02-06T18:23:49.074Z,1581013429.074 [CBIT](ERROR): Data Fault in component: NAL9602 2020-02-06T18:23:49.317Z,1581013429.317 [NAL9602](INFO): Powering down 2020-02-06T18:23:50.183Z,1581013430.183 [CBIT](INFO): Clearing failed state for component NAL9602 2020-02-06T18:23:50.183Z,1581013430.183 [NAL9602] No Fault, FailCount= 1 2020-02-06T18:24:04.696Z,1581013444.696 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-02-06T18:24:04.696Z,1581013444.696 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2020-02-06T18:24:19.619Z,1581013459.619 [NAL9602](INFO): Powering up NAL9602 2020-02-06T18:24:28.970Z,1581013468.970 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-02-06T18:24:28.970Z,1581013468.970 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-32768,-32768,-32768-32768,-32768,-32768,V 2020-02-06T18:24:30.528Z,1581013470.528 [NAL9602](INFO): NAL9602 initialized 2020-02-06T18:25:01.640Z,1581013501.640 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-06T18:25:03.665Z,1581013503.665 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-02-06T18:25:03.665Z,1581013503.665 [RDI_Pathfinder](ERROR): Failed to parse: :TS,20020611381686,35.0, -0.,1448.9, 0 2020-02-06T18:28:10.339Z,1581013690.339 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-02-06T18:28:10.339Z,1581013690.339 [RDI_Pathfinder](ERROR): Failed to parse: :TS,2002061141238-0.1, 0.0,1448.9, 0 2020-02-06T18:28:49.157Z,1581013729.157 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-06T18:28:49.158Z,1581013729.158 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-02-06T18:28:49.158Z,1581013729.158 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-06T18:28:49.158Z,1581013729.158 [DefaultWithUndock:CheckIn:D] Running Loop=1 2020-02-06T18:28:49.538Z,1581013729.538 [DefaultWithUndock:CheckIn:D] Stopped 2020-02-06T18:28:49.538Z,1581013729.538 [DefaultWithUndock:CheckIn:E] Running Loop=1 2020-02-06T18:28:49.983Z,1581013729.983 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 103.780029 min 2020-02-06T18:28:49.983Z,1581013729.983 [DefaultWithUndock:CheckIn:E] Stopped 2020-02-06T18:28:49.983Z,1581013729.983 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn 2020-02-06T18:28:49.983Z,1581013729.983 [DefaultWithUndock:CheckIn] Stopped 2020-02-06T18:28:49.983Z,1581013729.983 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-02-06T18:28:49.984Z,1581013729.984 [DefaultWithUndock:CheckIn](INFO): Running loop #15 2020-02-06T18:28:49.984Z,1581013729.984 [DefaultWithUndock:CheckIn] Running Loop=15 2020-02-06T18:28:49.984Z,1581013729.984 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn 2020-02-06T18:28:49.984Z,1581013729.984 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1 2020-02-06T18:28:53.959Z,1581013733.959 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-02-06T18:29:04.061Z,1581013744.061 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182902.00,A,4131.46990,N,07040.28392,W,1.263,0.00,060220,,,A*78 2020-02-06T18:29:04.063Z,1581013744.063 [NAL9602](INFO): GPS fix at 20200206T182902: (41.524498, -70.671399) 2020-02-06T18:29:04.087Z,1581013744.087 [DefaultWithUndock:CheckIn:Read_GPS] Stopped 2020-02-06T18:29:04.087Z,1581013744.087 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1 2020-02-06T18:29:11.472Z,1581013751.472 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200206T164211/Courier0045.lzma 2020-02-06T18:29:13.478Z,1581013753.478 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Courier0045.lzma.bak 2020-02-06T18:29:13.478Z,1581013753.478 [DataOverHttps](INFO): SBD MOMSN=12230579 2020-02-06T18:29:32.852Z,1581013772.852 [DataOverHttps](INFO): Sending 235 bytes from file Logs/20200206T164211/Express0046.lzma 2020-02-06T18:29:34.858Z,1581013774.858 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Express0046.lzma.bak 2020-02-06T18:29:34.858Z,1581013774.858 [DataOverHttps](INFO): SBD MOMSN=12230582 2020-02-06T18:29:36.374Z,1581013776.374 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-06T18:29:36.404Z,1581013776.404 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped 2020-02-06T18:29:36.421Z,1581013776.421 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1 2020-02-06T18:29:36.425Z,1581013776.425 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-06T18:31:09.329Z,1581013869.329 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-02-06T18:31:09.329Z,1581013869.329 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2020-02-06T18:32:23.649Z,1581013943.649 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00, 0.00 2020-02-06T18:34:37.001Z,1581014077.001 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-06T18:34:37.001Z,1581014077.001 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-02-06T18:34:37.001Z,1581014077.001 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-06T18:34:37.002Z,1581014077.002 [DefaultWithUndock:CheckIn:D] Running Loop=1 2020-02-06T18:34:37.408Z,1581014077.408 [DefaultWithUndock:CheckIn:D] Stopped 2020-02-06T18:34:37.409Z,1581014077.409 [DefaultWithUndock:CheckIn:E] Running Loop=1 2020-02-06T18:34:37.805Z,1581014077.805 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 109.577865 min 2020-02-06T18:34:37.805Z,1581014077.805 [DefaultWithUndock:CheckIn:E] Stopped 2020-02-06T18:34:37.805Z,1581014077.805 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn 2020-02-06T18:34:37.805Z,1581014077.805 [DefaultWithUndock:CheckIn] Stopped 2020-02-06T18:34:37.805Z,1581014077.805 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-02-06T18:34:37.805Z,1581014077.805 [DefaultWithUndock:CheckIn](INFO): Running loop #16 2020-02-06T18:34:37.805Z,1581014077.805 [DefaultWithUndock:CheckIn] Running Loop=16 2020-02-06T18:34:37.806Z,1581014077.806 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn 2020-02-06T18:34:37.806Z,1581014077.806 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1 2020-02-06T18:34:39.785Z,1581014079.785 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183439.00,A,4131.46299,N,07040.27574,W,0.058,201.99,060220,,,A*77 2020-02-06T18:34:39.787Z,1581014079.787 [NAL9602](INFO): GPS fix at 20200206T183439: (41.524383, -70.671262) 2020-02-06T18:34:39.817Z,1581014079.817 [DefaultWithUndock:CheckIn:Read_GPS] Stopped 2020-02-06T18:34:39.817Z,1581014079.817 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1 2020-02-06T18:34:49.408Z,1581014089.408 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200206T164211/Courier0048.lzma 2020-02-06T18:34:51.414Z,1581014091.414 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Courier0048.lzma.bak 2020-02-06T18:34:51.414Z,1581014091.414 [DataOverHttps](INFO): SBD MOMSN=12230587 2020-02-06T18:35:08.864Z,1581014108.864 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200206T164211/Express0049.lzma 2020-02-06T18:35:10.874Z,1581014110.874 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Express0049.lzma.bak 2020-02-06T18:35:10.874Z,1581014110.874 [DataOverHttps](INFO): SBD MOMSN=12230590 2020-02-06T18:35:12.181Z,1581014112.181 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped 2020-02-06T18:35:12.182Z,1581014112.182 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1 2020-02-06T18:35:12.182Z,1581014112.182 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-06T18:36:23.667Z,1581014183.667 [RDI_Pathfinder](ERROR): Failed to parse: :R, 0.00, 0.00, 0.00, 0.00 2020-02-06T18:39:28.308Z,1581014368.308 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-02-06T18:39:28.308Z,1581014368.308 [RDI_Pathfinder](ERROR): Failed to parse: :TS,20020611524186,3,1448.9, 0 2020-02-06T18:39:42.411Z,1581014382.411 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-02-06T18:40:11.181Z,1581014411.181 [RDI_Pathfinder](ERROR): only read 1 of 4 data items 2020-02-06T18:40:11.182Z,1581014411.182 [RDI_Pathfinder](ERROR): Failed to parse: :BS,1 2020-02-06T18:40:12.805Z,1581014412.805 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-06T18:40:12.805Z,1581014412.805 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-02-06T18:40:12.806Z,1581014412.806 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-06T18:40:12.806Z,1581014412.806 [DefaultWithUndock:CheckIn:D] Running Loop=1 2020-02-06T18:40:13.171Z,1581014413.171 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-06T18:40:13.196Z,1581014413.196 [DefaultWithUndock:CheckIn:D] Stopped 2020-02-06T18:40:13.196Z,1581014413.196 [DefaultWithUndock:CheckIn:E] Running Loop=1 2020-02-06T18:40:13.612Z,1581014413.612 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 115.174325 min 2020-02-06T18:40:13.612Z,1581014413.612 [DefaultWithUndock:CheckIn:E] Stopped 2020-02-06T18:40:13.612Z,1581014413.612 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn 2020-02-06T18:40:13.612Z,1581014413.612 [DefaultWithUndock:CheckIn] Stopped 2020-02-06T18:40:13.612Z,1581014413.612 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-02-06T18:40:13.613Z,1581014413.613 [DefaultWithUndock:CheckIn](INFO): Running loop #17 2020-02-06T18:40:13.613Z,1581014413.613 [DefaultWithUndock:CheckIn] Running Loop=17 2020-02-06T18:40:13.613Z,1581014413.613 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn 2020-02-06T18:40:13.613Z,1581014413.613 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1 2020-02-06T18:40:15.591Z,1581014415.591 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184014.00,A,4131.46943,N,07040.28140,W,0.058,195.62,060220,,,A*71 2020-02-06T18:40:15.593Z,1581014415.593 [NAL9602](INFO): GPS fix at 20200206T184014: (41.524490, -70.671357) 2020-02-06T18:40:15.644Z,1581014415.644 [DefaultWithUndock:CheckIn:Read_GPS] Stopped 2020-02-06T18:40:15.645Z,1581014415.645 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1 2020-02-06T18:40:25.456Z,1581014425.456 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200206T164211/Courier0051.lzma 2020-02-06T18:40:27.462Z,1581014427.462 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Courier0051.lzma.bak 2020-02-06T18:40:27.462Z,1581014427.462 [DataOverHttps](INFO): SBD MOMSN=12230601 2020-02-06T18:40:46.295Z,1581014446.295 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2020-02-06T18:40:46.368Z,1581014446.368 [NAL9602](FAULT): received: +CSQ:0 OK462, 2, 0, 0, 0 OK 2020-02-06T18:40:46.369Z,1581014446.369 [NAL9602] Data Fault, FailCount= 1 2020-02-06T18:40:46.369Z,1581014446.369 [NAL9602](ERROR): Data Fault 2020-02-06T18:40:46.399Z,1581014446.399 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-02-06T18:40:46.399Z,1581014446.399 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-32768,-32768,-32768-32768,-32768,-32768,V 2020-02-06T18:40:46.449Z,1581014446.449 [CBIT](ERROR): Data Fault in component: NAL9602 2020-02-06T18:40:46.705Z,1581014446.705 [NAL9602](INFO): Powering down 2020-02-06T18:40:47.113Z,1581014447.113 [DataOverHttps](INFO): Sending 197 bytes from file Logs/20200206T164211/Express0052.lzma 2020-02-06T18:40:47.567Z,1581014447.567 [CBIT](INFO): Clearing failed state for component NAL9602 2020-02-06T18:40:47.567Z,1581014447.567 [NAL9602] No Fault, FailCount= 1 2020-02-06T18:40:49.118Z,1581014449.118 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Express0052.lzma.bak 2020-02-06T18:40:49.118Z,1581014449.118 [DataOverHttps](INFO): SBD MOMSN=12230604 2020-02-06T18:40:50.372Z,1581014450.372 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped 2020-02-06T18:40:50.373Z,1581014450.373 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1 2020-02-06T18:40:50.373Z,1581014450.373 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-06T18:41:17.011Z,1581014477.011 [NAL9602](INFO): Powering up NAL9602 2020-02-06T18:41:27.958Z,1581014487.958 [NAL9602](INFO): NAL9602 initialized 2020-02-06T18:41:31.199Z,1581014491.199 [RDI_Pathfinder](ERROR): Failed to parse: :B +0.00, +0.00, +0.00, 0.00,7301.77 2020-02-06T18:41:59.023Z,1581014519.023 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-06T18:45:01.036Z,1581014701.036 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2020-02-06T18:45:01.036Z,1581014701.036 [RDI_Pathfinder](ERROR): Failed to parse: :BD, +0 +0.00, +0.00, 0.00,7512.66 2020-02-06T18:45:51.093Z,1581014751.093 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-06T18:45:51.093Z,1581014751.093 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-02-06T18:45:51.093Z,1581014751.093 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-06T18:45:51.093Z,1581014751.093 [DefaultWithUndock:CheckIn:D] Running Loop=1 2020-02-06T18:45:51.512Z,1581014751.512 [DefaultWithUndock:CheckIn:D] Stopped 2020-02-06T18:45:51.512Z,1581014751.512 [DefaultWithUndock:CheckIn:E] Running Loop=1 2020-02-06T18:45:51.903Z,1581014751.903 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 120.812923 min 2020-02-06T18:45:51.903Z,1581014751.903 [DefaultWithUndock:CheckIn:E] Stopped 2020-02-06T18:45:51.904Z,1581014751.904 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn 2020-02-06T18:45:51.904Z,1581014751.904 [DefaultWithUndock:CheckIn] Stopped 2020-02-06T18:45:51.904Z,1581014751.904 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-02-06T18:45:51.904Z,1581014751.904 [DefaultWithUndock:CheckIn](INFO): Running loop #18 2020-02-06T18:45:51.904Z,1581014751.904 [DefaultWithUndock:CheckIn] Running Loop=18 2020-02-06T18:45:51.904Z,1581014751.904 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn 2020-02-06T18:45:51.904Z,1581014751.904 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1 2020-02-06T18:45:55.956Z,1581014755.956 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-02-06T18:46:11.670Z,1581014771.670 [Micromodem](FAULT): Failed to receive CAREV within timeout 2020-02-06T18:46:11.670Z,1581014771.670 [Micromodem] Hardware Fault, FailCount= 1 2020-02-06T18:46:11.670Z,1581014771.670 [Micromodem](ERROR): Hardware Fault 2020-02-06T18:46:11.673Z,1581014771.673 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184610.00,A,4131.47699,N,07040.26368,W,1.147,288.23,060220,,,A*78 2020-02-06T18:46:11.675Z,1581014771.675 [NAL9602](INFO): GPS fix at 20200206T184610: (41.524617, -70.671061) 2020-02-06T18:46:11.757Z,1581014771.757 [DefaultWithUndock:CheckIn:Read_GPS] Stopped 2020-02-06T18:46:11.757Z,1581014771.757 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1 2020-02-06T18:46:11.766Z,1581014771.766 [CBIT](ERROR): Hardware Fault in component: Micromodem 2020-02-06T18:46:12.073Z,1581014772.073 [Micromodem](INFO): Powering down 2020-02-06T18:46:12.923Z,1581014772.923 [CBIT](INFO): Clearing failed state for component Micromodem 2020-02-06T18:46:12.923Z,1581014772.923 [Micromodem] No Fault, FailCount= 1 2020-02-06T18:46:15.706Z,1581014775.706 [Micromodem](INFO): Powering up 2020-02-06T18:46:15.706Z,1581014775.706 [Micromodem](DEBUG): Initializing Micromodem. 2020-02-06T18:46:18.164Z,1581014778.164 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20200206T164211/Courier0054.lzma 2020-02-06T18:46:20.170Z,1581014780.170 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Courier0054.lzma.bak 2020-02-06T18:46:20.170Z,1581014780.170 [DataOverHttps](INFO): SBD MOMSN=12230608 2020-02-06T18:46:20.558Z,1581014780.558 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33 2020-02-06T18:46:22.199Z,1581014782.199 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2020-02-06T18:46:22.199Z,1581014782.199 [RDI_Pathfinder] Communications Fault, FailCount= 1 2020-02-06T18:46:22.199Z,1581014782.199 [RDI_Pathfinder](ERROR): Communications Fault 2020-02-06T18:46:22.230Z,1581014782.230 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2020-02-06T18:46:22.661Z,1581014782.661 [RDI_Pathfinder](INFO): Powering down 2020-02-06T18:46:23.518Z,1581014783.518 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2020-02-06T18:46:23.518Z,1581014783.518 [RDI_Pathfinder] No Fault, FailCount= 1 2020-02-06T18:46:31.866Z,1581014791.866 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T18:46:36.735Z,1581014796.735 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 2020-02-06T18:46:39.209Z,1581014799.209 [DataOverHttps](INFO): Sending 282 bytes from file Logs/20200206T164211/Express0055.lzma 2020-02-06T18:46:41.214Z,1581014801.214 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Express0055.lzma.bak 2020-02-06T18:46:41.214Z,1581014801.214 [DataOverHttps](INFO): SBD MOMSN=12230611 2020-02-06T18:46:42.829Z,1581014802.829 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped 2020-02-06T18:46:42.829Z,1581014802.829 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1 2020-02-06T18:46:42.829Z,1581014802.829 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-06T18:46:43.178Z,1581014803.178 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T18:46:44.390Z,1581014804.390 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-06T18:46:54.494Z,1581014814.494 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T18:47:05.806Z,1581014825.806 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T18:47:17.114Z,1581014837.114 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T18:47:28.441Z,1581014848.441 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T18:47:39.738Z,1581014859.738 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T18:47:47.102Z,1581014867.102 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-02-06T18:47:47.102Z,1581014867.102 [RDI_Pathfinder](ERROR): Failed to parse: :TS,200206120100 -0.1, 0.0,1448.9, 0 2020-02-06T18:47:51.050Z,1581014871.050 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T18:48:02.362Z,1581014882.362 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T18:48:13.674Z,1581014893.674 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T18:48:15.692Z,1581014895.692 [Micromodem](FAULT): failed to initialize; deviceResponse_ loaded: , available: 2020-02-06T18:48:15.692Z,1581014895.692 [Micromodem] Communications Fault, FailCount= 2 2020-02-06T18:48:15.692Z,1581014895.692 [Micromodem](ERROR): Communications Fault 2020-02-06T18:48:15.733Z,1581014895.733 [CBIT](ERROR): Communications Fault in component: Micromodem 2020-02-06T18:48:16.102Z,1581014896.102 [Micromodem](INFO): Powering down 2020-02-06T18:48:16.957Z,1581014896.957 [CBIT](INFO): Clearing failed state for component Micromodem 2020-02-06T18:48:16.957Z,1581014896.957 [Micromodem] No Fault, FailCount= 2 2020-02-06T18:48:19.743Z,1581014899.743 [Micromodem](INFO): Powering up 2020-02-06T18:48:19.743Z,1581014899.743 [Micromodem](DEBUG): Initializing Micromodem. 2020-02-06T18:48:24.994Z,1581014904.994 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T18:48:27.028Z,1581014907.028 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2020-02-06T18:48:27.028Z,1581014907.028 [RDI_Pathfinder] Communications Fault, FailCount= 1 2020-02-06T18:48:27.029Z,1581014907.029 [RDI_Pathfinder](ERROR): Communications Fault 2020-02-06T18:48:27.074Z,1581014907.074 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2020-02-06T18:48:27.509Z,1581014907.509 [RDI_Pathfinder](INFO): Powering down 2020-02-06T18:48:28.269Z,1581014908.269 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2020-02-06T18:48:28.269Z,1581014908.269 [RDI_Pathfinder] No Fault, FailCount= 1 2020-02-06T18:48:36.302Z,1581014916.302 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T18:48:41.569Z,1581014921.569 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 2020-02-06T18:48:47.614Z,1581014927.614 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T18:48:58.928Z,1581014938.928 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T18:49:10.238Z,1581014950.238 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T18:49:21.550Z,1581014961.550 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T18:49:32.878Z,1581014972.878 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T18:49:44.174Z,1581014984.174 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T18:49:55.499Z,1581014995.499 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T18:50:06.802Z,1581015006.802 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T18:50:18.112Z,1581015018.112 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T18:50:19.730Z,1581015019.730 [Micromodem](FAULT): failed to initialize; deviceResponse_ loaded: , available: 2020-02-06T18:50:19.730Z,1581015019.730 [Micromodem] Communications Fault, FailCount= 3 2020-02-06T18:50:19.730Z,1581015019.730 [Micromodem](ERROR): Communications Fault 2020-02-06T18:50:19.856Z,1581015019.856 [CBIT](ERROR): Communications Fault in component: Micromodem 2020-02-06T18:50:20.137Z,1581015020.137 [Micromodem](INFO): Powering down 2020-02-06T18:50:21.036Z,1581015021.036 [CBIT](INFO): Clearing failed state for component Micromodem 2020-02-06T18:50:21.036Z,1581015021.036 [Micromodem] No Fault, FailCount= 3 2020-02-06T18:50:23.774Z,1581015023.774 [Micromodem](INFO): Powering up 2020-02-06T18:50:23.774Z,1581015023.774 [Micromodem](DEBUG): Initializing Micromodem. 2020-02-06T18:50:29.426Z,1581015029.426 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T18:50:31.065Z,1581015031.065 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2020-02-06T18:50:31.065Z,1581015031.065 [RDI_Pathfinder] Communications Fault, FailCount= 1 2020-02-06T18:50:31.065Z,1581015031.065 [RDI_Pathfinder](ERROR): Communications Fault 2020-02-06T18:50:31.089Z,1581015031.089 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2020-02-06T18:50:31.529Z,1581015031.529 [RDI_Pathfinder](INFO): Powering down 2020-02-06T18:50:32.293Z,1581015032.293 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2020-02-06T18:50:32.293Z,1581015032.293 [RDI_Pathfinder] No Fault, FailCount= 1 2020-02-06T18:50:40.736Z,1581015040.736 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T18:50:45.602Z,1581015045.602 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 2020-02-06T18:50:52.050Z,1581015052.050 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T18:51:03.362Z,1581015063.362 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T18:51:14.678Z,1581015074.678 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T18:51:25.991Z,1581015085.991 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T18:51:37.303Z,1581015097.303 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T18:51:43.388Z,1581015103.388 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-06T18:51:43.388Z,1581015103.388 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-02-06T18:51:43.388Z,1581015103.388 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-06T18:51:43.388Z,1581015103.388 [DefaultWithUndock:CheckIn:D] Running Loop=1 2020-02-06T18:51:43.798Z,1581015103.798 [DefaultWithUndock:CheckIn:D] Stopped 2020-02-06T18:51:43.798Z,1581015103.798 [DefaultWithUndock:CheckIn:E] Running Loop=1 2020-02-06T18:51:44.211Z,1581015104.211 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 126.684351 min 2020-02-06T18:51:44.211Z,1581015104.211 [DefaultWithUndock:CheckIn:E] Stopped 2020-02-06T18:51:44.211Z,1581015104.211 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn 2020-02-06T18:51:44.211Z,1581015104.211 [DefaultWithUndock:CheckIn] Stopped 2020-02-06T18:51:44.212Z,1581015104.212 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-02-06T18:51:44.212Z,1581015104.212 [DefaultWithUndock:CheckIn](INFO): Running loop #19 2020-02-06T18:51:44.212Z,1581015104.212 [DefaultWithUndock:CheckIn] Running Loop=19 2020-02-06T18:51:44.212Z,1581015104.212 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn 2020-02-06T18:51:44.212Z,1581015104.212 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1 2020-02-06T18:51:46.198Z,1581015106.198 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185145.00,A,4131.47721,N,07040.27508,W,0.797,73.67,060220,,,A*41 2020-02-06T18:51:46.200Z,1581015106.200 [NAL9602](INFO): GPS fix at 20200206T185145: (41.524620, -70.671251) 2020-02-06T18:51:46.225Z,1581015106.225 [DefaultWithUndock:CheckIn:Read_GPS] Stopped 2020-02-06T18:51:46.225Z,1581015106.225 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1 2020-02-06T18:51:48.616Z,1581015108.616 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T18:51:54.360Z,1581015114.360 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200206T164211/Courier0057.lzma 2020-02-06T18:51:56.366Z,1581015116.366 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Courier0057.lzma.bak 2020-02-06T18:51:56.366Z,1581015116.366 [DataOverHttps](INFO): SBD MOMSN=12230623 2020-02-06T18:51:59.927Z,1581015119.927 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T18:52:11.267Z,1581015131.267 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T18:52:19.432Z,1581015139.432 [DataOverHttps](INFO): Sending 186 bytes from file Logs/20200206T164211/Express0058.lzma 2020-02-06T18:52:21.438Z,1581015141.438 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Express0058.lzma.bak 2020-02-06T18:52:21.438Z,1581015141.438 [DataOverHttps](INFO): SBD MOMSN=12230626 2020-02-06T18:52:22.570Z,1581015142.570 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T18:52:23.039Z,1581015143.039 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped 2020-02-06T18:52:23.039Z,1581015143.039 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1 2020-02-06T18:52:23.039Z,1581015143.039 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-06T18:52:23.382Z,1581015143.382 [Micromodem](FAULT): failed to initialize; deviceResponse_ loaded: , available: 2020-02-06T18:52:23.382Z,1581015143.382 [Micromodem] Communications Fault, FailCount= 4 2020-02-06T18:52:23.382Z,1581015143.382 [Micromodem](ERROR): Communications Fault 2020-02-06T18:52:23.451Z,1581015143.451 [CBIT](ERROR): Communications Fault in component: Micromodem 2020-02-06T18:52:23.785Z,1581015143.785 [Micromodem](INFO): Powering down 2020-02-06T18:52:24.704Z,1581015144.704 [CBIT](INFO): Clearing failed state for component Micromodem 2020-02-06T18:52:24.704Z,1581015144.704 [Micromodem] No Fault, FailCount= 4 2020-02-06T18:52:27.430Z,1581015147.430 [Micromodem](INFO): Powering up 2020-02-06T18:52:27.430Z,1581015147.430 [Micromodem](DEBUG): Initializing Micromodem. 2020-02-06T18:52:33.881Z,1581015153.881 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T18:52:34.308Z,1581015154.308 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2020-02-06T18:52:34.308Z,1581015154.308 [RDI_Pathfinder] Communications Fault, FailCount= 1 2020-02-06T18:52:34.308Z,1581015154.308 [RDI_Pathfinder](ERROR): Communications Fault 2020-02-06T18:52:34.325Z,1581015154.325 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2020-02-06T18:52:34.785Z,1581015154.785 [RDI_Pathfinder](INFO): Powering down 2020-02-06T18:52:35.566Z,1581015155.566 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2020-02-06T18:52:35.566Z,1581015155.566 [RDI_Pathfinder] No Fault, FailCount= 1 2020-02-06T18:52:45.198Z,1581015165.198 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T18:52:48.857Z,1581015168.857 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 2020-02-06T18:52:56.510Z,1581015176.510 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T18:53:07.818Z,1581015187.818 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T18:53:19.130Z,1581015199.130 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T18:53:30.446Z,1581015210.446 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T18:53:41.405Z,1581015221.405 [Micromodem](INFO): Nmea in: 85342,AUV,2.0.32731*12 2020-02-06T18:53:41.405Z,1581015221.405 [Micromodem](ERROR): Response from modem failed NMEA checksum: 85342,AUV,2.0.32731*12 expected 0 2020-02-06T18:53:41.405Z,1581015221.405 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33 2020-02-06T18:53:52.672Z,1581015232.672 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T18:54:03.998Z,1581015243.998 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T18:54:15.328Z,1581015255.328 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T18:54:26.626Z,1581015266.626 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T18:54:27.034Z,1581015267.034 [Micromodem](FAULT): failed to initialize; deviceResponse_ loaded: $CAREV,185342,COPROC,0.10.0.46*49 , available: 2020-02-06T18:54:27.034Z,1581015267.034 [Micromodem] Communications Fault, FailCount= 5 2020-02-06T18:54:27.034Z,1581015267.034 [Micromodem](ERROR): Communications Fault 2020-02-06T18:54:27.160Z,1581015267.160 [CBIT](ERROR): Communications Fault in component: Micromodem 2020-02-06T18:54:27.442Z,1581015267.442 [Micromodem](INFO): Powering down 2020-02-06T18:54:28.297Z,1581015268.297 [CBIT](INFO): Clearing failed state for component Micromodem 2020-02-06T18:54:28.297Z,1581015268.297 [Micromodem] No Fault, FailCount= 5 2020-02-06T18:54:31.074Z,1581015271.074 [Micromodem](INFO): Powering up 2020-02-06T18:54:31.074Z,1581015271.074 [Micromodem](DEBUG): Initializing Micromodem. 2020-02-06T18:54:37.938Z,1581015277.938 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T18:54:38.358Z,1581015278.358 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2020-02-06T18:54:38.358Z,1581015278.358 [RDI_Pathfinder] Communications Fault, FailCount= 1 2020-02-06T18:54:38.358Z,1581015278.358 [RDI_Pathfinder](ERROR): Communications Fault 2020-02-06T18:54:38.423Z,1581015278.423 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2020-02-06T18:54:38.837Z,1581015278.837 [RDI_Pathfinder](INFO): Powering down 2020-02-06T18:54:39.598Z,1581015279.598 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2020-02-06T18:54:39.598Z,1581015279.598 [RDI_Pathfinder] No Fault, FailCount= 1 2020-02-06T18:54:49.250Z,1581015289.250 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T18:54:52.916Z,1581015292.916 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 2020-02-06T18:55:00.560Z,1581015300.560 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T18:55:02.587Z,1581015302.587 [NAL9602](INFO): SBD MO Status=2, MOMSN=10462, MT Status=2, MTMSN=0 2020-02-06T18:55:02.588Z,1581015302.588 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-02-06T18:55:05.022Z,1581015305.022 [Micromodem](INFO): Nmea in: 85505,AUV,2.0.32731*17 2020-02-06T18:55:05.022Z,1581015305.022 [Micromodem](ERROR): Response from modem failed NMEA checksum: 85505,AUV,2.0.32731*17 expected 0 2020-02-06T18:55:05.023Z,1581015305.023 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33 2020-02-06T18:55:05.830Z,1581015305.830 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31 2020-02-06T18:55:05.830Z,1581015305.830 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31 2020-02-06T18:55:17.124Z,1581015317.124 [Micromodem](ERROR): Nmea resend: $CCCFG,SRC,1*31 2020-02-06T18:55:17.530Z,1581015317.530 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33 2020-02-06T18:55:17.530Z,1581015317.530 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64 2020-02-06T18:55:17.939Z,1581015317.939 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66 2020-02-06T18:55:17.940Z,1581015317.940 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F 2020-02-06T18:55:18.342Z,1581015318.342 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D 2020-02-06T18:55:18.342Z,1581015318.342 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E 2020-02-06T18:55:18.746Z,1581015318.746 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C 2020-02-06T18:55:18.746Z,1581015318.746 [Micromodem](INFO): Nmea out: $CCCFG,FC0,10000*46 2020-02-06T18:55:19.161Z,1581015319.161 [Micromodem](INFO): Nmea in: $CACFG,FC0,10000*44 2020-02-06T18:55:19.162Z,1581015319.162 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63 2020-02-06T18:55:19.549Z,1581015319.549 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61 2020-02-06T18:55:19.549Z,1581015319.549 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A 2020-02-06T18:55:20.003Z,1581015320.003 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38 2020-02-06T18:55:20.003Z,1581015320.003 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33 2020-02-06T18:55:20.370Z,1581015320.370 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31 2020-02-06T18:55:20.370Z,1581015320.370 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31 2020-02-06T18:55:20.771Z,1581015320.771 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33 2020-02-06T18:55:20.771Z,1581015320.771 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64 2020-02-06T18:55:21.193Z,1581015321.193 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66 2020-02-06T18:55:21.194Z,1581015321.194 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F 2020-02-06T18:55:21.578Z,1581015321.578 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D 2020-02-06T18:55:21.578Z,1581015321.578 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E 2020-02-06T18:55:21.975Z,1581015321.975 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C 2020-02-06T18:55:21.975Z,1581015321.975 [Micromodem](INFO): Nmea out: $CCCFG,FC0,10000*46 2020-02-06T18:55:22.387Z,1581015322.387 [Micromodem](INFO): Nmea in: $CACFG,FC0,10000*44 2020-02-06T18:55:22.387Z,1581015322.387 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63 2020-02-06T18:55:22.787Z,1581015322.787 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61 2020-02-06T18:55:22.788Z,1581015322.788 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A 2020-02-06T18:55:23.190Z,1581015323.190 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38 2020-02-06T18:55:23.190Z,1581015323.190 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33 2020-02-06T18:55:23.594Z,1581015323.594 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31 2020-02-06T18:55:23.594Z,1581015323.594 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31 2020-02-06T18:55:24.003Z,1581015324.003 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33 2020-02-06T18:55:24.003Z,1581015324.003 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64 2020-02-06T18:55:24.402Z,1581015324.402 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66 2020-02-06T18:55:24.403Z,1581015324.403 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F 2020-02-06T18:55:24.833Z,1581015324.833 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D 2020-02-06T18:55:24.833Z,1581015324.833 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E 2020-02-06T18:55:25.206Z,1581015325.206 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C 2020-02-06T18:55:25.207Z,1581015325.207 [Micromodem](INFO): Nmea out: $CCCFG,FC0,10000*46 2020-02-06T18:55:26.418Z,1581015326.418 [Micromodem](INFO): Nmea in: $CACFG,FC0,10000*44 2020-02-06T18:55:26.418Z,1581015326.418 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63 2020-02-06T18:55:26.830Z,1581015326.830 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61 2020-02-06T18:55:26.831Z,1581015326.831 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A 2020-02-06T18:55:27.231Z,1581015327.231 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38 2020-02-06T18:55:27.231Z,1581015327.231 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33 2020-02-06T18:55:27.630Z,1581015327.630 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31 2020-02-06T18:55:27.630Z,1581015327.630 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31 2020-02-06T18:55:28.034Z,1581015328.034 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33 2020-02-06T18:55:28.034Z,1581015328.034 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64 2020-02-06T18:55:28.444Z,1581015328.444 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66 2020-02-06T18:55:28.444Z,1581015328.444 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F 2020-02-06T18:55:28.845Z,1581015328.845 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D 2020-02-06T18:55:28.845Z,1581015328.845 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E 2020-02-06T18:55:29.246Z,1581015329.246 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C 2020-02-06T18:55:29.246Z,1581015329.246 [Micromodem](INFO): Nmea out: $CCCFG,FC0,10000*46 2020-02-06T18:55:29.658Z,1581015329.658 [Micromodem](INFO): Nmea in: $CACFG,FC0,10000*44 2020-02-06T18:55:29.658Z,1581015329.658 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63 2020-02-06T18:55:30.058Z,1581015330.058 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61 2020-02-06T18:55:30.058Z,1581015330.058 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A 2020-02-06T18:55:30.462Z,1581015330.462 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38 2020-02-06T18:55:30.462Z,1581015330.462 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33 2020-02-06T18:55:30.866Z,1581015330.866 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31 2020-02-06T18:55:30.866Z,1581015330.866 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31 2020-02-06T18:55:31.266Z,1581015331.266 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33 2020-02-06T18:55:31.266Z,1581015331.266 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64 2020-02-06T18:55:31.678Z,1581015331.678 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66 2020-02-06T18:55:31.678Z,1581015331.678 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F 2020-02-06T18:55:32.074Z,1581015332.074 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D 2020-02-06T18:55:32.075Z,1581015332.075 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E 2020-02-06T18:55:32.482Z,1581015332.482 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C 2020-02-06T18:55:32.482Z,1581015332.482 [Micromodem](INFO): Nmea out: $CCCFG,FC0,10000*46 2020-02-06T18:55:32.886Z,1581015332.886 [Micromodem](INFO): Nmea in: $CACFG,FC0,10000*44 2020-02-06T18:55:32.886Z,1581015332.886 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63 2020-02-06T18:55:33.293Z,1581015333.293 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61 2020-02-06T18:55:33.293Z,1581015333.293 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A 2020-02-06T18:55:33.694Z,1581015333.694 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38 2020-02-06T18:55:33.694Z,1581015333.694 [Micromodem](INFO): Nmea out: $CCCLK,2020,02,06,18,55,21*4A 2020-02-06T18:55:34.102Z,1581015334.102 [Micromodem](INFO): Nmea in: $CACLK,2020,2,6,18,55,21*48 2020-02-06T18:55:34.104Z,1581015334.104 [Micromodem](INFO): Nmea in: $CATMS,0,2020-02-06T18:55:22Z*7B 2020-02-06T18:55:34.105Z,1581015334.105 [Micromodem](INFO): Nmea in: $CATMG,2020-02-06T18:55:22.027615Z,USER_CMD,RTC*1B 2020-02-06T18:56:48.456Z,1581015408.456 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-02-06T18:57:19.155Z,1581015439.155 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-06T18:57:23.624Z,1581015443.624 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-06T18:57:23.640Z,1581015443.640 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-02-06T18:57:23.641Z,1581015443.641 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-06T18:57:23.641Z,1581015443.641 [DefaultWithUndock:CheckIn:D] Running Loop=1 2020-02-06T18:57:24.037Z,1581015444.037 [DefaultWithUndock:CheckIn:D] Stopped 2020-02-06T18:57:24.037Z,1581015444.037 [DefaultWithUndock:CheckIn:E] Running Loop=1 2020-02-06T18:57:24.438Z,1581015444.438 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 132.354997 min 2020-02-06T18:57:24.438Z,1581015444.438 [DefaultWithUndock:CheckIn:E] Stopped 2020-02-06T18:57:24.438Z,1581015444.438 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn 2020-02-06T18:57:24.438Z,1581015444.438 [DefaultWithUndock:CheckIn] Stopped 2020-02-06T18:57:24.438Z,1581015444.438 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-02-06T18:57:24.438Z,1581015444.438 [DefaultWithUndock:CheckIn](INFO): Running loop #20 2020-02-06T18:57:24.439Z,1581015444.439 [DefaultWithUndock:CheckIn] Running Loop=20 2020-02-06T18:57:24.439Z,1581015444.439 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn 2020-02-06T18:57:24.439Z,1581015444.439 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1 2020-02-06T18:57:26.428Z,1581015446.428 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185725.00,A,4131.44632,N,07040.28182,W,0.467,264.40,060220,,,A*75 2020-02-06T18:57:26.430Z,1581015446.430 [NAL9602](INFO): GPS fix at 20200206T185725: (41.524105, -70.671364) 2020-02-06T18:57:26.473Z,1581015446.473 [DefaultWithUndock:CheckIn:Read_GPS] Stopped 2020-02-06T18:57:26.473Z,1581015446.473 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1 2020-02-06T18:57:33.872Z,1581015453.872 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200206T164211/Courier0060.lzma 2020-02-06T18:57:35.879Z,1581015455.879 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Courier0060.lzma.bak 2020-02-06T18:57:35.879Z,1581015455.879 [DataOverHttps](INFO): SBD MOMSN=12230631 2020-02-06T18:57:55.565Z,1581015475.565 [DataOverHttps](INFO): Sending 292 bytes from file Logs/20200206T164211/Express0061.lzma 2020-02-06T18:57:57.131Z,1581015477.131 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2020-02-06T18:57:57.212Z,1581015477.212 [NAL9602](FAULT): received: +CSQ:0 OK462, 2, 0, 0, 0 OK 2020-02-06T18:57:57.213Z,1581015477.213 [NAL9602] Data Fault, FailCount= 1 2020-02-06T18:57:57.213Z,1581015477.213 [NAL9602](ERROR): Data Fault 2020-02-06T18:57:57.286Z,1581015477.286 [CBIT](ERROR): Data Fault in component: NAL9602 2020-02-06T18:57:57.538Z,1581015477.538 [NAL9602](INFO): Powering down 2020-02-06T18:57:57.570Z,1581015477.570 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Express0061.lzma.bak 2020-02-06T18:57:57.570Z,1581015477.570 [DataOverHttps](INFO): SBD MOMSN=12230634 2020-02-06T18:57:58.426Z,1581015478.426 [CBIT](INFO): Clearing failed state for component NAL9602 2020-02-06T18:57:58.426Z,1581015478.426 [NAL9602] No Fault, FailCount= 1 2020-02-06T18:57:59.201Z,1581015479.201 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped 2020-02-06T18:57:59.201Z,1581015479.201 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1 2020-02-06T18:57:59.201Z,1581015479.201 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-06T18:58:27.865Z,1581015507.865 [NAL9602](INFO): Powering up NAL9602 2020-02-06T18:58:38.767Z,1581015518.767 [NAL9602](INFO): NAL9602 initialized 2020-02-06T18:59:09.879Z,1581015549.879 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-06T19:00:02.893Z,1581015602.893 [RDI_Pathfinder](ERROR): only read 0 of 4 data items 2020-02-06T19:00:02.893Z,1581015602.893 [RDI_Pathfinder](ERROR): Failed to parse: :RA,:BI,-32768,-32768,-32768,-32768,V 2020-02-06T19:02:59.808Z,1581015779.808 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-06T19:02:59.808Z,1581015779.808 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-02-06T19:02:59.809Z,1581015779.809 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-06T19:02:59.809Z,1581015779.809 [DefaultWithUndock:CheckIn:D] Running Loop=1 2020-02-06T19:03:00.208Z,1581015780.208 [DefaultWithUndock:CheckIn:D] Stopped 2020-02-06T19:03:00.208Z,1581015780.208 [DefaultWithUndock:CheckIn:E] Running Loop=1 2020-02-06T19:03:00.593Z,1581015780.593 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 137.957861 min 2020-02-06T19:03:00.594Z,1581015780.594 [DefaultWithUndock:CheckIn:E] Stopped 2020-02-06T19:03:00.594Z,1581015780.594 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn 2020-02-06T19:03:00.594Z,1581015780.594 [DefaultWithUndock:CheckIn] Stopped 2020-02-06T19:03:00.594Z,1581015780.594 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-02-06T19:03:00.594Z,1581015780.594 [DefaultWithUndock:CheckIn](INFO): Running loop #21 2020-02-06T19:03:00.594Z,1581015780.594 [DefaultWithUndock:CheckIn] Running Loop=21 2020-02-06T19:03:00.594Z,1581015780.594 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn 2020-02-06T19:03:00.594Z,1581015780.594 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1 2020-02-06T19:03:05.011Z,1581015785.011 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-02-06T19:03:39.789Z,1581015819.789 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2020-02-06T19:03:39.792Z,1581015819.792 [BPC1](INFO): Received data from all battery sticks. 2020-02-06T19:03:40.968Z,1581015820.968 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190339.00,A,4131.44772,N,07040.28761,W,0.603,0.00,060220,,,A*72 2020-02-06T19:03:40.970Z,1581015820.970 [NAL9602](INFO): GPS fix at 20200206T190339: (41.524129, -70.671460) 2020-02-06T19:03:40.994Z,1581015820.994 [DefaultWithUndock:CheckIn:Read_GPS] Stopped 2020-02-06T19:03:40.995Z,1581015820.995 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1 2020-02-06T19:03:52.380Z,1581015832.380 [DataOverHttps](INFO): Sending 83 bytes from file Logs/20200206T164211/Courier0063.lzma 2020-02-06T19:03:54.386Z,1581015834.386 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Courier0063.lzma.bak 2020-02-06T19:03:54.386Z,1581015834.386 [DataOverHttps](INFO): SBD MOMSN=12230649 2020-02-06T19:04:13.298Z,1581015853.298 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-06T19:04:19.716Z,1581015859.716 [DataOverHttps](INFO): Sending 237 bytes from file Logs/20200206T164211/Express0064.lzma 2020-02-06T19:04:21.722Z,1581015861.722 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Express0064.lzma.bak 2020-02-06T19:04:21.722Z,1581015861.722 [DataOverHttps](INFO): SBD MOMSN=12230652 2020-02-06T19:04:23.024Z,1581015863.024 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped 2020-02-06T19:04:23.024Z,1581015863.024 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1 2020-02-06T19:04:23.024Z,1581015863.024 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-06T19:09:23.634Z,1581016163.634 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-06T19:09:23.634Z,1581016163.634 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-02-06T19:09:23.634Z,1581016163.634 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-06T19:09:23.634Z,1581016163.634 [DefaultWithUndock:CheckIn:D] Running Loop=1 2020-02-06T19:09:24.052Z,1581016164.052 [DefaultWithUndock:CheckIn:D] Stopped 2020-02-06T19:09:24.052Z,1581016164.052 [DefaultWithUndock:CheckIn:E] Running Loop=1 2020-02-06T19:09:24.449Z,1581016164.449 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 144.355257 min 2020-02-06T19:09:24.449Z,1581016164.449 [DefaultWithUndock:CheckIn:E] Stopped 2020-02-06T19:09:24.449Z,1581016164.449 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn 2020-02-06T19:09:24.449Z,1581016164.449 [DefaultWithUndock:CheckIn] Stopped 2020-02-06T19:09:24.449Z,1581016164.449 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-02-06T19:09:24.450Z,1581016164.450 [DefaultWithUndock:CheckIn](INFO): Running loop #22 2020-02-06T19:09:24.450Z,1581016164.450 [DefaultWithUndock:CheckIn] Running Loop=22 2020-02-06T19:09:24.450Z,1581016164.450 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn 2020-02-06T19:09:24.450Z,1581016164.450 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1 2020-02-06T19:09:26.444Z,1581016166.444 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190925.00,A,4131.46726,N,07040.27921,W,0.233,0.00,060220,,,A*74 2020-02-06T19:09:26.446Z,1581016166.446 [NAL9602](INFO): GPS fix at 20200206T190925: (41.524454, -70.671320) 2020-02-06T19:09:26.490Z,1581016166.490 [DefaultWithUndock:CheckIn:Read_GPS] Stopped 2020-02-06T19:09:26.490Z,1581016166.490 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1 2020-02-06T19:09:34.540Z,1581016174.540 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200206T164211/Courier0066.lzma 2020-02-06T19:09:36.546Z,1581016176.546 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Courier0066.lzma.bak 2020-02-06T19:09:36.546Z,1581016176.546 [DataOverHttps](INFO): SBD MOMSN=12230667 2020-02-06T19:09:57.272Z,1581016197.272 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200206T164211/Express0067.lzma 2020-02-06T19:09:59.278Z,1581016199.278 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Express0067.lzma.bak 2020-02-06T19:09:59.278Z,1581016199.278 [DataOverHttps](INFO): SBD MOMSN=12230670 2020-02-06T19:10:00.816Z,1581016200.816 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped 2020-02-06T19:10:00.817Z,1581016200.817 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1 2020-02-06T19:10:00.817Z,1581016200.817 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-06T19:10:18.236Z,1581016218.236 [RDI_Pathfinder](ERROR): only read 0 of 4 data items 2020-02-06T19:10:18.236Z,1581016218.236 [RDI_Pathfinder](ERROR): Failed to parse: :RA 0.00, 0.00, 0.00, 0.00 2020-02-06T19:13:22.008Z,1581016402.008 [NAL9602](INFO): SBD MO Status=0, MOMSN=10462, MT Status=0, MTMSN=0 2020-02-06T19:13:22.008Z,1581016402.008 [NAL9602](INFO): No messages in MT queue 2020-02-06T19:13:52.707Z,1581016432.707 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-06T19:15:01.436Z,1581016501.436 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-06T19:15:01.436Z,1581016501.436 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-02-06T19:15:01.436Z,1581016501.436 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-06T19:15:01.437Z,1581016501.437 [DefaultWithUndock:CheckIn:D] Running Loop=1 2020-02-06T19:15:01.822Z,1581016501.822 [DefaultWithUndock:CheckIn:D] Stopped 2020-02-06T19:15:01.822Z,1581016501.822 [DefaultWithUndock:CheckIn:E] Running Loop=1 2020-02-06T19:15:02.242Z,1581016502.242 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 149.984766 min 2020-02-06T19:15:02.242Z,1581016502.242 [DefaultWithUndock:CheckIn:E] Stopped 2020-02-06T19:15:02.243Z,1581016502.243 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn 2020-02-06T19:15:02.243Z,1581016502.243 [DefaultWithUndock:CheckIn] Stopped 2020-02-06T19:15:02.243Z,1581016502.243 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-02-06T19:15:02.243Z,1581016502.243 [DefaultWithUndock:CheckIn](INFO): Running loop #23 2020-02-06T19:15:02.243Z,1581016502.243 [DefaultWithUndock:CheckIn] Running Loop=23 2020-02-06T19:15:02.243Z,1581016502.243 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn 2020-02-06T19:15:02.243Z,1581016502.243 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1 2020-02-06T19:15:04.224Z,1581016504.224 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191503.00,A,4131.46346,N,07040.27712,W,0.544,0.00,060220,,,A*76 2020-02-06T19:15:04.226Z,1581016504.226 [NAL9602](INFO): GPS fix at 20200206T191503: (41.524391, -70.671285) 2020-02-06T19:15:04.269Z,1581016504.269 [DefaultWithUndock:CheckIn:Read_GPS] Stopped 2020-02-06T19:15:04.269Z,1581016504.269 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1 2020-02-06T19:15:11.836Z,1581016511.836 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200206T164211/Courier0069.lzma 2020-02-06T19:15:13.842Z,1581016513.842 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Courier0069.lzma.bak 2020-02-06T19:15:13.842Z,1581016513.842 [DataOverHttps](INFO): SBD MOMSN=12230675 2020-02-06T19:15:36.404Z,1581016536.404 [DataOverHttps](INFO): Sending 121 bytes from file Logs/20200206T164211/Express0070.lzma 2020-02-06T19:15:38.410Z,1581016538.410 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Express0070.lzma.bak 2020-02-06T19:15:38.410Z,1581016538.410 [DataOverHttps](INFO): SBD MOMSN=12230678 2020-02-06T19:15:39.808Z,1581016539.808 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped 2020-02-06T19:15:39.809Z,1581016539.809 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1 2020-02-06T19:15:39.809Z,1581016539.809 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-06T19:20:03.208Z,1581016803.208 [RDI_Pathfinder](ERROR): Failed to parse: :0, 0.00, 0.00, 0.00, 0.00 2020-02-06T19:20:06.425Z,1581016806.425 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-02-06T19:20:37.114Z,1581016837.114 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-06T19:20:40.383Z,1581016840.383 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-06T19:20:40.383Z,1581016840.383 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-02-06T19:20:40.383Z,1581016840.383 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-06T19:20:40.383Z,1581016840.383 [DefaultWithUndock:CheckIn:D] Running Loop=1 2020-02-06T19:20:40.772Z,1581016840.772 [DefaultWithUndock:CheckIn:D] Stopped 2020-02-06T19:20:40.772Z,1581016840.772 [DefaultWithUndock:CheckIn:E] Running Loop=1 2020-02-06T19:20:41.217Z,1581016841.217 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 155.633936 min 2020-02-06T19:20:41.217Z,1581016841.217 [DefaultWithUndock:CheckIn:E] Stopped 2020-02-06T19:20:41.217Z,1581016841.217 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn 2020-02-06T19:20:41.217Z,1581016841.217 [DefaultWithUndock:CheckIn] Stopped 2020-02-06T19:20:41.217Z,1581016841.217 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-02-06T19:20:41.217Z,1581016841.217 [DefaultWithUndock:CheckIn](INFO): Running loop #24 2020-02-06T19:20:41.217Z,1581016841.217 [DefaultWithUndock:CheckIn] Running Loop=24 2020-02-06T19:20:41.217Z,1581016841.217 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn 2020-02-06T19:20:41.218Z,1581016841.218 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1 2020-02-06T19:20:43.180Z,1581016843.180 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192042.00,A,4131.46900,N,07040.28245,W,0.447,229.69,060220,,,A*71 2020-02-06T19:20:43.191Z,1581016843.191 [NAL9602](INFO): GPS fix at 20200206T192042: (41.524483, -70.671374) 2020-02-06T19:20:43.224Z,1581016843.224 [DefaultWithUndock:CheckIn:Read_GPS] Stopped 2020-02-06T19:20:43.224Z,1581016843.224 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1 2020-02-06T19:20:52.452Z,1581016852.452 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200206T164211/Courier0072.lzma 2020-02-06T19:20:54.458Z,1581016854.458 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Courier0072.lzma.bak 2020-02-06T19:20:54.458Z,1581016854.458 [DataOverHttps](INFO): SBD MOMSN=12230687 2020-02-06T19:21:13.967Z,1581016873.967 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2020-02-06T19:21:14.048Z,1581016874.048 [NAL9602](FAULT): received: +CSQ:0 OK462, 0, 0, 0, 0 OK 2020-02-06T19:21:14.049Z,1581016874.049 [NAL9602] Data Fault, FailCount= 1 2020-02-06T19:21:14.049Z,1581016874.049 [NAL9602](ERROR): Data Fault 2020-02-06T19:21:14.091Z,1581016874.091 [CBIT](ERROR): Data Fault in component: NAL9602 2020-02-06T19:21:14.371Z,1581016874.371 [NAL9602](INFO): Powering down 2020-02-06T19:21:14.818Z,1581016874.818 [RDI_Pathfinder](ERROR): Failed to parse: :BE,-32768,-32768,-32768,V 2020-02-06T19:21:14.976Z,1581016874.976 [DataOverHttps](INFO): Sending 197 bytes from file Logs/20200206T164211/Express0073.lzma 2020-02-06T19:21:15.221Z,1581016875.221 [CBIT](INFO): Clearing failed state for component NAL9602 2020-02-06T19:21:15.221Z,1581016875.221 [NAL9602] No Fault, FailCount= 1 2020-02-06T19:21:16.982Z,1581016876.982 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Express0073.lzma.bak 2020-02-06T19:21:16.982Z,1581016876.982 [DataOverHttps](INFO): SBD MOMSN=12230690 2020-02-06T19:21:18.448Z,1581016878.448 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped 2020-02-06T19:21:18.449Z,1581016878.449 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1 2020-02-06T19:21:18.449Z,1581016878.449 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-06T19:21:44.675Z,1581016904.675 [NAL9602](INFO): Powering up NAL9602 2020-02-06T19:21:55.584Z,1581016915.584 [NAL9602](INFO): NAL9602 initialized 2020-02-06T19:21:56.023Z,1581016916.023 [RDI_Pathfinder](ERROR): Failed to parse: :SA, +0.00, +0.00, 0.00 2020-02-06T19:22:26.690Z,1581016946.690 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-06T19:23:11.179Z,1581016991.179 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2020-02-06T19:23:11.179Z,1581016991.179 [RDI_Pathfinder](ERROR): Failed to parse: :BD, +0.0 +0.00, +0.00, 0.00,1697.88 2020-02-06T19:24:34.823Z,1581017074.823 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2020-02-06T19:24:34.823Z,1581017074.823 [RDI_Pathfinder](ERROR): Failed to parse: :BD, , +0.00, +0.00, 0.00,1781.87 2020-02-06T19:26:12.973Z,1581017172.973 [RDI_Pathfinder](ERROR): Failed to parse: : +0.00, +0.00, +0.00, 0.00,1878.85 2020-02-06T19:26:19.053Z,1581017179.053 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-06T19:26:19.053Z,1581017179.053 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-02-06T19:26:19.053Z,1581017179.053 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-06T19:26:19.053Z,1581017179.053 [DefaultWithUndock:CheckIn:D] Running Loop=1 2020-02-06T19:26:19.418Z,1581017179.418 [DefaultWithUndock:CheckIn:D] Stopped 2020-02-06T19:26:19.418Z,1581017179.418 [DefaultWithUndock:CheckIn:E] Running Loop=1 2020-02-06T19:26:19.833Z,1581017179.833 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 161.278027 min 2020-02-06T19:26:19.833Z,1581017179.833 [DefaultWithUndock:CheckIn:E] Stopped 2020-02-06T19:26:19.833Z,1581017179.833 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn 2020-02-06T19:26:19.833Z,1581017179.833 [DefaultWithUndock:CheckIn] Stopped 2020-02-06T19:26:19.833Z,1581017179.833 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-02-06T19:26:19.833Z,1581017179.833 [DefaultWithUndock:CheckIn](INFO): Running loop #25 2020-02-06T19:26:19.833Z,1581017179.833 [DefaultWithUndock:CheckIn] Running Loop=25 2020-02-06T19:26:19.833Z,1581017179.833 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn 2020-02-06T19:26:19.834Z,1581017179.834 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1 2020-02-06T19:26:24.238Z,1581017184.238 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-02-06T19:26:52.176Z,1581017212.176 [RDI_Pathfinder](ERROR): Failed to parse: :B,-32768,-32768,V2768,-32768,-32768,V 2020-02-06T19:27:56.824Z,1581017276.824 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2020-02-06T19:27:56.824Z,1581017276.824 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-2768,-32768,V 2020-02-06T19:28:16.191Z,1581017296.191 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-02-06T19:28:16.192Z,1581017296.192 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2020-02-06T19:29:45.845Z,1581017385.845 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192944.00,A,4131.47218,N,07040.26592,W,0.156,0.00,060220,,,A*7D 2020-02-06T19:29:45.847Z,1581017385.847 [NAL9602](INFO): GPS fix at 20200206T192944: (41.524536, -70.671099) 2020-02-06T19:29:45.900Z,1581017385.900 [DefaultWithUndock:CheckIn:Read_GPS] Stopped 2020-02-06T19:29:45.901Z,1581017385.901 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1 2020-02-06T19:29:55.348Z,1581017395.348 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20200206T164211/Courier0075.lzma 2020-02-06T19:29:57.354Z,1581017397.354 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Courier0075.lzma.bak 2020-02-06T19:29:57.354Z,1581017397.354 [DataOverHttps](INFO): SBD MOMSN=12230695 2020-02-06T19:30:12.175Z,1581017412.175 [RDI_Pathfinder](ERROR): Failed to parse: 68,-32768,-32768,V 2020-02-06T19:30:16.200Z,1581017416.200 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-02-06T19:30:16.201Z,1581017416.201 [RDI_Pathfinder](ERROR): Failed to parse: :TS,20020612432974,35.0, 0.0,1448.9, 0 2020-02-06T19:30:18.602Z,1581017418.602 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-06T19:30:23.720Z,1581017423.720 [DataOverHttps](INFO): Sending 236 bytes from file Logs/20200206T164211/Express0076.lzma 2020-02-06T19:30:25.726Z,1581017425.726 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Express0076.lzma.bak 2020-02-06T19:30:25.726Z,1581017425.726 [DataOverHttps](INFO): SBD MOMSN=12230704 2020-02-06T19:30:27.112Z,1581017427.112 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped 2020-02-06T19:30:27.112Z,1581017427.112 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1 2020-02-06T19:30:27.112Z,1581017427.112 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-06T19:30:31.532Z,1581017431.532 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-02-06T19:30:31.532Z,1581017431.532 [RDI_Pathfinder](ERROR): Failed to parse: :TS,20020612434474,.1, 0.0,1448.9, 0 2020-02-06T19:32:16.175Z,1581017536.175 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-02-06T19:32:16.175Z,1581017536.175 [RDI_Pathfinder](ERROR): Failed to parse: :TS2452974,35.0, -0.1, 0.0,1448.9, 0 2020-02-06T19:35:12.377Z,1581017712.377 [RDI_Pathfinder](ERROR): Failed to parse: :SA, +0.00, +0.00, 0.00 2020-02-06T19:35:27.722Z,1581017727.722 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-06T19:35:27.722Z,1581017727.722 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-02-06T19:35:27.722Z,1581017727.722 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-06T19:35:27.723Z,1581017727.723 [DefaultWithUndock:CheckIn:D] Running Loop=1 2020-02-06T19:35:28.136Z,1581017728.136 [DefaultWithUndock:CheckIn:D] Stopped 2020-02-06T19:35:28.136Z,1581017728.136 [DefaultWithUndock:CheckIn:E] Running Loop=1 2020-02-06T19:35:28.520Z,1581017728.520 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 170.423324 min 2020-02-06T19:35:28.520Z,1581017728.520 [DefaultWithUndock:CheckIn:E] Stopped 2020-02-06T19:35:28.520Z,1581017728.520 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn 2020-02-06T19:35:28.520Z,1581017728.520 [DefaultWithUndock:CheckIn] Stopped 2020-02-06T19:35:28.523Z,1581017728.523 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-02-06T19:35:28.523Z,1581017728.523 [DefaultWithUndock:CheckIn](INFO): Running loop #26 2020-02-06T19:35:28.523Z,1581017728.523 [DefaultWithUndock:CheckIn] Running Loop=26 2020-02-06T19:35:28.557Z,1581017728.557 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn 2020-02-06T19:35:28.558Z,1581017728.558 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1 2020-02-06T19:35:30.524Z,1581017730.524 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193529.00,A,4131.47110,N,07040.26230,W,0.603,207.57,060220,,,A*7F 2020-02-06T19:35:30.526Z,1581017730.526 [NAL9602](INFO): GPS fix at 20200206T193529: (41.524518, -70.671038) 2020-02-06T19:35:30.550Z,1581017730.550 [DefaultWithUndock:CheckIn:Read_GPS] Stopped 2020-02-06T19:35:30.550Z,1581017730.550 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1 2020-02-06T19:35:38.252Z,1581017738.252 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200206T164211/Courier0078.lzma 2020-02-06T19:35:40.258Z,1581017740.258 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Courier0078.lzma.bak 2020-02-06T19:35:40.259Z,1581017740.259 [DataOverHttps](INFO): SBD MOMSN=12230709 2020-02-06T19:35:57.652Z,1581017757.652 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20200206T164211/Express0079.lzma 2020-02-06T19:35:59.658Z,1581017759.658 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Express0079.lzma.bak 2020-02-06T19:35:59.658Z,1581017759.658 [DataOverHttps](INFO): SBD MOMSN=12230712 2020-02-06T19:36:01.278Z,1581017761.278 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped 2020-02-06T19:36:01.278Z,1581017761.278 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1 2020-02-06T19:36:01.278Z,1581017761.278 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-06T19:36:39.255Z,1581017799.255 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2020-02-06T19:36:39.255Z,1581017799.255 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00 2020-02-06T19:37:59.230Z,1581017879.230 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-02-06T19:37:59.230Z,1581017879.230 [RDI_Pathfinder](ERROR): Failed to parse: :TS,20020612511274,35.0, -0.1, 0.0 0 2020-02-06T19:40:32.363Z,1581018032.363 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-02-06T19:41:01.887Z,1581018061.887 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-06T19:41:01.887Z,1581018061.887 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-02-06T19:41:01.887Z,1581018061.887 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-06T19:41:01.888Z,1581018061.888 [DefaultWithUndock:CheckIn:D] Running Loop=1 2020-02-06T19:41:02.306Z,1581018062.306 [DefaultWithUndock:CheckIn:D] Stopped 2020-02-06T19:41:02.306Z,1581018062.306 [DefaultWithUndock:CheckIn:E] Running Loop=1 2020-02-06T19:41:02.706Z,1581018062.706 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 175.992822 min 2020-02-06T19:41:02.706Z,1581018062.706 [DefaultWithUndock:CheckIn:E] Stopped 2020-02-06T19:41:02.706Z,1581018062.706 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn 2020-02-06T19:41:02.706Z,1581018062.706 [DefaultWithUndock:CheckIn] Stopped 2020-02-06T19:41:02.706Z,1581018062.706 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-02-06T19:41:02.706Z,1581018062.706 [DefaultWithUndock:CheckIn](INFO): Running loop #27 2020-02-06T19:41:02.707Z,1581018062.707 [DefaultWithUndock:CheckIn] Running Loop=27 2020-02-06T19:41:02.707Z,1581018062.707 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn 2020-02-06T19:41:02.707Z,1581018062.707 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1 2020-02-06T19:41:03.070Z,1581018063.070 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-06T19:41:04.689Z,1581018064.689 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194103.00,A,4131.48487,N,07040.24018,W,0.272,0.00,060220,,,A*7F 2020-02-06T19:41:04.691Z,1581018064.691 [NAL9602](INFO): GPS fix at 20200206T194103: (41.524748, -70.670670) 2020-02-06T19:41:04.749Z,1581018064.749 [DefaultWithUndock:CheckIn:Read_GPS] Stopped 2020-02-06T19:41:04.749Z,1581018064.749 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1 2020-02-06T19:41:14.312Z,1581018074.312 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20200206T164211/Courier0081.lzma 2020-02-06T19:41:16.318Z,1581018076.318 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Courier0081.lzma.bak 2020-02-06T19:41:16.318Z,1581018076.318 [DataOverHttps](INFO): SBD MOMSN=12230723 2020-02-06T19:41:33.668Z,1581018093.668 [DataOverHttps](INFO): Sending 195 bytes from file Logs/20200206T164211/Express0082.lzma 2020-02-06T19:41:35.390Z,1581018095.390 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2020-02-06T19:41:35.472Z,1581018095.472 [NAL9602](FAULT): received: +CSQ:0 OK462, 0, 0, 0, 0 OK 2020-02-06T19:41:35.473Z,1581018095.473 [NAL9602] Data Fault, FailCount= 1 2020-02-06T19:41:35.473Z,1581018095.473 [NAL9602](ERROR): Data Fault 2020-02-06T19:41:35.544Z,1581018095.544 [CBIT](ERROR): Data Fault in component: NAL9602 2020-02-06T19:41:35.674Z,1581018095.674 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Express0082.lzma.bak 2020-02-06T19:41:35.674Z,1581018095.674 [DataOverHttps](INFO): SBD MOMSN=12230726 2020-02-06T19:41:35.806Z,1581018095.806 [NAL9602](INFO): Powering down 2020-02-06T19:41:36.642Z,1581018096.642 [CBIT](INFO): Clearing failed state for component NAL9602 2020-02-06T19:41:36.642Z,1581018096.642 [NAL9602] No Fault, FailCount= 1 2020-02-06T19:41:37.060Z,1581018097.060 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped 2020-02-06T19:41:37.065Z,1581018097.065 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1 2020-02-06T19:41:37.065Z,1581018097.065 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-06T19:41:59.256Z,1581018119.256 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-02-06T19:41:59.256Z,1581018119.256 [RDI_Pathfinder](ERROR): Failed to parse: :TS,20020612551274,35.0, -0.1, 8.9, 0 2020-02-06T19:42:06.114Z,1581018126.114 [NAL9602](INFO): Powering up NAL9602 2020-02-06T19:42:17.027Z,1581018137.027 [NAL9602](INFO): NAL9602 initialized 2020-02-06T19:42:48.139Z,1581018168.139 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-06T19:45:55.706Z,1581018355.706 [DataOverHttps](IMPORTANT): SBD MTMSN=20200206T194553 2020-02-06T19:46:09.967Z,1581018369.967 [DataOverHttps](INFO): Received command:configSet DUSBL_Hydroid.verbosity 3 enum persist 2020-02-06T19:46:10.301Z,1581018370.301 [CommandLine](IMPORTANT): got command configSet DUSBL_Hydroid.verbosity 3.000000 enum persist 2020-02-06T19:46:37.678Z,1581018397.678 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-06T19:46:37.678Z,1581018397.678 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-02-06T19:46:37.679Z,1581018397.679 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-06T19:46:37.679Z,1581018397.679 [DefaultWithUndock:CheckIn:D] Running Loop=1 2020-02-06T19:46:38.114Z,1581018398.114 [DefaultWithUndock:CheckIn:D] Stopped 2020-02-06T19:46:38.114Z,1581018398.114 [DefaultWithUndock:CheckIn:E] Running Loop=1 2020-02-06T19:46:38.484Z,1581018398.484 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 181.589616 min 2020-02-06T19:46:38.484Z,1581018398.484 [DefaultWithUndock:CheckIn:E] Stopped 2020-02-06T19:46:38.484Z,1581018398.484 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn 2020-02-06T19:46:38.484Z,1581018398.484 [DefaultWithUndock:CheckIn] Stopped 2020-02-06T19:46:38.504Z,1581018398.504 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-02-06T19:46:38.505Z,1581018398.505 [DefaultWithUndock:CheckIn](INFO): Running loop #28 2020-02-06T19:46:38.505Z,1581018398.505 [DefaultWithUndock:CheckIn] Running Loop=28 2020-02-06T19:46:38.505Z,1581018398.505 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn 2020-02-06T19:46:38.505Z,1581018398.505 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1 2020-02-06T19:46:42.081Z,1581018402.081 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-02-06T19:48:27.179Z,1581018507.179 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-02-06T19:48:27.179Z,1581018507.179 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2020-02-06T19:48:53.012Z,1581018533.012 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194852.00,A,4131.47007,N,07040.24954,W,0.661,0.00,060220,,,A*76 2020-02-06T19:48:53.014Z,1581018533.014 [NAL9602](INFO): GPS fix at 20200206T194852: (41.524501, -70.670826) 2020-02-06T19:48:53.068Z,1581018533.068 [DefaultWithUndock:CheckIn:Read_GPS] Stopped 2020-02-06T19:48:53.068Z,1581018533.068 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1 2020-02-06T19:49:00.872Z,1581018540.872 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20200206T164211/Courier0084.lzma 2020-02-06T19:49:02.878Z,1581018542.878 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Courier0084.lzma.bak 2020-02-06T19:49:02.878Z,1581018542.878 [DataOverHttps](INFO): SBD MOMSN=12230732 2020-02-06T19:49:25.386Z,1581018565.386 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-06T19:49:25.417Z,1581018565.417 [DataOverHttps](INFO): Sending 352 bytes from file Logs/20200206T164211/Express0085.lzma 2020-02-06T19:49:27.424Z,1581018567.424 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Express0085.lzma.bak 2020-02-06T19:49:27.424Z,1581018567.424 [DataOverHttps](INFO): SBD MOMSN=12230735 2020-02-06T19:49:43.184Z,1581018583.184 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-02-06T19:49:43.184Z,1581018583.184 [RDI_Pathfinder](ERROR): Failed to parse: :TS,20020613025674,35.0, -0.1, 0.0,1448.9A, 0.00, 0.00, 0.00, 0.00, 0.00 2020-02-06T19:49:46.160Z,1581018586.160 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20200206T164211/Express0088.lzma 2020-02-06T19:49:48.171Z,1581018588.171 [DataOverHttps](INFO): Moved sent file to Logs/20200206T164211/Express0088.lzma.bak 2020-02-06T19:49:48.172Z,1581018588.172 [DataOverHttps](INFO): SBD MOMSN=12230742 2020-02-06T19:49:49.660Z,1581018589.660 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped 2020-02-06T19:49:49.661Z,1581018589.661 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1 2020-02-06T19:49:49.661Z,1581018589.661 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-06T19:50:25.990Z,1581018625.990 [Micromodem](FAULT): Failed to receive CAREV within timeout 2020-02-06T19:50:25.990Z,1581018625.990 [Micromodem] Hardware Fault, FailCount= 1 2020-02-06T19:50:25.990Z,1581018625.990 [Micromodem](ERROR): Hardware Fault 2020-02-06T19:50:26.077Z,1581018626.077 [CBIT](ERROR): Hardware Fault in component: Micromodem 2020-02-06T19:50:26.401Z,1581018626.401 [Micromodem](INFO): Powering down 2020-02-06T19:50:27.245Z,1581018627.245 [CBIT](INFO): Clearing failed state for component Micromodem 2020-02-06T19:50:27.245Z,1581018627.245 [Micromodem] No Fault, FailCount= 1 2020-02-06T19:50:30.030Z,1581018630.030 [Micromodem](INFO): Powering up 2020-02-06T19:50:30.030Z,1581018630.030 [Micromodem](DEBUG): Initializing Micromodem. 2020-02-06T19:50:34.882Z,1581018634.882 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33 2020-02-06T19:50:36.932Z,1581018636.932 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2020-02-06T19:50:36.933Z,1581018636.933 [RDI_Pathfinder] Communications Fault, FailCount= 1 2020-02-06T19:50:36.933Z,1581018636.933 [RDI_Pathfinder](ERROR): Communications Fault 2020-02-06T19:50:36.950Z,1581018636.950 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2020-02-06T19:50:37.397Z,1581018637.397 [RDI_Pathfinder](INFO): Powering down 2020-02-06T19:50:38.162Z,1581018638.162 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2020-02-06T19:50:38.162Z,1581018638.162 [RDI_Pathfinder] No Fault, FailCount= 1 2020-02-06T19:50:46.188Z,1581018646.188 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T19:50:51.458Z,1581018651.458 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 2020-02-06T19:50:57.502Z,1581018657.502 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T19:51:08.822Z,1581018668.822 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T19:51:20.124Z,1581018680.124 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T19:51:31.438Z,1581018691.438 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T19:51:42.754Z,1581018702.754 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T19:51:54.062Z,1581018714.062 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T19:52:05.394Z,1581018725.394 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T19:52:16.692Z,1581018736.692 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T19:52:27.997Z,1581018747.997 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T19:52:30.018Z,1581018750.018 [Micromodem](FAULT): failed to initialize; deviceResponse_ loaded: , available: 2020-02-06T19:52:30.018Z,1581018750.018 [Micromodem] Communications Fault, FailCount= 2 2020-02-06T19:52:30.018Z,1581018750.018 [Micromodem](ERROR): Communications Fault 2020-02-06T19:52:30.096Z,1581018750.096 [CBIT](ERROR): Communications Fault in component: Micromodem 2020-02-06T19:52:30.420Z,1581018750.420 [Micromodem](INFO): Powering down 2020-02-06T19:52:31.268Z,1581018751.268 [CBIT](INFO): Clearing failed state for component Micromodem 2020-02-06T19:52:31.268Z,1581018751.268 [Micromodem] No Fault, FailCount= 2 2020-02-06T19:52:34.057Z,1581018754.057 [Micromodem](INFO): Powering up 2020-02-06T19:52:34.058Z,1581018754.058 [Micromodem](DEBUG): Initializing Micromodem. 2020-02-06T19:52:39.310Z,1581018759.310 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T19:52:40.951Z,1581018760.951 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2020-02-06T19:52:40.951Z,1581018760.951 [RDI_Pathfinder] Communications Fault, FailCount= 1 2020-02-06T19:52:40.951Z,1581018760.951 [RDI_Pathfinder](ERROR): Communications Fault 2020-02-06T19:52:40.969Z,1581018760.969 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2020-02-06T19:52:41.409Z,1581018761.409 [RDI_Pathfinder](INFO): Powering down 2020-02-06T19:52:42.181Z,1581018762.181 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2020-02-06T19:52:42.181Z,1581018762.181 [RDI_Pathfinder] No Fault, FailCount= 1 2020-02-06T19:52:50.622Z,1581018770.622 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T19:52:55.485Z,1581018775.485 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 2020-02-06T19:53:01.934Z,1581018781.934 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T19:53:09.646Z,1581018789.646 [Micromodem](INFO): Nmea in: 1*16 2020-02-06T19:53:09.646Z,1581018789.646 [Micromodem](ERROR): Response from modem failed NMEA checksum: 1*16 expected 0 2020-02-06T19:53:09.646Z,1581018789.646 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33 2020-02-06T19:53:20.930Z,1581018800.930 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T19:53:32.238Z,1581018812.238 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T19:53:43.549Z,1581018823.549 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T19:53:54.858Z,1581018834.858 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T19:54:06.218Z,1581018846.218 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T19:54:17.506Z,1581018857.506 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T19:54:28.818Z,1581018868.818 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T19:54:34.074Z,1581018874.074 [Micromodem](FAULT): failed to initialize; deviceResponse_ loaded: $CAREV,195257,COPROC,0.10.0.46*4D , available: 2020-02-06T19:54:34.074Z,1581018874.074 [Micromodem] Communications Fault, FailCount= 3 2020-02-06T19:54:34.074Z,1581018874.074 [Micromodem](ERROR): Communications Fault 2020-02-06T19:54:34.149Z,1581018874.149 [CBIT](ERROR): Communications Fault in component: Micromodem 2020-02-06T19:54:34.477Z,1581018874.477 [Micromodem](INFO): Powering down 2020-02-06T19:54:35.332Z,1581018875.332 [CBIT](INFO): Clearing failed state for component Micromodem 2020-02-06T19:54:35.332Z,1581018875.332 [Micromodem] No Fault, FailCount= 3 2020-02-06T19:54:38.110Z,1581018878.110 [Micromodem](INFO): Powering up 2020-02-06T19:54:38.110Z,1581018878.110 [Micromodem](DEBUG): Initializing Micromodem. 2020-02-06T19:54:40.135Z,1581018880.135 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T19:54:45.801Z,1581018885.801 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2020-02-06T19:54:45.801Z,1581018885.801 [RDI_Pathfinder] Communications Fault, FailCount= 1 2020-02-06T19:54:45.801Z,1581018885.801 [RDI_Pathfinder](ERROR): Communications Fault 2020-02-06T19:54:45.857Z,1581018885.857 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2020-02-06T19:54:46.273Z,1581018886.273 [RDI_Pathfinder](INFO): Powering down 2020-02-06T19:54:47.035Z,1581018887.035 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2020-02-06T19:54:47.035Z,1581018887.035 [RDI_Pathfinder] No Fault, FailCount= 1 2020-02-06T19:54:50.230Z,1581018890.230 [Micromodem](INFO): Nmea in: 46*4D 2020-02-06T19:54:50.230Z,1581018890.230 [Micromodem](ERROR): Response from modem failed NMEA checksum: 46*4D expected 0 2020-02-06T19:54:50.230Z,1581018890.230 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33 2020-02-06T19:54:50.262Z,1581018890.262 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-06T19:54:50.262Z,1581018890.262 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-02-06T19:54:50.262Z,1581018890.262 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-06T19:54:50.262Z,1581018890.262 [DefaultWithUndock:CheckIn:D] Running Loop=1 2020-02-06T19:54:50.665Z,1581018890.665 [DefaultWithUndock:CheckIn:D] Stopped 2020-02-06T19:54:50.665Z,1581018890.665 [DefaultWithUndock:CheckIn:E] Running Loop=1 2020-02-06T19:54:51.068Z,1581018891.068 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 189.798812 min 2020-02-06T19:54:51.068Z,1581018891.068 [DefaultWithUndock:CheckIn:E] Stopped 2020-02-06T19:54:51.068Z,1581018891.068 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn 2020-02-06T19:54:51.068Z,1581018891.068 [DefaultWithUndock:CheckIn] Stopped 2020-02-06T19:54:51.068Z,1581018891.068 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-02-06T19:54:51.068Z,1581018891.068 [DefaultWithUndock:CheckIn](INFO): Running loop #29 2020-02-06T19:54:51.068Z,1581018891.068 [DefaultWithUndock:CheckIn] Running Loop=29 2020-02-06T19:54:51.069Z,1581018891.069 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn 2020-02-06T19:54:51.069Z,1581018891.069 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1 2020-02-06T19:55:00.360Z,1581018900.360 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 2020-02-06T19:55:01.542Z,1581018901.542 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T19:55:12.854Z,1581018912.854 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T19:55:24.170Z,1581018924.170 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2020-02-06T19:55:24.578Z,1581018924.578 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31 2020-02-06T19:55:24.578Z,1581018924.578 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31 2020-02-06T19:55:24.983Z,1581018924.983 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33 2020-02-06T19:55:24.983Z,1581018924.983 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64 2020-02-06T19:55:25.376Z,1581018925.376 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66 2020-02-06T19:55:25.376Z,1581018925.376 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F 2020-02-06T19:55:25.786Z,1581018925.786 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D 2020-02-06T19:55:25.786Z,1581018925.786 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E 2020-02-06T19:55:26.190Z,1581018926.190 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C 2020-02-06T19:55:26.190Z,1581018926.190 [Micromodem](INFO): Nmea out: $CCCFG,FC0,10000*46 2020-02-06T19:55:26.590Z,1581018926.590 [Micromodem](INFO): Nmea in: $CACFG,FC0,10000*44 2020-02-06T19:55:26.590Z,1581018926.590 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63 2020-02-06T19:55:26.994Z,1581018926.994 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61 2020-02-06T19:55:26.994Z,1581018926.994 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A 2020-02-06T19:55:27.421Z,1581018927.421 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38 2020-02-06T19:55:27.422Z,1581018927.422 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33 2020-02-06T19:55:27.811Z,1581018927.811 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31 2020-02-06T19:55:27.811Z,1581018927.811 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31 2020-02-06T19:55:28.204Z,1581018928.204 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33 2020-02-06T19:55:28.205Z,1581018928.205 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64 2020-02-06T19:55:28.635Z,1581018928.635 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66 2020-02-06T19:55:28.636Z,1581018928.636 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F 2020-02-06T19:55:29.023Z,1581018929.023 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D 2020-02-06T19:55:29.023Z,1581018929.023 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E 2020-02-06T19:55:29.422Z,1581018929.422 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C 2020-02-06T19:55:29.422Z,1581018929.422 [Micromodem](INFO): Nmea out: $CCCFG,FC0,10000*46 2020-02-06T19:55:29.826Z,1581018929.826 [Micromodem](INFO): Nmea in: $CACFG,FC0,10000*44 2020-02-06T19:55:29.826Z,1581018929.826 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63 2020-02-06T19:55:30.234Z,1581018930.234 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61 2020-02-06T19:55:30.235Z,1581018930.235 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A 2020-02-06T19:55:31.442Z,1581018931.442 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38 2020-02-06T19:55:31.442Z,1581018931.442 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33 2020-02-06T19:55:31.850Z,1581018931.850 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31 2020-02-06T19:55:31.850Z,1581018931.850 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31 2020-02-06T19:55:32.277Z,1581018932.277 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33 2020-02-06T19:55:32.277Z,1581018932.277 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64 2020-02-06T19:55:32.654Z,1581018932.654 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66 2020-02-06T19:55:32.654Z,1581018932.654 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F 2020-02-06T19:55:33.058Z,1581018933.058 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D 2020-02-06T19:55:33.058Z,1581018933.058 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E 2020-02-06T19:55:33.510Z,1581018933.510 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C 2020-02-06T19:55:33.511Z,1581018933.511 [Micromodem](INFO): Nmea out: $CCCFG,FC0,10000*46 2020-02-06T19:55:33.860Z,1581018933.860 [Micromodem](INFO): Nmea in: $CACFG,FC0,10000*44 2020-02-06T19:55:33.860Z,1581018933.860 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63 2020-02-06T19:55:34.276Z,1581018934.276 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61 2020-02-06T19:55:34.277Z,1581018934.277 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A 2020-02-06T19:55:34.674Z,1581018934.674 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38 2020-02-06T19:55:34.674Z,1581018934.674 [Micromodem](INFO): Nmea out: $CCCLK,2020,02,06,19,55,28*42 2020-02-06T19:55:35.076Z,1581018935.076 [Micromodem](INFO): Nmea in: $CACLK,2020,2,6,19,55,28*40 2020-02-06T19:55:35.077Z,1581018935.077 [Micromodem](INFO): Nmea in: $CATMS,0,2020-02-06T19:55:29Z*71 2020-02-06T19:55:35.079Z,1581018935.079 [Micromodem](INFO): Nmea in: $CATMG,2020-02-06T19:55:29.026154Z,USER_CMD,RTC*12 2020-02-06T19:56:10.287Z,1581018970.287 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-02-06T19:56:10.287Z,1581018970.287 [RDI_Pathfinder](ERROR): Failed to parse: :TS,20020613092421,35.0, -0.1, 0.0,1448:RA, 0.00, 0.00, 0.00, 0.00, 0.00 2020-02-06T19:56:30.850Z,1581018990.850 [DataOverHttps](IMPORTANT): SBD MTMSN=20200206T195628 2020-02-06T19:56:38.632Z,1581018998.632 [DataOverHttps](INFO): Received command:configSet Micromodem.loadAtStartup 0 bool persist 2020-02-06T19:56:38.808Z,1581018998.808 [CommandLine](IMPORTANT): got command configSet Micromodem.loadAtStartup 0.000000 bool persist 2020-02-06T19:56:38.814Z,1581018998.814 [CommandLine](IMPORTANT): configSet Micromodem.loadAtStartup requires a restart to take effect. 2020-02-06T19:56:47.820Z,1581019007.820 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-02-06T19:56:47.821Z,1581019007.821 [RDI_Pathfinder](ERROR): Failed to parse: :TS,20020613100121,35.0, -0.1, 0.0,14 2020-02-06T19:56:50.289Z,1581019010.289 [RDI_Pathfinder](ERROR): Failed to parse: 68,-32768,-32768,V 2020-02-06T19:57:52.322Z,1581019072.322 [DataOverHttps](IMPORTANT): SBD MTMSN=20200206T195750 2020-02-06T19:58:03.596Z,1581019083.596 [DataOverHttps](INFO): Received command:restart app 2020-02-06T19:58:03.606Z,1581019083.606 [CommandLine](IMPORTANT): got command restart application 2020-02-06T19:58:04.609Z,1581019084.609 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2020-02-06T19:58:04.609Z,1581019084.609 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-02-06T19:58:04.785Z,1581019084.785 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2020-02-06T19:58:04.785Z,1581019084.785 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-02-06T19:58:04.786Z,1581019084.786 [CommandLine](INFO): Join timeout helper Thread ID is 4528 2020-02-06T19:58:04.805Z,1581019084.805 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2020-02-06T19:58:04.805Z,1581019084.805 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-02-06T19:58:04.805Z,1581019084.805 [NavChartDb](INFO): Join timeout helper Thread ID is 4529 2020-02-06T19:58:05.113Z,1581019085.113 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2020-02-06T19:58:05.113Z,1581019085.113 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-02-06T19:58:05.133Z,1581019085.133 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler 2020-02-06T19:58:05.133Z,1581019085.133 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2020-02-06T19:58:05.133Z,1581019085.133 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 4530 2020-02-06T19:58:05.321Z,1581019085.321 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread. 2020-02-06T19:58:05.321Z,1581019085.321 [WetLabsSeaOWL_UV_A](INFO): Powering down 2020-02-06T19:58:05.322Z,1581019085.322 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2020-02-06T19:58:05.329Z,1581019085.329 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2020-02-06T19:58:05.329Z,1581019085.329 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-02-06T19:58:05.329Z,1581019085.329 [Radio_Surface](INFO): Join timeout helper Thread ID is 4531 2020-02-06T19:58:05.589Z,1581019085.589 [Radio_Surface](INFO): Powering down 2020-02-06T19:58:05.590Z,1581019085.590 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2020-02-06T19:58:05.590Z,1581019085.590 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-02-06T19:58:05.593Z,1581019085.593 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2020-02-06T19:58:05.593Z,1581019085.593 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-02-06T19:58:05.593Z,1581019085.593 [DataOverHttps](INFO): Join timeout helper Thread ID is 4532 2020-02-06T19:58:05.601Z,1581019085.601 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2020-02-06T19:58:05.605Z,1581019085.605 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-02-06T19:58:05.614Z,1581019085.614 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2020-02-06T19:58:05.614Z,1581019085.614 [logger ThreadHandler](INFO): Thread cancelled. 2020-02-06T19:58:05.614Z,1581019085.614 [logger](INFO): Join timeout helper Thread ID is 4533 2020-02-06T19:58:05.625Z,1581019085.625 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2020-02-06T19:58:05.625Z,1581019085.625 [logger ThreadHandler](INFO): Thread cancelled. 2020-02-06T19:58:05.629Z,1581019085.629 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2020-02-06T19:58:05.630Z,1581019085.630 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-02-06T19:58:05.630Z,1581019085.630 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2020-02-06T19:58:05.630Z,1581019085.630 [controlThread ThreadHandler](INFO): Thread cancelled. 2020-02-06T19:58:05.630Z,1581019085.630 [controlThread](INFO): Join timeout helper Thread ID is 4534 2020-02-06T19:58:05.765Z,1581019085.765 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2020-02-06T19:58:05.765Z,1581019085.765 [controlThread](DEBUG): Uninitializing ControlThread 2020-02-06T19:58:05.765Z,1581019085.765 [AHRS_M2](INFO): Powering down 2020-02-06T19:58:05.837Z,1581019085.837 [DDM](INFO): Powering down 2020-02-06T19:58:05.909Z,1581019085.909 [DUSBL_Hydroid](INFO): Powering down 2020-02-06T19:58:05.981Z,1581019085.981 [Micromodem](INFO): Powering down 2020-02-06T19:58:06.077Z,1581019086.077 [NAL9602](INFO): Powering down 2020-02-06T19:58:06.149Z,1581019086.149 [RDI_Pathfinder](INFO): Powering down 2020-02-06T19:58:06.150Z,1581019086.150 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2020-02-06T19:58:06.151Z,1581019086.151 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2020-02-06T19:58:06.151Z,1581019086.151 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2020-02-06T19:58:06.152Z,1581019086.152 [MissionManager](INFO): Uninitializing Mission DefaultWithUndock 2020-02-06T19:58:06.152Z,1581019086.152 [DefaultWithUndock] Stopped 2020-02-06T19:58:06.152Z,1581019086.152 [DefaultWithUndock](DEBUG): Aggregate::uninitialize DefaultWithUndock 2020-02-06T19:58:06.152Z,1581019086.152 [DefaultWithUndock:C.GoToSurface] Stopped 2020-02-06T19:58:06.153Z,1581019086.153 [DefaultWithUndock:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-02-06T19:58:06.153Z,1581019086.153 [DefaultWithUndock:CheckIn] Stopped 2020-02-06T19:58:06.153Z,1581019086.153 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-02-06T19:58:06.153Z,1581019086.153 [DefaultWithUndock:CheckIn:Read_GPS] Stopped 2020-02-06T19:58:06.156Z,1581019086.156 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2020-02-06T19:58:06.156Z,1581019086.156 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2020-02-06T19:58:06.157Z,1581019086.157 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2020-02-06T19:58:06.157Z,1581019086.157 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2020-02-06T19:58:06.157Z,1581019086.157 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2020-02-06T19:58:06.157Z,1581019086.157 [BuoyancyServo](INFO): Powering down 2020-02-06T19:58:06.169Z,1581019086.169 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2020-02-06T19:58:06.169Z,1581019086.169 [ElevatorServo](INFO): Powering down 2020-02-06T19:58:06.169Z,1581019086.169 [MassServo](DEBUG): Uninitialize Mass Servo. 2020-02-06T19:58:06.170Z,1581019086.170 [MassServo](INFO): Powering down 2020-02-06T19:58:06.170Z,1581019086.170 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2020-02-06T19:58:06.170Z,1581019086.170 [RudderServo](INFO): Powering down 2020-02-06T19:58:06.171Z,1581019086.171 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2020-02-06T19:58:06.171Z,1581019086.171 [ThrusterServo](INFO): Powering down 2020-02-06T19:58:06.172Z,1581019086.172 [SBIT](DEBUG): Uninitialize SBIT Component. 2020-02-06T19:58:06.172Z,1581019086.172 [IBIT](DEBUG): Uninitialize IBIT Component. 2020-02-06T19:58:06.173Z,1581019086.173 [CBIT](DEBUG): Uninitialize CBIT Component. 2020-02-06T19:58:06.173Z,1581019086.173 [CBIT](DEBUG): Powering off loads. 2020-02-06T19:58:06.184Z,1581019086.184 [CBIT](DEBUG): Disabling WDT. 2020-02-06T19:58:06.196Z,1581019086.196 [CBIT](DEBUG): Opening all GF detection circuits. 2020-02-06T19:58:06.197Z,1581019086.197 [controlThread ThreadHandler](INFO): Thread cancelled. 2020-02-06T19:58:06.241Z,1581019086.241 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-02-06T19:58:06.252Z,1581019086.252 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-02-06T19:58:06.294Z,1581019086.294 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2020-02-06T19:58:06.351Z,1581019086.351 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-02-06T19:58:06.423Z,1581019086.423 [logger ThreadHandler](INFO): Thread cancelled.