2012-10-30T18:59:46.911Z,1351623586.911 [NAL9602](INFO): Received command:restart logs
2012-10-30T18:59:46.935Z,1351623586.935 [CommandLine](IMPORTANT): got command restart logs
2012-10-30T19:00:11.469Z,1351623611.469 [NAL9602](INFO): SBD MO Status=1, MOMSN=4249, MT Status=0, MTMSN=0
2012-10-30T19:00:11.507Z,1351623611.507 [NAL9602](INFO): Sent 264 bytes from file Logs/20121029T212005/shore0041.lzma
2012-10-30T19:00:11.507Z,1351623611.507 [NAL9602](INFO): Packets left to send: 0
2012-10-30T19:00:11.509Z,1351623611.509 [NAL9602](INFO): Stored copy of sent data in Logs/20121029T212005/shore0041.lzma.parts/0000.sbd
2012-10-30T19:00:22.092Z,1351623622.092 [NAL9602](INFO): SBD MO Status=1, MOMSN=4250, MT Status=0, MTMSN=0
2012-10-30T19:00:22.127Z,1351623622.127 [NAL9602](INFO): Sent 185 bytes from file Logs/20121029T212005/shore0042.lzma
2012-10-30T19:00:22.127Z,1351623622.127 [NAL9602](INFO): Packets left to send: 0
2012-10-30T19:00:22.129Z,1351623622.129 [NAL9602](INFO): Stored copy of sent data in Logs/20121029T212005/shore0042.lzma.parts/0000.sbd
2012-10-30T19:00:29.314Z,1351623629.314 [NAL9602](INFO): SBD MO Status=0, MOMSN=4251, MT Status=0, MTMSN=0
2012-10-30T19:00:48.460Z,1351623648.460 [NAL9602](INFO): SBD MO Status=1, MOMSN=4252, MT Status=0, MTMSN=0
2012-10-30T19:00:48.495Z,1351623648.495 [NAL9602](INFO): Sent 332 bytes from file Logs/20121030T185946/shore0000.lzma
2012-10-30T19:00:48.495Z,1351623648.495 [NAL9602](INFO): Packets left to send: 2
2012-10-30T19:00:48.496Z,1351623648.497 [NAL9602](INFO): Stored copy of sent data in Logs/20121030T185946/shore0000.lzma.parts/0002.sbd
2012-10-30T19:00:58.017Z,1351623658.017 [NAL9602](INFO): SBD MO Status=1, MOMSN=4253, MT Status=0, MTMSN=0
2012-10-30T19:00:58.055Z,1351623658.055 [NAL9602](INFO): Sent 332 bytes from file Logs/20121030T185946/shore0000.lzma
2012-10-30T19:00:58.055Z,1351623658.055 [NAL9602](INFO): Packets left to send: 1
2012-10-30T19:00:58.057Z,1351623658.057 [NAL9602](INFO): Stored copy of sent data in Logs/20121030T185946/shore0000.lzma.parts/0001.sbd
2012-10-30T19:01:09.452Z,1351623669.452 [NAL9602](INFO): SBD MO Status=1, MOMSN=4254, MT Status=0, MTMSN=0
2012-10-30T19:01:09.487Z,1351623669.487 [NAL9602](INFO): Sent 78 bytes from file Logs/20121030T185946/shore0000.lzma
2012-10-30T19:01:09.487Z,1351623669.487 [NAL9602](INFO): Packets left to send: 0
2012-10-30T19:01:09.488Z,1351623669.488 [NAL9602](INFO): Stored copy of sent data in Logs/20121030T185946/shore0000.lzma.parts/0000.sbd
2012-10-30T19:01:18.255Z,1351623678.254 [NAL9602](INFO): SBD MO Status=0, MOMSN=4255, MT Status=0, MTMSN=0
2012-10-30T19:01:18.311Z,1351623678.311 [Default:Iridium:Read_Iridium] Stopped
2012-10-30T19:01:18.312Z,1351623678.312 [Default:Iridium](INFO): Completed Default:Iridium
2012-10-30T19:01:18.312Z,1351623678.312 [Default:Iridium] Stopped
2012-10-30T19:01:18.312Z,1351623678.312 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-10-30T19:01:18.312Z,1351623678.312 [Default:Iridium:A.SetSpeed] Stopped
2012-10-30T19:01:18.312Z,1351623678.312 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-10-30T19:01:18.312Z,1351623678.312 [Default:F.Wait] Running Loop=1
2012-10-30T19:01:18.312Z,1351623678.312 [Default:F.Wait](DEBUG): Initialize Wait Component.
2012-10-30T19:01:18.688Z,1351623678.688 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2012-10-30T19:01:18.688Z,1351623678.688 [Default:CallIridium:B] Stopped
2012-10-30T19:01:18.688Z,1351623678.688 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-10-30T19:01:18.688Z,1351623678.688 [Default:CallIridium](INFO): Completed Default:CallIridium
2012-10-30T19:01:18.688Z,1351623678.688 [Default:CallIridium] Stopped
2012-10-30T19:01:18.689Z,1351623678.689 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-10-30T19:01:28.812Z,1351623688.812 [NAL9602](INFO): Powering down
2012-10-30T19:04:43.754Z,1351623883.754 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#7 STATUS: 65535
2012-10-30T19:06:18.808Z,1351623978.808 [Default:CallIridium] Running Loop=1
2012-10-30T19:06:18.808Z,1351623978.808 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-10-30T19:06:18.809Z,1351623978.809 [Default:CallIridium:A] Running Loop=1
2012-10-30T19:06:18.809Z,1351623978.809 [Default:CallIridium:A] Stopped
2012-10-30T19:06:18.809Z,1351623978.809 [Default:CallIridium:B] Running Loop=1
2012-10-30T19:06:18.809Z,1351623978.809 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-10-30T19:06:23.824Z,1351623983.824 [Default:Iridium] Running Loop=1
2012-10-30T19:06:23.824Z,1351623983.824 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-10-30T19:06:23.825Z,1351623983.825 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-10-30T19:06:23.825Z,1351623983.825 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-10-30T19:06:23.825Z,1351623983.825 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-10-30T19:06:23.825Z,1351623983.825 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-30T19:06:23.826Z,1351623983.826 [Default:Iridium:B.GoToSurface] Stopped
2012-10-30T19:06:23.826Z,1351623983.826 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-30T19:06:23.826Z,1351623983.826 [Default:Iridium:Read_Iridium] Running Loop=1
2012-10-30T19:06:23.826Z,1351623983.826 [Default:GPS] Running Loop=1
2012-10-30T19:06:23.826Z,1351623983.826 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-10-30T19:06:23.826Z,1351623983.826 [Default:GPS:A.SetSpeed] Running Loop=1
2012-10-30T19:06:23.826Z,1351623983.826 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-10-30T19:06:23.827Z,1351623983.827 [Default:GPS:B.GoToSurface] Running Loop=1
2012-10-30T19:06:23.827Z,1351623983.827 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-30T19:06:23.827Z,1351623983.828 [Default:GPS:B.GoToSurface] Stopped
2012-10-30T19:06:23.828Z,1351623983.828 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-30T19:06:23.828Z,1351623983.828 [Default:GPS:Read_GPS] Running Loop=1
2012-10-30T19:06:24.472Z,1351623984.472 [NAL9602](INFO): Powering up
2012-10-30T19:06:35.279Z,1351623995.279 [NAL9602](INFO): NAL9602 initialized
2012-10-30T19:07:03.377Z,1351624023.377 [NAL9602](INFO): SBD MO Status=1, MOMSN=4256, MT Status=0, MTMSN=0
2012-10-30T19:07:03.412Z,1351624023.411 [NAL9602](INFO): Sent 152 bytes from file Logs/20121030T185946/shore0001.lzma
2012-10-30T19:07:03.412Z,1351624023.412 [NAL9602](INFO): Packets left to send: 0
2012-10-30T19:07:03.413Z,1351624023.413 [NAL9602](INFO): Stored copy of sent data in Logs/20121030T185946/shore0001.lzma.parts/0000.sbd
2012-10-30T19:07:09.777Z,1351624029.777 [NAL9602](INFO): SBD MO Status=0, MOMSN=4257, MT Status=0, MTMSN=0
2012-10-30T19:07:09.839Z,1351624029.839 [Default:Iridium:Read_Iridium] Stopped
2012-10-30T19:07:09.839Z,1351624029.839 [Default:Iridium](INFO): Completed Default:Iridium
2012-10-30T19:07:09.839Z,1351624029.839 [Default:Iridium] Stopped
2012-10-30T19:07:09.839Z,1351624029.839 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-10-30T19:07:09.839Z,1351624029.839 [Default:Iridium:A.SetSpeed] Stopped
2012-10-30T19:07:09.840Z,1351624029.839 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-10-30T19:07:10.186Z,1351624030.186 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2012-10-30T19:07:10.186Z,1351624030.187 [Default:CallIridium:B] Stopped
2012-10-30T19:07:10.187Z,1351624030.187 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-10-30T19:07:10.187Z,1351624030.187 [Default:CallIridium](INFO): Completed Default:CallIridium
2012-10-30T19:07:10.187Z,1351624030.187 [Default:CallIridium] Stopped
2012-10-30T19:07:10.187Z,1351624030.187 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-10-30T19:07:19.609Z,1351624039.609 [NAL9602](IMPORTANT): GPS fix at: 1351624137.00
2012-10-30T19:07:19.625Z,1351624039.625 [Default:GPS:Read_GPS] Stopped
2012-10-30T19:07:19.626Z,1351624039.625 [Default:GPS:D] Running Loop=1
2012-10-30T19:07:20.027Z,1351624040.027 [Default:GPS:D] Stopped
2012-10-30T19:07:20.027Z,1351624040.027 [Default:GPS](INFO): Completed Default:GPS
2012-10-30T19:07:20.027Z,1351624040.027 [Default:GPS] Stopped
2012-10-30T19:07:20.028Z,1351624040.028 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-10-30T19:07:20.028Z,1351624040.028 [Default:GPS:A.SetSpeed] Stopped
2012-10-30T19:07:20.028Z,1351624040.028 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-10-30T19:07:40.540Z,1351624060.540 [NAL9602](INFO): Powering down
2012-10-30T19:12:10.524Z,1351624330.524 [Default:CallIridium] Running Loop=1
2012-10-30T19:12:10.525Z,1351624330.524 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-10-30T19:12:10.525Z,1351624330.525 [Default:CallIridium:A] Running Loop=1
2012-10-30T19:12:10.525Z,1351624330.525 [Default:CallIridium:A] Stopped
2012-10-30T19:12:10.525Z,1351624330.525 [Default:CallIridium:B] Running Loop=1
2012-10-30T19:12:10.525Z,1351624330.525 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-10-30T19:12:15.608Z,1351624335.608 [Default:Iridium] Running Loop=1
2012-10-30T19:12:15.608Z,1351624335.608 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-10-30T19:12:15.608Z,1351624335.608 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-10-30T19:12:15.609Z,1351624335.609 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-10-30T19:12:15.609Z,1351624335.609 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-10-30T19:12:15.609Z,1351624335.609 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-30T19:12:15.609Z,1351624335.609 [Default:Iridium:B.GoToSurface] Stopped
2012-10-30T19:12:15.609Z,1351624335.609 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-30T19:12:15.610Z,1351624335.610 [Default:Iridium:Read_Iridium] Running Loop=1
2012-10-30T19:12:15.610Z,1351624335.610 [Default:GPS] Running Loop=1
2012-10-30T19:12:15.610Z,1351624335.610 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-10-30T19:12:15.610Z,1351624335.610 [Default:GPS:A.SetSpeed] Running Loop=1
2012-10-30T19:12:15.610Z,1351624335.610 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-10-30T19:12:15.611Z,1351624335.611 [Default:GPS:B.GoToSurface] Running Loop=1
2012-10-30T19:12:15.611Z,1351624335.611 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-30T19:12:15.611Z,1351624335.611 [Default:GPS:B.GoToSurface] Stopped
2012-10-30T19:12:15.611Z,1351624335.611 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-30T19:12:15.612Z,1351624335.612 [Default:GPS:Read_GPS] Running Loop=1
2012-10-30T19:12:16.262Z,1351624336.262 [NAL9602](INFO): Powering up
2012-10-30T19:12:26.975Z,1351624346.975 [NAL9602](INFO): NAL9602 initialized
2012-10-30T19:12:53.345Z,1351624373.345 [NAL9602](INFO): SBD MO Status=1, MOMSN=4258, MT Status=0, MTMSN=0
2012-10-30T19:12:53.383Z,1351624373.383 [NAL9602](INFO): Sent 221 bytes from file Logs/20121030T185946/shore0002.lzma
2012-10-30T19:12:53.383Z,1351624373.383 [NAL9602](INFO): Packets left to send: 0
2012-10-30T19:12:53.385Z,1351624373.385 [NAL9602](INFO): Stored copy of sent data in Logs/20121030T185946/shore0002.lzma.parts/0000.sbd
2012-10-30T19:13:04.940Z,1351624384.940 [NAL9602](INFO): SBD MO Status=0, MOMSN=4259, MT Status=0, MTMSN=0
2012-10-30T19:13:04.996Z,1351624384.996 [Default:Iridium:Read_Iridium] Stopped
2012-10-30T19:13:04.997Z,1351624384.997 [Default:Iridium](INFO): Completed Default:Iridium
2012-10-30T19:13:04.997Z,1351624384.997 [Default:Iridium] Stopped
2012-10-30T19:13:04.997Z,1351624384.997 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-10-30T19:13:04.997Z,1351624384.997 [Default:Iridium:A.SetSpeed] Stopped
2012-10-30T19:13:04.997Z,1351624384.997 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-10-30T19:13:05.359Z,1351624385.359 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2012-10-30T19:13:05.360Z,1351624385.360 [Default:CallIridium:B] Stopped
2012-10-30T19:13:05.360Z,1351624385.360 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-10-30T19:13:05.360Z,1351624385.360 [Default:CallIridium](INFO): Completed Default:CallIridium
2012-10-30T19:13:05.360Z,1351624385.360 [Default:CallIridium] Stopped
2012-10-30T19:13:05.360Z,1351624385.360 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-10-30T19:18:05.363Z,1351624685.364 [Default:CallIridium] Running Loop=1
2012-10-30T19:18:05.364Z,1351624685.364 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-10-30T19:18:05.364Z,1351624685.364 [Default:CallIridium:A] Running Loop=1
2012-10-30T19:18:05.364Z,1351624685.364 [Default:CallIridium:A] Stopped
2012-10-30T19:18:05.364Z,1351624685.364 [Default:CallIridium:B] Running Loop=1
2012-10-30T19:18:05.364Z,1351624685.364 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-10-30T19:18:05.759Z,1351624685.759 [Default:Iridium] Running Loop=1
2012-10-30T19:18:05.760Z,1351624685.760 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-10-30T19:18:05.760Z,1351624685.760 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-10-30T19:18:05.760Z,1351624685.760 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-10-30T19:18:05.760Z,1351624685.760 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-10-30T19:18:05.760Z,1351624685.760 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-30T19:18:05.761Z,1351624685.761 [Default:Iridium:B.GoToSurface] Stopped
2012-10-30T19:18:05.761Z,1351624685.761 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-30T19:18:05.761Z,1351624685.761 [Default:Iridium:Read_Iridium] Running Loop=1
2012-10-30T19:18:25.888Z,1351624705.888 [NAL9602](INFO): SBD MO Status=1, MOMSN=4260, MT Status=0, MTMSN=0
2012-10-30T19:18:25.924Z,1351624705.924 [NAL9602](INFO): Sent 143 bytes from file Logs/20121030T185946/shore0003.lzma
2012-10-30T19:18:25.924Z,1351624705.924 [NAL9602](INFO): Packets left to send: 0
2012-10-30T19:18:25.925Z,1351624705.925 [NAL9602](INFO): Stored copy of sent data in Logs/20121030T185946/shore0003.lzma.parts/0000.sbd
2012-10-30T19:18:40.737Z,1351624720.737 [NAL9602](INFO): SBD MO Status=2, MOMSN=4261, MT Status=2, MTMSN=0
2012-10-30T19:18:40.737Z,1351624720.737 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2012-10-30T19:18:52.685Z,1351624732.685 [NAL9602](INFO): SBD MO Status=0, MOMSN=4261, MT Status=0, MTMSN=0
2012-10-30T19:18:52.743Z,1351624732.743 [Default:Iridium:Read_Iridium] Stopped
2012-10-30T19:18:52.743Z,1351624732.743 [Default:Iridium](INFO): Completed Default:Iridium
2012-10-30T19:18:52.743Z,1351624732.743 [Default:Iridium] Stopped
2012-10-30T19:18:52.743Z,1351624732.743 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-10-30T19:18:52.744Z,1351624732.744 [Default:Iridium:A.SetSpeed] Stopped
2012-10-30T19:18:52.744Z,1351624732.744 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-10-30T19:18:53.094Z,1351624733.094 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2012-10-30T19:18:53.095Z,1351624733.095 [Default:CallIridium:B] Stopped
2012-10-30T19:18:53.095Z,1351624733.095 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-10-30T19:18:53.095Z,1351624733.095 [Default:CallIridium](INFO): Completed Default:CallIridium
2012-10-30T19:18:53.095Z,1351624733.095 [Default:CallIridium] Stopped
2012-10-30T19:18:53.095Z,1351624733.095 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-10-30T19:22:28.720Z,1351624948.720 [NAL9602](FAULT): GPS failed to acquire within timeout.
2012-10-30T19:22:28.720Z,1351624948.720 [NAL9602] Data Fault, FailCount= 1
2012-10-30T19:22:28.720Z,1351624948.720 [NAL9602](ERROR): Data Fault
2012-10-30T19:22:28.737Z,1351624948.737 [Default:CallIridium] Running Loop=1
2012-10-30T19:22:28.737Z,1351624948.737 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-10-30T19:22:28.737Z,1351624948.737 [Default:CallIridium:A] Running Loop=1
2012-10-30T19:22:28.737Z,1351624948.737 [Default:CallIridium:A] Stopped
2012-10-30T19:22:28.737Z,1351624948.737 [Default:CallIridium:B] Running Loop=1
2012-10-30T19:22:28.737Z,1351624948.737 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-10-30T19:22:28.746Z,1351624948.746 [CBIT](ERROR): Data Fault in component: NAL9602
2012-10-30T19:22:29.078Z,1351624949.078 [NAL9602](INFO): Powering down
2012-10-30T19:22:29.095Z,1351624949.095 [Default:Iridium] Running Loop=1
2012-10-30T19:22:29.095Z,1351624949.095 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-10-30T19:22:29.096Z,1351624949.096 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-10-30T19:22:29.096Z,1351624949.096 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-10-30T19:22:29.096Z,1351624949.096 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-10-30T19:22:29.096Z,1351624949.096 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-30T19:22:29.097Z,1351624949.097 [Default:Iridium:B.GoToSurface] Stopped
2012-10-30T19:22:29.097Z,1351624949.097 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-30T19:22:29.097Z,1351624949.097 [Default:Iridium:Read_Iridium] Running Loop=1
2012-10-30T19:22:29.106Z,1351624949.106 [CBIT](INFO): Clearing failed state for component NAL9602
2012-10-30T19:22:29.106Z,1351624949.106 [NAL9602] No Fault, FailCount= 1
2012-10-30T19:22:34.305Z,1351624954.305 [NAL9602](INFO): Powering up NAL9602
2012-10-30T19:22:45.107Z,1351624965.107 [NAL9602](INFO): NAL9602 initialized
2012-10-30T19:23:23.462Z,1351625003.462 [NAL9602](INFO): SBD MO Status=1, MOMSN=4262, MT Status=0, MTMSN=0
2012-10-30T19:23:23.499Z,1351625003.499 [NAL9602](INFO): Sent 186 bytes from file Logs/20121030T185946/shore0004.lzma
2012-10-30T19:23:23.499Z,1351625003.499 [NAL9602](INFO): Packets left to send: 0
2012-10-30T19:23:23.501Z,1351625003.501 [NAL9602](INFO): Stored copy of sent data in Logs/20121030T185946/shore0004.lzma.parts/0000.sbd
2012-10-30T19:23:40.870Z,1351625020.870 [NAL9602](INFO): SBD MO Status=2, MOMSN=4263, MT Status=2, MTMSN=0
2012-10-30T19:23:40.870Z,1351625020.870 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2012-10-30T19:23:56.885Z,1351625036.885 [NAL9602](INFO): SBD MO Status=0, MOMSN=4263, MT Status=0, MTMSN=0
2012-10-30T19:23:56.943Z,1351625036.943 [Default:Iridium:Read_Iridium] Stopped
2012-10-30T19:23:56.944Z,1351625036.944 [Default:Iridium](INFO): Completed Default:Iridium
2012-10-30T19:23:56.944Z,1351625036.944 [Default:Iridium] Stopped
2012-10-30T19:23:56.944Z,1351625036.944 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-10-30T19:23:56.944Z,1351625036.944 [Default:Iridium:A.SetSpeed] Stopped
2012-10-30T19:23:56.944Z,1351625036.944 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-10-30T19:23:57.294Z,1351625037.294 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2012-10-30T19:23:57.294Z,1351625037.294 [Default:CallIridium:B] Stopped
2012-10-30T19:23:57.295Z,1351625037.295 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-10-30T19:23:57.295Z,1351625037.295 [Default:CallIridium](INFO): Completed Default:CallIridium
2012-10-30T19:23:57.295Z,1351625037.295 [Default:CallIridium] Stopped
2012-10-30T19:23:57.295Z,1351625037.295 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-10-30T19:28:25.687Z,1351625305.687 [NAL9602](IMPORTANT): GPS fix at: 1351625406.00
2012-10-30T19:28:25.703Z,1351625305.703 [Default:GPS:Read_GPS] Stopped
2012-10-30T19:28:25.703Z,1351625305.703 [Default:GPS:D] Running Loop=1
2012-10-30T19:28:26.103Z,1351625306.103 [Default:GPS:D] Stopped
2012-10-30T19:28:26.103Z,1351625306.103 [Default:GPS](INFO): Completed Default:GPS
2012-10-30T19:28:26.103Z,1351625306.103 [Default:GPS] Stopped
2012-10-30T19:28:26.104Z,1351625306.104 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-10-30T19:28:26.104Z,1351625306.104 [Default:GPS:A.SetSpeed] Stopped
2012-10-30T19:28:26.104Z,1351625306.104 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-10-30T19:28:46.624Z,1351625326.624 [NAL9602](INFO): Powering down
2012-10-30T19:29:02.063Z,1351625342.063 [Default:CallIridium] Running Loop=1
2012-10-30T19:29:02.063Z,1351625342.063 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-10-30T19:29:02.063Z,1351625342.063 [Default:CallIridium:A] Running Loop=1
2012-10-30T19:29:02.064Z,1351625342.063 [Default:CallIridium:A] Stopped
2012-10-30T19:29:02.064Z,1351625342.064 [Default:CallIridium:B] Running Loop=1
2012-10-30T19:29:02.064Z,1351625342.064 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-10-30T19:29:06.636Z,1351625346.636 [Default:Iridium] Running Loop=1
2012-10-30T19:29:06.637Z,1351625346.636 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-10-30T19:29:06.637Z,1351625346.637 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-10-30T19:29:06.637Z,1351625346.637 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-10-30T19:29:06.637Z,1351625346.637 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-10-30T19:29:06.637Z,1351625346.637 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-30T19:29:06.638Z,1351625346.638 [Default:Iridium:B.GoToSurface] Stopped
2012-10-30T19:29:06.638Z,1351625346.638 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-30T19:29:06.638Z,1351625346.638 [Default:Iridium:Read_Iridium] Running Loop=1
2012-10-30T19:29:06.638Z,1351625346.638 [Default:GPS] Running Loop=1
2012-10-30T19:29:06.638Z,1351625346.638 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-10-30T19:29:06.639Z,1351625346.639 [Default:GPS:A.SetSpeed] Running Loop=1
2012-10-30T19:29:06.639Z,1351625346.639 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-10-30T19:29:06.639Z,1351625346.639 [Default:GPS:B.GoToSurface] Running Loop=1
2012-10-30T19:29:06.639Z,1351625346.639 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-30T19:29:06.640Z,1351625346.640 [Default:GPS:B.GoToSurface] Stopped
2012-10-30T19:29:06.640Z,1351625346.640 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-30T19:29:06.640Z,1351625346.640 [Default:GPS:Read_GPS] Running Loop=1
2012-10-30T19:29:07.278Z,1351625347.278 [NAL9602](INFO): Powering up
2012-10-30T19:29:17.707Z,1351625357.707 [NAL9602](INFO): NAL9602 initialized
2012-10-30T19:29:42.228Z,1351625382.228 [NAL9602](INFO): SBD MO Status=2, MOMSN=4264, MT Status=2, MTMSN=0
2012-10-30T19:29:42.228Z,1351625382.228 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2012-10-30T19:30:02.777Z,1351625402.777 [NAL9602](INFO): SBD MO Status=1, MOMSN=4264, MT Status=0, MTMSN=0
2012-10-30T19:30:02.819Z,1351625402.819 [NAL9602](INFO): Sent 204 bytes from file Logs/20121030T185946/shore0005.lzma
2012-10-30T19:30:02.819Z,1351625402.819 [NAL9602](INFO): Packets left to send: 0
2012-10-30T19:30:02.821Z,1351625402.821 [NAL9602](INFO): Stored copy of sent data in Logs/20121030T185946/shore0005.lzma.parts/0000.sbd
2012-10-30T19:30:13.194Z,1351625413.194 [NAL9602](INFO): SBD MO Status=0, MOMSN=4265, MT Status=0, MTMSN=0
2012-10-30T19:30:13.254Z,1351625413.254 [Default:Iridium:Read_Iridium] Stopped
2012-10-30T19:30:13.254Z,1351625413.254 [Default:Iridium](INFO): Completed Default:Iridium
2012-10-30T19:30:13.255Z,1351625413.254 [Default:Iridium] Stopped
2012-10-30T19:30:13.255Z,1351625413.255 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-10-30T19:30:13.255Z,1351625413.255 [Default:Iridium:A.SetSpeed] Stopped
2012-10-30T19:30:13.255Z,1351625413.255 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-10-30T19:30:13.594Z,1351625413.594 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2012-10-30T19:30:13.594Z,1351625413.594 [Default:CallIridium:B] Stopped
2012-10-30T19:30:13.594Z,1351625413.594 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-10-30T19:30:13.594Z,1351625413.594 [Default:CallIridium](INFO): Completed Default:CallIridium
2012-10-30T19:30:13.595Z,1351625413.595 [Default:CallIridium] Stopped
2012-10-30T19:30:13.595Z,1351625413.595 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-10-30T19:30:14.373Z,1351625414.373 [NAL9602](IMPORTANT): GPS fix at: 1351625514.00
2012-10-30T19:30:14.390Z,1351625414.390 [Default:GPS:Read_GPS] Stopped
2012-10-30T19:30:14.390Z,1351625414.390 [Default:GPS:D] Running Loop=1
2012-10-30T19:30:14.795Z,1351625414.795 [Default:GPS:D] Stopped
2012-10-30T19:30:14.795Z,1351625414.795 [Default:GPS](INFO): Completed Default:GPS
2012-10-30T19:30:14.795Z,1351625414.795 [Default:GPS] Stopped
2012-10-30T19:30:14.795Z,1351625414.795 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-10-30T19:30:14.795Z,1351625414.795 [Default:GPS:A.SetSpeed] Stopped
2012-10-30T19:30:14.795Z,1351625414.796 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-10-30T19:30:35.308Z,1351625435.308 [NAL9602](INFO): Powering down
2012-10-30T19:35:15.350Z,1351625715.351 [Default:CallIridium] Running Loop=1
2012-10-30T19:35:15.351Z,1351625715.351 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-10-30T19:35:15.351Z,1351625715.351 [Default:CallIridium:A] Running Loop=1
2012-10-30T19:35:15.351Z,1351625715.351 [Default:CallIridium:A] Stopped
2012-10-30T19:35:15.351Z,1351625715.351 [Default:CallIridium:B] Running Loop=1
2012-10-30T19:35:15.352Z,1351625715.352 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-10-30T19:35:20.324Z,1351625720.324 [Default:Iridium] Running Loop=1
2012-10-30T19:35:20.324Z,1351625720.324 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-10-30T19:35:20.324Z,1351625720.324 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-10-30T19:35:20.324Z,1351625720.325 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-10-30T19:35:20.325Z,1351625720.325 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-10-30T19:35:20.325Z,1351625720.325 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-30T19:35:20.325Z,1351625720.325 [Default:Iridium:B.GoToSurface] Stopped
2012-10-30T19:35:20.326Z,1351625720.326 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-30T19:35:20.326Z,1351625720.326 [Default:Iridium:Read_Iridium] Running Loop=1
2012-10-30T19:35:20.326Z,1351625720.326 [Default:GPS] Running Loop=1
2012-10-30T19:35:20.326Z,1351625720.326 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-10-30T19:35:20.326Z,1351625720.326 [Default:GPS:A.SetSpeed] Running Loop=1
2012-10-30T19:35:20.326Z,1351625720.326 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-10-30T19:35:20.326Z,1351625720.326 [Default:GPS:B.GoToSurface] Running Loop=1
2012-10-30T19:35:20.327Z,1351625720.327 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-30T19:35:20.327Z,1351625720.327 [Default:GPS:B.GoToSurface] Stopped
2012-10-30T19:35:20.327Z,1351625720.327 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-30T19:35:20.327Z,1351625720.328 [Default:GPS:Read_GPS] Running Loop=1
2012-10-30T19:35:20.974Z,1351625720.974 [NAL9602](INFO): Powering up
2012-10-30T19:35:31.619Z,1351625731.619 [NAL9602](INFO): NAL9602 initialized
2012-10-30T19:35:48.915Z,1351625748.915 [NAL9602](INFO): SBD MO Status=1, MOMSN=4266, MT Status=0, MTMSN=0
2012-10-30T19:35:48.951Z,1351625748.951 [NAL9602](INFO): Sent 198 bytes from file Logs/20121030T185946/shore0006.lzma
2012-10-30T19:35:48.951Z,1351625748.951 [NAL9602](INFO): Packets left to send: 0
2012-10-30T19:35:48.952Z,1351625748.953 [NAL9602](INFO): Stored copy of sent data in Logs/20121030T185946/shore0006.lzma.parts/0000.sbd
2012-10-30T19:35:56.915Z,1351625756.915 [NAL9602](INFO): SBD MO Status=0, MOMSN=4267, MT Status=0, MTMSN=0
2012-10-30T19:35:56.971Z,1351625756.971 [Default:Iridium:Read_Iridium] Stopped
2012-10-30T19:35:56.972Z,1351625756.972 [Default:Iridium](INFO): Completed Default:Iridium
2012-10-30T19:35:56.972Z,1351625756.972 [Default:Iridium] Stopped
2012-10-30T19:35:56.972Z,1351625756.972 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-10-30T19:35:56.972Z,1351625756.972 [Default:Iridium:A.SetSpeed] Stopped
2012-10-30T19:35:56.972Z,1351625756.972 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-10-30T19:35:57.328Z,1351625757.328 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2012-10-30T19:35:57.328Z,1351625757.328 [Default:CallIridium:B] Stopped
2012-10-30T19:35:57.328Z,1351625757.328 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-10-30T19:35:57.328Z,1351625757.328 [Default:CallIridium](INFO): Completed Default:CallIridium
2012-10-30T19:35:57.329Z,1351625757.329 [Default:CallIridium] Stopped
2012-10-30T19:35:57.329Z,1351625757.329 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-10-30T19:35:58.114Z,1351625758.114 [NAL9602](IMPORTANT): GPS fix at: 1351625858.00
2012-10-30T19:35:58.131Z,1351625758.131 [Default:GPS:Read_GPS] Stopped
2012-10-30T19:35:58.131Z,1351625758.131 [Default:GPS:D] Running Loop=1
2012-10-30T19:35:58.535Z,1351625758.535 [Default:GPS:D] Stopped
2012-10-30T19:35:58.536Z,1351625758.536 [Default:GPS](INFO): Completed Default:GPS
2012-10-30T19:35:58.536Z,1351625758.536 [Default:GPS] Stopped
2012-10-30T19:35:58.536Z,1351625758.536 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-10-30T19:35:58.536Z,1351625758.536 [Default:GPS:A.SetSpeed] Stopped
2012-10-30T19:35:58.536Z,1351625758.536 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-10-30T19:36:19.044Z,1351625779.044 [NAL9602](INFO): Powering down
2012-10-30T19:40:59.091Z,1351626059.092 [Default:CallIridium] Running Loop=1
2012-10-30T19:40:59.092Z,1351626059.092 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-10-30T19:40:59.092Z,1351626059.092 [Default:CallIridium:A] Running Loop=1
2012-10-30T19:40:59.092Z,1351626059.092 [Default:CallIridium:A] Stopped
2012-10-30T19:40:59.092Z,1351626059.092 [Default:CallIridium:B] Running Loop=1
2012-10-30T19:40:59.092Z,1351626059.092 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-10-30T19:41:04.051Z,1351626064.051 [Default:Iridium] Running Loop=1
2012-10-30T19:41:04.052Z,1351626064.052 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-10-30T19:41:04.052Z,1351626064.052 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-10-30T19:41:04.052Z,1351626064.052 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-10-30T19:41:04.052Z,1351626064.052 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-10-30T19:41:04.052Z,1351626064.052 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-30T19:41:04.053Z,1351626064.053 [Default:Iridium:B.GoToSurface] Stopped
2012-10-30T19:41:04.053Z,1351626064.053 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-30T19:41:04.053Z,1351626064.053 [Default:Iridium:Read_Iridium] Running Loop=1
2012-10-30T19:41:04.053Z,1351626064.053 [Default:GPS] Running Loop=1
2012-10-30T19:41:04.053Z,1351626064.053 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-10-30T19:41:04.053Z,1351626064.053 [Default:GPS:A.SetSpeed] Running Loop=1
2012-10-30T19:41:04.054Z,1351626064.053 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-10-30T19:41:04.054Z,1351626064.054 [Default:GPS:B.GoToSurface] Running Loop=1
2012-10-30T19:41:04.054Z,1351626064.054 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-30T19:41:04.054Z,1351626064.055 [Default:GPS:B.GoToSurface] Stopped
2012-10-30T19:41:04.055Z,1351626064.055 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-30T19:41:04.055Z,1351626064.055 [Default:GPS:Read_GPS] Running Loop=1
2012-10-30T19:41:04.716Z,1351626064.716 [NAL9602](INFO): Powering up
2012-10-30T19:41:15.583Z,1351626075.583 [NAL9602](INFO): NAL9602 initialized
2012-10-30T19:41:35.269Z,1351626095.269 [NAL9602](INFO): SBD MO Status=1, MOMSN=4268, MT Status=0, MTMSN=0
2012-10-30T19:41:35.311Z,1351626095.311 [NAL9602](INFO): Sent 202 bytes from file Logs/20121030T185946/shore0007.lzma
2012-10-30T19:41:35.311Z,1351626095.311 [NAL9602](INFO): Packets left to send: 0
2012-10-30T19:41:35.312Z,1351626095.312 [NAL9602](INFO): Stored copy of sent data in Logs/20121030T185946/shore0007.lzma.parts/0000.sbd
2012-10-30T19:41:42.090Z,1351626102.090 [NAL9602](INFO): SBD MO Status=0, MOMSN=4269, MT Status=0, MTMSN=0
2012-10-30T19:41:42.147Z,1351626102.147 [Default:Iridium:Read_Iridium] Stopped
2012-10-30T19:41:42.147Z,1351626102.147 [Default:Iridium](INFO): Completed Default:Iridium
2012-10-30T19:41:42.147Z,1351626102.148 [Default:Iridium] Stopped
2012-10-30T19:41:42.148Z,1351626102.148 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-10-30T19:41:42.148Z,1351626102.148 [Default:Iridium:A.SetSpeed] Stopped
2012-10-30T19:41:42.148Z,1351626102.148 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-10-30T19:41:42.478Z,1351626102.478 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2012-10-30T19:41:42.478Z,1351626102.478 [Default:CallIridium:B] Stopped
2012-10-30T19:41:42.478Z,1351626102.478 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-10-30T19:41:42.479Z,1351626102.479 [Default:CallIridium](INFO): Completed Default:CallIridium
2012-10-30T19:41:42.479Z,1351626102.479 [Default:CallIridium] Stopped
2012-10-30T19:41:42.479Z,1351626102.479 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-10-30T19:41:43.271Z,1351626103.271 [NAL9602](IMPORTANT): GPS fix at: 1351626204.00
2012-10-30T19:41:43.287Z,1351626103.287 [Default:GPS:Read_GPS] Stopped
2012-10-30T19:41:43.287Z,1351626103.287 [Default:GPS:D] Running Loop=1
2012-10-30T19:41:43.691Z,1351626103.691 [Default:GPS:D] Stopped
2012-10-30T19:41:43.691Z,1351626103.691 [Default:GPS](INFO): Completed Default:GPS
2012-10-30T19:41:43.691Z,1351626103.691 [Default:GPS] Stopped
2012-10-30T19:41:43.691Z,1351626103.691 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-10-30T19:41:43.692Z,1351626103.691 [Default:GPS:A.SetSpeed] Stopped
2012-10-30T19:41:43.692Z,1351626103.692 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-10-30T19:42:04.200Z,1351626124.200 [NAL9602](INFO): Powering down
2012-10-30T19:46:44.220Z,1351626404.220 [Default:CallIridium] Running Loop=1
2012-10-30T19:46:44.220Z,1351626404.220 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-10-30T19:46:44.220Z,1351626404.220 [Default:CallIridium:A] Running Loop=1
2012-10-30T19:46:44.220Z,1351626404.220 [Default:CallIridium:A] Stopped
2012-10-30T19:46:44.221Z,1351626404.221 [Default:CallIridium:B] Running Loop=1
2012-10-30T19:46:44.221Z,1351626404.221 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-10-30T19:46:49.212Z,1351626409.212 [Default:Iridium] Running Loop=1
2012-10-30T19:46:49.212Z,1351626409.212 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-10-30T19:46:49.212Z,1351626409.212 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-10-30T19:46:49.212Z,1351626409.212 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-10-30T19:46:49.213Z,1351626409.213 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-10-30T19:46:49.213Z,1351626409.213 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-30T19:46:49.213Z,1351626409.213 [Default:Iridium:B.GoToSurface] Stopped
2012-10-30T19:46:49.213Z,1351626409.213 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-30T19:46:49.214Z,1351626409.214 [Default:Iridium:Read_Iridium] Running Loop=1
2012-10-30T19:46:49.214Z,1351626409.214 [Default:GPS] Running Loop=1
2012-10-30T19:46:49.214Z,1351626409.214 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-10-30T19:46:49.214Z,1351626409.214 [Default:GPS:A.SetSpeed] Running Loop=1
2012-10-30T19:46:49.214Z,1351626409.214 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-10-30T19:46:49.214Z,1351626409.214 [Default:GPS:B.GoToSurface] Running Loop=1
2012-10-30T19:46:49.215Z,1351626409.215 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-30T19:46:49.215Z,1351626409.215 [Default:GPS:B.GoToSurface] Stopped
2012-10-30T19:46:49.215Z,1351626409.215 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-30T19:46:49.216Z,1351626409.216 [Default:GPS:Read_GPS] Running Loop=1
2012-10-30T19:46:49.862Z,1351626409.862 [NAL9602](INFO): Powering up
2012-10-30T19:47:00.327Z,1351626420.327 [NAL9602](INFO): NAL9602 initialized
2012-10-30T19:47:19.654Z,1351626439.654 [NAL9602](INFO): SBD MO Status=1, MOMSN=4270, MT Status=0, MTMSN=0
2012-10-30T19:47:19.691Z,1351626439.691 [NAL9602](INFO): Sent 193 bytes from file Logs/20121030T185946/shore0008.lzma
2012-10-30T19:47:19.691Z,1351626439.691 [NAL9602](INFO): Packets left to send: 0
2012-10-30T19:47:19.693Z,1351626439.693 [NAL9602](INFO): Stored copy of sent data in Logs/20121030T185946/shore0008.lzma.parts/0000.sbd
2012-10-30T19:47:27.637Z,1351626447.637 [NAL9602](INFO): SBD MO Status=0, MOMSN=4271, MT Status=0, MTMSN=0
2012-10-30T19:47:27.695Z,1351626447.695 [Default:Iridium:Read_Iridium] Stopped
2012-10-30T19:47:27.695Z,1351626447.695 [Default:Iridium](INFO): Completed Default:Iridium
2012-10-30T19:47:27.695Z,1351626447.695 [Default:Iridium] Stopped
2012-10-30T19:47:27.696Z,1351626447.695 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-10-30T19:47:27.696Z,1351626447.696 [Default:Iridium:A.SetSpeed] Stopped
2012-10-30T19:47:27.696Z,1351626447.696 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-10-30T19:47:28.046Z,1351626448.046 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2012-10-30T19:47:28.046Z,1351626448.046 [Default:CallIridium:B] Stopped
2012-10-30T19:47:28.047Z,1351626448.047 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-10-30T19:47:28.047Z,1351626448.047 [Default:CallIridium](INFO): Completed Default:CallIridium
2012-10-30T19:47:28.047Z,1351626448.047 [Default:CallIridium] Stopped
2012-10-30T19:47:28.047Z,1351626448.047 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-10-30T19:48:52.433Z,1351626532.433 [NAL9602](IMPORTANT): GPS fix at: 1351626633.00
2012-10-30T19:48:52.449Z,1351626532.449 [Default:GPS:Read_GPS] Stopped
2012-10-30T19:48:52.449Z,1351626532.450 [Default:GPS:D] Running Loop=1
2012-10-30T19:48:52.854Z,1351626532.854 [Default:GPS:D] Stopped
2012-10-30T19:48:52.855Z,1351626532.855 [Default:GPS](INFO): Completed Default:GPS
2012-10-30T19:48:52.855Z,1351626532.855 [Default:GPS] Stopped
2012-10-30T19:48:52.855Z,1351626532.855 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-10-30T19:48:52.855Z,1351626532.855 [Default:GPS:A.SetSpeed] Stopped
2012-10-30T19:48:52.855Z,1351626532.855 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-10-30T19:49:13.368Z,1351626553.368 [NAL9602](INFO): Powering down
2012-10-30T19:52:28.396Z,1351626748.396 [Default:CallIridium] Running Loop=1
2012-10-30T19:52:28.396Z,1351626748.396 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-10-30T19:52:28.396Z,1351626748.396 [Default:CallIridium:A] Running Loop=1
2012-10-30T19:52:28.396Z,1351626748.397 [Default:CallIridium:A] Stopped
2012-10-30T19:52:28.397Z,1351626748.397 [Default:CallIridium:B] Running Loop=1
2012-10-30T19:52:28.397Z,1351626748.397 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-10-30T19:52:33.384Z,1351626753.384 [Default:Iridium] Running Loop=1
2012-10-30T19:52:33.384Z,1351626753.384 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-10-30T19:52:33.384Z,1351626753.384 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-10-30T19:52:33.384Z,1351626753.384 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-10-30T19:52:33.384Z,1351626753.385 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-10-30T19:52:33.385Z,1351626753.385 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-30T19:52:33.385Z,1351626753.385 [Default:Iridium:B.GoToSurface] Stopped
2012-10-30T19:52:33.385Z,1351626753.385 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-30T19:52:33.386Z,1351626753.385 [Default:Iridium:Read_Iridium] Running Loop=1
2012-10-30T19:52:33.386Z,1351626753.386 [Default:GPS] Running Loop=1
2012-10-30T19:52:33.386Z,1351626753.386 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-10-30T19:52:33.386Z,1351626753.386 [Default:GPS:A.SetSpeed] Running Loop=1
2012-10-30T19:52:33.386Z,1351626753.386 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-10-30T19:52:33.387Z,1351626753.386 [Default:GPS:B.GoToSurface] Running Loop=1
2012-10-30T19:52:33.387Z,1351626753.387 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-30T19:52:33.387Z,1351626753.387 [Default:GPS:B.GoToSurface] Stopped
2012-10-30T19:52:33.387Z,1351626753.387 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-30T19:52:33.387Z,1351626753.388 [Default:GPS:Read_GPS] Running Loop=1
2012-10-30T19:52:34.034Z,1351626754.034 [NAL9602](INFO): Powering up
2012-10-30T19:52:44.467Z,1351626764.467 [NAL9602](INFO): NAL9602 initialized
2012-10-30T19:53:39.528Z,1351626819.528 [NAL9602](INFO): SBD MO Status=1, MOMSN=4272, MT Status=0, MTMSN=0
2012-10-30T19:53:39.563Z,1351626819.563 [NAL9602](INFO): Sent 200 bytes from file Logs/20121030T185946/shore0009.lzma
2012-10-30T19:53:39.563Z,1351626819.563 [NAL9602](INFO): Packets left to send: 0
2012-10-30T19:53:39.564Z,1351626819.565 [NAL9602](INFO): Stored copy of sent data in Logs/20121030T185946/shore0009.lzma.parts/0000.sbd
2012-10-30T19:53:54.333Z,1351626834.333 [NAL9602](INFO): SBD MO Status=2, MOMSN=4273, MT Status=2, MTMSN=0
2012-10-30T19:53:54.333Z,1351626834.333 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2012-10-30T19:54:11.452Z,1351626851.452 [NAL9602](INFO): SBD MO Status=2, MOMSN=4273, MT Status=2, MTMSN=0
2012-10-30T19:54:11.452Z,1351626851.452 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2012-10-30T19:54:28.600Z,1351626868.600 [NAL9602](INFO): SBD MO Status=2, MOMSN=4273, MT Status=2, MTMSN=0
2012-10-30T19:54:28.600Z,1351626868.600 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2012-10-30T19:54:33.781Z,1351626873.781 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2012-10-30T19:54:38.064Z,1351626878.064 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2012-10-30T19:54:56.749Z,1351626896.749 [NAL9602](INFO): SBD MO Status=0, MOMSN=4273, MT Status=0, MTMSN=0
2012-10-30T19:54:56.807Z,1351626896.807 [Default:Iridium:Read_Iridium] Stopped
2012-10-30T19:54:56.807Z,1351626896.807 [Default:Iridium](INFO): Completed Default:Iridium
2012-10-30T19:54:56.807Z,1351626896.807 [Default:Iridium] Stopped
2012-10-30T19:54:56.807Z,1351626896.807 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-10-30T19:54:56.808Z,1351626896.807 [Default:Iridium:A.SetSpeed] Stopped
2012-10-30T19:54:56.808Z,1351626896.807 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-10-30T19:54:57.154Z,1351626897.154 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2012-10-30T19:54:57.154Z,1351626897.154 [Default:CallIridium:B] Stopped
2012-10-30T19:54:57.154Z,1351626897.154 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-10-30T19:54:57.155Z,1351626897.155 [Default:CallIridium](INFO): Completed Default:CallIridium
2012-10-30T19:54:57.155Z,1351626897.155 [Default:CallIridium] Stopped
2012-10-30T19:54:57.155Z,1351626897.155 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-10-30T19:59:57.224Z,1351627197.224 [Default:CallIridium] Running Loop=1
2012-10-30T19:59:57.224Z,1351627197.224 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-10-30T19:59:57.224Z,1351627197.224 [Default:CallIridium:A] Running Loop=1
2012-10-30T19:59:57.225Z,1351627197.225 [Default:CallIridium:A] Stopped
2012-10-30T19:59:57.225Z,1351627197.225 [Default:CallIridium:B] Running Loop=1
2012-10-30T19:59:57.225Z,1351627197.225 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-10-30T19:59:57.559Z,1351627197.559 [Default:Iridium] Running Loop=1
2012-10-30T19:59:57.560Z,1351627197.560 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-10-30T19:59:57.560Z,1351627197.560 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-10-30T19:59:57.560Z,1351627197.560 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-10-30T19:59:57.560Z,1351627197.560 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-10-30T19:59:57.560Z,1351627197.560 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-30T19:59:57.561Z,1351627197.561 [Default:Iridium:B.GoToSurface] Stopped
2012-10-30T19:59:57.561Z,1351627197.561 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-30T19:59:57.561Z,1351627197.561 [Default:Iridium:Read_Iridium] Running Loop=1
2012-10-30T20:02:47.872Z,1351627367.872 [NAL9602](FAULT): GPS failed to acquire within timeout.
2012-10-30T20:02:47.872Z,1351627367.872 [NAL9602] Data Fault, FailCount= 2
2012-10-30T20:02:47.872Z,1351627367.872 [NAL9602](ERROR): Data Fault
2012-10-30T20:02:47.921Z,1351627367.921 [CBIT](ERROR): Data Fault in component: NAL9602
2012-10-30T20:02:47.921Z,1351627367.921 [CBIT](CRITICAL): Data Fault in component: NAL9602
2012-10-30T20:02:48.270Z,1351627368.270 [NAL9602](INFO): Powering down
2012-10-30T20:04:48.045Z,1351627488.045 [CBIT](INFO): Clearing failed count for component NAL9602
2012-10-30T20:04:48.045Z,1351627488.045 [NAL9602] No Fault, FailCount= 2
2012-10-30T20:04:48.437Z,1351627488.437 [NAL9602](INFO): Powering up NAL9602
2012-10-30T20:04:59.239Z,1351627499.239 [NAL9602](INFO): NAL9602 initialized
2012-10-30T20:15:00.211Z,1351628100.211 [NAL9602](FAULT): GPS failed to acquire within timeout.
2012-10-30T20:15:00.211Z,1351628100.211 [NAL9602] Data Fault, FailCount= 1
2012-10-30T20:15:00.211Z,1351628100.211 [NAL9602](ERROR): Data Fault
2012-10-30T20:15:00.236Z,1351628100.236 [CBIT](ERROR): Data Fault in component: NAL9602
2012-10-30T20:15:00.616Z,1351628100.616 [NAL9602](INFO): Powering down
2012-10-30T20:15:00.641Z,1351628100.641 [CBIT](INFO): Clearing failed state for component NAL9602
2012-10-30T20:15:00.641Z,1351628100.641 [NAL9602] No Fault, FailCount= 1
2012-10-30T20:15:05.815Z,1351628105.815 [NAL9602](INFO): Powering up NAL9602
2012-10-30T20:15:16.659Z,1351628116.659 [NAL9602](INFO): NAL9602 initialized
2012-10-30T20:15:17.478Z,1351628117.478 [NAL9602](ERROR): getFix uart error: serial timeout
2012-10-30T20:18:24.771Z,1351628304.771 [CommandLine](IMPORTANT): got command @ ifconfig