* Linux hangs on Jetson TX1: Page fault (SMMU translation error [--S])
@ 2016-12-18 3:27 Martin Michlmayr
[not found] ` <20161218032759.GA6457-IRMp5PtgtdoigzW5ZibpNwC/G2K4zDHf@public.gmane.org>
0 siblings, 1 reply; 5+ messages in thread
From: Martin Michlmayr @ 2016-12-18 3:27 UTC (permalink / raw)
To: linux-tegra-u79uwXL29TY76Z2rM5mHXA
Cc: thierry.reding-Re5JQEeQqe8AvxtiuMwx3w, Alexandre Courbot
[-- Attachment #1: Type: text/plain, Size: 400 bytes --]
I get a hang with Linux on the TX1 developer kit and 4.9.0-rc8.
One of the last lines I see is:
> tegra-mc 70019000.memory-controller: gpuswr: write @0x00000003fffffff0: Page fault (SMMU translation error [--S])
When I remove all GPU modules from the disk (/lib/modules/.../kernel/drivers/gpu)
the system boot successfully.
I've attached the boot log.
--
Martin Michlmayr
http://www.cyrius.com/
[-- Attachment #2: tegra-page-fault --]
[-- Type: text/plain, Size: 30733 bytes --]
U-Boot 2016.09+dfsg1-2 (Oct 24 2016 - 02:36:36 +0000)
TEGRA210
Model: NVIDIA P2371-2180
Board: NVIDIA P2371-2180
DRAM: 3.5 GiB
MMC: Tegra SD/MMC: 0, Tegra SD/MMC: 1
In: serial
Out: serial
Err: serial
Net: No ethernet found.
Hit any key to stop autoboot: 2 \b\b\b 1 \b\b\b 0
switch to partitions #0, OK
mmc1 is current device
Scanning mmc 1:1...
Found U-Boot script /boot.scr
2176 bytes read in 78 ms (26.4 KiB/s)
## Executing script at 90000000
13558272 bytes read in 919 ms (14.1 MiB/s)
51409 bytes read in 108 ms (464.8 KiB/s)
16399539 bytes read in 1077 ms (14.5 MiB/s)
Booting Debian 4.9.0-rc8-arm64 from mmc 1:1...
## Flattened Device Tree blob at 82000000
Booting using the fdt blob at 0x82000000
Using Device Tree in place at 0000000082000000, end 000000008200f8d0
Starting kernel ...
[ 0.000000] Booting Linux on physical CPU 0x0
[ 0.000000] Linux version 4.9.0-rc8-arm64 (debian-kernel-0aAXYlwwYIKpJHnUorBY+A@public.gmane.orgg) (gcc version 6.2.1 20161124 (Debian 6.2.1-5) ) #1 SMP Debian 4.9~rc8-1~exp1 (2016-12-05)
[ 0.000000] Boot CPU: AArch64 Processor [411fd071]
[ 0.000000] efi: Getting EFI parameters from FDT:
[ 0.000000] efi: UEFI not found.
[ 0.000000] /cpus/cpu@1: missing enable-method property
[ 0.000000] /cpus/cpu@2: missing enable-method property
[ 0.000000] /cpus/cpu@3: missing enable-method property
[ 0.000000] percpu: Embedded 22 pages/cpu @ffff8000fff8a000 s50072 r8192 d31848 u90112
[ 0.000000] Detected PIPT I-cache on CPU0
[ 0.000000] CPU features: enabling workaround for ARM erratum 832075
[ 0.000000] CPU features: enabling workaround for ARM erratum 834220
[ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 903168
[ 0.000000] Kernel command line:
[ 0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes)
[ 0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes)
[ 0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes)
[ 0.000000] software IO TLB [mem 0xdbfff000-0xdffff000] (64MB) mapped at [ffff80005bfff000-ffff80005fffefff]
[ 0.000000] Memory: 3501920K/3670016K available (6780K kernel code, 1016K rwdata, 2156K rodata, 3264K init, 626K bss, 168096K reserved, 0K cma-reserved)
[ 0.000000] Virtual kernel memory layout:
[ 0.000000] modules : 0xffff000000000000 - 0xffff000008000000 ( 128 MB)
[ 0.000000] vmalloc : 0xffff000008000000 - 0xffff7dffbfff0000 (129022 GB)
[ 0.000000] .text : 0xffff000008080000 - 0xffff000008720000 ( 6784 KB)
[ 0.000000] .rodata : 0xffff000008720000 - 0xffff000008940000 ( 2176 KB)
[ 0.000000] .init : 0xffff000008940000 - 0xffff000008c70000 ( 3264 KB)
[ 0.000000] .data : 0xffff000008c70000 - 0xffff000008d6e200 ( 1017 KB)
[ 0.000000] .bss : 0xffff000008d6e200 - 0xffff000008e0ac34 ( 627 KB)
[ 0.000000] fixed : 0xffff7dfffe7fd000 - 0xffff7dfffec00000 ( 4108 KB)
[ 0.000000] PCI I/O : 0xffff7dfffee00000 - 0xffff7dffffe00000 ( 16 MB)
[ 0.000000] vmemmap : 0xffff7e0000000000 - 0xffff800000000000 ( 2048 GB maximum)
[ 0.000000] 0xffff7e0000000000 - 0xffff7e0004000000 ( 64 MB actual)
[ 0.000000] memory : 0xffff800000000000 - 0xffff800100000000 ( 4096 MB)
[ 0.000000] Hierarchical RCU implementation.
[ 0.000000] Build-time adjustment of leaf fanout to 64.
[ 0.000000] RCU restricting CPUs from NR_CPUS=64 to nr_cpu_ids=1.
[ 0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=64, nr_cpu_ids=1
[ 0.000000] NR_IRQS:64 nr_irqs:64 0
[ 0.000000] GIC: Using split EOI/Deactivate mode
[ 0.000000] /interrupt-controller@60004000: 192 interrupts forwarded to /interrupt-controller@50041000
[ 0.000000] PLL_MB already enabled. Postponing set full defaults
[ 0.000000] PLL_U already enabled. Postponing set full defaults
[ 0.000000] PLL_P already enabled. Postponing set full defaults
[ 0.000000] PLL_X already enabled. Postponing set full defaults
[ 0.000000] boot misc1 0x0: expected 0x20
[ 0.000000] (comparison mask = 0xffffff)
[ 0.000000] arm_arch_timer: Architected cp15 timer(s) running at 19.20MHz (phys).
[ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns
[ 0.000003] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns
[ 0.000258] Console: colour dummy device 80x25
[ 0.000722] console [tty0] enabled
[ 0.000743] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=76800)
[ 0.000767] pid_max: default: 32768 minimum: 301
[ 0.000890] Security Framework initialized
[ 0.000905] Yama: disabled by default; enable with sysctl kernel.yama.*
[ 0.000929] AppArmor: AppArmor disabled by boot time parameter
[ 0.000975] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes)
[ 0.000990] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes)
[ 0.001613] ftrace: allocating 24565 entries in 96 pages
[ 0.041431] ASID allocator initialised with 65536 entries
[ 0.043065] Speedo Revision 4
[ 0.043097] Tegra Revision: A02 SKU: 23 CPU Process: 0 SoC Process: -22
[ 0.043720] EFI services will not be available.
[ 0.043865] Brought up 1 CPUs
[ 0.043879] SMP: Total of 1 processors activated.
[ 0.043896] CPU features: detected feature: 32-bit EL0 Support
[ 0.043914] CPU: All CPU(s) started at EL2
[ 0.043933] alternatives: patching kernel code
[ 0.045053] devtmpfs: initialized
[ 0.055076] DMI not present or invalid.
[ 0.055256] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[ 0.055347] pinctrl core: initialized pinctrl subsystem
[ 0.056231] NET: Registered protocol family 16
[ 0.056880] cpuidle: using governor ladder
[ 0.056894] cpuidle: using governor menu
[ 0.056929] vdso: 2 pages (1 code @ ffff000008727000, 1 data @ ffff000008c75000)
[ 0.056954] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
[ 0.057091] DMA: preallocated 256 KiB pool for atomic allocations
[ 0.058062] Serial: AMBA PL011 UART driver
[ 0.068577] tegra-mc 70019000.memory-controller: no memory timings for RAM code 0 registered
[ 0.074024] HugeTLB registered 2 MB page size, pre-allocated 0 pages
[ 0.074720] ACPI: Interpreter disabled.
[ 0.074963] vgaarb: loaded
[ 0.075132] dmi: Firmware registration failed.
[ 0.075696] clocksource: Switched to clocksource arch_sys_counter
[ 0.097478] VFS: Disk quotas dquot_6.6.0
[ 0.097543] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[ 0.097721] pnp: PnP ACPI: disabled
[ 0.104167] NET: Registered protocol family 2
[ 0.104572] TCP established hash table entries: 32768 (order: 6, 262144 bytes)
[ 0.104712] TCP bind hash table entries: 32768 (order: 7, 524288 bytes)
[ 0.104935] TCP: Hash tables configured (established 32768 bind 32768)
[ 0.104992] UDP hash table entries: 2048 (order: 4, 65536 bytes)
[ 0.105030] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes)
[ 0.105180] NET: Registered protocol family 1
[ 0.105353] Unpacking initramfs...
[ 0.867685] Freeing initrd memory: 16012K (ffff800002100000 - ffff8000030a3000)
[ 0.867955] kvm [1]: 8-bit VMID
[ 0.867970] kvm [1]: IDMAP page: 8070b000
[ 0.867983] kvm [1]: HYP VA range: 800000000000:ffffffffffff
[ 0.868517] kvm [1]: Hyp mode initialized successfully
[ 0.868556] kvm [1]: vgic-v2@50044000
[ 0.868608] kvm [1]: vgic interrupt IRQ1
[ 0.868629] kvm [1]: virtual timer IRQ4
[ 0.869746] futex hash table entries: 256 (order: 3, 32768 bytes)
[ 0.869807] audit: initializing netlink subsys (disabled)
[ 0.869866] audit: type=2000 audit(0.835:1): initialized
[ 0.870174] Initialise system trusted keyrings
[ 0.870357] workingset: timestamp_bits=46 max_order=20 bucket_order=0
[ 0.870425] zbud: loaded
[ 0.977471] Key type asymmetric registered
[ 0.977486] Asymmetric key parser 'x509' registered
[ 0.977549] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250)
[ 0.977633] io scheduler noop registered
[ 0.977646] io scheduler deadline registered
[ 0.977674] io scheduler cfq registered (default)
[ 0.985732] tegra-apbdma 60020000.dma: Tegra20 APB DMA driver register 32 channels
[ 0.986183] tegra-pmc 7000e400.pmc: i2c-thermtrip node not found, emergency thermal reset disabled.
[ 0.986440] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[ 0.987610] 70006000.serial: ttyS0 at MMIO 0x70006000 (irq = 316, base_baud = 25500000) is a Tegra
[ 1.682209] console [ttyS0] enabled
[ 1.685967] Serial: AMBA driver
[ 1.689221] msm_serial: driver initialized
[ 1.693429] Unable to detect cache hierarchy from DT for CPU 0
[ 1.699568] mousedev: PS/2 mouse device common for all mice
[ 1.705595] tegra_rtc 7000e000.rtc: rtc core: registered 7000e000.rtc as rtc1
[ 1.712778] tegra_rtc 7000e000.rtc: Tegra internal Real Time Clock
[ 1.719749] tegra_soctherm 700e2000.thermal-sensor: thermtrip: will shut down when cpu reaches 102500 mC
[ 1.729236] tegra_soctherm 700e2000.thermal-sensor: throttrip: will throttle when cpu reaches 98500 mC
[ 1.738603] tegra_soctherm 700e2000.thermal-sensor: thermtrip: will shut down when gpu reaches 103000 mC
[ 1.748085] tegra_soctherm 700e2000.thermal-sensor: throttrip: will throttle when gpu reaches 100000 mC
[ 1.757542] tegra_soctherm 700e2000.thermal-sensor: thermtrip: will shut down when pll reaches 103000 mC
[ 1.767022] tegra_soctherm 700e2000.thermal-sensor: throttrip: pll: missing hot temperature
[ 1.775417] tegra_soctherm 700e2000.thermal-sensor: thermtrip: will shut down when mem reaches 103000 mC
[ 1.784896] tegra_soctherm 700e2000.thermal-sensor: throttrip: mem: missing hot temperature
[ 1.793496] ledtrig-cpu: registered to indicate activity on CPUs
[ 1.799507] dmi-sysfs: dmi entry is absent.
[ 1.803981] NET: Registered protocol family 10
[ 1.808939] mip6: Mobile IPv6
[ 1.811936] NET: Registered protocol family 17
[ 1.816390] mpls_gso: MPLS GSO support
[ 1.820532] registered taskstats version 1
[ 1.824640] Loading compiled-in X.509 certificates
[ 1.833452] alg: No test for pkcs1pad(rsa,sha256) (pkcs1pad(rsa-generic,sha256))
[ 1.841537] Loaded X.509 cert 'Debian Project: Ben Hutchings: 008a018dca80932630'
[ 1.849117] zswap: loaded using pool lzo/zbud
[ 1.858916] hctosys: unable to open rtc device (rtc0)
[ 1.866532] Freeing unused kernel memory: 3264K (ffff800000940000 - ffff800000c70000)
Loading, please wait...
starting version 232
[ 1.908787] random: systemd-udevd: uninitialized urandom read (16 bytes read)
[ 1.917872] random: udevadm: uninitialized urandom read (16 bytes read)
[ 1.924817] random: systemd-udevd: uninitialized urandom read (16 bytes read)
[ 1.932098] random: udevadm: uninitialized urandom read (16 bytes read)
[ 1.938779] random: systemd-udevd: uninitialized urandom read (16 bytes read)
[ 1.946387] random: udevadm: uninitialized urandom read (16 bytes read)
[ 1.955082] random: udevadm: uninitialized urandom read (16 bytes read)
[ 1.961938] random: udevadm: uninitialized urandom read (16 bytes read)
[ 1.968811] random: udevadm: uninitialized urandom read (16 bytes read)
[ 1.975639] random: udevadm: uninitialized urandom read (16 bytes read)
[ 2.091330] i2c_tegra: module verification failed: signature and/or required key missing - tainting kernel
[ 2.120780] sdhci: Secure Digital Host Controller Interface driver
[ 2.126986] sdhci: Copyright(c) Pierre Ossman
[ 2.137750] sdhci-pltfm: SDHCI platform and OF driver helper
[ 2.167845] usb2-0 supply vbus not found, using dummy regulator
[ 2.180978] usbcore: registered new interface driver usbfs
[ 2.200450] tegra-xusb-padctl 7009f000.padctl: failed to setup XUSB ports: -517
[ 2.213798] usbcore: registered new interface driver hub
[ 2.238287] usb2-0 supply vbus not found, using dummy regulator
[ 2.244341] sdhci-tegra 700b0000.sdhci: Got CD GPIO
[ 2.249366] usbcore: registered new device driver usb
[ 2.261860] 0-0074 supply vcc not found, using dummy regulator
[ 2.276326] tegra-xusb-padctl 7009f000.padctl: failed to setup XUSB ports: -517
[ 2.292921] mmc0: Unknown controller version (3). You may experience problems.
[ 2.324467] sdhci-tegra 700b0000.sdhci: Got CD GPIO
[ 2.339253] usb2-0 supply vbus not found, using dummy regulator
[ 2.352725] tegra-xusb-padctl 7009f000.padctl: failed to setup XUSB ports: -517
[ 2.363725] mmc0: SDHCI controller on 700b0600.sdhci [700b0600.sdhci] using ADMA 64-bit
[ 2.373357] sdhci-tegra 700b0000.sdhci: Got CD GPIO
[ 2.378465] tegra-i2c 7000c700.i2c: could not find pctldev for node /host1x@50000000/dpaux@54040000/pinmux-i2c, deferring probe
[ 2.407856] usb2-0 supply vbus not found, using dummy regulator
[ 2.415118] tegra-xusb 70090000.usb: Failed to get supply 'dvddio-pex': -517
[ 2.422176] tegra-xusb 70090000.usb: failed to get regulators: -517
[ 2.431723] tegra-xusb-padctl 7009f000.padctl: failed to setup XUSB ports: -517
[ 2.446111] max77620 1-003c: PMIC Version OTP:0x35 and ES:0x8
[ 2.458672] sdhci-tegra 700b0000.sdhci: Got CD GPIO
[ 2.465692] tegra-i2c 7000c700.i2c: could not find pctldev for node /host1x@50000000/dpaux@54040000/pinmux-i2c, deferring probe
[ 2.480646] mmc0: new high speed MMC card at address 0001
[ 2.492767] usb2-0 supply vbus not found, using dummy regulator
[ 2.499678] mmcblk0: mmc0:0001 016G32 14.7 GiB
[ 2.504487] tegra-xusb-padctl 7009f000.padctl: failed to setup XUSB ports: -517
[ 2.512033] mmcblk0boot0: mmc0:0001 016G32 partition 1 4.00 MiB
[ 2.523415] mmcblk0boot1: mmc0:0001 016G32 partition 2 4.00 MiB
[ 2.529644] mmcblk0rpmb: mmc0:0001 016G32 partition 3 4.00 MiB
[ 2.535537] VDD_5V0_SYS: supplied by VDD_SYS_MUX
[ 2.541554] RTL_5V: supplied by VDD_5V0_SYS
[ 2.546088] USB_VBUS_EN1: supplied by VDD_5V0_SYS
[ 2.551002] VDD_HDMI_5V0: supplied by VDD_5V0_SYS
[ 2.555835] mmcblk0: p1 p2 p3 p4 p5 p6 p7 p8 p9 p10 p11 p12 p13 p14 p15 p16 p17 p18
[ 2.564796] VDD_3V3_SYS: supplied by VDD_SYS_MUX
[ 2.574164] random: fast init done
[ 2.579346] VDD_3V3_SD: supplied by VDD_3V3_SYS
[ 2.589755] VDD_DIS_3V3_LCD: supplied by VDD_3V3_SYS
[ 2.595261] sdhci-tegra 700b0000.sdhci: Got CD GPIO
[ 2.600945] tegra-i2c 7000c700.i2c: could not find pctldev for node /host1x@50000000/dpaux@54040000/pinmux-i2c, deferring probe
[ 2.620670] usb2-0 supply vbus not found, using dummy regulator
[ 2.627050] usb2-3 supply vbus not found, using dummy regulator
[ 2.633166] usb3-0 supply vbus not found, using dummy regulator
[ 2.639462] usb3-1 supply vbus not found, using dummy regulator
[ 2.645906] max77686-rtc max77620-rtc: rtc core: registered max77620-rtc as rtc0
[ 2.690986] VDD_LCD_1V8_DIS: supplied by VDD_1V8
[ 2.707565] AVDD_DSI_CSI_1V2: supplied by AVDD_SYS_1V2
[ 2.715926] 70090000.usb supply dvdd-pex-pll not found, using dummy regulator
[ 2.724206] 70090000.usb supply hvdd-pex-pll-e not found, using dummy regulator
[ 2.732248] VDD_CAM_HV: Bringing 3100000uV into 2800000-2800000uV
[ 2.739776] tegra-xusb 70090000.usb: firmware: direct-loading firmware nvidia/tegra210/xusb.bin
[ 2.749656] VDD_RTC: Bringing 1000000uV into 850000-850000uV
[ 2.757234] VDD_TS_HV: Bringing 3100000uV into 3300000-3300000uV
[ 2.764813] VDD_TS_1V8: Bringing 2800000uV into 1800000-1800000uV
[ 2.808414] tegra-xusb 70090000.usb: Firmware timestamp: 2015-08-10 09:47:54 UTC
[ 2.829014] tegra-xusb 70090000.usb: xHCI Host Controller
[ 2.834851] tegra-xusb 70090000.usb: new USB bus registered, assigned bus number 1
[ 2.843593] tegra-xusb 70090000.usb: hcc params 0x0184f525 hci version 0x100 quirks 0x00010010
[ 2.852337] tegra-xusb 70090000.usb: irq 322, io mem 0x70090000
[ 2.858471] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[ 2.865278] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 2.872506] usb usb1: Product: xHCI Host Controller
[ 2.877387] usb usb1: Manufacturer: Linux 4.9.0-rc8-arm64 xhci-hcd
[ 2.883571] usb usb1: SerialNumber: 70090000.usb
[ 2.944289] hub 1-0:1.0: USB hub found
[ 2.948669] hub 1-0:1.0: 5 ports detected
[ 2.953596] tegra-xusb 70090000.usb: xHCI Host Controller
[ 2.959280] tegra-xusb 70090000.usb: new USB bus registered, assigned bus number 2
[ 2.967352] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
[ 2.976039] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003
[ 2.982853] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 2.990082] usb usb2: Product: xHCI Host Controller
[ 2.994962] usb usb2: Manufacturer: Linux 4.9.0-rc8-arm64 xhci-hcd
[ 3.001143] usb usb2: SerialNumber: 70090000.usb
[ 3.008543] hub 2-0:1.0: USB hub found
[ 3.012630] hub 2-0:1.0: 4 ports detected
[ 3.017944] sdhci-tegra 700b0000.sdhci: Got CD GPIO
[ 3.023595] mmc1: Unknown controller version (3). You may experience problems.
Begin: Loading essential drivers ... [ 3.084129] mmc1: SDHCI controller on 700b0000.sdhci [700b0000.sdhci] using ADMA 64-bit
[ 3.096124] tegra-i2c 7000c700.i2c: could not find pctldev for node /host1x@50000000/dpaux@54040000/pinmux-i2c, deferring probe
done.
Begin: Running /scripts/init-premount ... done.
Begin: Mounting root file system ... Begin: Running /scripts/local-top ... done.
Begin: Running /scripts/local-premount ... done.
Begin: Waiting for root file system ... [ 3.161108] mmc1: host does not support reading read-only switch, assuming write-enable
[ 3.173569] mmc1: new high speed SDHC card at address aaaa
[ 3.181761] mmcblk1: mmc1:aaaa SS32G 29.7 GiB
[ 3.193330] mmcblk1: p1 p2 p3 p4
[ 3.199088] tegra-i2c 7000c700.i2c: could not find pctldev for node /host1x@50000000/dpaux@54040000/pinmux-i2c, deferring probe
[ 3.280151] usb 1-2: new high-speed USB device number 2 using tegra-xusb
[ 3.475725] usb 1-3: new high-speed USB device number 3 using tegra-xusb
[ 3.636485] usb 1-3: New USB device found, idVendor=0b95, idProduct=7720
[ 3.643201] usb 1-3: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[ 3.650336] usb 1-3: Product: AX88x72A
[ 3.654090] usb 1-3: Manufacturer: ASIX Elec. Corp.
[ 3.658970] usb 1-3: SerialNumber: 136926
[ 3.666296] tegra-i2c 7000c700.i2c: could not find pctldev for node /host1x@50000000/dpaux@54040000/pinmux-i2c, deferring probe
[ 3.939709] usb 1-2: new high-speed USB device number 4 using tegra-xusb
[ 4.084531] usb 1-2: New USB device found, idVendor=0955, idProduct=09ff
[ 4.091238] usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=6
[ 4.098373] usb 1-2: Product: USB 10/100/1000 LAN
[ 4.103079] usb 1-2: Manufacturer: Nvidia
[ 4.107092] usb 1-2: SerialNumber: 000001000000
[ 4.113636] tegra-i2c 7000c700.i2c: could not find pctldev for node /host1x@50000000/dpaux@54040000/pinmux-i2c, deferring probe
Begin: Running /scripts/local-block ... done.
done.
Begin: Will now check root file system ... fsck from util-linux 2.29
fsck: error 2 (No such file or directory) while executing fsck.ext4 for /dev/mmcblk1p2
fsck exited with status code 8
done.
Warning: File system check failed but did not detect errors
[ 9.298724] EXT4-fs (mmcblk1p2): mounted filesystem with ordered data mode. Opts: (null)
done.
Begin: Running /scripts/local-bottom ... done.
Begin: Running /scripts/init-bottom ... done.
[ 9.758318] systemd[1]: System time before build time, advancing clock.
[ 9.859421] ip_tables: (C) 2000-2006 Netfilter Core Team
[ 9.898109] systemd[1]: systemd 232 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN)
[ 9.916424] systemd[1]: Detected architecture arm64.
Welcome to ^[[1mDebian GNU/Linux stretch/sid^[[0m!
[ 9.936596] systemd[1]: Set hostname to <tx1>.
[ 10.231371] systemd[1]: Listening on udev Kernel Socket.
[^[[0;32m OK ^[[0m] Listening on udev Kernel Socket.
[ 10.247975] systemd[1]: Listening on Journal Audit Socket.
[^[[0;32m OK ^[[0m] Listening on Journal Audit Socket.
[ 10.264189] systemd[1]: Created slice User and Session Slice.
[^[[0;32m OK ^[[0m] Created slice User and Session Slice.
[ 10.283766] systemd[1]: Reached target Remote File Systems.
[^[[0;32m OK ^[[0m] Reached target Remote File Systems.
[ 10.299871] systemd[1]: Listening on Journal Socket.
[^[[0;32m OK ^[[0m] Listening on Journal Socket.
[ 10.315834] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe.
[^[[0;32m OK ^[[0m] Listening on /dev/initctl Compatibility Named Pipe.
[ 10.336090] systemd[1]: Created slice System Slice.
[^[[0;32m OK ^[[0m] Created slice System Slice.
Mounting Huge Pages File System...
Starting Load Kernel Modules...
[^[[0;32m OK ^[[0m] Created slice system-getty.slice.
Starting Create list of required st…ce nodes for the current kernel...
Mounting Debug File System...
[^[[0;32m OK ^[[0m] Created slice system-serial\x2dgetty.slice.
[^[[0;32m OK ^[[0m] Set up automount Arbitrary Executab…rmats File System Automount Point.
[^[[0;32m OK ^[[0m] Created slice system-systemd\x2dfsck.slice.
[^[[0;32m OK ^[[0m] Started Forward Password Requests to Wall Directory Watch.
[^[[0;32m OK ^[[0m] Listening on Syslog Socket.
[^[[0;32m OK ^[[0m] Listening on fsck to fsckd communication Socket.
Starting File System Check on Root Device...
[^[[0;32m OK ^[[0m] Listening on udev Control Socket.
[^[[0;32m OK ^[[0m] Started Dispatch Password Requests to Console Directory Watch.
[^[[0;32m OK ^[[0m] Reached target Paths.
[^[[0;32m OK ^[[0m] Reached target Encrypted Volumes.
[^[[0;32m OK ^[[0m] Reached target Slices.
Mounting POSIX Message Queue File System...
[^[[0;32m OK ^[[0m] Listening on Journal Socket (/dev/log).
Starting Journal Service...
[^[[0;32m OK ^[[0m] Mounted POSIX Message Queue File System.
[^[[0;32m OK ^[[0m] Mounted Debug File System.
[^[[0;32m OK ^[[0m] Mounted Huge Pages File System.
[^[[0;32m OK ^[[0m] Started Load Kernel Modules.
[^[[0;32m OK ^[[0m] Started Create list of required sta…vice nodes for the current kernel.
[^[[0;32m OK ^[[0m] Started File System Check Daemon to report status.
Starting Create Static Device Nodes in /dev...
Checking in progress on 1 disk (0.0% complete)\r Starting Apply Kernel Variables...
[^[[0;32m OK ^[[0m] Started Journal Service.
[^[[0;32m OK ^[[0m] Started Apply Kernel Variables.
[^[[0;32m OK ^[[0m] Started Create Static Device Nodes in /dev.
Starting udev Kernel Device Manager...
Checking in progress on 1 disk (0.9% complete)\r[^[[0;32m OK ^[[0m] Started udev Kernel Device Manager.
Checking in progress on 1 disk (1.9% complete)\r\rChecking in progress on 1 disk (14.9% complete)\r\rChecking in progress on 1 disk (15.9% complete)\r\rChecking in progress on 1 disk (29.9% complete)\r\rChecking in progress on 1 disk (30.8% complete)\r\rChecking in progress on 1 disk (44.8% complete)\r\rChecking in progress on 1 disk (45.7% complete)\r\rChecking in progress on 1 disk (70.0% complete)\r\rChecking in progress on 1 disk (70.0% complete)\r\rChecking in progress on 1 disk (70.1% complete)\r\rChecking in progress on 1 disk (70.4% complete)\r\rChecking in progress on 1 disk (70.6% complete)\r\rChecking in progress on 1 disk (70.9% complete)\r\rChecking in progress on 1 disk (71.5% complete)\r\rChecking in progress on 1 disk (71.6% complete)\r\rChecking in progress on 1 disk (72.1% complete)\r\rChecking in progress on 1 disk (72.4% complete)\r\rChecking in progress on 1 disk (72.9% complete)\r\rChecking in progress on 1 disk (73.2% complete)\r\rChecking in progress on 1 disk (73.6% complete)\r\rChecking in progress on 1 disk (73.8% complete)\r\rChecking in progress on 1 disk (74.2% complete)\r\rChecking in progress on 1 disk (74.6% complete)\r\rChecking in progress on 1 disk (75.3% complete)\r\rChecking in progress on 1 disk (76.0% complete)\r\rChecking in progress on 1 disk (76.0% complete)\r\rChecking in progress on 1 disk (76.0% complete)\r\rChecking in progress on 1 disk (76.3% complete)\r\rChecking in progress on 1 disk (76.6% complete)\r\rChecking in progress on 1 disk (77.1% complete)\r\rChecking in progress on 1 disk (77.4% complete)\r\rChecking in progress on 1 disk (77.8% complete)\r\rChecking in progress on 1 disk (78.1% complete)\r\rChecking in progress on 1 disk (78.5% complete)\r\rChecking in progress on 1 disk (79.0% complete)\r\rChecking in progress on 1 disk (79.3% complete)\r\rChecking in progress on 1 disk (79.7% complete)\r\rChecking in progress on 1 disk (80.0% complete)\r\rChecking in progress on 1 disk (80.1% complete)\r\rChecking in progress on 1 disk (80.6% complete)\r\rChecking in progress on 1 disk (80.6% complete)\r\rChecking in progress on 1 disk (81.1% complete)\r\rChecking in progress on 1 disk (81.5% complete)\r\rChecking in progress on 1 disk (81.8% complete)\r\rChecking in progress on 1 disk (82.2% complete)\r\rChecking in progress on 1 disk (82.5% complete)\r\rChecking in progress on 1 disk (83.0% complete)\r\rChecking in progress on 1 disk (83.3% complete)\r\rChecking in progress on 1 disk (83.6% complete)\r\rChecking in progress on 1 disk (83.9% complete)\r\rChecking in progress on 1 disk (84.3% complete)\r\rChecking in progress on 1 disk (84.6% complete)\r\rChecking in progress on 1 disk (84.8% complete)\r\rChecking in progress on 1 disk (85.1% complete)\r\rChecking in progress on 1 disk (85.1% complete)\r\rChecking in progress on 1 disk (85.4% complete)\r\rChecking in progress on 1 disk (85.9% complete)\r\rChecking in progress on 1 disk (86.6% complete)\r\rChecking in progress on 1 disk (87.2% complete)\r\rChecking in progress on 1 disk (87.5% complete)\r\rChecking in progress on 1 disk (87.7% complete)\r\rChecking in progress on 1 disk (88.1% complete)\r\rChecking in progress on 1 disk (88.2% complete)\r\rChecking in progress on 1 disk (88.7% complete)\r\rChecking in progress on 1 disk (89.0% complete)\r\rChecking in progress on 1 disk (89.4% complete)\r\rChecking in progress on 1 disk (89.5% complete)\r\rChecking in progress on 1 disk (89.7% complete)\r\rChecking in progress on 1 disk (89.9% complete)\r\rChecking in progress on 1 disk (90.1% complete)\r\rChecking in progress on 1 disk (90.3% complete)\r\rChecking in progress on 1 disk (90.4% complete)\r\rChecking in progress on 1 disk (90.6% complete)\r\rChecking in progress on 1 disk (90.8% complete)\r\rChecking in progress on 1 disk (90.9% complete)\r\rChecking in progress on 1 disk (91.1% complete)\r\rChecking in progress on 1 disk (91.3% complete)\r\rChecking in progress on 1 disk (91.4% complete)\r\rChecking in progress on 1 disk (91.6% complete)\r\rChecking in progress on 1 disk (91.8% complete)\r\rChecking in progress on 1 disk (91.9% complete)\r\rChecking in progress on 1 disk (92.6% complete)\r\rChecking in progress on 1 disk (93.6% complete)\r\rChecking in progress on 1 disk (94.6% complete)\r\rChecking in progress on 1 disk (96.6% complete)\r\rChecking in progress on 1 disk (97.5% complete)\r\rChecking in progress on 1 disk (100.0% complete)\r\rChecking in progress on 0 disks (100.0% complete)\r[^[[0;32m OK ^[[0m] Started File System Check on Root Device.
Starting Remount Root and Kernel File Systems...
[ 12.588657] EXT4-fs (mmcblk1p2): re-mounted. Opts: errors=remount-ro
[^[[0;32m OK ^[[0m] Started Remount Root and Kernel File Systems.
[^[[0;32m OK ^[[0m] Reached target Local File Systems (Pre).
Starting Load/Save Random Seed...
Starting udev Coldplug all Devices...
Starting Flush Journal to Persistent Storage...
[^[[0;32m OK ^[[0m] Started Load/Save Random Seed.
[ 12.695677] systemd-journald[186]: Received request to flush runtime journal from PID 1
[^[[0;32m OK ^[[0m] Started Flush Journal to Persistent Storage.
[ 12.973592] tegra-i2c 7000c700.i2c: could not find pctldev for node /host1x@50000000/dpaux@54040000/pinmux-i2c, deferring probe
[^[[0;32m OK ^[[0m] Started udev Coldplug all Devices.
[^[[0;32m OK ^[[0m] Found device /dev/ttyS0.
[ 13.099280] 0-002c supply power not found, using dummy regulator
[ 13.120275] tegra-i2c 7000c700.i2c: could not find pctldev for node /host1x@50000000/dpaux@54040000/pinmux-i2c, deferring probe
[ 13.144235] [drm] Initialized
[ 13.156912] lp855x 0-002c: failed to read 0x00
[ 13.161383] lp855x 0-002c: pre init device err: -121
[ 13.166359] lp855x 0-002c: device config err: -121
[ 13.247809] lp855x: probe of 0-002c failed with error -121
[ 13.294459] tegra-i2c 7000c700.i2c: could not find pctldev for node /host1x@50000000/dpaux@54040000/pinmux-i2c, deferring probe
[ 13.307581] 54040000.dpaux supply vdd not found, using dummy regulator
[ 13.332329] tegra-sor 54580000.sor: failed to probe output: -517
[ 13.367740] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
[ 13.374368] [drm] No driver support for vblank timestamp query.
[ 13.447475] drm drm: No connectors reported connected with modes
[ 13.463272] [drm] Cannot find any crtc or sizes - going 1024x768
[ 13.507394] drm drm: fb0: frame buffer device
[ 13.559928] [drm] Initialized tegra 0.0.0 20120330 on minor 0
[ 13.589237] usbcore: registered new interface driver r8152
[ 13.633884] usbcore: registered new interface driver cdc_ether
[^[[0;32m OK ^[[0m] Found device /dev/disk/by-uuid/98a72b13-a480-4b9a-8627-04fd7dc8ff7d.
[ 13.728119] usb 1-2: reset high-speed USB device number 4 using tegra-xusb
[ 13.876813] 57000000.gpu supply vdd not found, using dummy regulator
[^[[0;32m OK ^[[0m] Found device /dev/disk/by-uuid/eac8dd58-c636-472d-89e5-79272fd02d9e.
[^[[0;32m OK ^[[0m] Found device /dev/disk/by-uuid/b2b4d279-7f34-4d03-b42a-b75c63d70f62.
[ 13.914076] tegra-mc 70019000.memory-controller: gpuswr: write @0x00000003fffffff0: Page fault (SMMU translation error [--S])
[^[[0;32m OK ^[[0m] Found device /dev/mmcblk1p3.
[ 13.965144] alg: No test for __ecb-aes-ce (__driver-ecb-aes-ce)
[ 13.981054] asix 1-3:1.0 eth0: register 'asix' at usb-70090000.usb-3, ASIX AX88772 USB 2.0 Ethernet, 00:50:b6:13:69:26
[ 14.025201] r8152 1-2:1.0 eth1: v1.08.6
^ permalink raw reply [flat|nested] 5+ messages in thread
* Re: Linux hangs on Jetson TX1: Page fault (SMMU translation error [--S])
[not found] ` <20161218032759.GA6457-IRMp5PtgtdoigzW5ZibpNwC/G2K4zDHf@public.gmane.org>
@ 2017-01-02 16:08 ` Thierry Reding
[not found] ` <20170102160844.GA18393-EkSeR96xj6Pcmrwk2tT4+A@public.gmane.org>
0 siblings, 1 reply; 5+ messages in thread
From: Thierry Reding @ 2017-01-02 16:08 UTC (permalink / raw)
To: Martin Michlmayr, Alexandre Courbot
Cc: linux-tegra-u79uwXL29TY76Z2rM5mHXA, Alexandre Courbot
[-- Attachment #1: Type: text/plain, Size: 33462 bytes --]
Hi Martin,
Happy New Year!
On Sat, Dec 17, 2016 at 07:27:59PM -0800, Martin Michlmayr wrote:
> I get a hang with Linux on the TX1 developer kit and 4.9.0-rc8.
> One of the last lines I see is:
>
> > tegra-mc 70019000.memory-controller: gpuswr: write @0x00000003fffffff0: Page fault (SMMU translation error [--S])
This looks very odd. It would indicate that the GPU is trying to write
to address 0x3fffffff0, which is way above any physical address in the
system.
> When I remove all GPU modules from the disk (/lib/modules/.../kernel/drivers/gpu)
> the system boot successfully.
>
> I've attached the boot log.
I can't spot anything in the log about what might be causing this.
However, looking at the code, I see something that's rather strange:
In drivers/gpu/drm/nouveau/nvkm/engine/device/tegra.c, the function
nvkm_device_tegra_probe_iommu() sets up a memory manager with a range
from 0 to 1 << 34 (iommu_bit), which I think might be why you see the
0x3fffffff0 address. Given that the code sets things up for the IOMMU
case, shouldn't the memory manager be restricted to the range of IOVA
addresses (0 to 1 << 32)?
Alex, any ideas?
Martin, one thing to check might be if your .config enables the
TEGRA_IOMMU_SMMU option. If not that would explain why you're seeing
this while I'm not. Although I'd still be confused because I don't see
either the "using IOMMU" nor the "using DMA API" message (from the
drivers/gpu/drm/nouveau/nvkm/subdev/instmem/gk20a.c:gk20a_instmem_new()
function) in the logs. One of them should be there.
Perhaps you can also regenerate a log with drm.debug=0x3f on the kernel
command-line. Maybe throw in a NOUVEAU_DEBUG_DEFAULT=5 in .config to
make sure Nouveau gives us as much info as possible (=7 should give
more, but it might be a little overkill).
Thanks,
Thierry
>
> --
> Martin Michlmayr
> http://www.cyrius.com/
> U-Boot 2016.09+dfsg1-2 (Oct 24 2016 - 02:36:36 +0000)
>
> TEGRA210
> Model: NVIDIA P2371-2180
> Board: NVIDIA P2371-2180
> DRAM: 3.5 GiB
> MMC: Tegra SD/MMC: 0, Tegra SD/MMC: 1
> In: serial
> Out: serial
> Err: serial
> Net: No ethernet found.
> Hit any key to stop autoboot: 2 \b\b\b 1 \b\b\b 0
> switch to partitions #0, OK
> mmc1 is current device
> Scanning mmc 1:1...
> Found U-Boot script /boot.scr
> 2176 bytes read in 78 ms (26.4 KiB/s)
> ## Executing script at 90000000
> 13558272 bytes read in 919 ms (14.1 MiB/s)
> 51409 bytes read in 108 ms (464.8 KiB/s)
> 16399539 bytes read in 1077 ms (14.5 MiB/s)
> Booting Debian 4.9.0-rc8-arm64 from mmc 1:1...
> ## Flattened Device Tree blob at 82000000
> Booting using the fdt blob at 0x82000000
> Using Device Tree in place at 0000000082000000, end 000000008200f8d0
>
> Starting kernel ...
>
> [ 0.000000] Booting Linux on physical CPU 0x0
> [ 0.000000] Linux version 4.9.0-rc8-arm64 (debian-kernel-0aAXYlwwYIKrKVvWRXNRGw@public.gmane.orgorg) (gcc version 6.2.1 20161124 (Debian 6.2.1-5) ) #1 SMP Debian 4.9~rc8-1~exp1 (2016-12-05)
> [ 0.000000] Boot CPU: AArch64 Processor [411fd071]
> [ 0.000000] efi: Getting EFI parameters from FDT:
> [ 0.000000] efi: UEFI not found.
> [ 0.000000] /cpus/cpu@1: missing enable-method property
> [ 0.000000] /cpus/cpu@2: missing enable-method property
> [ 0.000000] /cpus/cpu@3: missing enable-method property
> [ 0.000000] percpu: Embedded 22 pages/cpu @ffff8000fff8a000 s50072 r8192 d31848 u90112
> [ 0.000000] Detected PIPT I-cache on CPU0
> [ 0.000000] CPU features: enabling workaround for ARM erratum 832075
> [ 0.000000] CPU features: enabling workaround for ARM erratum 834220
> [ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 903168
> [ 0.000000] Kernel command line:
> [ 0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes)
> [ 0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes)
> [ 0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes)
> [ 0.000000] software IO TLB [mem 0xdbfff000-0xdffff000] (64MB) mapped at [ffff80005bfff000-ffff80005fffefff]
> [ 0.000000] Memory: 3501920K/3670016K available (6780K kernel code, 1016K rwdata, 2156K rodata, 3264K init, 626K bss, 168096K reserved, 0K cma-reserved)
> [ 0.000000] Virtual kernel memory layout:
> [ 0.000000] modules : 0xffff000000000000 - 0xffff000008000000 ( 128 MB)
> [ 0.000000] vmalloc : 0xffff000008000000 - 0xffff7dffbfff0000 (129022 GB)
> [ 0.000000] .text : 0xffff000008080000 - 0xffff000008720000 ( 6784 KB)
> [ 0.000000] .rodata : 0xffff000008720000 - 0xffff000008940000 ( 2176 KB)
> [ 0.000000] .init : 0xffff000008940000 - 0xffff000008c70000 ( 3264 KB)
> [ 0.000000] .data : 0xffff000008c70000 - 0xffff000008d6e200 ( 1017 KB)
> [ 0.000000] .bss : 0xffff000008d6e200 - 0xffff000008e0ac34 ( 627 KB)
> [ 0.000000] fixed : 0xffff7dfffe7fd000 - 0xffff7dfffec00000 ( 4108 KB)
> [ 0.000000] PCI I/O : 0xffff7dfffee00000 - 0xffff7dffffe00000 ( 16 MB)
> [ 0.000000] vmemmap : 0xffff7e0000000000 - 0xffff800000000000 ( 2048 GB maximum)
> [ 0.000000] 0xffff7e0000000000 - 0xffff7e0004000000 ( 64 MB actual)
> [ 0.000000] memory : 0xffff800000000000 - 0xffff800100000000 ( 4096 MB)
> [ 0.000000] Hierarchical RCU implementation.
> [ 0.000000] Build-time adjustment of leaf fanout to 64.
> [ 0.000000] RCU restricting CPUs from NR_CPUS=64 to nr_cpu_ids=1.
> [ 0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=64, nr_cpu_ids=1
> [ 0.000000] NR_IRQS:64 nr_irqs:64 0
> [ 0.000000] GIC: Using split EOI/Deactivate mode
> [ 0.000000] /interrupt-controller@60004000: 192 interrupts forwarded to /interrupt-controller@50041000
> [ 0.000000] PLL_MB already enabled. Postponing set full defaults
> [ 0.000000] PLL_U already enabled. Postponing set full defaults
> [ 0.000000] PLL_P already enabled. Postponing set full defaults
> [ 0.000000] PLL_X already enabled. Postponing set full defaults
> [ 0.000000] boot misc1 0x0: expected 0x20
> [ 0.000000] (comparison mask = 0xffffff)
> [ 0.000000] arm_arch_timer: Architected cp15 timer(s) running at 19.20MHz (phys).
> [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns
> [ 0.000003] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns
> [ 0.000258] Console: colour dummy device 80x25
> [ 0.000722] console [tty0] enabled
> [ 0.000743] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=76800)
> [ 0.000767] pid_max: default: 32768 minimum: 301
> [ 0.000890] Security Framework initialized
> [ 0.000905] Yama: disabled by default; enable with sysctl kernel.yama.*
> [ 0.000929] AppArmor: AppArmor disabled by boot time parameter
> [ 0.000975] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes)
> [ 0.000990] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes)
> [ 0.001613] ftrace: allocating 24565 entries in 96 pages
> [ 0.041431] ASID allocator initialised with 65536 entries
> [ 0.043065] Speedo Revision 4
> [ 0.043097] Tegra Revision: A02 SKU: 23 CPU Process: 0 SoC Process: -22
> [ 0.043720] EFI services will not be available.
> [ 0.043865] Brought up 1 CPUs
> [ 0.043879] SMP: Total of 1 processors activated.
> [ 0.043896] CPU features: detected feature: 32-bit EL0 Support
> [ 0.043914] CPU: All CPU(s) started at EL2
> [ 0.043933] alternatives: patching kernel code
> [ 0.045053] devtmpfs: initialized
> [ 0.055076] DMI not present or invalid.
> [ 0.055256] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
> [ 0.055347] pinctrl core: initialized pinctrl subsystem
> [ 0.056231] NET: Registered protocol family 16
> [ 0.056880] cpuidle: using governor ladder
> [ 0.056894] cpuidle: using governor menu
> [ 0.056929] vdso: 2 pages (1 code @ ffff000008727000, 1 data @ ffff000008c75000)
> [ 0.056954] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
> [ 0.057091] DMA: preallocated 256 KiB pool for atomic allocations
> [ 0.058062] Serial: AMBA PL011 UART driver
> [ 0.068577] tegra-mc 70019000.memory-controller: no memory timings for RAM code 0 registered
> [ 0.074024] HugeTLB registered 2 MB page size, pre-allocated 0 pages
> [ 0.074720] ACPI: Interpreter disabled.
> [ 0.074963] vgaarb: loaded
> [ 0.075132] dmi: Firmware registration failed.
> [ 0.075696] clocksource: Switched to clocksource arch_sys_counter
> [ 0.097478] VFS: Disk quotas dquot_6.6.0
> [ 0.097543] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
> [ 0.097721] pnp: PnP ACPI: disabled
> [ 0.104167] NET: Registered protocol family 2
> [ 0.104572] TCP established hash table entries: 32768 (order: 6, 262144 bytes)
> [ 0.104712] TCP bind hash table entries: 32768 (order: 7, 524288 bytes)
> [ 0.104935] TCP: Hash tables configured (established 32768 bind 32768)
> [ 0.104992] UDP hash table entries: 2048 (order: 4, 65536 bytes)
> [ 0.105030] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes)
> [ 0.105180] NET: Registered protocol family 1
> [ 0.105353] Unpacking initramfs...
> [ 0.867685] Freeing initrd memory: 16012K (ffff800002100000 - ffff8000030a3000)
> [ 0.867955] kvm [1]: 8-bit VMID
> [ 0.867970] kvm [1]: IDMAP page: 8070b000
> [ 0.867983] kvm [1]: HYP VA range: 800000000000:ffffffffffff
> [ 0.868517] kvm [1]: Hyp mode initialized successfully
> [ 0.868556] kvm [1]: vgic-v2@50044000
> [ 0.868608] kvm [1]: vgic interrupt IRQ1
> [ 0.868629] kvm [1]: virtual timer IRQ4
> [ 0.869746] futex hash table entries: 256 (order: 3, 32768 bytes)
> [ 0.869807] audit: initializing netlink subsys (disabled)
> [ 0.869866] audit: type=2000 audit(0.835:1): initialized
> [ 0.870174] Initialise system trusted keyrings
> [ 0.870357] workingset: timestamp_bits=46 max_order=20 bucket_order=0
> [ 0.870425] zbud: loaded
> [ 0.977471] Key type asymmetric registered
> [ 0.977486] Asymmetric key parser 'x509' registered
> [ 0.977549] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250)
> [ 0.977633] io scheduler noop registered
> [ 0.977646] io scheduler deadline registered
> [ 0.977674] io scheduler cfq registered (default)
> [ 0.985732] tegra-apbdma 60020000.dma: Tegra20 APB DMA driver register 32 channels
> [ 0.986183] tegra-pmc 7000e400.pmc: i2c-thermtrip node not found, emergency thermal reset disabled.
> [ 0.986440] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
> [ 0.987610] 70006000.serial: ttyS0 at MMIO 0x70006000 (irq = 316, base_baud = 25500000) is a Tegra
> [ 1.682209] console [ttyS0] enabled
> [ 1.685967] Serial: AMBA driver
> [ 1.689221] msm_serial: driver initialized
> [ 1.693429] Unable to detect cache hierarchy from DT for CPU 0
> [ 1.699568] mousedev: PS/2 mouse device common for all mice
> [ 1.705595] tegra_rtc 7000e000.rtc: rtc core: registered 7000e000.rtc as rtc1
> [ 1.712778] tegra_rtc 7000e000.rtc: Tegra internal Real Time Clock
> [ 1.719749] tegra_soctherm 700e2000.thermal-sensor: thermtrip: will shut down when cpu reaches 102500 mC
> [ 1.729236] tegra_soctherm 700e2000.thermal-sensor: throttrip: will throttle when cpu reaches 98500 mC
> [ 1.738603] tegra_soctherm 700e2000.thermal-sensor: thermtrip: will shut down when gpu reaches 103000 mC
> [ 1.748085] tegra_soctherm 700e2000.thermal-sensor: throttrip: will throttle when gpu reaches 100000 mC
> [ 1.757542] tegra_soctherm 700e2000.thermal-sensor: thermtrip: will shut down when pll reaches 103000 mC
> [ 1.767022] tegra_soctherm 700e2000.thermal-sensor: throttrip: pll: missing hot temperature
> [ 1.775417] tegra_soctherm 700e2000.thermal-sensor: thermtrip: will shut down when mem reaches 103000 mC
> [ 1.784896] tegra_soctherm 700e2000.thermal-sensor: throttrip: mem: missing hot temperature
> [ 1.793496] ledtrig-cpu: registered to indicate activity on CPUs
> [ 1.799507] dmi-sysfs: dmi entry is absent.
> [ 1.803981] NET: Registered protocol family 10
> [ 1.808939] mip6: Mobile IPv6
> [ 1.811936] NET: Registered protocol family 17
> [ 1.816390] mpls_gso: MPLS GSO support
> [ 1.820532] registered taskstats version 1
> [ 1.824640] Loading compiled-in X.509 certificates
> [ 1.833452] alg: No test for pkcs1pad(rsa,sha256) (pkcs1pad(rsa-generic,sha256))
> [ 1.841537] Loaded X.509 cert 'Debian Project: Ben Hutchings: 008a018dca80932630'
> [ 1.849117] zswap: loaded using pool lzo/zbud
> [ 1.858916] hctosys: unable to open rtc device (rtc0)
> [ 1.866532] Freeing unused kernel memory: 3264K (ffff800000940000 - ffff800000c70000)
> Loading, please wait...
> starting version 232
> [ 1.908787] random: systemd-udevd: uninitialized urandom read (16 bytes read)
> [ 1.917872] random: udevadm: uninitialized urandom read (16 bytes read)
> [ 1.924817] random: systemd-udevd: uninitialized urandom read (16 bytes read)
> [ 1.932098] random: udevadm: uninitialized urandom read (16 bytes read)
> [ 1.938779] random: systemd-udevd: uninitialized urandom read (16 bytes read)
> [ 1.946387] random: udevadm: uninitialized urandom read (16 bytes read)
> [ 1.955082] random: udevadm: uninitialized urandom read (16 bytes read)
> [ 1.961938] random: udevadm: uninitialized urandom read (16 bytes read)
> [ 1.968811] random: udevadm: uninitialized urandom read (16 bytes read)
> [ 1.975639] random: udevadm: uninitialized urandom read (16 bytes read)
> [ 2.091330] i2c_tegra: module verification failed: signature and/or required key missing - tainting kernel
> [ 2.120780] sdhci: Secure Digital Host Controller Interface driver
> [ 2.126986] sdhci: Copyright(c) Pierre Ossman
> [ 2.137750] sdhci-pltfm: SDHCI platform and OF driver helper
> [ 2.167845] usb2-0 supply vbus not found, using dummy regulator
> [ 2.180978] usbcore: registered new interface driver usbfs
> [ 2.200450] tegra-xusb-padctl 7009f000.padctl: failed to setup XUSB ports: -517
> [ 2.213798] usbcore: registered new interface driver hub
> [ 2.238287] usb2-0 supply vbus not found, using dummy regulator
> [ 2.244341] sdhci-tegra 700b0000.sdhci: Got CD GPIO
> [ 2.249366] usbcore: registered new device driver usb
> [ 2.261860] 0-0074 supply vcc not found, using dummy regulator
> [ 2.276326] tegra-xusb-padctl 7009f000.padctl: failed to setup XUSB ports: -517
> [ 2.292921] mmc0: Unknown controller version (3). You may experience problems.
> [ 2.324467] sdhci-tegra 700b0000.sdhci: Got CD GPIO
> [ 2.339253] usb2-0 supply vbus not found, using dummy regulator
> [ 2.352725] tegra-xusb-padctl 7009f000.padctl: failed to setup XUSB ports: -517
> [ 2.363725] mmc0: SDHCI controller on 700b0600.sdhci [700b0600.sdhci] using ADMA 64-bit
> [ 2.373357] sdhci-tegra 700b0000.sdhci: Got CD GPIO
> [ 2.378465] tegra-i2c 7000c700.i2c: could not find pctldev for node /host1x@50000000/dpaux@54040000/pinmux-i2c, deferring probe
> [ 2.407856] usb2-0 supply vbus not found, using dummy regulator
> [ 2.415118] tegra-xusb 70090000.usb: Failed to get supply 'dvddio-pex': -517
> [ 2.422176] tegra-xusb 70090000.usb: failed to get regulators: -517
> [ 2.431723] tegra-xusb-padctl 7009f000.padctl: failed to setup XUSB ports: -517
> [ 2.446111] max77620 1-003c: PMIC Version OTP:0x35 and ES:0x8
> [ 2.458672] sdhci-tegra 700b0000.sdhci: Got CD GPIO
> [ 2.465692] tegra-i2c 7000c700.i2c: could not find pctldev for node /host1x@50000000/dpaux@54040000/pinmux-i2c, deferring probe
> [ 2.480646] mmc0: new high speed MMC card at address 0001
> [ 2.492767] usb2-0 supply vbus not found, using dummy regulator
> [ 2.499678] mmcblk0: mmc0:0001 016G32 14.7 GiB
> [ 2.504487] tegra-xusb-padctl 7009f000.padctl: failed to setup XUSB ports: -517
> [ 2.512033] mmcblk0boot0: mmc0:0001 016G32 partition 1 4.00 MiB
> [ 2.523415] mmcblk0boot1: mmc0:0001 016G32 partition 2 4.00 MiB
> [ 2.529644] mmcblk0rpmb: mmc0:0001 016G32 partition 3 4.00 MiB
> [ 2.535537] VDD_5V0_SYS: supplied by VDD_SYS_MUX
> [ 2.541554] RTL_5V: supplied by VDD_5V0_SYS
> [ 2.546088] USB_VBUS_EN1: supplied by VDD_5V0_SYS
> [ 2.551002] VDD_HDMI_5V0: supplied by VDD_5V0_SYS
> [ 2.555835] mmcblk0: p1 p2 p3 p4 p5 p6 p7 p8 p9 p10 p11 p12 p13 p14 p15 p16 p17 p18
> [ 2.564796] VDD_3V3_SYS: supplied by VDD_SYS_MUX
> [ 2.574164] random: fast init done
> [ 2.579346] VDD_3V3_SD: supplied by VDD_3V3_SYS
> [ 2.589755] VDD_DIS_3V3_LCD: supplied by VDD_3V3_SYS
> [ 2.595261] sdhci-tegra 700b0000.sdhci: Got CD GPIO
> [ 2.600945] tegra-i2c 7000c700.i2c: could not find pctldev for node /host1x@50000000/dpaux@54040000/pinmux-i2c, deferring probe
> [ 2.620670] usb2-0 supply vbus not found, using dummy regulator
> [ 2.627050] usb2-3 supply vbus not found, using dummy regulator
> [ 2.633166] usb3-0 supply vbus not found, using dummy regulator
> [ 2.639462] usb3-1 supply vbus not found, using dummy regulator
> [ 2.645906] max77686-rtc max77620-rtc: rtc core: registered max77620-rtc as rtc0
> [ 2.690986] VDD_LCD_1V8_DIS: supplied by VDD_1V8
> [ 2.707565] AVDD_DSI_CSI_1V2: supplied by AVDD_SYS_1V2
> [ 2.715926] 70090000.usb supply dvdd-pex-pll not found, using dummy regulator
> [ 2.724206] 70090000.usb supply hvdd-pex-pll-e not found, using dummy regulator
> [ 2.732248] VDD_CAM_HV: Bringing 3100000uV into 2800000-2800000uV
> [ 2.739776] tegra-xusb 70090000.usb: firmware: direct-loading firmware nvidia/tegra210/xusb.bin
> [ 2.749656] VDD_RTC: Bringing 1000000uV into 850000-850000uV
> [ 2.757234] VDD_TS_HV: Bringing 3100000uV into 3300000-3300000uV
> [ 2.764813] VDD_TS_1V8: Bringing 2800000uV into 1800000-1800000uV
> [ 2.808414] tegra-xusb 70090000.usb: Firmware timestamp: 2015-08-10 09:47:54 UTC
> [ 2.829014] tegra-xusb 70090000.usb: xHCI Host Controller
> [ 2.834851] tegra-xusb 70090000.usb: new USB bus registered, assigned bus number 1
> [ 2.843593] tegra-xusb 70090000.usb: hcc params 0x0184f525 hci version 0x100 quirks 0x00010010
> [ 2.852337] tegra-xusb 70090000.usb: irq 322, io mem 0x70090000
> [ 2.858471] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
> [ 2.865278] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
> [ 2.872506] usb usb1: Product: xHCI Host Controller
> [ 2.877387] usb usb1: Manufacturer: Linux 4.9.0-rc8-arm64 xhci-hcd
> [ 2.883571] usb usb1: SerialNumber: 70090000.usb
> [ 2.944289] hub 1-0:1.0: USB hub found
> [ 2.948669] hub 1-0:1.0: 5 ports detected
> [ 2.953596] tegra-xusb 70090000.usb: xHCI Host Controller
> [ 2.959280] tegra-xusb 70090000.usb: new USB bus registered, assigned bus number 2
> [ 2.967352] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
> [ 2.976039] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003
> [ 2.982853] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
> [ 2.990082] usb usb2: Product: xHCI Host Controller
> [ 2.994962] usb usb2: Manufacturer: Linux 4.9.0-rc8-arm64 xhci-hcd
> [ 3.001143] usb usb2: SerialNumber: 70090000.usb
> [ 3.008543] hub 2-0:1.0: USB hub found
> [ 3.012630] hub 2-0:1.0: 4 ports detected
> [ 3.017944] sdhci-tegra 700b0000.sdhci: Got CD GPIO
> [ 3.023595] mmc1: Unknown controller version (3). You may experience problems.
> Begin: Loading essential drivers ... [ 3.084129] mmc1: SDHCI controller on 700b0000.sdhci [700b0000.sdhci] using ADMA 64-bit
> [ 3.096124] tegra-i2c 7000c700.i2c: could not find pctldev for node /host1x@50000000/dpaux@54040000/pinmux-i2c, deferring probe
> done.
> Begin: Running /scripts/init-premount ... done.
> Begin: Mounting root file system ... Begin: Running /scripts/local-top ... done.
> Begin: Running /scripts/local-premount ... done.
> Begin: Waiting for root file system ... [ 3.161108] mmc1: host does not support reading read-only switch, assuming write-enable
> [ 3.173569] mmc1: new high speed SDHC card at address aaaa
> [ 3.181761] mmcblk1: mmc1:aaaa SS32G 29.7 GiB
> [ 3.193330] mmcblk1: p1 p2 p3 p4
> [ 3.199088] tegra-i2c 7000c700.i2c: could not find pctldev for node /host1x@50000000/dpaux@54040000/pinmux-i2c, deferring probe
> [ 3.280151] usb 1-2: new high-speed USB device number 2 using tegra-xusb
> [ 3.475725] usb 1-3: new high-speed USB device number 3 using tegra-xusb
> [ 3.636485] usb 1-3: New USB device found, idVendor=0b95, idProduct=7720
> [ 3.643201] usb 1-3: New USB device strings: Mfr=1, Product=2, SerialNumber=3
> [ 3.650336] usb 1-3: Product: AX88x72A
> [ 3.654090] usb 1-3: Manufacturer: ASIX Elec. Corp.
> [ 3.658970] usb 1-3: SerialNumber: 136926
> [ 3.666296] tegra-i2c 7000c700.i2c: could not find pctldev for node /host1x@50000000/dpaux@54040000/pinmux-i2c, deferring probe
> [ 3.939709] usb 1-2: new high-speed USB device number 4 using tegra-xusb
> [ 4.084531] usb 1-2: New USB device found, idVendor=0955, idProduct=09ff
> [ 4.091238] usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=6
> [ 4.098373] usb 1-2: Product: USB 10/100/1000 LAN
> [ 4.103079] usb 1-2: Manufacturer: Nvidia
> [ 4.107092] usb 1-2: SerialNumber: 000001000000
> [ 4.113636] tegra-i2c 7000c700.i2c: could not find pctldev for node /host1x@50000000/dpaux@54040000/pinmux-i2c, deferring probe
> Begin: Running /scripts/local-block ... done.
> done.
> Begin: Will now check root file system ... fsck from util-linux 2.29
> fsck: error 2 (No such file or directory) while executing fsck.ext4 for /dev/mmcblk1p2
> fsck exited with status code 8
> done.
> Warning: File system check failed but did not detect errors
> [ 9.298724] EXT4-fs (mmcblk1p2): mounted filesystem with ordered data mode. Opts: (null)
> done.
> Begin: Running /scripts/local-bottom ... done.
> Begin: Running /scripts/init-bottom ... done.
> [ 9.758318] systemd[1]: System time before build time, advancing clock.
> [ 9.859421] ip_tables: (C) 2000-2006 Netfilter Core Team
> [ 9.898109] systemd[1]: systemd 232 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN)
> [ 9.916424] systemd[1]: Detected architecture arm64.
>
> Welcome to ^[[1mDebian GNU/Linux stretch/sid^[[0m!
>
> [ 9.936596] systemd[1]: Set hostname to <tx1>.
> [ 10.231371] systemd[1]: Listening on udev Kernel Socket.
> [^[[0;32m OK ^[[0m] Listening on udev Kernel Socket.
> [ 10.247975] systemd[1]: Listening on Journal Audit Socket.
> [^[[0;32m OK ^[[0m] Listening on Journal Audit Socket.
> [ 10.264189] systemd[1]: Created slice User and Session Slice.
> [^[[0;32m OK ^[[0m] Created slice User and Session Slice.
> [ 10.283766] systemd[1]: Reached target Remote File Systems.
> [^[[0;32m OK ^[[0m] Reached target Remote File Systems.
> [ 10.299871] systemd[1]: Listening on Journal Socket.
> [^[[0;32m OK ^[[0m] Listening on Journal Socket.
> [ 10.315834] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe.
> [^[[0;32m OK ^[[0m] Listening on /dev/initctl Compatibility Named Pipe.
> [ 10.336090] systemd[1]: Created slice System Slice.
> [^[[0;32m OK ^[[0m] Created slice System Slice.
> Mounting Huge Pages File System...
> Starting Load Kernel Modules...
> [^[[0;32m OK ^[[0m] Created slice system-getty.slice.
> Starting Create list of required st…ce nodes for the current kernel...
> Mounting Debug File System...
> [^[[0;32m OK ^[[0m] Created slice system-serial\x2dgetty.slice.
> [^[[0;32m OK ^[[0m] Set up automount Arbitrary Executab…rmats File System Automount Point.
> [^[[0;32m OK ^[[0m] Created slice system-systemd\x2dfsck.slice.
> [^[[0;32m OK ^[[0m] Started Forward Password Requests to Wall Directory Watch.
> [^[[0;32m OK ^[[0m] Listening on Syslog Socket.
> [^[[0;32m OK ^[[0m] Listening on fsck to fsckd communication Socket.
> Starting File System Check on Root Device...
> [^[[0;32m OK ^[[0m] Listening on udev Control Socket.
> [^[[0;32m OK ^[[0m] Started Dispatch Password Requests to Console Directory Watch.
> [^[[0;32m OK ^[[0m] Reached target Paths.
> [^[[0;32m OK ^[[0m] Reached target Encrypted Volumes.
> [^[[0;32m OK ^[[0m] Reached target Slices.
> Mounting POSIX Message Queue File System...
> [^[[0;32m OK ^[[0m] Listening on Journal Socket (/dev/log).
> Starting Journal Service...
> [^[[0;32m OK ^[[0m] Mounted POSIX Message Queue File System.
> [^[[0;32m OK ^[[0m] Mounted Debug File System.
> [^[[0;32m OK ^[[0m] Mounted Huge Pages File System.
> [^[[0;32m OK ^[[0m] Started Load Kernel Modules.
> [^[[0;32m OK ^[[0m] Started Create list of required sta…vice nodes for the current kernel.
> [^[[0;32m OK ^[[0m] Started File System Check Daemon to report status.
> Starting Create Static Device Nodes in /dev...
> Checking in progress on 1 disk (0.0% complete)\r Starting Apply Kernel Variables...
> [^[[0;32m OK ^[[0m] Started Journal Service.
> [^[[0;32m OK ^[[0m] Started Apply Kernel Variables.
> [^[[0;32m OK ^[[0m] Started Create Static Device Nodes in /dev.
> Starting udev Kernel Device Manager...
> Checking in progress on 1 disk (0.9% complete)\r[^[[0;32m OK ^[[0m] Started udev Kernel Device Manager.
> Checking in progress on 1 disk (1.9% complete)\r\rChecking in progress on 1 disk (14.9% complete)\r\rChecking in progress on 1 disk (15.9% complete)\r\rChecking in progress on 1 disk (29.9% complete)\r\rChecking in progress on 1 disk (30.8% complete)\r\rChecking in progress on 1 disk (44.8% complete)\r\rChecking in progress on 1 disk (45.7% complete)\r\rChecking in progress on 1 disk (70.0% complete)\r\rChecking in progress on 1 disk (70.0% complete)\r\rChecking in progress on 1 disk (70.1% complete)\r\rChecking in progress on 1 disk (70.4% complete)\r\rChecking in progress on 1 disk (70.6% complete)\r\rChecking in progress on 1 disk (70.9% complete)\r\rChecking in progress on 1 disk (71.5% complete)\r\rChecking in progress on 1 disk (71.6% complete)\r\rChecking in progress on 1 disk (72.1% complete)\r\rChecking in progress on 1 disk (72.4% complete)\r\rChecking in progress on 1 disk (72.9% complete)\r\rChecking in progress on 1 disk (73.2% complete)\r\rChecking in progress on 1 disk (73.6% complete)\r\rChecking in progress on 1 disk (73.8% complete)\r\rChecking in progress on 1 disk (74.2% complete)\r\rChecking in progress on 1 disk (74.6% complete)\r\rChecking in progress on 1 disk (75.3% complete)\r\rChecking in progress on 1 disk (76.0% complete)\r\rChecking in progress on 1 disk (76.0% complete)\r\rChecking in progress on 1 disk (76.0% complete)\r\rChecking in progress on 1 disk (76.3% complete)\r\rChecking in progress on 1 disk (76.6% complete)\r\rChecking in progress on 1 disk (77.1% complete)\r\rChecking in progress on 1 disk (77.4% complete)\r\rChecking in progress on 1 disk (77.8% complete)\r\rChecking in progress on 1 disk (78.1% complete)\r\rChecking in progress on 1 disk (78.5% complete)\r\rChecking in progress on 1 disk (79.0% complete)\r\rChecking in progress on 1 disk (79.3% complete)\r\rChecking in progress on 1 disk (79.7% complete)\r\rChecking in progress on 1 disk (80.0% complete)\r\rChecking in progress on 1 disk (80.1% complete)\r\rChecking in progress on 1 disk (80.6% complete)\r\rChecking in progress on 1 disk (80.6% complete)\r\rChecking in progress on 1 disk (81.1% complete)\r\rChecking in progress on 1 disk (81.5% complete)\r\rChecking in progress on 1 disk (81.8% complete)\r\rChecking in progress on 1 disk (82.2% complete)\r\rChecking in progress on 1 disk (82.5% complete)\r\rChecking in progress on 1 disk (83.0% complete)\r\rChecking in progress on 1 disk (83.3% complete)\r\rChecking in progress on 1 disk (83.6% complete)\r\rChecking in progress on 1 disk (83.9% complete)\r\rChecking in progress on 1 disk (84.3% complete)\r\rChecking in progress on 1 disk (84.6% complete)\r\rChecking in progress on 1 disk (84.8% complete)\r\rChecking in progress on 1 disk (85.1% complete)\r\rChecking in progress on 1 disk (85.1% complete)\r\rChecking in progress on 1 disk (85.4% complete)\r\rChecking in progress on 1 disk (85.9% complete)\r\rChecking in progress on 1 disk (86.6% complete)\r\rChecking in progress on 1 disk (87.2% complete)\r\rChecking in progress on 1 disk (87.5% complete)\r\rChecking in progress on 1 disk (87.7% complete)\r\rChecking in progress on 1 disk (88.1% complete)\r\rChecking in progress on 1 disk (88.2% complete)\r\rChecking in progress on 1 disk (88.7% complete)\r\rChecking in progress on 1 disk (89.0% complete)\r\rChecking in progress on 1 disk (89.4% complete)\r\rChecking in progress on 1 disk (89.5% complete)\r\rChecking in progress on 1 disk (89.7% complete)\r\rChecking in progress on 1 disk (89.9% complete)\r\rChecking in progress on 1 disk (90.1% complete)\r\rChecking in progress on 1 disk (90.3% complete)\r\rChecking in progress on 1 disk (90.4% complete)\r\rChecking in progress on 1 disk (90.6% complete)\r\rChecking in progress on 1 disk (90.8% complete)\r\rChecking in progress on 1 disk (90.9% complete)\r\rChecking in progress on 1 disk (91.1% complete)\r\rChecking in progress on 1 disk (91.3% complete)\r\rChecking in progress on 1 disk (91.4% complete)\r\rChecking in progress on 1 disk (91.6% complete)\r\rChecking in progress on 1 disk (91.8% complete)\r\rChecking in progress on 1 disk (91.9% complete)\r\rChecking in progress on 1 disk (92.6% complete)\r\rChecking in progress on 1 disk (93.6% complete)\r\rChecking in progress on 1 disk (94.6% complete)\r\rChecking in progress on 1 disk (96.6% complete)\r\rChecking in progress on 1 disk (97.5% complete)\r\rChecking in progress on 1 disk (100.0% complete)\r\rChecking in progress on 0 disks (100.0% complete)\r[^[[0;32m OK ^[[0m] Started File System Check on Root Device.
> Starting Remount Root and Kernel File Systems...
> [ 12.588657] EXT4-fs (mmcblk1p2): re-mounted. Opts: errors=remount-ro
> [^[[0;32m OK ^[[0m] Started Remount Root and Kernel File Systems.
> [^[[0;32m OK ^[[0m] Reached target Local File Systems (Pre).
> Starting Load/Save Random Seed...
> Starting udev Coldplug all Devices...
> Starting Flush Journal to Persistent Storage...
> [^[[0;32m OK ^[[0m] Started Load/Save Random Seed.
> [ 12.695677] systemd-journald[186]: Received request to flush runtime journal from PID 1
> [^[[0;32m OK ^[[0m] Started Flush Journal to Persistent Storage.
> [ 12.973592] tegra-i2c 7000c700.i2c: could not find pctldev for node /host1x@50000000/dpaux@54040000/pinmux-i2c, deferring probe
> [^[[0;32m OK ^[[0m] Started udev Coldplug all Devices.
> [^[[0;32m OK ^[[0m] Found device /dev/ttyS0.
> [ 13.099280] 0-002c supply power not found, using dummy regulator
> [ 13.120275] tegra-i2c 7000c700.i2c: could not find pctldev for node /host1x@50000000/dpaux@54040000/pinmux-i2c, deferring probe
> [ 13.144235] [drm] Initialized
> [ 13.156912] lp855x 0-002c: failed to read 0x00
> [ 13.161383] lp855x 0-002c: pre init device err: -121
> [ 13.166359] lp855x 0-002c: device config err: -121
> [ 13.247809] lp855x: probe of 0-002c failed with error -121
> [ 13.294459] tegra-i2c 7000c700.i2c: could not find pctldev for node /host1x@50000000/dpaux@54040000/pinmux-i2c, deferring probe
> [ 13.307581] 54040000.dpaux supply vdd not found, using dummy regulator
> [ 13.332329] tegra-sor 54580000.sor: failed to probe output: -517
> [ 13.367740] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
> [ 13.374368] [drm] No driver support for vblank timestamp query.
> [ 13.447475] drm drm: No connectors reported connected with modes
> [ 13.463272] [drm] Cannot find any crtc or sizes - going 1024x768
> [ 13.507394] drm drm: fb0: frame buffer device
> [ 13.559928] [drm] Initialized tegra 0.0.0 20120330 on minor 0
> [ 13.589237] usbcore: registered new interface driver r8152
> [ 13.633884] usbcore: registered new interface driver cdc_ether
> [^[[0;32m OK ^[[0m] Found device /dev/disk/by-uuid/98a72b13-a480-4b9a-8627-04fd7dc8ff7d.
> [ 13.728119] usb 1-2: reset high-speed USB device number 4 using tegra-xusb
> [ 13.876813] 57000000.gpu supply vdd not found, using dummy regulator
> [^[[0;32m OK ^[[0m] Found device /dev/disk/by-uuid/eac8dd58-c636-472d-89e5-79272fd02d9e.
> [^[[0;32m OK ^[[0m] Found device /dev/disk/by-uuid/b2b4d279-7f34-4d03-b42a-b75c63d70f62.
> [ 13.914076] tegra-mc 70019000.memory-controller: gpuswr: write @0x00000003fffffff0: Page fault (SMMU translation error [--S])
> [^[[0;32m OK ^[[0m] Found device /dev/mmcblk1p3.
> [ 13.965144] alg: No test for __ecb-aes-ce (__driver-ecb-aes-ce)
> [ 13.981054] asix 1-3:1.0 eth0: register 'asix' at usb-70090000.usb-3, ASIX AX88772 USB 2.0 Ethernet, 00:50:b6:13:69:26
> [ 14.025201] r8152 1-2:1.0 eth1: v1.08.6
[-- Attachment #2: signature.asc --]
[-- Type: application/pgp-signature, Size: 833 bytes --]
^ permalink raw reply [flat|nested] 5+ messages in thread
* Re: Linux hangs on Jetson TX1: Page fault (SMMU translation error [--S])
[not found] ` <20170102160844.GA18393-EkSeR96xj6Pcmrwk2tT4+A@public.gmane.org>
@ 2017-01-03 1:03 ` Martin Michlmayr
[not found] ` <20170103010317.GA1373-IRMp5PtgtdoigzW5ZibpNwC/G2K4zDHf@public.gmane.org>
0 siblings, 1 reply; 5+ messages in thread
From: Martin Michlmayr @ 2017-01-03 1:03 UTC (permalink / raw)
To: Thierry Reding
Cc: Alexandre Courbot, linux-tegra-u79uwXL29TY76Z2rM5mHXA, Alexandre Courbot
* Thierry Reding <thierry.reding-Re5JQEeQqe8AvxtiuMwx3w@public.gmane.org> [2017-01-02 17:08]:
> Martin, one thing to check might be if your .config enables the
> TEGRA_IOMMU_SMMU option.
Yes, it's built-in.
I disabled TEGRA_IOMMU_SMMU (but left TEGRA_MC=y) and now I get:
[ 6.556591] tegra-mc 70019000.memory-controller: gpusrd: read @0x0000000000000000: EMEM address decode error (EMEM decode error)
[ 6.568158] tegra-mc 70019000.memory-controller: gpusrd: read @0x0000000000000000: Page fault (EMEM decode error)
Ok, this is interesting. With both TEGRA_MC and TEGRA_IOMMU_SMMU
disabled, the boot process hangs. Unfortunately I don't have time
to investigate more right now.
> Perhaps you can also regenerate a log with drm.debug=0x3f on the
> kernel command-line. Maybe throw in a NOUVEAU_DEBUG_DEFAULT=5 in
> .config to make sure Nouveau gives us as much info as possible (=7
> should give more, but it might be a little overkill).
Maybe I made a mistake, but I didn't get any more verbose logs with
drm.debug=0x3f and NOUVEAU_DEBUG_DEFAULT=5.
Let me know if you can reproduce the (original) problem.
--
Martin Michlmayr
http://www.cyrius.com/
^ permalink raw reply [flat|nested] 5+ messages in thread
* Re: Linux hangs on Jetson TX1: Page fault (SMMU translation error [--S])
[not found] ` <20170103010317.GA1373-IRMp5PtgtdoigzW5ZibpNwC/G2K4zDHf@public.gmane.org>
@ 2017-01-03 22:47 ` Martin Michlmayr
[not found] ` <20170103224743.GB23466-IRMp5PtgtdoigzW5ZibpNwC/G2K4zDHf@public.gmane.org>
0 siblings, 1 reply; 5+ messages in thread
From: Martin Michlmayr @ 2017-01-03 22:47 UTC (permalink / raw)
To: Thierry Reding
Cc: Alexandre Courbot, linux-tegra-u79uwXL29TY76Z2rM5mHXA, Alexandre Courbot
* Martin Michlmayr <tbm-R+vWnYXSFMfQT0dZR+AlfA@public.gmane.org> [2017-01-02 17:03]:
> the boot process hangs.
Ok, so I figured it out. It's because this commit is missing in 4.9:
5e6b9a8 arm64: tegra: Add VDD_GPU regulator to Jetson TX1
> [as pointed out by Thierry on IRC, nobody has reported a bug
> in the field, but using a new bootloader with a .dtb that
> has the incorrect data, it will crash on boot]
When I apply this patch to 4.9, the system boots successfully. The
original tegra-mc page fault is also gone.
So looks like it's just a matter of waiting for 4.9.1.
--
Martin Michlmayr
http://www.cyrius.com/
^ permalink raw reply [flat|nested] 5+ messages in thread
* Re: Linux hangs on Jetson TX1: Page fault (SMMU translation error [--S])
[not found] ` <20170103224743.GB23466-IRMp5PtgtdoigzW5ZibpNwC/G2K4zDHf@public.gmane.org>
@ 2017-01-05 9:32 ` Alexandre Courbot
0 siblings, 0 replies; 5+ messages in thread
From: Alexandre Courbot @ 2017-01-05 9:32 UTC (permalink / raw)
To: Martin Michlmayr, Thierry Reding
Cc: Alexandre Courbot, linux-tegra-u79uwXL29TY76Z2rM5mHXA
On 01/04/2017 07:47 AM, Martin Michlmayr wrote:
> * Martin Michlmayr <tbm-R+vWnYXSFMfQT0dZR+AlfA@public.gmane.org> [2017-01-02 17:03]:
>> the boot process hangs.
>
> Ok, so I figured it out. It's because this commit is missing in 4.9:
> 5e6b9a8 arm64: tegra: Add VDD_GPU regulator to Jetson TX1
>
>> [as pointed out by Thierry on IRC, nobody has reported a bug
>> in the field, but using a new bootloader with a .dtb that
>> has the incorrect data, it will crash on boot]
>
> When I apply this patch to 4.9, the system boots successfully. The
> original tegra-mc page fault is also gone.
>
> So looks like it's just a matter of waiting for 4.9.1.
This seems to be a bug anyway - if the regulator is missing, probing of
the GPU should fail gracefully and not trigger errors like the one you got.
Looking at what is going wrong there, thanks for the report and for
finding the root cause!
^ permalink raw reply [flat|nested] 5+ messages in thread
end of thread, other threads:[~2017-01-05 9:32 UTC | newest]
Thread overview: 5+ messages (download: mbox.gz / follow: Atom feed)
-- links below jump to the message on this page --
2016-12-18 3:27 Linux hangs on Jetson TX1: Page fault (SMMU translation error [--S]) Martin Michlmayr
[not found] ` <20161218032759.GA6457-IRMp5PtgtdoigzW5ZibpNwC/G2K4zDHf@public.gmane.org>
2017-01-02 16:08 ` Thierry Reding
[not found] ` <20170102160844.GA18393-EkSeR96xj6Pcmrwk2tT4+A@public.gmane.org>
2017-01-03 1:03 ` Martin Michlmayr
[not found] ` <20170103010317.GA1373-IRMp5PtgtdoigzW5ZibpNwC/G2K4zDHf@public.gmane.org>
2017-01-03 22:47 ` Martin Michlmayr
[not found] ` <20170103224743.GB23466-IRMp5PtgtdoigzW5ZibpNwC/G2K4zDHf@public.gmane.org>
2017-01-05 9:32 ` Alexandre Courbot
This is an external index of several public inboxes,
see mirroring instructions on how to clone and mirror
all data and code used by this external index.