2013-08-28T20:21:30.049Z,1377721290.049 [CommandLine](IMPORTANT): got command restart logs
2013-08-28T20:21:40.040Z,1377721300.040 [NAL9602](INFO): SBD MO Status=1, MOMSN=14799, MT Status=0, MTMSN=0
2013-08-28T20:21:40.089Z,1377721300.089 [NAL9602](INFO): Sent 25 bytes from file Logs/20130827T234051/Courier0168.lzma
2013-08-28T20:21:40.090Z,1377721300.089 [NAL9602](INFO): Packets left to send: 0
2013-08-28T20:21:40.092Z,1377721300.092 [NAL9602](INFO): Stored copy of sent data in Logs/20130827T234051/Courier0168.lzma.parts/0000.sbd
2013-08-28T20:21:49.899Z,1377721309.899 [NAL9602](INFO): SBD MO Status=1, MOMSN=14800, MT Status=0, MTMSN=0
2013-08-28T20:21:49.953Z,1377721309.953 [NAL9602](INFO): Sent 125 bytes from file Logs/20130827T234051/Express0165.lzma
2013-08-28T20:21:49.953Z,1377721309.953 [NAL9602](INFO): Packets left to send: 0
2013-08-28T20:21:49.955Z,1377721309.955 [NAL9602](INFO): Stored copy of sent data in Logs/20130827T234051/Express0165.lzma.parts/0000.sbd
2013-08-28T20:21:56.160Z,1377721316.160 [NAL9602](INFO): SBD MO Status=1, MOMSN=14801, MT Status=0, MTMSN=0
2013-08-28T20:21:56.209Z,1377721316.209 [NAL9602](INFO): Sent 108 bytes from file Logs/20130827T234051/Express0169.lzma
2013-08-28T20:21:56.209Z,1377721316.209 [NAL9602](INFO): Packets left to send: 0
2013-08-28T20:21:56.212Z,1377721316.211 [NAL9602](INFO): Stored copy of sent data in Logs/20130827T234051/Express0169.lzma.parts/0000.sbd
2013-08-28T20:22:05.392Z,1377721325.392 [NAL9602](INFO): SBD MO Status=0, MOMSN=14802, MT Status=0, MTMSN=0
2013-08-28T20:22:06.633Z,1377721326.634 [NAL9602](IMPORTANT): GPS fix at: 1377721416.00
2013-08-28T20:22:06.670Z,1377721326.670 [Default:GPS:Read_GPS] Stopped
2013-08-28T20:22:06.671Z,1377721326.671 [Default:GPS:D] Running Loop=1
2013-08-28T20:22:07.062Z,1377721327.062 [Default:GPS:D] Stopped
2013-08-28T20:22:07.062Z,1377721327.062 [Default:GPS](INFO): Completed Default:GPS
2013-08-28T20:22:07.062Z,1377721327.062 [Default:GPS] Stopped
2013-08-28T20:22:07.063Z,1377721327.063 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-08-28T20:22:07.063Z,1377721327.063 [Default:GPS:A.SetSpeed] Stopped
2013-08-28T20:22:07.063Z,1377721327.063 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-08-28T20:22:23.732Z,1377721343.732 [NAL9602](INFO): SBD MO Status=1, MOMSN=14803, MT Status=0, MTMSN=0
2013-08-28T20:22:23.797Z,1377721343.797 [NAL9602](INFO): Sent 185 bytes from file Logs/20130828T202130/Courier0000.lzma
2013-08-28T20:22:23.798Z,1377721343.797 [NAL9602](INFO): Packets left to send: 0
2013-08-28T20:22:23.799Z,1377721343.799 [NAL9602](INFO): Stored copy of sent data in Logs/20130828T202130/Courier0000.lzma.parts/0000.sbd
2013-08-28T20:22:34.291Z,1377721354.291 [NAL9602](INFO): SBD MO Status=1, MOMSN=14804, MT Status=0, MTMSN=0
2013-08-28T20:22:34.341Z,1377721354.341 [NAL9602](INFO): Sent 254 bytes from file Logs/20130828T202130/Express0001.lzma
2013-08-28T20:22:34.342Z,1377721354.342 [NAL9602](INFO): Packets left to send: 0
2013-08-28T20:22:34.343Z,1377721354.343 [NAL9602](INFO): Stored copy of sent data in Logs/20130828T202130/Express0001.lzma.parts/0000.sbd
2013-08-28T20:22:44.728Z,1377721364.728 [NAL9602](INFO): SBD MO Status=0, MOMSN=14805, MT Status=0, MTMSN=0
2013-08-28T20:22:48.353Z,1377721368.353 [Default:Iridium:Read_Iridium] Stopped
2013-08-28T20:22:48.354Z,1377721368.354 [Default:Iridium](INFO): Completed Default:Iridium
2013-08-28T20:22:48.354Z,1377721368.354 [Default:Iridium] Stopped
2013-08-28T20:22:48.354Z,1377721368.354 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-08-28T20:22:48.354Z,1377721368.354 [Default:Iridium:A.SetSpeed] Stopped
2013-08-28T20:22:48.354Z,1377721368.354 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-08-28T20:22:48.674Z,1377721368.674 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-08-28T20:22:48.674Z,1377721368.674 [Default:CallIridium:B] Stopped
2013-08-28T20:22:48.674Z,1377721368.674 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-08-28T20:22:48.675Z,1377721368.675 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-08-28T20:22:48.675Z,1377721368.675 [Default:CallIridium] Stopped
2013-08-28T20:22:48.675Z,1377721368.675 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-08-28T20:22:58.710Z,1377721378.710 [NAL9602](INFO): Powering down
2013-08-28T20:27:48.787Z,1377721668.787 [Default:CallIridium] Running Loop=1
2013-08-28T20:27:48.787Z,1377721668.787 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-08-28T20:27:48.787Z,1377721668.787 [Default:CallIridium:A] Running Loop=1
2013-08-28T20:27:48.788Z,1377721668.787 [Default:CallIridium:A] Stopped
2013-08-28T20:27:48.788Z,1377721668.788 [Default:CallIridium:B] Running Loop=1
2013-08-28T20:27:48.788Z,1377721668.788 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-08-28T20:27:53.706Z,1377721673.706 [Default:Iridium] Running Loop=1
2013-08-28T20:27:53.706Z,1377721673.706 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-08-28T20:27:53.706Z,1377721673.706 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-08-28T20:27:53.706Z,1377721673.706 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-08-28T20:27:53.706Z,1377721673.706 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-08-28T20:27:53.706Z,1377721673.706 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-08-28T20:27:53.707Z,1377721673.707 [Default:Iridium:B.GoToSurface] Stopped
2013-08-28T20:27:53.707Z,1377721673.707 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-08-28T20:27:53.707Z,1377721673.707 [Default:Iridium:Read_Iridium] Running Loop=1
2013-08-28T20:27:53.708Z,1377721673.708 [Default:GPS] Running Loop=1
2013-08-28T20:27:53.708Z,1377721673.708 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-08-28T20:27:53.708Z,1377721673.708 [Default:GPS:A.SetSpeed] Running Loop=1
2013-08-28T20:27:53.708Z,1377721673.708 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-08-28T20:27:53.708Z,1377721673.708 [Default:GPS:B.GoToSurface] Running Loop=1
2013-08-28T20:27:53.708Z,1377721673.708 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-08-28T20:27:53.709Z,1377721673.709 [Default:GPS:B.GoToSurface] Stopped
2013-08-28T20:27:53.709Z,1377721673.709 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-08-28T20:27:53.709Z,1377721673.709 [Default:GPS:Read_GPS] Running Loop=1
2013-08-28T20:27:54.358Z,1377721674.358 [NAL9602](INFO): Powering up
2013-08-28T20:28:05.189Z,1377721685.189 [NAL9602](INFO): NAL9602 initialized
2013-08-28T20:28:22.124Z,1377721702.124 [NAL9602](INFO): SBD MO Status=1, MOMSN=14806, MT Status=0, MTMSN=0
2013-08-28T20:28:22.177Z,1377721702.177 [NAL9602](INFO): Sent 224 bytes from file Logs/20130828T202130/Courier0004.lzma
2013-08-28T20:28:22.177Z,1377721702.178 [NAL9602](INFO): Packets left to send: 0
2013-08-28T20:28:22.179Z,1377721702.179 [NAL9602](INFO): Stored copy of sent data in Logs/20130828T202130/Courier0004.lzma.parts/0000.sbd
2013-08-28T20:28:46.832Z,1377721726.832 [NAL9602](INFO): SBD MO Status=2, MOMSN=14807, MT Status=2, MTMSN=0
2013-08-28T20:28:46.833Z,1377721726.833 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-08-28T20:29:29.760Z,1377721769.760 [NAL9602](INFO): SBD MO Status=2, MOMSN=14807, MT Status=0, MTMSN=0
2013-08-28T20:29:29.760Z,1377721769.760 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-08-28T20:29:30.958Z,1377721770.958 [NAL9602](IMPORTANT): GPS fix at: 1377721861.00
2013-08-28T20:29:30.975Z,1377721770.975 [Default:GPS:Read_GPS] Stopped
2013-08-28T20:29:30.975Z,1377721770.975 [Default:GPS:D] Running Loop=1
2013-08-28T20:29:31.395Z,1377721771.395 [Default:GPS:D] Stopped
2013-08-28T20:29:31.395Z,1377721771.395 [Default:GPS](INFO): Completed Default:GPS
2013-08-28T20:29:31.395Z,1377721771.395 [Default:GPS] Stopped
2013-08-28T20:29:31.395Z,1377721771.395 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-08-28T20:29:31.395Z,1377721771.395 [Default:GPS:A.SetSpeed] Stopped
2013-08-28T20:29:31.396Z,1377721771.395 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-08-28T20:29:47.536Z,1377721787.536 [NAL9602](INFO): SBD MO Status=2, MOMSN=14807, MT Status=2, MTMSN=0
2013-08-28T20:29:47.536Z,1377721787.536 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-08-28T20:30:13.668Z,1377721813.668 [NAL9602](INFO): SBD MO Status=2, MOMSN=14807, MT Status=2, MTMSN=0
2013-08-28T20:30:13.668Z,1377721813.668 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-08-28T20:30:36.627Z,1377721836.627 [NAL9602](INFO): SBD MO Status=1, MOMSN=14807, MT Status=0, MTMSN=0
2013-08-28T20:30:36.677Z,1377721836.677 [NAL9602](INFO): Sent 269 bytes from file Logs/20130828T202130/Express0005.lzma
2013-08-28T20:30:36.678Z,1377721836.678 [NAL9602](INFO): Packets left to send: 0
2013-08-28T20:30:36.679Z,1377721836.679 [NAL9602](INFO): Stored copy of sent data in Logs/20130828T202130/Express0005.lzma.parts/0000.sbd
2013-08-28T20:31:42.221Z,1377721902.221 [NAL9602](ERROR): Verify xmit timeout failure.
2013-08-28T20:31:56.224Z,1377721916.224 [NAL9602](INFO): SBD MO Status=0, MOMSN=14808, MT Status=0, MTMSN=0
2013-08-28T20:31:59.928Z,1377721919.928 [Default:Iridium:Read_Iridium] Stopped
2013-08-28T20:31:59.929Z,1377721919.929 [Default:Iridium](INFO): Completed Default:Iridium
2013-08-28T20:31:59.929Z,1377721919.929 [Default:Iridium] Stopped
2013-08-28T20:31:59.929Z,1377721919.929 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-08-28T20:31:59.929Z,1377721919.929 [Default:Iridium:A.SetSpeed] Stopped
2013-08-28T20:31:59.929Z,1377721919.929 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-08-28T20:32:00.298Z,1377721920.298 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-08-28T20:32:00.298Z,1377721920.298 [Default:CallIridium:B] Stopped
2013-08-28T20:32:00.298Z,1377721920.298 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-08-28T20:32:00.298Z,1377721920.298 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-08-28T20:32:00.298Z,1377721920.298 [Default:CallIridium] Stopped
2013-08-28T20:32:00.298Z,1377721920.298 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-08-28T20:32:10.306Z,1377721930.306 [NAL9602](INFO): Powering down
2013-08-28T20:37:00.306Z,1377722220.306 [Default:CallIridium] Running Loop=1
2013-08-28T20:37:00.307Z,1377722220.307 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-08-28T20:37:00.307Z,1377722220.307 [Default:CallIridium:A] Running Loop=1
2013-08-28T20:37:00.307Z,1377722220.307 [Default:CallIridium:A] Stopped
2013-08-28T20:37:00.307Z,1377722220.307 [Default:CallIridium:B] Running Loop=1
2013-08-28T20:37:00.308Z,1377722220.308 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-08-28T20:37:05.327Z,1377722225.327 [Default:Iridium] Running Loop=1
2013-08-28T20:37:05.327Z,1377722225.327 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-08-28T20:37:05.327Z,1377722225.327 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-08-28T20:37:05.327Z,1377722225.327 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-08-28T20:37:05.327Z,1377722225.327 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-08-28T20:37:05.327Z,1377722225.327 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-08-28T20:37:05.328Z,1377722225.328 [Default:Iridium:B.GoToSurface] Stopped
2013-08-28T20:37:05.328Z,1377722225.328 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-08-28T20:37:05.328Z,1377722225.328 [Default:Iridium:Read_Iridium] Running Loop=1
2013-08-28T20:37:05.329Z,1377722225.329 [Default:GPS] Running Loop=1
2013-08-28T20:37:05.329Z,1377722225.329 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-08-28T20:37:05.329Z,1377722225.329 [Default:GPS:A.SetSpeed] Running Loop=1
2013-08-28T20:37:05.329Z,1377722225.329 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-08-28T20:37:05.329Z,1377722225.329 [Default:GPS:B.GoToSurface] Running Loop=1
2013-08-28T20:37:05.329Z,1377722225.329 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-08-28T20:37:05.330Z,1377722225.330 [Default:GPS:B.GoToSurface] Stopped
2013-08-28T20:37:05.330Z,1377722225.330 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-08-28T20:37:05.330Z,1377722225.330 [Default:GPS:Read_GPS] Running Loop=1
2013-08-28T20:37:05.925Z,1377722225.925 [NAL9602](INFO): Powering up
2013-08-28T20:37:16.361Z,1377722236.361 [NAL9602](INFO): NAL9602 initialized
2013-08-28T20:37:28.161Z,1377722248.161 [NAL9602](INFO): SBD MO Status=1, MOMSN=14809, MT Status=0, MTMSN=0
2013-08-28T20:37:28.209Z,1377722248.209 [NAL9602](INFO): Sent 81 bytes from file Logs/20130828T202130/Courier0008.lzma
2013-08-28T20:37:28.209Z,1377722248.209 [NAL9602](INFO): Packets left to send: 0
2013-08-28T20:37:28.211Z,1377722248.211 [NAL9602](INFO): Stored copy of sent data in Logs/20130828T202130/Courier0008.lzma.parts/0000.sbd
2013-08-28T20:37:37.956Z,1377722257.956 [NAL9602](INFO): SBD MO Status=1, MOMSN=14810, MT Status=0, MTMSN=0
2013-08-28T20:37:38.021Z,1377722258.021 [NAL9602](INFO): Sent 132 bytes from file Logs/20130828T202130/Express0009.lzma
2013-08-28T20:37:38.022Z,1377722258.022 [NAL9602](INFO): Packets left to send: 0
2013-08-28T20:37:38.023Z,1377722258.023 [NAL9602](INFO): Stored copy of sent data in Logs/20130828T202130/Express0009.lzma.parts/0000.sbd
2013-08-28T20:37:45.560Z,1377722265.560 [NAL9602](INFO): SBD MO Status=0, MOMSN=14811, MT Status=0, MTMSN=0
2013-08-28T20:37:49.102Z,1377722269.102 [Default:Iridium:Read_Iridium] Stopped
2013-08-28T20:37:49.102Z,1377722269.102 [Default:Iridium](INFO): Completed Default:Iridium
2013-08-28T20:37:49.102Z,1377722269.102 [Default:Iridium] Stopped
2013-08-28T20:37:49.103Z,1377722269.103 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-08-28T20:37:49.103Z,1377722269.103 [Default:Iridium:A.SetSpeed] Stopped
2013-08-28T20:37:49.103Z,1377722269.103 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-08-28T20:37:49.480Z,1377722269.480 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-08-28T20:37:49.480Z,1377722269.480 [Default:CallIridium:B] Stopped
2013-08-28T20:37:49.480Z,1377722269.480 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-08-28T20:37:49.480Z,1377722269.480 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-08-28T20:37:49.480Z,1377722269.480 [Default:CallIridium] Stopped
2013-08-28T20:37:49.480Z,1377722269.480 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-08-28T20:37:50.130Z,1377722270.130 [NAL9602](IMPORTANT): GPS fix at: 1377722361.00
2013-08-28T20:37:50.159Z,1377722270.159 [Default:GPS:Read_GPS] Stopped
2013-08-28T20:37:50.160Z,1377722270.159 [Default:GPS:D] Running Loop=1
2013-08-28T20:37:50.537Z,1377722270.537 [Default:GPS:D] Stopped
2013-08-28T20:37:50.538Z,1377722270.537 [Default:GPS](INFO): Completed Default:GPS
2013-08-28T20:37:50.538Z,1377722270.538 [Default:GPS] Stopped
2013-08-28T20:37:50.538Z,1377722270.538 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-08-28T20:37:50.538Z,1377722270.538 [Default:GPS:A.SetSpeed] Stopped
2013-08-28T20:37:50.538Z,1377722270.538 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-08-28T20:38:11.087Z,1377722291.087 [NAL9602](INFO): Powering down
2013-08-28T20:41:36.110Z,1377722496.110 [Depth_Keller](ERROR): Pressure reading out of range: 1830.481201 decibar
2013-08-28T20:41:56.094Z,1377722516.095 [Depth_Keller](ERROR): Pressure reading out of range: 1830.509155 decibar
2013-08-28T20:42:06.086Z,1377722526.086 [Depth_Keller](ERROR): Pressure reading out of range: 1830.475952 decibar
2013-08-28T20:42:16.086Z,1377722536.086 [Depth_Keller](ERROR): Pressure reading out of range: 1830.499512 decibar
2013-08-28T20:42:26.078Z,1377722546.078 [Depth_Keller](ERROR): Pressure reading out of range: 1830.509155 decibar
2013-08-28T20:42:36.064Z,1377722556.064 [Depth_Keller](ERROR): Pressure reading out of range: 1830.534424 decibar
2013-08-28T20:42:46.064Z,1377722566.063 [Depth_Keller](ERROR): Pressure reading out of range: 1830.509155 decibar
2013-08-28T20:42:46.080Z,1377722566.080 [Default:CallIridium] Running Loop=1
2013-08-28T20:42:46.080Z,1377722566.080 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-08-28T20:42:46.080Z,1377722566.080 [Default:CallIridium:A] Running Loop=1
2013-08-28T20:42:46.081Z,1377722566.081 [Default:CallIridium:A] Stopped
2013-08-28T20:42:46.081Z,1377722566.081 [Default:CallIridium:B] Running Loop=1
2013-08-28T20:42:46.081Z,1377722566.081 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-08-28T20:42:51.103Z,1377722571.103 [Default:Iridium] Running Loop=1
2013-08-28T20:42:51.103Z,1377722571.103 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-08-28T20:42:51.103Z,1377722571.103 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-08-28T20:42:51.103Z,1377722571.103 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-08-28T20:42:51.103Z,1377722571.103 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-08-28T20:42:51.103Z,1377722571.103 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-08-28T20:42:51.104Z,1377722571.104 [Default:Iridium:B.GoToSurface] Stopped
2013-08-28T20:42:51.104Z,1377722571.104 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-08-28T20:42:51.104Z,1377722571.104 [Default:Iridium:Read_Iridium] Running Loop=1
2013-08-28T20:42:51.105Z,1377722571.105 [Default:GPS] Running Loop=1
2013-08-28T20:42:51.105Z,1377722571.105 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-08-28T20:42:51.105Z,1377722571.105 [Default:GPS:A.SetSpeed] Running Loop=1
2013-08-28T20:42:51.105Z,1377722571.105 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-08-28T20:42:51.105Z,1377722571.105 [Default:GPS:B.GoToSurface] Running Loop=1
2013-08-28T20:42:51.105Z,1377722571.105 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-08-28T20:42:51.106Z,1377722571.106 [Default:GPS:B.GoToSurface] Stopped
2013-08-28T20:42:51.106Z,1377722571.106 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-08-28T20:42:51.106Z,1377722571.106 [Default:GPS:Read_GPS] Running Loop=1
2013-08-28T20:42:51.701Z,1377722571.701 [NAL9602](INFO): Powering up
2013-08-28T20:43:02.141Z,1377722582.141 [NAL9602](INFO): NAL9602 initialized
2013-08-28T20:43:23.128Z,1377722603.128 [NAL9602](INFO): SBD MO Status=2, MOMSN=14812, MT Status=2, MTMSN=0
2013-08-28T20:43:23.128Z,1377722603.128 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-08-28T20:43:29.455Z,1377722609.455 [NAL9602](IMPORTANT): GPS fix at: 1377722701.00
2013-08-28T20:43:29.471Z,1377722609.471 [Default:GPS:Read_GPS] Stopped
2013-08-28T20:43:29.473Z,1377722609.473 [Default:GPS:D] Running Loop=1
2013-08-28T20:43:29.923Z,1377722609.923 [Default:GPS:D] Stopped
2013-08-28T20:43:29.923Z,1377722609.923 [Default:GPS](INFO): Completed Default:GPS
2013-08-28T20:43:29.924Z,1377722609.924 [Default:GPS] Stopped
2013-08-28T20:43:29.924Z,1377722609.924 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-08-28T20:43:29.924Z,1377722609.924 [Default:GPS:A.SetSpeed] Stopped
2013-08-28T20:43:29.924Z,1377722609.924 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-08-28T20:43:47.668Z,1377722627.668 [NAL9602](INFO): SBD MO Status=1, MOMSN=14812, MT Status=0, MTMSN=0
2013-08-28T20:43:47.717Z,1377722627.717 [NAL9602](INFO): Sent 78 bytes from file Logs/20130828T202130/Courier0012.lzma
2013-08-28T20:43:47.717Z,1377722627.717 [NAL9602](INFO): Packets left to send: 0
2013-08-28T20:43:47.719Z,1377722627.719 [NAL9602](INFO): Stored copy of sent data in Logs/20130828T202130/Courier0012.lzma.parts/0000.sbd
2013-08-28T20:43:58.308Z,1377722638.308 [NAL9602](INFO): SBD MO Status=1, MOMSN=14813, MT Status=0, MTMSN=0
2013-08-28T20:43:58.357Z,1377722638.357 [NAL9602](INFO): Sent 112 bytes from file Logs/20130828T202130/Express0013.lzma
2013-08-28T20:43:58.357Z,1377722638.358 [NAL9602](INFO): Packets left to send: 0
2013-08-28T20:43:58.359Z,1377722638.359 [NAL9602](INFO): Stored copy of sent data in Logs/20130828T202130/Express0013.lzma.parts/0000.sbd
2013-08-28T20:44:18.708Z,1377722658.708 [NAL9602](INFO): SBD MO Status=0, MOMSN=14814, MT Status=0, MTMSN=0
2013-08-28T20:44:22.312Z,1377722662.312 [Default:Iridium:Read_Iridium] Stopped
2013-08-28T20:44:22.313Z,1377722662.313 [Default:Iridium](INFO): Completed Default:Iridium
2013-08-28T20:44:22.313Z,1377722662.313 [Default:Iridium] Stopped
2013-08-28T20:44:22.313Z,1377722662.313 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-08-28T20:44:22.313Z,1377722662.313 [Default:Iridium:A.SetSpeed] Stopped
2013-08-28T20:44:22.313Z,1377722662.313 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-08-28T20:44:22.715Z,1377722662.715 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-08-28T20:44:22.715Z,1377722662.715 [Default:CallIridium:B] Stopped
2013-08-28T20:44:22.715Z,1377722662.715 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-08-28T20:44:22.715Z,1377722662.715 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-08-28T20:44:22.715Z,1377722662.715 [Default:CallIridium] Stopped
2013-08-28T20:44:22.715Z,1377722662.715 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-08-28T20:44:32.691Z,1377722672.691 [NAL9602](INFO): Powering down
2013-08-28T20:44:52.730Z,1377722692.730 [Depth_Keller](ERROR): Pressure reading out of range: 1830.485474 decibar
2013-08-28T20:45:02.654Z,1377722702.654 [Depth_Keller](ERROR): Pressure reading out of range: -850.721863 decibar
2013-08-28T20:45:22.658Z,1377722722.658 [Depth_Keller](ERROR): Pressure reading out of range: 1830.525635 decibar
2013-08-28T20:45:42.702Z,1377722742.703 [Depth_Keller](ERROR): Pressure reading out of range: 1830.490723 decibar
2013-08-28T20:45:52.675Z,1377722752.675 [Depth_Keller](ERROR): Pressure reading out of range: 1830.504761 decibar
2013-08-28T20:46:02.663Z,1377722762.663 [Depth_Keller](ERROR): Pressure reading out of range: 1830.516113 decibar
2013-08-28T20:46:12.675Z,1377722772.675 [Depth_Keller](ERROR): Pressure reading out of range: 1830.492554 decibar
2013-08-28T20:46:22.663Z,1377722782.663 [Depth_Keller](ERROR): Pressure reading out of range: 1830.500366 decibar
2013-08-28T20:46:32.690Z,1377722792.690 [Depth_Keller](ERROR): Pressure reading out of range: 1830.505615 decibar
2013-08-28T20:46:42.695Z,1377722802.694 [Depth_Keller](ERROR): Pressure reading out of range: 1830.494263 decibar
2013-08-28T20:46:52.694Z,1377722812.694 [Depth_Keller](ERROR): Pressure reading out of range: 1830.492554 decibar
2013-08-28T20:47:02.695Z,1377722822.694 [Depth_Keller](ERROR): Pressure reading out of range: 1830.497803 decibar
2013-08-28T20:47:12.691Z,1377722832.691 [Depth_Keller](ERROR): Pressure reading out of range: 1830.489868 decibar
2013-08-28T20:47:22.691Z,1377722842.691 [Depth_Keller](ERROR): Pressure reading out of range: 1830.502075 decibar
2013-08-28T20:47:42.658Z,1377722862.658 [Depth_Keller](ERROR): Pressure reading out of range: 1830.440186 decibar
2013-08-28T20:47:52.663Z,1377722872.663 [Depth_Keller](ERROR): Pressure reading out of range: 1830.483765 decibar
2013-08-28T20:48:02.663Z,1377722882.663 [Depth_Keller](ERROR): Pressure reading out of range: 1830.535278 decibar
2013-08-28T20:48:12.663Z,1377722892.663 [Depth_Keller](ERROR): Pressure reading out of range: 1830.493408 decibar
2013-08-28T20:48:32.613Z,1377722912.614 [Depth_Keller](ERROR): Pressure reading out of range: 1830.513428 decibar
2013-08-28T20:48:42.634Z,1377722922.634 [Depth_Keller](ERROR): Pressure reading out of range: 1830.505615 decibar
2013-08-28T20:49:22.710Z,1377722962.710 [Default:CallIridium] Running Loop=1
2013-08-28T20:49:22.711Z,1377722962.711 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-08-28T20:49:22.711Z,1377722962.711 [Default:CallIridium:A] Running Loop=1
2013-08-28T20:49:22.711Z,1377722962.711 [Default:CallIridium:A] Stopped
2013-08-28T20:49:22.711Z,1377722962.711 [Default:CallIridium:B] Running Loop=1
2013-08-28T20:49:22.712Z,1377722962.712 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-08-28T20:49:27.695Z,1377722967.695 [Default:Iridium] Running Loop=1
2013-08-28T20:49:27.695Z,1377722967.695 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-08-28T20:49:27.695Z,1377722967.695 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-08-28T20:49:27.695Z,1377722967.695 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-08-28T20:49:27.695Z,1377722967.695 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-08-28T20:49:27.695Z,1377722967.695 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-08-28T20:49:27.696Z,1377722967.696 [Default:Iridium:B.GoToSurface] Stopped
2013-08-28T20:49:27.696Z,1377722967.696 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-08-28T20:49:27.696Z,1377722967.696 [Default:Iridium:Read_Iridium] Running Loop=1
2013-08-28T20:49:27.697Z,1377722967.697 [Default:GPS] Running Loop=1
2013-08-28T20:49:27.697Z,1377722967.697 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-08-28T20:49:27.697Z,1377722967.697 [Default:GPS:A.SetSpeed] Running Loop=1
2013-08-28T20:49:27.697Z,1377722967.697 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-08-28T20:49:27.697Z,1377722967.697 [Default:GPS:B.GoToSurface] Running Loop=1
2013-08-28T20:49:27.697Z,1377722967.697 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-08-28T20:49:27.698Z,1377722967.698 [Default:GPS:B.GoToSurface] Stopped
2013-08-28T20:49:27.698Z,1377722967.698 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-08-28T20:49:27.698Z,1377722967.698 [Default:GPS:Read_GPS] Running Loop=1
2013-08-28T20:49:28.310Z,1377722968.310 [NAL9602](INFO): Powering up
2013-08-28T20:49:39.141Z,1377722979.141 [NAL9602](INFO): NAL9602 initialized
2013-08-28T20:49:56.892Z,1377722996.892 [NAL9602](INFO): SBD MO Status=1, MOMSN=14815, MT Status=0, MTMSN=0
2013-08-28T20:49:56.941Z,1377722996.941 [NAL9602](INFO): Sent 78 bytes from file Logs/20130828T202130/Courier0016.lzma
2013-08-28T20:49:56.941Z,1377722996.941 [NAL9602](INFO): Packets left to send: 0
2013-08-28T20:49:56.943Z,1377722996.943 [NAL9602](INFO): Stored copy of sent data in Logs/20130828T202130/Courier0016.lzma.parts/0000.sbd
2013-08-28T20:50:17.956Z,1377723017.957 [NAL9602](INFO): SBD MO Status=1, MOMSN=14816, MT Status=0, MTMSN=0
2013-08-28T20:50:18.005Z,1377723018.005 [NAL9602](INFO): Sent 96 bytes from file Logs/20130828T202130/Express0017.lzma
2013-08-28T20:50:18.005Z,1377723018.006 [NAL9602](INFO): Packets left to send: 0
2013-08-28T20:50:18.007Z,1377723018.007 [NAL9602](INFO): Stored copy of sent data in Logs/20130828T202130/Express0017.lzma.parts/0000.sbd
2013-08-28T20:50:30.332Z,1377723030.332 [NAL9602](INFO): SBD MO Status=0, MOMSN=14817, MT Status=0, MTMSN=0
2013-08-28T20:50:33.995Z,1377723033.995 [Default:Iridium:Read_Iridium] Stopped
2013-08-28T20:50:33.995Z,1377723033.995 [Default:Iridium](INFO): Completed Default:Iridium
2013-08-28T20:50:33.995Z,1377723033.995 [Default:Iridium] Stopped
2013-08-28T20:50:33.996Z,1377723033.996 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-08-28T20:50:33.996Z,1377723033.996 [Default:Iridium:A.SetSpeed] Stopped
2013-08-28T20:50:33.996Z,1377723033.996 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-08-28T20:50:34.342Z,1377723034.342 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-08-28T20:50:34.342Z,1377723034.342 [Default:CallIridium:B] Stopped
2013-08-28T20:50:34.343Z,1377723034.342 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-08-28T20:50:34.343Z,1377723034.343 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-08-28T20:50:34.343Z,1377723034.343 [Default:CallIridium] Stopped
2013-08-28T20:50:34.343Z,1377723034.343 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-08-28T20:50:52.598Z,1377723052.598 [NAL9602](IMPORTANT): GPS fix at: 1377723145.00
2013-08-28T20:50:52.614Z,1377723052.614 [Default:GPS:Read_GPS] Stopped
2013-08-28T20:50:52.614Z,1377723052.614 [Default:GPS:D] Running Loop=1
2013-08-28T20:50:53.025Z,1377723053.025 [Default:GPS:D] Stopped
2013-08-28T20:50:53.025Z,1377723053.026 [Default:GPS](INFO): Completed Default:GPS
2013-08-28T20:50:53.026Z,1377723053.026 [Default:GPS] Stopped
2013-08-28T20:50:53.026Z,1377723053.026 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-08-28T20:50:53.026Z,1377723053.026 [Default:GPS:A.SetSpeed] Stopped
2013-08-28T20:50:53.026Z,1377723053.026 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-08-28T20:51:13.607Z,1377723073.607 [NAL9602](INFO): Powering down
2013-08-28T20:55:33.579Z,1377723333.579 [Default:CallIridium] Running Loop=1
2013-08-28T20:55:33.579Z,1377723333.579 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-08-28T20:55:33.579Z,1377723333.579 [Default:CallIridium:A] Running Loop=1
2013-08-28T20:55:33.580Z,1377723333.580 [Default:CallIridium:A] Stopped
2013-08-28T20:55:33.580Z,1377723333.580 [Default:CallIridium:B] Running Loop=1
2013-08-28T20:55:33.580Z,1377723333.580 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-08-28T20:55:38.591Z,1377723338.591 [Default:Iridium] Running Loop=1
2013-08-28T20:55:38.591Z,1377723338.591 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-08-28T20:55:38.591Z,1377723338.591 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-08-28T20:55:38.591Z,1377723338.591 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-08-28T20:55:38.591Z,1377723338.591 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-08-28T20:55:38.591Z,1377723338.591 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-08-28T20:55:38.592Z,1377723338.592 [Default:Iridium:B.GoToSurface] Stopped
2013-08-28T20:55:38.592Z,1377723338.592 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-08-28T20:55:38.592Z,1377723338.592 [Default:Iridium:Read_Iridium] Running Loop=1
2013-08-28T20:55:38.593Z,1377723338.593 [Default:GPS] Running Loop=1
2013-08-28T20:55:38.593Z,1377723338.593 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-08-28T20:55:38.593Z,1377723338.593 [Default:GPS:A.SetSpeed] Running Loop=1
2013-08-28T20:55:38.593Z,1377723338.593 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-08-28T20:55:38.593Z,1377723338.593 [Default:GPS:B.GoToSurface] Running Loop=1
2013-08-28T20:55:38.593Z,1377723338.593 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-08-28T20:55:38.594Z,1377723338.594 [Default:GPS:B.GoToSurface] Stopped
2013-08-28T20:55:38.594Z,1377723338.594 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-08-28T20:55:38.594Z,1377723338.594 [Default:GPS:Read_GPS] Running Loop=1
2013-08-28T20:55:39.200Z,1377723339.200 [NAL9602](INFO): Powering up
2013-08-28T20:55:50.037Z,1377723350.037 [NAL9602](INFO): NAL9602 initialized
2013-08-28T20:58:21.658Z,1377723501.658 [NAL9602](IMPORTANT): GPS fix at: 1377723594.00
2013-08-28T20:58:21.674Z,1377723501.674 [Default:GPS:Read_GPS] Stopped
2013-08-28T20:58:21.674Z,1377723501.674 [Default:GPS:D] Running Loop=1
2013-08-28T20:58:22.085Z,1377723502.085 [Default:GPS:D] Stopped
2013-08-28T20:58:22.085Z,1377723502.085 [Default:GPS](INFO): Completed Default:GPS
2013-08-28T20:58:22.086Z,1377723502.086 [Default:GPS] Stopped
2013-08-28T20:58:22.086Z,1377723502.086 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-08-28T20:58:22.086Z,1377723502.086 [Default:GPS:A.SetSpeed] Stopped
2013-08-28T20:58:22.086Z,1377723502.086 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-08-28T20:58:58.515Z,1377723538.515 [NAL9602](INFO): SBD MO Status=2, MOMSN=14818, MT Status=2, MTMSN=0
2013-08-28T20:58:58.515Z,1377723538.515 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-08-28T21:00:07.858Z,1377723607.858 [NAL9602](INFO): SBD MO Status=2, MOMSN=14818, MT Status=2, MTMSN=0
2013-08-28T21:00:07.858Z,1377723607.858 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-08-28T21:00:24.868Z,1377723624.868 [NAL9602](INFO): SBD MO Status=2, MOMSN=14818, MT Status=2, MTMSN=0
2013-08-28T21:00:24.868Z,1377723624.868 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-08-28T21:00:52.204Z,1377723652.204 [NAL9602](INFO): SBD MO Status=2, MOMSN=14818, MT Status=2, MTMSN=0
2013-08-28T21:00:52.204Z,1377723652.204 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-08-28T21:01:18.428Z,1377723678.428 [NAL9602](INFO): SBD MO Status=2, MOMSN=14818, MT Status=2, MTMSN=0
2013-08-28T21:01:18.428Z,1377723678.428 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-08-28T21:01:46.472Z,1377723706.472 [NAL9602](INFO): SBD MO Status=1, MOMSN=14818, MT Status=0, MTMSN=0
2013-08-28T21:01:46.521Z,1377723706.521 [NAL9602](INFO): Sent 79 bytes from file Logs/20130828T202130/Courier0020.lzma
2013-08-28T21:01:46.522Z,1377723706.522 [NAL9602](INFO): Packets left to send: 0
2013-08-28T21:01:46.523Z,1377723706.523 [NAL9602](INFO): Stored copy of sent data in Logs/20130828T202130/Courier0020.lzma.parts/0000.sbd
2013-08-28T21:02:01.512Z,1377723721.512 [NAL9602](INFO): SBD MO Status=1, MOMSN=14819, MT Status=0, MTMSN=0
2013-08-28T21:02:01.565Z,1377723721.565 [NAL9602](INFO): Sent 119 bytes from file Logs/20130828T202130/Express0021.lzma
2013-08-28T21:02:01.566Z,1377723721.566 [NAL9602](INFO): Packets left to send: 0
2013-08-28T21:02:01.567Z,1377723721.567 [NAL9602](INFO): Stored copy of sent data in Logs/20130828T202130/Express0021.lzma.parts/0000.sbd
2013-08-28T21:02:10.707Z,1377723730.707 [NAL9602](INFO): SBD MO Status=2, MOMSN=14820, MT Status=2, MTMSN=0
2013-08-28T21:02:10.707Z,1377723730.707 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-08-28T21:02:15.905Z,1377723735.905 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-08-28T21:02:18.231Z,1377723738.231 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-08-28T21:02:22.567Z,1377723742.567 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-08-28T21:02:32.915Z,1377723752.915 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-08-28T21:02:46.360Z,1377723766.360 [NAL9602](INFO): SBD MO Status=0, MOMSN=14820, MT Status=0, MTMSN=0
2013-08-28T21:02:49.900Z,1377723769.900 [Default:Iridium:Read_Iridium] Stopped
2013-08-28T21:02:49.900Z,1377723769.901 [Default:Iridium](INFO): Completed Default:Iridium
2013-08-28T21:02:49.901Z,1377723769.901 [Default:Iridium] Stopped
2013-08-28T21:02:49.901Z,1377723769.901 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-08-28T21:02:49.901Z,1377723769.901 [Default:Iridium:A.SetSpeed] Stopped
2013-08-28T21:02:49.901Z,1377723769.901 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-08-28T21:02:50.227Z,1377723770.227 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-08-28T21:02:50.227Z,1377723770.227 [Default:CallIridium:B] Stopped
2013-08-28T21:02:50.227Z,1377723770.227 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-08-28T21:02:50.227Z,1377723770.227 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-08-28T21:02:50.227Z,1377723770.227 [Default:CallIridium] Stopped
2013-08-28T21:02:50.227Z,1377723770.227 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-08-28T21:03:00.242Z,1377723780.242 [NAL9602](INFO): Powering down
2013-08-28T21:07:50.302Z,1377724070.302 [Default:CallIridium] Running Loop=1
2013-08-28T21:07:50.303Z,1377724070.303 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-08-28T21:07:50.303Z,1377724070.303 [Default:CallIridium:A] Running Loop=1
2013-08-28T21:07:50.303Z,1377724070.303 [Default:CallIridium:A] Stopped
2013-08-28T21:07:50.303Z,1377724070.303 [Default:CallIridium:B] Running Loop=1
2013-08-28T21:07:50.304Z,1377724070.304 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-08-28T21:07:55.280Z,1377724075.280 [Default:Iridium] Running Loop=1
2013-08-28T21:07:55.280Z,1377724075.280 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-08-28T21:07:55.281Z,1377724075.281 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-08-28T21:07:55.281Z,1377724075.281 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-08-28T21:07:55.281Z,1377724075.281 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-08-28T21:07:55.281Z,1377724075.281 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-08-28T21:07:55.282Z,1377724075.282 [Default:Iridium:B.GoToSurface] Stopped
2013-08-28T21:07:55.282Z,1377724075.282 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-08-28T21:07:55.282Z,1377724075.282 [Default:Iridium:Read_Iridium] Running Loop=1
2013-08-28T21:07:55.282Z,1377724075.282 [Default:GPS] Running Loop=1
2013-08-28T21:07:55.282Z,1377724075.283 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-08-28T21:07:55.283Z,1377724075.283 [Default:GPS:A.SetSpeed] Running Loop=1
2013-08-28T21:07:55.283Z,1377724075.283 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-08-28T21:07:55.283Z,1377724075.283 [Default:GPS:B.GoToSurface] Running Loop=1
2013-08-28T21:07:55.283Z,1377724075.283 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-08-28T21:07:55.284Z,1377724075.284 [Default:GPS:B.GoToSurface] Stopped
2013-08-28T21:07:55.284Z,1377724075.284 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-08-28T21:07:55.284Z,1377724075.284 [Default:GPS:Read_GPS] Running Loop=1
2013-08-28T21:07:55.902Z,1377724075.902 [NAL9602](INFO): Powering up
2013-08-28T21:08:06.341Z,1377724086.341 [NAL9602](INFO): NAL9602 initialized
2013-08-28T21:09:39.470Z,1377724179.470 [CBIT](IMPORTANT): Beginning GF scan
2013-08-28T21:09:40.072Z,1377724180.072 [Radio_Freewave](INFO): Powering down
2013-08-28T21:09:40.892Z,1377724180.892 [Radio_Freewave](INFO): Powering up
2013-08-28T21:18:09.471Z,1377724689.471 [NAL9602](FAULT): GPS failed to acquire within timeout.
2013-08-28T21:18:09.471Z,1377724689.471 [NAL9602] Data Fault, FailCount= 1
2013-08-28T21:18:09.471Z,1377724689.471 [NAL9602](ERROR): Data Fault
2013-08-28T21:18:09.497Z,1377724689.497 [CBIT](ERROR): Data Fault in component: NAL9602
2013-08-28T21:18:09.964Z,1377724689.964 [NAL9602](INFO): Powering down
2013-08-28T21:18:10.699Z,1377724690.699 [CBIT](INFO): Clearing failed state for component NAL9602
2013-08-28T21:18:10.699Z,1377724690.699 [NAL9602] No Fault, FailCount= 1
2013-08-28T21:18:15.065Z,1377724695.065 [NAL9602](INFO): Powering up NAL9602
2013-08-28T21:18:25.505Z,1377724705.505 [NAL9602](INFO): NAL9602 initialized
2013-08-28T21:28:29.559Z,1377725309.559 [NAL9602](FAULT): GPS failed to acquire within timeout.
2013-08-28T21:28:29.559Z,1377725309.559 [NAL9602] Data Fault, FailCount= 2
2013-08-28T21:28:29.559Z,1377725309.559 [NAL9602](ERROR): Data Fault
2013-08-28T21:28:29.586Z,1377725309.586 [CBIT](ERROR): Data Fault in component: NAL9602
2013-08-28T21:28:29.586Z,1377725309.586 [CBIT](CRITICAL): Data Fault in component: NAL9602
2013-08-28T21:28:29.934Z,1377725309.934 [NAL9602](INFO): Powering down
2013-08-28T21:30:29.960Z,1377725429.960 [CBIT](INFO): Clearing failed count for component NAL9602
2013-08-28T21:30:29.960Z,1377725429.960 [NAL9602] No Fault, FailCount= 2
2013-08-28T21:30:30.334Z,1377725430.334 [NAL9602](INFO): Powering up NAL9602
2013-08-28T21:30:41.222Z,1377725441.222 [NAL9602](INFO): NAL9602 initialized
2013-08-28T21:31:33.275Z,1377725493.275 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#3 STATUS: 65535
2013-08-28T21:31:37.613Z,1377725497.613 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#3 STATUS: 65535
2013-08-28T21:31:41.943Z,1377725501.943 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#3 STATUS: 65535
2013-08-28T21:31:46.262Z,1377725506.262 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#3 STATUS: 65535
2013-08-28T21:31:50.991Z,1377725510.991 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#3 STATUS: 65535
2013-08-28T21:31:55.310Z,1377725515.310 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#3 STATUS: 65535
2013-08-28T21:31:59.656Z,1377725519.656 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#3 STATUS: 65535
2013-08-28T21:32:03.968Z,137772