cancel
Showing results for 
Search instead for 
Did you mean: 
cancel
242
Views
2
Helpful
5
Replies
Highlighted
Enthusiast

TTransportException, Unknown error 0 in bind_result.status [policy_bulk_session_init]

Hello,

I am trying export code from AiO to real hardware and software (c2911,

Cisco IOS Software, C2900 Software (C2900-UNIVERSALK9-M), Version 15.4(AVC_20130910)T, EARLY DEPLOYMENT DEVELOPMENT BUILD, synced to  V153_3_M0_2).

After successfully authorized via TLS to router I tried create ACL and insert ACE to created ACL, but device/(something else) on application side raises exception TTransportException: TSocket read 0 bytes.

On controller side I use AiO image....

debug onep

How to fix it?

*Mar  4 20:22:06.082: [261] [ONEP][Session]: ONEP TLS done accept_fd: 2, pid: 261, context: 212CB210 [onep_al_tls_accept:241]

*Mar  4 20:22:06.786: [261] [ONEP][Message]: Server Done tos 1 [cthrift_recv_main__:2439]

*Mar  4 20:22:06.786: [261] [ONEP][Session]: Gosia-10.10.10.241: Connecting attempt: timeout[0] shandle[0] fd[2] addr[10.10.10.230] port[56448] transport[onep tls server] [NetworkElement_connectIDL:378]

*Mar  4 20:22:06.786: [261] [ONEP][AAA]: Authenticating ONEP Session for appname Gosia [onep_al_aaa_session_authenticate:1472]

*Mar  4 20:22:06.786: [261] [ONEP][AAA]: AAA is not configured, using local database [onep_al_aaa_session_authenticate:1476]

*Mar  4 20:22:06.790: [261] [ONEP][AAA]: Authentication using local database: SUCCESS [onep_al_aaa_session_authenticate:1480]

*Mar  4 20:22:06.790: [261] [ONEP][Session]: Gosia-10.10.10.241: Authentication succeeded for user cisco1 [NetworkElement_connectIDL:390]

*Mar  4 20:22:06.790: [261] [ONEP][Session]: Searching for application with AID Gosia-10.10.10.241 resulted in No App Found [NetworkElement_connectIDL:441]

*Mar  4 20:22:06.790: [261] [ONEP][Session]: Gosia-10.10.10.241: FSM: [Disconnected] ==> [Connecting] remote_addr[10.10.10.230] port[56448] [network_app_fsm_connecting:2734]

*Mar  4 20:22:06.790: [261] [ONEP][Session]: Fail to find handle [2362] [network_element_find_app_by_handle:548]

*Mar  4 20:22:06.790: [261] [ONEP][Session]: Fail to find handle [2362] [network_element_find_app_by_handle:548]

*Mar  4 20:22:06.790: [261] [ONEP][Session]: Gosia-10.10.10.241-2362: [0x212C70C0][93A] added [network_element_add_app_internal:383]

*Mar  4 20:22:06.790: [261] [ONEP][Session]: Gosia-10.10.10.241-2362: Connecting with handle [0x93A] at Tue Mar 04 20:22:06.790 [network_app_fsm_connecting:2761]

*Mar  4 20:22:06.790: [261] [ONEP][Session]: Gosia-10.10.10.241-2362: FSM: [Connecting] ==> [Authenticated] remote_addr[10.10.10.230] port[56448] [network_app_fsm_authenticated:2891]

*Mar  4 20:22:06.790: [261] [ONEP][Session]: entering ... [create_application_process:1977]

*Mar  4 20:22:06.790: [261] [ONEP][Session]: Gosia-10.10.10.241-2362: stack size changed from =6000 bytes, to =200% of normal [create_application_process:2036]

*Mar  4 20:22:06.790: [284] [ONEP][Server]: app name Gosia session handle 0x93A [network_application_xdm_init:2277]

*Mar  4 20:22:06.790: [284] [ONEP][Session]: Gosia: attach async stats return retval 0 Unknown error 0 [network_application_xdm_init:2341]

*Mar  4 20:22:06.790: [284] [ONEP][Session]: Gosia: attach timer return retval 0 Unknown error 0 [network_application_xdm_init:2347]

*Mar  4 20:22:06.790: [261] [ONEP][Session]: TLS dup old <2, 261>, new<0, 284> [onep_al_tls_dup:287]

*Mar  4 20:22:06.790: [261] [ONEP][Session]: Gosia-10.10.10.241-2362: Cloned fd 2 -> 0 [ne_to_app_fd_clone:2098]

*Mar  4 20:22:06.790: [261] [ONEP][Session]: Gosia-10.10.10.241-2362: cthrift_rb @0x212B08AC status = 0x8 [NetworkElement_connectIDL:685]

*Mar  4 20:22:06.790: [261] [ONEP][Message]: Write cthrift buffer to socket 2: bytes 507 [cthrift_write_entire_buffer__:319]

*Mar  4 20:22:06.790: [261] [ONEP][Message]: cthrift wrote 507 bytes, 0 remaining to send, took 0 usec [cthrift_write_entire_buffer__:392]

*Mar  4 20:22:06.794: [261] [ONEP][Session]: ONEP TLS close fd: 2, pid: 261 [onep_al_tls_close:227]

*Mar  4 20:22:07.494: [261] [ONEP][Session]: ONEP TLS done accept_fd: 2, pid: 261, context: 3D202B84 [onep_al_tls_accept:241]

*Mar  4 20:22:08.394: [261] [ONEP][Message]: Server Done tos 1 [cthrift_recv_main__:2439]

*Mar  4 20:22:08.394: [261] [ONEP][Session]: Gosia-10.10.10.241-2362: [0x212C70C0][93A] found [network_element_find_app_by_handle:543]

*Mar  4 20:22:08.394: [261] [ONEP][Session]: Gosia-10.10.10.241-2362: Event channel fd[2] [NetworkElement_evtChannelIDL:790]

*Mar  4 20:22:08.394: [261] [ONEP][Session]: TLS dup old <2, 261>, new<1, 284> [onep_al_tls_dup:287]

*Mar  4 20:22:08.394: [261] [ONEP][Session]: Gosia-10.10.10.241-2362: Cloned fd 2 -> 1 [ne_to_app_fd_clone:2098]

*Mar  4 20:22:08.394: [261] [ONEP][Session]: Gosia-10.10.10.241-2362: FSM: [Authenticated] ==> [Connected] remote_addr[10.10.10.230] port[56449] [network_app_fsm_connected:2980]

*Mar  4 20:22:08.394: [261] [ONEP][Server]: entering ... [onep_ha_ctx_store:241]

*Mar  4 20:22:08.394: [261] [ONEP][Server]: service=0, table=0, session handle (key)=0x93A cidl_onep_ha_netapp_ctx_size_hint: 2178 [onep_ha_ctx_store:248]

*Mar  4 20:22:08.394: [261] [ONEP][Server]: stored app name Gosia session handle 0x93A [onep_ha_ctx_store:259]

*Mar  4 20:22:08.394: [261] [ONEP][Session]: Gosia-10.10.10.241-2362: Connected with handle [93A] at Tue Mar 04 20:22:08.394 [network_app_fsm_connected:3020]

*Mar  4 20:22:08.394: [261] [ONEP][Message]: Write cthrift buffer to socket 2: bytes 48 [cthrift_write_entire_buffer__:319]

*Mar  4 20:22:08.394: [261] [ONEP][Message]: cthrift wrote 48 bytes, 0 remaining to send, took 0 usec [cthrift_write_entire_buffer__:392]

*Mar  4 20:22:08.394: [261] [ONEP][Session]: ONEP TLS close fd: 2, pid: 261 [onep_al_tls_close:227]

*Mar  4 20:22:08.394: [284] [ONEP][Session]: Gosia-10.10.10.241-2362: Starting keepalive timer for 60 secs [network_application_process:1718]

*Mar  4 20:22:08.394: [284] [ONEP][Session]: Gosia-10.10.10.241-2362: session_init [onep_session_manager_session_init:299]

*Mar  4 20:22:08.394: [284] [ONEP][Event]: Session init for handle 2362 [onep_async_session_init:946]

*Mar  4 20:22:08.394: [284] [ONEP][Event]: store async session handle=2362 successfully [onep_event_async_session_store:1554]

*Mar  4 20:22:08.394: [284] [ONEP][Session]: Gosia-10.10.10.241-2362: Async session_init done [onep_session_manager_session_init:310]

*Mar  4 20:22:08.394: [284] [ONEP][Event]: SESSION_INIT: session handle 2362 [onep_event_session_init:1036]

*Mar  4 20:22:08.394: [284] [ONEP][Session]: Gosia-10.10.10.241-2362: [0x212C70C0][93A] found [network_element_find_app_by_handle:543]

*Mar  4 20:22:08.394: [284] [ONEP][Event]: init new context pid 284 handle: 2362 [fh_first_time_init:639]

*Mar  4 20:22:08.394: [284] [ONEP][Session]: Gosia-10.10.10.241-2362: Event session_init done [onep_session_manager_session_init:310]

*Mar  4 20:22:08.394: [284] [ONEP][Policy]: Async thread already created [create_async_policy_thread:669]

*Mar  4 20:22:08.394: [284] [ONEP][Policy]: Binding to XOS for client 2362 [policy_bulk_session_init:289]

*Mar  4 20:22:08.394: [284] [ONEP][Policy]: Binding to XOS - xos_async_wait retured successfully [policy_bulk_session_init:304]

*Mar  4 20:22:08.394: [284] [ONEP][Policy]: Unknown error 0 in bind_result.status [policy_bulk_session_init:316]

*Mar  4 20:22:08.394: [284] [ONEP][Session]: Gosia-10.10.10.241-2362: [0x212C70C0][93A] found [network_element_find_app_by_handle:543]

*Mar  4 20:22:08.394: [284] [ONEP][Policy]: Session init 2362 [onep_policy_session_init:483]

*Mar  4 20:22:08.394: [284] [ONEP][Session]: Gosia-10.10.10.241-2362: Policy session_init done [onep_session_manager_session_init:310]

*Mar  4 20:22:08.394: [284] [ONEP][VTY]: onep_vty_session_init: Session init 2362l [onep_vty_session_init:2073]

*Mar  4 20:22:08.394: [284] [ONEP][Session]: Gosia-10.10.10.241-2362: VTY session_init done [onep_session_manager_session_init:310]

*Mar  4 20:22:08.394: [284] [ONEP][Session]: Gosia-10.10.10.241-2362: Topology session_init done [onep_session_manager_session_init:310]

*Mar  4 20:22:08.394: [284] [ONEP][MEDIATRACE]: ONEP Mediatrace: Ignoring session init

[onep_pathtrace_event_session_init:795]

*Mar  4 20:22:08.394: [284] [ONEP][Session]: Gosia-10.10.10.241-2362: Mediatrace session_init done [onep_session_manager_session_init:310]

*Mar  4 20:22:08.394: [284] [ONEP][ROUTING]: ONEP Routing: Session init sh: 2362 [onep_rtg_svc_session_init:129]

*Mar  4 20:22:08.394: [284] [ONEP][Session]: Gosia-10.10.10.241-2362: [0x212C70C0][93A] found [network_element_find_app_by_handle:543]

*Mar  4 20:22:08.394: [284] [ONEP][ROUTING]: session_init, rtg_client_handle 0 [onep_rtg_svc_session_init:136]

*Mar  4 20:22:08.394: [284] [ONEP][ROUTING]: ONEP Routing: Session init sh: 2362, app: 0x212C70C0 [onep_rtg_svc_session_init:152]

*Mar  4 20:22:08.394: [284] [ONEP][Session]: Gosia-10.10.10.241-2362: Routing session_init done [onep_session_manager_session_init:310]

*Mar  4 20:22:08.410: [284] [ONEP][Session]: Gosia-10.10.10.241-2362: Session event [0x1] occured [network_app_session_handler:1432]

*Mar  4 20:22:08.410: [284] [ONEP][Session]: TLS reset ioref fd: 0 [onep_tls_reset_ioref:210]

*Mar  4 20:22:08.410: [284] [ONEP][Session]: TLS reset ioref fd: 1 [onep_tls_reset_ioref:210]

*Mar  4 20:22:08.810: [284] [ONEP][Session]: Gosia-10.10.10.241-2362: Session event [0x1] occured [network_app_session_handler:1432]

*Mar  4 20:22:08.810: [284] [ONEP][Message]: Server Done tos 1 [cthrift_recv_main__:2439]

*Mar  4 20:22:08.810: [284] [ONEP][Policy]: Received createAcl t=1 AF=1 l=0 [onep_create_acl:5468]

*Mar  4 20:22:08.810: [284] [ONEP][Policy]: REGULAR ACL

[onep_create_acl:5492]

*Mar  4 20:22:08.810: [284] [ONEP][Policy]: Create ACE List and Interface List for the ACL.

[onep_create_acl:5510]

*Mar  4 20:22:08.810: [284] [ONEP][Policy]: L3 ACL name=onep-acl-10 AF=1 [onep_al_acl_create_l3_acl:147]

*Mar  4 20:22:08.810: [284] [ONEP][Policy]: Transient L3 ACL name=onep-acl-10 [onep_al_acl_create_l3_acl:152]

*Mar  4 20:22:08.810: [284] [ONEP][Policy]: L3 ACL created=onep-acl-10 [onep_al_acl_create_l3_acl:202]

*Mar  4 20:22:08.810: [284] [ONEP][Policy]: Created ACL aH=10 [onep_create_acl:5561]

*Mar  4 20:22:08.810: [284] [ONEP][Message]: Write cthrift buffer to socket 0: bytes 33 [cthrift_write_entire_buffer__:319]

*Mar  4 20:22:08.810: [284] [ONEP][Message]: cthrift wrote 33 bytes, 0 remaining to send, took 0 usec [cthrift_write_entire_buffer__:392]

*Mar  4 20:22:08.810: [284] [ONEP][Session]: Gosia-10.10.10.241-2362: API served - done [network_app_process_cthrift_msg:1367]

*Mar  4 20:22:08.814: [284] [ONEP][Session]: Gosia-10.10.10.241-2362: Session event [0x1] occured [network_app_session_handler:1432]

*Mar  4 20:22:09.014: [284] [ONEP][Session]: Gosia-10.10.10.241-2362: Session event [0x1] occured [network_app_session_handler:1432]

*Mar  4 20:22:09.218: [284] [ONEP][Session]: Gosia-10.10.10.241-2362: cthrift error: -65551 'invalid type' [na_rx_fsm_do_cthrift_read:1147]

*Mar  4 20:22:09.218: [284] [ONEP][Session]: Gosia-10.10.10.241-2362: NA_RX_FSM_STATE_READ error (1) - generating NA_RX_FSM_STATE_CLEANUP [network_app_process_cthrift_msg:1323]

*Mar  4 20:22:09.218: [284] [ONEP][Session]: Gosia-10.10.10.241-2362: NA_RX_FSM_STATE_CLEANUP - generating NETWORK_APP_STATE_DISCONNECTED [network_app_process_cthrift_msg:1371]

*Mar  4 20:22:09.218: [284] [ONEP][Session]: Gosia-10.10.10.241-2362: FSM: [Connected] ==> [Disconnected] remote_addr[10.10.10.230] port[56448] [network_app_fsm_disconnected:3126]

*Mar  4 20:22:09.218: [284] [ONEP][Session]: Gosia-10.10.10.241-2362: Disconnected with handle [93A] at Tue Mar 04 20:22:09.218 [network_app_fsm_disconnected:3238]

*Mar  4 20:22:09.218: [284] [ONEP][Session]: Gosia-10.10.10.241-2362: [0x212C70C0][93A] found [network_element_find_app_by_handle:543]

*Mar  4 20:22:09.218: [284] [ONEP][Session]: caller pc 0x0x3288A714 [network_application_final_cleanup:2495]

*Mar  4 20:22:09.218: [284] [ONEP][Session]: Gosia-10.10.10.241-2362: session_exit [onep_session_manager_session_exit:373]

*Mar  4 20:22:09.218: [284] [ONEP][Event]: Session exit for handle 2362 [onep_async_session_exit:981]

*Mar  4 20:22:09.218: [284] [ONEP][Event]: delete async tbl elem session handle= 2362 successfully [onep_event_async_session_remove:1575]

*Mar  4 20:22:09.218: [284] [ONEP][Session]: Gosia-10.10.10.241-2362: Async session_exit done [onep_session_manager_session_exit:390]

*Mar  4 20:22:09.218: [284] [ONEP][Event]: SESSION_EXIT: session handle 2362 [onep_event_session_exit:1058]

*Mar  4 20:22:09.218: [284] [ONEP][Event]: get by session_handle looking for: 2362 [fh_get_ctx_block_by_session_handle:275]

*Mar  4 20:22:09.218: [284] [ONEP][Event]: get by session_handle found: 2362 [fh_get_ctx_block_by_session_handle:278]

*Mar  4 20:22:09.218: [284] [ONEP][Session]: Gosia-10.10.10.241-2362: Event session_exit done [onep_session_manager_session_exit:390]

*Mar  4 20:22:09.218: [284] [ONEP][Policy]: Session exit 2362 [onep_policy_session_exit:504]

*Mar  4 20:22:09.218: [284] [ONEP][Policy]: Deleting Classes [onep_al_policy_delete_all_classes:424]

*Mar  4 20:22:09.218: [284] [ONEP][Policy]: No policy created [onep_al_policy_delete_all_policies:164]

*Mar  4 20:22:09.218: [284] [ONEP][Policy]: Deleting ACLs [onep_al_acl_delete_all_acls:230]

*Mar  4 20:22:09.218: [284] [ONEP][Policy]: Deleting ACL=10 [onep_al_acl_delete_all_acls:235]

*Mar  4 20:22:09.218: [284] [ONEP][Policy]: Delete ACL onep-acl-10 [onep_al_acl_delete_all_acls:249]

*Mar  4 20:22:09.218: [284] [ONEP][Policy]: Transient L3 ACL name=onep-acl-10 [onep_al_acl_delete_l3_acl:247]

*Mar  4 20:22:09.218: [284] [ONEP][Policy]: Deleted ACL onep-acl-10 [onep_al_acl_delete_all_acls:292]

*Mar  4 20:22:09.218: [284] [ONEP][Policy]: Deleting ACEs [onep_al_acl_delete_all_acls:302]

*Mar  4 20:22:09.218: [284] [ONEP][Policy]: @@@calling xos_plc_unbind id : 11 [policy_bulk_session_cleanup:367]

*Mar  4 20:22:09.218: [284] [ONEP][Policy]: @@@policy_bulk_get_client_handle: 11 [policy_bulk_get_client_handle:122]

*Mar  4 20:22:09.218: [284] [ONEP][Policy]: @@@unbind is successful [policy_bulk_session_cleanup:373]

*Mar  4 20:22:09.222: [284] [ONEP][Session]: Gosia-10.10.10.241-2362: Policy session_exit done [onep_session_manager_session_exit:390]

*Mar  4 20:22:09.222: [284] [ONEP][VTY]: Session exit 2362l [onep_vty_session_exit:2089]

*Mar  4 20:22:09.222: [284] [ONEP][Session]: Gosia-10.10.10.241-2362: [0x212C70C0][93A] found [network_element_find_app_by_handle:543]

*Mar  4 20:22:09.222: [284] [ONEP][Session]: Gosia-10.10.10.241-2362: VTY session_exit done [onep_session_manager_session_exit:390]

*Mar  4 20:22:09.222: [284] [ONEP][Session]: Gosia-10.10.10.241-2362: Topology session_exit done [onep_session_manager_session_exit:390]

*Mar  4 20:22:09.222: [284] [ONEP][MEDIATRACE]: ONEP Mediatrace: session exit

[onep_pathtrace_event_session_exit:809]

*Mar  4 20:22:09.222: [284] [ONEP][Session]: Gosia-10.10.10.241-2362: Mediatrace session_exit done [onep_session_manager_session_exit:390]

*Mar  4 20:22:09.222: [284] [ONEP][ROUTING]: ONEP Routing: session exit sh: 2362 [onep_rtg_svc_session_exit:166]

*Mar  4 20:22:09.222: [284] [ONEP][Session]: Gosia-10.10.10.241-2362: [0x212C70C0][93A] found [network_element_find_app_by_handle:543]

*Mar  4 20:22:09.222: [284] [ONEP][ROUTING]: session exit: app handle 0, rtg_session_id 0, session_handle 2362 [onep_rtg_svc_session_exit:177]

*Mar  4 20:22:09.222: [284] [ONEP][ROUTING]: ONEP Routing: session exit sh: 2362 app: 0x212C70C0 [onep_rtg_svc_session_exit:196]

*Mar  4 20:22:09.222: [284] [ONEP][Session]: Gosia-10.10.10.241-2362: Routing session_exit done [onep_session_manager_session_exit:390]

*Mar  4 20:22:09.222: [284] [ONEP][DATAPATH]: Subblock is NULL for session - 2362 [onep_datapath_session_exit:63]

*Mar  4 20:22:09.222: [284] [ONEP][Session]: Gosia-10.10.10.241-2362: Datapath session_exit done [onep_session_manager_session_exit:390]

*Mar  4 20:22:09.222: [261] [ONEP][Session]: Gosia-10.10.10.241-2362: [0x212C70C0][93A] removed [network_element_remove_app_cb:226]

*Mar  4 20:22:09.222: [261] [ONEP][Server]: service=0, table=0, session handle (key)=0x93A [onep_ha_ctx_remove:271]

*Mar  4 20:22:09.222: [261] [ONEP][Server]: removed app name Gosia session handle 0x93A [onep_ha_ctx_remove:282]

*Mar  4 20:22:09.222: [284] [ONEP][Session]: Gosia-10.10.10.241-2362: session_update thread [-1] [onep_session_manager_session_update:333]

*Mar  4 20:22:09.222: [284] [ONEP][Session]: Fail to find handle [2362] [network_element_find_app_by_handle:548]

*Mar  4 20:22:09.222: [284] [ONEP][Session]: Gosia-10.10.10.241-2362: session_update fail [2362] [onep_session_manager_session_update:341]

*Mar  4 20:22:09.222: [284] [ONEP][Session]: ONEP TLS close fd: 1, pid: 284 [onep_al_tls_close:227]

*Mar  4 20:22:09.222: [284] [ONEP][Session]: IOS: close_ctxt_fd [close_ctxt_fd:61]

*Mar  4 20:22:09.222: [284] [ONEP][Session]: ONEP TLS close fd: 0, pid: 284 [onep_al_tls_close:227]

*Mar  4 20:22:09.222: [284] [ONEP][Session]: IOS: close_ctxt_fd [close_ctxt_fd:61]

*Mar  4 20:22:09.222: [284] [ONEP][Session]: Gosia-10.10.10.241-2362: Session history archived, Count = 11 [onep_app_history_archive:271]

Everyone's tags (3)
1 ACCEPTED SOLUTION

Accepted Solutions
Highlighted
Enthusiast

Re: TTransportException, Unknown error 0 in bind_result.status [policy_bulk_session_init]

5 REPLIES 5
Highlighted
Enthusiast

Re: TTransportException, Unknown error 0 in bind_result.status [policy_bulk_session_init]

I will try it in with ACL tutorial...

Highlighted
Enthusiast

Re: TTransportException, Unknown error 0 in bind_result.status [policy_bulk_session_init]

Highlighted
Hall of Fame Cisco Employee

Re: TTransportException, Unknown error 0 in bind_result.status [policy_bulk_session_init]

Yes.  You need a crypto image for TLS now.  The NPE stands for "no payload encryption," which is a problem for TLS.

Highlighted
Enthusiast

Re: TTransportException, Unknown error 0 in bind_result.status [policy_bulk_session_init]

But I really don't understand how to is possible do something like create ACE, but is not possible create ACL (or something like that)?

Highlighted
Hall of Fame Cisco Employee

Re: TTransportException, Unknown error 0 in bind_result.status [policy_bulk_session_init]

Creating an ACE doesn't need an RPC.  You can do that entirely in the client API until you try and push the ACL (with the ACE) back to the device.