[+0.00s] DEBUG: simple-scan.vala:596: Starting Simple Scan 3.12.1, PID=4049
[+0.00s] DEBUG: Connecting to session manager
[+0.02s] DEBUG: ui.vala:1648: Loading state from /home/cadz/.cache/simple-scan/state
[+0.02s] DEBUG: ui.vala:1629: Restoring window to 600x372 pixels
[+0.02s] DEBUG: autosave-manager.vala:64: Loading autosave information
[+0.02s] DEBUG: autosave-manager.vala:258: Waiting to autosave...
[+0.02s] WARNING: autosave-manager.vala:76: Could not load autosave infomation; not restoring any autosaves
[+0.04s] DEBUG: scanner.vala:1443: sane_init () -> SANE_STATUS_GOOD
[+0.04s] DEBUG: scanner.vala:1449: SANE version 1.0.23
[+0.04s] DEBUG: scanner.vala:1510: Requesting redetection of scan devices
[+0.04s] DEBUG: scanner.vala:802: Processing request
[+0.12s] DEBUG: autosave-manager.vala:280: Autosaving book information
[+0.16s] DEBUG: ui.vala:1739: Saving state to /home/cadz/.cache/simple-scan/state
[+3.63s] DEBUG: scanner.vala:338: sane_get_devices () -> SANE_STATUS_GOOD
[+3.63s] DEBUG: scanner.vala:350: Device: name="plustek:libusb:003:005" vendor="Canon" model="CanoScan LiDE25" type="flatbed scanner"
[+10.61s] DEBUG: simple-scan.vala:310: Requesting scan at 300 dpi from device 'plustek:libusb:003:005'
[+10.61s] DEBUG: scanner.vala:1556: Scanner.scan ("plustek:libusb:003:005", dpi=300, scan_mode=ScanMode.GRAY, depth=2, type=ScanType.SINGLE, paper_width=2100, paper_height=2970, brightness=0, contrast=0)
[+10.61s] DEBUG: scanner.vala:802: Processing request
[+70.62s] DEBUG: scanner.vala:863: sane_open ("plustek:libusb:003:005") -> SANE_STATUS_GOOD
[+70.62s] DEBUG: scanner.vala:884: sane_get_option_descriptor (0)
[+70.62s] DEBUG: scanner.vala:734: Option 0: title='Number of options' type=int size=4 cap=,soft-detect
[+70.62s] DEBUG: scanner.vala:737:   Description: Read-only option that specifies how many options a specific devices supports.
[+70.62s] DEBUG: scanner.vala:884: sane_get_option_descriptor (1)
[+70.62s] DEBUG: scanner.vala:734: Option 1: name='(null)' title='Scan Mode' type=group size=4
[+70.62s] DEBUG: scanner.vala:737:   Description: 
[+70.62s] DEBUG: scanner.vala:884: sane_get_option_descriptor (2)
[+70.62s] DEBUG: scanner.vala:734: Option 2: name='mode' title='Scan mode' type=string size=8 values=["Lineart", "Gray", "Color"] cap=,soft-select,soft-detect
[+70.62s] DEBUG: scanner.vala:737:   Description: Selects the scan mode (e.g., lineart, monochrome, or color).
[+70.62s] DEBUG: scanner.vala:884: sane_get_option_descriptor (3)
[+70.62s] DEBUG: scanner.vala:734: Option 3: name='depth' title='Bit depth' type=int size=4 unit=bits values=[8, 16] cap=,soft-select,soft-detect
[+70.62s] DEBUG: scanner.vala:737:   Description: Number of bits per sample, typical values are 1 for "line-art" and 8 for multibit scans.
[+70.62s] DEBUG: scanner.vala:884: sane_get_option_descriptor (4)
[+70.62s] DEBUG: scanner.vala:734: Option 4: name='source' title='Scan source' type=string size=13 values=["Normal", "Transparency", "Negative"] cap=,soft-select,soft-detect,inactive
[+70.62s] DEBUG: scanner.vala:737:   Description: Selects the scan source (such as a document-feeder).
[+70.62s] DEBUG: scanner.vala:884: sane_get_option_descriptor (5)
[+70.62s] DEBUG: scanner.vala:734: Option 5: name='resolution' title='Scan resolution' type=int size=4 unit=dpi min=50, max=2400, quant=0 cap=,soft-select,soft-detect
[+70.62s] DEBUG: scanner.vala:737:   Description: Sets the resolution of the scanned image.
[+70.62s] DEBUG: scanner.vala:884: sane_get_option_descriptor (6)
[+70.62s] DEBUG: scanner.vala:734: Option 6: name='preview' title='Preview' type=bool size=4 cap=,soft-select,soft-detect
[+70.62s] DEBUG: scanner.vala:737:   Description: Request a preview-quality scan.
[+70.62s] DEBUG: scanner.vala:884: sane_get_option_descriptor (7)
[+70.62s] DEBUG: scanner.vala:734: Option 7: name='(null)' title='Geometry' type=group size=4 cap=,advanced
[+70.62s] DEBUG: scanner.vala:737:   Description: 
[+70.62s] DEBUG: scanner.vala:884: sane_get_option_descriptor (8)
[+70.62s] DEBUG: scanner.vala:734: Option 8: name='tl-x' title='Top-left x' type=fixed size=4 unit=mm min=0.000000, max=215.000000, quant=0 cap=,soft-select,soft-detect
[+70.62s] DEBUG: scanner.vala:737:   Description: Top-left x position of scan area.
[+70.62s] DEBUG: scanner.vala:884: sane_get_option_descriptor (9)
[+70.62s] DEBUG: scanner.vala:734: Option 9: name='tl-y' title='Top-left y' type=fixed size=4 unit=mm min=0.000000, max=297.000000, quant=0 cap=,soft-select,soft-detect
[+70.62s] DEBUG: scanner.vala:737:   Description: Top-left y position of scan area.
[+70.62s] DEBUG: scanner.vala:884: sane_get_option_descriptor (10)
[+70.62s] DEBUG: scanner.vala:734: Option 10: name='br-x' title='Bottom-right x' type=fixed size=4 unit=mm min=0.000000, max=215.000000, quant=0 cap=,soft-select,soft-detect
[+70.62s] DEBUG: scanner.vala:737:   Description: Bottom-right x position of scan area.
[+70.62s] DEBUG: scanner.vala:884: sane_get_option_descriptor (11)
[+70.62s] DEBUG: scanner.vala:734: Option 11: name='br-y' title='Bottom-right y' type=fixed size=4 unit=mm min=0.000000, max=297.000000, quant=0 cap=,soft-select,soft-detect
[+70.62s] DEBUG: scanner.vala:737:   Description: Bottom-right y position of scan area.
[+70.62s] DEBUG: scanner.vala:884: sane_get_option_descriptor (12)
[+70.62s] DEBUG: scanner.vala:734: Option 12: name='(null)' title='Enhancement' type=group size=4
[+70.62s] DEBUG: scanner.vala:737:   Description: 
[+70.62s] DEBUG: scanner.vala:884: sane_get_option_descriptor (13)
[+70.62s] DEBUG: scanner.vala:734: Option 13: name='brightness' title='Brightness' type=fixed size=4 unit=percent min=-100.000000, max=100.000000, quant=65536 cap=,soft-select,soft-detect
[+70.62s] DEBUG: scanner.vala:737:   Description: Controls the brightness of the acquired image.
[+70.62s] DEBUG: scanner.vala:884: sane_get_option_descriptor (14)
[+70.62s] DEBUG: scanner.vala:734: Option 14: name='contrast' title='Contrast' type=fixed size=4 unit=percent min=-100.000000, max=100.000000, quant=65536 cap=,soft-select,soft-detect
[+70.62s] DEBUG: scanner.vala:737:   Description: Controls the contrast of the acquired image.
[+70.62s] DEBUG: scanner.vala:884: sane_get_option_descriptor (15)
[+70.62s] DEBUG: scanner.vala:734: Option 15: name='custom-gamma' title='Use custom gamma table' type=bool size=4 cap=,soft-select,soft-detect
[+70.62s] DEBUG: scanner.vala:737:   Description: Determines whether a builtin or a custom gamma-table should be used.
[+70.62s] DEBUG: scanner.vala:884: sane_get_option_descriptor (16)
[+70.62s] DEBUG: scanner.vala:734: Option 16: name='gamma-table' title='Image intensity' type=int size=16384 min=0, max=255, quant=0 cap=,soft-select,soft-detect,inactive
[+70.62s] DEBUG: scanner.vala:737:   Description: Gamma-correction table.  In color mode this option equally affects the red, green, and blue channels simultaneously (i.e., it is an intensity gamma table).
[+70.62s] DEBUG: scanner.vala:884: sane_get_option_descriptor (17)
[+70.62s] DEBUG: scanner.vala:734: Option 17: name='red-gamma-table' title='Red intensity' type=int size=16384 min=0, max=255, quant=0 cap=,soft-select,soft-detect,inactive
[+70.62s] DEBUG: scanner.vala:737:   Description: Gamma-correction table for the red band.
[+70.62s] DEBUG: scanner.vala:884: sane_get_option_descriptor (18)
[+70.62s] DEBUG: scanner.vala:734: Option 18: name='green-gamma-table' title='Green intensity' type=int size=16384 min=0, max=255, quant=0 cap=,soft-select,soft-detect,inactive
[+70.62s] DEBUG: scanner.vala:737:   Description: Gamma-correction table for the green band.
[+70.62s] DEBUG: scanner.vala:884: sane_get_option_descriptor (19)
[+70.62s] DEBUG: scanner.vala:734: Option 19: name='blue-gamma-table' title='Blue intensity' type=int size=16384 min=0, max=255, quant=0 cap=,soft-select,soft-detect,inactive
[+70.62s] DEBUG: scanner.vala:737:   Description: Gamma-correction table for the blue band.
[+70.62s] DEBUG: scanner.vala:884: sane_get_option_descriptor (20)
[+70.62s] DEBUG: scanner.vala:734: Option 20: name='(null)' title='Device-Settings' type=group size=4
[+70.62s] DEBUG: scanner.vala:737:   Description: 
[+70.62s] DEBUG: scanner.vala:884: sane_get_option_descriptor (21)
[+70.62s] DEBUG: scanner.vala:734: Option 21: name='lamp-switch' title='Lampswitch' type=bool size=4 cap=,soft-select,soft-detect
[+70.62s] DEBUG: scanner.vala:737:   Description: Manually switching the lamp(s).
[+70.62s] DEBUG: scanner.vala:884: sane_get_option_descriptor (22)
[+70.62s] DEBUG: scanner.vala:734: Option 22: name='lampoff-time' title='Lampoff-time' type=int size=4 min=0, max=999, quant=1 cap=,soft-select,soft-detect
[+70.62s] DEBUG: scanner.vala:737:   Description: Lampoff-time in seconds.
[+70.62s] DEBUG: scanner.vala:884: sane_get_option_descriptor (23)
[+70.62s] DEBUG: scanner.vala:734: Option 23: name='lamp-off-at-exit' title='Lamp off at exit' type=bool size=4 cap=,soft-select,soft-detect
[+70.62s] DEBUG: scanner.vala:737:   Description: Turn off lamp when program exits
[+70.62s] DEBUG: scanner.vala:884: sane_get_option_descriptor (24)
[+70.62s] DEBUG: scanner.vala:734: Option 24: name='warmup-time' title='Warmup-time' type=int size=4 min=-1, max=999, quant=1 cap=,soft-select,soft-detect,inactive
[+70.62s] DEBUG: scanner.vala:737:   Description: Warmup-time in seconds.
[+70.62s] DEBUG: scanner.vala:884: sane_get_option_descriptor (25)
[+70.62s] DEBUG: scanner.vala:734: Option 25: name='lamp-off-during-dcal' title='Lamp off during dark calibration' type=bool size=4 cap=,soft-select,soft-detect
[+70.62s] DEBUG: scanner.vala:737:   Description: Always switches lamp off when doing dark calibration.
[+70.62s] DEBUG: scanner.vala:884: sane_get_option_descriptor (26)
[+70.62s] DEBUG: scanner.vala:734: Option 26: name='calibration-cache' title='Calibration data cache' type=bool size=4 cap=,soft-select,soft-detect
[+70.62s] DEBUG: scanner.vala:737:   Description: Enables or disables calibration data cache.
[+70.62s] DEBUG: scanner.vala:884: sane_get_option_descriptor (27)
[+70.62s] DEBUG: scanner.vala:734: Option 27: name='speedup-switch' title='Speedup sensor' type=bool size=4 cap=,soft-select,soft-detect,inactive
[+70.62s] DEBUG: scanner.vala:737:   Description: Enables or disables speeding up sensor movement.
[+70.62s] DEBUG: scanner.vala:884: sane_get_option_descriptor (28)
[+70.62s] DEBUG: scanner.vala:734: Option 28: name='calibrate' title='Calibrate' type=button size=4 cap=,soft-select,soft-detect,automatic,inactive
[+70.62s] DEBUG: scanner.vala:737:   Description: Performs calibration
[+70.62s] DEBUG: scanner.vala:884: sane_get_option_descriptor (29)
[+70.62s] DEBUG: scanner.vala:734: Option 29: name='(null)' title='Analog frontend' type=group size=4 cap=,advanced
[+70.62s] DEBUG: scanner.vala:737:   Description: 
[+70.62s] DEBUG: scanner.vala:884: sane_get_option_descriptor (30)
[+70.62s] DEBUG: scanner.vala:734: Option 30: name='red-gain' title='Red gain' type=int size=4 min=-1, max=63, quant=1 cap=,soft-select,soft-detect
[+70.62s] DEBUG: scanner.vala:737:   Description: Red gain value of the AFE
[+70.62s] DEBUG: scanner.vala:884: sane_get_option_descriptor (31)
[+70.62s] DEBUG: scanner.vala:734: Option 31: name='green-gain' title='Green gain' type=int size=4 min=-1, max=63, quant=1 cap=,soft-select,soft-detect
[+70.62s] DEBUG: scanner.vala:737:   Description: Green gain value of the AFE
[+70.62s] DEBUG: scanner.vala:884: sane_get_option_descriptor (32)
[+70.62s] DEBUG: scanner.vala:734: Option 32: name='blue-gain' title='Blue gain' type=int size=4 min=-1, max=63, quant=1 cap=,soft-select,soft-detect
[+70.62s] DEBUG: scanner.vala:737:   Description: Blue gain value of the AFE
[+70.62s] DEBUG: scanner.vala:884: sane_get_option_descriptor (33)
[+70.62s] DEBUG: scanner.vala:734: Option 33: name='red-offset' title='Red offset' type=int size=4 min=-1, max=63, quant=1 cap=,soft-select,soft-detect
[+70.62s] DEBUG: scanner.vala:737:   Description: Red offset value of the AFE
[+70.62s] DEBUG: scanner.vala:884: sane_get_option_descriptor (34)
[+70.62s] DEBUG: scanner.vala:734: Option 34: name='green-offset' title='Green offset' type=int size=4 min=-1, max=63, quant=1 cap=,soft-select,soft-detect
[+70.62s] DEBUG: scanner.vala:737:   Description: Green offset value of the AFE
[+70.62s] DEBUG: scanner.vala:884: sane_get_option_descriptor (35)
[+70.62s] DEBUG: scanner.vala:734: Option 35: name='blue-offset' title='Blue offset' type=int size=4 min=-1, max=63, quant=1 cap=,soft-select,soft-detect
[+70.62s] DEBUG: scanner.vala:737:   Description: Blue offset value of the AFE
[+70.62s] DEBUG: scanner.vala:884: sane_get_option_descriptor (36)
[+70.62s] DEBUG: scanner.vala:734: Option 36: name='redlamp-off' title='Red lamp off' type=int size=4 min=-1, max=16363, quant=1 cap=,soft-select,soft-detect
[+70.62s] DEBUG: scanner.vala:737:   Description: Defines red lamp off parameter
[+70.62s] DEBUG: scanner.vala:884: sane_get_option_descriptor (37)
[+70.62s] DEBUG: scanner.vala:734: Option 37: name='greenlamp-off' title='Green lamp off' type=int size=4 min=-1, max=16363, quant=1 cap=,soft-select,soft-detect
[+70.62s] DEBUG: scanner.vala:737:   Description: Defines green lamp off parameter
[+70.62s] DEBUG: scanner.vala:884: sane_get_option_descriptor (38)
[+70.62s] DEBUG: scanner.vala:734: Option 38: name='bluelamp-off' title='Blue lamp off' type=int size=4 min=-1, max=16363, quant=1 cap=,soft-select,soft-detect
[+70.62s] DEBUG: scanner.vala:737:   Description: Defines blue lamp off parameter
[+70.62s] DEBUG: scanner.vala:884: sane_get_option_descriptor (39)
[+70.62s] DEBUG: scanner.vala:734: Option 39: name='(null)' title='Buttons' type=group size=4 cap=,advanced
[+70.62s] DEBUG: scanner.vala:737:   Description: 
[+70.62s] DEBUG: scanner.vala:884: sane_get_option_descriptor (40)
[+70.62s] DEBUG: scanner.vala:734: Option 40: name='button 0' title='Scanner button 0' type=bool size=4 cap=,hard-select,soft-detect,advanced
[+70.62s] DEBUG: scanner.vala:737:   Description: This option reflects the status of the scanner buttons.
[+70.62s] DEBUG: scanner.vala:884: sane_get_option_descriptor (41)
[+70.62s] DEBUG: scanner.vala:734: Option 41: name='button 1' title='Scanner button 1' type=bool size=4 cap=,hard-select,soft-detect,advanced
[+70.62s] DEBUG: scanner.vala:737:   Description: This option reflects the status of the scanner buttons.
[+70.62s] DEBUG: scanner.vala:884: sane_get_option_descriptor (42)
[+70.62s] DEBUG: scanner.vala:734: Option 42: name='button 2' title='Scanner button 2' type=bool size=4 cap=,hard-select,soft-detect,advanced
[+70.62s] DEBUG: scanner.vala:737:   Description: This option reflects the status of the scanner buttons.
[+70.62s] DEBUG: scanner.vala:884: sane_get_option_descriptor (43)
[+70.62s] DEBUG: scanner.vala:734: Option 43: name='button 3' title='Scanner button 3' type=bool size=4 cap=,hard-select,soft-detect,inactive,advanced
[+70.62s] DEBUG: scanner.vala:737:   Description: This option reflects the status of the scanner buttons.
[+70.62s] DEBUG: scanner.vala:884: sane_get_option_descriptor (44)
[+70.62s] DEBUG: scanner.vala:734: Option 44: name='button 4' title='Scanner button 4' type=bool size=4 cap=,hard-select,soft-detect,inactive,advanced
[+70.62s] DEBUG: scanner.vala:737:   Description: This option reflects the status of the scanner buttons.
[+70.62s] DEBUG: scanner.vala:884: sane_get_option_descriptor (45)
[+70.62s] DEBUG: scanner.vala:895: SCAN_SOURCE not available, trying alternative "doc-source"
[+70.62s] DEBUG: scanner.vala:546: sane_control_option (2, SANE_ACTION_SET_VALUE, "Gray") -> (SANE_STATUS_GOOD, "Gray")
[+70.62s] DEBUG: scanner.vala:462: sane_control_option (5, SANE_ACTION_SET_VALUE, 300) -> (SANE_STATUS_GOOD, 300)
[+70.62s] DEBUG: scanner.vala:462: sane_control_option (3, SANE_ACTION_SET_VALUE, 2) -> (SANE_STATUS_GOOD, 8)
[+70.62s] DEBUG: scanner.vala:502: sane_control_option (10, SANE_ACTION_SET_VALUE, 210.000000) -> (SANE_STATUS_GOOD, 210.000000)
[+70.62s] DEBUG: scanner.vala:502: sane_control_option (11, SANE_ACTION_SET_VALUE, 297.000000) -> (SANE_STATUS_GOOD, 297.000000)
[+100.62s] DEBUG: scanner.vala:1209: sane_start (page=0, pass=0) -> SANE_STATUS_IO_ERROR
[+100.62s] WARNING: scanner.vala:1216: Unable to start device: Error during device I/O
[+100.62s] DEBUG: scanner.vala:764: sane_cancel ()
[+100.62s] DEBUG: scanner.vala:767: sane_close ()
[+100.72s] DEBU