{"action":"created","issue":{"url":"https://api.github.com/repos/IRATI/stack/issues/122","labels_url":"https://api.github.com/repos/IRATI/stack/issues/122/labels{/name}","comments_url":"https://api.github.com/repos/IRATI/stack/issues/122/comments","events_url":"https://api.github.com/repos/IRATI/stack/issues/122/events","html_url":"https://github.com/IRATI/stack/issues/122","id":58338904,"number":122,"title":"Echo server and echo client fail to start (NL related it seems)","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":"open","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":1,"closed_issues":72,"state":"closed","created_at":"2015-02-20T10:08:02Z","updated_at":"2015-02-20T11:18:48Z","due_on":"2013-10-11T07:00:00Z","closed_at":"2015-02-20T10:08:02Z"},"comments":4,"created_at":"2015-02-20T11:18:48Z","updated_at":"2015-02-20T11:19:06Z","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_Monday Oct 21, 2013 at 10:23 GMT_\n_Originally opened as https://github.com/dana-i2cat/irati/issues/119_\n\n----\n\nin VM (logged in as root):\r\nmodprobe arp826\r\nmodprobe shim-eth-vlan\r\nmodprobe 8021q\r\nip link add link eth0 name eth0.100 type vlan id 100 \r\nifconfig eth0.100 up\r\n./ipcmanager\r\n./echo-client or ./echo-server\r\n\r\nServer log:\r\n[-role, server]\r\n2013-10-21 12:10:32,406 - INFO rina.utils.apps.echo.utils.IPCEventConsumer - Added flow acceptor for application: -rina.utils.apps.ehco-1---\r\n}eu.irati.librina.ApplicationRegistrationException: Error registering application\r\n\tat eu.irati.librina.rinaJNI.IPCManagerSingleton_registerApplication(Native Method)\r\n\tat eu.irati.librina.IPCManagerSingleton.registerApplication(IPCManagerSingleton.java:84)\r\n\tat rina.utils.apps.echo.server.EchoServer.execute(EchoServer.java:62)\r\n\tat rina.utils.apps.echo.Echo.execute(Echo.java:27)\r\n\tat rina.utils.apps.echo.Main.main(Main.java:93)\r\n2848(1382350232)#concurrency (DBG): Lockable created successfully\r\n2848(1382350232)#concurrency (DBG): Lockable created successfully\r\n2848(1382350232)#netlink-manager (DBG): Netlink Manager constructor called, with netlink pid = 2848\r\n2848(1382350232)#netlink-manager (INFO): Netlink socket connected to local port 2848 \r\n2848(1382350232)#netlink-manager (DBG): Generic Netlink RINA family id: 24\r\n2848(1382350232)#core (DBG): Initialized Netlink Manager\r\n2848(1382350232)#concurrency (DBG): Lockable created successfully\r\n2848(1382350232)#concurrency (DBG): Condition variable created successfully\r\n2848(1382350232)#core (DBG): Initialized event queue\r\n2848(1382350232)#core (DBG): Started Netlink Message reader thread\r\n2848(1382350232)#core (DBG): Waiting for message 2848\r\n2848(1382350232)#application (DBG): IPCManager.registerApplication called\r\n2848(1382350232)#netlink-manager (DBG): Received 124 bytes, parsing the message\r\n2848(1382350232)#netlink-manager (DBG): Source: 1, Netlink family: 24; Version: 1; Operation code: 38; Flags: 0; Sequence number: 1382350233\r\n2848(1382350232)#core (DBG): Received Netlink message. Family: 24; Operation code: 38; Source port: 1; Destination port: 2848; Sequence Number: 1382350233\r\nIs request message? 0; Is response message? 1; Is notification message? 0\r\nSource IPC Process: 0; Destination IPC Process: 0 \r\n2848(1382350232)#netlink-manager (DBG): Sent message of 92 bytes. Family: 24; Operation code: 37; Source port: 2848; Destination port: 1; Sequence Number: 1382350233\r\nIs request message? 1; Is response message? 0; Is notification message? 0\r\nSource IPC Process: 0; Destination IPC Process: 0\r\n2848(1382350232)#core (DBG): Creating a new Netlink session with id 1\r\n2848(1382350232)#concurrency (DBG): Lockable created successfully\r\n2848(1382350232)#concurrency (DBG): Condition variable created successfully\r\n2848(1382350232)#core (DBG): Waiting for message 2848\r\n2848(1382350232)#core (DBG): Got Netlink reply to request with sequence number 1382350233\r\n2848(1382350232)#netlink-manager (DBG): Netlink Manager destructor called\r\n2848(1382350232)#concurrency (CRIT): Cannot destroy condition variable\r\n2848(1382350232)#concurrency (CRIT): Cannot destroy mutex\r\n\r\nClient log:\r\n[-role, client, -sdusize, 100, -count, 30]\r\neu.irati.librina.ApplicationRegistrationException: Error registering application\r\n\tat eu.irati.librina.rinaJNI.IPCManagerSingleton_registerApplication(Native Method)\r\n\tat eu.irati.librina.IPCManagerSingleton.registerApplication(IPCManagerSingleton.java:84)\r\n\tat rina.utils.apps.echo.client.EchoClient.execute(EchoClient.java:67)\r\n\tat rina.utils.apps.echo.Echo.execute(Echo.java:29)\r\n\tat rina.utils.apps.echo.Main.main(Main.java:93)\r\n2831(1382350071)#concurrency (DBG): Lockable created successfully\r\n2831(1382350071)#concurrency (DBG): Lockable created successfully\r\n2831(1382350071)#netlink-manager (DBG): Netlink Manager constructor called, with netlink pid = 2831\r\n2831(1382350071)#netlink-manager (INFO): Netlink socket connected to local port 2831 \r\n2831(1382350071)#netlink-manager (DBG): Generic Netlink RINA family id: 24\r\n2831(1382350071)#core (DBG): Initialized Netlink Manager\r\n2831(1382350071)#concurrency (DBG): Lockable created successfully\r\n2831(1382350071)#concurrency (DBG): Condition variable created successfully\r\n2831(1382350071)#core (DBG): Initialized event queue\r\n2831(1382350071)#core (DBG): Started Netlink Message reader thread\r\n2831(1382350071)#application (DBG): IPCManager.registerApplication called\r\n2831(1382350071)#core (DBG): Waiting for message 2831\r\n2831(1382350071)#netlink-manager (DBG): Received 128 bytes, parsing the message\r\n2831(1382350071)#netlink-manager (DBG): Source: 1, Netlink family: 24; Version: 1; Operation code: 38; Flags: 0; Sequence number: 1382350072\r\n2831(1382350071)#core (DBG): Received Netlink message. Family: 24; Operation code: 38; Source port: 1; Destination port: 2831; Sequence Number: 1382350072\r\nIs request message? 0; Is response message? 1; Is notification message? 0\r\nSource IPC Process: 0; Destination IPC Process: 0 \r\n2831(1382350071)#netlink-manager (DBG): Sent message of 96 bytes. Family: 24; Operation code: 37; Source port: 2831; Destination port: 1; Sequence Number: 1382350072\r\nIs request message? 1; Is response message? 0; Is notification message? 0\r\nSource IPC Process: 0; Destination IPC Process: 0\r\n2831(1382350071)#core (DBG): Creating a new Netlink session with id 1\r\n2831(1382350071)#concurrency (DBG): Lockable created successfully\r\n2831(1382350071)#concurrency (DBG): Condition variable created successfully\r\n2831(1382350071)#core (DBG): Waiting for message 2831\r\n2831(1382350071)#core (DBG): Got Netlink reply to request with sequence number 1382350072\r\n2831(1382350071)#netlink-manager (DBG): Netlink Manager destructor called\r\n\n"},"comment":{"url":"https://api.github.com/repos/IRATI/stack/issues/comments/75223305","html_url":"https://github.com/IRATI/stack/issues/122#issuecomment-75223305","issue_url":"https://api.github.com/repos/IRATI/stack/issues/122","id":75223305,"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},"created_at":"2015-02-20T11:19:06Z","updated_at":"2015-02-20T11:19:06Z","body":"<a href=\"https://github.com/sandervrijders\"><img src=\"https://avatars.githubusercontent.com/u/1212456?v=3\" align=\"left\" width=\"48\" height=\"48\" hspace=\"10\"></img></a> **Comment by [sandervrijders](https://github.com/sandervrijders)**\n_Monday Oct 21, 2013 at 11:51 GMT_\n\n----\n\n[  674.311240] rina-shim-eth-vlan: Starting regression tests\r\n[  674.311244] rina-shim-eth-vlan: Create-vlan-name regression tests\r\n[  674.311246] rina-shim-eth-vlan: Regression test #4\r\n[  674.311247] rina-shim-eth-vlan: Regression test #4.1\r\n[  674.311248] rina-shim-eth-vlan: Building complete VLAN interface name ('eth0', 0)\r\n[  674.311249] rina-shim-eth-vlan: Wrong vlan-id 0\r\n[  674.311250] rina-shim-eth-vlan: Regression test #4.2\r\n[  674.311252] rina-shim-eth-vlan: Building complete VLAN interface name ('eth0', 1)\r\n[  674.311253] rina-shim-eth-vlan: Wrong vlan-id 1\r\n[  674.311254] rina-shim-eth-vlan: Regression test #4.3\r\n[  674.311255] rina-shim-eth-vlan: Building complete VLAN interface name ('eth0', 4095)\r\n[  674.311256] rina-shim-eth-vlan: Wrong vlan-id 4095\r\n[  674.311257] rina-shim-eth-vlan: Regression test #4.4\r\n[  674.311258] rina-shim-eth-vlan: Building complete VLAN interface name ('eth0', 4096)\r\n[  674.311260] rina-shim-eth-vlan: Wrong vlan-id 4096\r\n[  674.311261] rina-shim-eth-vlan: Regression test #5\r\n[  674.311262] rina-shim-eth-vlan: Regression test #5.1\r\n[  674.311263] rina-shim-eth-vlan: Building complete VLAN interface name ('eth0', 2)\r\n[  674.311264] rina-shim-eth-vlan: VLAN id is '2'\r\n[  674.311266] rina-shim-eth-vlan: Complete vlan-interface-name length will be 7\r\n[  674.311289] rina-shim-eth-vlan: Complete vlan-interface-name = 'eth0.2'\r\n[  674.311297] rina-shim-eth-vlan: Regression test #5.2\r\n[  674.311298] rina-shim-eth-vlan: Building complete VLAN interface name ('eth0', 10)\r\n[  674.311300] rina-shim-eth-vlan: VLAN id is '10'\r\n[  674.311301] rina-shim-eth-vlan: Complete vlan-interface-name length will be 8\r\n[  674.311322] rina-shim-eth-vlan: Complete vlan-interface-name = 'eth0.10'\r\n[  674.311329] rina-shim-eth-vlan: Regression test #5.3\r\n[  674.311330] rina-shim-eth-vlan: Building complete VLAN interface name ('eth0', 100)\r\n[  674.311332] rina-shim-eth-vlan: VLAN id is '100'\r\n[  674.311333] rina-shim-eth-vlan: Complete vlan-interface-name length will be 9\r\n[  674.311358] rina-shim-eth-vlan: Complete vlan-interface-name = 'eth0.100'\r\n[  674.311365] rina-shim-eth-vlan: Regression test #5.4\r\n[  674.311367] rina-shim-eth-vlan: Building complete VLAN interface name ('eth0', 1000)\r\n[  674.311368] rina-shim-eth-vlan: VLAN id is '1000'\r\n[  674.311369] rina-shim-eth-vlan: Complete vlan-interface-name length will be 10\r\n[  674.311390] rina-shim-eth-vlan: Complete vlan-interface-name = 'eth0.1000'\r\n[  674.311398] rina-shim-eth-vlan: Regression tests completed successfully\r\n[  674.311399] rina-ipcp-factories: Registering new factory\r\n[  674.311401] rina-ipcp-factories: Checking name\r\n[  674.311402] rina-ipcp-factories: Name is ok\r\n[  674.311403] rina-ipcp-factories: Checking ops\r\n[  674.311404] rina-ipcp-factories: Ops are ok\r\n[  674.311405] rina-ipcp-factories: Checking name\r\n[  674.311406] rina-ipcp-factories: Name is ok\r\n[  674.311407] rina-ipcp-factories: Registering factory 'shim-eth-vlan'\r\n[  674.311501] rina-shim-eth-vlan: shim-eth-vlan intialized\r\n[  674.311503] rina-ipcp-factories: Factory 'shim-eth-vlan' registered successfully\r\n[  759.941419] rina-rnl: Dispatching message (skb-in=f0824900, info=f24bbc80)\r\n[  759.941423] rina-rnl: Multiplexing message type 29\r\n[  759.941425] rina-rnl: Fetching handler callback and data\r\n[  759.941427] rina-kipcm: IPC Manager started. It is listening at NL port-id 1\r\n[  759.941429] rina-rnl: Message 29 handled successfully\r\n[  759.956876] rina-ipcp-utils: Name at f3df4540 finalized successfully\r\n[  759.957000] rina-syscalls: Handling personality hook ipc_create\r\n[  759.957010] rina-syscalls: Calling personality hook ipc_create\r\n[  759.957011] rina-personality-default: Calling wrapped function\r\n[  759.957026] rina-kipcm: Creating IPC process:\r\n[  759.957028] rina-kipcm:   name:      test/1//\r\n[  759.957029] rina-kipcm:   id:        1\r\n[  759.957030] rina-kipcm:   factory:   shim-eth-vlan\r\n[  759.957036] rina-ipcp-factories: Checking name\r\n[  759.957037] rina-ipcp-factories: Name is ok\r\n[  759.957102] rina-ipcp-utils: Name at f3df4600 finalized successfully\r\n[  759.957174] rina-shim-eth-vlan: KFA instance f1397540 bound to the shim eth\r\n[  759.957209] rina-ipcp-utils: Name at f3df4540 finalized successfully\r\n[  759.957214] rina-ipcp-utils: Name at f3df4540 destroyed successfully\r\n[  759.961887] rina-rnl: Dispatching message (skb-in=f0f7cc00, info=f24bbc80)\r\n[  759.961890] rina-rnl: Multiplexing message type 1\r\n[  759.961892] rina-rnl: Fetching handler callback and data\r\n[  759.962052] rina-rnl-utils: RINA Netlink parser started...\r\n[  759.962054] rina-rnl-utils: Parsed Netlink message header:\r\n[  759.962054] msg->src_port: 1 msg->dst_port: 0 msg->seq_num:  1382355815 msg->op_code:  1 msg->rina_hdr->src_ipc_id: 0 msg->rina_hdr->dst_ipc_id: 1\r\n[  759.962057] rina-rnl-utils: [LDBG] msg is at f3de3340\r\n[  759.962058] rina-rnl-utils: [LDBG] msg->rina_hdr is at f3af9da4 and size is: 4\r\n[  759.962059] rina-rnl-utils: [LDBG] msg->attrs is at e8b55e60\r\n[  759.962060] rina-rnl-utils: [LDBG] (msg->rina_hdr)->src_ipc_id is 0\r\n[  759.962061] rina-rnl-utils: [LDBG] (msg->rina_hdr)->dst_ipc_id is 1\r\n[  759.962064] rina-rnl-utils: [LDBG] Entering parse_app_name_info with nlattr at f3af9dc0 and name_struct at f3df4780\r\n[  759.962135] rina-kipcm: Found IPC Process with id 1\r\n[  759.962174] rina-ipcp-utils: Name at f3df4840 finalized successfully\r\n[  759.962328] rina-shim-eth-vlan: Building complete VLAN interface name ('eth0', 100)\r\n[  759.962330] rina-shim-eth-vlan: VLAN id is '100'\r\n[  759.962331] rina-shim-eth-vlan: Complete vlan-interface-name length will be 9\r\n[  759.962360] rina-shim-eth-vlan: Complete vlan-interface-name = 'eth0.100'\r\n[  759.962362] rina-shim-eth-vlan: Got device 'eth0.100', trying to register handler\r\n[  759.962407] rina-shim-eth-vlan: Configured shim eth vlan IPC Process\r\n[  759.962537] rina-rnl-utils: Result of genlmesg_end: 32\r\n[  759.962594] rina-rnl: Message 1 handled successfully\r\n[  811.875571] rina-rnl-utils: Result of genlmesg_end: 32\r\n\n"}}