(proc 564): (debug)vortex-support: file found at: C:\Program Files (x86)\VortexLibraryW32\bin\channel.dtd
(proc 564): (debug)vortex: init winsocket for windows
(proc 564): (debug)vortex-win32: winsock initialization ok
(proc 564): (debug)vortex-support: file found at: C:\Program Files (x86)\VortexLibraryW32\bin\channel.dtd
(proc 564): (debug)vortex-dtds: dtd file definition found at: C:\Program Files (x86)\VortexLibraryW32\bin\channel.dtd
(proc 564): (debug)vortex-support: file found at: C:\Program Files (x86)\VortexLibraryW32\bin\sasl.dtd
(proc 564): (debug)vortex-dtds: dtd file definition found at: C:\Program Files (x86)\VortexLibraryW32\bin\sasl.dtd
(proc 564): (debug)vortex-support: file found at: C:\Program Files (x86)\VortexLibraryW32\bin\xml-rpc-boot.dtd
(proc 564): (debug)vortex-dtds: dtd file definition found at: C:\Program Files (x86)\VortexLibraryW32\bin\xml-rpc-boot.dtd
(proc 564): (debug)vortex: select mechanism selected, reconfiguring current socket limit: soft_limit=1024 > 1024..
(proc 564): (warning)vortex: found select(2) I/O configured, which can handled up to 1023 fds, reconfigured process with that value
(proc 564): (debug)vortex: starting vortex reader..
(proc 564): (debug)vortex: starting vortex sequencer..
(proc 564): (debug)vortex-thread: called vortex_cond_wait from vortex_thread.c:802
(proc 564): (debug)vortex: starting vortex thread pool: (5 threads the pool have)..
(proc 564): (debug)vortex-thread-pool: creating thread pool threads=5
(proc 564): (debug)vortex-thread: called vortex_cond_wait from vortex_thread.c:802
(proc 564): (debug)vortex-thread-pool: thread from pool started
(proc 564): (debug)vortex-thread-pool: --> thread from pool waiting for jobs
(proc 564): (debug)vortex-thread: called vortex_cond_wait from vortex_thread.c:802
(proc 564): (debug)vortex-thread-pool: thread from pool started
(proc 564): (debug)vortex-thread-pool: --> thread from pool waiting for jobs
(proc 564): (debug)vortex-thread: called vortex_cond_wait from vortex_thread.c:802
(proc 564): (debug)vortex-thread-pool: thread from pool started
(proc 564): (debug)vortex-thread-pool: --> thread from pool waiting for jobs
(proc 564): (debug)vortex-thread: called vortex_cond_wait from vortex_thread.c:802
(proc 564): (debug)vortex-listener: invoking listener_new non-threaded mode
(proc 564): (debug)vortex-thread-pool: thread from pool started
(proc 564): (debug)vortex-thread-pool: --> thread from pool waiting for jobs
(proc 564): (debug)vortex-thread: called vortex_cond_wait from vortex_thread.c:802
(proc 564): (debug)vortex-thread-pool: thread from pool started
(proc 564): (debug)vortex-thread-pool: --> thread from pool waiting for jobs
(proc 564): (debug)vortex-thread: called vortex_cond_wait from vortex_thread.c:802
(proc 564): (debug)vortex-thread: finished vortex_cond_wait from vortex_thread.c:802
(proc 564): (debug)vortex-reader: new listener connection to be watched (668 --> 0.0.0.0:44001)
(proc 564): (debug)vortex-connection: invoking connection_new non-threaded mode
(proc 564): (debug)vortex-connection: executing connection new in blocking mode to 192.168.0.109:44001 id=2
(proc 564): (debug)vortex-channel: sending reply for message 0 (size: 14)

(proc 564): (debug)vortex-channel: mime headers size calculated for channel 0 was: 38
(proc 564): (debug)vortex-channel: new reply message to sent size (52) = msg size (14) + mime size (38)
(proc 564): (debug)vortex-channel: updating channel sending status to: msgno=0, rpyno=1, seqno=52, ansno=0..
(proc 564): (debug)vortex-connection: greetings sent, waiting for reply
(proc 564): (debug)vortex-thread: finished vortex_cond_wait from vortex_thread.c:802
(proc 564): (debug)vortex-sequencer: a new message to be sequenced..
(proc 564): (debug)vortex-connection: increased connection id=2 reference to 2 by (vortex sequencer)
(proc 564): (debug)vortex-sequencer: sequence operation: next seq no=0 message size=52 max seq no=4095 step=0
(proc 564): (debug)vortex-sequencer: the channel is not stalled, continue with sequencing ...
(proc 564): (debug)vortex-sequencer: sequencing next message: type=2, channel num=0, msgno=0, more=0, next seq=0 size=52 ansno=0
(proc 564): (debug)vortex-sequencer: frame built, send the frame directly
(proc 564): (debug)vortex-sequencer: Sending message, size (73) over connection id=2
(proc 564): (debug)vortex-frame-factory: bytes written: 73
(proc 564): (debug)vortex-frame-factory: write on socket request=73 written=73
(proc 564): (debug)vortex-channel: updating channel sending status to: msgno=0, rpyno=1, seqno=52, ansno=0..
(proc 564): (debug)vortex-channel: channel=0 unref called, ref count status after calling=1
(proc 564): (debug)vortex-sequencer: it seems the message was sent completely
(proc 564): (debug)vortex-connection: decreased connection id=2 reference count to 1 decreased by (vortex sequencer)
(proc 564): (debug)vortex-thread: called vortex_cond_wait from vortex_thread.c:802
(proc 564): (debug)vortex-frame-factory: line read from underlying transport: 'RPY 0 0 . 0 131'
(proc 564): (debug)vortex-frame-factory: from='get-next' allocating a new frame id=1
(proc 564): (debug)vortex-frame-factory: Frame received on channel 0, content type=application/beep+xml, transfer encoding=, payload size=93, mime content size=38
(proc 564): (debug)vortex-channel: updating channel receiving status to: msgno=0, rpyno=1, seqno=131, ansno=0..
(proc 564): (debug)vortex-greetings: greetings frame header specification is ok
(proc 564): (debug)vortex-connection: greetings received, process reply frame
(proc 564): (debug)vortex-connection: About to parse the following message: (size: 93) '<greeting>   <profile uri='http://www.replicus.com/profiles/cell_profile' /></greeting>'
(proc 564): (debug)vortex-connection: document not found in cache, parsing as usual
(proc 564): (debug)vortex-connection: storing xml document for future cache hit
(proc 564): (debug)vortex-connection: greetings parsed..
(proc 564): (debug)vortex-frame-factory: deallocating frame id=1
(proc 564): (debug)vortex-connection: new connection created to 192.168.0.109:44001
(proc 564): (debug)vortex-connection: setting connection as non-blocking
(proc 564): (debug)vortex-connection: increased connection id=2 reference to 2 by vortex reader (process)
(proc 564): (debug)vortex-connection: locking pool channel..
(proc 564): (debug)vortex-connection: returning as next channel to create: 3
(proc 564): (debug)vortex-channel: channel num returned=3
(proc 564): (debug)vortex-channel: mime headers size calculated for channel 0 was: 38
(proc 564): (debug)vortex-channel: new message to sent, type=1 channel=0 msgno=1 size (136) = msg size (98) + mime size (38)
(proc 564): (debug)vortex-channel: updating channel sending status to: msgno=1, rpyno=1, seqno=188, ansno=0..
(proc 564): (debug)vortex-channel: getting reply at wait reply from the queue
(proc 564): (debug)vortex-thread: finished vortex_cond_wait from vortex_thread.c:802(proc 564): (debug)vortex-thread: 
called vortex_cond_timedwait from vortex_thread.c:856(proc 564): 
(debug)vortex-sequencer: a new message to be sequenced..
(proc 564): (debug)vortex-connection: increased connection id=2 reference to 3 by (vortex sequencer)
(proc 564): (debug)vortex-sequencer: sequence operation: next seq no=52 message size=136 max seq no=4095 step=0
(proc 564): (debug)vortex-sequencer: the channel is not stalled, continue with sequencing ...
(proc 564): (debug)vortex-sequencer: sequencing next message: type=1, channel num=0, msgno=1, more=0, next seq=52 size=136 ansno=0
(proc 564): (debug)vortex-sequencer: frame built, send the frame directly
(proc 564): (debug)vortex-sequencer: Sending message, size (159) over connection id=2
(proc 564): (debug)vortex-frame-factory: bytes written: 159
(proc 564): (debug)vortex-frame-factory: write on socket request=159 written=159
(proc 564): (debug)vortex-channel: channel=0 unref called, ref count status after calling=1
(proc 564): (debug)vortex-sequencer: it seems the message was sent completely
(proc 564): (debug)vortex-connection: decreased connection id=2 reference count to 2 decreased by (vortex sequencer)
(proc 564): (debug)vortex-thread: called vortex_cond_wait from vortex_thread.c:802
(proc 564): (debug)vortex-reader: new connection to be watched (1156)
(proc 564): (debug)vortex-reader: something to read
(proc 564): (debug)vortex-frame-factory: line read from underlying transport: 'RPY 0 1 . 131 103'
(proc 564): (debug)vortex-frame-factory: from='get-next' allocating a new frame id=2
(proc 564): (debug)vortex-frame-factory: Frame received on channel 0, content type=application/beep+xml, transfer encoding=, payload size=65, mime content size=38
(proc 564): (debug)vortex-reader: passed initial accept stage
(proc 564): (debug)vortex-reader: passed connection existence stage
(proc 564): (debug)vortex-reader: passed message number checking stage
(proc 564): (debug)vortex-channel: setting pending reply processed=0 for channel=0
(proc 564): (debug)vortex-channel: updating channel receiving status to: msgno=0, rpyno=2, seqno=234, ansno=0..
(proc 564): (debug)vortex-reader: passed channel update status due to frame received stage
(proc 564): (debug)vortex-channel: not updated, already not consumed half of window advertised: 234 < (4096 / 2)
(proc 564): (debug)vortex-reader: passed frame checking stage
(proc 564): (debug)vortex-channel: found a frame that were waited by a thread: reply no='1' over channel=0
(proc 564): (debug)vortex-reader: frame delivered on second (channel) level handler channel
(proc 564): (debug)vortex-thread: finished vortex_cond_timedwait (result: 1) from vortex_thread.c:856
(proc 564): (debug)vortex-channel: received reply, freeing wait reply object
(proc 564): (debug)vortex-channel: received reply to start message
(proc 564): (debug)vortex-channel: doing validate for start reply msg: '<profile uri='http://www.replicus.com/profiles/cell_profile' />'
(proc 564): (debug)vortex-channel: channel start reply validation stage finished
(proc 564): (debug)vortex-channel-pool: channels added 1 to the pool id=1
(proc 564): (debug)vortex-connection: unlocking pool channel..
(proc 564): (debug)vortex-channel-pool: channel pool created id=1 over connection id=2
(proc 564): (debug)vortex-channel-pool: getting new channel before locking..
(proc 564): (debug)vortex-connection: locking pool channel..
(proc 564): (debug)vortex-channel-pool: getting next channel to use
(proc 564): (debug)vortex-channel-pool: channel id=3 is ready to be used, flagged as busy
(proc 564): (debug)vortex-channel-pool: returning channel id=3 for pool id=1 connection id=2
[get_next_ready] (connection=2 actual pool=1 size: 1 channels [3(B)]
(proc 564): (debug)vortex-connection: unlocking pool channel..
(proc 564): (debug)vortex-channel: mime headers size calculated for channel 3 was: 0
(proc 564): (debug)vortex-channel: new message to sent, type=1 channel=3 msgno=0 size (120) = msg size (120) + mime size (0)
(proc 564): (debug)vortex-channel: updating channel sending status to: msgno=0, rpyno=0, seqno=120, ansno=0..
(proc 564): (debug)vortex-thread: finished vortex_cond_wait from vortex_thread.c:802
(proc 564): (debug)vortex-sequencer: a new message to be sequenced..
(proc 564): (debug)vortex-connection: increased connection id=2 reference to 3 by (vortex sequencer)
(proc 564): (debug)vortex-sequencer: sequence operation: next seq no=0 message size=120 max seq no=4095 step=0
(proc 564): (debug)vortex-sequencer: the channel is not stalled, continue with sequencing ...
(proc 564): (debug)vortex-sequencer: sequencing next message: type=1, channel num=3, msgno=0, more=0, next seq=0 size=120 ansno=0
(proc 564): (debug)vortex-sequencer: frame built, send the frame directly
(proc 564): (debug)vortex-sequencer: Sending message, size (142) over connection id=2
(proc 564): (debug)vortex-frame-factory: bytes written: 142
(proc 564): (debug)vortex-frame-factory: write on socket request=142 written=142
(proc 564): (debug)vortex-channel: channel=3 unref called, ref count status after calling=1
(proc 564): (debug)vortex-sequencer: it seems the message was sent completely
(proc 564): (debug)vortex-connection: decreased connection id=2 reference count to 2 decreased by (vortex sequencer)
(proc 564): (debug)vortex-thread: called vortex_cond_wait from vortex_thread.c:802
(proc 564): (debug)vortex-reader: something to read
(proc 564): (debug)vortex-frame-factory: line read from underlying transport: 'ANS 3 0 * 0 4096 0'
(proc 564): (debug)vortex-frame-factory: from='get-next' allocating a new frame id=3
(proc 564): (debug)vortex-frame-factory: (ok message) received a frame fragment (expected: 4101 read: 1436 remaining: 2665), storing into this connection id=2
(proc 564): (debug)vortex-reader: something to read
(proc 564): (debug)vortex-frame-factory: line read from underlying transport: 'p'
(proc 564): (critical) vortex-frame-factory: no line definition found for frame, over connection id=2, bytes read: 99, line: 
'p'
, closing session
(proc 564): (debug)vortex-connection: flagging the connection as non-connected
(proc 564): (debug)vortex-connection: closing connection id=2 to 192.168.0.109:44001
(proc 564): (debug)vortex-connection: closing session id=2 and set to be not connected
(proc 564): (debug)vortex-connection: decreased connection id=2 reference count to 1 decreased by vortex reader (process)
(proc 564): (debug)vortex: shutting down vortex library
(proc 564): (debug)vortex-sequencer: stopping vortex sequencer
(proc 564): (debug)vortex-thread: called vortex_cond_wait from vortex_thread.c:802
(proc 564): (debug)vortex-thread: finished vortex_cond_wait from vortex_thread.c:802
(proc 564): (debug)vortex-sequencer: vortex sequencer stopped..
(proc 564): (debug)vortex-thread: finished vortex_cond_wait from vortex_thread.c:802
(proc 564): (debug)vortex-sequencer: vortex sequencer completely stoped
(proc 564): (debug)vortex-reader: stopping vortex reader ..
(proc 564): (debug)vortex-reader: pushing data stop signal..
(proc 564): (debug)vortex-reader: signal sent reader ..
(proc 564): (debug)vortex-thread: called vortex_cond_wait from vortex_thread.c:802
(proc 564): (debug)vortex-connection: decreased connection id=1 reference count to 0 decreased by vortex reader
(proc 564): (debug)vortex-connection: freeing connection id=1
(proc 564): (debug)vortex-connection: freeing connection custom data holder id=1
(proc 564): (debug)vortex-connection: freeing connection message id=1
(proc 564): (debug)vortex-connection: freeing connection host id=1
(proc 564): (debug)vortex-connection: freeing connection port id=1
(proc 564): (debug)vortex-connection: freeing connection profiles id=1
(proc 564): (debug)vortex-connection: freeing connection channels id=1
(proc 564): (debug)vortex-connection: freeing connection channel pools id=1
(proc 564): (debug)vortex-connection: freeing connection channel mutex id=1
(proc 564): (debug)vortex-connection: freeing connection reference counting mutex id=1
(proc 564): (debug)vortex-connection: freeing connection operational mutex id=1
(proc 564): (debug)vortex-connection: freeing connection channel pools mutex id=1
(proc 564): (debug)vortex-connection: freeing/terminating connection id=1
(proc 564): (debug)vortex-connection: session socket closed
(proc 564): (debug)vortex-thread: finished vortex_cond_wait from vortex_thread.c:802
(proc 564): (debug)vortex-reader: vortex reader process stopped
(proc 564): (debug)vortex-profiles: stopping vortex profiles module
(proc 564): (debug)vortex: shutting down WinSock2(tm) API
(proc 564): (debug)vortex: shutting down vortex xml subsystem
(proc 564): (debug)vortex: shutting down xml library
(proc 564): (debug)vortex: unlocking vortex listeners
(proc 564): (debug)vortex-listener: (un)Locking listener..
(proc 564): (debug)vortex: vortex library stopped
(proc 564): (debug)vortex-thread-pool: stopping thread pool..
(proc 564): (debug)vortex-thread-pool: pushing beacon to stop thread from the pool..
(proc 564): (debug)vortex-thread-pool: pushing beacon to stop thread from the pool..
(proc 564): (debug)vortex-thread: finished vortex_cond_wait from vortex_thread.c:802
(proc 564): (debug)vortex-thread-pool: --> thread from pool stoping, found finish beacon
(proc 564): (debug)vortex-thread-pool: pushing beacon to stop thread from the pool..
(proc 564): (debug)vortex-thread: finished vortex_cond_wait from vortex_thread.c:802
(proc 564): (debug)vortex-thread-pool: --> thread from pool stoping, found finish beacon
(proc 564): (debug)vortex-thread-pool: pushing beacon to stop thread from the pool..
(proc 564): (debug)vortex-thread: finished vortex_cond_wait from vortex_thread.c:802
(proc 564): (debug)vortex-thread-pool: --> thread from pool stoping, found finish beacon
(proc 564): (debug)vortex-thread-pool: pushing beacon to stop thread from the pool..
(proc 564): (debug)vortex-thread: finished vortex_cond_wait from vortex_thread.c:802
(proc 564): (debug)vortex-thread-pool: --> thread from pool stoping, found finish beacon
(proc 564): (debug)vortex-thread: finished vortex_cond_wait from vortex_thread.c:802
(proc 564): (debug)vortex-thread-pool: --> thread from pool stoping, found finish beacon
(proc 564): (debug)vortex-thread-pool: thread pool is stopped..
(proc 564): (debug)vortex-frame-factory: deallocating frame id=2