2013-09-24 05:33:44 PM INFO: Attempting to connect to device 2013-09-24 05:33:44 PM DEBUG: expect (10): '['Connection closed by foreign host\\.', 'Connected\\.\r', 'Data Buffering Suspended\\.', ]' Trying 127.0.0.1... Connected to localhost. Escape character is '^]'. ser2net port 2000 device /dev/ttyUSB0 [115200 N81] (Debian GNU/Linux) [ 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 3.11.0+ (novello@asus) (gcc version 4.7.3 20130226 (prerelease) (crosstool-NG linaro-1.13.1-4.7-2013.03-20130313 - Linaro GCC 2013.03) ) #2 SMP Wed Sep 18 17:19:10 CEST 2013 [ 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: Freescale i.MX6 Quad/DualLite (Device Tree), model: Wandboard i.MX6 Dual Lite Board [ 0.000000] cma: CMA: reserved 16 MiB at 3e800000 [ 0.000000] Memory policy: ECC disabled, Data cache writealloc [ 0.000000] PERCPU: Embedded 9 pages/cpu @c191c000 s14080 r8192 d14592 u36864 [ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 260434 [ 0.000000] Kernel command line: console=ttymxc0,115200 root=/dev/mmcblk0p2 ro rootfstype=ext4 rootwait fixrtc video=HDMI-A-1:800x600@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] allocated 2097152 bytes of page_cgroup [ 0.000000] please try 'cgroup_disable=memory' option if you don't want memory cgroups [ 0.000000] Memory: 1005700K/1048576K available (8398K kernel code, 876K rwdata, 3412K rodata, 469K init, 1015K bss, 42876K reserved, 270336K highmem) [ 0.000000] Virtual kernel memory layout: [ 0.000000] vector : 0xffff0000 - 0xffff1000 ( 4 kB) [ 0.000000] fixmap : 0xfff00000 - 0xfffe0000 ( 896 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 - 0xc0b90a44 (11811 kB) [ 0.000000] .init : 0xc0b91000 - 0xc0c06700 ( 470 kB) [ 0.000000] .data : 0xc0c08000 - 0xc0ce3168 ( 877 kB) [ 0.000000] .bss : 0xc0ce3168 - 0xc0de0e7c (1016 kB) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 [ 0.000000] Hierarchical RCU implementation. [ 0.000000] RCU restricting CPUs from NR_CPUS=4 to nr_cpu_ids=2. [ 0.000000] NR_IRQS:16 nr_irqs:16 16 [ 0.000000] L310 cache controller enabled [ 0.000000] l2x0: 16 ways, CACHE_ID 0x410000c8, AUX_CTRL 0x32050000, Cache size: 524288 B [ 0.000000] sched_clock: 32 bits at 66MHz, resolution 15ns, wraps every 65075ms [ 0.000000] CPU identified as i.MX6DL, silicon rev 1.1 [ 0.000000] Console: colour dummy device 80x30 [ 0.000998] Calibrating delay loop... 1581.05 BogoMIPS (lpj=7905280) [ 0.090138] pid_max: default: 32768 minimum: 301 [ 0.090355] Security Framework initialized [ 0.090382] AppArmor: AppArmor initialized [ 0.090590] Mount-cache hash table entries: 512 [ 0.096384] Initializing cgroup subsys memory [ 0.096422] Initializing cgroup subsys devices [ 0.096433] Initializing cgroup subsys freezer [ 0.096443] Initializing cgroup subsys net_cls [ 0.096452] Initializing cgroup subsys blkio [ 0.096461] Initializing cgroup subsys perf_event [ 0.096553] CPU: Testing write buffer coherency: ok [ 0.097112] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 [ 0.097236] Setting up static identity map for 0xc07ef678 - 0xc07ef6d0 [ 0.099428] CPU1: Booted secondary processor [ 0.187164] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 [ 0.187293] Brought up 2 CPUs [ 0.187314] SMP: Total of 2 processors activated (3162.11 BogoMIPS). [ 0.187323] CPU: All CPU(s) started in SVC mode. [ 0.188488] devtmpfs: initialized [ 0.193924] xor: measuring software checksum speed [ 0.287146] arm4regs : 1112.400 MB/sec [ 0.387145] 8regs : 863.200 MB/sec [ 0.487146] 32regs : 908.800 MB/sec [ 0.487156] xor: using function: arm4regs (1112.400 MB/sec) [ 0.487175] pinctrl core: initialized pinctrl subsystem [ 0.487564] regulator-dummy: no parameters [ 0.497631] NET: Registered protocol family 16 [ 0.528339] DMA: preallocated 256 KiB pool for atomic coherent allocations [ 0.536890] syscon 20c8000.anatop: regmap [mem 0x020c8000-0x020c8fff] registered [ 0.537212] vdd1p1: 800 <--> 1375 mV at 1100 mV [ 0.537551] vdd3p0: 2800 <--> 3150 mV at 3000 mV [ 0.537895] vdd2p5: 2000 <--> 2750 mV at 2400 mV [ 0.538201] cpu: 725 <--> 1450 mV at 1150 mV [ 0.538463] vddpu: 725 <--> 1450 mV at 1150 mV [ 0.538741] vddsoc: 725 <--> 1450 mV at 1200 mV [ 0.540036] syscon 20e0000.iomuxc-gpr: regmap [mem 0x020e0000-0x020e0037] registered [ 0.542233] syscon 21bc000.ocotp: regmap [mem 0x021bc000-0x021bffff] registered [ 0.543527] No ATAGs? [ 0.543554] hw-breakpoint: found 5 (+1 reserved) breakpoint and 1 watchpoint registers. [ 0.543564] hw-breakpoint: maximum watchpoint size is 4 bytes. [ 0.544947] imx6dl-pinctrl 20e0000.iomuxc: initialized IMX pinctrl driver [ 0.566872] bio: create slab at 0 [ 0.737616] raid6: int32x1 76 MB/s [ 0.907285] raid6: int32x2 135 MB/s [ 1.077314] raid6: int32x4 130 MB/s [ 1.247200] raid6: int32x8 141 MB/s [ 1.247210] raid6: using algorithm int32x8 (141 MB/s) [ 1.247217] raid6: using intx1 recovery algorithm [ 1.247978] edma-dma-engine edma-dma-engine.0: Can't allocate PaRAM dummy slot [ 1.248008] edma-dma-engine: probe of edma-dma-engine.0 failed with error -5 [ 1.248530] 2P5V: 2500 mV [ 1.248744] 3P3V: 3300 mV [ 1.251411] SCSI subsystem initialized [ 1.251921] usbcore: registered new interface driver usbfs [ 1.251987] usbcore: registered new interface driver hub [ 1.252125] usbcore: registered new device driver usb [ 1.253459] i2c i2c-0: IMX I2C adapter registered [ 1.254343] i2c i2c-1: IMX I2C adapter registered [ 1.254678] i2c i2c-2: IMX I2C adapter registered [ 1.254922] media: Linux media interface: v0.10 [ 1.254978] Linux video capture interface: v2.00 [ 1.255055] pps_core: LinuxPPS API ver. 1 registered [ 1.255064] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 1.255087] PTP clock support registered [ 1.255161] EDAC MC: Ver: 3.0.0 [ 1.256411] NetLabel: Initializing [ 1.256423] NetLabel: domain hash size = 128 [ 1.256429] NetLabel: protocols = UNLABELED CIPSOv4 [ 1.256497] NetLabel: unlabeled traffic allowed by default [ 1.256690] nfc: nfc_init: NFC Core ver 0.1 [ 1.256767] NET: Registered protocol family 39 [ 1.257330] Switched to clocksource mxc_timer1 [ 1.274319] AppArmor: AppArmor Filesystem Enabled [ 1.284965] NET: Registered protocol family 2 [ 1.285793] TCP established hash table entries: 8192 (order: 4, 65536 bytes) [ 1.286114] TCP bind hash table entries: 8192 (order: 4, 65536 bytes) [ 1.286262] TCP: Hash tables configured (established 8192 bind 8192) [ 1.286503] TCP: reno registered [ 1.286574] UDP hash table entries: 512 (order: 2, 16384 bytes) [ 1.286659] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes) [ 1.287111] NET: Registered protocol family 1 [ 1.287621] RPC: Registered named UNIX socket transport module. [ 1.287634] RPC: Registered udp transport module. [ 1.287641] RPC: Registered tcp transport module. [ 1.287648] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 1.288663] hw perfevents: enabled with ARMv7 Cortex-A9 PMU driver, 7 counters available [ 1.289314] wandboard-rfkill rfkill.14: Wandboard rfkill initialization [ 1.289393] wandboard-rfkill rfkill.14: initialize wifi chip [ 1.317483] wandboard-rfkill rfkill.14: wifi-rfkill registered. [ 1.317534] wandboard-rfkill rfkill.14: initialize bluetooth chip [ 1.347469] wandboard-rfkill rfkill.14: bluetooth-rfkill registered. [ 1.348720] audit: initializing netlink socket (disabled) [ 1.348759] type=2000 audit(1.339:1): initialized [ 1.564445] bounce pool size: 64 pages [ 1.568852] VFS: Disk quotas dquot_6.5.2 [ 1.569138] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) [ 1.571722] NFS: Registering the id_resolver key type [ 1.571765] Key type id_resolver registered [ 1.571774] Key type id_legacy registered [ 1.571813] fuse init (API version 7.22) [ 1.572688] bio: create slab at 1 [ 1.573926] Btrfs loaded [ 1.574126] msgmni has been set to 1468 [ 1.575679] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) [ 1.575827] io scheduler noop registered [ 1.575838] io scheduler deadline registered [ 1.575999] io scheduler cfq registered (default) [ 1.576509] imx-weim 21b8000.weim: Driver registered. [ 1.583243] imx-sdma 20ec000.sdma: initialized [ 1.646477] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled [ 1.647883] Serial: IMX driver [ 1.648151] 2020000.serial: ttymxc0 at MMIO 0x2020000 (irq = 58) is a IMX [ 2.465040] console [ttymxc0] enabled [ 2.469346] 21ec000.serial: ttymxc2 at MMIO 0x21ec000 (irq = 60) is a IMX [ 2.477177] serial: Freescale lpuart driver [ 2.481697] [drm] Initialized drm 1.1.0 20060810 [ 2.486409] usbcore: registered new interface driver udl [ 2.495622] imx6dl-pinctrl 20e0000.iomuxc: pin MX6DL_PAD_GPIO_16 already requested by 21a8000.i2c; cannot claim for 2188000.ethernet [ 2.507583] imx6dl-pinctrl 20e0000.iomuxc: pin-133 (2188000.ethernet) status -22 [ 2.514990] imx6dl-pinctrl 20e0000.iomuxc: could not request pin 133 on device 20e0000.iomuxc [ 2.523552] fec 2188000.ethernet: Error applying setting, reverse things back [ 2.535735] libphy: fec_enet_mii_bus: probed [ 2.540531] fec 2188000.ethernet eth0: registered PHC device 0 [ 2.547264] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver [ 2.553821] ehci-omap: OMAP-EHCI Host Controller driver [ 2.559300] usbcore: registered new interface driver usb-storage [ 2.565835] platform 2184200.usb: Driver imx_usb requests probe deferral [ 2.573286] mousedev: PS/2 mouse device common for all mice [ 2.579879] snvs_rtc 20cc034.snvs-rtc-lp: rtc core: registered 20cc034.snvs-rtc-lp as rtc0 [ 2.588597] b2c2-flexcop: B2C2 FlexcopII/II(b)/III digital TV receiver chip loaded successfully [ 2.597603] usbcore: registered new interface driver dvb_usb_vp7045 [ 2.603931] usbcore: registered new interface driver dvb_usb_vp702x [ 2.610278] usbcore: registered new interface driver dvb_usb_gp8psk [ 2.616629] usbcore: registered new interface driver dvb_usb_dtt200u [ 2.623064] usbcore: registered new interface driver dvb_usb_a800 [ 2.629257] usbcore: registered new interface driver dvb_usb_dibusb_mb [ 2.635843] usbcore: registered new interface driver dvb_usb_dibusb_mc [ 2.642465] usbcore: registered new interface driver dvb_usb_nova_t_usb2 [ 2.649245] usbcore: registered new interface driver dvb_usb_umt_010 [ 2.655659] usbcore: registered new interface driver dvb_usb_m920x [ 2.661915] usbcore: registered new interface driver dvb_usb_digitv [ 2.668299] usbcore: registered new interface driver dvb_usb_cxusb [ 2.674541] usbcore: registered new interface driver dvb_usb_ttusb2 [ 2.680890] usbcore: registered new interface driver dvb_usb_dib0700 [ 2.687304] usbcore: registered new interface driver opera1 [ 2.692980] usbcore: registered new interface driver dvb_usb_af9005 [ 2.699374] usbcore: registered new interface driver pctv452e [ 2.705185] usbcore: registered new interface driver dw2102 [ 2.710863] usbcore: registered new interface driver dvb_usb_dtv5100 [ 2.717334] usbcore: registered new interface driver cinergyT2 [ 2.723236] usbcore: registered new interface driver dvb_usb_friio [ 2.729499] usbcore: registered new interface driver dvb_usb_az6027 [ 2.735831] usbcore: registered new interface driver dvb_usb_technisat_usb2 [ 2.742904] usbcore: registered new interface driver dvb_usb_af9015 [ 2.749270] usbcore: registered new interface driver dvb_usb_af9035 [ 2.755611] usbcore: registered new interface driver dvb_usb_anysee [ 2.761961] usbcore: registered new interface driver dvb_usb_au6610 [ 2.768329] usbcore: registered new interface driver dvb_usb_az6007 [ 2.774672] usbcore: registered new interface driver dvb_usb_ce6230 [ 2.781036] usbcore: registered new interface driver dvb_usb_ec168 [ 2.787288] usbcore: registered new interface driver dvb_usb_it913x [ 2.793638] usbcore: registered new interface driver dvb_usb_lmedm04 [ 2.800092] usbcore: registered new interface driver dvb_usb_gl861 [ 2.806338] usbcore: registered new interface driver dvb_usb_mxl111sf [ 2.812874] usbcore: registered new interface driver dvb_usb_rtl28xxu [ 2.819414] usbcore: registered new interface driver smsusb [ 2.825154] usbcore: registered new interface driver b2c2_flexcop_usb [ 2.831684] usbcore: registered new interface driver zr364xx [ 2.837432] usbcore: registered new interface driver stkwebcam [ 2.843320] usbcore: registered new interface driver s2255 [ 2.848922] usbcore: registered new interface driver uvcvideo [ 2.854671] USB Video Class driver (1.1.1) [ 2.858790] gspca_main: v2.14.0 registered [ 2.862954] usbcore: registered new interface driver Philips webcam [ 2.869249] cpia2: V4L-Driver for Vision CPiA2 based cameras v3.0.1 [ 2.875578] usbcore: registered new interface driver cpia2 [ 2.881139] usbcore: registered new interface driver sn9c102 [ 2.886802] au0828 driver loaded [ 2.890121] usbcore: registered new interface driver au0828 [ 2.895753] usbcore: registered new interface driver hdpvr [ 2.901471] usbcore: registered new interface driver pvrusb2 [ 2.907138] pvrusb2: V4L in-tree version:Hauppauge WinTV-PVR-USB2 MPEG2 Encoder/Tuner [ 2.915000] pvrusb2: Debug mask is 31 (0x1f) [ 2.919353] usbcore: registered new interface driver usbvision [ 2.925190] USBVision USB Video Device Driver for Linux : 0.9.11 [ 2.931318] usbcore: registered new interface driver stk1160 [ 2.937039] usbcore: registered new interface driver cx231xx [ 2.942724] cx231xx: Cx231xx dvb Extension initialized [ 2.947953] usbcore: registered new interface driver tm6000 [ 2.953586] usbcore: registered new interface driver em28xx [ 2.959184] Em28xx: Initialized (Em28xx dvb Extension) extension [ 2.965196] Em28xx: Initialized (Em28xx Input Extension) extension [ 2.971473] usbcore: registered new interface driver usbtv [ 2.978458] imx2-wdt 20bc000.wdog: IMX2+ Watchdog Timer enabled. timeout=60s (nowayout=1) [ 2.986986] cpuidle: using governor ladder [ 2.991120] cpuidle: using governor menu [ 2.995082] sdhci: Secure Digital Host Controller Interface driver [ 3.001283] sdhci: Copyright(c) Pierre Ossman [ 3.005907] sdhci-pltfm: SDHCI platform and OF driver helper [ 3.013533] mmc0: no vqmmc regulator found [ 3.017672] mmc0: no vmmc regulator found [ 3.067362] mmc0: SDHCI controller on 2190000.usdhc [2190000.usdhc] using ADMA [ 3.076048] mmc1: no vqmmc regulator found [ 3.080183] mmc1: no vmmc regulator found [ 3.127350] mmc1: SDHCI controller on 2194000.usdhc [2194000.usdhc] using ADMA [ 3.139326] mmc2: no vqmmc regulator found [ 3.143454] mmc2: no vmmc regulator found [ 3.173857] mmc1: queuing unknown CIS tuple 0x80 (50 bytes) [ 3.187348] mmc2: SDHCI controller on 2198000.usdhc [2198000.usdhc] using ADMA [ 3.194833] ledtrig-cpu: registered to indicate activity on CPUs [ 3.201274] mmc1: queuing unknown CIS tuple 0x80 (7 bytes) [ 3.201358] hidraw: raw HID events driver (C) Jiri Kosina [ 3.212639] usbcore: registered new interface driver usbhid [ 3.218263] usbhid: USB HID core driver [ 3.222733] ashmem: initialized [ 3.226281] mmc1: queuing unknown CIS tuple 0x80 (4 bytes) [ 3.232269] [drm] Supports vblank timestamp caching Rev 1 (10.10.2010). [ 3.238934] [drm] No driver support for vblank timestamp query. [ 3.244863] [drm] Initialized imx-drm 1.0.0 20120507 on minor 0 [ 3.252199] imx-ipuv3 2400000.ipu: IPUv3H probed [ 3.257626] imx-hdmi 120000.hdmi: Detected HDMI controller 0x13:0x1a:0xa0:0xc1 [ 3.265051] imx-hdmi 120000.hdmi: initialized [ 3.270324] oprofile: using arm/armv7-ca9 [ 3.274695] TCP: cubic registered [ 3.278557] NET: Registered protocol family 10 [ 3.284162] mip6: Mobile IPv6 [ 3.287161] NET: Registered protocol family 17 [ 3.291717] Key type dns_resolver registered [ 3.296444] VFP support v0.3: implementor 41 architecture 3 part 30 variant 9 rev 4 [ 3.304242] failed to init OPP table [ 3.308273] imx6q-cpufreq imx6q-cpufreq.0: failed to get clocks [ 3.314210] imx6q-cpufreq: probe of imx6q-cpufreq.0 failed with error -2 [ 3.320986] ThumbEE CPU extension supported. [ 3.325300] Registering SWP/SWPB emulation handler [ 3.330713] registered taskstats version 1 [ 3.336640] ci_hdrc ci_hdrc.0: doesn't support gadget [ 3.341764] ci_hdrc ci_hdrc.0: EHCI Host Controller [ 3.346901] ci_hdrc ci_hdrc.0: new USB bus registered, assigned bus number 1 [ 3.359639] mmc1: queuing unknown CIS tuple 0x02 (1 bytes) [ 3.367576] ci_hdrc ci_hdrc.0: USB 2.0 started, EHCI 1.00 [ 3.373053] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002 [ 3.379879] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 3.387127] usb usb1: Product: EHCI Host Controller [ 3.392047] usb usb1: Manufacturer: Linux 3.11.0+ ehci_hcd [ 3.397566] usb usb1: SerialNumber: ci_hdrc.0 [ 3.403054] hub 1-0:1.0: USB hub found [ 3.406842] hub 1-0:1.0: 1 port detected [ 3.411918] snvs_rtc 20cc034.snvs-rtc-lp: setting system clock to 1970-01-01 00:00:00 UTC (0) [ 3.420487] sr_init: No PMIC hook to init smartreflex [ 3.425646] sr_init: platform driver register failed for SR [ 3.432631] imx-hdmi 120000.hdmi: failed to read edid [ 3.437752] imx_hdmi_connector_mode_valid [ 3.441784] imx_hdmi_connector_mode_valid [ 3.445809] imx_hdmi_connector_mode_valid [ 3.449856] imx_hdmi_connector_mode_valid [ 3.453883] imx_hdmi_connector_mode_valid [ 3.458916] mmc1: new SDIO card at address 0001 [ 3.467506] imx_hdmi_encoder_prepare [ 3.467509] imx_hdmi_poweroff [ 3.467518] imx-hdmi 120000.hdmi: imx_hdmi_phy_disable [ 3.469636] imx_hdmi_encoder_mode_set [ 3.469644] imx-hdmi 120000.hdmi: imx_hdmi_setup [ 3.469652] imx-hdmi 120000.hdmi: imx_hdmi_setup - video mode changed [ 3.469657] imx-hdmi 120000.hdmi: Non-CEA mode used in HDMI [ 3.469663] imx-hdmi 120000.hdmi: hdmi_av_composer [ 3.469670] imx-hdmi 120000.hdmi: x=800, y=600, refresh=60 [ 3.469677] imx-hdmi 120000.hdmi: final pixclk = 39790080 [ 3.469688] imx-hdmi 120000.hdmi: hdmi_av_composer exit [ 3.469694] imx-hdmi 120000.hdmi: imx_hdmi_phy_init [ 3.469704] imx-hdmi 120000.hdmi: hdmi_phy_configure [ 3.491888] imx-hdmi 120000.hdmi: PHY PLL not locked [ 3.491896] imx-hdmi 120000.hdmi: imx_hdmi_phy_init: hdmi_phy_configure returned -22 [ 3.491906] imx-hdmi 120000.hdmi: hdmi_phy_configure [ 3.505977] imx-hdmi 120000.hdmi: PHY PLL not locked [ 3.505985] imx-hdmi 120000.hdmi: imx_hdmi_phy_init: hdmi_phy_configure returned -22 [ 3.505990] imx-hdmi 120000.hdmi: imx_hdmi_enable_video_path [ 3.506043] imx_hdmi_poweron [ 3.506048] imx-hdmi 120000.hdmi: imx_hdmi_setup [ 3.506055] imx-hdmi 120000.hdmi: imx_hdmi_setup - video mode changed [ 3.506061] imx-hdmi 120000.hdmi: Non-CEA mode used in HDMI [ 3.506067] imx-hdmi 120000.hdmi: hdmi_av_composer [ 3.506075] imx-hdmi 120000.hdmi: x=800, y=600, refresh=60 [ 3.506080] imx-hdmi 120000.hdmi: final pixclk = 39790080 [ 3.506092] imx-hdmi 120000.hdmi: hdmi_av_composer exit [ 3.506098] imx-hdmi 120000.hdmi: imx_hdmi_phy_init [ 3.506107] imx-hdmi 120000.hdmi: hdmi_phy_configure [ 3.517192] imx-hdmi 120000.hdmi: imx_hdmi_phy_init: hdmi_phy_configure returned 0 [ 3.517200] imx-hdmi 120000.hdmi: hdmi_phy_configure [ 3.528278] imx-hdmi 120000.hdmi: imx_hdmi_phy_init: hdmi_phy_configure returned 0 [ 3.528284] imx-hdmi 120000.hdmi: imx_hdmi_enable_video_path [ 3.531853] Console: switching to colour frame buffer device 100x37 [ 3.679627] mmc2: host does not support reading read-only switch. assuming write-enable. [ 3.705760] mmc2: new SDHC card at address e624 [ 3.706267] mmcblk0: mmc2:e624 SU08G 7.40 GiB [ 3.716813] mmcblk0: p1 p2 p3 p4 [ 3.737142] imx-drm imx-drm: fb0: frame buffer device [ 3.742309] imx-drm imx-drm: registered panic notifier [ 3.751650] EXT4-fs (mmcblk0p2): INFO: recovery required on readonly filesystem [ 3.759002] EXT4-fs (mmcblk0p2): write access will be enabled during recovery [ 3.892703] EXT4-fs (mmcblk0p2): recovery complete [ 3.901724] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null) [ 3.909917] VFS: Mounted root (ext4 filesystem) readonly on device 179:2. [ 3.934441] devtmpfs: mounted [ 3.938134] Freeing unused kernel memory: 468K (c0b91000 - c0c06000) [ 4.341749] EXT4-fs (mmcblk0p2): warning: mounting fs with errors, running e2fsck is recommended [ 4.352873] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null) Starting logging: OK Populating /dev using udev: [ 4.989878] udevd[76]: starting version 182 done [ 5.331395] device-mapper: uevent: version 1.0.3 [ 5.342490] device-mapper: ioctl: 4.25.0-ioctl (2013-06-26) initialised: dm-devel@redhat.com Setting up DMRAID devices... no block devices found Initializing random number generator... done. [ 5.578883] cfg80211: Calling CRDA to update world regulatory domain [ 5.642998] brcmfmac: brcmf_sdio_chip_drivestrengthinit: No SDIO Drive strength init done for chip 4329 rev 3 pmurev 6 [ 5.698891] Bluetooth: Core ver 2.16 [ 5.702607] NET: Registered protocol family 31 [ 5.707061] Bluetooth: HCI device and connection manager initialized [ 5.713508] Bluetooth: HCI socket layer initialized [ 5.718450] Bluetooth: L2CAP socket layer initialized [ 5.723548] Bluetooth: SCO socket layer initialized [ 5.747412] Bluetooth: Generic Bluetooth SDIO driver ver 0.1 Starting network plug daemon: /etc/netplug.d/netplug eth0 probe -> pid 115 [ 5.865403] fec 2188000.ethernet eth0: Freescale FEC PHY driver [Generic PHY] (mii_bus:phy_addr=2188000.ethernet:01, irq=-1) [ 5.876960] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready /etc/netplug.d/netplug eth1 probe -> pid 116 /etc/netplug.d/netplug eth2 probe -> pid 122 /etc/netplug.d/netplug eth3 probe -> pid 127 /etc/netplug.d/netplug eth4 probe -> pid 133 /etc/netplug.d/netplug eth5 probe -> pid 139 [ 6.651181] brcmfmac: brcmf_sdbrcm_download_code_file: Fail to request firmware -2 [ 6.658863] brcmfmac: _brcmf_sdbrcm_download_firmware: dongle image file download failed [ 6.667076] brcmfmac: brcmf_bus_start: brcmf_sdbrcm_bus_init failed -1 [ 6.673668] brcmfmac: brcmf_sdbrcm_probe: dongle is not responding [ 6.680925] brcmfmac: brcmf_sdio_probe: device attach failed [ 6.686767] brcmfmac: brcmf_ops_sdio_probe: F2 error, probe failed -19... /etc/netplug.d/netplug eth6 probe -> pid 146 /etc/netplug.d/netplug eth7 probe -> pid 155 /etc/netplug.d/netplug eth8 probe -> pid 160 /etc/netplug.d/netplug eth9 probe -> pid 165 /etc/netplug.d/netplug eth10 probe -> pid 170 /etc/netplug.d/netplug eth11 probe -> pid 175 /etc/netplug.d/netplug eth12 probe -> pid 180 /etc/netplug.d/netplug eth13 probe -> pid 185 /etc/netplug.d/netplug eth14 probe -> pid 190 /etc/netplug.d/netplug eth15 probe -> pid 195 touch: cannot touch '/var/lock/subsys/netplugd': No such file or directory Starting system message bus: 2013-09-24 05:33:54 PM INFO: Matched which means all-good 2013-09-24 05:33:54 PM DEBUG: [ACTION-B] Single node test! 2013-09-24 05:33:54 PM INFO: [ACTION-B] dummy_deploy is started with {u'target_type': u'oe'} 2013-09-24 05:33:54 PM DEBUG: setting status pass 2013-09-24 05:33:54 PM DEBUG: finally status pass 2013-09-24 05:33:54 PM INFO: [ACTION-E] dummy_deploy is finished successfully. 2013-09-24 05:33:54 PM INFO: [ACTION-B] lava_test_shell is started with {u'testdef_urls': [u'scp://root@192.168.1.203:/home/novello/ae/test/lava/test-definitions/common/passfail.yaml'], u'timeout': 1800} 2013-09-24 05:33:54 PM INFO: attempting to access master filesystem 2:lava 2013-09-24 05:33:54 PM DEBUG: sendline : 2013-09-24 05:33:54 PM DEBUG: send : 2013-09-24 05:33:54 PM DEBUG: send : 2013-09-24 05:33:54 PM DEBUG: expect (10): '['bash-4.2 \\[rc=(\\d+)\\]# ', ]' done Starting network... udhcpc (v1.21.1) started grep: /etc/resolv.conf: No such file or directory Failed to kill daemon: No such file or directory Sending discover... [ 10.567699] libphy: 2188000.ethernet:01 - Link is Up - 100/Full [ 10.573650] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Sending discover... Sending discover... Sending select for 192.168.1.4... Sending select for 192.168.1.4... 2013-09-24 05:34:04 PM INFO: Booting the system master image. Attempt: 1 2013-09-24 05:34:04 PM INFO: Perform soft reboot the system 2013-09-24 05:34:04 PM DEBUG: send :  2013-09-24 05:34:04 PM DEBUG: sendline : reboot 2013-09-24 05:34:04 PM DEBUG: send : reboot 2013-09-24 05:34:04 PM DEBUG: send : 2013-09-24 05:34:04 PM DEBUG: expect (120): '[, 'Restarting system.', 'The system is going down for reboot NOW', 'Will now restart', 'U-Boot']' Lease of 192.168.1.4 obtained, lease time 268435455 ^CFailed to kill daemon: No such file or directory deleting routers route: SIOCDELRT: No such process radding dns 192.168.1.1 eboStarting dropbear sshd: otOK Starting pulseaudio: W: [pulseaudio] main.c: Running in system mode, but --disallow-exit not set! W: [pulseaudio] main.c: Running in system mode, but --disallow-module-loading not set! N: [pulseaudio] main.c: Running in system mode, forcibly disabling SHM mode! N: [pulseaudio] main.c: Running in system mode, forcibly disabling exit idle time! OK Starting sshd: OK Starting network management services:. Starting SMB services: done Starting NMB services: done Starting mpd: OK Starting atd: OK Starting input-event-daemon: input-event-daemon: open(/dev/input/event0): No such file or directory done Starting XDM: done bash-4.2# reboot bash-4.2# [ 22.169745] imx_hdmi_encoder_dpms [ 22.169748] imx_hdmi_poweroff [ 22.169760] imx-hdmi 120000.hdmi: imx_hdmi_phy_disable [ 22.169769] imx-hdmi 120000.hdmi: imx_hdmi_phy_disable - exit [ 22.793918] fec 2188000.ethernet eth0: Freescale FEC PHY driver [Generic PHY] (mii_bus:phy_addr=2188000.ethernet:01, irq=-1) [ 23.193820] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null) The system is going down NOW! Sent SIGTERM to all processes [ 23.323581] imx_hdmi_encoder_dpms [ 23.323588] imx_hdmi_poweron [ 23.323606] imx-hdmi 120000.hdmi: imx_hdmi_setup [ 23.323621] imx-hdmi 120000.hdmi: imx_hdmi_setup - video mode changed [ 23.323632] imx-hdmi 120000.hdmi: Non-CEA mode used in HDMI [ 23.323639] imx-hdmi 120000.hdmi: hdmi_av_composer [ 23.323648] imx-hdmi 120000.hdmi: x=800, y=600, refresh=60 [ 23.323654] imx-hdmi 120000.hdmi: final pixclk = 39790080 [ 23.323665] imx-hdmi 120000.hdmi: hdmi_av_composer exit [ 23.323671] imx-hdmi 120000.hdmi: imx_hdmi_phy_init [ 23.323679] imx-hdmi 120000.hdmi: hdmi_phy_configure [ 23.334822] imx-hdmi 120000.hdmi: imx_hdmi_phy_init: hdmi_phy_configure returned 0 [ 23.334833] imx-hdmi 120000.hdmi: hdmi_phy_configure [ 23.345913] imx-hdmi 120000.hdmi: imx_hdmi_phy_init: hdmi_phy_configure returned 0 [ 23.345920] imx-hdmi 120000.hdmi: imx_hdmi_enable_video_path [ 23.525941] imx_hdmi_encoder_dpms [ 23.525944] imx_hdmi_poweroff [ 23.525955] imx-hdmi 120000.hdmi: imx_hdmi_phy_disable [ 23.525965] imx-hdmi 120000.hdmi: imx_hdmi_phy_disable - exit [ 24.129720] imx_hdmi_encoder_dpms [ 24.129723] imx_hdmi_poweron [ 24.129734] imx-hdmi 120000.hdmi: imx_hdmi_setup [ 24.129743] imx-hdmi 120000.hdmi: imx_hdmi_setup - video mode changed [ 24.129749] imx-hdmi 120000.hdmi: Non-CEA mode used in HDMI [ 24.129755] imx-hdmi 120000.hdmi: hdmi_av_composer [ 24.129764] imx-hdmi 120000.hdmi: x=800, y=600, refresh=60 [ 24.129770] imx-hdmi 120000.hdmi: final pixclk = 39790080 [ 24.129781] imx-hdmi 120000.hdmi: hdmi_av_composer exit [ 24.129787] imx-hdmi 120000.hdmi: imx_hdmi_phy_init [ 24.129797] imx-hdmi 120000.hdmi: hdmi_phy_configure [ 24.140926] imx-hdmi 120000.hdmi: imx_hdmi_phy_init: hdmi_phy_configure returned 0 [ 24.140936] imx-hdmi 120000.hdmi: hdmi_phy_configure [ 24.151997] imx-hdmi 120000.hdmi: imx_hdmi_phy_init: hdmi_phy_configure returned 0 [ 24.152003] imx-hdmi 120000.hdmi: imx_hdmi_enable_video_path Sent SIGKILL to all processes[ 24.797751] libphy: 2188000.ethernet:01 - Link is Up - 100/Full [ 25.250548] imx-sdma 20ec000.sdma: firmware not found [ 25.257747] reboot: Restarting system 2013-09-24 05:34:11 PM DEBUG: expect (300): 'Linux version' U-Boot 2013.07-dirty (Sep 14 2013 - 13:16:19) CPU: Freescale i.MX6DL rev1.1 at 792 MHz Reset cause: WDOG Board: Wandboard DRAM: 1 GiB MMC: FSL_SDHC: 0, FSL_SDHC: 1 *** Warning - bad CRC, using default environment In: serial Out: serial Err: serial Net: FEC [PRIME] Warning: FEC using MAC address from net device Hit any key to stop autoboot: 1  0 mmc0 is current device SD/MMC found on device 0 395 bytes read in 27 ms (13.7 KiB/s) Importing environment from mmc (uEnv.txt)... Checking if uenvcmd is set ... Running uenvcmd ... 4329112 bytes read in 534 ms (7.7 MiB/s) 32884 bytes read in 200 ms (160.2 KiB/s) Kernel image @ 0x12000000 [ 0x000000 - 0x420e98 ] ## Flattened Device Tree blob at 11000000 Booting using the fdt blob at 0x11000000 Using Device Tree in place at 11000000, end 1100b073 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 3.11.02013-09-24 05:34:19 PM DEBUG: expect (150): 'bash-4.2' + (novello@asus) (gcc version 4.7.3 20130226 (prerelease) (crosstool-NG linaro-1.13.1-4.7-2013.03-20130313 - Linaro GCC 2013.03) ) #2 SMP Wed Sep 18 17:19:10 CEST 2013 [ 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: Freescale i.MX6 Quad/DualLite (Device Tree), model: Wandboard i.MX6 Dual Lite Board [ 0.000000] cma: CMA: reserved 16 MiB at 3e800000 [ 0.000000] Memory policy: ECC disabled, Data cache writealloc [ 0.000000] PERCPU: Embedded 9 pages/cpu @c191c000 s14080 r8192 d14592 u36864 [ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 260434 [ 0.000000] Kernel command line: console=ttymxc0,115200 root=/dev/mmcblk0p2 ro rootfstype=ext4 rootwait fixrtc video=HDMI-A-1:800x600@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] allocated 2097152 bytes of page_cgroup [ 0.000000] please try 'cgroup_disable=memory' option if you don't want memory cgroups [ 0.000000] Memory: 1005700K/1048576K available (8398K kernel code, 876K rwdata, 3412K rodata, 469K init, 1015K bss, 42876K reserved, 270336K highmem) [ 0.000000] Virtual kernel memory layout: [ 0.000000] vector : 0xffff0000 - 0xffff1000 ( 4 kB) [ 0.000000] fixmap : 0xfff00000 - 0xfffe0000 ( 896 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 - 0xc0b90a44 (11811 kB) [ 0.000000] .init : 0xc0b91000 - 0xc0c06700 ( 470 kB) [ 0.000000] .data : 0xc0c08000 - 0xc0ce3168 ( 877 kB) [ 0.000000] .bss : 0xc0ce3168 - 0xc0de0e7c (1016 kB) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 [ 0.000000] Hierarchical RCU implementation. [ 0.000000] RCU restricting CPUs from NR_CPUS=4 to nr_cpu_ids=2. [ 0.000000] NR_IRQS:16 nr_irqs:16 16 [ 0.000000] L310 cache controller enabled [ 0.000000] l2x0: 16 ways, CACHE_ID 0x410000c8, AUX_CTRL 0x32050000, Cache size: 524288 B [ 0.000000] sched_clock: 32 bits at 66MHz, resolution 15ns, wraps every 65075ms [ 0.000000] CPU identified as i.MX6DL, silicon rev 1.1 [ 0.000000] Console: colour dummy device 80x30 [ 0.000983] Calibrating delay loop... 1581.05 BogoMIPS (lpj=7905280) [ 0.090138] pid_max: default: 32768 minimum: 301 [ 0.090354] Security Framework initialized [ 0.090381] AppArmor: AppArmor initialized [ 0.090591] Mount-cache hash table entries: 512 [ 0.096329] Initializing cgroup subsys memory [ 0.096367] Initializing cgroup subsys devices [ 0.096378] Initializing cgroup subsys freezer [ 0.096388] Initializing cgroup subsys net_cls [ 0.096397] Initializing cgroup subsys blkio [ 0.096406] Initializing cgroup subsys perf_event [ 0.096501] CPU: Testing write buffer coherency: ok [ 0.097054] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 [ 0.097178] Setting up static identity map for 0xc07ef678 - 0xc07ef6d0 [ 0.099373] CPU1: Booted secondary processor [ 0.187106] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 [ 0.187237] Brought up 2 CPUs [ 0.187259] SMP: Total of 2 processors activated (3162.11 BogoMIPS). [ 0.187268] CPU: All CPU(s) started in SVC mode. [ 0.188420] devtmpfs: initialized [ 0.193858] xor: measuring software checksum speed [ 0.287088] arm4regs : 1112.400 MB/sec [ 0.387085] 8regs : 863.200 MB/sec [ 0.487087] 32regs : 908.800 MB/sec [ 0.487096] xor: using function: arm4regs (1112.400 MB/sec) [ 0.487115] pinctrl core: initialized pinctrl subsystem [ 0.487511] regulator-dummy: no parameters [ 0.497378] NET: Registered protocol family 16 [ 0.527832] DMA: preallocated 256 KiB pool for atomic coherent allocations [ 0.536382] syscon 20c8000.anatop: regmap [mem 0x020c8000-0x020c8fff] registered [ 0.536653] vdd1p1: 800 <--> 1375 mV at 1100 mV [ 0.536984] vdd3p0: 2800 <--> 3150 mV at 3000 mV [ 0.537379] vdd2p5: 2000 <--> 2750 mV at 2400 mV [ 0.537679] cpu: 725 <--> 1450 mV at 1150 mV [ 0.537945] vddpu: 725 <--> 1450 mV at 1150 mV [ 0.538229] vddsoc: 725 <--> 1450 mV at 1200 mV [ 0.539522] syscon 20e0000.iomuxc-gpr: regmap [mem 0x020e0000-0x020e0037] registered [ 0.541729] syscon 21bc000.ocotp: regmap [mem 0x021bc000-0x021bffff] registered [ 0.543010] No ATAGs? [ 0.543038] hw-breakpoint: found 5 (+1 reserved) breakpoint and 1 watchpoint registers. [ 0.543049] hw-breakpoint: maximum watchpoint size is 4 bytes. [ 0.544422] imx6dl-pinctrl 20e0000.iomuxc: initialized IMX pinctrl driver [ 0.566298] bio: create slab at 0 [ 0.727537] raid6: int32x1 76 MB/s [ 0.897214] raid6: int32x2 135 MB/s [ 1.067283] raid6: int32x4 130 MB/s [ 1.237136] raid6: int32x8 141 MB/s [ 1.237146] raid6: using algorithm int32x8 (141 MB/s) [ 1.237153] raid6: using intx1 recovery algorithm [ 1.237907] edma-dma-engine edma-dma-engine.0: Can't allocate PaRAM dummy slot [ 1.237935] edma-dma-engine: probe of edma-dma-engine.0 failed with error -5 [ 1.238452] 2P5V: 2500 mV [ 1.238667] 3P3V: 3300 mV [ 1.241362] SCSI subsystem initialized [ 1.241863] usbcore: registered new interface driver usbfs [ 1.241925] usbcore: registered new interface driver hub [ 1.242062] usbcore: registered new device driver usb [ 1.243378] i2c i2c-0: IMX I2C adapter registered [ 1.244251] i2c i2c-1: IMX I2C adapter registered [ 1.244583] i2c i2c-2: IMX I2C adapter registered [ 1.244830] media: Linux media interface: v0.10 [ 1.244882] Linux video capture interface: v2.00 [ 1.244962] pps_core: LinuxPPS API ver. 1 registered [ 1.244971] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 1.244993] PTP clock support registered [ 1.245068] EDAC MC: Ver: 3.0.0 [ 1.246312] NetLabel: Initializing [ 1.246324] NetLabel: domain hash size = 128 [ 1.246330] NetLabel: protocols = UNLABELED CIPSOv4 [ 1.246397] NetLabel: unlabeled traffic allowed by default [ 1.246590] nfc: nfc_init: NFC Core ver 0.1 [ 1.246651] NET: Registered protocol family 39 [ 1.247194] Switched to clocksource mxc_timer1 [ 1.264188] AppArmor: AppArmor Filesystem Enabled [ 1.274883] NET: Registered protocol family 2 [ 1.275689] TCP established hash table entries: 8192 (order: 4, 65536 bytes) [ 1.276009] TCP bind hash table entries: 8192 (order: 4, 65536 bytes) [ 1.276156] TCP: Hash tables configured (established 8192 bind 8192) [ 1.276397] TCP: reno registered [ 1.276463] UDP hash table entries: 512 (order: 2, 16384 bytes) [ 1.276546] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes) [ 1.276892] NET: Registered protocol family 1 [ 1.277426] RPC: Registered named UNIX socket transport module. [ 1.277438] RPC: Registered udp transport module. [ 1.277445] RPC: Registered tcp transport module. [ 1.277452] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 1.278477] hw perfevents: enabled with ARMv7 Cortex-A9 PMU driver, 7 counters available [ 1.279118] wandboard-rfkill rfkill.14: Wandboard rfkill initialization [ 1.279198] wandboard-rfkill rfkill.14: initialize wifi chip [ 1.307339] wandboard-rfkill rfkill.14: wifi-rfkill registered. [ 1.307390] wandboard-rfkill rfkill.14: initialize bluetooth chip [ 1.337350] wandboard-rfkill rfkill.14: bluetooth-rfkill registered. [ 1.338586] audit: initializing netlink socket (disabled) [ 1.338626] type=2000 audit(1.329:1): initialized [ 1.553918] bounce pool size: 64 pages [ 1.558420] VFS: Disk quotas dquot_6.5.2 [ 1.558710] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) [ 1.561260] NFS: Registering the id_resolver key type [ 1.561318] Key type id_resolver registered [ 1.561327] Key type id_legacy registered [ 1.561385] fuse init (API version 7.22) [ 1.562276] bio: create slab at 1 [ 1.563338] Btrfs loaded [ 1.563537] msgmni has been set to 1468 [ 1.564984] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) [ 1.565139] io scheduler noop registered [ 1.565149] io scheduler deadline registered [ 1.565307] io scheduler cfq registered (default) [ 1.565809] imx-weim 21b8000.weim: Driver registered. [ 1.572508] imx-sdma 20ec000.sdma: initialized [ 1.635660] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled [ 1.636996] Serial: IMX driver [ 1.637386] 2020000.serial: ttymxc0 at MMIO 0x2020000 (irq = 58) is a IMX [ 2.453961] console [ttymxc0] enabled [ 2.458265] 21ec000.serial: ttymxc2 at MMIO 0x21ec000 (irq = 60) is a IMX [ 2.466128] serial: Freescale lpuart driver [ 2.470695] [drm] Initialized drm 1.1.0 20060810 [ 2.475410] usbcore: registered new interface driver udl [ 2.484618] imx6dl-pinctrl 20e0000.iomuxc: pin MX6DL_PAD_GPIO_16 already requested by 21a8000.i2c; cannot claim for 2188000.ethernet [ 2.496578] imx6dl-pinctrl 20e0000.iomuxc: pin-133 (2188000.ethernet) status -22 [ 2.504001] imx6dl-pinctrl 20e0000.iomuxc: could not request pin 133 on device 20e0000.iomuxc [ 2.512552] fec 2188000.ethernet: Error applying setting, reverse things back [ 2.524793] libphy: fec_enet_mii_bus: probed [ 2.529557] fec 2188000.ethernet eth0: registered PHC device 0 [ 2.536342] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver [ 2.542901] ehci-omap: OMAP-EHCI Host Controller driver [ 2.548384] usbcore: registered new interface driver usb-storage [ 2.554888] platform 2184200.usb: Driver imx_usb requests probe deferral [ 2.562335] mousedev: PS/2 mouse device common for all mice [ 2.568912] snvs_rtc 20cc034.snvs-rtc-lp: rtc core: registered 20cc034.snvs-rtc-lp as rtc0 [ 2.577627] b2c2-flexcop: B2C2 FlexcopII/II(b)/III digital TV receiver chip loaded successfully [ 2.586620] usbcore: registered new interface driver dvb_usb_vp7045 [ 2.592975] usbcore: registered new interface driver dvb_usb_vp702x [ 2.599339] usbcore: registered new interface driver dvb_usb_gp8psk [ 2.605665] usbcore: registered new interface driver dvb_usb_dtt200u [ 2.612099] usbcore: registered new interface driver dvb_usb_a800 [ 2.618271] usbcore: registered new interface driver dvb_usb_dibusb_mb [ 2.624875] usbcore: registered new interface driver dvb_usb_dibusb_mc [ 2.631483] usbcore: registered new interface driver dvb_usb_nova_t_usb2 [ 2.638260] usbcore: registered new interface driver dvb_usb_umt_010 [ 2.644675] usbcore: registered new interface driver dvb_usb_m920x [ 2.650954] usbcore: registered new interface driver dvb_usb_digitv [ 2.657298] usbcore: registered new interface driver dvb_usb_cxusb [ 2.663538] usbcore: registered new interface driver dvb_usb_ttusb2 [ 2.669885] usbcore: registered new interface driver dvb_usb_dib0700 [ 2.676315] usbcore: registered new interface driver opera1 [ 2.681982] usbcore: registered new interface driver dvb_usb_af9005 [ 2.688372] usbcore: registered new interface driver pctv452e [ 2.694182] usbcore: registered new interface driver dw2102 [ 2.699879] usbcore: registered new interface driver dvb_usb_dtv5100 [ 2.706309] usbcore: registered new interface driver cinergyT2 [ 2.712231] usbcore: registered new interface driver dvb_usb_friio [ 2.718492] usbcore: registered new interface driver dvb_usb_az6027 [ 2.724844] usbcore: registered new interface driver dvb_usb_technisat_usb2 [ 2.731906] usbcore: registered new interface driver dvb_usb_af9015 [ 2.738270] usbcore: registered new interface driver dvb_usb_af9035 [ 2.744608] usbcore: registered new interface driver dvb_usb_anysee [ 2.750952] usbcore: registered new interface driver dvb_usb_au6610 [ 2.757321] usbcore: registered new interface driver dvb_usb_az6007 [ 2.763671] usbcore: registered new interface driver dvb_usb_ce6230 [ 2.770038] usbcore: registered new interface driver dvb_usb_ec168 [ 2.776289] usbcore: registered new interface driver dvb_usb_it913x [ 2.782660] usbcore: registered new interface driver dvb_usb_lmedm04 [ 2.789115] usbcore: registered new interface driver dvb_usb_gl861 [ 2.795362] usbcore: registered new interface driver dvb_usb_mxl111sf [ 2.801900] usbcore: registered new interface driver dvb_usb_rtl28xxu [ 2.808438] usbcore: registered new interface driver smsusb [ 2.814111] usbcore: registered new interface driver b2c2_flexcop_usb [ 2.820633] usbcore: registered new interface driver zr364xx [ 2.826361] usbcore: registered new interface driver stkwebcam [ 2.832274] usbcore: registered new interface driver s2255 [ 2.837912] usbcore: registered new interface driver uvcvideo [ 2.843662] USB Video Class driver (1.1.1) [ 2.847778] gspca_main: v2.14.0 registered [ 2.851939] usbcore: registered new interface driver Philips webcam [ 2.858226] cpia2: V4L-Driver for Vision CPiA2 based cameras v3.0.1 [ 2.864555] usbcore: registered new interface driver cpia2 [ 2.870114] usbcore: registered new interface driver sn9c102 [ 2.875777] au0828 driver loaded [ 2.879107] usbcore: registered new interface driver au0828 [ 2.884736] usbcore: registered new interface driver hdpvr [ 2.890441] usbcore: registered new interface driver pvrusb2 [ 2.896106] pvrusb2: V4L in-tree version:Hauppauge WinTV-PVR-USB2 MPEG2 Encoder/Tuner [ 2.903959] pvrusb2: Debug mask is 31 (0x1f) [ 2.908324] usbcore: registered new interface driver usbvision [ 2.914160] USBVision USB Video Device Driver for Linux : 0.9.11 [ 2.920268] usbcore: registered new interface driver stk1160 [ 2.925990] usbcore: registered new interface driver cx231xx [ 2.931675] cx231xx: Cx231xx dvb Extension initialized [ 2.936887] usbcore: registered new interface driver tm6000 [ 2.942558] usbcore: registered new interface driver em28xx [ 2.948156] Em28xx: Initialized (Em28xx dvb Extension) extension [ 2.954167] Em28xx: Initialized (Em28xx Input Extension) extension [ 2.960425] usbcore: registered new interface driver usbtv [ 2.967401] imx2-wdt 20bc000.wdog: IMX2+ Watchdog Timer enabled. timeout=60s (nowayout=1) [ 2.975937] cpuidle: using governor ladder [ 2.980069] cpuidle: using governor menu [ 2.984029] sdhci: Secure Digital Host Controller Interface driver [ 2.990232] sdhci: Copyright(c) Pierre Ossman [ 2.994854] sdhci-pltfm: SDHCI platform and OF driver helper [ 3.002493] mmc0: no vqmmc regulator found [ 3.006600] mmc0: no vmmc regulator found [ 3.057247] mmc0: SDHCI controller on 2190000.usdhc [2190000.usdhc] using ADMA [ 3.065936] mmc1: no vqmmc regulator found [ 3.070070] mmc1: no vmmc regulator found [ 3.117224] mmc1: SDHCI controller on 2194000.usdhc [2194000.usdhc] using ADMA [ 3.130117] mmc2: no vqmmc regulator found [ 3.134246] mmc2: no vmmc regulator found [ 3.164760] mmc1: queuing unknown CIS tuple 0x80 (50 bytes) [ 3.177220] mmc2: SDHCI controller on 2198000.usdhc [2198000.usdhc] using ADMA [ 3.184700] ledtrig-cpu: registered to indicate activity on CPUs [ 3.191231] hidraw: raw HID events driver (C) Jiri Kosina [ 3.197151] usbcore: registered new interface driver usbhid [ 3.202778] usbhid: USB HID core driver [ 3.202798] mmc1: queuing unknown CIS tuple 0x80 (7 bytes) [ 3.212757] ashmem: initialized [ 3.213914] mmc1: queuing unknown CIS tuple 0x80 (4 bytes) [ 3.222553] [drm] Supports vblank timestamp caching Rev 1 (10.10.2010). [ 3.229206] [drm] No driver support for vblank timestamp query. [ 3.235135] [drm] Initialized imx-drm 1.0.0 20120507 on minor 0 [ 3.242476] imx-ipuv3 2400000.ipu: IPUv3H probed [ 3.243683] mmc1: queuing unknown CIS tuple 0x02 (1 bytes) [ 3.251021] mmc1: new SDIO card at address 0001 [ 3.257813] imx-hdmi 120000.hdmi: Detected HDMI controller 0x13:0x1a:0xa0:0xc1 [ 3.265237] imx-hdmi 120000.hdmi: initialized [ 3.270496] oprofile: using arm/armv7-ca9 [ 3.274905] TCP: cubic registered [ 3.278739] NET: Registered protocol family 10 [ 3.284346] mip6: Mobile IPv6 [ 3.287355] NET: Registered protocol family 17 [ 3.291884] Key type dns_resolver registered [ 3.296613] VFP support v0.3: implementor 41 architecture 3 part 30 variant 9 rev 4 [ 3.304385] failed to init OPP table [ 3.308385] imx6q-cpufreq imx6q-cpufreq.0: failed to get clocks [ 3.314322] imx6q-cpufreq: probe of imx6q-cpufreq.0 failed with error -2 [ 3.321088] ThumbEE CPU extension supported. [ 3.325381] Registering SWP/SWPB emulation handler [ 3.330753] registered taskstats version 1 [ 3.336858] ci_hdrc ci_hdrc.0: doesn't support gadget [ 3.341967] ci_hdrc ci_hdrc.0: EHCI Host Controller [ 3.347094] ci_hdrc ci_hdrc.0: new USB bus registered, assigned bus number 1 [ 3.357136] mmc2: host does not support reading read-only switch. assuming write-enable. [ 3.367342] ci_hdrc ci_hdrc.0: USB 2.0 started, EHCI 1.00 [ 3.372818] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002 [ 3.379641] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 3.386889] usb usb1: Product: EHCI Host Controller [ 3.387933] mmc2: new SDHC card at address e624 [ 3.388455] mmcblk0: mmc2:e624 SU08G 7.40 GiB [ 3.400847] usb usb1: Manufacturer: Linux 3.11.0+ ehci_hcd [ 3.400928] mmcblk0: p1 p2 p3 p4 [ 3.409684] usb usb1: SerialNumber: ci_hdrc.0 [ 3.414586] hub 1-0:1.0: USB hub found [ 3.418384] hub 1-0:1.0: 1 port detected [ 3.422878] snvs_rtc 20cc034.snvs-rtc-lp: setting system clock to 1970-01-01 00:00:32 UTC (32) [ 3.431543] sr_init: No PMIC hook to init smartreflex [ 3.436699] sr_init: platform driver register failed for SR [ 3.443413] imx-hdmi 120000.hdmi: failed to read edid [ 3.448529] imx_hdmi_connector_mode_valid [ 3.452541] imx_hdmi_connector_mode_valid [ 3.456552] imx_hdmi_connector_mode_valid [ 3.460582] imx_hdmi_connector_mode_valid [ 3.464593] imx_hdmi_connector_mode_valid [ 3.474696] imx_hdmi_encoder_prepare [ 3.474699] imx_hdmi_poweroff [ 3.474709] imx-hdmi 120000.hdmi: imx_hdmi_phy_disable [ 3.474798] imx_hdmi_encoder_mode_set [ 3.474805] imx-hdmi 120000.hdmi: imx_hdmi_setup [ 3.474813] imx-hdmi 120000.hdmi: imx_hdmi_setup - video mode changed [ 3.474819] imx-hdmi 120000.hdmi: Non-CEA mode used in HDMI [ 3.474824] imx-hdmi 120000.hdmi: hdmi_av_composer [ 3.474831] imx-hdmi 120000.hdmi: x=800, y=600, refresh=60 [ 3.474837] imx-hdmi 120000.hdmi: final pixclk = 39790080 [ 3.474849] imx-hdmi 120000.hdmi: hdmi_av_composer exit [ 3.474855] imx-hdmi 120000.hdmi: imx_hdmi_phy_init [ 3.474864] imx-hdmi 120000.hdmi: hdmi_phy_configure [ 3.488947] imx-hdmi 120000.hdmi: PHY PLL not locked [ 3.488954] imx-hdmi 120000.hdmi: imx_hdmi_phy_init: hdmi_phy_configure returned -22 [ 3.488964] imx-hdmi 120000.hdmi: hdmi_phy_configure [ 3.502998] imx-hdmi 120000.hdmi: PHY PLL not locked [ 3.503005] imx-hdmi 120000.hdmi: imx_hdmi_phy_init: hdmi_phy_configure returned -22 [ 3.503011] imx-hdmi 120000.hdmi: imx_hdmi_enable_video_path [ 3.503062] imx_hdmi_poweron [ 3.503067] imx-hdmi 120000.hdmi: imx_hdmi_setup [ 3.503074] imx-hdmi 120000.hdmi: imx_hdmi_setup - video mode changed [ 3.503080] imx-hdmi 120000.hdmi: Non-CEA mode used in HDMI [ 3.503086] imx-hdmi 120000.hdmi: hdmi_av_composer [ 3.503095] imx-hdmi 120000.hdmi: x=800, y=600, refresh=60 [ 3.503100] imx-hdmi 120000.hdmi: final pixclk = 39790080 [ 3.503112] imx-hdmi 120000.hdmi: hdmi_av_composer exit [ 3.503117] imx-hdmi 120000.hdmi: imx_hdmi_phy_init [ 3.503126] imx-hdmi 120000.hdmi: hdmi_phy_configure [ 3.514171] imx-hdmi 120000.hdmi: imx_hdmi_phy_init: hdmi_phy_configure returned 0 [ 3.514179] imx-hdmi 120000.hdmi: hdmi_phy_configure [ 3.525235] imx-hdmi 120000.hdmi: imx_hdmi_phy_init: hdmi_phy_configure returned 0 [ 3.525241] imx-hdmi 120000.hdmi: imx_hdmi_enable_video_path [ 3.528828] Console: switching to colour frame buffer device 100x37 [ 3.713443] imx-drm imx-drm: fb0: frame buffer device [ 3.718603] imx-drm imx-drm: registered panic notifier [ 3.742706] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null) [ 3.750899] VFS: Mounted root (ext4 filesystem) readonly on device 179:2. [ 3.773900] devtmpfs: mounted [ 3.777591] Freeing unused kernel memory: 468K (c0b91000 - c0c06000) [ 4.182700] EXT4-fs (mmcblk0p2): warning: mounting fs with errors, running e2fsck is recommended [ 4.193810] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null) Starting logging: OK Populating /dev using udev: [ 4.828424] udevd[76]: starting version 182 done [ 5.179686] device-mapper: uevent: version 1.0.3 [ 5.185667] device-mapper: ioctl: 4.25.0-ioctl (2013-06-26) initialised: dm-devel@redhat.com Setting up DMRAID devices... no block devices found Initializing random number generator... done. [ 5.442575] cfg80211: Calling CRDA to update world regulatory domain Starting network plug daemon: [ 5.528713] brcmfmac: brcmf_sdio_chip_drivestrengthinit: No SDIO Drive strength init done for chip 4329 rev 3 pmurev 6 [ 5.571835] Bluetooth: Core ver 2.16 [ 5.575494] NET: Registered protocol family 31 [ 5.580036] Bluetooth: HCI device and connection manager initialized [ 5.586428] Bluetooth: HCI socket layer initialized [ 5.591400] Bluetooth: L2CAP socket layer initialized [ 5.596495] Bluetooth: SCO socket layer initialized /etc/netplug.d/netplug eth0 probe -> pid 127 [ 5.610853] Bluetooth: Generic Bluetooth SDIO driver ver 0.1 [ 5.690527] fec 2188000.ethernet eth0: Freescale FEC PHY driver [Generic PHY] (mii_bus:phy_addr=2188000.ethernet:01, irq=-1) [ 5.701962] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready /etc/netplug.d/netplug eth1 probe -> pid 129 /etc/netplug.d/netplug eth2 probe -> pid 135 /etc/netplug.d/netplug eth3 probe -> pid 140 /etc/netplug.d/netplug eth4 probe -> pid 146 /etc/netplug.d/netplug eth5 probe -> pid 152 /etc/netplug.d/netplug eth6 probe -> pid 159 /etc/netplug.d/netplug eth7 probe -> pid 164 [ 6.630407] brcmfmac: brcmf_sdbrcm_download_code_file: Fail to request firmware -2 [ 6.638046] brcmfmac: _brcmf_sdbrcm_download_firmware: dongle image file download failed [ 6.646233] brcmfmac: brcmf_bus_start: brcmf_sdbrcm_bus_init failed -1 [ 6.652806] brcmfmac: brcmf_sdbrcm_probe: dongle is not responding [ 6.659887] brcmfmac: brcmf_sdio_probe: device attach failed [ 6.665678] brcmfmac: brcmf_ops_sdio_probe: F2 error, probe failed -19... /etc/netplug.d/netplug eth8 probe -> pid 172 /etc/netplug.d/netplug eth9 probe -> pid 178 /etc/netplug.d/netplug eth10 probe -> pid 183 /etc/netplug.d/netplug eth11 probe -> pid 188 /etc/netplug.d/netplug eth12 probe -> pid 193 /etc/netplug.d/netplug eth13 probe -> pid 198 /etc/netplug.d/netplug eth14 probe -> pid 203 /etc/netplug.d/netplug eth15 probe -> pid 208 touch: cannot touch '/var/lock/subsys/netplugd': No such file or directory Starting system message bus: done Starting network... udhcpc (v1.21.1) started grep: /etc/resolv.conf: No such file or directory Failed to kill daemon: No such file or directory Sending discover... [ 8.687577] libphy: 2188000.ethernet:01 - Link is Up - 100/Full [ 8.693525] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Sending discover... Sending select for 192.168.1.4... Lease of 192.168.1.4 obtained, lease time 268435455 Failed to kill daemon: No such file or directory deleting routers route: SIOCDELRT: No such process adding dns 192.168.1.1 Starting dropbear sshd: OK Starting pulseaudio: W: [pulseaudio] main.c: Running in system mode, but --disallow-exit not set! W: [pulseaudio] main.c: Running in system mode, but --disallow-module-loading not set! N: [pulseaudio] main.c: Running in system mode, forcibly disabling SHM mode! N: [pulseaudio] main.c: Running in system mode, forcibly disabling exit idle time! OK Starting sshd: OK Starting network management services:. Starting SMB services: done Starting NMB services: done Starting mpd: OK Starting atd: OK Starting input-event-daemon: input-event-daemon: open(/dev/input/event0): No such file or directory done Starting XDM: done bash-4.2# 2013-09-24 05:34:32 PM DEBUG: sendline : export PS1="bash-4.2 [rc=$(echo \$?)]# " 2013-09-24 05:34:32 PM DEBUG: send : export PS1="bash-4.2 [rc=$(echo \$?)]# " 2013-09-24 05:34:34 PM DEBUG: send : 2013-09-24 05:34:34 PM DEBUG: expect (120): 'bash-4.2 \[rc=(\d+)\]# ' export PS1="bash-4.2 [ 15.882005] imx_hdmi_encoder_dpms [ 15.882009] imx_hdmi_poweroff [ 15.882020] imx-hdmi 120000.hdmi: imx_hdmi_phy_disable [ 15.882029] imx-hdmi 120000.hdmi: imx_hdmi_phy_disable - exit [rc=$(echo \$?)]# "[ 16.849655] imx_hdmi_encoder_dpms [ 16.849657] imx_hdmi_poweron [ 16.849668] imx-hdmi 120000.hdmi: imx_hdmi_setup [ 16.849677] imx-hdmi 120000.hdmi: imx_hdmi_setup - video mode changed [ 16.849682] imx-hdmi 120000.hdmi: Non-CEA mode used in HDMI [ 16.849688] imx-hdmi 120000.hdmi: hdmi_av_composer [ 16.849697] imx-hdmi 120000.hdmi: x=800, y=600, refresh=60 [ 16.849704] imx-hdmi 120000.hdmi: final pixclk = 39790080 [ 16.849715] imx-hdmi 120000.hdmi: hdmi_av_composer exit [ 16.849722] imx-hdmi 120000.hdmi: imx_hdmi_phy_init [ 16.849730] imx-hdmi 120000.hdmi: hdmi_phy_configure [ 16.860797] imx-hdmi 120000.hdmi: imx_hdmi_phy_init: hdmi_phy_configure returned 0 [ 16.860806] imx-hdmi 120000.hdmi: hdmi_phy_configure [ 16.871851] imx-hdmi 120000.hdmi: imx_hdmi_phy_init: hdmi_phy_configure returned 0 [ 16.871857] imx-hdmi 120000.hdmi: imx_hdmi_enable_video_path bash-4.2 [rc=0]# 2013-09-24 05:34:34 PM INFO: Waiting for network to come up 2013-09-24 05:34:34 PM DEBUG: expect (1): '['.+', , ]' 2013-09-24 05:34:35 PM DEBUG: sendline : LC_ALL=C ping -W4 -c1 192.168.1.203 2013-09-24 05:34:35 PM DEBUG: send : LC_ALL=C ping -W4 -c1 192.168.1.203 2013-09-24 05:34:37 PM DEBUG: send : 2013-09-24 05:34:37 PM DEBUG: expect (60): '['1 received|1 packets received', '0 received|0 packets received', 'Network is unreachable']' LC_ALL=C ping -W4 -c1 192.168.1.203 PING 192.168.1.203 (192.168.1.203): 56 data bytes 64 bytes from 192.168.1.203: seq=0 ttl=64 time=0.837 ms --- 192.168.1.203 ping statistics --- 1 packets transmitted, 1 packets received, 0% pac2013-09-24 05:34:37 PM DEBUG: expect (29): 'bash-4.2 \[rc=(\d+)\]# ' ket loss round-trip min/avg/max = 0.837/0.837/0.837 ms bash-4.2 [rc=0]# 2013-09-24 05:34:37 PM DEBUG: expect (1): '['.+', , ]' 2013-09-24 05:34:38 PM DEBUG: sendline : ifconfig eth0 | grep 'inet addr' | awk -F: '{print $2}' |awk '{print "<" $1 ">"}' 2013-09-24 05:34:38 PM DEBUG: send : ifconfig eth0 | grep 'inet addr' | awk -F: '{print $2}' |awk '{print "<" $1 ">"}' 2013-09-24 05:34:42 PM DEBUG: send : 2013-09-24 05:34:42 PM DEBUG: expect (60): '['<(\\d?\\d?\\d?\\.\\d?\\d?\\d?\\.\\d?\\d?\\d?\\.\\d?\\d?\\d?)>', , ]' ifconfig eth0 | grep 'inet addr' | awk -F: '{print $2}' |awk '{ print "<" $1 ">"}' <192.168.1.4> 2013-09-24 05:34:43 PM DEBUG: expect (29): 'bash-4.2 \[rc=(\d+)\]# ' bash-4.2 [rc=0]# 2013-09-24 05:34:43 PM DEBUG: Target image IP is 192.168.1.4 2013-09-24 05:34:43 PM DEBUG: expect (1): '['.+', , ]' 2013-09-24 05:34:44 PM DEBUG: sendline : echo "device_version=$(lava-master-image-info --master-image-hwpack | sed 's/[^0-9-]//g; s/^-\+//')/$(lava-master-image-info --master-image-rootfs | sed 's/[^0-9-]//g; s/^-\+//')" 2013-09-24 05:34:44 PM DEBUG: send : echo "device_version=$(lava-master-image-info --master-image-hwpack | sed 's/[^0-9-]//g; s/^-\+//')/$(lava-master-image-info --master-image-rootfs | sed 's/[^0-9-]//g; s/^-\+//')" 2013-09-24 05:34:53 PM DEBUG: send : 2013-09-24 05:34:53 PM DEBUG: expect (5): '['device_version=(\\d+-\\d+/\\d+-\\d+)', , ]' echo "device_version=$(lava-master-image-info --master-image-hw pack | sed 's/[^0-9-]//g; s/^-\+//')/$(lava-master-image-info --master-image-roo tfs | sed 's/[^0-9-]//g; s/^-\+//')" bash: lava-master-image-info: command not found bash: lava-master-image-info: command not found device_version=/ bash-4.2 [rc=0]# 2013-09-24 05:34:58 PM WARNING: Could not determine image version! 2013-09-24 05:34:58 PM INFO: Setting up http proxy 2013-09-24 05:34:58 PM DEBUG: expect (1): '['.+', , ]' 2013-09-24 05:34:58 PM DEBUG: expect (1): '['.+', , ]' 2013-09-24 05:34:59 PM DEBUG: sendline : export http_proxy=http://192.168.1.203:3128/ 2013-09-24 05:34:59 PM DEBUG: send : export http_proxy=http://192.168.1.203:3128/ 2013-09-24 05:35:01 PM DEBUG: send : 2013-09-24 05:35:01 PM DEBUG: expect (15): 'bash-4.2 \[rc=(\d+)\]# ' export http_proxy=http://192.168.1.203:3128/ bash-4.2 [rc=0]# 2013-09-24 05:35:01 PM INFO: System is in master image now 2013-09-24 05:35:01 PM DEBUG: expect (1): '['.+', , ]' 2013-09-24 05:35:02 PM DEBUG: sendline : mount /dev/disk/by-label/testrootfs /mnt 2013-09-24 05:35:02 PM DEBUG: send : mount /dev/disk/by-label/testrootfs /mnt 2013-09-24 05:35:04 PM DEBUG: send : 2013-09-24 05:35:04 PM DEBUG: expect (600): 'bash-4.2 \[rc=(\d+)\]# ' mount /dev/disk/by-label/testrootfs /mnt [ 46.676747] EXT4-fs (mmcblk0p3): recovery complete [ 46.681626] EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null) bash-4.2 [rc=0]# 2013-09-24 05:35:04 PM DEBUG: expect (1): '['.+', , ]' 2013-09-24 05:35:05 PM DEBUG: sendline : ls /mnt/lava > /dev/null 2013-09-24 05:35:05 PM DEBUG: send : ls /mnt/lava > /dev/null 2013-09-24 05:35:06 PM DEBUG: send : 2013-09-24 05:35:06 PM DEBUG: expect (600): 'bash-4.2 \[rc=(\d+)\]# ' ls /mnt/lava > /dev/null bash-4.2 [rc=0]# 2013-09-24 05:35:06 PM DEBUG: expect (1): '['.+', , ]' 2013-09-24 05:35:07 PM DEBUG: sendline : nice tar -cf /tmp/fs.tgz -C /mnt lava 2013-09-24 05:35:07 PM DEBUG: send : nice tar -cf /tmp/fs.tgz -C /mnt lava 2013-09-24 05:35:09 PM DEBUG: send : 2013-09-24 05:35:09 PM DEBUG: expect (600): 'bash-4.2 \[rc=(\d+)\]# ' nice tar -cf /tmp/fs.tgz -C /mnt lava bash-4.2 [rc=0]# 2013-09-24 05:35:09 PM DEBUG: expect (1): '['.+', , ]' 2013-09-24 05:35:10 PM DEBUG: sendline : cd /tmp 2013-09-24 05:35:10 PM DEBUG: send : cd /tmp 2013-09-24 05:35:11 PM DEBUG: send : 2013-09-24 05:35:11 PM DEBUG: expect (600): 'bash-4.2 \[rc=(\d+)\]# ' cd /tmp bash-4.2 [rc=0]# 2013-09-24 05:35:11 PM DEBUG: sendline : python -m SimpleHTTPServer 0 2>/dev/null 2013-09-24 05:35:11 PM DEBUG: send : python -m SimpleHTTPServer 0 2>/dev/null 2013-09-24 05:35:13 PM DEBUG: send : 2013-09-24 05:35:13 PM DEBUG: expect (1200): '['Serving HTTP on 0.0.0.0 port (\\d+) \\.\\.', , ]' python -m SimpleHTTPServer 0 2>/dev/null [ 61.607364] imx-sdma 20ec000.sdma: firmware not found Serving HTTP on 0.0.0.0 port 59727 ...2013-09-24 05:35:24 PM INFO: download_with_retry::About to download http://192.168.1.4:59727/fs.tgz to the host 2013-09-24 05:35:24 PM INFO: Downloading image: http://192.168.1.4:59727/fs.tgz 2013-09-24 05:35:24 PM WARNING: unable to download: 'Traceback (most recent call last):\n File "/srv/lava/.cache/git-cache/exports/lava-dispatcher/2013-09-20-3ca1af8/lava_dispatcher/downloader.py", line 185, in download_with_retry\n return download_image(url, context, imgdir, decompress)\n File "/srv/lava/.cache/git-cache/exports/lava-dispatcher/2013-09-20-3ca1af8/lava_dispatcher/downloader.py", line 165, in download_image\n with reader(url, context.config.lava_proxy, cookies) as r:\n File "/usr/lib/python2.7/contextlib.py", line 17, in __enter__\n return self.gen.next()\n File "/srv/lava/.cache/git-cache/exports/lava-dispatcher/2013-09-20-3ca1af8/lava_dispatcher/downloader.py", line 67, in _http_stream\n resp = opener.open(url, timeout=30)\n File "/usr/lib/python2.7/urllib2.py", line 400, in open\n response = self._open(req, data)\n File "/usr/lib/python2.7/urllib2.py", line 418, in _open\n \'_open\', req)\n File "/usr/lib/python2.7/urllib2.py", line 378, in _call_chain\n result = func(*args)\n File "/usr/lib/python2.7/urllib2.py", line 1207, in http_open\n return self.do_open(httplib.HTTPConnection, req)\n File "/usr/lib/python2.7/urllib2.py", line 1177, in do_open\n raise URLError(err)\nURLError: \n' 2013-09-24 05:35:24 PM INFO: Sleep one minute and retry (1) 2013-09-24 05:36:24 PM INFO: Downloading image: http://192.168.1.4:59727/fs.tgz 2013-09-24 05:36:24 PM WARNING: unable to download: 'Traceback (most recent call last):\n File "/srv/lava/.cache/git-cache/exports/lava-dispatcher/2013-09-20-3ca1af8/lava_dispatcher/downloader.py", line 185, in download_with_retry\n return download_image(url, context, imgdir, decompress)\n File "/srv/lava/.cache/git-cache/exports/lava-dispatcher/2013-09-20-3ca1af8/lava_dispatcher/downloader.py", line 165, in download_image\n with reader(url, context.config.lava_proxy, cookies) as r:\n File "/usr/lib/python2.7/contextlib.py", line 17, in __enter__\n return self.gen.next()\n File "/srv/lava/.cache/git-cache/exports/lava-dispatcher/2013-09-20-3ca1af8/lava_dispatcher/downloader.py", line 67, in _http_stream\n resp = opener.open(url, timeout=30)\n File "/usr/lib/python2.7/urllib2.py", line 400, in open\n response = self._open(req, data)\n File "/usr/lib/python2.7/urllib2.py", line 418, in _open\n \'_open\', req)\n File "/usr/lib/python2.7/urllib2.py", line 378, in _call_chain\n result = func(*args)\n File "/usr/lib/python2.7/urllib2.py", line 1207, in http_open\n return self.do_open(httplib.HTTPConnection, req)\n File "/usr/lib/python2.7/urllib2.py", line 1177, in do_open\n raise URLError(err)\nURLError: \n' 2013-09-24 05:36:24 PM INFO: Sleep one minute and retry (2) 2013-09-24 05:37:24 PM INFO: Downloading image: http://192.168.1.4:59727/fs.tgz 2013-09-24 05:37:24 PM WARNING: unable to download: 'Traceback (most recent call last):\n File "/srv/lava/.cache/git-cache/exports/lava-dispatcher/2013-09-20-3ca1af8/lava_dispatcher/downloader.py", line 185, in download_with_retry\n return download_image(url, context, imgdir, decompress)\n File "/srv/lava/.cache/git-cache/exports/lava-dispatcher/2013-09-20-3ca1af8/lava_dispatcher/downloader.py", line 165, in download_image\n with reader(url, context.config.lava_proxy, cookies) as r:\n File "/usr/lib/python2.7/contextlib.py", line 17, in __enter__\n return self.gen.next()\n File "/srv/lava/.cache/git-cache/exports/lava-dispatcher/2013-09-20-3ca1af8/lava_dispatcher/downloader.py", line 67, in _http_stream\n resp = opener.open(url, timeout=30)\n File "/usr/lib/python2.7/urllib2.py", line 400, in open\n response = self._open(req, data)\n File "/usr/lib/python2.7/urllib2.py", line 418, in _open\n \'_open\', req)\n File "/usr/lib/python2.7/urllib2.py", line 378, in _call_chain\n result = func(*args)\n File "/usr/lib/python2.7/urllib2.py", line 1207, in http_open\n return self.do_open(httplib.HTTPConnection, req)\n File "/usr/lib/python2.7/urllib2.py", line 1177, in do_open\n raise URLError(err)\nURLError: \n' 2013-09-24 05:37:24 PM INFO: Sleep one minute and retry (3) 2013-09-24 05:38:24 PM INFO: Downloading image: http://192.168.1.4:59727/fs.tgz 2013-09-24 05:38:24 PM WARNING: unable to download: 'Traceback (most recent call last):\n File "/srv/lava/.cache/git-cache/exports/lava-dispatcher/2013-09-20-3ca1af8/lava_dispatcher/downloader.py", line 185, in download_with_retry\n return download_image(url, context, imgdir, decompress)\n File "/srv/lava/.cache/git-cache/exports/lava-dispatcher/2013-09-20-3ca1af8/lava_dispatcher/downloader.py", line 165, in download_image\n with reader(url, context.config.lava_proxy, cookies) as r:\n File "/usr/lib/python2.7/contextlib.py", line 17, in __enter__\n return self.gen.next()\n File "/srv/lava/.cache/git-cache/exports/lava-dispatcher/2013-09-20-3ca1af8/lava_dispatcher/downloader.py", line 67, in _http_stream\n resp = opener.open(url, timeout=30)\n File "/usr/lib/python2.7/urllib2.py", line 400, in open\n response = self._open(req, data)\n File "/usr/lib/python2.7/urllib2.py", line 418, in _open\n \'_open\', req)\n File "/usr/lib/python2.7/urllib2.py", line 378, in _call_chain\n result = func(*args)\n File "/usr/lib/python2.7/urllib2.py", line 1207, in http_open\n return self.do_open(httplib.HTTPConnection, req)\n File "/usr/lib/python2.7/urllib2.py", line 1177, in do_open\n raise URLError(err)\nURLError: \n' 2013-09-24 05:38:24 PM INFO: Sleep one minute and retry (4) 2013-09-24 05:39:24 PM INFO: Downloading image: http://192.168.1.4:59727/fs.tgz 2013-09-24 05:39:24 PM WARNING: unable to download: 'Traceback (most recent call last):\n File "/srv/lava/.cache/git-cache/exports/lava-dispatcher/2013-09-20-3ca1af8/lava_dispatcher/downloader.py", line 185, in download_with_retry\n return download_image(url, context, imgdir, decompress)\n File "/srv/lava/.cache/git-cache/exports/lava-dispatcher/2013-09-20-3ca1af8/lava_dispatcher/downloader.py", line 165, in download_image\n with reader(url, context.config.lava_proxy, cookies) as r:\n File "/usr/lib/python2.7/contextlib.py", line 17, in __enter__\n return self.gen.next()\n File "/srv/lava/.cache/git-cache/exports/lava-dispatcher/2013-09-20-3ca1af8/lava_dispatcher/downloader.py", line 67, in _http_stream\n resp = opener.open(url, timeout=30)\n File "/usr/lib/python2.7/urllib2.py", line 400, in open\n response = self._open(req, data)\n File "/usr/lib/python2.7/urllib2.py", line 418, in _open\n \'_open\', req)\n File "/usr/lib/python2.7/urllib2.py", line 378, in _call_chain\n result = func(*args)\n File "/usr/lib/python2.7/urllib2.py", line 1207, in http_open\n return self.do_open(httplib.HTTPConnection, req)\n File "/usr/lib/python2.7/urllib2.py", line 1177, in do_open\n raise URLError(err)\nURLError: \n' 2013-09-24 05:39:24 PM INFO: Sleep one minute and retry (5) 2013-09-24 05:40:24 PM INFO: Downloading image: http://192.168.1.4:59727/fs.tgz 2013-09-24 05:40:24 PM WARNING: unable to download: 'Traceback (most recent call last):\n File "/srv/lava/.cache/git-cache/exports/lava-dispatcher/2013-09-20-3ca1af8/lava_dispatcher/downloader.py", line 185, in download_with_retry\n return download_image(url, context, imgdir, decompress)\n File "/srv/lava/.cache/git-cache/exports/lava-dispatcher/2013-09-20-3ca1af8/lava_dispatcher/downloader.py", line 165, in download_image\n with reader(url, context.config.lava_proxy, cookies) as r:\n File "/usr/lib/python2.7/contextlib.py", line 17, in __enter__\n return self.gen.next()\n File "/srv/lava/.cache/git-cache/exports/lava-dispatcher/2013-09-20-3ca1af8/lava_dispatcher/downloader.py", line 67, in _http_stream\n resp = opener.open(url, timeout=30)\n File "/usr/lib/python2.7/urllib2.py", line 400, in open\n response = self._open(req, data)\n File "/usr/lib/python2.7/urllib2.py", line 418, in _open\n \'_open\', req)\n File "/usr/lib/python2.7/urllib2.py", line 378, in _call_chain\n result = func(*args)\n File "/usr/lib/python2.7/urllib2.py", line 1207, in http_open\n return self.do_open(httplib.HTTPConnection, req)\n File "/usr/lib/python2.7/urllib2.py", line 1177, in do_open\n raise URLError(err)\nURLError: \n' 2013-09-24 05:40:24 PM DEBUG: send :  2013-09-24 05:40:24 PM DEBUG: expect (1): '['.+', , ]' 2013-09-24 05:40:24 PM DEBUG: expect (1): '['.+', , ]' [ 303.847205] EXT4-fs (mmcblk0p2): error count: 1 [ 303.851748] EXT4-fs (mmcblk0p2): initial error at 1379834369: __ext4_get_inode_loc:3629: inode 132557: block 524588 [ 303.862253] EXT4-fs (mmcblk0p2): last error at 1379834369: __ext4_get_inode_loc:3629: inode 132557: block 524588 2013-09-24 05:40:24 PM DEBUG: expect (1): '['.+', , ]' ^2013-09-24 05:40:24 PM DEBUG: expect (1): '['.+', , ]' C2013-09-24 05:40:24 PM DEBUG: expect (1): '['.+', , ]' bash-4.2 [r2013-09-24 05:40:24 PM DEBUG: expect (1): '['.+', , ]' c2013-09-24 05:40:24 PM DEBUG: expect (1): '['.+', , ]' =255]# 2013-09-24 05:40:24 PM DEBUG: expect (1): '['.+', , ]' 2013-09-24 05:40:25 PM DEBUG: sendline : umount /mnt 2013-09-24 05:40:25 PM DEBUG: send : umount /mnt 2013-09-24 05:40:26 PM DEBUG: send : 2013-09-24 05:40:26 PM DEBUG: expect (600): 'bash-4.2 \[rc=(\d+)\]# ' umount /mnt bash-4.2 [rc=0]# 2013-09-24 05:40:26 PM INFO: General Exception: downloading http://192.168.1.4:59727/fs.tgz failed after 6 tries 2013-09-24 05:40:26 PM DEBUG: finally status fail 2013-09-24 05:40:26 PM WARNING: [ACTION-E] lava_test_shell is finished with error (downloading http://192.168.1.4:59727/fs.tgz failed after 6 tries). ErrorMessage: downloading http://192.168.1.4:59727/fs.tgz failed after 6 tries Lava failed at action lava_test_shell with error:downloading http://192.168.1.4:59727/fs.tgz failed after 6 tries Traceback (most recent call last): File "/srv/lava/.cache/git-cache/exports/lava-dispatcher/2013-09-20-3ca1af8/lava_dispatcher/job.py", line 254, in run action.run(**params) File "/srv/lava/.cache/git-cache/exports/lava-dispatcher/2013-09-20-3ca1af8/lava_dispatcher/actions/lava_test_shell.py", line 543, in run testdefs_by_uuid = self._configure_target(target, testdef_urls, testdef_repos) File "/srv/lava/.cache/git-cache/exports/lava-dispatcher/2013-09-20-3ca1af8/lava_dispatcher/actions/lava_test_shell.py", line 669, in _configure_target with target.file_system(results_part, 'lava') as d: File "/usr/lib/python2.7/contextlib.py", line 17, in __enter__ return self.gen.next() File "/srv/lava/.cache/git-cache/exports/lava-dispatcher/2013-09-20-3ca1af8/lava_dispatcher/device/master.py", line 352, in file_system self.context, self.scratch_dir, url, False) File "/srv/lava/.cache/git-cache/exports/lava-dispatcher/2013-09-20-3ca1af8/lava_dispatcher/downloader.py", line 191, in download_with_retry 'downloading %s failed after %d tries' % (url, tries)) RuntimeError: downloading http://192.168.1.4:59727/fs.tgz failed after 6 tries 2013-09-24 05:40:26 PM INFO: Submitting the test result with parameters = {u'stream': u'/anonymous/wand/', u'server': u'http://novello@192.168.1.203/RPC2/'} 2013-09-24 05:40:26 PM WARNING: specifying a user without a token is unlikely to work 2013-09-24 05:40:26 PM DEBUG: server RPC endpoint URL: http://novello@192.168.1.203/RPC2/ 2013-09-24 05:40:26 PM ERROR: Failed to submit the test result. Error = Username provided but no token found. srv/lava/.cache/git-cache/exports/lava-dispatcher/2013-09-20-3ca1af8/lava_dispatcher/downloader.py", line 185, in download_with_retry\n return download_image(url, context, imgdir, decompress)\n File "/srv/lava/.cache/git-cache/exports/lava-dispatcher/2013-09-20-3ca1af8/lava_dispatcher/downloader.py", line 165, in download_image\n with reader(url, context.config.lava_proxy, cookies) as r:\n File "/usr/lib/python2.7/contextlib.py", line 17, in __enter__\n return self.gen.next()\n File "/srv/lava/.cache/git-cache/exports/lava-dispatcher/2013-09-20-3ca1af8/lava_dispatcher/downloader.py", line 67, in _http_stream\n resp = opener.open(url, timeout=30)\n File "/usr/lib/python2.7/urllib2.py", line 400, in open\n response = self._open(req, data)\n File "/usr/lib/python2.7/urllib2.py", line 418, in _open\n \'_open\', req)\n File "/usr/lib/python2.7/urllib2.py", line 378, in _call_chain\n result = func(*args)\n File "/usr/lib/python2.7/urllib2.py", line 1207, in http_open\n return self.do_open(httplib.HTTPConnection, req)\n File "/usr/lib/python2.7/urllib2.py", line 117 IF I TRAY THE DOWNLOAD FROM EXPLORRER IT WORKS: http://192.168.1.4:59727/fs.tgZ iN THE TARGET ARE INSTALLED: A)python; B)lava-test; if i do: lava-test register-test file:///mnt/stream.json ...it works