Used the express install to create a local network. Here are controller, edge-router and application logs:
Controller logs:
[76013.332] ERROR channel/v2.(*classicListener).acceptConnection.func1 [tls:0.0.0.0:6262]: connection handler error for [tls:127.0.0.1:50896] (unknown/unenrolled router, routerId: q0dI7hhV6C)
[76014.668] ERROR fabric/controller/handler_ctrl.(*ConnectHandler).HandleConnection: {routerId=[OFQ2-ZurM]} unknown/unenrolled router
[76014.668] ERROR channel/v2.(*classicListener).acceptConnection.func1 [tls:0.0.0.0:6262]: connection handler error for [tls:127.0.0.1:50901] (unknown/unenrolled router, routerId: OFQ2-ZurM)
[76014.775] ERROR fabric/controller/handler_ctrl.(*ConnectHandler).HandleConnection: {routerId=[q0dI7hhV6C]} unknown/unenrolled router
[76014.776] ERROR channel/v2.(*classicListener).acceptConnection.func1 [tls:0.0.0.0:6262]: connection handler error for [tls:127.0.0.1:50902] (unknown/unenrolled router, routerId: q0dI7hhV6C)
Edge-router logs:
[76191.800] INFO channel/v2.getRetryVersionFor: defaulting to version 2
[76191.800] ERROR channel/v2.(*reconnectingDialer).Reconnect [u{reconnecting}->i{l8E6} @tls:ACHITRAPUR-LT:6262]: hello attempt [#14450] failed (EOF)
[76192.442] ERROR channel/v2.(*heartbeater).sendHeartbeat: {error=[timeout waiting for message reply: context deadline exceeded] channelId=[ch{ctrl}->u{reconnecting}->i{l8E6}]} failed to send heartbeat
[76193.443] ERROR channel/v2.(*heartbeater).sendHeartbeat: {error=[timeout waiting for message reply: context deadline exceeded] channelId=[ch{ctrl}->u{reconnecting}->i{l8E6}]} failed to send heartbeat
[76194.443] ERROR channel/v2.(*heartbeater).sendHeartbeat: {error=[timeout waiting for message reply: context deadline exceeded] channelId=[ch{ctrl}->u{reconnecting}->i{l8E6}]} failed to send heartbeat
[76195.445] ERROR channel/v2.(*heartbeater).sendHeartbeat: {error=[timeout waiting for message reply: context deadline exceeded] channelId=[ch{ctrl}->u{reconnecting}->i{l8E6}]} failed to send heartbeat
[76196.447] ERROR channel/v2.(*heartbeater).sendHeartbeat: {error=[timeout waiting for message reply: context deadline exceeded] channelId=[ch{ctrl}->u{reconnecting}->i{l8E6}]} failed to send heartbeat
[76196.823] INFO channel/v2.getRetryVersionFor: defaulting to version 2
[76196.823] ERROR channel/v2.(*reconnectingDialer).Reconnect [u{reconnecting}->i{l8E6} @tls:ACHITRAPUR-LT:6262]: hello attempt [#14451] failed (EOF)
[76197.449] ERROR channel/v2.(*heartbeater).sendHeartbeat: {error=[timeout waiting for message reply: context deadline exceeded] channelId=[ch{ctrl}->u{reconnecting}->i{l8E6}]} failed to send heartbeat
[76198.449] ERROR channel/v2.(*heartbeater).sendHeartbeat: {channelId=[ch{ctrl}->u{reconnecting}->i{l8E6}] error=[timeout waiting for message reply: context deadline exceeded]} failed to send heartbeat
[76199.451] ERROR channel/v2.(*heartbeater).sendHeartbeat: {error=[timeout waiting for message reply: context deadline exceeded] channelId=[ch{ctrl}->u{reconnecting}->i{l8E6}]} failed to send heartbeat
Weather sample logs:
Pasting logs from the enrollement step:
SDKd: ziti-sdk:ziti_enroll.c:163 well_known_certs_cb() CA PEM len = 12643
SDKi: ziti-sdk:ziti_ctrl.c:407 ziti_ctrl_init() ctrl[ACHITRAPUR-LT] ziti controller client initialized
SDKd: ziti-sdk:ziti_ctrl.c:324 ctrl_body_cb() ctrl[ACHITRAPUR-LT] completed POST[/enroll?method=ott&token=70ec2537-6ebb-4de0-8f3f-4740e2519c71] in 0.031 s
SDKd: ziti-sdk:ziti_enroll.c:239 enroll_cb() successfully enrolled with controller https://ACHITRAPUR-LT:1280
Strong identity enrolled successfully. File saved to: C:\Repos\GitHub\Samples\bin\Debug\net6.0/weather.demo.json
SDKd: ziti-sdk:zitilib.c:303 load_ziti_ctx() loading identity from C:\Repos\GitHub\Samples\bin\Debug\net6.0/weather.demo.json
SDKi: ziti_log_set_level set log level: root=4
SDKi: ziti-sdk:ziti.c:428 ziti_init_async() ztx[0] Ziti C SDK version 0.30.8-132 @ecfee7b(HEAD) starting at (2022-12-13T16:50:22.588)
SDKi: ziti-sdk:ziti.c:429 ziti_init_async() ztx[0] using uv_mbed[v0.14.11], tls[mbed TLS 3.2.1]
SDKi: ziti-sdk:ziti.c:430 ziti_init_async() ztx[0] Loading from config[C:\Repos\GitHub\Samples\bin\Debug\net6.0/weather.demo.json] controller[https://ACHITRAPUR-LT:1280]
SDKi: ziti-sdk:ziti_ctrl.c:407 ziti_ctrl_init() ctrl[ACHITRAPUR-LT] ziti controller client initialized
SDKd: ziti-sdk:ziti.c:452 ziti_init_async() ztx[0] using metrics interval: 0
SDKd: ziti-sdk:ziti.c:259 ziti_set_unauthenticated() ztx[0] setting api_session_state[0] to 0
SDKd: ziti-sdk:ziti_ctrl.c:244 ziti_ctrl_clear_api_session() ctrl[ACHITRAPUR-LT] clearing api session token for ziti_controller
SDKd: ziti-sdk:ziti.c:919 ziti_re_auth() ztx[0] re-auth executing, transitioning to unauthenticated
SDKd: ziti-sdk:ziti.c:259 ziti_set_unauthenticated() ztx[0] setting api_session_state[0] to 0
SDKd: ziti-sdk:ziti_ctrl.c:244 ziti_ctrl_clear_api_session() ctrl[ACHITRAPUR-LT] clearing api session token for ziti_controller
SDKd: ziti-sdk:ziti.c:290 is_api_session_expired() ztx[0] is_api_session_expired[TRUE] - api_session is null
SDKi: ziti-sdk:ziti.c:867 ziti_re_auth_with_cb() ztx[0] starting to re-auth with ctlr[https://ACHITRAPUR-LT:1280] api_session_status[0] api_session_expired[TRUE]
SDKd: ziti-sdk:ziti.c:252 ziti_set_auth_started() ztx[0] setting api_session_state[0] to 1
SDKd: ziti-sdk:ziti.c:324 ziti_stop_api_session_refresh() ztx[0] ziti_stop_api_session_refresh: stopping api session refresh
SDKd: ziti-sdk:ziti_ctrl.c:324 ctrl_body_cb() ctrl[ACHITRAPUR-LT] completed GET[/version] in 0.025 s
SDKi: ziti-sdk:ziti.c:1533 version_cb() ztx[0] connected to controller https://ACHITRAPUR-LT:1280 version v0.26.11(807dd591b1f5 2022-11-10T14:53:29Z)
SDKd: ziti-sdk:ziti_ctrl.c:324 ctrl_body_cb() ctrl[ACHITRAPUR-LT] completed POST[/authenticate?method=cert] in 0.043 sSDKd: ziti-sdk:ziti_ctrl.c:257 ctrl_login_cb() ctrl[ACHITRAPUR-LT] authenticated successfully session[clbmgmbe41mbggvs1l202806w]
SDKd: ziti-sdk:ziti.c:1449 api_session_cb() ztx[0] logged in successfully => api_session[clbmgmbe41mbggvs1l202806w]
SDKd: ziti-sdk:ziti.c:1399 ziti_set_api_session() ztx[0] ziti api session expires in 1800 seconds
SDKi: ziti-sdk:ziti.c:1423 ziti_set_api_session() ztx[0] api session set, setting api_session_timer to 1740s
SDKd: ziti-sdk:ziti.c:329 ziti_schedule_api_session_refresh() ztx[0] ziti_schedule_api_session_refresh: scheduling api session refresh: 1740000ms
SDKd: ziti-sdk:ziti.c:284 ziti_set_fully_authenticated() ztx[0] setting api_session_state[1] to 3
SDKd: ziti-sdk:ziti.c:284 ziti_set_fully_authenticated() ztx[0] setting api_session_state[3] to 3
SDKd: ziti-sdk:ziti_ctrl.c:774 ctrl_paging_req() ctrl[ACHITRAPUR-LT] starting paging request GET[/current-identity/edge-routers]
SDKd: ziti-sdk:ziti_ctrl.c:324 ctrl_body_cb() ctrl[ACHITRAPUR-LT] completed GET[/current-identity] in 0.003 s
SDKd: ziti-sdk:ziti_ctrl.c:324 ctrl_body_cb() ctrl[ACHITRAPUR-LT] completed GET[/current-identity] in 0.003 s
SDKd: ziti-sdk:ziti_ctrl.c:324 ctrl_body_cb() ctrl[ACHITRAPUR-LT] completed GET[/current-identity/edge-routers?limit=25&offset=0] in 0.006 s
SDKd: ziti-sdk:ziti_ctrl.c:340 ctrl_body_cb() ctrl[ACHITRAPUR-LT] received 1/1 for paging request GET[/current-identity/edge-routers]
SDKd: ziti-sdk:ziti_ctrl.c:352 ctrl_body_cb() ctrl[ACHITRAPUR-LT] completed paging request GET[/current-identity/edge-routers] in 0.006 s
SDKi: ziti-sdk:channel.c:231 new_ziti_channel() ch[0] (ACHITRAPUR-LT-edge-router@tls://ACHITRAPUR-LT:3022) new channel for ztx[0] identity[weather.demo]
SDKi: ziti-sdk:channel.c:742 reconnect_channel() ch[0] reconnecting NOW
SDKd: ziti-sdk:channel.c:713 reconnect_cb() ch[0] connecting to ACHITRAPUR-LT:3022
SDKd: ziti-sdk:ziti_ctrl.c:324 ctrl_body_cb() ctrl[ACHITRAPUR-LT] completed GET[/current-api-session/service-updates] in 0.042 s
SDKd: ziti-sdk:ziti_ctrl.c:774 ctrl_paging_req() ctrl[ACHITRAPUR-LT] starting paging request GET[/services]
SDKd: ziti-sdk:ziti_ctrl.c:324 ctrl_body_cb() ctrl[ACHITRAPUR-LT] completed GET[/services?limit=25&offset=0] in 0.003 s
SDKd: ziti-sdk:ziti_ctrl.c:340 ctrl_body_cb() ctrl[ACHITRAPUR-LT] received 1/1 for paging request GET[/services]
SDKd: ziti-sdk:ziti_ctrl.c:352 ctrl_body_cb() ctrl[ACHITRAPUR-LT] completed paging request GET[/services] in 0.007 s
SDKd: ziti-sdk:ziti.c:1146 update_services() ztx[0] sending service event 1 added, 0 removed, 0 changed
SDKd: ziti-sdk:channel.c:861 on_channel_connect_internal() ch[0] connected
SDKi: ziti-sdk:channel.c:640 hello_reply_cb() ch[0] connected. EdgeRouter version: v0.26.11|807dd591b1f5|2022-11-10T14:53:29Z|linux|amd64
SDKi: ziti-sdk:posture.c:204 ziti_send_posture_data() ztx[0] first run or potential controller restart detected
SDKd: ziti-sdk:posture.c:211 ziti_send_posture_data() ztx[0] posture checks must_send set to TRUE, new_session_id[TRUE], must_send_every_time[TRUE], new_controller_instance[TRUE]
Request:
Method: GET, RequestUri: ‘https://wttr.in/’, Version: 1.1, Content: , Headers:
{
User-Agent: curl/7.59.0
}
SDKd: ziti-sdk:zitilib.c:634 do_ziti_connect() connecting fd[1632] to (null):0
SDKd: ziti-sdk:zitilib.c:689 do_ziti_connect() connecting fd[1632] to service[weather-svc]
SDKd: ziti-sdk:posture.c:211 ziti_send_posture_data() ztx[0] posture checks must_send set to TRUE, new_session_id[FALSE], must_send_every_time[TRUE], new_controller_instance[FALSE]
SDKd: ziti-sdk:connect.c:518 process_connect() conn[0.0/Connecting] requesting ‘Dial’ session for service[weather-svc]
SDKd: ziti-sdk:ziti_ctrl.c:324 ctrl_body_cb() ctrl[ACHITRAPUR-LT] completed POST[/sessions] in 0.013 s
SDKd: ziti-sdk:connect.c:480 connect_get_net_session_cb() conn[0.0/Connecting] got session[clbmgmfud1mbogvs120lnq0so] for service[weather-svc]
SDKd: ziti-sdk:posture.c:211 ziti_send_posture_data() ztx[0] posture checks must_send set to TRUE, new_session_id[FALSE], must_send_every_time[TRUE], new_controller_instance[FALSE]
SDKd: ziti-sdk:connect.c:528 process_connect() conn[0.0/Connecting] starting Dial connection for service[weather-svc] with session[clbmgmfud1mbogvs120lnq0so]
SDKd: ziti-sdk:connect.c:411 ziti_connect() conn[0.0/Connecting] selected ch[ACHITRAPUR-LT-edge-router@tls://ACHITRAPUR-LT:3022] for best latency(6 ms)
SDKd: ziti-sdk:connect.c:299 on_channel_connected() conn[0.0/Connecting] selected ch[ACHITRAPUR-LT-edge-router@tls://ACHITRAPUR-LT:3022] status[0]
SDKd: ziti-sdk:channel.c:211 ziti_channel_add_receiver() ch[0] added receiver[0]
SDKi: ziti-sdk:channel.c:837 on_channel_data() ch[0] channel was closed [-4095/end of file]
SDKe: ziti-sdk:connect.c:926 connect_reply_cb() conn[0.0/Connecting] failed to connect [-20/Unknown system error -20]
SDKw: ziti-sdk:zitilib.c:591 on_ziti_connect() failed to establish ziti connection: -23(connection is closed)
SDKd: ziti-sdk:zitilib.c:565 on_bridge_close() closed conn for socket(1632)
SDKd: ziti-sdk:connect.c:1404 queue_edge_message() conn[0.0/Closed] closed due to err[-20](Connection to edge router terminated)
SDKw: ziti-sdk:connect.c:1417 queue_edge_message() conn[0.0/Closed] disconnecting from state[9]
SDKd: ziti-sdk:ziti.c:334 ziti_force_api_session_refresh() ztx[0] forcing session refresh
SDKd: ziti-sdk:ziti.c:329 ziti_schedule_api_session_refresh() ztx[0] ziti_schedule_api_session_refresh: scheduling api session refresh: 0ms
SDKi: ziti-sdk:channel.c:739 reconnect_channel() ch[0] reconnecting in 8863 ms (attempt = 1)
SDKi: ziti-sdk:channel.c:837 on_channel_data() ch[0] channel was closed [-4095/end of file]
SDKd: ziti-sdk:ziti.c:334 ziti_force_api_session_refresh() ztx[0] forcing session refresh
SDKd: ziti-sdk:ziti.c:329 ziti_schedule_api_session_refresh() ztx[0] ziti_schedule_api_session_refresh: scheduling api session refresh: 0ms
SDKi: ziti-sdk:channel.c:739 reconnect_channel() ch[0] reconnecting in 14292 ms (attempt = 2)
SDKd: ziti-sdk:ziti.c:840 api_session_refresh() ztx[0] api_session_refresh running
SDKd: ziti-sdk:ziti.c:302 is_api_session_expired() ztx[0] is_api_session_expired[FALSE] - default case
SDKd: ziti-sdk:ziti.c:855 api_session_refresh() ztx[0] api_session_refresh refreshing api session by querying controllerSDKd: ziti-sdk:ziti_ctrl.c:324 ctrl_body_cb() ctrl[ACHITRAPUR-LT] completed GET[/current-api-session] in 0.075 s
SDKd: ziti-sdk:ziti_ctrl.c:257 ctrl_login_cb() ctrl[ACHITRAPUR-LT] authenticated successfully session[clbmgmbe41mbggvs1l202806w]
SDKd: ziti-sdk:ziti.c:1449 api_session_cb() ztx[0] refreshed successfully => api_session[clbmgmbe41mbggvs1l202806w]
SDKd: ziti-sdk:ziti.c:1399 ziti_set_api_session() ztx[0] ziti api session expires in 1794 seconds
SDKi: ziti-sdk:ziti.c:1423 ziti_set_api_session() ztx[0] api session set, setting api_session_timer to 1740s
SDKd: ziti-sdk:ziti.c:329 ziti_schedule_api_session_refresh() ztx[0] ziti_schedule_api_session_refresh: scheduling api session refresh: 1740000ms
SDKd: ziti-sdk:ziti.c:284 ziti_set_fully_authenticated() ztx[0] setting api_session_state[3] to 3
SDKd: ziti-sdk:ziti.c:284 ziti_set_fully_authenticated() ztx[0] setting api_session_state[3] to 3
SDKd: ziti-sdk:ziti_ctrl.c:774 ctrl_paging_req() ctrl[ACHITRAPUR-LT] starting paging request GET[/current-identity/edge-routers]
SDKd: ziti-sdk:channel.c:713 reconnect_cb() ch[0] connecting to ACHITRAPUR-LT:3022
SDKd: ziti-sdk:posture.c:211 ziti_send_posture_data() ztx[0] posture checks must_send set to TRUE, new_session_id[FALSE], must_send_every_time[TRUE], new_controller_instance[FALSE]
Sample failed to execute: One or more errors occurred. (connection is closed (wttr.in:443))
SDKd: ziti-sdk:channel.c:861 on_channel_connect_internal() ch[0] connected
SDKi: ziti-sdk:channel.c:640 hello_reply_cb() ch[0] connected. EdgeRouter version: v0.26.11|807dd591b1f5|2022-11-10T14:53:29Z|linux|amd64
SDKd: ziti-sdk:ziti_ctrl.c:324 ctrl_body_cb() ctrl[ACHITRAPUR-LT] completed GET[/current-identity] in 45.061 s
SDKd: ziti-sdk:ziti_ctrl.c:324 ctrl_body_cb() ctrl[ACHITRAPUR-LT] completed GET[/current-identity] in 45.059 s
SDKd: ziti-sdk:ziti_ctrl.c:324 ctrl_body_cb() ctrl[ACHITRAPUR-LT] completed GET[/current-identity/edge-routers?limit=25&offset=0] in 0.162 s
SDKd: ziti-sdk:ziti_ctrl.c:340 ctrl_body_cb() ctrl[ACHITRAPUR-LT] received 1/1 for paging request GET[/current-identity/edge-routers]
SDKd: ziti-sdk:ziti_ctrl.c:352 ctrl_body_cb() ctrl[ACHITRAPUR-LT] completed paging request GET[/current-identity/edge-routers] in 45.061 s
SDKd: ziti-sdk:channel.c:276 ziti_channel_connect() ztx[0] existing ch0 found for ingress[tls://ACHITRAPUR-LT:3022]
SDKd: ziti-sdk:ziti_ctrl.c:324 ctrl_body_cb() ctrl[ACHITRAPUR-LT] completed GET[/current-api-session/service-updates] in 0.157 s
at System.Threading.Tasks.Task.ThrowIfExceptional(Boolean includeTaskCanceledExceptions)
at System.Threading.Tasks.Task1.GetResultCore(Boolean waitCompletionNotification) at System.Threading.Tasks.Task
1.get_Result()
at OpenZiti.Samples.Weather.Run(String args) in C:\Repos\GitHub\Samples\Weather.cs:line 41
at OpenZiti.Samples.Program.Main(String args) in C:\Repos\GitHub\Samples\Program.cs:line 26