Sun Feb 18 23:12:06 2007 us=421411 ACK reliable_can_send active=1 current=1 : [1] 0
Sun Feb 18 23:12:06 2007 us=421433 ACK reliable_send ID 0 (size=4 to=2)
Sun Feb 18 23:12:06 2007 us=421456 ACK write ID 0 (ack->len=1, n=1)
Sun Feb 18 23:12:06 2007 us=421476 Reliable -> TCP/UDP
Sun Feb 18 23:12:06 2007 us=421498 ACK reliable_send_timeout 2 [1] 0
Sun Feb 18 23:12:06 2007 us=421520 TLS: tls_process: timeout set to 2
Sun Feb 18 23:12:06 2007 us=421560 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=c003ab32 7ed065d8, stored-sid=00000000 00000000, stored-ip=[undef]
Sun Feb 18 23:12:06 2007 us=421604 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]
Sun Feb 18 23:12:06 2007 us=421639 WE_CTL n=0 ev=0x00469984 rwflags=0x0001 arg=0x00453560
Sun Feb 18 23:12:06 2007 us=421675 STREAM: SET NEXT, buf=[228,0] next=[228,1592] len=-1 maxlen=1592
Sun Feb 18 23:12:06 2007 us=421697 STREAM: GET NEXT len=1592
Sun Feb 18 23:12:06 2007 us=421736 WIN32 I/O: Socket Receive queued [1592]
Sun Feb 18 23:12:06 2007 us=421761 WE_CTL n=1 ev=0x009e288c rwflags=0x0003 arg=0x00453558
Sun Feb 18 23:12:06 2007 us=421796 I/O WAIT T?|T?|SRQ|SW1 [1/10976]
Sun Feb 18 23:12:06 2007 us=421820 WE_WAIT enter n=3 to=1011
Sun Feb 18 23:12:06 2007 us=421843 [0] ev=0x00000758 rwflags=0x0001 arg=0x00453560
Sun Feb 18 23:12:06 2007 us=421867 [1] ev=0x0000074c rwflags=0x0002 arg=0x00453558
Sun Feb 18 23:12:06 2007 us=421891 [2] ev=0x00000750 rwflags=0x0001 arg=0x00453558
Sun Feb 18 23:12:06 2007 us=421923 WE_WAIT leave [1,0] rwflags=0x0002 arg=0x00453558
Sun Feb 18 23:12:06 2007 us=433195  event_wait returned 1
Sun Feb 18 23:12:06 2007 us=433227 I/O WAIT status=0x0002
Sun Feb 18 23:12:06 2007 us=433289 TCPv4_CLIENT WRITE [26] to 125.209.65.208:1194: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 sid=36cab580 d4ffbb25 [ 0 sid=37086d0e 5d19c336 ] pid=0 DATA 
Sun Feb 18 23:12:06 2007 us=433317 STREAM: WRITE 26 offset=14
Sun Feb 18 23:12:06 2007 us=433340 WIN32 I/O: Socket Completion non-queued success [16]
Sun Feb 18 23:12:06 2007 us=433511 WIN32 I/O: Socket Send immediate return [28,28]
Sun Feb 18 23:12:06 2007 us=433538 TCPv4_CLIENT write returned 28
Sun Feb 18 23:12:06 2007 us=433590 TLS: tls_multi_process: i=0 state=S_PRE_START, mysid=36cab580 d4ffbb25, stored-sid=37086d0e 5d19c336, stored-ip=125.209.65.208:1194
Sun Feb 18 23:12:06 2007 us=433622 TLS: tls_process: chg=0 ks=S_PRE_START lame=S_UNDEF to_link->len=0 wakeup=604800
Sun Feb 18 23:12:06 2007 us=433651 ACK reliable_can_send active=1 current=0 : [1] 0
Sun Feb 18 23:12:06 2007 us=433672 Incoming Ciphertext -> TLS
Sun Feb 18 23:12:06 2007 us=433762 TLS: tls_process: chg=1 ks=S_PRE_START lame=S_UNDEF to_link->len=0 wakeup=604800
Sun Feb 18 23:12:06 2007 us=433793 ACK reliable_can_send active=1 current=0 : [1] 0
Sun Feb 18 23:12:06 2007 us=433877 ACK reliable_send_timeout 2 [1] 0
Sun Feb 18 23:12:06 2007 us=433901 TLS: tls_process: timeout set to 2
Sun Feb 18 23:12:06 2007 us=433943 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=c003ab32 7ed065d8, stored-sid=00000000 00000000, stored-ip=[undef]
Sun Feb 18 23:12:06 2007 us=438578 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]
Sun Feb 18 23:12:06 2007 us=438621 WE_CTL n=0 ev=0x00469984 rwflags=0x0001 arg=0x00453560
Sun Feb 18 23:12:06 2007 us=438649 STREAM: SET NEXT, buf=[228,0] next=[228,1592] len=-1 maxlen=1592
Sun Feb 18 23:12:06 2007 us=438675 WE_CTL n=1 ev=0x009e288c rwflags=0x0001 arg=0x00453558
Sun Feb 18 23:12:06 2007 us=438710 I/O WAIT T?|T?|SRQ|Sw1 [1/10976]
Sun Feb 18 23:12:06 2007 us=438978 WE_WAIT enter n=2 to=1011
Sun Feb 18 23:12:06 2007 us=439004 [0] ev=0x00000758 rwflags=0x0001 arg=0x00453560
Sun Feb 18 23:12:06 2007 us=439028 [1] ev=0x00000750 rwflags=0x0001 arg=0x00453558
Sun Feb 18 23:12:06 2007 us=929657 WE_WAIT leave rwflags=0x0001 arg=0x00453558
Sun Feb 18 23:12:06 2007 us=929707  event_wait returned 1
Sun Feb 18 23:12:06 2007 us=929730 I/O WAIT status=0x0001
Sun Feb 18 23:12:06 2007 us=929761 WIN32 I/O: Socket Completion success [28]
Sun Feb 18 23:12:06 2007 us=929782 STREAM: ADD length_added=28
Sun Feb 18 23:12:06 2007 us=929805 STREAM: ADD returned TRUE, buf_len=26, residual_len=0
Sun Feb 18 23:12:06 2007 us=929827 STREAM: GET FINAL len=26
Sun Feb 18 23:12:06 2007 us=929845 STREAM: RESET
Sun Feb 18 23:12:06 2007 us=929866 TCPv4_CLIENT read returned 26
Sun Feb 18 23:12:06 2007 us=929929 TCPv4_CLIENT READ [26] from 125.209.65.208:1194: P_CONTROL_HARD_RESET_SERVER_V2 kid=0 sid=37086d0e 5d19c336 [ 0 sid=36cab580 d4ffbb25 ] pid=0 DATA 
Sun Feb 18 23:12:06 2007 us=929963 TLS: control channel, op=P_CONTROL_HARD_RESET_SERVER_V2, IP=125.209.65.208:1194
Sun Feb 18 23:12:06 2007 us=930025 TLS: initial packet test, i=0 state=S_PRE_START, mysid=36cab580 d4ffbb25, rec-sid=37086d0e 5d19c336, rec-ip=125.209.65.208:1194, stored-sid=37086d0e 5d19c336, stored-ip=125.209.65.208:1194
Sun Feb 18 23:12:06 2007 us=930063 TLS: found match, session[0], sid=37086d0e 5d19c336
Sun Feb 18 23:12:06 2007 us=930095 TLS: received control channel packet s#=0 sid=37086d0e 5d19c336
Sun Feb 18 23:12:06 2007 us=930119 ACK received for pid 0, deleting from send buffer
Sun Feb 18 23:12:06 2007 us=930141 ACK read ID 0 (buf->len=0)
Sun Feb 18 23:12:06 2007 us=930164 ACK 0 is a replay: [1]
Sun Feb 18 23:12:06 2007 us=930185 ACK acknowledge ID 0 (ack->len=1)
Sun Feb 18 23:12:06 2007 us=930217 TIMER: coarse timer wakeup 1 seconds
Sun Feb 18 23:12:06 2007 us=930269 TLS: tls_multi_process: i=0 state=S_PRE_START, mysid=36cab580 d4ffbb25, stored-sid=37086d0e 5d19c336, stored-ip=125.209.65.208:1194
Sun Feb 18 23:12:06 2007 us=936366 TLS: tls_process: chg=0 ks=S_PRE_START lame=S_UNDEF to_link->len=0 wakeup=604800
Sun Feb 18 23:12:06 2007 us=936401 ACK reliable_can_send active=0 current=0 : [1]
Sun Feb 18 23:12:06 2007 us=936471 ACK write ID 0 (ack->len=1, n=1)
Sun Feb 18 23:12:06 2007 us=936494 Dedicated ACK -> TCP/UDP
Sun Feb 18 23:12:06 2007 us=936517 ACK reliable_send_timeout 604800 [1]
Sun Feb 18 23:12:06 2007 us=936539 TLS: tls_process: timeout set to 59
Sun Feb 18 23:12:06 2007 us=936582 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=c003ab32 7ed065d8, stored-sid=00000000 00000000, stored-ip=[undef]
Sun Feb 18 23:12:06 2007 us=936627 TLS: tls_multi_procSun Feb 18 23:12:06 2007 us=936662 WE_CTL n=0 ev=0x00469984 rwflags=0x0001 arg=0x00453560
ess: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]
Sun Feb 18 23:12:06 2007 us=936690 STREAM: SET NEXT, buf=[228,0] next=[228,1592] len=-1 maxlen=1592
Sun Feb 18 23:12:06 2007 us=936713 STREAM: GET NEXT len=1592
Sun Feb 18 23:12:06 2007 us=936752 WIN32 I/O: Socket Receive queued [1592]
Sun Feb 18 23:12:06 2007 us=936777 WE_CTL n=1 ev=0x009e288c rwflags=0x0003 arg=0x00453558
Sun Feb 18 23:12:06 2007 us=936812 I/O WAIT T?|T?|SRQ|SW1 [1/10976]
Sun Feb 18 23:12:06 2007 us=936837 WE_WAIT enter n=3 to=1011
Sun Feb 18 23:12:06 2007 us=936860 [0] ev=0x00000758 rwflags=0x0001 arg=0x00453560
Sun Feb 18 23:12:06 2007 us=936884 [1] ev=0x0000074c rwflags=0x0002 arg=0x00453558
Sun Feb 18 23:12:06 2007 us=936907 [2] ev=0x00000750 rwflags=0x0001 arg=0x00453558
Sun Feb 18 23:12:06 2007 us=936940 WE_WAIT leave [1,0] rwflags=0x0002 arg=0x00453558
Sun Feb 18 23:12:06 2007 us=936963  event_wait returned 1
Sun Feb 18 23:12:06 2007 us=936983 I/O WAIT status=0x0002
Sun Feb 18 23:12:06 2007 us=937037 TCPv4_CLIENT WRITE [22] to 125.209.65.208:1194: P_ACK_V1 kid=0 sid=36cab580 d4ffbb25 [ 0 sid=37086d0e 5d19c336 ]
Sun Feb 18 23:12:06 2007 us=937062 STREAM: WRITE 22 offset=31
Sun Feb 18 23:12:06 2007 us=937084 WIN32 I/O: Socket Completion non-queued success [28]
Sun Feb 18 23:12:06 2007 us=937247 WIN32 I/O: Socket Send immediate return [24,24]
Sun Feb 18 23:12:06 2007 us=937274 TCPv4_CLIENT write returned 24
Sun Feb 18 23:12:06 2007 us=937333 TLS: tls_multi_process: i=0 state=S_PRE_START, mysid=36cab580 d4ffbb25, stored-sid=37086d0e 5d19c336, stored-ip=125.209.65.208:1194
Sun Feb 18 23:12:06 2007 us=937364 TLS: tls_process: chg=0 ks=S_PRE_START lame=S_UNDEF to_link->len=0 wakeup=604800
Sun Feb 18 23:12:06 2007 us=937385 STATE S_START
Sun Feb 18 23:12:06 2007 us=937407 ACK reliable_can_send active=0 current=0 : [1]
Sun Feb 18 23:12:06 2007 us=937514 STATE S_SENT_KEY
Sun Feb 18 23:12:06 2007 us=937599 BIO read tls_read_ciphertext 88 bytes
Sun Feb 18 23:12:06 2007 us=937624 ACK mark active outgoing ID 1
Sun Feb 18 23:12:06 2007 us=937644 Outgoing Ciphertext -> Reliable
Sun Feb 18 23:12:06 2007 us=937668 TLS: tls_process: chg=1 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800
Sun Feb 18 23:12:06 2007 us=937694 ACK reliable_can_send active=1 current=1 : [2] 1
Sun Feb 18 23:12:06 2007 us=937717 ACK reliable_send ID 1 (size=92 to=2)
Sun Feb 18 23:12:06 2007 us=937738 Reliable -> TCP/UDP
Sun Feb 18 23:12:06 2007 us=937760 ACK reliable_send_timeout 2 [2] 1
Sun Feb 18 23:12:06 2007 us=937781 TLS: tls_process: timeout set to 2
Sun Feb 18 23:12:06 2007 us=952785 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=c003ab32 7ed065d8, stored-sid=00000000 00000000, stored-ip=[undef]
Sun Feb 18 23:12:06 2007 us=952844 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]
Sun Feb 18 23:12:06 2007 us=952882 WE_CTL n=0 ev=0x00469984 rwflags=0x0001 arg=0x00453560
Sun Feb 18 23:12:06 2007 us=952911 STREAM: SET NEXT, buf=[228,0] next=[228,1592] len=-1 maxlen=1592
Sun Feb 18 23:12:06 2007 us=952937 WE_CTL n=1 ev=0x009e288c rwflags=0x0003 arg=0x00453558
Sun Feb 18 23:12:06 2007 us=952973 I/O WAIT T?|T?|SRQ|SW1 [1/10976]
Sun Feb 18 23:12:06 2007 us=952997 WE_WAIT enter n=3 to=1011
Sun Feb 18 23:12:06 2007 us=953020 [0] ev=0x00000758 rwflags=0x0001 arg=0x00453560
Sun Feb 18 23:12:06 2007 us=965367 [1] ev=0x0000074c rwflags=0x0002 arg=0x00453558
Sun Feb 18 23:12:06 2007 us=965424 [2] ev=0x00000750 rwflags=0x0001 arg=0x00453558
Sun Feb 18 23:12:06 2007 us=965461 WE_WAIT leave [1,0] rwflags=0x0002 arg=0x00453558
Sun Feb 18 23:12:06 2007 us=965485  event_wait returned 1
Sun Feb 18 23:12:06 2007 us=965506 I/O WAIT status=0x0002
Sun Feb 18 23:12:06 2007 us=965636 TCPv4_CLIENT WRITE [102] to 125.209.65.208:1194: P_CONTROL_V1 kid=0 sid=36cab580 d4ffbb25 [ ] pid=1 DATA 16030100 53010000 4f030145 d896f5e7 879c69cb 3c80f50f d371db4a 4db4d2d[more...]
Sun Feb 18 23:12:06 2007 us=965666 STREAM: WRITE 102 offset=26
Sun Feb 18 23:12:06 2007 us=965689 WIN32 I/O: Socket Completion non-queued success [24]
Sun Feb 18 23:12:06 2007 us=965735 WIN32 I/O: Socket Send immediate return [104,104]
Sun Feb 18 23:12:06 2007 us=965758 TCPv4_CLIENT write returned 104
Sun Feb 18 23:12:06 2007 us=965812 TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=36cab580 d4ffbb25, stored-sid=37086d0e 5d19c336, stored-ip=125.209.65.208:1194
Sun Feb 18 23:12:06 2007 us=981947 TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800
Sun Feb 18 23:12:06 2007 us=981984 ACK reliable_can_send active=1 current=0 : [2] 1
Sun Feb 18 23:12:06 2007 us=982063 ACK reliable_send_timeout 2 [2] 1
Sun Feb 18 23:12:06 2007 us=982087 TLS: tls_process: timeout set to 2
Sun Feb 18 23:12:06 2007 us=982132 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=c003ab32 7ed065d8, stored-sid=00000000 00000000, stored-ip=[undef]
Sun Feb 18 23:12:06 2007 us=982177 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]
Sun Feb 18 23:12:06 2007 us=982213 WE_CTL n=0 ev=0x00469984 rwflags=0x0001 arg=0x00453560
Sun Feb 18 23:12:06 2007 us=982240 STREAM: SET NEXT, buf=[228,0] next=[228,1592] len=-1 maxlen=1592
Sun Feb 18 23:12:07 2007 us=5043 WE_CTL n=1 ev=0x009e288c rwflags=0x0001 arg=0x00453558
Sun Feb 18 23:12:07 2007 us=5090 I/O WAIT T?|T?|SRQ|Sw1 [1/10976]
Sun Feb 18 23:12:07 2007 us=5114 WE_WAIT enter n=2 to=1011
Sun Feb 18 23:12:07 2007 us=5137 [0] ev=0x00000758 rwflags=0x0001 arg=0x00453560
Sun Feb 18 23:12:07 2007 us=5160 [1] ev=0x00000750 rwflags=0x0001 arg=0x00453558
Sun Feb 18 23:12:07 2007 us=441873 WE_WAIT leave rwflags=0x0001 arg=0x00453558
Sun Feb 18 23:12:07 2007 us=441903  event_wait returned 1
Sun Feb 18 23:12:07 2007 us=441911 I/O WAIT status=0x0001
Sun Feb 18 23:12:07 2007 us=442105 WIN32 I/O: Socket Completion success [24]
Sun Feb 18 23:12:07 2007 us=442114 STREAM: ADD length_added=24
Sun Feb 18 23:12:07 2007 us=442123 STREAM: ADD returned TRUE, buf_len=22, residual_len=0
Sun Feb 18 23:12:07 2007 us=442130 STREAM: GET FINAL len=22
Sun Feb 18 23:12:07 2007 us=442136 STREAM: RESET
Sun Feb 18 23:12:07 2007 us=442143 TCPv4_CLIENT read returned 22
Sun Feb 18 23:12:07 2007 us=442166 TCPv4_CLIENT READ [22] from 125.209.65.208:1194: P_ACK_V1 kid=0 sid=37086d0e 5d19c336 [ 0 sid=36cab580 d4ffbb25 ]
Sun Feb 18 23:12:07 2007 us=442203 TLS: control channel, op=P_ACK_V1, IP=125.209.65.208:1194
Sun Feb 18 23:12:07 2007 us=442224 TLS: initial packet test, i=0 state=S_SENT_KEY, mysid=36cab580 d4ffbb25, rec-sid=37086d0e 5d19c336, rec-ip=125.209.65.208:1194, stored-sid=37086d0e 5d19c336, stored-ip=125.209.65.208:1194
Sun Feb 18 23:12:07 2007 us=442237 TLS: found match, session[0], sid=37086d0e 5d19c336
Sun Feb 18 23:12:07 2007 us=442248 TLS: received control channel packet s#=0 sid=37086d0e 5d19c336
Sun Feb 18 23:12:07 2007 us=442276 TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=36cab580 d4ffbb25, stored-sid=37086d0e 5d19c336, stored-ip=125.209.65.208:1194
Sun Feb 18 23:12:07 2007 us=442287 TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800
Sun Feb 18 23:12:07 2007 us=442296 ACK reliable_can_send active=1 current=0 : [2] 1
Sun Feb 18 23:12:07 2007 us=442340 ACK reliable_send_timeout 2 [2] 1
Sun Feb 18 23:12:07 2007 us=442348 TLS: tls_process: timeout set to 2
Sun Feb 18 23:12:07 2007 us=442362 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=c003ab32 7ed065d8, stored-sid=00000000 00000000, stored-ip=[undef]
Sun Feb 18 23:12:07 2007 us=442376 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]
Sun Feb 18 23:12:07 2007 us=442388 WE_CTL n=0 ev=0x00469984 rwflags=0x0001 arg=0x00453560
Sun Feb 18 23:12:07 2007 us=442398 STREAM: SET NEXT, buf=[228,0] next=[228,1592] len=-1 maxlen=1592
Sun Feb 18 23:12:07 2007 us=442406 STREAM: GET NEXT len=1592
Sun Feb 18 23:12:07 2007 us=442422 WIN32 I/O: Socket Receive queued [1592]
Sun Feb 18 23:12:07 2007 us=442431 WE_CTL n=1 ev=0x009e288c rwflags=0x0001 arg=0x00453558
Sun Feb 18 23:12:07 2007 us=442443 I/O WAIT T?|T?|SRQ|Sw1 [1/10976]
Sun Feb 18 23:12:07 2007 us=442450 WE_WAIT enter n=2 to=1011
Sun Feb 18 23:12:07 2007 us=442458 [0] ev=0x00000758 rwflags=0x0001 arg=0x00453560
Sun Feb 18 23:12:07 2007 us=442466 [1] ev=0x00000750 rwflags=0x0001 arg=0x00453558
Sun Feb 18 23:12:08 2007 us=368860 WE_WAIT leave rwflags=0x0001 arg=0x00453558
Sun Feb 18 23:12:08 2007 us=368948  event_wait returned 1
Sun Feb 18 23:12:08 2007 us=368972 I/O WAIT status=0x0001
Sun Feb 18 23:12:08 2007 us=369003 WIN32 I/O: Socket Completion success [128]
Sun Feb 18 23:12:08 2007 us=369025 STREAM: ADD length_added=128
Sun Feb 18 23:12:08 2007 us=369048 STREAM: ADD returned TRUE, buf_len=126, residual_len=0
Sun Feb 18 23:12:08 2007 us=369070 STREAM: GET FINAL len=126
Sun Feb 18 23:12:08 2007 us=369088 STREAM: RESET
Sun Feb 18 23:12:08 2007 us=369109 TCPv4_CLIENT read returned 126
Sun Feb 18 23:12:08 2007 us=369262 TCPv4_CLIENT READ [126] from 125.209.65.208:1194: P_CONTROL_V1 kid=0 sid=37086d0e 5d19c336 [ 1 sid=36cab580 d4ffbb25 ] pid=1 DATA 16030100 2a020000 26030145 d8dfc786 a7ef5646 24a04b49 87101e0f c303e37[more...]
Sun Feb 18 23:12:08 2007 us=369298 TLS: control channel, op=P_CONTROL_V1, IP=125.209.65.208:1194
Sun Feb 18 23:12:08 2007 us=369360 TLS: initial packet test, i=0 state=S_SENT_KEY, mysid=36cab580 d4ffbb25, rec-sid=37086d0e 5d19c336, rec-ip=125.209.65.208:1194, stored-sid=37086d0e 5d19c336, stored-ip=125.209.65.208:1194
Sun Feb 18 23:12:08 2007 us=369398 TLS: found match, session[0], sid=37086d0e 5d19c336
Sun Feb 18 23:12:08 2007 us=369429 TLS: received control channel packet s#=0 sid=37086d0e 5d19c336
Sun Feb 18 23:12:08 2007 us=369453 ACK received for pid 1, deleting from send buffer
Sun Feb 18 23:12:08 2007 us=369476 ACK read ID 1 (buf->len=100)
Sun Feb 18 23:12:08 2007 us=369498 ACK mark active incoming ID 1
Sun Feb 18 23:12:08 2007 us=369518 ACK acknowledge ID 1 (ack->len=1)
Sun Feb 18 23:12:08 2007 us=369549 TIMER: coarse timer wakeup 1 seconds
Sun Feb 18 23:12:08 2007 us=369603 TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=36cab580 d4ffbb25, stored-sid=37086d0e 5d19c336, stored-ip=125.209.65.208:1194
Sun Feb 18 23:12:08 2007 us=369634 TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800
Sun Feb 18 23:12:08 2007 us=369660 ACK reliable_can_send active=0 current=0 : [2]
Sun Feb 18 23:12:08 2007 us=369688 BIO write tls_write_ciphertext 100 bytes
Sun Feb 18 23:12:08 2007 us=369709 Incoming Ciphertext -> TLS
Sun Feb 18 23:12:08 2007 us=369838 SSL state (connect): SSLv3 read server hello A
Sun Feb 18 23:12:08 2007 us=371238 TLS: tls_process: chg=1 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800
Sun Feb 18 23:12:08 2007 us=371273 ACK reliable_can_send active=0 current=0 : [2]
Sun Feb 18 23:12:08 2007 us=371330 ACK write ID 1 (ack->len=1, n=1)
Sun Feb 18 23:12:08 2007 us=371354 Dedicated ACK -> TCP/UDP
Sun Feb 18 23:12:08 2007 us=371377 ACK reliable_send_timeout 604800 [2]
Sun Feb 18 23:12:08 2007 us=371398 TLS: tls_process: timeout set to 58
Sun Feb 18 23:12:08 2007 us=371442 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=c003ab32 7ed065d8, stored-sid=00000000 00000000, stored-ip=[undef]
Sun Feb 18 23:12:08 2007 us=371487 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]
Sun Feb 18 23:12:08 2007 us=371522 WE_CTL n=0 ev=0x00469984 rwflags=0x0001 arg=0x00453560
Sun Feb 18 23:12:08 2007 us=371549 STREAM: SET NEXT, buf=[228,0] next=[228,1592] len=-1 maxlen=1592
Sun Feb 18 23:12:08 2007 us=371572 STREAM: GET NEXT len=1592
Sun Feb 18 23:12:08 2007 us=371610 WIN32 I/O: Socket Receive queued [1592]
Sun Feb 18 23:12:08 2007 us=371635 WE_CTL n=1 ev=0x009e288c rwflags=0x0003 arg=0x00453558
Sun Feb 18 23:12:08 2007 us=371669 I/O WAIT T?|T?|SRQ|SW1 [1/10976]
Sun Feb 18 23:12:08 2007 us=371693 WE_WAIT enter n=3 to=1011
Sun Feb 18 23:12:08 2007 us=371715 [0] ev=0x00000758 rwflags=0x0001 arg=0x00453560
Sun Feb 18 23:12:08 2007 us=371739 [1] ev=0x0000074c rwflags=0x0002 arg=0x00453558
Sun Feb 18 23:12:08 2007 us=371763 [2] ev=0x00000750 rwflags=0x0001 arg=0x00453558
Sun Feb 18 23:12:08 2007 us=371795 WE_WAIT leave [1,0] rwflags=0x0002 arg=0x00453558
Sun Feb 18 23:12:08 2007 us=371818  event_wait returned 1
Sun Feb 18 23:12:08 2007 us=371838 I/O WAIT status=0x0002
Sun Feb 18 23:12:08 2007 us=371928 TCPv4_CLIENT WRITE [22] to 125.209.65.208:1194: P_ACK_V1 kid=0 sid=36cab580 d4ffbb25 [ 1 sid=37086d0e 5d19c336 ]
Sun Feb 18 23:12:08 2007 us=373432 STREAM: WRITE 22 offset=31
Sun Feb 18 23:12:08 2007 us=373463 WIN32 I/O: Socket Completion non-queued success [104]
Sun Feb 18 23:12:08 2007 us=373634 WIN32 I/O: Socket Send immediate return [24,24]
Sun Feb 18 23:12:08 2007 us=373661 TCPv4_CLIENT write returned 24
Sun Feb 18 23:12:08 2007 us=373714 TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=36cab580 d4ffbb25, stored-sid=37086d0e 5d19c336, stored-ip=125.209.65.208:1194
Sun Feb 18 23:12:08 2007 us=373746 TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800
Sun Feb 18 23:12:08 2007 us=373771 ACK reliable_can_send active=0 current=0 : [2]
Sun Feb 18 23:12:08 2007 us=373827 ACK reliable_send_timeout 604800 [2]
Sun Feb 18 23:12:08 2007 us=373851 TLS: tls_process: timeout set to 58
Sun Feb 18 23:12:08 2007 us=373979 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=c003ab32 7ed065d8, stored-sid=00000000 00000000, stored-ip=[undef]
Sun Feb 18 23:12:08 2007 us=388573 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]
Sun Feb 18 23:12:08 2007 us=388618 WE_CTL n=0 ev=0x00469984 rwflags=0x0001 arg=0x00453560
Sun Feb 18 23:12:08 2007 us=388646 STREAM: SET NEXT, buf=[228,0] next=[228,1592] len=-1 maxlen=1592
Sun Feb 18 23:12:08 2007 us=388673 WE_CTL n=1 ev=0x009e288c rwflags=0x0001 arg=0x00453558
Sun Feb 18 23:12:08 2007 us=388706 I/O WAIT T?|T?|SRQ|Sw1 [1/10976]
Sun Feb 18 23:12:08 2007 us=388729 WE_WAIT enter n=2 to=1011
Sun Feb 18 23:12:08 2007 us=388752 [0] ev=0x00000758 rwflags=0x0001 arg=0x00453560
Sun Feb 18 23:12:08 2007 us=388775 [1] ev=0x00000750 rwflags=0x0001 arg=0x00453558
Sun Feb 18 23:12:08 2007 us=979136 WE_WAIT leave rwflags=0x0001 arg=0x00453558
Sun Feb 18 23:12:08 2007 us=979199  event_wait returned 1
Sun Feb 18 23:12:08 2007 us=979221 I/O WAIT status=0x0001
Sun Feb 18 23:12:08 2007 us=979253 WIN32 I/O: Socket Completion success [348]
Sun Feb 18 23:12:08 2007 us=979275 STREAM: ADD length_added=348
Sun Feb 18 23:12:08 2007 us=979299 STREAM: ADD returned TRUE, buf_len=114, residual_len=232
Sun Feb 18 23:12:08 2007 us=979320 STREAM: GET FINAL len=114
Sun Feb 18 23:12:08 2007 us=979338 STREAM: RESET
Sun Feb 18 23:12:08 2007 us=979359 TCPv4_CLIENT read returned 114
Sun Feb 18 23:12:08 2007 us=979501 TCPv4_CLIENT READ [114] from 125.209.65.208:1194: P_CONTROL_V1 kid=0 sid=37086d0e 5d19c336 [ ] pid=2 DATA 02504b31 15301306 03550408 130c4665 6472616c 20537461 74653112 3010060[more...]
Sun Feb 18 23:12:08 2007 us=979535 TLS: control channel, op=P_CONTROL_V1, IP=125.209.65.208:1194
Sun Feb 18 23:12:08 2007 us=979596 TLS: initial packet test, i=0 state=S_SENT_KEY, mysid=36cab580 d4ffbb25, rec-sid=37086d0e 5d19c336, rec-ip=125.209.65.208:1194, stored-sid=37086d0e 5d19c336, stored-ip=125.209.65.208:1194
Sun Feb 18 23:12:08 2007 us=979634 TLS: found match, session[0], sid=37086d0e 5d19c336
Sun Feb 18 23:12:08 2007 us=979666 TLS: received control channel packet s#=0 sid=37086d0e 5d19c336
Sun Feb 18 23:12:08 2007 us=979691 ACK read ID 2 (buf->len=100)
Sun Feb 18 23:12:08 2007 us=979713 ACK mark active incoming ID 2
Sun Feb 18 23:12:08 2007 us=979733 ACK acknowledge ID 2 (ack->len=1)
Sun Feb 18 23:12:08 2007 us=979763 TIMER: coarse timer wakeup 1 seconds
Sun Feb 18 23:12:08 2007 us=979816 TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=36cab580 d4ffbb25, stored-sid=37086d0e 5d19c336, stored-ip=125.209.65.208:1194
Sun Feb 18 23:12:08 2007 us=981313 TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800
Sun Feb 18 23:12:08 2007 us=981344 ACK reliable_can_send active=0 current=0 : [2]
Sun Feb 18 23:12:08 2007 us=981371 BIO write tls_write_ciphertext 100 bytes
Sun Feb 18 23:12:08 2007 us=981392 Incoming Ciphertext -> TLS
Sun Feb 18 23:12:08 2007 us=981468 TLS: tls_process: chg=1 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800
Sun Feb 18 23:12:08 2007 us=981496 ACK reliable_can_send active=0 current=0 : [2]
Sun Feb 18 23:12:08 2007 us=981543 ACK write ID 2 (ack->len=1, n=1)
Sun Feb 18 23:12:08 2007 us=981566 Dedicated ACK -> TCP/UDP
Sun Feb 18 23:12:08 2007 us=981589 ACK reliable_send_timeout 604800 [2]
Sun Feb 18 23:12:08 2007 us=981610 TLS: tls_process: timeout set to 57
Sun Feb 18 23:12:08 2007 us=981653 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=c003ab32 7ed065d8, stored-sid=00000000 00000000, stored-ip=[undef]
Sun Feb 18 23:12:08 2007 us=981698 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]
Sun Feb 18 23:12:08 2007 us=981734 WE_CTL n=0 ev=0x00469984 rwflags=0x0001 arg=0x00453560
Sun Feb 18 23:12:08 2007 us=981757 STREAM: ADD length_added=0
Sun Feb 18 23:12:08 2007 us=981780 STREAM: ADD returned TRUE, buf_len=114, residual_len=116
Sun Feb 18 23:12:08 2007 us=981803 STREAM: RESIDUAL FULLY FORMED [YES], len=116
Sun Feb 18 23:12:08 2007 us=981828 WE_CTL n=1 ev=0x009e288c rwflags=0x0002 arg=0x00453558
Sun Feb 18 23:12:08 2007 us=981934 I/O WAIT status=0x0001
Sun Feb 18 23:12:08 2007 us=981957 STREAM: GET FINAL len=114
Sun Feb 18 23:12:08 2007 us=981975 STREAM: RESET
Sun Feb 18 23:12:08 2007 us=981996 TCPv4_CLIENT read returned 114
Sun Feb 18 23:12:08 2007 us=982130 TCPv4_CLIENT READ [114] from 125.209.65.208:1194: P_CONTROL_V1 kid=0 sid=37086d0e 5d19c336 [ ] pid=3 DATA 170d3037 30323138 32323438 35385a17 0d313730 32313532 32343835 385a306[more...]
Sun Feb 18 23:12:08 2007 us=982164 TLS: control channel, op=P_CONTROL_V1, IP=125.209.65.208:1194
Sun Feb 18 23:12:08 2007 us=982224 TLS: initial packet test, i=0 state=S_SENT_KEY, mysid=36cab580 d4ffbb25, rec-sid=37086d0e 5d19c336, rec-ip=125.209.65.208:1194, stored-sid=37086d0e 5d19c336, stored-ip=125.209.65.208:1194
Sun Feb 18 23:12:08 2007 us=982261 TLS: found match, session[0], sid=37086d0e 5d19c336
Sun Feb 18 23:12:08 2007 us=995869 TLS: received control channel packet s#=0 sid=37086d0e 5d19c336
Sun Feb 18 23:12:08 2007 us=996139 ACK read ID 3 (buf->len=100)
Sun Feb 18 23:12:08 2007 us=996165 ACK mark active incoming ID 3
Sun Feb 18 23:12:08 2007 us=996186 ACK acknowledge ID 3 (ack->len=1)
Sun Feb 18 23:12:08 2007 us=996245 TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=36cab580 d4ffbb25, stored-sid=37086d0e 5d19c336, stored-ip=125.209.65.208:1194
Sun Feb 18 23:12:08 2007 us=996278 TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=22 wakeup=604800
Sun Feb 18 23:12:08 2007 us=996304 BIO write tls_write_ciphertext 100 bytes
Sun Feb 18 23:12:08 2007 us=996325 Incoming Ciphertext -> TLS
Sun Feb 18 23:12:08 2007 us=996384 TLS: tls_process: chg=1 ks=S_SENT_KEY lame=S_UNDEF to_link->len=22 wakeup=604800
Sun Feb 18 23:12:08 2007 us=996434 ACK reliable_send_timeout 604800 [2]
Sun Feb 18 23:12:08 2007 us=996458 TLS: tls_process: timeout set to 57
Sun Feb 18 23:12:08 2007 us=996499 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=c003ab32 7ed065d8, stored-sid=00000000 00000000, stored-ip=[undef]
Sun Feb 18 23:12:09 2007 us=4139 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]
Sun Feb 18 23:12:09 2007 us=4182 WE_CTL n=0 ev=0x00469984 rwflags=0x0001 arg=0x00453560
Sun Feb 18 23:12:09 2007 us=4205 STREAM: ADD length_added=0
Sun Feb 18 23:12:09 2007 us=4227 STREAM: ADD returned TRUE, buf_len=114, residual_len=0
Sun Feb 18 23:12:09 2007 us=4249 STREAM: RESIDUAL FULLY FORMED [YES], len=0
Sun Feb 18 23:12:09 2007 us=4273 WE_CTL n=1 ev=0x009e288c rwflags=0x0002 arg=0x00453558
Sun Feb 18 23:12:09 2007 us=4294 I/O WAIT status=0x0001
Sun Feb 18 23:12:09 2007 us=4313 STREAM: GET FINAL len=114
Sun Feb 18 23:12:09 2007 us=4331 STREAM: RESET
Sun Feb 18 23:12:09 2007 us=17846 TCPv4_CLIENT read returned 114
Sun Feb 18 23:12:09 2007 us=18001 TCPv4_CLIENT READ [114] from 125.209.65.208:1194: P_CONTROL_V1 kid=0 sid=37086d0e 5d19c336 [ ] pid=4 DATA 436f7270 310b3009 06035504 0b130249 53311430 12060355 0403130b 61752e7[more...]
Sun Feb 18 23:12:09 2007 us=18036 TLS: control channel, op=P_CONTROL_V1, IP=125.209.65.208:1194
Sun Feb 18 23:12:09 2007 us=18096 TLS: initial packet test, i=0 state=S_SENT_KEY, mysid=36cab580 d4ffbb25, rec-sid=37086d0e 5d19c336, rec-ip=125.209.65.208:1194, stored-sid=37086d0e 5d19c336, stored-ip=125.209.65.208:1194
Sun Feb 18 23:12:09 2007 us=18133 TLS: found match, session[0], sid=37086d0e 5d19c336
Sun Feb 18 23:12:09 2007 us=18163 TLS: received control channel packet s#=0 sid=37086d0e 5d19c336
Sun Feb 18 23:12:09 2007 us=18187 ACK read ID 4 (buf->len=100)
Sun Feb 18 23:12:09 2007 us=18298 ACK mark active incoming ID 4
Sun Feb 18 23:12:09 2007 us=18321 ACK acknowledge ID 4 (ack->len=2)
Sun Feb 18 23:12:09 2007 us=41353 TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=36cab580 d4ffbb25, stored-sid=37086d0e 5d19c336, stored-ip=125.209.65.208:1194
Sun Feb 18 23:12:09 2007 us=41393 TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=22 wakeup=604800
Sun Feb 18 23:12:09 2007 us=41422 BIO write tls_write_ciphertext 100 bytes
Sun Feb 18 23:12:09 2007 us=41442 Incoming Ciphertext -> TLS
Sun Feb 18 23:12:09 2007 us=41506 TLS: tls_process: chg=1 ks=S_SENT_KEY lame=S_UNDEF to_link->len=22 wakeup=604800
Sun Feb 18 23:12:09 2007 us=41556 ACK reliable_send_timeout 604800 [2]
Sun Feb 18 23:12:09 2007 us=41580 TLS: tls_process: timeout set to 57
Sun Feb 18 23:12:09 2007 us=41621 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=c003ab32 7ed065d8, stored-sid=00000000 00000000, stored-ip=[undef]
Sun Feb 18 23:12:09 2007 us=41665 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]
Sun Feb 18 23:12:09 2007 us=60962 WE_CTL n=0 ev=0x00469984 rwflags=0x0001 arg=0x00453560
Sun Feb 18 23:12:09 2007 us=60997 STREAM: SET NEXT, buf=[228,0] next=[228,1592] len=-1 maxlen=1592
Sun Feb 18 23:12:09 2007 us=61020 STREAM: GET NEXT len=1592
Sun Feb 18 23:12:09 2007 us=61059 WIN32 I/O: Socket Receive queued [1592]
Sun Feb 18 23:12:09 2007 us=61131 WE_CTL n=1 ev=0x009e288c rwflags=0x0003 arg=0x00453558
Sun Feb 18 23:12:09 2007 us=61168 I/O WAIT T?|T?|SRQ|SW1 [1/10976]
Sun Feb 18 23:12:09 2007 us=61191 WE_WAIT enter n=3 to=1011
Sun Feb 18 23:12:09 2007 us=61213 [0] ev=0x00000758 rwflags=0x0001 arg=0x00453560
Sun Feb 18 23:12:09 2007 us=61237 [1] ev=0x0000074c rwflags=0x0002 arg=0x00453558
Sun Feb 18 23:12:09 2007 us=61260 [2] ev=0x00000750 rwflags=0x0001 arg=0x00453558
Sun Feb 18 23:12:09 2007 us=61291 WE_WAIT leave [1,0] rwflags=0x0002 arg=0x00453558
Sun Feb 18 23:12:09 2007 us=61403  event_wait returned 1
Sun Feb 18 23:12:09 2007 us=61424 I/O WAIT status=0x0002
Sun Feb 18 23:12:09 2007 us=77879 TCPv4_CLIENT WRITE [22] to 125.209.65.208:1194: P_ACK_V1 kid=0 sid=36cab580 d4ffbb25 [ 2 sid=37086d0e 5d19c336 ]
Sun Feb 18 23:12:09 2007 us=77912 STREAM: WRITE 22 offset=31
Sun Feb 18 23:12:09 2007 us=77935 WIN32 I/O: Socket Completion non-queued success [24]
Sun Feb 18 23:12:09 2007 us=78138 WIN32 I/O: Socket Send immediate return [24,24]
Sun Feb 18 23:12:09 2007 us=78172 TCPv4_CLIENT write returned 24
Sun Feb 18 23:12:09 2007 us=78225 TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=36cab580 d4ffbb25, stored-sid=37086d0e 5d19c336, stored-ip=125.209.65.208:1194
Sun Feb 18 23:12:09 2007 us=78257 TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800
Sun Feb 18 23:12:09 2007 us=78283 ACK reliable_can_send active=0 current=0 : [2]
Sun Feb 18 23:12:09 2007 us=78344 ACK write ID 3 (ack->len=2, n=2)
Sun Feb 18 23:12:09 2007 us=78368 ACK write ID 4 (ack->len=2, n=2)
Sun Feb 18 23:12:09 2007 us=78387 Dedicated ACK -> TCP/UDP
Sun Feb 18 23:12:09 2007 us=96508 ACK reliable_send_timeout 604800 [2]
Sun Feb 18 23:12:09 2007 us=96538 TLS: tls_process: timeout set to 57
Sun Feb 18 23:12:09 2007 us=96583 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=c003ab32 7ed065d8, stored-sid=00000000 00000000, stored-ip=[undef]
Sun Feb 18 23:12:09 2007 us=96628 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]
Sun Feb 18 23:12:09 2007 us=96663 WE_CTL n=0 ev=0x00469984 rwflags=0x0001 arg=0x00453560
Sun Feb 18 23:12:09 2007 us=96690 STREAM: SET NEXT, buf=[228,0] next=[228,1592] len=-1 maxlen=1592
Sun Feb 18 23:12:09 2007 us=96716 WE_CTL n=1 ev=0x009e288c rwflags=0x0003 arg=0x00453558
Sun Feb 18 23:12:09 2007 us=96749 I/O WAIT T?|T?|SRQ|SW1 [1/10976]
Sun Feb 18 23:12:09 2007 us=96772 WE_WAIT enter n=3 to=1011
Sun Feb 18 23:12:09 2007 us=96794 [0] ev=0x00000758 rwflags=0x0001 arg=0x00453560
Sun Feb 18 23:12:09 2007 us=109893 [1] ev=0x0000074c rwflags=0x0002 arg=0x00453558
Sun Feb 18 23:12:09 2007 us=109925 [2] ev=0x00000750 rwflags=0x0001 arg=0x00453558
Sun Feb 18 23:12:09 2007 us=109958 WE_WAIT leave [1,0] rwflags=0x0002 arg=0x00453558
Sun Feb 18 23:12:09 2007 us=109982  event_wait returned 1
Sun Feb 18 23:12:09 2007 us=110002 I/O WAIT status=0x0002
Sun Feb 18 23:12:09 2007 us=110060 TCPv4_CLIENT WRITE [26] to 125.209.65.208:1194: P_ACK_V1 kid=0 sid=36cab580 d4ffbb25 [ 3 4 sid=37086d0e 5d19c336 ]
Sun Feb 18 23:12:09 2007 us=110086 STREAM: WRITE 26 offset=31
Sun Feb 18 23:12:09 2007 us=110108 WIN32 I/O: Socket Completion non-queued success [24]
Sun Feb 18 23:12:09 2007 us=110150 WIN32 I/O: Socket Send immediate return [28,28]
Sun Feb 18 23:12:09 2007 us=110174 TCPv4_CLIENT write returned 28
Sun Feb 18 23:12:09 2007 us=110225 TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=36cab580 d4ffbb25, stored-sid=37086d0e 5d19c336, stored-ip=125.209.65.208:1194
Sun Feb 18 23:12:09 2007 us=132115 TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800
Sun Feb 18 23:12:09 2007 us=132149 ACK reliable_can_send active=0 current=0 : [2]
Sun Feb 18 23:12:09 2007 us=132214 ACK reliable_send_timeout 604800 [2]
Sun Feb 18 23:12:09 2007 us=132238 TLS: tls_process: timeout set to 57
Sun Feb 18 23:12:09 2007 us=132281 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=c003ab32 7ed065d8, stored-sid=00000000 00000000, stored-ip=[undef]
Sun Feb 18 23:12:09 2007 us=132345 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]
Sun Feb 18 23:12:09 2007 us=132381 WE_CTL n=0 ev=0x00469984 rwflags=0x0001 arg=0x00453560
Sun Feb 18 23:12:09 2007 us=132409 STREAM: SET NEXT, buf=[228,0] next=[228,1592] len=-1 maxlen=1592
Sun Feb 18 23:12:09 2007 us=132434 WE_CTL n=1 ev=0x009e288c rwflags=0x0001 arg=0x00453558
Sun Feb 18 23:12:09 2007 us=152003 I/O WAIT T?|T?|SRQ|Sw1 [1/10976]
Sun Feb 18 23:12:09 2007 us=152034 WE_WAIT enter n=2 to=1011
Sun Feb 18 23:12:09 2007 us=152058 [0] ev=0x00000758 rwflags=0x0001 arg=0x00453560
Sun Feb 18 23:12:09 2007 us=152082 [1] ev=0x00000750 rwflags=0x0001 arg=0x00453558
Sun Feb 18 23:12:09 2007 us=586561 WE_WAIT leave rwflags=0x0001 arg=0x00453558
Sun Feb 18 23:12:09 2007 us=586594  event_wait returned 1
Sun Feb 18 23:12:09 2007 us=586602 I/O WAIT status=0x0001
Sun Feb 18 23:12:09 2007 us=586617 WIN32 I/O: Socket Completion success [116]
Sun Feb 18 23:12:09 2007 us=586625 STREAM: ADD length_added=116
Sun Feb 18 23:12:09 2007 us=586634 STREAM: ADD returned TRUE, buf_len=114, residual_len=0
Sun Feb 18 23:12:09 2007 us=586642 STREAM: GET FINAL len=114
Sun Feb 18 23:12:09 2007 us=586648 STREAM: RESET
Sun Feb 18 23:12:09 2007 us=586655 TCPv4_CLIENT read returned 114
Sun Feb 18 23:12:09 2007 us=586704 TCPv4_CLIENT READ [114] from 125.209.65.208:1194: P_CONTROL_V1 kid=0 sid=37086d0e 5d19c336 [ ] pid=5 DATA a5b48a72 0760c66b 562930f3 705840fd 38b30207 1196a302 be80b583 80856fc[more...]
Sun Feb 18 23:12:09 2007 us=586717 TLS: control channel, op=P_CONTROL_V1, IP=125.209.65.208:1194
Sun Feb 18 23:12:09 2007 us=586737 TLS: initial packet test, i=0 state=S_SENT_KEY, mysid=36cab580 d4ffbb25, rec-sid=37086d0e 5d19c336, rec-ip=125.209.65.208:1194, stored-sid=37086d0e 5d19c336, stored-ip=125.209.65.208:1194
Sun Feb 18 23:12:09 2007 us=586751 TLS: found match, session[0], sid=37086d0e 5d19c336
Sun Feb 18 23:12:09 2007 us=586767 TLS: received control channel packet s#=0 sid=37086d0e 5d19c336
Sun Feb 18 23:12:09 2007 us=586777 ACK read ID 5 (buf->len=100)
Sun Feb 18 23:12:09 2007 us=586786 ACK mark active incoming ID 5
Sun Feb 18 23:12:09 2007 us=586793 ACK acknowledge ID 5 (ack->len=1)
Sun Feb 18 23:12:09 2007 us=586817 TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=36cab580 d4ffbb25, stored-sid=37086d0e 5d19c336, stored-ip=125.209.65.208:1194
Sun Feb 18 23:12:09 2007 us=586829 TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800
Sun Feb 18 23:12:09 2007 us=587015 ACK reliable_can_send active=0 current=0 : [2]
Sun Feb 18 23:12:09 2007 us=587027 BIO write tls_write_ciphertext 100 bytes
Sun Feb 18 23:12:09 2007 us=587034 Incoming Ciphertext -> TLS
Sun Feb 18 23:12:09 2007 us=587077 TLS: tls_process: chg=1 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800
Sun Feb 18 23:12:09 2007 us=587086 ACK reliable_can_send active=0 current=0 : [2]
Sun Feb 18 23:12:09 2007 us=587102 ACK write ID 5 (ack->len=1, n=1)
Sun Feb 18 23:12:09 2007 us=587110 Dedicated ACK -> TCP/UDP
Sun Feb 18 23:12:09 2007 us=587117 ACK reliable_send_timeout 604800 [2]
Sun Feb 18 23:12:09 2007 us=587124 TLS: tls_process: timeout set to 57
Sun Feb 18 23:12:09 2007 us=587138 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=c003ab32 7ed065d8, stored-sid=00000000 00000000, stored-ip=[undef]
Sun Feb 18 23:12:09 2007 us=587153 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]
Sun Feb 18 23:12:09 2007 us=587165 WE_CTL n=0 ev=0x00469984 rwflags=0x0001 arg=0x00453560
Sun Feb 18 23:12:09 2007 us=587176 STREAM: SET NEXT, buf=[228,0] next=[228,1592] len=-1 maxlen=1592
Sun Feb 18 23:12:09 2007 us=587184 STREAM: GET NEXT len=1592
Sun Feb 18 23:12:09 2007 us=587202 WIN32 I/O: Socket Receive queued [1592]
Sun Feb 18 23:12:09 2007 us=587211 WE_CTL n=1 ev=0x009e288c rwflags=0x0003 arg=0x00453558
Sun Feb 18 23:12:09 2007 us=587223 I/O WAIT T?|T?|SRQ|SW1 [1/10976]
Sun Feb 18 23:12:09 2007 us=587231 WE_WAIT enter n=3 to=1011
Sun Feb 18 23:12:09 2007 us=587239 [0] ev=0x00000758 rwflags=0x0001 arg=0x00453560
Sun Feb 18 23:12:09 2007 us=587247 [1] ev=0x0000074c rwflags=0x0002 arg=0x00453558
Sun Feb 18 23:12:09 2007 us=587254 [2] ev=0x00000750 rwflags=0x0001 arg=0x00453558
Sun Feb 18 23:12:09 2007 us=587265 WE_WAIT leave [1,0] rwflags=0x0002 arg=0x00453558
Sun Feb 18 23:12:09 2007 us=587273  event_wait returned 1
Sun Feb 18 23:12:09 2007 us=587280 I/O WAIT status=0x0002
Sun Feb 18 23:12:09 2007 us=587298 TCPv4_CLIENT WRITE [22] to 125.209.65.208:1194: P_ACK_V1 kid=0 sid=36cab580 d4ffbb25 [ 5 sid=37086d0e 5d19c336 ]
Sun Feb 18 23:12:09 2007 us=587307 STREAM: WRITE 22 offset=31
Sun Feb 18 23:12:09 2007 us=587314 WIN32 I/O: Socket Completion non-queued success [28]
Sun Feb 18 23:12:09 2007 us=587331 WIN32 I/O: Socket Send immediate return [24,24]
Sun Feb 18 23:12:09 2007 us=592385 TCPv4_CLIENT write returned 24
Sun Feb 18 23:12:09 2007 us=592408 TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=36cab580 d4ffbb25, stored-sid=37086d0e 5d19c336, stored-ip=125.209.65.208:1194
Sun Feb 18 23:12:09 2007 us=592419 TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800
Sun Feb 18 23:12:09 2007 us=592427 ACK reliable_can_send active=0 current=0 : [2]
Sun Feb 18 23:12:09 2007 us=592450 ACK reliable_send_timeout 604800 [2]
Sun Feb 18 23:12:09 2007 us=592457 TLS: tls_process: timeout set to 57
Sun Feb 18 23:12:09 2007 us=592471 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=c003ab32 7ed065d8, stored-sid=00000000 00000000, stored-ip=[undef]
Sun Feb 18 23:12:09 2007 us=592485 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]
Sun Feb 18 23:12:09 2007 us=592496 WE_CTL n=0 ev=0x00469984 rwflags=0x0001 arg=0x00453560
Sun Feb 18 23:12:09 2007 us=592505 STREAM: SET NEXT, buf=[228,0] next=[228,1592] len=-1 maxlen=1592
Sun Feb 18 23:12:09 2007 us=595495 WE_CTL n=1 ev=0x009e288c rwflags=0x0001 arg=0x00453558
Sun Feb 18 23:12:09 2007 us=595510 I/O WAIT T?|T?|SRQ|Sw1 [1/10976]
Sun Feb 18 23:12:09 2007 us=595518 WE_WAIT enter n=2 to=1011
Sun Feb 18 23:12:09 2007 us=595525 [0] ev=0x00000758 rwflags=0x0001 arg=0x00453560
Sun Feb 18 23:12:09 2007 us=595533 [1] ev=0x00000750 rwflags=0x0001 arg=0x00453558
Sun Feb 18 23:12:10 2007 us=103850 WE_WAIT leave rwflags=0x0001 arg=0x00453558
Sun Feb 18 23:12:10 2007 us=103902  event_wait returned 1
Sun Feb 18 23:12:10 2007 us=103924 I/O WAIT status=0x0001
Sun Feb 18 23:12:10 2007 us=103957 WIN32 I/O: Socket Completion success [116]
Sun Feb 18 23:12:10 2007 us=103978 STREAM: ADD length_added=116
Sun Feb 18 23:12:10 2007 us=104001 STREAM: ADD returned TRUE, buf_len=114, residual_len=0
Sun Feb 18 23:12:10 2007 us=104022 STREAM: GET FINAL len=114
Sun Feb 18 23:12:10 2007 us=104040 STREAM: RESET
Sun Feb 18 23:12:10 2007 us=104061 TCPv4_CLIENT read returned 114
Sun Feb 18 23:12:10 2007 us=116342 TCPv4_CLIENT READ [114] from 125.209.65.208:1194: P_CONTROL_V1 kid=0 sid=37086d0e 5d19c336 [ ] pid=6 DATA 5a8488c2 2aef3e58 0cb3a2c9 5449cbf0 974a1231 e0775d58 690f4abd 8a77b9c[more...]
Sun Feb 18 23:12:10 2007 us=116389 TLS: control channel, op=P_CONTROL_V1, IP=125.209.65.208:1194
Sun Feb 18 23:12:10 2007 us=116486 TLS: initial packet test, i=0 state=S_SENT_KEY, mysid=36cab580 d4ffbb25, rec-sid=37086d0e 5d19c336, rec-ip=125.209.65.208:1194, stored-sid=37086d0e 5d19c336, stored-ip=125.209.65.208:1194
Sun Feb 18 23:12:10 2007 us=116527 TLS: found match, session[0], sid=37086d0e 5d19c336
Sun Feb 18 23:12:10 2007 us=116558 TLS: received control channel packet s#=0 sid=37086d0e 5d19c336
Sun Feb 18 23:12:10 2007 us=116583 ACK read ID 6 (buf->len=100)
Sun Feb 18 23:12:10 2007 us=116606 ACK mark active incoming ID 6
Sun Feb 18 23:12:10 2007 us=116626 ACK acknowledge ID 6 (ack->len=1)
Sun Feb 18 23:12:10 2007 us=116657 TIMER: coarse timer wakeup 1 seconds
Sun Feb 18 23:12:10 2007 us=116710 TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=36cab580 d4ffbb25, stored-sid=37086d0e 5d19c336, stored-ip=125.209.65.208:1194
Sun Feb 18 23:12:10 2007 us=116741 TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800
Sun Feb 18 23:12:10 2007 us=116767 ACK reliable_can_send active=0 current=0 : [2]
Sun Feb 18 23:12:10 2007 us=116794 BIO write tls_write_ciphertext 100 bytes
Sun Feb 18 23:12:10 2007 us=116814 Incoming Ciphertext -> TLS
Sun Feb 18 23:12:10 2007 us=116891 TLS: tls_process: chg=1 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800
Sun Feb 18 23:12:10 2007 us=116919 ACK reliable_can_send active=0 current=0 : [2]
Sun Feb 18 23:12:10 2007 us=116964 ACK write ID 6 (ack->len=1, n=1)
Sun Feb 18 23:12:10 2007 us=116987 Dedicated ACK -> TCP/UDP
Sun Feb 18 23:12:10 2007 us=117009 ACK reliable_send_timeout 604800 [2]
Sun Feb 18 23:12:10 2007 us=117031 TLS: tls_process: timeout set to 56
Sun Feb 18 23:12:10 2007 us=117072 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=c003ab32 7ed065d8, stored-sid=00000000 00000000, stored-ip=[undef]
Sun Feb 18 23:12:10 2007 us=117117 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]
Sun Feb 18 23:12:10 2007 us=117152 WE_CTL n=0 ev=0x00469984 rwflags=0x0001 arg=0x00453560
Sun Feb 18 23:12:10 2007 us=117180 STREAM: SET NEXT, buf=[228,0] next=[228,1592] len=-1 maxlen=1592
Sun Feb 18 23:12:10 2007 us=117203 STREAM: GET NEXT len=1592
Sun Feb 18 23:12:10 2007 us=117242 WIN32 I/O: Socket Receive queued [1592]
Sun Feb 18 23:12:10 2007 us=117267 WE_CTL n=1 ev=0x009e288c rwflags=0x0003 arg=0x00453558
Sun Feb 18 23:12:10 2007 us=117301 I/O WAIT T?|T?|SRQ|SW1 [1/10976]
Sun Feb 18 23:12:10 2007 us=117324 WE_WAIT enter n=3 to=1011
Sun Feb 18 23:12:10 2007 us=117347 [0] ev=0x00000758 rwflags=0x0001 arg=0x00453560
Sun Feb 18 23:12:10 2007 us=117371 [1] ev=0x0000074c rwflags=0x0002 arg=0x00453558
Sun Feb 18 23:12:10 2007 us=117395 [2] ev=0x00000750 rwflags=0x0001 arg=0x00453558
Sun Feb 18 23:12:10 2007 us=117427 WE_WAIT leave [1,0] rwflags=0x0002 arg=0x00453558
Sun Feb 18 23:12:10 2007 us=117450  event_wait returned 1
Sun Feb 18 23:12:10 2007 us=117507 I/O WAIT status=0x0002
Sun Feb 18 23:12:10 2007 us=117562 TCPv4_CLIENT WRITE [22] to 125.209.65.208:1194: P_ACK_V1 kid=0 sid=36cab580 d4ffbb25 [ 6 sid=37086d0e 5d19c336 ]
Sun Feb 18 23:12:10 2007 us=117587 STREAM: WRITE 22 offset=31
Sun Feb 18 23:12:10 2007 us=117610 WIN32 I/O: Socket Completion non-queued success [24]
Sun Feb 18 23:12:10 2007 us=117651 WIN32 I/O: Socket Send immediate return [24,24]
Sun Feb 18 23:12:10 2007 us=117675 TCPv4_CLIENT write returned 24
Sun Feb 18 23:12:10 2007 us=117724 TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=36cab580 d4ffbb25, stored-sid=37086d0e 5d19c336, stored-ip=125.209.65.208:1194
Sun Feb 18 23:12:10 2007 us=117754 TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800
Sun Feb 18 23:12:10 2007 us=117780 ACK reliable_can_send active=0 current=0 : [2]
Sun Feb 18 23:12:10 2007 us=122056 ACK reliable_send_timeout 604800 [2]
Sun Feb 18 23:12:10 2007 us=122085 TLS: tls_process: timeout set to 56
Sun Feb 18 23:12:10 2007 us=122129 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=c003ab32 7ed065d8, stored-sid=00000000 00000000, stored-ip=[undef]
Sun Feb 18 23:12:10 2007 us=122174 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]
Sun Feb 18 23:12:10 2007 us=122209 WE_CTL n=0 ev=0x00469984 rwflags=0x0001 arg=0x00453560
Sun Feb 18 23:12:10 2007 us=122236 STREAM: SET NEXT, buf=[228,0] next=[228,1592] len=-1 maxlen=1592
Sun Feb 18 23:12:10 2007 us=122261 WE_CTL n=1 ev=0x009e288c rwflags=0x0001 arg=0x00453558
Sun Feb 18 23:12:10 2007 us=122294 I/O WAIT T?|T?|SRQ|Sw1 [1/10976]
Sun Feb 18 23:12:10 2007 us=122317 WE_WAIT enter n=2 to=1011
Sun Feb 18 23:12:10 2007 us=122339 [0] ev=0x00000758 rwflags=0x0001 arg=0x00453560
Sun Feb 18 23:12:10 2007 us=122364 [1] ev=0x00000750 rwflags=0x0001 arg=0x00453558