2012-05-31T01:01:46.887Z,1338426106.887 [NAL9601](INFO): SBD MO Status=1, MOMSN=7134, MT Status=0, MTMSN=0
2012-05-31T01:01:47.071Z,1338426107.071 [NAL9601](INFO): Sent 332 bytes from file Logs/20120530T215940/shore0006.lzma
2012-05-31T01:01:47.071Z,1338426107.071 [NAL9601](INFO): Packets left to send: 2
2012-05-31T01:01:47.072Z,1338426107.072 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000125
2012-05-31T01:01:54.572Z,1338426114.572 [NAL9601](INFO): SBD MO Status=1, MOMSN=7135, MT Status=0, MTMSN=0
2012-05-31T01:01:54.739Z,1338426114.739 [NAL9601](INFO): Sent 332 bytes from file Logs/20120530T215940/shore0006.lzma
2012-05-31T01:01:54.739Z,1338426114.739 [NAL9601](INFO): Packets left to send: 1
2012-05-31T01:01:54.740Z,1338426114.740 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000126
2012-05-31T01:02:02.751Z,1338426122.750 [NAL9601](INFO): SBD MO Status=1, MOMSN=7136, MT Status=0, MTMSN=0
2012-05-31T01:02:02.927Z,1338426122.927 [NAL9601](INFO): Sent 237 bytes from file Logs/20120530T215940/shore0006.lzma
2012-05-31T01:02:02.927Z,1338426122.927 [NAL9601](INFO): Packets left to send: 0
2012-05-31T01:02:02.928Z,1338426122.928 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000127
2012-05-31T01:02:10.418Z,1338426130.418 [NAL9601](INFO): SBD MO Status=1, MOMSN=7137, MT Status=0, MTMSN=0
2012-05-31T01:02:10.615Z,1338426130.615 [NAL9601](INFO): Sent 332 bytes from file Logs/20120530T215940/shore0007.lzma
2012-05-31T01:02:10.615Z,1338426130.615 [NAL9601](INFO): Packets left to send: 1
2012-05-31T01:02:10.616Z,1338426130.616 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000128
2012-05-31T01:02:20.517Z,1338426140.517 [NAL9601](INFO): SBD MO Status=1, MOMSN=7138, MT Status=0, MTMSN=0
2012-05-31T01:02:20.699Z,1338426140.699 [NAL9601](INFO): Sent 130 bytes from file Logs/20120530T215940/shore0007.lzma
2012-05-31T01:02:20.699Z,1338426140.699 [NAL9601](INFO): Packets left to send: 0
2012-05-31T01:02:23.002Z,1338426143.002 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000129
2012-05-31T01:02:35.240Z,1338426155.240 [NAL9601](INFO): SBD MO Status=1, MOMSN=7139, MT Status=0, MTMSN=0
2012-05-31T01:02:35.375Z,1338426155.375 [NAL9601](INFO): Sent 332 bytes from file Logs/20120530T215940/shore0008.lzma
2012-05-31T01:02:35.375Z,1338426155.375 [NAL9601](INFO): Packets left to send: 1
2012-05-31T01:02:35.376Z,1338426155.376 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000130
2012-05-31T01:02:45.730Z,1338426165.730 [NAL9601](INFO): SBD MO Status=1, MOMSN=7140, MT Status=0, MTMSN=0
2012-05-31T01:02:45.863Z,1338426165.863 [NAL9601](INFO): Sent 118 bytes from file Logs/20120530T215940/shore0008.lzma
2012-05-31T01:02:45.863Z,1338426165.863 [NAL9601](INFO): Packets left to send: 0
2012-05-31T01:02:45.864Z,1338426165.864 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000131
2012-05-31T01:02:55.326Z,1338426175.326 [NAL9601](INFO): SBD MO Status=0, MOMSN=7141, MT Status=0, MTMSN=0
2012-05-31T01:03:09.807Z,1338426189.806 [NAL9601](INFO): SBD MO Status=1, MOMSN=7142, MT Status=0, MTMSN=0
2012-05-31T01:03:10.023Z,1338426190.023 [NAL9601](INFO): Sent 332 bytes from file Logs/20120531T010135/shore0000.lzma
2012-05-31T01:03:10.023Z,1338426190.023 [NAL9601](INFO): Packets left to send: 2
2012-05-31T01:03:10.024Z,1338426190.024 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000132
2012-05-31T01:03:19.154Z,1338426199.154 [NAL9601](INFO): SBD MO Status=2, MOMSN=7143, MT Status=2, MTMSN=0
2012-05-31T01:03:19.154Z,1338426199.154 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-05-31T01:03:38.221Z,1338426218.221 [NAL9601](INFO): SBD MO Status=1, MOMSN=7143, MT Status=0, MTMSN=0
2012-05-31T01:03:38.379Z,1338426218.379 [NAL9601](INFO): Sent 332 bytes from file Logs/20120531T010135/shore0000.lzma
2012-05-31T01:03:38.379Z,1338426218.379 [NAL9601](INFO): Packets left to send: 1
2012-05-31T01:03:38.380Z,1338426218.380 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000133
2012-05-31T01:03:51.109Z,1338426231.109 [NAL9601](INFO): SBD MO Status=1, MOMSN=7144, MT Status=0, MTMSN=0
2012-05-31T01:03:51.263Z,1338426231.263 [NAL9601](INFO): Sent 61 bytes from file Logs/20120531T010135/shore0000.lzma
2012-05-31T01:03:51.263Z,1338426231.263 [NAL9601](INFO): Packets left to send: 0
2012-05-31T01:03:51.264Z,1338426231.264 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000134
2012-05-31T01:04:01.130Z,1338426241.129 [NAL9601](INFO): SBD MO Status=2, MOMSN=7145, MT Status=2, MTMSN=0
2012-05-31T01:04:01.130Z,1338426241.130 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-05-31T01:04:10.525Z,1338426250.526 [NAL9601](INFO): SBD MO Status=0, MOMSN=7145, MT Status=0, MTMSN=0
2012-05-31T01:04:10.741Z,1338426250.741 [Default:Iridium:Read_Iridium] Stopped
2012-05-31T01:04:10.742Z,1338426250.742 [Default:Iridium](INFO): Completed Default:Iridium
2012-05-31T01:04:10.742Z,1338426250.742 [Default:Iridium] Stopped
2012-05-31T01:04:10.742Z,1338426250.742 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-05-31T01:04:10.742Z,1338426250.742 [Default:Iridium:A.SetSpeed] Stopped
2012-05-31T01:04:10.742Z,1338426250.742 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-05-31T01:04:10.742Z,1338426250.742 [Default:F.Wait] Running Loop=1
2012-05-31T01:04:10.742Z,1338426250.742 [Default:F.Wait](DEBUG): Initialize Wait Component.
2012-05-31T01:04:10.976Z,1338426250.976 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2012-05-31T01:04:10.976Z,1338426250.976 [Default:CallIridium:B] Stopped
2012-05-31T01:04:10.976Z,1338426250.977 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-05-31T01:04:10.977Z,1338426250.977 [Default:CallIridium](INFO): Completed Default:CallIridium
2012-05-31T01:04:10.977Z,1338426250.977 [Default:CallIridium] Stopped
2012-05-31T01:04:10.977Z,1338426250.977 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-05-31T01:04:21.071Z,1338426261.071 [NAL9601](INFO): Powering down
2012-05-31T01:09:11.056Z,1338426551.056 [Default:CallIridium] Running Loop=1
2012-05-31T01:09:11.056Z,1338426551.056 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-05-31T01:09:11.056Z,1338426551.056 [Default:CallIridium:A] Running Loop=1
2012-05-31T01:09:11.056Z,1338426551.056 [Default:CallIridium:A] Stopped
2012-05-31T01:09:11.056Z,1338426551.056 [Default:CallIridium:B] Running Loop=1
2012-05-31T01:09:11.056Z,1338426551.056 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-05-31T01:09:16.202Z,1338426556.202 [Default:Iridium] Running Loop=1
2012-05-31T01:09:16.202Z,1338426556.202 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-05-31T01:09:16.202Z,1338426556.202 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-05-31T01:09:16.202Z,1338426556.202 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-05-31T01:09:16.203Z,1338426556.203 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-05-31T01:09:16.203Z,1338426556.203 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-05-31T01:09:16.204Z,1338426556.204 [Default:Iridium:B.GoToSurface] Stopped
2012-05-31T01:09:16.204Z,1338426556.204 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-05-31T01:09:16.204Z,1338426556.204 [Default:Iridium:Read_Iridium] Running Loop=1
2012-05-31T01:09:16.205Z,1338426556.205 [Default:GPS] Running Loop=1
2012-05-31T01:09:16.205Z,1338426556.205 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-05-31T01:09:16.205Z,1338426556.205 [Default:GPS:A.SetSpeed] Running Loop=1
2012-05-31T01:09:16.205Z,1338426556.205 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-05-31T01:09:16.205Z,1338426556.205 [Default:GPS:B.GoToSurface] Running Loop=1
2012-05-31T01:09:16.205Z,1338426556.205 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-05-31T01:09:16.206Z,1338426556.206 [Default:GPS:B.GoToSurface] Stopped
2012-05-31T01:09:16.206Z,1338426556.206 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-05-31T01:09:16.206Z,1338426556.207 [Default:GPS:Read_GPS] Running Loop=1
2012-05-31T01:09:16.727Z,1338426556.727 [NAL9601](INFO): Powering up
2012-05-31T01:10:22.451Z,1338426622.451 [NAL9601](INFO): NAL9601 initialized
2012-05-31T01:10:23.546Z,1338426623.546 [NAL9601](IMPORTANT): GPS fix at: 1338426656.00
2012-05-31T01:10:23.562Z,1338426623.562 [Default:GPS:Read_GPS] Stopped
2012-05-31T01:10:23.562Z,1338426623.562 [Default:GPS:D] Running Loop=1
2012-05-31T01:10:23.963Z,1338426623.963 [Default:GPS:D] Stopped
2012-05-31T01:10:23.963Z,1338426623.963 [Default:GPS](INFO): Completed Default:GPS
2012-05-31T01:10:23.963Z,1338426623.963 [Default:GPS] Stopped
2012-05-31T01:10:23.963Z,1338426623.963 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-05-31T01:10:23.963Z,1338426623.963 [Default:GPS:A.SetSpeed] Stopped
2012-05-31T01:10:23.964Z,1338426623.964 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-05-31T01:10:41.483Z,1338426641.483 [NAL9601](IMPORTANT): SBD MO Status=1, MOMSN=7146, MT Status=1, MTMSN=2648
2012-05-31T01:10:41.611Z,1338426641.611 [NAL9601](INFO): Sent 273 bytes from file Logs/20120531T010135/shore0001.lzma
2012-05-31T01:10:41.611Z,1338426641.611 [NAL9601](INFO): Packets left to send: 0
2012-05-31T01:10:41.612Z,1338426641.612 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000135
2012-05-31T01:10:42.024Z,1338426642.024 [NAL9601](INFO): Received command:restart application
2012-05-31T01:10:42.043Z,1338426642.043 [CommandLine](IMPORTANT): got command restart application
2012-05-31T01:10:42.198Z,1338426642.198 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2012-05-31T01:10:42.199Z,1338426642.199 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2012-05-31T01:10:42.473Z,1338426642.473 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2012-05-31T01:10:42.503Z,1338426642.503 [CTD_NeilBrown](INFO): Powering down
2012-05-31T01:10:42.504Z,1338426642.504 [ComponentRegistry](INFO): Shutting down AsyncPiEstimator ThreadHandler
2012-05-31T01:10:42.569Z,1338426642.569 [AsyncPiEstimator](DEBUG): Uninitialize AsyncPiEstimator.
2012-05-31T01:10:42.569Z,1338426642.569 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2012-05-31T01:10:42.569Z,1338426642.569 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2012-05-31T01:10:42.758Z,1338426642.758 [controlThread](DEBUG): Uninitializing ControlThread
2012-05-31T01:10:42.759Z,1338426642.759 [AHRS_sp3003D](INFO): Powering down
2012-05-31T01:10:42.848Z,1338426642.848 [DVL_micro](INFO): Powering down
2012-05-31T01:10:42.848Z,1338426642.848 [NAL9601](INFO): Powering down
2012-05-31T01:10:42.849Z,1338426642.849 [Aanderaa_O2](INFO): Powering down
2012-05-31T01:10:42.850Z,1338426642.849 [WetLabsBB2FL](INFO): Powering down
2012-05-31T01:10:42.850Z,1338426642.850 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2012-05-31T01:10:42.850Z,1338426642.851 [NavChart](DEBUG): Uninitialize NavChart Derivation.
2012-05-31T01:10:42.852Z,1338426642.852 [Default] Stopped
2012-05-31T01:10:42.852Z,1338426642.852 [Default](INFO): Aggregate::uninitialize Default
2012-05-31T01:10:42.852Z,1338426642.852 [Default:Iridium] Stopped
2012-05-31T01:10:42.852Z,1338426642.852 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-05-31T01:10:42.852Z,1338426642.852 [Default:Iridium:A.SetSpeed] Stopped
2012-05-31T01:10:42.852Z,1338426642.852 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-05-31T01:10:42.852Z,1338426642.852 [Default:Iridium:Read_Iridium] Stopped
2012-05-31T01:10:42.852Z,1338426642.852 [Default:CallIridium] Stopped
2012-05-31T01:10:42.852Z,1338426642.852 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-05-31T01:10:42.852Z,1338426642.852 [Default:CallIridium:B] Stopped
2012-05-31T01:10:42.853Z,1338426642.852 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-05-31T01:10:42.853Z,1338426642.853 [Default:D.SetSpeed] Stopped
2012-05-31T01:10:42.853Z,1338426642.853 [Default:D.SetSpeed](DEBUG): Uninitialize.
2012-05-31T01:10:42.853Z,1338426642.853 [Default:E.GoToSurface] Stopped
2012-05-31T01:10:42.853Z,1338426642.853 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-05-31T01:10:42.853Z,1338426642.853 [Default:F.Wait] Stopped
2012-05-31T01:10:42.853Z,1338426642.853 [Default:F.Wait](DEBUG): Uninitialize Wait Component.
2012-05-31T01:10:42.857Z,1338426642.857 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2012-05-31T01:10:42.858Z,1338426642.858 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2012-05-31T01:10:42.858Z,1338426642.858 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2012-05-31T01:10:42.858Z,1338426642.858 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2012-05-31T01:10:42.859Z,1338426642.859 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2012-05-31T01:10:42.859Z,1338426642.859 [BuoyancyServo](INFO): Powering down
2012-05-31T01:10:42.859Z,1338426642.859 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2012-05-31T01:10:42.859Z,1338426642.859 [ElevatorServo](INFO): Powering down
2012-05-31T01:10:42.860Z,1338426642.860 [MassServo](DEBUG): Uninitialize Mass Servo.
2012-05-31T01:10:42.860Z,1338426642.860 [MassServo](INFO): Powering down
2012-05-31T01:10:42.860Z,1338426642.860 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2012-05-31T01:10:42.860Z,1338426642.860 [RudderServo](INFO): Powering down
2012-05-31T01:10:42.861Z,1338426642.861 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2012-05-31T01:10:42.861Z,1338426642.861 [ThrusterServo](INFO): Powering down
2012-05-31T01:10:42.861Z,1338426642.861 [SBIT](DEBUG): Uninitialize SBIT Component.
2012-05-31T01:10:42.862Z,1338426642.862 [IBIT](DEBUG): Uninitialize IBIT Component.
2012-05-31T01:10:42.862Z,1338426642.862 [CBIT](DEBUG): Uninitialize CBIT Component.