IRATI / stack

RINA implementation for OS/Linux
http://irati.github.io/stack
73 stars 39 forks source link

RNL: sleeping function called from invalid context #210

Closed kewinrausch closed 9 years ago

kewinrausch commented 9 years ago

Issue by salvestrini Thursday Nov 14, 2013 at 17:36 GMT Originally opened as https://github.com/dana-i2cat/irati/issues/207


1) install-from-scratch 2) run the echo test with the shim-dummy only loaded (the default test runs over shim-eth-vlan)

Results: the echo-client and server will exit BUT the kernel will present stack-traces

[ 8.557003] ohci_hcd 0000:00:06.0: irq 22, io mem 0xf0804000 [ 8.613057] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001 [ 8.613061] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 8.613063] usb usb1: Product: OHCI Host Controller [ 8.613064] usb usb1: Manufacturer: Linux 3.10.0 ohci_hcd [ 8.613065] usb usb1: SerialNumber: 0000:00:06.0 [ 8.615992] hub 1-0:1.0: USB hub found [ 8.616254] hub 1-0:1.0: 8 ports detected [ 8.626522] uhci_hcd: USB Universal Host Controller Interface driver [ 8.628960] i8042: PNP: PS/2 Controller [PNP0303:PS2K,PNP0f03:PS2M] at 0x60,0x64 irq 1,12 [ 9.771160] ata2.00: ATAPI: VBOX CD-ROM, 1.0, max UDMA/133 [ 9.771429] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 9.771496] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 9.771715] ata2.00: configured for UDMA/33 [ 9.773516] scsi 1:0:0:0: CD-ROM VBOX CD-ROM 1.0 PQ: 0 ANSI: 5 [ 9.774423] mousedev: PS/2 mouse device common for all mice [ 9.775906] sr0: scsi3-mmc drive: 32x/32x xa/form2 tray [ 9.775916] cdrom: Uniform CD-ROM driver Revision: 3.20 [ 9.777740] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input2 [ 9.779099] sr 1:0:0:0: Attached scsi CD-ROM sr0 [ 9.781847] sr 1:0:0:0: Attached scsi generic sg0 type 5 [ 9.782541] rtc_cmos rtc_cmos: rtc core: registered rtc_cmos as rtc0 [ 9.782708] rtc_cmos rtc_cmos: alarms up to one day, 114 bytes nvram [ 9.784419] device-mapper: uevent: version 1.0.3 [ 9.786402] device-mapper: ioctl: 4.24.0-ioctl (2013-01-15) initialised: dm-devel@redhat.com [ 9.786431] cpuidle: using governor ladder [ 9.786433] cpuidle: using governor menu [ 9.790654] ashmem: initialized [ 9.845704] TCP: cubic registered [ 9.852336] NET: Registered protocol family 10 [ 9.855761] NET: Registered protocol family 17 [ 9.856053] Key type dns_resolver registered [ 9.856053] rina-core: RINA stack initializing [ 9.856053] rina-debug: Debugging facilities initializing [ 9.856766] rina-debug: Debug-fs support initialized successfully [ 9.856770] rina-debug: Debugging facilities initialized successfully [ 9.856774] rina-core: Creating root kset [ 9.856963] rina-core: Creating sysfs group [ 9.857017] rina-rnl: Initializing Netlink layer [ 9.857022] rina-rnl-workarounds: Setting NL_CFG_F_NONROOT_SEND flag for NL_GENERIC sockets [ 9.857041] rina-rnl-workarounds: NL_CFG_F_NONROOT_SEND flag set [ 9.857147] rina-rnl: NL family registered (id = 24) [ 9.857167] rina-rnl: NetLink layer initialized successfully [ 9.857168] rina-personality: Initializing personality layer [ 9.857228] rina-personality: Personality layer initialized successfully [ 9.857229] rina-core: RINA stack v0.2.4 initialized [ 9.857231] rina-core: Don't panic ... [ 9.861745] PM: Hibernation image not present or could not be loaded. [ 9.861865] registered taskstats version 1 [ 9.862338] kmemleak: Kernel memory leak detector initialized [ 9.862383] kmemleak: Automatic memory scanning thread started [ 9.874761] modprobe (54) used greatest stack depth: 4000 bytes left [ 9.892676] Key type trusted registered [ 9.904002] Key type encrypted registered [ 9.943650] Magic number: 5:55:239 [ 9.944267] rtc_cmos rtc_cmos: setting system clock to 2013-11-14 17:14:37 UTC (1384449277) [ 9.946515] BIOS EDD facility v0.16 2004-Jun-25, 0 devices found [ 9.946518] EDD information not available. [ 9.953714] debug: unmapping init [mem 0xffffffff81d0c000-0xffffffff82000fff] [ 9.954320] Write protecting the kernel read-only data: 12288k [ 9.955473] debug: unmapping init [mem 0xffff8800016db000-0xffff8800017fffff] [ 9.956217] debug: unmapping init [mem 0xffff880001b03000-0xffff880001bfffff] [ 10.084401] usb 1-1: new full-speed USB device number 2 using ohci_hcd [ 10.200883] udevd[91]: starting version 175 [ 10.491922] usb 1-1: New USB device found, idVendor=80ee, idProduct=0021 [ 10.491930] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=0 [ 10.491935] usb 1-1: Product: USB Tablet [ 10.491940] usb 1-1: Manufacturer: VirtualBox [ 10.801806] e1000: Intel(R) PRO/1000 Network Driver - version 7.3.21-k8-NAPI [ 10.801809] e1000: Copyright (c) 1999-2006 Intel Corporation. [ 11.532452] hidraw: raw HID events driver (C) Jiri Kosina [ 12.013005] ata_id (138) used greatest stack depth: 2560 bytes left [ 12.119708] usbcore: registered new interface driver usbhid [ 12.119711] usbhid: USB HID core driver [ 12.134043] e1000 0000:00:03.0 eth0: (PCI:33MHz:32-bit) 08:00:27:70:f3:97 [ 12.134118] e1000 0000:00:03.0 eth0: Intel(R) PRO/1000 Network Connection [ 12.136608] ahci 0000:00:0d.0: version 3.0 [ 12.416556] ahci: SSS flag set, parallel bus scan disabled [ 12.416772] ahci 0000:00:0d.0: AHCI 0001.0100 32 slots 1 ports 3 Gbps 0x1 impl SATA mode [ 12.416775] ahci 0000:00:0d.0: flags: 64bit ncq stag only ccc [ 12.420775] scsi2 : ahci [ 12.422404] ata3: SATA max UDMA/133 abar m8192@0xf0806000 port 0xf0806100 irq 21 [ 12.557965] input: VirtualBox USB Tablet as /devices/pci0000:00/0000:00:06.0/usb1/1-1/1-1:1.0/input/input3 [ 12.565706] hid-generic 0003:80EE:0021.0001: input,hidraw0: USB HID v1.10 Mouse [VirtualBox USB Tablet] on usb-0000:00:06.0-1/input0 [ 12.744491] ata3: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [ 12.744870] ata3.00: ATA-6: VBOX HARDDISK, 1.0, max UDMA/133 [ 12.744878] ata3.00: 16777216 sectors, multi 128: LBA48 NCQ (depth 31/32) [ 12.745229] ata3.00: configured for UDMA/133 [ 12.747999] scsi 2:0:0:0: Direct-Access ATA VBOX HARDDISK 1.0 PQ: 0 ANSI: 5 [ 12.759361] sd 2:0:0:0: [sda] 16777216 512-byte logical blocks: (8.58 GB/8.00 GiB) [ 12.762924] sd 2:0:0:0: Attached scsi generic sg1 type 0 [ 12.763980] sd 2:0:0:0: [sda] Write Protect is off [ 12.763992] sd 2:0:0:0: [sda] Mode Sense: 00 3a 00 00 [ 12.766836] sd 2:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA [ 12.781867] sda: sda1 sda2 < sda5 > [ 12.790575] sd 2:0:0:0: [sda] Attached SCSI disk [ 13.310383] PM: Starting manual resume from disk [ 13.310389] PM: Hibernation image partition 8:5 present [ 13.310391] PM: Looking for hibernation image. [ 13.313189] PM: Image not found (code -22) [ 13.313192] PM: Hibernation image not present or could not be loaded. [ 13.406079] EXT4-fs (sda1): mounted filesystem with ordered data mode. Opts: (null) [ 13.419774] rina-rnl: IPC Manager port: 0 [ 13.420829] rina-rnl: IPC Manager port: 0 [ 13.420912] rina-rnl: IPC Manager port: 0 [ 13.422785] rina-rnl: IPC Manager port: 0 [ 13.422859] rina-rnl: IPC Manager port: 0 [ 13.424699] rina-rnl: IPC Manager port: 0 [ 13.425089] rina-rnl: IPC Manager port: 0 [ 13.429330] rina-rnl: IPC Manager port: 0 [ 13.430621] rina-rnl: IPC Manager port: 0 [ 13.431852] rina-rnl: IPC Manager port: 0 [ 13.433361] rina-rnl: IPC Manager port: 0 [ 13.435724] rina-rnl: IPC Manager port: 0 [ 13.437176] rina-rnl: IPC Manager port: 0 [ 13.438566] rina-rnl: IPC Manager port: 0 [ 13.439856] rina-rnl: IPC Manager port: 0 [ 13.441318] rina-rnl: IPC Manager port: 0 [ 13.442665] rina-rnl: IPC Manager port: 0 [ 13.444029] rina-rnl: IPC Manager port: 0 [ 13.445511] rina-rnl: IPC Manager port: 0 [ 13.462125] rina-rnl: IPC Manager port: 0 [ 13.462463] rina-rnl: IPC Manager port: 0 [ 13.462762] rina-rnl: IPC Manager port: 0 [ 13.463285] rina-rnl: IPC Manager port: 0 [ 13.463895] rina-rnl: IPC Manager port: 0 [ 13.464843] rina-rnl: IPC Manager port: 0 [ 13.470360] rina-rnl: IPC Manager port: 0 [ 17.563201] udevd[340]: starting version 175 [ 19.098392] piix4_smbus 0000:00:07.0: SMBus base address uninitialized - upgrade BIOS or use force_addr=0xaddr [ 19.100780] input: PC Speaker as /devices/platform/pcspkr/input/input4 [ 20.011241] microcode: CPU0 sig=0x306a9, pf=0x1, revision=0x0 [ 20.224987] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input5 [ 20.631722] microcode: CPU1 sig=0x306a9, pf=0x1, revision=0x0 [ 20.657877] microcode: Microcode Update Driver: v2.00 tigran@aivazian.fsnet.co.uk, Peter Oruba [ 23.736782] Adding 392188k swap on /dev/sda5. Priority:-1 extents:1 across:392188k FS [ 23.984140] EXT4-fs (sda1): re-mounted. Opts: (null) [ 24.400970] EXT4-fs (sda1): re-mounted. Opts: errors=remount-ro [ 27.205713] rina-rnl: IPC Manager port: 0 [ 27.205963] rina-rnl: IPC Manager port: 0 [ 27.207940] rina-rnl: IPC Manager port: 0 [ 27.210317] rina-rnl: IPC Manager port: 0 [ 27.214846] rina-rnl: IPC Manager port: 0 [ 27.219457] rina-rnl: IPC Manager port: 0 [ 27.219767] rina-rnl: IPC Manager port: 0 [ 27.220233] rina-rnl: IPC Manager port: 0 [ 27.222035] rina-rnl: IPC Manager port: 0 [ 27.222049] rina-rnl: IPC Manager port: 0 [ 27.222533] rina-rnl: IPC Manager port: 0 [ 27.223895] rina-rnl: IPC Manager port: 0 [ 27.224690] rina-rnl: IPC Manager port: 0 [ 27.225116] rina-rnl: IPC Manager port: 0 [ 27.226314] rina-rnl: IPC Manager port: 0 [ 27.227505] rina-rnl: IPC Manager port: 0 [ 27.233668] rina-rnl: IPC Manager port: 0 [ 27.235486] rina-rnl: IPC Manager port: 0 [ 27.237448] rina-rnl: IPC Manager port: 0 [ 32.611133] rina-rnl: IPC Manager port: 0 [ 33.720640] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready [ 33.721312] rina-rnl: IPC Manager port: 0 [ 33.722429] e1000: eth0 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX [ 33.722459] rina-rnl: IPC Manager port: 0 [ 33.722947] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [ 33.723763] rina-rnl: IPC Manager port: 0 [ 33.726702] rina-rnl: IPC Manager port: 0 [ 33.727258] rina-rnl: IPC Manager port: 0 [ 33.729409] rina-rnl: IPC Manager port: 0 [ 33.730247] rina-rnl: IPC Manager port: 0 [ 33.731207] rina-rnl: IPC Manager port: 0 [ 33.732958] rina-rnl: IPC Manager port: 0 [ 33.735993] rina-rnl: IPC Manager port: 0 [ 33.737740] rina-rnl: IPC Manager port: 0 [ 33.739555] rina-rnl: IPC Manager port: 0 [ 34.351367] rina-rnl: IPC Manager port: 0 [ 34.357179] rina-rnl: IPC Manager port: 0 [ 34.358873] rina-rnl: IPC Manager port: 0 [ 34.360514] rina-rnl: IPC Manager port: 0 [ 34.667433] RPC: Registered named UNIX socket transport module. [ 34.667437] RPC: Registered udp transport module. [ 34.667440] RPC: Registered tcp transport module. [ 34.667442] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 34.704922] rina-rnl: IPC Manager port: 0 [ 34.713083] rina-rnl: IPC Manager port: 0 [ 34.843011] FS-Cache: Loaded [ 34.853629] rina-rnl: IPC Manager port: 0 [ 34.862603] rina-rnl: IPC Manager port: 0 [ 35.252763] FS-Cache: Netfs 'nfs' registered for caching [ 35.556405] Installing knfsd (copyright (C) 1996 okir@monad.swb.de). [ 43.199309] rina-rnl: IPC Manager port: 0 [ 45.073457] rina-rnl: IPC Manager port: 0 [ 121.179593] rina-rnl: IPC Manager port: 0 [ 121.309991] rina-rnl: IPC Manager port: 0 [ 121.310291] rina-rnl: IPC Manager port: 0 [ 132.051344] rina-personality-default: Rina default personality loading [ 132.051347] rina-personality-default: Finally registering personality [ 132.051348] rina-personality: Checking name [ 132.051349] rina-personality: Name is ok [ 132.051351] rina-personality: Checking ops ffffffffa01ae040 [ 132.051351] rina-personality: Ops are ok [ 132.051352] rina-personality: Registering personality 'default' [ 132.051408] rina-personality: Calling personality 'default' initializer [ 132.051409] rina-personality-default: Initializing default personality [ 132.051410] rina-personality-default: Initializing KFA [ 132.051431] rina-pidm: Instance initialized successfully (2048 bits) [ 132.051444] rina-personality-default: Initializing RNL [ 132.051455] rina-rnl: Set ffff8800331c64f8 created successfully [ 132.051456] rina-personality-default: Initializing KIPCM [ 132.051456] rina-kipcm: Initializing [ 132.051466] rina-ipcp-factories: Initializing layer [ 132.051624] rina-ipcp-factories: Layer initialized successfully [ 132.051704] rina-pidm: Instance initialized successfully (2048 bits) [ 132.051715] rina-rnl: Set ffff8800331c64f8 registered [ 132.051716] rina-rnl: Registering handler callback ffffffffa01a7f10 and data ffff88001ca2bb60 for message type 1 [ 132.051760] rina-rnl: Handler ffffffffa01a7f10 (data ffff88001ca2bb60) registered for message type 1 [ 132.051761] rina-rnl: Registering handler callback ffffffffa01a8350 and data ffff88001ca2bb60 for message type 3 [ 132.051762] rina-rnl: Handler ffffffffa01a8350 (data ffff88001ca2bb60) registered for message type 3 [ 132.051763] rina-rnl: Registering handler callback ffffffffa01a85a0 and data ffff88001ca2bb60 for message type 11 [ 132.051765] rina-rnl: Handler ffffffffa01a85a0 (data ffff88001ca2bb60) registered for message type 11 [ 132.051766] rina-rnl: Registering handler callback ffffffffa01a7c90 and data ffff88001ca2bb60 for message type 14 [ 132.051767] rina-rnl: Handler ffffffffa01a7c90 (data ffff88001ca2bb60) registered for message type 14 [ 132.051768] rina-rnl: Registering handler callback ffffffffa01a7450 and data ffff88001ca2bb60 for message type 15 [ 132.051770] rina-rnl: Handler ffffffffa01a7450 (data ffff88001ca2bb60) registered for message type 15 [ 132.051771] rina-rnl: Registering handler callback ffffffffa01a79d0 and data ffff88001ca2bb60 for message type 18 [ 132.051772] rina-rnl: Handler ffffffffa01a79d0 (data ffff88001ca2bb60) registered for message type 18 [ 132.051773] rina-rnl: Registering handler callback ffffffffa01a7710 and data ffff88001ca2bb60 for message type 20 [ 132.051774] rina-rnl: Handler ffffffffa01a7710 (data ffff88001ca2bb60) registered for message type 20 [ 132.051776] rina-rnl: Registering handler callback ffffffffa01a7390 and data ffff88001ca2bb60 for message type 29 [ 132.051777] rina-rnl: Handler ffffffffa01a7390 (data ffff88001ca2bb60) registered for message type 29 [ 132.051778] rina-rnl: Registering handler callback ffffffffa01a7180 and data ffff88001ca2bb60 for message type 30 [ 132.051779] rina-rnl: Handler ffffffffa01a7180 (data ffff88001ca2bb60) registered for message type 30 [ 132.051780] rina-rnl: Registering handler callback ffffffffa01a6f40 and data ffff88001ca2bb60 for message type 32 [ 132.051782] rina-rnl: Handler ffffffffa01a6f40 (data ffff88001ca2bb60) registered for message type 32 [ 132.051783] rina-rnl: Registering handler callback ffffffffa01a6d60 and data ffff88001ca2bb60 for message type 34 [ 132.051784] rina-rnl: Handler ffffffffa01a6d60 (data ffff88001ca2bb60) registered for message type 34 [ 132.051785] rina-rnl: Registering handler callback ffffffffa01a6b80 and data ffff88001ca2bb60 for message type 36 [ 132.051786] rina-rnl: Handler ffffffffa01a6b80 (data ffff88001ca2bb60) registered for message type 36 [ 132.051787] rina-kipcm: NL handlers registered successfully [ 132.051788] rina-kipcm: Initialized successfully [ 132.051789] rina-personality-default: Default personality initialized successfully [ 132.051790] rina-personality: Personality 'default' initialized successfully [ 132.051791] rina-personality: Default personality set to 'default' [ 132.051792] rina-personality: Personality 'default' registered successfully [ 132.051793] rina-personality-default: Rina default personality loaded successfully [ 135.402431] rina-ipcp-factories: Registering new factory [ 135.402434] rina-ipcp-factories: Checking name [ 135.402435] rina-ipcp-factories: Name is ok [ 135.402436] rina-ipcp-factories: Checking ops [ 135.402436] rina-ipcp-factories: Ops are ok [ 135.402438] rina-ipcp-factories: Checking name [ 135.402438] rina-ipcp-factories: Name is ok [ 135.402445] rina-ipcp-factories: Registering factory 'shim-dummy' [ 135.403211] rina-utils: Workqueue 'dummy-wq' (ffff880019467b10) created successfully [ 135.403213] rina-ipcp-factories: Factory 'shim-dummy' registered successfully [ 203.061533] rina-rnl: Dispatching message (skb-in=ffff8800331edd40, info=ffff8800228d39b0) [ 203.061537] rina-rnl: Multiplexing message type 29 [ 203.061538] rina-rnl: Fetching handler callback and data [ 203.061545] rina-kipcm: IPC Manager started. It is listening at NL port-id 1 [ 203.061547] rina-rnl: Message 29 handled successfully [ 203.075819] rina-ipcp-utils: Name at ffff88001a485248 finalized successfully [ 203.075823] rina-ipcp-utils: Cannot duplicate source string from user-space [ 203.075824] rina-ipcp-utils: Name at ffff88001a485248 finalized successfully [ 203.075825] rina-ipcp-utils: Name at ffff88001a485248 finalized successfully [ 203.075831] rina-ipcp-utils: Name at ffff88001a485248 destroyed successfully [ 216.006300] rina-rnl: IPC Manager port: 1 [ 216.006388] rina-rnl-utils: Result of genlmesg_end: 32 [ 216.006396] BUG: sleeping function called from invalid context at mm/slub.c:926 [ 216.006823] in_atomic(): 1, irqs_disabled(): 0, pid: 2724, name: sshd [ 216.007197] 1 lock held by sshd/2724: [ 216.007198] #3: (rcu_read_lock){.+.+.+}, at: [] atomic_notifier_call_chain+0x5/0xc0 [ 216.007207] CPU: 0 PID: 2724 Comm: sshd Not tainted 3.10.0 #1001 [ 216.007208] Hardware name: innotek GmbH VirtualBox/VirtualBox, BIOS VirtualBox 12/01/2006 [ 216.007210] ffff88003f402b40 ffff88001a16db18 ffffffff816c1f71 ffff88001a16db38 [ 216.007213] ffffffff81078647 ffff88003f402b40 0000000000000010 ffff88001a16dbc8 [ 216.007215] ffffffff8118394f 0000000000000000 ffffffff81c33518 ffff88001a16db88 [ 216.007217] Call Trace: [ 216.007221] [] dump_stack+0x19/0x1b [ 216.007231] [] might_sleep+0xe7/0x110 [ 216.007235] [] kmalloc_node_track_caller+0x6f/0x290 [ 216.007238] [] ? pskb_expand_head+0x6b/0x2e0 [ 216.007241] [] __kmalloc_reserve.isra.51+0x3c/0xa0 [ 216.007244] [] ? vprintk_emit+0x1ce/0x5c0 [ 216.007246] [] pskb_expand_head+0x6b/0x2e0 [ 216.007249] [] ? trace_hardirqs_on+0xd/0x10 [ 216.007252] [] netlink_trim+0x7f/0xc0 [ 216.007254] [] netlink_unicast+0x48/0x1e0 [ 216.007257] [] ? printk+0x61/0x63 [ 216.007260] [] send_nl_unicast_msg.constprop.44+0x3e/0xa0 [ 216.007263] [] rnl_ipcm_sock_closed_notif_msg+0xb8/0x120 [ 216.007265] [] kipcm_netlink_notify+0x6a/0xc0 [ 216.007267] [] ? skb_dequeue+0x67/0x90 [ 216.007270] [] notifier_call_chain+0x4d/0x70 [ 216.007272] [] atomic_notifier_call_chain+0x82/0xc0 [ 216.007274] [] ? atomic_notifier_call_chain+0x5/0xc0 [ 216.007276] [] atomic_notifier_call_chain+0x16/0x20 [ 216.007278] [] netlink_release+0x282/0x2c0 [ 216.007280] [] sock_release+0x28/0x90 [ 216.007282] [] sock_close+0x12/0x20 [ 216.007285] [] fput+0xd3/0x260 [ 216.007288] [] ? close_fd+0x80/0xb0 [ 216.007290] [] __fput+0xe/0x10 [ 216.007293] [] task_work_run+0xb4/0xe0 [ 216.007296] [] do_notify_resume+0xaa/0xc0 [ 216.007298] [] int_signal+0x12/0x17 [ 216.007345] rina-rnl-utils: Sent NL unicast msg of type 28 with seq num 0 to 1 [ 216.130157] rina-rnl: IPC Manager port: 1 [ 216.130223] rina-rnl-utils: Result of genlmesg_end: 32 [ 216.130339] rina-rnl-utils: Sent NL unicast msg of type 28 with seq num 0 to 1 [ 216.130748] rina-rnl: IPC Manager port: 1 [ 216.130771] rina-rnl-utils: Result of genlmesg_end: 32 [ 216.130795] rina-rnl-utils: Sent NL unicast msg of type 28 with seq num 0 to 1 [ 219.777911] rina-rnl: IPC Manager port: 1 [ 219.777937] rina-rnl-utils: Result of genlmesg_end: 32 [ 219.777939] BUG: sleeping function called from invalid context at mm/slub.c:926 [ 219.778380] in_atomic(): 1, irqs_disabled(): 0, pid: 2726, name: sshd [ 219.778946] 1 lock held by sshd/2726: [ 219.778948] #3: (rcu_read_lock){.+.+.+}, at: [] atomic_notifier_call_chain+0x5/0xc0 [ 219.778958] CPU: 0 PID: 2726 Comm: sshd Not tainted 3.10.0 #1001 [ 219.778959] Hardware name: innotek GmbH VirtualBox/VirtualBox, BIOS VirtualBox 12/01/2006 [ 219.778960] ffff88003f402b40 ffff88001997bb18 ffffffff816c1f71 ffff88001997bb38 [ 219.778963] ffffffff81078647 ffff88003f402b40 0000000000000010 ffff88001997bbc8 [ 219.778965] ffffffff8118394f 0000000000000000 ffffffff81c33518 ffff88001997bb88 [ 219.778967] Call Trace: [ 219.778971] [] dump_stack+0x19/0x1b [ 219.778974] [] might_sleep+0xe7/0x110 [ 219.778978] [] kmalloc_node_track_caller+0x6f/0x290 [ 219.778981] [] ? pskb_expand_head+0x6b/0x2e0 [ 219.778983] [] __kmalloc_reserve.isra.51+0x3c/0xa0 [ 219.778987] [] ? vprintk_emit+0x1ce/0x5c0 [ 219.778988] [] pskb_expand_head+0x6b/0x2e0 [ 219.778992] [] ? trace_hardirqs_on+0xd/0x10 [ 219.778994] [] netlink_trim+0x7f/0xc0 [ 219.778996] [] netlink_unicast+0x48/0x1e0 [ 219.779000] [] ? printk+0x61/0x63 [ 219.779003] [] send_nl_unicast_msg.constprop.44+0x3e/0xa0 [ 219.779006] [] rnl_ipcm_sock_closed_notif_msg+0xb8/0x120 [ 219.779008] [] kipcm_netlink_notify+0x6a/0xc0 [ 219.779010] [] ? skb_dequeue+0x67/0x90 [ 219.779012] [] notifier_call_chain+0x4d/0x70 [ 219.779014] [] atomic_notifier_call_chain+0x82/0xc0 [ 219.779017] [] ? atomic_notifier_call_chain+0x5/0xc0 [ 219.779019] [] atomic_notifier_call_chain+0x16/0x20 [ 219.779028] [] netlink_release+0x282/0x2c0 [ 219.779030] [] sock_release+0x28/0x90 [ 219.779032] [] sock_close+0x12/0x20 [ 219.779035] [] fput+0xd3/0x260 [ 219.779038] [] ? close_fd+0x80/0xb0 [ 219.779040] [] __fput+0xe/0x10 [ 219.779043] [] task_work_run+0xb4/0xe0 [ 219.779046] [] do_notify_resume+0xaa/0xc0 [ 219.779049] [] int_signal+0x12/0x17 [ 219.779095] rina-rnl-utils: Sent NL unicast msg of type 28 with seq num 0 to 1 [ 219.864604] rina-rnl: IPC Manager port: 1 [ 219.864676] rina-rnl-utils: Result of genlmesg_end: 32 [ 219.864793] rina-rnl-utils: Sent NL unicast msg of type 28 with seq num 0 to 1 [ 219.865705] rina-rnl: IPC Manager port: 1 [ 219.865765] rina-rnl-utils: Result of genlmesg_end: 32 [ 219.865833] rina-rnl-utils: Sent NL unicast msg of type 28 with seq num 0 to 1 [ 260.201820] rina-rnl: IPC Manager port: 1 [ 260.201853] rina-rnl-utils: Result of genlmesg_end: 32 [ 260.201855] BUG: sleeping function called from invalid context at mm/slub.c:926 [ 260.202924] in_atomic(): 1, irqs_disabled(): 0, pid: 2794, name: java [ 260.203698] 1 lock held by java/2794: [ 260.203702] #3: (rcu_read_lock){.+.+.+}, at: [] atomic_notifier_call_chain+0x5/0xc0 [ 260.203722] CPU: 0 PID: 2794 Comm: java Not tainted 3.10.0 #1001 [ 260.203725] Hardware name: innotek GmbH VirtualBox/VirtualBox, BIOS VirtualBox 12/01/2006 [ 260.203728] ffff88003f402b40 ffff8800318e5968 ffffffff816c1f71 ffff8800318e5988 [ 260.203736] ffffffff81078647 ffff88003f402b40 0000000000000010 ffff8800318e5a18 [ 260.203743] ffffffff8118394f 0000000000000000 ffffffff81c33518 ffff8800318e59d8 [ 260.203749] Call Trace: [ 260.203758] [] dump_stack+0x19/0x1b [ 260.203765] [] might_sleep+0xe7/0x110 [ 260.203772] [] kmalloc_node_track_caller+0x6f/0x290 [ 260.203780] [] ? pskb_expand_head+0x6b/0x2e0 [ 260.203785] [] __kmalloc_reserve.isra.51+0x3c/0xa0 [ 260.203793] [] ? vprintk_emit+0x1ce/0x5c0 [ 260.203799] [] pskb_expand_head+0x6b/0x2e0 [ 260.203806] [] ? trace_hardirqs_on+0xd/0x10 [ 260.203812] [] netlink_trim+0x7f/0xc0 [ 260.203817] [] netlink_unicast+0x48/0x1e0 [ 260.203824] [] ? printk+0x61/0x63 [ 260.203832] [] send_nl_unicast_msg.constprop.44+0x3e/0xa0 [ 260.203838] [] rnl_ipcm_sock_closed_notif_msg+0xb8/0x120 [ 260.203859] [] kipcm_netlink_notify+0x6a/0xc0 [ 260.203866] [] ? skb_dequeue+0x67/0x90 [ 260.203873] [] notifier_call_chain+0x4d/0x70 [ 260.203879] [] atomic_notifier_call_chain+0x82/0xc0 [ 260.203884] [] ? atomic_notifier_call_chain+0x5/0xc0 [ 260.203889] [] atomic_notifier_call_chain+0x16/0x20 [ 260.203894] [] netlink_release+0x282/0x2c0 [ 260.203900] [] sock_release+0x28/0x90 [ 260.203905] [] sock_close+0x12/0x20 [ 260.203912] [] fput+0xd3/0x260 [ 260.203917] [] __fput+0xe/0x10 [ 260.203924] [] task_work_run+0xb4/0xe0 [ 260.203930] [] get_signal_to_deliver+0x6fc/0x720 [ 260.203937] [] ? local_clock+0x4f/0x60 [ 260.203943] [] ? lock_release_holdtime.part.22+0x15/0x1b0 [ 260.203950] [] ? fget_light+0x9d/0x170 [ 260.203957] [] do_signal+0x58/0x5d0 [ 260.203962] [] ? fget_light+0xb5/0x170 [ 260.203967] [] ? fget_light+0x40/0x170 [ 260.203973] [] ? task_work_add+0x58/0x70 [ 260.203979] [] ? fput+0x7d/0xe0 [ 260.203985] [] ? __sys_recvmsg+0x75/0x90 [ 260.203990] [] ? int_very_careful+0x5/0x6 [ 260.203996] [] do_notify_resume+0x80/0xc0 [ 260.204181] [] int_signal+0x12/0x17 [ 260.204244] rina-rnl-utils: Sent NL unicast msg of type 28 with seq num 0 to 1 [ 268.311630] rina-rnl: IPC Manager port: 1 [ 268.311676] rina-rnl-utils: Result of genlmesg_end: 32 [ 268.311680] BUG: sleeping function called from invalid context at mm/slub.c:926 [ 268.313003] in_atomic(): 1, irqs_disabled(): 0, pid: 2806, name: java [ 268.313667] 1 lock held by java/2806: [ 268.313669] #3: (rcu_read_lock){.+.+.+}, at: [] atomic_notifier_call_chain+0x5/0xc0 [ 268.313686] CPU: 0 PID: 2806 Comm: java Not tainted 3.10.0 #1001 [ 268.313688] Hardware name: innotek GmbH VirtualBox/VirtualBox, BIOS VirtualBox 12/01/2006 [ 268.313691] ffff88003f402b40 ffff88001ad49968 ffffffff816c1f71 ffff88001ad49988 [ 268.313697] ffffffff81078647 ffff88003f402b40 0000000000000010 ffff88001ad49a18 [ 268.313702] ffffffff8118394f 0000000000000000 ffffffff81c33518 ffff88001ad499d8 [ 268.313708] Call Trace: [ 268.313714] [] dump_stack+0x19/0x1b [ 268.313720] [] might_sleep+0xe7/0x110 [ 268.313727] [] __kmalloc_node_track_caller+0x6f/0x290 [ 268.313733] [] ? pskb_expand_head+0x6b/0x2e0 [ 268.313753] [] kmalloc_reserve.isra.51+0x3c/0xa0 [ 268.313773] [] ? vprintk_emit+0x1ce/0x5c0 [ 268.313791] [] pskb_expand_head+0x6b/0x2e0 [ 268.313810] [] ? trace_hardirqs_on+0xd/0x10 [ 268.313828] [] netlink_trim+0x7f/0xc0 [ 268.313846] [] netlink_unicast+0x48/0x1e0 [ 268.313864] [] ? printk+0x61/0x63 [ 268.313893] [] send_nl_unicast_msg.constprop.44+0x3e/0xa0 [ 268.313900] [] rnl_ipcm_sock_closed_notif_msg+0xb8/0x120 [ 268.313906] [] kipcm_netlink_notify+0x6a/0xc0 [ 268.313910] [] ? skb_dequeue+0x67/0x90 [ 268.313915] [] notifier_call_chain+0x4d/0x70 [ 268.313920] [] atomic_notifier_call_chain+0x82/0xc0 [ 268.313924] [] ? __atomic_notifier_call_chain+0x5/0xc0 [ 268.313929] [] atomic_notifier_call_chain+0x16/0x20 [ 268.313933] [] netlink_release+0x282/0x2c0 [ 268.313938] [] sock_release+0x28/0x90 [ 268.313942] [] sock_close+0x12/0x20 [ 268.313948] [] fput+0xd3/0x260 [ 268.313953] [] __fput+0xe/0x10 [ 268.313959] [] task_work_run+0xb4/0xe0 [ 268.313964] [] get_signal_to_deliver+0x6fc/0x720 [ 268.313970] [] ? local_clock+0x4f/0x60 [ 268.313976] [] ? lock_release_holdtime.part.22+0x15/0x1b0 [ 268.313982] [] ? fget_light+0x9d/0x170 [ 268.313988] [] do_signal+0x58/0x5d0 [ 268.313993] [] ? fget_light+0xb5/0x170 [ 268.313998] [] ? fget_light+0x40/0x170 [ 268.314002] [] ? task_work_add+0x58/0x70 [ 268.314007] [] ? fput+0x7d/0xe0 [ 268.314012] [] ? sys_recvmsg+0x75/0x90 [ 268.314017] [] ? int_very_careful+0x5/0x6 [ 268.314023] [] do_notify_resume+0x80/0xc0 [ 268.314027] [] int_signal+0x12/0x17 [ 268.314137] rina-rnl-utils: Sent NL unicast msg of type 28 with seq num 0 to 1 [ 282.672187] rina-rnl: IPC Manager port: 1 [ 282.672211] rina-rnl-utils: Result of genlmesg_end: 32 [ 282.672213] BUG: sleeping function called from invalid context at mm/slub.c:926 [ 282.672811] in_atomic(): 1, irqs_disabled(): 0, pid: 2808, name: sshd [ 282.673347] 1 lock held by sshd/2808: [ 282.673350] #3: (rcu_read_lock){.+.+.+}, at: [] atomic_notifier_call_chain+0x5/0xc0 [ 282.673364] CPU: 0 PID: 2808 Comm: sshd Not tainted 3.10.0 #1001 [ 282.673366] Hardware name: innotek GmbH VirtualBox/VirtualBox, BIOS VirtualBox 12/01/2006 [ 282.673368] ffff88003f402b40 ffff880019971b18 ffffffff816c1f71 ffff880019971b38 [ 282.673373] ffffffff81078647 ffff88003f402b40 0000000000000010 ffff880019971bc8 [ 282.673377] ffffffff8118394f 0000000000000000 ffffffff81c33518 ffff880019971b88 [ 282.673381] Call Trace: [ 282.673387] [] dump_stack+0x19/0x1b [ 282.673392] [] might_sleep+0xe7/0x110 [ 282.673397] [] kmalloc_node_track_caller+0x6f/0x290 [ 282.673402] [] ? pskb_expand_head+0x6b/0x2e0 [ 282.673406] [] __kmalloc_reserve.isra.51+0x3c/0xa0 [ 282.673410] [] ? vprintk_emit+0x1ce/0x5c0 [ 282.673414] [] pskb_expand_head+0x6b/0x2e0 [ 282.673418] [] ? trace_hardirqs_on+0xd/0x10 [ 282.673422] [] netlink_trim+0x7f/0xc0 [ 282.673426] [] netlink_unicast+0x48/0x1e0 [ 282.673431] [] ? printk+0x61/0x63 [ 282.673436] [] send_nl_unicast_msg.constprop.44+0x3e/0xa0 [ 282.673441] [] rnl_ipcm_sock_closed_notif_msg+0xb8/0x120 [ 282.673444] [] kipcm_netlink_notify+0x6a/0xc0 [ 282.673448] [] ? skb_dequeue+0x67/0x90 [ 282.673451] [] notifier_call_chain+0x4d/0x70 [ 282.673455] [] atomic_notifier_call_chain+0x82/0xc0 [ 282.673458] [] ? atomic_notifier_call_chain+0x5/0xc0 [ 282.673461] [] atomic_notifier_call_chain+0x16/0x20 [ 282.673463] [] netlink_release+0x282/0x2c0 [ 282.673467] [] sock_release+0x28/0x90 [ 282.673470] [] sock_close+0x12/0x20 [ 282.673474] [] fput+0xd3/0x260 [ 282.673478] [] ? close_fd+0x80/0xb0 [ 282.673481] [] __fput+0xe/0x10 [ 282.673486] [] task_work_run+0xb4/0xe0 [ 282.673491] [] do_notify_resume+0xaa/0xc0 [ 282.673495] [] int_signal+0x12/0x17 [ 282.673594] rina-rnl-utils: Sent NL unicast msg of type 28 with seq num 0 to 1 [ 282.751325] rina-rnl: IPC Manager port: 1 [ 282.751392] rina-rnl-utils: Result of genlmesg_end: 32 [ 282.751506] rina-rnl-utils: Sent NL unicast msg of type 28 with seq num 0 to 1 [ 282.753807] rina-rnl: IPC Manager port: 1 [ 282.753914] rina-rnl-utils: Result of genlmesg_end: 32 [ 282.753990] rina-rnl-utils: Sent NL unicast msg of type 28 with seq num 0 to 1 [ 295.975121] rina-rnl: IPC Manager port: 1 [ 295.975224] rina-rnl-utils: Result of genlmesg_end: 32 [ 295.975230] BUG: sleeping function called from invalid context at mm/slub.c:926 [ 295.975667] in_atomic(): 1, irqs_disabled(): 0, pid: 2875, name: java [ 295.976047] 1 lock held by java/2875: [ 295.976048] #3: (rcu_read_lock){.+.+.+}, at: [] atomic_notifier_call_chain+0x5/0xc0 [ 295.976059] CPU: 1 PID: 2875 Comm: java Not tainted 3.10.0 #1001 [ 295.976060] Hardware name: innotek GmbH VirtualBox/VirtualBox, BIOS VirtualBox 12/01/2006 [ 295.976061] ffff88003f402b40 ffff88001a551968 ffffffff816c1f71 ffff88001a551988 [ 295.976065] ffffffff81078647 ffff88003f402b40 0000000000000010 ffff88001a551a18 [ 295.976067] ffffffff8118394f 0000000000000000 ffffffff81c33518 ffff88001a5519d8 [ 295.976070] Call Trace: [ 295.976073] [] dump_stack+0x19/0x1b [ 295.976077] [] might_sleep+0xe7/0x110 [ 295.976081] [] kmalloc_node_track_caller+0x6f/0x290 [ 295.976085] [] ? pskb_expand_head+0x6b/0x2e0 [ 295.976087] [] __kmalloc_reserve.isra.51+0x3c/0xa0 [ 295.976091] [] ? vprintk_emit+0x1ce/0x5c0 [ 295.976093] [] pskb_expand_head+0x6b/0x2e0 [ 295.976097] [] ? trace_hardirqs_on+0xd/0x10 [ 295.976099] [] netlink_trim+0x7f/0xc0 [ 295.976102] [] netlink_unicast+0x48/0x1e0 [ 295.976105] [] ? printk+0x61/0x63 [ 295.976109] [] send_nl_unicast_msg.constprop.44+0x3e/0xa0 [ 295.976113] [] rnl_ipcm_sock_closed_notif_msg+0xb8/0x120 [ 295.976115] [] kipcm_netlink_notify+0x6a/0xc0 [ 295.976117] [] ? skb_dequeue+0x67/0x90 [ 295.976120] [] notifier_call_chain+0x4d/0x70 [ 295.976122] [] atomic_notifier_call_chain+0x82/0xc0 [ 295.976124] [] ? atomic_notifier_call_chain+0x5/0xc0 [ 295.976127] [] atomic_notifier_call_chain+0x16/0x20 [ 295.976129] [] netlink_release+0x282/0x2c0 [ 295.976131] [] sock_release+0x28/0x90 [ 295.976134] [] sock_close+0x12/0x20 [ 295.976137] [] fput+0xd3/0x260 [ 295.976140] [] __fput+0xe/0x10 [ 295.976142] [] task_work_run+0xb4/0xe0 [ 295.976218] [] get_signal_to_deliver+0x6fc/0x720 [ 295.976229] [] ? local_clock+0x4f/0x60 [ 295.976239] [] ? lock_release_holdtime.part.22+0x15/0x1b0 [ 295.976249] [] ? fget_light+0x9d/0x170 [ 295.976260] [] do_signal+0x58/0x5d0 [ 295.976269] [] ? fget_light+0xb5/0x170 [ 295.976278] [] ? fget_light+0x40/0x170 [ 295.976288] [] ? task_work_add+0x58/0x70 [ 295.976298] [] ? fput+0x7d/0xe0 [ 295.976307] [] ? sys_recvmsg+0x75/0x90 [ 295.976317] [] ? int_very_careful+0x5/0x6 [ 295.976327] [] do_notify_resume+0x80/0xc0 [ 295.976336] [] int_signal+0x12/0x17 [ 295.976341] BUG: scheduling while atomic: java/2875/0x10000002 [ 295.976714] 1 lock held by java/2875: [ 295.976715] #3: (rcu_read_lock){.+.+.+}, at: [] atomic_notifier_call_chain+0x5/0xc0 [ 295.976720] Modules linked in: shim_dummy rina_personality_default nfsd auth_rpcgss oid_registry nfs_acl nfs lockd fscache sunrpc microcode psmouse i2c_piix4 pcspkr serio_raw hid_generic usbhid hid ahci libahci e1000 [ 295.976732] CPU: 1 PID: 2875 Comm: java Not tainted 3.10.0 #1001 [ 295.976734] Hardware name: innotek GmbH VirtualBox/VirtualBox, BIOS VirtualBox 12/01/2006 [ 295.976735] 0000000000000001 ffff88001a5518b8 ffffffff816c1f71 ffff88001a5518d8 [ 295.976746] ffffffff816bccd2 0000000000000000 ffff88003fbd4000 ffff88001a551958 [ 295.976750] ffffffff816c9075 ffff88003f9fffc0 000000000000000b ffff8800199e2440 [ 295.976753] Call Trace: [ 295.976757] [] dump_stack+0x19/0x1b [ 295.976762] [] schedule_bug+0x63/0x73 [ 295.976765] [] schedule+0x7e5/0x8a0 [ 295.976768] [] cond_resched+0x2a/0x40 [ 295.976771] [] _cond_resched+0x32/0x40 [ 295.976775] [] kmalloc_node_track_caller+0x74/0x290 [ 295.976779] [] ? pskb_expand_head+0x6b/0x2e0 [ 295.976783] [] __kmalloc_reserve.isra.51+0x3c/0xa0 [ 295.976787] [] ? vprintk_emit+0x1ce/0x5c0 [ 295.976789] [] pskb_expand_head+0x6b/0x2e0 [ 295.976792] [] ? trace_hardirqs_on+0xd/0x10 [ 295.976794] [] netlink_trim+0x7f/0xc0 [ 295.976797] [] netlink_unicast+0x48/0x1e0 [ 295.976799] [] ? printk+0x61/0x63 [ 295.976802] [] send_nl_unicast_msg.constprop.44+0x3e/0xa0 [ 295.976805] [] rnl_ipcm_sock_closed_notif_msg+0xb8/0x120 [ 295.976807] [] kipcm_netlink_notify+0x6a/0xc0 [ 295.976809] [] ? skb_dequeue+0x67/0x90 [ 295.976812] [] notifier_call_chain+0x4d/0x70 [ 295.976814] [] atomic_notifier_call_chain+0x82/0xc0 [ 295.976816] [] ? atomic_notifier_call_chain+0x5/0xc0 [ 295.976819] [] atomic_notifier_call_chain+0x16/0x20 [ 295.976820] [] netlink_release+0x282/0x2c0 [ 295.976823] [] sock_release+0x28/0x90 [ 295.976825] [] sock_close+0x12/0x20 [ 295.976828] [] fput+0xd3/0x260 [ 295.976830] [] __fput+0xe/0x10 [ 295.976833] [] task_work_run+0xb4/0xe0 [ 295.976835] [] get_signal_to_deliver+0x6fc/0x720 [ 295.976838] [] ? local_clock+0x4f/0x60 [ 295.976841] [] ? lock_release_holdtime.part.22+0x15/0x1b0 [ 295.976844] [] ? fget_light+0x9d/0x170 [ 295.976847] [] do_signal+0x58/0x5d0 [ 295.976849] [] ? fget_light+0xb5/0x170 [ 295.976851] [] ? fget_light+0x40/0x170 [ 295.976854] [] ? task_work_add+0x58/0x70 [ 295.976856] [] ? fput+0x7d/0xe0 [ 295.976858] [] ? __sys_recvmsg+0x75/0x90 [ 295.976861] [] ? int_very_careful+0x5/0x6 [ 295.976863] [] do_notify_resume+0x80/0xc0 [ 295.976865] [] int_signal+0x12/0x17 [ 295.976924] rina-rnl-utils: Sent NL unicast msg of type 28 with seq num 0 to 1 [ 298.410361] rina-rnl: IPC Manager port: 1 [ 298.410405] rina-rnl-utils: Result of genlmesg_end: 32 [ 298.410408] BUG: sleeping function called from invalid context at mm/slub.c:926 [ 298.411504] in_atomic(): 1, irqs_disabled(): 0, pid: 2888, name: java [ 298.412135] 1 lock held by java/2888: [ 298.412137] #3: (rcu_read_lock){.+.+.+}, at: [] atomic_notifier_call_chain+0x5/0xc0 [ 298.412145] CPU: 1 PID: 2888 Comm: java Tainted: G W 3.10.0 #1001 [ 298.412146] Hardware name: innotek GmbH VirtualBox/VirtualBox, BIOS VirtualBox 12/01/2006 [ 298.412147] ffff88003f402b40 ffff8800362f3968 ffffffff816c1f71 ffff8800362f3988 [ 298.412151] ffffffff81078647 ffff88003f402b40 0000000000000010 ffff8800362f3a18 [ 298.412153] ffffffff8118394f 0000000000000000 ffffffff81c33518 ffff8800362f39d8 [ 298.412155] Call Trace: [ 298.412159] [] dump_stack+0x19/0x1b [ 298.412162] [] might_sleep+0xe7/0x110 [ 298.412166] [] __kmalloc_node_track_caller+0x6f/0x290 [ 298.412169] [] ? pskb_expand_head+0x6b/0x2e0 [ 298.412172] [] kmalloc_reserve.isra.51+0x3c/0xa0 [ 298.412175] [] ? vprintk_emit+0x1ce/0x5c0 [ 298.412177] [] pskb_expand_head+0x6b/0x2e0 [ 298.412181] [] ? trace_hardirqs_on+0xd/0x10 [ 298.412183] [] netlink_trim+0x7f/0xc0 [ 298.412185] [] netlink_unicast+0x48/0x1e0 [ 298.412189] [] ? printk+0x61/0x63 [ 298.412192] [] send_nl_unicast_msg.constprop.44+0x3e/0xa0 [ 298.412195] [] rnl_ipcm_sock_closed_notif_msg+0xb8/0x120 [ 298.412198] [] kipcm_netlink_notify+0x6a/0xc0 [ 298.412200] [] ? skb_dequeue+0x67/0x90 [ 298.412202] [] notifier_call_chain+0x4d/0x70 [ 298.412205] [] atomic_notifier_call_chain+0x82/0xc0 [ 298.412207] [] ? __atomic_notifier_call_chain+0x5/0xc0 [ 298.412209] [] atomic_notifier_call_chain+0x16/0x20 [ 298.412211] [] netlink_release+0x282/0x2c0 [ 298.412213] [] sock_release+0x28/0x90 [ 298.412215] [] sock_close+0x12/0x20 [ 298.412218] [] fput+0xd3/0x260 [ 298.412221] [] __fput+0xe/0x10 [ 298.412224] [] task_work_run+0xb4/0xe0 [ 298.412226] [] get_signal_to_deliver+0x6fc/0x720 [ 298.412229] [] ? local_clock+0x4f/0x60 [ 298.412232] [] ? lock_release_holdtime.part.22+0x15/0x1b0 [ 298.412235] [] ? fget_light+0x9d/0x170 [ 298.412239] [] do_signal+0x58/0x5d0 [ 298.412241] [] ? fget_light+0xb5/0x170 [ 298.412243] [] ? fget_light+0x40/0x170 [ 298.412245] [] ? task_work_add+0x58/0x70 [ 298.412255] [] ? fput+0x7d/0xe0 [ 298.412255] [] ? sys_recvmsg+0x75/0x90 [ 298.412255] [] ? int_very_careful+0x5/0x6 [ 298.412255] [] do_notify_resume+0x80/0xc0 [ 298.412255] [] int_signal+0x12/0x17 [ 298.412416] rina-rnl-utils: Sent NL unicast msg of type 28 with seq num 0 to 1 [ 329.008182] rina-rnl: IPC Manager port: 1 [ 329.008237] rina-rnl-utils: Result of genlmesg_end: 32 [ 329.008241] BUG: sleeping function called from invalid context at mm/slub.c:926 [ 329.009747] in_atomic(): 1, irqs_disabled(): 0, pid: 2900, name: java [ 329.010641] 1 lock held by java/2900: [ 329.010643] #3: (rcu_read_lock){.+.+.+}, at: [] atomic_notifier_call_chain+0x5/0xc0 [ 329.010655] CPU: 1 PID: 2900 Comm: java Tainted: G W 3.10.0 #1001 [ 329.010657] Hardware name: innotek GmbH VirtualBox/VirtualBox, BIOS VirtualBox 12/01/2006 [ 329.010659] ffff88003f402b40 ffff88003a6d5968 ffffffff816c1f71 ffff88003a6d5988 [ 329.010663] ffffffff81078647 ffff88003f402b40 0000000000000010 ffff88003a6d5a18 [ 329.010666] ffffffff8118394f 0000000000000000 ffffffff81c33518 ffff88003a6d59d8 [ 329.010669] Call Trace: [ 329.010674] [] dump_stack+0x19/0x1b [ 329.010679] [] might_sleep+0xe7/0x110 [ 329.010683] [] kmalloc_node_track_caller+0x6f/0x290 [ 329.010693] [] ? pskb_expand_head+0x6b/0x2e0 [ 329.010706] [] __kmalloc_reserve.isra.51+0x3c/0xa0 [ 329.010720] [] ? vprintk_emit+0x1ce/0x5c0 [ 329.010733] [] pskb_expand_head+0x6b/0x2e0 [ 329.010749] [] ? trace_hardirqs_on+0xd/0x10 [ 329.010761] [] netlink_trim+0x7f/0xc0 [ 329.010773] [] netlink_unicast+0x48/0x1e0 [ 329.010786] [] ? printk+0x61/0x63 [ 329.010799] [] send_nl_unicast_msg.constprop.44+0x3e/0xa0 [ 329.010814] [] rnl_ipcm_sock_closed_notif_msg+0xb8/0x120 [ 329.010827] [] kipcm_netlink_notify+0x6a/0xc0 [ 329.010839] [] ? skb_dequeue+0x67/0x90 [ 329.010852] [] notifier_call_chain+0x4d/0x70 [ 329.010865] [] atomic_notifier_call_chain+0x82/0xc0 [ 329.010885] [] ? atomic_notifier_call_chain+0x5/0xc0 [ 329.010889] [] atomic_notifier_call_chain+0x16/0x20 [ 329.010891] [] netlink_release+0x282/0x2c0 [ 329.010895] [] sock_release+0x28/0x90 [ 329.010898] [] sock_close+0x12/0x20 [ 329.010902] [] fput+0xd3/0x260 [ 329.010905] [] __fput+0xe/0x10 [ 329.010909] [] task_work_run+0xb4/0xe0 [ 329.010913] [] get_signal_to_deliver+0x6fc/0x720 [ 329.010917] [] ? local_clock+0x4f/0x60 [ 329.010920] [] ? lock_release_holdtime.part.22+0x15/0x1b0 [ 329.010924] [] ? fget_light+0x9d/0x170 [ 329.010929] [] do_signal+0x58/0x5d0 [ 329.010932] [] ? fget_light+0xb5/0x170 [ 329.010935] [] ? fget_light+0x40/0x170 [ 329.010938] [] ? task_work_add+0x58/0x70 [ 329.010950] [] ? fput+0x7d/0xe0 [ 329.010954] [] ? __sys_recvmsg+0x75/0x90 [ 329.010958] [] ? int_very_careful+0x5/0x6 [ 329.010962] [] do_notify_resume+0x80/0xc0 [ 329.010964] [] int_signal+0x12/0x17 [ 329.011041] rina-rnl-utils: Sent NL unicast msg of type 28 with seq num 0 to 1 [ 331.525780] rina-rnl: IPC Manager port: 1 [ 331.525830] rina-rnl-utils: Result of genlmesg_end: 32 [ 331.525834] BUG: sleeping function called from invalid context at mm/slub.c:926 [ 331.528351] in_atomic(): 1, irqs_disabled(): 0, pid: 2912, name: java [ 331.529774] 1 lock held by java/2912: [ 331.529781] #3: (rcu_read_lock){.+.+.+}, at: [] atomic_notifier_call_chain+0x5/0xc0 [ 331.529799] CPU: 1 PID: 2912 Comm: java Tainted: G W 3.10.0 #1001 [ 331.529804] Hardware name: innotek GmbH VirtualBox/VirtualBox, BIOS VirtualBox 12/01/2006 [ 331.529811] ffff88003f402b40 ffff88001a619968 ffffffff816c1f71 ffff88001a619988 [ 331.529820] ffffffff81078647 ffff88003f402b40 0000000000000010 ffff88001a619a18 [ 331.529830] ffffffff8118394f 0000000000000000 ffffffff81c33518 ffff88001a6199d8 [ 331.529853] Call Trace: [ 331.529868] [] dump_stack+0x19/0x1b [ 331.529882] [] might_sleep+0xe7/0x110 [ 331.529896] [] __kmalloc_node_track_caller+0x6f/0x290 [ 331.529910] [] ? pskb_expand_head+0x6b/0x2e0 [ 331.529937] [] kmalloc_reserve.isra.51+0x3c/0xa0 [ 331.529951] [] ? vprintk_emit+0x1ce/0x5c0 [ 331.529963] [] pskb_expand_head+0x6b/0x2e0 [ 331.529977] [] ? trace_hardirqs_on+0xd/0x10 [ 331.529990] [] netlink_trim+0x7f/0xc0 [ 331.530017] [] netlink_unicast+0x48/0x1e0 [ 331.530032] [] ? printk+0x61/0x63 [ 331.530047] [] send_nl_unicast_msg.constprop.44+0x3e/0xa0 [ 331.530060] [] rnl_ipcm_sock_closed_notif_msg+0xb8/0x120 [ 331.530079] [] kipcm_netlink_notify+0x6a/0xc0 [ 331.530091] [] ? skb_dequeue+0x67/0x90 [ 331.530104] [] notifier_call_chain+0x4d/0x70 [ 331.530117] [] atomic_notifier_call_chain+0x82/0xc0 [ 331.530129] [] ? __atomic_notifier_call_chain+0x5/0xc0 [ 331.530156] [] atomic_notifier_call_chain+0x16/0x20 [ 331.530168] [] netlink_release+0x282/0x2c0 [ 331.530181] [] sock_release+0x28/0x90 [ 331.530192] [] sock_close+0x12/0x20 [ 331.530205] [] fput+0xd3/0x260 [ 331.530218] [] __fput+0xe/0x10 [ 331.530245] [] task_work_run+0xb4/0xe0 [ 331.530259] [] get_signal_to_deliver+0x6fc/0x720 [ 331.530272] [] ? local_clock+0x4f/0x60 [ 331.530285] [] ? lock_release_holdtime.part.22+0x15/0x1b0 [ 331.530299] [] ? fget_light+0x9d/0x170 [ 331.530327] [] do_signal+0x58/0x5d0 [ 331.530340] [] ? fget_light+0xb5/0x170 [ 331.530351] [] ? fget_light+0x40/0x170 [ 331.530364] [] ? task_work_add+0x58/0x70 [ 331.530385] [] ? fput+0x7d/0xe0 [ 331.530397] [] ? sys_recvmsg+0x75/0x90 [ 331.530410] [] ? int_very_careful+0x5/0x6 [ 331.530423] [] do_notify_resume+0x80/0xc0 [ 331.530442] [] int_signal+0x12/0x17 [ 331.530658] rina-rnl-utils: Sent NL unicast msg of type 28 with seq num 0 to 1 [ 428.804346] rina-rnl: IPC Manager port: 1 [ 428.804402] rina-rnl-utils: Result of genlmesg_end: 32 [ 428.804405] BUG: sleeping function called from invalid context at mm/slub.c:926 [ 428.805697] in_atomic(): 1, irqs_disabled(): 0, pid: 2927, name: java [ 428.806421] 1 lock held by java/2927: [ 428.806426] #3: (rcu_read_lock){.+.+.+}, at: [] atomic_notifier_call_chain+0x5/0xc0 [ 428.806440] CPU: 1 PID: 2927 Comm: java Tainted: G W 3.10.0 #1001 [ 428.806445] Hardware name: innotek GmbH VirtualBox/VirtualBox, BIOS VirtualBox 12/01/2006 [ 428.806450] ffff88003f402b40 ffff88001a1eb968 ffffffff816c1f71 ffff88001a1eb988 [ 428.806456] ffffffff81078647 ffff88003f402b40 0000000000000010 ffff88001a1eba18 [ 428.806462] ffffffff8118394f 0000000000000000 ffffffff81c33518 ffff88001a1eb9d8 [ 428.806706] Call Trace: [ 428.806711] [] dump_stack+0x19/0x1b [ 428.806715] [] might_sleep+0xe7/0x110 [ 428.806718] [] kmalloc_node_track_caller+0x6f/0x290 [ 428.806722] [] ? pskb_expand_head+0x6b/0x2e0 [ 428.806725] [] __kmalloc_reserve.isra.51+0x3c/0xa0 [ 428.806728] [] ? vprintk_emit+0x1ce/0x5c0 [ 428.806730] [] pskb_expand_head+0x6b/0x2e0 [ 428.806734] [] ? trace_hardirqs_on+0xd/0x10 [ 428.806736] [] netlink_trim+0x7f/0xc0 [ 428.806738] [] netlink_unicast+0x48/0x1e0 [ 428.806742] [] ? printk+0x61/0x63 [ 428.806746] [] send_nl_unicast_msg.constprop.44+0x3e/0xa0 [ 428.806749] [] rnl_ipcm_sock_closed_notif_msg+0xb8/0x120 [ 428.806751] [] kipcm_netlink_notify+0x6a/0xc0 [ 428.806753] [] ? skb_dequeue+0x67/0x90 [ 428.806756] [] notifier_call_chain+0x4d/0x70 [ 428.806759] [] atomic_notifier_call_chain+0x82/0xc0 [ 428.806761] [] ? atomic_notifier_call_chain+0x5/0xc0 [ 428.806763] [] atomic_notifier_call_chain+0x16/0x20 [ 428.806765] [] netlink_release+0x282/0x2c0 [ 428.806768] [] sock_release+0x28/0x90 [ 428.806770] [] sock_close+0x12/0x20 [ 428.806774] [] fput+0xd3/0x260 [ 428.806776] [] __fput+0xe/0x10 [ 428.806779] [] task_work_run+0xb4/0xe0 [ 428.806782] [] get_signal_to_deliver+0x6fc/0x720 [ 428.806785] [] ? local_clock+0x4f/0x60 [ 428.806788] [] ? lock_release_holdtime.part.22+0x15/0x1b0 [ 428.806791] [] ? fget_light+0x9d/0x170 [ 428.806795] [] do_signal+0x58/0x5d0 [ 428.806797] [] ? fget_light+0xb5/0x170 [ 428.806800] [] ? fget_light+0x40/0x170 [ 428.806802] [] ? task_work_add+0x58/0x70 [ 428.806805] [] ? fput+0x7d/0xe0 [ 428.806807] [] ? __sys_recvmsg+0x75/0x90 [ 428.806810] [] ? int_very_careful+0x5/0x6 [ 428.806813] [] do_notify_resume+0x80/0xc0 [ 428.806815] [] int_signal+0x12/0x17 [ 428.806884] rina-rnl-utils: Sent NL unicast msg of type 28 with seq num 0 to 1 [ 649.118828] rina-rnl: IPC Manager port: 1 [ 649.118885] rina-rnl-utils: Result of genlmesg_end: 32 [ 649.118889] BUG: sleeping function called from invalid context at mm/slub.c:926 [ 649.120656] in_atomic(): 1, irqs_disabled(): 0, pid: 2947, name: java [ 649.121615] 1 lock held by java/2947: [ 649.121622] #3: (rcu_read_lock){.+.+.+}, at: [] atomic_notifier_call_chain+0x5/0xc0 [ 649.121639] CPU: 1 PID: 2947 Comm: java Tainted: G W 3.10.0 #1001 [ 649.121646] Hardware name: innotek GmbH VirtualBox/VirtualBox, BIOS VirtualBox 12/01/2006 [ 649.121653] ffff88003f402b40 ffff88003670b968 ffffffff816c1f71 ffff88003670b988 [ 649.121662] ffffffff81078647 ffff88003f402b40 0000000000000010 ffff88003670ba18 [ 649.121671] ffffffff8118394f 0000000000000000 ffffffff81c33518 ffff88003670b9d8 [ 649.121680] Call Trace: [ 649.121695] [] dump_stack+0x19/0x1b [ 649.121709] [] might_sleep+0xe7/0x110 [ 649.121724] [] __kmalloc_node_track_caller+0x6f/0x290 [ 649.121739] [] ? pskb_expand_head+0x6b/0x2e0 [ 649.121753] [] kmalloc_reserve.isra.51+0x3c/0xa0 [ 649.121768] [] ? vprintk_emit+0x1ce/0x5c0 [ 649.121781] [] pskb_expand_head+0x6b/0x2e0 [ 649.121795] [] ? trace_hardirqs_on+0xd/0x10 [ 649.121809] [] netlink_trim+0x7f/0xc0 [ 649.121822] [] netlink_unicast+0x48/0x1e0 [ 649.121837] [] ? printk+0x61/0x63 [ 649.121858] [] send_nl_unicast_msg.constprop.44+0x3e/0xa0 [ 649.121863] [] rnl_ipcm_sock_closed_notif_msg+0xb8/0x120 [ 649.121867] [] kipcm_netlink_notify+0x6a/0xc0 [ 649.121870] [] ? skb_dequeue+0x67/0x90 [ 649.121884] [] notifier_call_chain+0x4d/0x70 [ 649.121888] [] atomic_notifier_call_chain+0x82/0xc0 [ 649.121891] [] ? __atomic_notifier_call_chain+0x5/0xc0 [ 649.121895] [] atomic_notifier_call_chain+0x16/0x20 [ 649.121898] [] netlink_release+0x282/0x2c0 [ 649.121902] [] sock_release+0x28/0x90 [ 649.121905] [] sock_close+0x12/0x20 [ 649.121909] [] fput+0xd3/0x260 [ 649.121912] [] __fput+0xe/0x10 [ 649.121917] [] task_work_run+0xb4/0xe0 [ 649.121920] [] get_signal_to_deliver+0x6fc/0x720 [ 649.121925] [] ? local_clock+0x4f/0x60 [ 649.121929] [] ? lock_release_holdtime.part.22+0x15/0x1b0 [ 649.121933] [] ? fget_light+0x9d/0x170 [ 649.121937] [] do_signal+0x58/0x5d0 [ 649.121941] [] ? fget_light+0xb5/0x170 [ 649.121944] [] ? fget_light+0x40/0x170 [ 649.121947] [] ? task_work_add+0x58/0x70 [ 649.121951] [] ? fput+0x7d/0xe0 [ 649.121954] [] ? sys_recvmsg+0x75/0x90 [ 649.121958] [] ? int_very_careful+0x5/0x6 [ 649.121961] [] do_notify_resume+0x80/0xc0 [ 649.121964] [] int_signal+0x12/0x17 [ 649.122015] rina-rnl-utils: Sent NL unicast msg of type 28 with seq num 0 to 1 [ 733.105530] rina-rnl: IPC Manager port: 1 [ 733.105564] rina-rnl-utils: Result of genlmesg_end: 32 [ 733.105567] BUG: sleeping function called from invalid context at mm/slub.c:926 [ 733.106696] in_atomic(): 1, irqs_disabled(): 0, pid: 2959, name: java [ 733.107433] 1 lock held by java/2959: [ 733.107434] #3: (rcu_read_lock){.+.+.+}, at: [] atomic_notifier_call_chain+0x5/0xc0 [ 733.107445] CPU: 0 PID: 2959 Comm: java Tainted: G W 3.10.0 #1001 [ 733.107446] Hardware name: innotek GmbH VirtualBox/VirtualBox, BIOS VirtualBox 12/01/2006 [ 733.107447] ffff88003f402b40 ffff880033e59968 ffffffff816c1f71 ffff880033e59988 [ 733.107451] ffffffff81078647 ffff88003f402b40 0000000000000010 ffff880033e59a18 [ 733.107453] ffffffff8118394f 0000000000000000 ffffffff81c33518 ffff880033e599d8 [ 733.107456] Call Trace: [ 733.107459] [] dump_stack+0x19/0x1b [ 733.107463] [] might_sleep+0xe7/0x110 [ 733.107467] [] kmalloc_node_track_caller+0x6f/0x290 [ 733.107469] [] ? pskb_expand_head+0x6b/0x2e0 [ 733.107469] [] __kmalloc_reserve.isra.51+0x3c/0xa0 [ 733.107475] [] ? vprintk_emit+0x1ce/0x5c0 [ 733.107485] [] pskb_expand_head+0x6b/0x2e0 [ 733.107496] [] ? trace_hardirqs_on+0xd/0x10 [ 733.107506] [] netlink_trim+0x7f/0xc0 [ 733.107516] [] netlink_unicast+0x48/0x1e0 [ 733.107527] [] ? printk+0x61/0x63 [ 733.107538] [] send_nl_unicast_msg.constprop.44+0x3e/0xa0 [ 733.107548] [] rnl_ipcm_sock_closed_notif_msg+0xb8/0x120 [ 733.107558] [] kipcm_netlink_notify+0x6a/0xc0 [ 733.107568] [] ? skb_dequeue+0x67/0x90 [ 733.107579] [] notifier_call_chain+0x4d/0x70 [ 733.107589] [] atomic_notifier_call_chain+0x82/0xc0 [ 733.107599] [] ? __atomic_notifier_call_chain+0x5/0xc0 [ 733.107609] [] atomic_notifier_call_chain+0x16/0x20 [ 733.107618] [] netlink_release+0x282/0x2c0 [ 733.107628] [] sock_release+0x28/0x90 [ 733.107638] [] sock_close+0x12/0x20 [ 733.107648] [] fput+0xd3/0x260 [ 733.107658] [] __fput+0xe/0x10 [ 733.107669] [] task_work_run+0xb4/0xe0 [ 733.107679] [] get_signal_to_deliver+0x6fc/0x720 [ 733.107690] [] ? local_clock+0x4f/0x60 [ 733.107701] [] ? lock_release_holdtime.part.22+0x15/0x1b0 [ 733.107712] [] ? fget_light+0x9d/0x170 [ 733.107722] [] do_signal+0x58/0x5d0 [ 733.107732] [] ? fget_light+0xb5/0x170 [ 733.107742] [] ? fget_light+0x40/0x170 [ 733.107752] [] ? task_work_add+0x58/0x70 [ 733.107762] [] ? fput+0x7d/0xe0 [ 733.107772] [] ? __sys_recvmsg+0x75/0x90 [ 733.107782] [] ? int_very_careful+0x5/0x6 [ 733.107792] [] do_notify_resume+0x80/0xc0 [ 733.107802] [] int_signal+0x12/0x17 [ 733.108273] rina-rnl-utils: Sent NL unicast msg of type 28 with seq num 0 to 1

kewinrausch commented 9 years ago

Comment by salvestrini Monday Nov 25, 2013 at 10:36 GMT


Log from cc5c50dbd3dbc4534967d947cbbc7389b2d9a57b (current wip-francesco)

[ 123.538326] rina-kfa: Sleeping read syscall should be working now [ 123.538340] rina-kfa: Woken up [ 123.538341] rina-syscalls: Personality returned value 0 [ 123.538354] rina-syscalls: Exiting SYSC_sdu_read syscall body [ 123.538397] rina-syscalls: Entered SYSC_sdu_read syscall body [ 123.538398] rina-syscalls: Handling personality hook sdu_read [ 123.538399] rina-syscalls: Calling personality hook sdu_read [ 123.538400] rina-personality-default: Calling wrapped function [ 123.538401] rina-kfa: Trying to read SDU from port-id 1 [ 123.538403] rina-kfa: Going to sleep on wait queue ffff88001af5b480 [ 123.538412] rina-syscalls: Entered SYSC_sdu_read syscall body [ 123.538413] rina-syscalls: Handling personality hook sdu_read [ 123.538414] rina-syscalls: Calling personality hook sdu_read [ 123.538415] rina-personality-default: Calling wrapped function [ 123.538416] rina-kfa: Trying to read SDU from port-id 2 [ 123.538417] rina-syscalls: Personality returned value 0 [ 123.538433] rina-syscalls: Exiting SYSC_sdu_read syscall body [ 123.538461] rina-syscalls: Entered SYSC_sdu_write syscall body [ 123.538462] rina-syscalls: Syscall write SDU of size 100 called with port-id 2 [ 123.538496] rina-syscalls: Handling personality hook sdu_write [ 123.538497] rina-syscalls: Calling personality hook sdu_write [ 123.538498] rina-personality-default: Calling wrapped function [ 123.538499] rina-kipcm: Tring to write SDU of size 100 to port_id 2 [ 123.538500] rina-shim-dummy: Dummy SDU write invoked. [ 123.538558] rina-shim-dummy: Write data complete? 1 [ 123.538584] rina-rwq: Work posted on workqueue ffff88001a4ac000, please wait ... [ 123.538585] rina-syscalls: Exiting SYSC_sdu_write syscall body [ 123.538595] rina-shim-dummy: Write data complete? 1 [ 123.538597] rina-kfa: Posting SDU of size 100 to port-id 1 [ 123.538599] rina-kfa: Wait queue ffff88001af5b480, next: ffff880031e5deb8, prev: ffff880031e5deb8 [ 123.538601] rina-kfa: SDU posted [ 123.538605] rina-kfa: Sleeping read syscall should be working now [ 123.538622] rina-kfa: Woken up [ 123.538624] rina-syscalls: Personality returned value 0 [ 123.538638] rina-syscalls: Exiting SYSC_sdu_read syscall body [ 123.538676] rina-syscalls: Entered SYSC_sdu_read syscall body [ 123.538678] rina-syscalls: Handling personality hook sdu_read [ 123.538679] rina-syscalls: Calling personality hook sdu_read [ 123.538680] rina-personality-default: Calling wrapped function [ 123.538681] rina-kfa: Trying to read SDU from port-id 1 [ 123.538682] rina-kfa: Going to sleep on wait queue ffff88001af5b480 [ 123.538697] rina-syscalls: Entered SYSC_sdu_read syscall body [ 123.538698] rina-syscalls: Handling personality hook sdu_read [ 123.538699] rina-syscalls: Calling personality hook sdu_read [ 123.538700] rina-personality-default: Calling wrapped function [ 123.538701] rina-kfa: Trying to read SDU from port-id 2 [ 123.538702] rina-syscalls: Personality returned value 0 [ 123.538718] rina-syscalls: Exiting SYSC_sdu_read syscall body [ 123.538746] rina-syscalls: Entered SYSC_sdu_write syscall body [ 123.538747] rina-syscalls: Syscall write SDU of size 100 called with port-id 2 [ 123.538780] rina-syscalls: Handling personality hook sdu_write [ 123.538781] rina-syscalls: Calling personality hook sdu_write [ 123.538782] rina-personality-default: Calling wrapped function [ 123.538783] rina-kipcm: Tring to write SDU of size 100 to port_id 2 [ 123.538785] rina-shim-dummy: Dummy SDU write invoked. [ 123.538870] rina-shim-dummy: Write data complete? 1 [ 123.538886] rina-rwq: Work posted on workqueue ffff88001a4ac000, please wait ... [ 123.538887] rina-syscalls: Exiting SYSC_sdu_write syscall body [ 123.538896] rina-shim-dummy: Write data complete? 1 [ 123.538898] rina-kfa: Posting SDU of size 100 to port-id 1 [ 123.538899] rina-kfa: Wait queue ffff88001af5b480, next: ffff880031e5deb8, prev: ffff880031e5deb8 [ 123.538900] rina-kfa: SDU posted [ 123.538904] rina-kfa: Sleeping read syscall should be working now [ 123.538918] rina-kfa: Woken up [ 123.538920] rina-syscalls: Personality returned value 0 [ 123.538933] rina-syscalls: Exiting SYSC_sdu_read syscall body [ 123.545687] rina-rnl: Dispatching message (skb-in=ffff88001b3c6900, info=ffff88001b0f39b0) [ 123.545698] rina-rnl: Multiplexing message type 15 [ 123.545700] rina-rnl: Fetching handler callback and data [ 123.545702] rina-rnl: Gonna call ffffffffa01a5b40(ffff88001a548490, ffff88001b3c6900, ffff88001b0f39b0) [ 123.545741] rina-rnl-utils: RINA Netlink parser started ... [ 123.545742] rina-rnl-utils: msg at ffff88001f84edc8 / msg->attrs at ffff88003bcb87e0 [ 123.545743] rina-rnl-utils: src-ipc-id: 0 [ 123.545744] rina-rnl-utils: dst-ipc-id: 1 [ 123.545748] rina-kfa: We are destroying a flow [ 123.545789] rina-pidm: Bitmap release completed successfully [ 123.545792] rina-kfa: We are destroying a flow [ 123.545816] rina-pidm: Bitmap release completed successfully [ 123.545853] rina-rnl-utils: Result of genlmesg_end: 40 [ 123.545898] rina-rnl-utils: Unicast NL message sent (type = 17, seq-num 0, port = 1) [ 123.545929] rina-ipcp-utils: Name at ffff88001ab59950 finalized successfully [ 123.545936] rina-ipcp-utils: Name at ffff88001ab59950 destroyed successfully [ 123.545962] rina-ipcp-utils: Name at ffff88001ab593b0 finalized successfully [ 123.545969] rina-ipcp-utils: Name at ffff88001ab593b0 destroyed successfully [ 123.546033] rina-rnl-utils: Result of genlmesg_end: 32 [ 123.570114] rina-rnl-utils: Unicast NL message sent (type = 16, seq-num 1385375477, port = 1) [ 123.570117] rina-rnl: Message 15 handled successfully [ 123.648315] rina-rnl: IPC Manager port: 1 [ 123.648315] rina-rnl-utils: Result of genlmesg_end: 32 [ 123.648315] BUG: sleeping function called from invalid context at mm/slub.c:926 [ 123.648754] in_atomic(): 1, irqs_disabled(): 0, pid: 2834, name: java [ 123.649174] 1 lock held by java/2834: [ 123.649179] #3: (rcu_read_lock){.+.+.+}, at: [] atomic_notifier_call_chain+0x5/0xc0 [ 123.649193] CPU: 0 PID: 2834 Comm: java Not tainted 3.10.0 #1163 [ 123.649198] Hardware name: innotek GmbH VirtualBox/VirtualBox, BIOS VirtualBox 12/01/2006 [ 123.649203] ffff88003f402b40 ffff88003359d968 ffffffff816c2f11 ffff88003359d988 [ 123.649210] ffffffff81078647 ffff88003f402b40 0000000000000010 ffff88003359da18 [ 123.649216] ffffffff8118394f 0000000000000000 ffffffff81c33518 ffff88003359d9d8 [ 123.649222] Call Trace: [ 123.649234] [] dump_stack+0x19/0x1b [ 123.649253] [] might_sleep+0xe7/0x110 [ 123.649264] [] kmalloc_node_track_caller+0x6f/0x290 [ 123.649275] [] ? pskb_expand_head+0x6b/0x2e0 [ 123.649285] [] __kmalloc_reserve.isra.51+0x3c/0xa0 [ 123.649296] [] ? vprintk_emit+0x1ce/0x5c0 [ 123.649305] [] pskb_expand_head+0x6b/0x2e0 [ 123.649316] [] ? trace_hardirqs_on+0xd/0x10 [ 123.649326] [] netlink_trim+0x7f/0xc0 [ 123.649336] [] netlink_unicast+0x48/0x1e0 [ 123.649350] [] ? printk+0x61/0x63 [ 123.649353] [] send_nl_unicast_msg.constprop.36+0x44/0xc0 [ 123.649356] [] rnl_ipcm_sock_closed_notif_msg+0xb4/0x120 [ 123.649358] [] netlink_notify_callback+0x6a/0xa0 [ 123.649360] [] ? skb_dequeue+0x67/0x90 [ 123.649363] [] notifier_call_chain+0x4d/0x70 [ 123.649365] [] atomic_notifier_call_chain+0x82/0xc0 [ 123.649367] [] ? __atomic_notifier_call_chain+0x5/0xc0 [ 123.649370] [] atomic_notifier_call_chain+0x16/0x20 [ 123.649372] [] netlink_release+0x282/0x2c0 [ 123.649375] [] sock_release+0x28/0x90 [ 123.649377] [] sock_close+0x12/0x20 [ 123.649380] [] fput+0xd3/0x260 [ 123.649382] [] __fput+0xe/0x10 [ 123.649385] [] task_work_run+0xb4/0xe0 [ 123.649388] [] get_signal_to_deliver+0x6fc/0x720 [ 123.649391] [] ? local_clock+0x4f/0x60 [ 123.649393] [] ? lock_release_holdtime.part.22+0x15/0x1b0 [ 123.649397] [] ? fget_light+0x9d/0x170 [ 123.649400] [] do_signal+0x58/0x5d0 [ 123.649402] [] ? fget_light+0xb5/0x170 [ 123.649404] [] ? fget_light+0x40/0x170 [ 123.649407] [] ? task_work_add+0x58/0x70 [ 123.649409] [] ? fput+0x7d/0xe0 [ 123.649411] [] ? __sys_recvmsg+0x75/0x90 [ 123.649414] [] ? int_very_careful+0x5/0x6 [ 123.649416] [] do_notify_resume+0x80/0xc0 [ 123.649418] [] int_signal+0x12/0x17 [ 123.649476] rina-rnl-utils: Unicast NL message sent (type = 28, seq-num 0, port = 1) [ 123.655529] rina-rnl: Dispatching message (skb-in=ffff88001cbceb40, info=ffff88001b0f39b0) [ 123.655532] rina-rnl: Multiplexing message type 20 [ 123.655534] rina-rnl: Fetching handler callback and data [ 123.655537] rina-rnl: Gonna call ffffffffa01a5d20(ffff88001a548490, ffff88001cbceb40, ffff88001b0f39b0) [ 123.655627] rina-rnl-utils: RINA Netlink parser started ... [ 123.655629] rina-rnl-utils: msg at ffff88001f84edc8 / msg->attrs at ffff88001aa48158 [ 123.655630] rina-rnl-utils: src-ipc-id: 0 [ 123.655631] rina-rnl-utils: dst-ipc-id: 1 [ 123.655634] rina-ipcp-utils: Name at ffff88001ab593b0 finalized successfully [ 123.655723] rina-ipcp-utils: Name at ffff88001ab59d88 finalized successfully [ 123.655845] rina-ipcp-utils: Name at ffff88001b0d42d0 finalized successfully [ 123.655857] rina-ipcp-utils: Name at ffff88001b0d42d0 destroyed successfully [ 123.655897] rina-ipcp-utils: Name at ffff88001ab593b0 finalized successfully [ 123.655916] rina-ipcp-utils: Name at ffff88001ab593b0 destroyed successfully [ 123.655949] rina-ipcp-utils: Name at ffff88001ab59d88 finalized successfully [ 123.655958] rina-ipcp-utils: Name at ffff88001ab59d88 destroyed successfully [ 123.656027] rina-rnl-utils: Result of genlmesg_end: 32 [ 123.656048] rina-rnl-utils: Unicast NL message sent (type = 21, seq-num 1385375478, port = 1) [ 123.656048] rina-rnl: Message 20 handled successfully

kewinrausch commented 9 years ago

Comment by salvestrini Monday Nov 25, 2013 at 11:02 GMT


From a9919e5f374209d1216aba741bb95228564faba1

[ 248.546936] rina-rnl-utils: Going to send NL unicast message (type = 28, seq-num 0, port = 1) [ 248.546948] BUG: sleeping function called from invalid context at mm/slub.c:926 [ 248.547426] in_atomic(): 1, irqs_disabled(): 0, pid: 2811, name: java [ 248.547893] 1 lock held by java/2811: [ 248.547895] #3: (rcu_read_lock){.+.+.+}, at: [] atomic_notifier_call_chain+0x5/0xc0 [ 248.547909] CPU: 1 PID: 2811 Comm: java Not tainted 3.10.0 #1168 [ 248.547912] Hardware name: innotek GmbH VirtualBox/VirtualBox, BIOS VirtualBox 12/01/2006 [ 248.547914] ffff88003f402b40 ffff880021dfb968 ffffffff816c2f21 ffff880021dfb988 [ 248.547919] ffffffff81078647 ffff88003f402b40 0000000000000010 ffff880021dfba18 [ 248.547923] ffffffff8118394f 0000000000000001 0000000000000086 0000000000000000 [ 248.547928] Call Trace: [ 248.547934] [] dump_stack+0x19/0x1b [ 248.547948] [] might_sleep+0xe7/0x110 [ 248.547954] [] kmalloc_node_track_caller+0x6f/0x290 [ 248.547959] [] ? pskb_expand_head+0x6b/0x2e0 [ 248.547964] [] ? mark_held_locks+0x74/0x150 [ 248.547968] [] kmalloc_reserve.isra.51+0x3c/0xa0 [ 248.547972] [] pskb_expand_head+0x6b/0x2e0 [ 248.547975] [] ? trace_hardirqs_on+0xd/0x10 [ 248.547979] [] netlink_trim+0x7f/0xc0 [ 248.547983] [] netlink_unicast+0x48/0x1e0 [ 248.547988] [] send_nl_unicast_msg.constprop.35+0x5d/0xd0 [ 248.547992] [] rnl_ipcm_sock_closed_notif_msg+0xb4/0x120 [ 248.547996] [] netlink_notify_callback+0x6a/0xa0 [ 248.547999] [] ? skb_dequeue+0x67/0x90 [ 248.548003] [] notifier_call_chain+0x4d/0x70 [ 248.548007] [] atomic_notifier_call_chain+0x82/0xc0 [ 248.548011] [] ? atomic_notifier_call_chain+0x5/0xc0 [ 248.548015] [] atomic_notifier_call_chain+0x16/0x20 [ 248.548019] [] netlink_release+0x282/0x2c0 [ 248.548023] [] sock_release+0x28/0x90 [ 248.548027] [] sock_close+0x12/0x20 [ 248.548031] [] fput+0xd3/0x260 [ 248.548035] [] __fput+0xe/0x10 [ 248.548039] [] task_work_run+0xb4/0xe0 [ 248.548043] [] get_signal_to_deliver+0x6fc/0x720 [ 248.548047] [] ? local_clock+0x4f/0x60 [ 248.548051] [] ? lock_release_holdtime.part.22+0x15/0x1b0 [ 248.548056] [] ? fget_light+0x9d/0x170 [ 248.548061] [] do_signal+0x58/0x5d0 [ 248.548064] [] ? fget_light+0xb5/0x170 [ 248.548068] [] ? fget_light+0x40/0x170 [ 248.548072] [] ? task_work_add+0x58/0x70 [ 248.548076] [] ? fput+0x7d/0xe0 [ 248.548080] [] ? __sys_recvmsg+0x75/0x90 [ 248.548084] [] ? int_very_careful+0x5/0x6 [ 248.548088] [] do_notify_resume+0x80/0xc0 [ 248.548091] [] int_signal+0x12/0x17 [ 248.548162] rina-rnl-utils: Unicast NL message sent (type = 28, seq-num 0, port = 1) [ 248.553610] rina-rnl: Dispatching message (skb-in=ffff88001ab12900, info=ffff8800236bd9b0) [ 248.553614] rina-rnl: Multiplexing message type 20 [ 248.553617] rina-rnl: Fetching handler callback and data [ 248.553620] rina-rnl: Gonna call ffffffffa01a4d20(ffff88003c3e1918, ffff88001ab12900, ffff8800236bd9b0) [ 248.553727] rina-rnl-utils: RINA Netlink parser started ... [ 248.553730] rina-rnl-utils: msg at ffff88001f0dfb90 / msg->attrs at ffff88001f0dcc18 [ 248.553731] rina-rnl-utils: src-ipc-id: 0 [ 248.553733] rina-rnl-utils: dst-ipc-id: 1 [ 248.553736] rina-ipcp-utils: Name at ffff88001a28f248 finalized successfully [ 248.553841] rina-ipcp-utils: Name at ffff88001a28e2d0 finalized successfully [ 248.553986] rina-ipcp-utils: Name at ffff88003c2889d8 finalized successfully [ 248.554000] rina-ipcp-utils: Name at ffff88003c2889d8 destroyed successfully [ 248.554055] rina-ipcp-utils: Name at ffff88001a28f248 finalized successfully [ 248.554074] rina-ipcp-utils: Name at ffff88001a28f248 destroyed successfully [ 248.554111] rina-ipcp-utils: Name at ffff88001a28e2d0 finalized successfully [ 248.554121] rina-ipcp-utils: Name at ffff88001a28e2d0 destroyed successfully [ 248.554204] rina-rnl-utils: Result of genlmesg_end: 32 [ 248.554207] rina-rnl-utils: Going to send NL unicast message (type = 21, seq-num 1385377112, port = 1) [ 248.554263] rina-rnl-utils: Unicast NL message sent (type = 21, seq-num 1385377112, port = 1) [ 248.554265] rina-rnl: Message 20 handled successfully

kewinrausch commented 9 years ago

Comment by lbergesio Monday Nov 25, 2013 at 17:24 GMT


Could you attach your .config file? I am not able to reproduce the error

kewinrausch commented 9 years ago

Comment by salvestrini Monday Nov 25, 2013 at 17:28 GMT


Here we go, .config should be (hopefully) attached now.

On Mon, Nov 25, 2013 at 6:24 PM, Leonardo Bergesio <notifications@github.com

wrote:

Could you attach your .config file? I am not able to reproduce the error

— Reply to this email directly or view it on GitHubhttps://github.com/dana-i2cat/irati/issues/207#issuecomment-29221621 .

kewinrausch commented 9 years ago

Comment by lbergesio Tuesday Nov 26, 2013 at 09:42 GMT


no file there... let's use email or skype for this