search

NixOS / nixpkgs

Nix Packages collection & NixOS
MIT License
17.17k stars 13.44k forks source link

Scanning with utsushi results in SIGPIPE after upgrade to 22.05 #177572

Closed esclear closed 1 year ago

esclear commented 2 years ago

Describe the bug

I have an EPSON DS-530 scanner, that I can't use to scan anymore. Prior to upgrading to NixOS 22.05, it worked perfectly fine.

Steps To Reproduce

Steps to reproduce the behavior:

  1. Configure scanning as follows: 
    { config, pkgs, ... }: {
hardware.sane = {
    enable = true;
    extraBackends = with pkgs; [ utsushi ];
};
services.udev.packages = with pkgs; [ utsushi ];
}
  2. Put a sheet of paper in the scanner and scan it using utsushi, scanimage, simple-scan, xscan, …
  3. The scanner pulls the paper through, but this does not result in a scanned document with any of the programs I tested.

The (probably) relevant lines of the full log output (see below) are:

[+23,19s] DEBUG: scanner.vala:1476: sane_read (7441) -> (SANE_STATUS_CANCELLED, 0)
[+23,19s] WARNING: scanner.vala:1503: Unable to read frame from device: Operation was canceled

Expected behavior

The page is scanned successfully.

Additional context

The symptoms look exactly like those described in #71070.

The full debug output is as follows:

Log ```text $ SANE_DEBUG_DLL=5 simple-scan -d > /tmp/log.txt [+0,00s] DEBUG: simple-scan.vala:2015: Starting simple-scan 42.1, PID=834208 [+0,00s] DEBUG: unsetenv() is not thread-safe and should not be used after threads are created [+0,00s] DEBUG: Trying to initialize portal [+0,06s] DEBUG: _g_io_module_get_default: Found default implementation dconf (DConfSettingsBackend) for ‘gsettings-backend’ [+0,07s] DEBUG: app-window.vala:1975: Loading state from /home/da/.config/simple-scan/state [+0,07s] DEBUG: app-window.vala:1954: Restoring window to 600x428 pixels [+0,07s] DEBUG: app-window.vala:1958: Restoring window to maximized [23:28:14.628515] [sanei_debug] Setting debug level of dll to 5. [23:28:14.628566] [dll] sane_init: SANE dll backend version 1.0.13 from sane-backends 1.0.32 [23:28:14.628576] [dll] sane_init/read_dlld: attempting to open directory `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/etc/sane.d/dll.d' [23:28:14.628589] [dll] sane_init/read_dlld: using config directory `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/etc/sane.d/dll.d' [23:28:14.628606] [dll] sane_init/read_dlld: done. [23:28:14.628615] [dll] sane_init/read_config: reading dll.conf [23:28:14.628627] [dll] add_backend: adding backend `net' << more backends removed because of the length limit >> [23:28:14.629088] [dll] add_backend: adding backend `utsushi' [+0,09s] DEBUG: scanner.vala:1620: sane_init () -> SANE_STATUS_GOOD [+0,09s] DEBUG: scanner.vala:1626: SANE version 1.0.32 [+0,09s] DEBUG: scanner.vala:1687: Requesting redetection of scan devices [+0,09s] DEBUG: scanner.vala:863: Processing request [23:28:14.629164] [dll] sane_get_devices [23:28:14.629173] [dll] load: searching backend `utsushi' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:14.629178] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-utsushi.so.1' [23:28:14.629205] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-utsushi.so.1' [23:28:14.635325] [dll] init: initializing backend `utsushi' [23:28:14.635344] [dll] init: backend `utsushi' is version 1.1.0 [+0,22s] DEBUG: app-window.vala:2051: Saving state to /home/da/.config/simple-scan/state [23:28:14.792166] [dll] load: searching backend `xerox_mfp' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:14.792196] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-xerox_mfp.so.1' [23:28:14.792214] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-xerox_mfp.so.1' [23:28:14.792535] [dll] init: initializing backend `xerox_mfp' [23:28:14.896225] [dll] init: backend `xerox_mfp' is version 1.0.13 [23:28:14.896327] [dll] load: searching backend `v4l' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:14.896336] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-v4l.so.1' [23:28:14.896350] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-v4l.so.1' [23:28:14.896816] [dll] init: initializing backend `v4l' [23:28:15.006915] [dll] init: backend `v4l' is version 1.0.5 [23:28:15.006942] [dll] load: searching backend `umax1220u' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:15.006954] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-umax1220u.so.1' [23:28:15.006978] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-umax1220u.so.1' [23:28:15.007198] [dll] init: initializing backend `umax1220u' [23:28:15.007351] [dll] init: backend `umax1220u' is version 1.0.2 [23:28:15.007360] [dll] load: searching backend `umax' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:15.007367] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-umax.so.1' [23:28:15.007377] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-umax.so.1' [23:28:15.007590] [dll] init: initializing backend `umax' [23:28:15.007752] [dll] init: backend `umax' is version 1.0.45 [23:28:15.007761] [dll] load: searching backend `u12' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:15.007766] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-u12.so.1' [23:28:15.007776] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-u12.so.1' [23:28:15.007929] [dll] init: initializing backend `u12' [23:28:15.008035] [dll] init: backend `u12' is version 1.0.0 [23:28:15.008042] [dll] load: searching backend `teco3' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:15.008049] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-teco3.so.1' [23:28:15.008058] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-teco3.so.1' [23:28:15.008146] [dll] init: initializing backend `teco3' [23:28:15.008195] [dll] init: backend `teco3' is version 1.0.1 [23:28:15.008205] [dll] load: searching backend `teco2' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:15.008219] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-teco2.so.1' [23:28:15.008229] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-teco2.so.1' [23:28:15.008315] [dll] init: initializing backend `teco2' [23:28:15.008369] [dll] init: backend `teco2' is version 1.0.10 [23:28:15.008376] [dll] load: searching backend `teco1' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:15.008381] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-teco1.so.1' [23:28:15.008389] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-teco1.so.1' [23:28:15.008464] [dll] init: initializing backend `teco1' [23:28:15.008504] [dll] init: backend `teco1' is version 1.0.10 [23:28:15.008510] [dll] load: searching backend `tamarack' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:15.008515] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-tamarack.so.1' [23:28:15.008524] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-tamarack.so.1' [23:28:15.008628] [dll] init: initializing backend `tamarack' [23:28:15.008667] [dll] init: backend `tamarack' is version 1.0.0 [23:28:15.008673] [dll] load: searching backend `sp15c' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:15.008680] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-sp15c.so.1' [23:28:15.008690] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-sp15c.so.1' [23:28:15.008775] [dll] init: initializing backend `sp15c' [23:28:15.008794] [dll] init: backend `sp15c' is version 1.0.0 [23:28:15.008801] [dll] load: searching backend `snapscan' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:15.008806] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-snapscan.so.1' [23:28:15.008815] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-snapscan.so.1' [23:28:15.008961] [dll] init: initializing backend `snapscan' [23:28:15.009095] [dll] init: backend `snapscan' is version 1.4.53 [23:28:15.009103] [dll] load: searching backend `sm3840' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:15.009110] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-sm3840.so.1' [23:28:15.009129] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-sm3840.so.1' [23:28:15.009294] [dll] init: initializing backend `sm3840' [23:28:15.009385] [dll] init: backend `sm3840' is version 1.0.0 [23:28:15.009394] [dll] load: searching backend `sm3600' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:15.009403] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-sm3600.so.1' [23:28:15.009421] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-sm3600.so.1' [23:28:15.009560] [dll] init: initializing backend `sm3600' [23:28:15.009647] [dll] init: backend `sm3600' is version 1.0.6 [23:28:15.009656] [dll] load: searching backend `sharp' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:15.009664] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-sharp.so.1' [23:28:15.009677] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-sharp.so.1' [23:28:15.009781] [dll] init: initializing backend `sharp' [23:28:15.009816] [dll] init: backend `sharp' is version 1.0.0 [23:28:15.009823] [dll] load: searching backend `sceptre' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:15.009832] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-sceptre.so.1' [23:28:15.009846] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-sceptre.so.1' [23:28:15.009934] [dll] init: initializing backend `sceptre' [23:28:15.009962] [dll] init: backend `sceptre' is version 1.0.10 [23:28:15.009969] [dll] load: searching backend `s9036' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:15.009973] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-s9036.so.1' [23:28:15.009982] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-s9036.so.1' [23:28:15.010059] [dll] init: initializing backend `s9036' [23:28:15.010082] [dll] init: backend `s9036' is version 1.0.0 [23:28:15.010088] [dll] load: searching backend `rts8891' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:15.010096] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-rts8891.so.1' [23:28:15.010111] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-rts8891.so.1' [23:28:15.010264] [dll] init: initializing backend `rts8891' [23:28:15.010350] [dll] init: backend `rts8891' is version 1.0.2401 [23:28:15.010421] [dll] load: searching backend `ricoh2' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:15.010428] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-ricoh2.so.1' [23:28:15.010437] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-ricoh2.so.1' [23:28:15.010542] [dll] init: initializing backend `ricoh2' [23:28:15.010613] [dll] init: backend `ricoh2' is version 1.0.0 [23:28:15.010620] [dll] load: searching backend `ricoh' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:15.010624] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-ricoh.so.1' [23:28:15.010635] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-ricoh.so.1' [23:28:15.010713] [dll] init: initializing backend `ricoh' [23:28:15.010738] [dll] init: backend `ricoh' is version 1.0.0 [23:28:15.010744] [dll] load: searching backend `qcam' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:15.010749] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-qcam.so.1' [23:28:15.010759] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-qcam.so.1' [23:28:15.010833] [dll] init: initializing backend `qcam' [23:28:15.010849] [dll] init: backend `qcam' is version 1.0.0 [23:28:15.010855] [dll] load: searching backend `plustek' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:15.010862] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-plustek.so.1' [23:28:15.010875] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-plustek.so.1' [23:28:15.011015] [dll] init: initializing backend `plustek' [23:28:15.011158] [dll] init: backend `plustek' is version 1.0.0 [23:28:15.011166] [dll] load: searching backend `pixma' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:15.011175] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-pixma.so.1' [23:28:15.011197] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-pixma.so.1' [23:28:15.011401] [dll] init: initializing backend `pixma' [23:28:15.011492] [dll] init: backend `pixma' is version 1.0.28 [23:28:15.532588] [dll] load: searching backend `pint' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:15.532619] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-pint.so.1' [23:28:15.532640] [dll] load: couldn't open `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-pint.so.1' (No such file or directory) [23:28:15.532647] [dll] load: trying to load `/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane/libsane-pint.so.1' [23:28:15.532657] [dll] load: couldn't open `/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane/libsane-pint.so.1' (No such file or directory) [23:28:15.532665] [dll] load: couldn't find backend `pint' (No such file or directory) [23:28:15.532670] [dll] load: searching backend `pieusb' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:15.532675] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-pieusb.so.1' [23:28:15.532691] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-pieusb.so.1' [23:28:15.533106] [dll] init: initializing backend `pieusb' [23:28:15.533312] [dll] init: backend `pieusb' is version 1.0.1 [23:28:15.533322] [dll] load: searching backend `pie' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:15.533330] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-pie.so.1' [23:28:15.533344] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-pie.so.1' [23:28:15.533491] [dll] init: initializing backend `pie' [23:28:15.533541] [dll] init: backend `pie' is version 1.0.9 [23:28:15.533550] [dll] load: searching backend `niash' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:15.533558] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-niash.so.1' [23:28:15.533570] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-niash.so.1' [23:28:15.533707] [dll] init: initializing backend `niash' [23:28:15.533803] [dll] init: backend `niash' is version 1.0.1 [23:28:15.533811] [dll] load: searching backend `nec' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:15.533818] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-nec.so.1' [23:28:15.533834] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-nec.so.1' [23:28:15.533939] [dll] init: initializing backend `nec' [23:28:15.533970] [dll] init: backend `nec' is version 1.0.0 [23:28:15.533978] [dll] load: searching backend `mustek_usb2' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:15.533984] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-mustek_usb2.so.1' [23:28:15.533995] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-mustek_usb2.so.1' [23:28:15.534132] [dll] init: initializing backend `mustek_usb2' [23:28:15.534145] [dll] init: backend `mustek_usb2' is version 1.0.10 [23:28:15.534248] [dll] load: searching backend `mustek_usb' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:15.534256] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-mustek_usb.so.1' [23:28:15.534266] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-mustek_usb.so.1' [23:28:15.534405] [dll] init: initializing backend `mustek_usb' [23:28:15.534508] [dll] init: backend `mustek_usb' is version 1.0.18 [23:28:15.534516] [dll] load: searching backend `mustek' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:15.534520] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-mustek.so.1' [23:28:15.534530] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-mustek.so.1' [23:28:15.534761] [dll] init: initializing backend `mustek' [23:28:15.534876] [dll] init: backend `mustek' is version 1.0.138 [23:28:15.534884] [dll] load: searching backend `microtek2' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:15.534889] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-microtek2.so.1' [23:28:15.534898] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-microtek2.so.1' [23:28:15.535018] [dll] init: initializing backend `microtek2' [23:28:15.535065] [dll] init: backend `microtek2' is version 1.0.0 [23:28:15.535074] [dll] load: searching backend `microtek' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:15.535080] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-microtek.so.1' [23:28:15.535092] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-microtek.so.1' [23:28:15.535213] [dll] init: initializing backend `microtek' [23:28:15.535245] [dll] init: backend `microtek' is version 1.0.0 [23:28:15.535252] [dll] load: searching backend `matsushita' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:15.535266] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-matsushita.so.1' [23:28:15.535278] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-matsushita.so.1' [23:28:15.535378] [dll] init: initializing backend `matsushita' [23:28:15.535421] [dll] init: backend `matsushita' is version 1.0.7 [23:28:15.535427] [dll] load: searching backend `magicolor' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:15.535434] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-magicolor.so.1' [23:28:15.535445] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-magicolor.so.1' [23:28:15.536619] [dll] init: initializing backend `magicolor' [23:28:15.536711] [dll] init: backend `magicolor' is version 1.0.1 [23:28:17.042822] [dll] load: searching backend `ma1509' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:17.042859] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-ma1509.so.1' [23:28:17.042887] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-ma1509.so.1' [23:28:17.043335] [dll] init: initializing backend `ma1509' [23:28:17.043581] [dll] init: backend `ma1509' is version 1.0.3 [23:28:17.043595] [dll] load: searching backend `lexmark' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:17.043604] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-lexmark.so.1' [23:28:17.043633] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-lexmark.so.1' [23:28:17.043928] [dll] init: initializing backend `lexmark' [23:28:17.044016] [dll] init: backend `lexmark' is version 1.0.32 [23:28:17.044117] [dll] load: searching backend `leo' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:17.044136] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-leo.so.1' [23:28:17.044148] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-leo.so.1' [23:28:17.044278] [dll] init: initializing backend `leo' [23:28:17.044356] [dll] init: backend `leo' is version 1.0.11 [23:28:17.044363] [dll] load: searching backend `kvs40xx' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:17.044370] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-kvs40xx.so.1' [23:28:17.044379] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-kvs40xx.so.1' [23:28:17.044563] [dll] init: initializing backend `kvs40xx' [23:28:17.044644] [dll] init: backend `kvs40xx' is version 1.0.1 [23:28:17.044659] [dll] load: searching backend `kvs20xx' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:17.044667] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-kvs20xx.so.1' [23:28:17.044678] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-kvs20xx.so.1' [23:28:17.044816] [dll] init: initializing backend `kvs20xx' [23:28:17.044896] [dll] init: backend `kvs20xx' is version 1.0.2 [23:28:17.044921] [dll] load: searching backend `kvs1025' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:17.044927] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-kvs1025.so.1' [23:28:17.044939] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-kvs1025.so.1' [23:28:17.045145] [dll] init: initializing backend `kvs1025' [23:28:17.045320] [dll] init: backend `kvs1025' is version 1.0.5 [23:28:17.045328] [dll] load: searching backend `kodakaio' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:17.045333] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-kodakaio.so.1' [23:28:17.045344] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-kodakaio.so.1' [23:28:17.046052] [dll] init: initializing backend `kodakaio' [23:28:17.046153] [dll] init: backend `kodakaio' is version 1.0.3 [23:28:17.047668] [dll] load: searching backend `kodak' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:17.047677] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-kodak.so.1' [23:28:17.047687] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-kodak.so.1' [23:28:17.047802] [dll] init: initializing backend `kodak' [23:28:17.047810] [dll] init: backend `kodak' is version 1.0.7 [23:28:17.047830] [dll] load: searching backend `ibm' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:17.047836] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-ibm.so.1' [23:28:17.047845] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-ibm.so.1' [23:28:17.047930] [dll] init: initializing backend `ibm' [23:28:17.047964] [dll] init: backend `ibm' is version 1.0.0 [23:28:17.047971] [dll] load: searching backend `hs2p' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:17.047976] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-hs2p.so.1' [23:28:17.047985] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-hs2p.so.1' [23:28:17.048073] [dll] init: initializing backend `hs2p' [23:28:17.048103] [dll] init: backend `hs2p' is version 1.0.0 [23:28:17.048110] [dll] load: searching backend `hpsj5s' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:17.048115] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-hpsj5s.so.1' [23:28:17.048124] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-hpsj5s.so.1' [23:28:17.048221] [dll] init: initializing backend `hpsj5s' [23:28:17.048237] [dll] init: backend `hpsj5s' is version 1.0.3 [23:28:17.048243] [dll] load: searching backend `hpljm1005' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:17.048248] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-hpljm1005.so.1' [23:28:17.048257] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-hpljm1005.so.1' [23:28:17.048358] [dll] init: initializing backend `hpljm1005' [23:28:17.048436] [dll] init: backend `hpljm1005' is version 1.0.1 [23:28:17.048443] [dll] load: searching backend `hp5590' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:17.048448] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-hp5590.so.1' [23:28:17.048459] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-hp5590.so.1' [23:28:17.048567] [dll] init: initializing backend `hp5590' [23:28:17.048636] [dll] init: backend `hp5590' is version 1.0.8 [23:28:17.048643] [dll] load: searching backend `hp5400' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:17.048648] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-hp5400.so.1' [23:28:17.048658] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-hp5400.so.1' [23:28:17.048764] [dll] init: initializing backend `hp5400' [23:28:17.048845] [dll] init: backend `hp5400' is version 1.0.3 [23:28:17.048852] [dll] load: searching backend `hp4200' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:17.048857] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-hp4200.so.1' [23:28:17.048867] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-hp4200.so.1' [23:28:17.048975] [dll] init: initializing backend `hp4200' [23:28:17.049053] [dll] init: backend `hp4200' is version 1.0.0 [23:28:17.049060] [dll] load: searching backend `hp3900' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:17.049066] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-hp3900.so.1' [23:28:17.049074] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-hp3900.so.1' [23:28:17.049254] [dll] init: initializing backend `hp3900' [23:28:17.049342] [dll] init: backend `hp3900' is version 1.0.0 [23:28:17.049349] [dll] load: searching backend `hp3500' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:17.049354] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-hp3500.so.1' [23:28:17.049363] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-hp3500.so.1' [23:28:17.049476] [dll] init: initializing backend `hp3500' [23:28:17.049546] [dll] init: backend `hp3500' is version 1.0.0 [23:28:17.049555] [dll] load: searching backend `hp' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:17.049561] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-hp.so.1' [23:28:17.049570] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-hp.so.1' [23:28:17.049773] [dll] init: initializing backend `hp' [23:28:17.049792] [dll] init: backend `hp' is version 1.0.8 [23:28:17.049902] [dll] load: searching backend `gt68xx' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:17.049910] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-gt68xx.so.1' [23:28:17.049918] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-gt68xx.so.1' [23:28:17.050045] [dll] init: initializing backend `gt68xx' [23:28:17.050149] [dll] init: backend `gt68xx' is version 1.0.84 [23:28:17.050258] [dll] load: searching backend `genesys' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:17.050265] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-genesys.so.1' [23:28:17.050272] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-genesys.so.1' [23:28:17.051182] [dll] init: initializing backend `genesys' [23:28:17.051491] [dll] init: backend `genesys' is version 1.0.0 [23:28:17.051580] [dll] load: searching backend `fujitsu' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:17.051587] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-fujitsu.so.1' [23:28:17.051597] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-fujitsu.so.1' [23:28:17.051735] [dll] init: initializing backend `fujitsu' [23:28:17.051749] [dll] init: backend `fujitsu' is version 1.0.137 [23:28:17.051844] [dll] load: searching backend `escl' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:17.051851] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-escl.so.1' [23:28:17.051860] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-escl.so.1' [23:28:17.060491] [dll] init: initializing backend `escl' [23:28:17.061005] [dll] init: backend `escl' is version 1.0.0 [23:28:17.062177] [dll] load: searching backend `epsonds' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:17.062204] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-epsonds.so.1' [23:28:17.062224] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-epsonds.so.1' [23:28:17.062509] [dll] init: initializing backend `epsonds' [23:28:17.062633] [dll] init: backend `epsonds' is version 1.0.0 [23:28:17.062655] [dll] load: searching backend `epson2' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:17.062663] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-epson2.so.1' [23:28:17.062675] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-epson2.so.1' [23:28:17.062945] [dll] init: initializing backend `epson2' [23:28:17.063025] [dll] init: backend `epson2' is version 1.0.124 [23:28:18.064179] [dll] load: searching backend `epjitsu' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:18.064245] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-epjitsu.so.1' [23:28:18.064283] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-epjitsu.so.1' [23:28:18.064732] [dll] init: initializing backend `epjitsu' [23:28:18.064754] [dll] init: backend `epjitsu' is version 1.0.31 [23:28:18.064959] [dll] load: searching backend `dmc' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:18.064966] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-dmc.so.1' [23:28:18.064975] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-dmc.so.1' [23:28:18.065084] [dll] init: initializing backend `dmc' [23:28:18.065129] [dll] init: backend `dmc' is version 1.0.0 [23:28:18.065138] [dll] load: searching backend `dell1600n_net' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:18.065145] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-dell1600n_net.so.1' [23:28:18.065154] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-dell1600n_net.so.1' [23:28:18.065312] [dll] init: initializing backend `dell1600n_net' [23:28:18.065323] [dll] init: backend `dell1600n_net' is version 1.0.0 [23:28:18.365759] [dll] load: searching backend `coolscan3' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:18.365802] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-coolscan3.so.1' [23:28:18.365846] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-coolscan3.so.1' [23:28:18.366334] [dll] init: initializing backend `coolscan3' [23:28:18.366592] [dll] init: backend `coolscan3' is version 1.0.0 [23:28:18.366673] [dll] load: searching backend `coolscan' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:18.366691] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-coolscan.so.1' [23:28:18.366719] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-coolscan.so.1' [23:28:18.367057] [dll] init: initializing backend `coolscan' [23:28:18.367127] [dll] init: backend `coolscan' is version 1.0.0 [23:28:18.367141] [dll] load: searching backend `cardscan' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:18.367150] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-cardscan.so.1' [23:28:18.367165] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-cardscan.so.1' [23:28:18.367361] [dll] init: initializing backend `cardscan' [23:28:18.367459] [dll] init: backend `cardscan' is version 1.0.3 [23:28:18.367481] [dll] load: searching backend `canon_lide70' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:18.367488] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-canon_lide70.so.1' [23:28:18.367501] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-canon_lide70.so.1' [23:28:18.367667] [dll] init: initializing backend `canon_lide70' [23:28:18.367766] [dll] init: backend `canon_lide70' is version 1.0.0 [23:28:18.367777] [dll] load: searching backend `canon_dr' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:18.367787] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-canon_dr.so.1' [23:28:18.367802] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-canon_dr.so.1' [23:28:18.367975] [dll] init: initializing backend `canon_dr' [23:28:18.367990] [dll] init: backend `canon_dr' is version 1.0.60 [23:28:18.368106] [dll] load: searching backend `canon630u' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:18.368115] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-canon630u.so.1' [23:28:18.368125] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-canon630u.so.1' [23:28:18.368297] [dll] init: initializing backend `canon630u' [23:28:18.368405] [dll] init: backend `canon630u' is version 1.0.1 [23:28:18.368413] [dll] load: searching backend `canon' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:18.368420] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-canon.so.1' [23:28:18.368429] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-canon.so.1' [23:28:18.368540] [dll] init: initializing backend `canon' [23:28:18.368571] [dll] init: backend `canon' is version 1.0.0 [23:28:18.368578] [dll] load: searching backend `bh' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:18.368584] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-bh.so.1' [23:28:18.368599] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-bh.so.1' [23:28:18.368713] [dll] init: initializing backend `bh' [23:28:18.368754] [dll] init: backend `bh' is version 1.0.4 [23:28:18.368760] [dll] load: searching backend `avision' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:18.368765] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-avision.so.1' [23:28:18.368777] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-avision.so.1' [23:28:18.368957] [dll] init: initializing backend `avision' [23:28:18.369211] [dll] init: backend `avision' is version 1.0.297 [23:28:18.369441] [dll] load: searching backend `as6e' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:18.369448] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-as6e.so.1' [23:28:18.369456] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-as6e.so.1' [23:28:18.369534] [dll] init: initializing backend `as6e' [23:28:18.369569] [dll] load: searching backend `artec_eplus48u' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:18.369581] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-artec_eplus48u.so.1' [23:28:18.369595] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-artec_eplus48u.so.1' [23:28:18.369722] [dll] init: initializing backend `artec_eplus48u' [23:28:18.369844] [dll] init: backend `artec_eplus48u' is version 1.0.0 [23:28:18.369853] [dll] load: searching backend `artec' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:18.369860] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-artec.so.1' [23:28:18.369874] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-artec.so.1' [23:28:18.369967] [dll] init: initializing backend `artec' [23:28:18.369998] [dll] init: backend `artec' is version 1.0.0 [23:28:18.370004] [dll] load: searching backend `apple' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:18.370009] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-apple.so.1' [23:28:18.370020] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-apple.so.1' [23:28:18.370112] [dll] init: initializing backend `apple' [23:28:18.370149] [dll] init: backend `apple' is version 1.0.0 [23:28:18.370157] [dll] load: searching backend `agfafocus' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:18.370170] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-agfafocus.so.1' [23:28:18.370197] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-agfafocus.so.1' [23:28:18.370329] [dll] init: initializing backend `agfafocus' [23:28:18.370360] [dll] init: backend `agfafocus' is version 1.0.0 [23:28:18.370366] [dll] load: searching backend `abaton' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:18.370371] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-abaton.so.1' [23:28:18.370385] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-abaton.so.1' [23:28:18.370494] [dll] init: initializing backend `abaton' [23:28:18.370540] [dll] init: backend `abaton' is version 1.0.0 [23:28:18.370546] [dll] load: searching backend `net' in `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane:/nix/store/w9yj3lfa9dsj27m0bcimm03r4k4608wl-sane-backends-1.0.32/lib/sane' [23:28:18.370552] [dll] load: trying to load `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-net.so.1' [23:28:18.370567] [dll] load: dlopen()ing `/nix/store/bkyh7ivdxjqja5w1dxw9mbxbj651yrdk-sane-config/lib/sane/libsane-net.so.1' [23:28:18.370756] [dll] init: initializing backend `net' [23:28:18.371956] [dll] init: backend `net' is version 1.0.32 [23:28:18.371965] [dll] sane_get_devices: found 3 devices [+3,83s] DEBUG: scanner.vala:348: sane_get_devices () -> SANE_STATUS_GOOD [+3,83s] DEBUG: scanner.vala:374: Device: name="utsushi:esci:usb:/sys/devices/pci0000:00/0000:00:08.1/0000:07:00.4/usb6/6-1/6-1.3/6-1.3:1.0" vendor="EPSON" model="DS-530" type="" [+18,77s] DEBUG: simple-scan.vala:1820: Requesting scan at 300 dpi from device 'utsushi:esci:usb:/sys/devices/pci0000:00/0000:00:08.1/0000:07:00.4/usb6/6-1/6-1.3/6-1.3:1.0' [+18,77s] DEBUG: scanner.vala:1778: Scanner.scan ("utsushi:esci:usb:/sys/devices/pci0000:00/0000:00:08.1/0000:07:00.4/usb6/6-1/6-1.3/6-1.3:1.0", dpi=300, scan_mode=ScanMode.COLOR, depth=8, type=single, side=both, paper_width=2100, paper_height=2970, brightness=0, contrast=0, delay=0ms) [+18,77s] DEBUG: scanner.vala:863: Processing request [23:28:33.307877] [dll] sane_open: trying to open `utsushi:esci:usb:/sys/devices/pci0000:00/0000:00:08.1/0000:07:00.4/usb6/6-1/6-1.3/6-1.3:1.0' [23:28:33.475998] [dll] sane_open: open successful [+18,94s] DEBUG: scanner.vala:924: sane_open ("utsushi:esci:usb:/sys/devices/pci0000:00/0000:00:08.1/0000:07:00.4/usb6/6-1/6-1.3/6-1.3:1.0") -> SANE_STATUS_GOOD [23:28:33.476043] [dll] sane_get_option_descriptor(handle=0x7fa568238e50,option=0) << snip (removed debug output related to options here and below) >> [23:28:33.476075] [dll] sane_get_option_descriptor(handle=0x7fa568238e50,option=1) << snip >> [23:28:33.476102] [dll] sane_get_option_descriptor(handle=0x7fa568238e50,option=2) << snip >> [23:28:33.476133] [dll] sane_get_option_descriptor(handle=0x7fa568238e50,option=3) << snip >> [23:28:33.476158] [dll] sane_get_option_descriptor(handle=0x7fa568238e50,option=4) << snip >> [23:28:33.476198] [dll] sane_get_option_descriptor(handle=0x7fa568238e50,option=5) << snip >> [23:28:33.476230] [dll] sane_get_option_descriptor(handle=0x7fa568238e50,option=6) << snip >> [23:28:33.476263] [dll] sane_get_option_descriptor(handle=0x7fa568238e50,option=7) << snip >> [23:28:33.476321] [dll] sane_get_option_descriptor(handle=0x7fa568238e50,option=8) << snip >> [23:28:33.476346] [dll] sane_get_option_descriptor(handle=0x7fa568238e50,option=9) << snip >> [23:28:33.476372] [dll] sane_get_option_descriptor(handle=0x7fa568238e50,option=10) << snip >> [23:28:33.476397] [dll] sane_get_option_descriptor(handle=0x7fa568238e50,option=11) << snip >> [23:28:33.476422] [dll] sane_get_option_descriptor(handle=0x7fa568238e50,option=12) << snip >> [23:28:33.476445] [dll] sane_get_option_descriptor(handle=0x7fa568238e50,option=13) << snip >> [23:28:33.476472] [dll] sane_get_option_descriptor(handle=0x7fa568238e50,option=14) << snip >> [23:28:33.476494] [dll] sane_get_option_descriptor(handle=0x7fa568238e50,option=15) << snip >> [23:28:33.476515] [dll] sane_get_option_descriptor(handle=0x7fa568238e50,option=16) [+18,94s] DEBUG: scanner.vala:1002: sane_get_option_descriptor (16) [+18,94s] DEBUG: scanner.vala:795: Option 16: name='force-extent' title='Force Extent' type=bool size=4 cap=soft-select,soft-detect,advanced [+18,94s] DEBUG: scanner.vala:798: Description: Force the image size to equal the user selected size. Scanners may trim the image data to the detected size of the document. This may result in images that are not all exactly the same size. This option makes sure all image sizes match the selected area. Note that this option may slow down application/driver side processing. [23:28:33.476534] [dll] sane_get_option_descriptor(handle=0x7fa568238e50,option=17) << snip >> [23:28:33.476555] [dll] sane_get_option_descriptor(handle=0x7fa568238e50,option=18) << snip >> [23:28:33.476579] [dll] sane_get_option_descriptor(handle=0x7fa568238e50,option=19) << snip >> [23:28:33.476607] [dll] sane_get_option_descriptor(handle=0x7fa568238e50,option=20) << snip >> [23:28:33.476628] [dll] sane_get_option_descriptor(handle=0x7fa568238e50,option=21) << snip >> [23:28:33.476647] [dll] sane_get_option_descriptor(handle=0x7fa568238e50,option=22) << snip >> [23:28:33.476667] [dll] sane_get_option_descriptor(handle=0x7fa568238e50,option=23) << snip >> [23:28:33.476693] [dll] sane_get_option_descriptor(handle=0x7fa568238e50,option=24) << snip >> [23:28:33.476713] [dll] sane_get_option_descriptor(handle=0x7fa568238e50,option=25) << snip >> [23:28:33.476733] [dll] sane_get_option_descriptor(handle=0x7fa568238e50,option=26) << snip >> [23:28:33.476752] [dll] sane_get_option_descriptor(handle=0x7fa568238e50,option=27) << snip >> [23:28:33.476773] [dll] sane_get_option_descriptor(handle=0x7fa568238e50,option=28) << snip >> [23:28:33.476793] [dll] sane_get_option_descriptor(handle=0x7fa568238e50,option=29) << snip >> [23:28:33.476809] [dll] sane_get_option_descriptor(handle=0x7fa568238e50,option=30) << snip >> [23:28:33.476825] [dll] sane_get_option_descriptor(handle=0x7fa568238e50,option=31) << snip >> [23:28:33.476842] [dll] sane_get_option_descriptor(handle=0x7fa568238e50,option=32) [+18,94s] DEBUG: scanner.vala:1002: sane_get_option_descriptor (32) [23:28:33.476851] [dll] sane_get_option_descriptor(handle=0x7fa568238e50,option=2) [+18,94s] WARNING: scanner.vala:1036: Unable to set single page source, trying to set ADF instead [+18,94s] WARNING: scanner.vala:1037: If Flatbed is existing and it is not set, please file a bug [23:28:33.476865] [dll] sane_control_option(handle=0x7fa568238e50,option=2,action=1,value=0x7fa568101240,info=(nil)) [+18,94s] DEBUG: scanner.vala:602: sane_control_option (2, SANE_ACTION_SET_VALUE, source="ADF") -> (SANE_STATUS_INVAL, "ADF") [+18,94s] WARNING: scanner.vala:993: Unable to set duplex ADF source, please file a bug [23:28:33.476886] [dll] sane_get_option_descriptor(handle=0x7fa568238e50,option=7) [23:28:33.476893] [dll] sane_control_option(handle=0x7fa568238e50,option=7,action=1,value=0x7fa568100990,info=(nil)) [+18,94s] DEBUG: scanner.vala:602: sane_control_option (7, SANE_ACTION_SET_VALUE, mode="Color") -> (SANE_STATUS_GOOD, "Color") [23:28:33.476911] [dll] sane_get_option_descriptor(handle=0x7fa568238e50,option=3) [23:28:33.476916] [dll] sane_control_option(handle=0x7fa568238e50,option=3,action=1,value=0x7fa572ffc984,info=(nil)) [+18,94s] DEBUG: scanner.vala:477: sane_control_option (3, SANE_ACTION_SET_VALUE, duplex=SANE_TRUE) -> (SANE_STATUS_GOOD, SANE_TRUE) [23:28:33.477119] [dll] sane_get_option_descriptor(handle=0x7fa568238e50,option=5) [23:28:33.477127] [dll] sane_control_option(handle=0x7fa568238e50,option=5,action=1,value=0x7fa572ffc944,info=(nil)) [+18,94s] DEBUG: scanner.vala:514: sane_control_option (5, SANE_ACTION_SET_VALUE, resolution=300) -> (SANE_STATUS_GOOD, 300) [23:28:33.477324] [dll] sane_get_option_descriptor(handle=0x7fa568238e50,option=9) [23:28:33.477332] [dll] sane_control_option(handle=0x7fa568238e50,option=9,action=1,value=0x7fa572ffc944,info=(nil)) [+18,94s] DEBUG: scanner.vala:554: sane_control_option (9, SANE_ACTION_SET_VALUE, br-x=210,000000) -> (SANE_STATUS_GOOD, 210,000000) [23:28:33.477508] [dll] sane_get_option_descriptor(handle=0x7fa568238e50,option=10) [23:28:33.477516] [dll] sane_control_option(handle=0x7fa568238e50,option=10,action=1,value=0x7fa572ffc944,info=(nil)) [+18,94s] DEBUG: scanner.vala:554: sane_control_option (10, SANE_ACTION_SET_VALUE, br-y=297,000000) -> (SANE_STATUS_GOOD, 297,000000) [23:28:33.477691] [dll] sane_get_option_descriptor(handle=0x7fa568238e50,option=19) [+18,94s] DEBUG: scanner.vala:449: scale_int: scaling 0 [min: -100, max: 100] to 0 [min: -100, max: 100] [23:28:33.477706] [dll] sane_control_option(handle=0x7fa568238e50,option=19,action=1,value=0x7fa572ffc984,info=(nil)) [+18,94s] DEBUG: scanner.vala:514: sane_control_option (19, SANE_ACTION_SET_VALUE, brightness=0) -> (SANE_STATUS_GOOD, 0) [23:28:33.477725] [dll] sane_get_option_descriptor(handle=0x7fa568238e50,option=20) [+18,94s] DEBUG: scanner.vala:449: scale_int: scaling 0 [min: -100, max: 100] to 0 [min: -100, max: 100] [23:28:33.477737] [dll] sane_control_option(handle=0x7fa568238e50,option=20,action=1,value=0x7fa572ffc984,info=(nil)) [+18,94s] DEBUG: scanner.vala:514: sane_control_option (20, SANE_ACTION_SET_VALUE, contrast=0) -> (SANE_STATUS_GOOD, 0) [23:28:33.477773] [dll] sane_start(handle=0x7fa568238e50) [+23,15s] DEBUG: scanner.vala:1352: sane_start (page=0, pass=0) -> SANE_STATUS_GOOD [23:28:37.692239] [dll] sane_get_parameters(handle=0x7fa568238e50,params=0x7fa572ffcab0) [+23,15s] DEBUG: scanner.vala:1389: sane_get_parameters () -> SANE_STATUS_GOOD [+23,15s] DEBUG: scanner.vala:1401: Parameters: format=SANE_FRAME_RGB last_frame=SANE_TRUE bytes_per_line=7440 pixels_per_line=2480 lines=3507 depth=8 [23:28:37.692427] [dll] sane_read(handle=0x7fa568238e50,data=0x7fa56810c390,maxlen=7441,lenp=0x7fa572ffcab0) [+23,15s] DEBUG: simple-scan.vala:1715: Page is 2480 pixels wide, 3507 pixels high, 8 bits per pixel [+23,16s] DEBUG: simple-scan.vala:1649: Getting color profile for device utsushi:esci:usb:/sys/devices/pci0000:00/0000:00:08.1/0000:07:00.4/usb6/6-1/6-1.3/6-1.3:1.0 [+23,17s] DEBUG: simple-scan.vala:1669: Unable to find colord device utsushi:esci:usb:/sys/devices/pci0000:00/0000:00:08.1/0000:07:00.4/usb6/6-1/6-1.3/6-1.3:1.0: The name org.freedesktop.ColorManager was not provided by any .service files [+23,19s] DEBUG: scanner.vala:1476: sane_read (7441) -> (SANE_STATUS_CANCELLED, 0) [+23,19s] WARNING: scanner.vala:1503: Unable to read frame from device: Operation was canceled [23:28:37.728817] [dll] sane_cancel(handle=0x7fa568238e50) [+23,19s] DEBUG: scanner.vala:825: sane_cancel () [23:28:37.728842] [dll] sane_close(handle=0x7fa568238e50) [+23,99s] DEBUG: scanner.vala:828: sane_close () [+26,23s] DEBUG: autosave-manager.vala:201: Deleting autosave records [+26,23s] DEBUG: scanner.vala:1808: Stopping scan thread [+26,23s] DEBUG: scanner.vala:863: Processing request [23:28:40.770991] [dll] sane_exit: exiting [23:28:40.771027] [dll] sane_exit: calling backend `utsushi's exit function << more backends removed because of the length limit >> [23:28:40.773650] [dll] sane_exit: finished [+26,24s] DEBUG: scanner.vala:1819: sane_exit () ```

Notify maintainers

@wucke13, @mwilsoncoding

Metadata

$ nix-shell -p nix-info --run "nix-info -m"
 - system: `"x86_64-linux"`
 - host os: `Linux 5.18.0, NixOS, 22.05 (Quokka), 22.05.866.1f8d88087a3`
 - multi-user?: `yes`
 - sandbox: `yes`
 - version: `nix-env (Nix) 2.8.1`
 - channels(root): `"nixos-22.05"`
 - channels(da): `"home-manager-22.05.tar.gz, nixpkgs-unstable"`
 - nixpkgs: `/nix/var/nix/profiles/per-user/root/channels/nixos`
esclear commented 2 years ago

I just noticed that utsushi reports version 0.65.0, which doesn't match the supposed version 3.65.0 in the channel. Though it seems kinda plausible to me that utsushi just reports major version 0, as the rest matches?

$ utsushi version
utsushi (Utsushi) 0.65.0
Copyright (C) 2012-2015  SEIKO EPSON CORPORATION
License: GPL-3.0+
esclear commented 2 years ago

Trying to debug this further by scanning with SANE_DEBUG_DLL=255 scanimage --verbose -d 'utsushi:esci:usb:path', I got the following lines of output:

[…]
[17:51:46.699747] [dll] sane_get_option_descriptor(handle=0x20344a0,option=28)
[17:51:46.699752] [dll] sane_get_option_descriptor(handle=0x20344a0,option=29)
[17:51:46.699757] [dll] sane_get_option_descriptor(handle=0x20344a0,option=30)
[17:51:46.699761] [dll] sane_get_option_descriptor(handle=0x20344a0,option=31)
[17:51:46.699766] [dll] sane_control_option(handle=0x20344a0,option=9,action=0,value=0x40d360,info=(nil))
[17:51:46.699772] [dll] sane_control_option(handle=0x20344a0,option=11,action=0,value=0x7fffc26ad9c4,info=(nil))
[17:51:46.699777] [dll] sane_control_option(handle=0x20344a0,option=10,action=0,value=0x40d364,info=(nil))
[17:51:46.699782] [dll] sane_control_option(handle=0x20344a0,option=12,action=0,value=0x7fffc26ad9c4,info=(nil))
[17:51:46.699794] [dll] sane_start(handle=0x20344a0)
[17:51:49.539757] [dll] sane_get_parameters(handle=0x20344a0,params=0x7fffc26adb20)
scanimage: scanning image of size 424x774 pixels at 24 bits/pixel
scanimage: acquiring RGB frame
[17:51:49.539839] [dll] sane_read(handle=0x20344a0,data=0x2289810,maxlen=32768,lenp=0x7fffc26adaf4)
scanimage: received signal 13
scanimage: trying to stop scanner
[17:51:49.541801] [dll] sane_cancel(handle=0x20344a0)
scanimage: min/max graylevel value = 255/0
scanimage: sane_read: Operation was canceled
[17:51:49.542653] [dll] sane_cancel(handle=0x20344a0)
[17:51:49.542680] [dll] sane_close(handle=0x20344a0)
[17:51:51.081057] [dll] sane_exit: exiting
[17:51:51.081112] [dll] sane_exit: calling backend `utsushi's exit function
[17:51:51.081158] [dll] sane_exit: finished

The line received signal 13 (SIGPIPE) looked suspicious to me.

I attempted to narrow this down further using strace:

statx(AT_FDCWD, "/nix/store/3wv5klknky16lh5ygq1d9k3bkcq96a28-imagescan-3.65.0/share/utsushi/drivers/esci/DS-530.dat", AT_STATX_SYNC_AS_STAT|AT_NO_AUTOMOUNT, STATX_TYPE|STATX_MODE, {stx_mask=STATX_BASIC_STATS|STATX_MNT_ID, stx_attributes=0, stx_mode=S_IFREG|0444, stx_size=1872, ...}) = 0
statx(AT_FDCWD, "/nix/store/3wv5klknky16lh5ygq1d9k3bkcq96a28-imagescan-3.65.0/share/utsushi/drivers/esci/DS-530.dat", AT_STATX_SYNC_AS_STAT|AT_NO_AUTOMOUNT, STATX_TYPE|STATX_MODE, {stx_mask=STATX_BASIC_STATS|STATX_MNT_ID, stx_attributes=0, stx_mode=S_IFREG|0444, stx_size=1872, ...}) = 0
openat(AT_FDCWD, "/nix/store/3wv5klknky16lh5ygq1d9k3bkcq96a28-imagescan-3.65.0/share/utsushi/drivers/esci/DS-530.dat", O_RDONLY) = 10
read(10, "INFOx0000128#ADFTYPEFEED#ADFDPLX"..., 8191) = 1872
read(10, "", 8191)                      = 0
close(10)                               = 0
statx(AT_FDCWD, "/nix/store/3wv5klknky16lh5ygq1d9k3bkcq96a28-imagescan-3.65.0/libexec/utsushi/get-text-orientation", AT_STATX_SYNC_AS_STAT|AT_NO_AUTOMOUNT, STATX_TYPE|STATX_MODE, {stx_mask=STATX_BASIC_STATS|STATX_MNT_ID, stx_attributes=0, stx_mode=S_IFREG|0555, stx_size=2449, ...}) = 0
pipe2([10, 11], O_CLOEXEC)              = 0
prlimit64(0, RLIMIT_NOFILE, NULL, {rlim_cur=1024, rlim_max=512*1024}) = 0
prlimit64(0, RLIMIT_NOFILE, NULL, {rlim_cur=1024, rlim_max=512*1024}) = 0
mmap(NULL, 36864, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS|MAP_STACK, -1, 0) = 0x7f201b725000
rt_sigprocmask(SIG_BLOCK, ~[], [], 8)   = 0
clone3({flags=CLONE_VM|CLONE_VFORK, exit_signal=SIGCHLD, stack=0x7f201b725000, stack_size=0x9000}, 88) = 1126126
munmap(0x7f201b725000, 36864)           = 0
rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
close(11)                               = 0
fcntl(10, F_SETFD, 0)                   = 0
newfstatat(10, "", {st_mode=S_IFIFO|0600, st_size=0, ...}, AT_EMPTY_PATH) = 0
read(10, "4.1.1\n", 4096)               = 6
close(10)                               = 0
wait4(1126126, [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], 0, NULL) = 1126126
--- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=1126126, si_uid=1000, si_status=0, si_utime=0, si_stime=0} ---
pipe2([10, 11], O_CLOEXEC)              = 0
prlimit64(0, RLIMIT_NOFILE, NULL, {rlim_cur=1024, rlim_max=512*1024}) = 0
prlimit64(0, RLIMIT_NOFILE, NULL, {rlim_cur=1024, rlim_max=512*1024}) = 0
mmap(NULL, 36864, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS|MAP_STACK, -1, 0) = 0x7f201b725000
rt_sigprocmask(SIG_BLOCK, ~[], [], 8)   = 0
clone3({flags=CLONE_VM|CLONE_VFORK, exit_signal=SIGCHLD, stack=0x7f201b725000, stack_size=0x9000}, 88) = 1126129
munmap(0x7f201b725000, 36864)           = 0
rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
close(11)                               = 0
fcntl(10, F_SETFD, 0)                   = 0
newfstatat(10, "", {st_mode=S_IFIFO|0600, st_size=0, ...}, AT_EMPTY_PATH) = 0
read(10, "osd\n", 4096)                 = 4
close(10)                               = 0
wait4(1126129, [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], 0, NULL) = 1126129
--- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=1126129, si_uid=1000, si_status=0, si_utime=0, si_stime=0} ---
access("/nix/store/3wv5klknky16lh5ygq1d9k3bkcq96a28-imagescan-3.65.0/libexec/utsushi/ocr-engine-getrotate", X_OK) = -1 ENOENT (No such file or directory)
access("/nix/store/3wv5klknky16lh5ygq1d9k3bkcq96a28-imagescan-3.65.0/libexec/utsushi/ocr-engine-getrotate", X_OK) = -1 ENOENT (No such file or directory)
rt_sigaction(SIGHUP, {sa_handler=0x407bc0, sa_mask=[HUP], sa_flags=SA_RESTORER|SA_RESTART, sa_restorer=0x7f201bea00e0}, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=0}, 8) = 0
rt_sigaction(SIGPIPE, {sa_handler=0x407bc0, sa_mask=[PIPE], sa_flags=SA_RESTORER|SA_RESTART, sa_restorer=0x7f201bea00e0}, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=0}, 8) = 0
rt_sigaction(SIGINT, {sa_handler=0x407bc0, sa_mask=[INT], sa_flags=SA_RESTORER|SA_RESTART, sa_restorer=0x7f201bea00e0}, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=0}, 8) = 0
rt_sigaction(SIGTERM, {sa_handler=0x407bc0, sa_mask=[TERM], sa_flags=SA_RESTORER|SA_RESTART, sa_restorer=0x7f201bea00e0}, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=0}, 8) = 0
ioctl(1, TCGETS, 0x7ffe86d7eab0)        = -1 ENOTTY (Inappropriate ioctl for device)
statx(AT_FDCWD, "/nix/store/3wv5klknky16lh5ygq1d9k3bkcq96a28-imagescan-3.65.0/libexec/utsushi/get-text-orientation", AT_STATX_SYNC_AS_STAT|AT_NO_AUTOMOUNT, STATX_TYPE|STATX_MODE, {stx_mask=STATX_BASIC_STATS|STATX_MNT_ID, stx_attributes=0, stx_mode=S_IFREG|0555, stx_size=2449, ...}) = 0
mmap(NULL, 8392704, PROT_NONE, MAP_PRIVATE|MAP_ANONYMOUS|MAP_STACK, -1, 0) = 0x7f200c7fe000
mprotect(0x7f200c7ff000, 8388608, PROT_READ|PROT_WRITE) = 0
rt_sigprocmask(SIG_BLOCK, ~[], [], 8)   = 0
clone3({flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, child_tid=0x7f200cffe910, parent_tid=0x7f200cffe910, exit_signal=0, stack=0x7f200c7fe000, stack_size=0x7fff00, tls=0x7f200cffe640} => {parent_tid=[1126132]}, 88) = 1126132
rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
mmap(NULL, 8392704, PROT_NONE, MAP_PRIVATE|MAP_ANONYMOUS|MAP_STACK, -1, 0) = 0x7f20077ff000
mprotect(0x7f2007800000, 8388608, PROT_READ|PROT_WRITE) = 0
rt_sigprocmask(SIG_BLOCK, ~[], [], 8)   = 0
clone3({flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, child_tid=0x7f2007fff910, parent_tid=0x7f2007fff910, exit_signal=0, stack=0x7f20077ff000, stack_size=0x7fff00, tls=0x7f2007fff640} => {parent_tid=[1126133]}, 88) = 1126133
rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
futex(0x15228a0, FUTEX_WAIT_BITSET_PRIVATE|FUTEX_CLOCK_REALTIME, 0, NULL, FUTEX_BITSET_MATCH_ANY) = 0
futex(0x1522850, FUTEX_WAKE_PRIVATE, 1) = 0
futex(0x15228a4, FUTEX_WAIT_BITSET_PRIVATE|FUTEX_CLOCK_REALTIME, 0, NULL, FUTEX_BITSET_MATCH_ANY) = 0
futex(0x1522850, FUTEX_WAKE_PRIVATE, 1) = 0

Interestingly, this refers to a file called ocr-engine-getrotate, that supposedly should exist in the imagescan / utsushipackage's libexec/utsushi directory, but doesn't as the strace indicates.

The only reference to this that I could find is in the get-text-orientation filter. If not provided with a different engine as a command line parameter, it should just use tesseract.

This in turn led me to reorient.cpp in the utsushi codebase, which calls the get-text-orientation filter. I stopped my investigation there for now.

Maybe @wucke13 can give more context, as they came up in many of my searches regarding this.

xaverdh commented 2 years ago

can you try adding tesseract4 to buildInputs and see if that works? It looks like reorient.cpp calls out to tesseract and expects to have it in path, so probably falls back to ocr-engine-getrotate?

xaverdh commented 2 years ago

can you check if https://github.com/NixOS/nixpkgs/pull/178014 fixes this?

esclear commented 2 years ago

Thank you @xaverdh, but unfortunately this didn't help. I'm still seeing references to ocr-engine-getrotate in the strace where I suspect that I should see references to tesseract instead.

It would be sufficient for me to deactivate all the postprocessing that happens, but this doesn't seem intended feature in utsushi :/

esclear commented 2 years ago

As I had amassed quite a backlog of documents to scan, I started a nix shell with the utsushi version I used previously, using the following command:

nix-shell -p utsushi -I nixpkgs=https://github.com/NixOS/nixpkgs/archive/b0249fdf998d782e1058b0cf3239091e59e393ef.tar.gz

This works.

However, this is only a workaround, as this means that I need to use the utsushi interface for scanning, which is somewhat cumbersome to use and this also pulls in a lot of old packages via the dependencies.

I'd be happy to try out anything at this point.

esclear commented 2 years ago

Hey, @abbradar, I started looking into this again and noticed that in 526238b288baafd99bb4fb6a69ead3f9db0c7f0a you switched the sources from the utsushi to the imagescan repository on gitlab.

I'm wondering what the reason for this was. Do you remember?

wucke13 commented 2 years ago

I think back than the maintainer of utsushi suggested this. Imagescan just tries to mirror the driver as it is release by epson, while utsushi contains community maintained patches, but unfortunately no clear versioning scheme.

esclear commented 2 years ago

I just bisected the changes and it turns out that 526238b288baafd99bb4fb6a69ead3f9db0c7f0a is indeed the commit at which utsushi stops working for me.

wucke13 commented 2 years ago

That is unfortunate. Maybe we should offer both utsushi and imagescan as packages, than the user can choose.

esclear commented 2 years ago

Maybe we should offer both utsushi and imagescan as packages, than the user can choose.

This could be a solution.

I am not sure yet, whether the problem may also stem from one of the patches introduced or the sed command removed in that commit.

As I am getting a SIGPIPE error, I think it possible that the problem may be related to the imagick (or a similar) dependency.

esclear commented 2 years ago

It turns out, that this problem had a relatively easy fix. Adding the following line back to the derivation makes utsushi work again.

sed -i '/\[AC_DEFINE(\[HAVE_IMAGE_MAGICK\], \[1\])/a \ MAGICK_CONVERT="${imagemagick}/bin/convert"' configure.ac

PR #187633 adds it back to the derivation and fixes scanning on my machine.