Scanned images are saved scrambled
Scanning images works properly and the preview in simple scan is correct as seen in the attached screenshot.
Saving the scanned image as a .pdf results in a scrambled image as seen in the attached saved file.Scanned_Document.pdf
Simple scan version is 3.36.2 installed on Manjaro KDE Plasma.
The previous versions of simple scan did not have this issue.
simple-scan --debug [+0.01s] DEBUG: simple-scan.vala:1719: Starting simple-scan 3.36.2, PID=39996 [+0.01s] DEBUG: unsetenv() is not thread-safe and should not be used after threads are created [+0.04s] DEBUG: _g_io_module_get_default: Found default implementation dconf (DConfSettingsBackend) for ‘gsettings-backend’ [+0.06s] DEBUG: app-window.vala:1903: Loading state from /home/drhedberg/.cache/simple-scan/state [+0.06s] DEBUG: app-window.vala:1882: Restoring window to 613x405 pixels [+0.08s] DEBUG: scanner.vala:1485: sane_init () -> SANE_STATUS_GOOD [+0.08s] DEBUG: scanner.vala:1491: SANE version 1.0.29 [+0.08s] DEBUG: scanner.vala:1552: Requesting redetection of scan devices [+0.08s] DEBUG: scanner.vala:828: Processing request [+0.20s] DEBUG: app-window.vala:1978: Saving state to /home/drhedberg/.cache/simple-scan/state [+4.22s] DEBUG: app-window.vala:1978: Saving state to /home/drhedberg/.cache/simple-scan/state [+5.54s] DEBUG: app-window.vala:1978: Saving state to /home/drhedberg/.cache/simple-scan/state [+9.37s] DEBUG: app-window.vala:1978: Saving state to /home/drhedberg/.cache/simple-scan/state [+9.60s] DEBUG: scanner.vala:341: sane_get_devices () -> SANE_STATUS_GOOD [+9.60s] DEBUG: scanner.vala:353: Device: name="escl:https://fe80::c665:16ff:fedb:1ae3:443" vendor="ESCL" model="HP LaserJet Pro M428f-M429f [DB1AE3]" type="flatbed scanner" [+9.60s] DEBUG: scanner.vala:353: Device: name="escl:http://192.168.1.203:8080" vendor="ESCL" model="HP LaserJet Pro M428f-M429f [DB1AE3]" type="flatbed scanner" [+9.60s] DEBUG: scanner.vala:353: Device: name="escl:https://192.168.1.203:443" vendor="ESCL" model="HP LaserJet Pro M428f-M429f [DB1AE3]" type="flatbed scanner" [+9.60s] DEBUG: scanner.vala:353: Device: name="hpaio:/net/HP_LaserJet_Pro_M428f-M429f?ip=192.168.1.203" vendor="Hewlett-Packard" model="HP_LaserJet_Pro_M428f-M429f" type="all-in-one" [+9.70s] DEBUG: app-window.vala:1978: Saving state to /home/drhedberg/.cache/simple-scan/state [+23.52s] DEBUG: app-window.vala:1978: Saving state to /home/drhedberg/.cache/simple-scan/state [+25.90s] DEBUG: app-window.vala:1978: Saving state to /home/drhedberg/.cache/simple-scan/state [+26.16s] DEBUG: simple-scan.vala:1536: Requesting scan at 300 dpi from device 'hpaio:/net/HP_LaserJet_Pro_M428f-M429f?ip=192.168.1.203' [+26.16s] DEBUG: scanner.vala:1620: Scanner.scan ("hpaio:/net/HP_LaserJet_Pro_M428f-M429f?ip=192.168.1.203", dpi=300, scan_mode=ScanMode.GRAY, depth=8, type=adf-front, paper_width=2159, paper_height=2794, brightness=0, contrast=0, delay=3000ms) [+26.16s] DEBUG: scanner.vala:828: Processing request [+26.29s] DEBUG: scanner.vala:889: sane_open ("hpaio:/net/HP_LaserJet_Pro_M428f-M429f?ip=192.168.1.203") -> SANE_STATUS_GOOD [+26.29s] DEBUG: scanner.vala:910: sane_get_option_descriptor (0) [+26.29s] DEBUG: scanner.vala:760: Option 0: name='option-cnt' title='Number of options' type=int size=4 cap=soft-detect [+26.29s] DEBUG: scanner.vala:763: Description: Read-only option that specifies how many options a specific devices supports. [+26.29s] DEBUG: scanner.vala:910: sane_get_option_descriptor (1) [+26.29s] DEBUG: scanner.vala:760: Option 1: name='mode-group' title='Scan mode' type=group size=0 [+26.29s] DEBUG: scanner.vala:910: sane_get_option_descriptor (2) [+26.29s] DEBUG: scanner.vala:760: Option 2: name='mode' title='Scan mode' type=string size=32 values=["Lineart", "Gray", "Color"] cap=soft-select,soft-detect [+26.29s] DEBUG: scanner.vala:763: Description: Selects the scan mode (e.g., lineart, monochrome, or color). [+26.29s] DEBUG: scanner.vala:910: sane_get_option_descriptor (3) [+26.29s] DEBUG: scanner.vala:760: Option 3: name='resolution' title='Scan resolution' type=int size=4 unit=dpi values=[75, 100, 150, 200, 300] cap=soft-select,soft-detect [+26.29s] DEBUG: scanner.vala:763: Description: Sets the resolution of the scanned image. [+26.29s] DEBUG: scanner.vala:910: sane_get_option_descriptor (4) [+26.29s] DEBUG: scanner.vala:760: Option 4: name='source' title='Scan source' type=string size=32 values=["Flatbed", "ADF", "Duplex"] cap=soft-select,soft-detect [+26.29s] DEBUG: scanner.vala:763: Description: Selects the scan source (such as a document-feeder). [+26.29s] DEBUG: scanner.vala:910: sane_get_option_descriptor (5) [+26.29s] DEBUG: scanner.vala:760: Option 5: name='advanced-group' title='Advanced' type=group size=0 cap=advanced [+26.29s] DEBUG: scanner.vala:910: sane_get_option_descriptor (6) [+26.29s] DEBUG: scanner.vala:760: Option 6: name='(null)' title='(null)' type=bool size=0 [+26.29s] DEBUG: scanner.vala:910: sane_get_option_descriptor (7) [+26.29s] DEBUG: scanner.vala:760: Option 7: name='compression' title='Compression' type=string size=32 values=["JPEG"] cap=soft-select,soft-detect,advanced [+26.29s] DEBUG: scanner.vala:763: Description: Selects the scanner compression method for faster scans, possibly at the expense of image quality. [+26.29s] DEBUG: scanner.vala:910: sane_get_option_descriptor (8) [+26.29s] DEBUG: scanner.vala:760: Option 8: name='jpeg-quality' title='JPEG compression factor' type=int size=4 min=0, max=100, quant=0 cap=soft-select,soft-detect,inactive,advanced [+26.29s] DEBUG: scanner.vala:763: Description: Sets the scanner JPEG compression factor. Larger numbers mean better compression, and smaller numbers mean better image quality. [+26.29s] DEBUG: scanner.vala:910: sane_get_option_descriptor (9) [+26.29s] DEBUG: scanner.vala:760: Option 9: name='geometry-group' title='Geometry' type=group size=0 cap=advanced [+26.29s] DEBUG: scanner.vala:910: sane_get_option_descriptor (10) [+26.29s] DEBUG: scanner.vala:760: Option 10: name='tl-x' title='Top-left x' type=fixed size=4 unit=mm min=0.000000, max=215.900009, quant=0 cap=soft-select,soft-detect [+26.29s] DEBUG: scanner.vala:763: Description: Top-left x position of scan area. [+26.29s] DEBUG: scanner.vala:910: sane_get_option_descriptor (11) [+26.29s] DEBUG: scanner.vala:760: Option 11: name='tl-y' title='Top-left y' type=fixed size=4 unit=mm min=0.000000, max=297.010681, quant=0 cap=soft-select,soft-detect [+26.29s] DEBUG: scanner.vala:763: Description: Top-left y position of scan area. [+26.29s] DEBUG: scanner.vala:910: sane_get_option_descriptor (12) [+26.29s] DEBUG: scanner.vala:760: Option 12: name='br-x' title='Bottom-right x' type=fixed size=4 unit=mm min=0.000000, max=215.900009, quant=0 cap=soft-select,soft-detect [+26.29s] DEBUG: scanner.vala:763: Description: Bottom-right x position of scan area. [+26.29s] DEBUG: scanner.vala:910: sane_get_option_descriptor (13) [+26.29s] DEBUG: scanner.vala:760: Option 13: name='br-y' title='Bottom-right y' type=fixed size=4 unit=mm min=0.000000, max=297.010681, quant=0 cap=soft-select,soft-detect [+26.29s] DEBUG: scanner.vala:763: Description: Bottom-right y position of scan area. [+26.29s] DEBUG: scanner.vala:910: sane_get_option_descriptor (14) [+26.29s] DEBUG: scanner.vala:760: Option 14: name='multi-pick' title='Multi pick' type=int size=4 min=0, max=1, quant=0 cap=soft-select,soft-detect,advanced [+26.29s] DEBUG: scanner.vala:763: Description: Select multipick option for ESCL protocal based devices [+26.29s] DEBUG: scanner.vala:910: sane_get_option_descriptor (15) [+26.29s] DEBUG: scanner.vala:760: Option 15: name='(null)' title='(null)' type=bool size=0 [+26.29s] DEBUG: scanner.vala:910: sane_get_option_descriptor (16) [+26.29s] DEBUG: scanner.vala:571: sane_control_option (4, SANE_ACTION_SET_VALUE, "ADF") -> (SANE_STATUS_GOOD, "ADF") [+26.29s] DEBUG: scanner.vala:571: sane_control_option (2, SANE_ACTION_SET_VALUE, "Gray") -> (SANE_STATUS_GOOD, "Gray") [+26.29s] DEBUG: scanner.vala:483: sane_control_option (3, SANE_ACTION_SET_VALUE, 300) -> (SANE_STATUS_GOOD, 300) [+26.29s] DEBUG: scanner.vala:523: sane_control_option (12, SANE_ACTION_SET_VALUE, 215.900000) -> (SANE_STATUS_GOOD, 215.899994) [+26.29s] DEBUG: scanner.vala:523: sane_control_option (13, SANE_ACTION_SET_VALUE, 279.400000) -> (SANE_STATUS_GOOD, 279.399994) [+26.49s] DEBUG: app-window.vala:1978: Saving state to /home/drhedberg/.cache/simple-scan/state [+42.47s] DEBUG: scanner.vala:1285: sane_start (page=0, pass=0) -> SANE_STATUS_GOOD [+42.47s] DEBUG: scanner.vala:1312: sane_get_parameters () -> SANE_STATUS_GOOD [+42.47s] DEBUG: scanner.vala:1324: Parameters: format=SANE_FRAME_GRAY last_frame=SANE_TRUE bytes_per_line=2550 pixels_per_line=2550 lines=3300 depth=8 [+42.47s] DEBUG: scanner.vala:1395: sane_read (2551) -> (SANE_STATUS_GOOD, 2551) [+42.47s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.47s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.47s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.47s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.47s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.47s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.47s] DEBUG: simple-scan.vala:1431: Page is 2550 pixels wide, 3300 pixels high, 8 bits per pixel [+42.47s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.47s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.47s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.47s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.47s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.47s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.47s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.47s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.47s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.47s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.47s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.47s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.47s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.47s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.47s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.47s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.47s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.47s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.47s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.48s] DEBUG: simple-scan.vala:1365: Getting color profile for device hpaio:/net/HP_LaserJet_Pro_M428f-M429f?ip=192.168.1.203 [+42.48s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.48s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.48s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.48s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.48s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.48s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.48s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.48s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.48s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.48s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.48s] DEBUG: simple-scan.vala:1385: Unable to find colord device hpaio:/net/HP_LaserJet_Pro_M428f-M429f?ip=192.168.1.203: property match 'Serial'='sane:hpaio:/net/HP_LaserJet_Pro_M428f-M429f?ip=192.168.1.203' does not exist [+42.48s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.48s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.48s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.48s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.48s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.48s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.48s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.48s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.48s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.48s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.48s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.48s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.48s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.48s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.48s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.48s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.48s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.53s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.53s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.53s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.53s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.53s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.53s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.53s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.53s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.53s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.53s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.53s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.53s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.53s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.53s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.53s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.53s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.53s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.53s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.53s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.53s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.53s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.53s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.53s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.54s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.54s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.54s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.54s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.54s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.54s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.54s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.54s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.54s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.54s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.54s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.54s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.54s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.54s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.54s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.54s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.54s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.54s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.54s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.54s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.54s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.54s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.54s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.54s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.54s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.59s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.59s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.59s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.59s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.59s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.59s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.59s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.59s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.59s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.59s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.59s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.60s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.60s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.60s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.60s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.60s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.60s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.60s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.60s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.60s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.60s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.60s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.60s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.60s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.60s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.60s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.60s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.60s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.60s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.60s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.60s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.60s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.60s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.65s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.65s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.65s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.65s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.65s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.65s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.65s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.65s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.65s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.66s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.66s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.66s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.66s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.66s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.66s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.66s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.66s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.66s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.66s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.66s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.66s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.66s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.66s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.66s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.66s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.66s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.66s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.66s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.66s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.66s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.66s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.66s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.66s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.66s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.66s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.66s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.66s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.66s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.66s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.66s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.66s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.66s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.66s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.66s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.66s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.66s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.66s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.66s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.66s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.66s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.67s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.72s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.73s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.73s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.73s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.73s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.73s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.73s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.73s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.73s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.73s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.73s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.73s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.73s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.73s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.73s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.73s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.73s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.73s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.73s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.73s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.73s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.73s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.73s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.73s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.73s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.78s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.78s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.78s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.78s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.78s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.78s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.78s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.78s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.78s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.78s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.78s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.78s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.78s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.78s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.78s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.78s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.78s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.78s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.78s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.78s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.78s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.78s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.78s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.78s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.78s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.78s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.78s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.78s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.78s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.78s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.78s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.78s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.78s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.78s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.78s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.78s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.78s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.78s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.78s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.78s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.78s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.78s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.78s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.78s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.78s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.78s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.78s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.78s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.79s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.79s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.79s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.79s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.79s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.79s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.79s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.79s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.79s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.79s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.79s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.79s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.79s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.79s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.79s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.79s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5100) [+42.79s] DEBUG: scanner.vala:1395: sane_read (5100) -> (SANE_STATUS_GOOD, 5099) [+42.79s] DEBUG: scanner.vala:1395: sane_read (5101) -> (SANE_STATUS_GOOD, 5101) [+42.79s] DEBUG: scanner.vala:1395: sane_read (7650) -> (SANE_STATUS_GOOD, 7650) [+42.79s] DEBUG: scanner.vala:1395: sane_read (7650) -> (SANE_STATUS_GOOD, 7650) [+42.79s] DEBUG: scanner.vala:1395: sane_read (7650) -> (SANE_STATUS_GOOD, 7650) [+42.79s] DEBUG: scanner.vala:1395: sane_read (7650) -> (SANE_STATUS_GOOD, 7650) [+42.79s] DEBUG: scanner.vala:1395: sane_read (7650) -> (SANE_STATUS_GOOD, 7650) [+42.79s] DEBUG: scanner.vala:1395: sane_read (7650) -> (SANE_STATUS_GOOD, 7650) [+42.79s] DEBUG: scanner.vala:1395: sane_read (7650) -> (SANE_STATUS_GOOD, 7650) [+42.79s] DEBUG: scanner.vala:1395: sane_read (7650) -> (SANE_STATUS_GOOD, 7650) [+42.79s] DEBUG: scanner.vala:1395: sane_read (7650) -> (SANE_STATUS_GOOD, 7650) [+42.79s] DEBUG: scanner.vala:1395: sane_read (7650) -> (SANE_STATUS_GOOD, 7650) [+42.79s] DEBUG: scanner.vala:1395: sane_read (7650) -> (SANE_STATUS_GOOD, 7650) [+42.79s] DEBUG: scanner.vala:1395: sane_read (7650) -> (SANE_STATUS_GOOD, 7650) [+42.79s] DEBUG: scanner.vala:1395: sane_read (7650) -> (SANE_STATUS_GOOD, 7650) [+42.79s] DEBUG: scanner.vala:1395: sane_read (7650) -> (SANE_STATUS_GOOD, 7650) [+42.84s] DEBUG: scanner.vala:1395: sane_read (7650) -> (SANE_STATUS_GOOD, 7650) [+42.84s] DEBUG: scanner.vala:1395: sane_read (7650) -> (SANE_STATUS_GOOD, 7650) [+42.84s] DEBUG: scanner.vala:1395: sane_read (7650) -> (SANE_STATUS_GOOD, 7650) [+42.84s] DEBUG: scanner.vala:1395: sane_read (7650) -> (SANE_STATUS_GOOD, 7650) [+42.84s] DEBUG: scanner.vala:1395: sane_read (7650) -> (SANE_STATUS_GOOD, 7650) [+42.84s] DEBUG: scanner.vala:1395: sane_read (7650) -> (SANE_STATUS_GOOD, 7650) [+42.84s] DEBUG: scanner.vala:1395: sane_read (7650) -> (SANE_STATUS_GOOD, 7650) [+42.84s] DEBUG: scanner.vala:1395: sane_read (7650) -> (SANE_STATUS_GOOD, 7650) [+42.84s] DEBUG: scanner.vala:1395: sane_read (7650) -> (SANE_STATUS_GOOD, 7650) [+42.84s] DEBUG: scanner.vala:1395: sane_read (7650) -> (SANE_STATUS_GOOD, 7650) [+42.84s] DEBUG: scanner.vala:1395: sane_read (7650) -> (SANE_STATUS_GOOD, 7650) [+42.84s] DEBUG: scanner.vala:1395: sane_read (7650) -> (SANE_STATUS_GOOD, 7649) [+42.84s] DEBUG: scanner.vala:1395: sane_read (7651) -> (SANE_STATUS_GOOD, 7651) [+42.84s] DEBUG: scanner.vala:1395: sane_read (10200) -> (SANE_STATUS_GOOD, 10200) [+42.84s] DEBUG: scanner.vala:1395: sane_read (10200) -> (SANE_STATUS_GOOD, 10200) [+42.84s] DEBUG: scanner.vala:1395: sane_read (10200) -> (SANE_STATUS_GOOD, 10200) [+42.84s] DEBUG: scanner.vala:1395: sane_read (10200) -> (SANE_STATUS_GOOD, 10200) [+42.84s] DEBUG: scanner.vala:1395: sane_read (10200) -> (SANE_STATUS_GOOD, 10200) [+42.84s] DEBUG: scanner.vala:1395: sane_read (10200) -> (SANE_STATUS_GOOD, 10200) [+42.84s] DEBUG: scanner.vala:1395: sane_read (10200) -> (SANE_STATUS_GOOD, 10200) [+42.84s] DEBUG: scanner.vala:1395: sane_read (10200) -> (SANE_STATUS_GOOD, 10200) [+42.84s] DEBUG: scanner.vala:1395: sane_read (10200) -> (SANE_STATUS_GOOD, 10200) [+42.84s] DEBUG: scanner.vala:1395: sane_read (10200) -> (SANE_STATUS_GOOD, 10200) [+42.84s] DEBUG: scanner.vala:1395: sane_read (10200) -> (SANE_STATUS_GOOD, 10200) [+42.84s] DEBUG: scanner.vala:1395: sane_read (10200) -> (SANE_STATUS_GOOD, 10200) [+42.84s] DEBUG: scanner.vala:1395: sane_read (10200) -> (SANE_STATUS_GOOD, 10200) [+42.84s] DEBUG: scanner.vala:1395: sane_read (10200) -> (SANE_STATUS_GOOD, 10200) [+42.84s] DEBUG: scanner.vala:1395: sane_read (10200) -> (SANE_STATUS_GOOD, 10200) [+42.84s] DEBUG: scanner.vala:1395: sane_read (10200) -> (SANE_STATUS_GOOD, 10200) [+42.84s] DEBUG: scanner.vala:1395: sane_read (10200) -> (SANE_STATUS_GOOD, 10200) [+42.84s] DEBUG: scanner.vala:1395: sane_read (10200) -> (SANE_STATUS_GOOD, 10200) [+42.84s] DEBUG: scanner.vala:1395: sane_read (10200) -> (SANE_STATUS_GOOD, 10200) [+42.84s] DEBUG: scanner.vala:1395: sane_read (10200) -> (SANE_STATUS_GOOD, 10200) [+42.84s] DEBUG: scanner.vala:1395: sane_read (10200) -> (SANE_STATUS_GOOD, 10199) [+42.84s] DEBUG: scanner.vala:1395: sane_read (10201) -> (SANE_STATUS_GOOD, 10201) [+42.90s] DEBUG: scanner.vala:1395: sane_read (12750) -> (SANE_STATUS_GOOD, 12750) [+42.90s] DEBUG: scanner.vala:1395: sane_read (12750) -> (SANE_STATUS_GOOD, 12750) [+42.90s] DEBUG: scanner.vala:1395: sane_read (12750) -> (SANE_STATUS_GOOD, 12750) [+42.90s] DEBUG: scanner.vala:1395: sane_read (12750) -> (SANE_STATUS_GOOD, 12750) [+42.90s] DEBUG: scanner.vala:1395: sane_read (12750) -> (SANE_STATUS_GOOD, 12750) [+42.90s] DEBUG: scanner.vala:1395: sane_read (12750) -> (SANE_STATUS_GOOD, 12750) [+42.90s] DEBUG: scanner.vala:1395: sane_read (12750) -> (SANE_STATUS_GOOD, 12750) [+42.90s] DEBUG: scanner.vala:1395: sane_read (12750) -> (SANE_STATUS_GOOD, 12750) [+42.90s] DEBUG: scanner.vala:1395: sane_read (12750) -> (SANE_STATUS_GOOD, 12750) [+42.90s] DEBUG: scanner.vala:1395: sane_read (12750) -> (SANE_STATUS_GOOD, 12750) [+42.90s] DEBUG: scanner.vala:1395: sane_read (12750) -> (SANE_STATUS_GOOD, 12750) [+42.90s] DEBUG: scanner.vala:1395: sane_read (12750) -> (SANE_STATUS_GOOD, 12750) [+42.90s] DEBUG: scanner.vala:1395: sane_read (12750) -> (SANE_STATUS_GOOD, 12750) [+42.90s] DEBUG: scanner.vala:1395: sane_read (12750) -> (SANE_STATUS_GOOD, 12750) [+42.90s] DEBUG: scanner.vala:1395: sane_read (12750) -> (SANE_STATUS_GOOD, 12750) [+42.90s] DEBUG: scanner.vala:1395: sane_read (12750) -> (SANE_STATUS_GOOD, 12750) [+42.90s] DEBUG: scanner.vala:1395: sane_read (12750) -> (SANE_STATUS_GOOD, 12749) [+42.90s] DEBUG: scanner.vala:1395: sane_read (12751) -> (SANE_STATUS_GOOD, 12751) [+42.90s] DEBUG: scanner.vala:1395: sane_read (15300) -> (SANE_STATUS_GOOD, 15300) [+42.90s] DEBUG: scanner.vala:1395: sane_read (15300) -> (SANE_STATUS_GOOD, 15300) [+42.90s] DEBUG: scanner.vala:1395: sane_read (15300) -> (SANE_STATUS_GOOD, 15300) [+42.90s] DEBUG: scanner.vala:1395: sane_read (15300) -> (SANE_STATUS_GOOD, 15300) [+42.90s] DEBUG: scanner.vala:1395: sane_read (15300) -> (SANE_STATUS_GOOD, 15300) [+42.90s] DEBUG: scanner.vala:1395: sane_read (15300) -> (SANE_STATUS_GOOD, 15300) [+42.96s] DEBUG: scanner.vala:1395: sane_read (15300) -> (SANE_STATUS_GOOD, 15300) [+42.96s] DEBUG: scanner.vala:1395: sane_read (15300) -> (SANE_STATUS_GOOD, 15300) [+42.96s] DEBUG: scanner.vala:1395: sane_read (15300) -> (SANE_STATUS_GOOD, 15300) [+42.96s] DEBUG: scanner.vala:1395: sane_read (15300) -> (SANE_STATUS_GOOD, 15300) [+42.96s] DEBUG: scanner.vala:1395: sane_read (15300) -> (SANE_STATUS_GOOD, 15300) [+42.96s] DEBUG: scanner.vala:1395: sane_read (15300) -> (SANE_STATUS_GOOD, 15300) [+42.96s] DEBUG: scanner.vala:1395: sane_read (15300) -> (SANE_STATUS_GOOD, 5099) [+42.96s] DEBUG: scanner.vala:1395: sane_read (15301) -> (SANE_STATUS_GOOD, 15301) [+42.96s] DEBUG: scanner.vala:1395: sane_read (17850) -> (SANE_STATUS_GOOD, 17850) [+42.96s] DEBUG: scanner.vala:1395: sane_read (17850) -> (SANE_STATUS_GOOD, 17850) [+42.96s] DEBUG: scanner.vala:1395: sane_read (17850) -> (SANE_STATUS_GOOD, 17850) [+42.96s] DEBUG: scanner.vala:1395: sane_read (17850) -> (SANE_STATUS_GOOD, 17850) [+42.96s] DEBUG: scanner.vala:1395: sane_read (17850) -> (SANE_STATUS_GOOD, 17850) [+42.96s] DEBUG: scanner.vala:1395: sane_read (17850) -> (SANE_STATUS_GOOD, 17850) [+42.96s] DEBUG: scanner.vala:1395: sane_read (17850) -> (SANE_STATUS_GOOD, 17850) [+42.96s] DEBUG: scanner.vala:1395: sane_read (17850) -> (SANE_STATUS_GOOD, 17850) [+43.02s] DEBUG: scanner.vala:1395: sane_read (17850) -> (SANE_STATUS_GOOD, 17850) [+43.02s] DEBUG: scanner.vala:1395: sane_read (17850) -> (SANE_STATUS_GOOD, 17850) [+43.02s] DEBUG: scanner.vala:1395: sane_read (17850) -> (SANE_STATUS_GOOD, 17850) [+43.02s] DEBUG: scanner.vala:1395: sane_read (17850) -> (SANE_STATUS_GOOD, 17850) [+43.02s] DEBUG: scanner.vala:1395: sane_read (17850) -> (SANE_STATUS_GOOD, 17850) [+43.02s] DEBUG: scanner.vala:1395: sane_read (17850) -> (SANE_STATUS_GOOD, 17850) [+43.08s] DEBUG: scanner.vala:1395: sane_read (17850) -> (SANE_STATUS_GOOD, 17850) [+43.08s] DEBUG: scanner.vala:1395: sane_read (17850) -> (SANE_STATUS_GOOD, 17850) [+43.08s] DEBUG: scanner.vala:1395: sane_read (17850) -> (SANE_STATUS_GOOD, 17850) [+43.08s] DEBUG: scanner.vala:1395: sane_read (17850) -> (SANE_STATUS_GOOD, 17850) [+43.08s] DEBUG: scanner.vala:1395: sane_read (17850) -> (SANE_STATUS_GOOD, 17850) [+43.08s] DEBUG: scanner.vala:1395: sane_read (17850) -> (SANE_STATUS_GOOD, 17850) [+43.08s] DEBUG: scanner.vala:1395: sane_read (17850) -> (SANE_STATUS_GOOD, 17850) [+43.08s] DEBUG: scanner.vala:1395: sane_read (17850) -> (SANE_STATUS_GOOD, 17850) [+43.08s] DEBUG: scanner.vala:1395: sane_read (17850) -> (SANE_STATUS_GOOD, 17850) [+43.08s] DEBUG: scanner.vala:1395: sane_read (17850) -> (SANE_STATUS_GOOD, 17850) [+43.08s] DEBUG: scanner.vala:1395: sane_read (17850) -> (SANE_STATUS_GOOD, 17850) [+43.08s] DEBUG: scanner.vala:1395: sane_read (17850) -> (SANE_STATUS_GOOD, 17850) [+43.08s] DEBUG: scanner.vala:1395: sane_read (17850) -> (SANE_STATUS_GOOD, 17850) [+43.08s] DEBUG: scanner.vala:1395: sane_read (17850) -> (SANE_STATUS_GOOD, 17850) [+43.08s] DEBUG: scanner.vala:1395: sane_read (17850) -> (SANE_STATUS_GOOD, 17850) [+43.08s] DEBUG: scanner.vala:1395: sane_read (17850) -> (SANE_STATUS_GOOD, 17850) [+43.08s] DEBUG: scanner.vala:1395: sane_read (17850) -> (SANE_STATUS_GOOD, 17850) [+43.08s] DEBUG: scanner.vala:1395: sane_read (17850) -> (SANE_STATUS_GOOD, 17850) [+43.08s] DEBUG: scanner.vala:1395: sane_read (17850) -> (SANE_STATUS_GOOD, 17850) [+43.08s] DEBUG: scanner.vala:1395: sane_read (17850) -> (SANE_STATUS_GOOD, 17850) [+43.08s] DEBUG: scanner.vala:1395: sane_read (17850) -> (SANE_STATUS_GOOD, 17850) [+43.08s] DEBUG: scanner.vala:1395: sane_read (17850) -> (SANE_STATUS_GOOD, 17850) [+43.09s] DEBUG: scanner.vala:1395: sane_read (17850) -> (SANE_STATUS_GOOD, 17850) [+43.09s] DEBUG: scanner.vala:1395: sane_read (17850) -> (SANE_STATUS_GOOD, 17850) [+43.09s] DEBUG: scanner.vala:1395: sane_read (17850) -> (SANE_STATUS_GOOD, 17850) [+43.14s] DEBUG: scanner.vala:1395: sane_read (17850) -> (SANE_STATUS_GOOD, 17850) [+43.14s] DEBUG: scanner.vala:1395: sane_read (17850) -> (SANE_STATUS_GOOD, 17850) [+43.14s] DEBUG: scanner.vala:1395: sane_read (17850) -> (SANE_STATUS_GOOD, 17850) [+43.14s] DEBUG: scanner.vala:1395: sane_read (17850) -> (SANE_STATUS_GOOD, 12749) [+43.14s] DEBUG: scanner.vala:1395: sane_read (17851) -> (SANE_STATUS_GOOD, 17851) [+43.20s] DEBUG: scanner.vala:1395: sane_read (20400) -> (SANE_STATUS_GOOD, 20400) [+43.20s] DEBUG: scanner.vala:1395: sane_read (20400) -> (SANE_STATUS_GOOD, 20400) [+43.20s] DEBUG: scanner.vala:1395: sane_read (20400) -> (SANE_STATUS_GOOD, 20400) [+43.20s] DEBUG: scanner.vala:1395: sane_read (20400) -> (SANE_STATUS_GOOD, 20400) [+43.20s] DEBUG: scanner.vala:1395: sane_read (20400) -> (SANE_STATUS_GOOD, 20400) [+43.21s] DEBUG: scanner.vala:1395: sane_read (20400) -> (SANE_STATUS_GOOD, 20400) [+43.21s] DEBUG: scanner.vala:1395: sane_read (20400) -> (SANE_STATUS_GOOD, 20400) [+43.21s] DEBUG: scanner.vala:1395: sane_read (20400) -> (SANE_STATUS_GOOD, 20400) [+43.21s] DEBUG: scanner.vala:1395: sane_read (20400) -> (SANE_STATUS_GOOD, 20400) [+43.21s] DEBUG: scanner.vala:1395: sane_read (20400) -> (SANE_STATUS_GOOD, 20400) [+43.21s] DEBUG: scanner.vala:1395: sane_read (20400) -> (SANE_STATUS_GOOD, 20400) [+43.21s] DEBUG: scanner.vala:1395: sane_read (20400) -> (SANE_STATUS_GOOD, 20400) [+43.21s] DEBUG: scanner.vala:1395: sane_read (20400) -> (SANE_STATUS_GOOD, 20400) [+43.21s] DEBUG: scanner.vala:1395: sane_read (20400) -> (SANE_STATUS_GOOD, 20400) [+43.21s] DEBUG: scanner.vala:1395: sane_read (20400) -> (SANE_STATUS_GOOD, 20400) [+43.21s] DEBUG: scanner.vala:1395: sane_read (20400) -> (SANE_STATUS_GOOD, 20400) [+43.21s] DEBUG: scanner.vala:1395: sane_read (20400) -> (SANE_STATUS_GOOD, 20400) [+43.21s] DEBUG: scanner.vala:1395: sane_read (20400) -> (SANE_STATUS_GOOD, 20400) [+43.21s] DEBUG: scanner.vala:1395: sane_read (20400) -> (SANE_STATUS_GOOD, 20400) [+43.21s] DEBUG: scanner.vala:1395: sane_read (20400) -> (SANE_STATUS_GOOD, 20400) [+43.21s] DEBUG: scanner.vala:1395: sane_read (20400) -> (SANE_STATUS_GOOD, 20400) [+43.21s] DEBUG: scanner.vala:1395: sane_read (20400) -> (SANE_STATUS_GOOD, 20400) [+43.21s] DEBUG: scanner.vala:1395: sane_read (20400) -> (SANE_STATUS_GOOD, 20400) [+43.21s] DEBUG: scanner.vala:1395: sane_read (20400) -> (SANE_STATUS_GOOD, 20400) [+43.21s] DEBUG: scanner.vala:1395: sane_read (20400) -> (SANE_STATUS_GOOD, 20400) [+43.21s] DEBUG: scanner.vala:1395: sane_read (20400) -> (SANE_STATUS_GOOD, 20400) [+43.21s] DEBUG: scanner.vala:1395: sane_read (20400) -> (SANE_STATUS_GOOD, 20400) [+43.21s] DEBUG: scanner.vala:1395: sane_read (20400) -> (SANE_STATUS_GOOD, 20400) [+43.21s] DEBUG: scanner.vala:1395: sane_read (20400) -> (SANE_STATUS_GOOD, 20400) [+43.21s] DEBUG: scanner.vala:1395: sane_read (20400) -> (SANE_STATUS_GOOD, 20400) [+43.21s] DEBUG: scanner.vala:1395: sane_read (20400) -> (SANE_STATUS_GOOD, 20400) [+43.21s] DEBUG: scanner.vala:1395: sane_read (20400) -> (SANE_STATUS_GOOD, 20400) [+43.21s] DEBUG: scanner.vala:1395: sane_read (20400) -> (SANE_STATUS_GOOD, 20400) [+43.21s] DEBUG: scanner.vala:1395: sane_read (20400) -> (SANE_STATUS_GOOD, 20400) [+43.21s] DEBUG: scanner.vala:1395: sane_read (20400) -> (SANE_STATUS_GOOD, 20400) [+43.21s] DEBUG: scanner.vala:1395: sane_read (20400) -> (SANE_STATUS_GOOD, 20400) [+43.21s] DEBUG: scanner.vala:1395: sane_read (20400) -> (SANE_STATUS_GOOD, 20400) [+43.21s] DEBUG: scanner.vala:1395: sane_read (20400) -> (SANE_STATUS_GOOD, 20399) [+43.26s] DEBUG: scanner.vala:1395: sane_read (20401) -> (SANE_STATUS_GOOD, 20401) [+43.26s] DEBUG: scanner.vala:1395: sane_read (22950) -> (SANE_STATUS_GOOD, 22950) [+43.27s] DEBUG: scanner.vala:1395: sane_read (22950) -> (SANE_STATUS_GOOD, 22950) [+43.27s] DEBUG: scanner.vala:1395: sane_read (22950) -> (SANE_STATUS_GOOD, 22950) [+43.27s] DEBUG: scanner.vala:1395: sane_read (22950) -> (SANE_STATUS_GOOD, 22950) [+43.27s] DEBUG: scanner.vala:1395: sane_read (22950) -> (SANE_STATUS_GOOD, 22950) [+43.27s] DEBUG: scanner.vala:1395: sane_read (22950) -> (SANE_STATUS_GOOD, 22950) [+43.27s] DEBUG: scanner.vala:1395: sane_read (22950) -> (SANE_STATUS_GOOD, 22950) [+43.27s] DEBUG: scanner.vala:1395: sane_read (22950) -> (SANE_STATUS_GOOD, 5099) [+43.27s] DEBUG: scanner.vala:1395: sane_read (22951) -> (SANE_STATUS_GOOD, 22951) [+43.33s] DEBUG: scanner.vala:1395: sane_read (25500) -> (SANE_STATUS_GOOD, 25500) [+43.33s] DEBUG: scanner.vala:1395: sane_read (25500) -> (SANE_STATUS_GOOD, 25500) [+43.33s] DEBUG: scanner.vala:1395: sane_read (25500) -> (SANE_STATUS_GOOD, 25500) [+43.33s] DEBUG: scanner.vala:1395: sane_read (25500) -> (SANE_STATUS_GOOD, 25500) [+43.33s] DEBUG: scanner.vala:1395: sane_read (25500) -> (SANE_STATUS_GOOD, 25500) [+43.33s] DEBUG: scanner.vala:1395: sane_read (25500) -> (SANE_STATUS_GOOD, 25500) [+43.33s] DEBUG: scanner.vala:1395: sane_read (25500) -> (SANE_STATUS_GOOD, 25500) [+43.33s] DEBUG: scanner.vala:1395: sane_read (25500) -> (SANE_STATUS_GOOD, 22949) [+43.33s] DEBUG: scanner.vala:1395: sane_read (25501) -> (SANE_STATUS_GOOD, 25501) [+43.33s] DEBUG: scanner.vala:1395: sane_read (28050) -> (SANE_STATUS_GOOD, 28050) [+43.33s] DEBUG: scanner.vala:1395: sane_read (28050) -> (SANE_STATUS_GOOD, 28050) [+43.33s] DEBUG: scanner.vala:1395: sane_read (28050) -> (SANE_STATUS_GOOD, 28050) [+43.33s] DEBUG: scanner.vala:1395: sane_read (28050) -> (SANE_STATUS_GOOD, 28050) [+43.33s] DEBUG: scanner.vala:1395: sane_read (28050) -> (SANE_STATUS_GOOD, 25499) [+43.39s] DEBUG: scanner.vala:1395: sane_read (28051) -> (SANE_STATUS_GOOD, 28051) [+43.39s] DEBUG: scanner.vala:1395: sane_read (30600) -> (SANE_STATUS_GOOD, 30600) [+43.39s] DEBUG: scanner.vala:1395: sane_read (30600) -> (SANE_STATUS_GOOD, 30600) [+43.39s] DEBUG: scanner.vala:1395: sane_read (30600) -> (SANE_STATUS_GOOD, 30600) [+43.39s] DEBUG: scanner.vala:1395: sane_read (30600) -> (SANE_STATUS_GOOD, 30600) [+43.39s] DEBUG: scanner.vala:1395: sane_read (30600) -> (SANE_STATUS_GOOD, 30600) [+43.39s] DEBUG: scanner.vala:1395: sane_read (30600) -> (SANE_STATUS_GOOD, 30600) [+43.39s] DEBUG: scanner.vala:1395: sane_read (30600) -> (SANE_STATUS_GOOD, 30600) [+43.39s] DEBUG: scanner.vala:1395: sane_read (30600) -> (SANE_STATUS_GOOD, 30600) [+43.39s] DEBUG: scanner.vala:1395: sane_read (30600) -> (SANE_STATUS_GOOD, 30600) [+43.39s] DEBUG: scanner.vala:1395: sane_read (30600) -> (SANE_STATUS_GOOD, 22949) [+43.39s] DEBUG: scanner.vala:1395: sane_read (30601) -> (SANE_STATUS_GOOD, 20400) [+43.45s] DEBUG: scanner.vala:1395: sane_read (30601) -> (SANE_STATUS_GOOD, 30601) [+43.45s] DEBUG: scanner.vala:1395: sane_read (33150) -> (SANE_STATUS_GOOD, 33150) [+43.45s] DEBUG: scanner.vala:1395: sane_read (33150) -> (SANE_STATUS_GOOD, 33150) [+43.45s] DEBUG: scanner.vala:1395: sane_read (33150) -> (SANE_STATUS_GOOD, 33150) [+43.45s] DEBUG: scanner.vala:1395: sane_read (33150) -> (SANE_STATUS_GOOD, 33150) [+43.45s] DEBUG: scanner.vala:1395: sane_read (33150) -> (SANE_STATUS_GOOD, 20399) [+43.45s] DEBUG: scanner.vala:1395: sane_read (33151) -> (SANE_STATUS_GOOD, 33151) [+43.45s] DEBUG: scanner.vala:1395: sane_read (35700) -> (SANE_STATUS_GOOD, 35700) [+43.51s] DEBUG: scanner.vala:1395: sane_read (35700) -> (SANE_STATUS_GOOD, 35700) [+43.51s] DEBUG: scanner.vala:1395: sane_read (35700) -> (SANE_STATUS_GOOD, 35700) [+43.51s] DEBUG: scanner.vala:1395: sane_read (35700) -> (SANE_STATUS_GOOD, 35700) [+43.51s] DEBUG: scanner.vala:1395: sane_read (35700) -> (SANE_STATUS_GOOD, 35700) [+43.51s] DEBUG: scanner.vala:1395: sane_read (35700) -> (SANE_STATUS_GOOD, 35700) [+43.57s] DEBUG: scanner.vala:1395: sane_read (35700) -> (SANE_STATUS_GOOD, 35700) [+43.57s] DEBUG: scanner.vala:1395: sane_read (35700) -> (SANE_STATUS_GOOD, 35700) [+43.57s] DEBUG: scanner.vala:1395: sane_read (35700) -> (SANE_STATUS_GOOD, 35700) [+43.57s] DEBUG: scanner.vala:1395: sane_read (35700) -> (SANE_STATUS_GOOD, 35700) [+43.57s] DEBUG: scanner.vala:1395: sane_read (35700) -> (SANE_STATUS_GOOD, 35700) [+43.57s] DEBUG: scanner.vala:1395: sane_read (35700) -> (SANE_STATUS_GOOD, 35700) [+43.57s] DEBUG: scanner.vala:1395: sane_read (35700) -> (SANE_STATUS_GOOD, 35700) [+43.57s] DEBUG: scanner.vala:1395: sane_read (35700) -> (SANE_STATUS_GOOD, 35700) [+43.57s] DEBUG: scanner.vala:1395: sane_read (35700) -> (SANE_STATUS_GOOD, 35700) [+43.57s] DEBUG: scanner.vala:1395: sane_read (35700) -> (SANE_STATUS_GOOD, 35700) [+43.57s] DEBUG: scanner.vala:1395: sane_read (35700) -> (SANE_STATUS_GOOD, 7649) [+43.57s] DEBUG: scanner.vala:1395: sane_read (35701) -> (SANE_STATUS_GOOD, 17850) [+43.57s] DEBUG: scanner.vala:1395: sane_read (35701) -> (SANE_STATUS_GOOD, 20400) [+43.63s] DEBUG: scanner.vala:1395: sane_read (35701) -> (SANE_STATUS_GOOD, 22950) [+43.63s] DEBUG: scanner.vala:1395: sane_read (35701) -> (SANE_STATUS_GOOD, 35701) [+43.63s] DEBUG: scanner.vala:1395: sane_read (38250) -> (SANE_STATUS_GOOD, 38250) [+43.63s] DEBUG: scanner.vala:1395: sane_read (38250) -> (SANE_STATUS_GOOD, 38250) [+43.63s] DEBUG: scanner.vala:1395: sane_read (38250) -> (SANE_STATUS_GOOD, 38250) [+43.63s] DEBUG: scanner.vala:1395: sane_read (38250) -> (SANE_STATUS_GOOD, 38250) [+43.63s] DEBUG: scanner.vala:1395: sane_read (38250) -> (SANE_STATUS_GOOD, 38250) [+43.63s] DEBUG: scanner.vala:1395: sane_read (38250) -> (SANE_STATUS_GOOD, 38250) [+43.63s] DEBUG: scanner.vala:1395: sane_read (38250) -> (SANE_STATUS_GOOD, 38250) [+43.63s] DEBUG: scanner.vala:1395: sane_read (38250) -> (SANE_STATUS_GOOD, 22949) [+43.63s] DEBUG: scanner.vala:1395: sane_read (38251) -> (SANE_STATUS_GOOD, 38251) [+43.63s] DEBUG: scanner.vala:1395: sane_read (40800) -> (SANE_STATUS_GOOD, 40800) [+43.69s] DEBUG: scanner.vala:1395: sane_read (40800) -> (SANE_STATUS_GOOD, 40800) [+43.69s] DEBUG: scanner.vala:1395: sane_read (40800) -> (SANE_STATUS_GOOD, 40800) [+43.69s] DEBUG: scanner.vala:1395: sane_read (40800) -> (SANE_STATUS_GOOD, 40800) [+43.69s] DEBUG: scanner.vala:1395: sane_read (40800) -> (SANE_STATUS_GOOD, 40800) [+43.69s] DEBUG: scanner.vala:1395: sane_read (40800) -> (SANE_STATUS_GOOD, 40800) [+43.69s] DEBUG: scanner.vala:1395: sane_read (40800) -> (SANE_STATUS_GOOD, 40800) [+43.69s] DEBUG: scanner.vala:1395: sane_read (40800) -> (SANE_STATUS_GOOD, 40800) [+43.69s] DEBUG: scanner.vala:1395: sane_read (40800) -> (SANE_STATUS_GOOD, 22949) [+43.70s] DEBUG: scanner.vala:1395: sane_read (40801) -> (SANE_STATUS_GOOD, 40801) [+43.70s] DEBUG: scanner.vala:1395: sane_read (43350) -> (SANE_STATUS_GOOD, 40799) [+43.70s] DEBUG: scanner.vala:1395: sane_read (43351) -> (SANE_STATUS_GOOD, 40800) [+43.75s] DEBUG: scanner.vala:1395: sane_read (43351) -> (SANE_STATUS_GOOD, 40800) [+43.75s] DEBUG: scanner.vala:1395: sane_read (43351) -> (SANE_STATUS_GOOD, 40800) [+43.75s] DEBUG: scanner.vala:1395: sane_read (43351) -> (SANE_STATUS_GOOD, 20400) [+43.75s] DEBUG: scanner.vala:1395: sane_read (43351) -> (SANE_STATUS_GOOD, 43351) [+43.81s] DEBUG: scanner.vala:1395: sane_read (45900) -> (SANE_STATUS_GOOD, 45900) [+43.81s] DEBUG: scanner.vala:1395: sane_read (45900) -> (SANE_STATUS_GOOD, 45900) [+43.81s] DEBUG: scanner.vala:1395: sane_read (45900) -> (SANE_STATUS_GOOD, 45900) [+43.82s] DEBUG: scanner.vala:1395: sane_read (45900) -> (SANE_STATUS_GOOD, 45900) [+43.82s] DEBUG: scanner.vala:1395: sane_read (45900) -> (SANE_STATUS_GOOD, 45900) [+43.82s] DEBUG: scanner.vala:1395: sane_read (45900) -> (SANE_STATUS_GOOD, 10199) [+43.82s] DEBUG: scanner.vala:1395: sane_read (45901) -> (SANE_STATUS_GOOD, 22950) [+43.82s] DEBUG: scanner.vala:1395: sane_read (45901) -> (SANE_STATUS_GOOD, 40800) [+43.82s] DEBUG: scanner.vala:1395: sane_read (45901) -> (SANE_STATUS_GOOD, 20400) [+43.82s] DEBUG: scanner.vala:1395: sane_read (45901) -> (SANE_STATUS_GOOD, 38250) [+43.82s] DEBUG: scanner.vala:1395: sane_read (45901) -> (SANE_STATUS_GOOD, 43350) [+43.87s] DEBUG: scanner.vala:1395: sane_read (45901) -> (SANE_STATUS_GOOD, 40800) [+43.87s] DEBUG: scanner.vala:1395: sane_read (45901) -> (SANE_STATUS_GOOD, 45901) [+43.94s] DEBUG: scanner.vala:1395: sane_read (48450) -> (SANE_STATUS_GOOD, 48450) [+43.94s] DEBUG: scanner.vala:1395: sane_read (48450) -> (SANE_STATUS_GOOD, 48450) [+43.94s] DEBUG: scanner.vala:1395: sane_read (48450) -> (SANE_STATUS_GOOD, 48450) [+43.94s] DEBUG: scanner.vala:1395: sane_read (48450) -> (SANE_STATUS_GOOD, 48450) [+43.98s] DEBUG: scanner.vala:1395: sane_read (48450) -> (SANE_STATUS_GOOD, 48450) [+43.98s] DEBUG: scanner.vala:1395: sane_read (48450) -> (SANE_STATUS_GOOD, 48450) [+43.98s] DEBUG: scanner.vala:1395: sane_read (48450) -> (SANE_STATUS_GOOD, 48450) [+43.98s] DEBUG: scanner.vala:1395: sane_read (48450) -> (SANE_STATUS_GOOD, 48450) [+43.98s] DEBUG: scanner.vala:1395: sane_read (48450) -> (SANE_STATUS_GOOD, 48450) [+43.98s] DEBUG: scanner.vala:1395: sane_read (48450) -> (SANE_STATUS_GOOD, 48450) [+43.98s] DEBUG: scanner.vala:1395: sane_read (48450) -> (SANE_STATUS_GOOD, 48450) [+43.98s] DEBUG: scanner.vala:1395: sane_read (48450) -> (SANE_STATUS_GOOD, 48450) [+43.98s] DEBUG: scanner.vala:1395: sane_read (48450) -> (SANE_STATUS_GOOD, 48450) [+43.98s] DEBUG: scanner.vala:1395: sane_read (48450) -> (SANE_STATUS_GOOD, 48450) [+43.98s] DEBUG: scanner.vala:1395: sane_read (48450) -> (SANE_STATUS_GOOD, 48450) [+43.98s] DEBUG: scanner.vala:1395: sane_read (48450) -> (SANE_STATUS_GOOD, 48450) [+43.99s] DEBUG: scanner.vala:1395: sane_read (48450) -> (SANE_STATUS_GOOD, 35699) [+43.99s] DEBUG: scanner.vala:1395: sane_read (48451) -> (SANE_STATUS_GOOD, 20400) [+43.99s] DEBUG: scanner.vala:1395: sane_read (48451) -> (SANE_STATUS_GOOD, 48451) [+43.99s] DEBUG: scanner.vala:1395: sane_read (51000) -> (SANE_STATUS_GOOD, 51000) [+43.99s] DEBUG: scanner.vala:1395: sane_read (51000) -> (SANE_STATUS_GOOD, 50999) [+44.09s] DEBUG: scanner.vala:1395: sane_read (51001) -> (SANE_STATUS_EOF, 0) [+44.09s] DEBUG: autosave-manager.vala:355: Autosaving page pixels to /home/drhedberg/.cache/simple-scan/autosaves/2739390048.pixels [+44.10s] DEBUG: autosave-manager.vala:287: Autosaving book information [+44.10s] DEBUG: autosave-manager.vala:304: Autosaving page page-0 [+44.10s] CRITICAL: page_finish: assertion 'self != NULL' failed [+48.71s] DEBUG: scanner.vala:1285: sane_start (page=1, pass=0) -> SANE_STATUS_NO_DOCS [+49.83s] DEBUG: scanner.vala:1258: sane_cancel () [+50.14s] DEBUG: app-window.vala:1978: Saving state to /home/drhedberg/.cache/simple-scan/state [+57.87s] DEBUG: app-window.vala:1978: Saving state to /home/drhedberg/.cache/simple-scan/state [+121.35s] DEBUG: app-window.vala:1978: Saving state to /home/drhedberg/.cache/simple-scan/state [+124.85s] DEBUG: app-window.vala:1978: Saving state to /home/drhedberg/.cache/simple-scan/state [+144.19s] DEBUG: app-window.vala:1978: Saving state to /home/drhedberg/.cache/simple-scan/state [+146.21s] DEBUG: app-window.vala:1978: Saving state to /home/drhedberg/.cache/simple-scan/state [+753.98s] DEBUG: app-window.vala:1978: Saving state to /home/drhedberg/.cache/simple-scan/state [+755.78s] DEBUG: app-window.vala:1978: Saving state to /home/drhedberg/.cache/simple-scan/state [+757.19s] DEBUG: app-window.vala:1978: Saving state to /home/drhedberg/.cache/simple-scan/state