2019-11-26T18:19:24.902Z,1574792364.902 [NAL9602](INFO): Received command:Restart logs 2019-11-26T18:19:24.909Z,1574792364.909 [CommandLine](IMPORTANT): got command restart logs 2019-11-26T18:19:42.985Z,1574792382.985 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-11-26T18:20:00.194Z,1574792400.194 [RDI_Pathfinder](ERROR): Failed to parse: :SA, +0.00, +0.00, 0.00 2019-11-26T18:20:18.153Z,1574792418.153 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-11-26T18:20:34.927Z,1574792434.927 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182026.00,A,3648.73700,N,12149.58836,W,1.225,280.05,261119,,,A*71 2019-11-26T18:20:34.929Z,1574792434.929 [NAL9602](INFO): GPS fix at 20191126T182026: (36.812283, -121.826473) 2019-11-26T18:20:35.002Z,1574792435.002 [UniversalFixResidualReporter](INFO): Fix residual: 8.9 %DT, over the last 1295.2 m. Residual distance 115.4 m at bearing -142.4 degrees. Fix at (36.8123, -121.8265) with 686.7 m made good. 2019-11-26T18:20:35.003Z,1574792435.003 [Default:CheckIn:Read_GPS] Stopped 2019-11-26T18:20:35.003Z,1574792435.003 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-11-26T18:20:53.293Z,1574792453.293 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-11-26T18:21:07.235Z,1574792467.235 [NAL9602](INFO): Not Powering down - fast GPS 2019-11-26T18:21:29.321Z,1574792489.321 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2019-11-26T18:21:42.017Z,1574792502.017 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003227 2019-11-26T18:21:49.772Z,1574792509.772 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20191126T173047/Courier0012.lzma 2019-11-26T18:21:51.766Z,1574792511.766 [DataOverHttps](INFO): Moved sent file to Logs/20191126T173047/Courier0012.lzma.bak 2019-11-26T18:21:51.766Z,1574792511.766 [DataOverHttps](INFO): SBD MOMSN=12107399 2019-11-26T18:21:53.315Z,1574792513.315 [RDI_Pathfinder](ERROR): Failed to parse: :BE,-32768,-32768,-32768,V 2019-11-26T18:22:13.817Z,1574792533.817 [DataOverHttps](INFO): Sending 276 bytes from file Logs/20191126T181924/Courier0000.lzma 2019-11-26T18:22:17.830Z,1574792537.830 [DataOverHttps](INFO): Moved sent file to Logs/20191126T181924/Courier0000.lzma.bak 2019-11-26T18:22:17.830Z,1574792537.830 [DataOverHttps](INFO): SBD MOMSN=12107402 2019-11-26T18:22:36.449Z,1574792556.449 [DataOverHttps](INFO): Sending 7279 bytes from file Logs/20191126T173047/Express0013.lzma 2019-11-26T18:22:39.442Z,1574792559.442 [DataOverHttps](INFO): Moved sent file to Logs/20191126T173047/Express0013.lzma.bak 2019-11-26T18:22:39.442Z,1574792559.442 [DataOverHttps](INFO): SBD MOMSN=12107406 2019-11-26T18:22:58.089Z,1574792578.089 [DataOverHttps](INFO): Sending 418 bytes from file Logs/20191126T181924/Express0001.lzma 2019-11-26T18:23:00.094Z,1574792580.094 [DataOverHttps](INFO): Moved sent file to Logs/20191126T181924/Express0001.lzma.bak 2019-11-26T18:23:00.095Z,1574792580.095 [DataOverHttps](INFO): SBD MOMSN=12107432 2019-11-26T18:23:02.019Z,1574792582.019 [Default:CheckIn:Read_Iridium] Stopped 2019-11-26T18:23:02.019Z,1574792582.019 [Default:CheckIn:C.Wait] Running Loop=1 2019-11-26T18:23:02.019Z,1574792582.019 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-11-26T18:23:12.940Z,1574792592.940 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2019-11-26T18:23:12.940Z,1574792592.940 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-32768,32768,V 2019-11-26T18:26:33.300Z,1574792793.300 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-11-26T18:26:33.300Z,1574792793.300 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19112611383326,35.0 -0.1, 0.0,1448.9, 0 2019-11-26T18:26:38.984Z,1574792798.984 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-11-26T18:26:38.984Z,1574792798.984 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19112611383926,35.0, -0.1,448.9, 0 2019-11-26T18:28:02.605Z,1574792882.605 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-11-26T18:28:02.605Z,1574792882.605 [Default:CheckIn:C.Wait] Stopped 2019-11-26T18:28:02.605Z,1574792882.605 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-11-26T18:28:02.606Z,1574792882.606 [Default:CheckIn:D] Running Loop=1 2019-11-26T18:28:03.020Z,1574792883.020 [Default:CheckIn:D] Stopped 2019-11-26T18:28:03.020Z,1574792883.020 [Default:CheckIn:E] Running Loop=1 2019-11-26T18:28:03.401Z,1574792883.401 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 9.716961 min 2019-11-26T18:28:03.401Z,1574792883.401 [Default:CheckIn:E] Stopped 2019-11-26T18:28:03.401Z,1574792883.401 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-11-26T18:28:03.401Z,1574792883.401 [Default:CheckIn] Stopped 2019-11-26T18:28:03.402Z,1574792883.402 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-11-26T18:28:03.402Z,1574792883.402 [Default:CheckIn](INFO): Running loop #2 2019-11-26T18:28:03.402Z,1574792883.402 [Default:CheckIn] Running Loop=2 2019-11-26T18:28:03.402Z,1574792883.402 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-11-26T18:28:03.402Z,1574792883.402 [Default:CheckIn:Read_GPS] Running Loop=1 2019-11-26T18:28:05.393Z,1574792885.393 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182758.00,A,3648.75769,N,12149.61248,W,0.680,229.14,261119,,,D*73 2019-11-26T18:28:05.395Z,1574792885.395 [NAL9602](INFO): GPS fix at 20191126T182758: (36.812628, -121.826875) 2019-11-26T18:28:05.452Z,1574792885.452 [Default:CheckIn:Read_GPS] Stopped 2019-11-26T18:28:05.457Z,1574792885.457 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-11-26T18:28:15.868Z,1574792895.868 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20191126T181924/Courier0003.lzma 2019-11-26T18:28:17.875Z,1574792897.875 [DataOverHttps](INFO): Moved sent file to Logs/20191126T181924/Courier0003.lzma.bak 2019-11-26T18:28:17.875Z,1574792897.875 [DataOverHttps](INFO): SBD MOMSN=12107435 2019-11-26T18:28:29.262Z,1574792909.262 [NAL9602](INFO): SBD MO Status=0, MOMSN=10049, MT Status=0, MTMSN=0 2019-11-26T18:28:29.263Z,1574792909.263 [NAL9602](INFO): No messages in MT queue 2019-11-26T18:28:35.689Z,1574792915.689 [DataOverHttps](INFO): Sending 406 bytes from file Logs/20191126T181924/Express0004.lzma 2019-11-26T18:28:37.694Z,1574792917.694 [DataOverHttps](INFO): Moved sent file to Logs/20191126T181924/Express0004.lzma.bak 2019-11-26T18:28:37.694Z,1574792917.694 [DataOverHttps](INFO): SBD MOMSN=12107438 2019-11-26T18:28:39.808Z,1574792919.808 [Default:CheckIn:Read_Iridium] Stopped 2019-11-26T18:28:39.809Z,1574792919.809 [Default:CheckIn:C.Wait] Running Loop=1 2019-11-26T18:28:39.809Z,1574792919.809 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-11-26T18:28:59.967Z,1574792939.967 [NAL9602](INFO): Not Powering down - fast GPS 2019-11-26T18:31:01.642Z,1574793061.642 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-11-26T18:31:01.643Z,1574793061.643 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2019-11-26T18:33:40.449Z,1574793220.449 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-11-26T18:33:40.449Z,1574793220.449 [Default:CheckIn:C.Wait] Stopped 2019-11-26T18:33:40.449Z,1574793220.449 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-11-26T18:33:40.449Z,1574793220.449 [Default:CheckIn:D] Running Loop=1 2019-11-26T18:33:40.833Z,1574793220.833 [Default:CheckIn:D] Stopped 2019-11-26T18:33:40.833Z,1574793220.833 [Default:CheckIn:E] Running Loop=1 2019-11-26T18:33:41.255Z,1574793221.255 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 15.347171 min 2019-11-26T18:33:41.255Z,1574793221.255 [Default:CheckIn:E] Stopped 2019-11-26T18:33:41.255Z,1574793221.255 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-11-26T18:33:41.255Z,1574793221.255 [Default:CheckIn] Stopped 2019-11-26T18:33:41.256Z,1574793221.256 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-11-26T18:33:41.256Z,1574793221.256 [Default:CheckIn](INFO): Running loop #3 2019-11-26T18:33:41.257Z,1574793221.257 [Default:CheckIn] Running Loop=3 2019-11-26T18:33:41.257Z,1574793221.257 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-11-26T18:33:41.258Z,1574793221.258 [Default:CheckIn:Read_GPS] Running Loop=1 2019-11-26T18:33:43.223Z,1574793223.223 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183335.00,A,3648.77138,N,12149.63117,W,0.408,37.05,261119,,,D*49 2019-11-26T18:33:43.225Z,1574793223.225 [NAL9602](INFO): GPS fix at 20191126T183335: (36.812856, -121.827186) 2019-11-26T18:33:43.277Z,1574793223.277 [Default:CheckIn:Read_GPS] Stopped 2019-11-26T18:33:43.277Z,1574793223.277 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-11-26T18:33:51.704Z,1574793231.704 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20191126T181924/Courier0006.lzma 2019-11-26T18:33:53.711Z,1574793233.711 [DataOverHttps](INFO): Moved sent file to Logs/20191126T181924/Courier0006.lzma.bak 2019-11-26T18:33:53.711Z,1574793233.711 [DataOverHttps](INFO): SBD MOMSN=12107443 2019-11-26T18:34:12.377Z,1574793252.377 [NAL9602](INFO): SBD MO Status=0, MOMSN=10050, MT Status=0, MTMSN=0 2019-11-26T18:34:12.377Z,1574793252.377 [NAL9602](INFO): No messages in MT queue 2019-11-26T18:34:14.859Z,1574793254.859 [DataOverHttps](INFO): Sending 296 bytes from file Logs/20191126T181924/Express0007.lzma 2019-11-26T18:34:16.862Z,1574793256.862 [DataOverHttps](INFO): Moved sent file to Logs/20191126T181924/Express0007.lzma.bak 2019-11-26T18:34:16.862Z,1574793256.862 [DataOverHttps](INFO): SBD MOMSN=12107446 2019-11-26T18:34:18.885Z,1574793258.885 [Default:CheckIn:Read_Iridium] Stopped 2019-11-26T18:34:18.885Z,1574793258.885 [Default:CheckIn:C.Wait] Running Loop=1 2019-11-26T18:34:18.885Z,1574793258.885 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-11-26T18:34:43.079Z,1574793283.079 [NAL9602](INFO): Not Powering down - fast GPS 2019-11-26T18:35:23.932Z,1574793323.932 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2019-11-26T18:35:23.933Z,1574793323.933 [RDI_Pathfinder](ERROR): Failed to parse: :BD, +0.00, +0. +0.00, 0.95 2019-11-26T18:35:40.088Z,1574793340.088 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2019-11-26T18:35:40.088Z,1574793340.088 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-3276,-32768,V 2019-11-26T18:36:23.296Z,1574793383.296 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-11-26T18:36:23.296Z,1574793383.296 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19112611482326,.1, 0.0,1448.9, 0 2019-11-26T18:37:00.459Z,1574793420.459 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-11-26T18:37:00.460Z,1574793420.460 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19112611490026,35.0 0.0,1448.9, 0 2019-11-26T18:38:04.311Z,1574793484.311 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-11-26T18:38:04.311Z,1574793484.311 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2019-11-26T18:39:19.493Z,1574793559.493 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-11-26T18:39:19.493Z,1574793559.493 [Default:CheckIn:C.Wait] Stopped 2019-11-26T18:39:19.493Z,1574793559.493 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-11-26T18:39:19.493Z,1574793559.493 [Default:CheckIn:D] Running Loop=1 2019-11-26T18:39:19.883Z,1574793559.883 [Default:CheckIn:D] Stopped 2019-11-26T18:39:19.884Z,1574793559.884 [Default:CheckIn:E] Running Loop=1 2019-11-26T18:39:20.297Z,1574793560.297 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 20.998014 min 2019-11-26T18:39:20.297Z,1574793560.297 [Default:CheckIn:E] Stopped 2019-11-26T18:39:20.298Z,1574793560.298 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-11-26T18:39:20.298Z,1574793560.298 [Default:CheckIn] Stopped 2019-11-26T18:39:20.298Z,1574793560.298 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-11-26T18:39:20.298Z,1574793560.298 [Default:CheckIn](INFO): Running loop #4 2019-11-26T18:39:20.298Z,1574793560.298 [Default:CheckIn] Running Loop=4 2019-11-26T18:39:20.298Z,1574793560.298 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-11-26T18:39:20.298Z,1574793560.298 [Default:CheckIn:Read_GPS] Running Loop=1 2019-11-26T18:39:22.279Z,1574793562.279 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183914.00,A,3648.78539,N,12149.65131,W,0.933,263.54,261119,,,A*7F 2019-11-26T18:39:22.282Z,1574793562.282 [NAL9602](INFO): GPS fix at 20191126T183914: (36.813090, -121.827522) 2019-11-26T18:39:22.351Z,1574793562.351 [Default:CheckIn:Read_GPS] Stopped 2019-11-26T18:39:22.351Z,1574793562.351 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-11-26T18:39:34.036Z,1574793574.036 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20191126T181924/Courier0009.lzma 2019-11-26T18:39:36.042Z,1574793576.042 [DataOverHttps](INFO): Moved sent file to Logs/20191126T181924/Courier0009.lzma.bak 2019-11-26T18:39:36.043Z,1574793576.043 [DataOverHttps](INFO): SBD MOMSN=12107451 2019-11-26T18:39:43.308Z,1574793583.308 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-11-26T18:39:43.308Z,1574793583.308 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19112611514326,35.0, -0.1, 0.0,144 2019-11-26T18:39:53.761Z,1574793593.761 [DataOverHttps](INFO): Sending 242 bytes from file Logs/20191126T181924/Express0010.lzma 2019-11-26T18:39:55.766Z,1574793595.766 [DataOverHttps](INFO): Moved sent file to Logs/20191126T181924/Express0010.lzma.bak 2019-11-26T18:39:55.766Z,1574793595.766 [DataOverHttps](INFO): SBD MOMSN=12107454 2019-11-26T18:39:57.865Z,1574793597.865 [Default:CheckIn:Read_Iridium] Stopped 2019-11-26T18:39:57.866Z,1574793597.866 [Default:CheckIn:C.Wait] Running Loop=1 2019-11-26T18:39:57.866Z,1574793597.866 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-11-26T18:40:15.241Z,1574793615.241 [RDI_Pathfinder](ERROR): Failed to parse: :BE,-32768,-32768,-32768,V 2019-11-26T18:40:21.684Z,1574793621.684 [NAL9602](INFO): SBD MO Status=2, MOMSN=10051, MT Status=2, MTMSN=0 2019-11-26T18:40:21.692Z,1574793621.692 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-11-26T18:40:51.165Z,1574793651.165 [NAL9602](INFO): SBD MO Status=2, MOMSN=10051, MT Status=2, MTMSN=0 2019-11-26T18:40:51.165Z,1574793651.165 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-11-26T18:41:08.987Z,1574793668.987 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2019-11-26T18:41:08.987Z,1574793668.987 [RDI_Pathfinder](ERROR): Failed to parse: :BS,32768,-32768,V 2019-11-26T18:41:58.628Z,1574793718.628 [NAL9602](INFO): SBD MO Status=0, MOMSN=10051, MT Status=0, MTMSN=0 2019-11-26T18:41:58.628Z,1574793718.628 [NAL9602](INFO): No messages in MT queue 2019-11-26T18:42:03.982Z,1574793723.982 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2019-11-26T18:42:03.982Z,1574793723.982 [RDI_Pathfinder](ERROR): Failed to parse: :BD, +0 2019-11-26T18:42:29.339Z,1574793749.339 [NAL9602](INFO): Not Powering down - fast GPS 2019-11-26T18:43:40.455Z,1574793820.455 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-11-26T18:43:40.455Z,1574793820.455 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19112611554026,31, 0.0,1448.9, 0 2019-11-26T18:43:43.285Z,1574793823.285 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-11-26T18:43:43.285Z,1574793823.285 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19112611554326,35.0, -0.1, 0.0,14 2019-11-26T18:44:44.294Z,1574793884.294 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-11-26T18:44:44.294Z,1574793884.294 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2019-11-26T18:44:58.450Z,1574793898.450 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-11-26T18:44:58.450Z,1574793898.450 [Default:CheckIn:C.Wait] Stopped 2019-11-26T18:44:58.450Z,1574793898.450 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-11-26T18:44:58.450Z,1574793898.450 [Default:CheckIn:D] Running Loop=1 2019-11-26T18:44:58.868Z,1574793898.868 [Default:CheckIn:D] Stopped 2019-11-26T18:44:58.868Z,1574793898.868 [Default:CheckIn:E] Running Loop=1 2019-11-26T18:44:59.268Z,1574793899.268 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 26.647758 min 2019-11-26T18:44:59.268Z,1574793899.268 [Default:CheckIn:E] Stopped 2019-11-26T18:44:59.268Z,1574793899.268 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-11-26T18:44:59.268Z,1574793899.268 [Default:CheckIn] Stopped 2019-11-26T18:44:59.268Z,1574793899.268 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-11-26T18:44:59.269Z,1574793899.269 [Default:CheckIn](INFO): Running loop #5 2019-11-26T18:44:59.269Z,1574793899.269 [Default:CheckIn] Running Loop=5 2019-11-26T18:44:59.269Z,1574793899.269 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-11-26T18:44:59.269Z,1574793899.269 [Default:CheckIn:Read_GPS] Running Loop=1 2019-11-26T18:45:00.463Z,1574793900.463 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-11-26T18:45:00.463Z,1574793900.463 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19112611570026,35.0, -0.1, 09, 0 2019-11-26T18:45:01.272Z,1574793901.272 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184453.00,A,3648.79786,N,12149.67125,W,1.477,105.37,261119,,,D*79 2019-11-26T18:45:01.274Z,1574793901.274 [NAL9602](INFO): GPS fix at 20191126T184453: (36.813298, -121.827854) 2019-11-26T18:45:01.309Z,1574793901.309 [Default:CheckIn:Read_GPS] Stopped 2019-11-26T18:45:01.309Z,1574793901.309 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-11-26T18:45:09.336Z,1574793909.336 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-11-26T18:45:09.336Z,1574793909.336 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19112611570926,35.0, 0.0,1448.9, 0 2019-11-26T18:45:10.673Z,1574793910.673 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20191126T181924/Courier0012.lzma 2019-11-26T18:45:12.786Z,1574793912.786 [DataOverHttps](INFO): Moved sent file to Logs/20191126T181924/Courier0012.lzma.bak 2019-11-26T18:45:12.786Z,1574793912.786 [DataOverHttps](INFO): SBD MOMSN=12107459 2019-11-26T18:45:22.652Z,1574793922.652 [NAL9602](INFO): SBD MO Status=2, MOMSN=10052, MT Status=2, MTMSN=0 2019-11-26T18:45:22.661Z,1574793922.661 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-11-26T18:45:31.525Z,1574793931.525 [DataOverHttps](INFO): Sending 304 bytes from file Logs/20191126T181924/Express0013.lzma 2019-11-26T18:45:33.530Z,1574793933.530 [DataOverHttps](INFO): Moved sent file to Logs/20191126T181924/Express0013.lzma.bak 2019-11-26T18:45:33.530Z,1574793933.530 [DataOverHttps](INFO): SBD MOMSN=12107462 2019-11-26T18:45:35.633Z,1574793935.633 [Default:CheckIn:Read_Iridium] Stopped 2019-11-26T18:45:35.634Z,1574793935.634 [Default:CheckIn:C.Wait] Running Loop=1 2019-11-26T18:45:35.634Z,1574793935.634 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-11-26T18:45:40.044Z,1574793940.044 [NAL9602](INFO): SBD MO Status=2, MOMSN=10052, MT Status=2, MTMSN=0 2019-11-26T18:45:40.044Z,1574793940.044 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-11-26T18:46:07.932Z,1574793967.932 [NAL9602](INFO): SBD MO Status=2, MOMSN=10052, MT Status=2, MTMSN=0 2019-11-26T18:46:07.932Z,1574793967.932 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-11-26T18:46:25.288Z,1574793985.288 [NAL9602](INFO): SBD MO Status=2, MOMSN=10052, MT Status=2, MTMSN=0 2019-11-26T18:46:25.288Z,1574793985.288 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-11-26T18:47:27.930Z,1574794047.930 [NAL9602](INFO): SBD MO Status=0, MOMSN=10052, MT Status=0, MTMSN=0 2019-11-26T18:47:27.930Z,1574794047.930 [NAL9602](INFO): No messages in MT queue 2019-11-26T18:47:58.615Z,1574794078.615 [NAL9602](INFO): Not Powering down - fast GPS 2019-11-26T18:48:29.354Z,1574794109.354 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-11-26T18:48:29.354Z,1574794109.354 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2019-11-26T18:50:04.280Z,1574794204.280 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-11-26T18:50:04.281Z,1574794204.281 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19112612020426,35.0, -0.1, 0.0,1448:RA, 0.00, 0.00, 0.00, 0.00, 0.00 2019-11-26T18:50:36.213Z,1574794236.213 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-11-26T18:50:36.214Z,1574794236.214 [Default:CheckIn:C.Wait] Stopped 2019-11-26T18:50:36.214Z,1574794236.214 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-11-26T18:50:36.214Z,1574794236.214 [Default:CheckIn:D] Running Loop=1 2019-11-26T18:50:36.618Z,1574794236.618 [Default:CheckIn:D] Stopped 2019-11-26T18:50:36.618Z,1574794236.618 [Default:CheckIn:E] Running Loop=1 2019-11-26T18:50:37.020Z,1574794237.020 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.276914 min 2019-11-26T18:50:37.021Z,1574794237.021 [Default:CheckIn:E] Stopped 2019-11-26T18:50:37.021Z,1574794237.021 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-11-26T18:50:37.021Z,1574794237.021 [Default:CheckIn] Stopped 2019-11-26T18:50:37.021Z,1574794237.021 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-11-26T18:50:37.021Z,1574794237.021 [Default:CheckIn](INFO): Running loop #6 2019-11-26T18:50:37.021Z,1574794237.021 [Default:CheckIn] Running Loop=6 2019-11-26T18:50:37.021Z,1574794237.021 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-11-26T18:50:37.022Z,1574794237.022 [Default:CheckIn:Read_GPS] Running Loop=1 2019-11-26T18:50:39.010Z,1574794239.010 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185031.00,A,3648.81241,N,12149.68533,W,0.914,286.37,261119,,,D*7C 2019-11-26T18:50:39.012Z,1574794239.012 [NAL9602](INFO): GPS fix at 20191126T185031: (36.813540, -121.828089) 2019-11-26T18:50:39.047Z,1574794239.047 [Default:CheckIn:Read_GPS] Stopped 2019-11-26T18:50:39.047Z,1574794239.047 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-11-26T18:50:46.756Z,1574794246.756 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20191126T181924/Courier0015.lzma 2019-11-26T18:50:48.762Z,1574794248.762 [DataOverHttps](INFO): Moved sent file to Logs/20191126T181924/Courier0015.lzma.bak 2019-11-26T18:50:48.762Z,1574794248.762 [DataOverHttps](INFO): SBD MOMSN=12107467 2019-11-26T18:51:03.250Z,1574794263.250 [NAL9602](INFO): SBD MO Status=0, MOMSN=10053, MT Status=0, MTMSN=0 2019-11-26T18:51:03.250Z,1574794263.250 [NAL9602](INFO): No messages in MT queue 2019-11-26T18:51:08.093Z,1574794268.093 [DataOverHttps](INFO): Sending 328 bytes from file Logs/20191126T181924/Express0016.lzma 2019-11-26T18:51:10.098Z,1574794270.098 [DataOverHttps](INFO): Moved sent file to Logs/20191126T181924/Express0016.lzma.bak 2019-11-26T18:51:10.098Z,1574794270.098 [DataOverHttps](INFO): SBD MOMSN=12107470 2019-11-26T18:51:12.180Z,1574794272.180 [Default:CheckIn:Read_Iridium] Stopped 2019-11-26T18:51:12.180Z,1574794272.180 [Default:CheckIn:C.Wait] Running Loop=1 2019-11-26T18:51:12.180Z,1574794272.180 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-11-26T18:51:22.279Z,1574794282.279 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2019-11-26T18:51:22.279Z,1574794282.279 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-32768,32768,V 2019-11-26T18:51:33.943Z,1574794293.943 [NAL9602](INFO): Not Powering down - fast GPS 2019-11-26T18:52:25.278Z,1574794345.278 [RDI_Pathfinder](ERROR): Failed to parse: 00, 0.00, 0.00, 0.00, 0.00 2019-11-26T18:52:48.298Z,1574794368.298 [RDI_Pathfinder](ERROR): Failed to parse: :BE,-32768,-32768,-32768,V 2019-11-26T18:53:45.283Z,1574794425.283 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-11-26T18:53:45.283Z,1574794425.283 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19112612054526,.1, 0.0,1448.9, 0 2019-11-26T18:56:12.776Z,1574794572.776 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-11-26T18:56:12.776Z,1574794572.776 [Default:CheckIn:C.Wait] Stopped 2019-11-26T18:56:12.776Z,1574794572.776 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-11-26T18:56:12.776Z,1574794572.776 [Default:CheckIn:D] Running Loop=1 2019-11-26T18:56:13.182Z,1574794573.182 [Default:CheckIn:D] Stopped 2019-11-26T18:56:13.182Z,1574794573.182 [Default:CheckIn:E] Running Loop=1 2019-11-26T18:56:13.581Z,1574794573.581 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 37.886328 min 2019-11-26T18:56:13.581Z,1574794573.581 [Default:CheckIn:E] Stopped 2019-11-26T18:56:13.581Z,1574794573.581 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-11-26T18:56:13.582Z,1574794573.582 [Default:CheckIn] Stopped 2019-11-26T18:56:13.582Z,1574794573.582 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-11-26T18:56:13.582Z,1574794573.582 [Default:CheckIn](INFO): Running loop #7 2019-11-26T18:56:13.582Z,1574794573.582 [Default:CheckIn] Running Loop=7 2019-11-26T18:56:13.582Z,1574794573.582 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-11-26T18:56:13.582Z,1574794573.582 [Default:CheckIn:Read_GPS] Running Loop=1 2019-11-26T18:56:15.565Z,1574794575.565 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185608.00,A,3648.84104,N,12149.63213,W,4.704,93.53,261119,,,D*46 2019-11-26T18:56:15.567Z,1574794575.567 [NAL9602](INFO): GPS fix at 20191126T185608: (36.814017, -121.827202) 2019-11-26T18:56:15.595Z,1574794575.595 [Default:CheckIn:Read_GPS] Stopped 2019-11-26T18:56:15.595Z,1574794575.595 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-11-26T18:56:17.592Z,1574794577.592 [DataOverHttps](IMPORTANT): SBD MTMSN=20191126T185608 2019-11-26T18:56:25.421Z,1574794585.421 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20191126T181924/Courier0018.lzma 2019-11-26T18:56:25.427Z,1574794585.427 [DataOverHttps](INFO): Received command:restart logs