2013-06-06T21:30:30.983Z,1370554230.983 [NAL9602](INFO): Received command:Restart logs
2013-06-06T21:30:30.998Z,1370554230.998 [CommandLine](IMPORTANT): got command restart logs
2013-06-06T21:30:58.263Z,1370554258.263 [NAL9602](INFO): SBD MO Status=1, MOMSN=14104, MT Status=0, MTMSN=0
2013-06-06T21:30:58.310Z,1370554258.310 [NAL9602](INFO): Sent 81 bytes from file Logs/20130606T143913/Courier0212.lzma
2013-06-06T21:30:58.310Z,1370554258.310 [NAL9602](INFO): Packets left to send: 0
2013-06-06T21:30:58.312Z,1370554258.312 [NAL9602](INFO): Stored copy of sent data in Logs/20130606T143913/Courier0212.lzma.parts/0000.sbd
2013-06-06T21:31:08.525Z,1370554268.525 [NAL9602](INFO): SBD MO Status=1, MOMSN=14105, MT Status=0, MTMSN=0
2013-06-06T21:31:08.574Z,1370554268.574 [NAL9602](INFO): Sent 112 bytes from file Logs/20130606T143913/Express0209.lzma
2013-06-06T21:31:08.574Z,1370554268.574 [NAL9602](INFO): Packets left to send: 0
2013-06-06T21:31:08.576Z,1370554268.576 [NAL9602](INFO): Stored copy of sent data in Logs/20130606T143913/Express0209.lzma.parts/0000.sbd
2013-06-06T21:31:15.615Z,1370554275.615 [NAL9602](INFO): SBD MO Status=1, MOMSN=14106, MT Status=0, MTMSN=0
2013-06-06T21:31:15.662Z,1370554275.662 [NAL9602](INFO): Sent 174 bytes from file Logs/20130606T143913/Express0213.lzma
2013-06-06T21:31:15.662Z,1370554275.662 [NAL9602](INFO): Packets left to send: 0
2013-06-06T21:31:15.664Z,1370554275.664 [NAL9602](INFO): Stored copy of sent data in Logs/20130606T143913/Express0213.lzma.parts/0000.sbd
2013-06-06T21:31:22.913Z,1370554282.913 [NAL9602](INFO): SBD MO Status=0, MOMSN=14107, MT Status=0, MTMSN=0
2013-06-06T21:31:41.545Z,1370554301.545 [NAL9602](INFO): SBD MO Status=1, MOMSN=14108, MT Status=0, MTMSN=0
2013-06-06T21:31:41.598Z,1370554301.598 [NAL9602](INFO): Sent 186 bytes from file Logs/20130606T213030/Courier0000.lzma
2013-06-06T21:31:41.599Z,1370554301.599 [NAL9602](INFO): Packets left to send: 0
2013-06-06T21:31:41.600Z,1370554301.600 [NAL9602](INFO): Stored copy of sent data in Logs/20130606T213030/Courier0000.lzma.parts/0000.sbd
2013-06-06T21:31:55.804Z,1370554315.805 [NAL9602](INFO): SBD MO Status=1, MOMSN=14109, MT Status=0, MTMSN=0
2013-06-06T21:31:55.854Z,1370554315.854 [NAL9602](INFO): Sent 256 bytes from file Logs/20130606T213030/Express0001.lzma
2013-06-06T21:31:55.854Z,1370554315.854 [NAL9602](INFO): Packets left to send: 0
2013-06-06T21:31:55.856Z,1370554315.856 [NAL9602](INFO): Stored copy of sent data in Logs/20130606T213030/Express0001.lzma.parts/0000.sbd
2013-06-06T21:32:06.199Z,1370554326.199 [NAL9602](INFO): SBD MO Status=0, MOMSN=14110, MT Status=0, MTMSN=0
2013-06-06T21:32:09.629Z,1370554329.629 [Default:Iridium:Read_Iridium] Stopped
2013-06-06T21:32:09.630Z,1370554329.630 [Default:Iridium](INFO): Completed Default:Iridium
2013-06-06T21:32:09.630Z,1370554329.630 [Default:Iridium] Stopped
2013-06-06T21:32:09.630Z,1370554329.630 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-06-06T21:32:09.630Z,1370554329.630 [Default:Iridium:A.SetSpeed] Stopped
2013-06-06T21:32:09.630Z,1370554329.630 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-06-06T21:32:10.034Z,1370554330.034 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-06-06T21:32:10.034Z,1370554330.034 [Default:CallIridium:B] Stopped
2013-06-06T21:32:10.034Z,1370554330.034 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-06-06T21:32:10.034Z,1370554330.034 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-06-06T21:32:10.034Z,1370554330.034 [Default:CallIridium] Stopped
2013-06-06T21:32:10.034Z,1370554330.034 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-06-06T21:32:19.980Z,1370554339.980 [NAL9602](INFO): Powering down
2013-06-06T21:37:09.976Z,1370554629.976 [Default:CallIridium] Running Loop=1
2013-06-06T21:37:09.976Z,1370554629.977 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-06-06T21:37:09.977Z,1370554629.977 [Default:CallIridium:A] Running Loop=1
2013-06-06T21:37:09.977Z,1370554629.977 [Default:CallIridium:A] Stopped
2013-06-06T21:37:09.977Z,1370554629.977 [Default:CallIridium:B] Running Loop=1
2013-06-06T21:37:09.977Z,1370554629.977 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-06-06T21:37:15.039Z,1370554635.039 [Default:Iridium] Running Loop=1
2013-06-06T21:37:15.040Z,1370554635.039 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-06-06T21:37:15.040Z,1370554635.040 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-06-06T21:37:15.040Z,1370554635.040 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-06-06T21:37:15.040Z,1370554635.040 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-06-06T21:37:15.040Z,1370554635.040 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-06-06T21:37:15.041Z,1370554635.041 [Default:Iridium:B.GoToSurface] Stopped
2013-06-06T21:37:15.041Z,1370554635.041 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-06-06T21:37:15.041Z,1370554635.041 [Default:Iridium:Read_Iridium] Running Loop=1
2013-06-06T21:37:15.041Z,1370554635.042 [Default:GPS] Running Loop=1
2013-06-06T21:37:15.042Z,1370554635.042 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-06-06T21:37:15.042Z,1370554635.042 [Default:GPS:A.SetSpeed] Running Loop=1
2013-06-06T21:37:15.042Z,1370554635.042 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-06-06T21:37:15.042Z,1370554635.042 [Default:GPS:B.GoToSurface] Running Loop=1
2013-06-06T21:37:15.042Z,1370554635.042 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-06-06T21:37:15.043Z,1370554635.043 [Default:GPS:B.GoToSurface] Stopped
2013-06-06T21:37:15.043Z,1370554635.043 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-06-06T21:37:15.043Z,1370554635.043 [Default:GPS:Read_GPS] Running Loop=1
2013-06-06T21:37:15.643Z,1370554635.643 [NAL9602](INFO): Powering up
2013-06-06T21:37:26.474Z,1370554646.474 [NAL9602](INFO): NAL9602 initialized
2013-06-06T21:37:43.424Z,1370554663.424 [NAL9602](INFO): SBD MO Status=1, MOMSN=14111, MT Status=0, MTMSN=0
2013-06-06T21:37:43.474Z,1370554663.474 [NAL9602](INFO): Sent 45 bytes from file Logs/20130606T213030/Courier0004.lzma
2013-06-06T21:37:43.474Z,1370554663.474 [NAL9602](INFO): Packets left to send: 0
2013-06-06T21:37:43.476Z,1370554663.476 [NAL9602](INFO): Stored copy of sent data in Logs/20130606T213030/Courier0004.lzma.parts/0000.sbd
2013-06-06T21:37:51.341Z,1370554671.341 [NAL9602](INFO): SBD MO Status=1, MOMSN=14112, MT Status=0, MTMSN=0
2013-06-06T21:37:51.398Z,1370554671.398 [NAL9602](INFO): Sent 61 bytes from file Logs/20130606T213030/Express0005.lzma
2013-06-06T21:37:51.398Z,1370554671.398 [NAL9602](INFO): Packets left to send: 0
2013-06-06T21:37:51.400Z,1370554671.399 [NAL9602](INFO): Stored copy of sent data in Logs/20130606T213030/Express0005.lzma.parts/0000.sbd
2013-06-06T21:38:00.973Z,1370554680.973 [NAL9602](INFO): SBD MO Status=0, MOMSN=14113, MT Status=0, MTMSN=0
2013-06-06T21:38:04.282Z,1370554684.283 [Default:Iridium:Read_Iridium] Stopped
2013-06-06T21:38:04.283Z,1370554684.283 [Default:Iridium](INFO): Completed Default:Iridium
2013-06-06T21:38:04.283Z,1370554684.283 [Default:Iridium] Stopped
2013-06-06T21:38:04.283Z,1370554684.283 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-06-06T21:38:04.283Z,1370554684.283 [Default:Iridium:A.SetSpeed] Stopped
2013-06-06T21:38:04.283Z,1370554684.283 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-06-06T21:38:04.623Z,1370554684.623 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-06-06T21:38:04.623Z,1370554684.623 [Default:CallIridium:B] Stopped
2013-06-06T21:38:04.623Z,1370554684.624 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-06-06T21:38:04.624Z,1370554684.624 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-06-06T21:38:04.624Z,1370554684.624 [Default:CallIridium] Stopped
2013-06-06T21:38:04.624Z,1370554684.624 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-06-06T21:38:05.306Z,1370554685.306 [NAL9602](IMPORTANT): GPS fix at: 1370554850.00
2013-06-06T21:38:05.322Z,1370554685.322 [Default:GPS:Read_GPS] Stopped
2013-06-06T21:38:05.323Z,1370554685.323 [Default:GPS:D] Running Loop=1
2013-06-06T21:38:05.736Z,1370554685.736 [Default:GPS:D] Stopped
2013-06-06T21:38:05.736Z,1370554685.736 [Default:GPS](INFO): Completed Default:GPS
2013-06-06T21:38:05.736Z,1370554685.736 [Default:GPS] Stopped
2013-06-06T21:38:05.736Z,1370554685.736 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-06-06T21:38:05.737Z,1370554685.737 [Default:GPS:A.SetSpeed] Stopped
2013-06-06T21:38:05.737Z,1370554685.737 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-06-06T21:38:26.312Z,1370554706.312 [NAL9602](INFO): Powering down
2013-06-06T21:43:01.324Z,1370554981.324 [Default:CallIridium] Running Loop=1
2013-06-06T21:43:01.324Z,1370554981.324 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-06-06T21:43:01.324Z,1370554981.324 [Default:CallIridium:A] Running Loop=1
2013-06-06T21:43:01.324Z,1370554981.325 [Default:CallIridium:A] Stopped
2013-06-06T21:43:01.325Z,1370554981.325 [Default:CallIridium:B] Running Loop=1
2013-06-06T21:43:01.325Z,1370554981.325 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-06-06T21:43:06.276Z,1370554986.276 [Default:Iridium] Running Loop=1
2013-06-06T21:43:06.276Z,1370554986.276 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-06-06T21:43:06.276Z,1370554986.276 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-06-06T21:43:06.276Z,1370554986.276 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-06-06T21:43:06.276Z,1370554986.276 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-06-06T21:43:06.276Z,1370554986.276 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-06-06T21:43:06.277Z,1370554986.277 [Default:Iridium:B.GoToSurface] Stopped
2013-06-06T21:43:06.277Z,1370554986.277 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-06-06T21:43:06.278Z,1370554986.278 [Default:Iridium:Read_Iridium] Running Loop=1
2013-06-06T21:43:06.278Z,1370554986.278 [Default:GPS] Running Loop=1
2013-06-06T21:43:06.278Z,1370554986.278 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-06-06T21:43:06.278Z,1370554986.278 [Default:GPS:A.SetSpeed] Running Loop=1
2013-06-06T21:43:06.278Z,1370554986.278 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-06-06T21:43:06.279Z,1370554986.279 [Default:GPS:B.GoToSurface] Running Loop=1
2013-06-06T21:43:06.279Z,1370554986.279 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-06-06T21:43:06.280Z,1370554986.280 [Default:GPS:B.GoToSurface] Stopped
2013-06-06T21:43:06.280Z,1370554986.280 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-06-06T21:43:06.280Z,1370554986.280 [Default:GPS:Read_GPS] Running Loop=1
2013-06-06T21:43:06.910Z,1370554986.910 [NAL9602](INFO): Powering up
2013-06-06T21:43:17.382Z,1370554997.382 [NAL9602](INFO): NAL9602 initialized
2013-06-06T21:43:51.553Z,1370555031.553 [NAL9602](INFO): SBD MO Status=1, MOMSN=14114, MT Status=0, MTMSN=0
2013-06-06T21:43:51.610Z,1370555031.610 [NAL9602](INFO): Sent 226 bytes from file Logs/20130606T213030/Courier0008.lzma
2013-06-06T21:43:51.610Z,1370555031.610 [NAL9602](INFO): Packets left to send: 0
2013-06-06T21:43:51.612Z,1370555031.612 [NAL9602](INFO): Stored copy of sent data in Logs/20130606T213030/Courier0008.lzma.parts/0000.sbd
2013-06-06T21:44:12.637Z,1370555052.636 [NAL9602](INFO): SBD MO Status=1, MOMSN=14115, MT Status=0, MTMSN=0
2013-06-06T21:44:12.686Z,1370555052.686 [NAL9602](INFO): Sent 147 bytes from file Logs/20130606T213030/Express0009.lzma
2013-06-06T21:44:12.686Z,1370555052.686 [NAL9602](INFO): Packets left to send: 0
2013-06-06T21:44:12.688Z,1370555052.688 [NAL9602](INFO): Stored copy of sent data in Logs/20130606T213030/Express0009.lzma.parts/0000.sbd
2013-06-06T21:44:31.431Z,1370555071.431 [NAL9602](INFO): SBD MO Status=0, MOMSN=14116, MT Status=0, MTMSN=0
2013-06-06T21:44:34.743Z,1370555074.743 [Default:Iridium:Read_Iridium] Stopped
2013-06-06T21:44:34.744Z,1370555074.744 [Default:Iridium](INFO): Completed Default:Iridium
2013-06-06T21:44:34.744Z,1370555074.744 [Default:Iridium] Stopped
2013-06-06T21:44:34.744Z,1370555074.744 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-06-06T21:44:34.744Z,1370555074.744 [Default:Iridium:A.SetSpeed] Stopped
2013-06-06T21:44:34.744Z,1370555074.744 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-06-06T21:44:35.152Z,1370555075.152 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-06-06T21:44:35.152Z,1370555075.152 [Default:CallIridium:B] Stopped
2013-06-06T21:44:35.152Z,1370555075.152 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-06-06T21:44:35.153Z,1370555075.153 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-06-06T21:44:35.153Z,1370555075.153 [Default:CallIridium] Stopped
2013-06-06T21:44:35.153Z,1370555075.153 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-06-06T21:44:35.750Z,1370555075.750 [NAL9602](IMPORTANT): GPS fix at: 1370555241.00
2013-06-06T21:44:35.767Z,1370555075.767 [Default:GPS:Read_GPS] Stopped
2013-06-06T21:44:35.767Z,1370555075.767 [Default:GPS:D] Running Loop=1
2013-06-06T21:44:36.228Z,1370555076.228 [Default:GPS:D] Stopped
2013-06-06T21:44:36.228Z,1370555076.228 [Default:GPS](INFO): Completed Default:GPS
2013-06-06T21:44:36.228Z,1370555076.228 [Default:GPS] Stopped
2013-06-06T21:44:36.228Z,1370555076.228 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-06-06T21:44:36.228Z,1370555076.228 [Default:GPS:A.SetSpeed] Stopped
2013-06-06T21:44:36.228Z,1370555076.228 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-06-06T21:44:56.768Z,1370555096.768 [NAL9602](INFO): Powering down
2013-06-06T21:49:31.735Z,1370555371.735 [Default:CallIridium] Running Loop=1
2013-06-06T21:49:31.735Z,1370555371.735 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-06-06T21:49:31.735Z,1370555371.735 [Default:CallIridium:A] Running Loop=1
2013-06-06T21:49:31.735Z,1370555371.735 [Default:CallIridium:A] Stopped
2013-06-06T21:49:31.735Z,1370555371.735 [Default:CallIridium:B] Running Loop=1
2013-06-06T21:49:31.736Z,1370555371.736 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-06-06T21:49:36.752Z,1370555376.752 [Default:Iridium] Running Loop=1
2013-06-06T21:49:36.752Z,1370555376.752 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-06-06T21:49:36.752Z,1370555376.752 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-06-06T21:49:36.752Z,1370555376.752 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-06-06T21:49:36.752Z,1370555376.752 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-06-06T21:49:36.752Z,1370555376.752 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-06-06T21:49:36.753Z,1370555376.753 [Default:Iridium:B.GoToSurface] Stopped
2013-06-06T21:49:36.753Z,1370555376.753 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-06-06T21:49:36.754Z,1370555376.754 [Default:Iridium:Read_Iridium] Running Loop=1
2013-06-06T21:49:36.754Z,1370555376.754 [Default:GPS] Running Loop=1
2013-06-06T21:49:36.754Z,1370555376.754 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-06-06T21:49:36.754Z,1370555376.754 [Default:GPS:A.SetSpeed] Running Loop=1
2013-06-06T21:49:36.754Z,1370555376.754 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-06-06T21:49:36.755Z,1370555376.755 [Default:GPS:B.GoToSurface] Running Loop=1
2013-06-06T21:49:36.755Z,1370555376.755 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-06-06T21:49:36.756Z,1370555376.756 [Default:GPS:B.GoToSurface] Stopped
2013-06-06T21:49:36.756Z,1370555376.756 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-06-06T21:49:36.756Z,1370555376.756 [Default:GPS:Read_GPS] Running Loop=1
2013-06-06T21:49:37.346Z,1370555377.346 [NAL9602](INFO): Powering up
2013-06-06T21:49:39.763Z,1370555379.763 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2013-06-06T21:49:42.163Z,1370555382.163 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2013-06-06T21:49:44.563Z,1370555384.563 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2013-06-06T21:49:46.963Z,1370555386.963 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2013-06-06T21:49:47.786Z,1370555387.786 [NAL9602](INFO): NAL9602 initialized
2013-06-06T21:49:49.367Z,1370555389.367 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2013-06-06T21:49:51.763Z,1370555391.763 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2013-06-06T21:49:54.226Z,1370555394.226 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2013-06-06T21:49:56.563Z,1370555396.563 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2013-06-06T21:50:15.176Z,1370555415.176 [NAL9602](INFO): SBD MO Status=2, MOMSN=14117, MT Status=2, MTMSN=0
2013-06-06T21:50:15.177Z,1370555415.177 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-06-06T21:50:16.403Z,1370555416.403 [NAL9602](IMPORTANT): GPS fix at: 1370555582.00
2013-06-06T21:50:16.428Z,1370555416.428 [Default:GPS:Read_GPS] Stopped
2013-06-06T21:50:16.428Z,1370555416.428 [Default:GPS:D] Running Loop=1
2013-06-06T21:50:16.809Z,1370555416.809 [Default:GPS:D] Stopped
2013-06-06T21:50:16.826Z,1370555416.826 [Default:GPS](INFO): Completed Default:GPS
2013-06-06T21:50:16.826Z,1370555416.826 [Default:GPS] Stopped
2013-06-06T21:50:16.826Z,1370555416.826 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-06-06T21:50:16.826Z,1370555416.826 [Default:GPS:A.SetSpeed] Stopped
2013-06-06T21:50:16.826Z,1370555416.826 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-06-06T21:50:40.704Z,1370555440.704 [NAL9602](INFO): SBD MO Status=1, MOMSN=14117, MT Status=0, MTMSN=0
2013-06-06T21:50:40.754Z,1370555440.754 [NAL9602](INFO): Sent 79 bytes from file Logs/20130606T213030/Courier0012.lzma
2013-06-06T21:50:40.754Z,1370555440.754 [NAL9602](INFO): Packets left to send: 0
2013-06-06T21:50:40.756Z,1370555440.756 [NAL9602](INFO): Stored copy of sent data in Logs/20130606T213030/Courier0012.lzma.parts/0000.sbd
2013-06-06T21:50:50.540Z,1370555450.540 [NAL9602](INFO): SBD MO Status=1, MOMSN=14118, MT Status=0, MTMSN=0
2013-06-06T21:50:50.598Z,1370555450.598 [NAL9602](INFO): Sent 242 bytes from file Logs/20130606T213030/Express0013.lzma
2013-06-06T21:50:50.598Z,1370555450.598 [NAL9602](INFO): Packets left to send: 0
2013-06-06T21:50:50.600Z,1370555450.600 [NAL9602](INFO): Stored copy of sent data in Logs/20130606T213030/Express0013.lzma.parts/0000.sbd
2013-06-06T21:51:04.534Z,1370555464.534 [NAL9602](INFO): SBD MO Status=0, MOMSN=14119, MT Status=0, MTMSN=0
2013-06-06T21:51:07.998Z,1370555467.998 [Default:Iridium:Read_Iridium] Stopped
2013-06-06T21:51:07.998Z,1370555467.999 [Default:Iridium](INFO): Completed Default:Iridium
2013-06-06T21:51:07.999Z,1370555467.999 [Default:Iridium] Stopped
2013-06-06T21:51:07.999Z,1370555467.999 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-06-06T21:51:07.999Z,1370555467.999 [Default:Iridium:A.SetSpeed] Stopped
2013-06-06T21:51:07.999Z,1370555467.999 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-06-06T21:51:08.376Z,1370555468.376 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-06-06T21:51:08.376Z,1370555468.376 [Default:CallIridium:B] Stopped
2013-06-06T21:51:08.377Z,1370555468.376 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-06-06T21:51:08.377Z,1370555468.377 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-06-06T21:51:08.377Z,1370555468.377 [Default:CallIridium] Stopped
2013-06-06T21:51:08.377Z,1370555468.377 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-06-06T21:51:18.372Z,1370555478.372 [NAL9602](INFO): Powering down
2013-06-06T21:54:28.302Z,1370555668.302 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#6 STATUS: 65535
2013-06-06T21:56:08.387Z,1370555768.387 [Default:CallIridium] Running Loop=1
2013-06-06T21:56:08.387Z,1370555768.387 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-06-06T21:56:08.387Z,1370555768.387 [Default:CallIridium:A] Running Loop=1
2013-06-06T21:56:08.388Z,1370555768.388 [Default:CallIridium:A] Stopped
2013-06-06T21:56:08.388Z,1370555768.388 [Default:CallIridium:B] Running Loop=1
2013-06-06T21:56:08.388Z,1370555768.388 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-06-06T21:56:13.355Z,1370555773.355 [Default:Iridium] Running Loop=1
2013-06-06T21:56:13.356Z,1370555773.355 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-06-06T21:56:13.356Z,1370555773.356 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-06-06T21:56:13.356Z,1370555773.356 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-06-06T21:56:13.356Z,1370555773.356 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-06-06T21:56:13.356Z,1370555773.356 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-06-06T21:56:13.357Z,1370555773.357 [Default:Iridium:B.GoToSurface] Stopped
2013-06-06T21:56:13.357Z,1370555773.357 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-06-06T21:56:13.357Z,1370555773.357 [Default:Iridium:Read_Iridium] Running Loop=1
2013-06-06T21:56:13.358Z,1370555773.358 [Default:GPS] Running Loop=1
2013-06-06T21:56:13.358Z,1370555773.358 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-06-06T21:56:13.358Z,1370555773.358 [Default:GPS:A.SetSpeed] Running Loop=1
2013-06-06T21:56:13.358Z,1370555773.358 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-06-06T21:56:13.358Z,1370555773.358 [Default:GPS:B.GoToSurface] Running Loop=1
2013-06-06T21:56:13.358Z,1370555773.358 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-06-06T21:56:13.359Z,1370555773.359 [Default:GPS:B.GoToSurface] Stopped
2013-06-06T21:56:13.359Z,1370555773.359 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-06-06T21:56:13.359Z,1370555773.359 [Default:GPS:Read_GPS] Running Loop=1
2013-06-06T21:56:13.994Z,1370555773.994 [NAL9602](INFO): Powering up
2013-06-06T21:56:24.860Z,1370555784.860 [NAL9602](INFO): NAL9602 initialized
2013-06-06T21:56:41.001Z,1370555801.000 [NAL9602](INFO): SBD MO Status=1, MOMSN=14120, MT Status=0, MTMSN=0
2013-06-06T21:56:41.050Z,1370555801.050 [NAL9602](INFO): Sent 80 bytes from file Logs/20130606T213030/Courier0016.lzma
2013-06-06T21:56:41.050Z,1370555801.050 [NAL9602](INFO): Packets left to send: 0
2013-06-06T21:56:41.052Z,1370555801.052 [NAL9602](INFO): Stored copy of sent data in Logs/20130606T213030/Courier0016.lzma.parts/0000.sbd
2013-06-06T21:56:52.541Z,1370555812.541 [NAL9602](INFO): SBD MO Status=1, MOMSN=14121, MT Status=0, MTMSN=0
2013-06-06T21:56:52.607Z,1370555812.607 [NAL9602](INFO): Sent 124 bytes from file Logs/20130606T213030/Express0017.lzma
2013-06-06T21:56:52.607Z,1370555812.607 [NAL9602](INFO): Packets left to send: 0
2013-06-06T21:56:52.609Z,1370555812.609 [NAL9602](INFO): Stored copy of sent data in Logs/20130606T213030/Express0017.lzma.parts/0000.sbd
2013-06-06T21:57:02.877Z,1370555822.877 [NAL9602](INFO): SBD MO Status=0, MOMSN=14122, MT Status=0, MTMSN=0
2013-06-06T21:57:06.188Z,1370555826.188 [Default:Iridium:Read_Iridium] Stopped
2013-06-06T21:57:06.188Z,1370555826.188 [Default:Iridium](INFO): Completed Default:Iridium
2013-06-06T21:57:06.189Z,1370555826.188 [Default:Iridium] Stopped
2013-06-06T21:57:06.189Z,1370555826.189 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-06-06T21:57:06.189Z,1370555826.189 [Default:Iridium:A.SetSpeed] Stopped
2013-06-06T21:57:06.189Z,1370555826.189 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-06-06T21:57:06.516Z,1370555826.516 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-06-06T21:57:06.517Z,1370555826.517 [Default:CallIridium:B] Stopped
2013-06-06T21:57:06.517Z,1370555826.517 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-06-06T21:57:06.517Z,1370555826.517 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-06-06T21:57:06.517Z,1370555826.517 [Default:CallIridium] Stopped
2013-06-06T21:57:06.517Z,1370555826.517 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-06-06T21:57:07.250Z,1370555827.250 [NAL9602](IMPORTANT): GPS fix at: 1370555993.00
2013-06-06T21:57:07.267Z,1370555827.267 [Default:GPS:Read_GPS] Stopped
2013-06-06T21:57:07.267Z,1370555827.267 [Default:GPS:D] Running Loop=1
2013-06-06T21:57:07.716Z,1370555827.716 [Default:GPS:D] Stopped
2013-06-06T21:57:07.716Z,1370555827.716 [Default:GPS](INFO): Completed Default:GPS
2013-06-06T21:57:07.716Z,1370555827.716 [Default:GPS] Stopped
2013-06-06T21:57:07.716Z,1370555827.716 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-06-06T21:57:07.716Z,1370555827.716 [Default:GPS:A.SetSpeed] Stopped
2013-06-06T21:57:07.716Z,1370555827.717 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-06-06T21:57:28.168Z,1370555848.168 [NAL9602](INFO): Powering down
2013-06-06T22:02:03.163Z,1370556123.163 [Default:CallIridium] Running Loop=1
2013-06-06T22:02:03.163Z,1370556123.164 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-06-06T22:02:03.164Z,1370556123.164 [Default:CallIridium:A] Running Loop=1
2013-06-06T22:02:03.164Z,1370556123.164 [Default:CallIridium:A] Stopped
2013-06-06T22:02:03.164Z,1370556123.164 [Default:CallIridium:B] Running Loop=1
2013-06-06T22:02:03.164Z,1370556123.164 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-06-06T22:02:08.187Z,1370556128.187 [Default:Iridium] Running Loop=1
2013-06-06T22:02:08.187Z,1370556128.187 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-06-06T22:02:08.187Z,1370556128.187 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-06-06T22:02:08.187Z,1370556128.187 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-06-06T22:02:08.188Z,1370556128.188 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-06-06T22:02:08.188Z,1370556128.188 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-06-06T22:02:08.189Z,1370556128.188 [Default:Iridium:B.GoToSurface] Stopped
2013-06-06T22:02:08.189Z,1370556128.189 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-06-06T22:02:08.189Z,1370556128.189 [Default:Iridium:Read_Iridium] Running Loop=1
2013-06-06T22:02:08.189Z,1370556128.189 [Default:GPS] Running Loop=1
2013-06-06T22:02:08.189Z,1370556128.189 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-06-06T22:02:08.210Z,1370556128.210 [Default:GPS:A.SetSpeed] Running Loop=1
2013-06-06T22:02:08.210Z,1370556128.210 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-06-06T22:02:08.210Z,1370556128.210 [Default:GPS:B.GoToSurface] Running Loop=1
2013-06-06T22:02:08.210Z,1370556128.210 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-06-06T22:02:08.211Z,1370556128.211 [Default:GPS:B.GoToSurface] Stopped
2013-06-06T22:02:08.211Z,1370556128.211 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-06-06T22:02:08.211Z,1370556128.211 [Default:GPS:Read_GPS] Running Loop=1
2013-06-06T22:02:08.856Z,1370556128.856 [NAL9602](INFO): Powering up
2013-06-06T22:02:19.262Z,1370556139.262 [NAL9602](INFO): NAL9602 initialized
2013-06-06T22:03:45.035Z,1370556225.035 [NAL9602](IMPORTANT): GPS fix at: 1370556392.00
2013-06-06T22:03:45.052Z,1370556225.052 [Default:GPS:Read_GPS] Stopped
2013-06-06T22:03:45.052Z,1370556225.052 [Default:GPS:D] Running Loop=1
2013-06-06T22:03:45.542Z,1370556225.542 [Default:GPS:D] Stopped
2013-06-06T22:03:45.543Z,1370556225.543 [Default:GPS](INFO): Completed Default:GPS
2013-06-06T22:03:45.543Z,1370556225.543 [Default:GPS] Stopped
2013-06-06T22:03:45.543Z,1370556225.543 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-06-06T22:03:45.543Z,1370556225.543 [Default:GPS:A.SetSpeed] Stopped
2013-06-06T22:03:45.543Z,1370556225.543 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-06-06T22:05:35.199Z,1370556335.199 [NAL9602](INFO): SBD MO Status=2, MOMSN=14123, MT Status=2, MTMSN=0
2013-06-06T22:05:35.199Z,1370556335.199 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-06-06T22:06:17.045Z,1370556377.045 [NAL9602](INFO): SBD MO Status=1, MOMSN=14123, MT Status=0, MTMSN=0
2013-06-06T22:06:17.098Z,1370556377.098 [NAL9602](INFO): Sent 79 bytes from file Logs/20130606T213030/Courier0020.lzma
2013-06-06T22:06:17.098Z,1370556377.098 [NAL9602](INFO): Packets left to send: 0
2013-06-06T22:06:17.100Z,1370556377.100 [NAL9602](INFO): Stored copy of sent data in Logs/20130606T213030/Courier0020.lzma.parts/0000.sbd
2013-06-06T22:06:29.300Z,1370556389.300 [NAL9602](INFO): SBD MO Status=1, MOMSN=14124, MT Status=0, MTMSN=0
2013-06-06T22:06:29.350Z,1370556389.350 [NAL9602](INFO): Sent 120 bytes from file Logs/20130606T213030/Express0021.lzma
2013-06-06T22:06:29.350Z,1370556389.350 [NAL9602](INFO): Packets left to send: 0
2013-06-06T22:06:29.352Z,1370556389.352 [NAL9602](INFO): Stored copy of sent data in Logs/20130606T213030/Express0021.lzma.parts/0000.sbd
2013-06-06T22:06:36.905Z,1370556396.905 [NAL9602](INFO): SBD MO Status=0, MOMSN=14125, MT Status=0, MTMSN=0
2013-06-06T22:06:40.209Z,1370556400.209 [Default:Iridium:Read_Iridium] Stopped
2013-06-06T22:06:40.209Z,1370556400.209 [Default:Iridium](INFO): Completed Default:Iridium
2013-06-06T22:06:40.209Z,1370556400.209 [Default:Iridium] Stopped
2013-06-06T22:06:40.210Z,1370556400.210 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-06-06T22:06:40.210Z,1370556400.210 [Default:Iridium:A.SetSpeed] Stopped
2013-06-06T22:06:40.210Z,1370556400.210 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-06-06T22:06:40.556Z,1370556400.556 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-06-06T22:06:40.556Z,1370556400.556 [Default:CallIridium:B] Stopped
2013-06-06T22:06:40.556Z,1370556400.556 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-06-06T22:06:40.557Z,1370556400.557 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-06-06T22:06:40.557Z,1370556400.557 [Default:CallIridium] Stopped
2013-06-06T22:06:40.557Z,1370556400.557 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-06-06T22:06:50.564Z,1370556410.564 [NAL9602](INFO): Powering down
2013-06-06T22:11:40.576Z,1370556700.576 [Default:CallIridium] Running Loop=1
2013-06-06T22:11:40.576Z,1370556700.576 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-06-06T22:11:40.576Z,1370556700.576 [Default:CallIridium:A] Running Loop=1
2013-06-06T22:11:40.576Z,1370556700.576 [Default:CallIridium:A] Stopped
2013-06-06T22:11:40.576Z,1370556700.576 [Default:CallIridium:B] Running Loop=1
2013-06-06T22:11:40.576Z,1370556700.576 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-06-06T22:11:45.608Z,1370556705.608 [Default:Iridium] Running Loop=1
2013-06-06T22:11:45.608Z,1370556705.608 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-06-06T22:11:45.608Z,1370556705.608 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-06-06T22:11:45.608Z,1370556705.608 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-06-06T22:11:45.608Z,1370556705.608 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-06-06T22:11:45.608Z,1370556705.608 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-06-06T22:11:45.609Z,1370556705.609 [Default:Iridium:B.GoToSurface] Stopped
2013-06-06T22:11:45.609Z,1370556705.609 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-06-06T22:11:45.614Z,1370556705.614 [Default:Iridium:Read_Iridium] Running Loop=1
2013-06-06T22:11:45.614Z,1370556705.614 [Default:GPS] Running Loop=1
2013-06-06T22:11:45.614Z,1370556705.614 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-06-06T22:11:45.614Z,1370556705.614 [Default:GPS:A.SetSpeed] Running Loop=1
2013-06-06T22:11:45.614Z,1370556705.615 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-06-06T22:11:45.615Z,1370556705.615 [Default:GPS:B.GoToSurface] Running Loop=1
2013-06-06T22:11:45.615Z,1370556705.615 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-06-06T22:11:45.616Z,1370556705.616 [Default:GPS:B.GoToSurface] Stopped
2013-06-06T22:11:45.616Z,1370556705.616 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-06-06T22:11:45.616Z,1370556705.616 [Default:GPS:Read_GPS] Running Loop=1
2013-06-06T22:11:46.223Z,1370556706.223 [NAL9602](INFO): Powering up
2013-06-06T22:11:57.062Z,1370556717.062 [NAL9602](INFO): NAL9602 initialized
2013-06-06T22:13:27.333Z,1370556807.333 [NAL9602](IMPORTANT): GPS fix at: 1370556976.00
2013-06-06T22:13:27.350Z,1370556807.350 [Default:GPS:Read_GPS] Stopped
2013-06-06T22:13:27.350Z,1370556807.350 [Default:GPS:D] Running Loop=1
2013-06-06T22:13:27.756Z,1370556807.756 [Default:GPS:D] Stopped
2013-06-06T22:13:27.756Z,1370556807.756 [Default:GPS](INFO): Completed Default:GPS
2013-06-06T22:13:27.756Z,1370556807.756 [Default:GPS] Stopped
2013-06-06T22:13:27.756Z,1370556807.756 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-06-06T22:13:27.756Z,1370556807.756 [Default:GPS:A.SetSpeed] Stopped
2013-06-06T22:13:27.757Z,1370556807.757 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.