2019-07-02T15:08:28.765Z,1562080108.765 [CommandLine](IMPORTANT): got command restart logs 2019-07-02T15:08:36.301Z,1562080116.301 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2019-07-02T15:08:36.303Z,1562080116.303 [BPC1](INFO): Received data from all battery sticks. 2019-07-02T15:08:36.437Z,1562080116.437 [DataOverHttps](INFO): Sending 398 bytes from file Logs/20190702T063730/Express0052.lzma 2019-07-02T15:08:37.242Z,1562080117.242 [DataOverHttps](INFO): Moved sent file to Logs/20190702T063730/Express0052.lzma.bak 2019-07-02T15:08:37.242Z,1562080117.242 [DataOverHttps](INFO): SBD MOMSN=11421221 2019-07-02T15:08:57.740Z,1562080137.740 [DataOverHttps](INFO): Sending 116 bytes from file Logs/20190702T063730/Express0055.lzma 2019-07-02T15:08:58.542Z,1562080138.542 [DataOverHttps](INFO): Moved sent file to Logs/20190702T063730/Express0055.lzma.bak 2019-07-02T15:08:58.543Z,1562080138.543 [DataOverHttps](INFO): SBD MOMSN=11421224 2019-07-02T15:08:59.302Z,1562080139.302 [NAL9602](INFO): Not Powering down - fast GPS 2019-07-02T15:09:01.752Z,1562080141.752 [Default:CheckIn:Read_Iridium] Stopped 2019-07-02T15:09:01.752Z,1562080141.752 [Default:CheckIn:C.Wait] Running Loop=1 2019-07-02T15:09:01.752Z,1562080141.752 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-07-02T15:12:00.314Z,1562080320.314 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 395.37, 375.24, 386.22, 380.73 2019-07-02T15:14:02.337Z,1562080442.337 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-07-02T15:14:02.337Z,1562080442.337 [Default:CheckIn:C.Wait] Stopped 2019-07-02T15:14:02.337Z,1562080442.337 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-07-02T15:14:02.338Z,1562080442.338 [Default:CheckIn:D] Running Loop=1 2019-07-02T15:14:02.747Z,1562080442.747 [Default:CheckIn:D] Stopped 2019-07-02T15:14:02.747Z,1562080442.747 [Default:CheckIn:E] Running Loop=1 2019-07-02T15:14:03.144Z,1562080443.144 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 14.756943 min 2019-07-02T15:14:03.144Z,1562080443.144 [Default:CheckIn:E] Stopped 2019-07-02T15:14:03.144Z,1562080443.144 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-07-02T15:14:03.144Z,1562080443.144 [Default:CheckIn] Stopped 2019-07-02T15:14:03.144Z,1562080443.144 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-07-02T15:14:03.144Z,1562080443.144 [Default:CheckIn](INFO): Running loop #3 2019-07-02T15:14:03.144Z,1562080443.144 [Default:CheckIn] Running Loop=3 2019-07-02T15:14:03.161Z,1562080443.161 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-07-02T15:14:03.161Z,1562080443.161 [Default:CheckIn:Read_GPS] Running Loop=1 2019-07-02T15:14:04.744Z,1562080444.744 [NAL9602](DEBUG): Fix Requested 2019-07-02T15:14:05.137Z,1562080445.137 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,151404.00,A,3648.61322,N,12149.54617,W,0.078,23.74,020719,,,D*4F 2019-07-02T15:14:05.139Z,1562080445.139 [NAL9602](INFO): GPS fix at 20190702T151404: (36.810220, -121.825770) 2019-07-02T15:14:05.172Z,1562080445.172 [Default:CheckIn:Read_GPS] Stopped 2019-07-02T15:14:05.172Z,1562080445.172 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-07-02T15:14:06.099Z,1562080446.099 [Micromodem](ERROR): Buffer fill timout failure. 2019-07-02T15:14:07.242Z,1562080447.242 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 6C731B5D000001005D00000400270300000000000000151D80030C5669BF3148E2FA22188B6A3173E5573572661C0BCBDFFAB423BD32D9A51E8904B0A920CE9A 2019-07-02T15:14:07.242Z,1562080447.242 [Micromodem](INFO): Outgoing frame #2, 64 bytes: EF9F66414C9B9FD6761AD008C3E3DC4FCA2E4E25B6359B14B772C256AEDD60B95108769F11B6F1CAFDFCAA023BD2A6AC8267BF03117F45C2DD2DCA8C8EEBE5F9 2019-07-02T15:14:07.243Z,1562080447.243 [Micromodem](INFO): Outgoing frame #3, 64 bytes: 7BD9043D223D555CF9F8C344436B63EF13BFE530A3AD5AB43B65A7E1E292F063AFC71143C404EE6C0C190841CC4668E710EE53236746E6180945B5ED98D6F188 2019-07-02T15:14:07.243Z,1562080447.243 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,3*53 2019-07-02T15:14:07.390Z,1562080447.390 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,3*51 2019-07-02T15:14:07.731Z,1562080447.731 [Micromodem](INFO): Nmea in: $CADRQ,151408,1,9,0,64,1*47 2019-07-02T15:14:07.731Z,1562080447.731 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,6C731B5D000001005D00000400270300000000000000151D80030C5669BF3148E2FA22188B6A3173E5573572661C0BCBDFFAB423BD32D9A51E8904B0A920CE9A*06 2019-07-02T15:14:08.134Z,1562080448.134 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71 2019-07-02T15:14:08.539Z,1562080448.539 [Micromodem](INFO): Nmea in: $CADRQ,151409,1,9,0,64,2*45 2019-07-02T15:14:08.539Z,1562080448.539 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,EF9F66414C9B9FD6761AD008C3E3DC4FCA2E4E25B6359B14B772C256AEDD60B95108769F11B6F1CAFDFCAA023BD2A6AC8267BF03117F45C2DD2DCA8C8EEBE5F9*70 2019-07-02T15:14:08.947Z,1562080448.947 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71 2019-07-02T15:14:09.351Z,1562080449.351 [Micromodem](INFO): Nmea in: $CADRQ,151409,1,9,0,64,3*44 2019-07-02T15:14:09.351Z,1562080449.351 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,7BD9043D223D555CF9F8C344436B63EF13BFE530A3AD5AB43B65A7E1E292F063AFC71143C404EE6C0C190841CC4668E710EE53236746E6180945B5ED98D6F188*0F 2019-07-02T15:14:09.762Z,1562080449.762 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71 2019-07-02T15:14:10.154Z,1562080450.154 [Micromodem](INFO): Nmea in: $CATXP,192*48 2019-07-02T15:14:12.116Z,1562080452.116 [DataOverHttps](INFO): Sending 301 bytes from file Logs/20190702T150828/Courier0000.lzma 2019-07-02T15:14:12.924Z,1562080452.924 [DataOverHttps](INFO): Moved sent file to Logs/20190702T150828/Courier0000.lzma.bak 2019-07-02T15:14:12.924Z,1562080452.924 [DataOverHttps](INFO): SBD MOMSN=11421228 2019-07-02T15:14:13.802Z,1562080453.802 [Micromodem](INFO): Nmea in: $CATXF,192*5E 2019-07-02T15:14:14.200Z,1562080454.200 [Micromodem](INFO): Nmea in: $CAXST,6,20190702,151410.777755,3,0,200,4000,10000,1,1,9,1,3,3,3,192*4F 2019-07-02T15:14:26.062Z,1562080466.062 [DataOverHttps](INFO): Sending 643 bytes from file Logs/20190702T150828/Express0001.lzma 2019-07-02T15:14:26.866Z,1562080466.866 [DataOverHttps](INFO): Moved sent file to Logs/20190702T150828/Express0001.lzma.bak 2019-07-02T15:14:26.866Z,1562080466.866 [DataOverHttps](INFO): SBD MOMSN=11421232 2019-07-02T15:14:28.829Z,1562080468.829 [Default:CheckIn:Read_Iridium] Stopped 2019-07-02T15:14:28.829Z,1562080468.829 [Default:CheckIn:C.Wait] Running Loop=1 2019-07-02T15:14:28.829Z,1562080468.829 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-07-02T15:14:33.184Z,1562080473.184 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=7936, MT Status=1, MTMSN=290 2019-07-02T15:14:33.184Z,1562080473.184 [NAL9602](INFO): Data available in MT queue 2019-07-02T15:14:33.931Z,1562080473.931 [NAL9602](INFO): Received command:sched asap "load Engineering/lineCaptureHomingUpdate.xml;set lineCaptureHoming.Repeat 2 count;set lineCaptureHoming.DockLat 36.806966 degree;set lineCaptureHoming.DockLon -121.824326 degree;set lineCaptureHoming.StartLat 36.812322 degree" 43g29 1 3 2019-07-02T15:14:33.967Z,1562080473.967 [CommandLine](IMPORTANT): got command schedule asap "load Engineering/lineCaptureHomingUpdate.xml;set lineCaptureHoming.Repeat 2 count;set lineCaptureHoming.DockLat 36.806966 degree;set lineCaptureHoming.DockLon -121.824326 degree;set lineCaptureHoming.StartLat 36.812322 degree" 43g29 1 3.000000 2019-07-02T15:14:33.969Z,1562080473.969 [CommandLine](IMPORTANT): Scheduling command #1 of 3 with id=43g29 2019-07-02T15:14:33.970Z,1562080473.970 [CommandLine](IMPORTANT): Scheduled #17 (#1 of 3 with id='43g29'): "load Engineering/lineCaptureHomingUpdate.xml;set lineCaptureHoming.Repeat 2 count;set lineCaptureHoming.DockLat 36.806966 degree;set lineCaptureHoming.DockLon -121.824326 degree;set lineCaptureHoming.StartLat 36.812322 degree" ASAP 2019-07-02T15:15:00.794Z,1562080500.794 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-07-02T15:15:00.794Z,1562080500.794 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00, 2019-07-02T15:15:04.383Z,1562080504.383 [NAL9602](INFO): Not Powering down - fast GPS 2019-07-02T15:16:02.171Z,1562080562.171 [RDI_Pathfinder](ERROR): Failed to parse: :BE,-32768,-32768,-32768,V 2019-07-02T15:16:11.018Z,1562080571.018 [CommandLine](IMPORTANT): got command show stack 2019-07-02T15:16:11.018Z,1562080571.018 [CommandLine](IMPORTANT): Behavior Stack: 2019-07-02T15:16:11.018Z,1562080571.018 [Default](IMPORTANT): Priority 0: Default:B.GoToSurface 2019-07-02T15:16:11.018Z,1562080571.018 [Default:CheckIn](IMPORTANT): Priority 1: Default:CheckIn:C.Wait 2019-07-02T15:16:25.733Z,1562080585.733 [CommandLine](IMPORTANT): got command schedule clear 2019-07-02T15:16:25.733Z,1562080585.733 [CommandLine](IMPORTANT): #Cleared scheduled commands:1