Thanks for the reply... I'm sure the server-side logging is not the problem, the same effects are seen without the server writing all this information. Even if we only do client-side logging we still get the same results. I think we ruled out the database being a bottleneck by using the Actionscript-adapter on the server, which again gets us the same results. That is why I still think our bottleneck is in the RTMP/AMF communication and this is backed-up by the server-side logging.
Does anybody have experience with performance issues using RTMP/AMF? Greetings, Rogier --- In [email protected], "seth_hodgson" <[EMAIL PROTECTED]> wrote: > > Hi Rogier, > > You shouldn't place any weight on timing metrics captured while the > server has debug level logging enabled. The IO overhead to output > all the debug level logging to a console or log file will > drastically skew your numbers. > > I'd recommend running your test wihtout debug level logging and > capturing timing data at the client; say by keeping track of deltas > between each message/update received. > > Be careful that your own logging at the server isn't acting as a > bottleneck, and the database could definitely become a bottleneck at > high rates of updates. > > Seth > > --- In [email protected], "Rogier Oorburg" > <rogier_oorburg@> wrote: > > > > Hi Jeff, > > > > Thank you for your reply. > > I have gathered some more logging information in this mail. To > maybe > > make clearer where all the time is spent. > > Is there a way to set the packet-size (or 'chunk' size?) to be > able > > to send rather large messages (or a lot of small ones) more > > efficiently? Or could there be some other cause for this slow > > communication between client and server? > > > > Thanks, hope these loggings shed some light on the situation... > > > > [LCDS] 08:06:40.406 [DEBUG] [SocketServer.General] Thread[my-nio- > > server-WorkerThread-2,5,main] Connection '9194103' starting a read. > > [LCDS] 08:06:40.406 [DEBUG] [Protocol.RTMP] Thread[my-nio-server- > > WorkerThread-2,5,main] chunk input stream processing 1 bytes. > > [LCDS] 08:06:40.406 [DEBUG] [Protocol.RTMP] Thread[my-nio-server- > > WorkerThread-2,5,main] chunk input stream processing 7 bytes. > > [LCDS] 08:06:40.406 [DEBUG] [Protocol.RTMP] Thread[my-nio-server- > > WorkerThread-2,5,main] chunk input stream processing 128 bytes. > > [LCDS] 08:06:40.406 [DEBUG] [Protocol.RTMP] Thread[my-nio-server- > > WorkerThread-2,5,main] chunk input stream processing 1 bytes. > > [LCDS] 08:06:40.406 [DEBUG] [Protocol.RTMP] Thread[my-nio-server- > > WorkerThread-2,5,main] chunk input stream processing 128 bytes. > > [LCDS] 08:06:40.406 [DEBUG] [Protocol.RTMP] Thread[my-nio-server- > > WorkerThread-2,5,main] chunk input stream processing 1 bytes. > > [LCDS] 08:06:40.406 [DEBUG] [Protocol.RTMP] Thread[my-nio-server- > > WorkerThread-2,5,main] chunk input stream processing 128 bytes. > > > > ... > > > > [LCDS] 08:06:40.421 [DEBUG] [Protocol.RTMP] Thread[my-nio-server- > > WorkerThread-2,5,main] chunk input stream processing 1 bytes. > > [LCDS] 08:06:40.421 [DEBUG] [Protocol.RTMP] Thread[my-nio-server- > > WorkerThread-2,5,main] chunk input stream processing 57 bytes. > > [LCDS] 08:06:40.421 [DEBUG] [SocketServer.General] Thread[my-nio- > > server-WorkerThread-2,5,main] registering read interest for > > Connection '9194103'. > > [LCDS] 08:06:40.421 [DEBUG] [SocketServer.General] Thread[my-nio- > > server-WorkerThread-2,5,main] Connection '9194103' finished a > read. > > 8192 bytes were read. > > [LCDS] 08:06:40.421 [DEBUG] [SocketServer.General] Thread[my-nio- > > server-Reactor1,5,main] unregistering read interest for > > Connection '9194103'. > > [LCDS] 08:06:40.421 [DEBUG] [SocketServer.General] Thread[my-nio- > > server-WorkerThread-2,5,main] Connection '9194103' starting a read. > > [LCDS] 08:06:40.421 [DEBUG] [Protocol.RTMP] Thread[my-nio-server- > > WorkerThread-2,5,main] chunk input stream processing 71 bytes. > > [LCDS] 08:06:40.421 [DEBUG] [Protocol.RTMP] Thread[my-nio-server- > > WorkerThread-2,5,main] chunk input stream processing 1 bytes. > > [LCDS] 08:06:40.421 [DEBUG] [Protocol.RTMP] Thread[my-nio-server- > > WorkerThread-2,5,main] chunk input stream processing 128 bytes. > > ... > > [LCDS] 08:06:40.468 [DEBUG] [Protocol.RTMP] Thread[my-nio-server- > > WorkerThread-2,5,main] chunk input stream processing 1 bytes. > > [LCDS] 08:06:40.468 [DEBUG] [Protocol.RTMP] Thread[my-nio-server- > > WorkerThread-2,5,main] chunk input stream processing 64 bytes. > > [LCDS] 08:06:40.468 [DEBUG] [SocketServer.General] Thread[my-nio- > > server-WorkerThread-2,5,main] registering read interest for > > Connection '9194103'. > > [LCDS] 08:06:40.468 [DEBUG] [SocketServer.General] Thread[my-nio- > > server-WorkerThread-2,5,main] Connection '9194103' finished a > read. > > 8192 bytes were read. > > [LCDS] 08:06:40.468 [DEBUG] [SocketServer.General] Thread[my-nio- > > server-Reactor1,5,main] unregistering read interest for > > Connection '9194103'. > > [LCDS] 08:06:40.468 [DEBUG] [SocketServer.General] Thread[my-nio- > > server-WorkerThread-2,5,main] Connection '9194103' starting a read. > > [LCDS] 08:06:40.468 [DEBUG] [Protocol.RTMP] Thread[my-nio-server- > > WorkerThread-2,5,main] chunk input stream processing 64 bytes. > > [LCDS] 08:06:40.468 [DEBUG] [Protocol.RTMP] Thread[my-nio-server- > > WorkerThread-2,5,main] chunk input stream processing 1 bytes. > > [LCDS] 08:06:40.468 [DEBUG] [Protocol.RTMP] Thread[my-nio-server- > > WorkerThread-2,5,main] chunk input stream processing 128 bytes. > > ... > > [LCDS] 08:06:40.468 [DEBUG] [Protocol.RTMP] Thread[my-nio-server- > > WorkerThread-2,5,main] chunk input stream processing 0 bytes. > > [LCDS] 08:06:40.468 [DEBUG] [SocketServer.General] Thread[my-nio- > > server-WorkerThread-2,5,main] registering read interest for > > Connection '9194103'. > > [LCDS] 08:06:40.468 [DEBUG] [SocketServer.General] Thread[my-nio- > > server-WorkerThread-2,5,main] Connection '9194103' finished a > read. > > 4321 bytes were read. > > [LCDS] 08:06:40.515 [DEBUG] [SocketServer.General] Thread[my-nio- > > server-Reactor1,5,main] unregistering read interest for > > Connection '9194103'. > > [LCDS] 08:06:40.515 [DEBUG] [SocketServer.General] Thread[my-nio- > > server-WorkerThread-2,5,main] Connection '9194103' starting a read. > > [LCDS] 08:06:40.515 [DEBUG] [Protocol.RTMP] Thread[my-nio-server- > > WorkerThread-2,5,main] chunk input stream processing 1 bytes. > > [LCDS] 08:06:40.515 [DEBUG] [Protocol.RTMP] Thread[my-nio-server- > > WorkerThread-2,5,main] chunk input stream processing 128 bytes. > > ... > > etc. > > etc. > > [LCDS] 08:06:40.671 [DEBUG] [SocketServer.General] Thread[my-nio- > > server-WorkerThread-2,5,main] Connection '9194103' finished a > read. > > 4321 bytes were read. > > [LCDS] 08:06:40.718 [DEBUG] [SocketServer.General] Thread[my-nio- > > server-Reactor1,5,main] unregistering read interest for > > Connection '9194103'. > > [LCDS] 08:06:40.718 [DEBUG] [SocketServer.General] Thread[my-nio- > > server-WorkerThread-2,5,main] Connection '9194103' starting a read. > > [LCDS] 08:06:40.718 [DEBUG] [Protocol.RTMP] Thread[my-nio-server- > > WorkerThread-2,5,main] chunk input stream processing 1 bytes. > > [LCDS] 08:06:40.718 [DEBUG] [Protocol.RTMP] Thread[my-nio-server- > > WorkerThread-2,5,main] chunk input stream processing 97 bytes. > > [LCDS] 08:06:40.718 [DEBUG] [Endpoint.RTMP] Thread[my-nio-server- > > WorkerThread-2,5,main] received message TCMessage [ Type: > MsgCmdEx, > > Stream ID: 0] > > [LCDS] 08:06:40.890 [DEBUG] [Endpoint.RTMP] Deserializing AMF/RTMP > > request > > >
