2013-03-05T05:57:46.377Z,1362463066.377 [CommandLine](IMPORTANT): got command restart logs
2013-03-05T05:57:46.529Z,1362463066.529 [DVL_micro](DEBUG): NQ1
2013-03-05T05:57:47.525Z,1362463067.525 [DVL_micro](DEBUG): NQ1
2013-03-05T05:57:48.517Z,1362463068.517 [DVL_micro](DEBUG): NQ1
2013-03-05T05:57:49.509Z,1362463069.509 [DVL_micro](DEBUG): NQ1
2013-03-05T05:57:50.505Z,1362463070.505 [DVL_micro](DEBUG): NQ1
2013-03-05T05:57:51.489Z,1362463071.489 [DVL_micro](DEBUG): NQ1
2013-03-05T05:57:52.481Z,1362463072.481 [DVL_micro](DEBUG): NQ1
2013-03-05T05:57:53.481Z,1362463073.481 [DVL_micro](DEBUG): NQ1
2013-03-05T05:57:54.465Z,1362463074.465 [DVL_micro](DEBUG): NQ1
2013-03-05T05:57:55.461Z,1362463075.461 [DVL_micro](DEBUG): NQ1
2013-03-05T05:57:56.457Z,1362463076.457 [DVL_micro](DEBUG): NQ1
2013-03-05T05:57:57.453Z,1362463077.453 [DVL_micro](DEBUG): NQ1
2013-03-05T05:57:58.445Z,1362463078.445 [DVL_micro](DEBUG): NQ1
2013-03-05T05:57:59.425Z,1362463079.425 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:00.421Z,1362463080.421 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:01.424Z,1362463081.424 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:02.417Z,1362463082.417 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:02.799Z,1362463082.799 [NAL9602](INFO): SBD MO Status=1, MOMSN=6479, MT Status=0, MTMSN=0
2013-03-05T05:58:02.849Z,1362463082.849 [NAL9602](INFO): Sent 194 bytes from file Logs/20130304T140637/shore0040.lzma
2013-03-05T05:58:02.849Z,1362463082.849 [NAL9602](INFO): Packets left to send: 0
2013-03-05T05:58:02.850Z,1362463082.850 [NAL9602](INFO): Stored copy of sent data in Logs/20130304T140637/shore0040.lzma.parts/0000.sbd
2013-03-05T05:58:03.393Z,1362463083.393 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:04.401Z,1362463084.401 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:05.389Z,1362463085.389 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:06.389Z,1362463086.389 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:07.381Z,1362463087.381 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:08.365Z,1362463088.365 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:09.353Z,1362463089.353 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:10.353Z,1362463090.353 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:11.341Z,1362463091.341 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:12.333Z,1362463092.333 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:12.647Z,1362463092.647 [NAL9602](INFO): SBD MO Status=1, MOMSN=6480, MT Status=0, MTMSN=0
2013-03-05T05:58:12.697Z,1362463092.697 [NAL9602](INFO): Sent 185 bytes from file Logs/20130304T140637/shore0041.lzma
2013-03-05T05:58:12.697Z,1362463092.697 [NAL9602](INFO): Packets left to send: 0
2013-03-05T05:58:12.699Z,1362463092.699 [NAL9602](INFO): Stored copy of sent data in Logs/20130304T140637/shore0041.lzma.parts/0000.sbd
2013-03-05T05:58:13.329Z,1362463093.329 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:14.317Z,1362463094.317 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:15.309Z,1362463095.309 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:16.309Z,1362463096.309 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:17.303Z,1362463097.303 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:18.285Z,1362463098.285 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:19.277Z,1362463099.277 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:20.273Z,1362463100.273 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:21.261Z,1362463101.261 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:22.253Z,1362463102.253 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:23.249Z,1362463103.249 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:24.241Z,1362463104.241 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:25.237Z,1362463105.237 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:26.225Z,1362463106.225 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:26.258Z,1362463106.258 [NAL9602](INFO): SBD MO Status=0, MOMSN=6481, MT Status=0, MTMSN=0
2013-03-05T05:58:27.213Z,1362463107.213 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:28.213Z,1362463108.213 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:29.202Z,1362463109.202 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:30.202Z,1362463110.202 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:31.189Z,1362463111.189 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:32.189Z,1362463112.189 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:33.181Z,1362463113.181 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:34.169Z,1362463114.169 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:35.165Z,1362463115.165 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:36.149Z,1362463116.149 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:37.149Z,1362463117.149 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:38.124Z,1362463118.124 [NAL9602](INFO): SBD MO Status=1, MOMSN=6482, MT Status=0, MTMSN=0
2013-03-05T05:58:38.133Z,1362463118.133 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:38.177Z,1362463118.177 [NAL9602](INFO): Sent 332 bytes from file Logs/20130305T055746/shore0000.lzma
2013-03-05T05:58:38.177Z,1362463118.177 [NAL9602](INFO): Packets left to send: 2
2013-03-05T05:58:38.179Z,1362463118.179 [NAL9602](INFO): Stored copy of sent data in Logs/20130305T055746/shore0000.lzma.parts/0002.sbd
2013-03-05T05:58:39.125Z,1362463119.125 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:40.125Z,1362463120.125 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:41.121Z,1362463121.121 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:42.113Z,1362463122.113 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:43.105Z,1362463123.105 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:44.089Z,1362463124.089 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:45.081Z,1362463125.081 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:46.077Z,1362463126.077 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:47.077Z,1362463127.077 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:48.069Z,1362463128.069 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:49.057Z,1362463129.057 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:50.053Z,1362463130.053 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:50.816Z,1362463130.816 [NAL9602](INFO): SBD MO Status=1, MOMSN=6483, MT Status=0, MTMSN=0
2013-03-05T05:58:50.865Z,1362463130.865 [NAL9602](INFO): Sent 332 bytes from file Logs/20130305T055746/shore0000.lzma
2013-03-05T05:58:50.865Z,1362463130.865 [NAL9602](INFO): Packets left to send: 1
2013-03-05T05:58:50.866Z,1362463130.866 [NAL9602](INFO): Stored copy of sent data in Logs/20130305T055746/shore0000.lzma.parts/0001.sbd
2013-03-05T05:58:51.045Z,1362463131.045 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:52.029Z,1362463132.029 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:53.033Z,1362463133.033 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:54.025Z,1362463134.025 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:55.005Z,1362463135.005 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:56.002Z,1362463136.001 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:56.993Z,1362463136.993 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:57.989Z,1362463137.989 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:58.977Z,1362463138.977 [DVL_micro](DEBUG): NQ1
2013-03-05T05:58:59.973Z,1362463139.973 [DVL_micro](DEBUG): NQ1
2013-03-05T05:59:00.207Z,1362463140.207 [NAL9602](INFO): SBD MO Status=1, MOMSN=6484, MT Status=0, MTMSN=0
2013-03-05T05:59:00.257Z,1362463140.257 [NAL9602](INFO): Sent 33 bytes from file Logs/20130305T055746/shore0000.lzma
2013-03-05T05:59:00.257Z,1362463140.257 [NAL9602](INFO): Packets left to send: 0
2013-03-05T05:59:00.258Z,1362463140.258 [NAL9602](INFO): Stored copy of sent data in Logs/20130305T055746/shore0000.lzma.parts/0000.sbd
2013-03-05T05:59:00.969Z,1362463140.969 [DVL_micro](DEBUG): NQ1
2013-03-05T05:59:01.953Z,1362463141.953 [DVL_micro](DEBUG): NQ1
2013-03-05T05:59:02.941Z,1362463142.941 [DVL_micro](DEBUG): NQ1
2013-03-05T05:59:03.937Z,1362463143.937 [DVL_micro](DEBUG): NQ1
2013-03-05T05:59:04.933Z,1362463144.933 [DVL_micro](DEBUG): NQ1
2013-03-05T05:59:05.929Z,1362463145.929 [DVL_micro](DEBUG): NQ1
2013-03-05T05:59:06.917Z,1362463146.917 [DVL_micro](DEBUG): NQ1
2013-03-05T05:59:07.921Z,1362463147.921 [DVL_micro](DEBUG): NQ1
2013-03-05T05:59:08.607Z,1362463148.607 [NAL9602](INFO): SBD MO Status=0, MOMSN=6485, MT Status=0, MTMSN=0
2013-03-05T05:59:08.692Z,1362463148.692 [transit_1km:transit:SURFACECOMMS:B:C] Stopped
2013-03-05T05:59:08.692Z,1362463148.692 [transit_1km:transit:SURFACECOMMS:B:D] Running Loop=1
2013-03-05T05:59:08.905Z,1362463148.905 [DVL_micro](DEBUG): NQ1
2013-03-05T05:59:09.897Z,1362463149.897 [DVL_micro](DEBUG): NQ1
2013-03-05T05:59:10.897Z,1362463150.897 [DVL_micro](DEBUG): NQ1
2013-03-05T05:59:11.010Z,1362463151.010 [NAL9602](IMPORTANT): GPS fix at: 1362506435.00
2013-03-05T05:59:11.028Z,1362463151.028 [transit_1km:transit:SURFACECOMMS:B:D] Stopped
2013-03-05T05:59:11.028Z,1362463151.028 [transit_1km:transit:SURFACECOMMS:B](INFO): Completed transit_1km:transit:SURFACECOMMS:B
2013-03-05T05:59:11.029Z,1362463151.029 [transit_1km:transit:SURFACECOMMS:B] Stopped
2013-03-05T05:59:11.029Z,1362463151.029 [transit_1km:transit:SURFACECOMMS:B](INFO): Aggregate::uninitialize transit_1km:transit:SURFACECOMMS:B
2013-03-05T05:59:11.029Z,1362463151.029 [transit_1km:transit:SURFACECOMMS:B:A.SetSpeed] Stopped
2013-03-05T05:59:11.029Z,1362463151.029 [transit_1km:transit:SURFACECOMMS:B:A.SetSpeed](DEBUG): Uninitialize.
2013-03-05T05:59:11.030Z,1362463151.030 [transit_1km:transit:SURFACECOMMS](INFO): Completed transit_1km:transit:SURFACECOMMS
2013-03-05T05:59:11.030Z,1362463151.030 [transit_1km:transit:SURFACECOMMS] Stopped
2013-03-05T05:59:11.030Z,1362463151.030 [transit_1km:transit:SURFACECOMMS](INFO): Aggregate::uninitialize transit_1km:transit:SURFACECOMMS
2013-03-05T05:59:11.030Z,1362463151.030 [transit_1km:transit:SURFACECOMMS:A.GoToSurface] Stopped
2013-03-05T05:59:11.030Z,1362463151.030 [transit_1km:transit:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-05T05:59:11.495Z,1362463151.495 [transit_1km:transit:WaypointOne:PHONEHOMEWPT1](INFO): Completed transit_1km:transit:WaypointOne:PHONEHOMEWPT1
2013-03-05T05:59:11.495Z,1362463151.495 [transit_1km:transit:WaypointOne:PHONEHOMEWPT1] Stopped
2013-03-05T05:59:11.495Z,1362463151.496 [transit_1km:transit:WaypointOne:PHONEHOMEWPT1](INFO): Aggregate::uninitialize transit_1km:transit:WaypointOne:PHONEHOMEWPT1
2013-03-05T05:59:11.497Z,1362463151.497 [transit_1km:transit:WaypointOne](INFO): Completed transit_1km:transit:WaypointOne
2013-03-05T05:59:11.497Z,1362463151.497 [transit_1km:transit:WaypointOne] Stopped
2013-03-05T05:59:11.497Z,1362463151.497 [transit_1km:transit:WaypointOne](INFO): Aggregate::uninitialize transit_1km:transit:WaypointOne
2013-03-05T05:59:11.497Z,1362463151.497 [transit_1km:transit:WaypointOne:A.Pitch] Stopped
2013-03-05T05:59:11.497Z,1362463151.497 [transit_1km:transit:WaypointOne:B.SetSpeed] Stopped
2013-03-05T05:59:11.497Z,1362463151.497 [transit_1km:transit:WaypointOne:B.SetSpeed](DEBUG): Uninitialize.
2013-03-05T05:59:11.498Z,1362463151.497 [transit_1km:transit:MassHold.Pitch] Running Loop=1
2013-03-05T05:59:11.498Z,1362463151.498 [transit_1km:transit:BuoyancyHold.Buoyancy] Running Loop=1
2013-03-05T05:59:11.500Z,1362463151.500 [transit_1km:transit](INFO): Completed transit_1km:transit
2013-03-05T05:59:11.500Z,1362463151.500 [transit_1km:transit] Stopped
2013-03-05T05:59:11.500Z,1362463151.500 [transit_1km:transit](INFO): Aggregate::uninitialize transit_1km:transit
2013-03-05T05:59:11.500Z,1362463151.500 [transit_1km:transit:A.AltitudeEnvelope] Stopped
2013-03-05T05:59:11.500Z,1362463151.500 [transit_1km:transit:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2013-03-05T05:59:11.500Z,1362463151.500 [transit_1km:transit:B.DepthEnvelope] Stopped
2013-03-05T05:59:11.500Z,1362463151.500 [transit_1km:transit:B.DepthEnvelope](DEBUG): Uninitialize.
2013-03-05T05:59:11.500Z,1362463151.500 [transit_1km:transit:C.OffshoreEnvelope] Stopped
2013-03-05T05:59:11.500Z,1362463151.500 [transit_1km:transit:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2013-03-05T05:59:11.500Z,1362463151.500 [transit_1km:transit:BuoyancyHold.Buoyancy] Stopped
2013-03-05T05:59:11.516Z,1362463151.516 [transit_1km:transit:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2013-03-05T05:59:11.517Z,1362463151.517 [transit_1km:transit:MassHold.Pitch] Stopped
2013-03-05T05:59:11.517Z,1362463151.517 [transit_1km](INFO): Completed transit_1km
2013-03-05T05:59:11.517Z,1362463151.517 [transit_1km] Stopped
2013-03-05T05:59:11.517Z,1362463151.517 [transit_1km](INFO): Aggregate::uninitialize transit_1km
2013-03-05T05:59:11.819Z,1362463151.819 [MissionManager](IMPORTANT): Started mission Default
2013-03-05T05:59:11.819Z,1362463151.819 [Default] Running Loop=1
2013-03-05T05:59:11.819Z,1362463151.819 [Default](INFO): Aggregate::initialize Default
2013-03-05T05:59:11.819Z,1362463151.819 [Default:D.SetSpeed] Running Loop=1
2013-03-05T05:59:11.819Z,1362463151.819 [Default:D.SetSpeed](DEBUG): Initialize.
2013-03-05T05:59:11.819Z,1362463151.819 [Default:E.GoToSurface] Running Loop=1
2013-03-05T05:59:11.819Z,1362463151.819 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-05T05:59:11.820Z,1362463151.819 [Default:Iridium] Running Loop=1
2013-03-05T05:59:11.820Z,1362463151.820 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-03-05T05:59:11.820Z,1362463151.820 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-03-05T05:59:11.820Z,1362463151.820 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-03-05T05:59:11.820Z,1362463151.820 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-03-05T05:59:11.820Z,1362463151.820 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-05T05:59:11.822Z,1362463151.822 [Default:Iridium:B.GoToSurface] Stopped
2013-03-05T05:59:11.822Z,1362463151.822 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-05T05:59:11.822Z,1362463151.822 [Default:Iridium:Read_Iridium] Running Loop=1
2013-03-05T05:59:11.889Z,1362463151.889 [DVL_micro](DEBUG): NQ1
2013-03-05T05:59:11.938Z,1362463151.938 [DVL_micro](INFO): pause:Powering down
2013-03-05T05:59:28.412Z,1362463168.411 [NAL9602](INFO): SBD MO Status=1, MOMSN=6486, MT Status=0, MTMSN=0
2013-03-05T05:59:28.461Z,1362463168.461 [NAL9602](INFO): Sent 242 bytes from file Logs/20130305T055746/shore0001.lzma
2013-03-05T05:59:28.461Z,1362463168.461 [NAL9602](INFO): Packets left to send: 0
2013-03-05T05:59:28.463Z,1362463168.463 [NAL9602](INFO): Stored copy of sent data in Logs/20130305T055746/shore0001.lzma.parts/0000.sbd
2013-03-05T05:59:38.812Z,1362463178.812 [NAL9602](INFO): SBD MO Status=0, MOMSN=6487, MT Status=0, MTMSN=0
2013-03-05T05:59:38.895Z,1362463178.895 [Default:Iridium:Read_Iridium] Stopped
2013-03-05T05:59:38.896Z,1362463178.896 [Default:Iridium](INFO): Completed Default:Iridium
2013-03-05T05:59:38.896Z,1362463178.896 [Default:Iridium] Stopped
2013-03-05T05:59:38.896Z,1362463178.896 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-03-05T05:59:38.896Z,1362463178.896 [Default:Iridium:A.SetSpeed] Stopped
2013-03-05T05:59:38.896Z,1362463178.896 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-03-05T05:59:38.896Z,1362463178.896 [Default:F.Wait] Running Loop=1
2013-03-05T05:59:38.896Z,1362463178.896 [Default:F.Wait](DEBUG): Initialize Wait Component.
2013-03-05T05:59:49.358Z,1362463189.358 [NAL9602](INFO): Powering down
2013-03-05T06:04:39.375Z,1362463479.375 [Default:CallIridium] Running Loop=1
2013-03-05T06:04:39.375Z,1362463479.375 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-03-05T06:04:39.375Z,1362463479.375 [Default:CallIridium:A] Running Loop=1
2013-03-05T06:04:39.376Z,1362463479.376 [Default:CallIridium:A] Stopped
2013-03-05T06:04:39.376Z,1362463479.376 [Default:CallIridium:B] Running Loop=1
2013-03-05T06:04:39.376Z,1362463479.376 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-03-05T06:04:44.351Z,1362463484.351 [Default:Iridium] Running Loop=1
2013-03-05T06:04:44.351Z,1362463484.351 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-03-05T06:04:44.351Z,1362463484.351 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-03-05T06:04:44.351Z,1362463484.351 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-03-05T06:04:44.351Z,1362463484.351 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-03-05T06:04:44.351Z,1362463484.351 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-05T06:04:44.352Z,1362463484.352 [Default:Iridium:B.GoToSurface] Stopped
2013-03-05T06:04:44.352Z,1362463484.352 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-05T06:04:44.352Z,1362463484.352 [Default:Iridium:Read_Iridium] Running Loop=1
2013-03-05T06:04:44.353Z,1362463484.353 [Default:GPS] Running Loop=1
2013-03-05T06:04:44.353Z,1362463484.353 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-03-05T06:04:44.353Z,1362463484.353 [Default:GPS:A.SetSpeed] Running Loop=1
2013-03-05T06:04:44.353Z,1362463484.353 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-03-05T06:04:44.353Z,1362463484.353 [Default:GPS:B.GoToSurface] Running Loop=1
2013-03-05T06:04:44.353Z,1362463484.353 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-05T06:04:44.354Z,1362463484.354 [Default:GPS:B.GoToSurface] Stopped
2013-03-05T06:04:44.354Z,1362463484.354 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-05T06:04:44.354Z,1362463484.354 [Default:GPS:Read_GPS] Running Loop=1
2013-03-05T06:04:45.014Z,1362463485.014 [NAL9602](INFO): Powering up
2013-03-05T06:04:55.445Z,1362463495.445 [NAL9602](INFO): NAL9602 initialized
2013-03-05T06:05:13.213Z,1362463513.214 [NAL9602](INFO): SBD MO Status=1, MOMSN=6488, MT Status=0, MTMSN=0
2013-03-05T06:05:13.261Z,1362463513.261 [NAL9602](INFO): Sent 138 bytes from file Logs/20130305T055746/shore0002.lzma
2013-03-05T06:05:13.261Z,1362463513.261 [NAL9602](INFO): Packets left to send: 0
2013-03-05T06:05:13.263Z,1362463513.263 [NAL9602](INFO): Stored copy of sent data in Logs/20130305T055746/shore0002.lzma.parts/0000.sbd
2013-03-05T06:05:22.760Z,1362463522.760 [NAL9602](INFO): SBD MO Status=0, MOMSN=6489, MT Status=0, MTMSN=0
2013-03-05T06:05:22.846Z,1362463522.846 [Default:Iridium:Read_Iridium] Stopped
2013-03-05T06:05:22.847Z,1362463522.847 [Default:Iridium](INFO): Completed Default:Iridium
2013-03-05T06:05:22.847Z,1362463522.847 [Default:Iridium] Stopped
2013-03-05T06:05:22.847Z,1362463522.847 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-03-05T06:05:22.847Z,1362463522.847 [Default:Iridium:A.SetSpeed] Stopped
2013-03-05T06:05:22.847Z,1362463522.847 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-03-05T06:05:23.178Z,1362463523.178 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-03-05T06:05:23.178Z,1362463523.178 [Default:CallIridium:B] Stopped
2013-03-05T06:05:23.178Z,1362463523.178 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-03-05T06:05:23.178Z,1362463523.178 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-03-05T06:05:23.178Z,1362463523.178 [Default:CallIridium] Stopped
2013-03-05T06:05:23.178Z,1362463523.178 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-03-05T06:05:23.961Z,1362463523.961 [NAL9602](IMPORTANT): GPS fix at: 1362506808.00
2013-03-05T06:05:23.979Z,1362463523.979 [Default:GPS:Read_GPS] Stopped
2013-03-05T06:05:23.979Z,1362463523.979 [Default:GPS:D] Running Loop=1
2013-03-05T06:05:24.391Z,1362463524.391 [Default:GPS:D] Stopped
2013-03-05T06:05:24.391Z,1362463524.391 [Default:GPS](INFO): Completed Default:GPS
2013-03-05T06:05:24.391Z,1362463524.391 [Default:GPS] Stopped
2013-03-05T06:05:24.391Z,1362463524.391 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-03-05T06:05:24.391Z,1362463524.391 [Default:GPS:A.SetSpeed] Stopped
2013-03-05T06:05:24.392Z,1362463524.392 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-03-05T06:05:44.919Z,1362463544.919 [NAL9602](INFO): Powering down
2013-03-05T06:10:24.955Z,1362463824.955 [Default:CallIridium] Running Loop=1
2013-03-05T06:10:24.955Z,1362463824.955 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-03-05T06:10:24.955Z,1362463824.955 [Default:CallIridium:A] Running Loop=1
2013-03-05T06:10:24.956Z,1362463824.956 [Default:CallIridium:A] Stopped
2013-03-05T06:10:24.956Z,1362463824.956 [Default:CallIridium:B] Running Loop=1
2013-03-05T06:10:24.956Z,1362463824.956 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-03-05T06:10:29.878Z,1362463829.878 [Default:Iridium] Running Loop=1
2013-03-05T06:10:29.878Z,1362463829.878 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-03-05T06:10:29.879Z,1362463829.879 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-03-05T06:10:29.879Z,1362463829.879 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-03-05T06:10:29.879Z,1362463829.879 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-03-05T06:10:29.879Z,1362463829.879 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-05T06:10:29.880Z,1362463829.880 [Default:Iridium:B.GoToSurface] Stopped
2013-03-05T06:10:29.880Z,1362463829.880 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-05T06:10:29.880Z,1362463829.880 [Default:Iridium:Read_Iridium] Running Loop=1
2013-03-05T06:10:29.880Z,1362463829.880 [Default:GPS] Running Loop=1
2013-03-05T06:10:29.880Z,1362463829.880 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-03-05T06:10:29.881Z,1362463829.881 [Default:GPS:A.SetSpeed] Running Loop=1
2013-03-05T06:10:29.881Z,1362463829.881 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-03-05T06:10:29.881Z,1362463829.881 [Default:GPS:B.GoToSurface] Running Loop=1
2013-03-05T06:10:29.881Z,1362463829.881 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-05T06:10:29.882Z,1362463829.882 [Default:GPS:B.GoToSurface] Stopped
2013-03-05T06:10:29.882Z,1362463829.882 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-05T06:10:29.882Z,1362463829.882 [Default:GPS:Read_GPS] Running Loop=1
2013-03-05T06:10:30.562Z,1362463830.562 [NAL9602](INFO): Powering up
2013-03-05T06:10:40.993Z,1362463840.993 [NAL9602](INFO): NAL9602 initialized
2013-03-05T06:11:08.324Z,1362463868.324 [NAL9602](INFO): SBD MO Status=1, MOMSN=6490, MT Status=0, MTMSN=0
2013-03-05T06:11:08.373Z,1362463868.373 [NAL9602](INFO): Sent 194 bytes from file Logs/20130305T055746/shore0003.lzma
2013-03-05T06:11:08.373Z,1362463868.373 [NAL9602](INFO): Packets left to send: 0
2013-03-05T06:11:08.375Z,1362463868.375 [NAL9602](INFO): Stored copy of sent data in Logs/20130305T055746/shore0003.lzma.parts/0000.sbd
2013-03-05T06:11:15.524Z,1362463875.524 [NAL9602](INFO): SBD MO Status=0, MOMSN=6491, MT Status=0, MTMSN=0
2013-03-05T06:11:15.619Z,1362463875.619 [Default:Iridium:Read_Iridium] Stopped
2013-03-05T06:11:15.619Z,1362463875.619 [Default:Iridium](INFO): Completed Default:Iridium
2013-03-05T06:11:15.620Z,1362463875.620 [Default:Iridium] Stopped
2013-03-05T06:11:15.620Z,1362463875.620 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-03-05T06:11:15.620Z,1362463875.620 [Default:Iridium:A.SetSpeed] Stopped
2013-03-05T06:11:15.620Z,1362463875.620 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-03-05T06:11:15.939Z,1362463875.939 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-03-05T06:11:15.939Z,1362463875.939 [Default:CallIridium:B] Stopped
2013-03-05T06:11:15.939Z,1362463875.939 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-03-05T06:11:15.939Z,1362463875.939 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-03-05T06:11:15.939Z,1362463875.939 [Default:CallIridium] Stopped
2013-03-05T06:11:15.939Z,1362463875.940 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-03-05T06:11:16.725Z,1362463876.725 [NAL9602](IMPORTANT): GPS fix at: 1362507161.00
2013-03-05T06:11:16.742Z,1362463876.742 [Default:GPS:Read_GPS] Stopped
2013-03-05T06:11:16.742Z,1362463876.742 [Default:GPS:D] Running Loop=1
2013-03-05T06:11:17.155Z,1362463877.155 [Default:GPS:D] Stopped
2013-03-05T06:11:17.156Z,1362463877.156 [Default:GPS](INFO): Completed Default:GPS
2013-03-05T06:11:17.156Z,1362463877.156 [Default:GPS] Stopped
2013-03-05T06:11:17.156Z,1362463877.156 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-03-05T06:11:17.156Z,1362463877.156 [Default:GPS:A.SetSpeed] Stopped
2013-03-05T06:11:17.156Z,1362463877.156 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-03-05T06:11:37.651Z,1362463897.651 [NAL9602](INFO): Powering down
2013-03-05T06:16:17.663Z,1362464177.663 [Default:CallIridium] Running Loop=1
2013-03-05T06:16:17.663Z,1362464177.663 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-03-05T06:16:17.663Z,1362464177.663 [Default:CallIridium:A] Running Loop=1
2013-03-05T06:16:17.663Z,1362464177.663 [Default:CallIridium:A] Stopped
2013-03-05T06:16:17.663Z,1362464177.663 [Default:CallIridium:B] Running Loop=1
2013-03-05T06:16:17.664Z,1362464177.664 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-03-05T06:16:22.720Z,1362464182.720 [Default:Iridium] Running Loop=1
2013-03-05T06:16:22.720Z,1362464182.720 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-03-05T06:16:22.721Z,1362464182.721 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-03-05T06:16:22.721Z,1362464182.721 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-03-05T06:16:22.721Z,1362464182.721 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-03-05T06:16:22.721Z,1362464182.721 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-05T06:16:22.722Z,1362464182.722 [Default:Iridium:B.GoToSurface] Stopped
2013-03-05T06:16:22.722Z,1362464182.722 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-05T06:16:22.722Z,1362464182.722 [Default:Iridium:Read_Iridium] Running Loop=1
2013-03-05T06:16:22.722Z,1362464182.722 [Default:GPS] Running Loop=1
2013-03-05T06:16:22.722Z,1362464182.722 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-03-05T06:16:22.723Z,1362464182.723 [Default:GPS:A.SetSpeed] Running Loop=1
2013-03-05T06:16:22.723Z,1362464182.723 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-03-05T06:16:22.723Z,1362464182.723 [Default:GPS:B.GoToSurface] Running Loop=1
2013-03-05T06:16:22.723Z,1362464182.723 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-05T06:16:22.724Z,1362464182.724 [Default:GPS:B.GoToSurface] Stopped
2013-03-05T06:16:22.724Z,1362464182.724 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-05T06:16:22.724Z,1362464182.724 [Default:GPS:Read_GPS] Running Loop=1
2013-03-05T06:16:23.317Z,1362464183.317 [NAL9602](INFO): Powering up
2013-03-05T06:16:34.157Z,1362464194.157 [NAL9602](INFO): NAL9602 initialized
2013-03-05T06:16:51.516Z,1362464211.516 [NAL9602](INFO): SBD MO Status=1, MOMSN=6492, MT Status=0, MTMSN=0
2013-03-05T06:16:51.565Z,1362464211.565 [NAL9602](INFO): Sent 193 bytes from file Logs/20130305T055746/shore0004.lzma
2013-03-05T06:16:51.565Z,1362464211.565 [NAL9602](INFO): Packets left to send: 0
2013-03-05T06:16:51.567Z,1362464211.567 [NAL9602](INFO): Stored copy of sent data in Logs/20130305T055746/shore0004.lzma.parts/0000.sbd
2013-03-05T06:17:01.116Z,1362464221.116 [NAL9602](INFO): SBD MO Status=0, MOMSN=6493, MT Status=0, MTMSN=0
2013-03-05T06:17:01.215Z,1362464221.215 [Default:Iridium:Read_Iridium] Stopped
2013-03-05T06:17:01.216Z,1362464221.216 [Default:Iridium](INFO): Completed Default:Iridium
2013-03-05T06:17:01.216Z,1362464221.216 [Default:Iridium] Stopped
2013-03-05T06:17:01.216Z,1362464221.216 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-03-05T06:17:01.216Z,1362464221.216 [Default:Iridium:A.SetSpeed] Stopped
2013-03-05T06:17:01.216Z,1362464221.216 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-03-05T06:17:01.531Z,1362464221.531 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-03-05T06:17:01.531Z,1362464221.531 [Default:CallIridium:B] Stopped
2013-03-05T06:17:01.531Z,1362464221.531 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-03-05T06:17:01.531Z,1362464221.531 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-03-05T06:17:01.531Z,1362464221.532 [Default:CallIridium] Stopped
2013-03-05T06:17:01.532Z,1362464221.532 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-03-05T06:17:02.317Z,1362464222.317 [NAL9602](IMPORTANT): GPS fix at: 1362507507.00
2013-03-05T06:17:02.334Z,1362464222.334 [Default:GPS:Read_GPS] Stopped
2013-03-05T06:17:02.334Z,1362464222.334 [Default:GPS:D] Running Loop=1
2013-03-05T06:17:02.743Z,1362464222.743 [Default:GPS:D] Stopped
2013-03-05T06:17:02.744Z,1362464222.744 [Default:GPS](INFO): Completed Default:GPS
2013-03-05T06:17:02.744Z,1362464222.744 [Default:GPS] Stopped
2013-03-05T06:17:02.744Z,1362464222.744 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-03-05T06:17:02.744Z,1362464222.744 [Default:GPS:A.SetSpeed] Stopped
2013-03-05T06:17:02.744Z,1362464222.744 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-03-05T06:17:23.242Z,1362464243.242 [NAL9602](INFO): Powering down
2013-03-05T06:22:03.271Z,1362464523.271 [Default:CallIridium] Running Loop=1
2013-03-05T06:22:03.271Z,1362464523.271 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-03-05T06:22:03.271Z,1362464523.271 [Default:CallIridium:A] Running Loop=1
2013-03-05T06:22:03.272Z,1362464523.272 [Default:CallIridium:A] Stopped
2013-03-05T06:22:03.272Z,1362464523.272 [Default:CallIridium:B] Running Loop=1
2013-03-05T06:22:03.272Z,1362464523.272 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-03-05T06:22:08.347Z,1362464528.347 [Default:Iridium] Running Loop=1
2013-03-05T06:22:08.347Z,1362464528.348 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-03-05T06:22:08.348Z,1362464528.348 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-03-05T06:22:08.348Z,1362464528.348 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-03-05T06:22:08.348Z,1362464528.348 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-03-05T06:22:08.348Z,1362464528.348 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-05T06:22:08.349Z,1362464528.349 [Default:Iridium:B.GoToSurface] Stopped
2013-03-05T06:22:08.349Z,1362464528.349 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-05T06:22:08.349Z,1362464528.349 [Default:Iridium:Read_Iridium] Running Loop=1
2013-03-05T06:22:08.349Z,1362464528.349 [Default:GPS] Running Loop=1
2013-03-05T06:22:08.350Z,1362464528.349 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-03-05T06:22:08.350Z,1362464528.350 [Default:GPS:A.SetSpeed] Running Loop=1
2013-03-05T06:22:08.350Z,1362464528.350 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-03-05T06:22:08.350Z,1362464528.350 [Default:GPS:B.GoToSurface] Running Loop=1
2013-03-05T06:22:08.350Z,1362464528.350 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-05T06:22:08.351Z,1362464528.351 [Default:GPS:B.GoToSurface] Stopped
2013-03-05T06:22:08.351Z,1362464528.351 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-05T06:22:08.351Z,1362464528.351 [Default:GPS:Read_GPS] Running Loop=1
2013-03-05T06:22:08.970Z,1362464528.970 [NAL9602](INFO): Powering up
2013-03-05T06:22:19.777Z,1362464539.777 [NAL9602](INFO): NAL9602 initialized
2013-03-05T06:22:38.336Z,1362464558.336 [NAL9602](INFO): SBD MO Status=1, MOMSN=6494, MT Status=0, MTMSN=0
2013-03-05T06:22:38.385Z,1362464558.385 [NAL9602](INFO): Sent 192 bytes from file Logs/20130305T055746/shore0005.lzma
2013-03-05T06:22:38.385Z,1362464558.385 [NAL9602](INFO): Packets left to send: 0
2013-03-05T06:22:38.387Z,1362464558.387 [NAL9602](INFO): Stored copy of sent data in Logs/20130305T055746/shore0005.lzma.parts/0000.sbd
2013-03-05T06:22:50.707Z,1362464570.707 [NAL9602](INFO): SBD MO Status=0, MOMSN=6495, MT Status=0, MTMSN=0
2013-03-05T06:22:50.792Z,1362464570.792 [Default:Iridium:Read_Iridium] Stopped
2013-03-05T06:22:50.792Z,1362464570.792 [Default:Iridium](INFO): Completed Default:Iridium
2013-03-05T06:22:50.792Z,1362464570.792 [Default:Iridium] Stopped
2013-03-05T06:22:50.792Z,1362464570.792 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-03-05T06:22:50.793Z,1362464570.793 [Default:Iridium:A.SetSpeed] Stopped
2013-03-05T06:22:50.793Z,1362464570.793 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-03-05T06:22:51.200Z,1362464571.200 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-03-05T06:22:51.200Z,1362464571.200 [Default:CallIridium:B] Stopped
2013-03-05T06:22:51.200Z,1362464571.200 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-03-05T06:22:51.200Z,1362464571.200 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-03-05T06:22:51.200Z,1362464571.200 [Default:CallIridium] Stopped
2013-03-05T06:22:51.200Z,1362464571.200 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-03-05T06:22:51.908Z,1362464571.908 [NAL9602](IMPORTANT): GPS fix at: 1362507857.00
2013-03-05T06:22:51.926Z,1362464571.926 [Default:GPS:Read_GPS] Stopped
2013-03-05T06:22:51.926Z,1362464571.926 [Default:GPS:D] Running Loop=1
2013-03-05T06:22:52.343Z,1362464572.343 [Default:GPS:D] Stopped
2013-03-05T06:22:52.344Z,1362464572.344 [Default:GPS](INFO): Completed Default:GPS
2013-03-05T06:22:52.344Z,1362464572.344 [Default:GPS] Stopped
2013-03-05T06:22:52.344Z,1362464572.344 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-03-05T06:22:52.344Z,1362464572.344 [Default:GPS:A.SetSpeed] Stopped
2013-03-05T06:22:52.344Z,1362464572.344 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-03-05T06:23:12.835Z,1362464592.835 [NAL9602](INFO): Powering down
2013-03-05T06:27:52.857Z,1362464872.857 [Default:CallIridium] Running Loop=1
2013-03-05T06:27:52.857Z,1362464872.857 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-03-05T06:27:52.857Z,1362464872.857 [Default:CallIridium:A] Running Loop=1
2013-03-05T06:27:52.858Z,1362464872.858 [Default:CallIridium:A] Stopped
2013-03-05T06:27:52.858Z,1362464872.858 [Default:CallIridium:B] Running Loop=1
2013-03-05T06:27:52.858Z,1362464872.858 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-03-05T06:27:57.960Z,1362464877.960 [Default:Iridium] Running Loop=1
2013-03-05T06:27:57.960Z,1362464877.960 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-03-05T06:27:57.960Z,1362464877.960 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-03-05T06:27:57.960Z,1362464877.960 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-03-05T06:27:57.960Z,1362464877.960 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-03-05T06:27:57.960Z,1362464877.960 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-05T06:27:57.961Z,1362464877.961 [Default:Iridium:B.GoToSurface] Stopped
2013-03-05T06:27:57.961Z,1362464877.961 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-05T06:27:57.961Z,1362464877.961 [Default:Iridium:Read_Iridium] Running Loop=1
2013-03-05T06:27:57.962Z,1362464877.962 [Default:GPS] Running Loop=1
2013-03-05T06:27:57.962Z,1362464877.962 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-03-05T06:27:57.962Z,1362464877.962 [Default:GPS:A.SetSpeed] Running Loop=1
2013-03-05T06:27:57.962Z,1362464877.962 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-03-05T06:27:57.962Z,1362464877.962 [Default:GPS:B.GoToSurface] Running Loop=1
2013-03-05T06:27:57.962Z,1362464877.962 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-05T06:27:57.963Z,1362464877.963 [Default:GPS:B.GoToSurface] Stopped
2013-03-05T06:27:57.963Z,1362464877.963 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-05T06:27:57.963Z,1362464877.963 [Default:GPS:Read_GPS] Running Loop=1
2013-03-05T06:27:58.553Z,1362464878.553 [NAL9602](INFO): Powering up
2013-03-05T06:28:09.389Z,1362464889.389 [NAL9602](INFO): NAL9602 initialized
2013-03-05T06:28:27.178Z,1362464907.178 [NAL9602](INFO): SBD MO Status=1, MOMSN=6496, MT Status=0, MTMSN=0
2013-03-05T06:28:27.225Z,1362464907.225 [NAL9602](INFO): Sent 193 bytes from file Logs/20130305T055746/shore0006.lzma
2013-03-05T06:28:27.225Z,1362464907.225 [NAL9602](INFO): Packets left to send: 0
2013-03-05T06:28:27.227Z,1362464907.227 [NAL9602](INFO): Stored copy of sent data in Logs/20130305T055746/shore0006.lzma.parts/0000.sbd
2013-03-05T06:28:38.748Z,1362464918.747 [NAL9602](INFO): SBD MO Status=0, MOMSN=6497, MT Status=0, MTMSN=0
2013-03-05T06:28:38.832Z,1362464918.832 [Default:Iridium:Read_Iridium] Stopped
2013-03-05T06:28:38.832Z,1362464918.832 [Default:Iridium](INFO): Completed Default:Iridium
2013-03-05T06:28:38.832Z,1362464918.832 [Default:Iridium] Stopped
2013-03-05T06:28:38.833Z,1362464918.833 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-03-05T06:28:38.833Z,1362464918.833 [Default:Iridium:A.SetSpeed] Stopped
2013-03-05T06:28:38.833Z,1362464918.833 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-03-05T06:28:39.162Z,1362464919.162 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-03-05T06:28:39.162Z,1362464919.162 [Default:CallIridium:B] Stopped
2013-03-05T06:28:39.162Z,1362464919.162 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-03-05T06:28:39.162Z,1362464919.162 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-03-05T06:28:39.162Z,1362464919.162 [Default:CallIridium] Stopped
2013-03-05T06:28:39.163Z,1362464919.163 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-03-05T06:28:39.985Z,1362464919.985 [NAL9602](IMPORTANT): GPS fix at: 1362508206.00
2013-03-05T06:28:40.019Z,1362464920.019 [Default:GPS:Read_GPS] Stopped
2013-03-05T06:28:40.019Z,1362464920.019 [Default:GPS:D] Running Loop=1
2013-03-05T06:28:40.377Z,1362464920.377 [Default:GPS:D] Stopped
2013-03-05T06:28:40.378Z,1362464920.378 [Default:GPS](INFO): Completed Default:GPS
2013-03-05T06:28:40.378Z,1362464920.378 [Default:GPS] Stopped
2013-03-05T06:28:40.378Z,1362464920.378 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-03-05T06:28:40.378Z,1362464920.378 [Default:GPS:A.SetSpeed] Stopped
2013-03-05T06:28:40.378Z,1362464920.378 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-03-05T06:29:00.867Z,1362464940.866 [NAL9602](INFO): Powering down
2013-03-05T06:33:40.924Z,1362465220.924 [Default:CallIridium] Running Loop=1
2013-03-05T06:33:40.924Z,1362465220.924 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-03-05T06:33:40.924Z,1362465220.924 [Default:CallIridium:A] Running Loop=1
2013-03-05T06:33:40.925Z,1362465220.925 [Default:CallIridium:A] Stopped
2013-03-05T06:33:40.925Z,1362465220.925 [Default:CallIridium:B] Running Loop=1
2013-03-05T06:33:40.925Z,1362465220.925 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-03-05T06:33:45.887Z,1362465225.886 [Default:Iridium] Running Loop=1
2013-03-05T06:33:45.887Z,1362465225.887 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-03-05T06:33:45.887Z,1362465225.887 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-03-05T06:33:45.887Z,1362465225.887 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-03-05T06:33:45.887Z,1362465225.887 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-03-05T06:33:45.887Z,1362465225.887 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-05T06:33:45.888Z,1362465225.888 [Default:Iridium:B.GoToSurface] Stopped
2013-03-05T06:33:45.888Z,1362465225.888 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-05T06:33:45.888Z,1362465225.888 [Default:Iridium:Read_Iridium] Running Loop=1
2013-03-05T06:33:45.888Z,1362465225.888 [Default:GPS] Running Loop=1
2013-03-05T06:33:45.889Z,1362465225.889 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-03-05T06:33:45.889Z,1362465225.889 [Default:GPS:A.SetSpeed] Running Loop=1
2013-03-05T06:33:45.889Z,1362465225.889 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-03-05T06:33:45.889Z,1362465225.889 [Default:GPS:B.GoToSurface] Running Loop=1
2013-03-05T06:33:45.889Z,1362465225.889 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-05T06:33:45.890Z,1362465225.890 [Default:GPS:B.GoToSurface] Stopped
2013-03-05T06:33:45.890Z,1362465225.890 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-05T06:33:45.890Z,1362465225.890 [Default:GPS:Read_GPS] Running Loop=1
2013-03-05T06:33:46.582Z,1362465226.582 [NAL9602](INFO): Powering up
2013-03-05T06:33:57.385Z,1362465237.385 [NAL9602](INFO): NAL9602 initialized
2013-03-05T06:34:15.116Z,1362465255.116 [NAL9602](INFO): SBD MO Status=1, MOMSN=6498, MT Status=0, MTMSN=0
2013-03-05T06:34:15.165Z,1362465255.165 [NAL9602](INFO): Sent 167 bytes from file Logs/20130305T055746/shore0007.lzma
2013-03-05T06:34:15.165Z,1362465255.165 [NAL9602](INFO): Packets left to send: 0
2013-03-05T06:34:15.167Z,1362465255.167 [NAL9602](INFO): Stored copy of sent data in Logs/20130305T055746/shore0007.lzma.parts/0000.sbd
2013-03-05T06:34:25.940Z,1362465265.940 [NAL9602](INFO): SBD MO Status=0, MOMSN=6499, MT Status=0, MTMSN=0
2013-03-05T06:34:26.035Z,1362465266.035 [Default:Iridium:Read_Iridium] Stopped
2013-03-05T06:34:26.035Z,1362465266.035 [Default:Iridium](INFO): Completed Default:Iridium
2013-03-05T06:34:26.035Z,1362465266.035 [Default:Iridium] Stopped
2013-03-05T06:34:26.036Z,1362465266.036 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-03-05T06:34:26.036Z,1362465266.036 [Default:Iridium:A.SetSpeed] Stopped
2013-03-05T06:34:26.036Z,1362465266.036 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-03-05T06:34:26.375Z,1362465266.375 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-03-05T06:34:26.375Z,1362465266.375 [Default:CallIridium:B] Stopped
2013-03-05T06:34:26.375Z,1362465266.375 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-03-05T06:34:26.375Z,1362465266.375 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-03-05T06:34:26.375Z,1362465266.375 [Default:CallIridium] Stopped
2013-03-05T06:34:26.375Z,1362465266.375 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-03-05T06:34:27.117Z,1362465267.117 [NAL9602](IMPORTANT): GPS fix at: 1362508554.00
2013-03-05T06:34:27.134Z,1362465267.134 [Default:GPS:Read_GPS] Stopped
2013-03-05T06:34:27.134Z,1362465267.134 [Default:GPS:D] Running Loop=1
2013-03-05T06:34:27.563Z,1362465267.563 [Default:GPS:D] Stopped
2013-03-05T06:34:27.563Z,1362465267.563 [Default:GPS](INFO): Completed Default:GPS
2013-03-05T06:34:27.564Z,1362465267.564 [Default:GPS] Stopped
2013-03-05T06:34:27.564Z,1362465267.564 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-03-05T06:34:27.564Z,1362465267.564 [Default:GPS:A.SetSpeed] Stopped
2013-03-05T06:34:27.564Z,1362465267.564 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-03-05T06:34:48.039Z,1362465288.039 [NAL9602](INFO): Powering down
2013-03-05T06:39:28.058Z,1362465568.058 [Default:CallIridium] Running Loop=1
2013-03-05T06:39:28.058Z,1362465568.058 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-03-05T06:39:28.058Z,1362465568.058 [Default:CallIridium:A] Running Loop=1
2013-03-05T06:39:28.058Z,1362465568.059 [Default:CallIridium:A] Stopped
2013-03-05T06:39:28.059Z,1362465568.059 [Default:CallIridium:B] Running Loop=1
2013-03-05T06:39:28.059Z,1362465568.059 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-03-05T06:39:33.153Z,1362465573.153 [Default:Iridium] Running Loop=1
2013-03-05T06:39:33.153Z,1362465573.153 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-03-05T06:39:33.153Z,1362465573.153 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-03-05T06:39:33.153Z,1362465573.153 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-03-05T06:39:33.154Z,1362465573.154 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-03-05T06:39:33.154Z,1362465573.154 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-05T06:39:33.154Z,1362465573.154 [Default:Iridium:B.GoToSurface] Stopped
2013-03-05T06:39:33.154Z,1362465573.155 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-05T06:39:33.155Z,1362465573.155 [Default:Iridium:Read_Iridium] Running Loop=1
2013-03-05T06:39:33.155Z,1362465573.155 [Default:GPS] Running Loop=1
2013-03-05T06:39:33.155Z,1362465573.155 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-03-05T06:39:33.155Z,1362465573.155 [Default:GPS:A.SetSpeed] Running Loop=1
2013-03-05T06:39:33.155Z,1362465573.155 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-03-05T06:39:33.155Z,1362465573.155 [Default:GPS:B.GoToSurface] Running Loop=1
2013-03-05T06:39:33.155Z,1362465573.155 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-05T06:39:33.156Z,1362465573.156 [Default:GPS:B.GoToSurface] Stopped
2013-03-05T06:39:33.156Z,1362465573.156 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-05T06:39:33.156Z,1362465573.157 [Default:GPS:Read_GPS] Running Loop=1
2013-03-05T06:39:33.741Z,1362465573.741 [NAL9602](INFO): Powering up
2013-03-05T06:39:44.581Z,1362465584.581 [NAL9602](INFO): NAL9602 initialized
2013-03-05T06:40:02.735Z,1362465602.735 [NAL9602](INFO): SBD MO Status=1, MOMSN=6500, MT Status=0, MTMSN=0
2013-03-05T06:40:02.785Z,1362465602.785 [NAL9602](INFO): Sent 183 bytes from file Logs/20130305T055746/shore0008.lzma
2013-03-05T06:40:02.786Z,1362465602.786 [NAL9602](INFO): Packets left to send: 0
2013-03-05T06:40:02.787Z,1362465602.787 [NAL9602](INFO): Stored copy of sent data in Logs/20130305T055746/shore0008.lzma.parts/0000.sbd
2013-03-05T06:40:14.336Z,1362465614.336 [NAL9602](INFO): SBD MO Status=0, MOMSN=6501, MT Status=0, MTMSN=0
2013-03-05T06:40:14.427Z,1362465614.427 [Default:Iridium:Read_Iridium] Stopped
2013-03-05T06:40:14.428Z,1362465614.428 [Default:Iridium](INFO): Completed Default:Iridium
2013-03-05T06:40:14.428Z,1362465614.428 [Default:Iridium] Stopped
2013-03-05T06:40:14.428Z,1362465614.428 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-03-05T06:40:14.428Z,1362465614.428 [Default:Iridium:A.SetSpeed] Stopped
2013-03-05T06:40:14.428Z,1362465614.428 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-03-05T06:40:14.786Z,1362465614.786 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-03-05T06:40:14.786Z,1362465614.786 [Default:CallIridium:B] Stopped
2013-03-05T06:40:14.786Z,1362465614.786 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-03-05T06:40:14.786Z,1362465614.786 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-03-05T06:40:14.786Z,1362465614.786 [Default:CallIridium] Stopped
2013-03-05T06:40:14.786Z,1362465614.786 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-03-05T06:40:15.538Z,1362465615.538 [NAL9602](IMPORTANT): GPS fix at: 1362508903.00
2013-03-05T06:40:15.555Z,1362465615.555 [Default:GPS:Read_GPS] Stopped
2013-03-05T06:40:15.555Z,1362465615.555 [Default:GPS:D] Running Loop=1
2013-03-05T06:40:15.961Z,1362465615.961 [Default:GPS:D] Stopped
2013-03-05T06:40:15.961Z,1362465615.961 [Default:GPS](INFO): Completed Default:GPS
2013-03-05T06:40:15.961Z,1362465615.961 [Default:GPS] Stopped
2013-03-05T06:40:15.962Z,1362465615.962 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-03-05T06:40:15.962Z,1362465615.962 [Default:GPS:A.SetSpeed] Stopped
2013-03-05T06:40:15.962Z,1362465615.962 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-03-05T06:40:36.455Z,1362465636.455 [NAL9602](INFO): Powering down
2013-03-05T06:45:16.470Z,1362465916.470 [Default:CallIridium] Running Loop=1
2013-03-05T06:45:16.471Z,1362465916.471 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-03-05T06:45:16.471Z,1362465916.471 [Default:CallIridium:A] Running Loop=1
2013-03-05T06:45:16.471Z,1362465916.471 [Default:CallIridium:A] Stopped
2013-03-05T06:45:16.471Z,1362465916.471 [Default:CallIridium:B] Running Loop=1
2013-03-05T06:45:16.472Z,1362465916.471 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-03-05T06:45:21.499Z,1362465921.499 [Default:Iridium] Running Loop=1
2013-03-05T06:45:21.499Z,1362465921.499 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-03-05T06:45:21.499Z,1362465921.499 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-03-05T06:45:21.499Z,1362465921.499 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-03-05T06:45:21.499Z,1362465921.499 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-03-05T06:45:21.499Z,1362465921.499 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-05T06:45:21.500Z,1362465921.500 [Default:Iridium:B.GoToSurface] Stopped
2013-03-05T06:45:21.500Z,1362465921.500 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-05T06:45:21.500Z,1362465921.500 [Default:Iridium:Read_Iridium] Running Loop=1
2013-03-05T06:45:21.525Z,1362465921.525 [Default:GPS] Running Loop=1
2013-03-05T06:45:21.525Z,1362465921.525 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-03-05T06:45:21.525Z,1362465921.525 [Default:GPS:A.SetSpeed] Running Loop=1
2013-03-05T06:45:21.525Z,1362465921.525 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-03-05T06:45:21.525Z,1362465921.525 [Default:GPS:B.GoToSurface] Running Loop=1
2013-03-05T06:45:21.525Z,1362465921.525 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-05T06:45:21.526Z,1362465921.526 [Default:GPS:B.GoToSurface] Stopped
2013-03-05T06:45:21.526Z,1362465921.526 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-05T06:45:21.526Z,1362465921.526 [Default:GPS:Read_GPS] Running Loop=1
2013-03-05T06:45:22.130Z,1362465922.130 [NAL9602](INFO): Powering up
2013-03-05T06:45:32.565Z,1362465932.565 [NAL9602](INFO): NAL9602 initialized
2013-03-05T06:46:00.287Z,1362465960.287 [NAL9602](INFO): SBD MO Status=1, MOMSN=6502, MT Status=0, MTMSN=0
2013-03-05T06:46:00.345Z,1362465960.345 [NAL9602](INFO): Sent 165 bytes from file Logs/20130305T055746/shore0009.lzma
2013-03-05T06:46:00.345Z,1362465960.345 [NAL9602](INFO): Packets left to send: 0
2013-03-05T06:46:00.347Z,1362465960.347 [NAL9602](INFO): Stored copy of sent data in Logs/20130305T055746/shore0009.lzma.parts/0000.sbd
2013-03-05T06:46:59.094Z,1362466019.094 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2013-03-05T06:47:01.494Z,1362466021.494 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2013-03-05T06:47:03.898Z,1362466023.898 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2013-03-05T06:47:05.886Z,1362466025.886 [NAL9602](ERROR): Verify xmit timeout failure.
2013-03-05T06:47:06.298Z,1362466026.298 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2013-03-05T06:47:08.699Z,1362466028.699 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2013-03-05T06:47:11.098Z,1362466031.098 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2013-03-05T06:47:21.483Z,1362466041.483 [NAL9602](INFO): SBD MO Status=0, MOMSN=6503, MT Status=0, MTMSN=0
2013-03-05T06:47:21.567Z,1362466041.567 [Default:Iridium:Read_Iridium] Stopped
2013-03-05T06:47:21.567Z,1362466041.567 [Default:Iridium](INFO): Completed Default:Iridium
2013-03-05T06:47:21.567Z,1362466041.567 [Default:Iridium] Stopped
2013-03-05T06:47:21.567Z,1362466041.567 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-03-05T06:47:21.567Z,1362466041.568 [Default:Iridium:A.SetSpeed] Stopped
2013-03-05T06:47:21.568Z,1362466041.568 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-03-05T06:47:21.914Z,1362466041.914 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-03-05T06:47:21.914Z,1362466041.914 [Default:CallIridium:B] Stopped
2013-03-05T06:47:21.914Z,1362466041.914 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-03-05T06:47:21.914Z,1362466041.914 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-03-05T06:47:21.914Z,1362466041.914 [Default:CallIridium] Stopped
2013-03-05T06:47:21.914Z,1362466041.914 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-03-05T06:47:22.738Z,1362466042.738 [NAL9602](IMPORTANT): GPS fix at: 1362509331.00
2013-03-05T06:47:22.754Z,1362466042.754 [Default:GPS:Read_GPS] Stopped
2013-03-05T06:47:22.755Z,1362466042.755 [Default:GPS:D] Running Loop=1
2013-03-05T06:47:23.119Z,1362466043.119 [Default:GPS:D] Stopped
2013-03-05T06:47:23.120Z,1362466043.120 [Default:GPS](INFO): Completed Default:GPS
2013-03-05T06:47:23.120Z,1362466043.120 [Default:GPS] Stopped
2013-03-05T06:47:23.120Z,1362466043.120 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-03-05T06:47:23.120Z,1362466043.120 [Default:GPS:A.SetSpeed] Stopped
2013-03-05T06:47:23.120Z,1362466043.120 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-03-05T06:47:43.631Z,1362466063.631 [NAL9602](INFO): Powering down
2013-03-05T06:52:23.630Z,1362466343.630 [Default:CallIridium] Running Loop=1
2013-03-05T06:52:23.630Z,1362466343.630 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-03-05T06:52:23.630Z,1362466343.630 [Default:CallIridium:A] Running Loop=1
2013-03-05T06:52:23.631Z,1362466343.631 [Default:CallIridium:A] Stopped
2013-03-05T06:52:23.631Z,1362466343.631 [Default:CallIridium:B] Running Loop=1
2013-03-05T06:52:23.631Z,1362466343.631 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-03-05T06:52:28.631Z,1362466348.631 [Default:Iridium] Running Loop=1
2013-03-05T06:52:28.631Z,1362466348.631 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-03-05T06:52:28.631Z,1362466348.631 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-03-05T06:52:28.631Z,1362466348.631 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-03-05T06:52:28.631Z,1362466348.631 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-03-05T06:52:28.631Z,1362466348.631 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-05T06:52:28.632Z,1362466348.632 [Default:Iridium:B.GoToSurface] Stopped
2013-03-05T06:52:28.632Z,1362466348.632 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-05T06:52:28.632Z,1362466348.632 [Default:Iridium:Read_Iridium] Running Loop=1
2013-03-05T06:52:28.633Z,1362466348.633 [Default:GPS] Running Loop=1
2013-03-05T06:52:28.633Z,1362466348.633 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-03-05T06:52:28.633Z,1362466348.633 [Default:GPS:A.SetSpeed] Running Loop=1
2013-03-05T06:52:28.633Z,1362466348.633 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-03-05T06:52:28.633Z,1362466348.633 [Default:GPS:B.GoToSurface] Running Loop=1
2013-03-05T06:52:28.633Z,1362466348.633 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-05T06:52:28.634Z,1362466348.634 [Default:GPS:B.GoToSurface] Stopped
2013-03-05T06:52:28.634Z,1362466348.634 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-05T06:52:28.634Z,1362466348.634 [Default:GPS:Read_GPS] Running Loop=1
2013-03-05T06:52:29.282Z,1362466349.282 [NAL9602](INFO): Powering up
2013-03-05T06:52:40.117Z,1362466360.117 [NAL9602](INFO): NAL9602 initialized
2013-03-05T07:00:57.993Z,1362466857.993 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2013-03-05T07:01:00.381Z,1362466860.381 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2013-03-05T07:01:02.781Z,1362466862.781 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2013-03-05T07:01:05.183Z,1362466865.183 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2013-03-05T07:01:07.531Z,1362466867.531 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2013-03-05T07:02:43.246Z,1362466963.246 [NAL9602](FAULT): GPS failed to acquire within timeout.
2013-03-05T07:02:43.246Z,1362466963.246 [NAL9602] Data Fault, FailCount= 1
2013-03-05T07:02:43.246Z,1362466963.246 [NAL9602](ERROR): Data Fault
2013-03-05T07:02:43.272Z,1362466963.272 [CBIT](ERROR): Data Fault in component: NAL9602
2013-03-05T07:02:43.646Z,1362466963.646 [NAL9602](INFO): Powering down
2013-03-05T07:02:44.471Z,1362466964.471 [CBIT](INFO): Clearing failed state for component NAL9602
2013-03-05T07:02:44.471Z,1362466964.471 [NAL9602] No Fault, FailCount= 1
2013-03-05T07:02:48.844Z,1362466968.844 [NAL9602](INFO): Powering up NAL9602
2013-03-05T07:02:59.677Z,1362466979.677 [NAL9602](INFO): NAL9602 initialized
2013-03-05T07:13:00.734Z,1362467580.734 [NAL9602](FAULT): GPS failed to acquire within timeout.
2013-03-05T07:13:00.734Z,1362467580.734 [NAL9602] Data Fault, FailCount= 2
2013-03-05T07:13:00.734Z,1362467580.734 [NAL9602](ERROR): Data Fault
2013-03-05T07:13:00.764Z,1362467580.764 [CBIT](ERROR): Data Fault in component: NAL9602
2013-03-05T07:13:00.764Z,1362467580.764 [CBIT](CRITICAL): Data Fault in component: NAL9602
2013-03-05T07:13:01.134Z,1362467581.134 [NAL9602](INFO): Powering down
2013-03-05T07:15:00.764Z,1362467700.764 [CBIT](INFO): Clearing failed count for component NAL9602
2013-03-05T07:15:00.764Z,1362467700.764 [NAL9602] No Fault, FailCount= 2
2013-03-05T07:15:01.135Z,1362467701.135 [NAL9602](INFO): Powering up NAL9602
2013-03-05T07:15:11.965Z,1362467711.965 [NAL9602](INFO): NAL9602 initialized
2013-03-05T07:15:12.801Z,1362467712.801 [NAL9602](ERROR): getFix uart error: serial timeout