Log when the database was created: [+0.00s] DEBUG: simple-scan.vala:582: Starting Simple Scan 3.6.0, PID=11458 [+0.00s] DEBUG: Connecting to session manager [+0.03s] WARNING: g_object_set_valist: invalid object type `GtkAdjustment' for value type `GtkWidget' [+0.03s] DEBUG: ui.vala:1463: Restoring window to 774x607 pixels [+0.03s] DEBUG: autosave-manager.vala:87: creating a new instance of the autosave manager [+0.03s] DEBUG: autosave-manager.vala:220: Executing query " CREATE TABLE IF NOT EXISTS pages ( id integer PRIMARY KEY, process_id integer, page_hash integer, book_hash integer, book_revision integer, page_number integer, dpi integer, width integer, height integer, depth integer, n_channels integer, rowstride integer, color_profile string, crop_x integer, crop_y integer, crop_width integer, crop_height integer, scan_direction integer, pixels binary )" [+0.23s] DEBUG: autosave-manager.vala:117: preparing query " SELECT process_id, book_hash, book_revision FROM pages WHERE NOT process_id IN (11458) LIMIT 1 " [+0.23s] DEBUG: autosave-manager.vala:57: setting book to autosave [+0.23s] DEBUG: autosave-manager.vala:72: connecting to signals of new book [+0.23s] DEBUG: autosave-manager.vala:300: adding an autosave for a new page [+0.23s] DEBUG: autosave-manager.vala:313: Executing query " INSERT INTO pages (process_id, page_hash, book_hash, book_revision) VALUES (11458, 20112144, 1744869936, 0) " [+0.39s] DEBUG: autosave-manager.vala:322: updating the autosave for a page [+0.39s] DEBUG: autosave-manager.vala:351: preparing query " UPDATE pages SET page_number=0, dpi=300, width=2362, height=2362, depth=0, n_channels=0, rowstride=0, crop_x=0, crop_y=0, crop_width=0, crop_height=0, scan_direction=0, color_profile=?1, pixels=?2 WHERE process_id = 11458 AND page_hash = 20112144 AND book_hash = 1744869936 AND book_revision = 0 " [+0.58s] DEBUG: scanner.vala:1419: sane_init () -> SANE_STATUS_GOOD [+0.58s] DEBUG: scanner.vala:1425: SANE version 1.0.23 [+0.58s] DEBUG: scanner.vala:1486: Requesting redetection of scan devices [+0.58s] DEBUG: scanner.vala:776: Processing request [+1.73s] DEBUG: simple-scan.vala:310: Requesting scan at 150 dpi from device 'test' [+1.73s] DEBUG: scanner.vala:1532: Scanner.scan ("test", dpi=150, scan_mode=ScanMode.GRAY, depth=2, type=ScanType.SINGLE, paper_width=0, paper_height=0, brightness=0, contrast=0) [+3.00s] DEBUG: scanner.vala:338: sane_get_devices () -> SANE_STATUS_GOOD [+3.00s] DEBUG: scanner.vala:776: Processing request [+3.00s] DEBUG: scanner.vala:837: sane_open ("test") -> SANE_STATUS_GOOD [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (0) [+3.00s] DEBUG: scanner.vala:708: Option 0: title='Number of options' type=int size=4 cap=,soft-detect [+3.00s] DEBUG: scanner.vala:711: Description: Read-only option that specifies how many options a specific devices supports. [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (1) [+3.00s] DEBUG: scanner.vala:708: Option 1: title='Scan Mode' type=group size=0 [+3.00s] DEBUG: scanner.vala:711: Description: [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (2) [+3.00s] DEBUG: scanner.vala:708: Option 2: name='mode' title='Scan mode' type=string size=6 values=["Gray", "Color"] cap=,soft-select,soft-detect [+3.00s] DEBUG: scanner.vala:711: Description: Selects the scan mode (e.g., lineart, monochrome, or color). [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (3) [+3.00s] DEBUG: scanner.vala:708: Option 3: name='depth' title='Bit depth' type=int size=4 values=[1, 8, 16] cap=,soft-select,soft-detect [+3.00s] DEBUG: scanner.vala:711: Description: Number of bits per sample, typical values are 1 for "line-art" and 8 for multibit scans. [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (4) [+3.00s] DEBUG: scanner.vala:708: Option 4: name='hand-scanner' title='Hand-scanner simulation' type=bool size=4 cap=,soft-select,soft-detect [+3.00s] DEBUG: scanner.vala:711: Description: Simulate a hand-scanner. Hand-scanners do not know the image height a priori. Instead, they return a height of -1. Setting this option allows to test whether a frontend can handle this correctly. This option also enables a fixed width of 11 cm. [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (5) [+3.00s] DEBUG: scanner.vala:708: Option 5: name='three-pass' title='Three-pass simulation' type=bool size=4 cap=,soft-select,soft-detect,inactive [+3.00s] DEBUG: scanner.vala:711: Description: Simulate a three-pass scanner. In color mode, three frames are transmitted. [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (6) [+3.00s] DEBUG: scanner.vala:708: Option 6: name='three-pass-order' title='Set the order of frames' type=string size=4 values=["RGB", "RBG", "GBR", "GRB", "BRG", "BGR"] cap=,soft-select,soft-detect,inactive [+3.00s] DEBUG: scanner.vala:711: Description: Set the order of frames in three-pass color mode. [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (7) [+3.00s] DEBUG: scanner.vala:708: Option 7: name='resolution' title='Scan resolution' type=fixed size=4 unit=dpi min=1.000000, max=1200.000000, quant=65536 cap=,soft-select,soft-detect [+3.00s] DEBUG: scanner.vala:711: Description: Sets the resolution of the scanned image. [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (8) [+3.00s] DEBUG: scanner.vala:708: Option 8: name='source' title='Scan source' type=string size=26 values=["Flatbed", "Automatic Document Feeder"] cap=,soft-select,soft-detect [+3.00s] DEBUG: scanner.vala:711: Description: If Automatic Document Feeder is selected, the feeder will be 'empty' after 10 scans. [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (9) [+3.00s] DEBUG: scanner.vala:708: Option 9: title='Special Options' type=group size=0 [+3.00s] DEBUG: scanner.vala:711: Description: [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (10) [+3.00s] DEBUG: scanner.vala:708: Option 10: name='test-picture' title='Select the test picture' type=string size=14 values=["Solid black", "Solid white", "Color pattern", "Grid"] cap=,soft-select,soft-detect [+3.00s] DEBUG: scanner.vala:711: Description: Select the kind of test picture. Available options: Solid black: fills the whole scan with black. Solid white: fills the whole scan with white. Color pattern: draws various color test patterns depending on the mode. Grid: draws a black/white grid with a width and height of 10 mm per square. [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (11) [+3.00s] DEBUG: scanner.vala:708: Option 11: name='invert-endianess' title='Invert endianness' type=bool size=4 cap=,soft-select,soft-detect,inactive [+3.00s] DEBUG: scanner.vala:711: Description: Exchange upper and lower byte of image data in 16 bit modes. This option can be used to test the 16 bit modes of frontends, e.g. if the frontend uses the correct endianness. [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (12) [+3.00s] DEBUG: scanner.vala:708: Option 12: name='read-limit' title='Read limit' type=bool size=4 cap=,soft-select,soft-detect [+3.00s] DEBUG: scanner.vala:711: Description: Limit the amount of data transferred with each call to sane_read(). [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (13) [+3.00s] DEBUG: scanner.vala:708: Option 13: name='read-limit-size' title='Size of read-limit' type=int size=4 min=1, max=65536, quant=1 cap=,soft-select,soft-detect,inactive [+3.00s] DEBUG: scanner.vala:711: Description: The (maximum) amount of data transferred with each call to sane_read(). [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (14) [+3.00s] DEBUG: scanner.vala:708: Option 14: name='read-delay' title='Read delay' type=bool size=4 cap=,soft-select,soft-detect [+3.00s] DEBUG: scanner.vala:711: Description: Delay the transfer of data to the pipe. [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (15) [+3.00s] DEBUG: scanner.vala:708: Option 15: name='read-delay-duration' title='Duration of read-delay' type=int size=4 unit=microseconds min=1000, max=200000, quant=1000 cap=,soft-select,soft-detect,inactive [+3.00s] DEBUG: scanner.vala:711: Description: How long to wait after transferring each buffer of data through the pipe. [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (16) [+3.00s] DEBUG: scanner.vala:708: Option 16: name='read-return-value' title='Return-value of sane_read' type=string size=26 values=["Default", "SANE_STATUS_UNSUPPORTED", "SANE_STATUS_CANCELLED", "SANE_STATUS_DEVICE_BUSY", "SANE_STATUS_INVAL", "SANE_STATUS_EOF", "SANE_STATUS_JAMMED", "SANE_STATUS_NO_DOCS", "SANE_STATUS_COVER_OPEN", "SANE_STATUS_IO_ERROR", "SANE_STATUS_NO_MEM", "SANE_STATUS_ACCESS_DENIED"] cap=,soft-select,soft-detect [+3.00s] DEBUG: scanner.vala:711: Description: Select the return-value of sane_read(). "Default" is the normal handling for scanning. All other status codes are for testing how the frontend handles them. [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (17) [+3.00s] DEBUG: scanner.vala:708: Option 17: name='ppl-loss' title='Loss of pixels per line' type=int size=4 unit=pixels min=0, max=128, quant=1 cap=,soft-select,soft-detect [+3.00s] DEBUG: scanner.vala:711: Description: The number of pixels that are wasted at the end of each line. [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (18) [+3.00s] DEBUG: scanner.vala:708: Option 18: name='fuzzy-parameters' title='Fuzzy parameters' type=bool size=4 cap=,soft-select,soft-detect [+3.00s] DEBUG: scanner.vala:711: Description: Return fuzzy lines and bytes per line when sane_parameters() is called before sane_start(). [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (19) [+3.00s] DEBUG: scanner.vala:708: Option 19: name='non-blocking' title='Use non-blocking IO' type=bool size=4 cap=,soft-select,soft-detect [+3.00s] DEBUG: scanner.vala:711: Description: Use non-blocking IO for sane_read() if supported by the frontend. [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (20) [+3.00s] DEBUG: scanner.vala:708: Option 20: name='select-fd' title='Offer select file descriptor' type=bool size=4 cap=,soft-select,soft-detect [+3.00s] DEBUG: scanner.vala:711: Description: Offer a select filedescriptor for detecting if sane_read() will return data. [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (21) [+3.00s] DEBUG: scanner.vala:708: Option 21: name='enable-test-options' title='Enable test options' type=bool size=4 cap=,soft-select,soft-detect [+3.00s] DEBUG: scanner.vala:711: Description: Enable various test options. This is for testing the ability of frontends to view and modify all the different SANE option types. [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (22) [+3.00s] DEBUG: scanner.vala:708: Option 22: name='print-options' title='Print options' type=button size=0 cap=,soft-select,soft-detect [+3.00s] DEBUG: scanner.vala:711: Description: Print a list of all options. [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (23) [+3.00s] DEBUG: scanner.vala:708: Option 23: title='Geometry' type=group size=0 [+3.00s] DEBUG: scanner.vala:711: Description: [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (24) [+3.00s] DEBUG: scanner.vala:708: Option 24: name='tl-x' title='Top-left x' type=fixed size=4 unit=mm min=0.000000, max=200.000000, quant=65536 cap=,soft-select,soft-detect [+3.00s] DEBUG: scanner.vala:711: Description: Top-left x position of scan area. [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (25) [+3.00s] DEBUG: scanner.vala:708: Option 25: name='tl-y' title='Top-left y' type=fixed size=4 unit=mm min=0.000000, max=200.000000, quant=65536 cap=,soft-select,soft-detect [+3.00s] DEBUG: scanner.vala:711: Description: Top-left y position of scan area. [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (26) [+3.00s] DEBUG: scanner.vala:708: Option 26: name='br-x' title='Bottom-right x' type=fixed size=4 unit=mm min=0.000000, max=200.000000, quant=65536 cap=,soft-select,soft-detect [+3.00s] DEBUG: scanner.vala:711: Description: Bottom-right x position of scan area. [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (27) [+3.00s] DEBUG: scanner.vala:708: Option 27: name='br-y' title='Bottom-right y' type=fixed size=4 unit=mm min=0.000000, max=200.000000, quant=65536 cap=,soft-select,soft-detect [+3.00s] DEBUG: scanner.vala:711: Description: Bottom-right y position of scan area. [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (28) [+3.00s] DEBUG: scanner.vala:708: Option 28: title='Bool test options' type=group size=0 cap=,advanced [+3.00s] DEBUG: scanner.vala:711: Description: [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (29) [+3.00s] DEBUG: scanner.vala:708: Option 29: name='bool-soft-select-soft-detect' title='(1/6) Bool soft select soft detect' type=bool size=4 cap=,soft-select,soft-detect,inactive,advanced [+3.00s] DEBUG: scanner.vala:711: Description: (1/6) Bool test option that has soft select and soft detect (and advanced) capabilities. That's just a normal bool option. [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (30) [+3.00s] DEBUG: scanner.vala:708: Option 30: name='bool-hard-select-soft-detect' title='(2/6) Bool hard select soft detect' type=bool size=4 cap=,hard-select,soft-detect,inactive,advanced [+3.00s] DEBUG: scanner.vala:711: Description: (2/6) Bool test option that has hard select and soft detect (and advanced) capabilities. That means the option can't be set by the frontend but by the user (e.g. by pressing a button at the device). [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (31) [+3.00s] DEBUG: scanner.vala:708: Option 31: name='bool-hard-select' title='(3/6) Bool hard select' type=bool size=4 cap=,hard-select,inactive,advanced [+3.00s] DEBUG: scanner.vala:711: Description: (3/6) Bool test option that has hard select (and advanced) capabilities. That means the option can't be set by the frontend but by the user (e.g. by pressing a button at the device) and can't be read by the frontend. [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (32) [+3.00s] DEBUG: scanner.vala:708: Option 32: name='bool-soft-detect' title='(4/6) Bool soft detect' type=bool size=4 cap=,soft-detect,inactive,advanced [+3.00s] DEBUG: scanner.vala:711: Description: (4/6) Bool test option that has soft detect (and advanced) capabilities. That means the option is read-only. [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (33) [+3.00s] DEBUG: scanner.vala:708: Option 33: name='bool-soft-select-soft-detect-emulated' title='(5/6) Bool soft select soft detect emulated' type=bool size=4 cap=,soft-select,soft-detect,emulated,inactive,advanced [+3.00s] DEBUG: scanner.vala:711: Description: (5/6) Bool test option that has soft select, soft detect, and emulated (and advanced) capabilities. [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (34) [+3.00s] DEBUG: scanner.vala:708: Option 34: name='bool-soft-select-soft-detect-auto' title='(6/6) Bool soft select soft detect auto' type=bool size=4 cap=,soft-select,soft-detect,automatic,inactive,advanced [+3.00s] DEBUG: scanner.vala:711: Description: (6/6) Bool test option that has soft select, soft detect, and automatic (and advanced) capabilities. This option can be automatically set by the backend. [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (35) [+3.00s] DEBUG: scanner.vala:708: Option 35: title='Int test options' type=group size=0 cap=,advanced [+3.00s] DEBUG: scanner.vala:711: Description: [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (36) [+3.00s] DEBUG: scanner.vala:708: Option 36: name='int' title='(1/6) Int' type=int size=4 cap=,soft-select,soft-detect,inactive,advanced [+3.00s] DEBUG: scanner.vala:711: Description: (1/6) Int test option with no unit and no constraint set. [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (37) [+3.00s] DEBUG: scanner.vala:708: Option 37: name='int-constraint-range' title='(2/6) Int constraint range' type=int size=4 unit=pixels min=4, max=192, quant=2 cap=,soft-select,soft-detect,inactive,advanced [+3.00s] DEBUG: scanner.vala:711: Description: (2/6) Int test option with unit pixel and constraint range set. Minimum is 4, maximum 192, and quant is 2. [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (38) [+3.00s] DEBUG: scanner.vala:708: Option 38: name='int-constraint-word-list' title='(3/6) Int constraint word list' type=int size=4 unit=bits values=[-42, -8, 0, 17, 42, 256, 65536, 16777216, 1073741824] cap=,soft-select,soft-detect,inactive,advanced [+3.00s] DEBUG: scanner.vala:711: Description: (3/6) Int test option with unit bits and constraint word list set. [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (39) [+3.00s] DEBUG: scanner.vala:708: Option 39: name='int-constraint-array' title='(4/6) Int array' type=int size=24 unit=mm cap=,soft-select,soft-detect,inactive,advanced [+3.00s] DEBUG: scanner.vala:711: Description: (4/6) Int test option with unit mm and using an array without constraints. [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (40) [+3.00s] DEBUG: scanner.vala:708: Option 40: name='int-constraint-array-constraint-range' title='(5/6) Int array constraint range' type=int size=24 unit=dpi min=4, max=192, quant=2 cap=,soft-select,soft-detect,inactive,advanced [+3.00s] DEBUG: scanner.vala:711: Description: (5/6) Int test option with unit dpi and using an array with a range constraint. Minimum is 4, maximum 192, and quant is 2. [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (41) [+3.00s] DEBUG: scanner.vala:708: Option 41: name='int-constraint-array-constraint-word-list' title='(6/6) Int array constraint word list' type=int size=24 unit=percent values=[-42, -8, 0, 17, 42, 256, 65536, 16777216, 1073741824] cap=,soft-select,soft-detect,inactive,advanced [+3.00s] DEBUG: scanner.vala:711: Description: (6/6) Int test option with unit percent and using an array with a word list constraint. [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (42) [+3.00s] DEBUG: scanner.vala:708: Option 42: title='Fixed test options' type=group size=0 cap=,advanced [+3.00s] DEBUG: scanner.vala:711: Description: [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (43) [+3.00s] DEBUG: scanner.vala:708: Option 43: name='fixed' title='(1/3) Fixed' type=fixed size=4 cap=,soft-select,soft-detect,inactive,advanced [+3.00s] DEBUG: scanner.vala:711: Description: (1/3) Fixed test option with no unit and no constraint set. [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (44) [+3.00s] DEBUG: scanner.vala:708: Option 44: name='fixed-constraint-range' title='(2/3) Fixed constraint range' type=fixed size=4 unit=microseconds min=-42.169998, max=32767.999893, quant=131072 cap=,soft-select,soft-detect,inactive,advanced [+3.00s] DEBUG: scanner.vala:711: Description: (2/3) Fixed test option with unit microsecond and constraint range set. Minimum is -42.17, maximum 32767.9999, and quant is 2.0. [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (45) [+3.00s] DEBUG: scanner.vala:708: Option 45: name='fixed-constraint-word-list' title='(3/3) Fixed constraint word list' type=fixed size=4 values=[-32.699997, 12.099991, 42.000000, 129.500000] cap=,soft-select,soft-detect,inactive,advanced [+3.00s] DEBUG: scanner.vala:711: Description: (3/3) Fixed test option with no unit and constraint word list set. [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (46) [+3.00s] DEBUG: scanner.vala:708: Option 46: title='String test options' type=group size=0 [+3.00s] DEBUG: scanner.vala:711: Description: [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (47) [+3.00s] DEBUG: scanner.vala:708: Option 47: name='string' title='(1/3) String' type=string size=97 cap=,soft-select,soft-detect,inactive [+3.00s] DEBUG: scanner.vala:711: Description: (1/3) String test option without constraint. [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (48) [+3.00s] DEBUG: scanner.vala:708: Option 48: name='string-constraint-string-list' title='(2/3) String constraint string list' type=string size=84 values=["First entry", "Second entry", "This is the very long third entry. Maybe the frontend has an idea how to display it"] cap=,soft-select,soft-detect,inactive [+3.00s] DEBUG: scanner.vala:711: Description: (2/3) String test option with string list constraint. [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (49) [+3.00s] DEBUG: scanner.vala:708: Option 49: name='string-constraint-long-string-list' title='(3/3) String constraint long string list' type=string size=13 values=["First entry", "Second entry", "3", "4", "5", "6", "7", "8", "9", "10", "11", "12", "13", "14", "15", "16", "17", "18", "19", "20", "21", "22", "23", "24", "25", "26", "27", "28", "29", "30", "31", "32", "33", "34", "35", "36", "37", "38", "39", "40", "41", "42", "43", "44", "45", "46"] cap=,soft-select,soft-detect,inactive [+3.00s] DEBUG: scanner.vala:711: Description: (3/3) String test option with string list constraint. Contains some more entries... [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (50) [+3.00s] DEBUG: scanner.vala:708: Option 50: title='Button test options' type=group size=0 [+3.00s] DEBUG: scanner.vala:711: Description: [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (51) [+3.00s] DEBUG: scanner.vala:708: Option 51: name='button' title='(1/1) Button' type=button size=0 cap=,soft-select,soft-detect,inactive [+3.00s] DEBUG: scanner.vala:711: Description: (1/1) Button test option. Prints some text... [+3.00s] DEBUG: scanner.vala:858: sane_get_option_descriptor (52) [+3.00s] DEBUG: scanner.vala:520: sane_control_option (8, SANE_ACTION_SET_VALUE, "Flatbed") -> (SANE_STATUS_GOOD, "Flatbed") [+3.00s] DEBUG: scanner.vala:520: sane_control_option (2, SANE_ACTION_SET_VALUE, "Gray") -> (SANE_STATUS_GOOD, "Gray") [+3.00s] DEBUG: scanner.vala:502: sane_control_option (7, SANE_ACTION_SET_VALUE, 150.000000) -> (SANE_STATUS_GOOD, 150.000000) [+3.00s] DEBUG: scanner.vala:462: sane_control_option (3, SANE_ACTION_SET_VALUE, 2) -> (SANE_STATUS_GOOD, 1) [+3.00s] DEBUG: scanner.vala:502: sane_control_option (26, SANE_ACTION_SET_VALUE, 200.000000) -> (SANE_STATUS_GOOD, 200.000000) [+3.00s] DEBUG: scanner.vala:502: sane_control_option (27, SANE_ACTION_SET_VALUE, 200.000000) -> (SANE_STATUS_GOOD, 200.000000) [+3.00s] DEBUG: scanner.vala:425: sane_control_option (4, SANE_ACTION_SET_VALUE, SANE_FALSE) -> (SANE_STATUS_GOOD, SANE_FALSE) [+3.00s] DEBUG: scanner.vala:520: sane_control_option (10, SANE_ACTION_SET_VALUE, "Color pattern") -> (SANE_STATUS_GOOD, "Color pattern") [+3.00s] DEBUG: scanner.vala:425: sane_control_option (14, SANE_ACTION_SET_VALUE, SANE_TRUE) -> (SANE_STATUS_GOOD, SANE_TRUE) [+3.00s] DEBUG: scanner.vala:1182: sane_start (page=0, pass=0) -> SANE_STATUS_GOOD [+3.00s] DEBUG: scanner.vala:1199: sane_get_parameters () -> SANE_STATUS_GOOD [+3.00s] DEBUG: scanner.vala:1211: Parameters: format=SANE_FRAME_GRAY last_frame=SANE_TRUE bytes_per_line=148 pixels_per_line=1181 lines=1181 depth=1 [+3.00s] DEBUG: scanner.vala:1286: sane_read (149) -> (SANE_STATUS_GOOD, 149) [+3.00s] DEBUG: scanner.vala:1286: sane_read (296) -> (SANE_STATUS_GOOD, 296) [+3.00s] DEBUG: scanner.vala:1286: sane_read (296) -> (SANE_STATUS_GOOD, 296) [+3.00s] DEBUG: scanner.vala:1286: sane_read (296) -> (SANE_STATUS_GOOD, 296) [+3.00s] DEBUG: scanner.vala:1286: sane_read (296) -> (SANE_STATUS_GOOD, 296) [+3.00s] DEBUG: scanner.vala:1286: sane_read (296) -> (SANE_STATUS_GOOD, 296) [+3.00s] DEBUG: scanner.vala:1286: sane_read (296) -> (SANE_STATUS_GOOD, 296) [+3.00s] DEBUG: scanner.vala:1286: sane_read (296) -> (SANE_STATUS_GOOD, 296) [+3.00s] DEBUG: scanner.vala:1286: sane_read (296) -> (SANE_STATUS_GOOD, 296) [+3.00s] DEBUG: scanner.vala:1286: sane_read (296) -> (SANE_STATUS_GOOD, 296) [+3.00s] DEBUG: scanner.vala:1286: sane_read (296) -> (SANE_STATUS_GOOD, 296) [+3.00s] DEBUG: scanner.vala:1286: sane_read (296) -> (SANE_STATUS_GOOD, 296) [+3.00s] DEBUG: scanner.vala:1286: sane_read (296) -> (SANE_STATUS_GOOD, 296) [+3.00s] DEBUG: scanner.vala:1286: sane_read (296) -> (SANE_STATUS_GOOD, 296) [+3.00s] DEBUG: scanner.vala:1286: sane_read (296) -> (SANE_STATUS_GOOD, 296) [+3.00s] DEBUG: scanner.vala:1286: sane_read (296) -> (SANE_STATUS_GOOD, 296) [+3.00s] DEBUG: scanner.vala:1286: sane_read (296) -> (SANE_STATUS_GOOD, 147) [+3.00s] DEBUG: scanner.vala:1286: sane_read (297) -> (SANE_STATUS_GOOD, 297) [+3.00s] DEBUG: scanner.vala:1286: sane_read (444) -> (SANE_STATUS_GOOD, 444) [+3.00s] DEBUG: scanner.vala:1286: sane_read (444) -> (SANE_STATUS_GOOD, 444) [+3.01s] DEBUG: scanner.vala:1286: sane_read (444) -> (SANE_STATUS_GOOD, 444) [+3.01s] DEBUG: scanner.vala:1286: sane_read (444) -> (SANE_STATUS_GOOD, 444) [+3.01s] DEBUG: scanner.vala:1286: sane_read (444) -> (SANE_STATUS_GOOD, 444) [+3.01s] DEBUG: scanner.vala:1286: sane_read (444) -> (SANE_STATUS_GOOD, 444) [+3.01s] DEBUG: scanner.vala:1286: sane_read (444) -> (SANE_STATUS_GOOD, 444) [+3.01s] DEBUG: scanner.vala:1286: sane_read (444) -> (SANE_STATUS_GOOD, 444) [+3.01s] DEBUG: scanner.vala:1286: sane_read (444) -> (SANE_STATUS_GOOD, 444) [+3.01s] DEBUG: scanner.vala:1286: sane_read (444) -> (SANE_STATUS_GOOD, 443) [+3.01s] DEBUG: scanner.vala:1286: sane_read (445) -> (SANE_STATUS_GOOD, 445) [+3.01s] DEBUG: scanner.vala:1286: sane_read (592) -> (SANE_STATUS_GOOD, 592) [+3.01s] DEBUG: scanner.vala:1286: sane_read (592) -> (SANE_STATUS_GOOD, 592) [+3.01s] DEBUG: scanner.vala:1286: sane_read (592) -> (SANE_STATUS_GOOD, 592) [+3.01s] DEBUG: scanner.vala:1286: sane_read (592) -> (SANE_STATUS_GOOD, 592) [+3.01s] DEBUG: scanner.vala:1286: sane_read (592) -> (SANE_STATUS_GOOD, 592) [+3.01s] DEBUG: scanner.vala:1286: sane_read (592) -> (SANE_STATUS_GOOD, 592) [+3.01s] DEBUG: scanner.vala:1286: sane_read (592) -> (SANE_STATUS_GOOD, 592) [+3.01s] DEBUG: scanner.vala:1286: sane_read (592) -> (SANE_STATUS_GOOD, 147) [+3.01s] DEBUG: scanner.vala:1286: sane_read (593) -> (SANE_STATUS_GOOD, 593) [+3.01s] DEBUG: scanner.vala:1286: sane_read (740) -> (SANE_STATUS_GOOD, 740) [+3.01s] DEBUG: scanner.vala:1286: sane_read (740) -> (SANE_STATUS_GOOD, 740) [+3.01s] DEBUG: scanner.vala:1286: sane_read (740) -> (SANE_STATUS_GOOD, 740) [+3.01s] DEBUG: scanner.vala:1286: sane_read (740) -> (SANE_STATUS_GOOD, 740) [+3.01s] DEBUG: scanner.vala:1286: sane_read (740) -> (SANE_STATUS_GOOD, 740) [+3.01s] DEBUG: scanner.vala:1286: sane_read (740) -> (SANE_STATUS_GOOD, 443) [+3.01s] DEBUG: simple-scan.vala:250: Page is 1181 pixels wide, 1181 pixels high, 1 bits per pixel [+3.01s] DEBUG: autosave-manager.vala:322: updating the autosave for a page [+3.01s] DEBUG: autosave-manager.vala:351: preparing query " UPDATE pages SET page_number=0, dpi=150, width=1181, height=1181, depth=1, n_channels=1, rowstride=148, crop_x=0, crop_y=0, crop_width=0, crop_height=0, scan_direction=0, color_profile=?1, pixels=?2 WHERE process_id = 11458 AND page_hash = 20112144 AND book_hash = 1744869936 AND book_revision = 0 " [+3.01s] DEBUG: scanner.vala:1286: sane_read (741) -> (SANE_STATUS_GOOD, 741) [+3.01s] DEBUG: scanner.vala:1286: sane_read (888) -> (SANE_STATUS_GOOD, 888) [+3.01s] DEBUG: scanner.vala:1286: sane_read (888) -> (SANE_STATUS_GOOD, 888) [+3.01s] DEBUG: scanner.vala:1286: sane_read (888) -> (SANE_STATUS_GOOD, 888) [+3.01s] DEBUG: scanner.vala:1286: sane_read (888) -> (SANE_STATUS_GOOD, 888) [+3.01s] DEBUG: scanner.vala:1286: sane_read (888) -> (SANE_STATUS_GOOD, 443) [+3.01s] DEBUG: scanner.vala:1286: sane_read (889) -> (SANE_STATUS_GOOD, 889) [+3.01s] DEBUG: scanner.vala:1286: sane_read (1036) -> (SANE_STATUS_GOOD, 1036) [+3.01s] DEBUG: scanner.vala:1286: sane_read (1036) -> (SANE_STATUS_GOOD, 1036) [+3.01s] DEBUG: scanner.vala:1286: sane_read (1036) -> (SANE_STATUS_GOOD, 1036) [+3.01s] DEBUG: scanner.vala:1286: sane_read (1036) -> (SANE_STATUS_GOOD, 739) [+3.01s] DEBUG: scanner.vala:1286: sane_read (1037) -> (SANE_STATUS_GOOD, 1037) [+3.01s] DEBUG: scanner.vala:1286: sane_read (1184) -> (SANE_STATUS_GOOD, 1184) [+3.01s] DEBUG: scanner.vala:1286: sane_read (1184) -> (SANE_STATUS_GOOD, 1184) [+3.01s] DEBUG: scanner.vala:1286: sane_read (1184) -> (SANE_STATUS_GOOD, 1184) [+3.01s] DEBUG: scanner.vala:1286: sane_read (1184) -> (SANE_STATUS_GOOD, 147) [+3.01s] DEBUG: scanner.vala:1286: sane_read (1185) -> (SANE_STATUS_GOOD, 1185) [+3.01s] DEBUG: scanner.vala:1286: sane_read (1332) -> (SANE_STATUS_GOOD, 1332) [+3.01s] DEBUG: scanner.vala:1286: sane_read (1332) -> (SANE_STATUS_GOOD, 1332) [+3.01s] DEBUG: scanner.vala:1286: sane_read (1332) -> (SANE_STATUS_GOOD, 887) [+3.01s] DEBUG: scanner.vala:1286: sane_read (1333) -> (SANE_STATUS_GOOD, 1333) [+3.01s] DEBUG: scanner.vala:1286: sane_read (1480) -> (SANE_STATUS_GOOD, 1480) [+3.01s] DEBUG: scanner.vala:1286: sane_read (1480) -> (SANE_STATUS_GOOD, 1480) [+3.01s] DEBUG: scanner.vala:1286: sane_read (1480) -> (SANE_STATUS_GOOD, 443) [+3.01s] DEBUG: scanner.vala:1286: sane_read (1481) -> (SANE_STATUS_GOOD, 1481) [+3.01s] DEBUG: scanner.vala:1286: sane_read (1628) -> (SANE_STATUS_GOOD, 1628) [+3.01s] DEBUG: scanner.vala:1286: sane_read (1628) -> (SANE_STATUS_GOOD, 1627) [+3.02s] DEBUG: scanner.vala:1286: sane_read (1629) -> (SANE_STATUS_GOOD, 1629) [+3.02s] DEBUG: scanner.vala:1286: sane_read (1776) -> (SANE_STATUS_GOOD, 1776) [+3.02s] DEBUG: scanner.vala:1286: sane_read (1776) -> (SANE_STATUS_GOOD, 1331) [+3.02s] DEBUG: scanner.vala:1286: sane_read (1777) -> (SANE_STATUS_GOOD, 1777) [+3.02s] DEBUG: scanner.vala:1286: sane_read (1924) -> (SANE_STATUS_GOOD, 1924) [+3.02s] DEBUG: scanner.vala:1286: sane_read (1924) -> (SANE_STATUS_GOOD, 1035) [+3.02s] DEBUG: scanner.vala:1286: sane_read (1925) -> (SANE_STATUS_GOOD, 1925) [+3.02s] DEBUG: scanner.vala:1286: sane_read (2072) -> (SANE_STATUS_GOOD, 2072) [+3.02s] DEBUG: scanner.vala:1286: sane_read (2072) -> (SANE_STATUS_GOOD, 739) [+3.02s] DEBUG: scanner.vala:1286: sane_read (2073) -> (SANE_STATUS_GOOD, 2073) [+3.02s] DEBUG: scanner.vala:1286: sane_read (2220) -> (SANE_STATUS_GOOD, 2220) [+3.02s] DEBUG: scanner.vala:1286: sane_read (2220) -> (SANE_STATUS_GOOD, 443) [+3.02s] DEBUG: scanner.vala:1286: sane_read (2221) -> (SANE_STATUS_GOOD, 2221) [+3.02s] DEBUG: scanner.vala:1286: sane_read (2368) -> (SANE_STATUS_GOOD, 2368) [+3.02s] DEBUG: scanner.vala:1286: sane_read (2368) -> (SANE_STATUS_GOOD, 147) [+3.02s] DEBUG: scanner.vala:1286: sane_read (2369) -> (SANE_STATUS_GOOD, 2369) [+3.02s] DEBUG: scanner.vala:1286: sane_read (2516) -> (SANE_STATUS_GOOD, 2367) [+3.02s] DEBUG: scanner.vala:1286: sane_read (2517) -> (SANE_STATUS_GOOD, 2517) [+3.02s] DEBUG: scanner.vala:1286: sane_read (2664) -> (SANE_STATUS_GOOD, 2219) [+3.02s] DEBUG: scanner.vala:1286: sane_read (2665) -> (SANE_STATUS_GOOD, 2665) [+3.02s] DEBUG: scanner.vala:1286: sane_read (2812) -> (SANE_STATUS_GOOD, 2071) [+3.02s] DEBUG: scanner.vala:1286: sane_read (2813) -> (SANE_STATUS_GOOD, 2813) [+3.02s] DEBUG: scanner.vala:1286: sane_read (2960) -> (SANE_STATUS_GOOD, 1923) [+3.02s] DEBUG: scanner.vala:1286: sane_read (2961) -> (SANE_STATUS_GOOD, 2961) [+3.02s] DEBUG: scanner.vala:1286: sane_read (3108) -> (SANE_STATUS_GOOD, 1775) [+3.03s] DEBUG: scanner.vala:1286: sane_read (3109) -> (SANE_STATUS_GOOD, 3109) [+3.03s] DEBUG: scanner.vala:1286: sane_read (3256) -> (SANE_STATUS_GOOD, 1627) [+3.03s] DEBUG: scanner.vala:1286: sane_read (3257) -> (SANE_STATUS_GOOD,