Lion: "Pseudo encoding 1886415973 is not supported&quot

Vine
#1

Hi Guys,

I’ve installed the Beta on Lion and am consistently getting some errors (see attached screenshot)

My Vine Server is running as the system server with the following options: /Library/Application Support/VineServer/OSXvnc-server -rfbport 5900 -desktop Michels-iMac-2 -rfbauth /Library/Application Support/VineServer/.vinevncauth -systemserver 1 -alwaysshared -restartonuserswitch N -unicodekeyboard 0 -keyboardloading y -pressmodsforkeys y -eventtap 3 -eventsource 2 -swapbuttons -rendezvous n -maxdepth 8

When I encounter this error (which is basically about once an hour…) I have to kill the process and let it respawn. I am then able to login a couple of times until it goes bang again. I have tested this with different VNC clients and all sorts of encoding settings, but it just doesn’t go away. I have also tested this with different bit depths, although I usually stick to “maxdepth 8” because anything else is unuseable over the interwebs.

My VNC client also crashes regularly (I have tried RealVNC and TightVNC, and they both do it) so I suspect another issue with the server.

Have you seen this issue before?

Many thanks,

Mich

#2

The other thing I get is the connection dying in the middle of an active session with the below error.

How can I debug this stuff? Can I enable a debug log to be written for the server somewhere that I can upload here?

Cheers,

Mich

#3

Thought I’d show a bit of initiative… Found the log! ;]

I think the issue might hinge around this:

rfbProcessClientNormalMessage: read: Operation timed out
2012-06-08 11:03:19.329 OSXvnc-server[18695:340b] Client 192.168.xx.xx disconnected

2012-06-08 10:56:33.520 OSXvnc-server[18695:b07] Main Bundle: /Library/Application Support/VineServer
2012-06-08 10:56:33.521 OSXvnc-server[18695:b07] Loading Bundle /Library/Application Support/VineServer/Resources/TigerBundle.bundle
2012-06-08 10:56:33.692 OSXvnc-server[18695:b07] Running in Little Endian
2012-06-08 10:56:33.694 OSXvnc-server[18695:b07] Waiting for clients
2012-06-08 10:56:33.694 OSXvnc-server[18695:3103] Using Private Event Source
2012-06-08 10:56:33.695 OSXvnc-server[18695:3103] Using Smart Event Tap – HID for console user
2012-06-08 10:56:33.696 OSXvnc-server[18695:3103] Started Listener Thread on port 5900
2012-06-08 10:56:38.188 OSXvnc-server[18695:340b] Protocol version 3.8
2012-06-08 10:56:41.968 OSXvnc-server[18695:340b] ENCODING: ZRLE for client 192.168.xx.xx
2012-06-08 10:56:41.978 OSXvnc-server[18695:340b] Using compression level 9 for client 192.168.xx.xx
2012-06-08 10:56:41.979 OSXvnc-server[18695:340b] Enabling Cursor Shape protocol extension for client 192.168.xx.xx
2012-06-08 10:56:41.979 OSXvnc-server[18695:340b] Enabling Cursor Position protocol extension for client 192.168.xx.xx
2012-06-08 10:56:41.980 OSXvnc-server[18695:340b] Enabling LastRect protocol extension for client 192.168.xx.xx
2012-06-08 10:56:41.980 OSXvnc-server[18695:340b] Enabling Dynamic Desktop Sizing for client 192.168.xx.xx
2012-06-08 10:56:41.980 OSXvnc-server[18695:340b] Using jpeg image quality level 6 for client 192.168.xx.xx
2012-06-08 10:56:41.981 OSXvnc-server[18695:340b] ENCODING: ZRLE for client 192.168.xx.xx
2012-06-08 10:56:41.981 OSXvnc-server[18695:340b] Using compression level 9 for client 192.168.xx.xx
2012-06-08 10:56:41.982 OSXvnc-server[18695:340b] Enabling Cursor Shape protocol extension for client 192.168.xx.xx
2012-06-08 10:56:41.982 OSXvnc-server[18695:340b] Enabling Cursor Position protocol extension for client 192.168.xx.xx
2012-06-08 10:56:41.982 OSXvnc-server[18695:340b] Enabling LastRect protocol extension for client 192.168.xx.xx
2012-06-08 10:56:41.983 OSXvnc-server[18695:340b] Enabling Dynamic Desktop Sizing for client 192.168.xx.xx
2012-06-08 10:56:41.983 OSXvnc-server[18695:340b] Using jpeg image quality level 6 for client 192.168.xx.xx
2012-06-08 10:56:42.110 OSXvnc-server[18695:340b] ENCODING: ZRLE for client 192.168.xx.xx
2012-06-08 10:56:42.111 OSXvnc-server[18695:340b] Using compression level 9 for client 192.168.xx.xx
2012-06-08 10:56:42.111 OSXvnc-server[18695:340b] Enabling Cursor Shape protocol extension for client 192.168.xx.xx
2012-06-08 10:56:42.111 OSXvnc-server[18695:340b] Enabling Cursor Position protocol extension for client 192.168.xx.xx
2012-06-08 10:56:42.112 OSXvnc-server[18695:340b] Enabling LastRect protocol extension for client 192.168.xx.xx
2012-06-08 10:56:42.122 OSXvnc-server[18695:340b] Enabling Dynamic Desktop Sizing for client 192.168.xx.xx
2012-06-08 10:56:42.122 OSXvnc-server[18695:340b] Using jpeg image quality level 6 for client 192.168.xx.xx
2012-06-08 10:56:42.123 OSXvnc-server[18695:340b] Pixel format for client 192.168.xx.xx:
2012-06-08 10:56:42.124 OSXvnc-server[18695:340b] 8 bpp, depth 8
2012-06-08 10:56:42.124 OSXvnc-server[18695:340b] true colour: max r 7 g 7 b 3, shift r 0 g 3 b 6
2012-06-08 10:56:42.124 OSXvnc-server[18695:340b] Client Connected - Registering Screen Update Notification
2012-06-08 11:03:19.317 OSXvnc-server[18695:340b] rfbProcessClientNormalMessage: read: Operation timed out
2012-06-08 11:03:19.329 OSXvnc-server[18695:340b] Client 192.168.xx.xx disconnected
2012-06-08 11:03:19.330 OSXvnc-server[18695:340b] Statistics:
2012-06-08 11:03:19.330 OSXvnc-server[18695:340b] key events received 18, pointer events 1112
2012-06-08 11:03:19.331 OSXvnc-server[18695:340b] framebuffer updates 112, rectangles 187, bytes 2104081
2012-06-08 11:03:19.331 OSXvnc-server[18695:340b] Cursor Shape Updates rectangles 16, bytes 7547
2012-06-08 11:03:19.331 OSXvnc-server[18695:340b] Cursor Position Updates rectangles 16, bytes 192
2012-06-08 11:03:19.332 OSXvnc-server[18695:340b] ZRLE rectangles 155, bytes 2096342
2012-06-08 11:03:19.348 OSXvnc-server[18695:340b] raw bytes equivalent 67383146, compression ratio 32.024977
2012-06-08 11:03:19.371 OSXvnc-server[18695:b07] UnRegistering Screen Update Notification - waiting for clients
2012-06-08 11:05:05.545 OSXvnc-server[18713:b07] Arguments: -rfbport 5900 -desktop Michels-iMac-2 -rfbauth /Library/Application Support/VineServer/.vinevncauth -SystemServer 1 -alwaysshared -restartonuserswitch N -UnicodeKeyboard 0 -keyboardLoading Y -pressModsForKeys Y -EventTap 3 -EventSource 2 -swapButtons -rendezvous N -maxdepth 8

#4

Anyone?

#5

Well, it would appear that I have found out what was causing this issue. For this to make sense I need to give you the low-down on how my home network is setup, so bare with me please;

Vine Server is installed on my iMac running OSX Lion (10.7).
I access my network remotely via a SSL based VPN terminator. So all tunnels are done over SSL (HTTPS). I create a session to my SSL VPN Terminator and then open a ‘native’ VNC connection to the private IP of my iMac. The SSL Tunnel is capable of routing private addresses directly, so no NAT is involved.

The session timeout were set as follows on the SSL VPN gateway; Inactivity Timeout: 10mins, Overall session limit: 60mins.

I never noticed the session limits, because I never actually got logged out. I suppose that the SSL VPN client I use on my machine is intelligent enough to automatically reconnect when the timeout is reached, but it never alerted me, so it all ‘looked’ like one long continous session.

I have today changed the timeouts of the session and by ‘sheer coincidence’ everything started working!

I would however still recommend that this is looked at by one of you chaps. The reason is, if the TCP session dies, (because the SSL VPN Gateway terminates it), Vine Server also dies and needed to be restarted. This isn’t normal! Re-negotiating existing or recovering from ‘dead’ sessions shouldn’t have to involved restarting the service.

Later!

#6

mictho100,

Thanks for posting both the original question and then following-up with the solution. I wouldn’t have thought to ask about what turned out to be the issue.

There isn’t necessarily anything we can do from the Vine side about the crashing clients; that’s really up to RealVNC and Tight to fix. But we will look into why Vine Server ends up in an unusable state after a session times out, that shouldn’t happen.