see http://bugs.gentoo.org/show_bug.cgi?id=227303#c148

vmware-crash.log (text/plain), 9.67 KB, created by alex on 2009-01-05 20:42:08 UTC

# cat /var/log/vmware/vmware-serverd.log
Jan 05 20:12:03: app| VMHS: Connection to VM broken: cfg: /var/lib/vmware/Virtual Machines/Windows XP Professional/Windows XP Professional.vmx; error: Pipe: Read failed; state: 3
Jan 05 20:12:06: app| VMServerd IPC closed the connection with thread /var/lib/vmware/Virtual Machines/Windows XP Professional/Windows XP Professional.vmx (0x831b078)
Jan 05 20:12:06: app| Lost connection to /var/lib/vmware/Virtual Machines/Windows XP Professional/Windows XP Professional.vmx (/var/lib/vmware/Virtual Machines/Windows XP Professional/Windows XP Professional.vmx) unexpectedly.
Jan 05 20:12:06: app| VM suddenly changed state: poweredOff.
Jan 05 20:12:06: app| VM suddenly changed state: poweredOff.
Jan 05 20:12:06: app| VM suddenly changed state: poweredOff.
Jan 05 20:12:38: app| New connection on socket server-vmdb from host 10.1.1.1 (ip address: 10.1.1.1) , user: root
Jan 05 20:12:38: app| SP: New user session for user: root, pos: 0
Jan 05 20:12:38: app| The vm-list file has changed! Reloading the list of registered vms
Jan 05 20:13:26: app| Attempting to launch vmx : /var/lib/vmware/Virtual Machines/Windows XP Professional/Windows XP Professional.vmx
Jan 05 20:13:26: app| New connection on socket server-vmxvmdb from host localhost (ip address: local) , user: root
Jan 05 20:13:26: app| Connection from : /var/lib/vmware/Virtual Machines/Windows XP Professional/Windows XP Professional.vmx
Jan 05 20:13:26: app| Setting up autoDetect info.
Jan 05 20:13:26: app| VMServerdConnect: connecting to /var/lib/vmware/Virtual Machines/Windows XP Professional/Windows XP Professional.vmx
Jan 05 20:13:27: app| Connected to /var/lib/vmware/Virtual Machines/Windows XP Professional/Windows XP Professional.vmx
Jan 05 20:13:27: app| VM suddenly changed state: poweredOn.
Jan 05 20:13:36: app| VmsdRegister: Config file has changed: /var/lib/vmware/Virtual Machines/Windows XP Professional/Windows XP Professional.vmx

================================================================================

# cat /var/log messages

Jan  5 20:12:02 s /dev/vmmon[16199]: host clock rate change request 83 -> 1043
Jan  5 20:12:02 s BUG: unable to handle kernel NULL pointer dereference at 0000000000000000
Jan  5 20:12:02 s IP: [<0000000000000000>] 0x00000000000000
Jan  5 20:12:02 s PGD 126b63067 PUD 117ae0067 PMD 0
Jan  5 20:12:02 s Oops: 0010 [1] SMP
Jan  5 20:12:02 s CPU 0
Jan  5 20:12:02 s Modules linked in: snd_pcm_oss snd_mixer_oss snd_seq_oss snd_seq_midi_event snd_seq snd_ymfpci snd_vx222 snd_vx_lib snd_via82xx_modem snd_via82xx snd_trident snd_sonicvibes snd_rme9652 snd_rme96 snd_rme32 snd_riptide snd_pcxhr snd_nm256 snd_mona snd_mixart snd_mia snd_maestro3 snd_layla24 snd_layla20 snd_korg1212 snd_intel8x0m snd_intel8x0 snd_indigoio snd_indigodj snd_indigo snd_ice1724 snd_ak4114 snd_pt2258 snd_ice1712 snd_ice17xx_ak4xxx snd_ak4xxx_adda snd_cs8427 snd_i2c snd_hdspm snd_hdsp snd_hda_intel snd_gina24 snd_gina20 snd_fm801 snd_es1968 snd_es1938 snd_ens1371 snd_ens1370 snd_emu10k1x snd_emu10k1 snd_util_mem snd_echo3g snd_darla24 snd_darla20 snd_cs5530 snd_sb16_dsp snd_cs46xx snd_cs4281 snd_cmipci snd_ca0106 snd_bt87x snd_azt3328 snd_au8830 snd_au8820 snd_au8810 snd_atiixp_modem snd_atiixp snd_als4000 snd_sb_common gameport snd_opl3_lib snd_hwdep snd_mpu401_uart snd_rawmidi snd_seq_device snd_als300 snd_ali5451 snd_ad1889 snd_ac97_codec snd_pcm snd_timer snd snd_page_alloc ac97_bus lirc_igorplugusb lirc_dev vmnet(P) vmmon(P) pppoe pppox ppp_generic slhc ipt_recent xt_state iptable_filter ipt_MASQUERADE xt_tcpudp iptable_nat nf_nat nf_conntrack_ipv4 nf_conntrack ip_tables x_tables ipv6 hci_usb bluetooth it87 hwmon_vid wcfxo dahdi crc_ccitt rtc_cmos rtc_core rtc_lib fglrx(P) ppdev thermal i2c_piix4 e100 r8169 pcspkr mii i2c_core k8temp processor sky2 parport_pc parport thermal_sys button tg3 libphy e1000 nfs lockd sunrpc jfs raid10 raid456 async_memcpy async_xor xor async_tx raid1 raid0 dm_bbr dm_snapshot dm_mirror dm_log dm_mod sbp2 ohci1394 ieee1394 sl811_hcd usbhid ohci_hcd ssb uhci_hcd usb_storage ehci_hcd usbcore lpfc qla2xxx megaraid_sas megaraid_mbox megaraid_mm megaraid aacraid sx8 DAC960 cciss 3w_9xxx 3w_xxxx mptsas scsi_transport_sas mptfc scsi_transport_fc scsi_tgt mptspi mptscsih mptbase atp870u dc395x qla1280 dmx3191d sym53c8xx gdth advansys initio BusLogic arcmsr aic7xxx aic79xx scsi_transport_spi sg videobuf_core pdc_adma sata_inic162x sata_mv ata_piix ahci sata_qstor sata_vsc sata_uli sata_sis sata_sx4 sata_nv sata_via sata_svw sata_sil24 sata_sil sata_promise scsi_wait_scan pata_sl82c105 pata_cs5530 pata_cs5520 pata_via pata_jmicron pata_marvell pata_sis pata_netcell pata_sc1200 pata_pdc202xx_old pata_triflex pata_atiixp pata_opti pata_amd pata_ali pata_it8213 pata_pcmcia pcmcia pcmcia_core firmware_class pata_ns87415 pata_ns87410 pata_serverworks pata_platform pata_artop pata_it821x pata_optidma pata_hpt3x2n pata_hpt3x3 pata_hpt37x pata_hpt366 pata_cmd64x pata_efar pata_rz1000 pata_sil680 pata_radisys pata_pdc2027x pata_mpiix libata dock [last unloaded: snd_page_alloc]
Jan  5 20:12:02 s Pid: 16199, comm: vmware-vmx Tainted: P          2.6.27-gentoo-r7 #1
Jan  5 20:12:02 s RIP: 0010:[<0000000000000000>]  [<0000000000000000>] 0x00000000000000
Jan  5 20:12:02 s RSP: 0018:ffff880122d05d40  EFLAGS: 00010246
Jan  5 20:12:02 s RAX: ffff8801276aa5f8 RBX: 0000000000000000 RCX: 0000000000000000
Jan  5 20:12:02 s RDX: 0000000000007005 RSI: ffff880122e82b00 RDI: ffff8801226a2420
Jan  5 20:12:02 s RBP: ffff880122d05d58 R08: ffffffffa06e0ba0 R09: ffff880122d05a98
Jan  5 20:12:02 s R10: 2222222222222222 R11: ffff880122d05c48 R12: ffff880122e82b00
Jan  5 20:12:02 s R13: 0000000000000413 R14: 00000000000007e9 R15: 0000000000000000
Jan  5 20:12:02 s FS:  00007f0ae95297c0(0000) GS:ffffffff806fba80(0063) knlGS:00000000f3b22b90
Jan  5 20:12:02 s CS:  0010 DS: 002b ES: 002b CR0: 000000008005003b
Jan  5 20:12:02 s CR2: 0000000000000000 CR3: 0000000122aa4000 CR4: 00000000000006e0
Jan  5 20:12:02 s DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
Jan  5 20:12:02 s DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
Jan  5 20:12:02 s Process vmware-vmx (pid: 16199, threadinfo ffff880122d04000, task ffff880122293890)
Jan  5 20:12:02 s Stack:  ffffffffa07da22c 0000000000000000 0000000000000413 ffff880122d05d78
Jan  5 20:12:02 s ffffffffa07dd3b6 0000000000000413 ffff880117a1f200 ffff880122d05eb8
Jan  5 20:12:02 s ffffffffa07d7f3f ffff880121db4780 0000000000000292 ffff880122d05db8
Jan  5 20:12:02 s Call Trace:
Jan  5 20:12:02 s [<ffffffffa07da22c>] ? 0xffffffffa07da22c
Jan  5 20:12:02 s [<ffffffffa07dd3b6>] 0xffffffffa07dd3b6
Jan  5 20:12:02 s [<ffffffffa07d7f3f>] 0xffffffffa07d7f3f
Jan  5 20:12:02 s [<ffffffff8053c23f>] ? 0xffffffff8053c23f
Jan  5 20:12:02 s [<ffffffff8053c382>] ? 0xffffffff8053c382
Jan  5 20:12:02 s [<ffffffff8053c23f>] ? 0xffffffff8053c23f
Jan  5 20:12:02 s [<ffffffff8053c26a>] ? 0xffffffff8053c26a
Jan  5 20:12:02 s [<ffffffffa07f95dd>] ? 0xffffffffa07f95dd
Jan  5 20:12:02 s [<ffffffffa07d849a>] 0xffffffffa07d849a
Jan  5 20:12:02 s [<ffffffff802bd713>] 0xffffffff802bd713
Jan  5 20:12:02 s [<ffffffff8024858b>] ? 0xffffffff8024858b
Jan  5 20:12:02 s [<ffffffff80225dd8>] 0xffffffff80225dd8
Jan  5 20:12:02 s
Jan  5 20:12:02 s
Jan  5 20:12:02 s Code:  Bad RIP value.
Jan  5 20:12:02 s RIP  [<0000000000000000>] 0x00000000000000
Jan  5 20:12:02 s RSP <ffff880122d05d40>
Jan  5 20:12:02 s CR2: 0000000000000000
Jan  5 20:12:02 s ---[ end trace a4cc71692cbb33db ]---
Jan  5 20:12:03 s device eth0 left promiscuous mode
Jan  5 20:12:03 s bridge-eth0: disabled promiscuous mode
Jan  5 20:12:38 s xinetd[16069]: START: vmware-authd pid=18017 from=10.1.1.1
Jan  5 20:12:38 s vmware-authd[18017]: PAM unable to dlopen(/lib/security/pam_deny.so)
Jan  5 20:12:38 s vmware-authd[18017]: PAM [error: /lib/security/pam_deny.so: wrong ELF class: ELFCLASS64]
Jan  5 20:12:38 s vmware-authd[18017]: PAM adding faulty module: /lib/security/pam_deny.so
Jan  5 20:12:38 s vmware-authd[18017]: login from 10.1.1.1 as root
Jan  5 20:12:38 s xinetd[16069]: EXIT: vmware-authd status=0 pid=18017 duration=0(sec)
Jan  5 20:13:04 s sshd[18025]: Accepted keyboard-interactive/pam for alex from 10.1.1.1 port 3515 ssh2
Jan  5 20:13:04 s sshd[18025]: pam_unix(sshd:session): session opened for user alex by (uid=0)
Jan  5 20:13:27 s /dev/vmnet: open called by PID 18037 (vmware-vmx)
Jan  5 20:13:27 s device eth0 entered promiscuous mode
Jan  5 20:13:27 s bridge-eth0: enabled promiscuous mode
Jan  5 20:13:27 s /dev/vmnet: port on hub 0 successfully opened
Jan  5 20:13:27 s xinetd[16069]: START: vmware-authd pid=18048 from=10.1.1.1
Jan  5 20:13:27 s vmware-authd[18048]: PAM unable to dlopen(/lib/security/pam_deny.so)
Jan  5 20:13:27 s vmware-authd[18048]: PAM [error: /lib/security/pam_deny.so: wrong ELF class: ELFCLASS64]
Jan  5 20:13:27 s vmware-authd[18048]: PAM adding faulty module: /lib/security/pam_deny.so
Jan  5 20:13:27 s vmware-authd[18048]: login from 10.1.1.1 as root

================================================================================

# ps ax | grep vmware
16129 ?        S      0:00 /opt/vmware/server/bin/vmnet-bridge -d /var/run/vmnet-bridge-0.pid /dev/vmnet0 eth0
16143 ?        Ss     2:22 /opt/vmware/server/sbin/vmware-serverd -s -d
18037 ?        S<sl   0:00 /opt/vmware/server/lib/bin/vmware-vmx -C /var/lib/vmware/Virtual Machines/Windows XP Professional/Windows XP Professional.vmx -@ ""
18848 ?        Ss     0:00 vmware-authd
18851 pts/0    R+     0:00 grep --colour=auto vmware

# kill -9 18037

# ps -o pid,ppid,command ax | grep vmware
16129     1 /opt/vmware/server/bin/vmnet-bridge -d /var/run/vmnet-bridge-0.pid /dev/vmnet0 eth0
16143     1 /opt/vmware/server/sbin/vmware-serverd -s -d
18037     1 [vmware-vmx] <defunct>