app@deb92:~/blade/vm/mod_signalwire$ rm testcli app@deb92:~/blade/vm/mod_signalwire$ make testcli gcc -o testcli -I/usr/include/libks -I/usr/local/include/signalwire-client-c -lks -L/usr/local/lib -lsignalwire_client -lconfig testcli.c app@deb92:~/blade/vm/mod_signalwire$ ./testcli 127.0.0.1:2100 a.json [DEBUG] 17:25:00 #000061EA 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:25:00 #000061EA ks_thread.c:783 __ks_thread_create_ex Thread state change: KS_THREAD_CALLER_STATE_INIT => KS_THREAD_STARTING, address: 0x7ffe043f07c8, tid: 00000000 [DEBUG] 17:25:00 #000061EA ks_thread.c:786 __ks_thread_create_ex Thread state change: KS_THREAD_CALLER_STATE_INIT => KS_THREAD_CALLER_STATE_START_REQUESTED, address: 0x7ffe043f07c8, tid: 00000000 [DEBUG] 17:25:00 #000061EA ks_thread.c:794 __ks_thread_create_ex Waiting for thread thread to set running, with address: 0x5609595f8358, tid: 00000000 [DEBUG] 17:25:00 #000061EB ks_thread.c:203 thread_launch Thread has launched with address: 0x5609595f8358, tid: 00000000 [DEBUG] 17:25:00 #000061EB ks_thread.c:217 thread_launch Marking thread as running, with address: 0x5609595f8358, tid: 00000000 [DEBUG] 17:25:00 #000061EB ks_thread.c:224 thread_launch Thread state change: KS_THREAD_STARTING => KS_THREAD_RUNNING, address: 0x7f6d50d44850, tid: 00000000 [DEBUG] 17:25:00 #000061EA ks_thread.c:821 __ks_thread_create_ex Allocated (attached) thread context ptr: 0x5609595f8358, tid: 000061eb [DEBUG] 17:25:00 #000061EB ks_thread.c:244 thread_launch START call user thread callback with address: 0x5609595f8358, tid: 000061eb [INFO] 17:25:00 #000061EB handle_manager.c:222 __manager_loop Manager loop starting [INFO] 17:25:00 #000061EB handle_manager.c:202 __requested_service_in_ms Manager over due for service, last service interval was 1530091500948ms ago (max allowed is 300000ms) [INFO] 17:25:00 #000061EB handle_manager.c:238 __manager_loop Manager sleeping for service in: 1ms [INFO] 17:25:00 #000061EB handle_manager.c:244 __manager_loop Manager timed out waiting for 1ms [INFO] 17:25:00 #000061EB handle_manager.c:147 __service_handle_type Service manager enumerating handles of type: Connection [INFO] 17:25:00 #000061EB handle_manager.c:162 __service_handle_type Service manager serviced: 0 handles of type: Connection [INFO] 17:25:00 #000061EB handle_manager.c:147 __service_handle_type Service manager enumerating handles of type: Session [INFO] 17:25:00 #000061EB handle_manager.c:162 __service_handle_type Service manager serviced: 0 handles of type: Session [INFO] 17:25:00 #000061EB handle_manager.c:147 __service_handle_type Service manager enumerating handles of type: Websocket [INFO] 17:25:00 #000061EB handle_manager.c:162 __service_handle_type Service manager serviced: 0 handles of type: Websocket [INFO] 17:25:00 #000061EB handle_manager.c:238 __manager_loop Manager sleeping for service in: 300000ms [DEBUG] 17:25:00 #000061EA ks_handle.c:284 __ks_handle_alloc_ex ALLOC - GROUP: 10 TOTAL: 0 [DEBUG] 17:25:00 #000061EA ks_handle.c:340 __ks_handle_alloc_ex ALLOCHANDLE: 000a0003f08f0001 [INFO] 17:25:00 #000061EA testcli.c:225 main connecting to 127.0.0.1:2100 ... [DEBUG] 17:25:00 #000061EA ks_handle.c:284 __ks_handle_alloc_ex ALLOC - GROUP: 11 TOTAL: 0 [DEBUG] 17:25:00 #000061EA ks_handle.c:340 __ks_handle_alloc_ex ALLOCHANDLE: 000b000538d00001 [DEBUG] 17:25:00 #000061EA identity.c:12 swclt_ident_from_str Parsing 127.0.0.1:2100 [DEBUG] 17:25:00 #000061EA ks_handle.c:284 __ks_handle_alloc_ex ALLOC - GROUP: 10 TOTAL: 0 [DEBUG] 17:25:00 #000061EA ks_handle.c:340 __ks_handle_alloc_ex ALLOCHANDLE: 000a0002f0900002 [DEBUG] 17:25:00 #000061EA ks_handle.c:284 __ks_handle_alloc_ex ALLOC - GROUP: 10 TOTAL: 0 [DEBUG] 17:25:00 #000061EA ks_handle.c:340 __ks_handle_alloc_ex ALLOCHANDLE: 000a0004f0910003 [ERROR] 17:25:00 #000061EA ks_handle.c:209 __lookup_allocated_slot VALIDATION FAILED : 35 [INFO] 17:25:00 #000061EA 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:25:00 #000061EA ks_handle.c:209 __lookup_allocated_slot VALIDATION FAILED : 35 [INFO] 17:25:00 #000061EA 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:25:00 #000061EA handle_manager.c:180 __set_sleep_amount_ms Client service interval: 1ms is < current service interval of: 300000ms, signalling manager [INFO] 17:25:00 #000061EB handle_manager.c:257 __manager_loop Manager signalled, checking sleep amount [INFO] 17:25:00 #000061EB handle_manager.c:238 __manager_loop Manager sleeping for service in: 3000ms [INFO] 17:25:03 #000061EB handle_manager.c:244 __manager_loop Manager timed out waiting for 3000ms [INFO] 17:25:03 #000061EB handle_manager.c:147 __service_handle_type Service manager enumerating handles of type: Connection [INFO] 17:25:03 #000061EB handle_manager.c:162 __service_handle_type Service manager serviced: 0 handles of type: Connection [INFO] 17:25:03 #000061EB handle_manager.c:147 __service_handle_type Service manager enumerating handles of type: Session [ERROR] 17:25:03 #000061EB ks_handle.c:209 __lookup_allocated_slot VALIDATION FAILED : 35 [INFO] 17:25:03 #000061EB handle_manager.c:155 __service_handle_type Service begin: SWCLT Session - SWCLT Session - get failed: 35 [INFO] 17:25:03 #000061EB session.c:214 __do_connect Session is performing connect [ERROR] 17:25:03 #000061EB ks_handle.c:209 __lookup_allocated_slot VALIDATION FAILED : 35 [INFO] 17:25:03 #000061EB handle_manager.c:112 __service_handle State change attempt (Normal=>Online) failed (21) re-queueing in: 5000ms [ERROR] 17:25:03 #000061EB ks_handle.c:209 __lookup_allocated_slot VALIDATION FAILED : 35 [INFO] 17:25:03 #000061EB 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:25:03 #000061EB ks_handle.c:209 __lookup_allocated_slot VALIDATION FAILED : 35 [INFO] 17:25:03 #000061EB 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:25:03 #000061EB handle_manager.c:180 __set_sleep_amount_ms Client service interval: 5000ms is < current service interval of: 300000ms, signalling manager [ERROR] 17:25:03 #000061EB ks_handle.c:209 __lookup_allocated_slot VALIDATION FAILED : 35 [INFO] 17:25:03 #000061EB handle_manager.c:157 __service_handle_type Service end: SWCLT Session - SWCLT Session - get failed: 35 [INFO] 17:25:03 #000061EB handle_manager.c:162 __service_handle_type Service manager serviced: 1 handles of type: Session [INFO] 17:25:03 #000061EB handle_manager.c:147 __service_handle_type Service manager enumerating handles of type: Websocket [INFO] 17:25:03 #000061EB handle_manager.c:162 __service_handle_type Service manager serviced: 0 handles of type: Websocket [INFO] 17:25:03 #000061EB handle_manager.c:238 __manager_loop Manager sleeping for service in: 3000ms [INFO] 17:25:06 #000061EB handle_manager.c:244 __manager_loop Manager timed out waiting for 3000ms [INFO] 17:25:06 #000061EB handle_manager.c:147 __service_handle_type Service manager enumerating handles of type: Connection [INFO] 17:25:06 #000061EB handle_manager.c:162 __service_handle_type Service manager serviced: 0 handles of type: Connection [INFO] 17:25:06 #000061EB handle_manager.c:147 __service_handle_type Service manager enumerating handles of type: Session [ERROR] 17:25:06 #000061EB ks_handle.c:209 __lookup_allocated_slot VALIDATION FAILED : 35 [INFO] 17:25:06 #000061EB handle_manager.c:155 __service_handle_type Service begin: SWCLT Session - SWCLT Session - get failed: 35 [INFO] 17:25:06 #000061EB session.c:214 __do_connect Session is performing connect [ERROR] 17:25:06 #000061EB ks_handle.c:209 __lookup_allocated_slot VALIDATION FAILED : 35 [INFO] 17:25:06 #000061EB handle_manager.c:112 __service_handle State change attempt (Normal=>Online) failed (21) re-queueing in: 5000ms [ERROR] 17:25:06 #000061EB ks_handle.c:209 __lookup_allocated_slot VALIDATION FAILED : 35 [INFO] 17:25:06 #000061EB 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:25:06 #000061EB ks_handle.c:209 __lookup_allocated_slot VALIDATION FAILED : 35 [INFO] 17:25:06 #000061EB 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:25:06 #000061EB handle_manager.c:180 __set_sleep_amount_ms Client service interval: 5000ms is < current service interval of: 300000ms, signalling manager [ERROR] 17:25:06 #000061EB ks_handle.c:209 __lookup_allocated_slot VALIDATION FAILED : 35 [INFO] 17:25:06 #000061EB handle_manager.c:157 __service_handle_type Service end: SWCLT Session - SWCLT Session - get failed: 35 [INFO] 17:25:06 #000061EB handle_manager.c:162 __service_handle_type Service manager serviced: 1 handles of type: Session [INFO] 17:25:06 #000061EB handle_manager.c:147 __service_handle_type Service manager enumerating handles of type: Websocket [INFO] 17:25:06 #000061EB handle_manager.c:162 __service_handle_type Service manager serviced: 0 handles of type: Websocket [INFO] 17:25:06 #000061EB handle_manager.c:238 __manager_loop Manager sleeping for service in: 3000ms [INFO] 17:25:09 #000061EB handle_manager.c:244 __manager_loop Manager timed out waiting for 3000ms [INFO] 17:25:09 #000061EB handle_manager.c:147 __service_handle_type Service manager enumerating handles of type: Connection [INFO] 17:25:09 #000061EB handle_manager.c:162 __service_handle_type Service manager serviced: 0 handles of type: Connection [INFO] 17:25:09 #000061EB handle_manager.c:147 __service_handle_type Service manager enumerating handles of type: Session [ERROR] 17:25:09 #000061EB ks_handle.c:209 __lookup_allocated_slot VALIDATION FAILED : 35 [INFO] 17:25:09 #000061EB handle_manager.c:155 __service_handle_type Service begin: SWCLT Session - SWCLT Session - get failed: 35 [INFO] 17:25:09 #000061EB session.c:214 __do_connect Session is performing connect [ERROR] 17:25:09 #000061EB ks_handle.c:209 __lookup_allocated_slot VALIDATION FAILED : 35 [INFO] 17:25:09 #000061EB handle_manager.c:112 __service_handle State change attempt (Normal=>Online) failed (21) re-queueing in: 5000ms [ERROR] 17:25:09 #000061EB ks_handle.c:209 __lookup_allocated_slot VALIDATION FAILED : 35 [INFO] 17:25:09 #000061EB 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:25:09 #000061EB ks_handle.c:209 __lookup_allocated_slot VALIDATION FAILED : 35 [INFO] 17:25:09 #000061EB 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:25:09 #000061EB handle_manager.c:180 __set_sleep_amount_ms Client service interval: 5000ms is < current service interval of: 300000ms, signalling manager [ERROR] 17:25:09 #000061EB ks_handle.c:209 __lookup_allocated_slot VALIDATION FAILED : 35 [INFO] 17:25:09 #000061EB handle_manager.c:157 __service_handle_type Service end: SWCLT Session - SWCLT Session - get failed: 35 [INFO] 17:25:09 #000061EB handle_manager.c:162 __service_handle_type Service manager serviced: 1 handles of type: Session [INFO] 17:25:09 #000061EB handle_manager.c:147 __service_handle_type Service manager enumerating handles of type: Websocket [INFO] 17:25:09 #000061EB handle_manager.c:162 __service_handle_type Service manager serviced: 0 handles of type: Websocket [INFO] 17:25:09 #000061EB handle_manager.c:238 __manager_loop Manager sleeping for service in: 3000ms