- app@deb92:~/blade/vm/mod_signalwire$ ./testcli 127.0.0.1:2100 a.json
- [DEBUG] 17:23:07 #000061C4 ks_thread.c:768 __ks_thread_create_ex Allocating new thread, current active and attached count: 1, current active and detatched count: 0
- [DEBUG] 17:23:07 #000061C4 ks_thread.c:783 __ks_thread_create_ex Thread state change: KS_THREAD_CALLER_STATE_INIT => KS_THREAD_STARTING, address: 0x7fff757d4f18, tid: 00000000
- [DEBUG] 17:23:07 #000061C4 ks_thread.c:786 __ks_thread_create_ex Thread state change: KS_THREAD_CALLER_STATE_INIT => KS_THREAD_CALLER_STATE_START_REQUESTED, address: 0x7fff757d4f18, tid: 00000000
- [DEBUG] 17:23:07 #000061C4 ks_thread.c:794 __ks_thread_create_ex Waiting for thread thread to set running, with address: 0x561a0fbcb358, tid: 00000000
- [DEBUG] 17:23:07 #000061C5 ks_thread.c:203 thread_launch Thread has launched with address: 0x561a0fbcb358, tid: 00000000
- [DEBUG] 17:23:07 #000061C5 ks_thread.c:217 thread_launch Marking thread as running, with address: 0x561a0fbcb358, tid: 00000000
- [DEBUG] 17:23:07 #000061C5 ks_thread.c:224 thread_launch Thread state change: KS_THREAD_STARTING => KS_THREAD_RUNNING, address: 0x7f2a174be850, tid: 00000000
- [DEBUG] 17:23:07 #000061C4 ks_thread.c:821 __ks_thread_create_ex Allocated (attached) thread context ptr: 0x561a0fbcb358, tid: 000061c5
- [DEBUG] 17:23:07 #000061C5 ks_thread.c:244 thread_launch START call user thread callback with address: 0x561a0fbcb358, tid: 000061c5
- [INFO] 17:23:07 #000061C5 handle_manager.c:222 __manager_loop Manager loop starting
- [INFO] 17:23:07 #000061C5 handle_manager.c:202 __requested_service_in_ms Manager over due for service, last service interval was 1530091387618ms ago (max allowed is 300000ms)
- [INFO] 17:23:07 #000061C5 handle_manager.c:238 __manager_loop Manager sleeping for service in: 1ms
- [INFO] 17:23:07 #000061C5 handle_manager.c:244 __manager_loop Manager timed out waiting for 1ms
- [INFO] 17:23:07 #000061C5 handle_manager.c:147 __service_handle_type Service manager enumerating handles of type: Connection
- [DEBUG] 17:23:07 #000061C4 ks_handle.c:284 __ks_handle_alloc_ex ALLOC - GROUP: 10 TOTAL: 0
- [DEBUG] 17:23:07 #000061C4 ks_handle.c:340 __ks_handle_alloc_ex ALLOCHANDLE: 000a0003a4d60001
- [INFO] 17:23:07 #000061C4 testcli.c:225 main connecting to 127.0.0.1:2100 ...
- [INFO] 17:23:07 #000061C5 handle_manager.c:162 __service_handle_type Service manager serviced: 0 handles of type: Connection
- [INFO] 17:23:07 #000061C5 handle_manager.c:147 __service_handle_type Service manager enumerating handles of type: Session
- [INFO] 17:23:07 #000061C5 handle_manager.c:162 __service_handle_type Service manager serviced: 0 handles of type: Session
- [INFO] 17:23:07 #000061C5 handle_manager.c:147 __service_handle_type Service manager enumerating handles of type: Websocket
- [DEBUG] 17:23:07 #000061C4 ks_handle.c:284 __ks_handle_alloc_ex ALLOC - GROUP: 11 TOTAL: 0
- [DEBUG] 17:23:07 #000061C4 ks_handle.c:340 __ks_handle_alloc_ex ALLOCHANDLE: 000b0005f5b80001
- [DEBUG] 17:23:07 #000061C4 identity.c:12 swclt_ident_from_str Parsing 127.0.0.1:2100
- [INFO] 17:23:07 #000061C5 handle_manager.c:162 __service_handle_type Service manager serviced: 0 handles of type: Websocket
- [INFO] 17:23:07 #000061C5 handle_manager.c:238 __manager_loop Manager sleeping for service in: 300000ms
- [DEBUG] 17:23:07 #000061C4 ks_handle.c:284 __ks_handle_alloc_ex ALLOC - GROUP: 10 TOTAL: 0
- [DEBUG] 17:23:07 #000061C4 ks_handle.c:340 __ks_handle_alloc_ex ALLOCHANDLE: 000a0002a4d70002
- [DEBUG] 17:23:07 #000061C4 ks_handle.c:284 __ks_handle_alloc_ex ALLOC - GROUP: 10 TOTAL: 0
- [DEBUG] 17:23:07 #000061C4 ks_handle.c:340 __ks_handle_alloc_ex ALLOCHANDLE: 000a0004a4d80003
- [ERROR] 17:23:07 #000061C4 ks_handle.c:209 __lookup_allocated_slot VALIDATION FAILED : 35
- [INFO] 17:23:07 #000061C4 handle_state.c:233 __swclt_hstate_initiate_change_in Handle: Normal is initiating state change (state change: Online=>SWCLT Session - SWCLT Session - get failed: 35) in: 1ms
- [ERROR] 17:23:07 #000061C4 ks_handle.c:209 __lookup_allocated_slot VALIDATION FAILED : 35
- [INFO] 17:23:07 #000061C4 handle_manager.c:314 swclt_hmgr_request_service_in Handle: SWCLT Session - SWCLT Session - get failed: 35 is requesting manager service in: 1ms
- [INFO] 17:23:07 #000061C4 handle_manager.c:180 __set_sleep_amount_ms Client service interval: 1ms is < current service interval of: 300000ms, signalling manager
- [INFO] 17:23:07 #000061C5 handle_manager.c:257 __manager_loop Manager signalled, checking sleep amount
- [INFO] 17:23:07 #000061C5 handle_manager.c:238 __manager_loop Manager sleeping for service in: 3000ms
- [INFO] 17:23:10 #000061C5 handle_manager.c:244 __manager_loop Manager timed out waiting for 3000ms
- [INFO] 17:23:10 #000061C5 handle_manager.c:147 __service_handle_type Service manager enumerating handles of type: Connection
- [INFO] 17:23:10 #000061C5 handle_manager.c:162 __service_handle_type Service manager serviced: 0 handles of type: Connection
- [INFO] 17:23:10 #000061C5 handle_manager.c:147 __service_handle_type Service manager enumerating handles of type: Session
- [ERROR] 17:23:10 #000061C5 ks_handle.c:209 __lookup_allocated_slot VALIDATION FAILED : 35
- [INFO] 17:23:10 #000061C5 handle_manager.c:155 __service_handle_type Service begin: SWCLT Session - SWCLT Session - get failed: 35
- [INFO] 17:23:10 #000061C5 session.c:214 __do_connect Session is performing connect
- [ERROR] 17:23:10 #000061C5 ks_handle.c:209 __lookup_allocated_slot VALIDATION FAILED : 35
- [INFO] 17:23:10 #000061C5 handle_manager.c:112 __service_handle State change attempt (Normal=>Online) failed (21) re-queueing in: 5000ms
- [ERROR] 17:23:10 #000061C5 ks_handle.c:209 __lookup_allocated_slot VALIDATION FAILED : 35
- [INFO] 17:23:10 #000061C5 handle_state.c:233 __swclt_hstate_initiate_change_in Handle: Normal is initiating state change (state change: Online=>SWCLT Session - SWCLT Session - get failed: 35) in: 5000ms
- [ERROR] 17:23:10 #000061C5 ks_handle.c:209 __lookup_allocated_slot VALIDATION FAILED : 35
- [INFO] 17:23:10 #000061C5 handle_manager.c:314 swclt_hmgr_request_service_in Handle: SWCLT Session - SWCLT Session - get failed: 35 is requesting manager service in: 5000ms
- [INFO] 17:23:10 #000061C5 handle_manager.c:180 __set_sleep_amount_ms Client service interval: 5000ms is < current service interval of: 300000ms, signalling manager
- [ERROR] 17:23:10 #000061C5 ks_handle.c:209 __lookup_allocated_slot VALIDATION FAILED : 35
- [INFO] 17:23:10 #000061C5 handle_manager.c:157 __service_handle_type Service end: SWCLT Session - SWCLT Session - get failed: 35
- [INFO] 17:23:10 #000061C5 handle_manager.c:162 __service_handle_type Service manager serviced: 1 handles of type: Session
- [INFO] 17:23:10 #000061C5 handle_manager.c:147 __service_handle_type Service manager enumerating handles of type: Websocket
- [INFO] 17:23:10 #000061C5 handle_manager.c:162 __service_handle_type Service manager serviced: 0 handles of type: Websocket
- [INFO] 17:23:10 #000061C5 handle_manager.c:238 __manager_loop Manager sleeping for service in: 3000ms
- ^C