You are not logged in.

#1 2013-11-09 05:55:49

chaembian
Member
Registered: 2012-02-25
Posts: 15

[SOLVED] systemd: no kernel messages in the journal

Hi

Currently the journal holds only application logs; I do not see any kernel logs in the journal. This is an old Arch system that was using syslog-ng but was transitioned to systemd when Arch made systemd default. This was working well until about three weeks back, when journal stopped holding kernel messages. The journal was causing journalctl to crash and I couldn't verify the journal since I had FSS setup but forgotten the keys. So, I had disabled the FSS, cleared the journals to startover again. Only to see kernel messages are not getting logged.

I would be grrateful for any help in resolving the issue. Thank you!

Last edited by chaembian (2013-11-20 02:53:00)

Offline

#2 2013-11-09 06:06:18

WonderWoofy
Member
From: Los Gatos, CA
Registered: 2012-05-19
Posts: 8,414

Re: [SOLVED] systemd: no kernel messages in the journal

There is a user journal and a system journal.  A normal user has access to their own journal, but not the system journal.  So you can run journalctl as root to see all the logs or you can add yourself to the systemd-journal group.


Edit: Also, this is information that could have easily been found.  Please do a little searching or reading man pages before posting your question next time.

Last edited by WonderWoofy (2013-11-09 06:07:15)

Offline

#3 2013-11-09 06:17:15

chaembian
Member
Registered: 2012-02-25
Posts: 15

Re: [SOLVED] systemd: no kernel messages in the journal

My apologies for not providing more details in my post.

Yes, I'm a member of systemd-journal group. Just to be sure, I tried seeing 'sudo journalctl -ab' and running 'journalctl -ab' as root; I do NOT see messages in either case. I also checked to see if system.journal contains these messages at all: 'sudo journalctl --file=/var/log/journal/e44c../system.journal'; there are no kernel messages in these as well.

I see messages in dmesg; but I do not see these messages getting captured in the journal.

In case if these information are useful:

chaembian ~ » sudo lsof /dev/kmsg
lsof: WARNING: can't stat() fuse.gvfsd-fuse file system /run/user/1004/gvfs
      Output information may be incomplete.
COMMAND   PID USER   FD   TYPE DEVICE SIZE/OFF NODE NAME
systemd     1 root    3w   CHR   1,11      0t0   20 /dev/kmsg
systemd-j 139 root    6w   CHR   1,11      0t0   20 /dev/kmsg
systemd-j 139 root    8u   CHR   1,11      0t0   20 /dev/kmsg
chaembian ~ » sudo lsof /dev/log                                                                                                                    1 ↵
lsof: WARNING: can't stat() fuse.gvfsd-fuse file system /run/user/1004/gvfs
      Output information may be incomplete.
COMMAND   PID USER   FD   TYPE             DEVICE SIZE/OFF NODE NAME
systemd     1 root   33u  unix 0xffff880119ae2680      0t0 8347 /dev/log
systemd-j 139 root    5u  unix 0xffff880119ae2680      0t0 8347 /dev/log
chaembian ~ » sudo ls -l /proc/139/fd 
total 0
lr-x------ 1 root root 64 Nov  7 16:53 0 -> /dev/null
l-wx------ 1 root root 64 Nov  7 16:53 1 -> /dev/null
lrwx------ 1 root root 64 Nov  7 16:53 10 -> anon_inode:[signalfd]
lrwx------ 1 root root 64 Nov  7 16:53 11 -> socket:[10218]
lrwx------ 1 root root 64 Nov  7 16:53 12 -> socket:[10208]
lrwx------ 1 root root 64 Nov  7 16:53 13 -> socket:[10206]
lrwx------ 1 root root 64 Nov  7 16:53 14 -> socket:[10209]
lrwx------ 1 root root 64 Nov  7 16:53 15 -> socket:[1012257]
lrwx------ 1 root root 64 Nov  7 16:53 16 -> socket:[6878]
lrwx------ 1 root root 64 Nov  7 16:53 17 -> socket:[10217]
lrwx------ 1 root root 64 Nov  7 16:53 18 -> socket:[11286]
lrwx------ 1 root root 64 Nov  7 16:53 19 -> socket:[10220]
l-wx------ 1 root root 64 Nov  7 16:53 2 -> /dev/null
lrwx------ 1 root root 64 Nov  7 16:53 20 -> socket:[10221]
lrwx------ 1 root root 64 Nov  7 16:53 21 -> /var/log/journal/e44c../user-1004.journal
lrwx------ 1 root root 64 Nov  7 16:53 22 -> socket:[11478]
lrwx------ 1 root root 64 Nov  7 16:53 23 -> socket:[11483]
lrwx------ 1 root root 64 Nov  7 16:53 24 -> socket:[11402]
lrwx------ 1 root root 64 Nov  7 16:53 25 -> socket:[11679]
lrwx------ 1 root root 64 Nov  7 16:53 26 -> /var/log/journal/e44c../system.journal
lrwx------ 1 root root 64 Nov  7 16:53 27 -> /var/log/journal/e44c../user-1000.journal
lrwx------ 1 root root 64 Nov  7 16:53 3 -> socket:[8342]
lrwx------ 1 root root 64 Nov  7 16:53 4 -> socket:[8345]
lrwx------ 1 root root 64 Nov  7 16:53 5 -> socket:[8347]
l-wx------ 1 root root 64 Nov  7 16:53 6 -> /dev/kmsg
lrwx------ 1 root root 64 Nov  7 16:53 7 -> anon_inode:[eventpoll]
lrwx------ 1 root root 64 Nov  7 16:53 8 -> /dev/kmsg
lrwx------ 1 root root 64 Nov  7 16:53 9 -> anon_inode:[timerfd]

/etc/systemd/journal.conf

[Journal]
Storage=auto
#Compress=yes
Seal=no
#SplitMode=login
#SyncIntervalSec=5m
#RateLimitInterval=30s
#RateLimitBurst=1000
#SystemMaxUse=
#SystemKeepFree=
#SystemMaxFileSize=
#RuntimeMaxUse=
#RuntimeKeepFree=
#RuntimeMaxFileSize=
#MaxRetentionSec=
#MaxFileSec=1month
#ForwardToSyslog=yes
#ForwardToKMsg=no
#ForwardToConsole=no
#TTYPath=/dev/console
#MaxLevelStore=debug
#MaxLevelSyslog=debug
#MaxLevelKMsg=notice
#MaxLevelConsole=info

/etc/systemd/system.conf

[Manager]
#LogLevel=info
#LogTarget=journal-or-kmsg
#LogColor=yes
#LogLocation=no
#DumpCore=yes
#CrashShell=no
#ShowStatus=yes
#CrashChVT=1
#CPUAffinity=1 2
#DefaultStandardOutput=journal
#DefaultStandardError=inherit
#JoinControllers=cpu,cpuacct net_cls,net_prio
#RuntimeWatchdogSec=0
#ShutdownWatchdogSec=10min
#CapabilityBoundingSet=
#TimerSlackNSec=
#DefaultEnvironment=
#DefaultLimitCPU=
#DefaultLimitFSIZE=
#DefaultLimitDATA=
#DefaultLimitSTACK=
#DefaultLimitCORE=
#DefaultLimitRSS=
#DefaultLimitNOFILE=
#DefaultLimitAS=
#DefaultLimitNPROC=
#DefaultLimitMEMLOCK=
#DefaultLimitLOCKS=
#DefaultLimitSIGPENDING=
#DefaultLimitMSGQUEUE=
#DefaultLimitNICE=
#DefaultLimitRTPRIO=
#DefaultLimitRTTIME=

Last edited by chaembian (2013-11-09 06:53:40)

Offline

#4 2013-11-17 06:52:03

chaembian
Member
Registered: 2012-02-25
Posts: 15

Re: [SOLVED] systemd: no kernel messages in the journal

I'm still stuck at the issue. I'll grateful for any help in resolving the issue. sad

Offline

#5 2013-11-17 10:11:39

berbae
Member
From: France
Registered: 2007-02-12
Posts: 1,302

Re: [SOLVED] systemd: no kernel messages in the journal

chaembian wrote:

This was working well until about three weeks back, when journal stopped holding kernel messages

What changes were made at this time?

The journal was causing journalctl to crash and I couldn't verify the journal since I had FSS setup but forgotten the keys. So, I had disabled the FSS, cleared the journals to startover again. Only to see kernel messages are not getting logged

It would have been clearer if you had given the exact error message you got when journalctl crashed.
And the precise commands you used, trying to solve the problem. If you could find all these and post them, it would be clearer.

Presently what do you get when you run these commands:

$ systemctl status systemd-journald
$ journalctl --disk-usage
$ journalctl --verify
$ journalctl --dmesg

Give also the result of:
ls -l /var/log/journal/e44c../

Do you have kernel command line options for systemd?

Offline

#6 2013-11-17 14:10:10

chaembian
Member
Registered: 2012-02-25
Posts: 15

Re: [SOLVED] systemd: no kernel messages in the journal

Thank you, Berbae!

Except for the system updates that I try to do regularly, there is no major update to the system. This is a system used by my folks that I manage remotely. Around mid-Oct, my folks were telling me that the system hung when they initiated shutdown. Only when I was analysing for the issue, I found out that the only entry I had in the journal was from 09/29. (so much for _managing_, I know!)

Then, searching through the forums, I came across suggestion to address the shutdown issue by adding 'shutdown' hook to mkinitcpio.conf. I did this on 10/19 and that addressed the shutdown issue. (No, I do not have /usr mounted on a separate partition).

before:
HOOKS="base udev autodetect pata scsi sata filesystems consolefont"

after:
HOOKS="base udev autodetect modconf block filesystems keyboard fsck shutdown"

Since no log was a big issue, I started looking around. In the rough order of things:
1. I had set the 'Storage to None' in journald.conf as a thread had suggested to see if that solved the issue.
2. I noticed that /var/log/kernel.log.* was holding messages. I looked to see who had /dev/log and /dev/kmsg; While, /dev/log was opened by systemd and systemd-j, /dev/kmsg was opened by systemd and syslog. (I had forgotten all about syslog-ng running in the system until then). I thought that could be the reason why I was not getting kmsg in the journal
3. I disabled syslog-ng.service; rebooted the system; verified if I could see system messages using journalctl -k and journalctl -b which it did (but only that time);
4. I cleaned up the contents of /var/log/e44c..
5. Reset the 'Storage to auto' in journald.conf
6. Restarted the journal
7. Verified the contents.

When I logged in few days later, I found that the issue was not resolved: I think journalctl -k returned just the "-- Logs begin " header for the day system was booted.

Offline

#7 2013-11-17 14:23:34

chaembian
Member
Registered: 2012-02-25
Posts: 15

Re: [SOLVED] systemd: no kernel messages in the journal

berbae wrote:

$ systemctl status systemd-journald

chaembian » systemctl status systemd-journald
systemd-journald.service - Journal Service
   Loaded: loaded (/usr/lib/systemd/system/systemd-journald.service; static)
   Active: active (running) since Sun 2013-11-17 08:01:26 IST; 11h ago
     Docs: man:systemd-journald.service(8)
           man:journald.conf(5)
 Main PID: 140 (systemd-journal)
   Status: "Processing requests..."
   CGroup: /system.slice/systemd-journald.service
           └─140 /usr/lib/systemd/systemd-journald

Warning: Journal has been rotated since unit was started. Log output is incomplete or unavailable.
berbae wrote:

$ journalctl --disk-usage

chaembian ~ » journalctl --disk-usage
Journals take up 7.3M on disk.
berbae wrote:

$ journalctl --verify

chaembian ~ » journalctl --verify    
PASS: /var/log/journal/e44c../system.journal                                                     
PASS: /var/log/journal/e44c../user-1000.journal
berbae wrote:

$ journalctl --dmesg

-- Logs begin at Sun 2013-11-17 14:25:17 IST, end at Sun 2013-11-17 19:45:08 IST. --
Nov 17 14:25:17 chaembian systemd[1]: Got D-Bus request: org.freedesktop.DBus.NameOwnerChanged() on /org/freedesktop/DBus
Nov 17 14:25:17 chaembian systemd[1]: Got D-Bus request: org.freedesktop.DBus.NameOwnerChanged() on /org/freedesktop/DBus
Nov 17 14:25:17 chaembian systemd[1]: Received SIGCHLD from PID 1474 (sshd).
Nov 17 14:25:17 chaembian systemd[1]: Got SIGCHLD for process 1474 (sshd)
Nov 17 14:25:17 chaembian systemd[1]: Child 1474 died (code=exited, status=255/n/a)
Nov 17 14:25:17 chaembian systemd[1]: Received SIGCHLD from PID 1475 (zsh).
Nov 17 14:25:17 chaembian systemd[1]: Got SIGCHLD for process 1475 (zsh)
Nov 17 14:25:17 chaembian systemd[1]: Child 1475 died (code=exited, status=1/FAILURE)
Nov 17 14:25:17 chaembian systemd[1]: Accepted connection on private bus.
Nov 17 14:25:17 chaembian systemd[1]: Got D-Bus request: org.freedesktop.systemd1.Agent.Released() on /org/freedesktop/systemd1/agent
Nov 17 14:25:17 chaembian systemd[1]: session-22.scope: cgroup is empty
Nov 17 14:25:17 chaembian systemd[1]: session-22.scope changed running -> dead
Nov 17 14:25:17 chaembian systemd[1]: Collecting session-22.scope
Nov 17 14:25:17 chaembian systemd[1]: Got D-Bus request: org.freedesktop.DBus.Local.Disconnected() on /org/freedesktop/DBus/Local
Nov 17 14:25:17 chaembian systemd[1]: Got D-Bus request: org.freedesktop.DBus.Properties.Get() on /org/freedesktop/systemd1/unit/session_2d22_2escope
Nov 17 14:25:17 chaembian systemd[1]: Failed to load configuration for session-22.scope: No such file or directory
Nov 17 14:25:17 chaembian systemd[1]: Collecting session-22.scope
Nov 17 14:25:17 chaembian systemd[1]: Got D-Bus request: org.freedesktop.systemd1.Manager.StopUnit() on /org/freedesktop/systemd1
Nov 17 14:25:17 chaembian systemd[1]: Failed to load configuration for session-22.scope: No such file or directory
Nov 17 14:25:17 chaembian systemd[1]: Collecting session-22.scope
Nov 17 14:25:17 chaembian systemd[1]: Got D-Bus request: org.freedesktop.DBus.Properties.Get() on /org/freedesktop/systemd1/unit/user_2d1000_2eslice
Nov 17 14:51:01 chaembian systemd[1]: Received SIGCHLD from PID 31056 (sadc).
Nov 17 14:51:01 chaembian systemd[1]: Got SIGCHLD for process 31056 (sadc)
Nov 17 14:51:01 chaembian systemd[1]: Child 31056 died (code=exited, status=0/SUCCESS)
Nov 17 15:51:01 chaembian systemd[1]: Received SIGCHLD from PID 31088 (sadc).
Nov 17 15:51:01 chaembian systemd[1]: Got SIGCHLD for process 31088 (sadc)
Nov 17 15:51:01 chaembian systemd[1]: Child 31088 died (code=exited, status=0/SUCCESS)
Nov 17 16:51:01 chaembian systemd[1]: Received SIGCHLD from PID 31113 (sadc).
Nov 17 16:51:01 chaembian systemd[1]: Got SIGCHLD for process 31113 (sadc)
Nov 17 16:51:01 chaembian systemd[1]: Child 31113 died (code=exited, status=0/SUCCESS)
Nov 17 17:51:01 chaembian systemd[1]: Received SIGCHLD from PID 31143 (sadc).
Nov 17 17:51:01 chaembian systemd[1]: Got SIGCHLD for process 31143 (sadc)
Nov 17 17:51:01 chaembian systemd[1]: Child 31143 died (code=exited, status=0/SUCCESS)
Nov 17 18:30:05 chaembian systemd[1]: Got D-Bus request: org.freedesktop.DBus.NameOwnerChanged() on /org/freedesktop/DBus
Nov 17 18:30:05 chaembian systemd[1]: Got D-Bus request: org.freedesktop.systemd1.Manager.StartTransientUnit() on /org/freedesktop/systemd1
Nov 17 18:30:05 chaembian systemd[1]: Failed to load configuration for session-57.scope: No such file or directory
Nov 17 18:30:05 chaembian systemd[1]: Trying to enqueue job session-57.scope/start/fail
Nov 17 18:30:05 chaembian systemd[1]: Installed new job session-57.scope/start as 1386
Nov 17 18:30:05 chaembian systemd[1]: Enqueued job session-57.scope/start as 1386
Nov 17 18:30:05 chaembian systemd[1]: Starting Session 57 of user xxxx.
Nov 17 18:30:05 chaembian systemd[1]: session-57.scope changed dead -> running
Nov 17 18:30:05 chaembian systemd[1]: Job session-57.scope/start finished, result=done
Nov 17 18:30:05 chaembian systemd[1]: Started Session 57 of user xxxx.
Nov 17 18:30:05 chaembian systemd[1]: Got D-Bus request: org.freedesktop.DBus.NameOwnerChanged() on /org/freedesktop/DBus
Nov 17 18:30:11 chaembian systemd[1]: Received SIGCHLD from PID 31216 (zsh).
Nov 17 18:30:11 chaembian systemd[1]: Got SIGCHLD for process 31216 (zsh)
Nov 17 18:30:11 chaembian systemd[1]: Child 31216 died (code=exited, status=0/SUCCESS)
Nov 17 18:51:01 chaembian systemd[1]: Received SIGCHLD from PID 31185 (sadc).
Nov 17 18:51:01 chaembian systemd[1]: Got SIGCHLD for process 31185 (sadc)
Nov 17 18:51:01 chaembian systemd[1]: Child 31185 died (code=exited, status=0/SUCCESS)
Nov 17 19:06:30 chaembian systemd[1]: Got D-Bus request: org.freedesktop.DBus.NameOwnerChanged() on /org/freedesktop/DBus
Nov 17 19:06:30 chaembian systemd[1]: Got D-Bus request: org.freedesktop.DBus.Properties.GetAll() on /org/freedesktop/systemd1/unit/systemd_2djournald_2eservice
Nov 17 19:06:30 chaembian systemd[1]: Looking for unit files in (higher priority first):
Nov 17 19:06:30 chaembian systemd[1]:         /etc/systemd/system
Nov 17 19:06:30 chaembian systemd[1]:         /run/systemd/system
Nov 17 19:06:30 chaembian systemd[1]:         /usr/local/lib/systemd/system
Nov 17 19:06:30 chaembian systemd[1]:         /usr/lib/systemd/system
Nov 17 19:06:30 chaembian systemd[1]: SysV init scripts and rcN.d links support disabled
Nov 17 19:06:30 chaembian systemd[1]: Got D-Bus request: org.freedesktop.DBus.NameOwnerChanged() on /org/freedesktop/DBus
Nov 17 19:42:58 chaembian systemd[1]: Got D-Bus request: org.freedesktop.DBus.NameOwnerChanged() on /org/freedesktop/DBus
Nov 17 19:42:58 chaembian systemd[1]: Got D-Bus request: org.freedesktop.DBus.Properties.GetAll() on /org/freedesktop/systemd1/unit/systemd_2djournald_2eservice
Nov 17 19:42:58 chaembian systemd[1]: Got D-Bus request: org.freedesktop.DBus.NameOwnerChanged() on /org/freedesktop/DBus
berbae wrote:

Give also the result of:
ls -l /var/log/journal/e44c../

chaembian ~ » ls -l /var/log/journal/e44c.. 
total 11164
-rw-r-----  1 root systemd-journal 3948544 Nov 17 19:47 system.journal
-rw-r-----+ 1 root systemd-journal 3739648 Nov 17 18:35 user-1000.journal
-rw-r-----+ 1 root systemd-journal 3735552 Nov 17 14:30 user-1004.journal
berbae wrote:

Do you have kernel command line options for systemd?

Yes, recently changed this to the following to see if I notice anything unusual from the log

GRUB_CMDLINE_LINUX="systemd.log_level=debug systemd.log_target=kmsg log_buf_len=1M"

Offline

#8 2013-11-17 14:31:37

chaembian
Member
Registered: 2012-02-25
Posts: 15

Re: [SOLVED] systemd: no kernel messages in the journal

berbae wrote:

It would have been clearer if you had given the exact error message you got when journalctl crashed.

For sometime, journalctl was slow to start. If I had started the journalctl with no options and did a GG to go to bottom, I get a segfault. An example from old log,

May 13 11:32:31 chaembian kernel: journalctl[3006]: segfault at 7ff658382000 ip 00007ff657cdded8 sp 00007fff52397250 error 6 in libgcrypt.so.11.8.1[7ff657cc00
May 13 11:32:31 chaembian systemd-coredump[3007]: Process 3006 (journalctl) dumped core.
May 13 11:34:41 chaembian kernel: journalctl[3037]: segfault at 7f4866667000 ip 00007f4865fc2ed8 sp 00007fffa8ef0d80 error 6 in libgcrypt.so.11.8.1[7f4865fa50
May 13 11:34:41 chaembian systemd-coredump[3038]: Process 3037 (journalctl) dumped core.
May 13 11:42:15 chaembian kernel: journalctl[3081]: segfault at 7f7b65725000 ip 00007f7b65080ed8 sp 00007fffeba26760 error 6 in libgcrypt.so.11.8.1[7f7b650630
May 13 11:42:15 chaembian systemd-coredump[3082]: Process 3081 (journalctl) dumped core.

I was working around this using journalctl -ab, since usually I need to look at the messages from that boot only. I tried verifying but without the keys to FSS it was a no-go.

Offline

#9 2013-11-17 16:10:11

berbae
Member
From: France
Registered: 2007-02-12
Posts: 1,302

Re: [SOLVED] systemd: no kernel messages in the journal

GRUB_CMDLINE_LINUX="systemd.log_level=debug systemd.log_target=kmsg log_buf_len=1M"

systemd.log_level defaults to debug, so it is superfluous.
I have doubts about the 'systemd.log_target=kms': it could possibly prevent the use of the systemd journal.
I am not sure but the 'systemd.journald.forward_to_kmsg=' may be better; but I didn't use these options, so I can't say for sure.

That seems confirmed by the output you get from the 'journalct --dmesg' command. For informations here is what I get:

$ journalctl -b --dmesg|grep "systemd"  
nov. 17 10:31:47 arch64 systemd-udevd[47]: starting version 208
nov. 17 10:31:47 arch64 systemd[1]: systemd 208 running in system mode. (+PAM -LIBWRAP -AUDIT -SELINUX -IMA -SYSVINIT +LIBCRYPTSETUP
+GCRYPT +ACL +XZ)
nov. 17 10:31:47 arch64 systemd[1]: Set hostname to <arch64>.
nov. 17 10:31:47 arch64 systemd[1]: Starting Forward Password Requests to Wall Directory Watch.
nov. 17 10:31:47 arch64 systemd[1]: Started Forward Password Requests to Wall Directory Watch.
nov. 17 10:31:47 arch64 systemd[1]: Expecting device sys-subsystem-net-devices-eth0.device...
nov. 17 10:31:47 arch64 systemd[1]: Starting Remote File Systems.
nov. 17 10:31:47 arch64 systemd[1]: Reached target Remote File Systems.
nov. 17 10:31:47 arch64 systemd[1]: Starting LVM2 metadata daemon socket.
nov. 17 10:31:47 arch64 systemd[1]: Listening on LVM2 metadata daemon socket.
nov. 17 10:31:47 arch64 systemd[1]: Starting Delayed Shutdown Socket.
nov. 17 10:31:47 arch64 systemd[1]: Listening on Delayed Shutdown Socket.
nov. 17 10:31:47 arch64 systemd[1]: Starting Device-mapper event daemon FIFOs.
nov. 17 10:31:47 arch64 systemd[1]: Listening on Device-mapper event daemon FIFOs.
nov. 17 10:31:47 arch64 systemd[1]: Starting /dev/initctl Compatibility Named Pipe.
nov. 17 10:31:47 arch64 systemd[1]: Listening on /dev/initctl Compatibility Named Pipe.
nov. 17 10:31:47 arch64 systemd[1]: Starting Arbitrary Executable File Formats File System Automount Point.
nov. 17 10:31:47 arch64 systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
nov. 17 10:31:47 arch64 systemd[1]: Starting Encrypted Volumes.
nov. 17 10:31:47 arch64 systemd[1]: Reached target Encrypted Volumes.
nov. 17 10:31:47 arch64 systemd[1]: Starting Dispatch Password Requests to Console Directory Watch.
nov. 17 10:31:47 arch64 systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
nov. 17 10:31:47 arch64 systemd[1]: Starting Paths.
nov. 17 10:31:47 arch64 systemd[1]: Reached target Paths.
nov. 17 10:31:47 arch64 systemd[1]: Starting udev Kernel Socket.
nov. 17 10:31:47 arch64 systemd[1]: Listening on udev Kernel Socket.
nov. 17 10:31:47 arch64 systemd[1]: Starting udev Control Socket.
nov. 17 10:31:47 arch64 systemd[1]: Listening on udev Control Socket.
nov. 17 10:31:47 arch64 systemd[1]: Starting Journal Socket.
nov. 17 10:31:47 arch64 systemd[1]: Listening on Journal Socket.
nov. 17 10:31:47 arch64 systemd[1]: Starting Create list of required static device nodes for the current kernel...
nov. 17 10:31:47 arch64 systemd[1]: Mounting Huge Pages File System...
nov. 17 10:31:47 arch64 systemd[1]: Started Set Up Additional Binary Formats.
nov. 17 10:31:47 arch64 systemd[1]: Starting udev Coldplug all Devices...
nov. 17 10:31:47 arch64 systemd[1]: Mounting POSIX Message Queue File System...
nov. 17 10:31:47 arch64 systemd[1]: Started Load Kernel Modules.
nov. 17 10:31:47 arch64 systemd[1]: Mounted FUSE Control File System.
nov. 17 10:31:47 arch64 systemd[1]: Starting Apply Kernel Variables...
nov. 17 10:31:47 arch64 systemd[1]: Starting Journal Service...
nov. 17 10:31:47 arch64 systemd[1]: Started Journal Service.
nov. 17 10:31:48 arch64 systemd-udevd[138]: starting version 208

and of course all the kernel log messages wich I filtered out.
You should see all the systemd services 'Starting' and 'Started' lines.
As they don't appear in your output, it seems to indicate that nothing is logged to the systemd journal.
If you get a long list of services with the command 'systemctl', it would be confirmed that the journal doesn't log anything useful.

But apart from that, it is difficult to see clearly among the many interventions done to the system (known by you or not known).
You seem to tell that the journal worked sometimes...

So try to change the kernel command line parameters, and verify if the systemd services start normally; they should tell you in the log.

Offline

#10 2013-11-17 18:40:18

chaembian
Member
Registered: 2012-02-25
Posts: 15

Re: [SOLVED] systemd: no kernel messages in the journal

OK, I removed the commandline options, regenerated grub config, rebooted.

journalctl --dmesg starts:

-- Logs begin at Sun 2013-11-17 23:26:51 IST, end at Sun 2013-11-17 23:35:38 IST. --
Nov 17 23:26:51 chaembian kernel: input: HDA ATI SB Front Headphone as /devices/pci0000:00/0000:00:14.2/sound/card0/input3
Nov 17 23:26:51 chaembian kernel: input: HDA ATI SB Line Out Side as /devices/pci0000:00/0000:00:14.2/sound/card0/input4
Nov 17 23:26:51 chaembian kernel: input: HDA ATI SB Line Out CLFE as /devices/pci0000:00/0000:00:14.2/sound/card0/input5
Nov 17 23:26:51 chaembian kernel: input: HDA ATI SB Line Out Surround as /devices/pci0000:00/0000:00:14.2/sound/card0/input6
Nov 17 23:26:51 chaembian kernel: input: HDA ATI SB Line Out Front as /devices/pci0000:00/0000:00:14.2/sound/card0/input7
Nov 17 23:26:51 chaembian kernel: input: HDA ATI SB Line as /devices/pci0000:00/0000:00:14.2/sound/card0/input8
Nov 17 23:26:51 chaembian kernel: input: HDA ATI SB Rear Mic as /devices/pci0000:00/0000:00:14.2/sound/card0/input9
Nov 17 23:26:51 chaembian kernel: input: HDA ATI SB Front Mic as /devices/pci0000:00/0000:00:14.2/sound/card0/input10
Nov 17 23:26:51 chaembian kernel: snd_hda_intel 0000:01:05.1: setting latency timer to 64
Nov 17 23:26:51 chaembian kernel: input: HDA ATI HDMI HDMI/DP,pcm=3 as /devices/pci0000:00/0000:00:01.0/0000:01:05.1/sound/card1/input11
Nov 17 23:26:51 chaembian kernel: microcode: CPU0: new patch_level=0x010000c8
Nov 17 23:26:51 chaembian kernel: microcode: CPU1: patch_level=0x010000b6
Nov 17 23:26:51 chaembian kernel: microcode: CPU1: new patch_level=0x010000c8
Nov 17 23:26:51 chaembian kernel: microcode: CPU2: patch_level=0x010000b6
Nov 17 23:26:51 chaembian kernel: microcode: CPU2: new patch_level=0x010000c8
Nov 17 23:26:51 chaembian kernel: microcode: CPU3: patch_level=0x010000b6
Nov 17 23:26:51 chaembian kernel: microcode: CPU3: new patch_level=0x010000c8
Nov 17 23:26:51 chaembian kernel: microcode: Microcode Update Driver: v2.00 <tigran@aivazian.fsnet.co.uk>, Peter Oruba
Nov 17 23:26:51 chaembian kernel: media: Linux media interface: v0.10
Nov 17 23:26:51 chaembian kernel: Linux video capture interface: v2.00
Nov 17 23:26:51 chaembian kernel: kvm: Nested Virtualization enabled
Nov 17 23:26:51 chaembian kernel: kvm: Nested Paging enabled
Nov 17 23:26:52 chaembian kernel: hidraw: raw HID events driver (C) Jiri Kosina
...

That is indeed a step in the right direction. smile

dmesg for cross-reference

[    0.000000] Initializing cgroup subsys cpuset
[    0.000000] Initializing cgroup subsys cpu
[    0.000000] Initializing cgroup subsys cpuacct
[    0.000000] Linux version 3.11.6-1-ARCH (nobody@var-lib-archbuild-extra-x86_64-thomas) (gcc version 4.8.1 20130725 (prerelease) (GCC) ) #1 SMP PREEMPT Fri Oct 18 23:22:36 CEST 2013
[    0.000000] Command line: BOOT_IMAGE=/vmlinuz-linux root=UUID=639e14d4-e548-48ed-a6e5-c73a19c618ff rw quiet
[    0.000000] e820: BIOS-provided physical RAM map:
[    0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009abff] usable
[    0.000000] BIOS-e820: [mem 0x000000000009ac00-0x000000000009ffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000000e2000-0x00000000000fffff] reserved
[    0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000cff7ffff] usable
[    0.000000] BIOS-e820: [mem 0x00000000cff80000-0x00000000cff97fff] ACPI data
[    0.000000] BIOS-e820: [mem 0x00000000cff98000-0x00000000cffcffff] ACPI NVS
[    0.000000] BIOS-e820: [mem 0x00000000cffd0000-0x00000000cfffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000ffa00000-0x00000000ffffffff] reserved
[    0.000000] BIOS-e820: [mem 0x0000000100000000-0x000000011fffffff] usable
[    0.000000] NX (Execute Disable) protection: active
[    0.000000] SMBIOS 2.5 present.
[    0.000000] DMI: System manufacturer System Product Name/M4A88TD-M EVO, BIOS 1702    12/10/2010
[    0.000000] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
[    0.000000] e820: remove [mem 0x000a0000-0x000fffff] usable
[    0.000000] No AGP bridge found
[    0.000000] e820: last_pfn = 0x120000 max_arch_pfn = 0x400000000
[    0.000000] MTRR default type: uncachable
[    0.000000] MTRR fixed ranges enabled:
[    0.000000]   00000-9FFFF write-back
[    0.000000]   A0000-EFFFF uncachable
[    0.000000]   F0000-FFFFF write-protect
[    0.000000] MTRR variable ranges enabled:
[    0.000000]   0 base 000000000000 mask FFFF80000000 write-back
[    0.000000]   1 base 000080000000 mask FFFFC0000000 write-back
[    0.000000]   2 base 0000C0000000 mask FFFFF0000000 write-back
[    0.000000]   3 disabled
[    0.000000]   4 disabled
[    0.000000]   5 disabled
[    0.000000]   6 disabled
[    0.000000]   7 disabled
[    0.000000] TOM2: 0000000130000000 aka 4864M
[    0.000000] x86 PAT enabled: cpu 0, old 0x7040600070406, new 0x7010600070106
[    0.000000] e820: update [mem 0xd0000000-0xffffffff] usable ==> reserved
[    0.000000] e820: last_pfn = 0xcff80 max_arch_pfn = 0x400000000
[    0.000000] found SMP MP-table at [mem 0x000ff780-0x000ff78f] mapped at [ffff8800000ff780]
[    0.000000] Scanning 1 areas for low memory corruption
[    0.000000] Base memory trampoline at [ffff880000094000] 94000 size 24576
[    0.000000] Using GB pages for direct mapping
[    0.000000] init_memory_mapping: [mem 0x00000000-0x000fffff]
[    0.000000]  [mem 0x00000000-0x000fffff] page 4k
[    0.000000] BRK [0x01b2f000, 0x01b2ffff] PGTABLE
[    0.000000] BRK [0x01b30000, 0x01b30fff] PGTABLE
[    0.000000] BRK [0x01b31000, 0x01b31fff] PGTABLE
[    0.000000] init_memory_mapping: [mem 0x11fe00000-0x11fffffff]
[    0.000000]  [mem 0x11fe00000-0x11fffffff] page 2M
[    0.000000] BRK [0x01b32000, 0x01b32fff] PGTABLE
[    0.000000] init_memory_mapping: [mem 0x11c000000-0x11fdfffff]
[    0.000000]  [mem 0x11c000000-0x11fdfffff] page 2M
[    0.000000] init_memory_mapping: [mem 0x100000000-0x11bffffff]
[    0.000000]  [mem 0x100000000-0x11bffffff] page 2M
[    0.000000] init_memory_mapping: [mem 0x00100000-0xcff7ffff]
[    0.000000]  [mem 0x00100000-0x001fffff] page 4k
[    0.000000]  [mem 0x00200000-0x3fffffff] page 2M
[    0.000000]  [mem 0x40000000-0xbfffffff] page 1G
[    0.000000]  [mem 0xc0000000-0xcfdfffff] page 2M
[    0.000000]  [mem 0xcfe00000-0xcff7ffff] page 4k
[    0.000000] RAMDISK: [mem 0x37690000-0x37b3ffff]
[    0.000000] ACPI: RSDP 00000000000fb570 00024 (v02 ACPIAM)
[    0.000000] ACPI: XSDT 00000000cff80100 0005C (v01 121010 XSDT1437 20101210 MSFT 00000097)
[    0.000000] ACPI: FACP 00000000cff80290 000F4 (v03 121010 FACP1437 20101210 MSFT 00000097)
[    0.000000] ACPI: DSDT 00000000cff80450 0F2D6 (v01  A1581 A1581001 00000001 INTL 20060113)
[    0.000000] ACPI: FACS 00000000cff98000 00040
[    0.000000] ACPI: APIC 00000000cff80390 0007C (v01 121010 APIC1437 20101210 MSFT 00000097)
[    0.000000] ACPI: MCFG 00000000cff80410 0003C (v01 121010 OEMMCFG  20101210 MSFT 00000097)
[    0.000000] ACPI: OEMB 00000000cff98040 00072 (v01 121010 OEMB1437 20101210 MSFT 00000097)
[    0.000000] ACPI: SRAT 00000000cff8f8a0 000E8 (v01 AMD    FAM_F_10 00000002 AMD  00000001)
[    0.000000] ACPI: HPET 00000000cff8f990 00038 (v01 121010 OEMHPET  20101210 MSFT 00000097)
[    0.000000] ACPI: SSDT 00000000cff8f9d0 0088C (v01 A M I  POWERNOW 00000001 AMD  00000001)
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] SRAT: PXM 0 -> APIC 0x00 -> Node 0
[    0.000000] SRAT: PXM 0 -> APIC 0x01 -> Node 0
[    0.000000] SRAT: PXM 0 -> APIC 0x02 -> Node 0
[    0.000000] SRAT: PXM 0 -> APIC 0x03 -> Node 0
[    0.000000] SRAT: Node 0 PXM 0 [mem 0x00000000-0x0009ffff]
[    0.000000] SRAT: Node 0 PXM 0 [mem 0x00100000-0xcfffffff]
[    0.000000] SRAT: Node 0 PXM 0 [mem 0x100000000-0x12fffffff]
[    0.000000] NUMA: Node 0 [mem 0x00000000-0x0009ffff] + [mem 0x00100000-0xcfffffff] -> [mem 0x00000000-0xcfffffff]
[    0.000000] NUMA: Node 0 [mem 0x00000000-0xcfffffff] + [mem 0x100000000-0x11fffffff] -> [mem 0x00000000-0x11fffffff]
[    0.000000] Initmem setup node 0 [mem 0x00000000-0x11fffffff]
[    0.000000]   NODE_DATA [mem 0x11fff9000-0x11fffdfff]
[    0.000000]  [ffffea0000000000-ffffea00047fffff] PMD -> [ffff88011ba00000-ffff88011f5fffff] on node 0
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x00001000-0x00ffffff]
[    0.000000]   DMA32    [mem 0x01000000-0xffffffff]
[    0.000000]   Normal   [mem 0x100000000-0x11fffffff]
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x00001000-0x00099fff]
[    0.000000]   node   0: [mem 0x00100000-0xcff7ffff]
[    0.000000]   node   0: [mem 0x100000000-0x11fffffff]
[    0.000000] On node 0 totalpages: 982809
[    0.000000]   DMA zone: 64 pages used for memmap
[    0.000000]   DMA zone: 21 pages reserved
[    0.000000]   DMA zone: 3993 pages, LIFO batch:0
[    0.000000]   DMA32 zone: 13246 pages used for memmap
[    0.000000]   DMA32 zone: 847744 pages, LIFO batch:31
[    0.000000]   Normal zone: 2048 pages used for memmap
[    0.000000]   Normal zone: 131072 pages, LIFO batch:31
[    0.000000] ACPI: PM-Timer IO Port: 0x808
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] ACPI: LAPIC (acpi_id[0x01] lapic_id[0x00] enabled)
[    0.000000] ACPI: LAPIC (acpi_id[0x02] lapic_id[0x01] enabled)
[    0.000000] ACPI: LAPIC (acpi_id[0x03] lapic_id[0x02] enabled)
[    0.000000] ACPI: LAPIC (acpi_id[0x04] lapic_id[0x03] enabled)
[    0.000000] ACPI: LAPIC (acpi_id[0x05] lapic_id[0x84] disabled)
[    0.000000] ACPI: LAPIC (acpi_id[0x06] lapic_id[0x85] disabled)
[    0.000000] ACPI: IOAPIC (id[0x04] address[0xfec00000] gsi_base[0])
[    0.000000] IOAPIC[0]: apic_id 4, version 33, address 0xfec00000, GSI 0-23
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 low level)
[    0.000000] ACPI: IRQ0 used by override.
[    0.000000] ACPI: IRQ2 used by override.
[    0.000000] ACPI: IRQ9 used by override.
[    0.000000] Using ACPI (MADT) for SMP configuration information
[    0.000000] ACPI: HPET id: 0x8300 base: 0xfed00000
[    0.000000] smpboot: Allowing 6 CPUs, 2 hotplug CPUs
[    0.000000] nr_irqs_gsi: 40
[    0.000000] PM: Registered nosave memory: [mem 0x0009a000-0x0009afff]
[    0.000000] PM: Registered nosave memory: [mem 0x0009b000-0x0009ffff]
[    0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000e1fff]
[    0.000000] PM: Registered nosave memory: [mem 0x000e2000-0x000fffff]
[    0.000000] PM: Registered nosave memory: [mem 0xcff80000-0xcff97fff]
[    0.000000] PM: Registered nosave memory: [mem 0xcff98000-0xcffcffff]
[    0.000000] PM: Registered nosave memory: [mem 0xcffd0000-0xcfffffff]
[    0.000000] PM: Registered nosave memory: [mem 0xd0000000-0xff9fffff]
[    0.000000] PM: Registered nosave memory: [mem 0xffa00000-0xffffffff]
[    0.000000] e820: [mem 0xd0000000-0xff9fffff] available for PCI devices
[    0.000000] Booting paravirtualized kernel on bare hardware
[    0.000000] setup_percpu: NR_CPUS:128 nr_cpumask_bits:128 nr_cpu_ids:6 nr_node_ids:1
[    0.000000] PERCPU: Embedded 29 pages/cpu @ffff88011fc00000 s86528 r8192 d24064 u262144
[    0.000000] pcpu-alloc: s86528 r8192 d24064 u262144 alloc=1*2097152
[    0.000000] pcpu-alloc: [0] 0 1 2 3 4 5 - - 
[    0.000000] Built 1 zonelists in Node order, mobility grouping on.  Total pages: 967430
[    0.000000] Policy zone: Normal
[    0.000000] Kernel command line: BOOT_IMAGE=/vmlinuz-linux root=UUID=639e14d4-e548-48ed-a6e5-c73a19c618ff rw quiet
[    0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes)
[    0.000000] Checking aperture...
[    0.000000] No AGP bridge found
[    0.000000] Node 0: aperture @ c4000000 size 32 MB
[    0.000000] Aperture pointing to e820 RAM. Ignoring.
[    0.000000] Your BIOS doesn't leave a aperture memory hole
[    0.000000] Please enable the IOMMU option in the BIOS setup
[    0.000000] This costs you 64 MB of RAM
[    0.000000] Mapping aperture over 65536 KB of RAM @ c4000000
[    0.000000] PM: Registered nosave memory: [mem 0xc4000000-0xc7ffffff]
[    0.000000] Memory: 3720876K/3931236K available (5050K kernel code, 799K rwdata, 1696K rodata, 1140K init, 1288K bss, 210360K reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1
[    0.000000] Preemptible hierarchical RCU implementation.
[    0.000000] 	RCU dyntick-idle grace-period acceleration is enabled.
[    0.000000] 	Dump stacks of tasks blocking RCU-preempt GP.
[    0.000000] 	RCU restricting CPUs from NR_CPUS=128 to nr_cpu_ids=6.
[    0.000000] NR_IRQS:8448 nr_irqs:728 16
[    0.000000] spurious 8259A interrupt: IRQ7.
[    0.000000] Console: colour dummy device 80x25
[    0.000000] console [tty0] enabled
[    0.000000] allocated 15728640 bytes of page_cgroup
[    0.000000] please try 'cgroup_disable=memory' option if you don't want memory cgroups
[    0.000000] hpet clockevent registered
[    0.000000] tsc: Fast TSC calibration using PIT
[    0.003333] tsc: Detected 3415.125 MHz processor
[    0.000003] Calibrating delay loop (skipped), value calculated using timer frequency.. 6832.84 BogoMIPS (lpj=11383750)
[    0.000005] pid_max: default: 32768 minimum: 301
[    0.000027] Security Framework initialized
[    0.000033] AppArmor: AppArmor disabled by boot time parameter
[    0.000034] Yama: becoming mindful.
[    0.000231] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes)
[    0.001248] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes)
[    0.001717] Mount-cache hash table entries: 256
[    0.001875] Initializing cgroup subsys memory
[    0.001885] Initializing cgroup subsys devices
[    0.001887] Initializing cgroup subsys freezer
[    0.001888] Initializing cgroup subsys net_cls
[    0.001889] Initializing cgroup subsys blkio
[    0.001904] tseg: 0000000000
[    0.001910] CPU: Physical Processor ID: 0
[    0.001910] CPU: Processor Core ID: 0
[    0.001912] mce: CPU supports 6 MCE banks
[    0.001916] LVT offset 0 assigned for vector 0xf9
[    0.001919] process: using AMD E400 aware idle routine
[    0.001921] Last level iTLB entries: 4KB 512, 2MB 16, 4MB 8
Last level dTLB entries: 4KB 512, 2MB 128, 4MB 64
tlb_flushall_shift: 4
[    0.001983] Freeing SMP alternatives memory: 20K (ffffffff819e6000 - ffffffff819eb000)
[    0.002531] ACPI: Core revision 20130517
[    0.006010] ACPI: All ACPI Tables successfully acquired
[    0.109344] ftrace: allocating 20100 entries in 79 pages
[    0.116210] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[    0.149233] smpboot: CPU0: AMD Phenom(tm) II X4 965 Processor (fam: 10, model: 04, stepping: 03)
[    0.253603] Performance Events: AMD PMU driver.
[    0.253606] ... version:                0
[    0.253607] ... bit width:              48
[    0.253608] ... generic registers:      4
[    0.253609] ... value mask:             0000ffffffffffff
[    0.253609] ... max period:             00007fffffffffff
[    0.253610] ... fixed-purpose events:   0
[    0.253611] ... event mask:             000000000000000f
[    0.253639] process: System has AMD C1E enabled
[    0.253645] process: Switch to broadcast mode on CPU0
[    0.270404] NMI watchdog: enabled on all CPUs, permanently consumes one hw-PMU counter.
[    0.290256] process: Switch to broadcast mode on CPU1
[    0.306943] process: Switch to broadcast mode on CPU2
[    0.277079] smpboot: Booting Node   0, Processors  #1 #2 #3
[    0.323592] Brought up 4 CPUs
[    0.323595] smpboot: Total of 4 processors activated (27331.37 BogoMIPS)
[    0.323636] process: Switch to broadcast mode on CPU3
[    0.328034] devtmpfs: initialized
[    0.329347] PM: Registering ACPI NVS region [mem 0xcff98000-0xcffcffff] (229376 bytes)
[    0.330091] RTC time: 17:56:43, date: 11/17/13
[    0.330127] NET: Registered protocol family 16
[    0.330208] node 0 link 0: io port [1000, ffffff]
[    0.330210] TOM: 00000000d0000000 aka 3328M
[    0.330212] Fam 10h mmconf [mem 0xe0000000-0xefffffff]
[    0.330213] node 0 link 0: mmio [a0000, bffff]
[    0.330215] node 0 link 0: mmio [d0000000, efffffff] ==> [d0000000, dfffffff]
[    0.330217] node 0 link 0: mmio [f0000000, fe6fffff]
[    0.330219] node 0 link 0: mmio [fe700000, fe8fffff]
[    0.330220] node 0 link 0: mmio [fe900000, ffdfffff]
[    0.330221] TOM2: 0000000130000000 aka 4864M
[    0.330222] bus: [bus 00-07] on node 0 link 0
[    0.330223] bus: 00 [io  0x0000-0xffff]
[    0.330224] bus: 00 [mem 0x000a0000-0x000bffff]
[    0.330225] bus: 00 [mem 0xd0000000-0xdfffffff]
[    0.330226] bus: 00 [mem 0xf0000000-0xffffffff]
[    0.330227] bus: 00 [mem 0x130000000-0xfcffffffff]
[    0.330268] ACPI: bus type PCI registered
[    0.330269] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
[    0.330314] PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xe0000000-0xefffffff] (base 0xe0000000)
[    0.330316] PCI: not using MMCONFIG
[    0.330317] PCI: Using configuration type 1 for base access
[    0.330317] PCI: Using configuration type 1 for extended access
[    0.330790] bio: create slab <bio-0> at 0
[    0.330911] ACPI: Added _OSI(Module Device)
[    0.330913] ACPI: Added _OSI(Processor Device)
[    0.330914] ACPI: Added _OSI(3.0 _SCP Extensions)
[    0.330915] ACPI: Added _OSI(Processor Aggregator Device)
[    0.331704] ACPI: EC: Look up EC in DSDT
[    0.343813] ACPI: Executed 3 blocks of module-level executable AML code
[    0.657725] ACPI: Interpreter enabled
[    0.657735] ACPI Exception: AE_NOT_FOUND, While evaluating Sleep State [\_S2_] (20130517/hwxface-571)
[    0.657746] ACPI: (supports S0 S1 S3 S4 S5)
[    0.657748] ACPI: Using IOAPIC for interrupt routing
[    0.657763] PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xe0000000-0xefffffff] (base 0xe0000000)
[    0.658965] PCI: MMCONFIG at [mem 0xe0000000-0xefffffff] reserved in ACPI motherboard resources
[    0.665871] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[    0.665931] ACPI: No dock devices found.
[    0.670245] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[    0.670357] acpi PNP0A03:00: Requesting ACPI _OSC control (0x1d)
[    0.670453] acpi PNP0A03:00: ACPI _OSC control (0x1d) granted
[    0.670632] PCI host bridge to bus 0000:00
[    0.670635] pci_bus 0000:00: root bus resource [bus 00-ff]
[    0.670636] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7]
[    0.670638] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff]
[    0.670639] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff]
[    0.670641] pci_bus 0000:00: root bus resource [mem 0x000d0000-0x000dffff]
[    0.670642] pci_bus 0000:00: root bus resource [mem 0xd0000000-0xdfffffff]
[    0.670643] pci_bus 0000:00: root bus resource [mem 0xf0000000-0xfebfffff]
[    0.670652] pci 0000:00:00.0: [1022:9601] type 00 class 0x060000
[    0.670728] pci 0000:00:01.0: [1043:9602] type 01 class 0x060400
[    0.670791] pci 0000:00:05.0: [1022:9605] type 01 class 0x060400
[    0.670818] pci 0000:00:05.0: PME# supported from D0 D3hot D3cold
[    0.670846] pci 0000:00:05.0: System wakeup disabled by ACPI
[    0.670871] pci 0000:00:07.0: [1022:9607] type 01 class 0x060400
[    0.670897] pci 0000:00:07.0: PME# supported from D0 D3hot D3cold
[    0.670924] pci 0000:00:07.0: System wakeup disabled by ACPI
[    0.670947] pci 0000:00:09.0: [1022:9608] type 01 class 0x060400
[    0.670974] pci 0000:00:09.0: PME# supported from D0 D3hot D3cold
[    0.671001] pci 0000:00:09.0: System wakeup disabled by ACPI
[    0.671023] pci 0000:00:0a.0: [1022:9609] type 01 class 0x060400
[    0.671050] pci 0000:00:0a.0: PME# supported from D0 D3hot D3cold
[    0.671077] pci 0000:00:0a.0: System wakeup disabled by ACPI
[    0.671108] pci 0000:00:11.0: [1002:4391] type 00 class 0x010601
[    0.671122] pci 0000:00:11.0: reg 0x10: [io  0x9000-0x9007]
[    0.671129] pci 0000:00:11.0: reg 0x14: [io  0x8000-0x8003]
[    0.671135] pci 0000:00:11.0: reg 0x18: [io  0x7000-0x7007]
[    0.671142] pci 0000:00:11.0: reg 0x1c: [io  0x6000-0x6003]
[    0.671149] pci 0000:00:11.0: reg 0x20: [io  0x5000-0x500f]
[    0.671156] pci 0000:00:11.0: reg 0x24: [mem 0xfe6ffc00-0xfe6fffff]
[    0.671231] pci 0000:00:12.0: [1002:4397] type 00 class 0x0c0310
[    0.671241] pci 0000:00:12.0: reg 0x10: [mem 0xfe6fe000-0xfe6fefff]
[    0.671303] pci 0000:00:12.0: System wakeup disabled by ACPI
[    0.671332] pci 0000:00:12.2: [1002:4396] type 00 class 0x0c0320
[    0.671346] pci 0000:00:12.2: reg 0x10: [mem 0xfe6ff800-0xfe6ff8ff]
[    0.671405] pci 0000:00:12.2: supports D1 D2
[    0.671406] pci 0000:00:12.2: PME# supported from D0 D1 D2 D3hot
[    0.671435] pci 0000:00:12.2: System wakeup disabled by ACPI
[    0.671464] pci 0000:00:13.0: [1002:4397] type 00 class 0x0c0310
[    0.671474] pci 0000:00:13.0: reg 0x10: [mem 0xfe6fd000-0xfe6fdfff]
[    0.671536] pci 0000:00:13.0: System wakeup disabled by ACPI
[    0.671563] pci 0000:00:13.2: [1002:4396] type 00 class 0x0c0320
[    0.671577] pci 0000:00:13.2: reg 0x10: [mem 0xfe6ff400-0xfe6ff4ff]
[    0.671635] pci 0000:00:13.2: supports D1 D2
[    0.671637] pci 0000:00:13.2: PME# supported from D0 D1 D2 D3hot
[    0.671665] pci 0000:00:13.2: System wakeup disabled by ACPI
[    0.671691] pci 0000:00:14.0: [1002:4385] type 00 class 0x0c0500
[    0.671780] pci 0000:00:14.2: [1002:4383] type 00 class 0x040300
[    0.671795] pci 0000:00:14.2: reg 0x10: [mem 0xfe6f4000-0xfe6f7fff 64bit]
[    0.671842] pci 0000:00:14.2: PME# supported from D0 D3hot D3cold
[    0.671870] pci 0000:00:14.2: System wakeup disabled by ACPI
[    0.671893] pci 0000:00:14.3: [1002:439d] type 00 class 0x060100
[    0.671979] pci 0000:00:14.4: [1002:4384] type 01 class 0x060401
[    0.672026] pci 0000:00:14.4: System wakeup disabled by ACPI
[    0.672049] pci 0000:00:14.5: [1002:4399] type 00 class 0x0c0310
[    0.672059] pci 0000:00:14.5: reg 0x10: [mem 0xfe6fc000-0xfe6fcfff]
[    0.672121] pci 0000:00:14.5: System wakeup disabled by ACPI
[    0.672146] pci 0000:00:16.0: [1002:4397] type 00 class 0x0c0310
[    0.672156] pci 0000:00:16.0: reg 0x10: [mem 0xfe6f3000-0xfe6f3fff]
[    0.672218] pci 0000:00:16.0: System wakeup disabled by ACPI
[    0.672245] pci 0000:00:16.2: [1002:4396] type 00 class 0x0c0320
[    0.672258] pci 0000:00:16.2: reg 0x10: [mem 0xfe6ff000-0xfe6ff0ff]
[    0.672317] pci 0000:00:16.2: supports D1 D2
[    0.672318] pci 0000:00:16.2: PME# supported from D0 D1 D2 D3hot
[    0.672347] pci 0000:00:16.2: System wakeup disabled by ACPI
[    0.672372] pci 0000:00:18.0: [1022:1200] type 00 class 0x060000
[    0.672416] pci 0000:00:18.1: [1022:1201] type 00 class 0x060000
[    0.672457] pci 0000:00:18.2: [1022:1202] type 00 class 0x060000
[    0.672499] pci 0000:00:18.3: [1022:1203] type 00 class 0x060000
[    0.672542] pci 0000:00:18.4: [1022:1204] type 00 class 0x060000
[    0.672618] pci 0000:01:05.0: [1002:9715] type 00 class 0x030000
[    0.672624] pci 0000:01:05.0: reg 0x10: [mem 0xd0000000-0xdfffffff pref]
[    0.672628] pci 0000:01:05.0: reg 0x14: [io  0xa000-0xa0ff]
[    0.672632] pci 0000:01:05.0: reg 0x18: [mem 0xfe8f0000-0xfe8fffff]
[    0.672639] pci 0000:01:05.0: reg 0x24: [mem 0xfe700000-0xfe7fffff]
[    0.672654] pci 0000:01:05.0: supports D1 D2
[    0.672678] pci 0000:01:05.1: [1002:970f] type 00 class 0x040300
[    0.672684] pci 0000:01:05.1: reg 0x10: [mem 0xfe8e8000-0xfe8ebfff]
[    0.672709] pci 0000:01:05.1: supports D1 D2
[    0.672750] pci 0000:00:01.0: PCI bridge to [bus 01]
[    0.672753] pci 0000:00:01.0:   bridge window [io  0xa000-0xafff]
[    0.672755] pci 0000:00:01.0:   bridge window [mem 0xfe700000-0xfe8fffff]
[    0.672758] pci 0000:00:01.0:   bridge window [mem 0xd0000000-0xdfffffff 64bit pref]
[    0.672796] pci 0000:02:00.0: [1033:0194] type 00 class 0x0c0330
[    0.672810] pci 0000:02:00.0: reg 0x10: [mem 0xfe9fe000-0xfe9fffff 64bit]
[    0.672875] pci 0000:02:00.0: PME# supported from D0 D3hot
[    0.680606] pci 0000:00:05.0: PCI bridge to [bus 02]
[    0.680611] pci 0000:00:05.0:   bridge window [mem 0xfe900000-0xfe9fffff]
[    0.680673] pci 0000:03:00.0: [1106:3403] type 00 class 0x0c0010
[    0.680702] pci 0000:03:00.0: reg 0x10: [mem 0xfeaff800-0xfeafffff 64bit]
[    0.680717] pci 0000:03:00.0: reg 0x18: [io  0xb800-0xb8ff]
[    0.680838] pci 0000:03:00.0: supports D2
[    0.680839] pci 0000:03:00.0: PME# supported from D2 D3hot D3cold
[    0.690631] pci 0000:00:07.0: PCI bridge to [bus 03]
[    0.690635] pci 0000:00:07.0:   bridge window [io  0xb000-0xbfff]
[    0.690637] pci 0000:00:07.0:   bridge window [mem 0xfea00000-0xfeafffff]
[    0.690704] pci 0000:04:00.0: [1106:0415] type 00 class 0x010185
[    0.690726] pci 0000:04:00.0: reg 0x10: [io  0xcc00-0xcc07]
[    0.690740] pci 0000:04:00.0: reg 0x14: [io  0xc880-0xc883]
[    0.690755] pci 0000:04:00.0: reg 0x18: [io  0xc800-0xc807]
[    0.690769] pci 0000:04:00.0: reg 0x1c: [io  0xc480-0xc483]
[    0.690783] pci 0000:04:00.0: reg 0x20: [io  0xc400-0xc40f]
[    0.690811] pci 0000:04:00.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref]
[    0.690883] pci 0000:04:00.0: supports D1 D2
[    0.690885] pci 0000:04:00.0: PME# supported from D1 D2 D3hot
[    0.700620] pci 0000:00:09.0: PCI bridge to [bus 04]
[    0.700625] pci 0000:00:09.0:   bridge window [io  0xc000-0xcfff]
[    0.700627] pci 0000:00:09.0:   bridge window [mem 0xfeb00000-0xfebfffff]
[    0.700675] pci 0000:05:00.0: [10ec:8168] type 00 class 0x020000
[    0.700687] pci 0000:05:00.0: reg 0x10: [io  0xd800-0xd8ff]
[    0.700705] pci 0000:05:00.0: reg 0x18: [mem 0xfdfff000-0xfdffffff 64bit pref]
[    0.700717] pci 0000:05:00.0: reg 0x20: [mem 0xfdff8000-0xfdffbfff 64bit pref]
[    0.700768] pci 0000:05:00.0: supports D1 D2
[    0.700769] pci 0000:05:00.0: PME# supported from D0 D1 D2 D3hot D3cold
[    0.710623] pci 0000:00:0a.0: PCI bridge to [bus 05]
[    0.710627] pci 0000:00:0a.0:   bridge window [io  0xd000-0xdfff]
[    0.710631] pci 0000:00:0a.0:   bridge window [mem 0xfdf00000-0xfdffffff 64bit pref]
[    0.710674] pci 0000:06:05.0: [1412:1724] type 00 class 0x040100
[    0.710691] pci 0000:06:05.0: reg 0x10: [io  0xec00-0xec1f]
[    0.710701] pci 0000:06:05.0: reg 0x14: [io  0xe880-0xe8ff]
[    0.710769] pci 0000:06:05.0: supports D2
[    0.710824] pci 0000:00:14.4: PCI bridge to [bus 06] (subtractive decode)
[    0.710827] pci 0000:00:14.4:   bridge window [io  0xe000-0xefff]
[    0.710832] pci 0000:00:14.4:   bridge window [io  0x0000-0x0cf7] (subtractive decode)
[    0.710833] pci 0000:00:14.4:   bridge window [io  0x0d00-0xffff] (subtractive decode)
[    0.710835] pci 0000:00:14.4:   bridge window [mem 0x000a0000-0x000bffff] (subtractive decode)
[    0.710836] pci 0000:00:14.4:   bridge window [mem 0x000d0000-0x000dffff] (subtractive decode)
[    0.710837] pci 0000:00:14.4:   bridge window [mem 0xd0000000-0xdfffffff] (subtractive decode)
[    0.710839] pci 0000:00:14.4:   bridge window [mem 0xf0000000-0xfebfffff] (subtractive decode)
[    0.711399] ACPI: PCI Interrupt Link [LNKA] (IRQs 3 4 *5 6 7 9 10 11 14 15)
[    0.711445] ACPI: PCI Interrupt Link [LNKB] (IRQs 3 4 5 6 7 9 *10 11 14 15)
[    0.711491] ACPI: PCI Interrupt Link [LNKC] (IRQs 3 4 5 6 7 *9 10 11 14 15)
[    0.711536] ACPI: PCI Interrupt Link [LNKD] (IRQs 3 4 5 6 9 10 *11 14 15)
[    0.711572] ACPI: PCI Interrupt Link [LNKE] (IRQs 3 4 5 6 7 9 10 11 14 15) *0
[    0.711602] ACPI: PCI Interrupt Link [LNKF] (IRQs 3 4 5 6 7 9 10 11 14 15) *0
[    0.711631] ACPI: PCI Interrupt Link [LNKG] (IRQs 3 4 5 6 7 9 10 11 14 15) *0
[    0.711661] ACPI: PCI Interrupt Link [LNKH] (IRQs 3 4 5 6 7 9 10 11 14 15) *0
[    0.711760] ACPI: Enabled 1 GPEs in block 00 to 1F
[    0.711764] ACPI: \_SB_.PCI0: notify handler is installed
[    0.711791] Found 1 acpi root devices
[    0.711811] ACPI: EC: GPE = 0xa, I/O: command/status = 0x66, data = 0x62
[    0.711884] vgaarb: device added: PCI:0000:01:05.0,decodes=io+mem,owns=io+mem,locks=none
[    0.711885] vgaarb: loaded
[    0.711886] vgaarb: bridge control possible 0000:01:05.0
[    0.711914] PCI: Using ACPI for IRQ routing
[    0.717926] PCI: pci_cache_line_size set to 64 bytes
[    0.717976] e820: reserve RAM buffer [mem 0x0009ac00-0x0009ffff]
[    0.717978] e820: reserve RAM buffer [mem 0xcff80000-0xcfffffff]
[    0.718041] NetLabel: Initializing
[    0.718042] NetLabel:  domain hash size = 128
[    0.718042] NetLabel:  protocols = UNLABELED CIPSOv4
[    0.718052] NetLabel:  unlabeled traffic allowed by default
[    0.718064] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
[    0.718066] hpet0: 3 comparators, 32-bit 14.318180 MHz counter
[    0.720112] Switched to clocksource hpet
[    0.723318] pnp: PnP ACPI init
[    0.723328] ACPI: bus type PNP registered
[    0.723421] system 00:00: Plug and Play ACPI device, IDs PNP0c02 (active)
[    0.723488] pnp 00:01: [dma 4]
[    0.723503] pnp 00:01: Plug and Play ACPI device, IDs PNP0200 (active)
[    0.723526] pnp 00:02: Plug and Play ACPI device, IDs PNP0b00 (active)
[    0.723543] pnp 00:03: Plug and Play ACPI device, IDs PNP0800 (active)
[    0.723560] pnp 00:04: Plug and Play ACPI device, IDs PNP0c04 (active)
[    0.723934] pnp 00:05: [dma 0 disabled]
[    0.724064] pnp 00:05: Plug and Play ACPI device, IDs PNP0400 (active)
[    0.724097] pnp 00:06: Plug and Play ACPI device, IDs PNP0103 (active)
[    0.724374] pnp 00:07: [dma 0 disabled]
[    0.724425] pnp 00:07: Plug and Play ACPI device, IDs PNP0501 (active)
[    0.724491] system 00:08: [mem 0xfec00000-0xfec00fff] could not be reserved
[    0.724493] system 00:08: [mem 0xfee00000-0xfee00fff] has been reserved
[    0.724495] system 00:08: Plug and Play ACPI device, IDs PNP0c02 (active)
[    0.724649] system 00:09: [io  0x04d0-0x04d1] has been reserved
[    0.724651] system 00:09: [io  0x040b] has been reserved
[    0.724653] system 00:09: [io  0x04d6] has been reserved
[    0.724654] system 00:09: [io  0x0c00-0x0c01] has been reserved
[    0.724656] system 00:09: [io  0x0c14] has been reserved
[    0.724657] system 00:09: [io  0x0c50-0x0c51] has been reserved
[    0.724659] system 00:09: [io  0x0c52] has been reserved
[    0.724660] system 00:09: [io  0x0c6c] has been reserved
[    0.724661] system 00:09: [io  0x0c6f] has been reserved
[    0.724663] system 00:09: [io  0x0cd0-0x0cd1] has been reserved
[    0.724664] system 00:09: [io  0x0cd2-0x0cd3] has been reserved
[    0.724666] system 00:09: [io  0x0cd4-0x0cd5] has been reserved
[    0.724667] system 00:09: [io  0x0cd6-0x0cd7] has been reserved
[    0.724669] system 00:09: [io  0x0cd8-0x0cdf] has been reserved
[    0.724670] system 00:09: [io  0x0b00-0x0b3f] has been reserved
[    0.724672] system 00:09: [io  0x0800-0x089f] could not be reserved
[    0.724673] system 00:09: [io  0x0b00-0x0b1f] has been reserved
[    0.724675] system 00:09: [io  0x0b20-0x0b3f] has been reserved
[    0.724677] system 00:09: [io  0x0900-0x090f] has been reserved
[    0.724678] system 00:09: [io  0x0910-0x091f] has been reserved
[    0.724680] system 00:09: [io  0xfe00-0xfefe] has been reserved
[    0.724682] system 00:09: [mem 0xffb80000-0xffbfffff] has been reserved
[    0.724683] system 00:09: [mem 0xfec10000-0xfec1001f] has been reserved
[    0.724685] system 00:09: [mem 0xfed40000-0xfed44fff] has been reserved
[    0.724686] system 00:09: [mem 0xfed80000-0xfed80fff] has been reserved
[    0.724688] system 00:09: Plug and Play ACPI device, IDs PNP0c02 (active)
[    0.724807] system 00:0a: [io  0x0230-0x023f] has been reserved
[    0.724809] system 00:0a: [io  0x0290-0x029f] has been reserved
[    0.724810] system 00:0a: [io  0x0300-0x030f] has been reserved
[    0.724812] system 00:0a: [io  0x0a30-0x0a3f] has been reserved
[    0.724814] system 00:0a: Plug and Play ACPI device, IDs PNP0c02 (active)
[    0.724858] system 00:0b: [mem 0xe0000000-0xefffffff] has been reserved
[    0.724860] system 00:0b: Plug and Play ACPI device, IDs PNP0c02 (active)
[    0.724952] system 00:0c: [mem 0x00000000-0x0009ffff] could not be reserved
[    0.724953] system 00:0c: [mem 0x000c0000-0x000cffff] could not be reserved
[    0.724955] system 00:0c: [mem 0x000e0000-0x000fffff] could not be reserved
[    0.724957] system 00:0c: [mem 0x00100000-0xcfffffff] could not be reserved
[    0.724958] system 00:0c: [mem 0xfec00000-0xffffffff] could not be reserved
[    0.724960] system 00:0c: Plug and Play ACPI device, IDs PNP0c01 (active)
[    0.725022] pnp: PnP ACPI: found 13 devices
[    0.725022] ACPI: bus type PNP unregistered
[    0.731469] pci 0000:00:01.0: PCI bridge to [bus 01]
[    0.731472] pci 0000:00:01.0:   bridge window [io  0xa000-0xafff]
[    0.731474] pci 0000:00:01.0:   bridge window [mem 0xfe700000-0xfe8fffff]
[    0.731476] pci 0000:00:01.0:   bridge window [mem 0xd0000000-0xdfffffff 64bit pref]
[    0.731479] pci 0000:00:05.0: PCI bridge to [bus 02]
[    0.731481] pci 0000:00:05.0:   bridge window [mem 0xfe900000-0xfe9fffff]
[    0.731484] pci 0000:00:07.0: PCI bridge to [bus 03]
[    0.731486] pci 0000:00:07.0:   bridge window [io  0xb000-0xbfff]
[    0.731488] pci 0000:00:07.0:   bridge window [mem 0xfea00000-0xfeafffff]
[    0.731491] pci 0000:00:09.0: PCI bridge to [bus 04]
[    0.731493] pci 0000:00:09.0:   bridge window [io  0xc000-0xcfff]
[    0.731495] pci 0000:00:09.0:   bridge window [mem 0xfeb00000-0xfebfffff]
[    0.731498] pci 0000:00:0a.0: PCI bridge to [bus 05]
[    0.731499] pci 0000:00:0a.0:   bridge window [io  0xd000-0xdfff]
[    0.731502] pci 0000:00:0a.0:   bridge window [mem 0xfdf00000-0xfdffffff 64bit pref]
[    0.731505] pci 0000:00:14.4: PCI bridge to [bus 06]
[    0.731507] pci 0000:00:14.4:   bridge window [io  0xe000-0xefff]
[    0.731716] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7]
[    0.731718] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff]
[    0.731719] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff]
[    0.731721] pci_bus 0000:00: resource 7 [mem 0x000d0000-0x000dffff]
[    0.731722] pci_bus 0000:00: resource 8 [mem 0xd0000000-0xdfffffff]
[    0.731723] pci_bus 0000:00: resource 9 [mem 0xf0000000-0xfebfffff]
[    0.731725] pci_bus 0000:01: resource 0 [io  0xa000-0xafff]
[    0.731726] pci_bus 0000:01: resource 1 [mem 0xfe700000-0xfe8fffff]
[    0.731727] pci_bus 0000:01: resource 2 [mem 0xd0000000-0xdfffffff 64bit pref]
[    0.731729] pci_bus 0000:02: resource 1 [mem 0xfe900000-0xfe9fffff]
[    0.731730] pci_bus 0000:03: resource 0 [io  0xb000-0xbfff]
[    0.731732] pci_bus 0000:03: resource 1 [mem 0xfea00000-0xfeafffff]
[    0.731733] pci_bus 0000:04: resource 0 [io  0xc000-0xcfff]
[    0.731734] pci_bus 0000:04: resource 1 [mem 0xfeb00000-0xfebfffff]
[    0.731736] pci_bus 0000:05: resource 0 [io  0xd000-0xdfff]
[    0.731737] pci_bus 0000:05: resource 2 [mem 0xfdf00000-0xfdffffff 64bit pref]
[    0.731739] pci_bus 0000:06: resource 0 [io  0xe000-0xefff]
[    0.731740] pci_bus 0000:06: resource 4 [io  0x0000-0x0cf7]
[    0.731741] pci_bus 0000:06: resource 5 [io  0x0d00-0xffff]
[    0.731742] pci_bus 0000:06: resource 6 [mem 0x000a0000-0x000bffff]
[    0.731744] pci_bus 0000:06: resource 7 [mem 0x000d0000-0x000dffff]
[    0.731745] pci_bus 0000:06: resource 8 [mem 0xd0000000-0xdfffffff]
[    0.731746] pci_bus 0000:06: resource 9 [mem 0xf0000000-0xfebfffff]
[    0.731774] NET: Registered protocol family 2
[    0.731920] TCP established hash table entries: 32768 (order: 7, 524288 bytes)
[    0.732050] TCP bind hash table entries: 32768 (order: 7, 524288 bytes)
[    0.732170] TCP: Hash tables configured (established 32768 bind 32768)
[    0.732202] TCP: reno registered
[    0.732208] UDP hash table entries: 2048 (order: 4, 65536 bytes)
[    0.732229] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes)
[    0.732295] NET: Registered protocol family 1
[    0.732302] pci 0000:00:01.0: MSI quirk detected; subordinate MSI disabled
[    1.774244] pci 0000:01:05.0: Boot video device
[    1.774293] PCI: CLS 64 bytes, default 64
[    1.774329] Unpacking initramfs...
[    1.838503] Freeing initrd memory: 4800K (ffff880037690000 - ffff880037b40000)
[    1.839314] PCI-DMA: Disabling AGP.
[    1.839384] PCI-DMA: aperture base @ c4000000 size 65536 KB
[    1.839385] PCI-DMA: using GART IOMMU.
[    1.839387] PCI-DMA: Reserving 64MB of IOMMU area in the AGP aperture
[    1.842458] LVT offset 1 assigned for vector 0x400
[    1.842466] IBS: LVT offset 1 assigned
[    1.842487] perf: AMD IBS detected (0x0000001f)
[    1.842507] Scanning for low memory corruption every 60 seconds
[    1.842725] audit: initializing netlink socket (disabled)
[    1.842734] type=2000 audit(1384711004.543:1): initialized
[    1.851049] HugeTLB registered 2 MB page size, pre-allocated 0 pages
[    1.852054] zbud: loaded
[    1.852166] VFS: Disk quotas dquot_6.5.2
[    1.852200] Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    1.852354] msgmni has been set to 7405
[    1.852587] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
[    1.852634] io scheduler noop registered
[    1.852637] io scheduler deadline registered
[    1.852674] io scheduler cfq registered (default)
[    1.852784] pcieport 0000:00:05.0: irq 40 for MSI/MSI-X
[    1.852849] pcieport 0000:00:07.0: irq 41 for MSI/MSI-X
[    1.852906] pcieport 0000:00:09.0: irq 42 for MSI/MSI-X
[    1.852964] pcieport 0000:00:0a.0: irq 43 for MSI/MSI-X
[    1.853049] pcieport 0000:00:05.0: Signaling PME through PCIe PME interrupt
[    1.853053] pci 0000:02:00.0: Signaling PME through PCIe PME interrupt
[    1.853055] pcie_pme 0000:00:05.0:pcie01: service driver pcie_pme loaded
[    1.853064] pcieport 0000:00:07.0: Signaling PME through PCIe PME interrupt
[    1.853066] pci 0000:03:00.0: Signaling PME through PCIe PME interrupt
[    1.853068] pcie_pme 0000:00:07.0:pcie01: service driver pcie_pme loaded
[    1.853078] pcieport 0000:00:09.0: Signaling PME through PCIe PME interrupt
[    1.853079] pci 0000:04:00.0: Signaling PME through PCIe PME interrupt
[    1.853081] pcie_pme 0000:00:09.0:pcie01: service driver pcie_pme loaded
[    1.853092] pcieport 0000:00:0a.0: Signaling PME through PCIe PME interrupt
[    1.853093] pci 0000:05:00.0: Signaling PME through PCIe PME interrupt
[    1.853095] pcie_pme 0000:00:0a.0:pcie01: service driver pcie_pme loaded
[    1.853105] pci_hotplug: PCI Hot Plug PCI Core version: 0.5
[    1.853116] pciehp: PCI Express Hot Plug Controller Driver version: 0.4
[    1.853160] vesafb: mode is 1920x1440x32, linelength=7680, pages=0
[    1.853161] vesafb: scrolling: redraw
[    1.853162] vesafb: Truecolor: size=0:8:8:8, shift=0:16:8:0
[    1.853503] vesafb: framebuffer at 0xd0000000, mapped to 0xffffc90010800000, using 10816k, total 10816k
[    2.187925] Console: switching to colour frame buffer device 240x90
[    2.522112] fb0: VESA VGA frame buffer device
[    2.522136] GHES: HEST is not enabled!
[    2.522194] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled
[    2.542718] 00:07: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
[    2.543025] Linux agpgart interface v0.103
[    2.543063] i8042: PNP: No PS/2 controller found. Probing ports directly.
[    2.543414] serio: i8042 KBD port at 0x60,0x64 irq 1
[    2.543440] serio: i8042 AUX port at 0x60,0x64 irq 12
[    2.543545] mousedev: PS/2 mouse device common for all mice
[    2.543591] rtc_cmos 00:02: RTC can wake from S4
[    2.543688] rtc_cmos 00:02: rtc core: registered rtc_cmos as rtc0
[    2.543707] rtc_cmos 00:02: alarms up to one month, y3k, 114 bytes nvram, hpet irqs
[    2.543713] cpuidle: using governor ladder
[    2.543715] cpuidle: using governor menu
[    2.543764] drop_monitor: Initializing network drop monitor service
[    2.543811] TCP: cubic registered
[    2.543876] NET: Registered protocol family 10
[    2.544001] NET: Registered protocol family 17
[    2.544009] Key type dns_resolver registered
[    2.544240] PM: Hibernation image not present or could not be loaded.
[    2.544247] registered taskstats version 1
[    2.544768]   Magic number: 5:4:947
[    2.544782] tty tty4: hash matches
[    2.544801] cpu cpu2: hash matches
[    2.544807] memory memory0: hash matches
[    2.544836] rtc_cmos 00:02: setting system clock to 2013-11-17 17:56:45 UTC (1384711005)
[    2.545743] Freeing unused kernel memory: 1140K (ffffffff818c9000 - ffffffff819e6000)
[    2.545745] Write protecting the kernel read-only data: 8192k
[    2.547931] Freeing unused kernel memory: 1084K (ffff8800014f1000 - ffff880001600000)
[    2.548636] Freeing unused kernel memory: 352K (ffff8800017a8000 - ffff880001800000)
[    2.554741] systemd-udevd[57]: starting version 208
[    2.556092] SCSI subsystem initialized
[    2.556877] ACPI: bus type ATA registered
[    2.556944] libata version 3.00 loaded.
[    2.557360] ahci 0000:00:11.0: version 3.0
[    2.557535] ahci 0000:00:11.0: AHCI 0001.0200 32 slots 6 ports 6 Gbps 0x3f impl SATA mode
[    2.557538] ahci 0000:00:11.0: flags: 64bit ncq sntf ilck pm led clo pmp pio slum part 
[    2.558505] scsi0 : ahci
[    2.558599] scsi1 : ahci
[    2.558778] scsi2 : ahci
[    2.558868] scsi3 : ahci
[    2.559022] scsi4 : ahci
[    2.559089] scsi5 : ahci
[    2.559150] ata1: SATA max UDMA/133 abar m1024@0xfe6ffc00 port 0xfe6ffd00 irq 19
[    2.559153] ata2: SATA max UDMA/133 abar m1024@0xfe6ffc00 port 0xfe6ffd80 irq 19
[    2.559155] ata3: SATA max UDMA/133 abar m1024@0xfe6ffc00 port 0xfe6ffe00 irq 19
[    2.559156] ata4: SATA max UDMA/133 abar m1024@0xfe6ffc00 port 0xfe6ffe80 irq 19
[    2.559159] ata5: SATA max UDMA/133 abar m1024@0xfe6ffc00 port 0xfe6fff00 irq 19
[    2.559161] ata6: SATA max UDMA/133 abar m1024@0xfe6ffc00 port 0xfe6fff80 irq 19
[    2.560807] [drm] Initialized drm 1.1.0 20060810
[    2.564745] [drm] radeon kernel modesetting enabled.
[    2.564805] checking generic (d0000000 a90000) vs hw (d0000000 10000000)
[    2.564808] fb: conflicting fb hw usage radeondrmfb vs VESA VGA - removing generic driver
[    2.564821] Console: switching to colour dummy device 80x25
[    2.565086] [drm] initializing kernel modesetting (RS880 0x1002:0x9715 0x1043:0x843E).
[    2.565095] [drm] register mmio base: 0xFE8F0000
[    2.565096] [drm] register mmio size: 65536
[    2.565630] ATOM BIOS: 113
[    2.565643] radeon 0000:01:05.0: VRAM: 368M 0x00000000C0000000 - 0x00000000D6FFFFFF (368M used)
[    2.565645] radeon 0000:01:05.0: GTT: 512M 0x00000000A0000000 - 0x00000000BFFFFFFF
[    2.565648] [drm] Detected VRAM RAM=368M, BAR=256M
[    2.565649] [drm] RAM width 32bits DDR
[    2.565696] [TTM] Zone  kernel: Available graphics memory: 1897112 kiB
[    2.565697] [TTM] Initializing pool allocator
[    2.565700] [TTM] Initializing DMA pool allocator
[    2.565716] [drm] radeon: 368M of VRAM memory ready
[    2.565717] [drm] radeon: 512M of GTT memory ready.
[    2.565725] [drm] GART: num cpu pages 131072, num gpu pages 131072
[    2.576556] [drm] Loading RS780 Microcode
[    2.578778] [drm] PCIE GART of 512M enabled (table at 0x00000000C0040000).
[    2.578836] radeon 0000:01:05.0: WB enabled
[    2.578838] radeon 0000:01:05.0: fence driver on ring 0 use gpu addr 0x00000000a0000c00 and cpu addr 0xffff880117cfdc00
[    2.578839] radeon 0000:01:05.0: fence driver on ring 3 use gpu addr 0x00000000a0000c0c and cpu addr 0xffff880117cfdc0c
[    2.578841] [drm] Supports vblank timestamp caching Rev 1 (10.10.2010).
[    2.578842] [drm] Driver supports precise vblank timestamp query.
[    2.578860] [drm] radeon: irq initialized.
[    2.579084] radeon 0000:01:05.0: setting latency timer to 64
[    2.611015] [drm] ring test on 0 succeeded in 1 usecs
[    2.611072] [drm] ring test on 3 succeeded in 1 usecs
[    2.611137] [drm] ib test on ring 0 succeeded in 0 usecs
[    2.611149] [drm] ib test on ring 3 succeeded in 0 usecs
[    2.611351] [drm] Radeon Display Connectors
[    2.611352] [drm] Connector 0:
[    2.611353] [drm]   VGA-1
[    2.611355] [drm]   DDC: 0x7e40 0x7e40 0x7e44 0x7e44 0x7e48 0x7e48 0x7e4c 0x7e4c
[    2.611355] [drm]   Encoders:
[    2.611356] [drm]     CRT1: INTERNAL_KLDSCP_DAC1
[    2.611357] [drm] Connector 1:
[    2.611357] [drm]   DVI-D-1
[    2.611358] [drm]   HPD1
[    2.611359] [drm]   DDC: 0x7e50 0x7e50 0x7e54 0x7e54 0x7e58 0x7e58 0x7e5c 0x7e5c
[    2.611360] [drm]   Encoders:
[    2.611361] [drm]     DFP3: INTERNAL_KLDSCP_LVTMA
[    2.611408] [drm] radeon: power management initialized
[    2.652806] [drm] fb mappable at 0xD0142000
[    2.652807] [drm] vram apper at 0xD0000000
[    2.652808] [drm] size 5242880
[    2.652808] [drm] fb depth is 24
[    2.652809] [drm]    pitch is 5120
[    2.652866] fbcon: radeondrmfb (fb0) is primary device
[    2.664744] Console: switching to colour frame buffer device 160x64
[    2.675575] radeon 0000:01:05.0: fb0: radeondrmfb frame buffer device
[    2.675577] radeon 0000:01:05.0: registered panic notifier
[    2.675630] [drm] Initialized radeon 2.34.0 20080528 for 0000:01:05.0 on minor 0
[    2.692173] pata_via 0000:04:00.0: version 0.3.4
[    2.693001] scsi6 : pata_via
[    2.693202] scsi7 : pata_via
[    2.693237] ata7: PATA max UDMA/133 cmd 0xcc00 ctl 0xc880 bmdma 0xc400 irq 17
[    2.693238] ata8: PATA max UDMA/133 cmd 0xc800 ctl 0xc480 bmdma 0xc408 irq 17
[    2.693302] ACPI: bus type USB registered
[    2.693369] usbcore: registered new interface driver usbfs
[    2.693378] usbcore: registered new interface driver hub
[    2.693419] usbcore: registered new device driver usb
[    2.694028] xhci_hcd 0000:02:00.0: xHCI Host Controller
[    2.694034] xhci_hcd 0000:02:00.0: new USB bus registered, assigned bus number 1
[    2.694163] xhci_hcd 0000:02:00.0: irq 44 for MSI/MSI-X
[    2.694168] xhci_hcd 0000:02:00.0: irq 45 for MSI/MSI-X
[    2.694173] xhci_hcd 0000:02:00.0: irq 46 for MSI/MSI-X
[    2.694177] xhci_hcd 0000:02:00.0: irq 47 for MSI/MSI-X
[    2.694181] xhci_hcd 0000:02:00.0: irq 48 for MSI/MSI-X
[    2.694382] xHCI xhci_add_endpoint called for root hub
[    2.694383] xHCI xhci_check_bandwidth called for root hub
[    2.694913] hub 1-0:1.0: USB hub found
[    2.694918] hub 1-0:1.0: 2 ports detected
[    2.695055] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    2.695347] ehci-pci: EHCI PCI platform driver
[    2.695437] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    2.695532] ohci-pci: OHCI PCI platform driver
[    2.695613] xhci_hcd 0000:02:00.0: xHCI Host Controller
[    2.695617] xhci_hcd 0000:02:00.0: new USB bus registered, assigned bus number 2
[    2.697530] ehci-pci 0000:00:12.2: EHCI Host Controller
[    2.697595] xHCI xhci_add_endpoint called for root hub
[    2.697596] xHCI xhci_check_bandwidth called for root hub
[    2.697636] hub 2-0:1.0: USB hub found
[    2.697645] hub 2-0:1.0: 2 ports detected
[    2.697762] ehci-pci 0000:00:12.2: new USB bus registered, assigned bus number 3
[    2.697764] QUIRK: Enable AMD PLL fix
[    2.697766] ehci-pci 0000:00:12.2: applying AMD SB700/SB800/Hudson-2/3 EHCI dummy qh workaround
[    2.697776] ehci-pci 0000:00:12.2: debug port 1
[    2.697800] ehci-pci 0000:00:12.2: irq 17, io mem 0xfe6ff800
[    2.704582] ehci-pci 0000:00:12.2: USB 2.0 started, EHCI 1.00
[    2.704674] hub 3-0:1.0: USB hub found
[    2.704677] hub 3-0:1.0: 5 ports detected
[    2.704845] ehci-pci 0000:00:13.2: EHCI Host Controller
[    2.704850] ehci-pci 0000:00:13.2: new USB bus registered, assigned bus number 4
[    2.704852] ehci-pci 0000:00:13.2: applying AMD SB700/SB800/Hudson-2/3 EHCI dummy qh workaround
[    2.704861] ehci-pci 0000:00:13.2: debug port 1
[    2.704883] ehci-pci 0000:00:13.2: irq 17, io mem 0xfe6ff400
[    2.714637] ehci-pci 0000:00:13.2: USB 2.0 started, EHCI 1.00
[    2.714734] hub 4-0:1.0: USB hub found
[    2.714737] hub 4-0:1.0: 5 ports detected
[    2.714896] ehci-pci 0000:00:16.2: EHCI Host Controller
[    2.714900] ehci-pci 0000:00:16.2: new USB bus registered, assigned bus number 5
[    2.714903] ehci-pci 0000:00:16.2: applying AMD SB700/SB800/Hudson-2/3 EHCI dummy qh workaround
[    2.714911] ehci-pci 0000:00:16.2: debug port 1
[    2.714930] ehci-pci 0000:00:16.2: irq 17, io mem 0xfe6ff000
[    2.724628] ehci-pci 0000:00:16.2: USB 2.0 started, EHCI 1.00
[    2.724725] hub 5-0:1.0: USB hub found
[    2.724728] hub 5-0:1.0: 4 ports detected
[    2.724888] ohci-pci 0000:00:12.0: OHCI PCI host controller
[    2.724892] ohci-pci 0000:00:12.0: new USB bus registered, assigned bus number 6
[    2.724904] ohci-pci 0000:00:12.0: irq 18, io mem 0xfe6fe000
[    2.758021] firewire_ohci 0000:03:00.0: added OHCI v1.10 device as card 0, 4 IR + 8 IT contexts, quirks 0x11
[    2.782133] hub 6-0:1.0: USB hub found
[    2.782138] hub 6-0:1.0: 5 ports detected
[    2.782291] ohci-pci 0000:00:13.0: OHCI PCI host controller
[    2.782296] ohci-pci 0000:00:13.0: new USB bus registered, assigned bus number 7
[    2.782308] ohci-pci 0000:00:13.0: irq 18, io mem 0xfe6fd000
[    2.838807] hub 7-0:1.0: USB hub found
[    2.838813] hub 7-0:1.0: 5 ports detected
[    2.838974] ohci-pci 0000:00:14.5: OHCI PCI host controller
[    2.838978] ohci-pci 0000:00:14.5: new USB bus registered, assigned bus number 8
[    2.838989] ohci-pci 0000:00:14.5: irq 18, io mem 0xfe6fc000
[    2.844705] tsc: Refined TSC clocksource calibration: 3415.257 MHz
[    2.871387] ata1: SATA link down (SStatus 0 SControl 300)
[    2.874721] ata6: SATA link down (SStatus 0 SControl 300)
[    2.878060] ata4: SATA link down (SStatus 0 SControl 300)
[    2.878096] ata3: SATA link down (SStatus 0 SControl 300)
[    2.895510] hub 8-0:1.0: USB hub found
[    2.895515] hub 8-0:1.0: 2 ports detected
[    2.895650] ohci-pci 0000:00:16.0: OHCI PCI host controller
[    2.895654] ohci-pci 0000:00:16.0: new USB bus registered, assigned bus number 9
[    2.895665] ohci-pci 0000:00:16.0: irq 18, io mem 0xfe6f3000
[    2.952187] hub 9-0:1.0: USB hub found
[    2.952192] hub 9-0:1.0: 4 ports detected
[    3.011462] usb 3-3: new high-speed USB device number 2 using ehci-pci
[    3.041482] ata5: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
[    3.042668] ata5.00: ATA-8: ST31000524AS, JC45, max UDMA/133
[    3.042671] ata5.00: 1953525168 sectors, multi 0: LBA48 NCQ (depth 31/32)
[    3.044091] ata5.00: configured for UDMA/133
[    3.044818] ata2: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
[    3.046010] ata2.00: ATA-8: ST31000524AS, JC45, max UDMA/133
[    3.046012] ata2.00: 1953525168 sectors, multi 0: LBA48 NCQ (depth 31/32)
[    3.047443] ata2.00: configured for UDMA/133
[    3.047593] scsi 1:0:0:0: Direct-Access     ATA      ST31000524AS     JC45 PQ: 0 ANSI: 5
[    3.047815] scsi 4:0:0:0: Direct-Access     ATA      ST31000524AS     JC45 PQ: 0 ANSI: 5
[    3.048310] sd 1:0:0:0: [sda] 1953525168 512-byte logical blocks: (1.00 TB/931 GiB)
[    3.048332] sd 4:0:0:0: [sdb] 1953525168 512-byte logical blocks: (1.00 TB/931 GiB)
[    3.048407] sd 1:0:0:0: [sda] Write Protect is off
[    3.048410] sd 1:0:0:0: [sda] Mode Sense: 00 3a 00 00
[    3.048414] sd 4:0:0:0: [sdb] Write Protect is off
[    3.048416] sd 4:0:0:0: [sdb] Mode Sense: 00 3a 00 00
[    3.048445] sd 1:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    3.048454] sd 4:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    3.082120]  sdb: sdb1 < sdb5 >
[    3.082492] sd 4:0:0:0: [sdb] Attached SCSI disk
[    3.123617]  sda: sda1 < sda5 sda6 sda7 sda8 sda9 sda10 >
[    3.124128] sd 1:0:0:0: [sda] Attached SCSI disk
[    3.258326] firewire_core 0000:03:00.0: created device fw0: GUID 001e8c0000dded87, S400
[    3.845323] Switched to clocksource tsc
[    3.887097] JFS: nTxBlock = 8192, nTxLock = 65536
[    4.225475] usb 7-1: new low-speed USB device number 2 using ohci-pci
[    4.639033] usb 9-4: new full-speed USB device number 2 using ohci-pci
[    4.953305] systemd[1]: systemd 208 running in system mode. (+PAM -LIBWRAP -AUDIT -SELINUX -IMA -SYSVINIT +LIBCRYPTSETUP +GCRYPT +ACL +XZ)
[    4.973795] systemd[1]: Set hostname to <chaembian>.
[    5.179335] usb 6-4: new full-speed USB device number 2 using ohci-pci
[    5.596246] usb 6-5: new low-speed USB device number 3 using ohci-pci
[    5.801932] systemd[1]: Cannot add dependency job for unit vnstat.service, ignoring: Unit vnstat.service failed to load: No such file or directory.
[    5.801948] systemd[1]: Cannot add dependency job for unit display-manager.service, ignoring: Unit display-manager.service failed to load: No such file or directory.
[    5.802146] systemd[1]: Starting Forward Password Requests to Wall Directory Watch.
[    5.802185] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[    5.802193] systemd[1]: Starting Login Prompts.
[    5.802200] systemd[1]: Reached target Login Prompts.
[    5.802206] systemd[1]: Starting Remote File Systems.
[    5.802211] systemd[1]: Reached target Remote File Systems.
[    5.802218] systemd[1]: Starting Delayed Shutdown Socket.
[    5.802235] systemd[1]: Listening on Delayed Shutdown Socket.
[    5.802240] systemd[1]: Starting /dev/initctl Compatibility Named Pipe.
[    5.802253] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe.
[    5.802258] systemd[1]: Starting LVM2 metadata daemon socket.
[    5.802272] systemd[1]: Listening on LVM2 metadata daemon socket.
[    5.802278] systemd[1]: Starting Device-mapper event daemon FIFOs.
[    5.802294] systemd[1]: Listening on Device-mapper event daemon FIFOs.
[    5.802303] systemd[1]: Starting udev Kernel Socket.
[    5.802314] systemd[1]: Listening on udev Kernel Socket.
[    5.802321] systemd[1]: Starting udev Control Socket.
[    5.802332] systemd[1]: Listening on udev Control Socket.
[    5.802340] systemd[1]: Starting Journal Socket.
[    5.802364] systemd[1]: Listening on Journal Socket.
[    5.828144] systemd[1]: Started Load Kernel Modules.
[    5.837412] systemd[1]: Starting Apply Kernel Variables...
[    5.837770] systemd[1]: Starting udev Coldplug all Devices...
[    5.838054] systemd[1]: Starting Setup Virtual Console...
[    5.838365] systemd[1]: Starting Journal Service...
[    5.838647] systemd[1]: Started Journal Service.
[    6.402315] systemd-journald[139]: Vacuuming done, freed 0 bytes
[    7.319880] systemd-udevd[177]: starting version 208
[    7.794545] wmi: Mapper loaded
[    7.847347] parport_pc 00:05: reported by Plug and Play ACPI
[    7.847391] parport0: PC-style at 0x378, irq 7 [PCSPP,TRISTATE]
[    7.851969] ACPI Warning: 0x0000000000000b00-0x0000000000000b07 SystemIO conflicts with Region \_SB_.PCI0.SBRG.ASOC.SMRG 1 (20130517/utaddress-251)
[    7.851974] ACPI: If an ACPI driver is available for this device, you should use it instead of the native driver
[    7.874491] sp5100_tco: SP5100/SB800 TCO WatchDog Timer Driver v0.05
[    7.874519] sp5100_tco: PCI Revision ID: 0x41
[    7.874547] sp5100_tco: Using 0xfed80b00 for watchdog MMIO address
[    7.874557] sp5100_tco: Last reboot was not triggered by watchdog.
[    7.874600] sp5100_tco: initialized (0xffffc900123fcb00). heartbeat=60 sec (nowayout=0)
[    7.881641] input: Power Button as /devices/LNXSYSTM:00/device:00/PNP0C0C:00/input/input0
[    7.881645] ACPI: Power Button [PWRB]
[    7.881697] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input1
[    7.881699] ACPI: Power Button [PWRF]
[    7.882757] ACPI: processor limited to max C-state 1
[    7.905064] acpi-cpufreq: overriding BIOS provided _PSD data
[    7.918926] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4
[    8.165090] r8169 Gigabit Ethernet driver 2.3LK-NAPI loaded
[    8.165417] r8169 0000:05:00.0: irq 49 for MSI/MSI-X
[    8.165792] r8169 0000:05:00.0 eth0: RTL8168e/8111e at 0xffffc900123fe000, 20:cf:30:4c:49:a9, XID 0c200000 IRQ 49
[    8.165800] r8169 0000:05:00.0 eth0: jumbo features [frames: 9200 bytes, tx checksumming: ko]
[    8.342332] MCE: In-kernel MCE decoding enabled.
[    8.346927] EDAC MC: Ver: 3.0.0
[    8.348961] AMD64 EDAC driver v3.4.0
[    8.349044] EDAC amd64: DRAM ECC disabled.
[    8.349073] EDAC amd64: ECC disabled in the BIOS or no ECC capability, module will not load.
 Either enable ECC checking or force module loading by setting 'ecc_enable_override'.
 (Note that use of the override may cause unknown side effects.)
[    8.495021] input: PC Speaker as /devices/platform/pcspkr/input/input2
[    8.558418] snd_ice1724 0000:06:05.0: enabling device (0000 -> 0001)
[    8.558556] snd_ice1724 0000:06:05.0: setting latency timer to 64
[    8.587452] ice1724: No matching model found for ID 0xffffffa0
[    8.588183] ice1724: Invalid EEPROM (size = 255)
[    8.588437] snd_ice1724: probe of 0000:06:05.0 failed with error -5
[    8.607952] microcode: CPU0: patch_level=0x010000b6
[    8.629397] ppdev: user-space parallel port driver
[    8.825646] input: HDA ATI SB Front Headphone as /devices/pci0000:00/0000:00:14.2/sound/card0/input3
[    8.825863] input: HDA ATI SB Line Out Side as /devices/pci0000:00/0000:00:14.2/sound/card0/input4
[    8.826029] input: HDA ATI SB Line Out CLFE as /devices/pci0000:00/0000:00:14.2/sound/card0/input5
[    8.826190] input: HDA ATI SB Line Out Surround as /devices/pci0000:00/0000:00:14.2/sound/card0/input6
[    8.826357] input: HDA ATI SB Line Out Front as /devices/pci0000:00/0000:00:14.2/sound/card0/input7
[    8.826514] input: HDA ATI SB Line as /devices/pci0000:00/0000:00:14.2/sound/card0/input8
[    8.826667] input: HDA ATI SB Rear Mic as /devices/pci0000:00/0000:00:14.2/sound/card0/input9
[    8.826823] input: HDA ATI SB Front Mic as /devices/pci0000:00/0000:00:14.2/sound/card0/input10
[    8.827628] snd_hda_intel 0000:01:05.1: setting latency timer to 64
[    8.868368] input: HDA ATI HDMI HDMI/DP,pcm=3 as /devices/pci0000:00/0000:00:01.0/0000:01:05.1/sound/card1/input11
[    9.003261] microcode: CPU0: new patch_level=0x010000c8
[    9.003302] microcode: CPU1: patch_level=0x010000b6
[    9.003328] microcode: CPU1: new patch_level=0x010000c8
[    9.003357] microcode: CPU2: patch_level=0x010000b6
[    9.003381] microcode: CPU2: new patch_level=0x010000c8
[    9.003396] microcode: CPU3: patch_level=0x010000b6
[    9.003412] microcode: CPU3: new patch_level=0x010000c8
[    9.003552] microcode: Microcode Update Driver: v2.00 <tigran@aivazian.fsnet.co.uk>, Peter Oruba
[    9.031976] media: Linux media interface: v0.10
[    9.034867] Linux video capture interface: v2.00
[    9.043789] kvm: Nested Virtualization enabled
[    9.043793] kvm: Nested Paging enabled
[    9.244784] hidraw: raw HID events driver (C) Jiri Kosina
[    9.423576] usbcore: registered new interface driver usbhid
[    9.423583] usbhid: USB HID core driver
[    9.513383] input: Plus More Enterprise LTD. USB-compliant keyboard as /devices/pci0000:00/0000:00:13.0/usb7/7-1/7-1:1.0/input/input12
[    9.513638] hid-generic 0003:0518:0001.0001: input,hidraw0: USB HID v1.10 Keyboard [Plus More Enterprise LTD. USB-compliant keyboard] on usb-0000:00:13.0-1/input0
[    9.514469] input: Plus More Enterprise LTD. USB-compliant keyboard as /devices/pci0000:00/0000:00:13.0/usb7/7-1/7-1:1.1/input/input13
[    9.514899] hid-generic 0003:0518:0001.0002: input,hidraw1: USB HID v1.10 Mouse [Plus More Enterprise LTD. USB-compliant keyboard] on usb-0000:00:13.0-1/input1
[    9.516355] input: C-Media Electronics Inc. Philips SPA5210 as /devices/pci0000:00/0000:00:16.0/usb9/9-4/9-4:1.2/input/input14
[    9.516925] hid-generic 0003:0D8C:0126.0003: input,hidraw2: USB HID v1.00 Device [C-Media Electronics Inc. Philips SPA5210] on usb-0000:00:16.0-4/input2
[    9.595842] hid-generic 0003:051D:0002.0004: hiddev0,hidraw3: USB HID v1.00 Device [American Power Conversion Back-UPS RS 1000G-IN FW:900.L4 .I USB FW:L4 ] on usb-0000:00:12.0-4/input0
[    9.596476] input: Logitech USB Optical Mouse as /devices/pci0000:00/0000:00:12.0/usb6/6-5/6-5:1.0/input/input15
[    9.596993] hid-generic 0003:046D:C05B.0005: input,hidraw4: USB HID v1.11 Mouse [Logitech USB Optical Mouse] on usb-0000:00:12.0-5/input0
[    9.885366] ata5: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
[    9.886617] ata5.00: failed to set xfermode (err_mask=0x100)
[   10.370083] set resolution quirk: cval->res = 384
[   10.370534] uvcvideo: Found UVC 1.00 device <unnamed> (046d:081b)
[   10.385720] input: UVC Camera (046d:081b) as /devices/pci0000:00/0000:00:12.2/usb3/3-3/3-3:1.0/input/input16
[   10.385897] usbcore: registered new interface driver uvcvideo
[   10.385902] USB Video Class driver (1.1.1)
[   10.443673] usbcore: registered new interface driver snd-usb-audio
[   10.530521] Adding 995992k swap on /dev/sda7.  Priority:-1 extents:1 across:995992k FS
[   17.204835] systemd-journald[139]: Received request to flush runtime journal from PID 1
[   17.564410] systemd-journald[139]: Vacuuming done, freed 4194304 bytes
[   17.564429] systemd-journald[139]: Vacuuming done, freed 0 bytes
[   17.594012] systemd-journald[139]: Vacuuming done, freed 4198400 bytes
[   17.594091] systemd-journald[139]: Deleted empty journal /run/log/journal/e44c../system@85b25..-0000000000000000-0000000000000000.journal (684032 bytes).
[   17.594101] systemd-journald[139]: Vacuuming done, freed 684032 bytes
[   18.430485] r8169 0000:05:00.0 eth0: link down
[   18.430501] r8169 0000:05:00.0 eth0: link down
[   18.430540] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[   20.057527] r8169 0000:05:00.0 eth0: link up
[   20.057545] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[   26.308009] systemd-journald[139]: File /var/log/journal/e44c../user-1004.journal corrupted or uncleanly shut down, renaming and replacing.
[   31.900749] hda-intel: IRQ timing workaround is activated for card #1. Suggest a bigger bdl_pos_adj.
[   32.146160] fuse init (API version 7.22)
[   63.406994] systemd-journald[139]: File /var/log/journal/e44c../user-1000.journal corrupted or uncleanly shut down, renaming and replacing.

I did not get any output when I tried: " journalctl -b --dmesg | grep systemd". However, when I tried  "journalctl | grep systemd", I got these:

Nov 17 23:26:51 chaembian systemd[1]: Starting Sound Card.
Nov 17 23:26:51 chaembian systemd[1]: Reached target Sound Card.
Nov 17 23:26:52 chaembian systemd[1]: Found device ST31000524AS.
Nov 17 23:26:52 chaembian systemd[1]: Starting File System Check on /dev/disk/by-uuid/cd1ecac7-97b5-4bcc-b114-858efb77e4c5...
Nov 17 23:26:52 chaembian systemd[1]: Found device ST31000524AS.
Nov 17 23:26:52 chaembian systemd[1]: Starting File System Check on /dev/disk/by-uuid/8f972288-02c8-4dd7-a590-400f4fe60244...
Nov 17 23:26:53 chaembian systemd[1]: Found device ST31000524AS.
Nov 17 23:26:53 chaembian systemd[1]: Activating swap /dev/disk/by-uuid/cf5549b8-edd7-465a-985c-c096f39b916e...
Nov 17 23:26:53 chaembian systemd-fsck[254]: fsck.jfs version 1.1.15, 04-Mar-2011
Nov 17 23:26:53 chaembian systemd-fsck[254]: processing started: 11/17/2013 23:26:53
Nov 17 23:26:53 chaembian systemd-fsck[254]: The current device is:  /dev/sda9
Nov 17 23:26:53 chaembian systemd-fsck[254]: Block size in bytes:  4096
Nov 17 23:26:53 chaembian systemd-fsck[254]: Filesystem size in blocks:  50000094
Nov 17 23:26:53 chaembian systemd-fsck[254]: **Phase 0 - Replay Journal Log
Nov 17 23:26:53 chaembian systemd-fsck[254]: Filesystem is clean.
Nov 17 23:26:53 chaembian systemd[1]: Started File System Check on /dev/disk/by-uuid/8f972288-02c8-4dd7-a590-400f4fe60244.
Nov 17 23:26:53 chaembian systemd[1]: Activated swap /dev/disk/by-uuid/cf5549b8-edd7-465a-985c-c096f39b916e.
Nov 17 23:26:53 chaembian systemd[1]: Starting Swap.
Nov 17 23:26:53 chaembian systemd[1]: Reached target Swap.
Nov 17 23:26:53 chaembian systemd[1]: Found device ST31000524AS.
Nov 17 23:26:53 chaembian systemd[1]: Starting File System Check on /dev/disk/by-uuid/b36313d6-8efb-444c-b6c9-0d3ca3bdaad1...
Nov 17 23:26:53 chaembian systemd[1]: Found device ST31000524AS.
Nov 17 23:26:53 chaembian systemd[1]: Starting File System Check on /dev/disk/by-uuid/e4ee5aff-ef4a-436c-9385-cb37b98df3e1...
Nov 17 23:26:53 chaembian systemd[1]: Found device ST31000524AS.
Nov 17 23:26:53 chaembian systemd[1]: Starting File System Check on /dev/disk/by-uuid/da692a03-33e5-4aad-b716-7dedfea99f85...
Nov 17 23:26:53 chaembian systemd-fsck[267]: fsck.jfs version 1.1.15, 04-Mar-2011
Nov 17 23:26:53 chaembian systemd-fsck[267]: processing started: 11/17/2013 23:26:53
Nov 17 23:26:53 chaembian systemd-fsck[267]: The current device is:  /dev/sda8
Nov 17 23:26:53 chaembian systemd-fsck[267]: Block size in bytes:  4096
Nov 17 23:26:53 chaembian systemd-fsck[267]: Filesystem size in blocks:  24999140
Nov 17 23:26:53 chaembian systemd-fsck[267]: **Phase 0 - Replay Journal Log
Nov 17 23:26:53 chaembian systemd-fsck[267]: Filesystem is clean.
Nov 17 23:26:53 chaembian systemd[1]: Started File System Check on /dev/disk/by-uuid/b36313d6-8efb-444c-b6c9-0d3ca3bdaad1.
Nov 17 23:26:53 chaembian systemd[1]: Mounting /home...
Nov 17 23:26:53 chaembian systemd-fsck[273]: fsck.jfs version 1.1.15, 04-Mar-2011
Nov 17 23:26:53 chaembian systemd-fsck[273]: processing started: 11/17/2013 23:26:53
Nov 17 23:26:53 chaembian systemd-fsck[273]: The current device is:  /dev/sda10
Nov 17 23:26:53 chaembian systemd-fsck[273]: Block size in bytes:  4096
Nov 17 23:26:53 chaembian systemd-fsck[273]: Filesystem size in blocks:  161393838
Nov 17 23:26:53 chaembian systemd-fsck[273]: **Phase 0 - Replay Journal Log
Nov 17 23:26:53 chaembian systemd-fsck[273]: Filesystem is clean.
Nov 17 23:26:53 chaembian systemd[1]: Started File System Check on /dev/disk/by-uuid/da692a03-33e5-4aad-b716-7dedfea99f85.
Nov 17 23:26:53 chaembian systemd-fsck[270]: fsck.jfs version 1.1.15, 04-Mar-2011
Nov 17 23:26:53 chaembian systemd-fsck[270]: processing started: 11/17/2013 23:26:53
Nov 17 23:26:53 chaembian systemd-fsck[270]: The current device is:  /dev/sda5
Nov 17 23:26:53 chaembian systemd-fsck[270]: Block size in bytes:  4096
Nov 17 23:26:53 chaembian systemd-fsck[270]: Filesystem size in blocks:  48179
Nov 17 23:26:53 chaembian systemd-fsck[270]: **Phase 0 - Replay Journal Log
Nov 17 23:26:53 chaembian systemd-fsck[270]: Filesystem is clean.
Nov 17 23:26:53 chaembian systemd[1]: Started File System Check on /dev/disk/by-uuid/e4ee5aff-ef4a-436c-9385-cb37b98df3e1.
Nov 17 23:26:53 chaembian systemd[1]: Mounting /boot...
Nov 17 23:26:53 chaembian systemd[1]: Mounted /boot.
Nov 17 23:26:53 chaembian systemd[1]: Mounted /home.
Nov 17 23:26:59 chaembian systemd-fsck[246]: fsck.jfs version 1.1.15, 04-Mar-2011
Nov 17 23:26:59 chaembian systemd-fsck[246]: processing started: 11/17/2013 23:26:52
Nov 17 23:26:59 chaembian systemd-fsck[246]: The current device is:  /dev/sdb5
Nov 17 23:26:59 chaembian systemd-fsck[246]: Block size in bytes:  4096
Nov 17 23:26:59 chaembian systemd-fsck[246]: Filesystem size in blocks:  244189952
Nov 17 23:26:59 chaembian systemd-fsck[246]: **Phase 0 - Replay Journal Log
Nov 17 23:26:59 chaembian systemd-fsck[246]: Filesystem is clean.
Nov 17 23:26:59 chaembian systemd[1]: Started File System Check on /dev/disk/by-uuid/cd1ecac7-97b5-4bcc-b114-858efb77e4c5.
Nov 17 23:26:59 chaembian systemd[1]: Mounting /home/xxxx...
Nov 17 23:27:00 chaembian systemd[1]: Mounted /home/xxxx.
Nov 17 23:27:00 chaembian systemd[1]: Starting Local File Systems.
Nov 17 23:27:00 chaembian systemd[1]: Reached target Local File Systems.
Nov 17 23:27:00 chaembian systemd[1]: Starting Trigger Flushing of Journal to Persistent Storage...
Nov 17 23:27:00 chaembian systemd[1]: Starting Recreate Volatile Files and Directories...
Nov 17 23:27:00 chaembian systemd-journal[139]: Permanent journal is using 11.0M (max 2.8G, leaving 4.0G of free 1.5G, current limit 0B).
Nov 17 23:27:00 chaembian systemd[1]: Started Trigger Flushing of Journal to Persistent Storage.
Nov 17 23:27:00 chaembian systemd[1]: Started Recreate Volatile Files and Directories.
Nov 17 23:27:00 chaembian systemd[1]: Starting Update UTMP about System Reboot/Shutdown...
Nov 17 23:27:00 chaembian systemd[1]: Started Update UTMP about System Reboot/Shutdown.
Nov 17 23:27:00 chaembian systemd[1]: Starting System Initialization.
Nov 17 23:27:00 chaembian systemd[1]: Reached target System Initialization.
Nov 17 23:27:00 chaembian systemd[1]: Starting D-Bus System Message Bus Socket.
Nov 17 23:27:00 chaembian systemd[1]: Listening on D-Bus System Message Bus Socket.
Nov 17 23:27:00 chaembian systemd[1]: Starting Sockets.
Nov 17 23:27:00 chaembian systemd[1]: Reached target Sockets.
Nov 17 23:27:00 chaembian systemd[1]: Starting Daily Cleanup of Temporary Directories.
Nov 17 23:27:00 chaembian systemd[1]: Started Daily Cleanup of Temporary Directories.
Nov 17 23:27:00 chaembian systemd[1]: Starting Timers.
Nov 17 23:27:00 chaembian systemd[1]: Reached target Timers.
Nov 17 23:27:00 chaembian systemd[1]: Started Manage Sound Card State (restore and store).
Nov 17 23:27:00 chaembian systemd[1]: Starting Restore Sound Card State...
Nov 17 23:27:00 chaembian systemd[1]: Starting Basic System.
Nov 17 23:27:00 chaembian systemd[1]: Reached target Basic System.
Nov 17 23:27:00 chaembian systemd[1]: Starting APC UPS Monitor...
Nov 17 23:27:00 chaembian systemd[1]: Started APC UPS Monitor.
Nov 17 23:27:00 chaembian systemd[1]: Starting Apply cpupower configuration...
Nov 17 23:27:00 chaembian systemd[1]: Starting Periodic Command Scheduler...
Nov 17 23:27:00 chaembian systemd[1]: Started Periodic Command Scheduler.
Nov 17 23:27:00 chaembian systemd[1]: Starting Provides automatic netcfg wired connection...
Nov 17 23:27:00 chaembian systemd[1]: Started Provides automatic netcfg wired connection.
Nov 17 23:27:00 chaembian systemd[1]: Starting Network Time Service...
Nov 17 23:27:01 chaembian systemd[1]: Starting Self Monitoring and Reporting Technology (SMART) Daemon...
Nov 17 23:27:01 chaembian systemd[1]: Started Self Monitoring and Reporting Technology (SMART) Daemon.
Nov 17 23:27:01 chaembian systemd[1]: Started SSH Key Generation.
Nov 17 23:27:01 chaembian systemd[1]: Starting OpenSSH Daemon...
Nov 17 23:27:01 chaembian systemd[1]: Started OpenSSH Daemon.
...

Offline

#11 2013-11-17 21:47:11

berbae
Member
From: France
Registered: 2007-02-12
Posts: 1,302

Re: [SOLVED] systemd: no kernel messages in the journal

So what is the problem now?
It's not clear to me.

I do not get the same outputs as yours; but are they complete or only extracted portions?

Offline

#12 2013-11-18 01:01:15

chaembian
Member
Registered: 2012-02-25
Posts: 15

Re: [SOLVED] systemd: no kernel messages in the journal

While dmesg is complete upto that point, the other two extracts are partial.

1. The messages upto 8.6s from dmesg are not present in systemd-journal.
2. While I see the starting/started messages, I do not see them when journalctl is invoked with --dmesg as you did. Does this indicate a problem?

Offline

#13 2013-11-18 01:37:08

chaembian
Member
Registered: 2012-02-25
Posts: 15

Re: [SOLVED] systemd: no kernel messages in the journal

Hmmm, the system has been running for sometime now. When I did a 'journalctl -k', I get:

chaembian ~ » journalctl -k            
-- Logs begin at Mon 2013-11-18 00:00:08 IST, end at Mon 2013-11-18 06:55:13 IST. --

That's it. Note that the log header is different from what I had noted earlier.

-- Logs begin at Sun 2013-11-17 23:26:51 IST, end at Sun 2013-11-17 23:35:38 IST. --

When did a 'journalctl -ab', the log starts at (a snippet):

-- Logs begin at Mon 2013-11-18 00:00:08 IST, end at Mon 2013-11-18 06:55:13 IST. --
Nov 18 00:00:08 chaembian CROND[905]: (xxxx) CMDOUT (    response.begin())
Nov 18 00:00:08 chaembian CROND[905]: (xxxx) CMDOUT (  File "/usr/lib/python2.7/httplib.py", line 409, in begin)
Nov 18 00:00:08 chaembian CROND[905]: (xxxx) CMDOUT (    version, status, reason = self._read_status())
Nov 18 00:00:08 chaembian CROND[905]: (xxxx) CMDOUT (  File "/usr/lib/python2.7/httplib.py", line 365, in _read_status)
Nov 18 00:00:08 chaembian CROND[905]: (xxxx) CMDOUT (    line = self.fp.readline(_MAXLINE + 1))
Nov 18 00:00:08 chaembian CROND[905]: (xxxx) CMDOUT (  File "/usr/lib/python2.7/socket.py", line 476, in readline)
...

The last set of messages from dmesg,

[   17.204835] systemd-journald[139]: Received request to flush runtime journal from PID 1
[   17.564410] systemd-journald[139]: Vacuuming done, freed 4194304 bytes
[   17.564429] systemd-journald[139]: Vacuuming done, freed 0 bytes
[   17.594012] systemd-journald[139]: Vacuuming done, freed 4198400 bytes
[   17.594091] systemd-journald[139]: Deleted empty journal /run/log/journal/e44c../system@85b25..-0000000000000000-0000000000000000.journal (684032 bytes).
[   17.594101] systemd-journald[139]: Vacuuming done, freed 684032 bytes
[   18.430485] r8169 0000:05:00.0 eth0: link down
[   18.430501] r8169 0000:05:00.0 eth0: link down
[   18.430540] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[   20.057527] r8169 0000:05:00.0 eth0: link up
[   20.057545] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[   26.308009] systemd-journald[139]: File /var/log/journal/e44c../user-1004.journal corrupted or uncleanly shut down, renaming and replacing.
[   31.900749] hda-intel: IRQ timing workaround is activated for card #1. Suggest a bigger bdl_pos_adj.
[   32.146160] fuse init (API version 7.22)
[   63.406994] systemd-journald[139]: File /var/log/journal/e44c../user-1000.journal corrupted or uncleanly shut down, renaming and replacing.
[ 2005.462590] systemd-journald[139]: Deleted empty journal /var/log/journal/e44c../user-1000@0004eb6..830.journal~ (3739648 bytes).
[ 2005.469886] systemd-journald[139]: Deleted empty journal /var/log/journal/e44c../user-1004@0004eb6..838.journal~ (3735552 bytes).
[ 2005.474872] systemd-journald[139]: Vacuuming done, freed 19202048 bytes

Could the deletes be the reason why the kernel messages I saw earlier are not there now any more?

Last edited by chaembian (2013-11-18 01:41:04)

Offline

#14 2013-11-18 13:51:23

berbae
Member
From: France
Registered: 2007-02-12
Posts: 1,302

Re: [SOLVED] systemd: no kernel messages in the journal

Could the deletes be the reason why the kernel messages I saw earlier are not there now any more?

It's probably the reason.
I do not understand all the 'vacuum done' and 'Deleted empty journal' messages you get in dmesg.
You get also 'corrupted or uncleanly shut down' user journal files.
I don't have all these things here:

$ dmesg|grep journald  
[    3.961262] systemd-journald[110]: Vacuuming done, freed 0 bytes
[    9.604421] systemd-journald[110]: Received request to flush runtime journal from PID 1
[   12.375952] systemd-journald[110]: Vacuuming done, freed 3481600 bytes

There definitely is something wrong with your installation, but I cannot determine what it is from here. Sorry.
Your log files seems to be deleted very often; is this a partition problem?

Offline

#15 2013-11-18 16:40:12

WonderWoofy
Member
From: Los Gatos, CA
Registered: 2012-05-19
Posts: 8,414

Re: [SOLVED] systemd: no kernel messages in the journal

Interestingly berbae, I do have those things.

$ dmesg | grep journald
[    1.191262] systemd-journald[51]: Vacuuming done, freed 0 bytes
[    2.231366] systemd-journald[51]: Received SIGTERM
[    2.337421] systemd-journald[177]: Vacuuming done, freed 0 bytes
[    2.499465] systemd-journald[177]: Received request to flush runtime journal from PID 1

I don't know what this means either...

Offline

#16 2013-11-18 22:38:06

berbae
Member
From: France
Registered: 2007-02-12
Posts: 1,302

Re: [SOLVED] systemd: no kernel messages in the journal

In fact I know what 'Vacuuming done, freed # bytes' means: it is the automatic cleanup of the journald files.
It is normal to find such lines in the log: I get one for the volatile journal and one for the persistent journal deleting old rotated journal files. For me that happens at the start of the systemd-journald service.
What I found abnormal in chaembian's case, is the cleanup being done several times with files deleted, and the 'Deleted empty journal' messages which I have not in my logs.
And manifestly parts of his journal are also quickly deleted, because he loses parts of the dmesg messages, and I don't see why.

Last edited by berbae (2013-11-19 10:14:54)

Offline

#17 2013-11-18 23:47:57

chaembian
Member
Registered: 2012-02-25
Posts: 15

Re: [SOLVED] systemd: no kernel messages in the journal

berbae wrote:

What I found abnormal in chaembian's case, is the cleanup being done several times with files deleted, and the 'Deleted empty journal' messages which I have not in my logs.

I noticed that I was running out of space on the / (1.5G was free). Perhaps this was triggering the clean-up? I had deleted older packages from pacman cache and now / has plenty free space). I'm not sure if this is the reason behind the cleanup triggers. My apologies for the noise it was throwing up.

Offline

#18 2013-11-19 03:43:24

chaembian
Member
Registered: 2012-02-25
Posts: 15

Re: [SOLVED] systemd: no kernel messages in the journal

OK, I have all the kernel messages now. The messages survived a reboot as well. I'll let this run for sometime and post if I see anything different.

"journal -k' starts:

-- Logs begin at Mon 2013-11-18 00:00:08 IST, end at Tue 2013-11-19 09:02:15 IST. --
Nov 19 08:56:52 chaembian kernel: Initializing cgroup subsys cpuset
Nov 19 08:56:52 chaembian kernel: Initializing cgroup subsys cpu
Nov 19 08:56:52 chaembian kernel: Initializing cgroup subsys cpuacct
Nov 19 08:56:52 chaembian kernel: Linux version 3.11.6-1-ARCH (nobody@var-lib-archbuild-extra-x86_64-thomas) (gcc version 4.8.1 20130725 (prerelease) (GCC) ) 
Nov 19 08:56:52 chaembian kernel: Command line: BOOT_IMAGE=/vmlinuz-linux root=UUID=639e14d4-e548-48ed-a6e5-c73a19c618ff rw quiet
Nov 19 08:56:52 chaembian kernel: e820: BIOS-provided physical RAM map:
Nov 19 08:56:52 chaembian kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009abff] usable
Nov 19 08:56:52 chaembian kernel: BIOS-e820: [mem 0x000000000009ac00-0x000000000009ffff] reserved
Nov 19 08:56:52 chaembian kernel: BIOS-e820: [mem 0x00000000000e2000-0x00000000000fffff] reserved
Nov 19 08:56:52 chaembian kernel: BIOS-e820: [mem 0x0000000000100000-0x00000000cff7ffff] usable
Nov 19 08:56:52 chaembian kernel: BIOS-e820: [mem 0x00000000cff80000-0x00000000cff97fff] ACPI data
Nov 19 08:56:52 chaembian kernel: BIOS-e820: [mem 0x00000000cff98000-0x00000000cffcffff] ACPI NVS
Nov 19 08:56:52 chaembian kernel: BIOS-e820: [mem 0x00000000cffd0000-0x00000000cfffffff] reserved
Nov 19 08:56:52 chaembian kernel: BIOS-e820: [mem 0x00000000ffa00000-0x00000000ffffffff] reserved
Nov 19 08:56:52 chaembian kernel: BIOS-e820: [mem 0x0000000100000000-0x000000011fffffff] usable
Nov 19 08:56:52 chaembian kernel: NX (Execute Disable) protection: active
Nov 19 08:56:52 chaembian kernel: SMBIOS 2.5 present.
...

"journalctl -k -b -1" starts:

-- Logs begin at Mon 2013-11-18 00:00:08 IST, end at Tue 2013-11-19 09:02:15 IST. --
Nov 19 05:21:50 chaembian kernel: Initializing cgroup subsys cpuset
Nov 19 05:21:50 chaembian kernel: Initializing cgroup subsys cpu
Nov 19 05:21:50 chaembian kernel: Initializing cgroup subsys cpuacct
Nov 19 05:21:50 chaembian kernel: Linux version 3.11.6-1-ARCH (nobody@var-lib-archbuild-extra-x86_64-thomas) (gcc version 4.8.1 20130725 (prerelease) (GCC) ) 
Nov 19 05:21:50 chaembian kernel: Command line: BOOT_IMAGE=/vmlinuz-linux root=UUID=639e14d4-e548-48ed-a6e5-c73a19c618ff rw quiet
Nov 19 05:21:50 chaembian kernel: e820: BIOS-provided physical RAM map:
Nov 19 05:21:50 chaembian kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009abff] usable
Nov 19 05:21:50 chaembian kernel: BIOS-e820: [mem 0x000000000009ac00-0x000000000009ffff] reserved
Nov 19 05:21:50 chaembian kernel: BIOS-e820: [mem 0x00000000000e2000-0x00000000000fffff] reserved
Nov 19 05:21:50 chaembian kernel: BIOS-e820: [mem 0x0000000000100000-0x00000000cff7ffff] usable
Nov 19 05:21:50 chaembian kernel: BIOS-e820: [mem 0x00000000cff80000-0x00000000cff97fff] ACPI data
Nov 19 05:21:50 chaembian kernel: BIOS-e820: [mem 0x00000000cff98000-0x00000000cffcffff] ACPI NVS
Nov 19 05:21:50 chaembian kernel: BIOS-e820: [mem 0x00000000cffd0000-0x00000000cfffffff] reserved
Nov 19 05:21:50 chaembian kernel: BIOS-e820: [mem 0x00000000ffa00000-0x00000000ffffffff] reserved
Nov 19 05:21:50 chaembian kernel: BIOS-e820: [mem 0x0000000100000000-0x000000011fffffff] usable
Nov 19 05:21:50 chaembian kernel: NX (Execute Disable) protection: active
Nov 19 05:21:50 chaembian kernel: SMBIOS 2.5 present.
...

On journal sizes, "journalctl | grep journal":

...
Nov 19 05:20:47 chaembian systemd-journal[139]: Journal stopped
Nov 19 05:21:50 chaembian systemd-journal[141]: Runtime journal is using 668.0K (max 185.2M, leaving 277.8M of free 1.8G, current limit 185.2M).
Nov 19 05:21:50 chaembian systemd-journal[141]: Runtime journal is using 668.0K (max 185.2M, leaving 277.8M of free 1.8G, current limit 185.2M).
Nov 19 05:21:50 chaembian systemd-journal[141]: Journal started
Nov 19 05:21:57 chaembian systemd-journal[141]: Permanent journal is using 11.7M (max 2.8G, leaving 4.0G of free 11.1G, current limit 2.8G).
Nov 19 08:55:50 chaembian systemd-journal[141]: Journal stopped
Nov 19 08:56:52 chaembian systemd-journal[136]: Runtime journal is using 668.0K (max 185.2M, leaving 277.8M of free 1.8G, current limit 185.2M).
Nov 19 08:56:52 chaembian systemd-journal[136]: Runtime journal is using 668.0K (max 185.2M, leaving 277.8M of free 1.8G, current limit 185.2M).
Nov 19 08:56:52 chaembian systemd-journal[136]: Journal started
Nov 19 08:56:58 chaembian systemd-journal[136]: Permanent journal is using 18.8M (max 2.8G, leaving 4.0G of free 11.1G, current limit 2.8G).
...

Offline

#19 2013-11-19 04:19:56

cfr
Member
From: Cymru
Registered: 2011-11-27
Posts: 7,130

Re: [SOLVED] systemd: no kernel messages in the journal

You can configure this but journald will basic limit the size of journals it allows itself to avoid eating up your last disk space. When I start running out of space on /var, the first thing I notice is extremely short journalctl output - just a few lines generally. As I read the thread, this is what I was going to ask because I've seen it happen several times. So it is very likely why.


CLI Paste | How To Ask Questions

Arch Linux | x86_64 | GPT | EFI boot | refind | stub loader | systemd | LVM2 on LUKS
Lenovo x270 | Intel(R) Core(TM) i5-7200U CPU @ 2.50GHz | Intel Wireless 8265/8275 | US keyboard w/ Euro | 512G NVMe INTEL SSDPEKKF512G7L

Offline

#20 2013-11-20 02:51:57

chaembian
Member
Registered: 2012-02-25
Posts: 15

Re: [SOLVED] systemd: no kernel messages in the journal

Messages have survived few boots and systemd-journal seems to be sane. Thank you for all the help berbae and Wonderwoofy! Thank you to cfr to confirm that this is the issue that is happening. I'll mark the thread solved.

Offline

Board footer

Powered by FluxBB