{"action":"opened","issue":{"url":"https://api.github.com/repos/kewinrausch/Test/issues/217","labels_url":"https://api.github.com/repos/kewinrausch/Test/issues/217/labels{/name}","comments_url":"https://api.github.com/repos/kewinrausch/Test/issues/217/comments","events_url":"https://api.github.com/repos/kewinrausch/Test/issues/217/events","html_url":"https://github.com/kewinrausch/Test/issues/217","id":56944035,"number":217,"title":"[CLOSED] Syscall read fails","user":{"login":"kewinrausch","id":8994703,"avatar_url":"https://avatars.githubusercontent.com/u/8994703?v=3","gravatar_id":"","url":"https://api.github.com/users/kewinrausch","html_url":"https://github.com/kewinrausch","followers_url":"https://api.github.com/users/kewinrausch/followers","following_url":"https://api.github.com/users/kewinrausch/following{/other_user}","gists_url":"https://api.github.com/users/kewinrausch/gists{/gist_id}","starred_url":"https://api.github.com/users/kewinrausch/starred{/owner}{/repo}","subscriptions_url":"https://api.github.com/users/kewinrausch/subscriptions","organizations_url":"https://api.github.com/users/kewinrausch/orgs","repos_url":"https://api.github.com/users/kewinrausch/repos","events_url":"https://api.github.com/users/kewinrausch/events{/privacy}","received_events_url":"https://api.github.com/users/kewinrausch/received_events","type":"User","site_admin":false},"labels":[],"state":"open","locked":false,"assignee":null,"milestone":{"url":"https://api.github.com/repos/kewinrausch/Test/milestones/3","labels_url":"https://api.github.com/repos/kewinrausch/Test/milestones/3/labels","id":969802,"number":3,"title":"v0.9 Cleanups & fixes","description":"","creator":{"login":"kewinrausch","id":8994703,"avatar_url":"https://avatars.githubusercontent.com/u/8994703?v=3","gravatar_id":"","url":"https://api.github.com/users/kewinrausch","html_url":"https://github.com/kewinrausch","followers_url":"https://api.github.com/users/kewinrausch/followers","following_url":"https://api.github.com/users/kewinrausch/following{/other_user}","gists_url":"https://api.github.com/users/kewinrausch/gists{/gist_id}","starred_url":"https://api.github.com/users/kewinrausch/starred{/owner}{/repo}","subscriptions_url":"https://api.github.com/users/kewinrausch/subscriptions","organizations_url":"https://api.github.com/users/kewinrausch/orgs","repos_url":"https://api.github.com/users/kewinrausch/repos","events_url":"https://api.github.com/users/kewinrausch/events{/privacy}","received_events_url":"https://api.github.com/users/kewinrausch/received_events","type":"User","site_admin":false},"open_issues":25,"closed_issues":0,"state":"open","created_at":"2015-02-07T10:52:53Z","updated_at":"2015-02-08T08:31:32Z","due_on":null,"closed_at":null},"comments":0,"created_at":"2015-02-08T08:31:32Z","updated_at":"2015-02-08T08:31:32Z","closed_at":null,"body":"<a href=\"https://github.com/sandervrijders\"><img src=\"https://avatars.githubusercontent.com/u/1212456?v=3\" align=\"left\" width=\"96\" height=\"96\" hspace=\"10\"></img></a> **Issue by [sandervrijders](https://github.com/sandervrijders)**\n_Tuesday Nov 19, 2013 at 12:23 GMT_\n_Originally opened as https://github.com/dana-i2cat/irati/issues/217_\n\n----\n\nServer\r\n\r\nsander@m:/usr/local/irati/bin$ sudo ./echo-server \r\n4123(1384860962)#concurrency (DBG): Lockable created successfully\r\n[-role, server]\r\n4123(1384860962)#concurrency (DBG): Lockable created successfully\r\n4123(1384860962)#netlink-manager (DBG): Netlink Manager constructor called, with netlink pid = 4123\r\n4123(1384860962)#netlink-manager (INFO): Netlink socket connected to local port 4123 \r\n4123(1384860962)#netlink-manager (DBG): Generic Netlink RINA family id: 21\r\n4123(1384860962)#core (DBG): Initialized Netlink Manager\r\n4123(1384860962)#concurrency (DBG): Lockable created successfully\r\n4123(1384860962)#concurrency (DBG): Condition variable created successfully\r\n4123(1384860962)#core (DBG): Initialized event queue\r\n4123(1384860962)#core (DBG): Started Netlink Message reader thread\r\n4123(1384860962)#core (DBG): Waiting for message 4123\r\n2013-11-19 12:36:02,310 - INFO rina.utils.apps.echo.utils.IPCEventConsumer - Added flow acceptor for application: -rina.utils.apps.echo-1---\r\n}4123(1384860962)#concurrency (DBG): Lockable created successfully\r\n4123(1384860962)#netlink-manager (DBG): Sent message of 92 bytes. Family: 21; Operation code: 38; Source port: 4123; Destination port: 1; Sequence Number: 1384860963\r\nIs request message? 1; Is response message? 0; Is notification message? 0\r\nSource IPC Process: 0; Destination IPC Process: 0\r\n2013-11-19 12:36:02,316 - INFO rina.utils.apps.echo.server.EchoServer - Requested registration of control AE: Process name: rina.utils.apps.echo; Process instance: 1; Entity name: ; Entity instance: \r\n}4123(1384860962)#netlink-manager (DBG): Received 124 bytes, parsing the message\r\n4123(1384860962)#netlink-manager (DBG): Source: 1, Netlink family: 21; Version: 1; Operation code: 39; Flags: 0; Sequence number: 1384860963\r\n4123(1384860962)#core (DBG): Received Netlink message. Family: 21; Operation code: 39; Source port: 1; Destination port: 4123; Sequence Number: 1384860963\r\nIs request message? 0; Is response message? 1; Is notification message? 0\r\nSource IPC Process: 0; Destination IPC Process: 0 \r\n4123(1384860962)#core (DBG): Added event of type 8 and sequence number 1384860963 to events queue\r\n4123(1384860962)#core (DBG): Waiting for message 4123\r\nIPC Event has happened: REGISTER_APPLICATION_RESPONSE_EVENT\r\n2013-11-19 12:36:02,324 - INFO rina.utils.apps.echo.server.EchoServer - Succesfully registered AE Process name: rina.utils.apps.echo; Process instance: 1; Entity name: ; Entity instance:  to DIF100\r\n}\r\n\r\nClient\r\n\r\n}3939(1384860928)#netlink-manager (DBG): Received 128 bytes, parsing the message\r\n3939(1384860928)#netlink-manager (DBG): Source: 1, Netlink family: 21; Version: 1; Operation code: 39; Flags: 0; Sequence number: 1384860929\r\n3939(1384860928)#core (DBG): Received Netlink message. Family: 21; Operation code: 39; Source port: 1; Destination port: 3939; Sequence Number: 1384860929\r\nIs request message? 0; Is response message? 1; Is notification message? 0\r\nSource IPC Process: 0; Destination IPC Process: 0 \r\n3939(1384860928)#core (DBG): Added event of type 8 and sequence number 1384860929 to events queue\r\n3939(1384860928)#core (DBG): Waiting for message 3939\r\nIPC Event has happened: REGISTER_APPLICATION_RESPONSE_EVENT\r\n2013-11-19 12:35:28,464 - INFO rina.utils.apps.echo.client.EchoClient - Succesfully registered AE Process name: rina.utils.apps.echoclient; Process instance: 1; Entity name: ; Entity instance:  to DIF100\r\n}3939(1384860928)#netlink-manager (DBG): Sent message of 184 bytes. Family: 21; Operation code: 31; Source port: 3939; Destination port: 1; Sequence Number: 1384860930\r\nIs request message? 1; Is response message? 0; Is notification message? 0\r\nSource IPC Process: 0; Destination IPC Process: 0\r\n3939(1384860928)#netlink-manager (DBG): Received 136 bytes, parsing the message\r\n3939(1384860928)#netlink-manager (DBG): Source: 1, Netlink family: 21; Version: 1; Operation code: 32; Flags: 0; Sequence number: 1384860930\r\n3939(1384860928)#core (DBG): Received Netlink message. Family: 21; Operation code: 32; Source port: 1; Destination port: 3939; Sequence Number: 1384860930\r\nIs request message? 0; Is response message? 1; Is notification message? 0\r\nSource IPC Process: 0; Destination IPC Process: 0 \r\n3939(1384860928)#core (DBG): Added event of type 1 and sequence number 1384860930 to events queue\r\n3939(1384860928)#core (DBG): Waiting for message 3939\r\nIPC Event has happened: ALLOCATE_FLOW_REQUEST_RESULT_EVENT\r\n3939(1384860928)#syscalls (DBG): Gonna call syscall SYS_writeSDU (354)\r\n2013-11-19 12:35:28,596 - INFO rina.utils.apps.echo.client.EchoClient - Requested echo server to start a test with the following parameters: \r\nNumber of SDUs: 30\r\nSDU size in bytes: 100\r\n\r\n}3939(1384860928)#syscalls (DBG): Gonna call syscall SYS_readSDU (353)\r\n3939(1384860930)#netlink-manager (DBG): Netlink Manager destructor called\r\n\r\n[ 3498.645390] rina-kipcm: Tring to write SDU of size 125 to port_id 1\r\n[ 3498.645458] rina-shim-eth-vlan: Entered the sdu write\r\n[ 3498.645523] rina-shim-eth-vlan: Found the flow associated with the id\r\n[ 3498.645591] rina-shim-eth-vlan: Converted the hw addresses\r\n[ 3498.645658] rina-shim-eth-vlan: Filling up the sk_buff\r\n[ 3498.645724] rina-shim-eth-vlan: Gonna send it now\r\n[ 3498.645792] rina-syscalls: Exiting SYSC_sdu_write syscall body\r\n[ 3498.648408] rina-syscalls: Entered SYSC_sdu_read syscall body\r\n[ 3498.648476] rina-syscalls: Handling personality hook sdu_read\r\n[ 3498.648543] rina-syscalls: Calling personality hook sdu_read\r\n[ 3498.648609] rina-personality-default: Calling wrapped function\r\n[ 3498.648676] rina-kfa: Trying to read SDU from port-id 1\r\n[ 3498.648742] rina-kfa: Going to sleep on wait queue f71e8464\r\n[ 3500.966030] rina-kfa: Woken up\r\n[ 3500.966099] rina-kfa: Wait-event interrupted, returned error -512\r\n[ 3500.966172] rina-kipcm: Failed to read sdu\r\n[ 3500.966196] rina-rnl: IPC Manager port: 1\r\n[ 3500.966200] rina-rnl-utils: Result of genlmesg_end: 32\r\n[ 3500.966210] rina-rnl-utils: Sent NL unicast msg of type 28 with seq num 0 to 1\r\n[ 3500.966458] rina-syscalls: Personality returned value -1\r\n[ 3500.967097] rina-rnl: Dispatching message (skb-in=f6dafac0, info=ef16fcc4)\r\n[ 3500.967099] rina-rnl: Multiplexing message type 15\r\n[ 3500.967100] rina-rnl: Fetching handler callback and data\r\n[ 3500.967101] rina-rnl: Gonna call f86104fa(ef4391c0, f6dafac0, ef16fcc4)\r\n[ 3500.967103] rina-rnl-utils: RINA Netlink parser started ...\r\n[ 3500.967104] rina-rnl-utils: msg is at ee9be2c0\r\n[ 3500.967105] rina-rnl-utils:   msg->rina_hdr is at ef5cfcd4 and size is: 4\r\n[ 3500.967106] rina-rnl-utils:   msg->attrs is at ee9be280\r\n[ 3500.967107] rina-rnl-utils:   (msg->rina_hdr)->src_ipc_id is 0\r\n[ 3500.967108] rina-rnl-utils:   (msg->rina_hdr)->dst_ipc_id is 1\r\n[ 3500.967110] rina-kfa: We are destroying a flow\r\n[ 3500.967112] rina-pidm: Bitmap release completed successfully\r\n[ 3500.967117] rina-rnl-utils: Result of genlmesg_end: 32\r\n[ 3500.967125] rina-rnl-utils: Sent NL unicast msg of type 16 with seq num 1384860757 to 1\r\n[ 3500.967126] rina-rnl: Message 15 handled successfully\r\n[ 3500.967429] rina-rnl: Dispatching message (skb-in=f6dafac0, info=ef16fcc4)\r\n[ 3500.967430] rina-rnl: Multiplexing message type 20\r\n[ 3500.967431] rina-rnl: Fetching handler callback and data\r\n[ 3500.967432] rina-rnl: Gonna call f8610739(ef4391c0, f6dafac0, ef16fcc4)\r\n[ 3500.967434] rina-rnl-utils: RINA Netlink parser started ...\r\n[ 3500.967435] rina-rnl-utils: msg is at ef4d32c0\r\n[ 3500.967436] rina-rnl-utils:   msg->rina_hdr is at f71b2e14 and size is: 4\r\n[ 3500.967437] rina-rnl-utils:   msg->attrs is at ee9be2c0\r\n[ 3500.967437] rina-rnl-utils:   (msg->rina_hdr)->src_ipc_id is 0\r\n[ 3500.967438] rina-rnl-utils:   (msg->rina_hdr)->dst_ipc_id is 1\r\n[ 3500.967440] rina-ipcp-utils: Name at ee9be280 finalized successfully\r\n[ 3500.967442] rina-ipcp-utils: Name at ef2594e0 finalized successfully\r\n[ 3500.967447] rina-ipcp-utils: Name at ef2fe5c0 finalized successfully\r\n[ 3500.967448] rina-ipcp-utils: Name at ef2fe5c0 destroyed successfully\r\n[ 3500.967449] rina-ipcp-utils: Name at ee9be280 finalized successfully\r\n[ 3500.967450] rina-ipcp-utils: Name at ee9be280 destroyed successfully\r\n[ 3500.967451] rina-ipcp-utils: Name at ef2594e0 finalized successfully\r\n[ 3500.967452] rina-ipcp-utils: Name at ef2594e0 destroyed successfully\r\n[ 3500.967454] rina-rnl-utils: Result of genlmesg_end: 32\r\n[ 3500.967460] rina-rnl-utils: Sent NL unicast msg of type 21 with seq num 1384860758 to 1\r\n[ 3500.967461] rina-rnl: Message 20 handled successfully\r\n[ 3500.974066] rina-syscalls: Exiting SYSC_sdu_read syscall body\r\n\n"}}