05/25/2006 14:43:16

[pdl :PDL :ERR /0: pntx_adt] read failed: Connection reset by peer

[pdl :PDL :DBUG/0: pntx_adt] input buffer accepted 0 bytes, now 0

05/25/2006 14:43:16

[pdl :PDL :ERR /0: pntx_adt] read returned error 73 (Connection reset by peer)

[pdl :PDL :INFO/0: pntx_adt] no PDL exception handler registered => input error

[pdl :PDL :DBUG/0: pntx_adt] IDLE and 0 bytes and an error: starting READ

[pdl :PDL :DBUG/2: pntx_adt] PDL changed states: old 0, new 1

[pdl :PDL :DBUG/0: pntx_adt] Calling Tcl procedure: hci_pd.read

[pdl :PDL :DBUG/0: pntx_adt] with args: {}

[pdl :PDL :DBUG/0: pntx_adt] Tcl procedure hci_pd.read returns 'RECEIVE'

[pdl :PDL :DBUG/0: pntx_adt] trying to match phrase: basic-msg

[pdl :PDL :INFO/0: pntx_adt] input-error in dfa 'basic-msg'

[pdl :PDL :DBUG/0: pntx_adt] multi_phrase_2: status = error

[pdl :PDL :DBUG/0: pntx_adt] Calling Tcl procedure: read.error

[pdl :PDL :DBUG/0: pntx_adt] with args: {{status error} {type input-error}}

05/25/2006 14:43:16

[pdl :PDL :ERR /0: pntx_adt] PDL signaled exception: code 1, msg device error (remote side probably shut down)

[pdl :PDL :DBUG/0: pntx_adt] pdiIgnoreInput: chop to 4294967295, bolen 0

[pdl :PDL :DBUG/0: pntx_adt] pdiIgnoreInput: after clear: 0 + 0

[pdl :PDL :DBUG/0: pntx_adt] Tcl procedure read.error returns '0'

[pdl :PDL :DBUG/2: pntx_adt] PDL changed states: old 1, new 0

[pdl :PDL :DBUG/0: pntx_adt] READ operation completed (0 bytes buffered still, 0 before)

[pdl :PDL :DBUG/2: pntx_adt] PDL changed states: old 0, new 5

[pdl :PDL :DBUG/2: pntx_adt] PDL changed states: old 5, new 6

[pdl :PDL :DBUG/1: pntx_adt] Clearing PDL listen event

[pti :even:DBUG/0: pntx_adt] Unregistering SOCKET (PDL server) event 0x20bf0d08 for tid 7

[pti :even:DBUG/0: pntx_adt] evUnregister SOCKET event 0x20BF0D08 for tid 7

[msg :Msg :DBUG/0: pntx_adt] [0.0.4773187] msgFree 0x3000603c

[pdl :clse:DBUG/1: pntx_adt] PDL did shutdown: code = 1000

[pdl :open:INFO/1: pntx_adt] Scheduling driver reopen try in 5.0 secs

[pd :pdtd:INFO/1: pntx_adt] Set driver status to PD_STATUS_OPENING

[diag:leak:DBUG/0: pntx_adt] diag timeval alloc 0x20be04d8

[diag:leak:DBUG/0: pntx_adt] diag ev alloc 0x21257d68

[diag:leak:DBUG/0: pntx_adt] diag dqe alloc 0x20be2b28

[pti :even:DBUG/0: pntx_adt] Registering TIMER (<no description>) event 0x21257d68 for tid 7

[pti :even:DBUG/0: pntx_adt] Registering TIMER event for tid 7

[diag:leak:DBUG/0: pntx_adt] diag timeval free 0x20be04d8

[pti :sche:INFO/1: pntx_adt] Thread has 0 ready events left.

[pti :sche:DBUG/2: pntx_adt] Thread 7 has been enabled

[pti :sche:INFO/1: pntx_adt] Thread has 1 ready events.

[pti :even:DBUG/0: pntx_adt] Processing ACTIVE_TIMER (<no description>) event 0x219b38a8

[pti :even:DBUG/1: pntx_adt] Calling cb 0x2009c674

[msi :msi :DBUG/1: pntx_adt] msiExportStats: export for thread: pntx_adt

[pti :even:DBUG/0: pntx_adt] Unregistering ACTIVE_TIMER (<no description>) event 0x219b38a8 for tid 7

[pti :even:DBUG/0: pntx_adt] evUnregister ACTIVE_TIMER event 0x219B38A8 for tid 7

[diag:leak:DBUG/0: pntx_adt] diag timeval alloc 0x20be04d8

[diag:leak:DBUG/0: pntx_adt] diag dqe alloc 0x20bf9c18

[pti :even:DBUG/0: pntx_adt] Registering ACTIVE_TIMER (<no description>) event 0x219b38a8 for tid 7

[pti :even:DBUG/0: pntx_adt] Registering ACTIVE_TIMER event for tid 7

[diag:leak:DBUG/0: pntx_adt] diag timeval free 0x20be04d8

[pti :sche:INFO/1: pntx_adt] Thread has 0 ready events left.

[pti :sche:INFO/2: pntx_adt] Performing apply callback for thread 7

[msi :msi :DBUG/1: pntx_adt] msiExportStats: export for thread: pntx_adt

[pti :sche:DBUG/2: pntx_adt] Thread 7 has been enabled

[pti :sche:INFO/1: pntx_adt] Thread has 1 ready events.

[pti :even:DBUG/0: pntx_adt] Processing TIMER (<no description>) event 0x21257d68

[pti :even:DBUG/1: pntx_adt] Calling cb 0x2009b750

[pdl :open:INFO/0: pntx_adt] Driver attempting reopen

[dbi :elog:DBUG/3: pntx_adt] [0.0.4773188] Looking for mid in error db

[dbi :rlog:DBUG/3: pntx_adt] [0.0.4773188] Looking for mid in recovery db

[msg :Mid :DBUG/3: pntx_adt] Assigned mid [0.0.4773188] to msg 3000603c

[msg :Msg :DBUG/0: pntx_adt] [0.0.4773188] MSG alloc 0x3000603c

[pdl :PDL :DBUG/2: pntx_adt] PDL changed states: old 6, new 7

[pdl :PDL :DBUG/0: pntx_adt] Evaling:

proc hci_pd.write { info } {

global MsgId

keylget info message MsgId

keylset continuations ok write.done

keylset continuations error write.error

keylset continuations timeout [list 15000 write.timeout]

hci_pd_send basic-msg [list [list data [list message $MsgId]]] $continuations

}

proc write.done {info} {}

proc write.error {info} {

hci_pd_report_exception 1 "write failure"

hci_pd_set_result_code 1

}

proc write.timeout {info} {

global MsgId

msgmetaset $MsgId FLAGS {{proto_timeout 1}}

hci_pd_set_result_code 1

}

proc hci_pd.read {info} {

keylset continuations basic-msg read.done

keylset continuations error read.error

keylset continuations timeout [list 15000 read.timeout]

hci_pd_receive $continuations

}

proc read.done {info} {

keylset accept text [list [keylget info data]]

keylset accept end [keylget info end]

hci_pd_accept $accept

}

proc read.error {info} {

keylget info type type

switch -exact -- $type {

input-error {

hci_pd_report_exception 1 "device error (remote side probably shut down)"

hci_pd_ignore_input -all

}

no-match {

hci_pd_ignore_input 1

hci_pd_ignore_input -until \xb

}

default {

hci_pd_report_exception 2 "unknown fail: $type"

hci_pd_ignore_input -all

}

}

}

proc read.timeout {info} {

hci_pd_ignore_input 1

hci_pd_ignore_input -until \xb

}

[pdl :PDL :DBUG/2: pntx_adt] PDL changed states: old 7, new 4

[pdl :PDL :DBUG/0: pntx_adt] Calling Tcl procedure: hci_pd.initialize

[pdl :PDL :DBUG/0: pntx_adt] with args: {}

[pdl :PDL :DBUG/0: pntx_adt] Tcl procedure hci_pd.initialize returns ''

[pdl :PDL :INFO/0: pntx_adt] connected to 172.16.101.125 on port 16000

[pdl :PDL :DBUG/0: pntx_adt] tcp-client: attempting connect to: 172.16.101.125:16000

[pdl :PDL :INFO/0: pntx_adt] tcp-client: connect error (Operation now in progress)

[pdl :PDL :DBUG/1: pntx_adt] PDL setting timeout in 0.10 seconds

[diag:leak:DBUG/0: pntx_adt] diag ev alloc 0x21257958

[diag:leak:DBUG/0: pntx_adt] diag dqe alloc 0x20be2b28

[pti :even:DBUG/0: pntx_adt] Registering TIMER (<no description>) event 0x21257958 for tid 7

[pti :even:DBUG/0: pntx_adt] Registering TIMER event for tid 7

[pti :even:DBUG/0: pntx_adt] Unregistering TIMER (<no description>) event 0x21257d68 for tid 7

[pti :even:DBUG/0: pntx_adt] evUnregister TIMER event 0x21257D68 for tid 7

[diag:leak:DBUG/0: pntx_adt] diag ev free 0x21257d68

[pti :sche:INFO/1: pntx_adt] Thread has 0 ready events left.

[pti :sche:DBUG/2: pntx_adt] Thread 7 has been enabled

[pti :sche:INFO/1: pntx_adt] Thread has 1 ready events.

[pti :even:DBUG/0: pntx_adt] Processing ACTIVE_TIMER (<no description>) event 0x219b38a8

[pti :even:DBUG/1: pntx_adt] Calling cb 0x2009c674

[msi :msi :DBUG/1: pntx_adt] msiExportStats: export for thread: pntx_adt

[pti :even:DBUG/0: pntx_adt] Unregistering ACTIVE_TIMER (<no description>) event 0x219b38a8 for tid 7

[pti :even:DBUG/0: pntx_adt] evUnregister ACTIVE_TIMER event 0x219B38A8 for tid 7

[diag:leak:DBUG/0: pntx_adt] diag timeval alloc 0x20bddea8

[diag:leak:DBUG/0: pntx_adt] diag dqe alloc 0x20bf9c18

[pti :even:DBUG/0: pntx_adt] Registering ACTIVE_TIMER (<no description>) event 0x219b38a8 for tid 7

[pti :even:DBUG/0: pntx_adt] Registering ACTIVE_TIMER event for tid 7

[diag:leak:DBUG/0: pntx_adt] diag timeval free 0x20bddea8

[pti :sche:INFO/1: pntx_adt] Thread has 0 ready events left.

[pti :sche:INFO/2: pntx_adt] Performing apply callback for thread 7

[msi :msi :DBUG/1: pntx_adt] msiExportStats: export for thread: pntx_adt

[pti :sche:DBUG/2: pntx_adt] Thread 7 has been enabled

[pti :sche:INFO/1: pntx_adt] Thread has 1 ready events.

[pti :even:DBUG/0: pntx_adt] Processing TIMER (<no description>) event 0x21257958

[pti :even:DBUG/1: pntx_adt] Calling cb 0x2009b744

[pdl :PDL :DBUG/0: pntx_adt] timeout continues: 2009b69c

[pdl :PDL :DBUG/0: pntx_adt] tcp-client: attempting connect to: 172.16.101.125:16000

[pdl :PDL :INFO/0: pntx_adt] tcp-client: connect error (Invalid argument)

[pdl :PDL :DBUG/1: pntx_adt] PDL setting timeout in 5.00 seconds

[diag:leak:DBUG/0: pntx_adt] diag ev alloc 0x21257d68

[diag:leak:DBUG/0: pntx_adt] diag dqe alloc 0x20be2b28

[pti :even:DBUG/0: pntx_adt] Registering TIMER (<no description>) event 0x21257d68 for tid 7

[pti :even:DBUG/0: pntx_adt] Registering TIMER event for tid 7

[pti :even:DBUG/0: pntx_adt] Unregistering TIMER (<no description>) event 0x21257958 for tid 7

[pti :even:DBUG/0: pntx_adt] evUnregister TIMER event 0x21257958 for tid 7

[diag:leak:DBUG/0: pntx_adt] diag ev free 0x21257958

[pti :sche:INFO/1: pntx_adt] Thread has 0 ready events left.

[pti :sche:INFO/2: pntx_adt] Performing apply callback for thread 7

[msi :msi :DBUG/1: pntx_adt] msiExportStats: export for thread: pntx_adt

[pti :sche:DBUG/2: pntx_adt] Thread 7 has been enabled

[pti :sche:INFO/1: pntx_adt] Thread has 1 ready events.

[pti :even:DBUG/0: pntx_adt] Processing TIMER (<no description>) event 0x21257d68

[pti :even:DBUG/1: pntx_adt] Calling cb 0x2009b744

[pdl :PDL :DBUG/0: pntx_adt] timeout continues: 2009b6a8

[pdl :PDL :DBUG/0: pntx_adt] tcp-client: attempting connect to: 172.16.101.125:16000

[pdl :PDL :INFO/0: pntx_adt] tcp-client: connect error (Operation now in progress)

[pdl :PDL :DBUG/1: pntx_adt] PDL setting timeout in 0.10 seconds

[diag:leak:DBUG/0: pntx_adt] diag ev alloc 0x21257958

[diag:leak:DBUG/0: pntx_adt] diag dqe alloc 0x20be2b28

[pti :even:DBUG/0: pntx_adt] Registering TIMER (<no description>) event 0x21257958 for tid 7

[pti :even:DBUG/0: pntx_adt] Registering TIMER event for tid 7

[pti :even:DBUG/0: pntx_adt] Unregistering TIMER (<no description>) event 0x21257d68 for tid 7

[pti :even:DBUG/0: pntx_adt] evUnregister TIMER event 0x21257D68 for tid 7

[diag:leak:DBUG/0: pntx_adt] diag ev free 0x21257d68

[pti :sche:INFO/1: pntx_adt] Thread has 0 ready events left.

[pti :sche:DBUG/2: pntx_adt] Thread 7 has been enabled

[pti :sche:INFO/1: pntx_adt] Thread has 1 ready events.

[pti :even:DBUG/0: pntx_adt] Processing ACTIVE_TIMER (<no description>) event 0x219b38a8

[pti :even:DBUG/1: pntx_adt] Calling cb 0x2009c674

[msi :msi :DBUG/1: pntx_adt] msiExportStats: export for thread: pntx_adt

[pti :even:DBUG/0: pntx_adt] Unregistering ACTIVE_TIMER (<no description>) event 0x219b38a8 for tid 7

[pti :even:DBUG/0: pntx_adt] evUnregister ACTIVE_TIMER event 0x219B38A8 for tid 7

[diag:leak:DBUG/0: pntx_adt] diag timeval alloc 0x20bddea8

[diag:leak:DBUG/0: pntx_adt] diag dqe alloc 0x20bf9c18

[pti :even:DBUG/0: pntx_adt] Registering ACTIVE_TIMER (<no description>) event 0x219b38a8 for tid 7

[pti :even:DBUG/0: pntx_adt] Registering ACTIVE_TIMER event for tid 7

[diag:leak:DBUG/0: pntx_adt] diag timeval free 0x20bddea8

[pti :sche:INFO/1: pntx_adt] Thread has 0 ready events left.

[pti :sche:INFO/2: pntx_adt] Performing apply callback for thread 7

[msi :msi :DBUG/1: pntx_adt] msiExportStats: export for thread: pntx_adt

[pti :sche:DBUG/2: pntx_adt] Thread 7 has been enabled

[pti :sche:INFO/1: pntx_adt] Thread has 1 ready events.

[pti :even:DBUG/0: pntx_adt] Processing TIMER (<no description>) event 0x21257958

[pti :even:DBUG/1: pntx_adt] Calling cb 0x2009b744

[pdl :PDL :DBUG/0: pntx_adt] timeout continues: 2009b69c

[pdl :PDL :DBUG/0: pntx_adt] tcp-client: attempting connect to: 172.16.101.125:16000

[pdl :PDL :DBUG/1: pntx_adt] Creating PDL listen event on fd 93

[pti :even:DBUG/0: pntx_adt] Registering SOCKET (PDL server) event 0x20bf0d08 for tid 7

[pti :even:DBUG/0: pntx_adt] Registering SOCKET event for tid 7

[pdl :PDL :DBUG/0: pntx_adt] Calling Tcl procedure: hci_pd.default.open-ok

[pdl :PDL :DBUG/0: pntx_adt] with args: {{status ok}}

[pdl :PDL :DBUG/0: pntx_adt] Tcl procedure hci_pd.default.open-ok returns ''

[pdl :PDL :DBUG/2: pntx_adt] PDL changed states: old 4, new 0

[pdl :init:DBUG/1: pntx_adt] PDL did initialize: code = 0

[pd :pdtd:INFO/1: pntx_adt] Set driver status to PD_STATUS_UP

[pti :even:DBUG/0: pntx_adt] Unregistering TIMER (<no description>) event 0x21257958 for tid 7

[pti :even:DBUG/0: pntx_adt] evUnregister TIMER event 0x21257958 for tid 7

[diag:leak:DBUG/0: pntx_adt] diag ev free 0x21257958

[pti :sche:INFO/1: pntx_adt] Thread has 0 ready events left.

[pti :sche:INFO/2: pntx_adt] Performing apply callback for thread 7

[msi :msi :DBUG/1: pntx_adt] msiExportStats: export for thread: pntx_adt

Engine idle -- 05/25/2006 14:43:36

[pti :sche:DBUG/2: pntx_adt] Thread 7 has been enabled

[pti :sche:INFO/1: pntx_adt] Thread has 1 ready events.

[pti :even:DBUG/0: pntx_adt] Processing SOCKET (PDL server) event 0x20bf0d08

[pti :even:DBUG/1: pntx_adt] Calling cb 0x2009b738

[pdl :read:DBUG/2: pntx_adt] Events: E 0, R 8, W 0

05/25/2006 14:59:32

[pdl :PDL :ERR /0: pntx_adt] read failed: Connection reset by peer

[pdl :PDL :DBUG/0: pntx_adt] input buffer accepted 0 bytes, now 0

05/25/2006 14:59:32

[pdl :PDL :ERR /0: pntx_adt] read returned error 73 (Connection reset by peer)

[pdl :PDL :INFO/0: pntx_adt] no PDL exception handler registered => input error

[pdl :PDL :DBUG/0: pntx_adt] IDLE and 0 bytes and an error: starting READ

[pdl :PDL :DBUG/2: pntx_adt] PDL changed states: old 0, new 1

[pdl :PDL :DBUG/0: pntx_adt] Calling Tcl procedure: hci_pd.read

[pdl :PDL :DBUG/0: pntx_adt] with args: {}

[pdl :PDL :DBUG/0: pntx_adt] Tcl procedure hci_pd.read returns 'RECEIVE'

[pdl :PDL :DBUG/0: pntx_adt] trying to match phrase: basic-msg

[pdl :PDL :INFO/0: pntx_adt] input-error in dfa 'basic-msg'

[pdl :PDL :DBUG/0: pntx_adt] multi_phrase_2: status = error

[pdl :PDL :DBUG/0: pntx_adt] Calling Tcl procedure: read.error

[pdl :PDL :DBUG/0: pntx_adt] with args: {{status error} {type input-error}}

05/25/2006 14:59:32

[pdl :PDL :ERR /0: pntx_adt] PDL signaled exception: code 1, msg device error (remote side probably shut down)

[pdl :PDL :DBUG/0: pntx_adt] pdiIgnoreInput: chop to 4294967295, bolen 0

[pdl :PDL :DBUG/0: pntx_adt] pdiIgnoreInput: after clear: 0 + 0

[pdl :PDL :DBUG/0: pntx_adt] Tcl procedure read.error returns '0'

[pdl :PDL :DBUG/2: pntx_adt] PDL changed states: old 1, new 0

[pdl :PDL :DBUG/0: pntx_adt] READ operation completed (0 bytes buffered still, 0 before)

[pdl :PDL :DBUG/2: pntx_adt] PDL changed states: old 0, new 5

[pdl :PDL :DBUG/2: pntx_adt] PDL changed states: old 5, new 6

[pdl :PDL :DBUG/1: pntx_adt] Clearing PDL listen event

[pti :even:DBUG/0: pntx_adt] Unregistering SOCKET (PDL server) event 0x20bf0d08 for tid 7

[pti :even:DBUG/0: pntx_adt] evUnregister SOCKET event 0x20BF0D08 for tid 7

[msg :Msg :DBUG/0: pntx_adt] [0.0.4773188] msgFree 0x3000603c

[pdl :clse:DBUG/1: pntx_adt] PDL did shutdown: code = 1000

[pdl :open:INFO/1: pntx_adt] Scheduling driver reopen try in 5.0 secs

[pd :pdtd:INFO/1: pntx_adt] Set driver status to PD_STATUS_OPENING

[diag:leak:DBUG/0: pntx_adt] diag timeval alloc 0x20be04d8

[diag:leak:DBUG/0: pntx_adt] diag ev alloc 0x21257d68

[diag:leak:DBUG/0: pntx_adt] diag dqe alloc 0x20be2b28

[pti :even:DBUG/0: pntx_adt] Registering TIMER (<no description>) event 0x21257d68 for tid 7

[pti :even:DBUG/0: pntx_adt] Registering TIMER event for tid 7

[diag:leak:DBUG/0: pntx_adt] diag timeval free 0x20be04d8

[pti :sche:INFO/1: pntx_adt] Thread has 0 ready events left.

[pti :sche:DBUG/2: pntx_adt] Thread 7 has been enabled

[pti :sche:INFO/1: pntx_adt] Thread has 1 ready events.

[pti :even:DBUG/0: pntx_adt] Processing ACTIVE_TIMER (<no description>) event 0x219b38a8

[pti :even:DBUG/1: pntx_adt] Calling cb 0x2009c674

[msi :msi :DBUG/1: pntx_adt] msiExportStats: export for thread: pntx_adt

[pti :even:DBUG/0: pntx_adt] Unregistering ACTIVE_TIMER (<no description>) event 0x219b38a8 for tid 7

[pti :even:DBUG/0: pntx_adt] evUnregister ACTIVE_TIMER event 0x219B38A8 for tid 7

[diag:leak:DBUG/0: pntx_adt] diag timeval alloc 0x20be04d8

[diag:leak:DBUG/0: pntx_adt] diag dqe alloc 0x20bf9c18

[pti :even:DBUG/0: pntx_adt] Registering ACTIVE_TIMER (<no description>) event 0x219b38a8 for tid 7

[pti :even:DBUG/0: pntx_adt] Registering ACTIVE_TIMER event for tid 7

[diag:leak:DBUG/0: pntx_adt] diag timeval free 0x20be04d8

[pti :sche:INFO/1: pntx_adt] Thread has 0 ready events left.

[pti :sche:INFO/2: pntx_adt] Performing apply callback for thread 7

[msi :msi :DBUG/1: pntx_adt] msiExportStats: export for thread: pntx_adt

[pti :sche:DBUG/2: pntx_adt] Thread 7 has been enabled

[pti :sche:INFO/1: pntx_adt] Thread has 1 ready events.

[pti :even:DBUG/0: pntx_adt] Processing TIMER (<no description>) event 0x21257d68