You are not logged in.

#1 2020-09-23 08:44:25

lukaslsm
Member
Registered: 2020-09-23
Posts: 5

[SOLVED] CIFS Share freezes if accessed over WiFi

Hi,

whenever I try to copy a large file (about 5GiB in size) over to my Synology DS220+ NAS over WiFi using Dolphin, the program completely freezes at some point. If I try to copy the same file while connected via Ethernet, it works just fine (although with about 60MB/s it is almost double as slow as under Windows Server 2019 where I get a solid 100MB/s).

I mount my CIFS share with the following entry in my /etc/fstab:

//192.168.0.90/movies /mnt/movies cifs _netdev,noauto,user,username=#####,password=#####,file_mode=0666,dir_mode=0777,uid=1000,gid=100,iocharset=utf8,vers=3.0,noperm,x-systemd.automount 0 0

I also tried to set the vers=3.0 to a lower version, but even vers=1.0 resulted in the same freeze. It also makes no difference if I hard mount the drive with the mount command as root or if I use the fstab entry.

My relevant part of the journal looks like this, I started to copy the file between 23:33:14 and 23:33:41:

Sep 22 23:33:14 orndalis kernel: CIFS: Attempting to mount //192.168.0.90/movies
Sep 22 23:33:41 orndalis kdeinit5[1513]: Qt: Session management error: networkIdsList argument is NULL
Sep 22 23:33:43 orndalis wpa_supplicant[695]: wlp2s0: CTRL-EVENT-STARTED-CHANNEL-SWITCH freq=5500 ht_enabled=1 ch_offset=1 ch_width=80 MHz cf1=5530 cf2=0
Sep 22 23:33:43 orndalis dolphin[1290]: Couldn't start kuiserver from org.kde.kuiserver.service: QDBusError("org.freedesktop.DBus.Error.ServiceUnknown", "The name org.kde.kuiserver was not provided by any .service files")
Sep 22 23:33:43 orndalis wpa_supplicant[695]: wlp2s0: CTRL-EVENT-CHANNEL-SWITCH freq=5500 ht_enabled=1 ch_offset=1 ch_width=80 MHz cf1=5530 cf2=0
Sep 22 23:33:57 orndalis kernel: CIFS: VFS: \\192.168.0.90 Cancelling wait for mid 4616 cmd: 8
Sep 22 23:33:57 orndalis kernel: CIFS: VFS: Send error in read = -512
Sep 22 23:33:58 orndalis kernel: CIFS: VFS: \\192.168.0.90\movies Close interrupted close
Sep 22 23:33:58 orndalis kdeinit5[1584]: Qt: Session management error: networkIdsList argument is NULL
Sep 22 23:34:00 orndalis kdeinit5[1320]: kf.kio.slaves.file: copy() QUrl("file:///home/#####/Little Women.mkv") to QUrl("file:///mnt/movies/Movies/Little Women/Little Women.mkv>
Sep 22 23:35:13 orndalis wpa_supplicant[695]: wlp2s0: CTRL-EVENT-STARTED-CHANNEL-SWITCH freq=5640 ht_enabled=1 ch_offset=-1 ch_width=80 MHz cf1=5610 cf2=0
Sep 22 23:35:13 orndalis wpa_supplicant[695]: wlp2s0: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-40 noise=9999 txrate=650000
Sep 22 23:35:14 orndalis kernel: iwlwifi 0000:02:00.0: No beacon heard and the time event is over already...
Sep 22 23:35:14 orndalis kernel: wlp2s0: Connection to AP a0:ff:70:a8:f2:00 lost
Sep 22 23:35:16 orndalis kernel: iwlwifi 0000:02:00.0: fail to flush all tx fifo queues Q 5
Sep 22 23:35:16 orndalis kernel: iwlwifi 0000:02:00.0: Queue 5 is active on fifo 3 and stuck for 10000 ms. SW [8, 9] HW [8, 9] FH TRB=0x080305008
Sep 22 23:35:16 orndalis wpa_supplicant[695]: wlp2s0: CTRL-EVENT-SIGNAL-CHANGE above=0 signal=0 noise=9999 txrate=0
Sep 22 23:35:16 orndalis ntpd[707]: Deleting interface #2 wlp2s0, 192.168.0.67#123, interface stats: received=12, sent=12, dropped=0, active_time=145 secs
Sep 22 23:35:16 orndalis ntpd[707]: 144.76.43.40 local addr 192.168.0.67 -> <null>
Sep 22 23:35:16 orndalis ntpd[707]: 5.9.57.158 local addr 192.168.0.67 -> <null>
Sep 22 23:35:16 orndalis ntpd[707]: 217.144.138.234 local addr 192.168.0.67 -> <null>
Sep 22 23:35:16 orndalis ntpd[707]: 212.18.3.19 local addr 192.168.0.67 -> <null>
Sep 22 23:35:16 orndalis wpa_supplicant[695]: wlp2s0: CTRL-EVENT-DISCONNECTED bssid=a0:ff:70:a8:f2:00 reason=4 locally_generated=1
Sep 22 23:35:16 orndalis NetworkManager[693]: <info>  [1600810516.2746] device (wlp2s0): supplicant interface state: completed -> disconnected
Sep 22 23:35:16 orndalis NetworkManager[693]: <info>  [1600810516.2747] device (p2p-dev-wlp2s0): supplicant management interface state: completed -> disconnected
Sep 22 23:35:16 orndalis wpa_supplicant[695]: wlp2s0: Reject scan trigger since one is already pending
Sep 22 23:35:16 orndalis wpa_supplicant[695]: wlp2s0: Failed to initiate AP scan
Sep 22 23:35:17 orndalis wpa_supplicant[695]: wlp2s0: Reject scan trigger since one is already pending
Sep 22 23:35:17 orndalis wpa_supplicant[695]: wlp2s0: Failed to initiate AP scan
Sep 22 23:35:17 orndalis wpa_supplicant[695]: wlp2s0: SME: Trying to authenticate with a0:ff:70:a8:f1:f8 (SSID='Neuland' freq=2452 MHz)
Sep 22 23:35:17 orndalis kernel: wlp2s0: authenticate with a0:ff:70:a8:f1:f8
Sep 22 23:35:17 orndalis NetworkManager[693]: <info>  [1600810517.4052] device (wlp2s0): supplicant interface state: disconnected -> authenticating
Sep 22 23:35:17 orndalis NetworkManager[693]: <info>  [1600810517.4053] device (p2p-dev-wlp2s0): supplicant management interface state: disconnected -> authenticating
Sep 22 23:35:17 orndalis kernel: wlp2s0: send auth to a0:ff:70:a8:f1:f8 (try 1/3)
Sep 22 23:35:17 orndalis wpa_supplicant[695]: wlp2s0: Trying to associate with a0:ff:70:a8:f1:f8 (SSID='Neuland' freq=2452 MHz)
Sep 22 23:35:17 orndalis NetworkManager[693]: <info>  [1600810517.4110] device (wlp2s0): supplicant interface state: authenticating -> associating
Sep 22 23:35:17 orndalis NetworkManager[693]: <info>  [1600810517.4111] device (p2p-dev-wlp2s0): supplicant management interface state: authenticating -> associating
Sep 22 23:35:17 orndalis kernel: wlp2s0: authenticated
Sep 22 23:35:17 orndalis kernel: wlp2s0: associate with a0:ff:70:a8:f1:f8 (try 1/3)
Sep 22 23:35:17 orndalis wpa_supplicant[695]: wlp2s0: Associated with a0:ff:70:a8:f1:f8
Sep 22 23:35:17 orndalis wpa_supplicant[695]: wlp2s0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Sep 22 23:35:17 orndalis kernel: wlp2s0: RX AssocResp from a0:ff:70:a8:f1:f8 (capab=0x411 status=0 aid=3)
Sep 22 23:35:17 orndalis kernel: wlp2s0: associated
Sep 22 23:35:17 orndalis NetworkManager[693]: <info>  [1600810517.4260] device (wlp2s0): supplicant interface state: associating -> associated
Sep 22 23:35:17 orndalis NetworkManager[693]: <info>  [1600810517.4261] device (p2p-dev-wlp2s0): supplicant management interface state: associating -> associated
Sep 22 23:35:17 orndalis NetworkManager[693]: <info>  [1600810517.4369] device (wlp2s0): supplicant interface state: associated -> 4way_handshake
Sep 22 23:35:17 orndalis NetworkManager[693]: <info>  [1600810517.4370] device (p2p-dev-wlp2s0): supplicant management interface state: associated -> 4way_handshake
Sep 22 23:35:17 orndalis wpa_supplicant[695]: wlp2s0: WPA: Key negotiation completed with a0:ff:70:a8:f1:f8 [PTK=CCMP GTK=CCMP]
Sep 22 23:35:17 orndalis wpa_supplicant[695]: wlp2s0: CTRL-EVENT-CONNECTED - Connection to a0:ff:70:a8:f1:f8 completed [id=0 id_str=]
Sep 22 23:35:17 orndalis NetworkManager[693]: <info>  [1600810517.4579] device (wlp2s0): supplicant interface state: 4way_handshake -> completed
Sep 22 23:35:17 orndalis NetworkManager[693]: <info>  [1600810517.4610] device (p2p-dev-wlp2s0): supplicant management interface state: 4way_handshake -> completed
Sep 22 23:35:17 orndalis wpa_supplicant[695]: wlp2s0: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-44 noise=9999 txrate=144400
Sep 22 23:35:19 orndalis ntpd[707]: Listen normally on 3 wlp2s0 192.168.0.67:123
Sep 22 23:35:19 orndalis ntpd[707]: new interface(s) found: waking up resolver
Sep 22 23:35:39 orndalis kernel: CIFS: VFS: \\192.168.0.90 sends on sock 00000000aad43068 stuck for 15 seconds
Sep 22 23:35:39 orndalis kernel: CIFS: VFS: \\192.168.0.90 Error -11 sending data on socket to server
Sep 22 23:35:54 orndalis kernel: CIFS: VFS: \\192.168.0.90 sends on sock 00000000aad43068 stuck for 15 seconds
Sep 22 23:35:54 orndalis kernel: CIFS: VFS: \\192.168.0.90 Error -11 sending data on socket to server
Sep 22 23:36:55 orndalis kernel: CIFS: VFS: \\192.168.0.90 Send error in SessSetup = -11
Sep 22 23:37:13 orndalis wpa_supplicant[695]: wlp2s0: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-35 noise=9999 txrate=144400
Sep 22 23:37:20 orndalis NetworkManager[693]: <info>  [1600810640.0256] device (enp0s31f6): carrier: link connected
Sep 22 23:37:20 orndalis NetworkManager[693]: <info>  [1600810640.0269] device (enp0s31f6): state change: unavailable -> disconnected (reason 'carrier-changed', sys-iface-state: 'managed')
Sep 22 23:37:20 orndalis kernel: e1000e 0000:00:1f.6 enp0s31f6: NIC Link is Up 1000 Mbps Full Duplex, Flow Control: None
Sep 22 23:37:20 orndalis kernel: IPv6: ADDRCONF(NETDEV_CHANGE): enp0s31f6: link becomes ready
Sep 22 23:37:20 orndalis NetworkManager[693]: <info>  [1600810640.0296] policy: auto-activating connection 'Wired connection 1' (e7f8b717-caf7-3797-8392-0cac8b411420)
Sep 22 23:37:20 orndalis NetworkManager[693]: <info>  [1600810640.0299] device (enp0s31f6): Activation: starting connection 'Wired connection 1' (e7f8b717-caf7-3797-8392-0cac8b411420)
Sep 22 23:37:20 orndalis NetworkManager[693]: <info>  [1600810640.0300] device (enp0s31f6): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
Sep 22 23:37:20 orndalis NetworkManager[693]: <info>  [1600810640.0304] device (enp0s31f6): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Sep 22 23:37:20 orndalis NetworkManager[693]: <info>  [1600810640.0309] device (enp0s31f6): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
Sep 22 23:37:20 orndalis NetworkManager[693]: <info>  [1600810640.0312] dhcp4 (enp0s31f6): activation: beginning transaction (timeout in 45 seconds)
Sep 22 23:37:22 orndalis NetworkManager[693]: <info>  [1600810642.6529] dhcp6 (enp0s31f6): activation: beginning transaction (timeout in 45 seconds)
Sep 22 23:37:22 orndalis NetworkManager[693]: <info>  [1600810642.6617] dhcp6 (enp0s31f6): option dhcp6_name_servers   => '2a02:810a:1440:66a0:10:18ff:fea8:f1f4'
Sep 22 23:37:22 orndalis NetworkManager[693]: <info>  [1600810642.6618] dhcp6 (enp0s31f6): option ip6_address          => '2a02:810a:1440:66a0::4577'
Sep 22 23:37:22 orndalis NetworkManager[693]: <info>  [1600810642.6618] dhcp6 (enp0s31f6): state changed unknown -> bound
Sep 22 23:37:22 orndalis dbus-daemon[692]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.2' (uid=0 pid=693 comm="/usr/bin/NetworkManager --no-daemon ")
Sep 22 23:37:22 orndalis systemd[1]: Starting Network Manager Script Dispatcher Service...

I am using a Dell Precision 7510, my WiFi card is a

00:1f.6 Ethernet controller: Intel Corporation Ethernet Connection (2) I219-LM (rev 31)
02:00.0 Network controller: Intel Corporation Wireless 8260 (rev 3a)

It also freezes when I try to copy a file with a different file manager or using the cp command.

When I shut down my computer I get "A stop job is running..." until it times out, my journal looks like this:

Sep 22 23:39:36 orndalis audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=sddm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 22 23:39:58 orndalis kernel: CIFS: VFS: \\192.168.0.90 has not responded in 180 seconds. Reconnecting...
Sep 22 23:39:58 orndalis kactivitymanagerd[1300]: The X11 connection broke (error 1). Did the X11 server die?
Sep 22 23:40:42 orndalis kernel: INFO: task kworker/u16:2:152 blocked for more than 122 seconds.
Sep 22 23:40:42 orndalis kernel:       Tainted: G           OE     5.8.9-arch2-1 #1
Sep 22 23:40:42 orndalis kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Sep 22 23:40:42 orndalis kernel: kworker/u16:2   D    0   152      2 0x00004000
Sep 22 23:40:42 orndalis kernel: Workqueue: writeback wb_workfn (flush-cifs-1)
Sep 22 23:40:42 orndalis kernel: Call Trace:
Sep 22 23:40:42 orndalis kernel:  __schedule+0x2a6/0x810
Sep 22 23:40:42 orndalis kernel:  schedule+0x46/0xf0
Sep 22 23:40:42 orndalis kernel:  schedule_preempt_disabled+0x14/0x20
Sep 22 23:40:42 orndalis kernel:  __mutex_lock.constprop.0+0x180/0x510
Sep 22 23:40:42 orndalis kernel:  ? preempt_count_add+0x49/0xa0
Sep 22 23:40:42 orndalis kernel:  cifs_reopen_file+0x72/0xad0 [cifs]
Sep 22 23:40:42 orndalis kernel:  ? _raw_spin_unlock+0x16/0x30
Sep 22 23:40:42 orndalis kernel:  cifs_get_writable_file+0x15f/0x290 [cifs]
Sep 22 23:40:42 orndalis kernel:  cifs_writepages+0x16a/0xdb0 [cifs]
Sep 22 23:40:42 orndalis kernel:  do_writepages+0x38/0xf0
Sep 22 23:40:42 orndalis kernel:  ? update_load_avg+0x7e/0x660
Sep 22 23:40:42 orndalis kernel:  ? preempt_count_add+0x68/0xa0
Sep 22 23:40:42 orndalis kernel:  __writeback_single_inode+0x3d/0x3e0
Sep 22 23:40:42 orndalis kernel:  ? _raw_spin_lock+0x13/0x30
Sep 22 23:40:42 orndalis kernel:  writeback_sb_inodes+0x201/0x490
Sep 22 23:40:42 orndalis kernel:  __writeback_inodes_wb+0x4c/0xe0
Sep 22 23:40:42 orndalis kernel:  wb_writeback+0x241/0x350
Sep 22 23:40:42 orndalis kernel:  wb_workfn+0x3b5/0x600
Sep 22 23:40:42 orndalis kernel:  ? finish_task_switch+0x80/0x270
Sep 22 23:40:42 orndalis kernel:  process_one_work+0x1da/0x3d0
Sep 22 23:40:42 orndalis kernel:  worker_thread+0x4d/0x3d0
Sep 22 23:40:42 orndalis kernel:  ? rescuer_thread+0x410/0x410
Sep 22 23:40:42 orndalis kernel:  kthread+0x142/0x160
Sep 22 23:40:42 orndalis kernel:  ? __kthread_bind_mask+0x60/0x60
Sep 22 23:40:42 orndalis kernel:  ret_from_fork+0x22/0x30
Sep 22 23:40:42 orndalis kernel: INFO: task kworker/7:1:177 blocked for more than 122 seconds.
Sep 22 23:40:42 orndalis kernel:       Tainted: G           OE     5.8.9-arch2-1 #1
Sep 22 23:40:42 orndalis kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Sep 22 23:40:42 orndalis kernel: kworker/7:1     D    0   177      2 0x00004000
Sep 22 23:40:42 orndalis kernel: Workqueue: cifsoplockd cifs_oplock_break [cifs]
Sep 22 23:40:42 orndalis kernel: Call Trace:
Sep 22 23:40:42 orndalis kernel:  __schedule+0x2a6/0x810
Sep 22 23:40:42 orndalis kernel:  schedule+0x46/0xf0
Sep 22 23:40:42 orndalis kernel:  schedule_preempt_disabled+0x14/0x20
Sep 22 23:40:42 orndalis kernel:  __mutex_lock.constprop.0+0x180/0x510
Sep 22 23:40:42 orndalis kernel:  ? preempt_count_add+0x49/0xa0
Sep 22 23:40:42 orndalis kernel:  cifs_reopen_file+0x72/0xad0 [cifs]
Sep 22 23:40:42 orndalis kernel:  ? _raw_spin_unlock+0x16/0x30
Sep 22 23:40:42 orndalis kernel:  cifs_get_writable_file+0x15f/0x290 [cifs]
Sep 22 23:40:42 orndalis kernel:  cifs_writepages+0x16a/0xdb0 [cifs]
Sep 22 23:40:42 orndalis kernel:  do_writepages+0x38/0xf0
Sep 22 23:40:42 orndalis kernel:  ? sched_clock+0x5/0x10
Sep 22 23:40:42 orndalis kernel:  ? preempt_count_add+0x68/0xa0
Sep 22 23:40:42 orndalis kernel:  __filemap_fdatawrite_range+0xc5/0x100
Sep 22 23:40:42 orndalis kernel:  cifs_oplock_break+0x305/0x520 [cifs]
Sep 22 23:40:42 orndalis kernel:  process_one_work+0x1da/0x3d0
Sep 22 23:40:42 orndalis kernel:  worker_thread+0x4d/0x3d0
Sep 22 23:40:42 orndalis kernel:  ? rescuer_thread+0x410/0x410
Sep 22 23:40:42 orndalis kernel:  kthread+0x142/0x160
Sep 22 23:40:42 orndalis kernel:  ? __kthread_bind_mask+0x60/0x60
Sep 22 23:40:42 orndalis kernel:  ret_from_fork+0x22/0x30
Sep 22 23:41:04 orndalis systemd[1]: session-2.scope: Stopping timed out. Killing.
Sep 22 23:41:04 orndalis systemd[1]: session-2.scope: Killing process 1320 (file.so) with signal SIGKILL.
Sep 22 23:41:04 orndalis systemd[1]: session-2.scope: Failed with result 'timeout'.
░░ Subject: Unit failed
░░ Defined-By: systemd
░░ Support: https://lists.freedesktop.org/mailman/listinfo/systemd-devel
░░ 
░░ The unit session-2.scope has entered the 'failed' state with result 'timeout'
[...]
Sep 22 23:41:05 orndalis systemd[1]: Shutting down.
Sep 22 23:41:05 orndalis audit: BPF prog-id=8 op=UNLOAD
Sep 22 23:41:05 orndalis audit: BPF prog-id=7 op=UNLOAD
Sep 22 23:41:05 orndalis audit: BPF prog-id=4 op=UNLOAD
Sep 22 23:41:05 orndalis audit: BPF prog-id=3 op=UNLOAD
Sep 22 23:41:05 orndalis audit: BPF prog-id=6 op=UNLOAD
Sep 22 23:41:05 orndalis audit: BPF prog-id=5 op=UNLOAD
Sep 22 23:41:05 orndalis systemd[1]: Hardware watchdog 'iTCO_wdt', version 0
Sep 22 23:41:05 orndalis systemd[1]: Set hardware watchdog to 10min.
Sep 22 23:41:05 orndalis kernel: watchdog: watchdog0: watchdog did not stop!
Sep 22 23:41:05 orndalis systemd-shutdown[1]: Syncing filesystems and block devices.
Sep 22 23:41:45 orndalis systemd-shutdown[1]: Syncing filesystems and block devices - timed out, issuing SIGKILL to PID 2790.
Sep 22 23:41:45 orndalis systemd-shutdown[1]: Sending SIGTERM to remaining processes...
Sep 22 23:41:45 orndalis haveged[275]: haveged: Stopping due to signal 15
Sep 22 23:41:45 orndalis haveged[275]: haveged starting up
Sep 22 23:41:45 orndalis systemd-journald[276]: Journal stopped

Otherwise, my WiFi seems to work fine, I have no problems downloading or uploading files to the internet and I don't have any connection issues.
Can someone help me figuring out the cause of this problem?

Update May 2021: A recent update seems to have fixed the problem, I can now successfully mount my shares using

mount -t cifs -o netdev,user,username=####,password=####,file_mode=0666,dir_mode=0777,uid=1000,gid=985,iocharset=utf8,vers=3.0,noperm //192.168.0.10/${1} /mnt/${1}

Last edited by lukaslsm (2021-05-13 13:19:54)

Offline

Board footer

Powered by FluxBB