2012-10-24T16:10:00.603Z,1351095000.603 [CommandLine](IMPORTANT): got command restart logs
2012-10-24T16:10:53.702Z,1351095053.702 [NAL9602](INFO): SBD MO Status=1, MOMSN=3219, MT Status=0, MTMSN=0
2012-10-24T16:10:53.743Z,1351095053.743 [NAL9602](INFO): Sent 195 bytes from file Logs/20121023T192007/shore0065.lzma
2012-10-24T16:10:53.743Z,1351095053.743 [NAL9602](INFO): Packets left to send: 0
2012-10-24T16:10:53.746Z,1351095053.746 [NAL9602](INFO): Stored copy of sent data in Logs/20121023T192007/shore0065.lzma.parts/0000.sbd
2012-10-24T16:11:08.898Z,1351095068.898 [NAL9602](INFO): SBD MO Status=2, MOMSN=3220, MT Status=2, MTMSN=0
2012-10-24T16:11:08.898Z,1351095068.898 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2012-10-24T16:11:14.088Z,1351095074.088 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2012-10-24T16:11:17.956Z,1351095077.957 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2012-10-24T16:11:25.405Z,1351095085.405 [NAL9602](INFO): SBD MO Status=0, MOMSN=3220, MT Status=0, MTMSN=0
2012-10-24T16:11:53.038Z,1351095113.038 [NAL9602](INFO): SBD MO Status=1, MOMSN=3221, MT Status=0, MTMSN=0
2012-10-24T16:11:53.075Z,1351095113.075 [NAL9602](INFO): Sent 332 bytes from file Logs/20121024T161000/shore0000.lzma
2012-10-24T16:11:53.075Z,1351095113.075 [NAL9602](INFO): Packets left to send: 2
2012-10-24T16:11:53.076Z,1351095113.076 [NAL9602](INFO): Stored copy of sent data in Logs/20121024T161000/shore0000.lzma.parts/0002.sbd
2012-10-24T16:12:05.890Z,1351095125.890 [NAL9602](INFO): SBD MO Status=1, MOMSN=3222, MT Status=0, MTMSN=0
2012-10-24T16:12:05.927Z,1351095125.927 [NAL9602](INFO): Sent 332 bytes from file Logs/20121024T161000/shore0000.lzma
2012-10-24T16:12:05.927Z,1351095125.927 [NAL9602](INFO): Packets left to send: 1
2012-10-24T16:12:05.928Z,1351095125.928 [NAL9602](INFO): Stored copy of sent data in Logs/20121024T161000/shore0000.lzma.parts/0001.sbd
2012-10-24T16:12:17.297Z,1351095137.297 [NAL9602](INFO): SBD MO Status=1, MOMSN=3223, MT Status=0, MTMSN=0
2012-10-24T16:12:17.335Z,1351095137.335 [NAL9602](INFO): Sent 85 bytes from file Logs/20121024T161000/shore0000.lzma
2012-10-24T16:12:17.335Z,1351095137.335 [NAL9602](INFO): Packets left to send: 0
2012-10-24T16:12:17.336Z,1351095137.336 [NAL9602](INFO): Stored copy of sent data in Logs/20121024T161000/shore0000.lzma.parts/0000.sbd
2012-10-24T16:12:34.097Z,1351095154.097 [NAL9602](INFO): SBD MO Status=0, MOMSN=3224, MT Status=0, MTMSN=0
2012-10-24T16:12:34.157Z,1351095154.157 [Default:Iridium:Read_Iridium] Stopped
2012-10-24T16:12:34.158Z,1351095154.158 [Default:Iridium](INFO): Completed Default:Iridium
2012-10-24T16:12:34.158Z,1351095154.158 [Default:Iridium] Stopped
2012-10-24T16:12:34.158Z,1351095154.158 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-10-24T16:12:34.158Z,1351095154.158 [Default:Iridium:A.SetSpeed] Stopped
2012-10-24T16:12:34.158Z,1351095154.158 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-10-24T16:12:34.507Z,1351095154.507 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2012-10-24T16:12:34.507Z,1351095154.507 [Default:CallIridium:B] Stopped
2012-10-24T16:12:34.507Z,1351095154.507 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-10-24T16:12:34.507Z,1351095154.507 [Default:CallIridium](INFO): Completed Default:CallIridium
2012-10-24T16:12:34.507Z,1351095154.507 [Default:CallIridium] Stopped
2012-10-24T16:12:34.507Z,1351095154.507 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-10-24T16:12:37.706Z,1351095157.706 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-10-24T16:12:37.715Z,1351095157.715 [NAL9602](IMPORTANT): GPS fix at: 1351095296.00
2012-10-24T16:12:37.732Z,1351095157.732 [Default:GPS:Read_GPS] Stopped
2012-10-24T16:12:37.732Z,1351095157.732 [Default:GPS:D] Running Loop=1
2012-10-24T16:12:38.111Z,1351095158.111 [Default:GPS:D] Stopped
2012-10-24T16:12:38.111Z,1351095158.111 [Default:GPS](INFO): Completed Default:GPS
2012-10-24T16:12:38.111Z,1351095158.111 [Default:GPS] Stopped
2012-10-24T16:12:38.112Z,1351095158.112 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-10-24T16:12:38.112Z,1351095158.112 [Default:GPS:A.SetSpeed] Stopped
2012-10-24T16:12:38.112Z,1351095158.112 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-10-24T16:12:43.633Z,1351095163.633 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-10-24T16:12:48.633Z,1351095168.633 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-10-24T16:12:53.641Z,1351095173.641 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-10-24T16:12:58.637Z,1351095178.637 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-10-24T16:12:58.644Z,1351095178.644 [NAL9602](INFO): Powering down
2012-10-24T16:17:38.628Z,1351095458.628 [Default:CallIridium] Running Loop=1
2012-10-24T16:17:38.628Z,1351095458.628 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-10-24T16:17:38.629Z,1351095458.629 [Default:CallIridium:A] Running Loop=1
2012-10-24T16:17:38.629Z,1351095458.629 [Default:CallIridium:A] Stopped
2012-10-24T16:17:38.629Z,1351095458.629 [Default:CallIridium:B] Running Loop=1
2012-10-24T16:17:38.629Z,1351095458.629 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-10-24T16:17:43.663Z,1351095463.663 [Default:Iridium] Running Loop=1
2012-10-24T16:17:43.663Z,1351095463.663 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-10-24T16:17:43.663Z,1351095463.663 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-10-24T16:17:43.663Z,1351095463.663 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-10-24T16:17:43.663Z,1351095463.663 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-10-24T16:17:43.663Z,1351095463.663 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-24T16:17:43.664Z,1351095463.664 [Default:Iridium:B.GoToSurface] Stopped
2012-10-24T16:17:43.664Z,1351095463.664 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-24T16:17:43.664Z,1351095463.664 [Default:Iridium:Read_Iridium] Running Loop=1
2012-10-24T16:17:43.664Z,1351095463.664 [Default:GPS] Running Loop=1
2012-10-24T16:17:43.665Z,1351095463.665 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-10-24T16:17:43.665Z,1351095463.665 [Default:GPS:A.SetSpeed] Running Loop=1
2012-10-24T16:17:43.665Z,1351095463.665 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-10-24T16:17:43.665Z,1351095463.665 [Default:GPS:B.GoToSurface] Running Loop=1
2012-10-24T16:17:43.665Z,1351095463.665 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-24T16:17:43.666Z,1351095463.666 [Default:GPS:B.GoToSurface] Stopped
2012-10-24T16:17:43.666Z,1351095463.666 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-24T16:17:43.666Z,1351095463.666 [Default:GPS:Read_GPS] Running Loop=1
2012-10-24T16:17:44.312Z,1351095464.312 [NAL9602](INFO): Powering up
2012-10-24T16:17:55.131Z,1351095475.131 [NAL9602](INFO): NAL9602 initialized
2012-10-24T16:18:13.043Z,1351095493.043 [NAL9602](IMPORTANT): GPS fix at: 1351095631.99
2012-10-24T16:18:13.060Z,1351095493.060 [Default:GPS:Read_GPS] Stopped
2012-10-24T16:18:13.060Z,1351095493.060 [Default:GPS:D] Running Loop=1
2012-10-24T16:18:13.391Z,1351095493.391 [Default:GPS:D] Stopped
2012-10-24T16:18:13.392Z,1351095493.392 [Default:GPS](INFO): Completed Default:GPS
2012-10-24T16:18:13.392Z,1351095493.392 [Default:GPS] Stopped
2012-10-24T16:18:13.392Z,1351095493.392 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-10-24T16:18:13.392Z,1351095493.392 [Default:GPS:A.SetSpeed] Stopped
2012-10-24T16:18:13.392Z,1351095493.392 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-10-24T16:18:56.705Z,1351095536.705 [NAL9602](INFO): SBD MO Status=2, MOMSN=3225, MT Status=2, MTMSN=0
2012-10-24T16:18:56.705Z,1351095536.705 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2012-10-24T16:19:17.785Z,1351095557.786 [NAL9602](INFO): SBD MO Status=1, MOMSN=3225, MT Status=0, MTMSN=0
2012-10-24T16:19:17.823Z,1351095557.823 [NAL9602](INFO): Sent 229 bytes from file Logs/20121024T161000/shore0001.lzma
2012-10-24T16:19:17.823Z,1351095557.823 [NAL9602](INFO): Packets left to send: 0
2012-10-24T16:19:17.824Z,1351095557.824 [NAL9602](INFO): Stored copy of sent data in Logs/20121024T161000/shore0001.lzma.parts/0000.sbd
2012-10-24T16:19:26.564Z,1351095566.564 [NAL9602](INFO): SBD MO Status=0, MOMSN=3226, MT Status=0, MTMSN=0
2012-10-24T16:19:26.619Z,1351095566.619 [Default:Iridium:Read_Iridium] Stopped
2012-10-24T16:19:26.620Z,1351095566.620 [Default:Iridium](INFO): Completed Default:Iridium
2012-10-24T16:19:26.620Z,1351095566.620 [Default:Iridium] Stopped
2012-10-24T16:19:26.620Z,1351095566.620 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-10-24T16:19:26.620Z,1351095566.620 [Default:Iridium:A.SetSpeed] Stopped
2012-10-24T16:19:26.620Z,1351095566.620 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-10-24T16:19:26.979Z,1351095566.979 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2012-10-24T16:19:26.979Z,1351095566.979 [Default:CallIridium:B] Stopped
2012-10-24T16:19:26.979Z,1351095566.979 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-10-24T16:19:26.979Z,1351095566.979 [Default:CallIridium](INFO): Completed Default:CallIridium
2012-10-24T16:19:26.979Z,1351095566.979 [Default:CallIridium] Stopped
2012-10-24T16:19:26.979Z,1351095566.979 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-10-24T16:19:37.116Z,1351095577.116 [NAL9602](INFO): Powering down
2012-10-24T16:21:12.071Z,1351095672.071 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#1 STATUS: 65535
2012-10-24T16:21:17.102Z,1351095677.102 [Radio_Freewave](INFO): Powering down
2012-10-24T16:21:22.113Z,1351095682.113 [Radio_Freewave](INFO): Powering up
2012-10-24T16:24:27.132Z,1351095867.132 [Default:CallIridium] Running Loop=1
2012-10-24T16:24:27.132Z,1351095867.132 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-10-24T16:24:27.132Z,1351095867.132 [Default:CallIridium:A] Running Loop=1
2012-10-24T16:24:27.133Z,1351095867.133 [Default:CallIridium:A] Stopped
2012-10-24T16:24:27.133Z,1351095867.133 [Default:CallIridium:B] Running Loop=1
2012-10-24T16:24:27.133Z,1351095867.133 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-10-24T16:24:32.127Z,1351095872.127 [Default:Iridium] Running Loop=1
2012-10-24T16:24:32.127Z,1351095872.128 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-10-24T16:24:32.127Z,1351095872.128 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-10-24T16:24:32.128Z,1351095872.128 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-10-24T16:24:32.128Z,1351095872.128 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-10-24T16:24:32.128Z,1351095872.128 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-24T16:24:32.128Z,1351095872.128 [Default:Iridium:B.GoToSurface] Stopped
2012-10-24T16:24:32.129Z,1351095872.129 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-24T16:24:32.129Z,1351095872.129 [Default:Iridium:Read_Iridium] Running Loop=1
2012-10-24T16:24:32.129Z,1351095872.129 [Default:GPS] Running Loop=1
2012-10-24T16:24:32.129Z,1351095872.129 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-10-24T16:24:32.129Z,1351095872.129 [Default:GPS:A.SetSpeed] Running Loop=1
2012-10-24T16:24:32.129Z,1351095872.129 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-10-24T16:24:32.130Z,1351095872.129 [Default:GPS:B.GoToSurface] Running Loop=1
2012-10-24T16:24:32.130Z,1351095872.130 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-24T16:24:32.131Z,1351095872.131 [Default:GPS:B.GoToSurface] Stopped
2012-10-24T16:24:32.131Z,1351095872.131 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-24T16:24:32.131Z,1351095872.131 [Default:GPS:Read_GPS] Running Loop=1
2012-10-24T16:24:32.784Z,1351095872.784 [NAL9602](INFO): Powering up
2012-10-24T16:24:43.339Z,1351095883.339 [NAL9602](INFO): NAL9602 initialized
2012-10-24T16:25:08.682Z,1351095908.681 [NAL9602](INFO): SBD MO Status=2, MOMSN=3227, MT Status=2, MTMSN=0
2012-10-24T16:25:08.682Z,1351095908.682 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2012-10-24T16:25:09.863Z,1351095909.863 [NAL9602](IMPORTANT): GPS fix at: 1351096049.00
2012-10-24T16:25:09.880Z,1351095909.880 [Default:GPS:Read_GPS] Stopped
2012-10-24T16:25:09.880Z,1351095909.880 [Default:GPS:D] Running Loop=1
2012-10-24T16:25:10.283Z,1351095910.283 [Default:GPS:D] Stopped
2012-10-24T16:25:10.283Z,1351095910.283 [Default:GPS](INFO): Completed Default:GPS
2012-10-24T16:25:10.283Z,1351095910.283 [Default:GPS] Stopped
2012-10-24T16:25:10.284Z,1351095910.284 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-10-24T16:25:10.284Z,1351095910.284 [Default:GPS:A.SetSpeed] Stopped
2012-10-24T16:25:10.284Z,1351095910.284 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-10-24T16:25:42.136Z,1351095942.136 [NAL9602](INFO): SBD MO Status=1, MOMSN=3227, MT Status=0, MTMSN=0
2012-10-24T16:25:42.171Z,1351095942.171 [NAL9602](INFO): Sent 195 bytes from file Logs/20121024T161000/shore0002.lzma
2012-10-24T16:25:42.171Z,1351095942.171 [NAL9602](INFO): Packets left to send: 0
2012-10-24T16:25:42.172Z,1351095942.172 [NAL9602](INFO): Stored copy of sent data in Logs/20121024T161000/shore0002.lzma.parts/0000.sbd
2012-10-24T16:26:01.332Z,1351095961.332 [NAL9602](INFO): SBD MO Status=0, MOMSN=3228, MT Status=0, MTMSN=0
2012-10-24T16:26:01.387Z,1351095961.387 [Default:Iridium:Read_Iridium] Stopped
2012-10-24T16:26:01.388Z,1351095961.388 [Default:Iridium](INFO): Completed Default:Iridium
2012-10-24T16:26:01.388Z,1351095961.388 [Default:Iridium] Stopped
2012-10-24T16:26:01.388Z,1351095961.388 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-10-24T16:26:01.388Z,1351095961.388 [Default:Iridium:A.SetSpeed] Stopped
2012-10-24T16:26:01.388Z,1351095961.388 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-10-24T16:26:01.747Z,1351095961.747 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2012-10-24T16:26:01.747Z,1351095961.747 [Default:CallIridium:B] Stopped
2012-10-24T16:26:01.747Z,1351095961.747 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-10-24T16:26:01.747Z,1351095961.747 [Default:CallIridium](INFO): Completed Default:CallIridium
2012-10-24T16:26:01.747Z,1351095961.747 [Default:CallIridium] Stopped
2012-10-24T16:26:01.747Z,1351095961.747 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-10-24T16:26:11.852Z,1351095971.852 [NAL9602](INFO): Powering down
2012-10-24T16:31:01.840Z,1351096261.840 [Default:CallIridium] Running Loop=1
2012-10-24T16:31:01.840Z,1351096261.840 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-10-24T16:31:01.840Z,1351096261.841 [Default:CallIridium:A] Running Loop=1
2012-10-24T16:31:01.841Z,1351096261.841 [Default:CallIridium:A] Stopped
2012-10-24T16:31:01.841Z,1351096261.841 [Default:CallIridium:B] Running Loop=1
2012-10-24T16:31:01.841Z,1351096261.841 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-10-24T16:31:06.944Z,1351096266.944 [Default:Iridium] Running Loop=1
2012-10-24T16:31:06.944Z,1351096266.944 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-10-24T16:31:06.945Z,1351096266.944 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-10-24T16:31:06.945Z,1351096266.945 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-10-24T16:31:06.945Z,1351096266.945 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-10-24T16:31:06.945Z,1351096266.945 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-24T16:31:06.946Z,1351096266.945 [Default:Iridium:B.GoToSurface] Stopped
2012-10-24T16:31:06.946Z,1351096266.946 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-24T16:31:06.946Z,1351096266.946 [Default:Iridium:Read_Iridium] Running Loop=1
2012-10-24T16:31:06.946Z,1351096266.946 [Default:GPS] Running Loop=1
2012-10-24T16:31:06.946Z,1351096266.946 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-10-24T16:31:06.947Z,1351096266.947 [Default:GPS:A.SetSpeed] Running Loop=1
2012-10-24T16:31:06.947Z,1351096266.947 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-10-24T16:31:06.947Z,1351096266.947 [Default:GPS:B.GoToSurface] Running Loop=1
2012-10-24T16:31:06.947Z,1351096266.947 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-24T16:31:06.947Z,1351096266.947 [Default:GPS:B.GoToSurface] Stopped
2012-10-24T16:31:06.948Z,1351096266.948 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-24T16:31:06.948Z,1351096266.948 [Default:GPS:Read_GPS] Running Loop=1
2012-10-24T16:31:07.600Z,1351096267.600 [NAL9602](INFO): Powering up
2012-10-24T16:31:18.023Z,1351096278.023 [NAL9602](INFO): NAL9602 initialized
2012-10-24T16:31:35.368Z,1351096295.368 [NAL9602](INFO): SBD MO Status=1, MOMSN=3229, MT Status=0, MTMSN=0
2012-10-24T16:31:35.403Z,1351096295.403 [NAL9602](INFO): Sent 189 bytes from file Logs/20121024T161000/shore0003.lzma
2012-10-24T16:31:35.403Z,1351096295.403 [NAL9602](INFO): Packets left to send: 0
2012-10-24T16:31:35.405Z,1351096295.405 [NAL9602](INFO): Stored copy of sent data in Logs/20121024T161000/shore0003.lzma.parts/0000.sbd
2012-10-24T16:31:43.088Z,1351096303.088 [NAL9602](INFO): SBD MO Status=0, MOMSN=3230, MT Status=0, MTMSN=0
2012-10-24T16:31:43.143Z,1351096303.143 [Default:Iridium:Read_Iridium] Stopped
2012-10-24T16:31:43.144Z,1351096303.144 [Default:Iridium](INFO): Completed Default:Iridium
2012-10-24T16:31:43.144Z,1351096303.144 [Default:Iridium] Stopped
2012-10-24T16:31:43.144Z,1351096303.144 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-10-24T16:31:43.144Z,1351096303.144 [Default:Iridium:A.SetSpeed] Stopped
2012-10-24T16:31:43.144Z,1351096303.144 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-10-24T16:31:43.544Z,1351096303.544 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2012-10-24T16:31:43.544Z,1351096303.544 [Default:CallIridium:B] Stopped
2012-10-24T16:31:43.544Z,1351096303.544 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-10-24T16:31:43.544Z,1351096303.544 [Default:CallIridium](INFO): Completed Default:CallIridium
2012-10-24T16:31:43.544Z,1351096303.545 [Default:CallIridium] Stopped
2012-10-24T16:31:43.545Z,1351096303.545 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-10-24T16:31:44.291Z,1351096304.291 [NAL9602](IMPORTANT): GPS fix at: 1351096444.00
2012-10-24T16:31:44.308Z,1351096304.308 [Default:GPS:Read_GPS] Stopped
2012-10-24T16:31:44.308Z,1351096304.308 [Default:GPS:D] Running Loop=1
2012-10-24T16:31:44.719Z,1351096304.719 [Default:GPS:D] Stopped
2012-10-24T16:31:44.719Z,1351096304.719 [Default:GPS](INFO): Completed Default:GPS
2012-10-24T16:31:44.719Z,1351096304.719 [Default:GPS] Stopped
2012-10-24T16:31:44.720Z,1351096304.720 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-10-24T16:31:44.720Z,1351096304.720 [Default:GPS:A.SetSpeed] Stopped
2012-10-24T16:31:44.720Z,1351096304.720 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-10-24T16:32:05.236Z,1351096325.236 [NAL9602](INFO): Powering down
2012-10-24T16:36:45.216Z,1351096605.217 [Default:CallIridium] Running Loop=1
2012-10-24T16:36:45.217Z,1351096605.217 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-10-24T16:36:45.217Z,1351096605.217 [Default:CallIridium:A] Running Loop=1
2012-10-24T16:36:45.217Z,1351096605.217 [Default:CallIridium:A] Stopped
2012-10-24T16:36:45.217Z,1351096605.217 [Default:CallIridium:B] Running Loop=1
2012-10-24T16:36:45.217Z,1351096605.217 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-10-24T16:36:50.256Z,1351096610.256 [Default:Iridium] Running Loop=1
2012-10-24T16:36:50.257Z,1351096610.257 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-10-24T16:36:50.257Z,1351096610.257 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-10-24T16:36:50.257Z,1351096610.257 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-10-24T16:36:50.257Z,1351096610.257 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-10-24T16:36:50.257Z,1351096610.257 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-24T16:36:50.258Z,1351096610.258 [Default:Iridium:B.GoToSurface] Stopped
2012-10-24T16:36:50.258Z,1351096610.258 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-24T16:36:50.258Z,1351096610.258 [Default:Iridium:Read_Iridium] Running Loop=1
2012-10-24T16:36:50.258Z,1351096610.258 [Default:GPS] Running Loop=1
2012-10-24T16:36:50.259Z,1351096610.259 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-10-24T16:36:50.259Z,1351096610.259 [Default:GPS:A.SetSpeed] Running Loop=1
2012-10-24T16:36:50.259Z,1351096610.259 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-10-24T16:36:50.259Z,1351096610.259 [Default:GPS:B.GoToSurface] Running Loop=1
2012-10-24T16:36:50.259Z,1351096610.259 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-24T16:36:50.260Z,1351096610.260 [Default:GPS:B.GoToSurface] Stopped
2012-10-24T16:36:50.260Z,1351096610.260 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-24T16:36:50.260Z,1351096610.260 [Default:GPS:Read_GPS] Running Loop=1
2012-10-24T16:36:50.908Z,1351096610.908 [NAL9602](INFO): Powering up
2012-10-24T16:37:01.727Z,1351096621.727 [NAL9602](INFO): NAL9602 initialized
2012-10-24T16:37:33.552Z,1351096653.552 [NAL9602](INFO): SBD MO Status=2, MOMSN=3231, MT Status=2, MTMSN=0
2012-10-24T16:37:33.552Z,1351096653.552 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2012-10-24T16:37:52.720Z,1351096672.720 [NAL9602](INFO): SBD MO Status=1, MOMSN=3231, MT Status=0, MTMSN=0
2012-10-24T16:37:52.755Z,1351096672.755 [NAL9602](INFO): Sent 188 bytes from file Logs/20121024T161000/shore0004.lzma
2012-10-24T16:37:52.755Z,1351096672.755 [NAL9602](INFO): Packets left to send: 0
2012-10-24T16:37:52.757Z,1351096672.757 [NAL9602](INFO): Stored copy of sent data in Logs/20121024T161000/shore0004.lzma.parts/0000.sbd
2012-10-24T16:37:59.920Z,1351096679.920 [NAL9602](INFO): SBD MO Status=0, MOMSN=3232, MT Status=0, MTMSN=0
2012-10-24T16:37:59.975Z,1351096679.975 [Default:Iridium:Read_Iridium] Stopped
2012-10-24T16:37:59.975Z,1351096679.975 [Default:Iridium](INFO): Completed Default:Iridium
2012-10-24T16:37:59.975Z,1351096679.975 [Default:Iridium] Stopped
2012-10-24T16:37:59.975Z,1351096679.976 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-10-24T16:37:59.976Z,1351096679.976 [Default:Iridium:A.SetSpeed] Stopped
2012-10-24T16:37:59.976Z,1351096679.976 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-10-24T16:38:00.380Z,1351096680.380 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2012-10-24T16:38:00.380Z,1351096680.380 [Default:CallIridium:B] Stopped
2012-10-24T16:38:00.380Z,1351096680.381 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-10-24T16:38:00.381Z,1351096680.381 [Default:CallIridium](INFO): Completed Default:CallIridium
2012-10-24T16:38:00.381Z,1351096680.381 [Default:CallIridium] Stopped
2012-10-24T16:38:00.381Z,1351096680.381 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-10-24T16:38:01.127Z,1351096681.127 [NAL9602](IMPORTANT): GPS fix at: 1351096821.00
2012-10-24T16:38:01.143Z,1351096681.143 [Default:GPS:Read_GPS] Stopped
2012-10-24T16:38:01.143Z,1351096681.143 [Default:GPS:D] Running Loop=1
2012-10-24T16:38:01.559Z,1351096681.559 [Default:GPS:D] Stopped
2012-10-24T16:38:01.559Z,1351096681.559 [Default:GPS](INFO): Completed Default:GPS
2012-10-24T16:38:01.559Z,1351096681.559 [Default:GPS] Stopped
2012-10-24T16:38:01.559Z,1351096681.559 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-10-24T16:38:01.559Z,1351096681.559 [Default:GPS:A.SetSpeed] Stopped
2012-10-24T16:38:01.559Z,1351096681.559 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-10-24T16:38:22.072Z,1351096702.072 [NAL9602](INFO): Powering down
2012-10-24T16:43:02.072Z,1351096982.072 [Default:CallIridium] Running Loop=1
2012-10-24T16:43:02.072Z,1351096982.072 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-10-24T16:43:02.072Z,1351096982.072 [Default:CallIridium:A] Running Loop=1
2012-10-24T16:43:02.073Z,1351096982.073 [Default:CallIridium:A] Stopped
2012-10-24T16:43:02.073Z,1351096982.073 [Default:CallIridium:B] Running Loop=1
2012-10-24T16:43:02.073Z,1351096982.073 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-10-24T16:43:07.088Z,1351096987.088 [Default:Iridium] Running Loop=1
2012-10-24T16:43:07.088Z,1351096987.088 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-10-24T16:43:07.088Z,1351096987.088 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-10-24T16:43:07.088Z,1351096987.088 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-10-24T16:43:07.088Z,1351096987.088 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-10-24T16:43:07.088Z,1351096987.088 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-24T16:43:07.089Z,1351096987.089 [Default:Iridium:B.GoToSurface] Stopped
2012-10-24T16:43:07.089Z,1351096987.089 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-24T16:43:07.089Z,1351096987.089 [Default:Iridium:Read_Iridium] Running Loop=1
2012-10-24T16:43:07.089Z,1351096987.089 [Default:GPS] Running Loop=1
2012-10-24T16:43:07.090Z,1351096987.090 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-10-24T16:43:07.090Z,1351096987.090 [Default:GPS:A.SetSpeed] Running Loop=1
2012-10-24T16:43:07.090Z,1351096987.090 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-10-24T16:43:07.090Z,1351096987.090 [Default:GPS:B.GoToSurface] Running Loop=1
2012-10-24T16:43:07.090Z,1351096987.090 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-24T16:43:07.091Z,1351096987.091 [Default:GPS:B.GoToSurface] Stopped
2012-10-24T16:43:07.091Z,1351096987.091 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-24T16:43:07.091Z,1351096987.091 [Default:GPS:Read_GPS] Running Loop=1
2012-10-24T16:43:07.736Z,1351096987.736 [NAL9602](INFO): Powering up
2012-10-24T16:43:18.559Z,1351096998.559 [NAL9602](INFO): NAL9602 initialized
2012-10-24T16:43:36.498Z,1351097016.498 [NAL9602](INFO): SBD MO Status=1, MOMSN=3233, MT Status=0, MTMSN=0
2012-10-24T16:43:36.535Z,1351097016.535 [NAL9602](INFO): Sent 174 bytes from file Logs/20121024T161000/shore0005.lzma
2012-10-24T16:43:36.535Z,1351097016.535 [NAL9602](INFO): Packets left to send: 0
2012-10-24T16:43:36.539Z,1351097016.539 [NAL9602](INFO): Stored copy of sent data in Logs/20121024T161000/shore0005.lzma.parts/0000.sbd
2012-10-24T16:43:56.898Z,1351097036.898 [NAL9602](INFO): SBD MO Status=0, MOMSN=3234, MT Status=0, MTMSN=0
2012-10-24T16:43:56.955Z,1351097036.955 [Default:Iridium:Read_Iridium] Stopped
2012-10-24T16:43:56.956Z,1351097036.955 [Default:Iridium](INFO): Completed Default:Iridium
2012-10-24T16:43:56.956Z,1351097036.955 [Default:Iridium] Stopped
2012-10-24T16:43:56.956Z,1351097036.956 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-10-24T16:43:56.956Z,1351097036.956 [Default:Iridium:A.SetSpeed] Stopped
2012-10-24T16:43:56.956Z,1351097036.956 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-10-24T16:43:57.270Z,1351097037.270 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2012-10-24T16:43:57.271Z,1351097037.270 [Default:CallIridium:B] Stopped
2012-10-24T16:43:57.271Z,1351097037.271 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-10-24T16:43:57.271Z,1351097037.271 [Default:CallIridium](INFO): Completed Default:CallIridium
2012-10-24T16:43:57.271Z,1351097037.271 [Default:CallIridium] Stopped
2012-10-24T16:43:57.271Z,1351097037.271 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-10-24T16:44:37.657Z,1351097077.657 [NAL9602](IMPORTANT): GPS fix at: 1351097219.00
2012-10-24T16:44:37.674Z,1351097077.674 [Default:GPS:Read_GPS] Stopped
2012-10-24T16:44:37.674Z,1351097077.674 [Default:GPS:D] Running Loop=1
2012-10-24T16:44:38.115Z,1351097078.115 [Default:GPS:D] Stopped
2012-10-24T16:44:38.115Z,1351097078.115 [Default:GPS](INFO): Completed Default:GPS
2012-10-24T16:44:38.115Z,1351097078.115 [Default:GPS] Stopped
2012-10-24T16:44:38.116Z,1351097078.115 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-10-24T16:44:38.116Z,1351097078.116 [Default:GPS:A.SetSpeed] Stopped
2012-10-24T16:44:38.116Z,1351097078.116 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-10-24T16:44:58.548Z,1351097098.548 [NAL9602](INFO): Powering down
2012-10-24T16:48:59.963Z,1351097339.963 [Default:CallIridium] Running Loop=1
2012-10-24T16:48:59.963Z,1351097339.963 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-10-24T16:48:59.963Z,1351097339.963 [Default:CallIridium:A] Running Loop=1
2012-10-24T16:48:59.964Z,1351097339.964 [Default:CallIridium:A] Stopped
2012-10-24T16:48:59.964Z,1351097339.964 [Default:CallIridium:B] Running Loop=1
2012-10-24T16:48:59.964Z,1351097339.964 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-10-24T16:49:03.625Z,1351097343.625 [Default:Iridium] Running Loop=1
2012-10-24T16:49:03.625Z,1351097343.625 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-10-24T16:49:03.625Z,1351097343.625 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-10-24T16:49:03.625Z,1351097343.625 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-10-24T16:49:03.625Z,1351097343.625 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-10-24T16:49:03.625Z,1351097343.625 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-24T16:49:03.626Z,1351097343.626 [Default:Iridium:B.GoToSurface] Stopped
2012-10-24T16:49:03.626Z,1351097343.626 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-24T16:49:03.626Z,1351097343.626 [Default:Iridium:Read_Iridium] Running Loop=1
2012-10-24T16:49:03.627Z,1351097343.627 [Default:GPS] Running Loop=1
2012-10-24T16:49:03.627Z,1351097343.627 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-10-24T16:49:03.627Z,1351097343.627 [Default:GPS:A.SetSpeed] Running Loop=1
2012-10-24T16:49:03.627Z,1351097343.627 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-10-24T16:49:03.627Z,1351097343.627 [Default:GPS:B.GoToSurface] Running Loop=1
2012-10-24T16:49:03.627Z,1351097343.627 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-24T16:49:03.628Z,1351097343.628 [Default:GPS:B.GoToSurface] Stopped
2012-10-24T16:49:03.628Z,1351097343.628 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-24T16:49:03.628Z,1351097343.628 [Default:GPS:Read_GPS] Running Loop=1
2012-10-24T16:49:04.270Z,1351097344.270 [NAL9602](INFO): Powering up
2012-10-24T16:49:15.095Z,1351097355.095 [NAL9602](INFO): NAL9602 initialized
2012-10-24T16:50:10.276Z,1351097410.276 [NAL9602](INFO): SBD MO Status=2, MOMSN=3235, MT Status=2, MTMSN=0
2012-10-24T16:50:10.276Z,1351097410.276 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2012-10-24T16:59:18.638Z,1351097958.638 [NAL9602](FAULT): GPS failed to acquire within timeout.
2012-10-24T16:59:18.638Z,1351097958.638 [NAL9602] Data Fault, FailCount= 1
2012-10-24T16:59:18.639Z,1351097958.639 [NAL9602](ERROR): Data Fault
2012-10-24T16:59:18.668Z,1351097958.668 [CBIT](ERROR): Data Fault in component: NAL9602
2012-10-24T16:59:19.038Z,1351097959.038 [NAL9602](INFO): Powering down
2012-10-24T16:59:19.065Z,1351097959.065 [CBIT](INFO): Clearing failed state for component NAL9602
2012-10-24T16:59:19.065Z,1351097959.065 [NAL9602] No Fault, FailCount= 1
2012-10-24T16:59:24.236Z,1351097964.236 [NAL9602](INFO): Powering up NAL9602
2012-10-24T16:59:34.663Z,1351097974.663 [NAL9602](INFO): NAL9602 initialized
2012-10-24T17:05:59.591Z,1351098359.591 [CommandLine](IMPORTANT): got command @ ifconfig