You are not logged in.

#1 2017-05-20 11:02:58

BlackAc3
Member
Registered: 2017-05-13
Posts: 20

Arch hang during heavy hard disk usage

Hi all,

It happened twice today, while Arch is heavily using the hard disk ( Downloading image files + moving my file in my hard disk ).
My system hang and not moving. I tried to pressed the num lock and it is not responding.
The second time it happened, I am able to move my mouse and close file manager after 30s. But after a while my system hang and the display is gone.
Is there any way I can debug or fix this?

Thanks in advance for those who helped me.

Offline

#2 2017-05-20 13:29:55

seth
Member
Registered: 2012-09-03
Posts: 49,967

Re: Arch hang during heavy hard disk usage

check "journalctl -b -1" after such incident (last boots dmesg)
Also use smartctl to check your disks healthyness: https://wiki.archlinux.org/index.php/S.M.A.R.T.

Edit: obviously you can already check older boots just as well (adjust "-1" to -2, -3, ...)

Last edited by seth (2017-05-20 13:30:50)

Offline

#3 2017-05-20 13:52:52

graysky
Wiki Maintainer
From: :wq
Registered: 2008-12-01
Posts: 10,595
Website

Re: Arch hang during heavy hard disk usage

Try linux-ck which uses a different scheduler aimed at responsiveness.


CPU-optimized Linux-ck packages @ Repo-ck  • AUR packagesZsh and other configs

Offline

#4 2017-05-20 13:57:29

Ropid
Member
Registered: 2015-03-09
Posts: 1,069

Re: Arch hang during heavy hard disk usage

I had something similar a while back with I think 4.7.x, 4.8.x kernels and the early 4.9.x kernel versions (not sure if I remember this right). I have a backup script scheduled to move things between an SSD and an HDD and the hang often happened while this script was doing its work in the background. I couldn't find a solution. I tried removing the "discard" mount option and scheduling "fstrim -a" instead, and this seemed to help a bit, but there was still a hang occasionally. For me, the machine unfroze after waiting for a minute or so. The problem went away with some kernel update.

If there's nothing wrong you see with smartctl, what you could try is use the "linux-lts" kernel. You'd know if your problem is caused by the current kernel version or if it's something else, like a hardware issue.

Last edited by Ropid (2017-05-20 13:58:06)

Offline

#5 2017-05-24 22:30:20

BlackAc3
Member
Registered: 2017-05-13
Posts: 20

Re: Arch hang during heavy hard disk usage

Hi guys,

Not sure why but until now it cant be reproduce again.

Offline

#6 2017-05-26 14:46:53

BlackAc3
Member
Registered: 2017-05-13
Posts: 20

Re: Arch hang during heavy hard disk usage

seth wrote:

check "journalctl -b -1" after such incident (last boots dmesg)
Also use smartctl to check your disks healthyness: https://wiki.archlinux.org/index.php/S.M.A.R.T.

Edit: obviously you can already check older boots just as well (adjust "-1" to -2, -3, ...)

It happen again today but this time I din copy anything except I am using google chrome.
For hard disk health, this is the output :

smartctl 6.5 2016-05-07 r4318 [x86_64-linux-4.10.13-1-ARCH] (local build)
Copyright (C) 2002-16, Bruce Allen, Christian Franke, www.smartmontools.org

=== START OF READ SMART DATA SECTION ===
SMART overall-health self-assessment test result: PASSED

From the journal : there is a lot i just copy the one almost the end :

May 26 22:16:56 falcon systemd[1]: Started Network Manager.
May 26 22:16:56 falcon systemd[1]: Reached target Network.
May 26 22:16:56 falcon systemd[1]: Starting Permit User Sessions...
May 26 22:16:56 falcon dbus[416]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service'
May 26 22:16:56 falcon systemd[1]: Starting Network Manager Script Dispatcher Service...
May 26 22:16:56 falcon systemd[1]: Started Permit User Sessions.
May 26 22:16:56 falcon systemd[1]: Starting GNOME Display Manager...
May 26 22:16:56 falcon systemd[1]: Reached target Multi-User System.
May 26 22:16:57 falcon dbus[416]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
May 26 22:16:57 falcon systemd[1]: Started Network Manager Script Dispatcher Service.
May 26 22:16:57 falcon NetworkManager[428]: <info>  [1495808217.0345] settings: loaded plugin keyfile: (c) 2007 - 2016 Red Hat, Inc.  To report bugs please use the NetworkManager mailing list.
May 26 22:16:57 falcon NetworkManager[428]: <info>  [1495808217.0425] settings: loaded plugin iBFT: (c) 2014 Red Hat, Inc.  To report bugs please use the NetworkManager mailing list. (/usr/lib/NetworkManager/lib
May 26 22:16:57 falcon NetworkManager[428]: <info>  [1495808217.0727] keyfile: new connection /etc/NetworkManager/system-connections/SuaVecito (3cf38f7c-3914-46c2-961f-c4d7997f6212,"SuaVecito")
May 26 22:16:57 falcon NetworkManager[428]: <info>  [1495808217.1102] keyfile: new connection /etc/NetworkManager/system-connections/DIRECT-BW-BRAVIA (061eecbe-8a79-4ac2-8ff7-aa3fe23e1aca,"DIRECT-BW-BRAVIA")
May 26 22:16:57 falcon systemd[1]: Started GNOME Display Manager.
May 26 22:16:57 falcon systemd[1]: Reached target Graphical Interface.
May 26 22:16:57 falcon systemd[1]: Startup finished in 2.752s (kernel) + 8.067s (userspace) = 30.656s.
May 26 22:16:57 falcon dbus[416]: [system] Activating via systemd: service name='org.freedesktop.Accounts' unit='accounts-daemon.service'
May 26 22:16:57 falcon systemd[1]: Reached target User and Group Name Lookups.
May 26 22:16:57 falcon systemd[1]: Starting Accounts Service...
May 26 22:16:57 falcon dbus[416]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service'
May 26 22:16:57 falcon dbus[416]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service'
May 26 22:16:57 falcon systemd[1]: Starting Authorization Manager...
May 26 22:16:57 falcon systemd[1]: Starting Hostname Service...
May 26 22:16:57 falcon polkitd[455]: Started polkitd version 0.114
May 26 22:16:57 falcon polkitd[455]: Loading rules from directory /etc/polkit-1/rules.d
May 26 22:16:57 falcon polkitd[455]: Loading rules from directory /usr/share/polkit-1/rules.d
May 26 22:16:57 falcon dbus[416]: [system] Successfully activated service 'org.freedesktop.hostname1'
May 26 22:16:57 falcon systemd[1]: Started Hostname Service.
May 26 22:16:57 falcon NetworkManager[428]: <info>  [1495808217.6001] settings: hostname: using hostnamed
May 26 22:16:57 falcon NetworkManager[428]: <info>  [1495808217.6002] settings: hostname changed from (none) to "falcon"
May 26 22:16:57 falcon NetworkManager[428]: <info>  [1495808217.6006] dhcp-init: default DHCP client 'dhclient' is not available
May 26 22:16:57 falcon NetworkManager[428]: <info>  [1495808217.6007] dhcp-init: Using DHCP client 'internal'
May 26 22:16:57 falcon NetworkManager[428]: <info>  [1495808217.6007] manager: WiFi enabled by radio killswitch; enabled by state file
May 26 22:16:57 falcon nm-dispatcher[443]: req:1 'hostname': new request (0 scripts)
May 26 22:16:57 falcon NetworkManager[428]: <info>  [1495808217.6008] manager: WWAN enabled by radio killswitch; enabled by state file
May 26 22:16:57 falcon nm-dispatcher[443]: req:1 'hostname': completed: no scripts
May 26 22:16:57 falcon NetworkManager[428]: <info>  [1495808217.6008] manager: Networking is enabled by state file
May 26 22:16:57 falcon polkitd[455]: Finished loading, compiling and executing 4 rules
May 26 22:16:57 falcon dbus[416]: [system] Successfully activated service 'org.freedesktop.PolicyKit1'
May 26 22:16:57 falcon systemd[1]: Started Authorization Manager.
May 26 22:16:57 falcon polkitd[455]: Acquired the name org.freedesktop.PolicyKit1 on the system bus
May 26 22:16:57 falcon accounts-daemon[450]: started daemon version 0.6.45
May 26 22:16:57 falcon dbus[416]: [system] Successfully activated service 'org.freedesktop.Accounts'
May 26 22:16:57 falcon systemd[1]: Started Accounts Service.
May 26 22:16:57 falcon NetworkManager[428]: <info>  [1495808217.6596] Loaded device plugin: NMBondDeviceFactory (internal)
May 26 22:16:57 falcon NetworkManager[428]: <info>  [1495808217.6597] Loaded device plugin: NMBridgeDeviceFactory (internal)
May 26 22:16:57 falcon NetworkManager[428]: <info>  [1495808217.6597] Loaded device plugin: NMDummyDeviceFactory (internal)
May 26 22:16:57 falcon NetworkManager[428]: <info>  [1495808217.6598] Loaded device plugin: NMEthernetDeviceFactory (internal)
May 26 22:16:57 falcon NetworkManager[428]: <info>  [1495808217.6598] Loaded device plugin: NMInfinibandDeviceFactory (internal)
May 26 22:16:57 falcon NetworkManager[428]: <info>  [1495808217.6599] Loaded device plugin: NMIPTunnelDeviceFactory (internal)
May 26 22:16:57 falcon NetworkManager[428]: <info>  [1495808217.6599] Loaded device plugin: NMMacsecDeviceFactory (internal)
May 26 22:16:57 falcon NetworkManager[428]: <info>  [1495808217.6599] Loaded device plugin: NMMacvlanDeviceFactory (internal)
May 26 22:16:57 falcon NetworkManager[428]: <info>  [1495808217.6603] Loaded device plugin: NMTunDeviceFactory (internal)
May 26 22:16:57 falcon NetworkManager[428]: <info>  [1495808217.6603] Loaded device plugin: NMVethDeviceFactory (internal)
May 26 22:16:57 falcon NetworkManager[428]: <info>  [1495808217.6604] Loaded device plugin: NMVlanDeviceFactory (internal)
May 26 22:16:57 falcon NetworkManager[428]: <info>  [1495808217.6604] Loaded device plugin: NMVxlanDeviceFactory (internal)
May 26 22:16:57 falcon NetworkManager[428]: <info>  [1495808217.6980] Loaded device plugin: NMWwanFactory (/usr/lib/NetworkManager/libnm-device-plugin-wwan.so)
May 26 22:16:57 falcon NetworkManager[428]: <info>  [1495808217.7190] Loaded device plugin: NMBluezManager (/usr/lib/NetworkManager/libnm-device-plugin-bluetooth.so)
May 26 22:16:57 falcon NetworkManager[428]: <info>  [1495808217.7290] Loaded device plugin: NMWifiFactory (/usr/lib/NetworkManager/libnm-device-plugin-wifi.so)
May 26 22:16:57 falcon NetworkManager[428]: <info>  [1495808217.7428] Loaded device plugin: NMAtmManager (/usr/lib/NetworkManager/libnm-device-plugin-adsl.so)
May 26 22:16:57 falcon NetworkManager[428]: <info>  [1495808217.8240] Loaded device plugin: NMTeamFactory (/usr/lib/NetworkManager/libnm-device-plugin-team.so)
May 26 22:16:57 falcon NetworkManager[428]: <info>  [1495808217.9142] device (lo): link connected
May 26 22:16:57 falcon NetworkManager[428]: <info>  [1495808217.9161] manager: (lo): new Generic device (/org/freedesktop/NetworkManager/Devices/1)
May 26 22:16:57 falcon NetworkManager[428]: <info>  [1495808217.9193] manager: (enp4s0): new Ethernet device (/org/freedesktop/NetworkManager/Devices/2)
May 26 22:16:57 falcon NetworkManager[428]: <info>  [1495808217.9310] keyfile: add connection in-memory (567e99a7-61a7-3142-8d99-f9fc2d763f1e,"Wired connection 1")
May 26 22:16:57 falcon NetworkManager[428]: <info>  [1495808217.9319] settings: (enp4s0): created default wired connection 'Wired connection 1'
May 26 22:16:57 falcon NetworkManager[428]: <info>  [1495808217.9342] device (enp4s0): state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
May 26 22:16:57 falcon kernel: IPv6: ADDRCONF(NETDEV_UP): enp4s0: link is not ready
May 26 22:16:57 falcon kernel: IPv6: ADDRCONF(NETDEV_UP): enp4s0: link is not ready
May 26 22:16:57 falcon NetworkManager[428]: <info>  [1495808217.9379] wifi-nl80211: (wlp0s20f0u14): using nl80211 for WiFi device control
May 26 22:16:57 falcon NetworkManager[428]: <info>  [1495808217.9385] device (wlp0s20f0u14): driver supports Access Point (AP) mode
May 26 22:16:57 falcon NetworkManager[428]: <info>  [1495808217.9408] manager: (wlp0s20f0u14): new 802.11 WiFi device (/org/freedesktop/NetworkManager/Devices/3)
May 26 22:16:57 falcon NetworkManager[428]: <info>  [1495808217.9429] device (wlp0s20f0u14): state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
May 26 22:16:57 falcon kernel: IPv6: ADDRCONF(NETDEV_UP): wlp0s20f0u14: link is not ready
May 26 22:16:58 falcon kernel: IPv6: ADDRCONF(NETDEV_UP): wlp0s20f0u14: link is not ready
May 26 22:16:58 falcon NetworkManager[428]: <info>  [1495808218.1618] device (wlp0s20f0u14): set-hw-addr: set MAC address to A2:0A:9C:8F:B7:28 (scanning)
May 26 22:16:58 falcon systemd[1]: Created slice User Slice of gdm.
May 26 22:16:58 falcon systemd[1]: Starting User Manager for UID 120...
May 26 22:16:58 falcon systemd-logind[427]: New session c1 of user gdm.
May 26 22:16:58 falcon systemd[1]: Started Session c1 of user gdm.
May 26 22:16:58 falcon systemd[469]: pam_unix(systemd-user:session): session opened for user gdm by (uid=0)
May 26 22:16:58 falcon kernel: IPv6: ADDRCONF(NETDEV_UP): wlp0s20f0u14: link is not ready
May 26 22:16:58 falcon systemd[469]: Listening on Sound System.
May 26 22:16:58 falcon systemd[469]: Starting D-Bus User Message Bus Socket.
May 26 22:16:58 falcon systemd[469]: Reached target Timers.
May 26 22:16:58 falcon systemd[469]: Reached target Paths.
May 26 22:16:58 falcon dbus[416]: [system] Activating via systemd: service name='fi.w1.wpa_supplicant1' unit='wpa_supplicant.service'
May 26 22:16:58 falcon systemd[1]: Starting WPA supplicant...
May 26 22:16:58 falcon systemd[469]: Listening on D-Bus User Message Bus Socket.
May 26 22:16:58 falcon systemd[469]: Reached target Sockets.
May 26 22:16:58 falcon systemd[469]: Reached target Basic System.
May 26 22:16:58 falcon systemd[469]: Starting Update XDG user dir configuration...
May 26 22:16:58 falcon systemd[469]: Started Update XDG user dir configuration.
May 26 22:16:58 falcon systemd[469]: Reached target Default.
May 26 22:16:58 falcon systemd[469]: Startup finished in 267ms.
May 26 22:16:58 falcon systemd[1]: Started User Manager for UID 120.
May 26 22:16:58 falcon dbus[416]: [system] Successfully activated service 'fi.w1.wpa_supplicant1'
May 26 22:16:58 falcon systemd[1]: Started WPA supplicant.
May 26 22:16:58 falcon wpa_supplicant[473]: Successfully initialized wpa_supplicant
May 26 22:16:58 falcon NetworkManager[428]: <info>  [1495808218.5618] supplicant: wpa_supplicant running
May 26 22:16:58 falcon NetworkManager[428]: <info>  [1495808218.5618] device (wlp0s20f0u14): supplicant interface state: init -> starting
May 26 22:16:58 falcon systemd[469]: Started D-Bus User Message Bus.
May 26 22:16:58 falcon NetworkManager[428]: <info>  [1495808218.7607] sup-iface[0x1ab1830,wlp0s20f0u14]: supports 4 scan SSIDs
May 26 22:16:58 falcon NetworkManager[428]: <info>  [1495808218.7623] device (wlp0s20f0u14): supplicant interface state: starting -> ready
May 26 22:16:58 falcon NetworkManager[428]: <info>  [1495808218.7624] device (wlp0s20f0u14): state change: unavailable -> disconnected (reason 'supplicant-available') [20 30 42]
May 26 22:16:58 falcon kernel: IPv6: ADDRCONF(NETDEV_UP): wlp0s20f0u14: link is not ready
May 26 22:17:00 falcon NetworkManager[428]: <info>  [1495808220.0324] device (wlp0s20f0u14): supplicant interface state: ready -> inactive
May 26 22:17:00 falcon NetworkManager[428]: <info>  [1495808220.0351] policy: auto-activating connection 'SuaVecito'
May 26 22:17:00 falcon NetworkManager[428]: <info>  [1495808220.0358] device (wlp0s20f0u14): Activation: starting connection 'SuaVecito' (3cf38f7c-3914-46c2-961f-c4d7997f6212)
May 26 22:17:00 falcon NetworkManager[428]: <info>  [1495808220.0360] device (wlp0s20f0u14): state change: disconnected -> prepare (reason 'none') [30 40 0]
May 26 22:17:00 falcon NetworkManager[428]: <info>  [1495808220.0361] manager: NetworkManager state is now CONNECTING
May 26 22:17:00 falcon NetworkManager[428]: <info>  [1495808220.3123] device (wlp0s20f0u14): set-hw-addr: reset MAC address to 18:A6:F7:0E:FA:AD (preserve)
May 26 22:17:00 falcon NetworkManager[428]: <info>  [1495808220.5242] device (wlp0s20f0u14): supplicant interface state: inactive -> disabled
May 26 22:17:00 falcon NetworkManager[428]: <info>  [1495808220.5244] device (wlp0s20f0u14): state change: prepare -> config (reason 'none') [40 50 0]
May 26 22:17:00 falcon NetworkManager[428]: <info>  [1495808220.5245] device (wlp0s20f0u14): Activation: (wifi) access point 'SuaVecito' has security, but secrets are required.
May 26 22:17:00 falcon NetworkManager[428]: <info>  [1495808220.5245] device (wlp0s20f0u14): state change: config -> need-auth (reason 'none') [50 60 0]
May 26 22:17:00 falcon kernel: IPv6: ADDRCONF(NETDEV_UP): wlp0s20f0u14: link is not ready
May 26 22:17:00 falcon NetworkManager[428]: <info>  [1495808220.5268] device (wlp0s20f0u14): state change: need-auth -> prepare (reason 'none') [60 40 0]
May 26 22:17:00 falcon NetworkManager[428]: <info>  [1495808220.5270] device (wlp0s20f0u14): state change: prepare -> config (reason 'none') [40 50 0]
May 26 22:17:00 falcon NetworkManager[428]: <info>  [1495808220.5271] device (wlp0s20f0u14): Activation: (wifi) connection 'SuaVecito' has security, and secrets exist.  No new secrets needed.
May 26 22:17:00 falcon NetworkManager[428]: <info>  [1495808220.5271] Config: added 'ssid' value 'SuaVecito'
May 26 22:17:00 falcon NetworkManager[428]: <info>  [1495808220.5271] Config: added 'scan_ssid' value '1'
May 26 22:17:00 falcon NetworkManager[428]: <info>  [1495808220.5271] Config: added 'key_mgmt' value 'WPA-PSK'
May 26 22:17:00 falcon NetworkManager[428]: <info>  [1495808220.5271] Config: added 'psk' value '<hidden>'
May 26 22:17:00 falcon NetworkManager[428]: <info>  [1495808220.5627] device (wlp0s20f0u14): supplicant interface state: disabled -> inactive
May 26 22:17:00 falcon NetworkManager[428]: <info>  [1495808220.5950] device (wlp0s20f0u14): supplicant interface state: inactive -> scanning
May 26 22:17:00 falcon dbus[416]: [system] Activating via systemd: service name='org.freedesktop.UPower' unit='upower.service'
May 26 22:17:00 falcon systemd[1]: Starting Daemon for power management...
May 26 22:17:01 falcon dbus[416]: [system] Successfully activated service 'org.freedesktop.UPower'
May 26 22:17:01 falcon systemd[1]: Started Daemon for power management.
May 26 22:17:01 falcon wpa_supplicant[473]: wlp0s20f0u14: SME: Trying to authenticate with 74:18:65:07:de:74 (SSID='SuaVecito' freq=2462 MHz)
May 26 22:17:01 falcon kernel: wlp0s20f0u14: authenticate with 74:18:65:07:de:74
May 26 22:17:02 falcon kernel: wlp0s20f0u14: send auth to 74:18:65:07:de:74 (try 1/3)
May 26 22:17:02 falcon NetworkManager[428]: <info>  [1495808222.1223] device (wlp0s20f0u14): supplicant interface state: scanning -> authenticating
May 26 22:17:02 falcon wpa_supplicant[473]: wlp0s20f0u14: Trying to associate with 74:18:65:07:de:74 (SSID='SuaVecito' freq=2462 MHz)
May 26 22:17:02 falcon kernel: wlp0s20f0u14: authenticated
May 26 22:17:02 falcon kernel: wlp0s20f0u14: associate with 74:18:65:07:de:74 (try 1/3)
May 26 22:17:02 falcon NetworkManager[428]: <info>  [1495808222.1394] device (wlp0s20f0u14): supplicant interface state: authenticating -> associating
May 26 22:17:02 falcon kernel: wlp0s20f0u14: RX AssocResp from 74:18:65:07:de:74 (capab=0x411 status=0 aid=1)
May 26 22:17:02 falcon wpa_supplicant[473]: wlp0s20f0u14: Associated with 74:18:65:07:de:74
May 26 22:17:02 falcon wpa_supplicant[473]: wlp0s20f0u14: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
May 26 22:17:02 falcon kernel: IPv6: ADDRCONF(NETDEV_CHANGE): wlp0s20f0u14: link becomes ready
May 26 22:17:02 falcon kernel: wlp0s20f0u14: associated
May 26 22:17:02 falcon NetworkManager[428]: <info>  [1495808222.1689] device (wlp0s20f0u14): supplicant interface state: associating -> associated
May 26 22:17:02 falcon org.gnome.Shell.desktop[488]: glamor: EGL version 1.4 (DRI2):
May 26 22:17:02 falcon NetworkManager[428]: <info>  [1495808222.9142] device (wlp0s20f0u14): supplicant interface state: associated -> 4-way handshake
May 26 22:17:03 falcon NetworkManager[428]: <info>  [1495808223.0762] device (wlp0s20f0u14): supplicant interface state: 4-way handshake -> group handshake
May 26 22:17:03 falcon dbus-daemon[479]: Activating via systemd: service name='org.a11y.Bus' unit='at-spi-dbus-bus.service'
May 26 22:17:03 falcon systemd[469]: Starting Accessibility services bus...
May 26 22:17:03 falcon dbus-daemon[479]: Successfully activated service 'org.a11y.Bus'
May 26 22:17:03 falcon systemd[469]: Started Accessibility services bus.
May 26 22:17:03 falcon at-spi-bus-launcher[535]: Activating service name='org.a11y.atspi.Registry'
May 26 22:17:03 falcon at-spi-bus-launcher[535]: Successfully activated service 'org.a11y.atspi.Registry'
May 26 22:17:03 falcon org.a11y.atspi.Registry[540]: SpiRegistry daemon is running with well-known name - org.a11y.atspi.Registry
May 26 22:17:03 falcon systemd[469]: Starting Sound Service...
May 26 22:17:04 falcon dbus[416]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service'
May 26 22:17:04 falcon systemd[1]: Starting RealtimeKit Scheduling Policy Service...
May 26 22:17:04 falcon dbus[416]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1'
May 26 22:17:04 falcon systemd[1]: Started RealtimeKit Scheduling Policy Service.
May 26 22:17:04 falcon rtkit-daemon[547]: Successfully called chroot.
May 26 22:17:04 falcon rtkit-daemon[547]: Successfully dropped privileges.
May 26 22:17:04 falcon rtkit-daemon[547]: Successfully limited resources.
May 26 22:17:04 falcon rtkit-daemon[547]: Running.
May 26 22:17:04 falcon rtkit-daemon[547]: Canary thread running.
May 26 22:17:04 falcon rtkit-daemon[547]: Watchdog thread running.
May 26 22:17:04 falcon rtkit-daemon[547]: Supervising 0 threads of 0 processes of 1 users.
May 26 22:17:04 falcon rtkit-daemon[547]: Supervising 0 threads of 0 processes of 1 users.
May 26 22:17:04 falcon rtkit-daemon[547]: Supervising 0 threads of 0 processes of 1 users.
May 26 22:17:04 falcon rtkit-daemon[547]: Supervising 0 threads of 0 processes of 1 users.
May 26 22:17:04 falcon rtkit-daemon[547]: Supervising 0 threads of 0 processes of 1 users.
May 26 22:17:04 falcon rtkit-daemon[547]: Supervising 0 threads of 0 processes of 1 users.
May 26 22:17:04 falcon rtkit-daemon[547]: Supervising 0 threads of 0 processes of 1 users.
May 26 22:17:04 falcon rtkit-daemon[547]: Supervising 0 threads of 0 processes of 1 users.
May 26 22:17:04 falcon rtkit-daemon[547]: Supervising 0 threads of 0 processes of 1 users.
May 26 22:17:04 falcon rtkit-daemon[547]: Supervising 0 threads of 0 processes of 1 users.
May 26 22:17:04 falcon dbus[416]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service'
May 26 22:17:04 falcon dbus[416]: [system] Activation via systemd failed for unit 'dbus-org.bluez.service': Unit dbus-org.bluez.service not found.
May 26 22:17:04 falcon systemd[469]: Started Sound Service.
May 26 22:17:04 falcon pulseaudio[546]: E: [pulseaudio] bluez5-util.c: GetManagedObjects() failed: org.freedesktop.systemd1.NoSuchUnit: Unit dbus-org.bluez.service not found.
May 26 22:17:05 falcon wpa_supplicant[473]: wlp0s20f0u14: WPA: Key negotiation completed with 74:18:65:07:de:74 [PTK=CCMP GTK=CCMP]
May 26 22:17:05 falcon wpa_supplicant[473]: wlp0s20f0u14: CTRL-EVENT-CONNECTED - Connection to 74:18:65:07:de:74 completed [id=0 id_str=]
May 26 22:17:05 falcon NetworkManager[428]: <info>  [1495808225.9424] device (wlp0s20f0u14): supplicant interface state: group handshake -> completed
May 26 22:17:05 falcon NetworkManager[428]: <info>  [1495808225.9425] device (wlp0s20f0u14): Activation: (wifi) Stage 2 of 5 (Device Configure) successful.  Connected to wireless network 'SuaVecito'.
May 26 22:17:05 falcon NetworkManager[428]: <info>  [1495808225.9427] device (wlp0s20f0u14): state change: config -> ip-config (reason 'none') [50 70 0]
May 26 22:17:05 falcon NetworkManager[428]: <info>  [1495808225.9436] dhcp4 (wlp0s20f0u14): activation: beginning transaction (timeout in 45 seconds)
May 26 22:17:06 falcon gnome-shell[488]: JS WARNING: [resource:///org/gnome/shell/ui/main.js 317]: reference to undefined property global.stage
May 26 22:17:06 falcon gnome-shell[488]: JS WARNING: [resource:///org/gnome/shell/ui/layout.js 217]: reference to undefined property global.window_group
May 26 22:17:06 falcon gnome-shell[488]: JS WARNING: [resource:///org/gnome/shell/ui/layout.js 899]: reference to undefined property this.monitors[this.findIndexForActor(...)]
May 26 22:17:06 falcon gnome-shell[488]: JS WARNING: [resource:///org/gnome/shell/ui/osdMonitorLabeler.js 59]: reference to undefined property Meta.MonitorManager
May 26 22:17:07 falcon dbus-daemon[479]: Activating via systemd: service name='org.freedesktop.impl.portal.PermissionStore' unit='xdg-permission-store.service'
May 26 22:17:07 falcon systemd[469]: Starting sandboxed app permission store...
May 26 22:17:07 falcon gnome-shell[488]: JS WARNING: [resource:///org/gnome/shell/ui/panel.js 1107]: reference to undefined property indicator.menu._openChangedId
May 26 22:17:07 falcon dbus[416]: [system] Activating via systemd: service name='org.freedesktop.locale1' unit='dbus-org.freedesktop.locale1.service'
May 26 22:17:07 falcon dbus-daemon[479]: Successfully activated service 'org.freedesktop.impl.portal.PermissionStore'
May 26 22:17:07 falcon systemd[469]: Started sandboxed app permission store.
May 26 22:17:07 falcon systemd[1]: Starting Locale Service...
May 26 22:17:07 falcon gnome-shell[488]: Failed to launch ibus-daemon: Failed to execute child process “ibus-daemon” (No such file or directory)
May 26 22:17:07 falcon dbus[416]: [system] Successfully activated service 'org.freedesktop.locale1'
May 26 22:17:07 falcon systemd[1]: Started Locale Service.
May 26 22:17:07 falcon gnome-shell[488]: JS WARNING: [resource:///org/gnome/shell/ui/slider.js 34]: reference to undefined property St.GenericAccessible
May 26 22:17:07 falcon dbus[416]: [system] Activating via systemd: service name='org.freedesktop.GeoClue2' unit='geoclue.service'
May 26 22:17:07 falcon systemd[1]: Starting Location Lookup Service...
May 26 22:17:07 falcon NetworkManager[428]: <info>  [1495808227.6337] dhcp6 (wlp0s20f0u14): activation: beginning transaction (timeout in 45 seconds)
May 26 22:17:07 falcon dbus[416]: [system] Activating via systemd: service name='org.freedesktop.Avahi' unit='dbus-org.freedesktop.Avahi.service'
May 26 22:17:07 falcon dbus[416]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.Avahi.service': Unit dbus-org.freedesktop.Avahi.service not found.
May 26 22:17:07 falcon geoclue[573]: Failed to connect to avahi service: Daemon not running
May 26 22:17:07 falcon dbus[416]: [system] Successfully activated service 'org.freedesktop.GeoClue2'
May 26 22:17:07 falcon systemd[1]: Started Location Lookup Service.
May 26 22:17:07 falcon polkitd[455]: Registered Authentication Agent for unix-session:c1 (system bus name :1.24 [/usr/bin/gnome-shell], object path /org/freedesktop/PolicyKit1/AuthenticationAgent, locale en_US.U
May 26 22:17:07 falcon gnome-shell[488]: No permission to trigger offline updates: Polkit.Error: GDBus.Error:org.freedesktop.PolicyKit1.Error.Failed: Action org.freedesktop.packagekit.trigger-offline-update is n
May 26 22:17:08 falcon gnome-shell[488]: JS WARNING: [resource:///org/gnome/shell/gdm/util.js 331]: reference to undefined property this._preemptingService
May 26 22:17:08 falcon gnome-shell[488]: JS WARNING: [resource:///org/gnome/gjs/modules/tweener/tweener.js 538]: reference to undefined property properties[istr].arrayIndex
May 26 22:17:08 falcon gnome-shell[488]: Error looking up permission: GDBus.Error:org.freedesktop.portal.Error.NotFound: No entry for geolocation
May 26 22:17:09 falcon dbus[416]: [system] Activating via systemd: service name='org.freedesktop.ColorManager' unit='colord.service'
May 26 22:17:09 falcon systemd[1]: Starting Manage, Install and Generate Color Profiles...
May 26 22:17:09 falcon gnome-session-binary[481]: Entering running state
May 26 22:17:09 falcon xbrlapi.desktop[667]: openConnection: connect: No such file or directory
May 26 22:17:09 falcon xbrlapi.desktop[667]: cannot connect to braille devices daemon brltty at :0
May 26 22:17:09 falcon gnome-shell[488]: JS WARNING: [resource:///org/gnome/shell/ui/windowManager.js 1493]: reference to undefined property actor.meta_window
May 26 22:17:09 falcon dbus[416]: [system] Successfully activated service 'org.freedesktop.ColorManager'
May 26 22:17:09 falcon systemd[1]: Started Manage, Install and Generate Color Profiles.
May 26 22:17:09 falcon colord[670]: /usr/lib/colord/colord-sane: error while loading shared libraries: libsane.so.1: cannot open shared object file: No such file or directory
May 26 22:17:09 falcon gnome-shell[488]: JS WARNING: [resource:///org/gnome/shell/ui/status/network.js 1897]: reference to undefined property this._mainConnectionStateChangedId
May 26 22:17:10 falcon NetworkManager[428]: <info>  [1495808230.4025] dhcp4 (wlp0s20f0u14):   address 192.168.1.12
May 26 22:17:10 falcon NetworkManager[428]: <info>  [1495808230.4026] dhcp4 (wlp0s20f0u14):   plen 24
May 26 22:17:10 falcon NetworkManager[428]: <info>  [1495808230.4026] dhcp4 (wlp0s20f0u14):   expires in 259200 seconds
May 26 22:17:10 falcon NetworkManager[428]: <info>  [1495808230.4026] dhcp4 (wlp0s20f0u14):   nameserver '192.168.1.1'
May 26 22:17:10 falcon NetworkManager[428]: <info>  [1495808230.4027] dhcp4 (wlp0s20f0u14):   hostname 'dhcppc10'
May 26 22:17:10 falcon NetworkManager[428]: <info>  [1495808230.4027] dhcp4 (wlp0s20f0u14):   gateway 192.168.1.1
May 26 22:17:10 falcon NetworkManager[428]: <info>  [1495808230.4250] dhcp4 (wlp0s20f0u14): state changed unknown -> bound
May 26 22:17:10 falcon NetworkManager[428]: <info>  [1495808230.4278] device (wlp0s20f0u14): state change: ip-config -> ip-check (reason 'none') [70 80 0]
May 26 22:17:10 falcon NetworkManager[428]: <info>  [1495808230.4288] device (wlp0s20f0u14): state change: ip-check -> secondaries (reason 'none') [80 90 0]
May 26 22:17:10 falcon NetworkManager[428]: <info>  [1495808230.4307] device (wlp0s20f0u14): state change: secondaries -> activated (reason 'none') [90 100 0]
May 26 22:17:10 falcon NetworkManager[428]: <info>  [1495808230.4313] manager: NetworkManager state is now CONNECTED_LOCAL
May 26 22:17:10 falcon NetworkManager[428]: <info>  [1495808230.4727] manager: NetworkManager state is now CONNECTED_SITE
May 26 22:17:10 falcon NetworkManager[428]: <info>  [1495808230.4729] policy: set 'SuaVecito' (wlp0s20f0u14) as default for IPv4 routing and DNS
May 26 22:17:10 falcon NetworkManager[428]: <info>  [1495808230.4730] policy: set 'SuaVecito' (wlp0s20f0u14) as default for IPv6 routing and DNS
May 26 22:17:10 falcon NetworkManager[428]: <info>  [1495808230.4732] dns-mgr: Writing DNS information to /usr/bin/resolvconf
May 26 22:17:10 falcon NetworkManager[428]: <info>  [1495808230.6737] device (wlp0s20f0u14): Activation: successful, device activated.
May 26 22:17:10 falcon NetworkManager[428]: <info>  [1495808230.6753] manager: startup complete
May 26 22:17:10 falcon dbus[416]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service'
May 26 22:17:10 falcon systemd[1]: Starting Network Manager Script Dispatcher Service...
May 26 22:17:10 falcon dbus[416]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
May 26 22:17:10 falcon systemd[1]: Started Network Manager Script Dispatcher Service.
May 26 22:17:10 falcon nm-dispatcher[718]: req:1 'up' [wlp0s20f0u14]: new request (0 scripts)
May 26 22:17:10 falcon nm-dispatcher[718]: req:1 'up' [wlp0s20f0u14]: completed: no scripts
May 26 22:17:23 falcon NetworkManager[428]: <info>  [1495808243.3731] dhcp6 (wlp0s20f0u14):   address 2001:e68:4416:326e::100/128 lft 259200sec pref 172800sec lifetime 27-27[172800,259200] src dhcp
May 26 22:17:23 falcon NetworkManager[428]: <info>  [1495808243.3732] dhcp6 (wlp0s20f0u14):   nameserver fe80::7618:65ff:fe07:de74
May 26 22:17:23 falcon NetworkManager[428]: <info>  [1495808243.3732] dhcp6 (wlp0s20f0u14): state changed unknown -> bound
May 26 22:17:23 falcon dbus[416]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service'
May 26 22:17:23 falcon systemd[1]: Starting Network Manager Script Dispatcher Service...
May 26 22:17:23 falcon dbus[416]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
May 26 22:17:23 falcon systemd[1]: Started Network Manager Script Dispatcher Service.
May 26 22:17:23 falcon nm-dispatcher[730]: req:1 'dhcp6-change' [wlp0s20f0u14]: new request (0 scripts)
May 26 22:17:23 falcon nm-dispatcher[730]: req:1 'dhcp6-change' [wlp0s20f0u14]: completed: no scripts
May 26 22:17:27 falcon systemd[469]: Time has been changed
May 26 22:17:27 falcon systemd[1]: Time has been changed
May 26 22:17:27 falcon systemd-timesyncd[414]: Synchronized to time server [2001:418:3ff::53]:123 (2.arch.pool.ntp.org).
May 26 22:17:42 falcon NetworkManager[428]: <info>  [1495808262.0501] connectivity: (wlp0s20f0u14) timed out
May 26 22:17:42 falcon NetworkManager[428]: <info>  [1495808262.0502] manager: NetworkManager state is now CONNECTED_GLOBAL
May 26 22:17:42 falcon dbus[416]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service'
May 26 22:17:42 falcon systemd[1]: Starting Network Manager Script Dispatcher Service...
May 26 22:17:42 falcon dbus[416]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
May 26 22:17:42 falcon systemd[1]: Started Network Manager Script Dispatcher Service.
May 26 22:17:42 falcon nm-dispatcher[744]: req:1 'connectivity-change': new request (0 scripts)
May 26 22:17:42 falcon nm-dispatcher[744]: req:1 'connectivity-change': completed: no scripts
May 26 22:18:18 falcon gdm-password][748]: pam_unix(gdm-password:session): session opened for user ezpz93 by (uid=0)
May 26 22:18:18 falcon systemd[1]: Created slice User Slice of ezpz93.
May 26 22:18:18 falcon systemd[1]: Starting User Manager for UID 1000...
May 26 22:18:18 falcon systemd-logind[427]: New session c2 of user ezpz93.
May 26 22:18:18 falcon systemd[1]: Started Session c2 of user ezpz93.
May 26 22:18:18 falcon systemd[754]: pam_unix(systemd-user:session): session opened for user ezpz93 by (uid=0)
May 26 22:18:18 falcon systemd[754]: Listening on Sound System.
May 26 22:18:18 falcon systemd[754]: Starting D-Bus User Message Bus Socket.
May 26 22:18:18 falcon systemd[754]: Reached target Timers.
May 26 22:18:18 falcon systemd[754]: Reached target Paths.
May 26 22:18:18 falcon systemd[754]: Listening on D-Bus User Message Bus Socket.
May 26 22:18:18 falcon systemd[754]: Reached target Sockets.
May 26 22:18:18 falcon systemd[754]: Reached target Basic System.
May 26 22:18:18 falcon systemd[754]: Starting Update XDG user dir configuration...
May 26 22:18:18 falcon systemd[754]: Started Update XDG user dir configuration.
May 26 22:18:18 falcon systemd[754]: Reached target Default.
May 26 22:18:18 falcon systemd[754]: Startup finished in 13ms.
May 26 22:18:18 falcon systemd[1]: Started User Manager for UID 1000.
May 26 22:18:18 falcon systemd[754]: Started D-Bus User Message Bus.
May 26 22:18:19 falcon gnome-keyring-ssh.desktop[796]: SSH_AUTH_SOCK=/run/user/1000/keyring/ssh
May 26 22:18:19 falcon gnome-keyring-secrets.desktop[797]: SSH_AUTH_SOCK=/run/user/1000/keyring/ssh
May 26 22:18:20 falcon dbus-daemon[769]: Activating via systemd: service name='org.gtk.vfs.Daemon' unit='gvfs-daemon.service'
May 26 22:18:20 falcon dbus-daemon[769]: Successfully activated service 'org.gtk.vfs.Daemon'
May 26 22:18:20 falcon systemd[754]: Starting Virtual filesystem service...
May 26 22:18:20 falcon kernel: fuse init (API version 7.26)
May 26 22:18:20 falcon systemd[754]: Started Virtual filesystem service.
May 26 22:18:20 falcon systemd[1]: Mounting FUSE Control File System...
May 26 22:18:20 falcon systemd[1]: Mounted FUSE Control File System.
May 26 22:18:20 falcon org.gnome.Shell.desktop[802]: glamor: EGL version 1.4 (DRI2):
May 26 22:18:20 falcon dbus-daemon[769]: Activating via systemd: service name='org.a11y.Bus' unit='at-spi-dbus-bus.service'
May 26 22:18:20 falcon systemd[754]: Starting Accessibility services bus...
May 26 22:18:20 falcon dbus-daemon[769]: Successfully activated service 'org.a11y.Bus'
May 26 22:18:20 falcon systemd[754]: Started Accessibility services bus.
May 26 22:18:20 falcon at-spi-bus-launcher[836]: Activating service name='org.a11y.atspi.Registry'
May 26 22:18:20 falcon at-spi-bus-launcher[836]: Successfully activated service 'org.a11y.atspi.Registry'
May 26 22:18:20 falcon org.a11y.atspi.Registry[841]: SpiRegistry daemon is running with well-known name - org.a11y.atspi.Registry
May 26 22:18:20 falcon systemd[754]: Starting Sound Service...
May 26 22:18:20 falcon rtkit-daemon[547]: Successfully made thread 847 of process 847 (/usr/bin/pulseaudio) owned by '1000' high priority at nice level -11.
May 26 22:18:20 falcon rtkit-daemon[547]: Supervising 1 threads of 1 processes of 1 users.
May 26 22:18:20 falcon rtkit-daemon[547]: Supervising 1 threads of 1 processes of 1 users.
May 26 22:18:20 falcon rtkit-daemon[547]: Successfully made thread 848 of process 847 (/usr/bin/pulseaudio) owned by '1000' RT at priority 5.
May 26 22:18:20 falcon rtkit-daemon[547]: Supervising 2 threads of 1 processes of 1 users.
May 26 22:18:20 falcon rtkit-daemon[547]: Supervising 2 threads of 1 processes of 1 users.
May 26 22:18:20 falcon rtkit-daemon[547]: Successfully made thread 849 of process 847 (/usr/bin/pulseaudio) owned by '1000' RT at priority 5.
May 26 22:18:20 falcon rtkit-daemon[547]: Supervising 3 threads of 1 processes of 1 users.
May 26 22:18:20 falcon dbus[416]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service'
May 26 22:18:20 falcon dbus[416]: [system] Activation via systemd failed for unit 'dbus-org.bluez.service': Unit dbus-org.bluez.service not found.
May 26 22:18:20 falcon systemd[754]: Started Sound Service.
May 26 22:18:20 falcon pulseaudio[847]: E: [pulseaudio] bluez5-util.c: GetManagedObjects() failed: org.freedesktop.systemd1.NoSuchUnit: Unit dbus-org.bluez.service not found.
May 26 22:18:21 falcon gnome-shell[802]: JS WARNING: [resource:///org/gnome/shell/ui/main.js 317]: reference to undefined property global.stage
May 26 22:18:21 falcon gnome-shell[802]: JS WARNING: [resource:///org/gnome/shell/ui/layout.js 217]: reference to undefined property global.window_group
May 26 22:18:21 falcon gnome-shell[802]: JS WARNING: [resource:///org/gnome/shell/ui/layout.js 899]: reference to undefined property this.monitors[this.findIndexForActor(...)]
May 26 22:18:21 falcon gnome-shell[802]: JS WARNING: [resource:///org/gnome/shell/ui/osdMonitorLabeler.js 59]: reference to undefined property Meta.MonitorManager
May 26 22:18:21 falcon gnome-shell[802]: JS WARNING: [resource:///org/gnome/shell/ui/panel.js 1107]: reference to undefined property indicator.menu._openChangedId
May 26 22:18:21 falcon dbus-daemon[769]: Activating via systemd: service name='org.freedesktop.impl.portal.PermissionStore' unit='xdg-permission-store.service'
May 26 22:18:21 falcon systemd[754]: Starting sandboxed app permission store...
May 26 22:18:21 falcon dbus-daemon[769]: Activating service name='org.gnome.Shell.CalendarServer'
May 26 22:18:21 falcon dbus-daemon[769]: Successfully activated service 'org.freedesktop.impl.portal.PermissionStore'
May 26 22:18:21 falcon systemd[754]: Started sandboxed app permission store.
May 26 22:18:21 falcon gnome-shell[802]: Failed to launch ibus-daemon: Failed to execute child process “ibus-daemon” (No such file or directory)
May 26 22:18:21 falcon gnome-shell[802]: JS WARNING: [resource:///org/gnome/shell/ui/slider.js 34]: reference to undefined property St.GenericAccessible
May 26 22:18:21 falcon dbus-daemon[769]: Activating via systemd: service name='org.gnome.evolution.dataserver.Sources5' unit='evolution-source-registry.service'
May 26 22:18:21 falcon polkitd[455]: Registered Authentication Agent for unix-session:c2 (system bus name :1.71 [/usr/bin/gnome-shell], object path /org/freedesktop/PolicyKit1/AuthenticationAgent, locale en_US.U
May 26 22:18:21 falcon systemd[754]: Starting Evolution source registry...
May 26 22:18:21 falcon dbus-daemon[769]: Activating service name='org.gnome.OnlineAccounts'
May 26 22:18:21 falcon dbus-daemon[769]: Activating service name='org.freedesktop.Telepathy.AccountManager'
May 26 22:18:21 falcon dbus-daemon[769]: Activating via systemd: service name='org.gtk.vfs.UDisks2VolumeMonitor' unit='gvfs-udisks2-volume-monitor.service'
May 26 22:18:21 falcon dbus-daemon[769]: Successfully activated service 'org.gnome.evolution.dataserver.Sources5'
May 26 22:18:21 falcon systemd[754]: Started Evolution source registry.
May 26 22:18:21 falcon systemd[754]: Starting Virtual filesystem service - disk device monitor...
May 26 22:18:21 falcon dbus-daemon[769]: Successfully activated service 'org.gnome.Shell.CalendarServer'
May 26 22:18:22 falcon dbus[416]: [system] Activating via systemd: service name='org.freedesktop.UDisks2' unit='udisks2.service'
May 26 22:18:22 falcon systemd[1]: Starting Disk Manager...
May 26 22:18:22 falcon udisksd[891]: The --no-debug option is deprecated and ignored. See '--help'.
May 26 22:18:22 falcon udisksd[891]: udisks daemon version 2.6.4 starting
May 26 22:18:22 falcon dbus[416]: [system] Successfully activated service 'org.freedesktop.UDisks2'
May 26 22:18:22 falcon systemd[1]: Started Disk Manager.
May 26 22:18:22 falcon udisksd[891]: Acquired the name org.freedesktop.UDisks2 on the system message bus
May 26 22:18:22 falcon dbus-daemon[769]: Successfully activated service 'org.freedesktop.Telepathy.AccountManager'
May 26 22:18:22 falcon dbus-daemon[769]: Successfully activated service 'org.gtk.vfs.UDisks2VolumeMonitor'
May 26 22:18:22 falcon systemd[754]: Started Virtual filesystem service - disk device monitor.
May 26 22:18:22 falcon dbus-daemon[769]: Activating via systemd: service name='org.gtk.vfs.GoaVolumeMonitor' unit='gvfs-goa-volume-monitor.service'
May 26 22:18:22 falcon systemd[754]: Starting Virtual filesystem service - GNOME Online Accounts monitor...
May 26 22:18:22 falcon goa-daemon[880]: goa-daemon version 3.24.0 starting
May 26 22:18:22 falcon dbus-daemon[769]: Activating service name='org.gnome.Identity'
May 26 22:18:22 falcon dbus-daemon[769]: Successfully activated service 'org.gnome.OnlineAccounts'
May 26 22:18:22 falcon dbus-daemon[769]: Successfully activated service 'org.gtk.vfs.GoaVolumeMonitor'
May 26 22:18:22 falcon systemd[754]: Started Virtual filesystem service - GNOME Online Accounts monitor.
May 26 22:18:22 falcon dbus-daemon[769]: Activating via systemd: service name='org.gtk.vfs.MTPVolumeMonitor' unit='gvfs-mtp-volume-monitor.service'
May 26 22:18:22 falcon dbus-daemon[769]: Successfully activated service 'org.gnome.Identity'
May 26 22:18:22 falcon systemd[754]: Starting Virtual filesystem service - Media Transfer Protocol monitor...
May 26 22:18:22 falcon dbus-daemon[769]: Successfully activated service 'org.gtk.vfs.MTPVolumeMonitor'
May 26 22:18:22 falcon systemd[754]: Started Virtual filesystem service - Media Transfer Protocol monitor.
May 26 22:18:22 falcon dbus-daemon[769]: Activating via systemd: service name='org.gtk.vfs.GPhoto2VolumeMonitor' unit='gvfs-gphoto2-volume-monitor.service'
May 26 22:18:22 falcon systemd[754]: Starting Virtual filesystem service - digital camera monitor...
May 26 22:18:23 falcon dbus-daemon[769]: Successfully activated service 'org.gtk.vfs.GPhoto2VolumeMonitor'
May 26 22:18:23 falcon systemd[754]: Started Virtual filesystem service - digital camera monitor.
May 26 22:18:23 falcon dbus-daemon[769]: Activating via systemd: service name='org.gtk.vfs.AfcVolumeMonitor' unit='gvfs-afc-volume-monitor.service'
May 26 22:18:23 falcon systemd[754]: Starting Virtual filesystem service - Apple File Conduit monitor...
May 26 22:18:23 falcon gvfs-afc-volume-monitor[923]: Volume monitor alive
May 26 22:18:23 falcon dbus-daemon[769]: Successfully activated service 'org.gtk.vfs.AfcVolumeMonitor'
May 26 22:18:23 falcon systemd[754]: Started Virtual filesystem service - Apple File Conduit monitor.
May 26 22:18:23 falcon gnome-shell[802]: JS WARNING: [resource:///org/gnome/gjs/modules/tweener/tweener.js 538]: reference to undefined property properties[istr].arrayIndex
May 26 22:18:23 falcon gnome-shell[802]: JS WARNING: [resource:///org/gnome/shell/ui/search.js 436]: reference to undefined property provider.isRemoteProvider
May 26 22:18:23 falcon gnome-shell[802]: No permission to trigger offline updates: Polkit.Error: GDBus.Error:org.freedesktop.PolicyKit1.Error.Failed: Action org.freedesktop.packagekit.trigger-offline-update is n
May 26 22:18:23 falcon dbus[416]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service'
May 26 22:18:23 falcon systemd[1]: Starting Hostname Service...
May 26 22:18:23 falcon dbus[416]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service'
May 26 22:18:23 falcon systemd[1]: Starting Time & Date Service...
May 26 22:18:23 falcon dbus[416]: [system] Successfully activated service 'org.freedesktop.timedate1'
May 26 22:18:23 falcon systemd[1]: Started Time & Date Service.
May 26 22:18:23 falcon dbus-daemon[769]: Activating via systemd: service name='org.gnome.evolution.dataserver.Calendar7' unit='evolution-calendar-factory.service'
May 26 22:18:23 falcon dbus[416]: [system] Successfully activated service 'org.freedesktop.hostname1'
May 26 22:18:23 falcon systemd[1]: Started Hostname Service.
May 26 22:18:23 falcon systemd[754]: Starting Evolution calendar service...
May 26 22:18:23 falcon gnome-shell[802]: Error looking up permission: GDBus.Error:org.freedesktop.portal.Error.NotFound: No entry for geolocation
May 26 22:18:23 falcon gnome-shell[802]: JS WARNING: [resource:///org/gnome/shell/ui/status/network.js 1897]: reference to undefined property this._mainConnectionStateChangedId
May 26 22:18:23 falcon dbus[416]: [system] Activating via systemd: service name='org.freedesktop.locale1' unit='dbus-org.freedesktop.locale1.service'
May 26 22:18:23 falcon systemd[1]: Starting Locale Service...
May 26 22:18:23 falcon gsd-color[590]: failed to set screen _ICC_PROFILE: Failed to open file “/home/ezpz93/.local/share/icc/edid-0b82bdde2f6d272d4f0b94e8d6ec81fb.icc”: Permission denied
May 26 22:18:23 falcon gnome-shell[802]: GNOME Shell started at Fri May 26 2017 22:18:23 GMT+0800
May 26 22:18:23 falcon gsd-color[590]: failed to set screen _ICC_PROFILE: Failed to open file “/home/ezpz93/.local/share/icc/edid-0b82bdde2f6d272d4f0b94e8d6ec81fb.icc”: Permission denied
May 26 22:18:23 falcon dbus[416]: [system] Successfully activated service 'org.freedesktop.locale1'
May 26 22:18:23 falcon systemd[1]: Started Locale Service.
May 26 22:18:24 falcon dbus-daemon[769]: Activating via systemd: service name='org.freedesktop.Tracker1' unit='tracker-store.service'
May 26 22:18:24 falcon gnome-session-binary[771]: Entering running state
May 26 22:18:24 falcon systemd[754]: Starting Tracker metadata database store and lookup manager...
May 26 22:18:24 falcon gsd-power[931]: gsd_power_backlight_abs_to_percentage: assertion 'max > min' failed
May 26 22:18:24 falcon dbus-daemon[769]: Successfully activated service 'org.freedesktop.Tracker1'
May 26 22:18:24 falcon systemd[754]: Started Tracker metadata database store and lookup manager.
May 26 22:18:24 falcon systemd[754]: Started Evolution calendar service.
May 26 22:18:24 falcon tracker-store.desktop[1050]: (uint32 1,)
May 26 22:18:24 falcon dbus-daemon[769]: Successfully activated service 'org.gnome.evolution.dataserver.Calendar7'
May 26 22:18:24 falcon dbus-daemon[769]: Activating service name='ca.desrt.dconf'
May 26 22:18:24 falcon dbus-daemon[769]: Activating via systemd: service name='org.gnome.evolution.dataserver.AddressBook9' unit='evolution-addressbook-factory.service'
May 26 22:18:24 falcon dbus-daemon[769]: Successfully activated service 'ca.desrt.dconf'
May 26 22:18:24 falcon systemd[754]: Starting Evolution address book service...
May 26 22:18:25 falcon dbus-daemon[769]: Successfully activated service 'org.gnome.evolution.dataserver.AddressBook9'
May 26 22:18:25 falcon systemd[754]: Started Evolution address book service.
May 26 22:18:26 falcon fcitx-autostart.desktop[1044]: Fcitx seems is not running
May 26 22:18:26 falcon fcitx-autostart.desktop[1044]: (INFO-1162 /build/fcitx/src/fcitx-4.2.9.1/src/lib/fcitx/addon.c:151) Load Addon Config File:fcitx-ipc.conf
May 26 22:18:26 falcon fcitx-autostart.desktop[1044]: (INFO-1162 /build/fcitx/src/fcitx-4.2.9.1/src/lib/fcitx/addon.c:151) Load Addon Config File:fcitx-spell.conf
May 26 22:18:26 falcon fcitx-autostart.desktop[1044]: (INFO-1162 /build/fcitx/src/fcitx-4.2.9.1/src/lib/fcitx/addon.c:151) Load Addon Config File:fcitx-autoeng.conf
May 26 22:18:26 falcon fcitx-autostart.desktop[1044]: (INFO-1162 /build/fcitx/src/fcitx-4.2.9.1/src/lib/fcitx/addon.c:151) Load Addon Config File:fcitx-quickphrase.conf
May 26 22:18:26 falcon fcitx-autostart.desktop[1044]: (INFO-1162 /build/fcitx/src/fcitx-4.2.9.1/src/lib/fcitx/addon.c:151) Load Addon Config File:fcitx-keyboard.conf
May 26 22:18:26 falcon fcitx-autostart.desktop[1044]: (INFO-1162 /build/fcitx/src/fcitx-4.2.9.1/src/lib/fcitx/addon.c:151) Load Addon Config File:fcitx-clipboard.conf
May 26 22:18:26 falcon fcitx-autostart.desktop[1044]: (INFO-1162 /build/fcitx/src/fcitx-4.2.9.1/src/lib/fcitx/addon.c:151) Load Addon Config File:fcitx-notificationitem.conf
May 26 22:18:26 falcon fcitx-autostart.desktop[1044]: (INFO-1162 /build/fcitx/src/fcitx-4.2.9.1/src/lib/fcitx/addon.c:151) Load Addon Config File:fcitx-x11.conf
May 26 22:18:26 falcon fcitx-autostart.desktop[1044]: (INFO-1162 /build/fcitx/src/fcitx-4.2.9.1/src/lib/fcitx/addon.c:151) Load Addon Config File:fcitx-xkb.conf
May 26 22:18:26 falcon fcitx-autostart.desktop[1044]: (INFO-1162 /build/fcitx/src/fcitx-4.2.9.1/src/lib/fcitx/addon.c:151) Load Addon Config File:fcitx-fullwidth-char.conf
May 26 22:18:26 falcon fcitx-autostart.desktop[1044]: (INFO-1162 /build/fcitx/src/fcitx-4.2.9.1/src/lib/fcitx/addon.c:151) Load Addon Config File:fcitx-table.conf
May 26 22:18:26 falcon fcitx-autostart.desktop[1044]: (INFO-1162 /build/fcitx/src/fcitx-4.2.9.1/src/lib/fcitx/addon.c:151) Load Addon Config File:fcitx-chttrans.conf
May 26 22:18:26 falcon fcitx-autostart.desktop[1044]: (INFO-1162 /build/fcitx/src/fcitx-4.2.9.1/src/lib/fcitx/addon.c:151) Load Addon Config File:fcitx-xkbdbus.conf
May 26 22:18:26 falcon fcitx-autostart.desktop[1044]: (INFO-1162 /build/fcitx/src/fcitx-4.2.9.1/src/lib/fcitx/addon.c:151) Load Addon Config File:fcitx-remote-module.conf
May 26 22:18:26 falcon fcitx-autostart.desktop[1044]: (INFO-1162 /build/fcitx/src/fcitx-4.2.9.1/src/lib/fcitx/addon.c:151) Load Addon Config File:fcitx-pinyin-enhance.conf
May 26 22:18:26 falcon fcitx-autostart.desktop[1044]: (INFO-1162 /build/fcitx/src/fcitx-4.2.9.1/src/lib/fcitx/addon.c:151) Load Addon Config File:fcitx-unicode.conf
May 26 22:18:26 falcon fcitx-autostart.desktop[1044]: (INFO-1162 /build/fcitx/src/fcitx-4.2.9.1/src/lib/fcitx/addon.c:151) Load Addon Config File:fcitx-dbus.conf
May 26 22:18:26 falcon fcitx-autostart.desktop[1044]: (INFO-1162 /build/fcitx/src/fcitx-4.2.9.1/src/lib/fcitx/addon.c:151) Load Addon Config File:fcitx-imselector.conf
May 26 22:18:26 falcon fcitx-autostart.desktop[1044]: (INFO-1162 /build/fcitx/src/fcitx-4.2.9.1/src/lib/fcitx/addon.c:151) Load Addon Config File:fcitx-pinyin.conf
May 26 22:18:26 falcon fcitx-autostart.desktop[1044]: (INFO-1162 /build/fcitx/src/fcitx-4.2.9.1/src/lib/fcitx/addon.c:151) Load Addon Config File:fcitx-qw.conf
May 26 22:18:26 falcon fcitx-autostart.desktop[1044]: (INFO-1162 /build/fcitx/src/fcitx-4.2.9.1/src/lib/fcitx/addon.c:151) Load Addon Config File:fcitx-freedesktop-notify.conf
May 26 22:18:26 falcon fcitx-autostart.desktop[1044]: (INFO-1162 /build/fcitx/src/fcitx-4.2.9.1/src/lib/fcitx/addon.c:151) Load Addon Config File:fcitx-classic-ui.conf
May 26 22:18:26 falcon fcitx-autostart.desktop[1044]: (INFO-1162 /build/fcitx/src/fcitx-4.2.9.1/src/lib/fcitx/addon.c:151) Load Addon Config File:fcitx-xim.conf
May 26 22:18:26 falcon fcitx-autostart.desktop[1044]: (INFO-1162 /build/fcitx/src/fcitx-4.2.9.1/src/lib/fcitx/addon.c:151) Load Addon Config File:fcitx-kimpanel-ui.conf
May 26 22:18:26 falcon fcitx-autostart.desktop[1044]: (INFO-1162 /build/fcitx/src/fcitx-4.2.9.1/src/lib/fcitx/addon.c:151) Load Addon Config File:fcitx-punc.conf
May 26 22:18:26 falcon fcitx-autostart.desktop[1044]: (INFO-1162 /build/fcitx/src/fcitx-4.2.9.1/src/lib/fcitx/addon.c:151) Load Addon Config File:fcitx-light-ui.conf
May 26 22:18:26 falcon fcitx-autostart.desktop[1044]: (INFO-1162 /build/fcitx/src/fcitx-4.2.9.1/src/lib/fcitx/addon.c:151) Load Addon Config File:fcitx-googlepinyin.conf
May 26 22:18:26 falcon fcitx-autostart.desktop[1044]: (INFO-1162 /build/fcitx/src/fcitx-4.2.9.1/src/lib/fcitx/addon.c:151) Load Addon Config File:fcitx-vk.conf
May 26 22:18:26 falcon fcitx-autostart.desktop[1044]: (ERROR-1162 /build/fcitx/src/fcitx-4.2.9.1/src/lib/fcitx/ime.c:432) fcitx-keyboard-cm-mmuock already exists
May 26 22:18:27 falcon fcitx-autostart.desktop[1044]: (WARN-1162 /build/fcitx/src/fcitx-4.2.9.1/src/frontend/xim/xim.c:161) Please set XMODIFIERS.
May 26 22:18:42 falcon gnome-shell[802]: JS WARNING: [resource:///org/gnome/shell/ui/dash.js 760]: reference to undefined property oldApps[oldIndex]
May 26 22:19:13 falcon systemd[754]: Time has been changed
May 26 22:19:13 falcon systemd[1]: Time has been changed
May 26 22:19:13 falcon systemd[469]: Time has been changed
May 26 22:19:25 falcon gnome-shell[802]: JS WARNING: [resource:///org/gnome/shell/ui/workspace.js 1233]: reference to undefined property this._reservedSlotWindow
May 26 22:19:27 falcon dbus-daemon[769]: Activating service name='org.gnome.GConf'
May 26 22:19:27 falcon dbus-daemon[769]: Successfully activated service 'org.gnome.GConf'
May 26 22:19:27 falcon dbus[416]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service'
May 26 22:19:27 falcon dbus[416]: [system] Activation via systemd failed for unit 'dbus-org.bluez.service': Unit dbus-org.bluez.service not found.
May 26 22:19:29 falcon gnome-keyring-daemon[763]: couldn't allocate secure memory to keep passwords and or keys from being written to the disk
May 26 22:19:29 falcon gnome-keyring-daemon[763]: asked to register item /org/freedesktop/secrets/collection/login/1, but it's already registered
May 26 22:19:32 falcon gnome-keyring-daemon[763]: asked to register item /org/freedesktop/secrets/collection/login/1, but it's already registered
May 26 22:19:42 falcon systemd[1]: Time has been changed
May 26 22:19:42 falcon systemd[754]: Time has been changed
May 26 22:19:42 falcon systemd[469]: Time has been changed
May 26 22:20:07 falcon tracker-miner-f[1045]:   (Sparql buffer) Error in task 0 (file:///home/ezpz93/Documents/Night.Of.Something.Strange.2016.1080p.BluRay.x264-%5BYTS.AG%5D.mp4.crdownload) of the array-update: 
May 26 22:20:07 falcon tracker-miner-f[1045]: Could not execute sparql: UNIQUE constraint failed: nie:DataObject.nie:url (strerror of errno (not necessarily related): Resource temporarily unavailable)

Offline

#7 2017-05-26 14:48:43

BlackAc3
Member
Registered: 2017-05-13
Posts: 20

Re: Arch hang during heavy hard disk usage

graysky wrote:

Try linux-ck which uses a different scheduler aimed at responsiveness.

I will learn about it and try to setup and see, thanks for the help ^^

Offline

#8 2017-05-26 19:41:14

seth
Member
Registered: 2012-09-03
Posts: 49,967

Re: Arch hang during heavy hard disk usage

most of the journal is unimportant, only the kernel messages matter.
You can filter them "-k" and use a paste service to upload them: https://wiki.archlinux.org/index.php/Li … in_clients

Offline

#9 2017-05-26 22:27:05

BlackAc3
Member
Registered: 2017-05-13
Posts: 20

Re: Arch hang during heavy hard disk usage

seth wrote:

most of the journal is unimportant, only the kernel messages matter.
You can filter them "-k" and use a paste service to upload them: https://wiki.archlinux.org/index.php/Li … in_clients

Thanks ^^ it happen quite frequent yesterday.
Here is the kernel message : https://ptpb.pw/q7yc

When i tried to shut down during the weird behavior ( nothing responding, chrome sudden auto off and file manager wont open )
I got this error message :

starting version 232
/dev/sda2 : clean, 317586/16351232 files, 12603313/65404672 blocks.
[31758.397813] EXT4-fs error (device sda2): ext4_find_entry: 1463: inode #1547232: comm gdm-session-wor: reading directory Iblock 0
[31758.397895] EXT4-fs (sda2): previous I/O error to superblock detected
[31758.397971] Buffer I/O error on dev sda2. logical block 0. lost sync page write

if I tried to press any key on keyboard,I will get below message:
[31872.231394] blk_update_request: I/O error, dev sda, sector 211396032

Offline

#10 2017-05-27 07:23:29

seth
Member
Registered: 2012-09-03
Posts: 49,967

Re: Arch hang during heavy hard disk usage

You got (at least) a bad block on /dev/sda

You should
1. backup your precious data (master thesis first, porn collection later)
2. run a long smart test
3. try to deal with the bad block: https://wiki.archlinux.org/index.php/Id … aged_files - but be careful with that disk.

Offline

#11 2017-05-28 13:33:27

BlackAc3
Member
Registered: 2017-05-13
Posts: 20

Re: Arch hang during heavy hard disk usage

seth wrote:

You got (at least) a bad block on /dev/sda

You should
1. backup your precious data (master thesis first, porn collection later)
2. run a long smart test
3. try to deal with the bad block: https://wiki.archlinux.org/index.php/Id … aged_files - but be careful with that disk.


I completed long smart test and the report said it is a pass.
I experience the hang more frequent now, is there other thing I can do ?

Offline

#12 2017-05-28 13:36:44

seth
Member
Registered: 2012-09-03
Posts: 49,967

Re: Arch hang during heavy hard disk usage

smartctl -x /dev/sda

If it's always the same bad block, see the link on how to
a) figure which file is affected
b) to (hopefully) "fix" it

Edit: could be wonky sata cable, smartctl -x will tell us more.

Last edited by seth (2017-05-28 13:37:15)

Offline

#13 2017-05-29 16:28:23

BlackAc3
Member
Registered: 2017-05-13
Posts: 20

Re: Arch hang during heavy hard disk usage

seth wrote:
smartctl -x /dev/sda

If it's always the same bad block, see the link on how to
a) figure which file is affected
b) to (hopefully) "fix" it

Edit: could be wonky sata cable, smartctl -x will tell us more.


Hi, I just tried to run badblock to check, this time on my sda1 it reported 40 bad blocks...

Checking blocks 0 to 261618687
Checking for bad blocks (read-only test): 4506828
4506829
4506830
4506831
4516312
4516313
4516314
4516315
4520960
4520961
4520962
4520963
4522508
4522509
4658740
5422656
5422657
5422658
5422659
6281888
6281889
6281890
6281891
6283440
6283441
6283442
6283443
6284988
6284989
6284990
6284991
8472744
9349840
9349841
9349842
9349843
9352936
9352937
9352938
9352939
done                                                 
Pass completed, 40 bad blocks found. (40/0/0 errors)

What can I do with this information ? Should I run smartctl -t long again to test again ?

Thanks and regards.

Offline

#14 2017-05-29 16:48:26

seth
Member
Registered: 2012-09-03
Posts: 49,967

Re: Arch hang during heavy hard disk usage

Try that again and see whether you get the same result.
If so, consult https://wiki.archlinux.org/index.php/Id … aged_files again. Identify the affected file(s), try to archive them (if you have no backup or they're public anyway) and try to write the sector.

If the disk holds no valuable data, you can also just "dd if=/dev/zero" it.
If it does hold valubale data MAKE A BACKUP BEFORE TRYING TO DEAL WITH THE BAD BLOCKS!

Offline

Board footer

Powered by FluxBB