2019-01-04 19:29:38 INFO rapid.py 6108: Rapid Photo Downloader is starting
2019-01-04 19:29:42 INFO rapid.py 481: Rapid Photo Downloader: 0.9.9
2019-01-04 19:29:42 INFO rapid.py 481: Platform: Linux-4.15.0-43-generic-x86_64-with-Ubuntu-18.04-bionic
2019-01-04 19:29:42 INFO rapid.py 481: Memory: 1.21 GB used of 16.7 GB
2019-01-04 19:29:42 INFO rapid.py 481: Python: 3.6.7
2019-01-04 19:29:42 INFO rapid.py 481: Python executable: /usr/bin/python3
2019-01-04 19:29:42 INFO rapid.py 481: Qt: 5.9.5
2019-01-04 19:29:42 INFO rapid.py 481: PyQt: 5.10.1
2019-01-04 19:29:42 INFO rapid.py 481: SIP: 4.19.7
2019-01-04 19:29:42 INFO rapid.py 481: ZeroMQ: 4.2.5
2019-01-04 19:29:42 INFO rapid.py 481: Python ZeroMQ: 16.0.2 (cython backend)
2019-01-04 19:29:42 INFO rapid.py 481: gPhoto2: 2.5.16
2019-01-04 19:29:42 INFO rapid.py 481: Python gPhoto2: 1.8.2
2019-01-04 19:29:42 INFO rapid.py 481: ExifTool: 10.80
2019-01-04 19:29:42 INFO rapid.py 481: pymediainfo: None
2019-01-04 19:29:42 INFO rapid.py 481: GExiv2: 0.10.08
2019-01-04 19:29:42 INFO rapid.py 481: Gstreamer: 1.14.1
2019-01-04 19:29:42 INFO rapid.py 481: PyGObject: 3.26.1
2019-01-04 19:29:42 INFO rapid.py 481: libraw: 0.18.8-Release
2019-01-04 19:29:42 INFO rapid.py 481: rawkit: 0.6.0
2019-01-04 19:29:42 INFO rapid.py 481: psutil: 5.4.2
2019-01-04 19:29:42 INFO rapid.py 481: glibc: 2.25
2019-01-04 19:29:42 INFO rapid.py 481: Tornado: 4.5.3
2019-01-04 19:29:42 ERROR rapid.py 489: pymediainfo is installed, but the library libmediainfo appears to be missing
2019-01-04 19:29:42 DEBUG rapid.py 637: Initial program run detected
2019-01-04 19:29:42 INFO rapid.py 528: Device autodetection: True
2019-01-04 19:29:42 INFO rapid.py 536: For automatically detected devices, only the contents the following folders will be scanned: DCIM, PRIVATE, MP_ROOT
2019-01-04 19:29:42 INFO rapid.py 557: This Computer is not used as a download source
2019-01-04 19:29:42 INFO rapid.py 561: Photo download location: /home/truth/Pictures
2019-01-04 19:29:42 INFO rapid.py 564: Video download location: /home/truth/Videos
2019-01-04 19:29:42 INFO rapid.py 569: Backing up files: False
2019-01-04 19:29:42 DEBUG rapid.py 617: Starting main ExifTool process
2019-01-04 19:29:42 DEBUG preferences.py 1006: Validating CPU core count for thumbnail generation...
2019-01-04 19:29:42 DEBUG preferences.py 1008: ...2 physical cores detected
2019-01-04 19:29:42 DEBUG rapid.py 730: Starting logging subscription manager...
2019-01-04 19:29:42 DEBUG rapid.py 735: ...logging subscription manager started
2019-01-04 19:29:42 DEBUG rapid.py 740: Stage 2 initialization
2019-01-04 19:29:42 DEBUG cache.py 384: Created thumbnails cache /home/truth/.cache/rapid-photo-downloader/thumbnails
2019-01-04 19:29:43 DEBUG rapid.py 772: Starting thumbnail daemon model
2019-01-04 19:29:43 DEBUG interprocess.py 293: Running sink for Thumbnail Daemon Manager
2019-01-04 19:29:43 DEBUG rapid.py 785: Stage 3 initialization
2019-01-04 19:29:43 DEBUG rapid.py 790: ...thumbnail daemon model started
2019-01-04 19:29:43 DEBUG interprocess.py 726: Starting worker for Thumbnail Daemon Manager
2019-01-04 19:29:43 DEBUG interprocess.py 192: Started '/usr/bin/python3 /usr/lib/python3/dist-packages/raphodo/thumbnaildaemon.py --receive 44483 --send 40751 --logging 38395' with pid 2516
2019-01-04 19:29:43 DEBUG thumbnailer.py 188: Starting thumbnail model...
2019-01-04 19:29:43 DEBUG thumbnaildisplay.py 168: Setting arrow locale to en_US
2019-01-04 19:29:43 DEBUG interprocess.py 293: Running sink for Thumbnail Manager
2019-01-04 19:29:43 DEBUG thumbnailer.py 207: ...thumbnail model started
2019-01-04 19:29:43 DEBUG thumbnailer.py 212: Starting thumbnail load balancer...
2019-01-04 19:29:43 DEBUG interprocess.py 192: Started '/usr/bin/python3 /usr/lib/python3/dist-packages/raphodo/thumbloadbalancer.py --receive 42873 --send 42537 --controller 36997 --logging 38395' with pid 2519
2019-01-04 19:29:43 DEBUG interprocess.py 1203: Subscribing to logging on port 45989
2019-01-04 19:29:43 DEBUG thumbnailer.py 225: ...thumbnail load balancer started
2019-01-04 19:29:43 DEBUG rapid.py 802: Stage 4 initialization
2019-01-04 19:29:43 DEBUG interprocess.py 192: Started '/usr/bin/python3 /usr/lib/python3/dist-packages/raphodo/thumbnailextractor.py --request 46011 --send 42537 --identity 0 --logging 38395' with pid 2527
2019-01-04 19:29:43 DEBUG interprocess.py 192: Started '/usr/bin/python3 /usr/lib/python3/dist-packages/raphodo/thumbnailextractor.py --request 46011 --send 42537 --identity 1 --logging 38395' with pid 2528
2019-01-04 19:29:44 DEBUG interprocess.py 1203: Subscribing to logging on port 46183
2019-01-04 19:29:44 INFO rapid.py 826: Default file manager: nautilus
2019-01-04 19:29:44 DEBUG rapid.py 839: Locale directory: /usr/share/locale
2019-01-04 19:29:44 DEBUG rapid.py 842: Getting gphoto2 context
2019-01-04 19:29:44 DEBUG rapid.py 849: Probing for valid mounts
2019-01-04 19:29:44 DEBUG storage.py 269: Media dir is /media/truth
2019-01-04 19:29:44 DEBUG storage.py 316: To be recognized, partitions must be mounted under /media/truth
2019-01-04 19:29:44 DEBUG rapid.py 853: Freedesktop.org thumbnails location: /home/truth/.cache/thumbnails
2019-01-04 19:29:44 DEBUG rapid.py 855: Probing desktop environment
2019-01-04 19:29:44 DEBUG rapid.py 858: Desktop environment: ubuntu:GNOME
2019-01-04 19:29:44 DEBUG rapid.py 890: Unity progress indicator found, using 2 launcher(s)
2019-01-04 19:29:44 DEBUG rapid.py 1924: Checking path validity
2019-01-04 19:29:44 DEBUG storage.py 812: Updating watched paths
2019-01-04 19:29:44 DEBUG storage.py 826: Adding to watched paths: /home/truth
2019-01-04 19:29:44 DEBUG interprocess.py 1203: Subscribing to logging on port 36159
2019-01-04 19:29:44 DEBUG interprocess.py 1203: Subscribing to logging on port 33937
2019-01-04 19:29:44 DEBUG rapid.py 896: Laying out main window
2019-01-04 19:29:44 DEBUG thumbnailextractor.py 615: Thumbnail-Extractor-1 worker started
2019-01-04 19:29:44 DEBUG thumbnailextractor.py 615: Thumbnail-Extractor-0 worker started
2019-01-04 19:29:44 DEBUG renamepanel.py 146: Setting photo combobox chosen value to Original Filename
2019-01-04 19:29:44 DEBUG renamepanel.py 236: Updating example photo name in rename panel
2019-01-04 19:29:44 DEBUG renamepanel.py 146: Setting video combobox chosen value to Original Filename
2019-01-04 19:29:44 DEBUG renamepanel.py 239: Updating example video name in rename panel
2019-01-04 19:29:45 DEBUG jobcodepanel.py 311: Inserting 2 job codes into job code widget
2019-01-04 19:29:45 DEBUG storage.py 269: Media dir is /media/truth
2019-01-04 19:29:45 DEBUG storage.py 316: To be recognized, partitions must be mounted under /media/truth
2019-01-04 19:29:45 DEBUG foldercombo.py 74: Rebuilding photo combobox entries...
2019-01-04 19:29:45 DEBUG foldercombo.py 158: ...12 combobox entries added
2019-01-04 19:29:45 INFO foldercombo.py 210: photo path /home/truth is a default value or path to an external volume
2019-01-04 19:29:45 DEBUG foldercombo.py 74: Rebuilding video combobox entries...
2019-01-04 19:29:45 DEBUG foldercombo.py 158: ...12 combobox entries added
2019-01-04 19:29:45 INFO foldercombo.py 210: video path /home/truth is a default value or path to an external volume
2019-01-04 19:29:45 DEBUG rapid.py 900: Have GIO module: True
2019-01-04 19:29:45 DEBUG rapid.py 903: Using GIO: True
2019-01-04 19:29:45 DEBUG rapid.py 933: Starting GVolumeMonitor...
2019-01-04 19:29:45 DEBUG rapid.py 935: ...GVolumeMonitor started
2019-01-04 19:29:45 DEBUG rapid.py 944: Version check disabled
2019-01-04 19:29:45 DEBUG rapid.py 976: Starting download tracker
2019-01-04 19:29:45 DEBUG rapid.py 983: Setting up download update timer
2019-01-04 19:29:45 DEBUG rapid.py 990: Starting offload manager...
2019-01-04 19:29:48 DEBUG interprocess.py 293: Running sink for Offload Manager
2019-01-04 19:29:48 DEBUG rapid.py 1004: ...offload manager started
2019-01-04 19:29:48 DEBUG interprocess.py 726: Starting worker for Offload Manager
2019-01-04 19:29:48 DEBUG rapid.py 1029: Starting rename manager...
2019-01-04 19:29:48 DEBUG interprocess.py 293: Running sink for Rename and Move File Manager
2019-01-04 19:29:48 DEBUG rapid.py 1034: ...rename manager started
2019-01-04 19:29:48 DEBUG interprocess.py 192: Started '/usr/bin/python3 /usr/lib/python3/dist-packages/raphodo/offload.py --receive 42157 --send 46247 --logging 38395' with pid 2643
2019-01-04 19:29:48 DEBUG rapid.py 1055: Starting scan manager...
2019-01-04 19:29:48 DEBUG interprocess.py 726: Starting worker for Rename and Move File Manager
2019-01-04 19:29:48 DEBUG interprocess.py 293: Running sink for Scan Manager
2019-01-04 19:29:48 DEBUG rapid.py 1060: ...scan manager started
2019-01-04 19:29:48 DEBUG rapid.py 1079: Starting copy files manager...
2019-01-04 19:29:48 DEBUG interprocess.py 293: Running sink for Copy Files Manager
2019-01-04 19:29:48 DEBUG rapid.py 1084: ...copy files manager started
2019-01-04 19:29:48 DEBUG interprocess.py 192: Started '/usr/bin/python3 /usr/lib/python3/dist-packages/raphodo/renameandmovefile.py --receive 39863 --send 37051 --logging 38395' with pid 2646
2019-01-04 19:29:48 DEBUG rapid.py 1099: Starting backup manager ...
2019-01-04 19:29:48 DEBUG interprocess.py 293: Running sink for Backup Manager
2019-01-04 19:29:48 DEBUG rapid.py 1104: ...backup manager started
2019-01-04 19:29:48 DEBUG rpdsql.py 332: SELECT uid FROM files WHERE marked=? AND file_type=? [True, <FileType.photo: 1>]
2019-01-04 19:29:48 DEBUG rpdsql.py 332: SELECT uid FROM files WHERE marked=? AND file_type=? [True, <FileType.video: 2>]
2019-01-04 19:29:49 DEBUG rapid.py 4943: Not scanning volume with path /media/truth/XT1575 because it lacks a folder at the base level that indicates it should be scanned
2019-01-04 19:29:49 DEBUG rapid.py 5339: Will not scan XT1575
2019-01-04 19:29:49 DEBUG rapid.py 4943: Not scanning volume with path /media/truth/envy backup because it lacks a folder at the base level that indicates it should be scanned
2019-01-04 19:29:49 DEBUG rapid.py 5339: Will not scan envy backup
2019-01-04 19:29:49 DEBUG rapid.py 4943: Not scanning volume with path /media/truth/unused because it lacks a folder at the base level that indicates it should be scanned
2019-01-04 19:29:49 DEBUG rapid.py 5339: Will not scan unused
2019-01-04 19:29:49 DEBUG rapid.py 4943: Not scanning volume with path /media/truth/instrumental because it lacks a folder at the base level that indicates it should be scanned
2019-01-04 19:29:49 DEBUG rapid.py 5339: Will not scan instrumental
2019-01-04 19:29:49 DEBUG rapid.py 4943: Not scanning volume with path /media/truth/My Passport because it lacks a folder at the base level that indicates it should be scanned
2019-01-04 19:29:49 DEBUG rapid.py 5339: Will not scan My Passport
2019-01-04 19:29:50 DEBUG interprocess.py 1203: Subscribing to logging on port 44801
2019-01-04 19:29:50 DEBUG interprocess.py 1203: Subscribing to logging on port 43881
2019-01-04 19:49:14 DEBUG rapid.py 1199: Completed stage 9 initializing main window
2019-01-04 19:52:39 DEBUG rapid.py 4943: Not scanning volume with path /media/truth/XT1575 because it lacks a folder at the base level that indicates it should be scanned
2019-01-04 19:52:39 DEBUG rapid.py 5339: Will not scan XT1575
2019-01-04 19:52:39 DEBUG rapid.py 4943: Not scanning volume with path /media/truth/envy backup because it lacks a folder at the base level that indicates it should be scanned
2019-01-04 19:52:39 DEBUG rapid.py 5339: Will not scan envy backup
2019-01-04 19:52:39 DEBUG rapid.py 4943: Not scanning volume with path /media/truth/unused because it lacks a folder at the base level that indicates it should be scanned
2019-01-04 19:52:39 DEBUG rapid.py 5339: Will not scan unused
2019-01-04 19:52:39 DEBUG rapid.py 4943: Not scanning volume with path /media/truth/instrumental because it lacks a folder at the base level that indicates it should be scanned
2019-01-04 19:52:39 DEBUG rapid.py 5339: Will not scan instrumental
2019-01-04 19:52:39 DEBUG rapid.py 4943: Not scanning volume with path /media/truth/My Passport because it lacks a folder at the base level that indicates it should be scanned
2019-01-04 19:52:39 DEBUG rapid.py 5339: Will not scan My Passport
2019-01-04 20:05:27 DEBUG rapid.py 5489: Change in watched folder /home/truth; validating download destinations
2019-01-04 20:05:27 DEBUG storage.py 1429: GIO: Volume added Canon Digital Camera. Automount: True
2019-01-04 20:05:28 DEBUG storage.py 1380: GIO: Looking for camera at mount /run/user/1000/gvfs/gphoto2:host=%5Busb%3A001%2C005%5D
2019-01-04 20:05:28 DEBUG rapid.py 4816: Detected USB PTP Class Camera on port usb:001,005
2019-01-04 20:05:28 DEBUG storage.py 1380: GIO: Looking for camera at mount /run/user/1000/gvfs/gphoto2:host=%5Busb%3A001%2C005%5D
2019-01-04 20:05:28 DEBUG storage.py 1282: GIO: Attempting to unmount USB PTP Class Camera...
2019-01-04 20:05:28 DEBUG storage.py 1380: GIO: Looking for camera at mount /run/user/1000/gvfs/gphoto2:host=%5Busb%3A001%2C005%5D
2019-01-04 20:05:28 DEBUG rapid.py 4805: Already unmounting USB PTP Class Camera
2019-01-04 20:05:28 DEBUG storage.py 1380: GIO: Looking for camera at mount /run/user/1000/gvfs/gphoto2:host=%5Busb%3A001%2C005%5D
2019-01-04 20:05:28 DEBUG storage.py 1307: ...successfully unmounted USB PTP Class Camera
2019-01-04 20:05:28 DEBUG rapid.py 4852: Assigning scan id 0 to Canon Digital Camera
2019-01-04 20:05:28 DEBUG rpdsql.py 118: INSERT OR REPLACE INTO devices (scan_id, device_name) VALUES (?,?) (0, Canon Digital Camera)
2019-01-04 20:05:28 DEBUG devicedisplay.py 158: Adding Canon Digital Camera to Devices display with scan id 0 at row 0
2019-01-04 20:05:28 DEBUG devices.py 542: Setting device state for Canon Digital Camera to scanning
2019-01-04 20:05:28 DEBUG rpdsql.py 332: SELECT uid FROM files WHERE marked=? AND file_type=? [True, <FileType.photo: 1>]
2019-01-04 20:05:28 DEBUG rpdsql.py 332: SELECT uid FROM files WHERE marked=? AND file_type=? [True, <FileType.video: 2>]
2019-01-04 20:05:28 DEBUG rapid.py 1582: Setting progress bar to show scanning activity
2019-01-04 20:05:28 DEBUG interprocess.py 192: Started '/usr/bin/python3 /usr/lib/python3/dist-packages/raphodo/scan.py --receive 35427 --send 35999 --controller 34465 --syncclient 42857 --filter 0 --logging 38395' with pid 2829
2019-01-04 20:05:28 DEBUG interprocess.py 1203: Subscribing to logging on port 35267
2019-01-04 20:05:28 DEBUG scan.py 161: Scan 0 worker started
2019-01-04 20:05:28 ERROR scan.py 150: Unexpected exception while scanning Canon Digital Camera
Traceback (most recent call last):
File "/usr/lib/python3/dist-packages/raphodo/scan.py", line 144, in do_work
self.do_scan()
File "/usr/lib/python3/dist-packages/raphodo/scan.py", line 248, in do_scan
specific_folders=specific_folder_prefs
File "/usr/lib/python3/dist-packages/raphodo/camera.py", line 182, in __init__
concise_model_name = self._concise_model_name()
File "/usr/lib/python3/dist-packages/raphodo/camera.py", line 682, in _concise_model_name
self.camera_config = self.camera.get_config(self.context)
gphoto2.GPhoto2Error: [-6] Unsupported operation
2019-01-04 20:05:28 DEBUG scan.py 842: Sending 0 scanned files from Canon Digital Camera to sink
2019-01-04 20:05:28 DEBUG interprocess.py 1212: Unsubscribing to logging on port 35267
2019-01-04 20:05:28 DEBUG interprocess.py 336: Scan Manager currently has no workers
2019-01-04 20:05:28 DEBUG devices.py 542: Setting device state for Canon Digital Camera to idle
2019-01-04 20:05:28 DEBUG rapid.py 1585: Resetting progress bar
2019-01-04 20:05:28 DEBUG devicedisplay.py 342: Setting Canon Digital Camera checkbox to unchecked
2019-01-04 20:05:28 DEBUG devicedisplay.py 323: -- Device Model for Devices --
2019-01-04 20:05:28 DEBUG devicedisplay.py 325: Known devices: Canon Digital Camera
2019-01-04 20:05:28 DEBUG devicedisplay.py 330: Row 0: Canon Digital Camera
2019-01-04 20:05:28 DEBUG devicedisplay.py 330: Row 1: Canon Digital Camera
2019-01-04 20:05:28 DEBUG devicedisplay.py 333: Spinner states: Canon Digital Camera: scanning
2019-01-04 20:05:28 DEBUG devicedisplay.py 335: Canon Digital Camera: unchecked
2019-01-04 20:05:28 DEBUG rpdsql.py 332: SELECT uid FROM files WHERE marked=? AND file_type=? [True, <FileType.photo: 1>]
2019-01-04 20:05:28 DEBUG rpdsql.py 332: SELECT uid FROM files WHERE marked=? AND file_type=? [True, <FileType.video: 2>]
2019-01-04 20:05:28 DEBUG devices.py 490: -- Device Collection --
2019-01-04 20:05:28 DEBUG devices.py 493: 1 devices: 1 volumes/cameras (1 cameras), 0 this computer
2019-01-04 20:05:28 DEBUG devices.py 496: Device states: Canon Digital Camera: idle
2019-01-04 20:05:28 DEBUG devices.py 501: No devices scanning
2019-01-04 20:05:28 DEBUG devices.py 507: No devices downloading
2019-01-04 20:05:28 DEBUG devices.py 513: No devices thumbnailing
2019-01-04 20:05:28 DEBUG thumbnaildisplay.py 239: -- Thumbnail Model --
2019-01-04 20:05:28 DEBUG thumbnaildisplay.py 249: 0 thumbnails (0 marked)
2019-01-04 20:05:28 DEBUG thumbnaildisplay.py 254: 0 not downloaded; 0 downloaded; 0 previously downloaded
2019-01-04 20:05:28 DEBUG thumbnaildisplay.py 268: Active devices: Canon Digital Camera
2019-01-04 20:05:28 DEBUG devicedisplay.py 323: -- Device Model for Devices --
2019-01-04 20:05:28 DEBUG devicedisplay.py 325: Known devices: Canon Digital Camera
2019-01-04 20:05:28 DEBUG devicedisplay.py 330: Row 0: Canon Digital Camera
2019-01-04 20:05:28 DEBUG devicedisplay.py 330: Row 1: Canon Digital Camera
2019-01-04 20:05:28 DEBUG devicedisplay.py 333: Spinner states: Canon Digital Camera: scanning
2019-01-04 20:05:28 DEBUG devicedisplay.py 335: Canon Digital Camera: unchecked
2019-01-04 20:05:28 INFO rapid.py 4497: Was tasked to generate Timeline because a download source has finished being scanned, but there is nothing to generate
2019-01-04 20:05:28 DEBUG rapid.py 4456: No files are marked for download for Canon Digital Camera
