1 [ 0.000000] Linux version 5.3.0-10-generic (buildd@lgw01-amd64-010) (gcc version 9.2.1 20190905 (Ubuntu 9.2.1-7ubuntu2)) #11-Ubuntu SMP Mon Sep 9 15:12:17 UTC 2019 (Ubuntu 5.3.0-10.11-generic 5.3.0-rc8)
2 [ 0.000000] Command line: BOOT_IMAGE=/boot/vmlinuz-5.3.0-10-generic root=UUID=d3aed8bc-565a-4d63-ad4e-0a21ca5ae9e2 ro
3 [ 0.000000] KERNEL supported cpus:
4 [ 0.000000] Intel GenuineIntel
5 [ 0.000000] AMD AuthenticAMD
6 [ 0.000000] Hygon HygonGenuine
7 [ 0.000000] Centaur CentaurHauls
8 [ 0.000000] zhaoxin Shanghai
9 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'
10 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'
11 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'
12 [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256
13 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format.
14 [ 0.000000] BIOS-provided physical RAM map:
15 [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable
16 [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved
17 [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved
18 [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffeffff] usable
19 [ 0.000000] BIOS-e820: [mem 0x000000003fff0000-0x000000003fffffff] ACPI data
20 [ 0.000000] BIOS-e820: [mem 0x00000000fec00000-0x00000000fec00fff] reserved
21 [ 0.000000] BIOS-e820: [mem 0x00000000fee00000-0x00000000fee00fff] reserved
22 [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
23 [ 0.000000] NX (Execute Disable) protection: active
24 [ 0.000000] SMBIOS 2.5 present.
25 [ 0.000000] DMI: innotek GmbH VirtualBox/VirtualBox, BIOS VirtualBox 12/01/2006
26 [ 0.000000] Hypervisor detected: KVM
27 [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
28 [ 0.000001] kvm-clock: cpu 0, msr 7a01001, primary cpu clock
29 [ 0.000001] kvm-clock: using sched offset of 5934469299983 cycles
30 [ 0.000004] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
31 [ 0.000008] tsc: Detected 2893.430 MHz processor
32 [ 0.002783] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
33 [ 0.002785] e820: remove [mem 0x000a0000-0x000fffff] usable
34 [ 0.002788] last_pfn = 0x3fff0 max_arch_pfn = 0x400000000
35 [ 0.002799] MTRR default type: uncachable
36 [ 0.002800] MTRR variable ranges disabled:
38 [ 0.002803] x86/PAT: MTRRs disabled, skipping PAT initialization too.
39 [ 0.002805] CPU MTRRs all blank - virtualized system.
40 [ 0.002808] x86/PAT: Configuration [0-7]: WB WT UC- UC WB WT UC- UC
41 [ 0.165011] check: Scanning 1 areas for low memory corruption
42 [ 0.165054] BRK [0x07c01000, 0x07c01fff] PGTABLE
43 [ 0.165057] BRK [0x07c02000, 0x07c02fff] PGTABLE
44 [ 0.165058] BRK [0x07c03000, 0x07c03fff] PGTABLE
45 [ 0.165086] BRK [0x07c04000, 0x07c04fff] PGTABLE
46 [ 0.165153] BRK [0x07c05000, 0x07c05fff] PGTABLE
47 [ 0.165190] RAMDISK: [mem 0x333fb000-0x359f4fff]
48 [ 0.165211] ACPI: Early table checksum verification disabled
49 [ 0.165217] ACPI: RSDP 0x00000000000E0000 000024 (v02 VBOX )
50 [ 0.165221] ACPI: XSDT 0x000000003FFF0030 00003C (v01 VBOX VBOXXSDT 00000001 ASL 00000061)
51 [ 0.165228] ACPI: FACP 0x000000003FFF00F0 0000F4 (v04 VBOX VBOXFACP 00000001 ASL 00000061)
52 [ 0.165235] ACPI: DSDT 0x000000003FFF0470 0022EA (v02 VBOX VBOXBIOS 00000002 INTL 20180105)
53 [ 0.165238] ACPI: FACS 0x000000003FFF0200 000040
54 [ 0.165253] ACPI: FACS 0x000000003FFF0200 000040
55 [ 0.165269] ACPI: APIC 0x000000003FFF0240 00005C (v02 VBOX VBOXAPIC 00000001 ASL 00000061)
56 [ 0.165273] ACPI: SSDT 0x000000003FFF02A0 0001CC (v01 VBOX VBOXCPUT 00000002 INTL 20180105)
57 [ 0.165283] ACPI: Local APIC address 0xfee00000
58 [ 0.165547] No NUMA configuration found
59 [ 0.165549] Faking a node at [mem 0x0000000000000000-0x000000003ffeffff]
60 [ 0.165556] NODE_DATA(0) allocated [mem 0x3ffc5000-0x3ffeffff]
61 [ 0.165766] Zone ranges:
62 [ 0.165768] DMA [mem 0x0000000000001000-0x0000000000ffffff]
63 [ 0.165769] DMA32 [mem 0x0000000001000000-0x000000003ffeffff]
64 [ 0.165770] Normal empty
65 [ 0.165770] Device empty
66 [ 0.165771] Movable zone start for each node
67 [ 0.165774] Early memory node ranges
68 [ 0.165774] node 0: [mem 0x0000000000001000-0x000000000009efff]
69 [ 0.165775] node 0: [mem 0x0000000000100000-0x000000003ffeffff]
70 [ 0.165777] Zeroed struct page in unavailable ranges: 98 pages
71 [ 0.165778] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffeffff]
72 [ 0.165780] On node 0 totalpages: 262030
73 [ 0.165781] DMA zone: 64 pages used for memmap
74 [ 0.165782] DMA zone: 21 pages reserved
75 [ 0.165782] DMA zone: 3998 pages, LIFO batch:0
76 [ 0.165823] DMA32 zone: 4032 pages used for memmap
77 [ 0.165823] DMA32 zone: 258032 pages, LIFO batch:63
78 [ 0.168687] ACPI: PM-Timer IO Port: 0x4008
79 [ 0.168692] ACPI: Local APIC address 0xfee00000
80 [ 0.168755] IOAPIC[0]: apic_id 2, version 32, address 0xfec00000, GSI 0-23
81 [ 0.168759] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
82 [ 0.168761] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 low level)
83 [ 0.168763] ACPI: IRQ0 used by override.
84 [ 0.168764] ACPI: IRQ9 used by override.
85 [ 0.168766] Using ACPI (MADT) for SMP configuration information
86 [ 0.168774] smpboot: Allowing 2 CPUs, 0 hotplug CPUs
87 [ 0.168795] PM: Registered nosave memory: [mem 0x00000000-0x00000fff]
88 [ 0.168796] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff]
89 [ 0.168797] PM: Registered nosave memory: [mem 0x000a0000-0x000effff]
90 [ 0.168798] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff]
91 [ 0.168800] [mem 0x40000000-0xfebfffff] available for PCI devices
92 [ 0.168801] Booting paravirtualized kernel on KVM
93 [ 0.168804] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645519600211568 ns
94 [ 0.168809] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1
95 [ 0.168955] percpu: Embedded 55 pages/cpu s188416 r8192 d28672 u1048576
96 [ 0.168960] pcpu-alloc: s188416 r8192 d28672 u1048576 alloc=1*2097152
97 [ 0.168961] pcpu-alloc: [0] 0 1
98 [ 0.168981] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear)
99 [ 0.168987] Built 1 zonelists, mobility grouping on. Total pages: 257913
100 [ 0.168988] Policy zone: DMA32
101 [ 0.168989] Kernel command line: BOOT_IMAGE=/boot/vmlinuz-5.3.0-10-generic root=UUID=d3aed8bc-565a-4d63-ad4e-0a21ca5ae9e2 ro
102 [ 0.169072] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
103 [ 0.169100] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)
104 [ 0.169152] mem auto-init: stack:off, heap alloc:on, heap free:off
105 [ 0.169155] Calgary: detecting Calgary via BIOS EBDA area
106 [ 0.169156] Calgary: Unable to locate Rio Grande table in EBDA - bailing!
107 [ 0.171832] Memory: 957480K/1048120K available (14339K kernel code, 2388K rwdata, 4728K rodata, 2668K init, 5056K bss, 90640K reserved, 0K cma-reserved)
108 [ 0.171948] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
109 [ 0.171961] Kernel/User page tables isolation: enabled
110 [ 0.171988] ftrace: allocating 43177 entries in 169 pages
111 [ 0.185652] rcu: Hierarchical RCU implementation.
112 [ 0.185656] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=2.
113 [ 0.185657] Tasks RCU enabled.
114 [ 0.185658] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.
115 [ 0.185659] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2
116 [ 0.188600] NR_IRQS: 524544, nr_irqs: 440, preallocated irqs: 16
117 [ 0.188995] random: crng done (trusting CPU's manufacturer)
118 [ 0.197148] Console: colour VGA+ 80x25
119 [ 0.220930] printk: console [tty0] enabled
120 [ 0.221148] ACPI: Core revision 20190703
121 [ 0.221495] APIC: Switch to symmetric I/O mode setup
122 [ 0.221922] x2apic enabled
123 [ 0.222274] Switched APIC routing to physical x2apic.
124 [ 0.223554] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
125 [ 0.223870] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x29b5049ca8c, max_idle_ns: 440795222133 ns
126 [ 0.224347] Calibrating delay loop (skipped) preset value.. 5786.86 BogoMIPS (lpj=11573720)
127 [ 0.224684] pid_max: default: 32768 minimum: 301
128 [ 0.224910] LSM: Security Framework initializing
129 [ 0.225121] Yama: becoming mindful.
130 [ 0.225365] AppArmor: AppArmor initialized
131 [ 0.225598] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
132 [ 0.225959] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
133 [ 0.226412] *** VALIDATE proc ***
134 [ 0.226627] *** VALIDATE cgroup1 ***
135 [ 0.226803] *** VALIDATE cgroup2 ***
136 [ 0.227088] Last level iTLB entries: 4KB 512, 2MB 8, 4MB 8
137 [ 0.227310] Last level dTLB entries: 4KB 512, 2MB 32, 4MB 32, 1GB 0
138 [ 0.227551] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization
139 [ 0.227893] Spectre V2 : Mitigation: Full generic retpoline
140 [ 0.228114] Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch
141 [ 0.228343] Speculative Store Bypass: Vulnerable
142 [ 0.228343] MDS: Mitigation: Clear CPU buffers
143 [ 0.228343] Freeing SMP alternatives memory: 36K
144 [ 0.358031] smpboot: CPU0: Intel(R) Core(TM) i7-3520M CPU @ 2.90GHz (family: 0x6, model: 0x3a, stepping: 0x9)
145 [ 0.358584] Performance Events: unsupported p6 CPU model 58 no PMU driver, software events only.
146 [ 0.358965] rcu: Hierarchical SRCU implementation.
147 [ 0.359687] NMI watchdog: Perf NMI watchdog permanently disabled
148 [ 0.359979] smp: Bringing up secondary CPUs ...
149 [ 0.360278] x86: Booting SMP configuration:
150 [ 0.360343] .... node #0, CPUs: #1
151 [ 0.039389] kvm-clock: cpu 1, msr 7a01041, secondary cpu clock
152 [ 0.360368] smp: Brought up 1 node, 2 CPUs
153 [ 0.360956] smpboot: Max logical packages: 1
154 [ 0.361215] smpboot: Total of 2 processors activated (11573.72 BogoMIPS)
155 [ 0.361582] devtmpfs: initialized
156 [ 0.361582] x86/mm: Memory block size: 128MB
157 [ 0.361582] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
158 [ 0.361582] futex hash table entries: 512 (order: 3, 32768 bytes, linear)
159 [ 0.364404] pinctrl core: initialized pinctrl subsystem
160 [ 0.364642] PM: RTC time: 13:55:51, date: 2019-09-25
161 [ 0.364642] NET: Registered protocol family 16
162 [ 0.364914] audit: initializing netlink subsys (disabled)
163 [ 0.365309] EISA bus registered
164 [ 0.365309] audit: type=2000 audit(1569455865.581:1): state=initialized audit_enabled=0 res=1
165 [ 0.365525] cpuidle: using governor ladder
166 [ 0.365855] cpuidle: using governor menu
167 [ 0.366219] ACPI: bus type PCI registered
168 [ 0.366537] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
169 [ 0.367121] PCI: Using configuration type 1 for base access
170 [ 0.372438] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
171 [ 0.384468] ACPI: Added _OSI(Module Device)
172 [ 0.384688] ACPI: Added _OSI(Processor Device)
173 [ 0.384893] ACPI: Added _OSI(3.0 _SCP Extensions)
174 [ 0.385104] ACPI: Added _OSI(Processor Aggregator Device)
175 [ 0.385332] ACPI: Added _OSI(Linux-Dell-Video)
176 [ 0.385569] ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio)
177 [ 0.385798] ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics)
178 [ 0.388349] ACPI: 2 ACPI AML tables successfully acquired and loaded
179 [ 0.389807] ACPI: Interpreter enabled
180 [ 0.390035] ACPI: (supports S0 S5)
181 [ 0.390239] ACPI: Using IOAPIC for interrupt routing
182 [ 0.390752] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
183 [ 0.391322] ACPI: Enabled 2 GPEs in block 00 to 07
184 [ 0.397286] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
185 [ 0.397595] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3]
186 [ 0.398660] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI]
187 [ 0.399116] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge.
188 [ 0.400585] PCI host bridge to bus 0000:00
189 [ 0.400817] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]
190 [ 0.401111] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]
191 [ 0.401401] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
192 [ 0.401761] pci_bus 0000:00: root bus resource [mem 0x40000000-0xfdffffff window]
193 [ 0.402848] pci_bus 0000:00: root bus resource [bus 00-ff]
194 [ 0.403158] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000
195 [ 0.405217] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100
196 [ 0.406716] pci 0000:00:01.1: [8086:7111] type 00 class 0x01018a
197 [ 0.407760] pci 0000:00:01.1: reg 0x20: [io 0xd000-0xd00f]
198 [ 0.408343] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7]
199 [ 0.408352] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6]
200 [ 0.408803] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177]
201 [ 0.409257] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376]
202 [ 0.410537] pci 0000:00:02.0: [80ee:beef] type 00 class 0x030000
203 [ 0.413112] pci 0000:00:02.0: reg 0x10: [mem 0xe0000000-0xe0ffffff pref]
204 [ 0.421580] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000
205 [ 0.422764] pci 0000:00:03.0: reg 0x10: [mem 0xf0000000-0xf001ffff]
206 [ 0.424473] pci 0000:00:03.0: reg 0x18: [io 0xd010-0xd017]
207 [ 0.428888] pci 0000:00:04.0: [80ee:cafe] type 00 class 0x088000
208 [ 0.430040] pci 0000:00:04.0: reg 0x10: [io 0xd020-0xd03f]
209 [ 0.431091] pci 0000:00:04.0: reg 0x14: [mem 0xf0400000-0xf07fffff]
210 [ 0.432562] pci 0000:00:04.0: reg 0x18: [mem 0xf0800000-0xf0803fff pref]
211 [ 0.436811] pci 0000:00:05.0: [8086:2415] type 00 class 0x040100
212 [ 0.437396] pci 0000:00:05.0: reg 0x10: [io 0xd100-0xd1ff]
213 [ 0.437771] pci 0000:00:05.0: reg 0x14: [io 0xd200-0xd23f]
214 [ 0.439106] pci 0000:00:06.0: [106b:003f] type 00 class 0x0c0310
215 [ 0.440226] pci 0000:00:06.0: reg 0x10: [mem 0xf0804000-0xf0804fff]
216 [ 0.445100] pci 0000:00:07.0: [8086:7113] type 00 class 0x068000
217 [ 0.445943] pci 0000:00:07.0: quirk: [io 0x4000-0x403f] claimed by PIIX4 ACPI
218 [ 0.446344] pci 0000:00:07.0: quirk: [io 0x4100-0x410f] claimed by PIIX4 SMB
219 [ 0.448890] pci 0000:00:0d.0: [8086:2829] type 00 class 0x010601
220 [ 0.450051] pci 0000:00:0d.0: reg 0x10: [io 0xd240-0xd247]
221 [ 0.451144] pci 0000:00:0d.0: reg 0x14: [io 0xd248-0xd24b]
222 [ 0.452550] pci 0000:00:0d.0: reg 0x18: [io 0xd250-0xd257]
223 [ 0.453817] pci 0000:00:0d.0: reg 0x1c: [io 0xd258-0xd25b]
224 [ 0.455142] pci 0000:00:0d.0: reg 0x20: [io 0xd260-0xd26f]
225 [ 0.456394] pci 0000:00:0d.0: reg 0x24: [mem 0xf0806000-0xf0807fff]
226 [ 0.465358] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 9 10 *11)
227 [ 0.465938] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 9 *10 11)
228 [ 0.466293] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 *9 10 11)
229 [ 0.466646] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 9 10 *11)
230 [ 0.467191] SCSI subsystem initialized
231 [ 0.467191] libata version 3.00 loaded.
232 [ 0.467191] pci 0000:00:02.0: vgaarb: setting as boot VGA device
233 [ 0.467191] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
234 [ 0.468356] pci 0000:00:02.0: vgaarb: bridge control possible
235 [ 0.468595] vgaarb: loaded
236 [ 0.468778] ACPI: bus type USB registered
237 [ 0.468993] usbcore: registered new interface driver usbfs
238 [ 0.469234] usbcore: registered new interface driver hub
239 [ 0.469567] usbcore: registered new device driver usb
240 [ 0.469835] pps_core: LinuxPPS API ver. 1 registered
241 [ 0.470058] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
242 [ 0.470437] PTP clock support registered
243 [ 0.470681] EDAC MC: Ver: 3.0.0
244 [ 0.470681] PCI: Using ACPI for IRQ routing
245 [ 0.472355] PCI: pci_cache_line_size set to 64 bytes
246 [ 0.472602] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff]
247 [ 0.472606] e820: reserve RAM buffer [mem 0x3fff0000-0x3fffffff]
248 [ 0.472748] NetLabel: Initializing
249 [ 0.472939] NetLabel: domain hash size = 128
250 [ 0.473183] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO
251 [ 0.473439] NetLabel: unlabeled traffic allowed by default
252 [ 0.473699] clocksource: Switched to clocksource kvm-clock
253 [ 0.485978] VFS: Disk quotas dquot_6.6.0
254 [ 0.486198] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
255 [ 0.486523] *** VALIDATE hugetlbfs ***
256 [ 0.486797] AppArmor: AppArmor Filesystem Enabled
257 [ 0.487031] pnp: PnP ACPI init
258 [ 0.487272] pnp 00:00: Plug and Play ACPI device, IDs PNP0303 (active)
259 [ 0.487341] pnp 00:01: Plug and Play ACPI device, IDs PNP0f03 (active)
260 [ 0.488089] pnp: PnP ACPI: found 2 devices
261 [ 0.494831] thermal_sys: Registered thermal governor 'fair_share'
262 [ 0.494833] thermal_sys: Registered thermal governor 'bang_bang'
263 [ 0.495119] thermal_sys: Registered thermal governor 'step_wise'
264 [ 0.495366] thermal_sys: Registered thermal governor 'user_space'
265 [ 0.495613] thermal_sys: Registered thermal governor 'power_allocator'
266 [ 0.500501] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
267 [ 0.501154] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]
268 [ 0.501434] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]
269 [ 0.501689] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
270 [ 0.501942] pci_bus 0000:00: resource 7 [mem 0x40000000-0xfdffffff window]
271 [ 0.502254] NET: Registered protocol family 2
272 [ 0.502591] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)
273 [ 0.502936] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)
274 [ 0.503271] TCP bind hash table entries: 8192 (order: 5, 131072 bytes, linear)
275 [ 0.503587] TCP: Hash tables configured (established 8192 bind 8192)
276 [ 0.503855] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
277 [ 0.504108] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
278 [ 0.504423] NET: Registered protocol family 1
279 [ 0.504485] NET: Registered protocol family 44
280 [ 0.504485] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
281 [ 0.505129] pci 0000:00:01.0: Activating ISA DMA hang workarounds
282 [ 0.505478] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
283 [ 0.507255] PCI: CLS 0 bytes, default 64
284 [ 0.507513] Trying to unpack rootfs image as initramfs...
285 [ 1.107793] Freeing initrd memory: 38888K
286 [ 1.108097] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x29b5049ca8c, max_idle_ns: 440795222133 ns
287 [ 1.108594] clocksource: Switched to clocksource tsc
288 [ 1.108863] platform rtc_cmos: registered platform RTC device (no PNP device found)
289 [ 1.109204] check: Scanning for low memory corruption every 60 seconds
290 [ 1.111756] Initialise system trusted keyrings
291 [ 1.111979] Key type blacklist registered
292 [ 1.112338] workingset: timestamp_bits=36 max_order=18 bucket_order=0
293 [ 1.114073] zbud: loaded
294 [ 1.114786] squashfs: version 4.0 (2009/01/31) Phillip Lougher
295 [ 1.115376] fuse: init (API version 7.31)
296 [ 1.115674] Platform Keyring initialized
297 [ 1.121280] Key type asymmetric registered
298 [ 1.121562] Asymmetric key parser 'x509' registered
299 [ 1.121798] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 244)
300 [ 1.122260] io scheduler mq-deadline registered
301 [ 1.122671] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4
302 [ 1.123008] intel_idle: Please enable MWAIT in BIOS SETUP
303 [ 1.123147] ACPI: AC Adapter [AC] (on-line)
304 [ 1.123400] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0
305 [ 1.123751] ACPI: Power Button [PWRF]
306 [ 1.124003] input: Sleep Button as /devices/LNXSYSTM:00/LNXSLPBN:00/input/input1
307 [ 1.124345] ACPI: Sleep Button [SLPF]
308 [ 1.124984] Serial: 8250/16550 driver, 32 ports, IRQ sharing enabled
309 [ 1.126173] battery: ACPI: Battery Slot [BAT0] (battery present)
310 [ 1.127261] Linux agpgart interface v0.103
311 [ 1.129176] loop: module loaded
312 [ 1.129522] ata_piix 0000:00:01.1: version 2.13
313 [ 1.130035] scsi host0: ata_piix
314 [ 1.130488] scsi host1: ata_piix
315 [ 1.130717] ata1: PATA max UDMA/33 cmd 0x1f0 ctl 0x3f6 bmdma 0xd000 irq 14
316 [ 1.130991] ata2: PATA max UDMA/33 cmd 0x170 ctl 0x376 bmdma 0xd008 irq 15
317 [ 1.131388] libphy: Fixed MDIO Bus: probed
318 [ 1.131681] tun: Universal TUN/TAP device driver, 1.6
319 [ 1.132068] PPP generic driver version 2.4.2
320 [ 1.132302] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
321 [ 1.132588] ehci-pci: EHCI PCI platform driver
322 [ 1.132797] ehci-platform: EHCI generic platform driver
323 [ 1.133026] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
324 [ 1.133273] ohci-pci: OHCI PCI platform driver
325 [ 1.134192] ohci-pci 0000:00:06.0: OHCI PCI host controller
326 [ 1.134424] ohci-pci 0000:00:06.0: new USB bus registered, assigned bus number 1
327 [ 1.134801] ohci-pci 0000:00:06.0: irq 22, io mem 0xf0804000
328 [ 1.193959] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 5.03
329 [ 1.195061] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
330 [ 1.196011] usb usb1: Product: OHCI PCI host controller
331 [ 1.196648] usb usb1: Manufacturer: Linux 5.3.0-10-generic ohci_hcd
332 [ 1.197042] usb usb1: SerialNumber: 0000:00:06.0
333 [ 1.197042] hub 1-0:1.0: USB hub found
334 [ 1.197042] hub 1-0:1.0: 12 ports detected
335 [ 1.200771] ohci-platform: OHCI generic platform driver
336 [ 1.201539] uhci_hcd: USB Universal Host Controller Interface driver
337 [ 1.201899] i8042: PNP: PS/2 Controller [PNP0303:PS2K,PNP0f03:PS2M] at 0x60,0x64 irq 1,12
338 [ 1.202770] serio: i8042 KBD port at 0x60,0x64 irq 1
339 [ 1.203046] serio: i8042 AUX port at 0x60,0x64 irq 12
340 [ 1.203396] mousedev: PS/2 mouse device common for all mice
341 [ 1.204239] rtc_cmos rtc_cmos: registered as rtc0
342 [ 1.204559] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input2
343 [ 1.205107] rtc_cmos rtc_cmos: alarms up to one day, 114 bytes nvram
344 [ 1.205420] i2c /dev entries driver
345 [ 1.205677] device-mapper: uevent: version 1.0.3
346 [ 1.205979] device-mapper: ioctl: 4.40.0-ioctl (2019-01-18) initialised: dm-devel@redhat.com
347 [ 1.206852] platform eisa.0: Probing EISA bus 0
348 [ 1.207126] platform eisa.0: EISA: Cannot allocate resource for mainboard
349 [ 1.207421] platform eisa.0: Cannot allocate resource for EISA slot 1
350 [ 1.207711] platform eisa.0: Cannot allocate resource for EISA slot 2
351 [ 1.207992] platform eisa.0: Cannot allocate resource for EISA slot 3
352 [ 1.208273] platform eisa.0: Cannot allocate resource for EISA slot 4
353 [ 1.208505] platform eisa.0: Cannot allocate resource for EISA slot 5
354 [ 1.208505] platform eisa.0: Cannot allocate resource for EISA slot 6
355 [ 1.208505] platform eisa.0: Cannot allocate resource for EISA slot 7
356 [ 1.208505] platform eisa.0: Cannot allocate resource for EISA slot 8
357 [ 1.208505] platform eisa.0: EISA: Detected 0 cards
358 [ 1.208505] intel_pstate: CPU model not supported
359 [ 1.210326] ledtrig-cpu: registered to indicate activity on CPUs
360 [ 1.210845] NET: Registered protocol family 10
361 [ 1.226587] Segment Routing with IPv6
362 [ 1.227012] NET: Registered protocol family 17
363 [ 1.227705] Key type dns_resolver registered
364 [ 1.228628] RAS: Correctable Errors collector initialized.
365 [ 1.229231] sched_clock: Marking stable (1193060735, 35389662)->(1236347133, -7896736)
366 [ 1.230577] registered taskstats version 1
367 [ 1.231087] Loading compiled-in X.509 certificates
368 [ 1.234877] Loaded X.509 cert 'Build time autogenerated kernel key: 433921b71fd64227fb99bfe1143482d12b616a67'
369 [ 1.235957] zswap: loaded using pool lzo/zbud
370 [ 1.249610] Key type big_key registered
371 [ 1.257170] Key type encrypted registered
372 [ 1.257687] AppArmor: AppArmor sha1 policy hashing enabled
373 [ 1.258258] ima: No TPM chip found, activating TPM-bypass!
374 [ 1.258802] ima: Allocated hash algorithm: sha1
375 [ 1.259378] No architecture policies found
376 [ 1.259860] evm: Initialising EVM extended attributes:
377 [ 1.260409] evm: security.selinux
378 [ 1.260805] evm: security.SMACK64
379 [ 1.261230] evm: security.SMACK64EXEC
380 [ 1.261610] evm: security.SMACK64TRANSMUTE
381 [ 1.261898] evm: security.SMACK64MMAP
382 [ 1.262172] evm: security.apparmor
383 [ 1.262434] evm: security.ima
384 [ 1.262678] evm: security.capability
385 [ 1.262945] evm: HMAC attrs: 0x1
386 [ 1.263578] PM: Magic number: 3:168:939
387 [ 1.264046] rtc_cmos rtc_cmos: setting system clock to 2019-09-25T13:55:52 UTC (1569419752)
388 [ 1.289094] ata2.00: ATAPI: VBOX CD-ROM, 1.0, max UDMA/133
389 [ 1.291295] scsi 1:0:0:0: CD-ROM VBOX CD-ROM 1.0 PQ: 0 ANSI: 5
390 [ 1.293370] sr 1:0:0:0: [sr0] scsi3-mmc drive: 32x/32x xa/form2 tray
391 [ 1.294046] cdrom: Uniform CD-ROM driver Revision: 3.20
392 [ 1.295126] sr 1:0:0:0: Attached scsi CD-ROM sr0
393 [ 1.295308] sr 1:0:0:0: Attached scsi generic sg0 type 5
394 [ 1.299863] Freeing unused decrypted memory: 2040K
395 [ 1.301557] Freeing unused kernel image memory: 2668K
396 [ 1.311529] Write protecting the kernel read-only data: 22528k
397 [ 1.312889] Freeing unused kernel image memory: 2008K
398 [ 1.313877] Freeing unused kernel image memory: 1416K
399 [ 1.325314] x86/mm: Checked W+X mappings: passed, no W+X pages found.
400 [ 1.325599] x86/mm: Checking user space page tables
401 [ 1.334021] x86/mm: Checked W+X mappings: passed, no W+X pages found.
402 [ 1.334315] Run /init as init process
403 [ 1.426780] piix4_smbus 0000:00:07.0: SMBus Host Controller at 0x4100, revision 0
404 [ 1.429719] e1000: Intel(R) PRO/1000 Network Driver - version 7.3.21-k8-NAPI
405 [ 1.430120] e1000: Copyright (c) 1999-2006 Intel Corporation.
406 [ 1.432879] ahci 0000:00:0d.0: version 3.0
407 [ 1.439399] ACPI: Video Device [GFX0] (multi-head: yes rom: no post: no)
408 [ 1.440587] ahci 0000:00:0d.0: SSS flag set, parallel bus scan disabled
409 [ 1.440977] ahci 0000:00:0d.0: AHCI 0001.0100 32 slots 1 ports 3 Gbps 0x1 impl SATA mode
410 [ 1.441327] ahci 0000:00:0d.0: flags: 64bit ncq stag only ccc
411 [ 1.442221] input: Video Bus as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A03:00/LNXVIDEO:00/input/input5
412 [ 1.448067] scsi host2: ahci
413 [ 1.448336] ata3: SATA max UDMA/133 abar m8192@0xf0806000 port 0xf0806100 irq 21
414 [ 1.606278] usb 1-1: new full-speed USB device number 2 using ohci-pci
415 [ 1.640341] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input4
416 [ 1.767402] ata3: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
417 [ 1.767986] ata3.00: ATA-8: VBOX HARDDISK, 1.0, max UDMA/133
418 [ 1.768304] ata3.00: 14690288 sectors, multi 128: LBA48 NCQ (depth 32)
419 [ 1.768825] ata3.00: ATA Identify Device Log not supported
420 [ 1.769155] ata3.00: Security Log not supported
421 [ 1.769923] ata3.00: ATA Identify Device Log not supported
422 [ 1.770256] ata3.00: Security Log not supported
423 [ 1.770551] ata3.00: configured for UDMA/133
424 [ 1.772874] scsi 2:0:0:0: Direct-Access ATA VBOX HARDDISK 1.0 PQ: 0 ANSI: 5
425 [ 1.773850] sd 2:0:0:0: [sda] 14690288 512-byte logical blocks: (7.52 GB/7.00 GiB)
426 [ 1.774330] sd 2:0:0:0: [sda] Write Protect is off
427 [ 1.774615] sd 2:0:0:0: [sda] Mode Sense: 00 3a 00 00
428 [ 1.774644] sd 2:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
429 [ 1.775873] sd 2:0:0:0: Attached scsi generic sg1 type 0
430 [ 1.776724] sda: sda1
431 [ 1.777275] sd 2:0:0:0: [sda] Attached SCSI disk
432 [ 1.914263] e1000 0000:00:03.0 eth0: (PCI:33MHz:32-bit) 00:00:00:09:01:66
433 [ 1.914580] e1000 0000:00:03.0 eth0: Intel(R) PRO/1000 Network Connection
434 [ 1.916974] e1000 0000:00:03.0 enp0s3: renamed from eth0
435 [ 1.938248] usb 1-1: New USB device found, idVendor=80ee, idProduct=0021, bcdDevice= 1.00
436 [ 1.939051] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=0
437 [ 1.939604] usb 1-1: Product: USB Tablet
438 [ 1.940034] usb 1-1: Manufacturer: VirtualBox
439 [ 1.950664] hidraw: raw HID events driver (C) Jiri Kosina
440 [ 1.962143] usbcore: registered new interface driver usbhid
441 [ 1.962552] usbhid: USB HID core driver
442 [ 1.976438] input: VirtualBox USB Tablet as /devices/pci0000:00/0000:00:06.0/usb1/1-1/1-1:1.0/0003:80EE:0021.0001/input/input6
443 [ 1.978012] hid-generic 0003:80EE:0021.0001: input,hidraw0: USB HID v1.10 Mouse [VirtualBox USB Tablet] on usb-0000:00:06.0-1/input0
444 [ 2.213566] EXT4-fs (sda1): mounted filesystem with ordered data mode. Opts: (null)
445 [ 2.325868] systemd[1]: systemd 237 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid)
446 [ 2.326758] systemd[1]: Detected virtualization oracle.
447 [ 2.327008] systemd[1]: Detected architecture x86-64.
448 [ 2.332889] systemd[1]: Set hostname to <ub1704-166>.
449 [ 2.446573] systemd[1]: Reached target System Time Synchronized.
450 [ 2.447912] systemd[1]: Reached target Swap.
451 [ 2.449278] systemd[1]: Created slice System Slice.
452 [ 2.450232] systemd[1]: Listening on Journal Socket.
453 [ 2.451780] systemd[1]: Starting Uncomplicated firewall...
454 [ 2.452778] systemd[1]: Created slice User and Session Slice.
455 [ 2.513947] EXT4-fs (sda1): re-mounted. Opts: errors=remount-ro
456 [ 2.625276] systemd-journald[289]: Received request to flush runtime journal from PID 1
457 [ 2.674853] systemd-journald[289]: File /var/log/journal/329433dba2114e52af0201a0d688d536/system.journal corrupted or uncleanly shut down, renaming and replacing.
458 [ 2.949327] audit: type=1400 audit(1569419754.183:2): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/bin/man" pid=403 comm="apparmor_parser"
459 [ 2.949331] audit: type=1400 audit(1569419754.183:3): apparmor="STATUS" operation="profile_load" profile="unconfined" name="man_filter" pid=403 comm="apparmor_parser"
460 [ 2.949333] audit: type=1400 audit(1569419754.183:4): apparmor="STATUS" operation="profile_load" profile="unconfined" name="man_groff" pid=403 comm="apparmor_parser"
461 [ 2.953231] audit: type=1400 audit(1569419754.183:5): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/sbin/dhclient" pid=402 comm="apparmor_parser"
462 [ 2.953235] audit: type=1400 audit(1569419754.183:6): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/lib/NetworkManager/nm-dhcp-client.action" pid=402 comm="apparmor_parser"
463 [ 2.953237] audit: type=1400 audit(1569419754.183:7): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/lib/NetworkManager/nm-dhcp-helper" pid=402 comm="apparmor_parser"
464 [ 2.953239] audit: type=1400 audit(1569419754.183:8): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/lib/connman/scripts/dhclient-script" pid=402 comm="apparmor_parser"
465 [ 2.960067] vboxguest: loading out-of-tree module taints kernel.
466 [ 2.961131] audit: type=1400 audit(1569419754.195:9): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/sbin/tcpdump" pid=405 comm="apparmor_parser"
467 [ 3.033609] vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
468 [ 3.033788] input: Unspecified device as /devices/pci0000:00/0000:00:04.0/input/input7
469 [ 3.042889] vboxguest: misc device minor 54, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
470 [ 3.042891] vboxguest: Successfully loaded version 6.0.12_Ubuntu (interface 0x00010004)
471 [ 3.336318] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer
472 [ 3.538137] cryptd: max_cpu_qlen set to 1000
473 [ 3.545020] [drm] VRAM 01000000
474 [ 3.562910] AVX version of gcm_enc/dec engaged.
475 [ 3.562911] AES CTR mode by8 optimization enabled
476 [ 3.566935] [TTM] Zone kernel: Available graphics memory: 502268 KiB
477 [ 3.566936] [TTM] Initializing pool allocator
478 [ 3.566941] [TTM] Initializing DMA pool allocator
479 [ 3.609533] snd_intel8x0 0000:00:05.0: white list rate for 1028:0177 is 48000
480 [ 3.642222] fbcon: vboxvideodrmfb (fb0) is primary device
481 [ 3.646965] Console: switching to colour frame buffer device 100x37
482 [ 3.648323] vboxvideo 0000:00:02.0: fb0: vboxvideodrmfb frame buffer device
483 [ 3.658022] [drm] Initialized vboxvideo 1.0.0 20130823 for 0000:00:02.0 on minor 0
484 [ 3.829024] vboxsf: g_fHostFeatures=0x8000000f g_fSfFeatures=0x1 g_uSfLastFunction=29
485 [ 3.829121] vboxsf: Successfully loaded version 6.0.12_Ubuntu
486 [ 3.829152] vboxsf: Successfully loaded version 6.0.12_Ubuntu on 5.3.0-10-generic SMP mod_unload (LINUX_VERSION_CODE=0x50300)
487 [ 3.912886] VBoxService 5.2.8 r121009 (verbosity: 0) linux.amd64 (Feb 26 2018 15:59:51) release log
488 00:00:00.000162 main Log opened 2019-09-25T13:55:55.148692000Z
489 [ 3.912974] 00:00:00.000386 main OS Product: Linux
490 [ 3.913002] 00:00:00.000423 main OS Release: 5.3.0-10-generic
491 [ 3.913026] 00:00:00.000449 main OS Version: #11-Ubuntu SMP Mon Sep 9 15:12:17 UTC 2019
492 [ 3.913056] 00:00:00.000472 main Executable: /opt/VBoxGuestAdditions-5.2.8/sbin/VBoxService
493 00:00:00.000473 main Process ID: 674
494 00:00:00.000474 main Package type: LINUX_64BITS_GENERIC
495 [ 3.914553] 00:00:00.001937 main 5.2.8 r121009 started. Verbose level = 0
496 [ 3.932833] 00:00:00.020195 automount vbsvcAutoMountWorker: Shared folder 'kernel' was mounted to '/media/sf_kernel'
497 [ 3.934015] 00:00:00.021326 automount vbsvcAutoMountWorker: Shared folder 'linux' was mounted to '/media/sf_linux'
498 [ 3.945201] 00:00:00.032553 automount vbsvcAutoMountWorker: Shared folder 'rdma' was mounted to '/media/sf_rdma'
499 [ 3.946335] 00:00:00.033656 automount vbsvcAutoMountWorker: Shared folder 'smbdirect' was mounted to '/media/sf_smbdirect'
500 [ 5.668302] e1000: enp0s3 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX
501 [ 5.669422] IPv6: ADDRCONF(NETDEV_CHANGE): enp0s3: link becomes ready
502 [ 13.920834] 00:00:10.008133 timesync vgsvcTimeSyncWorker: Radical guest time change: 30 189 366 370 000ns (GuestNow=1 569 449 944 521 453 000 ns GuestLast=1 569 419 755 155 083 000 ns fSetTimeLastLoop=true )
503 [ 105.995136] SoftiWARP attached
504 [ 109.326480] SoftiWARP detached
505 [ 115.493527] siw: module verification failed: signature and/or required key missing - tainting kernel
506 [ 115.494513] SoftiWARP attached
507 [ 129.658652] net lo siw_lo: siw_device_register: HWaddr=00:00:00:00:00:00
508 [ 129.663482] e1000 0000:00:03.0 siw_enp0s3: siw_device_register: HWaddr=00:00:00:09:01:66
509 [ 154.715870] /root/smbdirect/smbdirect_socket.c:2921:smbdirect_socket_create_wrapper(): before rcu_read_lock(00000000f0531aa9) pid=0x5b3
510 [ 154.715872] /root/smbdirect/smbdirect_socket.c:2921:smbdirect_socket_create_wrapper(): after rcu_read_lock(00000000f0531aa9) pid=0x5b3
511 [ 154.715874] /root/smbdirect/smbdirect_socket.c:2974:smbdirect_socket_create_wrapper(): before rcu_read_unlock(00000000f0531aa9) pid=0x5b3
512 [ 154.715875] /root/smbdirect/smbdirect_socket.c:2974:smbdirect_socket_create_wrapper(): after rcu_read_unlock(00000000f0531aa9) pid=0x5b3
513 [ 154.715880] /root/smbdirect/smbdirect_socket.c:51:smbdirect_socket_init_internal(): before mutex_lock(00000000468d6ed9) pid=0x5b3
514 [ 154.715881] /root/smbdirect/smbdirect_socket.c:51:smbdirect_socket_init_internal(): after mutex_lock(00000000468d6ed9) pid=0x5b3
515 [ 154.715882] /root/smbdirect/smbdirect_socket.c:55:smbdirect_socket_init_internal(): before unmutex_lock(00000000468d6ed9) pid=0x5b3
516 [ 154.715884] /root/smbdirect/smbdirect_socket.c:55:smbdirect_socket_init_internal(): after unmutex_lock(00000000468d6ed9) pid=0x5b3
517 [ 154.715885] /root/smbdirect/smbdirect_socket.c:58:smbdirect_socket_init_internal(): INITIALIZED[00000000abda1b1f]
518 [ 154.715886] /root/smbdirect/smbdirect_socket.c:153:smbdirect_proto_init(): CONSTRUCTED[00000000abda1b1f]
519 [ 154.715887] /root/smbdirect/smbdirect_socket.c:2794:smbdirect_family_create(): here
520 [ 154.717344] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): before lock_sock(00000000abda1b1f) pid=0x5b3
521 [ 154.717346] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): after lock_sock(00000000abda1b1f) pid=0x5b3
522 [ 154.717348] /root/smbdirect/smbdirect_connect.c:774:smbdirect_connect(): here
523 [ 154.717350] /root/smbdirect/smbdirect_connect.c:66:smbdirect_connect_setup_connection(): src: (einval) dst: 10.31.9.167:5445
524 [ 154.717352] /root/smbdirect/smbdirect_connect.c:73:smbdirect_connect_setup_connection(): here
525 [ 154.717356] /root/smbdirect/smbdirect_connect.c:78:smbdirect_connect_setup_connection(): here
526 [ 154.717357] /root/smbdirect/smbdirect_connect.c:91:smbdirect_connect_setup_connection(): OK
527 [ 154.717358] /root/smbdirect/smbdirect_connect.c:776:smbdirect_connect(): here
528 [ 154.717359] /root/smbdirect/smbdirect_connect.c:783:smbdirect_connect(): here
529 [ 154.717367] /root/smbdirect/smbdirect_connect.c:105:smbdirect_connect_resolve_addr(): here
530 [ 154.717368] /root/smbdirect/smbdirect_connect.c:107:smbdirect_connect_resolve_addr(): here
531 [ 154.717389] /root/smbdirect/smbdirect_connect.c:111:smbdirect_connect_resolve_addr(): here
532 [ 154.717390] /root/smbdirect/smbdirect_connect.c:117:smbdirect_connect_resolve_addr(): OK
533 [ 154.717390] /root/smbdirect/smbdirect_connect.c:785:smbdirect_connect(): here
534 [ 154.717391] /root/smbdirect/smbdirect_connect.c:792:smbdirect_connect(): here
535 [ 154.717392] /root/smbdirect/smbdirect_socket.c:408:smbdirect_proto_ops_connect(): STILL CONNECTING[00000000abda1b1f]
536 [ 154.717394] /root/smbdirect/smbdirect_socket.c:409:smbdirect_proto_ops_connect(): before release_sock(00000000abda1b1f) pid=0x5b3
537 [ 154.717395] /root/smbdirect/smbdirect_socket.c:409:smbdirect_proto_ops_connect(): after release_sock(00000000abda1b1f) pid=0x5b3
538 [ 154.717448] /root/smbdirect/smbdirect_connect.c:189:smbdirect_connect_rdma_event_handler(): here
539 [ 154.717458] /root/smbdirect/smbdirect_connect.c:195:smbdirect_connect_rdma_event_handler(): here
540 [ 154.717460] /root/smbdirect/smbdirect_connect.c:196:smbdirect_connect_rdma_event_handler(): GOT[address resolved] EXP[address resolved]
541 [ 154.717460] /root/smbdirect/smbdirect_connect.c:199:smbdirect_connect_rdma_event_handler(): here
542 [ 154.717463] /root/smbdirect/smbdirect_connect.c:200:smbdirect_connect_rdma_event_handler(): before spin_lock_irqsave(00000000abda1b1f) pid=0x8
543 [ 154.717464] /root/smbdirect/smbdirect_connect.c:200:smbdirect_connect_rdma_event_handler(): after spin_lock_irqsave(00000000abda1b1f) pid=0x8
544 [ 154.717465] /root/smbdirect/smbdirect_connect.c:202:smbdirect_connect_rdma_event_handler(): GOT[address resolved] EXP[address resolved]
545 [ 154.717466] /root/smbdirect/smbdirect_connect.c:213:smbdirect_connect_rdma_event_handler(): here
546 [ 154.717467] /root/smbdirect/smbdirect_connect.c:222:smbdirect_connect_rdma_event_handler(): here
547 [ 154.717468] /root/smbdirect/smbdirect_connect.c:227:smbdirect_connect_rdma_event_handler(): SCHEDULE smbdirect_connect_rdma_event_continue[00000000abda1b1f]
548 [ 154.717473] /root/smbdirect/smbdirect_connect.c:231:smbdirect_connect_rdma_event_handler(): here
549 [ 154.717474] /root/smbdirect/smbdirect_connect.c:232:smbdirect_connect_rdma_event_handler(): before spin_unlock_irqrestore(00000000abda1b1f) pid=0x8
550 [ 154.717476] /root/smbdirect/smbdirect_connect.c:232:smbdirect_connect_rdma_event_handler(): after spin_unlock_irqrestore(00000000abda1b1f) pid=0x8
551 [ 154.717476] /root/smbdirect/smbdirect_connect.c:233:smbdirect_connect_rdma_event_handler(): here
552 [ 154.717479] /root/smbdirect/smbdirect_connect.c:249:smbdirect_connect_rdma_event_continue(): here
553 [ 154.717480] /root/smbdirect/smbdirect_connect.c:255:smbdirect_connect_rdma_event_continue(): CONTINUE[address resolved]
554 [ 154.717482] /root/smbdirect/smbdirect_connect.c:258:smbdirect_connect_rdma_event_continue(): before lock_sock(00000000abda1b1f) pid=0x8
555 [ 154.717483] /root/smbdirect/smbdirect_connect.c:258:smbdirect_connect_rdma_event_continue(): after lock_sock(00000000abda1b1f) pid=0x8
556 [ 154.717484] /root/smbdirect/smbdirect_connect.c:260:smbdirect_connect_rdma_event_continue(): here
557 [ 154.717485] /root/smbdirect/smbdirect_connect.c:263:smbdirect_connect_rdma_event_continue(): here
558 [ 154.717486] /root/smbdirect/smbdirect_connect.c:126:smbdirect_connect_resolve_route(): here
559 [ 154.717488] /root/smbdirect/smbdirect_connect.c:129:smbdirect_connect_resolve_route(): here
560 [ 154.717489] /root/smbdirect/smbdirect_connect.c:135:smbdirect_connect_resolve_route(): OK
561 [ 154.717489] /root/smbdirect/smbdirect_connect.c:271:smbdirect_connect_rdma_event_continue(): here
562 [ 154.717491] /root/smbdirect/smbdirect_connect.c:272:smbdirect_connect_rdma_event_continue(): before release_sock(00000000abda1b1f) pid=0x8
563 [ 154.717492] /root/smbdirect/smbdirect_connect.c:272:smbdirect_connect_rdma_event_continue(): after release_sock(00000000abda1b1f) pid=0x8
564 [ 154.717493] /root/smbdirect/smbdirect_connect.c:189:smbdirect_connect_rdma_event_handler(): here
565 [ 154.717494] /root/smbdirect/smbdirect_connect.c:195:smbdirect_connect_rdma_event_handler(): here
566 [ 154.717495] /root/smbdirect/smbdirect_connect.c:196:smbdirect_connect_rdma_event_handler(): GOT[route resolved ] EXP[route resolved ]
567 [ 154.717496] /root/smbdirect/smbdirect_connect.c:199:smbdirect_connect_rdma_event_handler(): here
568 [ 154.717497] /root/smbdirect/smbdirect_connect.c:200:smbdirect_connect_rdma_event_handler(): before spin_lock_irqsave(00000000abda1b1f) pid=0x8
569 [ 154.717499] /root/smbdirect/smbdirect_connect.c:200:smbdirect_connect_rdma_event_handler(): after spin_lock_irqsave(00000000abda1b1f) pid=0x8
570 [ 154.717500] /root/smbdirect/smbdirect_connect.c:202:smbdirect_connect_rdma_event_handler(): GOT[route resolved ] EXP[route resolved ]
571 [ 154.717501] /root/smbdirect/smbdirect_connect.c:213:smbdirect_connect_rdma_event_handler(): here
572 [ 154.717501] /root/smbdirect/smbdirect_connect.c:222:smbdirect_connect_rdma_event_handler(): here
573 [ 154.717503] /root/smbdirect/smbdirect_connect.c:227:smbdirect_connect_rdma_event_handler(): SCHEDULE smbdirect_connect_rdma_event_continue[00000000abda1b1f]
574 [ 154.717504] /root/smbdirect/smbdirect_connect.c:231:smbdirect_connect_rdma_event_handler(): here
575 [ 154.717505] /root/smbdirect/smbdirect_connect.c:232:smbdirect_connect_rdma_event_handler(): before spin_unlock_irqrestore(00000000abda1b1f) pid=0x8
576 [ 154.717506] /root/smbdirect/smbdirect_connect.c:232:smbdirect_connect_rdma_event_handler(): after spin_unlock_irqrestore(00000000abda1b1f) pid=0x8
577 [ 154.717507] /root/smbdirect/smbdirect_connect.c:233:smbdirect_connect_rdma_event_handler(): here
578 [ 154.717508] /root/smbdirect/smbdirect_connect.c:249:smbdirect_connect_rdma_event_continue(): here
579 [ 154.717509] /root/smbdirect/smbdirect_connect.c:255:smbdirect_connect_rdma_event_continue(): CONTINUE[route resolved ]
580 [ 154.717511] /root/smbdirect/smbdirect_connect.c:258:smbdirect_connect_rdma_event_continue(): before lock_sock(00000000abda1b1f) pid=0x8
581 [ 154.717512] /root/smbdirect/smbdirect_connect.c:258:smbdirect_connect_rdma_event_continue(): after lock_sock(00000000abda1b1f) pid=0x8
582 [ 154.717513] /root/smbdirect/smbdirect_connect.c:260:smbdirect_connect_rdma_event_continue(): here
583 [ 154.717514] /root/smbdirect/smbdirect_connect.c:276:smbdirect_connect_rdma_event_continue(): here
584 [ 154.717515] /root/smbdirect/smbdirect_connect.c:143:smbdirect_connect_rdma_connect(): here
585 [ 154.717520] e1000 0000:00:03.0 siw_enp0s3: PD[0] siw_alloc_pd: now 1 PD's(s)
586 [ 154.717531] e1000 0000:00:03.0 siw_enp0s3: PD[0] siw_get_dma_mr: [MEM 1824816128]: success
587 [ 154.717544] e1000 0000:00:03.0 siw_enp0s3: siw_create_cq: new CQ [872301973]
588 [ 154.717547] e1000 0000:00:03.0 siw_enp0s3: CQ[872301973] siw_req_notify_cq: flags: 0x02
589 [ 154.717553] e1000 0000:00:03.0 siw_enp0s3: siw_create_cq: new CQ [2752568961]
590 [ 154.717555] e1000 0000:00:03.0 siw_enp0s3: CQ[2752568961] siw_req_notify_cq: flags: 0x02
591 [ 154.717556] e1000 0000:00:03.0 siw_enp0s3: siw_create_qp: create new QP
592 [ 154.717559] e1000 0000:00:03.0 siw_enp0s3: QP[1] siw_qp_add: new QP
593 [ 154.717575] e1000 0000:00:03.0 siw_enp0s3: siw_get_tx_cpu: tx cpu 1, node -1, 0 qp's
594 [ 154.717579] e1000 0000:00:03.0 siw_enp0s3: QP[1] siw_verbs_modify_qp: desired IB QP state: INIT
595 [ 154.717581] e1000 0000:00:03.0 siw_enp0s3: QP[1] siw_qp_modify: state: IDLE => IDLE
596 [ 154.717582] /root/smbdirect/smbdirect_connect.c:145:smbdirect_connect_rdma_connect(): here
597 [ 154.717583] /root/smbdirect/smbdirect_connect.c:151:smbdirect_connect_rdma_connect(): here
598 [ 154.717584] /root/smbdirect/smbdirect_connect.c:167:smbdirect_connect_rdma_connect(): here
599 [ 154.717585] /root/smbdirect/smbdirect_connect.c:169:smbdirect_connect_rdma_connect(): here
600 [ 154.717588] e1000 0000:00:03.0 siw_enp0s3: QP[1] siw_verbs_modify_qp: desired IB QP state: INIT
601 [ 154.717589] e1000 0000:00:03.0 siw_enp0s3: QP[1] siw_qp_modify: state: IDLE => IDLE
602 [ 154.717591] e1000 0000:00:03.0 siw_enp0s3: QP[1] siw_verbs_modify_qp: desired IB QP state: RTR
603 [ 154.717592] e1000 0000:00:03.0 siw_enp0s3: QP[1] siw_qp_modify: state: IDLE => RTR
604 [ 154.717601] iwpm_register_pid: Unable to send a nlmsg (client = 2)
605 [ 154.717604] e1000 0000:00:03.0 siw_enp0s3: SDEV[000000006f8a44b1] siw_connect: 1397: here
606 [ 154.717606] e1000 0000:00:03.0 siw_enp0s3: QP[1] siw_connect: pd_len 8, laddr 172.31.9.166 53547, raddr 10.31.9.167 5445
607 [ 154.717621] e1000 0000:00:03.0 siw_enp0s3: SDEV[000000006f8a44b1] siw_connect: 1443: here
608 [ 154.717630] e1000 0000:00:03.0 siw_enp0s3: SDEV[000000006f8a44b1] siw_connect: 1448: here
609 [ 154.717633] e1000 0000:00:03.0 siw_enp0s3: CEP[0xffff8d2fb96f3800] siw_cep_alloc: new endpoint
610 [ 154.717634] e1000 0000:00:03.0 siw_enp0s3: SDEV[000000006f8a44b1] siw_connect: 1454: here
611 [ 154.717636] e1000 0000:00:03.0 siw_enp0s3: SDEV[000000006f8a44b1] siw_connect: 1456: here
612 [ 154.717637] e1000 0000:00:03.0 siw_enp0s3: SDEV[000000006f8a44b1] siw_connect: 1462: here
613 [ 154.717639] e1000 0000:00:03.0 siw_enp0s3: SDEV[000000006f8a44b1] siw_connect: 1466: here
614 [ 154.717640] e1000 0000:00:03.0 siw_enp0s3: SDEV[000000006f8a44b1] siw_connect: 1470: here
615 [ 154.717641] e1000 0000:00:03.0 siw_enp0s3: SDEV[000000006f8a44b1] siw_connect: 1476: here
616 [ 154.717643] e1000 0000:00:03.0 siw_enp0s3: SDEV[000000006f8a44b1] siw_connect: 1482: here
617 [ 154.717645] e1000 0000:00:03.0 siw_enp0s3: SDEV[000000006f8a44b1] siw_connect: 1489: here
618 [ 154.717646] e1000 0000:00:03.0 siw_enp0s3: SDEV[000000006f8a44b1] siw_connect: 1497: here
619 [ 154.717647] e1000 0000:00:03.0 siw_enp0s3: SDEV[000000006f8a44b1] siw_connect: 1502: here
620 [ 154.717649] e1000 0000:00:03.0 siw_enp0s3: SDEV[000000006f8a44b1] siw_connect: 1511: here
621 [ 154.717650] e1000 0000:00:03.0 siw_enp0s3: SDEV[000000006f8a44b1] siw_connect: 1525: here
622 [ 154.717651] e1000 0000:00:03.0 siw_enp0s3: SDEV[000000006f8a44b1] siw_connect: 1543: here
623 [ 154.717653] e1000 0000:00:03.0 siw_enp0s3: SDEV[000000006f8a44b1] siw_connect: 1551: here
624 [ 154.717656] e1000 0000:00:03.0 siw_enp0s3: CEP[0xffff8d2fb96f3800] siw_cm_queue_work: [QP 1]: work type: 6, timeout 1250
625 [ 154.717658] e1000 0000:00:03.0 siw_enp0s3: SDEV[000000006f8a44b1] siw_connect: 1558: here
626 [ 154.717819] e1000 0000:00:03.0 siw_enp0s3: QP[1] siw_connect: kernel_bindconnect: EINPROGRESS
627 [ 154.717822] e1000 0000:00:03.0 siw_enp0s3: CEP[0xffff8d2fb96f3800] siw_connect: [QP 1]: exit
628 [ 154.717824] /root/smbdirect/smbdirect_connect.c:171:smbdirect_connect_rdma_connect(): here
629 [ 154.717825] /root/smbdirect/smbdirect_connect.c:177:smbdirect_connect_rdma_connect(): OK
630 [ 154.717826] /root/smbdirect/smbdirect_connect.c:284:smbdirect_connect_rdma_event_continue(): here
631 [ 154.717827] /root/smbdirect/smbdirect_connect.c:285:smbdirect_connect_rdma_event_continue(): before release_sock(00000000abda1b1f) pid=0x8
632 [ 154.717828] /root/smbdirect/smbdirect_connect.c:285:smbdirect_connect_rdma_event_continue(): after release_sock(00000000abda1b1f) pid=0x8
633 [ 154.732197] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): before lock_sock(00000000abda1b1f) pid=0x5b3
634 [ 154.732199] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): after lock_sock(00000000abda1b1f) pid=0x5b3
635 [ 154.732200] /root/smbdirect/smbdirect_socket.c:358:smbdirect_proto_ops_connect(): ALREADY CONNECTING[00000000abda1b1f]
636 [ 154.732201] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): before release_sock(00000000abda1b1f) pid=0x5b3
637 [ 154.732203] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): after release_sock(00000000abda1b1f) pid=0x5b3
638 [ 154.749473] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): before lock_sock(00000000abda1b1f) pid=0x5b3
639 [ 154.749477] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): after lock_sock(00000000abda1b1f) pid=0x5b3
640 [ 154.749479] /root/smbdirect/smbdirect_socket.c:358:smbdirect_proto_ops_connect(): ALREADY CONNECTING[00000000abda1b1f]
641 [ 154.749480] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): before release_sock(00000000abda1b1f) pid=0x5b3
642 [ 154.749482] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): after release_sock(00000000abda1b1f) pid=0x5b3
643 [ 154.774722] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): before lock_sock(00000000abda1b1f) pid=0x5b3
644 [ 154.774726] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): after lock_sock(00000000abda1b1f) pid=0x5b3
645 [ 154.774728] /root/smbdirect/smbdirect_socket.c:358:smbdirect_proto_ops_connect(): ALREADY CONNECTING[00000000abda1b1f]
646 [ 154.774729] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): before release_sock(00000000abda1b1f) pid=0x5b3
647 [ 154.774731] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): after release_sock(00000000abda1b1f) pid=0x5b3
648 [ 154.811090] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): before lock_sock(00000000abda1b1f) pid=0x5b3
649 [ 154.811093] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): after lock_sock(00000000abda1b1f) pid=0x5b3
650 [ 154.811094] /root/smbdirect/smbdirect_socket.c:358:smbdirect_proto_ops_connect(): ALREADY CONNECTING[00000000abda1b1f]
651 [ 154.811095] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): before release_sock(00000000abda1b1f) pid=0x5b3
652 [ 154.811096] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): after release_sock(00000000abda1b1f) pid=0x5b3
653 [ 154.864682] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): before lock_sock(00000000abda1b1f) pid=0x5b3
654 [ 154.864686] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): after lock_sock(00000000abda1b1f) pid=0x5b3
655 [ 154.864688] /root/smbdirect/smbdirect_socket.c:358:smbdirect_proto_ops_connect(): ALREADY CONNECTING[00000000abda1b1f]
656 [ 154.864690] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): before release_sock(00000000abda1b1f) pid=0x5b3
657 [ 154.864692] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): after release_sock(00000000abda1b1f) pid=0x5b3
658 [ 154.943322] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): before lock_sock(00000000abda1b1f) pid=0x5b3
659 [ 154.943325] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): after lock_sock(00000000abda1b1f) pid=0x5b3
660 [ 154.943326] /root/smbdirect/smbdirect_socket.c:358:smbdirect_proto_ops_connect(): ALREADY CONNECTING[00000000abda1b1f]
661 [ 154.943327] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): before release_sock(00000000abda1b1f) pid=0x5b3
662 [ 154.943328] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): after release_sock(00000000abda1b1f) pid=0x5b3
663 [ 155.059647] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): before lock_sock(00000000abda1b1f) pid=0x5b3
664 [ 155.059650] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): after lock_sock(00000000abda1b1f) pid=0x5b3
665 [ 155.059652] /root/smbdirect/smbdirect_socket.c:358:smbdirect_proto_ops_connect(): ALREADY CONNECTING[00000000abda1b1f]
666 [ 155.059654] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): before release_sock(00000000abda1b1f) pid=0x5b3
667 [ 155.059656] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): after release_sock(00000000abda1b1f) pid=0x5b3
668 [ 155.232641] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): before lock_sock(00000000abda1b1f) pid=0x5b3
669 [ 155.232643] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): after lock_sock(00000000abda1b1f) pid=0x5b3
670 [ 155.232644] /root/smbdirect/smbdirect_socket.c:358:smbdirect_proto_ops_connect(): ALREADY CONNECTING[00000000abda1b1f]
671 [ 155.232646] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): before release_sock(00000000abda1b1f) pid=0x5b3
672 [ 155.232647] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): after release_sock(00000000abda1b1f) pid=0x5b3
673 [ 155.492556] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): before lock_sock(00000000abda1b1f) pid=0x5b3
674 [ 155.492559] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): after lock_sock(00000000abda1b1f) pid=0x5b3
675 [ 155.492561] /root/smbdirect/smbdirect_socket.c:358:smbdirect_proto_ops_connect(): ALREADY CONNECTING[00000000abda1b1f]
676 [ 155.492562] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): before release_sock(00000000abda1b1f) pid=0x5b3
677 [ 155.492564] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): after release_sock(00000000abda1b1f) pid=0x5b3
678 [ 155.751868] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): before lock_sock(00000000abda1b1f) pid=0x5b3
679 [ 155.751870] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): after lock_sock(00000000abda1b1f) pid=0x5b3
680 [ 155.751871] /root/smbdirect/smbdirect_socket.c:358:smbdirect_proto_ops_connect(): ALREADY CONNECTING[00000000abda1b1f]
681 [ 155.751872] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): before release_sock(00000000abda1b1f) pid=0x5b3
682 [ 155.751873] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): after release_sock(00000000abda1b1f) pid=0x5b3
683 [ 156.013735] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): before lock_sock(00000000abda1b1f) pid=0x5b3
684 [ 156.013739] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): after lock_sock(00000000abda1b1f) pid=0x5b3
685 [ 156.013741] /root/smbdirect/smbdirect_socket.c:358:smbdirect_proto_ops_connect(): ALREADY CONNECTING[00000000abda1b1f]
686 [ 156.013743] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): before release_sock(00000000abda1b1f) pid=0x5b3
687 [ 156.013745] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): after release_sock(00000000abda1b1f) pid=0x5b3
688 [ 156.273087] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): before lock_sock(00000000abda1b1f) pid=0x5b3
689 [ 156.273091] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): after lock_sock(00000000abda1b1f) pid=0x5b3
690 [ 156.273093] /root/smbdirect/smbdirect_socket.c:358:smbdirect_proto_ops_connect(): ALREADY CONNECTING[00000000abda1b1f]
691 [ 156.273094] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): before release_sock(00000000abda1b1f) pid=0x5b3
692 [ 156.273097] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): after release_sock(00000000abda1b1f) pid=0x5b3
693 [ 156.537621] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): before lock_sock(00000000abda1b1f) pid=0x5b3
694 [ 156.537623] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): after lock_sock(00000000abda1b1f) pid=0x5b3
695 [ 156.537624] /root/smbdirect/smbdirect_socket.c:358:smbdirect_proto_ops_connect(): ALREADY CONNECTING[00000000abda1b1f]
696 [ 156.537625] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): before release_sock(00000000abda1b1f) pid=0x5b3
697 [ 156.537626] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): after release_sock(00000000abda1b1f) pid=0x5b3
698 [ 156.797173] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): before lock_sock(00000000abda1b1f) pid=0x5b3
699 [ 156.797176] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): after lock_sock(00000000abda1b1f) pid=0x5b3
700 [ 156.797177] /root/smbdirect/smbdirect_socket.c:358:smbdirect_proto_ops_connect(): ALREADY CONNECTING[00000000abda1b1f]
701 [ 156.797179] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): before release_sock(00000000abda1b1f) pid=0x5b3
702 [ 156.797180] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): after release_sock(00000000abda1b1f) pid=0x5b3
703 [ 157.057404] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): before lock_sock(00000000abda1b1f) pid=0x5b3
704 [ 157.057407] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): after lock_sock(00000000abda1b1f) pid=0x5b3
705 [ 157.057409] /root/smbdirect/smbdirect_socket.c:358:smbdirect_proto_ops_connect(): ALREADY CONNECTING[00000000abda1b1f]
706 [ 157.057410] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): before release_sock(00000000abda1b1f) pid=0x5b3
707 [ 157.057412] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): after release_sock(00000000abda1b1f) pid=0x5b3
708 [ 157.316586] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): before lock_sock(00000000abda1b1f) pid=0x5b3
709 [ 157.316588] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): after lock_sock(00000000abda1b1f) pid=0x5b3
710 [ 157.316589] /root/smbdirect/smbdirect_socket.c:358:smbdirect_proto_ops_connect(): ALREADY CONNECTING[00000000abda1b1f]
711 [ 157.316590] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): before release_sock(00000000abda1b1f) pid=0x5b3
712 [ 157.316591] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): after release_sock(00000000abda1b1f) pid=0x5b3
713 [ 157.576589] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): before lock_sock(00000000abda1b1f) pid=0x5b3
714 [ 157.576592] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): after lock_sock(00000000abda1b1f) pid=0x5b3
715 [ 157.576593] /root/smbdirect/smbdirect_socket.c:358:smbdirect_proto_ops_connect(): ALREADY CONNECTING[00000000abda1b1f]
716 [ 157.576594] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): before release_sock(00000000abda1b1f) pid=0x5b3
717 [ 157.576596] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): after release_sock(00000000abda1b1f) pid=0x5b3
718 [ 157.835885] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): before lock_sock(00000000abda1b1f) pid=0x5b3
719 [ 157.835888] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): after lock_sock(00000000abda1b1f) pid=0x5b3
720 [ 157.835889] /root/smbdirect/smbdirect_socket.c:358:smbdirect_proto_ops_connect(): ALREADY CONNECTING[00000000abda1b1f]
721 [ 157.835891] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): before release_sock(00000000abda1b1f) pid=0x5b3
722 [ 157.835893] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): after release_sock(00000000abda1b1f) pid=0x5b3
723 [ 158.096113] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): before lock_sock(00000000abda1b1f) pid=0x5b3
724 [ 158.096116] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): after lock_sock(00000000abda1b1f) pid=0x5b3
725 [ 158.096117] /root/smbdirect/smbdirect_socket.c:358:smbdirect_proto_ops_connect(): ALREADY CONNECTING[00000000abda1b1f]
726 [ 158.096118] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): before release_sock(00000000abda1b1f) pid=0x5b3
727 [ 158.096119] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): after release_sock(00000000abda1b1f) pid=0x5b3
728 [ 158.355687] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): before lock_sock(00000000abda1b1f) pid=0x5b3
729 [ 158.355689] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): after lock_sock(00000000abda1b1f) pid=0x5b3
730 [ 158.355690] /root/smbdirect/smbdirect_socket.c:358:smbdirect_proto_ops_connect(): ALREADY CONNECTING[00000000abda1b1f]
731 [ 158.355692] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): before release_sock(00000000abda1b1f) pid=0x5b3
732 [ 158.355693] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): after release_sock(00000000abda1b1f) pid=0x5b3
733 [ 158.614314] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): before lock_sock(00000000abda1b1f) pid=0x5b3
734 [ 158.614316] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): after lock_sock(00000000abda1b1f) pid=0x5b3
735 [ 158.614317] /root/smbdirect/smbdirect_socket.c:358:smbdirect_proto_ops_connect(): ALREADY CONNECTING[00000000abda1b1f]
736 [ 158.614318] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): before release_sock(00000000abda1b1f) pid=0x5b3
737 [ 158.614320] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): after release_sock(00000000abda1b1f) pid=0x5b3
738 [ 158.873812] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): before lock_sock(00000000abda1b1f) pid=0x5b3
739 [ 158.873816] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): after lock_sock(00000000abda1b1f) pid=0x5b3
740 [ 158.873817] /root/smbdirect/smbdirect_socket.c:358:smbdirect_proto_ops_connect(): ALREADY CONNECTING[00000000abda1b1f]
741 [ 158.873820] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): before release_sock(00000000abda1b1f) pid=0x5b3
742 [ 158.873822] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): after release_sock(00000000abda1b1f) pid=0x5b3
743 [ 159.136822] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): before lock_sock(00000000abda1b1f) pid=0x5b3
744 [ 159.136825] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): after lock_sock(00000000abda1b1f) pid=0x5b3
745 [ 159.136827] /root/smbdirect/smbdirect_socket.c:358:smbdirect_proto_ops_connect(): ALREADY CONNECTING[00000000abda1b1f]
746 [ 159.136829] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): before release_sock(00000000abda1b1f) pid=0x5b3
747 [ 159.136831] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): after release_sock(00000000abda1b1f) pid=0x5b3
748 [ 159.395851] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): before lock_sock(00000000abda1b1f) pid=0x5b3
749 [ 159.395854] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): after lock_sock(00000000abda1b1f) pid=0x5b3
750 [ 159.395855] /root/smbdirect/smbdirect_socket.c:358:smbdirect_proto_ops_connect(): ALREADY CONNECTING[00000000abda1b1f]
751 [ 159.395856] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): before release_sock(00000000abda1b1f) pid=0x5b3
752 [ 159.395857] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): after release_sock(00000000abda1b1f) pid=0x5b3
753 [ 159.654284] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): before lock_sock(00000000abda1b1f) pid=0x5b3
754 [ 159.654288] /root/smbdirect/smbdirect_socket.c:333:smbdirect_proto_ops_connect(): after lock_sock(00000000abda1b1f) pid=0x5b3
755 [ 159.654290] /root/smbdirect/smbdirect_socket.c:358:smbdirect_proto_ops_connect(): ALREADY CONNECTING[00000000abda1b1f]
756 [ 159.654292] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): before release_sock(00000000abda1b1f) pid=0x5b3
757 [ 159.654293] /root/smbdirect/smbdirect_socket.c:359:smbdirect_proto_ops_connect(): after release_sock(00000000abda1b1f) pid=0x5b3
758 [ 159.717689] /root/smbdirect/smbdirect_socket.c:195:smbdirect_proto_ops_release(): before lock_sock(00000000abda1b1f) pid=0x5b3
759 [ 159.717693] /root/smbdirect/smbdirect_socket.c:195:smbdirect_proto_ops_release(): after lock_sock(00000000abda1b1f) pid=0x5b3
760 [ 159.717695] /root/smbdirect/smbdirect_socket.c:201:smbdirect_proto_ops_release(): before release_sock(00000000abda1b1f) pid=0x5b3
761 [ 159.717696] /root/smbdirect/smbdirect_socket.c:201:smbdirect_proto_ops_release(): after release_sock(00000000abda1b1f) pid=0x5b3
762 [ 159.717698] /root/smbdirect/smbdirect_socket.c:130:smbdirect_sk_destruct(): DESTROYING[00000000abda1b1f]
763 [ 159.717699] /root/smbdirect/smbdirect_socket.c:77:smbdirect_socket_destroy_internal(): before mutex_lock(00000000468d6ed9) pid=0x5b3
764 [ 159.717701] /root/smbdirect/smbdirect_socket.c:77:smbdirect_socket_destroy_internal(): after mutex_lock(00000000468d6ed9) pid=0x5b3
765 [ 159.717702] /root/smbdirect/smbdirect_socket.c:79:smbdirect_socket_destroy_internal(): before unmutex_lock(00000000468d6ed9) pid=0x5b3
766 [ 159.717703] /root/smbdirect/smbdirect_socket.c:79:smbdirect_socket_destroy_internal(): after unmutex_lock(00000000468d6ed9) pid=0x5b3
767 [ 159.937858] e1000 0000:00:03.0 siw_enp0s3: CEP[0xffff8d2fb96f3800] siw_cm_work_handler: [QP 1]: work type: 6, state 3
768 [ 159.937862] e1000 0000:00:03.0 siw_enp0s3: CEP[0xffff8d2fb96f3800] siw_cm_work_handler: 1130: here
769 [ 159.937863] e1000 0000:00:03.0 siw_enp0s3: CEP[0xffff8d2fb96f3800] siw_cm_work_handler: 1156: here
770 [ 159.937864] e1000 0000:00:03.0 siw_enp0s3: CEP[0xffff8d2fb96f3800] siw_cm_work_handler: 1162: here
771 [ 159.937865] e1000 0000:00:03.0 siw_enp0s3: CEP[0xffff8d2fb96f3800] siw_cm_work_handler: 1200: here
772 [ 159.937867] e1000 0000:00:03.0 siw_enp0s3: CEP[0xffff8d2fb96f3800] siw_cm_work_handler: 1203: here
773 [ 285.888973] e1000 0000:00:03.0 siw_enp0s3: CEP[0x pK-error] siw_cm_llp_error_report: error 110, socket state: 2, cep state: 3
774 [ 285.889079] e1000 0000:00:03.0 siw_enp0s3: CEP[0x pK-error] siw_cm_llp_state_change: state: 3
775 [ 285.889100] e1000 0000:00:03.0 siw_enp0s3: CEP[0x pK-error] siw_cm_llp_state_change: state: TCP_CLOSE:
776 [ 285.889114] e1000 0000:00:03.0 siw_enp0s3: CEP[0x pK-error] siw_cm_llp_state_change: state: fall through:
777 [ 285.889128] e1000 0000:00:03.0 siw_enp0s3: CEP[0x pK-error] siw_cm_llp_state_change: state: TCP_CLOSE_WAIT:
778 [ 285.889142] e1000 0000:00:03.0 siw_enp0s3: CEP[0x pK-error] siw_cm_llp_state_change: state: SIW_CM_WORK_PEER_CLOSE:
779 [ 285.889159] e1000 0000:00:03.0 siw_enp0s3: CEP[0x pK-error] siw_cm_queue_work: [QP 1]: work type: 5, timeout 0
780 [ 285.889184] e1000 0000:00:03.0 siw_enp0s3: CEP[0x pK-error] siw_cm_llp_state_change: state: end
781 [ 285.893955] e1000 0000:00:03.0 siw_enp0s3: CEP[0xffff8d2fb96f3800] siw_cm_work_handler: [QP 1]: work type: 5, state 3
782 [ 285.894034] e1000 0000:00:03.0 siw_enp0s3: CEP[0xffff8d2fb96f3800] siw_cm_work_handler: 1089: here
783 [ 285.894039] e1000 0000:00:03.0 siw_enp0s3: CEP[0xffff8d2fb96f3800] siw_cm_work_handler: 1126: here
784 [ 285.894043] e1000 0000:00:03.0 siw_enp0s3: CEP[0xffff8d2fb96f3800] siw_cm_work_handler: 1162: here
785 [ 285.894048] e1000 0000:00:03.0 siw_enp0s3: CEP[0xffff8d2fb96f3800] siw_cm_work_handler: release: timer=n, QP[1]
786 [ 285.894055] e1000 0000:00:03.0 siw_enp0s3: QP[1] siw_qp_llp_close: enter llp close, state = IDLE
787 [ 285.894079] e1000 0000:00:03.0 siw_enp0s3: QP[1] siw_qp_llp_close: llp close exit: state ERROR
788 [ 285.894119] e1000 0000:00:03.0 siw_enp0s3: CEP[0xffff8d2fb96f3800] siw_cm_work_handler: 1200: here
789 [ 285.894125] e1000 0000:00:03.0 siw_enp0s3: CEP[0xffff8d2fb96f3800] siw_cm_work_handler: 1203: here
790 [ 285.894132] e1000 0000:00:03.0 siw_enp0s3: CEP[0xffff8d2fb96f3800] __siw_cep_dealloc: free endpoint
791 [ 363.713366] INFO: task smbclient:1459 blocked for more than 120 seconds.
792 [ 363.713444] Tainted: G OE 5.3.0-10-generic #11-Ubuntu
793 [ 363.713520] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
794 [ 363.713599] smbclient D 0 1459 1457 0x00004003
795 [ 363.713605] Call Trace:
796 [ 363.713622] __schedule+0x2b9/0x6c0
797 [ 363.713631] ? del_timer+0x5a/0x80
798 [ 363.713635] schedule+0x42/0xb0
799 [ 363.713645] iw_cm_disconnect+0xf4/0x130 [iw_cm]
800 [ 363.713649] ? wait_woken+0x80/0x80
801 [ 363.713658] rdma_disconnect+0x45/0x90 [rdma_cm]
802 [ 363.713665] smbdirect_connection_destroy+0x9b/0x510 [smbdirect]
803 [ 363.713672] smbdirect_sk_destruct+0x172/0x1d0 [smbdirect]
804 [ 363.713678] __sk_destruct+0x2b/0x1d0
805 [ 363.713682] sk_destruct+0x2e/0x30
806 [ 363.713685] __sk_free+0x30/0xc0
807 [ 363.713689] sk_free+0x1e/0x20
808 [ 363.713694] smbdirect_proto_ops_release+0x18b/0x1a0 [smbdirect]
809 [ 363.713698] __sock_release+0x42/0xb0
810 [ 363.713702] sock_close+0x15/0x20
811 [ 363.713709] __fput+0xcc/0x260
812 [ 363.713713] ____fput+0xe/0x10
813 [ 363.713718] task_work_run+0x8f/0xb0
814 [ 363.713722] ptrace_notify+0x76/0x80
815 [ 363.713727] syscall_slow_exit_work+0xe3/0x150
816 [ 363.713731] do_syscall_64+0x102/0x130
817 [ 363.713736] entry_SYSCALL_64_after_hwframe+0x44/0xa9
818 [ 363.713740] RIP: 0033:0x7fa72a013421
819 [ 363.713751] Code: Bad RIP value.
820 [ 363.713753] RSP: 002b:00007ffd29f500b8 EFLAGS: 00000246 ORIG_RAX: 0000000000000003
821 [ 363.713757] RAX: 0000000000000000 RBX: 0000000000000000 RCX: 00007fa72a013421
822 [ 363.713758] RDX: 0000561202e61010 RSI: 0000561202e611f0 RDI: 0000000000000009
823 [ 363.713760] RBP: 00007ffd29f500e0 R08: 00007fa727a1eb62 R09: 00007fa727a14da9
824 [ 363.713762] R10: 00007ffd29f4fd5c R11: 0000000000000246 R12: 0000000000000000
825 [ 363.713763] R13: 0000561202e99930 R14: 000000000000000d R15: 0000000000001545
828 root@ub1704-166:~/smbdirect# cat /proc/1459/stack
829 [<0>] rdma_disconnect+0x45/0x90 [rdma_cm]
830 [<0>] smbdirect_connection_destroy+0x9b/0x510 [smbdirect]
831 [<0>] smbdirect_sk_destruct+0x172/0x1d0 [smbdirect]
832 [<0>] __sk_destruct+0x2b/0x1d0
833 [<0>] sk_destruct+0x2e/0x30
834 [<0>] __sk_free+0x30/0xc0
835 [<0>] sk_free+0x1e/0x20
836 [<0>] smbdirect_proto_ops_release+0x18b/0x1a0 [smbdirect]
837 [<0>] __sock_release+0x42/0xb0
838 [<0>] sock_close+0x15/0x20
839 [<0>] __fput+0xcc/0x260
840 [<0>] ____fput+0xe/0x10
841 [<0>] task_work_run+0x8f/0xb0
842 [<0>] ptrace_notify+0x76/0x80
843 [<0>] syscall_slow_exit_work+0xe3/0x150
844 [<0>] do_syscall_64+0x102/0x130
845 [<0>] entry_SYSCALL_64_after_hwframe+0x44/0xa9
git.samba.org / metze / linux / smbdirect.git / blob