LXDE VM was frozen,LXDE Host terminal shows various messages please advice thank

[joejac]

Hello,

I was working with my LXDE VM only inside my LXDE Host and suddenly my VM got frozen, fortunately this time the LXDE Host was working and I had the Terminal Console open, this is what I saw:

Code: [Select]

[myaccount@localhost ~]$
Message from syslogd@localhost at Fri Jun  8 11:40:50 2012 ...
localhost klogd: Oops: 0002 [#1] PREEMPT SMP

Message from syslogd@localhost at Fri Jun  8 11:40:50 2012 ...
localhost klogd: Process VirtualBox (pid: 5713, ti=e0bae000 task=e19a4d00 task.ti=e0bae000)

Message from syslogd@localhost at Fri Jun  8 11:40:50 2012 ...
localhost klogd: Stack:

Message from syslogd@localhost at Fri Jun  8 11:40:50 2012 ...
localhost klogd: Call Trace:

Message from syslogd@localhost at Fri Jun  8 11:40:50 2012 ...
localhost klogd: Code: 0f 31 89 45 d8 89 55 dc 8b 55 d8 29 55 e0 8b 4d dc 19 4d e4 8b 45 e0 8b 55 e4 8b 4d 10 89 01 89 51 04 8b 55 ec 8b 45 e8 83 fa 00 <89> 45 d0 89 55 d4 0f 87 e0 fe ff ff 3d ff c9 9a 3b 0f 87 d5 fe

Message from syslogd@localhost at Fri Jun  8 11:40:50 2012 ...
localhost klogd: EIP: [<f014c9c0>] 0xf014c9c0 SS:ESP 0068:e0bafd18

Message from syslogd@localhost at Fri Jun  8 11:40:50 2012 ...
localhost klogd: CR2: 0000000000002c3e
^C
[myaccount@localhost ~]$
My Host still has 12GB RAM available, I do not understand. Well, in my LXDE VM guest I was running The Brain, a Java application that is not in the repos, the file manager and Firefox with 9 tabs open. The freeze occurred when I was in Firefox, The Brain was open but I was not working with The Brain. The LXDE VM has 4GB RAM and 2 Cores from 8 of the Desktop.

I will force a shutdown in the VM. Please note that my host only runs programs from Synaptic, and the minimum.

Thanks for any suggestions.
regards
joejac

[pags]

What kernel is running on your host?

[joejac]

Hi, pags

kernel-3.2.18-pclos1.pae.bfs

Regards
joejac

[pags]

Apparently, there's a chance there could be hardware issues (kinda old):
http://marc.info/?l=linux-kernel&m=104713322017590&w=2

Could you post the output of

Code: [Select]

su -c "grep -i oops /var/log/messages"

Supply your root password, when requested.

[joejac]

Hi,

Code: [Select]

$ su -c "grep -i oops /var/log/messages"
Password:
Jun  8 11:40:50 localhost klogd: Oops: 0002 [#1] PREEMPT SMP
[myaccount@localhost ~]$
Regards
joejac

[pags]

Hi,

Code: [Select]

$ su -c "grep -i oops /var/log/messages"
Password:
Jun  8 11:40:50 localhost klogd: Oops: 0002 [#1] PREEMPT SMP
[myaccount@localhost ~]$
Regards
joejac

OK.  Only one occurrence, from today.

Let's expand that a little.  Do this, now:

Code: [Select]

su -c "grep -i oops /var/log/messages -4"

This will show the 4 line before and after this.

Let's also see if anything else was logged around it:

Code: [Select]

dmesg | grep -i oops -4

You can change the "4" to another number, if you think there might be more relevant information a few lines away (did anything odd happen around the time your VM froze?).

You might also want to check you VirtualBox logs.  They are (I think) in the same path as your VM configuration, in a "Logs" directory.

[joejac]

1. First output:

Code: [Select]

$ su -c "grep -i oops /var/log/messages -32"
Password:
Jun  8 11:40:50 localhost klogd: IP: [<f014c9c0>] 0xf014c9bf
Jun  8 11:40:50 localhost klogd: *pdpt = 0000000025b9e001 *pde = 0000000000000000
Jun  8 11:40:50 localhost klogd: Oops: 0002 [#1] PREEMPT SMP
Jun  8 11:40:50 localhost klogd: Modules linked in: iptable_filter ip_tables x_tables snd_usb_audio snd_usbmidi_lib snd_rawmidi af_packet pci_stub vboxpci(O) vboxnetadp(O) vboxnetflt(O) ipv6 vboxdrv(O) binfmt_misc uas usb_storage loop dm_mod cpufreq_ondemand cpufreq_conservative cpufreq_powersave acpi_cpufreq freq_table mperf pciehp firewire_ohci firewire_sbp2 firewire_core crc_itu_t fuse uvcvideo videodev nvidia(P) snd_hda_codec_hdmi usblp snd_hda_codec_realtek snd_hda_intel snd_hda_codec snd_hwdep snd_seq_dummy snd_seq_oss eeepc_wmi asus_wmi snd_seq_midi_event sparse_keymap snd_seq rfkill snd_seq_device pci_hotplug snd_pcm_oss snd_pcm snd_timer snd_mixer_oss evdev snd video xhci_hcd sr_mod fan iTCO_wdt cdrom wmi thermal r8169 i2c_i801 iTCO_vendor_support i2c_core mii soundcore snd_page_alloc processor button serio_raw sg ata_piix ahci libahci libata sd_mod scsi_mod crc_t10dif ext4 jbd2 crc16 uhci_hcd ohci_hcd ehci_hcd usbhid hid usbcore usb_common [last unloaded: scsi_wait_scan]
Jun  8 11:40:50 localhost klogd:

2.- Second Output: nothing

Code: [Select]

[root@localhost myaccount]# dmesg | grep -i oops -32
[root@localhost myaccount]#

3. vBox.log Application log, I had to cut part of the beginning of the file because it did not load into the post.

Code: [Select]

VirtualBox 4.1.16 r78094 linux.x86 (May 22 2012 14:23:04) release log
00:00:01.836 Log opened 2012-06-07T23:19:01.655970000Z
00:00:01.836 OS Product: Linux
00:00:01.836 OS Release: 3.2.18-pclos1.pae.bfs
00:00:01.836 OS Version: #1 SMP PREEMPT Tue May 22 04:56:12 CEST 2012
00:00:01.836 DMI Product Name: System Product Name
00:00:01.836 DMI Product Version: System Version
00:00:01.837 Host RAM: 16201MB RAM, available: 15922MB
00:00:01.837 Executable: /opt/VirtualBox/VirtualBox
00:00:01.837 Process ID: 26804
00:00:01.837 Package type: LINUX_32BITS_GENERIC
00:00:01.849 Installed Extension Packs:
00:00:01.849   Oracle VM VirtualBox Extension Pack (Version: 4.1.16 r78094; VRDE Module: VBoxVRDP)
00:00:01.851 SUP: Loaded VMMR0.r0 (/opt/VirtualBox/VMMR0.r0) at 0xf0081020 - ModuleInit at 00000000f00959ec and ModuleTerm at 00000000f0095a68
...

00:01:53.949 /PROF/CPU3/EM/Capped                    0 ticks/call (           0 ticks,       0 times, max         0, min      -1)
00:01:53.949 /PROF/CPU3/EM/ForcedActions        122304 times
00:01:53.949 /PROF/CPU3/EM/Halted                77487 times
00:01:53.949 /PROF/CPU3/EM/RAWTotal                  0 times
00:01:53.949 /PROF/CPU3/EM/REMTotal                  0 times
00:01:53.949 /PROF/CPU3/EM/Total              380983015816 ticks/call (380983015816 ticks,       1 times, max 380983015816, min 380983015816)
00:01:53.949 /PROF/VM/CPU0/Halt/Block          1307950 ns/call ( 72497090641 ticks,   55428 times, max  16235972, min       1)
00:01:53.949 /PROF/VM/CPU0/Halt/BlockInsomnia        0 ns/call (           0 ticks,       0 times, max         0, min      -1)
00:01:53.949 /PROF/VM/CPU0/Halt/BlockOnTime          0 ns/call (           0 ticks,       0 times, max         0, min      -1)
00:01:53.949 /PROF/VM/CPU0/Halt/BlockOverslept        0 ns/call (           0 ticks,       0 times, max         0, min      -1)
00:01:53.949 /PROF/VM/CPU0/Halt/Timers             232 ns/call (    25773549 ticks,  110968 times, max     84520, min       1)
00:01:53.949 /PROF/VM/CPU0/Halt/Yield                0 ns/call (           0 ticks,       0 times, max         0, min      -1)
00:01:53.949 /PROF/VM/CPU1/Halt/Block          1217030 ns/call ( 78211222587 ticks,   64264 times, max 500006482, min       1)
00:01:53.949 /PROF/VM/CPU1/Halt/BlockInsomnia        0 ns/call (           0 ticks,       0 times, max         0, min      -1)
00:01:53.949 /PROF/VM/CPU1/Halt/BlockOnTime          0 ns/call (           0 ticks,       0 times, max         0, min      -1)
00:01:53.949 /PROF/VM/CPU1/Halt/BlockOverslept        0 ns/call (           0 ticks,       0 times, max         0, min      -1)
00:01:53.949 /PROF/VM/CPU1/Halt/Timers             255 ns/call (    32796097 ticks,  128416 times, max     80590, min       1)
00:01:53.949 /PROF/VM/CPU1/Halt/Yield                0 ns/call (           0 ticks,       0 times, max         0, min      -1)
00:01:53.949 /PROF/VM/CPU2/Halt/Block           943092 ns/call ( 77942802218 ticks,   82646 times, max 500045814, min       1)
00:01:53.949 /PROF/VM/CPU2/Halt/BlockInsomnia        0 ns/call (           0 ticks,       0 times, max         0, min      -1)
00:01:53.949 /PROF/VM/CPU2/Halt/BlockOnTime          0 ns/call (           0 ticks,       0 times, max         0, min      -1)
00:01:53.949 /PROF/VM/CPU2/Halt/BlockOverslept        0 ns/call (           0 ticks,       0 times, max         0, min      -1)
00:01:53.949 /PROF/VM/CPU2/Halt/Timers             242 ns/call (    39974378 ticks,  164893 times, max     82533, min       1)
00:01:53.949 /PROF/VM/CPU2/Halt/Yield                0 ns/call (           0 ticks,       0 times, max         0, min      -1)
00:01:53.949 /PROF/VM/CPU3/Halt/Block           686816 ns/call ( 77517575660 ticks,  112865 times, max   5570440, min       1)
00:01:53.949 /PROF/VM/CPU3/Halt/BlockInsomnia        0 ns/call (           0 ticks,       0 times, max         0, min      -1)
00:01:53.949 /PROF/VM/CPU3/Halt/BlockOnTime          0 ns/call (           0 ticks,       0 times, max         0, min      -1)
00:01:53.949 /PROF/VM/CPU3/Halt/BlockOverslept        0 ns/call (           0 ticks,       0 times, max         0, min      -1)
00:01:53.949 /PROF/VM/CPU3/Halt/Timers            2146 ns/call (   410054232 ticks,  191066 times, max   2030063, min       2)
00:01:53.949 /PROF/VM/CPU3/Halt/Yield             2128 ns/call (       85156 ticks,      40 times, max      5081, min    1449)
00:01:53.949 /REM/TbFlushCount                       0 times
00:01:53.949 /REM/TbPhysInvldCount               42153 times
00:01:53.949 /REM/TlbFlushCount                  64615 times
00:01:53.949 /TM/CPU/00/cNsExecuting          22606709227 ns
00:01:53.949 /TM/CPU/00/cNsHalted             72572822892 ns
00:01:53.949 /TM/CPU/00/cNsOther              16549282215 ns
00:01:53.950 /TM/CPU/00/cNsTotal              111728814334 ns
00:01:53.950 /TM/CPU/00/cPeriodsExecuting      2709768 count
00:01:53.950 /TM/CPU/00/cPeriodsHalted           55540 count
00:01:53.950 /TM/CPU/00/pctExecuting                28 %
00:01:53.950 /TM/CPU/00/pctHalted                   45 %
00:01:53.950 /TM/CPU/00/pctOther                    26 %
00:01:53.950 /TM/CPU/01/cNsExecuting          19874157969 ns
00:01:53.950 /TM/CPU/01/cNsHalted             78312323299 ns
00:01:53.950 /TM/CPU/01/cNsOther              13542353021 ns
00:01:53.950 /TM/CPU/01/cNsTotal              111728834289 ns
00:01:53.950 /TM/CPU/01/cPeriodsExecuting      1594297 count
00:01:53.950 /TM/CPU/01/cPeriodsHalted           64152 count
00:01:53.950 /TM/CPU/01/pctExecuting                12 %
00:01:53.950 /TM/CPU/01/pctHalted                   70 %
00:01:53.950 /TM/CPU/01/pctOther                    16 %
00:01:53.950 /TM/CPU/02/cNsExecuting          19373752383 ns
00:01:53.950 /TM/CPU/02/cNsHalted             78068439110 ns
00:01:53.950 /TM/CPU/02/cNsOther              14286641927 ns
00:01:53.950 /TM/CPU/02/cNsTotal              111728833420 ns
00:01:53.950 /TM/CPU/02/cPeriodsExecuting      1850731 count
00:01:53.950 /TM/CPU/02/cPeriodsHalted           82247 count
00:01:53.950 /TM/CPU/02/pctExecuting                10 %
00:01:53.950 /TM/CPU/02/pctHalted                   71 %
00:01:53.950 /TM/CPU/02/pctOther                    17 %
00:01:53.950 /TM/CPU/03/cNsExecuting          19353182405 ns
00:01:53.950 /TM/CPU/03/cNsHalted             78022969437 ns
00:01:53.950 /TM/CPU/03/cNsOther              14348644099 ns
00:01:53.950 /TM/CPU/03/cNsTotal              111724795941 ns
00:01:53.950 /TM/CPU/03/cPeriodsExecuting      1851620 count
00:01:53.950 /TM/CPU/03/cPeriodsHalted           75504 count
00:01:53.950 /TM/CPU/03/pctExecuting                19 %
00:01:53.950 /TM/CPU/03/pctHalted                   58 %
00:01:53.950 /TM/CPU/03/pctOther                    22 %
00:01:53.950 /TM/CPU/pctExecuting                   17 %
00:01:53.950 /TM/CPU/pctHalted                      61 %
00:01:53.950 /TM/CPU/pctOther                       20 %
00:01:53.950 /TM/MaxHzHint                          64 Hz
00:01:53.950 /TM/R0/1nsSteps                    207522 times
00:01:53.950 /TM/R3/1nsSteps                    207742 times
00:01:53.950 /TM/TSC/offCPU0                  13869822729 ticks
00:01:53.950 /TM/TSC/offCPU1                  13869871472 ticks
00:01:53.950 /TM/TSC/offCPU2                  13869199305 ticks
00:01:53.950 /TM/TSC/offCPU3                  13869894649 ticks
00:01:53.950 /TM/VirtualSync/CurrentOffset       68870 ns
00:01:53.950 /VUSB/0/cUrbsInPool                     0 count
00:01:53.950 /VUSB/1/cUrbsInPool                     1 count
00:01:53.950 ********************* End of statistics **********************
00:01:53.966 NAT: zone(nm:mbuf, used:1)
00:01:53.966 NAT: zone(nm:mbuf_cluster, used:0)
00:01:53.966 NAT: zone(nm:mbuf_packet, used:0)
00:01:53.966 NAT: zone(nm:mbuf_jumbo_pagesize, used:0)
00:01:53.966 NAT: zone(nm:mbuf_jumbo_9k, used:0)
00:01:53.966 NAT: zone(nm:mbuf_jumbo_16k, used:0)
00:01:53.976 Changing the VM state from 'DESTROYING' to 'TERMINATED'.
I do not remember anything strange by the time it froze.
I am sorry, but the analysis of those data is beyond my knowledge.
Thanks
joejac

[pags]

Have you had any additional occurrences?

If there is a hardware issue, it can be problematic to track down if it doesn't re-present itself.

You could try running memtest from a LiveCD...