modem["ttyUSB0"]: error starting PPP: Could not find "pppd" binary

[pelzflorian (Florian Pelz)]

On Thu, Jul 04, 2019 at 03:48:11PM +0200, Adam Mazurkiewicz wrote:
> I am geeting the error
> 
> modem["ttyUSB0"]: error starting PPP: Could not find "pppd" binary
> 
> when choosing a modem connection i nm.
> 
> Here you are details:
> 
> s@s:~$ cat ~/Dropbox/Guix/export/txt/grep1
> Jul  4 14:50:04 localhost ModemManager[419]: <warn>  (ttyUSB0): port
> attributes not fully set
> Jul  4 14:50:04 localhost ModemManager[419]: <warn>  (ttyUSB1): port
> attributes not fully set
> Jul  4 14:50:07 localhost ModemManager[419]: <warn>  (ttyUSB1): port
> attributes not fully set
> Jul  4 14:50:13 localhost ModemManager[419]: <warn>  Could not grab
> port (usbmisc/cdc-wdm2): 'Cannot add port 'usbmisc/cdc-wdm2',
> unsupported'
> Jul  4 14:50:13 localhost ModemManager[419]: <warn>  (ttyUSB0): port
> attributes not fully set
> Jul  4 14:50:17 localhost ModemManager[419]: <warn>  couldn't load
> list of Own Numbers: 'Not found'
> Jul  4 14:50:18 localhost ModemManager[419]: <warn>  (ttyUSB0): port
> attributes not fully set
> Jul  4 14:50:41 localhost ModemManager[419]: <warn>  (ttyUSB1): port
> attributes not fully set
> Jul  4 14:50:41 localhost NetworkManager[325]: <warn>
> [1562244641.5798] device (ttyUSB0): ip-ifname: set ifname 'ttyUSB1',
> unknown ifindex
> Jul  4 14:50:41 localhost NetworkManager[325]: <warn>
> [1562244641.5806] device (ttyUSB0): interface ttyUSB1 not up for IP
> configuration
> Jul  4 14:50:41 localhost NetworkManager[325]: <error>
> [1562244641.5826] modem["ttyUSB0"]: error starting PPP: Could not find
> "pppd" binary
> Jul  4 14:50:41 localhost NetworkManager[325]: <warn>
> [1562244641.5838] device (ttyUSB0): Activation: failed for connection
> 'Orange Standard access - with image compression'
> Jul  4 14:50:42 localhost ModemManager[419]: <warn>  (ttyUSB1): port
> attributes not fully set
> s@s:~$
> 
> s@s:~$ cat ~/Dropbox/Guix/export/txt/messages
> (...)
> Jul  4 14:50:03 localhost shepherd[1]: Service xorg-server has been started.
> Jul  4 14:50:03 localhost vmunix: [    4.581161] option 1-2:1.0: GSM
> modem (1-port) converter detected
> Jul  4 14:50:03 localhost vmunix: [    4.589076] usb 1-2: GSM modem
> (1-port) converter now attached to ttyUSB0
> Jul  4 14:50:03 localhost vmunix: [    4.589145] option 1-2:1.1: GSM
> modem (1-port) converter detected
> Jul  4 14:50:03 localhost vmunix: [    4.589235] usb 1-2: GSM modem
> (1-port) converter now attached to ttyUSB1
> Jul  4 14:50:03 localhost vmunix: [    4.618852] kvm: disabled by bios
> Jul  4 14:50:03 localhost vmunix: [    4.622803] intel_powerclamp: No
> package C-state available
> Jul  4 14:50:03 localhost vmunix: [    4.643252] sr 7:0:0:0: [sr0]
> tag#0 FAILED Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE
> Jul  4 14:50:03 localhost vmunix: [    4.643256] sr 7:0:0:0: [sr0]
> tag#0 Sense Key : Medium Error [current]
> Jul  4 14:50:03 localhost vmunix: [    4.643258] sr 7:0:0:0: [sr0]
> tag#0 Add. Sense: Unrecovered read error
> Jul  4 14:50:03 localhost vmunix: [    4.643262] sr 7:0:0:0: [sr0]
> tag#0 CDB: Read(10) 28 00 00 00 8c 80 00 00 3c 00
> Jul  4 14:50:03 localhost vmunix: [    4.643264] print_req_error:
> critical medium error, dev sr0, sector 143872 flags 80700
> Jul  4 14:50:03 localhost vmunix: [    4.681836] r8169 0000:02:00.0
> enp2s0: renamed from eth0
> Jul  4 14:50:03 localhost vmunix: [    4.683250] random: crng init done
> Jul  4 14:50:03 localhost vmunix: [    4.683253] random: 7 urandom
> warning(s) missed due to ratelimiting
> Jul  4 14:50:02 localhost avahi-daemon[332]: Loading service file
> /services/sftp-ssh.service.
> Jul  4 14:50:03 localhost vmunix: [    4.689711] lm90 3-004c: 3-004c
> supply vcc not found, using dummy regulator
> Jul  4 14:50:03 localhost vmunix: [    4.690028] huawei_cdc_ncm
> 1-2:1.2: resetting NTB format to 16-bit
> Jul  4 14:50:03 localhost vmunix: [    4.690068] gpio_ich
> gpio_ich.1.auto: GPIO from 451 to 511
> Jul  4 14:50:03 localhost vmunix: [    4.690368] huawei_cdc_ncm
> 1-2:1.2: MAC-Address: 00:1e:10:1f:00:00
> Jul  4 14:50:03 localhost vmunix: [    4.690371] huawei_cdc_ncm
> 1-2:1.2: setting rx_max = 16384
> Jul  4 14:50:03 localhost vmunix: [    4.691083] iTCO_vendor_support:
> vendor-support=0
> Jul  4 14:50:03 localhost vmunix: [    4.693305] iTCO_wdt: Intel TCO
> WatchDog Timer Driver v1.11
> Jul  4 14:50:03 localhost vmunix: [    4.693345] iTCO_wdt: Found a
> ICH9R TCO device (Version=2, TCOBASE=0x0860)
> Jul  4 14:50:03 localhost vmunix: [    4.694250] iTCO_wdt:
> initialized. heartbeat=30 sec (nowayout=0)
> Jul  4 14:50:03 localhost vmunix: [    4.697758] huawei_cdc_ncm
> 1-2:1.2: NDP will be placed at end of frame for this device.
> Jul  4 14:50:03 localhost vmunix: [    4.697832] huawei_cdc_ncm
> 1-2:1.2: cdc-wdm2: USB WDM device
> Jul  4 14:50:03 localhost vmunix: [    4.697874] sr 7:0:0:0: [sr0]
> tag#0 FAILED Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE
> Jul  4 14:50:03 localhost vmunix: [    4.697877] sr 7:0:0:0: [sr0]
> tag#0 Sense Key : Medium Error [current]
> Jul  4 14:50:02 localhost dbus-daemon[322]: [system] Activating
> service name='org.freedesktop.Accounts' requested by ':1.5' (uid=0
> pid=385 comm="/gnu/store/x5jax3ic5369zvq4b2f9b0k7zgg45hg3-gdm-3.")
> (using servicehelper)
> Jul  4 14:50:03 localhost vmunix: [    4.697879] sr 7:0:0:0: [sr0]
> tag#0 Add. Sense: Unrecovered read error
> Jul  4 14:50:03 localhost vmunix: [    4.697883] sr 7:0:0:0: [sr0]
> tag#0 CDB: Read(10) 28 00 00 00 8c 80 00 00 02 00
> Jul  4 14:50:03 localhost vmunix: [    4.697885] print_req_error:
> critical medium error, dev sr0, sector 143872 flags 0
> Jul  4 14:50:03 localhost vmunix: [    4.697951] Buffer I/O error on
> dev sr0, logical block 17984, async page read
> Jul  4 14:50:03 localhost vmunix: [    4.705244] sr 7:0:0:0: [sr0]
> tag#0 FAILED Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE
> Jul  4 14:50:03 localhost vmunix: [    4.705246] sr 7:0:0:0: [sr0]
> tag#0 Sense Key : Medium Error [current]
> Jul  4 14:50:03 localhost vmunix: [    4.705248] sr 7:0:0:0: [sr0]
> tag#0 Add. Sense: Unrecovered read error
> Jul  4 14:50:03 localhost vmunix: [    4.705251] sr 7:0:0:0: [sr0]
> tag#0 CDB: Read(10) 28 00 00 00 8d fa 00 00 02 00
> Jul  4 14:50:03 localhost vmunix: [    4.705252] print_req_error:
> critical medium error, dev sr0, sector 145384 flags 80700
> Jul  4 14:50:03 localhost vmunix: [    4.705316] attempt to access
> beyond end of device
> Jul  4 14:50:03 localhost vmunix: [    4.705318] sr0: rw=0,
> want=145392, limit=145384
> Jul  4 14:50:03 localhost vmunix: [    4.705319] Buffer I/O error on
> dev sr0, logical block 18173, async page read
> Jul  4 14:50:02 localhost ntpd[326]: Listen normally on 3 lo [::1]:123
> Jul  4 14:50:03 localhost vmunix: [    4.705917] huawei_cdc_ncm
> 1-2:1.2 wwan0: register 'huawei_cdc_ncm' at usb-0000:00:1a.7-2, Huawei
> CDC NCM device, 00:1e:10:1f:00:00
> Jul  4 14:50:03 localhost vmunix: [    4.705968] usbcore: registered
> new interface driver huawei_cdc_ncm
> Jul  4 14:50:03 localhost vmunix: [    4.719914] [TTM] Zone  kernel:
> Available graphics memory: 2535360 kiB
> Jul  4 14:50:03 localhost vmunix: [    4.719916] [TTM] Zone   dma32:
> Available graphics memory: 2097152 kiB
> Jul  4 14:50:03 localhost vmunix: [    4.719917] [TTM] Initializing
> pool allocator
> Jul  4 14:50:03 localhost vmunix: [    4.719923] [TTM] Initializing
> DMA pool allocator
> Jul  4 14:50:03 localhost vmunix: [    4.719949] nouveau 0000:04:00.0:
> DRM: VRAM: 256 MiB
> Jul  4 14:50:03 localhost vmunix: [    4.719950] nouveau 0000:04:00.0:
> DRM: GART: 1048576 MiB
> Jul  4 14:50:03 localhost vmunix: [    4.719955] nouveau 0000:04:00.0:
> DRM: TMDS table version 2.0
> Jul  4 14:50:03 localhost vmunix: [    4.719957] nouveau 0000:04:00.0:
> DRM: DCB version 4.0
> Jul  4 14:50:03 localhost vmunix: [    4.719960] nouveau 0000:04:00.0:
> DRM: DCB outp 00: 02000300 00000028
> Jul  4 14:50:03 localhost vmunix: [    4.719962] nouveau 0000:04:00.0:
> DRM: DCB outp 01: 01000302 00000030
> Jul  4 14:50:03 localhost vmunix: [    4.719964] nouveau 0000:04:00.0:
> DRM: DCB outp 02: 04011310 00000028
> Jul  4 14:50:02 localhost NetworkManager[325]: <info>
> [1562244602.8286] manager[0xb09100]: rfkill: WiFi hardware radio set
> enabled
> Jul  4 14:50:03 localhost vmunix: [    4.719966] nouveau 0000:04:00.0:
> DRM: DCB outp 03: 02011312 00000030
> Jul  4 14:50:03 localhost vmunix: [    4.719967] nouveau 0000:04:00.0:
> DRM: DCB outp 04: 010223f1 00c0c080
> Jul  4 14:50:03 localhost vmunix: [    4.719969] nouveau 0000:04:00.0:
> DRM: DCB conn 00: 1030
> Jul  4 14:50:03 localhost vmunix: [    4.719971] nouveau 0000:04:00.0:
> DRM: DCB conn 01: 2130
> Jul  4 14:50:03 localhost vmunix: [    4.719973] nouveau 0000:04:00.0:
> DRM: DCB conn 02: 0210
> Jul  4 14:50:03 localhost vmunix: [    4.719974] nouveau 0000:04:00.0:
> DRM: DCB conn 03: 0211
> Jul  4 14:50:03 localhost vmunix: [    4.719976] nouveau 0000:04:00.0:
> DRM: DCB conn 04: 0213
> Jul  4 14:50:03 localhost vmunix: [    4.722998] nouveau 0000:04:00.0:
> DRM: MM: using CRYPT for buffer copies
> Jul  4 14:50:03 localhost vmunix: [    4.723719] nouveau 0000:04:00.0:
> DRM: failed to create encoder 0/1/0: -19
> Jul  4 14:50:03 localhost vmunix: [    4.723720] nouveau 0000:04:00.0:
> DRM: TV-1 has no encoders, removing
> Jul  4 14:50:03 localhost vmunix: [    4.723730] [drm] Supports vblank
> timestamp caching Rev 2 (21.10.2013).
> Jul  4 14:50:03 localhost vmunix: [    4.723731] [drm] Driver supports
> precise vblank timestamp query.
> Jul  4 14:50:03 localhost vmunix: [    4.734850] huawei_cdc_ncm
> 1-2:1.2 wwp0s26f7u2i2: renamed from wwan0
> Jul  4 14:50:02 localhost avahi-daemon[332]: Loading service file
> /services/ssh.service.
> Jul  4 14:50:03 localhost vmunix: [    4.781077] nouveau 0000:04:00.0:
> DRM: allocated 1600x1200 fb: 0x70000, bo 0000000094694a46
> Jul  4 14:50:03 localhost vmunix: [    4.785275] fbcon: nouveaufb
> (fb0) is primary device
> Jul  4 14:50:03 localhost vmunix: [    4.933972] Console: switching to
> colour frame buffer device 200x75
> Jul  4 14:50:03 localhost vmunix: [    4.935396] nouveau 0000:04:00.0:
> fb0: nouveaufb frame buffer device
> Jul  4 14:50:03 localhost vmunix: [    4.953274] [drm] Initialized
> nouveau 1.3.1 20120801 for 0000:04:00.0 on minor 0
> Jul  4 14:50:03 localhost vmunix: [    4.975821] shepherd[1]: Service
> udev has been started.
> Jul  4 14:50:03 localhost vmunix: [    5.049167] firewire_core
> 0000:01:00.2: created device fw0: GUID 00023c0091075980, S400
> Jul  4 14:50:03 localhost vmunix: [    5.049680] EXT4-fs (sda6):
> mounted filesystem with ordered data mode. Opts: (null)
> Jul  4 14:50:03 localhost vmunix: [    5.051917] shepherd[1]: Service
> file-system-/root/debianek has been started.
> Jul  4 14:50:03 localhost vmunix: [    5.053120] firewire_core
> 0000:01:05.0: created device fw1: GUID 001e8c0001a27b87, S400
> Jul  4 14:50:03 localhost vmunix: [    5.054522] shepherd[1]: Service
> file-system-/dev/pts has been started.
> Jul  4 14:50:03 localhost vmunix: [    5.056977] shepherd[1]: Service
> file-system-/dev/shm has been started.
> Jul  4 14:50:03 localhost dbus-daemon[322]: [system] Activating
> service name='org.freedesktop.PolicyKit1' requested by ':1.6' (uid=0
> pid=389 comm="/gnu/store/mp2s63jb2la48vbsk930hp41ych1gw7d-accoun")
> (using servicehelper)
> Jul  4 14:50:03 localhost vmunix: [    5.059394] shepherd[1]: Service
> file-system-/gnu/store has been started.
> Jul  4 14:50:03 localhost vmunix: [    5.061838] shepherd[1]: Service
> file-system-/run/systemd has been started.
> Jul  4 14:50:03 localhost vmunix: [    5.064256] shepherd[1]: Service
> file-system-/run/user has been started.
> Jul  4 14:50:03 localhost vmunix: [    5.066679] shepherd[1]: Service
> file-system-/sys/fs/cgroup has been started.
> Jul  4 14:50:03 localhost vmunix: [    5.069273] shepherd[1]: Service
> file-system-/sys/fs/cgroup/elogind has been started.
> Jul  4 14:50:03 localhost vmunix: [    5.072121] shepherd[1]: Service
> file-system-/sys/fs/cgroup/cpuset has been started.
> Jul  4 14:50:03 localhost vmunix: [    5.074734] shepherd[1]: Service
> file-system-/sys/fs/cgroup/cpu has been started.
> Jul  4 14:50:03 localhost vmunix: [    5.077304] shepherd[1]: Service
> file-system-/sys/fs/cgroup/cpuacct has been started.
> Jul  4 14:50:03 localhost vmunix: [    5.080979] shepherd[1]: Service
> file-system-/sys/fs/cgroup/memory has been started.
> Jul  4 14:50:03 localhost vmunix: [    5.083571] shepherd[1]: Service
> file-system-/sys/fs/cgroup/devices has been started.
> Jul  4 14:50:03 localhost vmunix: [    5.086128] shepherd[1]: Service
> file-system-/sys/fs/cgroup/freezer has been started.
> Jul  4 14:50:03 localhost ntpd[326]: Listening on routing socket on fd
> #20 for interface updates
> Jul  4 14:50:03 localhost vmunix: [    5.088830] shepherd[1]: Service
> file-system-/sys/fs/cgroup/blkio has been started.
> Jul  4 14:50:03 localhost vmunix: [    5.091427] shepherd[1]: Service
> file-system-/sys/fs/cgroup/perf_event has been started.
> Jul  4 14:50:03 localhost vmunix: [    5.093536] shepherd[1]: Service
> file-systems has been started.
> Jul  4 14:50:03 localhost vmunix: [    5.105409] shepherd[1]: Service
> urandom-seed has been started.
> Jul  4 14:50:03 localhost vmunix: [    5.107506] shepherd[1]: Service
> user-processes has been started.
> Jul  4 14:50:03 localhost vmunix: [    5.109653] shepherd[1]: Service
> host-name has been started.
> Jul  4 14:50:03 localhost vmunix: [    5.113693] shepherd[1]: Service
> user-homes has been started.
> Jul  4 14:50:03 localhost vmunix: [    5.623567] input: ImPS/2 Generic
> Wheel Mouse as /devices/platform/i8042/serio1/input/input8
> Jul  4 14:50:03 localhost vmunix: [    6.122307] shepherd[1]: Service
> nscd has been started.
> Jul  4 14:50:03 localhost vmunix: [    6.128336] shepherd[1]: Service
> guix-daemon has been started.
> Jul  4 14:50:03 localhost NetworkManager[325]: <info>
> [1562244603.0920] manager[0xb09100]: rfkill: WWAN hardware radio set
> enabled
> Jul  4 14:50:03 localhost avahi-daemon[332]: Network interface
> enumeration completed.
> Jul  4 14:50:03 localhost polkitd[394]: Started polkitd version 0.115
> Jul  4 14:50:03 localhost ntpd[326]: kernel reports TIME_ERROR: 0x41:
> Clock Unsynchronized
> Jul  4 14:50:03 localhost dbus-daemon[322]: [system] Activating
> service name='org.freedesktop.nm_dispatcher' requested by ':1.3'
> (uid=0 pid=325 comm="/gnu/store/8pw0d0qbq5rjd0v61ibfripnsa7sajw7-networ")
> (using servicehelper)
> Jul  4 14:50:03 localhost avahi-daemon[332]: Server startup complete.
> Host name is s.local. Local service cookie is 1047123886.
> Jul  4 14:50:03 localhost ntpd[326]: kernel reports TIME_ERROR: 0x41:
> Clock Unsynchronized
> Jul  4 14:50:03 localhost dbus-daemon[322]: [system] Successfully
> activated service 'org.freedesktop.nm_dispatcher'
> Jul  4 14:50:03 localhost avahi-daemon[332]: Service "s"
> (/services/ssh.service) successfully established.
> Jul  4 14:50:03 localhost NetworkManager[325]: <info>
> [1562244603.8040] settings: loaded plugin keyfile: (c) 2007 - 2016 Red
> Hat, Inc.  To report bugs please use the NetworkManager mailing list.
> Jul  4 14:50:03 localhost avahi-daemon[332]: Service "s"
> (/services/sftp-ssh.service) successfully established.
> Jul  4 14:50:03 localhost NetworkManager[325]: <info>
> [1562244603.9670] keyfile: new connection
> /etc/NetworkManager/system-connections/Orange Standard access - with
> image compression (7e9e6560-8b0e-4628-a7ae-c3d74955bbe0,"Orange
> Standard access - with image compression")
> Jul  4 14:50:03 localhost NetworkManager[325]: <info>
> [1562244603.9998] manager: rfkill: WiFi enabled by radio killswitch;
> enabled by state file
> Jul  4 14:50:03 localhost NetworkManager[325]: <info>
> [1562244603.9998] manager: rfkill: WWAN enabled by radio killswitch;
> enabled by state file
> Jul  4 14:50:03 localhost NetworkManager[325]: <info>
> [1562244603.9999] manager: Networking is enabled by state file
> Jul  4 14:50:03 localhost NetworkManager[325]: <info>
> [1562244604.0057] dhcp-init: Using DHCP client 'dhclient'
> Jul  4 14:50:04 localhost NetworkManager[325]: <info>
> [1562244604.0064] Loaded device plugin: NMBondDeviceFactory (internal)
> Jul  4 14:50:04 localhost NetworkManager[325]: <info>
> [1562244604.0065] Loaded device plugin: NMBridgeDeviceFactory
> (internal)
> Jul  4 14:50:04 localhost NetworkManager[325]: <info>
> [1562244604.0065] Loaded device plugin: NMDummyDeviceFactory
> (internal)
> Jul  4 14:50:04 localhost NetworkManager[325]: <info>
> [1562244604.0065] Loaded device plugin: NMEthernetDeviceFactory
> (internal)
> Jul  4 14:50:04 localhost NetworkManager[325]: <info>
> [1562244604.0065] Loaded device plugin: NMInfinibandDeviceFactory
> (internal)
> Jul  4 14:50:04 localhost NetworkManager[325]: <info>
> [1562244604.0066] Loaded device plugin: NMIPTunnelDeviceFactory
> (internal)
> Jul  4 14:50:04 localhost NetworkManager[325]: <info>
> [1562244604.0066] Loaded device plugin: NMMacsecDeviceFactory
> (internal)
> Jul  4 14:50:04 localhost NetworkManager[325]: <info>
> [1562244604.0066] Loaded device plugin: NMMacvlanDeviceFactory
> (internal)
> Jul  4 14:50:04 localhost NetworkManager[325]: <info>
> [1562244604.0066] Loaded device plugin: NMPppDeviceFactory (internal)
> Jul  4 14:50:04 localhost NetworkManager[325]: <info>
> [1562244604.0066] Loaded device plugin: NMTunDeviceFactory (internal)
> Jul  4 14:50:04 localhost NetworkManager[325]: <info>
> [1562244604.0067] Loaded device plugin: NMVethDeviceFactory (internal)
> Jul  4 14:50:04 localhost NetworkManager[325]: <info>
> [1562244604.0067] Loaded device plugin: NMVlanDeviceFactory (internal)
> Jul  4 14:50:04 localhost NetworkManager[325]: <info>
> [1562244604.0067] Loaded device plugin: NMVxlanDeviceFactory
> (internal)
> Jul  4 14:50:04 localhost NetworkManager[325]: <info>
> [1562244604.0180] Loaded device plugin: NMBluezManager
> (/gnu/store/8pw0d0qbq5rjd0v61ibfripnsa7sajw7-network-manager-1.10.10/lib/NetworkManager/libnm-device-plugin-bluetooth.so)
> Jul  4 14:50:04 localhost dbus-daemon[322]: [system] Successfully
> activated service 'org.freedesktop.PolicyKit1'
> Jul  4 14:50:04 localhost NetworkManager[325]: <info>
> [1562244604.0204] Loaded device plugin: NMWifiFactory
> (/gnu/store/8pw0d0qbq5rjd0v61ibfripnsa7sajw7-network-manager-1.10.10/lib/NetworkManager/libnm-device-plugin-wifi.so)
> Jul  4 14:50:04 localhost accounts-daemon[389]: started daemon version 0.6.50
> Jul  4 14:50:04 localhost dbus-daemon[322]: [system] Successfully
> activated service 'org.freedesktop.Accounts'
> Jul  4 14:50:04 localhost NetworkManager[325]: <info>
> [1562244604.0232] Loaded device plugin: NMOvsFactory
> (/gnu/store/8pw0d0qbq5rjd0v61ibfripnsa7sajw7-network-manager-1.10.10/lib/NetworkManager/libnm-device-plugin-ovs.so)
> Jul  4 14:50:04 localhost NetworkManager[325]: <info>
> [1562244604.0243] Loaded device plugin: NMAtmManager
> (/gnu/store/8pw0d0qbq5rjd0v61ibfripnsa7sajw7-network-manager-1.10.10/lib/NetworkManager/libnm-device-plugin-adsl.so)
> Jul  4 14:50:04 localhost NetworkManager[325]: <info>
> [1562244604.0264] Loaded device plugin: NMWwanFactory
> (/gnu/store/8pw0d0qbq5rjd0v61ibfripnsa7sajw7-network-manager-1.10.10/lib/NetworkManager/libnm-device-plugin-wwan.so)
> Jul  4 14:50:04 localhost NetworkManager[325]: <info>
> [1562244604.0286] device (lo): carrier: link connected
> Jul  4 14:50:04 localhost NetworkManager[325]: <info>
> [1562244604.0294] manager: (lo): new Generic device
> (/org/freedesktop/NetworkManager/Devices/1)
> Jul  4 14:50:04 localhost NetworkManager[325]: <info>
> [1562244604.0310] manager: (enp2s0): new Ethernet device
> (/org/freedesktop/NetworkManager/Devices/2)
> Jul  4 14:50:04 localhost NetworkManager[325]: <info>
> [1562244604.0324] keyfile: add connection in-memory
> (449658c5-4788-32aa-92e8-af1f4ec72f88,"Wired connection 1")
> Jul  4 14:50:04 localhost NetworkManager[325]: <info>
> [1562244604.0330] settings: (enp2s0): created default wired connection
> 'Wired connection 1'
> Jul  4 14:50:04 localhost NetworkManager[325]: <info>
> [1562244604.0342] device (enp2s0): state change: unmanaged ->
> unavailable (reason 'managed', sys-iface-state: 'external')
> Jul  4 14:50:04 localhost vmunix: [   10.189803] RTL8211B Gigabit
> Ethernet r8169-200:00: attached PHY driver [RTL8211B Gigabit Ethernet]
> (mii_bus:phy_addr=r8169-200:00, irq=IGNORE)
> Jul  4 14:50:04 localhost gdm: gdm_session_set_environment_variable:
> assertion 'value != NULL' failed
> Jul  4 14:50:04 localhost vmunix: [   10.290541] r8169 0000:02:00.0
> enp2s0: Link is Down
> Jul  4 14:50:04 localhost NetworkManager[325]: <info>
> [1562244604.1400] ovsdb: Could not connect: No such file or directory
> Jul  4 14:50:04 localhost dbus-daemon[322]: [system] Activating
> service name='org.freedesktop.ModemManager1' requested by ':1.3'
> (uid=0 pid=325 comm="/gnu/store/8pw0d0qbq5rjd0v61ibfripnsa7sajw7-networ")
> (using servicehelper)
> Jul  4 14:50:04 localhost ModemManager[419]: <info>  ModemManager
> (version 1.4.14) starting in system bus...
> Jul  4 14:50:04 localhost dbus-daemon[322]: [system] Successfully
> activated service 'org.freedesktop.ModemManager1'
> Jul  4 14:50:04 localhost NetworkManager[325]: <info>
> [1562244604.2193] modem-manager: ModemManager not available
> Jul  4 14:50:04 localhost NetworkManager[325]: <info>
> [1562244604.2308] modem-manager: ModemManager now available
> Jul  4 14:50:04 localhost ModemManager[419]: <warn>  (ttyUSB0): port
> attributes not fully set
> Jul  4 14:50:04 localhost ModemManager[419]: <warn>  (ttyUSB1): port
> attributes not fully set
> Jul  4 14:50:05 localhost gnome-session-binary[441]: WARNING: Could
> not parse desktop file orca-autostart.desktop or it references a not
> found TryExec binary
> Jul  4 14:50:06 localhost ModemManager[419]: <info>  Couldn't find
> support for device at
> '/sys/devices/pci0000:00/0000:00:1c.2/0000:02:00.0': not supported by
> any plugin
> Jul  4 14:50:07 localhost pulseaudio[474]: [pulseaudio] bluez5-util.c:
> GetManagedObjects() failed: org.freedesktop.DBus.Error.ServiceUnknown:
> The name org.bluez was not provided by any .service files
> Jul  4 14:50:07 localhost dbus-daemon[322]: [system] Activating
> service name='org.freedesktop.locale1' requested by ':1.15' (uid=983
> pid=463 comm="/gnu/store/nanzzlj24i8lc3n8ir8xrj47an45bma5-gnome-")
> (using servicehelper)
> Jul  4 14:50:07 localhost dbus-daemon[322]: [system] Successfully
> activated service 'org.freedesktop.locale1'
> Jul  4 14:50:07 localhost ModemManager[419]: <warn>  (ttyUSB1): port
> attributes not fully set
> Jul  4 14:50:08 localhost dbus-daemon[322]: [system] Activating
> service name='org.freedesktop.GeoClue2' requested by ':1.15' (uid=983
> pid=463 comm="/gnu/store/nanzzlj24i8lc3n8ir8xrj47an45bma5-gnome-")
> (using servicehelper)
> Jul  4 14:50:08 localhost dbus-daemon[322]: [system] Successfully
> activated service 'org.freedesktop.GeoClue2'
> Jul  4 14:50:08 localhost dbus-daemon[322]: [system] Activating
> service name='org.freedesktop.ColorManager' requested by ':1.41'
> (uid=983 pid=524
> comm="/gnu/store/p655k0kn5a7g8as02yx9zp57s2njp8bc-gnome-") (using
> servicehelper)
> Jul  4 14:50:08 localhost colord: Using mapping database file
> /var/lib/colord/mapping.db
> Jul  4 14:50:08 localhost colord: Using device database file
> /var/lib/colord/storage.db
> Jul  4 14:50:08 localhost colord: loaded plugin libcd_plugin_camera.so
> Jul  4 14:50:08 localhost colord: loaded plugin libcd_plugin_scanner.so
> Jul  4 14:50:08 localhost colord: loaded plugin libcd_plugin_sane.so
> Jul  4 14:50:08 localhost colord: Daemon ready for requests
> Jul  4 14:50:08 localhost dbus-daemon[322]: [system] Successfully
> activated service 'org.freedesktop.ColorManager'
> Jul  4 14:50:08 localhost colord: Profile added:
> icc-57f7c5419f2048eedd1a7d0acc0f9763
> Jul  4 14:50:08 localhost colord: Profile added:
> icc-1265c009f8b96e1e8575bc279b5a10f1
> Jul  4 14:50:08 localhost colord: Profile added:
> icc-d4e5f2920f8ea4be4d83f2007ceb17dc
> Jul  4 14:50:09 localhost colord: Automatic metadata add
> icc-57f7c5419f2048eedd1a7d0acc0f9763 to
> xrandr-Undefined-S2100-58097040
> Jul  4 14:50:09 localhost colord: Device added: 
> xrandr-Undefined-S2100-58097040
> Jul  4 14:50:09 localhost colord: Automatic metadata add
> icc-1265c009f8b96e1e8575bc279b5a10f1 to
> xrandr-Undefined-S2100-58090040
> Jul  4 14:50:09 localhost colord: Device added: 
> xrandr-Undefined-S2100-58090040
> Jul  4 14:50:10 localhost NetworkManager[325]: <info>
> [1562244610.1371] manager: startup complete
> Jul  4 14:50:13 localhost ModemManager[419]: <info>  Creating modem
> with plugin 'Huawei' and '4' ports
> Jul  4 14:50:13 localhost ModemManager[419]: <warn>  Could not grab
> port (usbmisc/cdc-wdm2): 'Cannot add port 'usbmisc/cdc-wdm2',
> unsupported'
> Jul  4 14:50:13 localhost ModemManager[419]: <warn>  (ttyUSB0): port
> attributes not fully set
> Jul  4 14:50:13 localhost ModemManager[419]: <info>  Modem for device
> at '/sys/devices/pci0000:00/0000:00:1a.7/usb1/1-2' successfully
> created
> Jul  4 14:50:14 localhost vmunix: [   20.456781] fuse init (API version 7.29)
> Jul  4 14:50:14 localhost dbus-daemon[322]: [system] Activating
> service name='org.freedesktop.UDisks2' requested by ':1.52' (uid=1000
> pid=696 comm="/gnu/store/57dh2aqlgysh76wayg8hdp4k2y4nllqz-gvfs-1")
> (using servicehelper)
> Jul  4 14:50:14 localhost dbus-daemon[322]: [system] Successfully
> activated service 'org.freedesktop.UDisks2'
> Jul  4 14:50:15 localhost pulseaudio[732]: [pulseaudio] bluez5-util.c:
> GetManagedObjects() failed: org.freedesktop.DBus.Error.ServiceUnknown:
> The name org.bluez was not provided by any .service files
> Jul  4 14:50:17 localhost ModemManager[419]: <warn>  couldn't load
> list of Own Numbers: 'Not found'
> Jul  4 14:50:18 localhost ModemManager[419]: <info>  Modem: state
> changed (unknown -> disabled)
> Jul  4 14:50:18 localhost NetworkManager[325]: <info>
> [1562244618.0894] modem["ttyUSB0"]: modem state changed, 'disabled'
> --> 'enabling' (reason: user preference)
> Jul  4 14:50:18 localhost NetworkManager[325]: <info>
> [1562244618.0902] manager: (ttyUSB0): new Broadband device
> (/org/freedesktop/NetworkManager/Devices/3)
> Jul  4 14:50:18 localhost NetworkManager[325]: <info>
> [1562244618.0904] device (ttyUSB0): state change: unmanaged ->
> unavailable (reason 'managed', sys-iface-state: 'external')
> Jul  4 14:50:18 localhost NetworkManager[325]: <info>
> [1562244618.0906] device (ttyUSB0): modem state 'enabling'
> Jul  4 14:50:18 localhost NetworkManager[325]: <info>
> [1562244618.0910] device (ttyUSB0): state change: unavailable ->
> disconnected (reason 'none', sys-iface-state: 'managed')
> Jul  4 14:50:18 localhost ModemManager[419]: <info>  Modem
> /org/freedesktop/ModemManager1/Modem/0: state changed (disabled ->
> enabling)
> Jul  4 14:50:18 localhost ModemManager[419]: <warn>  (ttyUSB0): port
> attributes not fully set
> Jul  4 14:50:18 localhost ModemManager[419]: <info>  Modem
> /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state
> changed (unknown -> registering)
> Jul  4 14:50:18 localhost ModemManager[419]: <info>  Modem
> /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state
> changed (registering -> home)
> Jul  4 14:50:19 localhost ModemManager[419]: <info>  Modem
> /org/freedesktop/ModemManager1/Modem/0: state changed (enabling ->
> registered)
> Jul  4 14:50:19 localhost NetworkManager[325]: <info>
> [1562244619.0452] modem["ttyUSB0"]: modem state changed, 'enabling'
> --> 'registered' (reason: user-requested)
> === after booting
> Jul  4 14:50:40 localhost NetworkManager[325]: <info>
> [1562244641.0058] device (ttyUSB0): Activation: starting connection
> 'Orange Standard access - with image compression'
> (7e9e6560-8b0e-4628-a7ae-c3d74955bbe0)
> Jul  4 14:50:40 localhost NetworkManager[325]: <info>
> [1562244641.0060] audit: op="connection-activate"
> uuid="7e9e6560-8b0e-4628-a7ae-c3d74955bbe0" name="Orange Standard
> access - with image compression" pid=728 uid=1000 result="success"
> Jul  4 14:50:40 localhost NetworkManager[325]: <info>
> [1562244641.0062] device (ttyUSB0): state change: disconnected ->
> prepare (reason 'none', sys-iface-state: 'managed')
> Jul  4 14:50:40 localhost NetworkManager[325]: <info>
> [1562244641.0064] manager: NetworkManager state is now CONNECTING
> Jul  4 14:50:41 localhost NetworkManager[325]: <info>
> [1562244641.0068] device (ttyUSB0): state change: prepare -> need-auth
> (reason 'none', sys-iface-state: 'managed')
> Jul  4 14:50:41 localhost NetworkManager[325]: <info>
> [1562244641.0092] device (ttyUSB0): state change: need-auth -> prepare
> (reason 'none', sys-iface-state: 'managed')
> Jul  4 14:50:41 localhost ModemManager[419]: <info>  Simple connect started...
> Jul  4 14:50:41 localhost ModemManager[419]: <info>  Simple connect
> state (4/8): Wait to get fully enabled
> Jul  4 14:50:41 localhost ModemManager[419]: <info>  Simple connect
> state (5/8): Register
> Jul  4 14:50:41 localhost ModemManager[419]: <info>  Simple connect
> state (6/8): Bearer
> Jul  4 14:50:41 localhost ModemManager[419]: <info>  Simple connect
> state (7/8): Connect
> Jul  4 14:50:41 localhost ModemManager[419]: <info>  Modem
> /org/freedesktop/ModemManager1/Modem/0: state changed (registered ->
> connecting)
> Jul  4 14:50:41 localhost NetworkManager[325]: <info>
> [1562244641.0142] modem["ttyUSB0"]: modem state changed, 'registered'
> --> 'connecting' (reason: user-requested)
> Jul  4 14:50:41 localhost ModemManager[419]: <warn>  (ttyUSB1): port
> attributes not fully set
> Jul  4 14:50:41 localhost ModemManager[419]: <info>  Modem
> /org/freedesktop/ModemManager1/Modem/0: state changed (connecting ->
> connected)
> Jul  4 14:50:41 localhost ModemManager[419]: <info>  Simple connect
> state (8/8): All done
> Jul  4 14:50:41 localhost NetworkManager[325]: <info>
> [1562244641.5331] modem["ttyUSB0"]: modem state changed, 'connecting'
> --> 'connected' (reason: user-requested)
> Jul  4 14:50:41 localhost NetworkManager[325]: <warn>
> [1562244641.5798] device (ttyUSB0): ip-ifname: set ifname 'ttyUSB1',
> unknown ifindex
> Jul  4 14:50:41 localhost NetworkManager[325]: <info>
> [1562244641.5801] device (ttyUSB0): state change: prepare -> config
> (reason 'none', sys-iface-state: 'managed')
> Jul  4 14:50:41 localhost NetworkManager[325]: <info>
> [1562244641.5805] device (ttyUSB0): state change: config -> ip-config
> (reason 'none', sys-iface-state: 'managed')
> Jul  4 14:50:41 localhost NetworkManager[325]: <warn>
> [1562244641.5806] device (ttyUSB0): interface ttyUSB1 not up for IP
> configuration
> Jul  4 14:50:41 localhost NetworkManager[325]: <info>
> [1562244641.5806] modem["ttyUSB0"]: using modem-specified IP timeout:
> 20 seconds
> Jul  4 14:50:41 localhost NetworkManager[325]: <info>
> [1562244641.5818] loaded PPP plugin
> /gnu/store/8pw0d0qbq5rjd0v61ibfripnsa7sajw7-network-manager-1.10.10/lib/NetworkManager/libnm-ppp-plugin.so
> Jul  4 14:50:41 localhost NetworkManager[325]: <error>
> [1562244641.5826] modem["ttyUSB0"]: error starting PPP: Could not find
> "pppd" binary
> Jul  4 14:50:41 localhost NetworkManager[325]: <info>
> [1562244641.5826] device (ttyUSB0): state change: ip-config -> failed
> (reason 'ppp-start-failed', sys-iface-state: 'managed')
> Jul  4 14:50:41 localhost NetworkManager[325]: <info>
> [1562244641.5828] manager: NetworkManager state is now DISCONNECTED
> Jul  4 14:50:41 localhost NetworkManager[325]: <warn>
> [1562244641.5838] device (ttyUSB0): Activation: failed for connection
> 'Orange Standard access - with image compression'
> Jul  4 14:50:41 localhost NetworkManager[325]: <info>
> [1562244641.5843] device (ttyUSB0): state change: failed ->
> disconnected (reason 'none', sys-iface-state: 'managed')
> Jul  4 14:50:41 localhost ModemManager[419]: <info>  Modem
> /org/freedesktop/ModemManager1/Modem/0: state changed (connected ->
> disconnecting)
> Jul  4 14:50:41 localhost NetworkManager[325]: <info>
> [1562244641.5891] modem["ttyUSB0"]: modem state changed, 'connected'
> --> 'disconnecting' (reason: user-requested)
> Jul  4 14:50:42 localhost ModemManager[419]: <warn>  (ttyUSB1): port
> attributes not fully set
> Jul  4 14:50:43 localhost ModemManager[419]: <info>  Modem
> /org/freedesktop/ModemManager1/Modem/0: state changed (disconnecting
> -> registered)
> Jul  4 14:50:43 localhost NetworkManager[325]: <info>
> [1562244643.6541] modem["ttyUSB0"]: modem state changed,
> 'disconnecting' --> 'registered' (reason: user-requested)
> […]
> Any help would be appreciated
> 

Cc'ing address@hidden.

I suppose the attached patch could help.  It builds, but I could not
test.

Adam, could you try it?  Just follow the Guix manual’s instructions on
Contributing and `git am the-attached-patch`.

Regards,
Florian

0001-gnu-network-manager-Add-ppp-input-and-configure-flag.patch
Description: Text document