VirtualBox wylogowywanie

[kodama]

Cześć
Mam VirtualBoxa instalowanego ze strony producenta (wersja 4.2.14), na Squeezie (i386). Ostatnio - nie mam pojęcia czy po jakiejś aktualizacji czy jak - "włączyła mu się" dziwna przypadłość. Otóż wciśnięcie przycisku Ustawienia/Settings powoduje wylogowanie użytkownika z systemu, ale tego gospodarza - czyli Squeeza. I trzeba się logować na nowo. Nawet jak się stworzy nową maszynę to jest to samo. Widział ktoś kiedy takie coś?

Piszę w Luźnych, bo nie jest to jakaś kwestia paląca i nie chcę zaśmiecać innych działów (na dniach i tak będę wymieniał dysk i instalował świeży system, Jessie zapewne), ale może komuś się po prostu przytrafiło coś podobnego?

Pozdrawiam

[Rafal_F]

Sprawdź logi w /var/log

[kodama]

Jedynie co tam jest ewentualnie interesującego, to w /var/log/messages takie wpisy

Kod: Zaznacz cały

[B]Jul  2 18:00:07 debian kernel: [   14.787966] vboxdrv: fAsync=0 offMin=0x38e offMax=0x1923
Jul  2 18:00:07 debian kernel: [   14.788033] vboxdrv: TSC mode is 'synchronous', kernel timer mode is 'normal'.
Jul  2 18:00:09 debian kernel: [   16.617023] vboxpci: IOMMU not found (not compiled)[/B]
Jul  2 19:23:55 debian kernel: [ 5043.186404] device vboxnet0 entered promiscuous mode
Jul  2 19:24:09 debian kernel: [ 5056.761633] device vboxnet0 left promiscuous mode
Jul  2 19:24:09 debian kernel: [ 5056.772534] vboxnetflt: dropped 0 out of 33 packets
Jul  2 19:24:50 debian kernel: [ 5097.998723] device vboxnet0 entered promiscuous mode
Jul  2 19:29:49 debian kernel: [ 5396.935638] device vboxnet0 left promiscuous mode
Jul  2 19:29:49 debian kernel: [ 5396.944022] vboxnetflt: dropped 0 out of 9 packets
 [b]Jul  2 19:30:40 debian kernel: [ 5447.438338] vboxdrv: fAsync=0 offMin=0x367 offMax=0x1887
Jul  2 19:30:40 debian kernel: [ 5447.438405] vboxdrv: TSC mode is 'synchronous', kernel timer mode is 'normal'.
 Jul  2 19:30:40 debian kernel: [ 5447.658463] vboxpci: IOMMU not found (not compiled)
Jul  2 21:31:34 debian kernel: [   17.889493] vboxdrv: fAsync=0 offMin=0x367 offMax=0x1957
Jul  2 21:31:34 debian kernel: [   17.889565] vboxdrv: TSC mode is 'synchronous', kernel timer mode is 'normal'.
 Jul  2 21:31:35 debian kernel: [   18.490552] vboxpci: IOMMU not found (not compiled)[/b]

Te pogrubione powtarzają się bardzo często.

[saturno]

VirtualBox też tworzy logi.

[kodama]

W /home/kodama/.VirtualBox/VBoxSVC.log jest tylko takie coś:

Kod: Zaznacz cały

VirtualBox XPCOM Server 4.2.14 r86644 linux.x86 (Jun 21 2013 12:36:15) release log
00:00:00.012103 main     Log opened 2013-07-02T17:35:27.946617000Z
00:00:00.012109 main     OS Product: Linux
00:00:00.012112 main     OS Release: 2.6.32-5-686
00:00:00.012115 main     OS Version: #1 SMP Fri May 10 08:33:48 UTC 2013
00:00:00.012147 main     DMI Product Name: G31M-ES2L
00:00:00.012162 main     DMI Product Version:  
00:00:00.012281 main     Host RAM: 2024MB total, 1362MB available
00:00:00.012288 main     Executable: /usr/lib/virtualbox/VBoxSVC
00:00:00.012290 main     Process ID: 5942
00:00:00.012292 main     Package type: LINUX_32BITS_DEBIAN_6_0
00:00:00.118812 nspr-2   Loading settings file "/home/kodama/.VirtualBox/VirtualBox.xml" with version "1.11-linux"
00:00:00.228003 nspr-2   Successfully initialised host USB using sysfs
00:00:00.232556 nspr-2   NetIfAdpCtlOut: VBoxNetAdpCtl: Error while retrieving link speed for pan0: VBoxNetAdpCtl: ioctl failed: Operation not supported
00:00:00.235853 nspr-2   NetIfAdpCtlOut: VBoxNetAdpCtl: Error while retrieving link status for vboxnet0: VBoxNetAdpCtl: ioctl failed: Operation not supported
00:00:00.386459 nspr-2   failed to create vboxnet0, error (0x80004005)
00:00:00.386877 nspr-2   VDInit finished
00:00:00.395632 nspr-2   Loading settings file "/home/kodama/VirtualBox VMs/MX7/MX7.vbox" with version "1.12-linux"
00:00:00.400953 nspr-2   Loading settings file "/home/kodama/VirtualBox VMs/debian/debian.vbox" with version "1.12-linux"
00:00:00.403367 nspr-2   Loading settings file "/home/kodama/VirtualBox VMs/5biuro_xp/5biuro_xp.vbox" with version "1.12-linux"
00:06:51.924986 main     ERROR [COM]: aRC=VBOX_E_OBJECT_IN_USE (0x80bb000c) aIID={29989373-b111-4654-8493-2e1176cba890} aComponent={Medium} aText={Medium '/home/kodama/VirtualBox VMs/MX7/Snapshots/{99e151f7-4b14-409d-9cfd-679dbba94bf4}.vdi' cannot be closed because it is still attached to 1 virtual machines}, preserve=false
00:06:51.925081 main     ERROR [COM]: aRC=VBOX_E_OBJECT_IN_USE (0x80bb000c) aIID={29989373-b111-4654-8493-2e1176cba890} aComponent={Medium} aText={Medium '/home/kodama/Dokumenty/winmx7_virt/winmx7.vdi' cannot be closed because it is still attached to 1 virtual machines}, preserve=false
00:06:51.925666 main     ERROR [COM]: aRC=VBOX_E_OBJECT_IN_USE (0x80bb000c) aIID={29989373-b111-4654-8493-2e1176cba890} aComponent={Medium} aText={Medium '/home/kodama/VirtualBox VMs/debian/debian.vdi' cannot be closed because it is still attached to 1 virtual machines}, preserve=false
00:06:51.925887 main     ERROR [COM]: aRC=VBOX_E_OBJECT_IN_USE (0x80bb000c) aIID={29989373-b111-4654-8493-2e1176cba890} aComponent={Medium} aText={Medium '/home/kodama/VirtualBox VMs/5biuro_xp/5biuro_xp.vdi' cannot be closed because it is still attached to 1 virtual machines}, preserve=false
00:06:51.970291 Watcher  ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={3b2f08eb-b810-4715-bee0-bb06b9880ad2} aComponent={VirtualBox} aText={The object is not ready}, preserve=false

[saturno]

To co przedstawiłeś, to logi globalne.
A tobie wywala się przy ustawieniach dla danej maszyny więc log powinien być w:

Kod: Zaznacz cały

/home/user/VirtualBox VMs/nazwa_maszyny/Logs/VBox.log

Edycja:
Czy błąd występuje przy zmianie ustawień którejś konkretnie maszyny czy wszystkich?

[kodama]

Racja, mój błąd.
Tak - wysypuje się przy próbie zmiany ustawień dla dowolnej z 3 maszyn wirtualnych (de facto nie mogę tych zmian dokonać nawet bo zamiast okna ustawień, które powinno się pojawić, mam czarny ekran przez ok. 2 sekundy i wylogowanie).
Uruchomiłem VBoxa i sprawdziłem, które logi się aktualizują. Tylko dwa:

Kod: Zaznacz cały

[b]~/.VirtualBox/VBoxSVC.log[/b]

VirtualBox XPCOM Server 4.2.14 r86644 linux.x86 (Jun 21 2013 12:36:15) release log
00:00:00.023848 main     Log opened 2013-07-05T04:48:57.525374000Z
00:00:00.023855 main     OS Product: Linux
00:00:00.023858 main     OS Release: 2.6.32-5-686
00:00:00.023861 main     OS Version: #1 SMP Fri May 10 08:33:48 UTC 2013
00:00:00.023891 main     DMI Product Name: G31M-ES2L
00:00:00.023906 main     DMI Product Version:  
00:00:00.024035 main     Host RAM: 2024MB total, 1545MB available
00:00:00.024043 main     Executable: /usr/lib/virtualbox/VBoxSVC
00:00:00.024045 main     Process ID: 2826
00:00:00.024047 main     Package type: LINUX_32BITS_DEBIAN_6_0
00:00:00.171697 nspr-2   Loading settings file "/home/kodama/.VirtualBox/VirtualBox.xml" with version "1.11-linux"
00:00:00.380853 nspr-2   Successfully initialised host USB using sysfs
00:00:00.386340 nspr-2   NetIfAdpCtlOut: VBoxNetAdpCtl: Error while retrieving link speed for pan0: VBoxNetAdpCtl: ioctl failed: Operation not supported
00:00:00.604251 nspr-2   NetIfAdpCtlOut: VBoxNetAdpCtl: Error while retrieving link status for vboxnet0: VBoxNetAdpCtl: ioctl failed: Operation not supported
00:00:00.605038 nspr-2   VDInit finished
00:00:00.665601 nspr-2   Loading settings file "/home/kodama/VirtualBox VMs/MX7/MX7.vbox" with version "1.12-linux"
00:00:00.724441 nspr-2   Loading settings file "/home/kodama/VirtualBox VMs/debian/debian.vbox" with version "1.12-linux"
00:00:00.727133 nspr-2   Loading settings file "/home/kodama/VirtualBox VMs/5biuro_xp/5biuro_xp.vbox" with version "1.12-linux"
00:00:24.481733 main     ERROR [COM]: aRC=VBOX_E_OBJECT_IN_USE (0x80bb000c) aIID={29989373-b111-4654-8493-2e1176cba890} aComponent={Medium} aText={Medium '/home/kodama/VirtualBox VMs/MX7/Snapshots/{99e151f7-4b14-409d-9cfd-679dbba94bf4}.vdi' cannot be closed because it is still attached to 1 virtual machines}, preserve=false
00:00:24.481819 main     ERROR [COM]: aRC=VBOX_E_OBJECT_IN_USE (0x80bb000c) aIID={29989373-b111-4654-8493-2e1176cba890} aComponent={Medium} aText={Medium '/home/kodama/Dokumenty/winmx7_virt/winmx7.vdi' cannot be closed because it is still attached to 1 virtual machines}, preserve=false
00:00:24.482264 main     ERROR [COM]: aRC=VBOX_E_OBJECT_IN_USE (0x80bb000c) aIID={29989373-b111-4654-8493-2e1176cba890} aComponent={Medium} aText={Medium '/home/kodama/VirtualBox VMs/debian/debian.vdi' cannot be closed because it is still attached to 1 virtual machines}, preserve=false
00:00:24.482549 main     ERROR [COM]: aRC=VBOX_E_OBJECT_IN_USE (0x80bb000c) aIID={29989373-b111-4654-8493-2e1176cba890} aComponent={Medium} aText={Medium '/home/kodama/VirtualBox VMs/5biuro_xp/5biuro_xp.vdi' cannot be closed because it is still attached to 1 virtual machines}, preserve=false
00:00:24.543792 Watcher  ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={3b2f08eb-b810-4715-bee0-bb06b9880ad2} aComponent={VirtualBox} aText={The object is not ready}, preserve=false

oraz

Kod: Zaznacz cały

[b]~/.VirtualBox/selectorwindow.log[/b]

00:00:03.317205 Log opened 2013-07-05T04:48:58.983587000Z
00:00:03.317210 OS Product: Linux
00:00:03.317213 OS Release: 2.6.32-5-686
00:00:03.317216 OS Version: #1 SMP Fri May 10 08:33:48 UTC 2013
00:00:03.317245 DMI Product Name: G31M-ES2L
00:00:03.317260 DMI Product Version:  
00:00:03.317411 Host RAM: 2024MB total, 1541MB available
00:00:03.317417 Executable: /usr/lib/virtualbox/VirtualBox
00:00:03.317419 Process ID: 2774
00:00:03.317420 Package type: LINUX_32BITS_DEBIAN_6_0
00:00:03.477068 Loading VMs started...
00:00:03.485004  Loading VM {9800b0ab-9a97-4aeb-a7b8-74fb5a45ce37}...
00:00:03.485406  VM {MX7} is accessible.
00:00:03.485630  VM {MX7} groups are {/}.
00:00:03.485648   Creating item for VM {MX7}, group {}.
00:00:03.515588  Loading VM {c5161a62-e145-4bf4-8690-e93b74689121}...
00:00:03.515999  VM {debian} is accessible.
00:00:03.516692  VM {debian} groups are {/}.
00:00:03.516714   Creating item for VM {debian}, group {}.
00:00:03.522593  Loading VM {942ce5ca-4201-4dc1-b95d-67c7fff42561}...
00:00:03.522983  VM {5biuro_xp} is accessible.
00:00:03.523187  VM {5biuro_xp} groups are {/}.
00:00:03.523204   Creating item for VM {5biuro_xp}, group {}.
00:00:03.528776 Loading VMs finished.
00:00:21.133284 2D support test failed: err code (VINF_SUCCESS (0) - Success.)

W katalogu, który podałeś nie tworzy się nic (przeniosłem stare logi gdzie indziej, żeby sprawdzić i jest pusto po takim wywaleniu się VBoxa).

[lun]

A czy to wylogowanie następuje jakimś konkretnym mechanizmem? Sprawdź auth logi systemowe pod tym kątem. Najpewniej nic nie znajdziesz, ale warto spróbować. Jest jeszcze coś takiego fajnego w Linuksie jak strace. Nie jest to może najbardziej intuicyjne i proste w debugowaniu (czy ktoś zna dobry polski odpowiednik tego słowa?) narzędzie, ale pokaże Ci wszystko co działo się z aplikacją. Możesz to spróbować zrzucić do logu i wkleić na forum - pomożemy.

Edycja:
A tu jeszcze do poczytania na temat tego narzędzia: http://www.varlog.pl/2010/03/strace-czy ... low-kilka/

[kodama]

W auth.log ciekawostek nie ma:

Kod: Zaznacz cały

Jul  5 16:08:02 debian polkitd(authority=local): Registered Authentication Agent for session /org/freedesktop/ConsoleKit/Session4 (system bus name :1.67 [/usr/lib/policykit-1-gnome/polkit-gnome-authentication-agent-1], object path /org/gnome/PolicyKit1/AuthenticationAgent, locale pl_PL.utf8)
Jul  5 16:08:17 debian sudo:   kodama : TTY=pts/0 ; PWD=/home/kodama ; USER=root ; COMMAND=/bin/cat /var/log/auth.log
Jul  5 16:09:25 debian sudo:   kodama : TTY=pts/0 ; PWD=/home/kodama ; USER=root ; COMMAND=/bin/cat /var/log/auth.log
Jul  5 16:10:01 debian gnome-keyring-daemon[2772]: dbus failure unregistering from session: Connection is closed
Jul  5 16:10:01 debian polkitd(authority=local): Unregistered Authentication Agent for session /org/freedesktop/ConsoleKit/Session3 (system bus name :1.55, object path /org/gnome/PolicyKit1/AuthenticationAgent, locale pl_PL.UTF-8) (disconnected from bus)
Jul  5 16:10:01 debian gnome-keyring-daemon[2772]: dbus failure unregistering from session: Connection is closed
Jul  5 16:10:01 debian polkitd(authority=local): Unregistered Authentication Agent for session /org/freedesktop/ConsoleKit/Session4 (system bus name :1.67, object path /org/gnome/PolicyKit1/AuthenticationAgent, locale pl_PL.utf8) (disconnected from bus)
Jul  5 16:10:01 debian gdm-session-worker[2762]: pam_unix(gdm3:session): session closed for user kodama
Jul  5 16:10:04 debian polkitd(authority=local): Registered Authentication Agent for session /org/freedesktop/ConsoleKit/Session5 (system bus name :1.81 [/usr/lib/policykit-1-gnome/polkit-gnome-authentication-agent-1], object path /org/gnome/PolicyKit1/AuthenticationAgent, locale pl_PL.UTF-8)
Jul  5 16:10:07 debian gdm-session-worker[3092]: pam_unix(gdm3:session): session opened for user kodama by (uid=0)
Jul  5 16:10:07 debian gdm-session-worker[3092]: pam_ck_connector(gdm3:session): nox11 mode, ignoring PAM_TTY :0
Jul  5 16:10:10 debian polkitd(authority=local): Registered Authentication Agent for session /org/freedesktop/ConsoleKit/Session6 (system bus name :1.94 [/usr/lib/policykit-1-gnome/polkit-gnome-authentication-agent-1], object path /org/gnome/PolicyKit1/AuthenticationAgent, locale pl_PL.utf8)
Jul  5 16:10:17 debian sudo:   kodama : TTY=pts/0 ; PWD=/home/kodama ; USER=root ; COMMAND=/bin/cat /var/log/auth.log
 

16:10 kliknąłem w "Ustawienia" i mnie wywaliło. Później jak widać logowanie i wyświetlenie auth.log.

Co do strace, na szybko zrobiłem tak (nawet nie wiem czy dobrze, ale nie mam teraz za wiele czasu na zastanawianie)

Kod: Zaznacz cały

$ sudo strace -p PID_VIRTUALBOXA -o PLIK

i jest tam duuużo różnych rzeczy (~7000 linii, ponad 600KB - a działało dosłownie kilka sekund - do momentu wylogowania). Nawet nie będę udawał, że coś z nich rozumiem, ale wkleję plik z logiem tutaj:
http://www.speedyshare.com/NwJwr/virtualbox

[Yampress]

Mnie coś takiego robił virtualbox, wylogowywał mnie albo resetował gdy miałem pomieszane różna pamięci RAM o różnym taktowaniu w komputerze i uruchamiałem na virtualboksie systemy 64 bitowe.