U-Boot SPL 2012.07-00012-g391a28d (Dec 30 2012 - 17:40:39) OMAP4460 ES1.1 OMAP SD/MMC: 0 reading u-boot.img reading u-boot.bin mkimage signature not found - ih_magic = ea000014 reading u-boot.bin U-Boot 2012.07-00012-g391a28d (Dec 30 2012 - 17:40:39) CPU : OMAP4460 ES1.1 Board: OMAP4 Variscite SOM I2C: ready DRAM: 1 GiB MMC: OMAP SD/MMC: 0 Using default environment In: serial Out: serial Err: serial Net: Net Initialization Skipped No ethernet found. checking for preEnv.txt reading preEnv.txt 0 bytes read Loaded environment from preEnv.txt Importing preboot environment from mmc ... Hit any key to stop autoboot: 3  2  0 VAR-SOM-OM44 # VAR-SOM-OM44 # setenv bootargs 'console=ttyO2,115200n8 mem=1G@0x80000000 root=/dev/mmcblk1p2 ro rootwait earlyprintk ignore_loglevel fixrtc phy.smsc.gb_enable=false video=HDMI-A-1:1920x1080@60' VAR-SOM-OM44 # loady 0x81000000 ## Ready for binary (ymodem) download to 0x81000000 at 115200 bps... CSending: uImage-dtb.omap4-var-stk-om44 Sending: Transfer complete em - CRC mode, 8(SOH)/3556(STX)/0(CAN) packets, 3 retries ## Total Size = 0x003792a1 = 3642017 Bytes VAR-SOM-OM44 # bootm 0x81000000 ## Booting kernel from Legacy Image at 81000000 ... Image Name: Linux- Image Type: ARM Linux Kernel Image (uncompressed) Data Size: 3641953 Bytes = 3.5 MiB Load Address: 80008000 Entry Point: 80008000 Verifying Checksum ... OK Loading Kernel Image ... OK OK Starting kernel ... [ 0.000000] Booting Linux on physical CPU 0x0 [ 0.000000] Initializing cgroup subsys cpuset [ 0.000000] Initializing cgroup subsys cpu [ 0.000000] Initializing cgroup subsys cpuacct [ 0.000000] Linux version 4.3.0-rc4-170966-g049e6dd (paul@knihy) (gcc version 4.7.2 (Debian 4.7.2-5) ) #1 SMP Sun Oct 4 14:36:43 MDT 2015 [ 0.000000] CPU: ARMv7 Processor [412fc09a] revision 10 (ARMv7), cr=10c5387d [ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache [ 0.000000] Machine model: Variscite VAR-STK-OM44 [ 0.000000] debug: ignoring loglevel setting. [ 0.000000] cma: Reserved 16 MiB at 0xbe800000 [ 0.000000] Memory policy: Data cache writealloc [ 0.000000] OMAP4: Map 0xbfe00000 to fe600000 for dram barrier [ 0.000000] On node 0 totalpages: 261632 [ 0.000000] free_area_init_node: node 0, pgdat c09c4380, node_mem_map eeef9000 [ 0.000000] Normal zone: 1710 pages used for memmap [ 0.000000] Normal zone: 0 pages reserved [ 0.000000] Normal zone: 194560 pages, LIFO batch:31 [ 0.000000] HighMem zone: 67072 pages, LIFO batch:15 [ 0.000000] OMAP4460 ES1.1 [ 0.000000] PERCPU: Embedded 14 pages/cpu @eee94000 s25088 r8192 d24064 u57344 [ 0.000000] pcpu-alloc: s25088 r8192 d24064 u57344 alloc=14*4096 [ 0.000000] pcpu-alloc: [0] 0 [0] 1 [ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 259922 [ 0.000000] Kernel command line: console=ttyO2,115200n8 mem=1G@0x80000000 root=/dev/mmcblk1p2 ro rootwait earlyprintk ignore_loglevel fixrtc phy.smsc.gb_enable=false video=HDMI-A-1:1920x1080@60 [ 0.000000] PID hash table entries: 4096 (order: 2, 16384 bytes) [ 0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes) [ 0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes) [ 0.000000] Memory: 1001372K/1046528K available (6566K kernel code, 744K rwdata, 2228K rodata, 452K init, 8263K bss, 28772K reserved, 16384K cma-reserved, 251904K highmem) [ 0.000000] Virtual kernel memory layout: [ 0.000000] vector : 0xffff0000 - 0xffff1000 ( 4 kB) [ 0.000000] fixmap : 0xffc00000 - 0xfff00000 (3072 kB) [ 0.000000] vmalloc : 0xf0000000 - 0xff000000 ( 240 MB) [ 0.000000] lowmem : 0xc0000000 - 0xef800000 ( 760 MB) [ 0.000000] pkmap : 0xbfe00000 - 0xc0000000 ( 2 MB) [ 0.000000] modules : 0xbf000000 - 0xbfe00000 ( 14 MB) [ 0.000000] .text : 0xc0008000 - 0xc089ec78 (8796 kB) [ 0.000000] .init : 0xc089f000 - 0xc0910000 ( 452 kB) [ 0.000000] .data : 0xc0910000 - 0xc09ca318 ( 745 kB) [ 0.000000] .bss : 0xc09cd000 - 0xc11dee78 (8264 kB) [ 0.000000] Running RCU self tests [ 0.000000] Hierarchical RCU implementation. [ 0.000000] RCU lockdep checking is enabled. [ 0.000000] Build-time adjustment of leaf fanout to 32. [ 0.000000] NR_IRQS:16 nr_irqs:16 16 [ 0.000000] L2C: platform modifies aux control register: 0x0e070000 -> 0x3e470000 [ 0.000000] L2C: DT/platform modifies aux control register: 0x0e070000 -> 0x3e470000 [ 0.000000] L2C-310 enabling early BRESP for Cortex-A9 [ 0.000000] OMAP L2C310: ROM does not support power control setting [ 0.000000] L2C-310 dynamic clock gating disabled, standby mode disabled [ 0.000000] L2C-310 cache controller enabled, 16 ways, 1024 kB [ 0.000000] L2C-310: CACHE_ID 0x410000c7, AUX_CTRL 0x4e470000 [ 0.000000] ti_dt_clocks_register: failed to lookup clock node dss_fck [ 0.000000] ti_dt_clocks_register: failed to lookup clock node dss_fck [ 0.000000] ti_dt_clocks_register: failed to lookup clock node bandgap_fclk [ 0.000000] OMAP clockevent source: timer1 at 32768 Hz [ 0.000000] clocksource: 32k_counter: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 58327039986419 ns [ 0.000000] sched_clock: 32 bits at 32kHz, resolution 30517ns, wraps every 65535999984741ns [ 0.000000] OMAP clocksource: 32k_counter at 32768 Hz [ 0.002105] Console: colour dummy device 80x30 [ 0.002166] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar [ 0.002166] ... MAX_LOCKDEP_SUBCLASSES: 8 [ 0.002166] ... MAX_LOCK_DEPTH: 48 [ 0.002197] ... MAX_LOCKDEP_KEYS: 8191 [ 0.002197] ... CLASSHASH_SIZE: 4096 [ 0.002197] ... MAX_LOCKDEP_ENTRIES: 32768 [ 0.002197] ... MAX_LOCKDEP_CHAINS: 65536 [ 0.002227] ... CHAINHASH_SIZE: 32768 [ 0.002227] memory used by lock dependency info: 5167 kB [ 0.002227] per task-struct memory footprint: 1536 bytes [ 0.002258] Calibrating delay loop... 1397.55 BogoMIPS (lpj=6987776) [ 0.080291] pid_max: default: 32768 minimum: 301 [ 0.080688] Security Framework initialized [ 0.080841] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes) [ 0.080841] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes) [ 0.084167] Initializing cgroup subsys io [ 0.084259] Initializing cgroup subsys memory [ 0.084381] Initializing cgroup subsys devices [ 0.084472] Initializing cgroup subsys freezer [ 0.084594] Initializing cgroup subsys perf_event [ 0.084655] CPU: Testing write buffer coherency: ok [ 0.086120] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 [ 0.086212] Setting up static identity map for 0x80008280 - 0x800082f0 [ 0.180297] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 [ 0.181060] Brought up 2 CPUs [ 0.181060] SMP: Total of 2 processors activated (2795.11 BogoMIPS). [ 0.181091] CPU: All CPU(s) started in SVC mode. [ 0.184509] devtmpfs: initialized [ 0.240386] VFP support v0.3: implementor 41 architecture 3 part 30 variant 9 rev 4 [ 0.245391] omap_hwmod: l3_main_3 using broken dt data from ocp [ 0.249969] omap_hwmod: l3_main_2 using broken dt data from ocp [ 0.432708] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns [ 0.435333] pinctrl core: initialized pinctrl subsystem [ 0.440795] NET: Registered protocol family 16 [ 0.446166] DMA: preallocated 256 KiB pool for atomic coherent allocations [ 0.470489] cpuidle: using governor ladder [ 0.500366] cpuidle: using governor menu [ 0.514221] gpiochip_add: registered GPIOs 0 to 31 on device: gpio [ 0.515075] OMAP GPIO hardware version 0.1 [ 0.516326] gpiochip_add: registered GPIOs 32 to 63 on device: gpio [ 0.518280] gpiochip_add: registered GPIOs 64 to 95 on device: gpio [ 0.520172] gpiochip_add: registered GPIOs 96 to 127 on device: gpio [ 0.522125] gpiochip_add: registered GPIOs 128 to 159 on device: gpio [ 0.524017] gpiochip_add: registered GPIOs 160 to 191 on device: gpio [ 0.545349] platform 4b501000.aes: Cannot lookup hwmod 'aes' [ 0.545898] platform 480a5000.des: Cannot lookup hwmod 'des' [ 0.554046] No ATAGs? [ 0.554138] hw-breakpoint: found 5 (+1 reserved) breakpoint and 1 watchpoint registers. [ 0.554138] hw-breakpoint: maximum watchpoint size is 4 bytes. [ 0.557342] OMAP DMA hardware revision 0.0 [ 0.615570] omap-dma-engine 4a056000.dma-controller: OMAP DMA engine driver [ 0.616607] of_get_named_gpiod_flags: can't parse 'gpio' property of node '/fixedregulator-vbat[0]' [ 0.617553] reg-fixed-voltage wl12xx_vmmc: could not find pctldev for node /ocp/l4@4a000000/scm@100000/pinmux@40/pinmux_wl12xx_ctrl_pins, deferring probe [ 0.621826] SCSI subsystem initialized [ 0.622375] libata version 3.00 loaded. [ 0.623077] usb_phy_generic hsusb1_phy: could not find pctldev for node /ocp/l4@4a000000/l4@300000/pinmux@1e040/pinmux_hsusbb1_phy_clk_pins, deferring probe [ 0.623626] omap_i2c 48070000.i2c: could not find pctldev for node /ocp/l4@4a000000/scm@100000/pinmux@40/pinmux_i2c1_pins, deferring probe [ 0.623718] omap_i2c 48060000.i2c: could not find pctldev for node /ocp/l4@4a000000/scm@100000/pinmux@40/pinmux_i2c3_pins, deferring probe [ 0.623840] omap_i2c 48350000.i2c: could not find pctldev for node /ocp/l4@4a000000/scm@100000/pinmux@40/pinmux_i2c4_pins, deferring probe [ 0.624176] pps_core: LinuxPPS API ver. 1 registered [ 0.624206] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.624267] PTP clock support registered [ 0.628570] clocksource: Switched to clocksource 32k_counter [ 0.769958] NET: Registered protocol family 2 [ 0.772064] TCP established hash table entries: 8192 (order: 3, 32768 bytes) [ 0.772277] TCP bind hash table entries: 8192 (order: 6, 294912 bytes) [ 0.774810] TCP: Hash tables configured (established 8192 bind 8192) [ 0.775146] UDP hash table entries: 512 (order: 3, 40960 bytes) [ 0.775482] UDP-Lite hash table entries: 512 (order: 3, 40960 bytes) [ 0.776580] NET: Registered protocol family 1 [ 0.778259] RPC: Registered named UNIX socket transport module. [ 0.778259] RPC: Registered udp transport module. [ 0.778289] RPC: Registered tcp transport module. [ 0.778289] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.783691] hw perfevents: enabled with armv7_cortex_a9 PMU driver, 7 counters available [ 0.789276] futex hash table entries: 512 (order: 3, 32768 bytes) [ 0.789581] audit: initializing netlink subsys (disabled) [ 0.789855] audit: type=2000 audit(0.770:1): initialized [ 0.793579] VFS: Disk quotas dquot_6.6.0 [ 0.793884] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) [ 0.796569] NFS: Registering the id_resolver key type [ 0.797088] Key type id_resolver registered [ 0.797088] Key type id_legacy registered [ 0.797302] jffs2: version 2.2. (NAND) (SUMMARY) © 2001-2006 Red Hat, Inc. [ 0.802703] bounce: pool size: 64 pages [ 0.802825] io scheduler noop registered [ 0.802856] io scheduler deadline registered [ 0.802917] io scheduler cfq registered (default) [ 0.806518] pinctrl-single 4a100040.pinmux: 203 pins at pa fc100040 size 406 [ 0.807250] pinctrl-single 4a31e040.pinmux: 28 pins at pa fc31e040 size 56 [ 0.813690] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 0.819732] omap_uart 4806a000.serial: no wakeirq for uart0 [ 0.819732] of_get_named_gpiod_flags: can't parse 'rts-gpio' property of node '/ocp/serial@4806a000[0]' [ 0.820251] 4806a000.serial: ttyO0 at MMIO 0x4806a000 (irq = 223, base_baud = 3000000) is a OMAP UART0 [ 0.822113] omap_uart 4806c000.serial: no wakeirq for uart1 [ 0.822113] of_get_named_gpiod_flags: can't parse 'rts-gpio' property of node '/ocp/serial@4806c000[0]' [ 0.822387] 4806c000.serial: ttyO1 at MMIO 0x4806c000 (irq = 224, base_baud = 3000000) is a OMAP UART1 [ 0.823547] omap_uart 48020000.serial: no wakeirq for uart2 [ 0.823547] of_get_named_gpiod_flags: can't parse 'rts-gpio' property of node '/ocp/serial@48020000[0]' [ 0.823822] 48020000.serial: ttyO2 at MMIO 0x48020000 (irq = 225, base_baud = 3000000) is a OMAP UART2 [ 1.839294] console [ttyO2] enabled [ 1.876281] brd: module loaded [ 1.901336] loop: module loaded [ 1.907653] mtdoops: mtd device (mtddev=name/number) must be supplied [ 1.921417] of_get_named_gpiod_flags: can't parse 'reset-gpios' property of node '/ocp/spi@48098000/eth@0[0]' [ 1.931854] spi1.0 supply vdd-io not found, using dummy regulator [ 1.938751] spi1.0 supply vdd not found, using dummy regulator [ 1.945037] ks8851 spi1.0: message enable is 0 [ 1.956146] ks8851 spi1.0 eth0: revision 1, MAC e6:7e:5c:1d:4b:73, IRQ 202, no EEPROM [ 1.968780] mousedev: PS/2 mouse device common for all mice [ 1.974639] i2c /dev entries driver [ 1.979919] omap_hsmmc 4809c000.mmc: GPIO lookup for consumer cd [ 1.986206] omap_hsmmc 4809c000.mmc: using device tree for GPIO lookup [ 1.993103] of_get_named_gpiod_flags: can't parse 'cd-gpios' property of node '/ocp/mmc@4809c000[0]' [ 2.002685] of_get_named_gpiod_flags: can't parse 'cd-gpio' property of node '/ocp/mmc@4809c000[0]' [ 2.012176] omap_hsmmc 4809c000.mmc: using lookup tables for GPIO lookup [ 2.019378] omap_hsmmc 4809c000.mmc: lookup for GPIO cd failed [ 2.025482] omap_hsmmc 4809c000.mmc: GPIO lookup for consumer wp [ 2.031799] omap_hsmmc 4809c000.mmc: using device tree for GPIO lookup [ 2.038665] of_get_named_gpiod_flags: can't parse 'wp-gpios' property of node '/ocp/mmc@4809c000[0]' [ 2.048248] of_get_named_gpiod_flags: can't parse 'wp-gpio' property of node '/ocp/mmc@4809c000[0]' [ 2.057739] omap_hsmmc 4809c000.mmc: using lookup tables for GPIO lookup [ 2.064788] omap_hsmmc 4809c000.mmc: lookup for GPIO wp failed [ 2.100616] omap_hsmmc 480d1000.mmc: GPIO lookup for consumer wp [ 2.106933] omap_hsmmc 480d1000.mmc: using device tree for GPIO lookup [ 2.113800] of_get_named_gpiod_flags: can't parse 'wp-gpios' property of node '/ocp/mmc@480d1000[0]' [ 2.123382] of_get_named_gpiod_flags: can't parse 'wp-gpio' property of node '/ocp/mmc@480d1000[0]' [ 2.132904] omap_hsmmc 480d1000.mmc: using lookup tables for GPIO lookup [ 2.139923] omap_hsmmc 480d1000.mmc: lookup for GPIO wp failed [ 2.169006] omap_hsmmc 480d5000.mmc: GPIO lookup for consumer cd [ 2.175292] omap_hsmmc 480d5000.mmc: using device tree for GPIO lookup [ 2.182220] of_get_named_gpiod_flags: parsed 'cd-gpios' property of node '/ocp/mmc@480d5000[0]' - status (0) [ 2.192596] omap_hsmmc 480d5000.mmc: Got CD GPIO [ 2.197448] omap_hsmmc 480d5000.mmc: GPIO lookup for consumer wp [ 2.203735] omap_hsmmc 480d5000.mmc: using device tree for GPIO lookup [ 2.210601] of_get_named_gpiod_flags: can't parse 'wp-gpios' property of node '/ocp/mmc@480d5000[0]' [ 2.220184] of_get_named_gpiod_flags: can't parse 'wp-gpio' property of node '/ocp/mmc@480d5000[0]' [ 2.229675] omap_hsmmc 480d5000.mmc: using lookup tables for GPIO lookup [ 2.236694] omap_hsmmc 480d5000.mmc: lookup for GPIO wp failed [ 2.279602] ledtrig-cpu: registered to indicate activity on CPUs [ 2.287170] oprofile: using arm/armv7-ca9 [ 2.292266] Initializing XFRM netlink socket [ 2.297241] NET: Registered protocol family 10 [ 2.305084] sit: IPv6 over IPv4 tunneling driver [ 2.312225] NET: Registered protocol family 17 [ 2.316955] NET: Registered protocol family 15 [ 2.322052] Key type dns_resolver registered [ 2.326812] twl: not initialized [ 2.330230] twl6030_uv_to_vsel:OUT OF RANGE! non mapped vsel for 1375000 Vs max 1316660 [ 2.338623] twl6030_uv_to_vsel:OUT OF RANGE! non mapped vsel for 1375000 Vs max 1316660 [ 2.347045] twl6030_uv_to_vsel:OUT OF RANGE! non mapped vsel for 1375000 Vs max 1316660 [ 2.355438] twl6030_uv_to_vsel:OUT OF RANGE! non mapped vsel for 1375000 Vs max 1316660 [ 2.363861] twl6030_uv_to_vsel:OUT OF RANGE! non mapped vsel for 1375000 Vs max 1316660 [ 2.372283] twl6030_uv_to_vsel:OUT OF RANGE! non mapped vsel for 1375000 Vs max 1316660 [ 2.380676] twl6030_uv_to_vsel:OUT OF RANGE! non mapped vsel for 1410000 Vs max 1316660 [ 2.389129] omap2_set_init_voltage: unable to find boot up OPP for vdd_mpu [ 2.396331] omap2_set_init_voltage: unable to set vdd_mpu [ 2.402008] omap2_set_init_voltage: unable to find boot up OPP for vdd_core [ 2.409332] omap2_set_init_voltage: unable to set vdd_core [ 2.415100] omap2_set_init_voltage: unable to find boot up OPP for vdd_iva [ 2.422332] omap2_set_init_voltage: unable to set vdd_iva [ 2.430297] Power Management for TI OMAP4+ devices. [ 2.435394] OMAP4 PM: u-boot >= v2012.07 is required for full PM support [ 2.442779] ThumbEE CPU extension supported. [ 2.448455] Registering SWP/SWPB emulation handler [ 2.448455] SmartReflex Class3 initialized [ 2.462829] of_get_named_gpiod_flags: parsed 'gpio' property of node '/wl12xx_vmmc[0]' - status (0) [ 2.474487] usb_phy_generic hsusb1_phy: GPIO lookup for consumer reset [ 2.481781] usb_phy_generic hsusb1_phy: using device tree for GPIO lookup [ 2.488983] of_get_named_gpiod_flags: parsed 'reset-gpios' property of node '/hsusb1_phy[0]' - status (0) [ 2.499053] no flags found for reset [ 2.499114] usb_phy_generic hsusb1_phy: GPIO lookup for consumer vbus-detect [ 2.510223] usb_phy_generic hsusb1_phy: using device tree for GPIO lookup [ 2.517333] of_get_named_gpiod_flags: can't parse 'vbus-detect-gpios' property of node '/hsusb1_phy[0]' [ 2.527191] of_get_named_gpiod_flags: can't parse 'vbus-detect-gpio' property of node '/hsusb1_phy[0]' [ 2.536956] usb_phy_generic hsusb1_phy: using lookup tables for GPIO lookup [ 2.544281] usb_phy_generic hsusb1_phy: lookup for GPIO vbus-detect failed [ 2.558898] mmc0: host does not support reading read-only switch, assuming write-enable [ 2.560211] Skipping twl internal clock init and using bootloader value (unknown osc rate) [ 2.576599] hw-breakpoint: Failed to enable monitor mode on CPU 0. [ 2.584197] mmc0: new high speed SDHC card at address b368 [ 2.592834] mmcblk0: mmc0:b368 USD 3.75 GiB [ 2.598724] twl 0-0048: PIH (irq 327) nested IRQs [ 2.606536] mmcblk0: p1 p2 [ 2.623138] VUSIM: failed to get the current voltage(-22) [ 2.628845] twl_reg 48070000.i2c:twl@48:regulator-vusim: can't register VUSIM, -22 [ 2.640777] twl_reg: probe of 48070000.i2c:twl@48:regulator-vusim failed with error -22 [ 2.659667] twl6040 0-004b: clk32k is not handled [ 2.671051] of_get_named_gpiod_flags: parsed 'ti,audpwron-gpio' property of node '/ocp/i2c@48070000/twl@4b[0]' - status (0) [ 2.685974] omap_i2c 48070000.i2c: bus 0 rev0.11 at 400 kHz [ 2.694976] omap_i2c 48060000.i2c: bus 2 rev0.11 at 400 kHz [ 2.703674] omap_i2c 48350000.i2c: bus 3 rev0.11 at 400 kHz [ 2.709777] omap_hsmmc 4809c000.mmc: GPIO lookup for consumer cd [ 2.716766] omap_hsmmc 4809c000.mmc: using device tree for GPIO lookup [ 2.723632] of_get_named_gpiod_flags: can't parse 'cd-gpios' property of node '/ocp/mmc@4809c000[0]' [ 2.733215] of_get_named_gpiod_flags: can't parse 'cd-gpio' property of node '/ocp/mmc@4809c000[0]' [ 2.742706] omap_hsmmc 4809c000.mmc: using lookup tables for GPIO lookup [ 2.749755] omap_hsmmc 4809c000.mmc: lookup for GPIO cd failed [ 2.749786] omap_hsmmc 4809c000.mmc: GPIO lookup for consumer wp [ 2.762176] omap_hsmmc 4809c000.mmc: using device tree for GPIO lookup [ 2.769042] of_get_named_gpiod_flags: can't parse 'wp-gpios' property of node '/ocp/mmc@4809c000[0]' [ 2.778625] of_get_named_gpiod_flags: can't parse 'wp-gpio' property of node '/ocp/mmc@4809c000[0]' [ 2.788116] omap_hsmmc 4809c000.mmc: using lookup tables for GPIO lookup [ 2.788146] omap_hsmmc 4809c000.mmc: lookup for GPIO wp failed [ 2.801635] ------------[ cut here ]------------ [ 2.801635] WARNING: CPU: 1 PID: 65 at drivers/regulator/core.c:2104 _regulator_disable+0x140/0x184() [ 2.801635] unbalanced disables for pbias_mmc_omap4 [ 2.801635] Modules linked in: [ 2.825988] CPU: 1 PID: 65 Comm: kworker/u4:1 Not tainted 4.3.0-rc4-170966-g049e6dd #1 [ 2.834289] Hardware name: Generic OMAP4 (Flattened Device Tree) [ 2.840606] Workqueue: deferwq deferred_probe_work_func [ 2.840606] [] (unwind_backtrace) from [] (show_stack+0x10/0x14) [ 2.854278] [] (show_stack) from [] (dump_stack+0x80/0x9c) [ 2.861907] [] (dump_stack) from [] (warn_slowpath_common+0x78/0xb4) [ 2.861907] [] (warn_slowpath_common) from [] (warn_slowpath_fmt+0x30/0x40) [ 2.879516] [] (warn_slowpath_fmt) from [] (_regulator_disable+0x140/0x184) [ 2.888671] [] (_regulator_disable) from [] (regulator_disable+0x34/0x60) [ 2.888671] [] (regulator_disable) from [] (omap_hsmmc_set_power+0x158/0x1d4) [ 2.906921] [] (omap_hsmmc_set_power) from [] (omap_hsmmc_set_ios+0x204/0x264) [ 2.906921] [] (omap_hsmmc_set_ios) from [] (mmc_set_initial_state+0x48/0x4c) [ 2.925628] [] (mmc_set_initial_state) from [] (mmc_power_up+0x3c/0x104) [ 2.934509] [] (mmc_power_up) from [] (mmc_start_host+0x40/0x6c) [ 2.942657] [] (mmc_start_host) from [] (mmc_add_host+0x5c/0x84) [ 2.950805] [] (mmc_add_host) from [] (omap_hsmmc_probe+0x5cc/0xa10) [ 2.959289] [] (omap_hsmmc_probe) from [] (platform_drv_probe+0x48/0xa4) [ 2.968139] [] (platform_drv_probe) from [] (driver_probe_device+0x1dc/0x2a0) [ 2.977478] [] (driver_probe_device) from [] (bus_for_each_drv+0x44/0x8c) [ 2.986419] [] (bus_for_each_drv) from [] (__device_attach+0xa4/0x108) [ 2.995117] [] (__device_attach) from [] (bus_probe_device+0x88/0x90) [ 3.003692] [] (bus_probe_device) from [] (deferred_probe_work_func+0x60/0x90) [ 3.013092] [] (deferred_probe_work_func) from [] (process_one_work+0x1b4/0x4b0) [ 3.022674] [] (process_one_work) from [] (worker_thread+0x154/0x470) [ 3.031280] [] (worker_thread) from [] (kthread+0xd4/0xf0) [ 3.038879] [] (kthread) from [] (ret_from_fork+0x14/0x24) [ 3.038909] ---[ end trace 0712913210d404a7 ]--- [ 3.051300] omap_hsmmc 4809c000.mmc: pbias reg disable fail [ 3.068664] hw-breakpoint: CPU 0 failed to disable vector catch [ 3.089477] omap_hsmmc 480d1000.mmc: GPIO lookup for consumer wp [ 3.095764] omap_hsmmc 480d1000.mmc: using device tree for GPIO lookup [ 3.102813] of_get_named_gpiod_flags: can't parse 'wp-gpios' property of node '/ocp/mmc@480d1000[0]' [ 3.102844] of_get_named_gpiod_flags: can't parse 'wp-gpio' property of node '/ocp/mmc@480d1000[0]' [ 3.121887] omap_hsmmc 480d1000.mmc: using lookup tables for GPIO lookup [ 3.126556] mmc1: host does not support reading read-only switch, assuming write-enable [ 3.128936] mmc1: new high speed SDHC card at address 0007 [ 3.130462] mmcblk1: mmc1:0007 SD08G 7.42 GiB [ 3.132598] mmcblk1: p1 p2 [ 3.150665] omap_hsmmc 480d1000.mmc: lookup for GPIO wp failed [ 3.269775] hctosys: unable to open rtc device (rtc0) [ 3.276428] sr_init: No PMIC hook to init smartreflex [ 3.276428] smartreflex smartreflex.0: omap_sr_probe: SmartReflex driver initialized [ 3.290863] smartreflex smartreflex.1: omap_sr_probe: SmartReflex driver initialized [ 3.299438] smartreflex smartreflex.2: omap_sr_probe: SmartReflex driver initialized [ 3.310607] omap_hsmmc 480d1000.mmc: card claims to support voltages below defined range [ 3.334014] mmc2: queuing unknown CIS tuple 0x91 (3 bytes) [ 3.344207] mmc2: new SDIO card at address 0001 [ 3.351776] pbias_mmc_omap4: disabling [ 3.351776] vwl1271: disabling [ 3.362030] VAUX1_6030: disabling [ 3.367584] VMMC: disabling [ 3.372070] VDAC: disabling [ 3.375854] VANA: disabling [ 3.379699] VUSB: disabling [ 3.441802] mmcblk1: error -110 sending status command, retrying [ 3.474609] mmcblk1: error -110 sending status command, retrying [ 3.507476] mmcblk1: error -110 sending status command, aborting [ 3.598541] mmc1: tried to reset card [ 3.603637] EXT2-fs (mmcblk1p2): error: couldn't mount because of unsupported optional features (244) [ 3.616088] EXT4-fs (mmcblk1p2): couldn't mount as ext3 due to feature incompatibilities [ 3.649963] EXT4-fs (mmcblk1p2): INFO: recovery required on readonly filesystem [ 3.659912] EXT4-fs (mmcblk1p2): write access will be enabled during recovery [ 6.758666] EXT4-fs (mmcblk1p2): recovery complete [ 7.072418] EXT4-fs (mmcblk1p2): mounted filesystem with ordered data mode. Opts: (null) [ 7.081024] VFS: Mounted root (ext4 filesystem) readonly on device 179:10. [ 7.093688] devtmpfs: mounted [ 7.097381] Freeing unused kernel memory: 452K (c089f000 - c0910000) [ 7.408386] random: init urandom read with 29 bits of entropy available [ 7.429809] init: Failed to create pty - disabling logging for job [ 7.438110] init: Temporary process spawn error: No space left on device [ 7.490020] init: Failed to create pty - disabling logging for job [ 7.496551] init: Temporary process spawn error: No space left on device [ 7.525451] init: Failed to create pty - disabling logging for job [ 7.532653] init: Temporary process spawn error: No space left on device [ 7.545013] init: Failed to create pty - disabling logging for job [ 7.551879] init: Temporary process spawn error: No space left on device [ 7.643951] init: hwclock main process (84) terminated with status 1 [ 7.700988] init: ureadahead main process (85) terminated with status 5 [ 7.902496] init: Failed to create pty - disabling logging for job [ 7.909362] init: Temporary process spawn error: No space left on device [ 7.949890] init: Failed to create pty - disabling logging for job [ 7.956420] init: Temporary process spawn error: No space left on device [ 8.050140] init: Failed to create pty - disabling logging for job [ 8.056854] init: Temporary process spawn error: No space left on device [ 8.591735] udevd[140]: starting version 175 fsck from util-linux 2.20.1 /dev/mmcblk1p2: clean, 177404/963584 files, 2989767/7708672 blocks [ 11.807830] init: udev-fallback-graphics main process (404) terminated with status 1 [ 12.072326] EXT4-fs (mmcblk1p2): re-mounted. Opts: errors=remount-ro [ 12.112457] random: nonblocking pool is initialized [ 12.129364] init: plymouth main process (86) killed by SEGV signal [ 12.141815] init: plymouth-splash main process (426) terminated with status 2 mountall: Plymouth command failed mountall: Plymouth command failed mountall: Plymouth command failed mountall: Plymouth command failed mountall: Plymouth command failed mountall: Plymouth command failed [ 12.671234] init: plymouth-log main process (487) terminated with status 1 [ 12.701965] init: failsafe main process (490) killed by TERM signal [ 13.460510] init: plymouth-upstart-bridge main process (533) terminated with status 1 [ 13.706909] Adding 524232k swap on /SWAP.swap. Priority:-1 extents:13 across:577528k SS mountall: Plymouth command failed mountall: Plymouth command failed mountall: Plymouth command failed mountall: Plymouth command failed mountall: Disconnected from Plymouth [ 13.870056] init: bluetooth main process (543) terminated with status 1 [ 13.883666] init: bluetooth main process ended, respawning speech-dispatcher disabled; edit /etc/default/speech-dispatcher saned disabled; edit /etc/default/saned [ 14.446502] init: alsa-restore main process (581) terminated with status 19 [ 14.478515] init: hybrid-gfx main process (601) terminated with status 1 FATAL: Could not load /lib/modules/4.3.0-rc4-170966-g049e6dd/modules.dep: No such file or directory [ 15.087615] init: bluetooth main process (665) terminated with status 1 [ 15.103332] init: bluetooth main process ended, respawning [ 15.583160] init: bluetooth main process (699) terminated with status 1 [ 15.590911] init: bluetooth main process ended, respawning [ 15.802886] init: bluetooth main process (718) terminated with status 1 [ 15.810150] init: bluetooth main process ended, respawning [ 16.002136] init: bluetooth main process (735) terminated with status 1 [ 16.009277] init: bluetooth main process ended, respawning [ 16.261322] init: bluetooth main process (753) terminated with status 1 [ 16.277832] init: bluetooth main process ended, respawning Ubuntu 12.04.1 LTS variscite-desktop ttyO2 variscite-desktop login: [ 16.698516] init: bluetooth main process (783) terminated with status 1 [ 16.707733] init: bluetooth main process ended, respawning [ 16.803863] init: lightdm main process (639) terminated with status 1 [ 16.941650] init: bluetooth main process (801) terminated with status 1 [ 16.954650] init: bluetooth main process ended, respawning [ 17.147491] init: bluetooth main process (817) terminated with status 1 [ 17.154876] init: bluetooth main process ended, respawning [ 17.400299] init: bluetooth main process (833) terminated with status 1 [ 17.407379] init: bluetooth main process ended, respawning Ubuntu 12.04.1 LTS variscite-desktop ttyO2 variscite-desktop login: Ubuntu 12.04.1 LTS variscite-desktop ttyO2 variscite-desktop login: [ 17.628540] init: bluetooth main process (860) terminated with status 1 [ 17.639984] init: bluetooth respawning too fast, stopped root Last login: Thu Jan 1 02:00:17 IST 1970 on ttyO2 Welcome to Ubuntu 12.04.1 LTS (GNU/Linux 4.3.0-rc4-170966-g049e6dd armv7l) * Documentation: https://help.ubuntu.com/ root@variscite-desktop:~# root@variscite-desktop:~# %% Start PM consolidated quick test (retention only) %% Preparation root@variscite-desktop:~# root@variscite-desktop:~# mkdir -p /sys; mount -t sysfs none /sys mount: none already mounted or /sys busy mount: according to mtab, none is already mounted on /sys root@variscite-desktop:~# root@variscite-desktop:~# mkdir -p /debug; mount -t debugfs none /debug root@variscite-desktop:~# root@variscite-desktop:~# root@variscite-desktop:~# root@variscite-desktop:~# for u in `ls -1d /sys/bus/platform/drivers/omap_uart/* .serial | paste -s -d' '`; do echo -1 > $u/power/autosuspend_delay_ms; done root@variscite-desktop:~# root@variscite-desktop:~# for u in `ls -1d /sys/bus/platform/drivers/omap_uart/* .serial | paste -s -d' '`; do ls -l $u/power/autosuspend_delay_ms; cat $u/power /autosuspend_delay_ms; done -rw-r--r-- 1 root root 4096 Jan 1 02:00 /sys/bus/platform/drivers/omap_uart/48020000.serial/power/autosuspend_delay_ms -1 -rw-r--r-- 1 root root 4096 Jan 1 02:00 /sys/bus/platform/drivers/omap_uart/4806a000.serial/power/autosuspend_delay_ms -1 -rw-r--r-- 1 root root 4096 Jan 1 02:00 /sys/bus/platform/drivers/omap_uart/4806c000.serial/power/autosuspend_delay_ms -1 root@variscite-desktop:~# root@variscite-desktop:~# echo 0 > /debug/pm_debug/enable_off_mode root@variscite-desktop:~# root@variscite-desktop:~# %% Baseline pm_debug count dump cat /debug/pm_debug/count cefuse_pwrdm (OFF),OFF:2,RET:0,INA:0,ON:1,RET-LOGIC-OFF:0 always_on_core_pwrdm (ON),OFF:339,RET:0,INA:0,ON:340,RET-LOGIC-OFF:0 l4per_pwrdm (ON),OFF:0,RET:0,INA:0,ON:1,RET-LOGIC-OFF:0,RET-MEMBANK1-OFF:0,RET-MEMBANK2-OFF:0 l3init_pwrdm (RET),OFF:0,RET:12,INA:0,ON:12,RET-LOGIC-OFF:0,RET-MEMBANK1-OFF:0 cam_pwrdm (OFF),OFF:1,RET:0,INA:0,ON:1,RET-LOGIC-OFF:0,RET-MEMBANK1-OFF:0 ivahd_pwrdm (RET),OFF:1,RET:1,INA:0,ON:1,RET-LOGIC-OFF:0,RET-MEMBANK1-OFF:0,RET-MEMBANK2-OFF:0,RET-MEMBANK3-OFF:0,RET-MEMBANK4-OFF:0 mpu_pwrdm (ON),OFF:0,RET:150,INA:0,ON:151,RET-LOGIC-OFF:144,RET-MEMBANK1-OFF:150,RET-MEMBANK2-OFF:0,RET-MEMBANK3-OFF:0 cpu1_pwrdm (ON),OFF:338,RET:0,INA:0,ON:339,RET-LOGIC-OFF:0,RET-MEMBANK1-OFF:0 cpu0_pwrdm (ON),OFF:276,RET:0,INA:0,ON:277,RET-LOGIC-OFF:0,RET-MEMBANK1-OFF:0 tesla_pwrdm (RET),OFF:1,RET:1,INA:0,ON:1,RET-LOGIC-OFF:0,RET-MEMBANK1-OFF:0,RET-MEMBANK2-OFF:0,RET-MEMBANK3-OFF:0 dss_pwrdm (RET),OFF:0,RET:1,INA:0,ON:1,RET-LOGIC-OFF:0,RET-MEMBANK1-OFF:0 abe_pwrdm (ON),OFF:0,RET:0,INA:0,ON:1,RET-LOGIC-OFF:0,RET-MEMBANK1-OFF:0,RET-MEMBANK2-OFF:0 gfx_pwrdm (OFF),OFF:1,RET:0,INA:0,ON:1,RET-LOGIC-OFF:0,RET-MEMBANK1-OFF:0 core_pwrdm (ON),OFF:0,RET:0,INA:0,ON:1,RET-LOGIC-OFF:0,RET-MEMBANK1-OFF:0,RET-MEMBANK2-OFF:0,RET-MEMBANK3-OFF:0,RET-MEMBANK4-OFF:0,RET-MEMBANK5-OFF:0 l3_dma_clkdm->core_pwrdm (0) emu_sys_clkdm->emu_pwrdm (0) l4_wkup_clkdm->wkup_pwrdm (2) l3_dss_clkdm->dss_pwrdm (0) iss_clkdm->cam_pwrdm (0) l3_1_clkdm->core_pwrdm (0) l3_2_clkdm->core_pwrdm (1) mpuss_clkdm->mpu_pwrdm (1) ducati_clkdm->core_pwrdm (0) l4_ao_clkdm->always_on_core_pwrdm (0) l3_emif_clkdm->core_pwrdm (2) mpu1_clkdm->cpu1_pwrdm (0) mpu0_clkdm->cpu0_pwrdm (0) d2d_clkdm->core_pwrdm (0) l3_init_clkdm->l3init_pwrdm (0) l3_instr_clkdm->core_pwrdm (0) abe_clkdm->abe_pwrdm (0) l4_per_clkdm->l4per_pwrdm (7) l4_secure_clkdm->l4per_pwrdm (0) ivahd_clkdm->ivahd_pwrdm (0) l3_gfx_clkdm->gfx_pwrdm (0) tesla_clkdm->tesla_pwrdm (0) l4_cfg_clkdm->core_pwrdm (0) l4_cefuse_clkdm->cefuse_pwrdm (0) root@variscite-desktop:~# root@variscite-desktop:~# for u in `ls -1d /sys/class/tty/ttyO* | paste -s -d' ' `; do echo enabled > $u/power/wakeup; done root@variscite-desktop:~# %% Start retention double suspend/resume UART wakeup test root@variscite-desktop:~# for u in `ls -1d /sys/class/tty/ttyO* | paste -s -d' ' `; do echo auto > $u/power/control; done root@variscite-desktop:~# root@variscite-desktop:~# for u in `ls -1d /sys/class/tty/ttyO* | paste -s -d' ' `; do ls -l $u/power/wakeup; cat $u/power/wakeup; done -rw-r--r-- 1 root root 4096 Jan 1 02:00 /sys/class/tty/ttyO0/power/wakeup enabled -rw-r--r-- 1 root root 4096 Jan 1 02:00 /sys/class/tty/ttyO1/power/wakeup enabled -rw-r--r-- 1 root root 4096 Jan 1 02:00 /sys/class/tty/ttyO2/power/wakeup enabled root@variscite-desktop:~# root@variscite-desktop:~# for u in `ls -1d /sys/class/tty/ttyO* | paste -s -d' ' `; do ls -l $u/power/control; cat $u/power/control; done -rw-r--r-- 1 root root 4096 Jan 1 02:00 /sys/class/tty/ttyO0/power/control auto -rw-r--r-- 1 root root 4096 Jan 1 02:00 /sys/class/tty/ttyO1/power/control auto -rw-r--r-- 1 root root 4096 Jan 1 02:00 /sys/class/tty/ttyO2/power/control auto root@variscite-desktop:~# root@variscite-desktop:~# echo mem > /sys/power/state [ 28.359802] PM: Syncing filesystems ... [ 33.234954] init: failsafe-x main process (799) terminated with status 1 [ 35.887725] done. [ 35.889892] PM: Preparing system for sleep (mem) [ 35.909973] Freezing user space processes ... (elapsed 0.012 seconds) done. [ 35.922210] Freezing remaining freezable tasks ... (elapsed 0.002 seconds) done. [ 35.933349] PM: Suspending system (mem) [ 35.940246] Suspending console(s) (use no_console_suspend to debug) [ 35.961730] PM: suspend of devices complete after 11.657 msecs [ 35.965515] PM: late suspend of devices complete after 0.001 msecs [ 35.971313] omap_hwmod: gpio6: _wait_target_disable failed [ 35.978759] PM: noirq suspend of devices complete after 13.092 msecs [ 35.978759] Disabling non-boot CPUs ... [ 35.988861] CPU1: shutdown [ 36.018463] Powerdomain (core_pwrdm) didn't enter target state 1 [ 36.018463] Powerdomain (abe_pwrdm) didn't enter target state 1 [ 36.018463] Powerdomain (mpu_pwrdm) didn't enter target state 1 [ 36.018463] Powerdomain (l4per_pwrdm) didn't enter target state 1 [ 36.018463] Could not enter target state in pm_suspend [ 36.018463] A possible cause could be an old bootloader - try u-boot >= v2012.07 [ 36.018493] Enabling non-boot CPUs ... [ 36.087005] CPU1 is up [ 36.089721] PM: noirq resume of devices complete after 2.563 msecs [ 36.090515] PM: early resume of devices complete after 0.001 msecs [ 36.363647] PM: resume of devices complete after 273.132 msecs [ 36.463256] PM: Finishing wakeup. [ 36.463256] Restarting tasks ... done. root@variscite-desktop:~# root@variscite-desktop:~# root@variscite-desktop:~# root@variscite-desktop:~# cat /debug/pm_debug/count cefuse_pwrdm (OFF),OFF:2,RET:0,INA:0,ON:1,RET-LOGIC-OFF:0 always_on_core_pwrdm (ON),OFF:880,RET:0,INA:0,ON:881,RET-LOGIC-OFF:0 l4per_pwrdm (ON),OFF:0,RET:0,INA:0,ON:1,RET-LOGIC-OFF:0,RET-MEMBANK1-OFF:0,RET-MEMBANK2-OFF:0 l3init_pwrdm (RET),OFF:0,RET:15,INA:0,ON:15,RET-LOGIC-OFF:1,RET-MEMBANK1-OFF:0 cam_pwrdm (OFF),OFF:1,RET:0,INA:0,ON:1,RET-LOGIC-OFF:0,RET-MEMBANK1-OFF:0 ivahd_pwrdm (RET),OFF:1,RET:1,INA:0,ON:1,RET-LOGIC-OFF:0,RET-MEMBANK1-OFF:0,RET-MEMBANK2-OFF:0,RET-MEMBANK3-OFF:0,RET-MEMBANK4-OFF:0 mpu_pwrdm (ON),OFF:0,RET:150,INA:0,ON:151,RET-LOGIC-OFF:144,RET-MEMBANK1-OFF:150,RET-MEMBANK2-OFF:0,RET-MEMBANK3-OFF:0 cpu1_pwrdm (ON),OFF:879,RET:0,INA:0,ON:880,RET-LOGIC-OFF:0,RET-MEMBANK1-OFF:0 cpu0_pwrdm (ON),OFF:685,RET:0,INA:0,ON:686,RET-LOGIC-OFF:0,RET-MEMBANK1-OFF:0 tesla_pwrdm (RET),OFF:1,RET:1,INA:0,ON:1,RET-LOGIC-OFF:1,RET-MEMBANK1-OFF:0,RET-MEMBANK2-OFF:0,RET-MEMBANK3-OFF:0 dss_pwrdm (RET),OFF:0,RET:1,INA:0,ON:1,RET-LOGIC-OFF:0,RET-MEMBANK1-OFF:0 abe_pwrdm (ON),OFF:0,RET:0,INA:0,ON:1,RET-LOGIC-OFF:0,RET-MEMBANK1-OFF:0,RET-MEMBANK2-OFF:0 gfx_pwrdm (OFF),OFF:1,RET:0,INA:0,ON:1,RET-LOGIC-OFF:0,RET-MEMBANK1-OFF:0 core_pwrdm (ON),OFF:0,RET:0,INA:0,ON:1,RET-LOGIC-OFF:0,RET-MEMBANK1-OFF:0,RET-MEMBANK2-OFF:0,RET-MEMBANK3-OFF:0,RET-MEMBANK4-OFF:0,RET-MEMBANK5-OFF:0 l3_dma_clkdm->core_pwrdm (0) emu_sys_clkdm->emu_pwrdm (0) l4_wkup_clkdm->wkup_pwrdm (2) l3_dss_clkdm->dss_pwrdm (0) iss_clkdm->cam_pwrdm (0) l3_1_clkdm->core_pwrdm (0) l3_2_clkdm->core_pwrdm (1) mpuss_clkdm->mpu_pwrdm (1) ducati_clkdm->core_pwrdm (0) l4_ao_clkdm->always_on_core_pwrdm (0) l3_emif_clkdm->core_pwrdm (2) mpu1_clkdm->cpu1_pwrdm (0) mpu0_clkdm->cpu0_pwrdm (0) d2d_clkdm->core_pwrdm (0) l3_init_clkdm->l3init_pwrdm (0) l3_instr_clkdm->core_pwrdm (0) abe_clkdm->abe_pwrdm (0) l4_per_clkdm->l4per_pwrdm (8) l4_secure_clkdm->l4per_pwrdm (0) ivahd_clkdm->ivahd_pwrdm (0) l3_gfx_clkdm->gfx_pwrdm (0) tesla_clkdm->tesla_pwrdm (0) l4_cfg_clkdm->core_pwrdm (0) l4_cefuse_clkdm->cefuse_pwrdm (0) root@variscite-desktop:~# root@variscite-desktop:~# root@variscite-desktop:~# root@variscite-desktop:~# echo mem > /sys/power/state [ 39.258941] PM: Syncing filesystems ... done. [ 40.192718] PM: Preparing system for sleep (mem) [ 40.200073] Freezing user space processes ... (elapsed 0.004 seconds) done. [ 40.211944] Freezing remaining freezable tasks ... (elapsed 0.006 seconds) done. [ 40.221740] PM: Suspending system (mem) [ 40.225769] Suspending console(s) (use no_console_suspend to debug) [ 40.244384] PM: suspend of devices complete after 18.615 msecs [ 40.244506] PM: late suspend of devices complete after 0.001 msecs [ 40.251983] omap_hwmod: gpio6: _wait_target_disable failed [ 40.259277] PM: noirq suspend of devices complete after 14.770 msecs [ 40.259277] Disabling non-boot CPUs ... [ 40.261077] [ 40.261138] =============================== [ 40.261138] [ INFO: suspicious RCU usage. ] [ 40.261138] 4.3.0-rc4-170966-g049e6dd #1 Tainted: G W [ 40.261138] ------------------------------- [ 40.261138] kernel/sched/fair.c:4872 suspicious rcu_dereference_check() usage! [ 40.261169] [ 40.261169] other info that might help us debug this: [ 40.261169] [ 40.261169] [ 40.261169] RCU used illegally from offline CPU! [ 40.261169] rcu_scheduler_active = 1, debug_locks = 0 [ 40.261169] 3 locks held by swapper/1/0: [ 40.261169] #0: ((cpu_died).wait.lock){......}, at: [] complete+0x14/0x44 [ 40.261169] #1: (&p->pi_lock){-.-.-.}, at: [] try_to_wake_up+0x30/0x450 [ 40.261169] #2: (rcu_read_lock){......}, at: [] select_task_rq_fair+0x44/0xb58 [ 40.261169] [ 40.261169] stack backtrace: [ 40.261291] CPU: 1 PID: 0 Comm: swapper/1 Tainted: G W 4.3.0-rc4-170966-g049e6dd #1 [ 40.261291] Hardware name: Generic OMAP4 (Flattened Device Tree) [ 40.261291] [] (unwind_backtrace) from [] (show_stack+0x10/0x14) [ 40.261352] [] (show_stack) from [] (dump_stack+0x80/0x9c) [ 40.261352] [] (dump_stack) from [] (select_task_rq_fair+0x970/0xb58) [ 40.261383] [] (select_task_rq_fair) from [] (try_to_wake_up+0x13c/0x450) [ 40.261413] [] (try_to_wake_up) from [] (__wake_up_common+0x4c/0x80) [ 40.261413] [] (__wake_up_common) from [] (__wake_up_locked+0x14/0x1c) [ 40.261444] [] (__wake_up_locked) from [] (complete+0x34/0x44) [ 40.261444] [] (complete) from [] (arch_cpu_idle_dead+0x34/0x9c) [ 40.261444] [] (arch_cpu_idle_dead) from [] (cpu_startup_entry+0x32c/0x374) [ 40.261474] [] (cpu_startup_entry) from [<800095ec>] (0x800095ec) [ 40.261505] CPU1: shutdown [ 40.299743] Powerdomain (core_pwrdm) didn't enter target state 1 [ 40.299743] Powerdomain (abe_pwrdm) didn't enter target state 1 [ 40.299743] Powerdomain (mpu_pwrdm) didn't enter target state 1 [ 40.299743] Powerdomain (l4per_pwrdm) didn't enter target state 1 [ 40.299743] Could not enter target state in pm_suspend [ 40.299743] A possible cause could be an old bootloader - try u-boot >= v2012.07 [ 40.299774] Enabling non-boot CPUs ... [ 40.349975] CPU1 is up [ 40.351196] PM: noirq resume of devices complete after 1.220 msecs [ 40.351593] PM: early resume of devices complete after 0.001 msecs [ 40.720794] PM: resume of devices complete after 369.201 msecs [ 40.998168] PM: Finishing wakeup. [ 41.001647] Restarting tasks ... done. root@variscite-desktop:~# root@variscite-desktop:~# root@variscite-desktop:~# root@variscite-desktop:~# cat /debug/pm_debug/count cefuse_pwrdm (OFF),OFF:2,RET:0,INA:0,ON:1,RET-LOGIC-OFF:0 always_on_core_pwrdm (ON),OFF:1007,RET:0,INA:0,ON:1008,RET-LOGIC-OFF:0 l4per_pwrdm (ON),OFF:0,RET:0,INA:0,ON:1,RET-LOGIC-OFF:0,RET-MEMBANK1-OFF:0,RET-MEMBANK2-OFF:0 l3init_pwrdm (RET),OFF:0,RET:17,INA:0,ON:17,RET-LOGIC-OFF:2,RET-MEMBANK1-OFF:0 cam_pwrdm (OFF),OFF:1,RET:0,INA:0,ON:1,RET-LOGIC-OFF:0,RET-MEMBANK1-OFF:0 ivahd_pwrdm (RET),OFF:1,RET:1,INA:0,ON:1,RET-LOGIC-OFF:0,RET-MEMBANK1-OFF:0,RET-MEMBANK2-OFF:0,RET-MEMBANK3-OFF:0,RET-MEMBANK4-OFF:0 mpu_pwrdm (ON),OFF:0,RET:150,INA:0,ON:151,RET-LOGIC-OFF:144,RET-MEMBANK1-OFF:150,RET-MEMBANK2-OFF:0,RET-MEMBANK3-OFF:0 cpu1_pwrdm (ON),OFF:1006,RET:0,INA:0,ON:1007,RET-LOGIC-OFF:0,RET-MEMBANK1-OFF:0 cpu0_pwrdm (ON),OFF:798,RET:0,INA:0,ON:799,RET-LOGIC-OFF:0,RET-MEMBANK1-OFF:0 tesla_pwrdm (RET),OFF:1,RET:1,INA:0,ON:1,RET-LOGIC-OFF:2,RET-MEMBANK1-OFF:0,RET-MEMBANK2-OFF:0,RET-MEMBANK3-OFF:0 dss_pwrdm (RET),OFF:0,RET:1,INA:0,ON:1,RET-LOGIC-OFF:0,RET-MEMBANK1-OFF:0 abe_pwrdm (ON),OFF:0,RET:0,INA:0,ON:1,RET-LOGIC-OFF:0,RET-MEMBANK1-OFF:0,RET-MEMBANK2-OFF:0 gfx_pwrdm (OFF),OFF:1,RET:0,INA:0,ON:1,RET-LOGIC-OFF:0,RET-MEMBANK1-OFF:0 core_pwrdm (ON),OFF:0,RET:0,INA:0,ON:1,RET-LOGIC-OFF:0,RET-MEMBANK1-OFF:0,RET-MEMBANK2-OFF:0,RET-MEMBANK3-OFF:0,RET-MEMBANK4-OFF:0,RET-MEMBANK5-OFF:0 l3_dma_clkdm->core_pwrdm (0) emu_sys_clkdm->emu_pwrdm (0) l4_wkup_clkdm->wkup_pwrdm (2) l3_dss_clkdm->dss_pwrdm (0) iss_clkdm->cam_pwrdm (0) l3_1_clkdm->core_pwrdm (0) l3_2_clkdm->core_pwrdm (1) mpuss_clkdm->mpu_pwrdm (1) ducati_clkdm->core_pwrdm (0) l4_ao_clkdm->always_on_core_pwrdm (0) l3_emif_clkdm->core_pwrdm (2) mpu1_clkdm->cpu1_pwrdm (0) mpu0_clkdm->cpu0_pwrdm (0) d2d_clkdm->core_pwrdm (0) l3_init_clkdm->l3init_pwrdm (0) l3_instr_clkdm->core_pwrdm (0) abe_clkdm->abe_pwrdm (0) l4_per_clkdm->l4per_pwrdm (8) l4_secure_clkdm->l4per_pwrdm (0) ivahd_clkdm->ivahd_pwrdm (0) l3_gfx_clkdm->gfx_pwrdm (0) tesla_clkdm->tesla_pwrdm (0) l4_cfg_clkdm->core_pwrdm (0) l4_cefuse_clkdm->cefuse_pwrdm (0) root@variscite-desktop:~# root@variscite-desktop:~# root@variscite-desktop:~# root@variscite-desktop:~# ./test_rootfs_access_nonexistent_file__ret_susp -bash: ./test_rootfs_access_nonexistent_file__ret_susp: No such file or directory root@variscite-desktop:~# root@variscite-desktop:~# %% End retention double suspend/resume UART wakeup test %% Start retention dynamic idle UART wakeup test for u in `ls -1d /sys/bus/platform/drivers/omap_uart/* .serial | paste -s -d' '`; do echo 3000 > $u/power/autosuspend_delay_ms; done root@variscite-desktop:~# root@variscite-desktop:~# for u in `ls -1d /sys/bus/platform/drivers/omap_uart/* .serial | paste -s -d' '`; do ls -l $u/power/autosuspend_delay_ms; cat $u/power /autosuspend_delay_ms; done -rw-r--r-- 1 root root 4096 Jan 1 02:00 /sys/bus/platform/drivers/omap_uart/48020000.serial/power/autosuspend_delay_ms 3000 -rw-r--r-- 1 root root 4096 Jan 1 02:00 /sys/bus/platform/drivers/omap_uart/4806a000.serial/power/autosuspend_delay_ms 3000 -rw-r--r-- 1 root root 4096 Jan 1 02:00 /sys/bus/platform/drivers/omap_uart/4806c000.serial/power/autosuspend_delay_ms 3000 root@variscite-desktop:~# root@variscite-desktop:~# root@variscite-desktop:~# root@variscite-desktop:~# cat /debug/pm_debug/count cefuse_pwrdm (OFF),OFF:2,RET:0,INA:0,ON:1,RET-LOGIC-OFF:0 always_on_core_pwrdm (ON),OFF:1135,RET:0,INA:0,ON:1136,RET-LOGIC-OFF:0 l4per_pwrdm (ON),OFF:0,RET:0,INA:0,ON:1,RET-LOGIC-OFF:0,RET-MEMBANK1-OFF:0,RET-MEMBANK2-OFF:0 l3init_pwrdm (RET),OFF:0,RET:20,INA:0,ON:20,RET-LOGIC-OFF:2,RET-MEMBANK1-OFF:0 cam_pwrdm (OFF),OFF:1,RET:0,INA:0,ON:1,RET-LOGIC-OFF:0,RET-MEMBANK1-OFF:0 ivahd_pwrdm (RET),OFF:1,RET:1,INA:0,ON:1,RET-LOGIC-OFF:0,RET-MEMBANK1-OFF:0,RET-MEMBANK2-OFF:0,RET-MEMBANK3-OFF:0,RET-MEMBANK4-OFF:0 mpu_pwrdm (ON),OFF:0,RET:150,INA:0,ON:151,RET-LOGIC-OFF:144,RET-MEMBANK1-OFF:150,RET-MEMBANK2-OFF:0,RET-MEMBANK3-OFF:0 cpu1_pwrdm (ON),OFF:1134,RET:0,INA:0,ON:1135,RET-LOGIC-OFF:0,RET-MEMBANK1-OFF:0 cpu0_pwrdm (ON),OFF:922,RET:0,INA:0,ON:923,RET-LOGIC-OFF:0,RET-MEMBANK1-OFF:0 tesla_pwrdm (RET),OFF:1,RET:1,INA:0,ON:1,RET-LOGIC-OFF:2,RET-MEMBANK1-OFF:0,RET-MEMBANK2-OFF:0,RET-MEMBANK3-OFF:0 dss_pwrdm (RET),OFF:0,RET:1,INA:0,ON:1,RET-LOGIC-OFF:0,RET-MEMBANK1-OFF:0 abe_pwrdm (ON),OFF:0,RET:0,INA:0,ON:1,RET-LOGIC-OFF:0,RET-MEMBANK1-OFF:0,RET-MEMBANK2-OFF:0 gfx_pwrdm (OFF),OFF:1,RET:0,INA:0,ON:1,RET-LOGIC-OFF:0,RET-MEMBANK1-OFF:0 core_pwrdm (ON),OFF:0,RET:0,INA:0,ON:1,RET-LOGIC-OFF:0,RET-MEMBANK1-OFF:0,RET-MEMBANK2-OFF:0,RET-MEMBANK3-OFF:0,RET-MEMBANK4-OFF:0,RET-MEMBANK5-OFF:0 l3_dma_clkdm->core_pwrdm (0) emu_sys_clkdm->emu_pwrdm (0) l4_wkup_clkdm->wkup_pwrdm (2) l3_dss_clkdm->dss_pwrdm (0) iss_clkdm->cam_pwrdm (0) l3_1_clkdm->core_pwrdm (0) l3_2_clkdm->core_pwrdm (1) mpuss_clkdm->mpu_pwrdm (1) ducati_clkdm->core_pwrdm (0) l4_ao_clkdm->always_on_core_pwrdm (0) l3_emif_clkdm->core_pwrdm (2) mpu1_clkdm->cpu1_pwrdm (0) mpu0_clkdm->cpu0_pwrdm (0) d2d_clkdm->core_pwrdm (0) l3_init_clkdm->l3init_pwrdm (0) l3_instr_clkdm->core_pwrdm (0) abe_clkdm->abe_pwrdm (0) l4_per_clkdm->l4per_pwrdm (4) l4_secure_clkdm->l4per_pwrdm (0) ivahd_clkdm->ivahd_pwrdm (0) l3_gfx_clkdm->gfx_pwrdm (0) tesla_clkdm->tesla_pwrdm (0) l4_cfg_clkdm->core_pwrdm (0) l4_cefuse_clkdm->cefuse_pwrdm (0) root@variscite-desktop:~# root@variscite-desktop:~# root@variscite-desktop:~# root@variscite-desktop:~# ./test_rootfs_access_nonexistent_file__ret_dyn -bash: ./test_rootfs_access_nonexistent_file__ret_dyn: No such file or directory root@variscite-desktop:~# root@variscite-desktop:~# for u in `ls -1d /sys/bus/platform/drivers/omap_uart/* .serial | paste -s -d' '`; do echo -1 > $u/power/autosuspend_delay_ms; done root@variscite-desktop:~# root@variscite-desktop:~# for u in `ls -1d /sys/bus/platform/drivers/omap_uart/* .serial | paste -s -d' '`; do ls -l $u/power/autosuspend_delay_ms; cat $u/power /autosuspend_delay_ms; done -rw-r--r-- 1 root root 4096 Jan 1 02:01 /sys/bus/platform/drivers/omap_uart/48020000.serial/power/autosuspend_delay_ms -1 -rw-r--r-- 1 root root 4096 Jan 1 02:01 /sys/bus/platform/drivers/omap_uart/4806a000.serial/power/autosuspend_delay_ms -1 -rw-r--r-- 1 root root 4096 Jan 1 02:01 /sys/bus/platform/drivers/omap_uart/4806c000.serial/power/autosuspend_delay_ms -1 root@variscite-desktop:~# root@variscite-desktop:~# %% End retention dynamic idle UART wakeup test %% End PM consolidated quick test (retention only)