Image-stitching and virtual tour solutions My account Updates
It is currently Wed Sep 17, 2014 12:37 am

All times are UTC + 1 hour




Post new topic Reply to topic  [ 18 posts ] 
Author Message
PostPosted: Wed Jan 23, 2013 11:11 pm 
Offline
Member

Joined: Tue Dec 18, 2012 9:30 pm
Posts: 15
Hi Folks,

I'm running a Merlin head using papywizard on a Raspberry Pi, connected with a USB to serial connector. Everything is going great, except for one issue. I cannot get the camera to trigger at regular intervals for the time lapse.

For example, with the camera set to shooting at 1/10 of a sec, and Merlin set with a stablisation delay of 1s and time value of 1 sec, I get photos at strange intervals, some times 2 seconds apart, sometimes 4 seconds. I cant see a pattern to the strange invervals.

This also happens with the camera set to longer exposure times. eg 8sec exposure, with Merlin stabalisation delay 1sec and time value of 8sec. I get anything from 9 seconds to 14 seconds between shots.

I guess there is probably a post that describes why, but I could not find it. Can someone point me in the right direction?

Thanks,
Simon

PS Despite this shooting in iceland is going great! I'll put up some examples when I can get a chance :)


Top
 Profile  
 
 Post subject:
PostPosted: Thu Jan 24, 2013 7:09 am 
Offline
Member
User avatar

Joined: Wed Dec 07, 2005 6:21 pm
Posts: 5826
Location: Grenoble, France
Does the head moves between shots?

The timer is not real time at all. I mean, there is just a wait() call after each shot, and it can't be very accuratte. But the values you are getting are very strange... There must be something else.

Could you turn the debug to 'trace' level, and post the log file after a shooting sequence?

_________________
Frédéric

Canon 20D + 17-40/f4 L USM + 70-200/f4 L USM + 50/f1.4 USM
Merlin/Orion panohead + Papywizard on Nokia N800 and HP TC-1100


Top
 Profile  
 
 Post subject:
PostPosted: Thu Jan 24, 2013 9:04 am 
Offline
Member
User avatar

Joined: Sun Nov 25, 2007 11:12 pm
Posts: 1341
Location: Mont-Saint-André (Belgium)
Hello Simon,

Very strange indeed ...
Is you camera set in "full manual" mode" ? (focus mainly ...)

_________________
:cool: Claude :cool:
Merlin + Papywizard on Windows 7 & Nokia 770 § N810 & Acer (Netbook) + PanoramaApp Androïd + Deltawave PapyMerlin BT + Autopano
Spherical Pano (180 x 360) with Canon 40D + Canon EF-S 10-22mm f/3.5-4.5 Zoom & Pôle Pano with Canon 5D MK2 and shaved Tokina 10-17 3.5-4.5 AF DX Fisheye
Gigapixel photography with Nikon D200 + Sigma 70-200 F 2.8 EX DG APO HSM


Top
 Profile  
 
 Post subject:
PostPosted: Thu Jan 24, 2013 12:19 pm 
Offline
Member

Joined: Tue Dec 18, 2012 9:30 pm
Posts: 15
Thanks for the quick response guys, you are both making papywizard a really fantastic community to be part of!

Ok details.

I did a test moving the Merlin head from 0.0 to 0.5 degrees by 0.1degree steps in yaw between each shot, using a preset file I built myself. The camera is fully manual, 1/10s exposure, manual focus. Papywizard settings have 0.5s stabalisation and 1s time value.

When I run the script I cannot see the head move, but that is only due to the small step size, I can see the change when I run more steps.

The camera triggers, but the interval between shots is very strange. In the test setup I got 6 shots with interval: 2s, 4s, 2s, 4s and 4s.

The log file from the run is below:

Code:
2013-01-15 09:26:32,061::Main::INFO::Starting Papywizard...
2013-01-15 09:26:32,068::Main::DEBUG::main(): platform=linux2
2013-01-15 09:26:32,829::Main::INFO::Importing modules...
2013-01-15 09:26:34,275::Main::INFO::Loading i18n files...
2013-01-15 09:26:34,315::Main::DEBUG::main(): locale=en_GB
2013-01-15 09:26:34,353::Main::WARNING::Can't find qt translation file
2013-01-15 09:26:34,369::Main::INFO::Loading Style Sheets...
2013-01-15 09:26:34,446::Main::WARNING::No user Style Sheet found
2013-01-15 09:26:34,456::Main::INFO::Loading configuration...
2013-01-15 09:26:34,482::Main::DEBUG::ConfigManager.__init__(): versions: dist=['2', '1', '21'], user=[u'2', u'1', u'21']
2013-01-15 09:26:34,488::Main::DEBUG::ConfigManager.__init__(): user config. is up-to-date
2013-01-15 09:26:34,500::Main::INFO::Load plugins...
2013-01-15 09:26:34,631::Main::DEBUG::DslrRemoteProShutter._defineConfig()
2013-01-15 09:26:34,643::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-15 09:26:34,656::Main::DEBUG::AbstractPlugin._loadConfig(): config={'USER_EXPOSURE_COMPENSATION_LIST': '-2, 0, +2', 'DRY_RUN': False, 'MIRROR_LOCKUP': False, 'FILENAME_PREFIX': '', 'CAMERA_EXPOSURE_COMPENSATION_LIST': u'\xb12@1/3', 'OUTPUT_DIR': '/home/pi', 'PROGRAM_PATH': 'C:\\Program Files\\BreezeSys\\DSLR Remote Pro\\DSLRRemoteTest\\DSLRRemoteTest.exe'}
2013-01-15 09:26:34,671::Main::DEBUG::PluginsManager.register(): added 'DSLR Remote Pro' plugin with capacity 'shutter'
2013-01-15 09:26:34,706::Main::TRACE::EOSUtilityShutter._defineConfig()
2013-01-15 09:26:34,712::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-15 09:26:34,740::Main::DEBUG::AbstractPlugin._loadConfig(): config={'FOCUS_DIRECTION': u'far', 'BRACKETING_TYPE': '0-+', 'BULB_ENABLE': False, 'DRY_RUN': True, 'BULB_BASE_EXPOSURE': 1, 'FOCUS_NB_PICTS': 1, 'BRACKETING_NB_PICTS': 1, 'FOCUS_STEP_COUNT': 1, 'FOCUS_ENABLE': False, 'EOS_UTILITY_VERSION': u'new', 'PROGRAM_PATH': 'C:\\Program Files\\Papywizard\\EOSBracket.exe', 'BRACKETING_STEP': '1', 'FOCUS_STEP': u'medium'}
2013-01-15 09:26:34,746::Main::DEBUG::PluginsManager.register(): added 'EOS Utility' plugin with capacity 'shutter'
2013-01-15 09:26:34,776::Main::TRACE::GenericTetheredShutter._defineConfig()
2013-01-15 09:26:34,782::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-15 09:26:34,804::Main::DEBUG::AbstractPlugin._loadConfig(): config={'MIRROR_LOCKUP_COMMAND': u'gphoto2 --capture-image', 'MIRROR_LOCKUP': False, 'PARAM_4': u'', 'PARAM_1': u'', 'PARAM_0': u'', 'SHOOT_COMMAND': u'gphoto2 --capture-image', 'PARAM_2': u'', 'TIME_VALUE': 0.1, 'PARAM_3': u''}
2013-01-15 09:26:34,813::Main::DEBUG::PluginsManager.register(): added 'Generic Tethered' plugin with capacity 'shutter'
2013-01-15 09:26:34,864::Main::TRACE::GigaPanBotAxis._init()
2013-01-15 09:26:34,871::Main::TRACE::AbstractHardwarePlugin._defineConfig()
2013-01-15 09:26:34,875::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-15 09:26:34,883::Main::DEBUG::AbstractPlugin._loadConfig(): config={'HIGH_LIMIT': 360.0, 'DRIVER_TYPE': 'bluetooth', 'LOW_LIMIT': -360.0}
2013-01-15 09:26:34,888::Main::DEBUG::PluginsManager.register(): added 'GigaPanBot' plugin with capacity 'yawAxis'
2013-01-15 09:26:34,896::Main::TRACE::GigaPanBotAxis._init()
2013-01-15 09:26:34,900::Main::TRACE::AbstractHardwarePlugin._defineConfig()
2013-01-15 09:26:34,908::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-15 09:26:34,913::Main::DEBUG::AbstractPlugin._loadConfig(): config={'HIGH_LIMIT': 360.0, 'DRIVER_TYPE': 'bluetooth', 'LOW_LIMIT': -360.0}
2013-01-15 09:26:34,921::Main::DEBUG::PluginsManager.register(): added 'GigaPanBot' plugin with capacity 'pitchAxis'
2013-01-15 09:26:34,925::Main::TRACE::GigaPanBotShutter._init()
2013-01-15 09:26:34,933::Main::TRACE::ShutterPlugin._init()
2013-01-15 09:26:34,938::Main::TRACE::AbstractHardwarePlugin._defineConfig()
2013-01-15 09:26:34,944::Main::TRACE::ShutterPlugin._defineConfig()
2013-01-15 09:26:34,950::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-15 09:26:34,956::Main::DEBUG::AbstractPlugin._loadConfig(): config={'DRIVER_TYPE': 'bluetooth', 'PULSE_WIDTH_LOW': 250, 'BULB_ENABLE': False, 'MIRROR_LOCKUP': False, 'BRACKETING_NB_PICTS': 1, 'TIME_VALUE': 0.5, 'PULSE_WIDTH_HIGH': 250, 'TRIGGER_ONLY_ONCE': False}
2013-01-15 09:26:34,964::Main::DEBUG::PluginsManager.register(): added 'GigaPanBot' plugin with capacity 'shutter'
2013-01-15 09:26:34,983::Main::TRACE::GphotoBracketShutter._defineConfig()
2013-01-15 09:26:34,989::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-15 09:26:34,998::Main::DEBUG::AbstractPlugin._loadConfig(): config={'BRACKETING_EV_STEP': 1, 'BRACKETING_EV_LIST': '0', 'BRACKETING_PLUS_STEP': 1, 'MIRROR_LOCKUP_COMMAND': 'gphoto2 --capture-image', 'MIRROR_LOCKUP': False, 'DOWNLOAD_DIR': '/home/pi', 'BRACKETING_MINUS_STEP': 1, 'DOWNLOAD_ENABLE': False, 'BRACKETING_NB_PICTS': 1, 'BRACKETING_MINUS_NB_PICTS': 0, 'BRACKETING_EV_BIAS': 0, 'BRACKETING_ADVANCED': False, 'BRACKETING_PLUS_NB_PICTS': 0, 'DOWNLOAD_THEN_DELETE': False, 'PROGRAM_PATH': 'gphoto2', 'DOWNLOAD_AFTER': u'Each shot'}
2013-01-15 09:26:35,006::Main::DEBUG::PluginsManager.register(): added 'Gphoto Bracket' plugin with capacity 'shutter'
2013-01-15 09:26:35,027::Main::TRACE::MerlinOrionAxis._init()
2013-01-15 09:26:35,034::Main::TRACE::AbstractHardwarePlugin._defineConfig()
2013-01-15 09:26:35,038::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-15 09:26:35,050::Main::DEBUG::AbstractPlugin._loadConfig(): config={'HIGH_LIMIT': 360.0, 'ALTERNATE_DRIVE_ANGLE': 7.0, 'INERTIA_ANGLE': 1.0, 'DRIVER_TYPE': u'serial', 'LOW_LIMIT': -360.0, 'ALTERNATE_DRIVE': True}
2013-01-15 09:26:35,056::Main::TRACE::MerlinOrionAxis._init()
2013-01-15 09:26:35,067::Main::TRACE::AbstractHardwarePlugin._defineConfig()
2013-01-15 09:26:35,074::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-15 09:26:35,087::Main::DEBUG::AbstractPlugin._loadConfig(): config={'HIGH_LIMIT': 360.0, 'ALTERNATE_DRIVE_ANGLE': 7.0, 'INERTIA_ANGLE': 1.0, 'DRIVER_TYPE': u'serial', 'LOW_LIMIT': -360.0, 'ALTERNATE_DRIVE': True}
2013-01-15 09:26:35,098::Main::DEBUG::PluginsManager.register(): added 'Merlin-Orion' plugin with capacity 'yawAxis'
2013-01-15 09:26:35,105::Main::TRACE::MerlinOrionAxis._init()
2013-01-15 09:26:35,115::Main::TRACE::AbstractHardwarePlugin._defineConfig()
2013-01-15 09:26:35,121::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-15 09:26:35,131::Main::DEBUG::AbstractPlugin._loadConfig(): config={'HIGH_LIMIT': 360.0, 'ALTERNATE_DRIVE_ANGLE': 7.0, 'INERTIA_ANGLE': 1.0, 'DRIVER_TYPE': u'serial', 'LOW_LIMIT': -360.0, 'ALTERNATE_DRIVE': True}
2013-01-15 09:26:35,138::Main::TRACE::MerlinOrionAxis._init()
2013-01-15 09:26:35,148::Main::TRACE::AbstractHardwarePlugin._defineConfig()
2013-01-15 09:26:35,156::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-15 09:26:35,166::Main::DEBUG::AbstractPlugin._loadConfig(): config={'HIGH_LIMIT': 360.0, 'ALTERNATE_DRIVE_ANGLE': 7.0, 'INERTIA_ANGLE': 1.0, 'DRIVER_TYPE': u'serial', 'LOW_LIMIT': -360.0, 'ALTERNATE_DRIVE': True}
2013-01-15 09:26:35,173::Main::DEBUG::PluginsManager.register(): added 'Merlin-Orion' plugin with capacity 'pitchAxis'
2013-01-15 09:26:35,179::Main::TRACE::MerlinOrionShutter._init()
2013-01-15 09:26:35,184::Main::TRACE::ShutterPlugin._init()
2013-01-15 09:26:35,191::Main::TRACE::AbstractHardwarePlugin._defineConfig()
2013-01-15 09:26:35,196::Main::TRACE::ShutterPlugin._defineConfig()
2013-01-15 09:26:35,202::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-15 09:26:35,212::Main::DEBUG::AbstractPlugin._loadConfig(): config={'DRIVER_TYPE': u'serial', 'PULSE_WIDTH_LOW': 500, 'BULB_ENABLE': False, 'MIRROR_LOCKUP': False, 'BRACKETING_NB_PICTS': 1, 'TIME_VALUE': 1.5, 'PULSE_WIDTH_HIGH': 500, 'TRIGGER_ONLY_ONCE': True}
2013-01-15 09:26:35,219::Main::TRACE::MerlinOrionShutter._init()
2013-01-15 09:26:35,225::Main::TRACE::ShutterPlugin._init()
2013-01-15 09:26:35,230::Main::TRACE::AbstractHardwarePlugin._defineConfig()
2013-01-15 09:26:35,236::Main::TRACE::ShutterPlugin._defineConfig()
2013-01-15 09:26:35,242::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-15 09:26:35,252::Main::DEBUG::AbstractPlugin._loadConfig(): config={'DRIVER_TYPE': u'serial', 'PULSE_WIDTH_LOW': 500, 'BULB_ENABLE': False, 'MIRROR_LOCKUP': False, 'BRACKETING_NB_PICTS': 1, 'TIME_VALUE': 1.5, 'PULSE_WIDTH_HIGH': 500, 'TRIGGER_ONLY_ONCE': True}
2013-01-15 09:26:35,258::Main::DEBUG::PluginsManager.register(): added 'Merlin-Orion' plugin with capacity 'shutter'
2013-01-15 09:26:35,275::Main::DEBUG::NkRemoteShutter._defineConfig()
2013-01-15 09:26:35,281::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-15 09:26:35,289::Main::DEBUG::AbstractPlugin._loadConfig(): config={'USER_EXPOSURE_COMPENSATION_LIST': '-2, 0, +2', 'DRY_RUN': False, 'MIRROR_LOCKUP': False, 'FILENAME_PREFIX': '', 'CAMERA_EXPOSURE_COMPENSATION_LIST': u'\xb15@1/2', 'OUTPUT_DIR': '/home/pi', 'PROGRAM_PATH': 'C:\\Program Files\\BreezeSys\\NKRemote\\NKRemoteLibTest.exe'}
2013-01-15 09:26:35,300::Main::DEBUG::PluginsManager.register(): added 'NK Remote' plugin with capacity 'shutter'
2013-01-15 09:26:35,327::Main::TRACE::PanoduinoAxis._init()
2013-01-15 09:26:35,333::Main::TRACE::AbstractHardwarePlugin._defineConfig()
2013-01-15 09:26:35,338::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-15 09:26:35,347::Main::DEBUG::AbstractPlugin._loadConfig(): config={'NEUTRAL_POSITION': 1000, 'HIGH_LIMIT': 360.0, 'DIRECTION': u'forward', 'ANGLE_1MS': 315.0, 'ACCEL': 1, 'LOW_LIMIT': -360.0, 'DRIVER_TYPE': 'bluetooth', 'ADDITIONAL_DELAY': 0.5, 'SPEED': 5, 'CHANNEL': 0}
2013-01-15 09:26:35,354::Main::DEBUG::PluginsManager.register(): added 'Panoduino' plugin with capacity 'yawAxis'
2013-01-15 09:26:35,360::Main::TRACE::PanoduinoAxis._init()
2013-01-15 09:26:35,365::Main::TRACE::AbstractHardwarePlugin._defineConfig()
2013-01-15 09:26:35,373::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-15 09:26:35,380::Main::DEBUG::AbstractPlugin._loadConfig(): config={'NEUTRAL_POSITION': 1500, 'HIGH_LIMIT': 360.0, 'DIRECTION': u'reverse', 'ANGLE_1MS': 330.0, 'ACCEL': 1, 'LOW_LIMIT': -360.0, 'DRIVER_TYPE': 'bluetooth', 'ADDITIONAL_DELAY': 0.5, 'SPEED': 5, 'CHANNEL': 1}
2013-01-15 09:26:35,387::Main::DEBUG::PluginsManager.register(): added 'Panoduino' plugin with capacity 'pitchAxis'
2013-01-15 09:26:35,392::Main::TRACE::PanoduinoShutter._init()
2013-01-15 09:26:35,399::Main::TRACE::ShutterPlugin._init()
2013-01-15 09:26:35,404::Main::TRACE::AbstractHardwarePlugin._defineConfig()
2013-01-15 09:26:35,410::Main::TRACE::ShutterPlugin._defineConfig()
2013-01-15 09:26:35,416::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-15 09:26:35,424::Main::DEBUG::AbstractPlugin._loadConfig(): config={'DRIVER_TYPE': 'bluetooth', 'PULSE_WIDTH_LOW': 250, 'BULB_ENABLE': False, 'MIRROR_LOCKUP': False, 'SHUTTER_ON': 2000, 'SHUTTER_OFF': 1000, 'BRACKETING_NB_PICTS': 1, 'TIME_VALUE': 0.5, 'CHANNEL': 2, 'PULSE_WIDTH_HIGH': 250, 'TRIGGER_ONLY_ONCE': False}
2013-01-15 09:26:35,430::Main::DEBUG::PluginsManager.register(): added 'Panoduino' plugin with capacity 'shutter'
2013-01-15 09:26:35,451::Main::TRACE::PixOrbAxis._init()
2013-01-15 09:26:35,457::Main::TRACE::AbstractHardwarePlugin._defineConfig()
2013-01-15 09:26:35,462::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-15 09:26:35,470::Main::DEBUG::AbstractPlugin._loadConfig(): config={'AXIS_ACCURACY': 0.1, 'HIGH_LIMIT': 360.0, 'DRIVER_TYPE': 'bluetooth', 'LOW_LIMIT': -360.0, 'SPEED_INDEX': 9, 'AXIS_WITH_BREAK': False}
2013-01-15 09:26:35,475::Main::DEBUG::PluginsManager.register(): added 'PixOrb' plugin with capacity 'yawAxis'
2013-01-15 09:26:35,483::Main::TRACE::PixOrbAxis._init()
2013-01-15 09:26:35,488::Main::TRACE::AbstractHardwarePlugin._defineConfig()
2013-01-15 09:26:35,495::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-15 09:26:35,501::Main::DEBUG::AbstractPlugin._loadConfig(): config={'AXIS_ACCURACY': 0.1, 'HIGH_LIMIT': 360.0, 'DRIVER_TYPE': 'bluetooth', 'LOW_LIMIT': -360.0, 'SPEED_INDEX': 9, 'AXIS_WITH_BREAK': False}
2013-01-15 09:26:35,508::Main::DEBUG::PluginsManager.register(): added 'PixOrb' plugin with capacity 'pitchAxis'
2013-01-15 09:26:35,513::Main::TRACE::PixOrbShutter._init()
2013-01-15 09:26:35,521::Main::TRACE::ShutterPlugin._init()
2013-01-15 09:26:35,526::Main::TRACE::AbstractHardwarePlugin._defineConfig()
2013-01-15 09:26:35,533::Main::TRACE::ShutterPlugin._defineConfig()
2013-01-15 09:26:35,538::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-15 09:26:35,547::Main::DEBUG::AbstractPlugin._loadConfig(): config={'DRIVER_TYPE': 'bluetooth', 'PULSE_WIDTH_LOW': 250, 'BULB_ENABLE': False, 'MIRROR_LOCKUP': False, 'BRACKETING_NB_PICTS': 1, 'TIME_VALUE': 0.5, 'PULSE_WIDTH_HIGH': 250, 'TRIGGER_ONLY_ONCE': False}
2013-01-15 09:26:35,553::Main::DEBUG::PluginsManager.register(): added 'PixOrb' plugin with capacity 'shutter'
2013-01-15 09:26:35,570::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-15 09:26:35,578::Main::DEBUG::AbstractPlugin._loadConfig(): config={'HIGH_LIMIT': 360.0, 'SPEED': 30, 'LOW_LIMIT': -360.0}
2013-01-15 09:26:35,585::Main::DEBUG::PluginsManager.register(): added 'Simulation' plugin with capacity 'yawAxis'
2013-01-15 09:26:35,592::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-15 09:26:35,601::Main::DEBUG::AbstractPlugin._loadConfig(): config={'HIGH_LIMIT': 360.0, 'SPEED': 30, 'LOW_LIMIT': -360.0}
2013-01-15 09:26:35,609::Main::DEBUG::PluginsManager.register(): added 'Simulation' plugin with capacity 'pitchAxis'
2013-01-15 09:26:35,616::Main::TRACE::AbstractShutterPlugin._defineConfig()
2013-01-15 09:26:35,622::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-15 09:26:35,631::Main::DEBUG::AbstractPlugin._loadConfig(): config={'TIME_VALUE': 0.5, 'TRIGGER_ONLY_ONCE': False, 'BRACKETING_NB_PICTS': 1, 'MIRROR_LOCKUP': False}
2013-01-15 09:26:35,636::Main::DEBUG::PluginsManager.register(): added 'Simulation' plugin with capacity 'shutter'
2013-01-15 09:26:35,649::Main::TRACE::TimelordShutter._defineConfig()
2013-01-15 09:26:35,655::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-15 09:26:35,660::Main::DEBUG::AbstractPlugin._loadConfig(): config={'PROGRAM_PATH': 'C:\\Program Files\\OxfordEye\\Timelord\\Timelord.exe', 'LRD_FILE': 'C:\\Documents and Settings\\win2k\\My Documents\\timelord.lrd'}
2013-01-15 09:26:35,668::Main::DEBUG::PluginsManager.register(): added 'Timelord' plugin with capacity 'shutter'
2013-01-15 09:26:35,684::Main::TRACE::UrsaMinorBt2Shutter._init()
2013-01-15 09:26:35,690::Main::TRACE::ShutterPlugin._init()
2013-01-15 09:26:35,695::Main::TRACE::UrsaMinorBt2Shutter._defineConfig()
2013-01-15 09:26:35,702::Main::TRACE::AbstractHardwarePlugin._defineConfig()
2013-01-15 09:26:35,707::Main::TRACE::ShutterPlugin._defineConfig()
2013-01-15 09:26:35,713::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-15 09:26:35,721::Main::DEBUG::AbstractPlugin._loadConfig(): config={'DRIVER_TYPE': 'bluetooth', 'PULSE_WIDTH_LOW': 250, 'BULB_ENABLE': False, 'MIRROR_LOCKUP': False, 'FOCUS_PULSE_WIDTH': 0.5, 'FOCUS_MAINTAIN': False, 'BRACKETING_NB_PICTS': 1, 'TIME_VALUE': 0.5, 'FOCUS_ENABLE': False, 'PULSE_WIDTH_HIGH': 250, 'TRIGGER_ONLY_ONCE': False}
2013-01-15 09:26:35,727::Main::TRACE::UrsaMinorBt2Shutter._init()
2013-01-15 09:26:35,735::Main::TRACE::ShutterPlugin._init()
2013-01-15 09:26:35,740::Main::TRACE::UrsaMinorBt2Shutter._defineConfig()
2013-01-15 09:26:35,746::Main::TRACE::AbstractHardwarePlugin._defineConfig()
2013-01-15 09:26:35,752::Main::TRACE::ShutterPlugin._defineConfig()
2013-01-15 09:26:35,756::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-15 09:26:35,764::Main::DEBUG::AbstractPlugin._loadConfig(): config={'DRIVER_TYPE': 'bluetooth', 'PULSE_WIDTH_LOW': 250, 'BULB_ENABLE': False, 'MIRROR_LOCKUP': False, 'FOCUS_PULSE_WIDTH': 0.5, 'FOCUS_MAINTAIN': False, 'BRACKETING_NB_PICTS': 1, 'TIME_VALUE': 0.5, 'FOCUS_ENABLE': False, 'PULSE_WIDTH_HIGH': 250, 'TRIGGER_ONLY_ONCE': False}
2013-01-15 09:26:35,770::Main::DEBUG::PluginsManager.register(): added 'Ursa Minor BT2' plugin with capacity 'shutter'
2013-01-15 09:26:35,787::Main::TRACE::UrsaMinorUsbShutter._init()
2013-01-15 09:26:35,793::Main::TRACE::ShutterPlugin._init()
2013-01-15 09:26:35,797::Main::TRACE::UrsaMinorUsbShutter._defineConfig()
2013-01-15 09:26:35,804::Main::TRACE::AbstractHardwarePlugin._defineConfig()
2013-01-15 09:26:35,809::Main::TRACE::ShutterPlugin._defineConfig()
2013-01-15 09:26:35,814::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-15 09:26:35,822::Main::DEBUG::AbstractPlugin._loadConfig(): config={'DRIVER_TYPE': 'bluetooth', 'PULSE_WIDTH_LOW': 250, 'BULB_ENABLE': False, 'MIRROR_LOCKUP': False, 'TRIGGER_LINE_INVERTED': False, 'BRACKETING_NB_PICTS': 1, 'TRIGGER_LINE': 'RTS', 'TIME_VALUE': 0.5, 'PULSE_WIDTH_HIGH': 250, 'TRIGGER_ONLY_ONCE': False}
2013-01-15 09:26:35,828::Main::DEBUG::PluginsManager.register(): added 'Ursa Minor USB' plugin with capacity 'shutter'
2013-01-15 09:26:35,849::Main::TRACE::ClaussAxis._init()
2013-01-15 09:26:35,856::Main::TRACE::AbstractHardwarePlugin._defineConfig()
2013-01-15 09:26:35,860::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-15 09:26:35,869::Main::DEBUG::AbstractPlugin._loadConfig(): config={'HIGH_LIMIT': 360.0, 'PARK_POSITION': '0.', 'SPEED_FAST': 100, 'DRIVER_TYPE': 'bluetooth', 'LOW_LIMIT': -360.0, 'PARK_ENABLE': False, 'SPEED_NORMAL': 50, 'SPEED_SLOW': 10}
2013-01-15 09:26:35,874::Main::DEBUG::PluginsManager.register(): added 'Clauss' plugin with capacity 'yawAxis'
2013-01-15 09:26:35,882::Main::TRACE::ClaussAxis._init()
2013-01-15 09:26:35,887::Main::TRACE::AbstractHardwarePlugin._defineConfig()
2013-01-15 09:26:35,895::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-15 09:26:35,901::Main::DEBUG::AbstractPlugin._loadConfig(): config={'HIGH_LIMIT': 360.0, 'PARK_POSITION': '0.', 'SPEED_FAST': 100, 'DRIVER_TYPE': 'bluetooth', 'LOW_LIMIT': -360.0, 'PARK_ENABLE': True, 'SPEED_NORMAL': 50, 'SPEED_SLOW': 10}
2013-01-15 09:26:35,911::Main::DEBUG::PluginsManager.register(): added 'Clauss' plugin with capacity 'pitchAxis'
2013-01-15 09:26:35,918::Main::TRACE::ClaussShutter._init()
2013-01-15 09:26:35,924::Main::TRACE::ShutterPlugin._init()
2013-01-15 09:26:35,929::Main::TRACE::AbstractHardwarePlugin._defineConfig()
2013-01-15 09:26:35,936::Main::TRACE::ShutterPlugin._defineConfig()
2013-01-15 09:26:35,941::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-15 09:26:35,949::Main::DEBUG::AbstractPlugin._loadConfig(): config={'DRIVER_TYPE': 'bluetooth', 'PULSE_WIDTH_LOW': 250, 'BULB_ENABLE': False, 'MIRROR_LOCKUP': False, 'FOCUS_TIME': 1.5, 'BRACKETING_NB_PICTS': 1, 'DUAL_TIME': 2.0, 'TIME_VALUE': 0.5, 'FOCUS_ENABLE': False, 'DUAL_ENABLE': False, 'PULSE_WIDTH_HIGH': 250, 'TRIGGER_ONLY_ONCE': False}
2013-01-15 09:26:35,955::Main::DEBUG::PluginsManager.register(): added 'Clauss' plugin with capacity 'shutter'
2013-01-15 09:26:35,960::Main::INFO::Loading user plugins...
2013-01-15 09:26:35,967::Main::DEBUG::PluginsManager.register(): parsing '/home/pi/.config/papywizard2/plugins' dir...
2013-01-15 09:26:35,975::Main::INFO::Activate plugins...
2013-01-15 09:26:35,984::Main::TRACE::MerlinOrionPlugin.activate()
2013-01-15 09:26:35,993::Main::TRACE::MerlinOrionPlugin.activate()
2013-01-15 09:26:35,997::Merlin-Orion_yawAxis::DEBUG::MerlinOrionAxis.run(): start thread
2013-01-15 09:26:36,010::Merlin-Orion_pitchAxis::DEBUG::MerlinOrionAxis.run(): start thread
2013-01-15 09:26:36,022::Main::INFO::Creating model...
2013-01-15 09:26:36,041::Main::INFO::Loading default presets
2013-01-15 09:26:36,302::Main::DEBUG::Preset.__init__(): loading name='4@0 + Z + N'
2013-01-15 09:26:36,316::Main::DEBUG::Preset.__init__(): loading name='3@-15 + Z'
2013-01-15 09:26:36,327::Main::DEBUG::Preset.__init__(): loading name='6@30 + 6@-15 + N'
2013-01-15 09:26:36,338::Main::DEBUG::Preset.__init__(): loading name='4@30 + 4@-30'
2013-01-15 09:26:36,348::Main::DEBUG::Preset.__init__(): loading name='6@30 + 6@-30'
2013-01-15 09:26:36,358::Main::DEBUG::Preset.__init__(): loading name='6@0 + Z + N'
2013-01-15 09:26:36,368::Main::DEBUG::Preset.__init__(): loading name='6@-10 + 1@+60'
2013-01-15 09:26:36,377::Main::DEBUG::Preset.__init__(): loading name='3 + 6 + 12 + 6 + 3 (28mm)'
2013-01-15 09:26:36,392::Main::DEBUG::Preset.__init__(): loading name='4 + 12 + 12 + 12 + 8 (32mm)'
2013-01-15 09:26:36,412::Main::INFO::Loading user presets
2013-01-15 09:26:36,418::Main::WARNING::No user presets found
2013-01-15 09:26:36,422::Main::INFO::Starting Spy...
2013-01-15 09:26:36,430::Main::INFO::Creating GUI...
2013-01-15 09:26:36,439::Spy::INFO::Starting Spy...
2013-01-15 09:26:36,457::Spy::INFO::Spy suspended
2013-01-15 09:26:37,843::Main::WARNING::MainController.__getFullScreenFlag(): fix fullScreenFlag property!!!
2013-01-15 09:26:37,938::Main::TRACE::MainController.__onTabWidgetCurrentChanged()
2013-01-15 09:26:37,944::Main::DEBUG::MainController.__onTabWidgetCurrentChanged(): shooting mode set to 'preset'
2013-01-15 09:26:37,955::Main::WARNING::Previously selected 'time_lapse.xml' preset not found

2013-01-15 09:26:53,873::Main::DEBUG::MainController.__importPresetFile(): preset file=/home/pi/Documents/time_lapse/scripts/time_lapse.xml
2013-01-15 09:26:54,065::Main::DEBUG::Preset.__init__(): loading name='time_lapse.xml'
2013-01-15 09:26:54,087::Main::DEBUG::MainController.__onPresetComboBoxCurrentIndexChanged()
Traceback (most recent call last):
  File "/usr/local/lib/python2.7/dist-packages/papywizard/controller/mainController.py", line 519, in __onPresetComboBoxCurrentIndexChanged
    preset = presets.getByIndex(self._view.presetComboBox.currentIndex())
  File "/usr/local/lib/python2.7/dist-packages/papywizard/common/presetManager.py", line 176, in getByIndex
    raise ValueError("No Preset at index '%d'" % index)
ValueError: No Preset at index '-1'

2013-01-15 09:27:01,450::Main::DEBUG::MainController.__onActionHardwareConnectToggled(True)
2013-01-15 09:27:01,457::Main::INFO::Starting connection. Please wait...
2013-01-15 09:27:01,550::Main::DEBUG::PluginsConnector.start(): 'yawAxis' establish connection
2013-01-15 09:27:01,692::Main::DEBUG::PluginsConnector.start(): 'yawAxis' init
2013-01-15 09:27:02,710::Main::EXCEPTION::MerlinOrionHardware.__sendCmd
Traceback (most recent call last):
  File "/usr/local/lib/python2.7/dist-packages/papywizard/hardware/merlinOrionHardware.py", line 145, in __sendCmd
    c = self._driver.read(1)
  File "/usr/local/lib/python2.7/dist-packages/papywizard/driver/serialDriver.py", line 115, in read
    raise IOError("Timeout while reading on serial bus (size=%d, data=%s)" % (size, repr(data)))
IOError: Timeout while reading on serial bus (size=1, data='')
2013-01-15 09:27:02,730::Main::WARNING::MerlinOrionHardware.__sendCmd(): axis 1 can't sent command ':L1\r'. Retrying...
2013-01-15 09:27:03,746::Main::EXCEPTION::MerlinOrionHardware.__sendCmd
Traceback (most recent call last):
  File "/usr/local/lib/python2.7/dist-packages/papywizard/hardware/merlinOrionHardware.py", line 145, in __sendCmd
    c = self._driver.read(1)
  File "/usr/local/lib/python2.7/dist-packages/papywizard/driver/serialDriver.py", line 115, in read
    raise IOError("Timeout while reading on serial bus (size=%d, data=%s)" % (size, repr(data)))
IOError: Timeout while reading on serial bus (size=1, data='')
2013-01-15 09:27:03,758::Main::WARNING::MerlinOrionHardware.__sendCmd(): axis 1 can't sent command ':L1\r'. Retrying...
2013-01-15 09:27:04,774::Main::EXCEPTION::MerlinOrionHardware.__sendCmd
Traceback (most recent call last):
  File "/usr/local/lib/python2.7/dist-packages/papywizard/hardware/merlinOrionHardware.py", line 145, in __sendCmd
    c = self._driver.read(1)
  File "/usr/local/lib/python2.7/dist-packages/papywizard/driver/serialDriver.py", line 115, in read
    raise IOError("Timeout while reading on serial bus (size=%d, data=%s)" % (size, repr(data)))
IOError: Timeout while reading on serial bus (size=1, data='')
2013-01-15 09:27:04,785::Main::WARNING::MerlinOrionHardware.__sendCmd(): axis 1 can't sent command ':L1\r'. Retrying...
2013-01-15 09:27:04,794::Main::EXCEPTION::PluginsConnector.connectPlugins()
Traceback (most recent call last):
  File "/usr/local/lib/python2.7/dist-packages/papywizard/plugins/pluginsConnector.py", line 89, in start
    plugin.init()
  File "/usr/local/lib/python2.7/dist-packages/papywizard/plugins/merlinOrionPlugins.py", line 157, in init
    AbstractHardwarePlugin.init(self)
  File "/usr/local/lib/python2.7/dist-packages/papywizard/plugins/abstractHardwarePlugin.py", line 104, in init
    self._hardware.init()
  File "/usr/local/lib/python2.7/dist-packages/papywizard/hardware/merlinOrionHardware.py", line 178, in init
    self.__sendCmd("L")
  File "/usr/local/lib/python2.7/dist-packages/papywizard/hardware/merlinOrionHardware.py", line 164, in __sendCmd
    raise HardwareError("axis %d can't send command %s" % (self._axis, repr(cmd)))
HardwareError: axis 1 can't send command ':L1\r'
2013-01-15 09:27:04,821::Main::DEBUG::PluginsConnector.start(): 'pitchAxis' establish connection
2013-01-15 09:27:04,829::Main::DEBUG::PluginsConnector.start(): 'pitchAxis' init
2013-01-15 09:27:05,847::Main::EXCEPTION::MerlinOrionHardware.__sendCmd
Traceback (most recent call last):
  File "/usr/local/lib/python2.7/dist-packages/papywizard/hardware/merlinOrionHardware.py", line 145, in __sendCmd
    c = self._driver.read(1)
  File "/usr/local/lib/python2.7/dist-packages/papywizard/driver/serialDriver.py", line 115, in read
    raise IOError("Timeout while reading on serial bus (size=%d, data=%s)" % (size, repr(data)))
IOError: Timeout while reading on serial bus (size=1, data='')
2013-01-15 09:27:05,857::Main::WARNING::MerlinOrionHardware.__sendCmd(): axis 2 can't sent command ':L2\r'. Retrying...
2013-01-15 09:27:06,874::Main::EXCEPTION::MerlinOrionHardware.__sendCmd
Traceback (most recent call last):
  File "/usr/local/lib/python2.7/dist-packages/papywizard/hardware/merlinOrionHardware.py", line 145, in __sendCmd
    c = self._driver.read(1)
  File "/usr/local/lib/python2.7/dist-packages/papywizard/driver/serialDriver.py", line 115, in read
    raise IOError("Timeout while reading on serial bus (size=%d, data=%s)" % (size, repr(data)))
IOError: Timeout while reading on serial bus (size=1, data='')
2013-01-15 09:27:06,883::Main::WARNING::MerlinOrionHardware.__sendCmd(): axis 2 can't sent command ':L2\r'. Retrying...
2013-01-15 09:27:07,900::Main::EXCEPTION::MerlinOrionHardware.__sendCmd
Traceback (most recent call last):
  File "/usr/local/lib/python2.7/dist-packages/papywizard/hardware/merlinOrionHardware.py", line 145, in __sendCmd
    c = self._driver.read(1)
  File "/usr/local/lib/python2.7/dist-packages/papywizard/driver/serialDriver.py", line 115, in read
    raise IOError("Timeout while reading on serial bus (size=%d, data=%s)" % (size, repr(data)))
IOError: Timeout while reading on serial bus (size=1, data='')
2013-01-15 09:27:08,098::Main::WARNING::MerlinOrionHardware.__sendCmd(): axis 2 can't sent command ':L2\r'. Retrying...
2013-01-15 09:27:08,110::Main::EXCEPTION::PluginsConnector.connectPlugins()
Traceback (most recent call last):
  File "/usr/local/lib/python2.7/dist-packages/papywizard/plugins/pluginsConnector.py", line 108, in start
    plugin.init()
  File "/usr/local/lib/python2.7/dist-packages/papywizard/plugins/merlinOrionPlugins.py", line 157, in init
    AbstractHardwarePlugin.init(self)
  File "/usr/local/lib/python2.7/dist-packages/papywizard/plugins/abstractHardwarePlugin.py", line 104, in init
    self._hardware.init()
  File "/usr/local/lib/python2.7/dist-packages/papywizard/hardware/merlinOrionHardware.py", line 178, in init
    self.__sendCmd("L")
  File "/usr/local/lib/python2.7/dist-packages/papywizard/hardware/merlinOrionHardware.py", line 164, in __sendCmd
    raise HardwareError("axis %d can't send command %s" % (self._axis, repr(cmd)))
HardwareError: axis 2 can't send command ':L2\r'
2013-01-15 09:27:08,129::Main::DEBUG::PluginsConnector.start(): 'shutter' establish connection
2013-01-15 09:27:08,137::Main::DEBUG::PluginsConnector.start(): 'shutter' init
2013-01-15 09:27:09,155::Main::EXCEPTION::MerlinOrionHardware.__sendCmd
Traceback (most recent call last):
  File "/usr/local/lib/python2.7/dist-packages/papywizard/hardware/merlinOrionHardware.py", line 145, in __sendCmd
    c = self._driver.read(1)
  File "/usr/local/lib/python2.7/dist-packages/papywizard/driver/serialDriver.py", line 115, in read
    raise IOError("Timeout while reading on serial bus (size=%d, data=%s)" % (size, repr(data)))
IOError: Timeout while reading on serial bus (size=1, data='')
2013-01-15 09:27:09,165::Main::WARNING::MerlinOrionHardware.__sendCmd(): axis 1 can't sent command ':L1\r'. Retrying...
2013-01-15 09:27:10,181::Main::EXCEPTION::MerlinOrionHardware.__sendCmd
Traceback (most recent call last):
  File "/usr/local/lib/python2.7/dist-packages/papywizard/hardware/merlinOrionHardware.py", line 145, in __sendCmd
    c = self._driver.read(1)
  File "/usr/local/lib/python2.7/dist-packages/papywizard/driver/serialDriver.py", line 115, in read
    raise IOError("Timeout while reading on serial bus (size=%d, data=%s)" % (size, repr(data)))
IOError: Timeout while reading on serial bus (size=1, data='')
2013-01-15 09:27:10,191::Main::WARNING::MerlinOrionHardware.__sendCmd(): axis 1 can't sent command ':L1\r'. Retrying...
2013-01-15 09:27:10,229::Main::DEBUG::MerlinOrionHardware.init(): firmeware version=020A84
2013-01-15 09:27:10,253::Main::DEBUG::MerlinOrionHardware.init(): encoder full circle=0xe62d3
2013-01-15 09:27:10,277::Main::DEBUG::MerlinOrionHardware.init(): sidereal rate=0x6f9
2013-01-15 09:27:10,287::Main::ERROR::Connection failed to start
2013-01-15 09:27:12,813::Main::DEBUG::MainController.__onActionHardwareConnectToggled(False)
2013-01-15 09:27:12,841::Main::DEBUG::PluginsConnector.start(): 'yawAxis' stop connection
2013-01-15 09:27:12,858::Main::DEBUG::PluginsConnector.start(): 'pitchAxis' stop connection
2013-01-15 09:27:12,922::Main::DEBUG::PluginsConnector.start(): 'shutter' shutdown
2013-01-15 09:27:12,978::Main::DEBUG::PluginsConnector.start(): 'shutter' stop connection
2013-01-15 09:27:14,714::Main::DEBUG::MainController.__onActionHardwareConnectToggled(True)
2013-01-15 09:27:14,721::Main::INFO::Starting connection. Please wait...
2013-01-15 09:27:14,803::Main::DEBUG::PluginsConnector.start(): 'yawAxis' establish connection
2013-01-15 09:27:14,821::Main::DEBUG::PluginsConnector.start(): 'yawAxis' init
2013-01-15 09:27:14,865::Main::DEBUG::MerlinOrionHardware.init(): firmeware version=020A84
2013-01-15 09:27:14,901::Main::DEBUG::MerlinOrionHardware.init(): encoder full circle=0xe62d3
2013-01-15 09:27:14,936::Main::DEBUG::MerlinOrionHardware.init(): sidereal rate=0x6f9
2013-01-15 09:27:14,959::Main::DEBUG::PluginsConnector.start(): 'pitchAxis' establish connection
2013-01-15 09:27:14,977::Main::DEBUG::PluginsConnector.start(): 'pitchAxis' init
2013-01-15 09:27:15,025::Main::DEBUG::MerlinOrionHardware.init(): firmeware version=020A84
2013-01-15 09:27:15,048::Main::DEBUG::MerlinOrionHardware.init(): encoder full circle=0xe62d3
2013-01-15 09:27:15,072::Main::DEBUG::MerlinOrionHardware.init(): sidereal rate=0x6f9
2013-01-15 09:27:15,089::Main::DEBUG::PluginsConnector.start(): 'shutter' establish connection
2013-01-15 09:27:15,097::Main::DEBUG::PluginsConnector.start(): 'shutter' init
2013-01-15 09:27:15,137::Main::DEBUG::MerlinOrionHardware.init(): firmeware version=020A84
2013-01-15 09:27:15,161::Main::DEBUG::MerlinOrionHardware.init(): encoder full circle=0xe62d3
2013-01-15 09:27:15,184::Main::DEBUG::MerlinOrionHardware.init(): sidereal rate=0x6f9
2013-01-15 09:27:15,203::Main::INFO::Connection started
2013-01-15 09:27:15,319::Spy::INFO::Spy resumed

2013-01-15 09:27:19,733::Main::DEBUG::ConfigController.__onCameraOrientationComboBoxCurrentIndexChanged(): orientation=portrait
2013-01-15 09:27:19,759::Main::DEBUG::ConfigController.__onLensTypeComboBoxCurrentIndexChanged(): type=rectilinear
2013-01-15 09:27:19,780::Main::DEBUG::ConfigController.__onLensTypeComboBoxCurrentIndexChanged(): lens type set to 'rectilinear'

2013-01-15 09:27:26,049::Main::DEBUG::Configuration saved

2013-01-15 09:27:36,472::Main::DEBUG::ConfigController.__onCameraOrientationComboBoxCurrentIndexChanged(): orientation=portrait
2013-01-15 09:27:36,491::Main::DEBUG::ConfigController.__onLensTypeComboBoxCurrentIndexChanged(): type=rectilinear
2013-01-15 09:27:36,499::Main::DEBUG::ConfigController.__onLensTypeComboBoxCurrentIndexChanged(): lens type set to 'rectilinear'
2013-01-15 09:27:38,510::Main::DEBUG::AbstractPluginController._initWidgets(): added 'Time value' field
2013-01-15 09:27:38,521::Main::DEBUG::AbstractPluginController._initWidgets(): added 'Bulb' field
2013-01-15 09:27:38,533::Main::DEBUG::AbstractPluginController._initWidgets(): added 'Mirror lockup' field
2013-01-15 09:27:38,544::Main::DEBUG::AbstractPluginController._initWidgets(): added 'Bracketing nb picts' field
2013-01-15 09:27:38,578::Main::DEBUG::AbstractPluginController._initWidgets(): added 'Trigger only once' field
2013-01-15 09:27:38,601::Main::DEBUG::AbstractPluginController._initWidgets(): created 'Hard' tab
2013-01-15 09:27:38,611::Main::DEBUG::AbstractPluginController._initWidgets(): added 'Pulse width high' field
2013-01-15 09:27:38,622::Main::DEBUG::AbstractPluginController._initWidgets(): added 'Pulse width low' field

2013-01-15 09:27:48,114::Main::DEBUG::AbstractPluginController._onAccepted(): config={'DRIVER_TYPE': u'serial', 'PULSE_WIDTH_LOW': 500, 'BULB_ENABLE': False, 'MIRROR_LOCKUP': False, 'BRACKETING_NB_PICTS': 1, 'TIME_VALUE': 1.0, 'PULSE_WIDTH_HIGH': 500, 'TRIGGER_ONLY_ONCE': True}
2013-01-15 09:27:48,127::Main::DEBUG::AbstractPlugin._saveConfig(): Merlin-Orion_shutter/DRIVER_TYPE=serial
2013-01-15 09:27:48,166::Main::DEBUG::AbstractPlugin._saveConfig(): Merlin-Orion_shutter/PULSE_WIDTH_LOW=500
2013-01-15 09:27:48,186::Main::DEBUG::AbstractPlugin._saveConfig(): Merlin-Orion_shutter/BULB_ENABLE=False
2013-01-15 09:27:48,229::Main::DEBUG::AbstractPlugin._saveConfig(): Merlin-Orion_shutter/MIRROR_LOCKUP=False
2013-01-15 09:27:48,248::Main::DEBUG::AbstractPlugin._saveConfig(): Merlin-Orion_shutter/BRACKETING_NB_PICTS=1
2013-01-15 09:27:48,264::Main::DEBUG::AbstractPlugin._saveConfig(): Merlin-Orion_shutter/TIME_VALUE=1.0
2013-01-15 09:27:48,299::Main::DEBUG::AbstractPlugin._saveConfig(): Merlin-Orion_shutter/PULSE_WIDTH_HIGH=500
2013-01-15 09:27:48,325::Main::DEBUG::AbstractPlugin._saveConfig(): Merlin-Orion_shutter/TRIGGER_ONLY_ONCE=True
2013-01-15 09:27:48,388::Main::DEBUG::Configuration saved

2013-01-15 09:27:59,440::Main::DEBUG::Configuration saved

2013-01-15 09:28:06,797::Main::DEBUG::PresetScan.generatePositions(): preset=<Preset name=time_lapse.xml>

2013-01-15 09:28:16,067::Shooting::INFO::Start shooting process...
2013-01-15 09:28:16,077::Shooting::DEBUG::Shooting.start(): create preset data object
2013-01-15 09:28:16,090::Shooting::DEBUG::Data.__init__(): create xml tree
2013-01-15 09:28:16,135::Shooting::DEBUG::Data.createHeader(): values={'comment': u'Generated by Papywizard 2.1.21', 'counter': '034', 'focal': '17.0', 'cameraOrientation': u'portrait', 'name': u'time_lapse.xml', 'title': u'Here goes the title', 'bracketingNbPicts': '1', 'timeValue': '1.0', 'stabilizationDelay': '0.5', 'headOrientation': 'up', 'lensType': u'rectilinear', 'sensorCoef': '1.6', 'sensorRatio': u'3:2', 'roll': '90.0', 'gps': u'Here goes the location'}
2013-01-15 09:28:16,146::Shooting::DEBUG::Data._addNode(): parent=header, tag=general, value=None, attr={}
2013-01-15 09:28:16,160::Shooting::DEBUG::Data._addNode(): parent=general, tag=title, value=Here goes the title, attr={}
2013-01-15 09:28:16,169::Shooting::DEBUG::Data._addNode(): parent=general, tag=gps, value=Here goes the location, attr={}
2013-01-15 09:28:16,184::Shooting::DEBUG::Data._addNode(): parent=general, tag=comment, value=Generated by Papywizard 2.1.21, attr={}
2013-01-15 09:28:16,194::Shooting::DEBUG::Data._addNode(): parent=header, tag=shooting, value=None, attr={'mode': 'preset'}
2013-01-15 09:28:16,203::Shooting::DEBUG::Data._addNode(): parent=shooting, tag=headOrientation, value=up, attr={}
2013-01-15 09:28:16,212::Shooting::DEBUG::Data._addNode(): parent=shooting, tag=cameraOrientation, value=portrait, attr={}
2013-01-15 09:28:16,224::Shooting::DEBUG::Data._addNode(): parent=shooting, tag=stabilizationDelay, value=0.5, attr={}
2013-01-15 09:28:16,232::Shooting::DEBUG::Data._addNode(): parent=shooting, tag=counter, value=034, attr={}
2013-01-15 09:28:16,242::Shooting::DEBUG::Data._addNode(): parent=shooting, tag=startTime, value=2013-01-15_09h28m16s, attr={}
2013-01-15 09:28:16,250::Shooting::DEBUG::Data._addNode(): parent=shooting, tag=endTime, value=2013-01-15_09h28m16s, attr={}
2013-01-15 09:28:16,259::Shooting::DEBUG::Data._addNode(): parent=header, tag=camera, value=None, attr={}
2013-01-15 09:28:16,267::Shooting::DEBUG::Data._addNode(): parent=camera, tag=timeValue, value=1.0, attr={}
2013-01-15 09:28:16,277::Shooting::DEBUG::Data._addNode(): parent=camera, tag=bracketing, value=None, attr={'nbPicts': '1'}
2013-01-15 09:28:16,285::Shooting::DEBUG::Data._addNode(): parent=camera, tag=sensor, value=None, attr={'ratio': u'3:2', 'coef': '1.6'}
2013-01-15 09:28:16,295::Shooting::DEBUG::Data._addNode(): parent=header, tag=lens, value=None, attr={'type': u'rectilinear'}
2013-01-15 09:28:16,304::Shooting::DEBUG::Data._addNode(): parent=lens, tag=focal, value=17.0, attr={}
2013-01-15 09:28:16,315::Shooting::DEBUG::Data._addNode(): parent=header, tag=preset, value=None, attr={'name': u'time_lapse.xml'}
2013-01-15 09:28:16,376::Shooting::DEBUG::Configuration saved
2013-01-15 09:28:16,394::Shooting::DEBUG::Shooting.start(): repeat 1/1
2013-01-15 09:28:16,434::Shooting::DEBUG::Shooting.start(): pict #1 of 6, index=1, yaw=0.0, pitch=0.0
2013-01-15 09:28:16,443::Main::DEBUG::ShootController.__onShootingUpdate(): index=1, yaw=0.0, pitch=0.0, state=None, next=True
2013-01-15 09:28:16,461::Shooting::INFO::Moving
2013-01-15 09:28:16,469::Main::DEBUG::ShootController.__onShootingSequence(): sequence=moving
2013-01-15 09:28:16,483::Shooting::DEBUG::MerlinOrionAxis.drive(): 'yawAxis' drive to 0.0
2013-01-15 09:28:16,519::Shooting::DEBUG::MerlinOrionAxis.drive(): 'pitchAxis' drive to 0.0
2013-01-15 09:28:16,985::Shooting::INFO::Stabilization
2013-01-15 09:28:16,994::Main::DEBUG::ShootController.__onShootingSequence(): sequence=stabilization
2013-01-15 09:28:17,505::Shooting::INFO::Shutter cycle
2013-01-15 09:28:17,521::Shooting::DEBUG::Shooting.start(): bracket #1 of 1
2013-01-15 09:28:17,532::Main::DEBUG::ShootController.__onShootingSequence(): sequence=shutter
2013-01-15 09:28:18,625::Shooting::DEBUG::Data.addPicture(): bracket=1, yaw=0.0, pitch=0.0, roll=90.0
2013-01-15 09:28:18,633::Shooting::DEBUG::Data._addNode(): parent=shoot, tag=pict, value=None, attr={'bracket': '1', 'id': '1'}
2013-01-15 09:28:18,644::Shooting::DEBUG::Data._addNode(): parent=pict, tag=time, value=2013-01-15_09h28m18s, attr={}
2013-01-15 09:28:18,652::Shooting::DEBUG::Data._addNode(): parent=pict, tag=position, value=None, attr={'yaw': '0.0', 'roll': '90.0', 'pitch': '0.0'}
2013-01-15 09:28:18,777::Main::DEBUG::ShootController.__onShootingUpdate(): index=1, yaw=0.0, pitch=0.0, state=ok, next=None
2013-01-15 09:28:18,811::Shooting::DEBUG::Shooting.start(): position index=2, yaw=0.1, pitch=0.0
2013-01-15 09:28:18,836::Main::DEBUG::ShootController.__onShootingUpdate(): index=2, yaw=0.1, pitch=0.0, state=None, next=True
2013-01-15 09:28:18,878::Shooting::DEBUG::Shooting.start(): pict #2 of 6, index=2, yaw=0.1, pitch=0.0
2013-01-15 09:28:18,887::Main::DEBUG::ShootController.__onShootingUpdate(): index=2, yaw=0.1, pitch=0.0, state=None, next=True
2013-01-15 09:28:18,905::Shooting::INFO::Moving
2013-01-15 09:28:18,914::Main::DEBUG::ShootController.__onShootingSequence(): sequence=moving
2013-01-15 09:28:18,933::Shooting::DEBUG::MerlinOrionAxis.drive(): 'yawAxis' drive to 0.1
2013-01-15 09:28:18,964::Shooting::DEBUG::MerlinOrionAxis.drive(): 'pitchAxis' drive to 0.0
2013-01-15 09:28:19,433::Shooting::INFO::Stabilization
2013-01-15 09:28:19,442::Main::DEBUG::ShootController.__onShootingSequence(): sequence=stabilization
2013-01-15 09:28:19,955::Shooting::INFO::Shutter cycle
2013-01-15 09:28:19,979::Shooting::DEBUG::Shooting.start(): bracket #1 of 1
2013-01-15 09:28:19,987::Main::DEBUG::ShootController.__onShootingSequence(): sequence=shutter
2013-01-15 09:28:21,087::Shooting::DEBUG::Data.addPicture(): bracket=1, yaw=0.0, pitch=0.0, roll=90.0
2013-01-15 09:28:21,106::Shooting::DEBUG::Data._addNode(): parent=shoot, tag=pict, value=None, attr={'bracket': '1', 'id': '2'}
2013-01-15 09:28:21,117::Shooting::DEBUG::Data._addNode(): parent=pict, tag=time, value=2013-01-15_09h28m21s, attr={}
2013-01-15 09:28:21,126::Shooting::DEBUG::Data._addNode(): parent=pict, tag=position, value=None, attr={'yaw': '0.0', 'roll': '90.0', 'pitch': '0.0'}
2013-01-15 09:28:21,194::Main::DEBUG::ShootController.__onShootingUpdate(): index=2, yaw=0.1, pitch=0.0, state=ok, next=None
2013-01-15 09:28:21,213::Shooting::DEBUG::Shooting.start(): position index=3, yaw=0.2, pitch=0.0
2013-01-15 09:28:21,223::Main::DEBUG::ShootController.__onShootingUpdate(): index=3, yaw=0.2, pitch=0.0, state=None, next=True
2013-01-15 09:28:21,260::Shooting::DEBUG::Shooting.start(): pict #3 of 6, index=3, yaw=0.2, pitch=0.0
2013-01-15 09:28:21,268::Main::DEBUG::ShootController.__onShootingUpdate(): index=3, yaw=0.2, pitch=0.0, state=None, next=True
2013-01-15 09:28:21,311::Shooting::INFO::Moving
2013-01-15 09:28:21,327::Main::DEBUG::ShootController.__onShootingSequence(): sequence=moving
2013-01-15 09:28:21,346::Shooting::DEBUG::MerlinOrionAxis.drive(): 'yawAxis' drive to 0.2
2013-01-15 09:28:21,382::Shooting::DEBUG::MerlinOrionAxis.drive(): 'pitchAxis' drive to 0.0
2013-01-15 09:28:22,758::Shooting::INFO::Stabilization
2013-01-15 09:28:22,766::Main::DEBUG::ShootController.__onShootingSequence(): sequence=stabilization
2013-01-15 09:28:23,278::Shooting::INFO::Shutter cycle
2013-01-15 09:28:23,294::Shooting::DEBUG::Shooting.start(): bracket #1 of 1
2013-01-15 09:28:23,307::Main::DEBUG::ShootController.__onShootingSequence(): sequence=shutter
2013-01-15 09:28:24,403::Shooting::DEBUG::Data.addPicture(): bracket=1, yaw=0.2, pitch=0.0, roll=90.0
2013-01-15 09:28:24,411::Shooting::DEBUG::Data._addNode(): parent=shoot, tag=pict, value=None, attr={'bracket': '1', 'id': '3'}
2013-01-15 09:28:24,421::Shooting::DEBUG::Data._addNode(): parent=pict, tag=time, value=2013-01-15_09h28m24s, attr={}
2013-01-15 09:28:24,430::Shooting::DEBUG::Data._addNode(): parent=pict, tag=position, value=None, attr={'yaw': '0.2', 'roll': '90.0', 'pitch': '0.0'}
2013-01-15 09:28:24,504::Main::DEBUG::ShootController.__onShootingUpdate(): index=3, yaw=0.2, pitch=0.0, state=ok, next=None
2013-01-15 09:28:24,524::Shooting::DEBUG::Shooting.start(): position index=4, yaw=0.3, pitch=0.0
2013-01-15 09:28:24,534::Main::DEBUG::ShootController.__onShootingUpdate(): index=4, yaw=0.3, pitch=0.0, state=None, next=True
2013-01-15 09:28:24,574::Shooting::DEBUG::Shooting.start(): pict #4 of 6, index=4, yaw=0.3, pitch=0.0
2013-01-15 09:28:24,583::Main::DEBUG::ShootController.__onShootingUpdate(): index=4, yaw=0.3, pitch=0.0, state=None, next=True
2013-01-15 09:28:24,621::Shooting::INFO::Moving
2013-01-15 09:28:24,638::Main::DEBUG::ShootController.__onShootingSequence(): sequence=moving
2013-01-15 09:28:24,659::Shooting::DEBUG::MerlinOrionAxis.drive(): 'yawAxis' drive to 0.3
2013-01-15 09:28:24,696::Shooting::DEBUG::MerlinOrionAxis.drive(): 'pitchAxis' drive to 0.0
2013-01-15 09:28:25,648::Shooting::INFO::Stabilization
2013-01-15 09:28:25,656::Main::DEBUG::ShootController.__onShootingSequence(): sequence=stabilization
2013-01-15 09:28:26,168::Shooting::INFO::Shutter cycle
2013-01-15 09:28:26,193::Shooting::DEBUG::Shooting.start(): bracket #1 of 1
2013-01-15 09:28:26,202::Main::DEBUG::ShootController.__onShootingSequence(): sequence=shutter
2013-01-15 09:28:27,283::Shooting::DEBUG::Data.addPicture(): bracket=1, yaw=0.3, pitch=0.0, roll=90.0
2013-01-15 09:28:27,302::Shooting::DEBUG::Data._addNode(): parent=shoot, tag=pict, value=None, attr={'bracket': '1', 'id': '4'}
2013-01-15 09:28:27,311::Shooting::DEBUG::Data._addNode(): parent=pict, tag=time, value=2013-01-15_09h28m27s, attr={}
2013-01-15 09:28:27,321::Shooting::DEBUG::Data._addNode(): parent=pict, tag=position, value=None, attr={'yaw': '0.3', 'roll': '90.0', 'pitch': '0.0'}
2013-01-15 09:28:27,394::Main::DEBUG::ShootController.__onShootingUpdate(): index=4, yaw=0.3, pitch=0.0, state=ok, next=None
2013-01-15 09:28:27,416::Shooting::DEBUG::Shooting.start(): position index=5, yaw=0.4, pitch=0.0
2013-01-15 09:28:27,426::Main::DEBUG::ShootController.__onShootingUpdate(): index=5, yaw=0.4, pitch=0.0, state=None, next=True
2013-01-15 09:28:27,456::Shooting::DEBUG::Shooting.start(): pict #5 of 6, index=5, yaw=0.4, pitch=0.0
2013-01-15 09:28:27,472::Main::DEBUG::ShootController.__onShootingUpdate(): index=5, yaw=0.4, pitch=0.0, state=None, next=True
2013-01-15 09:28:27,491::Shooting::INFO::Moving
2013-01-15 09:28:27,513::Main::DEBUG::ShootController.__onShootingSequence(): sequence=moving
2013-01-15 09:28:27,534::Shooting::DEBUG::MerlinOrionAxis.drive(): 'yawAxis' drive to 0.4
2013-01-15 09:28:27,599::Shooting::DEBUG::MerlinOrionAxis.drive(): 'pitchAxis' drive to 0.0
2013-01-15 09:28:28,748::Shooting::INFO::Stabilization
2013-01-15 09:28:28,756::Main::DEBUG::ShootController.__onShootingSequence(): sequence=stabilization
2013-01-15 09:28:29,268::Shooting::INFO::Shutter cycle
2013-01-15 09:28:29,288::Shooting::DEBUG::Shooting.start(): bracket #1 of 1
2013-01-15 09:28:29,310::Main::DEBUG::ShootController.__onShootingSequence(): sequence=shutter
2013-01-15 09:28:30,428::Shooting::DEBUG::Data.addPicture(): bracket=1, yaw=0.4, pitch=0.0, roll=90.0
2013-01-15 09:28:30,436::Shooting::DEBUG::Data._addNode(): parent=shoot, tag=pict, value=None, attr={'bracket': '1', 'id': '5'}
2013-01-15 09:28:30,446::Shooting::DEBUG::Data._addNode(): parent=pict, tag=time, value=2013-01-15_09h28m30s, attr={}
2013-01-15 09:28:30,454::Shooting::DEBUG::Data._addNode(): parent=pict, tag=position, value=None, attr={'yaw': '0.4', 'roll': '90.0', 'pitch': '0.0'}
2013-01-15 09:28:30,537::Main::DEBUG::ShootController.__onShootingUpdate(): index=5, yaw=0.4, pitch=0.0, state=ok, next=None
2013-01-15 09:28:30,557::Shooting::DEBUG::Shooting.start(): position index=6, yaw=0.5, pitch=0.0
2013-01-15 09:28:30,567::Main::DEBUG::ShootController.__onShootingUpdate(): index=6, yaw=0.5, pitch=0.0, state=None, next=True
2013-01-15 09:28:30,608::Shooting::DEBUG::Shooting.start(): pict #6 of 6, index=6, yaw=0.5, pitch=0.0
2013-01-15 09:28:30,617::Main::DEBUG::ShootController.__onShootingUpdate(): index=6, yaw=0.5, pitch=0.0, state=None, next=True
2013-01-15 09:28:30,656::Shooting::INFO::Moving
2013-01-15 09:28:30,676::Main::DEBUG::ShootController.__onShootingSequence(): sequence=moving
2013-01-15 09:28:30,694::Shooting::DEBUG::MerlinOrionAxis.drive(): 'yawAxis' drive to 0.5
2013-01-15 09:28:30,722::Shooting::DEBUG::MerlinOrionAxis.drive(): 'pitchAxis' drive to 0.0
2013-01-15 09:28:31,195::Shooting::INFO::Stabilization
2013-01-15 09:28:31,211::Main::DEBUG::ShootController.__onShootingSequence(): sequence=stabilization
2013-01-15 09:28:31,723::Shooting::INFO::Shutter cycle
2013-01-15 09:28:31,738::Shooting::DEBUG::Shooting.start(): bracket #1 of 1
2013-01-15 09:28:31,750::Main::DEBUG::ShootController.__onShootingSequence(): sequence=shutter
2013-01-15 09:28:32,846::Shooting::DEBUG::Data.addPicture(): bracket=1, yaw=0.4, pitch=0.0, roll=90.0
2013-01-15 09:28:32,854::Shooting::DEBUG::Data._addNode(): parent=shoot, tag=pict, value=None, attr={'bracket': '1', 'id': '6'}
2013-01-15 09:28:32,864::Shooting::DEBUG::Data._addNode(): parent=pict, tag=time, value=2013-01-15_09h28m32s, attr={}
2013-01-15 09:28:32,873::Shooting::DEBUG::Data._addNode(): parent=pict, tag=position, value=None, attr={'yaw': '0.4', 'roll': '90.0', 'pitch': '0.0'}
2013-01-15 09:28:32,959::Main::DEBUG::ShootController.__onShootingUpdate(): index=6, yaw=0.5, pitch=0.0, state=ok, next=None
2013-01-15 09:28:32,982::Main::DEBUG::ShootController.__onShootingUpdate(): index=7, yaw=0.5, pitch=0.0, state=None, next=True
2013-01-15 09:28:33,011::Shooting::INFO::Shoot process finished
2013-01-15 09:28:33,053::Main::DEBUG::ShootController.__onShootingStopped(): status=ok

2013-01-15 09:28:41,556::Shooting::INFO::Start shooting process...
2013-01-15 09:28:41,566::Shooting::DEBUG::Shooting.start(): create preset data object
2013-01-15 09:28:41,575::Shooting::DEBUG::Data.__init__(): create xml tree
2013-01-15 09:28:41,586::Shooting::DEBUG::Data.createHeader(): values={'comment': u'Generated by Papywizard 2.1.21', 'counter': '035', 'focal': '17.0', 'cameraOrientation': u'portrait', 'name': u'time_lapse.xml', 'title': u'Here goes the title', 'bracketingNbPicts': '1', 'timeValue': '1.0', 'stabilizationDelay': '0.5', 'headOrientation': 'up', 'lensType': u'rectilinear', 'sensorCoef': '1.6', 'sensorRatio': u'3:2', 'roll': '90.0', 'gps': u'Here goes the location'}
2013-01-15 09:28:41,595::Shooting::DEBUG::Data._addNode(): parent=header, tag=general, value=None, attr={}
2013-01-15 09:28:41,603::Shooting::DEBUG::Data._addNode(): parent=general, tag=title, value=Here goes the title, attr={}
2013-01-15 09:28:41,614::Shooting::DEBUG::Data._addNode(): parent=general, tag=gps, value=Here goes the location, attr={}
2013-01-15 09:28:41,623::Shooting::DEBUG::Data._addNode(): parent=general, tag=comment, value=Generated by Papywizard 2.1.21, attr={}
2013-01-15 09:28:41,634::Shooting::DEBUG::Data._addNode(): parent=header, tag=shooting, value=None, attr={'mode': 'preset'}
2013-01-15 09:28:41,643::Shooting::DEBUG::Data._addNode(): parent=shooting, tag=headOrientation, value=up, attr={}
2013-01-15 09:28:41,652::Shooting::DEBUG::Data._addNode(): parent=shooting, tag=cameraOrientation, value=portrait, attr={}
2013-01-15 09:28:41,662::Shooting::DEBUG::Data._addNode(): parent=shooting, tag=stabilizationDelay, value=0.5, attr={}
2013-01-15 09:28:41,671::Shooting::DEBUG::Data._addNode(): parent=shooting, tag=counter, value=035, attr={}
2013-01-15 09:28:41,680::Shooting::DEBUG::Data._addNode(): parent=shooting, tag=startTime, value=2013-01-15_09h28m41s, attr={}
2013-01-15 09:28:41,689::Shooting::DEBUG::Data._addNode(): parent=shooting, tag=endTime, value=2013-01-15_09h28m41s, attr={}
2013-01-15 09:28:41,697::Shooting::DEBUG::Data._addNode(): parent=header, tag=camera, value=None, attr={}
2013-01-15 09:28:41,711::Shooting::DEBUG::Data._addNode(): parent=camera, tag=timeValue, value=1.0, attr={}
2013-01-15 09:28:41,720::Shooting::DEBUG::Data._addNode(): parent=camera, tag=bracketing, value=None, attr={'nbPicts': '1'}
2013-01-15 09:28:41,732::Shooting::DEBUG::Data._addNode(): parent=camera, tag=sensor, value=None, attr={'ratio': u'3:2', 'coef': '1.6'}
2013-01-15 09:28:41,741::Shooting::DEBUG::Data._addNode(): parent=header, tag=lens, value=None, attr={'type': u'rectilinear'}
2013-01-15 09:28:41,752::Shooting::DEBUG::Data._addNode(): parent=lens, tag=focal, value=17.0, attr={}
2013-01-15 09:28:41,773::Shooting::DEBUG::Data._addNode(): parent=header, tag=preset, value=None, attr={'name': u'time_lapse.xml'}
2013-01-15 09:28:41,839::Shooting::DEBUG::Configuration saved
2013-01-15 09:28:41,848::Shooting::DEBUG::Shooting.start(): repeat 1/1
2013-01-15 09:28:41,873::Shooting::DEBUG::Shooting.start(): pict #1 of 6, index=1, yaw=0.0, pitch=0.0
2013-01-15 09:28:41,882::Main::DEBUG::ShootController.__onShootingUpdate(): index=1, yaw=0.0, pitch=0.0, state=None, next=True
2013-01-15 09:28:41,900::Shooting::INFO::Moving
2013-01-15 09:28:41,908::Main::DEBUG::ShootController.__onShootingSequence(): sequence=moving
2013-01-15 09:28:41,923::Shooting::DEBUG::MerlinOrionAxis.drive(): 'yawAxis' drive to 0.0
2013-01-15 09:28:41,965::Shooting::DEBUG::MerlinOrionAxis.drive(): 'pitchAxis' drive to 0.0
2013-01-15 09:28:44,226::Shooting::INFO::Stabilization
2013-01-15 09:28:44,235::Main::DEBUG::ShootController.__onShootingSequence(): sequence=stabilization
2013-01-15 09:28:44,746::Shooting::INFO::Shutter cycle
2013-01-15 09:28:44,766::Shooting::DEBUG::Shooting.start(): bracket #1 of 1
2013-01-15 09:28:44,774::Main::DEBUG::ShootController.__onShootingSequence(): sequence=shutter
2013-01-15 09:28:45,872::Shooting::DEBUG::Data.addPicture(): bracket=1, yaw=0.0, pitch=0.0, roll=90.0
2013-01-15 09:28:45,880::Shooting::DEBUG::Data._addNode(): parent=shoot, tag=pict, value=None, attr={'bracket': '1', 'id': '1'}
2013-01-15 09:28:45,890::Shooting::DEBUG::Data._addNode(): parent=pict, tag=time, value=2013-01-15_09h28m45s, attr={}
2013-01-15 09:28:45,899::Shooting::DEBUG::Data._addNode(): parent=pict, tag=position, value=None, attr={'yaw': '0.0', 'roll': '90.0', 'pitch': '0.0'}
2013-01-15 09:28:45,963::Main::DEBUG::ShootController.__onShootingUpdate(): index=1, yaw=0.0, pitch=0.0, state=ok, next=None
2013-01-15 09:28:45,985::Shooting::DEBUG::Shooting.start(): position index=2, yaw=0.1, pitch=0.0
2013-01-15 09:28:45,995::Main::DEBUG::ShootController.__onShootingUpdate(): index=2, yaw=0.1, pitch=0.0, state=None, next=True
2013-01-15 09:28:46,029::Shooting::DEBUG::Shooting.start(): pict #2 of 6, index=2, yaw=0.1, pitch=0.0
2013-01-15 09:28:46,044::Main::DEBUG::ShootController.__onShootingUpdate(): index=2, yaw=0.1, pitch=0.0, state=None, next=True
2013-01-15 09:28:46,067::Shooting::INFO::Moving
2013-01-15 09:28:46,084::Main::DEBUG::ShootController.__onShootingSequence(): sequence=moving
2013-01-15 09:28:46,112::Shooting::DEBUG::MerlinOrionAxis.drive(): 'yawAxis' drive to 0.1
2013-01-15 09:28:46,159::Shooting::DEBUG::MerlinOrionAxis.drive(): 'pitchAxis' drive to 0.0
2013-01-15 09:28:46,629::Shooting::INFO::Stabilization
2013-01-15 09:28:46,819::Main::DEBUG::ShootController.__onShootingSequence(): sequence=stabilization
2013-01-15 09:28:47,338::Shooting::INFO::Shutter cycle
2013-01-15 09:28:47,359::Shooting::DEBUG::Shooting.start(): bracket #1 of 1
2013-01-15 09:28:47,367::Main::DEBUG::ShootController.__onShootingSequence(): sequence=shutter
2013-01-15 09:28:48,459::Shooting::DEBUG::Data.addPicture(): bracket=1, yaw=0.0, pitch=0.0, roll=90.0
2013-01-15 09:28:48,479::Shooting::DEBUG::Data._addNode(): parent=shoot, tag=pict, value=None, attr={'bracket': '1', 'id': '2'}
2013-01-15 09:28:48,489::Shooting::DEBUG::Data._addNode(): parent=pict, tag=time, value=2013-01-15_09h28m48s, attr={}
2013-01-15 09:28:48,499::Shooting::DEBUG::Data._addNode(): parent=pict, tag=position, value=None, attr={'yaw': '0.0', 'roll': '90.0', 'pitch': '0.0'}
2013-01-15 09:28:48,565::Main::DEBUG::ShootController.__onShootingUpdate(): index=2, yaw=0.1, pitch=0.0, state=ok, next=None
2013-01-15 09:28:48,584::Shooting::DEBUG::Shooting.start(): position index=3, yaw=0.2, pitch=0.0
2013-01-15 09:28:48,594::Main::DEBUG::ShootController.__onShootingUpdate(): index=3, yaw=0.2, pitch=0.0, state=None, next=True
2013-01-15 09:28:48,631::Shooting::DEBUG::Shooting.start(): pict #3 of 6, index=3, yaw=0.2, pitch=0.0
2013-01-15 09:28:48,641::Main::DEBUG::ShootController.__onShootingUpdate(): index=3, yaw=0.2, pitch=0.0, state=None, next=True
2013-01-15 09:28:48,685::Shooting::INFO::Moving
2013-01-15 09:28:48,696::Main::DEBUG::ShootController.__onShootingSequence(): sequence=moving
2013-01-15 09:28:48,718::Shooting::DEBUG::MerlinOrionAxis.drive(): 'yawAxis' drive to 0.2
2013-01-15 09:28:48,757::Shooting::DEBUG::MerlinOrionAxis.drive(): 'pitchAxis' drive to 0.0
2013-01-15 09:28:50,123::Shooting::INFO::Stabilization
2013-01-15 09:28:50,132::Main::DEBUG::ShootController.__onShootingSequence(): sequence=stabilization
2013-01-15 09:28:50,645::Shooting::INFO::Shutter cycle
2013-01-15 09:28:50,661::Shooting::DEBUG::Shooting.start(): bracket #1 of 1
2013-01-15 09:28:50,669::Main::DEBUG::ShootController.__onShootingSequence(): sequence=shutter
2013-01-15 09:28:51,797::Shooting::DEBUG::Data.addPicture(): bracket=1, yaw=0.2, pitch=0.0, roll=90.0
2013-01-15 09:28:51,818::Shooting::DEBUG::Data._addNode(): parent=shoot, tag=pict, value=None, attr={'bracket': '1', 'id': '3'}
2013-01-15 09:28:51,828::Shooting::DEBUG::Data._addNode(): parent=pict, tag=time, value=2013-01-15_09h28m51s, attr={}
2013-01-15 09:28:51,837::Shooting::DEBUG::Data._addNode(): parent=pict, tag=position, value=None, attr={'yaw': '0.2', 'roll': '90.0', 'pitch': '0.0'}
2013-01-15 09:28:51,913::Main::DEBUG::ShootController.__onShootingUpdate(): index=3, yaw=0.2, pitch=0.0, state=ok, next=None
2013-01-15 09:28:51,936::Shooting::DEBUG::Shooting.start(): position index=4, yaw=0.3, pitch=0.0
2013-01-15 09:28:51,945::Main::DEBUG::ShootController.__onShootingUpdate(): index=4, yaw=0.3, pitch=0.0, state=None, next=True
2013-01-15 09:28:51,982::Shooting::DEBUG::Shooting.start(): pict #4 of 6, index=4, yaw=0.3, pitch=0.0
2013-01-15 09:28:51,991::Main::DEBUG::ShootController.__onShootingUpdate(): index=4, yaw=0.3, pitch=0.0, state=None, next=True
2013-01-15 09:28:52,008::Shooting::INFO::Moving
2013-01-15 09:28:52,026::Main::DEBUG::ShootController.__onShootingSequence(): sequence=moving
2013-01-15 09:28:52,058::Shooting::DEBUG::MerlinOrionAxis.drive(): 'yawAxis' drive to 0.3
2013-01-15 09:28:52,111::Shooting::DEBUG::MerlinOrionAxis.drive(): 'pitchAxis' drive to 0.0
2013-01-15 09:28:53,265::Shooting::INFO::Stabilization
2013-01-15 09:28:53,273::Main::DEBUG::ShootController.__onShootingSequence(): sequence=stabilization
2013-01-15 09:28:53,785::Shooting::INFO::Shutter cycle
2013-01-15 09:28:53,816::Shooting::DEBUG::Shooting.start(): bracket #1 of 1
2013-01-15 09:28:53,835::Main::DEBUG::ShootController.__onShootingSequence(): sequence=shutter
2013-01-15 09:28:54,931::Shooting::DEBUG::Data.addPicture(): bracket=1, yaw=0.3, pitch=0.0, roll=90.0
2013-01-15 09:28:54,939::Shooting::DEBUG::Data._addNode(): parent=shoot, tag=pict, value=None, attr={'bracket': '1', 'id': '4'}
2013-01-15 09:28:54,951::Shooting::DEBUG::Data._addNode(): parent=pict, tag=time, value=2013-01-15_09h28m54s, attr={}
2013-01-15 09:28:55,311::Shooting::DEBUG::Data._addNode(): parent=pict, tag=position, value=None, attr={'yaw': '0.3', 'roll': '90.0', 'pitch': '0.0'}
2013-01-15 09:28:55,416::Main::DEBUG::ShootController.__onShootingUpdate(): index=4, yaw=0.3, pitch=0.0, state=ok, next=None
2013-01-15 09:28:55,436::Shooting::DEBUG::Shooting.start(): position index=5, yaw=0.4, pitch=0.0
2013-01-15 09:28:55,445::Main::DEBUG::ShootController.__onShootingUpdate(): index=5, yaw=0.4, pitch=0.0, state=None, next=True
2013-01-15 09:28:55,481::Shooting::DEBUG::Shooting.start(): pict #5 of 6, index=5, yaw=0.4, pitch=0.0
2013-01-15 09:28:55,502::Main::DEBUG::ShootController.__onShootingUpdate(): index=5, yaw=0.4, pitch=0.0, state=None, next=True
2013-01-15 09:28:55,522::Shooting::INFO::Moving
2013-01-15 09:28:55,534::Main::DEBUG::ShootController.__onShootingSequence(): sequence=moving
2013-01-15 09:28:55,556::Shooting::DEBUG::MerlinOrionAxis.drive(): 'yawAxis' drive to 0.4
2013-01-15 09:28:55,595::Shooting::DEBUG::MerlinOrionAxis.drive(): 'pitchAxis' drive to 0.0
2013-01-15 09:28:56,565::Shooting::INFO::Stabilization
2013-01-15 09:28:56,574::Main::DEBUG::ShootController.__onShootingSequence(): sequence=stabilization
2013-01-15 09:28:57,086::Shooting::INFO::Shutter cycle
2013-01-15 09:28:57,122::Shooting::DEBUG::Shooting.start(): bracket #1 of 1
2013-01-15 09:28:57,130::Main::DEBUG::ShootController.__onShootingSequence(): sequence=shutter
2013-01-15 09:28:58,237::Shooting::DEBUG::Data.addPicture(): bracket=1, yaw=0.4, pitch=0.0, roll=90.0
2013-01-15 09:28:58,245::Shooting::DEBUG::Data._addNode(): parent=shoot, tag=pict, value=None, attr={'bracket': '1', 'id': '5'}
2013-01-15 09:28:58,256::Shooting::DEBUG::Data._addNode(): parent=pict, tag=time, value=2013-01-15_09h28m58s, attr={}
2013-01-15 09:28:58,264::Shooting::DEBUG::Data._addNode(): parent=pict, tag=position, value=None, attr={'yaw': '0.4', 'roll': '90.0', 'pitch': '0.0'}
2013-01-15 09:28:58,345::Main::DEBUG::ShootController.__onShootingUpdate(): index=5, yaw=0.4, pitch=0.0, state=ok, next=None
2013-01-15 09:28:58,370::Shooting::DEBUG::Shooting.start(): position index=6, yaw=0.5, pitch=0.0
2013-01-15 09:28:58,380::Main::DEBUG::ShootController.__onShootingUpdate(): index=6, yaw=0.5, pitch=0.0, state=None, next=True
2013-01-15 09:28:58,440::Shooting::DEBUG::Shooting.start(): pict #6 of 6, index=6, yaw=0.5, pitch=0.0
2013-01-15 09:28:58,458::Main::DEBUG::ShootController.__onShootingUpdate(): index=6, yaw=0.5, pitch=0.0, state=None, next=True
2013-01-15 09:28:58,480::Shooting::INFO::Moving
2013-01-15 09:28:58,500::Main::DEBUG::ShootController.__onShootingSequence(): sequence=moving
2013-01-15 09:28:58,518::Shooting::DEBUG::MerlinOrionAxis.drive(): 'yawAxis' drive to 0.5
2013-01-15 09:28:58,558::Shooting::DEBUG::MerlinOrionAxis.drive(): 'pitchAxis' drive to 0.0
2013-01-15 09:28:59,496::Shooting::INFO::Stabilization
2013-01-15 09:28:59,505::Main::DEBUG::ShootController.__onShootingSequence(): sequence=stabilization
2013-01-15 09:29:00,017::Shooting::INFO::Shutter cycle
2013-01-15 09:29:00,032::Shooting::DEBUG::Shooting.start(): bracket #1 of 1
2013-01-15 09:29:00,041::Main::DEBUG::ShootController.__onShootingSequence(): sequence=shutter
2013-01-15 09:29:01,148::Shooting::DEBUG::Data.addPicture(): bracket=1, yaw=0.5, pitch=0.0, roll=90.0
2013-01-15 09:29:01,158::Shooting::DEBUG::Data._addNode(): parent=shoot, tag=pict, value=None, attr={'bracket': '1', 'id': '6'}
2013-01-15 09:29:01,175::Shooting::DEBUG::Data._addNode(): parent=pict, tag=time, value=2013-01-15_09h29m01s, attr={}
2013-01-15 09:29:01,187::Shooting::DEBUG::Data._addNode(): parent=pict, tag=position, value=None, attr={'yaw': '0.5', 'roll': '90.0', 'pitch': '0.0'}
2013-01-15 09:29:01,301::Main::DEBUG::ShootController.__onShootingUpdate(): index=6, yaw=0.5, pitch=0.0, state=ok, next=None
2013-01-15 09:29:01,326::Main::DEBUG::ShootController.__onShootingUpdate(): index=7, yaw=0.5, pitch=0.0, state=None, next=True
2013-01-15 09:29:01,377::Shooting::INFO::Shoot process finished
2013-01-15 09:29:01,394::Main::DEBUG::ShootController.__onShootingStopped(): status=ok

2013-01-15 09:29:22,302::Main::DEBUG::ConfigController.__onCameraOrientationComboBoxCurrentIndexChanged(): orientation=portrait
2013-01-15 09:29:22,320::Main::DEBUG::ConfigController.__onLensTypeComboBoxCurrentIndexChanged(): type=rectilinear
2013-01-15 09:29:22,328::Main::DEBUG::ConfigController.__onLensTypeComboBoxCurrentIndexChanged(): lens type set to 'rectilinear'

2013-01-15 09:29:28,893::Main::DEBUG::Configuration saved


Top
 Profile  
 
 Post subject:
PostPosted: Thu Jan 24, 2013 12:52 pm 
Offline
Member
User avatar

Joined: Wed Dec 07, 2005 6:21 pm
Posts: 5826
Location: Grenoble, France
Thanks for the log.

Well, the problems are multiples: the move sequence takes more or less time, saving datas also takes more or less time... So, total time varies a lot.

You can try to disable the data writing, but for the move, there is nothing we can do.

For timelapse, where precise timing is needed, this would need some modifications in the code, to ensure the triggering sequence occurs at a precise intervalle...

_________________
Frédéric

Canon 20D + 17-40/f4 L USM + 70-200/f4 L USM + 50/f1.4 USM
Merlin/Orion panohead + Papywizard on Nokia N800 and HP TC-1100


Top
 Profile  
 
 Post subject:
PostPosted: Thu Jan 24, 2013 12:54 pm 
Offline
Member

Joined: Tue Dec 18, 2012 9:30 pm
Posts: 15
Sorry I just noticed that I had logger level set to debug, I did it again with trace. The shooting intervals are now: 2, 2, 4, 2 and 4 seconds. Settings are otherwise as stated above.

Trace log:

Code:
None

2013-01-24 12:50:29,626::Main::INFO::Starting Papywizard...
2013-01-24 12:50:29,627::Main::DEBUG::main(): platform=linux2
2013-01-24 12:50:29,698::Main::INFO::Importing modules...
2013-01-24 12:50:29,779::Main::INFO::Loading i18n files...
2013-01-24 12:50:29,780::Main::DEBUG::main(): locale=en_AU
2013-01-24 12:50:29,780::Main::WARNING::Can't find qt translation file
2013-01-24 12:50:29,781::Main::INFO::Loading Style Sheets...
2013-01-24 12:50:29,781::Main::WARNING::No user Style Sheet found
2013-01-24 12:50:29,781::Main::INFO::Loading configuration...
2013-01-24 12:50:29,782::Main::DEBUG::ConfigManager.__init__(): versions: dist=['2', '1', '21'], user=[u'2', u'1', u'21']
2013-01-24 12:50:29,824::Main::DEBUG::ConfigManager.__init__(): user config. is up-to-date
2013-01-24 12:50:29,825::Main::INFO::Load plugins...
2013-01-24 12:50:29,832::Main::DEBUG::DslrRemoteProShutter._defineConfig()
2013-01-24 12:50:29,833::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-24 12:50:29,834::Main::DEBUG::AbstractPlugin._loadConfig(): config={'USER_EXPOSURE_COMPENSATION_LIST': '-2, 0, +2', 'DRY_RUN': False, 'MIRROR_LOCKUP': False, 'FILENAME_PREFIX': '', 'CAMERA_EXPOSURE_COMPENSATION_LIST': u'\xb12@1/3', 'OUTPUT_DIR': '/home/sbell', 'PROGRAM_PATH': 'C:\\Program Files\\BreezeSys\\DSLR Remote Pro\\DSLRRemoteTest\\DSLRRemoteTest.exe'}
2013-01-24 12:50:29,835::Main::DEBUG::PluginsManager.register(): added 'DSLR Remote Pro' plugin with capacity 'shutter'
2013-01-24 12:50:29,836::Main::TRACE::EOSUtilityShutter._defineConfig()
2013-01-24 12:50:29,836::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-24 12:50:29,836::Main::DEBUG::AbstractPlugin._loadConfig(): config={'FOCUS_DIRECTION': u'far', 'BRACKETING_TYPE': u'0-+', 'BULB_ENABLE': False, 'DRY_RUN': True, 'BULB_BASE_EXPOSURE': 1, 'FOCUS_NB_PICTS': 1, 'BRACKETING_NB_PICTS': 1, 'FOCUS_STEP_COUNT': 1, 'FOCUS_ENABLE': False, 'EOS_UTILITY_VERSION': u'new', 'PROGRAM_PATH': u'C:\\Program Files\\Papywizard\\EOSBracket.exe', 'BRACKETING_STEP': u'1', 'FOCUS_STEP': u'medium'}
2013-01-24 12:50:29,837::Main::DEBUG::PluginsManager.register(): added 'EOS Utility' plugin with capacity 'shutter'
2013-01-24 12:50:29,837::Main::TRACE::GenericTetheredShutter._defineConfig()
2013-01-24 12:50:29,838::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-24 12:50:29,838::Main::DEBUG::AbstractPlugin._loadConfig(): config={'MIRROR_LOCKUP_COMMAND': u'gphoto2 --capture-image', 'MIRROR_LOCKUP': False, 'PARAM_4': u'', 'PARAM_1': u'', 'PARAM_0': u'', 'SHOOT_COMMAND': u'gphoto2 --capture-image-and-download', 'PARAM_2': u'', 'TIME_VALUE': 6.9999999999999902, 'PARAM_3': u''}
2013-01-24 12:50:29,838::Main::DEBUG::PluginsManager.register(): added 'Generic Tethered' plugin with capacity 'shutter'
2013-01-24 12:50:29,840::Main::TRACE::GigaPanBotAxis._init()
2013-01-24 12:50:29,840::Main::TRACE::AbstractHardwarePlugin._defineConfig()
2013-01-24 12:50:29,840::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-24 12:50:29,841::Main::DEBUG::AbstractPlugin._loadConfig(): config={'HIGH_LIMIT': 360.0, 'DRIVER_TYPE': 'bluetooth', 'LOW_LIMIT': -360.0}
2013-01-24 12:50:29,841::Main::DEBUG::PluginsManager.register(): added 'GigaPanBot' plugin with capacity 'yawAxis'
2013-01-24 12:50:29,841::Main::TRACE::GigaPanBotAxis._init()
2013-01-24 12:50:29,841::Main::TRACE::AbstractHardwarePlugin._defineConfig()
2013-01-24 12:50:29,842::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-24 12:50:29,842::Main::DEBUG::AbstractPlugin._loadConfig(): config={'HIGH_LIMIT': 360.0, 'DRIVER_TYPE': 'bluetooth', 'LOW_LIMIT': -360.0}
2013-01-24 12:50:29,842::Main::DEBUG::PluginsManager.register(): added 'GigaPanBot' plugin with capacity 'pitchAxis'
2013-01-24 12:50:29,842::Main::TRACE::GigaPanBotShutter._init()
2013-01-24 12:50:29,843::Main::TRACE::ShutterPlugin._init()
2013-01-24 12:50:29,843::Main::TRACE::AbstractHardwarePlugin._defineConfig()
2013-01-24 12:50:29,843::Main::TRACE::ShutterPlugin._defineConfig()
2013-01-24 12:50:29,843::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-24 12:50:29,844::Main::DEBUG::AbstractPlugin._loadConfig(): config={'DRIVER_TYPE': 'bluetooth', 'PULSE_WIDTH_LOW': 250, 'BULB_ENABLE': False, 'MIRROR_LOCKUP': False, 'BRACKETING_NB_PICTS': 1, 'TIME_VALUE': 0.5, 'PULSE_WIDTH_HIGH': 250, 'TRIGGER_ONLY_ONCE': False}
2013-01-24 12:50:29,844::Main::DEBUG::PluginsManager.register(): added 'GigaPanBot' plugin with capacity 'shutter'
2013-01-24 12:50:29,845::Main::TRACE::GphotoBracketShutter._defineConfig()
2013-01-24 12:50:29,845::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-24 12:50:29,846::Main::DEBUG::AbstractPlugin._loadConfig(): config={'BRACKETING_EV_STEP': 1, 'BRACKETING_EV_LIST': '0', 'BRACKETING_PLUS_STEP': 1, 'MIRROR_LOCKUP_COMMAND': 'gphoto2 --capture-image', 'MIRROR_LOCKUP': False, 'DOWNLOAD_DIR': '/home/sbell', 'BRACKETING_MINUS_STEP': 1, 'DOWNLOAD_ENABLE': False, 'BRACKETING_NB_PICTS': 1, 'BRACKETING_MINUS_NB_PICTS': 0, 'BRACKETING_EV_BIAS': 0, 'BRACKETING_ADVANCED': False, 'BRACKETING_PLUS_NB_PICTS': 0, 'DOWNLOAD_THEN_DELETE': False, 'PROGRAM_PATH': 'gphoto2', 'DOWNLOAD_AFTER': u'Each shot'}
2013-01-24 12:50:29,846::Main::DEBUG::PluginsManager.register(): added 'Gphoto Bracket' plugin with capacity 'shutter'
2013-01-24 12:50:29,847::Main::TRACE::MerlinOrionAxis._init()
2013-01-24 12:50:29,847::Main::TRACE::AbstractHardwarePlugin._defineConfig()
2013-01-24 12:50:29,847::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-24 12:50:29,848::Main::DEBUG::AbstractPlugin._loadConfig(): config={'HIGH_LIMIT': 360.0, 'ALTERNATE_DRIVE_ANGLE': 7.0, 'INERTIA_ANGLE': 1.0, 'DRIVER_TYPE': u'serial', 'LOW_LIMIT': -360.0, 'ALTERNATE_DRIVE': True}
2013-01-24 12:50:29,848::Main::TRACE::MerlinOrionAxis._init()
2013-01-24 12:50:29,848::Main::TRACE::AbstractHardwarePlugin._defineConfig()
2013-01-24 12:50:29,848::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-24 12:50:29,849::Main::DEBUG::AbstractPlugin._loadConfig(): config={'HIGH_LIMIT': 360.0, 'ALTERNATE_DRIVE_ANGLE': 7.0, 'INERTIA_ANGLE': 1.0, 'DRIVER_TYPE': u'serial', 'LOW_LIMIT': -360.0, 'ALTERNATE_DRIVE': True}
2013-01-24 12:50:29,849::Main::DEBUG::PluginsManager.register(): added 'Merlin-Orion' plugin with capacity 'yawAxis'
2013-01-24 12:50:29,849::Main::TRACE::MerlinOrionAxis._init()
2013-01-24 12:50:29,849::Main::TRACE::AbstractHardwarePlugin._defineConfig()
2013-01-24 12:50:29,850::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-24 12:50:29,850::Main::DEBUG::AbstractPlugin._loadConfig(): config={'HIGH_LIMIT': 360.0, 'ALTERNATE_DRIVE_ANGLE': 7.0, 'INERTIA_ANGLE': 1.0, 'DRIVER_TYPE': u'serial', 'LOW_LIMIT': -360.0, 'ALTERNATE_DRIVE': True}
2013-01-24 12:50:29,850::Main::TRACE::MerlinOrionAxis._init()
2013-01-24 12:50:29,851::Main::TRACE::AbstractHardwarePlugin._defineConfig()
2013-01-24 12:50:29,851::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-24 12:50:29,851::Main::DEBUG::AbstractPlugin._loadConfig(): config={'HIGH_LIMIT': 360.0, 'ALTERNATE_DRIVE_ANGLE': 7.0, 'INERTIA_ANGLE': 1.0, 'DRIVER_TYPE': u'serial', 'LOW_LIMIT': -360.0, 'ALTERNATE_DRIVE': True}
2013-01-24 12:50:29,851::Main::DEBUG::PluginsManager.register(): added 'Merlin-Orion' plugin with capacity 'pitchAxis'
2013-01-24 12:50:29,852::Main::TRACE::MerlinOrionShutter._init()
2013-01-24 12:50:29,852::Main::TRACE::ShutterPlugin._init()
2013-01-24 12:50:29,852::Main::TRACE::AbstractHardwarePlugin._defineConfig()
2013-01-24 12:50:29,852::Main::TRACE::ShutterPlugin._defineConfig()
2013-01-24 12:50:29,853::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-24 12:50:29,853::Main::DEBUG::AbstractPlugin._loadConfig(): config={'DRIVER_TYPE': u'serial', 'PULSE_WIDTH_LOW': 250, 'BULB_ENABLE': False, 'MIRROR_LOCKUP': False, 'BRACKETING_NB_PICTS': 1, 'TIME_VALUE': 0.5, 'PULSE_WIDTH_HIGH': 250, 'TRIGGER_ONLY_ONCE': False}
2013-01-24 12:50:29,853::Main::TRACE::MerlinOrionShutter._init()
2013-01-24 12:50:29,854::Main::TRACE::ShutterPlugin._init()
2013-01-24 12:50:29,854::Main::TRACE::AbstractHardwarePlugin._defineConfig()
2013-01-24 12:50:29,854::Main::TRACE::ShutterPlugin._defineConfig()
2013-01-24 12:50:29,854::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-24 12:50:29,855::Main::DEBUG::AbstractPlugin._loadConfig(): config={'DRIVER_TYPE': u'serial', 'PULSE_WIDTH_LOW': 250, 'BULB_ENABLE': False, 'MIRROR_LOCKUP': False, 'BRACKETING_NB_PICTS': 1, 'TIME_VALUE': 0.5, 'PULSE_WIDTH_HIGH': 250, 'TRIGGER_ONLY_ONCE': False}
2013-01-24 12:50:29,855::Main::DEBUG::PluginsManager.register(): added 'Merlin-Orion' plugin with capacity 'shutter'
2013-01-24 12:50:29,855::Main::DEBUG::NkRemoteShutter._defineConfig()
2013-01-24 12:50:29,856::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-24 12:50:29,856::Main::DEBUG::AbstractPlugin._loadConfig(): config={'USER_EXPOSURE_COMPENSATION_LIST': u'-2, 0, +2', 'DRY_RUN': False, 'MIRROR_LOCKUP': False, 'FILENAME_PREFIX': u'', 'CAMERA_EXPOSURE_COMPENSATION_LIST': u'\xb15@1/2', 'OUTPUT_DIR': u'/home/sbell', 'PROGRAM_PATH': u'C:\\Program Files\\BreezeSys\\NKRemote\\NKRemoteLibTest.exe'}
2013-01-24 12:50:29,856::Main::DEBUG::PluginsManager.register(): added 'NK Remote' plugin with capacity 'shutter'
2013-01-24 12:50:29,857::Main::TRACE::PanoduinoAxis._init()
2013-01-24 12:50:29,858::Main::TRACE::AbstractHardwarePlugin._defineConfig()
2013-01-24 12:50:29,858::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-24 12:50:29,858::Main::DEBUG::AbstractPlugin._loadConfig(): config={'NEUTRAL_POSITION': 1000, 'HIGH_LIMIT': 360.0, 'DIRECTION': u'forward', 'ANGLE_1MS': 315.0, 'ACCEL': 1, 'LOW_LIMIT': -360.0, 'DRIVER_TYPE': 'bluetooth', 'ADDITIONAL_DELAY': 0.5, 'SPEED': 5, 'CHANNEL': 0}
2013-01-24 12:50:29,858::Main::DEBUG::PluginsManager.register(): added 'Panoduino' plugin with capacity 'yawAxis'
2013-01-24 12:50:29,859::Main::TRACE::PanoduinoAxis._init()
2013-01-24 12:50:29,859::Main::TRACE::AbstractHardwarePlugin._defineConfig()
2013-01-24 12:50:29,859::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-24 12:50:29,859::Main::DEBUG::AbstractPlugin._loadConfig(): config={'NEUTRAL_POSITION': 1500, 'HIGH_LIMIT': 360.0, 'DIRECTION': u'reverse', 'ANGLE_1MS': 330.0, 'ACCEL': 1, 'LOW_LIMIT': -360.0, 'DRIVER_TYPE': 'bluetooth', 'ADDITIONAL_DELAY': 0.5, 'SPEED': 5, 'CHANNEL': 1}
2013-01-24 12:50:29,860::Main::DEBUG::PluginsManager.register(): added 'Panoduino' plugin with capacity 'pitchAxis'
2013-01-24 12:50:29,860::Main::TRACE::PanoduinoShutter._init()
2013-01-24 12:50:29,860::Main::TRACE::ShutterPlugin._init()
2013-01-24 12:50:29,860::Main::TRACE::AbstractHardwarePlugin._defineConfig()
2013-01-24 12:50:29,861::Main::TRACE::ShutterPlugin._defineConfig()
2013-01-24 12:50:29,861::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-24 12:50:29,861::Main::DEBUG::AbstractPlugin._loadConfig(): config={'DRIVER_TYPE': 'bluetooth', 'PULSE_WIDTH_LOW': 250, 'BULB_ENABLE': False, 'MIRROR_LOCKUP': False, 'SHUTTER_ON': 2000, 'SHUTTER_OFF': 1000, 'BRACKETING_NB_PICTS': 1, 'TIME_VALUE': 0.5, 'CHANNEL': 2, 'PULSE_WIDTH_HIGH': 250, 'TRIGGER_ONLY_ONCE': False}
2013-01-24 12:50:29,861::Main::DEBUG::PluginsManager.register(): added 'Panoduino' plugin with capacity 'shutter'
2013-01-24 12:50:29,862::Main::TRACE::PixOrbAxis._init()
2013-01-24 12:50:29,863::Main::TRACE::AbstractHardwarePlugin._defineConfig()
2013-01-24 12:50:29,863::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-24 12:50:29,863::Main::DEBUG::AbstractPlugin._loadConfig(): config={'AXIS_ACCURACY': 0.10000000000000001, 'HIGH_LIMIT': 360.0, 'DRIVER_TYPE': 'bluetooth', 'LOW_LIMIT': -360.0, 'SPEED_INDEX': 9, 'AXIS_WITH_BREAK': False}
2013-01-24 12:50:29,863::Main::DEBUG::PluginsManager.register(): added 'PixOrb' plugin with capacity 'yawAxis'
2013-01-24 12:50:29,864::Main::TRACE::PixOrbAxis._init()
2013-01-24 12:50:29,864::Main::TRACE::AbstractHardwarePlugin._defineConfig()
2013-01-24 12:50:29,864::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-24 12:50:29,864::Main::DEBUG::AbstractPlugin._loadConfig(): config={'AXIS_ACCURACY': 0.10000000000000001, 'HIGH_LIMIT': 360.0, 'DRIVER_TYPE': 'bluetooth', 'LOW_LIMIT': -360.0, 'SPEED_INDEX': 9, 'AXIS_WITH_BREAK': False}
2013-01-24 12:50:29,865::Main::DEBUG::PluginsManager.register(): added 'PixOrb' plugin with capacity 'pitchAxis'
2013-01-24 12:50:29,865::Main::TRACE::PixOrbShutter._init()
2013-01-24 12:50:29,865::Main::TRACE::ShutterPlugin._init()
2013-01-24 12:50:29,865::Main::TRACE::AbstractHardwarePlugin._defineConfig()
2013-01-24 12:50:29,866::Main::TRACE::ShutterPlugin._defineConfig()
2013-01-24 12:50:29,866::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-24 12:50:29,866::Main::DEBUG::AbstractPlugin._loadConfig(): config={'DRIVER_TYPE': 'bluetooth', 'PULSE_WIDTH_LOW': 250, 'BULB_ENABLE': False, 'MIRROR_LOCKUP': False, 'BRACKETING_NB_PICTS': 1, 'TIME_VALUE': 0.5, 'PULSE_WIDTH_HIGH': 250, 'TRIGGER_ONLY_ONCE': False}
2013-01-24 12:50:29,866::Main::DEBUG::PluginsManager.register(): added 'PixOrb' plugin with capacity 'shutter'
2013-01-24 12:50:29,867::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-24 12:50:29,867::Main::DEBUG::AbstractPlugin._loadConfig(): config={'HIGH_LIMIT': 360.0, 'SPEED': 30, 'LOW_LIMIT': -360.0}
2013-01-24 12:50:29,868::Main::DEBUG::PluginsManager.register(): added 'Simulation' plugin with capacity 'yawAxis'
2013-01-24 12:50:29,868::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-24 12:50:29,868::Main::DEBUG::AbstractPlugin._loadConfig(): config={'HIGH_LIMIT': 360.0, 'SPEED': 30, 'LOW_LIMIT': -360.0}
2013-01-24 12:50:29,868::Main::DEBUG::PluginsManager.register(): added 'Simulation' plugin with capacity 'pitchAxis'
2013-01-24 12:50:29,869::Main::TRACE::AbstractShutterPlugin._defineConfig()
2013-01-24 12:50:29,869::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-24 12:50:29,869::Main::DEBUG::AbstractPlugin._loadConfig(): config={'TIME_VALUE': 5.0, 'TRIGGER_ONLY_ONCE': False, 'BRACKETING_NB_PICTS': 1, 'MIRROR_LOCKUP': False}
2013-01-24 12:50:29,869::Main::DEBUG::PluginsManager.register(): added 'Simulation' plugin with capacity 'shutter'
2013-01-24 12:50:29,870::Main::TRACE::TimelordShutter._defineConfig()
2013-01-24 12:50:29,870::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-24 12:50:29,870::Main::DEBUG::AbstractPlugin._loadConfig(): config={'PROGRAM_PATH': 'C:\\Program Files\\OxfordEye\\Timelord\\Timelord.exe', 'LRD_FILE': 'C:\\Documents and Settings\\win2k\\My Documents\\timelord.lrd'}
2013-01-24 12:50:29,870::Main::DEBUG::PluginsManager.register(): added 'Timelord' plugin with capacity 'shutter'
2013-01-24 12:50:29,871::Main::TRACE::UrsaMinorBt2Shutter._init()
2013-01-24 12:50:29,871::Main::TRACE::ShutterPlugin._init()
2013-01-24 12:50:29,872::Main::TRACE::UrsaMinorBt2Shutter._defineConfig()
2013-01-24 12:50:29,872::Main::TRACE::AbstractHardwarePlugin._defineConfig()
2013-01-24 12:50:29,872::Main::TRACE::ShutterPlugin._defineConfig()
2013-01-24 12:50:29,872::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-24 12:50:29,873::Main::DEBUG::AbstractPlugin._loadConfig(): config={'DRIVER_TYPE': 'bluetooth', 'PULSE_WIDTH_LOW': 250, 'BULB_ENABLE': False, 'MIRROR_LOCKUP': False, 'FOCUS_PULSE_WIDTH': 0.5, 'FOCUS_MAINTAIN': False, 'BRACKETING_NB_PICTS': 1, 'TIME_VALUE': 0.5, 'FOCUS_ENABLE': False, 'PULSE_WIDTH_HIGH': 250, 'TRIGGER_ONLY_ONCE': False}
2013-01-24 12:50:29,873::Main::TRACE::UrsaMinorBt2Shutter._init()
2013-01-24 12:50:29,873::Main::TRACE::ShutterPlugin._init()
2013-01-24 12:50:29,873::Main::TRACE::UrsaMinorBt2Shutter._defineConfig()
2013-01-24 12:50:29,874::Main::TRACE::AbstractHardwarePlugin._defineConfig()
2013-01-24 12:50:29,874::Main::TRACE::ShutterPlugin._defineConfig()
2013-01-24 12:50:29,874::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-24 12:50:29,874::Main::DEBUG::AbstractPlugin._loadConfig(): config={'DRIVER_TYPE': 'bluetooth', 'PULSE_WIDTH_LOW': 250, 'BULB_ENABLE': False, 'MIRROR_LOCKUP': False, 'FOCUS_PULSE_WIDTH': 0.5, 'FOCUS_MAINTAIN': False, 'BRACKETING_NB_PICTS': 1, 'TIME_VALUE': 0.5, 'FOCUS_ENABLE': False, 'PULSE_WIDTH_HIGH': 250, 'TRIGGER_ONLY_ONCE': False}
2013-01-24 12:50:29,874::Main::DEBUG::PluginsManager.register(): added 'Ursa Minor BT2' plugin with capacity 'shutter'
2013-01-24 12:50:29,875::Main::TRACE::UrsaMinorUsbShutter._init()
2013-01-24 12:50:29,875::Main::TRACE::ShutterPlugin._init()
2013-01-24 12:50:29,876::Main::TRACE::UrsaMinorUsbShutter._defineConfig()
2013-01-24 12:50:29,876::Main::TRACE::AbstractHardwarePlugin._defineConfig()
2013-01-24 12:50:29,876::Main::TRACE::ShutterPlugin._defineConfig()
2013-01-24 12:50:29,876::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-24 12:50:29,877::Main::DEBUG::AbstractPlugin._loadConfig(): config={'DRIVER_TYPE': 'bluetooth', 'PULSE_WIDTH_LOW': 250, 'BULB_ENABLE': False, 'MIRROR_LOCKUP': False, 'TRIGGER_LINE_INVERTED': False, 'BRACKETING_NB_PICTS': 1, 'TRIGGER_LINE': 'RTS', 'TIME_VALUE': 0.5, 'PULSE_WIDTH_HIGH': 250, 'TRIGGER_ONLY_ONCE': False}
2013-01-24 12:50:29,877::Main::DEBUG::PluginsManager.register(): added 'Ursa Minor USB' plugin with capacity 'shutter'
2013-01-24 12:50:29,878::Main::TRACE::ClaussAxis._init()
2013-01-24 12:50:29,878::Main::TRACE::AbstractHardwarePlugin._defineConfig()
2013-01-24 12:50:29,878::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-24 12:50:29,879::Main::DEBUG::AbstractPlugin._loadConfig(): config={'HIGH_LIMIT': 360.0, 'PARK_POSITION': '0.', 'SPEED_FAST': 100, 'DRIVER_TYPE': 'bluetooth', 'LOW_LIMIT': -360.0, 'PARK_ENABLE': False, 'SPEED_NORMAL': 50, 'SPEED_SLOW': 10}
2013-01-24 12:50:29,879::Main::DEBUG::PluginsManager.register(): added 'Clauss' plugin with capacity 'yawAxis'
2013-01-24 12:50:29,879::Main::TRACE::ClaussAxis._init()
2013-01-24 12:50:29,879::Main::TRACE::AbstractHardwarePlugin._defineConfig()
2013-01-24 12:50:29,879::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-24 12:50:29,880::Main::DEBUG::AbstractPlugin._loadConfig(): config={'HIGH_LIMIT': 360.0, 'PARK_POSITION': '0.', 'SPEED_FAST': 100, 'DRIVER_TYPE': 'bluetooth', 'LOW_LIMIT': -360.0, 'PARK_ENABLE': True, 'SPEED_NORMAL': 50, 'SPEED_SLOW': 10}
2013-01-24 12:50:29,880::Main::DEBUG::PluginsManager.register(): added 'Clauss' plugin with capacity 'pitchAxis'
2013-01-24 12:50:29,880::Main::TRACE::ClaussShutter._init()
2013-01-24 12:50:29,880::Main::TRACE::ShutterPlugin._init()
2013-01-24 12:50:29,881::Main::TRACE::AbstractHardwarePlugin._defineConfig()
2013-01-24 12:50:29,881::Main::TRACE::ShutterPlugin._defineConfig()
2013-01-24 12:50:29,881::Main::TRACE::AbstractPlugin._loadConfig()
2013-01-24 12:50:29,881::Main::DEBUG::AbstractPlugin._loadConfig(): config={'DRIVER_TYPE': 'bluetooth', 'PULSE_WIDTH_LOW': 250, 'BULB_ENABLE': False, 'MIRROR_LOCKUP': False, 'FOCUS_TIME': 1.5, 'BRACKETING_NB_PICTS': 1, 'DUAL_TIME': 2.0, 'TIME_VALUE': 0.5, 'FOCUS_ENABLE': False, 'DUAL_ENABLE': False, 'PULSE_WIDTH_HIGH': 250, 'TRIGGER_ONLY_ONCE': False}
2013-01-24 12:50:29,882::Main::DEBUG::PluginsManager.register(): added 'Clauss' plugin with capacity 'shutter'
2013-01-24 12:50:29,882::Main::INFO::Loading user plugins...
2013-01-24 12:50:29,882::Main::DEBUG::PluginsManager.register(): parsing '/home/sbell/.config/papywizard2/plugins' dir...
2013-01-24 12:50:29,882::Main::INFO::Activate plugins...
2013-01-24 12:50:29,883::Main::TRACE::MerlinOrionPlugin.activate()
2013-01-24 12:50:29,883::Main::TRACE::MerlinOrionPlugin.activate()
2013-01-24 12:50:29,883::Merlin-Orion_yawAxis::DEBUG::MerlinOrionAxis.run(): start thread
2013-01-24 12:50:29,884::Main::INFO::Creating model...
2013-01-24 12:50:29,884::Merlin-Orion_pitchAxis::DEBUG::MerlinOrionAxis.run(): start thread
2013-01-24 12:50:29,885::Main::INFO::Loading default presets
2013-01-24 12:50:29,893::Main::DEBUG::Preset.__init__(): loading name='4@0 + Z + N'
2013-01-24 12:50:29,893::Main::DEBUG::Preset.__init__(): loading name='3@-15 + Z'
2013-01-24 12:50:29,894::Main::DEBUG::Preset.__init__(): loading name='6@30 + 6@-15 + N'
2013-01-24 12:50:29,894::Main::DEBUG::Preset.__init__(): loading name='4@30 + 4@-30'
2013-01-24 12:50:29,895::Main::DEBUG::Preset.__init__(): loading name='6@30 + 6@-30'
2013-01-24 12:50:29,895::Main::DEBUG::Preset.__init__(): loading name='6@0 + Z + N'
2013-01-24 12:50:29,895::Main::DEBUG::Preset.__init__(): loading name='6@-10 + 1@+60'
2013-01-24 12:50:29,896::Main::DEBUG::Preset.__init__(): loading name='3 + 6 + 12 + 6 + 3 (28mm)'
2013-01-24 12:50:29,896::Main::DEBUG::Preset.__init__(): loading name='4 + 12 + 12 + 12 + 8 (32mm)'
2013-01-24 12:50:29,899::Main::INFO::Loading user presets
2013-01-24 12:50:29,899::Main::WARNING::No user presets found
2013-01-24 12:50:29,900::Main::INFO::Starting Spy...
2013-01-24 12:50:29,900::Main::INFO::Creating GUI...
2013-01-24 12:50:29,900::Spy::INFO::Starting Spy...
2013-01-24 12:50:29,901::Spy::INFO::Spy suspended
2013-01-24 12:50:30,035::Main::WARNING::MainController.__getFullScreenFlag(): fix fullScreenFlag property!!!
2013-01-24 12:50:30,041::Main::TRACE::MainController.__onTabWidgetCurrentChanged()
2013-01-24 12:50:30,042::Main::DEBUG::MainController.__onTabWidgetCurrentChanged(): shooting mode set to 'preset'
2013-01-24 12:50:30,042::Main::WARNING::Previously selected 'time_lapse.xml' preset not found

2013-01-24 12:50:35,466::Main::TRACE::MainController.__onConfigPushButtonClicked()
2013-01-24 12:50:35,724::Main::DEBUG::ConfigController.__onCameraOrientationComboBoxCurrentIndexChanged(): orientation=landscape
2013-01-24 12:50:35,725::Main::DEBUG::ConfigController.__onLensTypeComboBoxCurrentIndexChanged(): type=rectilinear
2013-01-24 12:50:35,726::Main::DEBUG::ConfigController.__onLensTypeComboBoxCurrentIndexChanged(): lens type set to 'rectilinear'

2013-01-24 12:50:39,395::Main::TRACE::ConfigController._onAccepted()
2013-01-24 12:50:39,401::Main::DEBUG::Configuration saved
2013-01-24 12:50:41,908::Main::TRACE::MainController.__onActionFileImportPresetActivated()

2013-01-24 12:50:53,298::Main::DEBUG::MainController.__importPresetFile(): preset file=/home/sbell/Documents/photography/time_lapse/pappy_preset_script/time_lapse.xml
2013-01-24 12:50:53,299::Main::DEBUG::Preset.__init__(): loading name='time_lapse.xml'
2013-01-24 12:50:53,299::Main::DEBUG::MainController.__onPresetComboBoxCurrentIndexChanged()
Traceback (most recent call last):
  File "/usr/local/lib/python2.6/dist-packages/papywizard/controller/mainController.py", line 519, in __onPresetComboBoxCurrentIndexChanged
    preset = presets.getByIndex(self._view.presetComboBox.currentIndex())
  File "/usr/local/lib/python2.6/dist-packages/papywizard/common/presetManager.py", line 176, in getByIndex
    raise ValueError("No Preset at index '%d'" % index)
ValueError: No Preset at index '-1'

2013-01-24 12:50:58,854::Main::DEBUG::MainController.__onActionHardwareConnectToggled(True)
2013-01-24 12:50:58,859::Main::INFO::Starting connection. Please wait...
2013-01-24 12:50:58,879::Main::DEBUG::PluginsConnector.start(): 'yawAxis' establish connection
2013-01-24 12:50:58,881::Main::TRACE::AbstractHardwarePlugin.establishConnection()
2013-01-24 12:50:58,886::Main::TRACE::AbstractDriver.establishConnection()
2013-01-24 12:50:58,901::Main::TRACE::AbstractPlugin.establishConnection()
2013-01-24 12:50:58,902::Main::DEBUG::PluginsConnector.start(): 'yawAxis' init
2013-01-24 12:50:58,903::Main::TRACE::MerlinOrionAxis.init()
2013-01-24 12:50:58,904::Main::TRACE::AbstractHardwarePlugin.init()
2013-01-24 12:50:58,945::Main::DEBUG::MerlinOrionHardware.init(): firmeware version=020A84
2013-01-24 12:50:58,966::Main::DEBUG::MerlinOrionHardware.init(): encoder full circle=0xe62d3
2013-01-24 12:50:58,988::Main::DEBUG::MerlinOrionHardware.init(): sidereal rate=0x6f9
2013-01-24 12:50:58,989::Main::DEBUG::PluginsConnector.start(): 'pitchAxis' establish connection
2013-01-24 12:50:58,990::Main::TRACE::AbstractHardwarePlugin.establishConnection()
2013-01-24 12:50:58,991::Main::TRACE::AbstractDriver.establishConnection()
2013-01-24 12:50:58,992::Main::TRACE::AbstractPlugin.establishConnection()
2013-01-24 12:50:58,992::Main::DEBUG::PluginsConnector.start(): 'pitchAxis' init
2013-01-24 12:50:58,993::Main::TRACE::MerlinOrionAxis.init()
2013-01-24 12:50:58,994::Main::TRACE::AbstractHardwarePlugin.init()
2013-01-24 12:50:59,038::Main::DEBUG::MerlinOrionHardware.init(): firmeware version=020A84
2013-01-24 12:50:59,055::Main::DEBUG::MerlinOrionHardware.init(): encoder full circle=0xe62d3
2013-01-24 12:50:59,075::Main::DEBUG::MerlinOrionHardware.init(): sidereal rate=0x6f9
2013-01-24 12:50:59,077::Main::DEBUG::PluginsConnector.start(): 'shutter' establish connection
2013-01-24 12:50:59,078::Main::TRACE::AbstractHardwarePlugin.establishConnection()
2013-01-24 12:50:59,079::Main::TRACE::AbstractDriver.establishConnection()
2013-01-24 12:50:59,080::Main::TRACE::AbstractPlugin.establishConnection()
2013-01-24 12:50:59,080::Main::DEBUG::PluginsConnector.start(): 'shutter' init
2013-01-24 12:50:59,081::Main::TRACE::MerlinOrionShutter.init()
2013-01-24 12:50:59,082::Main::TRACE::AbstractHardwarePlugin.init()
2013-01-24 12:50:59,125::Main::DEBUG::MerlinOrionHardware.init(): firmeware version=020A84
2013-01-24 12:50:59,146::Main::DEBUG::MerlinOrionHardware.init(): encoder full circle=0xe62d3
2013-01-24 12:50:59,166::Main::DEBUG::MerlinOrionHardware.init(): sidereal rate=0x6f9
2013-01-24 12:50:59,168::Main::INFO::Connection started
2013-01-24 12:50:59,331::Spy::INFO::Spy resumed
2013-01-24 12:51:00,538::Main::TRACE::MainController.__onShootPushButtonClicked()
2013-01-24 12:51:00,541::Main::DEBUG::PresetScan.generatePositions(): preset=<Preset name=time_lapse.xml>
2013-01-24 12:51:00,632::Main::TRACE::ShootingView.resizeEvent()
2013-01-24 12:51:03,344::Main::TRACE::ShootController.__startPushButtonClicked()
2013-01-24 12:51:03,346::Shooting::TRACE::Shooting.start()
2013-01-24 12:51:03,348::Main::TRACE::ShootController.__onShootingStarted()
2013-01-24 12:51:03,365::Main::TRACE::ShootController.__onShootingProgress()
2013-01-24 12:51:03,368::Shooting::INFO::Start shooting process...
2013-01-24 12:51:03,369::Shooting::DEBUG::Shooting.start(): create preset data object
2013-01-24 12:51:03,370::Shooting::DEBUG::Data.__init__(): create xml tree
2013-01-24 12:51:03,371::Shooting::DEBUG::Data.createHeader(): values={'comment': u'Generated by Papywizard 2.1.21', 'counter': '042', 'focal': '17.0', 'cameraOrientation': u'landscape', 'name': u'time_lapse.xml', 'title': u'simulation_test', 'bracketingNbPicts': '1', 'timeValue': '0.5', 'stabilizationDelay': '0.5', 'headOrientation': 'up', 'lensType': u'rectilinear', 'sensorCoef': '1.6', 'sensorRatio': u'3:2', 'roll': '0.0', 'gps': u'Here goes the location'}
2013-01-24 12:51:03,372::Shooting::DEBUG::Data._addNode(): parent=header, tag=general, value=None, attr={}
2013-01-24 12:51:03,373::Shooting::DEBUG::Data._addNode(): parent=general, tag=title, value=simulation_test, attr={}
2013-01-24 12:51:03,373::Shooting::DEBUG::Data._addNode(): parent=general, tag=gps, value=Here goes the location, attr={}
2013-01-24 12:51:03,374::Shooting::DEBUG::Data._addNode(): parent=general, tag=comment, value=Generated by Papywizard 2.1.21, attr={}
2013-01-24 12:51:03,375::Shooting::DEBUG::Data._addNode(): parent=header, tag=shooting, value=None, attr={'mode': 'preset'}
2013-01-24 12:51:03,376::Shooting::DEBUG::Data._addNode(): parent=shooting, tag=headOrientation, value=up, attr={}
2013-01-24 12:51:03,377::Shooting::DEBUG::Data._addNode(): parent=shooting, tag=cameraOrientation, value=landscape, attr={}
2013-01-24 12:51:03,378::Shooting::DEBUG::Data._addNode(): parent=shooting, tag=stabilizationDelay, value=0.5, attr={}
2013-01-24 12:51:03,379::Shooting::DEBUG::Data._addNode(): parent=shooting, tag=counter, value=042, attr={}
2013-01-24 12:51:03,380::Shooting::DEBUG::Data._addNode(): parent=shooting, tag=startTime, value=2013-01-24_12h51m03s, attr={}
2013-01-24 12:51:03,380::Shooting::DEBUG::Data._addNode(): parent=shooting, tag=endTime, value=2013-01-24_12h51m03s, attr={}
2013-01-24 12:51:03,381::Shooting::DEBUG::Data._addNode(): parent=header, tag=camera, value=None, attr={}
2013-01-24 12:51:03,382::Shooting::DEBUG::Data._addNode(): parent=camera, tag=timeValue, value=0.5, attr={}
2013-01-24 12:51:03,383::Shooting::DEBUG::Data._addNode(): parent=camera, tag=bracketing, value=None, attr={'nbPicts': '1'}
2013-01-24 12:51:03,383::Shooting::DEBUG::Data._addNode(): parent=camera, tag=sensor, value=None, attr={'ratio': u'3:2', 'coef': '1.6'}
2013-01-24 12:51:03,384::Shooting::DEBUG::Data._addNode(): parent=header, tag=lens, value=None, attr={'type': u'rectilinear'}
2013-01-24 12:51:03,385::Shooting::DEBUG::Data._addNode(): parent=lens, tag=focal, value=17.0, attr={}
2013-01-24 12:51:03,385::Shooting::DEBUG::Data._addNode(): parent=header, tag=preset, value=None, attr={'name': u'time_lapse.xml'}
2013-01-24 12:51:03,386::Shooting::TRACE::Data.serialize()
2013-01-24 12:51:03,387::Shooting::DEBUG::Configuration saved
2013-01-24 12:51:03,388::Shooting::DEBUG::Shooting.start(): repeat 1/1
2013-01-24 12:51:03,388::Main::TRACE::ShootController.__onShootingRepeat()
2013-01-24 12:51:03,389::Main::TRACE::ShootController.__onShootingProgress()
2013-01-24 12:51:03,389::Shooting::DEBUG::Shooting.start(): pict #1 of 6, index=1, yaw=0.0, pitch=0.0
2013-01-24 12:51:03,391::Main::DEBUG::ShootController.__onShootingUpdate(): index=1, yaw=0.0, pitch=0.0, state=None, next=True
2013-01-24 12:51:03,392::Shooting::INFO::Moving
2013-01-24 12:51:03,392::Main::DEBUG::ShootController.__onShootingSequence(): sequence=moving
2013-01-24 12:51:03,393::Shooting::DEBUG::MerlinOrionAxis.drive(): 'yawAxis' drive to 0.0
2013-01-24 12:51:03,418::Shooting::DEBUG::MerlinOrionAxis.drive(): 'pitchAxis' drive to 0.0
2013-01-24 12:51:03,917::Shooting::INFO::Stabilization
2013-01-24 12:51:03,920::Main::DEBUG::ShootController.__onShootingSequence(): sequence=stabilization
2013-01-24 12:51:04,422::Shooting::INFO::Shutter cycle
2013-01-24 12:51:04,424::Shooting::DEBUG::Shooting.start(): bracket #1 of 1
2013-01-24 12:51:04,425::Main::DEBUG::ShootController.__onShootingSequence(): sequence=shutter
2013-01-24 12:51:04,427::Shooting::TRACE::ShutterPlugin.shoot()
2013-01-24 12:51:04,428::Shooting::TRACE::ShutterPlugin._ensurePulseWidthLowDelay()
2013-01-24 12:51:04,438::Shooting::TRACE::ShutterPlugin._triggerShutter()
2013-01-24 12:51:04,998::Shooting::DEBUG::Data.addPicture(): bracket=1, yaw=0.0, pitch=0.0, roll=0.0
2013-01-24 12:51:04,999::Shooting::DEBUG::Data._addNode(): parent=shoot, tag=pict, value=None, attr={'bracket': '1', 'id': '1'}
2013-01-24 12:51:05,000::Shooting::DEBUG::Data._addNode(): parent=pict, tag=time, value=2013-01-24_12h51m05s, attr={}
2013-01-24 12:51:05,001::Shooting::DEBUG::Data._addNode(): parent=pict, tag=position, value=None, attr={'yaw': '0.0', 'roll': '0.0', 'pitch': '0.0'}
2013-01-24 12:51:05,002::Shooting::TRACE::Data.serialize()
2013-01-24 12:51:05,006::Main::TRACE::ShootController.__onShootingProgress()
2013-01-24 12:51:05,024::Main::DEBUG::ShootController.__onShootingUpdate(): index=1, yaw=0.0, pitch=0.0, state=ok, next=None
2013-01-24 12:51:05,027::Shooting::DEBUG::Shooting.start(): position index=2, yaw=0.1, pitch=0.0
2013-01-24 12:51:05,029::Main::DEBUG::ShootController.__onShootingUpdate(): index=2, yaw=0.1, pitch=0.0, state=None, next=True
2013-01-24 12:51:05,031::Shooting::DEBUG::Shooting.start(): pict #2 of 6, index=2, yaw=0.1, pitch=0.0
2013-01-24 12:51:05,033::Main::DEBUG::ShootController.__onShootingUpdate(): index=2, yaw=0.1, pitch=0.0, state=None, next=True
2013-01-24 12:51:05,036::Shooting::INFO::Moving
2013-01-24 12:51:05,036::Main::DEBUG::ShootController.__onShootingSequence(): sequence=moving
2013-01-24 12:51:05,037::Shooting::DEBUG::MerlinOrionAxis.drive(): 'yawAxis' drive to 0.1
2013-01-24 12:51:05,058::Shooting::DEBUG::MerlinOrionAxis.drive(): 'pitchAxis' drive to 0.0
2013-01-24 12:51:05,509::Shooting::INFO::Stabilization
2013-01-24 12:51:05,510::Main::DEBUG::ShootController.__onShootingSequence(): sequence=stabilization
2013-01-24 12:51:06,012::Shooting::INFO::Shutter cycle
2013-01-24 12:51:06,013::Shooting::DEBUG::Shooting.start(): bracket #1 of 1
2013-01-24 12:51:06,014::Main::DEBUG::ShootController.__onShootingSequence(): sequence=shutter
2013-01-24 12:51:06,016::Shooting::TRACE::ShutterPlugin.shoot()
2013-01-24 12:51:06,017::Shooting::TRACE::ShutterPlugin._ensurePulseWidthLowDelay()
2013-01-24 12:51:06,018::Shooting::TRACE::ShutterPlugin._triggerShutter()
2013-01-24 12:51:06,596::Shooting::DEBUG::Data.addPicture(): bracket=1, yaw=0.0, pitch=0.0, roll=0.0
2013-01-24 12:51:06,597::Shooting::DEBUG::Data._addNode(): parent=shoot, tag=pict, value=None, attr={'bracket': '1', 'id': '2'}
2013-01-24 12:51:06,598::Shooting::DEBUG::Data._addNode(): parent=pict, tag=time, value=2013-01-24_12h51m06s, attr={}
2013-01-24 12:51:06,599::Shooting::DEBUG::Data._addNode(): parent=pict, tag=position, value=None, attr={'yaw': '0.0', 'roll': '0.0', 'pitch': '0.0'}
2013-01-24 12:51:06,600::Shooting::TRACE::Data.serialize()
2013-01-24 12:51:06,604::Main::TRACE::ShootController.__onShootingProgress()
2013-01-24 12:51:06,623::Main::DEBUG::ShootController.__onShootingUpdate(): index=2, yaw=0.1, pitch=0.0, state=ok, next=None
2013-01-24 12:51:06,625::Shooting::DEBUG::Shooting.start(): position index=3, yaw=0.2, pitch=0.0
2013-01-24 12:51:06,627::Main::DEBUG::ShootController.__onShootingUpdate(): index=3, yaw=0.2, pitch=0.0, state=None, next=True
2013-01-24 12:51:06,629::Shooting::DEBUG::Shooting.start(): pict #3 of 6, index=3, yaw=0.2, pitch=0.0
2013-01-24 12:51:06,631::Main::DEBUG::ShootController.__onShootingUpdate(): index=3, yaw=0.2, pitch=0.0, state=None, next=True
2013-01-24 12:51:06,633::Shooting::INFO::Moving
2013-01-24 12:51:06,635::Main::DEBUG::ShootController.__onShootingSequence(): sequence=moving
2013-01-24 12:51:06,636::Shooting::DEBUG::MerlinOrionAxis.drive(): 'yawAxis' drive to 0.2
2013-01-24 12:51:06,657::Shooting::DEBUG::MerlinOrionAxis.drive(): 'pitchAxis' drive to 0.0
2013-01-24 12:51:06,677::Merlin-Orion_yawAxis::TRACE::MerlinOrionAxis._directDrive()
2013-01-24 12:51:08,059::Shooting::INFO::Stabilization
2013-01-24 12:51:08,060::Main::DEBUG::ShootController.__onShootingSequence(): sequence=stabilization
2013-01-24 12:51:08,562::Shooting::INFO::Shutter cycle
2013-01-24 12:51:08,564::Shooting::DEBUG::Shooting.start(): bracket #1 of 1
2013-01-24 12:51:08,565::Main::DEBUG::ShootController.__onShootingSequence(): sequence=shutter
2013-01-24 12:51:08,566::Shooting::TRACE::ShutterPlugin.shoot()
2013-01-24 12:51:08,567::Shooting::TRACE::ShutterPlugin._ensurePulseWidthLowDelay()
2013-01-24 12:51:08,568::Shooting::TRACE::ShutterPlugin._triggerShutter()
2013-01-24 12:51:09,148::Shooting::DEBUG::Data.addPicture(): bracket=1, yaw=0.2, pitch=0.0, roll=0.0
2013-01-24 12:51:09,149::Shooting::DEBUG::Data._addNode(): parent=shoot, tag=pict, value=None, attr={'bracket': '1', 'id': '3'}
2013-01-24 12:51:09,150::Shooting::DEBUG::Data._addNode(): parent=pict, tag=time, value=2013-01-24_12h51m09s, attr={}
2013-01-24 12:51:09,151::Shooting::DEBUG::Data._addNode(): parent=pict, tag=position, value=None, attr={'yaw': '0.2', 'roll': '0.0', 'pitch': '0.0'}
2013-01-24 12:51:09,152::Shooting::TRACE::Data.serialize()
2013-01-24 12:51:09,157::Main::TRACE::ShootController.__onShootingProgress()
2013-01-24 12:51:09,176::Main::DEBUG::ShootController.__onShootingUpdate(): index=3, yaw=0.2, pitch=0.0, state=ok, next=None
2013-01-24 12:51:09,178::Shooting::DEBUG::Shooting.start(): position index=4, yaw=0.3, pitch=0.0
2013-01-24 12:51:09,180::Main::DEBUG::ShootController.__onShootingUpdate(): index=4, yaw=0.3, pitch=0.0, state=None, next=True
2013-01-24 12:51:09,183::Shooting::DEBUG::Shooting.start(): pict #4 of 6, index=4, yaw=0.3, pitch=0.0
2013-01-24 12:51:09,185::Main::DEBUG::ShootController.__onShootingUpdate(): index=4, yaw=0.3, pitch=0.0, state=None, next=True
2013-01-24 12:51:09,186::Shooting::INFO::Moving
2013-01-24 12:51:09,188::Main::DEBUG::ShootController.__onShootingSequence(): sequence=moving
2013-01-24 12:51:09,190::Shooting::DEBUG::MerlinOrionAxis.drive(): 'yawAxis' drive to 0.3
2013-01-24 12:51:09,208::Shooting::DEBUG::MerlinOrionAxis.drive(): 'pitchAxis' drive to 0.0
2013-01-24 12:51:09,231::Merlin-Orion_yawAxis::TRACE::MerlinOrionAxis._directDrive()
2013-01-24 12:51:10,387::Shooting::INFO::Stabilization
2013-01-24 12:51:10,388::Main::DEBUG::ShootController.__onShootingSequence(): sequence=stabilization
2013-01-24 12:51:10,890::Shooting::INFO::Shutter cycle
2013-01-24 12:51:10,892::Shooting::DEBUG::Shooting.start(): bracket #1 of 1
2013-01-24 12:51:10,893::Main::DEBUG::ShootController.__onShootingSequence(): sequence=shutter
2013-01-24 12:51:10,894::Shooting::TRACE::ShutterPlugin.shoot()
2013-01-24 12:51:10,895::Shooting::TRACE::ShutterPlugin._ensurePulseWidthLowDelay()
2013-01-24 12:51:10,896::Shooting::TRACE::ShutterPlugin._triggerShutter()
2013-01-24 12:51:11,478::Shooting::DEBUG::Data.addPicture(): bracket=1, yaw=0.3, pitch=0.0, roll=0.0
2013-01-24 12:51:11,479::Shooting::DEBUG::Data._addNode(): parent=shoot, tag=pict, value=None, attr={'bracket': '1', 'id': '4'}
2013-01-24 12:51:11,480::Shooting::DEBUG::Data._addNode(): parent=pict, tag=time, value=2013-01-24_12h51m11s, attr={}
2013-01-24 12:51:11,481::Shooting::DEBUG::Data._addNode(): parent=pict, tag=position, value=None, attr={'yaw': '0.3', 'roll': '0.0', 'pitch': '0.0'}
2013-01-24 12:51:11,482::Shooting::TRACE::Data.serialize()
2013-01-24 12:51:11,487::Main::TRACE::ShootController.__onShootingProgress()
2013-01-24 12:51:11,506::Main::DEBUG::ShootController.__onShootingUpdate(): index=4, yaw=0.3, pitch=0.0, state=ok, next=None
2013-01-24 12:51:11,509::Shooting::DEBUG::Shooting.start(): position index=5, yaw=0.4, pitch=0.0
2013-01-24 12:51:11,511::Main::DEBUG::ShootController.__onShootingUpdate(): index=5, yaw=0.4, pitch=0.0, state=None, next=True
2013-01-24 12:51:11,514::Shooting::DEBUG::Shooting.start(): pict #5 of 6, index=5, yaw=0.4, pitch=0.0
2013-01-24 12:51:11,516::Main::DEBUG::ShootController.__onShootingUpdate(): index=5, yaw=0.4, pitch=0.0, state=None, next=True
2013-01-24 12:51:11,518::Shooting::INFO::Moving
2013-01-24 12:51:11,519::Main::DEBUG::ShootController.__onShootingSequence(): sequence=moving
2013-01-24 12:51:11,519::Shooting::DEBUG::MerlinOrionAxis.drive(): 'yawAxis' drive to 0.4
2013-01-24 12:51:11,538::Shooting::DEBUG::MerlinOrionAxis.drive(): 'pitchAxis' drive to 0.0
2013-01-24 12:51:11,556::Merlin-Orion_yawAxis::TRACE::MerlinOrionAxis._directDrive()
2013-01-24 12:51:12,739::Shooting::INFO::Stabilization
2013-01-24 12:51:12,741::Main::DEBUG::ShootController.__onShootingSequence(): sequence=stabilization
2013-01-24 12:51:13,242::Shooting::INFO::Shutter cycle
2013-01-24 12:51:13,244::Shooting::DEBUG::Shooting.start(): bracket #1 of 1
2013-01-24 12:51:13,245::Main::DEBUG::ShootController.__onShootingSequence(): sequence=shutter
2013-01-24 12:51:13,247::Shooting::TRACE::ShutterPlugin.shoot()
2013-01-24 12:51:13,249::Shooting::TRACE::ShutterPlugin._ensurePulseWidthLowDelay()
2013-01-24 12:51:13,250::Shooting::TRACE::ShutterPlugin._triggerShutter()
2013-01-24 12:51:13,836::Shooting::DEBUG::Data.addPicture(): bracket=1, yaw=0.4, pitch=0.0, roll=0.0
2013-01-24 12:51:13,837::Shooting::DEBUG::Data._addNode(): parent=shoot, tag=pict, value=None, attr={'bracket': '1', 'id': '5'}
2013-01-24 12:51:13,838::Shooting::DEBUG::Data._addNode(): parent=pict, tag=time, value=2013-01-24_12h51m13s, attr={}
2013-01-24 12:51:13,839::Shooting::DEBUG::Data._addNode(): parent=pict, tag=position, value=None, attr={'yaw': '0.4', 'roll': '0.0', 'pitch': '0.0'}
2013-01-24 12:51:13,840::Shooting::TRACE::Data.serialize()
2013-01-24 12:51:13,845::Main::TRACE::ShootController.__onShootingProgress()
2013-01-24 12:51:13,866::Main::DEBUG::ShootController.__onShootingUpdate(): index=5, yaw=0.4, pitch=0.0, state=ok, next=None
2013-01-24 12:51:13,869::Shooting::DEBUG::Shooting.start(): position index=6, yaw=0.5, pitch=0.0
2013-01-24 12:51:13,870::Main::DEBUG::ShootController.__onShootingUpdate(): index=6, yaw=0.5, pitch=0.0, state=None, next=True
2013-01-24 12:51:13,872::Shooting::DEBUG::Shooting.start(): pict #6 of 6, index=6, yaw=0.5, pitch=0.0
2013-01-24 12:51:13,873::Main::DEBUG::ShootController.__onShootingUpdate(): index=6, yaw=0.5, pitch=0.0, state=None, next=True
2013-01-24 12:51:13,874::Shooting::INFO::Moving
2013-01-24 12:51:13,875::Main::DEBUG::ShootController.__onShootingSequence(): sequence=moving
2013-01-24 12:51:13,877::Shooting::DEBUG::MerlinOrionAxis.drive(): 'yawAxis' drive to 0.5
2013-01-24 12:51:13,898::Shooting::DEBUG::MerlinOrionAxis.drive(): 'pitchAxis' drive to 0.0
2013-01-24 12:51:13,918::Merlin-Orion_yawAxis::TRACE::MerlinOrionAxis._directDrive()
2013-01-24 12:51:15,039::Shooting::INFO::Stabilization
2013-01-24 12:51:15,040::Main::DEBUG::ShootController.__onShootingSequence(): sequence=stabilization
2013-01-24 12:51:15,542::Shooting::INFO::Shutter cycle
2013-01-24 12:51:15,544::Shooting::DEBUG::Shooting.start(): bracket #1 of 1
2013-01-24 12:51:15,544::Main::DEBUG::ShootController.__onShootingSequence(): sequence=shutter
2013-01-24 12:51:15,546::Shooting::TRACE::ShutterPlugin.shoot()
2013-01-24 12:51:15,547::Shooting::TRACE::ShutterPlugin._ensurePulseWidthLowDelay()
2013-01-24 12:51:15,549::Shooting::TRACE::ShutterPlugin._triggerShutter()
2013-01-24 12:51:16,128::Shooting::DEBUG::Data.addPicture(): bracket=1, yaw=0.5, pitch=0.0, roll=0.0
2013-01-24 12:51:16,129::Shooting::DEBUG::Data._addNode(): parent=shoot, tag=pict, value=None, attr={'bracket': '1', 'id': '6'}
2013-01-24 12:51:16,130::Shooting::DEBUG::Data._addNode(): parent=pict, tag=time, value=2013-01-24_12h51m16s, attr={}
2013-01-24 12:51:16,131::Shooting::DEBUG::Data._addNode(): parent=pict, tag=position, value=None, attr={'yaw': '0.5', 'roll': '0.0', 'pitch': '0.0'}
2013-01-24 12:51:16,132::Shooting::TRACE::Data.serialize()
2013-01-24 12:51:16,138::Main::TRACE::ShootController.__onShootingProgress()
2013-01-24 12:51:16,148::Main::DEBUG::ShootController.__onShootingUpdate(): index=6, yaw=0.5, pitch=0.0, state=ok, next=None
2013-01-24 12:51:16,150::Main::DEBUG::ShootController.__onShootingUpdate(): index=7, yaw=0.5, pitch=0.0, state=None, next=True
2013-01-24 12:51:16,150::Shooting::INFO::Shoot process finished
2013-01-24 12:51:16,151::Main::DEBUG::ShootController.__onShootingStopped(): status=ok

2013-01-24 12:51:20,625::Main::TRACE::ShootController.__onStopFinishPushButtonClicked()
2013-01-24 12:51:22,422::Main::TRACE::AbstractModalDialogController._onRejected()

2013-01-24 12:51:34,512::Main::TRACE::MainController.__onActionHelpViewLogActivated()

2013-01-24 12:51:51,831::Main::TRACE::LoggerController.__onSaveLogPushButtonClicked()
2013-01-24 12:51:51,834::Main::DEBUG::LoggerController.__onSaveLogPushButtonClicked(): log saved to '/home/sbell/Pictures/2013/time_lapse/data/papywizard_2013-01-24_12h51m51s.log'

2013-01-24 12:51:56,353::Main::TRACE::AbstractController._onCloseEvent()


2013-01-24 12:54:27,694::Main::TRACE::MainController.__onConfigPushButtonClicked()
2013-01-24 12:54:27,742::Main::DEBUG::ConfigController.__onCameraOrientationComboBoxCurrentIndexChanged(): orientation=landscape
2013-01-24 12:54:27,756::Main::DEBUG::ConfigController.__onLensTypeComboBoxCurrentIndexChanged(): type=rectilinear
2013-01-24 12:54:27,756::Main::DEBUG::ConfigController.__onLensTypeComboBoxCurrentIndexChanged(): lens type set to 'rectilinear'


2013-01-24 12:55:48,606::Main::TRACE::AbstractModalDialogController._onRejected()
2013-01-24 12:55:51,028::Main::TRACE::MainController.__onActionHelpViewLogActivated()


Last edited by sbell on Thu Jan 24, 2013 1:03 pm, edited 1 time in total.

Top
 Profile  
 
 Post subject:
PostPosted: Thu Jan 24, 2013 1:02 pm 
Offline
Member

Joined: Tue Dec 18, 2012 9:30 pm
Posts: 15
Hi Frédéric,

Thanks for the suggestion. I just tried turning off the data logging, but still get the same issues.

I understand that getting a very precise interval is difficult, but a difference of 2 seconds seems like a very long time for such a system. Surely there is something else going on?


Last edited by sbell on Thu Jan 24, 2013 1:04 pm, edited 1 time in total.

Top
 Profile  
 
 Post subject:
PostPosted: Thu Jan 24, 2013 2:04 pm 
Offline
Member
User avatar

Joined: Wed Dec 07, 2005 6:21 pm
Posts: 5826
Location: Grenoble, France
Look at the timings, and you will see that there are some drifts at all levels (hardware and software). So, in fine, I'm not surprised...

Also try to turn off the GUI (switch to the second screen, with only positions as text)...

_________________
Frédéric

Canon 20D + 17-40/f4 L USM + 70-200/f4 L USM + 50/f1.4 USM
Merlin/Orion panohead + Papywizard on Nokia N800 and HP TC-1100


Top
 Profile  
 
 Post subject:
PostPosted: Thu Jan 24, 2013 2:12 pm 
Offline
Member

Joined: Wed Nov 14, 2007 2:12 pm
Posts: 13956
Location: Isleham, Cambridgeshire, UK.
It is easy to toggle between the graphic and text styles of display:





_________________
Andrew Stephens
Many different Nodal Ninja and Agnos pano heads. Merlin/Panogear mount with Papywizard on Nokia Internet tablets.
Nikon D5100 and D40, Sigma 8mm f3.5 FE, Nikon 10.5mm FE, 35mm, 50mm, 18-55mm, 70-210mm. Promote control.


Top
 Profile  
 
 Post subject:
PostPosted: Thu Jan 24, 2013 7:58 pm 
Offline
Member

Joined: Tue Dec 18, 2012 9:30 pm
Posts: 15
Sadly no improvement by switching to the text based view.

Could this have something to do with the pulse width high, and pulse width low settings for the camera trigger in the Merlin head plugin? I don't really understand what these parameters do.

But it looks like is just something I'm going to have to live with.


Top
 Profile  
 
 Post subject:
PostPosted: Thu Jan 24, 2013 8:35 pm 
Offline
Member

Joined: Wed Nov 14, 2007 2:12 pm
Posts: 13956
Location: Isleham, Cambridgeshire, UK.
sbell wrote:
Could this have something to do with the pulse width high, and pulse width low settings for the camera trigger in the Merlin head plugin? I don't really understand what these parameters do.

See: http://www.papywizard.org/wiki/TipsAndTricks#Shootingsequence

_________________
Andrew Stephens
Many different Nodal Ninja and Agnos pano heads. Merlin/Panogear mount with Papywizard on Nokia Internet tablets.
Nikon D5100 and D40, Sigma 8mm f3.5 FE, Nikon 10.5mm FE, 35mm, 50mm, 18-55mm, 70-210mm. Promote control.


Top
 Profile  
 
 Post subject:
PostPosted: Thu Jan 24, 2013 11:40 pm 
Offline
Member
User avatar

Joined: Sun Nov 25, 2007 11:12 pm
Posts: 1341
Location: Mont-Saint-André (Belgium)
Hello Simon,

I suggest that you use "Chronomotion" who has been developped only for Timelapse on the Merlin head ...
http://apertus.org/en/chronomotion

You will get very accurate and precise results then with a Timelapse presest.
The software is free and have been developed by Sebastian Pichelhofer
sebastian.pichelhofer@gmail.com

But to use Chronomotion, you must have a wired connection with the Merlin. (USB to TTL V)

_________________
:cool: Claude :cool:
Merlin + Papywizard on Windows 7 & Nokia 770 § N810 & Acer (Netbook) + PanoramaApp Androïd + Deltawave PapyMerlin BT + Autopano
Spherical Pano (180 x 360) with Canon 40D + Canon EF-S 10-22mm f/3.5-4.5 Zoom & Pôle Pano with Canon 5D MK2 and shaved Tokina 10-17 3.5-4.5 AF DX Fisheye
Gigapixel photography with Nikon D200 + Sigma 70-200 F 2.8 EX DG APO HSM


Top
 Profile  
 
 Post subject:
PostPosted: Fri Jan 25, 2013 10:32 pm 
Offline
Member

Joined: Tue Dec 18, 2012 9:30 pm
Posts: 15
Hi Claude,

Thanks for the tip, Chronomotion looks like an interesting option. Do you have any experience using it? I have installed and got it talking to the Merlin, but can't figure out much more than that! The information on how to use it is much more limited, no real info in the wiki and can't find much in the forums.

I'll also try on the timescapes forum as Sebastian seems to spend some time there :)


Top
 Profile  
 
 Post subject:
PostPosted: Sat Jan 26, 2013 3:11 pm 
Offline
Member

Joined: Sun Apr 24, 2011 3:08 pm
Posts: 13
Creating a demo video showing how the software can be used and what it is capable of is the next thing I want to work on.
So far you are right there is not a lot of usage documentation.

Since I come from the video compositing/editing and 3D animation world my approach was to center the software around a timeline with keyframes.
So you set up your movement curves for both pan and tilt, create bezier keyframes and when you are happy you "play back" the entire timeline with the head.

Some notes:
-) double click into the timeline to create a new keyframe
-) the animation will run until it reaches the last keyframe in one of the channels - so you need to make sure both channels have the last keyframe at the desired end of your shot
-) triggering the cameras shutter is done via the head itself so you need to connect head and camera with a shutter cable

Let me know if you need more help.
Maybe we can work together on creating the documentation on the apertus wiki?

_________________
Apertus - open source cinema
www.apertus.org


Top
 Profile  
 
 Post subject:
PostPosted: Sat Jan 26, 2013 4:42 pm 
Offline
Member
User avatar

Joined: Wed Dec 07, 2005 6:21 pm
Posts: 5826
Location: Grenoble, France
Sebastian, very nice work! I will try to shoot a timelapse, as soon as I have time...

_________________
Frédéric

Canon 20D + 17-40/f4 L USM + 70-200/f4 L USM + 50/f1.4 USM
Merlin/Orion panohead + Papywizard on Nokia N800 and HP TC-1100


Top
 Profile  
 
 Post subject:
PostPosted: Mon Feb 04, 2013 8:24 pm 
Offline
Member

Joined: Tue Dec 18, 2012 9:30 pm
Posts: 15
Hi Sebastian,

Thanks for the information. I just got back from a 2 week long holiday in Iceland with a good mate, aiming to shoot the Northern lights in time lapse. We're very happy with our results and videos will follow after they've been through editing!

From our experience on the trip we have decided that a fully software control option is just not optimum. Although it is the solution with the most flexibility, software requires a computer to run the setup. We used a Raspberry Pi which was great for power saving, but it added complexity in terms of the number of connections and number of different voltages we needed to run the system.

We have decided to look into fully hardware options, eg MX2. If this does not give all the options we want then I am excited about building my own controller based on an Arduino or similar product. I will do some research on what the MX2 does and decide if it suits us. If not then I will start an alternative project based on the idea and ideals of open source hardware. That will mean a lot of questions in the forums about what people want from a hardware based time lapse controller!


Top
 Profile  
 
 Post subject:
PostPosted: Tue Feb 12, 2013 6:52 pm 
Offline
Member

Joined: Sun Apr 24, 2011 3:08 pm
Posts: 13
sbell wrote:
Hi Sebastian,

Thanks for the information. I just got back from a 2 week long holiday in Iceland with a good mate, aiming to shoot the Northern lights in time lapse. We're very happy with our results and videos will follow after they've been through editing!

From our experience on the trip we have decided that a fully software control option is just not optimum. Although it is the solution with the most flexibility, software requires a computer to run the setup. We used a Raspberry Pi which was great for power saving, but it added complexity in terms of the number of connections and number of different voltages we needed to run the system.

We have decided to look into fully hardware options, eg MX2. If this does not give all the options we want then I am excited about building my own controller based on an Arduino or similar product. I will do some research on what the MX2 does and decide if it suits us. If not then I will start an alternative project based on the idea and ideals of open source hardware. That will mean a lot of questions in the forums about what people want from a hardware based time lapse controller!

I think you are the first Chronomotion real life environment production tester?
How has it been?
Do you think the complexity can be reduced by creating a more sophisticated device based on the raspberry pi?

Dynamic Perceptions new MoCoBus based system will deliver what you are after.

_________________
Apertus - open source cinema
www.apertus.org


Top
 Profile  
 
 Post subject:
PostPosted: Wed Feb 13, 2013 12:26 am 
Offline
Member

Joined: Tue Dec 18, 2012 9:30 pm
Posts: 15
Hi Sebastian,

I am sorry to admit that I did not end up using Chronomotion. We stuck with papywizard and just dealt with the fact that the timing interval was not always the same.

This decision was mainly because I could not figure out how to correctly use the motion time line that presets the motion of the Merlin. We also decided that as we want a hardware solution in the future that it would not be worth investing the time to learn a new piece of software.

I am very keen to find some thing (self made or otherwise) that can reduce the complexity of things in the field. We spent the cloudy nights (we had more than a few!) brainstorming the best way to do this with hardware and think we have a good idea of what we would like. I think it would be best based on an arduino rather than a Raspberry Pi.

If you're keen to hear more about our ideas I can send you the write up we made of it.


Top
 Profile  
 
Display posts from previous:  Sort by  
Post new topic Reply to topic  [ 18 posts ] 

All times are UTC + 1 hour


Who is online

Users browsing this forum: No registered users and 1 guest


You cannot post new topics in this forum
You cannot reply to topics in this forum
You cannot edit your posts in this forum
You cannot delete your posts in this forum
You cannot post attachments in this forum

Search for:
Jump to:  
Powered by phpBB® Forum Software © phpBB Group