RPD very slow on open and when switching memory cards

I was about to submit a question why RPD is not starting but it turns out that it just starts very slow (like around 20 minutes).
Same thing happens when I switch memory cards - application freezes for 10-20 minutes.

Can you start it from the terminal and post the terminal output?

Without debug/verbose I am not getting output. And here is an interesting find:

I’ve modified code and added simple

logging.basicConfig(format='%(asctime)s %(levelname)-8s %(message)s', level=logging.INFO, datefmt='%Y-%m-%d %H:%M:%S')

at line ~6210 of rapid.py to measure time.

With DEBUG on (or with that line of code) RPD loaded within 0:07 sec (this is ~time from open to close as time will display on app exit)

4.958u 1.016s 0:07.68 77.6% 0+0k 0+496io 0pf+0w

Without DEBUG 2:23 (which is still 10x faster then i was getting before measuring)

>  time rapid-photo-downloader
5.220u 1.450s 2:23.22 4.6%	0+0k 0+496io 0pf+0w

So with my first tests - if I keep DEBUG, everything is blazing fast, but without it it slows down drastically

This is timed debug which works fine:

>  time rapid-photo-downloader
2019-06-17 16:47:26 INFO     Rapid Photo Downloader is starting
2019-06-17 16:47:26 INFO     Rapid Photo Downloader: 0.9.14
2019-06-17 16:47:26 INFO     Platform: Linux-5.0.17-200.fc29.x86_64-x86_64-with-fedora-29-Twenty_Nine
2019-06-17 16:47:26 INFO     Memory: 6.61 GB used of 135.14 GB
2019-06-17 16:47:26 INFO     Python: 3.7.3
2019-06-17 16:47:26 INFO     Python executable: /usr/bin/python3
2019-06-17 16:47:26 INFO     Qt: 5.12.3
2019-06-17 16:47:26 INFO     PyQt: 5.12.2
2019-06-17 16:47:26 INFO     SIP: 4.19.17
2019-06-17 16:47:26 INFO     ZeroMQ: 4.3.1
2019-06-17 16:47:26 INFO     Python ZeroMQ: 18.0.1 (cython backend)
2019-06-17 16:47:26 INFO     gPhoto2: 2.5.21
2019-06-17 16:47:26 INFO     Python gPhoto2: 2.0.0
2019-06-17 16:47:26 INFO     ExifTool: 11.30
2019-06-17 16:47:26 INFO     pymediainfo: 4.0
2019-06-17 16:47:26 INFO     GExiv2: 0.10.10
2019-06-17 16:47:26 INFO     Gstreamer: 1.14.4
2019-06-17 16:47:26 INFO     PyGObject: 3.30.4
2019-06-17 16:47:26 INFO     libraw: 0.19.2
2019-06-17 16:47:26 INFO     rawkit: 0.6.0 (not functional)
2019-06-17 16:47:26 INFO     psutil: 5.4.3
2019-06-17 16:47:26 INFO     Exiv2: 0.26.0
2019-06-17 16:47:26 INFO     glibc: 2.2.5
2019-06-17 16:47:26 INFO     Arrow: 0.14.2
2019-06-17 16:47:26 INFO     Tornado: 4.5.3
2019-06-17 16:47:26 DEBUG    Avaiable screen geometry: 1920x1200 on 1920x1200 display
2019-06-17 16:47:26 INFO     Device autodetection: True
2019-06-17 16:47:26 INFO     For automatically detected devices, only the contents the following folders will be scanned: DCIM, PRIVATE, MP_ROOT
2019-06-17 16:47:26 INFO     This Computer is not used as a download source
2019-06-17 16:47:26 INFO     Photo download location: /Photography
2019-06-17 16:47:26 INFO     Video download location: /VideoEditing
2019-06-17 16:47:26 INFO     Backing up files: False
2019-06-17 16:47:26 DEBUG    Starting main ExifTool process
2019-06-17 16:47:26 DEBUG    Validating CPU core count for thumbnail generation...
2019-06-17 16:47:26 DEBUG    ...12 physical cores detected
2019-06-17 16:47:26 DEBUG    Starting logging subscription manager...
2019-06-17 16:47:26 DEBUG    ...logging subscription manager started
2019-06-17 16:47:26 DEBUG    Stage 2 initialization
2019-06-17 16:47:26 DEBUG    Starting thumbnail daemon model
2019-06-17 16:47:26 DEBUG    Running sink for Thumbnail Daemon Manager
2019-06-17 16:47:26 DEBUG    Stage 3 initialization
2019-06-17 16:47:26 DEBUG    ...thumbnail daemon model started
2019-06-17 16:47:26 DEBUG    Starting worker for Thumbnail Daemon Manager
2019-06-17 16:47:26 DEBUG    Starting thumbnail model...
2019-06-17 16:47:26 DEBUG    Setting arrow locale to en_US
2019-06-17 16:47:26 DEBUG    Started '/usr/bin/python3 /home/myuser/.local/lib/python3.7/site-packages/raphodo/thumbnaildaemon.py --receive 42581 --send 35385 --logging 34161' with pid 12337
2019-06-17 16:47:26 DEBUG    Running sink for Thumbnail Manager
2019-06-17 16:47:26 DEBUG    ...thumbnail model started
2019-06-17 16:47:26 DEBUG    Starting thumbnail load balancer...
2019-06-17 16:47:26 DEBUG    Started '/usr/bin/python3 /home/myuser/.local/lib/python3.7/site-packages/raphodo/thumbloadbalancer.py --receive 38771 --send 39703 --controller 38769 --logging 34161' with pid 12340
2019-06-17 16:47:27 DEBUG    Subscribing to logging on port 39375
2019-06-17 16:47:27 DEBUG    ...thumbnail load balancer started
2019-06-17 16:47:27 DEBUG    Stage 4 initialization
2019-06-17 16:47:27 DEBUG    Started '/usr/bin/python3 /home/myuser/.local/lib/python3.7/site-packages/raphodo/thumbnailextractor.py --request 38057 --send 39703 --identity 0 --logging 34161' with pid 12349
2019-06-17 16:47:27 DEBUG    Subscribing to logging on port 38369
2019-06-17 16:47:27 DEBUG    Started '/usr/bin/python3 /home/myuser/.local/lib/python3.7/site-packages/raphodo/thumbnailextractor.py --request 38057 --send 39703 --identity 1 --logging 34161' with pid 12350
2019-06-17 16:47:27 DEBUG    Started '/usr/bin/python3 /home/myuser/.local/lib/python3.7/site-packages/raphodo/thumbnailextractor.py --request 38057 --send 39703 --identity 2 --logging 34161' with pid 12353
2019-06-17 16:47:27 DEBUG    Started '/usr/bin/python3 /home/myuser/.local/lib/python3.7/site-packages/raphodo/thumbnailextractor.py --request 38057 --send 39703 --identity 3 --logging 34161' with pid 12356
2019-06-17 16:47:27 DEBUG    Started '/usr/bin/python3 /home/myuser/.local/lib/python3.7/site-packages/raphodo/thumbnailextractor.py --request 38057 --send 39703 --identity 4 --logging 34161' with pid 12357
2019-06-17 16:47:27 DEBUG    Started '/usr/bin/python3 /home/myuser/.local/lib/python3.7/site-packages/raphodo/thumbnailextractor.py --request 38057 --send 39703 --identity 5 --logging 34161' with pid 12358
2019-06-17 16:47:27 DEBUG    Started '/usr/bin/python3 /home/myuser/.local/lib/python3.7/site-packages/raphodo/thumbnailextractor.py --request 38057 --send 39703 --identity 6 --logging 34161' with pid 12360
2019-06-17 16:47:27 DEBUG    Started '/usr/bin/python3 /home/myuser/.local/lib/python3.7/site-packages/raphodo/thumbnailextractor.py --request 38057 --send 39703 --identity 7 --logging 34161' with pid 12361
2019-06-17 16:47:27 DEBUG    Started '/usr/bin/python3 /home/myuser/.local/lib/python3.7/site-packages/raphodo/thumbnailextractor.py --request 38057 --send 39703 --identity 8 --logging 34161' with pid 12368
2019-06-17 16:47:27 DEBUG    Started '/usr/bin/python3 /home/myuser/.local/lib/python3.7/site-packages/raphodo/thumbnailextractor.py --request 38057 --send 39703 --identity 9 --logging 34161' with pid 12378
2019-06-17 16:47:27 DEBUG    Started '/usr/bin/python3 /home/myuser/.local/lib/python3.7/site-packages/raphodo/thumbnailextractor.py --request 38057 --send 39703 --identity 10 --logging 34161' with pid 12388
2019-06-17 16:47:27 DEBUG    Started '/usr/bin/python3 /home/myuser/.local/lib/python3.7/site-packages/raphodo/thumbnailextractor.py --request 38057 --send 39703 --identity 11 --logging 34161' with pid 12395
2019-06-17 16:47:27 INFO     Default file manager: caja (dir_only_uri)
2019-06-17 16:47:27 DEBUG    Locale directory: /home/myuser/.local/share/locale
2019-06-17 16:47:27 DEBUG    Getting gphoto2 context
2019-06-17 16:47:27 DEBUG    Probing for valid mounts
2019-06-17 16:47:27 DEBUG    Media dir is /run/media/myuser
2019-06-17 16:47:27 DEBUG    To be recognized, partitions must be mounted under /run/media/myuser
2019-06-17 16:47:27 DEBUG    Freedesktop.org thumbnails location: /home/myuser/.cache/thumbnails
2019-06-17 16:47:27 DEBUG    Probing desktop environment
2019-06-17 16:47:27 DEBUG    Desktop environment: MATE
2019-06-17 16:47:27 DEBUG    Checking path validity
2019-06-17 16:47:27 DEBUG    Updating watched paths
2019-06-17 16:47:27 DEBUG    Adding to watched paths: /
2019-06-17 16:47:27 DEBUG    Laying out main window
2019-06-17 16:47:27 DEBUG    Setting photo combobox chosen value to Original Filename
2019-06-17 16:47:27 DEBUG    Updating example photo name in rename panel
2019-06-17 16:47:27 DEBUG    Setting video combobox chosen value to Original Filename
2019-06-17 16:47:27 DEBUG    Updating example video name in rename panel
2019-06-17 16:47:27 DEBUG    Inserting 2 job codes into job code widget
2019-06-17 16:47:27 DEBUG    Media dir is /run/media/myuser
2019-06-17 16:47:27 DEBUG    To be recognized, partitions must be mounted under /run/media/myuser
2019-06-17 16:47:27 DEBUG    Rebuilding photo combobox entries...
2019-06-17 16:47:27 DEBUG    ...8 combobox entries added
2019-06-17 16:47:27 INFO     photo path /home/myuser is a default value or path to an external volume
2019-06-17 16:47:27 DEBUG    Rebuilding video combobox entries...
2019-06-17 16:47:27 DEBUG    ...8 combobox entries added
2019-06-17 16:47:27 INFO     video path /home/myuser is a default value or path to an external volume
2019-06-17 16:47:28 DEBUG    Have GIO module: True
2019-06-17 16:47:28 DEBUG    Using GIO: True
2019-06-17 16:47:28 DEBUG    Starting GVolumeMonitor...
2019-06-17 16:47:28 DEBUG    ...GVolumeMonitor started
2019-06-17 16:47:28 DEBUG    Starting version check
2019-06-17 16:47:28 DEBUG    Starting download tracker
2019-06-17 16:47:28 DEBUG    Setting up download update timer
2019-06-17 16:47:28 DEBUG    Starting offload manager...
2019-06-17 16:47:28 DEBUG    Running sink for Offload Manager
2019-06-17 16:47:28 DEBUG    ...offload manager started
2019-06-17 16:47:28 DEBUG    Starting worker for Offload Manager
2019-06-17 16:47:28 DEBUG    Starting rename manager...
2019-06-17 16:47:28 DEBUG    Running sink for Rename and Move File Manager
2019-06-17 16:47:28 DEBUG    ...rename manager started
2019-06-17 16:47:28 DEBUG    Starting worker for Rename and Move File Manager
2019-06-17 16:47:28 DEBUG    Started '/usr/bin/python3 /home/myuser/.local/lib/python3.7/site-packages/raphodo/offload.py --receive 32835 --send 39369 --logging 34161' with pid 12421
2019-06-17 16:47:28 DEBUG    Starting scan manager...
2019-06-17 16:47:28 DEBUG    Running sink for Scan Manager
2019-06-17 16:47:28 DEBUG    ...scan manager started
2019-06-17 16:47:28 DEBUG    Starting copy files manager...
2019-06-17 16:47:28 DEBUG    Running sink for Copy Files Manager
2019-06-17 16:47:28 DEBUG    ...copy files manager started
2019-06-17 16:47:28 DEBUG    Started '/usr/bin/python3 /home/myuser/.local/lib/python3.7/site-packages/raphodo/renameandmovefile.py --receive 45183 --send 46199 --logging 34161' with pid 12423
2019-06-17 16:47:28 DEBUG    Starting backup manager ...
2019-06-17 16:47:28 DEBUG    Running sink for Backup Manager
2019-06-17 16:47:28 DEBUG    ...backup manager started
2019-06-17 16:47:28 DEBUG    SELECT uid FROM files WHERE marked=? AND file_type=? [True, <FileType.photo: 1>]
2019-06-17 16:47:28 DEBUG    SELECT uid FROM files WHERE marked=? AND file_type=? [True, <FileType.video: 2>]
2019-06-17 16:47:28 DEBUG    Will scan EOS_DIGITAL
2019-06-17 16:47:28 DEBUG    Assigning scan id 0 to EOS_DIGITAL
2019-06-17 16:47:28 DEBUG    INSERT OR REPLACE INTO devices (scan_id, device_name) VALUES (?,?) (0, EOS_DIGITAL)
2019-06-17 16:47:28 DEBUG    Adding EOS_DIGITAL to Devices display with scan id 0 at row 0
2019-06-17 16:47:28 DEBUG    Setting device state for EOS_DIGITAL to scanning
2019-06-17 16:47:28 DEBUG    SELECT uid FROM files WHERE marked=? AND file_type=? [True, <FileType.photo: 1>]
2019-06-17 16:47:28 DEBUG    SELECT uid FROM files WHERE marked=? AND file_type=? [True, <FileType.video: 2>]
2019-06-17 16:47:28 DEBUG    Setting progress bar to show scanning activity
2019-06-17 16:47:28 DEBUG    Started '/usr/bin/python3 /home/myuser/.local/lib/python3.7/site-packages/raphodo/scan.py --receive 46351 --send 35947 --controller 37457 --syncclient 42945 --filter 0 --logging 34161' with pid 12434
2019-06-17 16:47:28 DEBUG    Subscribing to logging on port 39597
2019-06-17 16:47:29 DEBUG    Subscribing to logging on port 33461
2019-06-17 16:47:29 DEBUG    Subscribing to logging on port 35289
2019-06-17 16:47:29 DEBUG    Subscribing to logging on port 40667
2019-06-17 16:47:29 DEBUG    Subscribing to logging on port 39673
2019-06-17 16:47:29 DEBUG    Subscribing to logging on port 33899
2019-06-17 16:47:29 DEBUG    Subscribing to logging on port 42593
2019-06-17 16:47:29 DEBUG    Subscribing to logging on port 45041
2019-06-17 16:47:29 DEBUG    Subscribing to logging on port 42639
2019-06-17 16:47:29 DEBUG    Subscribing to logging on port 40989
2019-06-17 16:47:29 DEBUG    Subscribing to logging on port 44577
2019-06-17 16:47:29 DEBUG    Subscribing to logging on port 33785
2019-06-17 16:47:29 DEBUG    Subscribing to logging on port 44791
2019-06-17 16:47:29 DEBUG    Completed stage 9 initializing main window
2019-06-17 16:47:29 DEBUG    Starting new HTTPS connection (1): www.damonlynch.net:443
2019-06-17 16:47:29 DEBUG    Window position quirk delta: PyQt5.QtCore.QPoint(0, 78)
2019-06-17 16:47:29 DEBUG    Subscribing to logging on port 43445
2019-06-17 16:47:29 DEBUG    Subscribing to logging on port 40641
2019-06-17 16:47:29 DEBUG    Scan 0 worker started
2019-06-17 16:47:29 INFO     For device EOS_DIGITAL, identified paths: /run/media/myuser/EOS_DIGITAL/DCIM
2019-06-17 16:47:29 INFO     Scanning device EOS_DIGITAL
2019-06-17 16:47:29 DEBUG    Distinguishing approach to timestamp time zones on EOS_DIGITAL
2019-06-17 16:47:29 DEBUG    Examining sample /run/media/myuser/EOS_DIGITAL/DCIM/100EOS5D/2Z8A7685.CR2
2019-06-17 16:47:29 INFO     Device timezone setting for EOS_DIGITAL is local time, as indicated by RAW file
2019-06-17 16:47:29 INFO     Scanning /run/media/myuser/EOS_DIGITAL/DCIM on EOS_DIGITAL
2019-06-17 16:47:29 INFO     Successfully extracted sample photo metadata from EOS_DIGITAL
2019-06-17 16:47:29 DEBUG    Extracted video time 2019:06:13 06:52:47 using ExifTool
2019-06-17 16:47:29 DEBUG    Although ExifTool located a time zonein /run/media/myuser/EOS_DIGITAL/DCIM/100EOS5D/2Z8A7699.MOV's metadata, using the mediainfo result, because the two results are different. Mediainfo: 2019-06-12 23:52:34-07:00 / 2019-06-12 10:52:34  (before / after). ExifTool: 2019-06-13 06:52:47. Time zone: 780
2019-06-17 16:47:29 INFO     Successfully extracted sample video metadata from EOS_DIGITAL
2019-06-17 16:47:29 DEBUG    Sending 50 scanned files from EOS_DIGITAL to sink
2019-06-17 16:47:29 INFO     Updating example file name using sample photo from EOS_DIGITAL
2019-06-17 16:47:30 DEBUG    Updating example photo name in rename panel
2019-06-17 16:47:30 INFO     Updating example file name using sample video from EOS_DIGITAL
2019-06-17 16:47:30 DEBUG    Sending 50 scanned files from EOS_DIGITAL to sink
2019-06-17 16:47:30 DEBUG    Updating example video name in rename panel
2019-06-17 16:47:30 DEBUG    No files are marked for download for EOS_DIGITAL
2019-06-17 16:47:30 DEBUG    Adding 50 rows to db
2019-06-17 16:47:30 DEBUG    SELECT uid, marked FROM files ORDER BY mtime ASC
2019-06-17 16:47:30 DEBUG    SELECT uid FROM files WHERE marked=? AND file_type=? [True, <FileType.photo: 1>]
2019-06-17 16:47:30 DEBUG    SELECT uid FROM files WHERE marked=? AND file_type=? [True, <FileType.video: 2>]
2019-06-17 16:47:30 DEBUG    No files are marked for download for EOS_DIGITAL
2019-06-17 16:47:30 DEBUG    Provisional download folders received
2019-06-17 16:47:30 DEBUG    Provisional download folders change detected
2019-06-17 16:47:30 DEBUG    Updating file system model and views
2019-06-17 16:47:30 DEBUG    Assigning queued provisional download folders to be generated
2019-06-17 16:47:30 DEBUG    Sending 50 scanned files from EOS_DIGITAL to sink
2019-06-17 16:47:30 DEBUG    No files are marked for download for EOS_DIGITAL
2019-06-17 16:47:30 DEBUG    Adding 100 rows to db
2019-06-17 16:47:30 DEBUG    SELECT uid, marked FROM files ORDER BY mtime ASC
2019-06-17 16:47:30 DEBUG    SELECT uid FROM files WHERE marked=? AND file_type=? [True, <FileType.photo: 1>]
2019-06-17 16:47:30 DEBUG    SELECT uid FROM files WHERE marked=? AND file_type=? [True, <FileType.video: 2>]
2019-06-17 16:47:30 DEBUG    Provisional download folders received
2019-06-17 16:47:30 DEBUG    Assigning queued provisional download folders to be generated
2019-06-17 16:47:30 DEBUG    Sending 50 scanned files from EOS_DIGITAL to sink
2019-06-17 16:47:30 DEBUG    Provisional download folders received
2019-06-17 16:47:30 DEBUG    No files are marked for download for EOS_DIGITAL
2019-06-17 16:47:30 DEBUG    https://www.damonlynch.net:443 "GET /rapid/version.json HTTP/1.1" 200 778
2019-06-17 16:47:30 DEBUG    Sending 50 scanned files from EOS_DIGITAL to sink
2019-06-17 16:47:30 DEBUG    Installed using pip: True
2019-06-17 16:47:30 DEBUG    Sending 31 scanned files from EOS_DIGITAL to sink
2019-06-17 16:47:30 INFO     282 total files scanned on EOS_DIGITAL
2019-06-17 16:47:30 DEBUG    Unsubscribing to logging on port 40641
2019-06-17 16:47:30 DEBUG    Scan Manager currently has no workers
2019-06-17 16:47:30 DEBUG    Provisional download folders received
2019-06-17 16:47:30 DEBUG    No files are marked for download for EOS_DIGITAL
2019-06-17 16:47:30 DEBUG    No files are marked for download for EOS_DIGITAL
2019-06-17 16:47:30 DEBUG    Setting device state for EOS_DIGITAL to idle
2019-06-17 16:47:30 DEBUG    Adding 131 rows to db
2019-06-17 16:47:30 DEBUG    SELECT uid, marked FROM files ORDER BY mtime ASC
2019-06-17 16:47:30 DEBUG    Resetting progress bar
2019-06-17 16:47:30 DEBUG    Setting EOS_DIGITAL checkbox to unchecked
2019-06-17 16:47:30 DEBUG    -- Device Model for Devices --
2019-06-17 16:47:30 DEBUG    Known devices: EOS_DIGITAL
2019-06-17 16:47:30 DEBUG    Row 0: EOS_DIGITAL
2019-06-17 16:47:30 DEBUG    Row 1: EOS_DIGITAL
2019-06-17 16:47:30 DEBUG    Spinner states: EOS_DIGITAL: scanning
2019-06-17 16:47:30 DEBUG    EOS_DIGITAL: unchecked
2019-06-17 16:47:30 DEBUG    SELECT uid FROM files WHERE marked=? AND file_type=? [True, <FileType.photo: 1>]
2019-06-17 16:47:30 DEBUG    SELECT uid FROM files WHERE marked=? AND file_type=? [True, <FileType.video: 2>]
2019-06-17 16:47:30 DEBUG    -- Device Collection --
2019-06-17 16:47:30 DEBUG    1 devices: 1 volumes/cameras (0 cameras), 0 this computer
2019-06-17 16:47:30 DEBUG    Device states: EOS_DIGITAL: idle
2019-06-17 16:47:30 DEBUG    No devices scanning
2019-06-17 16:47:30 DEBUG    No devices downloading
2019-06-17 16:47:30 DEBUG    No devices thumbnailing
2019-06-17 16:47:30 DEBUG    -- Thumbnail Model --
2019-06-17 16:47:30 DEBUG    281 thumbnails (0 marked)
2019-06-17 16:47:30 DEBUG    281 not downloaded; 0 downloaded; 281 previously downloaded
2019-06-17 16:47:30 DEBUG    281 to be generated; 0 generated
2019-06-17 16:47:30 DEBUG    Active devices: EOS_DIGITAL
2019-06-17 16:47:30 DEBUG    -- Device Model for Devices --
2019-06-17 16:47:30 DEBUG    Known devices: EOS_DIGITAL
2019-06-17 16:47:30 DEBUG    Row 0: EOS_DIGITAL
2019-06-17 16:47:30 DEBUG    Row 1: EOS_DIGITAL
2019-06-17 16:47:30 DEBUG    Spinner states: EOS_DIGITAL: scanning
2019-06-17 16:47:30 DEBUG    EOS_DIGITAL: unchecked
2019-06-17 16:47:30 INFO     Generating Timeline because a download source has finished being scanned
2019-06-17 16:47:30 DEBUG    Updating Timeline state from empty to generating
2019-06-17 16:47:30 DEBUG    No files are marked for download for EOS_DIGITAL
2019-06-17 16:47:30 DEBUG    Setting device state for EOS_DIGITAL to thumbnailing
2019-06-17 16:47:30 DEBUG    Setting progress bar maximum to 281
2019-06-17 16:47:30 DEBUG    SELECT uid FROM files WHERE scan_id=? (0, )
2019-06-17 16:47:30 DEBUG    Provisional download folders received
2019-06-17 16:47:30 DEBUG    Assigning queued provisional download folders to be generated
2019-06-17 16:47:30 INFO     Generating provisional download folders for 31 files
2019-06-17 16:47:30 DEBUG    Started '/usr/bin/python3 /home/myuser/.local/lib/python3.7/site-packages/raphodo/thumbnailpara.py --receive 43809 --send 39703 --controller 44309 --syncclient 38575 --filter 0 --logging 34161' with pid 12587
2019-06-17 16:47:30 INFO     Timeline validation passed
2019-06-17 16:47:30 DEBUG    Updating Timeline state from generating to generated
2019-06-17 16:47:30 DEBUG    UPDATE files SET proximity_col1=?, proximity_col2=? WHERE uid=? (281 operations)
2019-06-17 16:47:30 DEBUG    Provisional download folders received
2019-06-17 16:47:31 DEBUG    Subscribing to logging on port 45243
2019-06-17 16:47:31 INFO     Generating 281 thumbnails for EOS_DIGITAL
2019-06-17 16:47:31 DEBUG    Finished phase 1 of thumbnail generation for EOS_DIGITAL
2019-06-17 16:47:31 INFO     281 of 281 thumbnails for EOS_DIGITAL came from thumbnail cache
2019-06-17 16:47:31 DEBUG    Unsubscribing to logging on port 45243
2019-06-17 16:47:31 DEBUG    Thumbnail Manager currently has no workers
2019-06-17 16:47:31 DEBUG    Setting device state for EOS_DIGITAL to idle
2019-06-17 16:47:31 INFO     Finished thumbnail generation for EOS_DIGITAL
2019-06-17 16:47:31 DEBUG    Resetting progress bar
2019-06-17 16:47:31 DEBUG    -- Thumbnail Model --
2019-06-17 16:47:31 DEBUG    281 thumbnails (0 marked)
2019-06-17 16:47:31 DEBUG    281 not downloaded; 0 downloaded; 281 previously downloaded
2019-06-17 16:47:31 DEBUG    Active devices: EOS_DIGITAL
2019-06-17 16:47:32 DEBUG    Close event activated
2019-06-17 16:47:32 DEBUG    Scan Manager halting
2019-06-17 16:47:32 DEBUG    Thumbnail Manager halting
2019-06-17 16:47:32 DEBUG    Scan Manager is terminating
2019-06-17 16:47:32 DEBUG    Thumbnail Manager is terminating
2019-06-17 16:47:32 DEBUG    Thumbnail load balancer sending stop cmd to worker Thumbnail-Extractor-4
2019-06-17 16:47:32 DEBUG    Thumbnail load balancer sending stop cmd to worker Thumbnail-Extractor-3
2019-06-17 16:47:32 DEBUG    Thumbnail load balancer sending stop cmd to worker Thumbnail-Extractor-0
2019-06-17 16:47:32 DEBUG    Copy Files Manager halting
2019-06-17 16:47:32 DEBUG    Writing window settings
2019-06-17 16:47:32 DEBUG    Cleaning up provisional download folders
2019-06-17 16:47:32 DEBUG    Copy Files Manager is terminating
2019-06-17 16:47:32 DEBUG    Terminating main ExifTool process
2019-06-17 16:47:32 DEBUG    Offload Manager halting
2019-06-17 16:47:32 DEBUG    Offload Manager worker 0 has stopped
2019-06-17 16:47:32 DEBUG    Offload Manager currently has no workers
2019-06-17 16:47:32 DEBUG    Offload Manager is exiting
2019-06-17 16:47:32 DEBUG    Rename and Move File Manager halting
2019-06-17 16:47:32 DEBUG    Rename and Move File Manager worker 0 has stopped
2019-06-17 16:47:32 DEBUG    Rename and Move File Manager currently has no workers
2019-06-17 16:47:32 DEBUG    Rename and Move File Manager is exiting
2019-06-17 16:47:32 DEBUG    Backup Manager halting
2019-06-17 16:47:32 DEBUG    Backup Manager is terminating
2019-06-17 16:47:32 DEBUG    Thumbnail Daemon Manager halting
2019-06-17 16:47:32 DEBUG    Thumbnail Daemon Manager worker 0 has stopped
2019-06-17 16:47:32 DEBUG    Thumbnail Daemon Manager currently has no workers
2019-06-17 16:47:32 DEBUG    Thumbnail Daemon Manager is exiting
2019-06-17 16:47:32 DEBUG    Cleaning any device cache dirs and sample video
2019-06-17 16:47:32 DEBUG    Cleaning up Thumbnail cache
2019-06-17 16:47:32 DEBUG    Accepting close event
2019-06-17 16:47:32 DEBUG    Exiting
4.958u 1.016s 0:07.68 77.6%	0+0k 0+496io 0pf+0w