{"action":"closed","issue":{"url":"https://api.github.com/repos/IRATI/stack/issues/360","labels_url":"https://api.github.com/repos/IRATI/stack/issues/360/labels{/name}","comments_url":"https://api.github.com/repos/IRATI/stack/issues/360/comments","events_url":"https://api.github.com/repos/IRATI/stack/issues/360/events","html_url":"https://github.com/IRATI/stack/issues/360","id":58360231,"number":360,"title":"Shim-eth: Sleeping in atomic when creating a flow","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":"e11d21"}],"state":"closed","locked":false,"assignee":{"login":"sandervrijders","id":1212456,"avatar_url":"https://avatars.githubusercontent.com/u/1212456?v=3","gravatar_id":"","url":"https://api.github.com/users/sandervrijders","html_url":"https://github.com/sandervrijders","followers_url":"https://api.github.com/users/sandervrijders/followers","following_url":"https://api.github.com/users/sandervrijders/following{/other_user}","gists_url":"https://api.github.com/users/sandervrijders/gists{/gist_id}","starred_url":"https://api.github.com/users/sandervrijders/starred{/owner}{/repo}","subscriptions_url":"https://api.github.com/users/sandervrijders/subscriptions","organizations_url":"https://api.github.com/users/sandervrijders/orgs","repos_url":"https://api.github.com/users/sandervrijders/repos","events_url":"https://api.github.com/users/sandervrijders/events{/privacy}","received_events_url":"https://api.github.com/users/sandervrijders/received_events","type":"User","site_admin":false},"milestone":{"url":"https://api.github.com/repos/IRATI/stack/milestones/9","html_url":"https://github.com/IRATI/stack/milestones/v0.6%20Routing","labels_url":"https://api.github.com/repos/IRATI/stack/milestones/9/labels","id":988079,"number":9,"title":"v0.6 Routing","description":"Unreliable flows support (fixes)\r\nFIA demo stability fixes\r\nRouting improvements","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":23,"closed_issues":61,"state":"closed","created_at":"2015-02-20T10:08:32Z","updated_at":"2015-03-25T15:13:49Z","due_on":"2014-03-28T07:00:00Z","closed_at":"2015-02-20T10:08:32Z"},"comments":1,"created_at":"2015-02-20T14:32:04Z","updated_at":"2015-03-25T15:13:49Z","closed_at":"2015-03-25T15:13:49Z","body":"<a href=\"https://github.com/lbergesio\"><img src=\"https://avatars.githubusercontent.com/u/1797884?v=3\" align=\"left\" width=\"96\" height=\"96\" hspace=\"10\"></img></a> **Issue by [lbergesio](https://github.com/lbergesio)**\n_Friday Jul 25, 2014 at 08:05 GMT_\n_Originally opened as https://github.com/dana-i2cat/irati/issues/357_\n\n----\n\nDuring enrollment, normal over shim-eth\r\n\r\nJul 25 11:18:10 irati-vm-leo-2 kernel: [62490.625046] rina-rnl-utils: Going to send NL unicast message (type = 19, seq-num 1406279892, port = 1)\r\nJul 25 11:18:10 irati-vm-leo-2 kernel: [62490.625095] rina-rnl-utils: Unicast NL message sent (type = 19, seq-num 1406279892, port = 1)\r\nJul 25 11:18:10 irati-vm-leo-2 kernel: [62490.625095] rina-rnl: Message 18 handled successfully\r\nJul 25 11:18:23 irati-vm-leo-2 kernel: [62503.758064] rina-arp826-rxtx: Processing ARP skb e9c47c80\r\nJul 25 11:18:23 irati-vm-leo-2 kernel: [62503.761512] rina-arp826-rxtx: Decoded ARP header:\r\nJul 25 11:18:23 irati-vm-leo-2 kernel: [62503.761904] rina-arp826-rxtx:   Hardware type           = 0x0001\r\nJul 25 11:18:23 irati-vm-leo-2 kernel: [62503.761904] rina-arp826-rxtx:   Protocol type           = 0xD1F0\r\nJul 25 11:18:23 irati-vm-leo-2 kernel: [62503.761904] rina-arp826-rxtx:   Hardware address length = 6\r\nJul 25 11:18:23 irati-vm-leo-2 kernel: [62503.761904] rina-arp826-rxtx:   Protocol address length = 15\r\nJul 25 11:18:23 irati-vm-leo-2 kernel: [62503.761904] rina-arp826-rxtx:   Operation               = 0x0001\r\nJul 25 11:18:23 irati-vm-leo-2 kernel: [62503.761904] rina-arp826-rxtx: We can handle this ARP\r\nJul 25 11:18:23 irati-vm-leo-2 kernel: [62503.761904] rina-arp826-rxtx: Fetching addresses\r\nJul 25 11:18:23 irati-vm-leo-2 kernel: [62503.761904] rina-arp826-rxtx: Shrinking as needed\r\nJul 25 11:18:23 irati-vm-leo-2 kernel: [62503.761904] rina-arp826-utils: GPA f4615f18 (15): 0x74657374312E49524154492F312F2F\r\nJul 25 11:18:23 irati-vm-leo-2 kernel: [62503.761904] rina-arp826-utils: Looking for filler 0x00 in GPA (length = 15)\r\nJul 25 11:18:23 irati-vm-leo-2 kernel: [62503.761904] rina-arp826-utils: GPA doesn't need to be shrinked ...\r\nJul 25 11:18:23 irati-vm-leo-2 kernel: [62503.761904] rina-arp826-utils: GPA f4615170 (15): 0x74657374322E49524154492F312F2F\r\nJul 25 11:18:23 irati-vm-leo-2 kernel: [62503.761904] rina-arp826-utils: Looking for filler 0x00 in GPA (length = 15)\r\nJul 25 11:18:23 irati-vm-leo-2 kernel: [62503.761904] rina-arp826-utils: GPA doesn't need to be shrinked ...\r\nJul 25 11:18:23 irati-vm-leo-2 kernel: [62503.761904] rina-arp826-tables: Looking for the following address in table\r\nJul 25 11:18:23 irati-vm-leo-2 kernel: [62503.761904] rina-arp826-utils: GPA f4615f18 (15): 0x74657374312E49524154492F312F2F\r\nJul 25 11:18:23 irati-vm-leo-2 kernel: [62503.761904] rina-arp826-tables: Showing addresses in table in the meanwhile\r\nJul 25 11:18:23 irati-vm-leo-2 kernel: [62503.852917] rina-arp826-utils: GPA f46150b8 (15): 0x74657374322E49524154492F312F2F\r\nJul 25 11:18:23 irati-vm-leo-2 kernel: [62503.858660] rina-arp826-utils: GPA efccb7e8 (15): 0x74657374312E49524154492F312F2F\r\nJul 25 11:18:23 irati-vm-leo-2 kernel: [62503.862639] rina-arp826-tables: That's the address I need\r\nJul 25 11:18:23 irati-vm-leo-2 kernel: [62503.865683] rina-arp826-rxtx: Updating old entry e60ffc00 into the table\r\nJul 25 11:18:23 irati-vm-leo-2 kernel: [62503.869122] rina-arp826-tables: Looking for the following address in table\r\nJul 25 11:18:23 irati-vm-leo-2 kernel: [62503.873073] rina-arp826-utils: GPA f4615170 (15): 0x74657374322E49524154492F312F2F\r\nJul 25 11:18:23 irati-vm-leo-2 kernel: [62503.877157] rina-arp826-tables: Showing addresses in table in the meanwhile\r\nJul 25 11:18:23 irati-vm-leo-2 kernel: [62503.880825] rina-arp826-utils: GPA f46150b8 (15): 0x74657374322E49524154492F312F2F\r\nJul 25 11:18:23 irati-vm-leo-2 kernel: [62503.884680] rina-arp826-tables: That's the address I need\r\nJul 25 11:18:23 irati-vm-leo-2 kernel: [62503.887963] rina-arp826-rxtx: Showing the target ha\r\nJul 25 11:18:23 irati-vm-leo-2 kernel: [62503.890403] rina-arp826-utils: GHA efd2b6c0: 95:F0:44:3E:16:00\r\nJul 25 11:18:23 irati-vm-leo-2 kernel: [62503.893409] rina-arp826-rxtx: Sending ARP reply\r\nJul 25 11:18:23 irati-vm-leo-2 kernel: [62503.895623] rina-arp826-rxtx: Growing addresses to 15\r\nJul 25 11:18:23 irati-vm-leo-2 kernel: [62503.898630] rina-arp826-utils: GPA f4615e60 (15): 0x74657374322E49524154492F312F2F\r\nJul 25 11:18:23 irati-vm-leo-2 kernel: [62503.902682] rina-arp826-utils: No needs to grow the GPA\r\nJul 25 11:18:23 irati-vm-leo-2 kernel: [62503.905652] rina-arp826-utils: GPA f4615228 (15): 0x74657374312E49524154492F312F2F\r\nJul 25 11:18:23 irati-vm-leo-2 kernel: [62503.909990] rina-arp826-utils: No needs to grow the GPA\r\nJul 25 11:18:23 irati-vm-leo-2 kernel: [62503.912992] rina-arp826-rxtx: Creating an ARP packet\r\nJul 25 11:18:23 irati-vm-leo-2 kernel: [62503.916000] rina-arp826-rxtx: ARP packet sent successfully\r\nJul 25 11:18:23 irati-vm-leo-2 kernel: [62503.925664] rina-arp826-rxtx: Request replied successfully\r\nJul 25 11:18:23 irati-vm-leo-2 kernel: [62503.933398] rina-arp826-rxtx: Processing completed successfully\r\nJul 25 11:18:24 irati-vm-leo-2 kernel: [62504.287335] rina-shim-eth-vlan: Worker waking up\r\nJul 25 11:18:24 irati-vm-leo-2 kernel: [62504.291963] rina-shim-eth-vlan: Have to create a new flow\r\nJul 25 11:18:24 irati-vm-leo-2 kernel: [62504.295658] BUG: sleeping function called from invalid context at kernel/locking/mutex.c:586\r\nJul 25 11:18:24 irati-vm-leo-2 kernel: [62504.305173] in_atomic(): 1, irqs_disabled(): 0, pid: 4243, name: kworker/u17:2\r\nJul 25 11:18:24 irati-vm-leo-2 kernel: [62504.312589] INFO: lockdep is turned off.\r\nJul 25 11:18:24 irati-vm-leo-2 kernel: [62504.317058] CPU: 0 PID: 4243 Comm: kworker/u17:2 Not tainted 3.15.0 #133\r\nJul 25 11:18:24 irati-vm-leo-2 kernel: [62504.327785] Hardware name: Xen HVM domU, BIOS 4.0.1 05/03/2013\r\nJul 25 11:18:24 irati-vm-leo-2 kernel: [62504.332527] Workqueue: shim-eth-vlan eth_vlan_rcv_worker [shim_eth_vlan]\r\nJul 25 11:18:24 irati-vm-leo-2 kernel: [62504.340929]  00000000 00000000 e5f5dde0 c1653c61 eecd96b0 e5f5de04 c1069ef9 c182e038\r\nJul 25 11:18:24 irati-vm-leo-2 kernel: [62504.353425]  00000001 00000000 00001093 eecd9a78 f079fcb0 f079fcb0 e5f5de4c c165d073\r\nJul 25 11:18:24 irati-vm-leo-2 kernel: [62504.361219]  c164ad2c 00008020 00000000 e5f5de48 c116668e 00008020 00000000 00000000\r\nJul 25 11:18:24 irati-vm-leo-2 kernel: [62504.366816] Call Trace:\r\nJul 25 11:18:24 irati-vm-leo-2 kernel: [62504.368276]  [<c1653c61>] dump_stack+0x48/0x60\r\nJul 25 11:18:24 irati-vm-leo-2 kernel: [62504.371576]  [<c1069ef9>] __might_sleep+0xe9/0x120\r\nJul 25 11:18:24 irati-vm-leo-2 kernel: [62504.374369]  [<c165d073>] mutex_lock_nested+0x23/0x370\r\nJul 25 11:18:24 irati-vm-leo-2 kernel: [62504.377583]  [<c164ad2c>] ? kmemleak_alloc+0x2c/0x60\r\nJul 25 11:18:24 irati-vm-leo-2 kernel: [62504.380721]  [<c116668e>] ? __kmalloc+0xde/0x1c0\r\nJul 25 11:18:24 irati-vm-leo-2 kernel: [62504.384151]  [<c163e92f>] ? rkzalloc+0x1f/0x60\r\nJul 25 11:18:24 irati-vm-leo-2 kernel: [62504.387404]  [<f80e9eba>] kfa_port_id_reserve+0x1a/0x80 [rina_personality_default]\r\nJul 25 11:18:24 irati-vm-leo-2 kernel: [62504.391983]  [<f80d6a00>] eth_vlan_rcv_worker+0x2a0/0x6a4 [shim_eth_vlan]\r\nJul 25 11:18:24 irati-vm-leo-2 kernel: [62504.396335]  [<c105ad9c>] process_one_work+0x19c/0x490\r\nJul 25 11:18:24 irati-vm-leo-2 kernel: [62504.399198]  [<c105ad27>] ? process_one_work+0x127/0x490\r\nJul 25 11:18:24 irati-vm-leo-2 kernel: [62504.402701]  [<c105b25e>] ? worker_thread+0x19e/0x320\r\nJul 25 11:18:24 irati-vm-leo-2 kernel: [62504.405969]  [<c105b1b9>] worker_thread+0xf9/0x320\r\nJul 25 11:18:24 irati-vm-leo-2 kernel: [62504.409777]  [<c105b0c0>] ? process_scheduled_works+0x30/0x30\r\nJul 25 11:18:24 irati-vm-leo-2 kernel: [62504.415298]  [<c10617db>] kthread+0x9b/0xb0\r\nJul 25 11:18:24 irati-vm-leo-2 kernel: [62504.418668]  [<c1060000>] ? SYSC_clock_adjtime+0x60/0xb0\r\nJul 25 11:18:24 irati-vm-leo-2 kernel: [62504.421963]  [<c1668ae1>] ret_from_kernel_thread+0x21/0x30\r\nJul 25 11:18:24 irati-vm-leo-2 kernel: [62504.425442]  [<c1061740>] ? kthread_create_on_node+0x170/0x170\r\nJul 25 11:18:24 irati-vm-leo-2 kernel: [62504.429961] rina-pidm: The pidm bitmap find returned id 0 (bad = -1)\r\nJul 25 11:18:24 irati-vm-leo-2 kernel: [62504.434775] rina-pidm: Bitmap allocation completed successfully (id = 0)\r\nJul 25 11:18:24 irati-vm-leo-2 kernel: [62504.439597] rina-shim-eth-vlan: Added flow to the list\r\nJul 25 11:18:24 irati-vm-leo-2 kernel: [62504.443410] rina-rfifo: FIFO f4615f18 created successfully\r\nJul 25 11:18:24 irati-vm-leo-2 kernel: [62504.447098] rina-shim-eth-vlan: Created the queue\r\nJul 25 11:18:24 irati-vm-leo-2 kernel: [62504.450279] rina-rqueue: Entry e60ffcc0 tail-pushed into queue e60fff00 (length = 1)\r\nJul 25 11:18:24 irati-vm-leo-2 kernel: [62504.455387] rina-ipcp-utils: Name at e60ffe40 finalized successfully\r\nJul 25 11:18:24 irati-vm-leo-2 kernel: [62504.459252] rina-shim-eth-vlan: Got the address from ARP\r\nJul 25 11:18:24 irati-vm-leo-2 kernel: [62504.463162] rina-rnl-utils: Result of genlmesg_end: 248\r\nJul 25 11:18:24 irati-vm-leo-2 kernel: [62504.466618] rina-rnl-utils: Going to send NL unicast message (type = 12, seq-num 3, port = 1)\r\nJul 25 11:18:24 irati-vm-leo-2 kernel: [62504.471855] rina-rnl-utils: Unicast NL message sent (type = 12, seq-num 3, port = 1)\r\nJul 25 11:18:24 irati-vm-leo-2 kernel: [62504.482869] rina-rnl: Dispatching message (skb-in=ed1b5b00, info=eb2b7b44, attrs=f01311e0)\r\nJul 25 11:18:24 irati-vm-leo-2 kernel: [62504.482870] rina-rnl: Multiplexing message type 14\r\nJul 25 11:18:24 irati-vm-leo-2 kernel: [62504.482871] rina-rnl: Fetching handler callback and data\r\n\n"}}