Re: gphoto 2.5.4/2.5.10/2.5.11 works unreliable on RP3 with Canon 1200D

On Sun, Jan 15, 2017 at 02:15:44AM +0100, Dr. Volker Jaenisch wrote:
> Hi gphoto User list!
> 
> I am stuck utilizing a raspberry Pi 3 to steer a Canon 1200D via
> gphoto2. Using a home brew python script that takes a capture in intervals.
> 
> Raspbian comes with gphoto 2.5.4. This version detects the camera an can
> perform any action on it except making captures in a stable fashion.
> 
> Have updated to 2.5.10 from Jessie-Backport. Same behavior.
> 
> Have compiled myself 2.5.11 Raspbian package from the git-Repository.
> Same behavior.
> 
> Have had a clean start with fresh OS-Image. Some behavior.
> 
> The behavior is not deterministic. Sometime it captures sometimes not.
> 
> Resetting the camera via ghpoto2 --reset does not help.
> 
> Resetting the linux USB-Bus does not help.
> 
> Resetting the camera cures the problem, but only for a few shots.
> 
> No other process is using the Canon-usb-device. gvfs-gphoto is disabled,
> no kernel module is using it.
> 
> Here the picture from 10000ft: First shot does not work. Second shot
> works, third shot does not work:
> 
> pi@raspberrypi:~ $ ./run_meteocam
> 
> 2017-01-15 01:42:37,338 INFO     Meteocam starting
> 2017-01-15 01:42:37,339 INFO     Starting processing step
> 2017-01-15 01:42:37,340 INFO     Resetting Camera
> 2017-01-15 01:42:37,340 INFO     Running: /usr/bin/gphoto2 --reset
> 2017-01-15 01:42:37,648 INFO     Got
> 2017-01-15 01:42:37,650 INFO        
> 2017-01-15 01:42:37,651 INFO     Camera resetted
> 2017-01-15 01:42:37,652 INFO     Starting image capture
> 2017-01-15 01:42:37,652 INFO     Running: /usr/bin/gphoto2
> --capture-image-and-download --force-overwrite
> --filename=capture/Schedule_20170115-014237.jpg --set-config aperture=8
> --set-config iso=0
> FEHLER: Konnte Bild nicht aufnehmen.
> FEHLER: Konnte nicht aufnehmen.
> 2017-01-15 01:43:41,303 INFO     Got
> 2017-01-15 01:43:41,305 INFO        
> 2017-01-15 01:43:41,307 INFO     Image captured
> 2017-01-15 01:43:41,308 INFO     Starting FTP upload
> 2017-01-15 01:43:41,309 INFO     Looking for images to load up:
> beo-webcam.ch
> 2017-01-15 01:43:41,311 INFO     0 files found
> 2017-01-15 01:43:41,312 INFO     FTP: Nothing to upload
> 2017-01-15 01:43:41,313 INFO     Processing step finished
> 2017-01-15 01:43:41,314 INFO     Sleeping 60 seconds
> 2017-01-15 01:44:41,376 INFO     Starting processing step
> 2017-01-15 01:44:41,377 INFO     Resetting Camera
> 2017-01-15 01:44:41,378 INFO     Running: /usr/bin/gphoto2 --reset
> 2017-01-15 01:44:41,718 INFO     Got
> 2017-01-15 01:44:41,719 INFO        
> 2017-01-15 01:44:41,720 INFO     Camera resetted
> 2017-01-15 01:44:41,721 INFO     Starting image capture
> 2017-01-15 01:44:41,721 INFO     Running: /usr/bin/gphoto2
> --capture-image-and-download --force-overwrite
> --filename=capture/Schedule_20170115-014441.jpg --set-config aperture=8
> --set-config iso=0
> 2017-01-15 01:44:48,871 INFO     Got
> 2017-01-15 01:44:49,552 INFO         Neue Datei ist in /capt0000.jpg auf
> der Kamera
> 2017-01-15 01:44:49,553 INFO         Speichere Datei als
> capture/Schedule_20170115-014441.jpg
> 2017-01-15 01:44:49,554 INFO         Lösche Datei /capt0000.jpg auf der
> Kamera
> 2017-01-15 01:44:49,556 INFO        
> 2017-01-15 01:44:49,557 INFO     Image captured
> 2017-01-15 01:44:49,558 INFO     Starting FTP upload
> 2017-01-15 01:44:49,559 INFO     Looking for images to load up:
> beo-webcam.ch
> 2017-01-15 01:44:49,560 INFO     1 files found
> 2017-01-15 01:44:49,561 INFO     Opening FTP Connection to: beo-webcam.ch
> 2017-01-15 01:44:52,368 INFO     Loading up:
> capture/Schedule_20170115-014441.jpg
> 2017-01-15 01:44:53,667 INFO     upload finished for:
> capture/Schedule_20170115-014441.jpg
> 2017-01-15 01:44:53,669 INFO     Removing local file:
> capture/Schedule_20170115-014441.jpg
> 2017-01-15 01:44:53,677 INFO     local file removed:
> capture/Schedule_20170115-014441.jpg
> 2017-01-15 01:44:53,680 INFO     FTP Upload finished
> 2017-01-15 01:44:53,681 INFO     Processing step finished
> 2017-01-15 01:44:53,682 INFO     Sleeping 60 seconds
> 2017-01-15 01:45:53,743 INFO     Starting processing step
> 2017-01-15 01:45:53,744 INFO     Resetting Camera
> 2017-01-15 01:45:53,745 INFO     Running: /usr/bin/gphoto2 --reset
> 2017-01-15 01:45:54,108 INFO     Got
> 2017-01-15 01:45:54,110 INFO        
> 2017-01-15 01:45:54,110 INFO     Camera resetted
> 2017-01-15 01:45:54,111 INFO     Starting image capture
> 2017-01-15 01:45:54,111 INFO     Running: /usr/bin/gphoto2
> --capture-image-and-download --force-overwrite
> --filename=capture/Schedule_20170115-014554.jpg --set-config aperture=8
> --set-config iso=0
> FEHLER: Konnte Bild nicht aufnehmen.
> FEHLER: Konnte nicht aufnehmen.
> 2017-01-15 01:46:57,765 INFO     Got
> 2017-01-15 01:46:57,767 INFO        
> 2017-01-15 01:46:57,768 INFO     Image captured
> 2017-01-15 01:46:57,769 INFO     Starting FTP upload
> 2017-01-15 01:46:57,770 INFO     Looking for images to load up:
> beo-webcam.ch
> 2017-01-15 01:46:57,771 INFO     0 files found
> 2017-01-15 01:46:57,772 INFO     FTP: Nothing to upload
> 2017-01-15 01:46:57,773 INFO     Processing step finished
> 2017-01-15 01:46:57,774 INFO     Sleeping 60 seconds
> 
> And the weird stuff at last:
> 
> If I enable --debug everything is working. A true Heisenbug? Timing problem?
> 
> What am I doing wrong? What can I do better?

If enabling debug helps this is very likely a timing issue.

We are not waiting long enough for something or something is happening too quick. :/

As debugging does not work well...  Can you try:

	gphoto2 --trigger-capture --wait-event-and-download=20s > events.log

This will print the events coming from the camera. If that has also
working/nonworking happenings, logs for both fail/success would be good.

Ciao, Marcus

------------------------------------------------------------------------------
Check out the vibrant tech community on one of the world's most
engaging tech sites, SlashDot.org! http://sdm.link/slashdot