{"action":"closed","issue":{"url":"https://api.github.com/repos/IRATI/stack/issues/205","labels_url":"https://api.github.com/repos/IRATI/stack/issues/205/labels{/name}","comments_url":"https://api.github.com/repos/IRATI/stack/issues/205/comments","events_url":"https://api.github.com/repos/IRATI/stack/issues/205/events","html_url":"https://github.com/IRATI/stack/issues/205","id":58347238,"number":205,"title":"SDU 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":[{"url":"https://api.github.com/repos/IRATI/stack/labels/bug","name":"bug","color":"fc2929"},{"url":"https://api.github.com/repos/IRATI/stack/labels/imported","name":"imported","color":"d4c5f9"}],"state":"closed","locked":false,"assignee":{"login":"edugrasa","id":325005,"avatar_url":"https://avatars.githubusercontent.com/u/325005?v=3","gravatar_id":"","url":"https://api.github.com/users/edugrasa","html_url":"https://github.com/edugrasa","followers_url":"https://api.github.com/users/edugrasa/followers","following_url":"https://api.github.com/users/edugrasa/following{/other_user}","gists_url":"https://api.github.com/users/edugrasa/gists{/gist_id}","starred_url":"https://api.github.com/users/edugrasa/starred{/owner}{/repo}","subscriptions_url":"https://api.github.com/users/edugrasa/subscriptions","organizations_url":"https://api.github.com/users/edugrasa/orgs","repos_url":"https://api.github.com/users/edugrasa/repos","events_url":"https://api.github.com/users/edugrasa/events{/privacy}","received_events_url":"https://api.github.com/users/edugrasa/received_events","type":"User","site_admin":false},"milestone":{"url":"https://api.github.com/repos/IRATI/stack/milestones/2","labels_url":"https://api.github.com/repos/IRATI/stack/milestones/2/labels","id":988072,"number":2,"title":"v0.2 Shim DIFs over Ethernet complete","description":"Complete the shim DIF over Ethernet and minor fixes to the other components","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":0,"closed_issues":124,"state":"closed","created_at":"2015-02-20T10:08:02Z","updated_at":"2015-02-20T12:42:07Z","due_on":"2013-10-11T07:00:00Z","closed_at":"2015-02-20T10:08:02Z"},"comments":2,"created_at":"2015-02-20T12:41:48Z","updated_at":"2015-02-20T12:42:07Z","closed_at":"2015-02-20T12:42:07Z","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_Friday Nov 08, 2013 at 16:15 GMT_\n_Originally opened as https://github.com/dana-i2cat/irati/issues/202_\n\n----\n\nWhen running the echo-client several times fast after each other, a read will fail.\r\n\r\nSource IPC Process: 0; Destination IPC Process: 0\r\n2948(1383926990)#core (DBG): Added event of type 4 and sequence number 1383926858 to events queue\r\nIPC Event has happened: DEALLOCATE_FLOW_RESPONSE_EVENT\r\n2948(1383926990)#core (DBG): Waiting for message 2948\r\n2948(1383926990)#netlink-manager (DBG): Received 176 bytes, parsing the message\r\n2948(1383926990)#netlink-manager (DBG): Source: 1, Netlink family: 24; Version: 1; Operation code: 33; Flags: 1; Sequence number: 1383926856\r\n2948(1383926990)#core (DBG): Received Netlink message. Family: 24; Operation code: 33; Source port: 1; Destination port: 2948; Sequence Number: 1383926856\r\nIs request message? 1; Is response message? 0; Is notification message? 0\r\nSource IPC Process: 0; Destination IPC Process: 0 \r\n2948(1383926990)#core (DBG): Added event of type 0 and sequence number 1383926856 to events queue\r\nIPC Event has happened: FLOW_ALLOCATION_REQUESTED_EVENT\r\n2013-11-08 17:09:50,839 - INFO rina.utils.apps.echo.utils.IPCEventConsumer - Looking for flow acceptors for application: -rina.utils.apps.echo-1---\r\n}2948(1383926990)#core (DBG): Waiting for message 2948\r\n2948(1383926990)#netlink-manager (DBG): Sent message of 36 bytes. Family: 24; Operation code: 34; Source port: 2948; Destination port: 1; Sequence Number: 1383926856\r\nIs request message? 0; Is response message? 1; Is notification message? 0\r\nSource IPC Process: 0; Destination IPC Process: 0\r\n2013-11-08 17:09:50,840 - DEBUG rina.utils.apps.echo.server.FlowReader - Wrote SDU of size 100 to portId 1\r\n}2013-11-08 17:09:50,841 - INFO rina.utils.apps.echo.server.EchoServer - New flow to the echo AE allocated, with port id 1\r\n}2013-11-08 17:09:50,842 - DEBUG rina.utils.apps.echo.server.FlowReader - Read SDU of size 100 from portId 1\r\n}2013-11-08 17:09:50,843 - DEBUG rina.utils.apps.echo.server.FlowReader - Wrote SDU of size 100 to portId 1\r\n}2013-11-08 17:09:50,851 - DEBUG rina.utils.apps.echo.server.FlowReader - Read SDU of size 100 from portId 1\r\n}2013-11-08 17:09:50,858 - DEBUG rina.utils.apps.echo.server.FlowReader - Wrote SDU of size 100 to portId 1\r\n}2013-11-08 17:09:50,858 - DEBUG rina.utils.apps.echo.server.FlowReader - Read SDU of size 100 from portId 1\r\n}2013-11-08 17:09:50,862 - DEBUG rina.utils.apps.echo.server.FlowReader - Wrote SDU of size 100 to portId 1\r\n}2013-11-08 17:09:50,863 - DEBUG rina.utils.apps.echo.server.FlowReader - Read SDU of size 100 from portId 1\r\n}2013-11-08 17:09:50,866 - DEBUG rina.utils.apps.echo.server.FlowReader - Wrote SDU of size 100 to portId 1\r\n}2013-11-08 17:09:51,843 - INFO rina.utils.apps.echo.server.FlowReader - Requesting reader of flow 1 to stop\r\n}2948(1383926991)#application (DBG): Application rina.utils.apps.echo requested to deallocate flow with port-id 1\r\n2948(1383926991)#netlink-manager (DBG): Received 88 bytes, parsing the message\r\n2948(1383926991)#netlink-manager (DBG): Source: 1, Netlink family: 24; Version: 1; Operation code: 36; Flags: 0; Sequence number: 1383926859\r\n2948(1383926991)#core (DBG): Received Netlink message. Family: 24; Operation code: 36; Source port: 1; Destination port: 2948; Sequence Number: 1383926859\r\nIs request message? 0; Is response message? 1; Is notification message? 0\r\nSource IPC Process: 0; Destination IPC Process: 0 \r\n2948(1383926991)#netlink-manager (DBG): Sent message of 88 bytes. Family: 24; Operation code: 35; Source port: 2948; Destination port: 1; Sequence Number: 1383926859\r\nIs request message? 1; Is response message? 0; Is notification message? 0\r\nSource IPC Process: 0; Destination IPC Process: 0\r\n2948(1383926991)#core (DBG): Added event of type 4 and sequence number 1383926859 to events queue\r\nIPC Event has happened: DEALLOCATE_FLOW_RESPONSE_EVENT\r\n2948(1383926991)#core (DBG): Waiting for message 2948\r\n2948(1383926991)#syscalls (ERR): Read SDU failed, errno = 14, \r\n\r\n2013-11-08 17:09:51,854 - ERROR rina.utils.apps.echo.server.FlowReader - Problems reading SDU from flow 1\r\n\r\n\r\n[  299.512830] rina-shim-eth-vlan: Converted the hw addresses\r\n[  299.512863] rina-shim-eth-vlan: Filling up the sk_buff\r\n[  299.512865] rina-shim-eth-vlan: Gonna send it now\r\n[  299.514298] rina-rnl: Dispatching message (skb-in=e849ea80, info=f2ef1c80)\r\n[  299.514301] rina-rnl: Multiplexing message type 15\r\n[  299.514302] rina-rnl: Fetching handler callback and data\r\n[  299.514383] rina-rnl-utils: RINA Netlink parser started ...\r\n[  299.514384] rina-rnl-utils: msg is at f187e340\r\n[  299.514947] rina-rnl-utils:   msg->rina_hdr is at f4e766d4 and size is: 4\r\n[  299.514949] rina-rnl-utils:   msg->attrs is at e7eb5450\r\n[  299.514950] rina-rnl-utils:   (msg->rina_hdr)->src_ipc_id is 0\r\n[  299.514952] rina-rnl-utils:   (msg->rina_hdr)->dst_ipc_id is 1\r\n[  299.514953] rina-rnl-utils: rnl_parse_generic_u32_param_msg started ...\r\n[  299.514955] rina-rnl-utils: Parsed result: 1\r\n[  299.514959] rina-kfa: We are destroying a flow\r\n[  299.515002] rina-pidm: Bitmap release completed successfully\r\n[  299.515170] rina-rnl-utils: Result of genlmesg_end: 32\r\n[  299.515224] rina-rnl-utils: Sent NL unicast msg of type 16 with seq num 1383926855 to 1\r\n[  299.515225] rina-rnl: Message 15 handled successfully\r\n[  299.518989] rina-shim-eth-vlan: Already got past getting the data and some checks\r\n[  299.518993] rina-shim-eth-vlan: Have to create a new flow\r\n[  299.519012] rina-pidm: The pidm bitmap find returned id 0 (bad = -1)\r\n[  299.519013] rina-pidm: Bitmap allocation completed successfully (id = 0)\r\n[  299.519045] rina-shim-eth-vlan: Added flow to the list\r\n[  299.519063] rina-shim-eth-vlan: Created the queue\r\n[  299.519127] rina-ipcp-utils: Name at e840e840 finalized successfully\r\n[  299.519192] rina-shim-eth-vlan: Got the address from ARP\r\n[  299.519247] rina-rnl-utils: Entering format_app_name_info with name at e840e840 and sk_buff at e849e600\r\n[  299.519249] rina-rnl-utils: Format app name successs\r\n[  299.519250] rina-rnl-utils: Entering format_app_name_info with name at f13df300 and sk_buff at e849e600\r\n[  299.519252] rina-rnl-utils: Format app name successs\r\n[  299.519254] rina-rnl-utils: Entering format_app_name_info with name at f13df9c0 and sk_buff at e849e600\r\n[  299.519268] rina-rnl-utils: Format app name successs\r\n[  299.519270] rina-rnl-utils: Entering format_flow_spec with fspec at f1c82690 and sk_buff at e849e600\r\n[  299.519271] rina-rnl-utils: Format flow spec success\r\n[  299.519272] rina-rnl-utils: Result of genlmesg_end: 276\r\n[  299.519311] rina-rnl-utils: Sent NL unicast msg of type 12 with seq num 6 to 1\r\n[  299.533765] rina-shim-eth-vlan: Already got past getting the data and some checks\r\n[  299.533785] rina-shim-eth-vlan: Flow exists, queueing or delivering\r\n[  299.533799] rina-shim-eth-vlan: Queueing frame\r\n[  299.533892] rina-shim-eth-vlan: Already got past getting the data and some checks\r\n[  299.533904] rina-shim-eth-vlan: Flow exists, queueing or delivering\r\n[  299.533915] rina-shim-eth-vlan: Queueing frame\r\n[  299.536620] rina-shim-eth-vlan: Already got past getting the data and some checks\r\n[  299.536624] rina-shim-eth-vlan: Flow exists, queueing or delivering\r\n[  299.536625] rina-shim-eth-vlan: Queueing frame\r\n[  299.538203] rina-rnl: Dispatching message (skb-in=e849e900, info=f2ef1c80)\r\n[  299.538206] rina-rnl: Multiplexing message type 14\r\n[  299.538208] rina-rnl: Fetching handler callback and data\r\n[  299.538287] rina-rnl-utils: RINA Netlink parser started ...\r\n[  299.538288] rina-rnl-utils: msg is at f187e410\r\n[  299.538290] rina-rnl-utils:   msg->rina_hdr is at f4e76524 and size is: 4\r\n[  299.538291] rina-rnl-utils:   msg->attrs is at e840e780\r\n[  299.538292] rina-rnl-utils:   (msg->rina_hdr)->src_ipc_id is 0\r\n[  299.538293] rina-rnl-utils:   (msg->rina_hdr)->dst_ipc_id is 1\r\n[  299.538338] rina-kfa: Flow bound to port id 1 with waitqueue f1c825c0\r\n[  299.538340] rina-shim-eth-vlan: Got a new element from the fifo\r\n[  299.538342] rina-kfa: Posting SDU of size 100 to port-id 1 \r\n[  299.538344] rina-kfa: Wait queue f1c825c0, next: f1c825c4, prev: f1c825c4\r\n[  299.538345] rina-kfa: SDU posted\r\n[  299.538346] rina-kfa: Sleeping read syscall should be working now\r\n[  299.538347] rina-shim-eth-vlan: Got a new element from the fifo\r\n[  299.538348] rina-kfa: Posting SDU of size 100 to port-id 1 \r\n[  299.538350] rina-kfa: Wait queue f1c825c0, next: f1c825c4, prev: f1c825c4\r\n[  299.538351] rina-kfa: SDU posted\r\n[  299.538352] rina-kfa: Sleeping read syscall should be working now\r\n[  299.538353] rina-shim-eth-vlan: Got a new element from the fifo\r\n[  299.538354] rina-kfa: Posting SDU of size 100 to port-id 1 \r\n[  299.538356] rina-kfa: Wait queue f1c825c0, next: f1c825c4, prev: f1c825c4\r\n[  299.538357] rina-kfa: SDU posted\r\n[  299.538358] rina-kfa: Sleeping read syscall should be working now\r\n[  299.538359] rina-shim-eth-vlan: Got a new element from the fifo\r\n[  299.538360] rina-kfa: Posting SDU of size 100 to port-id 1 \r\n[  299.538362] rina-kfa: Wait queue f1c825c0, next: f1c825c4, prev: f1c825c4\r\n[  299.538363] rina-kfa: SDU posted\r\n[  299.538364] rina-kfa: Sleeping read syscall should be working now\r\n[  299.538391] rina-rnl: Message 14 handled successfully\r\n[  299.540534] rina-syscalls: Handling personality hook sdu_read\r\n[  299.540538] rina-syscalls: Calling personality hook sdu_read\r\n[  299.540540] rina-personality-default: Calling wrapped function\r\n[  299.540542] rina-kfa: Trying to read SDU from port-id 1\r\n[  299.540544] rina-syscalls: Personality returned value 0\r\n[  299.540770] rina-syscalls: Syscall write SDU of size 100 called with port-id 1\r\n[  299.540806] rina-syscalls: Handling personality hook sdu_write\r\n[  299.540807] rina-syscalls: Calling personality hook sdu_write\r\n[  299.540808] rina-personality-default: Calling wrapped function\r\n[  299.540810] rina-kipcm: Tring to write SDU of size 100 to port_id 1\r\n[  299.540812] rina-shim-eth-vlan: Entered the sdu write\r\n[  299.540813] rina-shim-eth-vlan: Found the flow associated with the id\r\n[  299.540814] rina-shim-eth-vlan: Converted the hw addresses\r\n[  299.540848] rina-shim-eth-vlan: Filling up the sk_buff\r\n[  299.540851] rina-shim-eth-vlan: Gonna send it now\r\n[  299.549498] rina-syscalls: Handling personality hook sdu_read\r\n[  299.549518] rina-syscalls: Calling personality hook sdu_read\r\n[  299.549520] rina-personality-default: Calling wrapped function\r\n[  299.549522] rina-kfa: Trying to read SDU from port-id 1\r\n[  299.549525] rina-syscalls: Personality returned value 0\r\n[  299.551544] rina-syscalls: Syscall write SDU of size 100 called with port-id 1\r\n[  299.551587] rina-syscalls: Handling personality hook sdu_write\r\n[  299.551589] rina-syscalls: Calling personality hook sdu_write\r\n[  299.551611] rina-personality-default: Calling wrapped function\r\n[  299.551613] rina-kipcm: Tring to write SDU of size 100 to port_id 1\r\n[  299.551617] rina-shim-eth-vlan: Entered the sdu write\r\n[  299.551618] rina-shim-eth-vlan: Found the flow associated with the id\r\n[  299.551620] rina-shim-eth-vlan: Converted the hw addresses\r\n[  299.551658] rina-shim-eth-vlan: Filling up the sk_buff\r\n[  299.551660] rina-shim-eth-vlan: Gonna send it now\r\n[  299.556437] rina-syscalls: Handling personality hook sdu_read\r\n[  299.556441] rina-syscalls: Calling personality hook sdu_read\r\n[  299.556442] rina-personality-default: Calling wrapped function\r\n[  299.556444] rina-kfa: Trying to read SDU from port-id 1\r\n[  299.556447] rina-syscalls: Personality returned value 0\r\n[  299.560610] rina-syscalls: Syscall write SDU of size 100 called with port-id 1\r\n[  299.560659] rina-syscalls: Handling personality hook sdu_write\r\n[  299.560661] rina-syscalls: Calling personality hook sdu_write\r\n[  299.560662] rina-personality-default: Calling wrapped function\r\n[  299.560664] rina-kipcm: Tring to write SDU of size 100 to port_id 1\r\n[  299.560666] rina-shim-eth-vlan: Entered the sdu write\r\n[  299.560667] rina-shim-eth-vlan: Found the flow associated with the id\r\n[  299.560669] rina-shim-eth-vlan: Converted the hw addresses\r\n[  299.560704] rina-shim-eth-vlan: Filling up the sk_buff\r\n[  299.560706] rina-shim-eth-vlan: Gonna send it now\r\n[  299.561114] rina-syscalls: Handling personality hook sdu_read\r\n[  299.561130] rina-syscalls: Calling personality hook sdu_read\r\n[  299.561131] rina-personality-default: Calling wrapped function\r\n[  299.561132] rina-kfa: Trying to read SDU from port-id 1\r\n[  299.561134] rina-syscalls: Personality returned value 0\r\n[  299.561337] rina-syscalls: Syscall write SDU of size 100 called with port-id 1\r\n[  299.561369] rina-syscalls: Handling personality hook sdu_write\r\n[  299.561371] rina-syscalls: Calling personality hook sdu_write\r\n[  299.561372] rina-personality-default: Calling wrapped function\r\n[  299.561373] rina-kipcm: Tring to write SDU of size 100 to port_id 1\r\n[  299.561375] rina-shim-eth-vlan: Entered the sdu write\r\n[  299.561376] rina-shim-eth-vlan: Found the flow associated with the id\r\n[  299.561377] rina-shim-eth-vlan: Converted the hw addresses\r\n[  299.561406] rina-shim-eth-vlan: Filling up the sk_buff\r\n[  299.561408] rina-shim-eth-vlan: Gonna send it now\r\n[  299.567551] rina-syscalls: Handling personality hook sdu_read\r\n[  299.567557] rina-syscalls: Calling personality hook sdu_read\r\n[  299.567558] rina-personality-default: Calling wrapped function\r\n[  299.567560] rina-kfa: Trying to read SDU from port-id 1\r\n[  299.567563] rina-kfa: Going to sleep on wait queue f1c825c0\r\n[  300.547872] rina-rnl: Dispatching message (skb-in=e849e900, info=f2ef1c80)\r\n[  300.547909] rina-rnl: Multiplexing message type 15\r\n[  300.547911] rina-rnl: Fetching handler callback and data\r\n[  300.547997] rina-rnl-utils: RINA Netlink parser started ...\r\n[  300.547999] rina-rnl-utils: msg is at f187e410\r\n[  300.548001] rina-rnl-utils:   msg->rina_hdr is at f4e76524 and size is: 4\r\n[  300.548002] rina-rnl-utils:   msg->attrs is at e7eb5da8\r\n[  300.548003] rina-rnl-utils:   (msg->rina_hdr)->src_ipc_id is 0\r\n[  300.548004] rina-rnl-utils:   (msg->rina_hdr)->dst_ipc_id is 1\r\n[  300.548006] rina-rnl-utils: rnl_parse_generic_u32_param_msg started ...\r\n[  300.548007] rina-rnl-utils: Parsed result: 1\r\n[  300.548154] rina-rnl-utils: Result of genlmesg_end: 32\r\n[  300.548199] rina-rnl-utils: Sent NL unicast msg of type 16 with seq num 1383926857 to 1\r\n[  300.548200] rina-rnl: Message 15 handled successfully\r\n[  300.552874] rina-kfa: Woken up\r\n[  300.552878] rina-kfa: We are destroying a flow\r\n[  300.552905] rina-pidm: Bitmap release completed successfully\r\n[  300.552907] rina-syscalls: Personality returned value 0\r\n[  342.911499] rina-rnl: IPC Manager port: 1\r\n[  342.911571] rina-rnl-utils: Result of genlmesg_end: 32\r\n[  342.911634] rina-rnl-utils: Sent NL unicast msg of type 28 with seq num 0 to 1\r\n[  344.592144] rina-rnl: IPC Manager port: 1\r\n[  344.592152] rina-rnl-utils: Result of genlmesg_end: 32\r\n[  344.592610] rina-rnl-utils: Sent NL unicast msg of type 28 with seq num 0 to 1\r\n[  344.593864] rina-rnl: IPC Manager port: 1\r\n[  344.593902] rina-rnl-utils: Result of genlmesg_end: 32\r\n[  344.593933] rina-rnl-utils: Sent NL unicast msg of type 28 with seq num 0 to 1\r\n[  344.595425] rina-rnl: IPC Manager port: 1\r\n[  344.595463] rina-rnl-utils: Result of genlmesg_end: 32\r\n[  344.595494] rina-rnl-utils: Sent NL unicast msg of type 28 with seq num 0 to 1\r\n\r\n\r\n2930(1383926991)#netlink-manager (DBG): Source: 0, Netlink family: 24; Version: 1; Operation code: 16; Flags: 0; Sequence number: 1383926857\r\n2930(1383926991)#core (DBG): Received Netlink message. Family: 24; Operation code: 16; Source port: 0; Destination port: 1; Sequence Number: 1383926857\r\nIs request message? 0; Is response message? 1; Is notification message? 0\r\nSource IPC Process: 1; Destination IPC Process: 0 \r\n2930(1383926991)#netlink-manager (DBG): Sent message of 32 bytes. Family: 24; Operation code: 15; Source port: 1; Destination port: 0; Sequence Number: 1383926857\r\nIs request message? 1; Is response message? 0; Is notification message? 0\r\nSource IPC Process: 0; Destination IPC Process: 1\r\n2930(1383926991)#core (DBG): Added event of type 25 and sequence number 1383926857 to events queue\r\n2930(1383926991)#core (DBG): Waiting for message 1\r\n2013-11-08 17:09:51,851 - DEBUG rina.ipcmanager.impl.helpers.FlowManager - Requested deallocation of flow with portId 1 to DIF 100\r\n}2013-11-08 17:09:51,851 - INFO rina.ipcmanager.impl.IPCManager - Got event of type: IPCM_DEALLOCATE_FLOW_RESPONSE_EVENT and sequence number: 1383926857\r\n}2013-11-08 17:09:51,852 - INFO rina.ipcmanager.impl.helpers.FlowManager - Successfully deallocated flow with portId 1 in DIF Process name: 100; Process instance: ; Entity name: ; Entity instance: \r\n}2930(1383926991)#ipc-manager (DBG): ApplicationManager::flowdeallocated called\r\n2930(1383926991)#netlink-manager (DBG): Sent message of 88 bytes. Family: 24; Operation code: 36; Source port: 1; Destination port: 2948; Sequence Number: 1383926859\r\nIs request message? 0; Is response message? 1; Is notification message? 0\r\nSource IPC Process: 0; Destination IPC Process: 0\r\n2930(1383927034)#netlink-manager (DBG): Received 32 bytes, parsing the message\r\n2930(1383927034)#netlink-manager (DBG): Source: 0, Netlink family: 24; Version: 1; Operation code: 28; Flags: 0; Sequence number: 0\r\n2930(1383927034)#core (DBG): Received Netlink message. Family: 24; Operation code: 28; Source port: 0; Destination port: 1; Sequence Number: 0\r\nIs request message? 0; Is response message? 0; Is notification message? 1\r\nSource IPC Process: 0; Destination IPC Process: 0 \r\n2930(1383927034)#core (DBG): NL socket at port 2968 is closed\r\n2930(1383927034)#core (DBG): Waiting for message 1\r\n2930(1383927035)#netlink-manager (DBG): Received 32 bytes, parsing the message\r\n2930(1383927035)#netlink-manager (DBG): Source: 0, Netlink family: 24; Version: 1; Operation code: 28; Flags: 0; Sequence number: 0\r\n2930(1383927035)#core (DBG): Received Netlink message. Family: 24; Operation code: 28; Source port: 0; Destination port: 1; Sequence Number: 0\r\nIs request message? 0; Is response message? 0; Is notification message? 1\r\nSource IPC Process: 0; Destination IPC Process: 0 \r\n2930(1383927035)#core (DBG): NL socket at port 2968 is closed\r\n2930(1383927035)#core (DBG): Waiting for message 1\r\n2930(1383927035)#netlink-manager (DBG): Received 32 bytes, parsing the message\r\n2930(1383927035)#netlink-manager (DBG): Source: 0, Netlink family: 24; Version: 1; Operation code: 28; Flags: 0; Sequence number: 0\r\n2930(1383927035)#core (DBG): Received Netlink message. Family: 24; Operation code: 28; Source port: 0; Destination port: 1; Sequence Number: 0\r\nIs request message? 0; Is response message? 0; Is notification message? 1\r\nSource IPC Process: 0; Destination IPC Process: 0 \r\n2930(1383927035)#core (DBG): NL socket at port 2968 is closed\r\n2930(1383927035)#core (DBG): Waiting for message 1\r\n2930(1383927035)#netlink-manager (DBG): Received 32 bytes, parsing the message\r\n2930(1383927035)#netlink-manager (DBG): Source: 0, Netlink family: 24; Version: 1; Operation code: 28; Flags: 0; Sequence number: 0\r\n2930(1383927035)#core (DBG): Received Netlink message. Family: 24; Operation code: 28; Source port: 0; Destination port: 1; Sequence Number: 0\r\nIs request message? 0; Is response message? 0; Is notification message? 1\r\nSource IPC Process: 0; Destination IPC Process: 0 \r\n2930(1383927035)#core (DBG): NL socket at port 2968 is closed\r\n2930(1383927035)#core (DBG): Waiting for message 1\r\n\n"}}