Help
Description
[lavafed 2019.04.0032.g55afae484] qemu - hikey
Device-type
qemu
Submitter
Rémi Duraffort
Created
1 week, 4 days ago
Priority
Medium
Visibility
Publicly visible
Required Tags
staging.validation.linaro.org
#462
Complete
Device
staging-qemu01@staging.validation.linaro.org
Started
1 week, 4 days ago
Duration
4 minutes
Results
  
lava-dispatcher, installed at version: 2019.04.0032.g55afae484+stretch start: 0 validate Start time: 2019-05-15 09:10:20.756611+00:00 (UTC) Validating that http://snapshots.linaro.org/openembedded/lkft/morty/hikey/rpb/linux-stable-rc-4.14/64/rpb-console-image-hikey-20180108141340-64.rootfs.ext4.gz exists Validating that http://snapshots.linaro.org/openembedded/lkft/morty/hikey/rpb/linux-stable-rc-4.14/64/Image--4.14+git0+ae407d95ee-r0-hikey-20180108141340-64.bin exists qemu-system-arm, installed at version: 1:2.8+dfsg-6+deb9u5, host architecture: amd64 validate duration: 1.95 case: validate
case_id: 10185
definition: lava
result: pass
start: 1 deployimages (timeout 00:10:00) [common] start: 1.1 download-retry (timeout 00:10:00) [common] start: 1.1.1 http-download (timeout 00:10:00) [common] downloading http://snapshots.linaro.org/openembedded/lkft/morty/hikey/rpb/linux-stable-rc-4.14/64/rpb-console-image-hikey-20180108141340-64.rootfs.ext4.gz saving as /var/lib/lava/dispatcher/tmp/lavafed-462/deployimages-0atk5prv/rootfs/rpb-console-image-hikey-20180108141340-64.rootfs.ext4 total size: 361037083 (344MB) Using gunzip to decompress gz progress 0% (0MB) progress 5% (17MB) progress 10% (34MB) progress 15% (51MB) progress 20% (68MB) progress 25% (86MB) progress 30% (103MB) progress 35% (120MB) progress 40% (137MB) progress 45% (154MB) progress 50% (172MB) progress 55% (189MB) progress 60% (206MB) progress 65% (223MB) progress 70% (241MB) progress 75% (258MB) progress 80% (275MB) progress 85% (292MB) progress 90% (309MB) progress 95% (327MB) progress 100% (344MB) 344MB downloaded in 31.03s (11.10MB/s) end: 1.1.1 http-download (duration 00:00:31) [common] case: http-download
case_id: 10218
definition: lava
duration: 31.03
extra: ...
level: 1.1.1
namespace: common
result: pass
end: 1.1 download-retry (duration 00:00:31) [common] start: 1.2 download-retry (timeout 00:09:29) [common] start: 1.2.1 http-download (timeout 00:09:29) [common] downloading http://snapshots.linaro.org/openembedded/lkft/morty/hikey/rpb/linux-stable-rc-4.14/64/Image--4.14+git0+ae407d95ee-r0-hikey-20180108141340-64.bin saving as /var/lib/lava/dispatcher/tmp/lavafed-462/deployimages-0atk5prv/kernel/Image--4.14+git0+ae407d95ee-r0-hikey-20180108141340-64.bin total size: 19952128 (19MB) No compression specified progress 0% (0MB) progress 5% (0MB) progress 10% (1MB) progress 15% (2MB) progress 20% (3MB) progress 25% (4MB) progress 30% (5MB) progress 35% (6MB) progress 40% (7MB) progress 45% (8MB) progress 50% (9MB) progress 55% (10MB) progress 60% (11MB) progress 65% (12MB) progress 70% (13MB) progress 75% (14MB) progress 80% (15MB) progress 85% (16MB) progress 90% (17MB) progress 95% (18MB) progress 100% (19MB) 19MB downloaded in 3.67s (5.18MB/s) end: 1.2.1 http-download (duration 00:00:04) [common] case: http-download
case_id: 10218
definition: lava
duration: 3.68
extra: ...
level: 1.2.1
namespace: common
result: pass
end: 1.2 download-retry (duration 00:00:04) [common] start: 1.3 lava-overlay (timeout 00:09:25) [common] [common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/lavafed-462/lava-overlay-byb8mn4c makedir: /var/lib/lava/dispatcher/tmp/lavafed-462/lava-overlay-byb8mn4c/lava-462/bin makedir: /var/lib/lava/dispatcher/tmp/lavafed-462/lava-overlay-byb8mn4c/lava-462/tests makedir: /var/lib/lava/dispatcher/tmp/lavafed-462/lava-overlay-byb8mn4c/lava-462/results Creating /var/lib/lava/dispatcher/tmp/lavafed-462/lava-overlay-byb8mn4c/lava-462/bin/lava-add-keys Creating /var/lib/lava/dispatcher/tmp/lavafed-462/lava-overlay-byb8mn4c/lava-462/bin/lava-add-sources Creating /var/lib/lava/dispatcher/tmp/lavafed-462/lava-overlay-byb8mn4c/lava-462/bin/lava-background-process-start Creating /var/lib/lava/dispatcher/tmp/lavafed-462/lava-overlay-byb8mn4c/lava-462/bin/lava-background-process-stop Creating /var/lib/lava/dispatcher/tmp/lavafed-462/lava-overlay-byb8mn4c/lava-462/bin/lava-common-functions Creating /var/lib/lava/dispatcher/tmp/lavafed-462/lava-overlay-byb8mn4c/lava-462/bin/lava-echo-ipv4 Creating /var/lib/lava/dispatcher/tmp/lavafed-462/lava-overlay-byb8mn4c/lava-462/bin/lava-install-packages Creating /var/lib/lava/dispatcher/tmp/lavafed-462/lava-overlay-byb8mn4c/lava-462/bin/lava-installed-packages Creating /var/lib/lava/dispatcher/tmp/lavafed-462/lava-overlay-byb8mn4c/lava-462/bin/lava-os-build Creating /var/lib/lava/dispatcher/tmp/lavafed-462/lava-overlay-byb8mn4c/lava-462/bin/lava-probe-channel Creating /var/lib/lava/dispatcher/tmp/lavafed-462/lava-overlay-byb8mn4c/lava-462/bin/lava-probe-ip Creating /var/lib/lava/dispatcher/tmp/lavafed-462/lava-overlay-byb8mn4c/lava-462/bin/lava-target-ip Creating /var/lib/lava/dispatcher/tmp/lavafed-462/lava-overlay-byb8mn4c/lava-462/bin/lava-target-mac Creating /var/lib/lava/dispatcher/tmp/lavafed-462/lava-overlay-byb8mn4c/lava-462/bin/lava-target-storage Creating /var/lib/lava/dispatcher/tmp/lavafed-462/lava-overlay-byb8mn4c/lava-462/bin/lava-test-case Creating /var/lib/lava/dispatcher/tmp/lavafed-462/lava-overlay-byb8mn4c/lava-462/bin/lava-test-event Creating /var/lib/lava/dispatcher/tmp/lavafed-462/lava-overlay-byb8mn4c/lava-462/bin/lava-test-feedback Creating /var/lib/lava/dispatcher/tmp/lavafed-462/lava-overlay-byb8mn4c/lava-462/bin/lava-test-raise Creating /var/lib/lava/dispatcher/tmp/lavafed-462/lava-overlay-byb8mn4c/lava-462/bin/lava-test-reference Creating /var/lib/lava/dispatcher/tmp/lavafed-462/lava-overlay-byb8mn4c/lava-462/bin/lava-test-runner Creating /var/lib/lava/dispatcher/tmp/lavafed-462/lava-overlay-byb8mn4c/lava-462/bin/lava-test-set Creating /var/lib/lava/dispatcher/tmp/lavafed-462/lava-overlay-byb8mn4c/lava-462/bin/lava-test-shell Updating /var/lib/lava/dispatcher/tmp/lavafed-462/lava-overlay-byb8mn4c/lava-462/bin/lava-install-packages (oe) Updating /var/lib/lava/dispatcher/tmp/lavafed-462/lava-overlay-byb8mn4c/lava-462/bin/lava-installed-packages (oe) start: 1.3.1 lava-vland-overlay (timeout 00:09:25) [common] skipped lava-vland-overlay end: 1.3.1 lava-vland-overlay (duration 00:00:00) [common] start: 1.3.2 lava-multinode-overlay (timeout 00:09:25) [common] skipped lava-multinode-overlay end: 1.3.2 lava-multinode-overlay (duration 00:00:00) [common] start: 1.3.3 test-definition (timeout 00:09:25) [common] Loading test definitions start: 1.3.3.1 git-repo-action (timeout 00:09:25) [common] Using /lava-462 at stage 0 Fetching tests from http://git.linaro.org/lava-team/lava-functional-tests.git Running '/usr/bin/git clone http://git.linaro.org/lava-team/lava-functional-tests.git /var/lib/lava/dispatcher/tmp/lavafed-462/lava-overlay-byb8mn4c/lava-462/0/tests/0_smoke-tests --depth=1' Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/lavafed-462/lava-overlay-byb8mn4c/lava-462/0/tests/0_smoke-tests/lava-test-shell/smoke-tests-basic.yaml uuid=462_1.3.3.1 testdef=None end: 1.3.3.1 git-repo-action (duration 00:00:01) [common] case: git-repo-action
case_id: 10224
definition: lava
duration: 0.69
extra: ...
level: 1.3.3.1
namespace: common
result: pass
start: 1.3.3.2 test-overlay (timeout 00:09:25) [common] end: 1.3.3.2 test-overlay (duration 00:00:00) [common] case: test-overlay
case_id: 10225
definition: lava
duration: 0.01
extra: ...
level: 1.3.3.2
namespace: common
result: pass
start: 1.3.3.3 test-install-overlay (timeout 00:09:25) [common] end: 1.3.3.3 test-install-overlay (duration 00:00:00) [common] case: test-install-overlay
case_id: 10226
definition: lava
duration: 0.03
extra: ...
level: 1.3.3.3
namespace: common
result: pass
start: 1.3.3.4 test-runscript-overlay (timeout 00:09:24) [common] runner path: /var/lib/lava/dispatcher/tmp/lavafed-462/lava-overlay-byb8mn4c/lava-462/0/tests/0_smoke-tests test_uuid 462_1.3.3.1 end: 1.3.3.4 test-runscript-overlay (duration 00:00:00) [common] case: test-runscript-overlay
case_id: 10227
definition: lava
duration: 0.03
extra: ...
level: 1.3.3.4
namespace: common
result: pass
start: 1.3.3.5 git-repo-action (timeout 00:09:24) [common] Using /lava-462 at stage 0 Fetching tests from http://git.linaro.org/lava-team/lava-functional-tests.git Running '/usr/bin/git clone http://git.linaro.org/lava-team/lava-functional-tests.git /var/lib/lava/dispatcher/tmp/lavafed-462/lava-overlay-byb8mn4c/lava-462/0/tests/1_singlenode-basic --depth=1' Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/lavafed-462/lava-overlay-byb8mn4c/lava-462/0/tests/1_singlenode-basic/lava-test-shell/single-node/singlenode01.yaml uuid=462_1.3.3.5 testdef=None end: 1.3.3.5 git-repo-action (duration 00:00:01) [common] case: git-repo-action
case_id: 10224
definition: lava
duration: 0.77
extra: ...
level: 1.3.3.5
namespace: common
result: pass
start: 1.3.3.6 test-overlay (timeout 00:09:24) [common] end: 1.3.3.6 test-overlay (duration 00:00:00) [common] case: test-overlay
case_id: 10225
definition: lava
duration: 0.01
extra: ...
level: 1.3.3.6
namespace: common
result: pass
start: 1.3.3.7 test-install-overlay (timeout 00:09:24) [common] end: 1.3.3.7 test-install-overlay (duration 00:00:00) [common] case: test-install-overlay
case_id: 10226
definition: lava
duration: 0.05
extra: ...
level: 1.3.3.7
namespace: common
result: pass
start: 1.3.3.8 test-runscript-overlay (timeout 00:09:24) [common] runner path: /var/lib/lava/dispatcher/tmp/lavafed-462/lava-overlay-byb8mn4c/lava-462/0/tests/1_singlenode-basic test_uuid 462_1.3.3.5 end: 1.3.3.8 test-runscript-overlay (duration 00:00:00) [common] case: test-runscript-overlay
case_id: 10227
definition: lava
duration: 0.03
extra: ...
level: 1.3.3.8
namespace: common
result: pass
Creating lava-test-runner.conf files Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/lavafed-462/lava-overlay-byb8mn4c/lava-462/0 for stage 0 - 0_smoke-tests - 1_singlenode-basic end: 1.3.3 test-definition (duration 00:00:02) [common] start: 1.3.4 compress-overlay (timeout 00:09:24) [common] end: 1.3.4 compress-overlay (duration 00:00:00) [common] start: 1.3.5 persistent-nfs-overlay (timeout 00:09:23) [common] end: 1.3.5 persistent-nfs-overlay (duration 00:00:00) [common] end: 1.3 lava-overlay (duration 00:00:02) [common] start: 1.4 apply-overlay-guest (timeout 00:09:23) [common] Overlay: /var/lib/lava/dispatcher/tmp/lavafed-462/compress-overlay-urxx0k1j/overlay-1.3.4.tar.gz end: 1.4 apply-overlay-guest (duration 00:00:06) [common] case: apply-overlay-guest
case_id: 10234
definition: lava
duration: 6.11
extra: ...
level: 1.4
namespace: common
result: pass
start: 1.5 deploy-device-env (timeout 00:09:17) [common] end: 1.5 deploy-device-env (duration 00:00:00) [common] end: 1 deployimages (duration 00:00:43) [common] start: 2 boot-image-retry (timeout 00:05:00) [common] start: 2.1 boot-qemu-image (timeout 00:05:00) [common] start: 2.1.1 execute-qemu (timeout 00:05:00) [common] Extending command line for qcow2 test overlay Boot command: /usr/bin/qemu-system-aarch64 -cpu cortex-a57 -machine virt -nographic -net nic,model=virtio,macaddr=DE:AD:BE:EF:32:01 -net user -m 1024 -monitor none -drive format=raw,file=/var/lib/lava/dispatcher/tmp/lavafed-462/deployimages-0atk5prv/rootfs/rpb-console-image-hikey-20180108141340-64.rootfs.ext4 -cpu cortex-a57 -machine type=virt -nographic -smp 4 -m 2048 -kernel /var/lib/lava/dispatcher/tmp/lavafed-462/deployimages-0atk5prv/kernel/Image--4.14+git0+ae407d95ee-r0-hikey-20180108141340-64.bin --append "console=ttyAMA0 root=/dev/vda rw" -drive format=qcow2,file=/var/lib/lava/dispatcher/tmp/lavafed-462/apply-overlay-guest-525dyd97/lava-guest.qcow2,media=disk,if=virtio,id=lavatest started a shell command end: 2.1.1 execute-qemu (duration 00:00:01) [common] case: execute-qemu
case_id: 10235
definition: lava
duration: 1.04
extra: ...
level: 2.1.1
namespace: common
result: pass
end: 2.1 boot-qemu-image (duration 00:00:01) [common] start: 2.2 auto-login-action (timeout 00:04:59) [common] auto-login-action: Wait for prompt ['Linux version [0-9]'] (timeout 00:05:00) [ 0.000000] Booting Linux on physical CPU 0x0 Using line separator: #'\n'# Waiting for the login prompt Parsing kernel messages ['-+\\[ cut here \\]-+\\s+(.*\\s+-+\\[ end trace (\\w*) \\]-+)', '(Unhandled fault.*)\\r\\n', 'Kernel panic - (.*) end Kernel panic', 'Stack:\\s+(.*\\s+-+\\[ end trace (\\w*) \\]-+)', 'root@hikey:~#', 'login:', 'Login incorrect'] [auto-login-action] Waiting for messages, (timeout 00:04:57) [ 0.000000] Linux version 4.14.13-rc1 (buildslave@x86-64-08) (gcc version 6.2.1 20161016 (Linaro GCC 6.2-2016.11)) #1 SMP PREEMPT Mon Jan 8 14:21:41 UTC 2018 [ 0.000000] Boot CPU: AArch64 Processor [411fd070] [ 0.000000] Machine model: linux,dummy-virt [ 0.000000] efi: Getting EFI parameters from FDT: [ 0.000000] efi: UEFI not found. [ 0.000000] cma: Reserved 16 MiB at 0x00000000bf000000 [ 0.000000] NUMA: No NUMA configuration found [ 0.000000] NUMA: Faking a node at [mem 0x0000000000000000-0x00000000bfffffff] [ 0.000000] NUMA: NODE_DATA [mem 0xbefe9480-0xbefeb0ff] [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000040000000-0x00000000bfffffff] [ 0.000000] Normal empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000040000000-0x00000000bfffffff] [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x00000000bfffffff] [ 0.000000] psci: probing for conduit method from DT. [ 0.000000] psci: PSCIv0.2 detected in firmware. [ 0.000000] psci: Using standard PSCI v0.2 function IDs [ 0.000000] psci: Trusted OS migration not required [ 0.000000] percpu: Embedded 25 pages/cpu @ffff80007ef80000 s64456 r8192 d29752 u102400 [ 0.000000] Detected PIPT I-cache on CPU0 [ 0.000000] CPU features: enabling workaround for ARM erratum 832075 [ 0.000000] CPU features: enabling workaround for ARM erratum 834220 [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 516096 [ 0.000000] Policy zone: DMA [ 0.000000] Kernel command line: console=ttyAMA0 root=/dev/vda rw [ 0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes) [ 0.000000] Memory: 2015476K/2097152K available (11324K kernel code, 1820K rwdata, 5044K rodata, 1280K init, 12405K bss, 65292K reserved, 16384K cma-reserved) [ 0.000000] Virtual kernel memory layout: [ 0.000000] modules : 0xffff000000000000 - 0xffff000008000000 ( 128 MB) [ 0.000000] vmalloc : 0xffff000008000000 - 0xffff7dffbfff0000 (129022 GB) [ 0.000000] .text : 0xffff000008080000 - 0xffff000008b90000 ( 11328 KB) [ 0.000000] .rodata : 0xffff000008b90000 - 0xffff000009080000 ( 5056 KB) [ 0.000000] .init : 0xffff000009080000 - 0xffff0000091c0000 ( 1280 KB) [ 0.000000] .data : 0xffff0000091c0000 - 0xffff000009387200 ( 1821 KB) [ 0.000000] .bss : 0xffff000009387200 - 0xffff000009fa4868 ( 12406 KB) [ 0.000000] fixed : 0xffff7dfffe7fb000 - 0xffff7dfffec00000 ( 4116 KB) [ 0.000000] PCI I/O : 0xffff7dfffee00000 - 0xffff7dffffe00000 ( 16 MB) [ 0.000000] vmemmap : 0xffff7e0000000000 - 0xffff800000000000 ( 2048 GB maximum) [ 0.000000] 0xffff7e0000000000 - 0xffff7e0002000000 ( 32 MB actual) [ 0.000000] memory : 0xffff800000000000 - 0xffff800080000000 ( 2048 MB) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] Running RCU self tests [ 0.000000] Preemptible hierarchical RCU implementation. [ 0.000000] RCU lockdep checking is enabled. [ 0.000000] RCU restricting CPUs from NR_CPUS=64 to nr_cpu_ids=4. [ 0.000000] Tasks RCU enabled. [ 0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 [ 0.000000] GICv2m: range[mem 0x08020000-0x08020fff], SPI[80:143] [ 0.000000] arch_timer: WARNING: Invalid trigger for IRQ3, assuming level low [ 0.000000] arch_timer: WARNING: Please fix your firmware [ 0.000000] arch_timer: cp15 timer(s) running at 62.50MHz (virt). [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x1cd42e208c, max_idle_ns: 881590405314 ns [ 0.000254] sched_clock: 56 bits at 62MHz, resolution 16ns, wraps every 4398046511096ns [ 0.016364] Console: colour dummy device 80x25 [ 0.017511] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar [ 0.017607] ... MAX_LOCKDEP_SUBCLASSES: 8 [ 0.017687] ... MAX_LOCK_DEPTH: 48 [ 0.017765] ... MAX_LOCKDEP_KEYS: 8191 [ 0.017842] ... CLASSHASH_SIZE: 4096 [ 0.017919] ... MAX_LOCKDEP_ENTRIES: 32768 [ 0.017995] ... MAX_LOCKDEP_CHAINS: 65536 [ 0.018071] ... CHAINHASH_SIZE: 32768 [ 0.018148] memory used by lock dependency info: 7391 kB [ 0.018224] per task-struct memory footprint: 1920 bytes [ 0.020113] Calibrating delay loop (skipped), value calculated using timer frequency.. 125.00 BogoMIPS (lpj=250000) [ 0.020449] pid_max: default: 32768 minimum: 301 [ 0.023736] Security Framework initialized [ 0.030907] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes) [ 0.034258] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes) [ 0.035292] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.035520] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.125416] ASID allocator initialised with 65536 entries [ 0.129832] Hierarchical SRCU implementation. [ 0.157339] EFI services will not be available. [ 0.171620] smp: Bringing up secondary CPUs ... [ 0.205331] Detected PIPT I-cache on CPU1 [ 0.207138] CPU1: Booted secondary processor [411fd070] [ 0.247603] Detected PIPT I-cache on CPU2 [ 0.247951] CPU2: Booted secondary processor [411fd070] [ 0.284441] Detected PIPT I-cache on CPU3 [ 0.284925] CPU3: Booted secondary processor [411fd070] [ 0.288521] smp: Brought up 1 node, 4 CPUs [ 0.288797] SMP: Total of 4 processors activated. [ 0.289719] CPU features: detected feature: 32-bit EL0 Support [ 0.323882] CPU: All CPU(s) started at EL1 [ 0.327750] alternatives: patching kernel code [ 0.404848] devtmpfs: initialized [ 0.474882] random: get_random_u32 called from bucket_table_alloc+0x11c/0x270 with crng_init=0 [ 0.563279] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns [ 0.563978] futex hash table entries: 1024 (order: 5, 131072 bytes) [ 0.580906] pinctrl core: initialized pinctrl subsystem [ 0.616127] random: fast init done [ 0.693203] DMI not present or invalid. [ 0.752736] NET: Registered protocol family 16 [ 0.898388] cpuidle: using governor menu [ 0.910494] vdso: 2 pages (1 code @ ffff000008b96000, 1 data @ ffff0000091c5000) [ 0.910949] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. [ 0.967152] DMA: preallocated 256 KiB pool for atomic allocations [ 1.015581] Serial: AMBA PL011 UART driver [ 1.416460] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 39, base_baud = 0) is a PL011 rev1 [ 1.483126] console [ttyAMA0] enabled [ 1.534622] irq: type mismatch, failed to map hwirq-27 for /intc! [ 2.985630] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 3.057674] ACPI: Interpreter disabled. [ 3.124383] vgaarb: loaded [ 3.140913] SCSI subsystem initialized [ 3.195287] usbcore: registered new interface driver usbfs [ 3.198942] usbcore: registered new interface driver hub [ 3.225126] usbcore: registered new device driver usb [ 3.276874] pps_core: LinuxPPS API ver. 1 registered [ 3.281849] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it> [ 3.284964] PTP clock support registered [ 3.304819] EDAC MC: Ver: 3.0.0 [ 3.328597] dmi: Firmware registration failed. [ 3.369720] Advanced Linux Sound Architecture Driver Initialized. [ 3.430664] clocksource: Switched to clocksource arch_sys_counter [ 4.778144] VFS: Disk quotas dquot_6.6.0 [ 4.780399] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 4.789289] pnp: PnP ACPI: disabled [ 5.409578] NET: Registered protocol family 2 [ 5.448793] TCP established hash table entries: 16384 (order: 5, 131072 bytes) [ 5.453240] TCP bind hash table entries: 16384 (order: 8, 1048576 bytes) [ 5.492236] TCP: Hash tables configured (established 16384 bind 16384) [ 5.501377] UDP hash table entries: 1024 (order: 5, 163840 bytes) [ 5.508179] UDP-Lite hash table entries: 1024 (order: 5, 163840 bytes) [ 5.523938] NET: Registered protocol family 1 [ 5.545877] RPC: Registered named UNIX socket transport module. [ 5.546557] RPC: Registered udp transport module. [ 5.547995] RPC: Registered tcp transport module. [ 5.548417] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 5.570313] kvm [1]: HYP mode not available [ 5.809866] audit: initializing netlink subsys (disabled) [ 5.820590] audit: type=2000 audit(5.012:1): state=initialized audit_enabled=0 res=1 [ 5.858666] workingset: timestamp_bits=44 max_order=19 bucket_order=0 [ 6.840243] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ 6.925262] NFS: Registering the id_resolver key type [ 6.936801] Key type id_resolver registered [ 6.937480] Key type id_legacy registered [ 6.938372] nfs4filelayout_init: NFSv4 File Layout Driver Registering... [ 6.963435] 9p: Installing v9fs 9p2000 file system support [ 7.197153] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 246) [ 7.198658] io scheduler noop registered [ 7.210513] io scheduler cfq registered (default) [ 7.212522] io scheduler mq-deadline registered [ 7.213001] io scheduler kyber registered [ 7.224745] test_firmware: interface ready [ 7.425177] pl061_gpio 9030000.pl061: PL061 GPIO chip @0x0000000009030000 registered [ 7.698079] OF: PCI: host bridge /pcie@10000000 ranges: [ 7.750256] OF: PCI: IO 0x3eff0000..0x3effffff -> 0x00000000 [ 7.837803] OF: PCI: MEM 0x10000000..0x3efeffff -> 0x10000000 [ 7.838656] OF: PCI: MEM 0x8000000000..0xffffffffff -> 0x8000000000 [ 7.932586] pci-host-generic 3f000000.pcie: ECAM at [mem 0x3f000000-0x3fffffff] for [bus 00-0f] [ 7.944999] pci-host-generic 3f000000.pcie: PCI host bridge to bus 0000:00 [ 7.949109] pci_bus 0000:00: root bus resource [bus 00-0f] [ 7.949918] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] [ 7.950601] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] [ 7.953360] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] [ 8.014670] pci 0000:00:01.0: BAR 6: assigned [mem 0x10000000-0x1003ffff pref] [ 8.026280] pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] [ 8.054734] pci 0000:00:02.0: BAR 4: assigned [mem 0x8000004000-0x8000007fff 64bit pref] [ 8.061129] pci 0000:00:03.0: BAR 4: assigned [mem 0x8000008000-0x800000bfff 64bit pref] [ 8.062064] pci 0000:00:01.0: BAR 1: assigned [mem 0x10040000-0x10040fff] [ 8.065120] pci 0000:00:02.0: BAR 1: assigned [mem 0x10041000-0x10041fff] [ 8.065889] pci 0000:00:03.0: BAR 1: assigned [mem 0x10042000-0x10042fff] [ 8.066689] pci 0000:00:02.0: BAR 0: assigned [io 0x1000-0x103f] [ 8.070373] pci 0000:00:03.0: BAR 0: assigned [io 0x1040-0x107f] [ 8.073572] pci 0000:00:01.0: BAR 0: assigned [io 0x1080-0x109f] [ 8.723725] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) [ 8.749257] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) [ 8.772470] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) [ 9.328667] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 9.417408] SuperH (H)SCI(F) driver initialized [ 9.488817] msm_serial: driver initialized [ 9.593698] cacheinfo: Unable to detect cache hierarchy for CPU 0 [ 10.164204] loop: module loaded [ 10.810126] libphy: Fixed MDIO Bus: probed [ 11.155885] e1000e: Intel(R) PRO/1000 Network Driver - 3.2.6-k [ 11.156557] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. [ 11.161500] igb: Intel(R) Gigabit Ethernet Network Driver - version 5.4.0-k [ 11.162216] igb: Copyright (c) 2007-2014 Intel Corporation. [ 11.181289] igbvf: Intel(R) Gigabit Virtual Function Network Driver - version 2.4.0-k [ 11.182083] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. [ 11.252535] sky2: driver version 1.30 [ 11.298726] VFIO - User Level meta-driver version: 0.3 [ 11.424769] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver [ 11.425549] ehci-pci: EHCI PCI platform driver [ 11.428713] ehci-platform: EHCI generic platform driver [ 11.432812] ehci-orion: EHCI orion driver [ 11.442084] ehci-exynos: EHCI EXYNOS driver [ 11.446746] ehci-msm: Qualcomm On-Chip EHCI Host Controller [ 11.453845] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver [ 11.456691] ohci-pci: OHCI PCI platform driver [ 11.458648] ohci-platform: OHCI generic platform driver [ 11.470714] ohci-exynos: OHCI EXYNOS driver [ 11.573455] usbcore: registered new interface driver usb-storage [ 11.744512] rtc-pl031 9010000.pl031: rtc core: registered pl031 as rtc0 [ 11.874689] i2c /dev entries driver [ 11.976857] sdhci: Secure Digital Host Controller Interface driver [ 11.977519] sdhci: Copyright(c) Pierre Ossman [ 11.984709] Synopsys Designware Multimedia Card Interface Driver [ 12.008522] sdhci-pltfm: SDHCI platform and OF driver helper [ 12.256074] ledtrig-cpu: registered to indicate activity on CPUs [ 12.514334] usbcore: registered new interface driver usbhid [ 12.516724] usbhid: USB HID core driver [ 12.798699] NET: Registered protocol family 10 [ 12.950490] Segment Routing with IPv6 [ 12.953343] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver [ 12.997403] NET: Registered protocol family 17 [ 13.008559] 9pnet: Installing 9P2000 support [ 13.016285] Key type dns_resolver registered [ 13.160201] registered taskstats version 1 [ 13.457433] input: gpio-keys as /devices/platform/gpio-keys/input/input0 [ 13.516983] rtc-pl031 9010000.pl031: setting system clock to 2019-05-15 09:11:20 UTC (1557911480) [ 13.528232] ALSA device list: [ 13.528692] No soundcards found. [ 13.552586] uart-pl011 9000000.pl011: no DMA platform data [ 13.604401] EXT4-fs (vda): couldn't mount as ext3 due to feature incompatibilities [ 13.798459] EXT4-fs (vda): mounted filesystem with ordered data mode. Opts: (null) [ 13.801703] VFS: Mounted root (ext4 filesystem) on device 254:0. [ 13.856623] devtmpfs: mounted [ 14.116890] Freeing unused kernel memory: 1280K [ 15.163364] systemd[1]: systemd 230 running in system mode. (+PAM -AUDIT -SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP -LIBCRYPTSETUP -GCRYPT +GNUTLS +ACL +XZ -LZ4 -SECCOMP +BLKID -ELFUTILS +KMOD -IDN) [ 15.177438] systemd[1]: Detected virtualization qemu. [ 15.180124] systemd[1]: Detected architecture arm64. [ 15.181222] systemd[1]: Running with unpopulated /etc. Welcome to [1mReference-Platform-Build-X11 2.2+linaro[0m! [ 15.202251] systemd[1]: Set hostname to <hikey>. [ 15.240372] systemd[1]: Initializing machine ID from random generator. [ 17.796776] systemd[1]: Failed to populate /etc with preset unit settings, ignoring: Too many levels of symbolic links [ 20.785473] systemd[1]: Reached target Swap. [[0;32m OK [0m] Reached target Swap. [ 20.808383] systemd[1]: Listening on Journal Audit Socket. [[0;32m OK [0m] Listening on Journal Audit Socket. [ 20.822048] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [[0;32m OK [0m] Started Dispatch Password Requests to Console Directory Watch. [ 20.889671] systemd[1]: Created slice User and Session Slice. [[0;32m OK [0m] Created slice User and Session Slice. [ 20.901697] systemd[1]: Started Forward Password Requests to Wall Directory Watch. [[0;32m OK [0m] Started Forward Password Requests to Wall Directory Watch. [ 20.907471] systemd[1]: Reached target Paths. [[0;32m OK [0m] Reached target Paths. [ 20.932656] systemd[1]: Created slice System Slice. [[0;32m OK [0m] Created slice System Slice. [ 20.960568] systemd[1]: Created slice system-getty.slice. [[0;32m OK [0m] Created slice system-getty.slice. [ 20.972246] systemd[1]: Listening on udev Control Socket. [[0;32m OK [0m] Listening on udev Control Socket. [ 20.998586] systemd[1]: Created slice system-serial\x2dgetty.slice. [[0;32m OK [0m] Created slice system-serial\x2dgetty.slice. [[0;32m OK [0m] Listening on Journal Socket. Mounting POSIX Message Queue File System... Starting Load Kernel Modules... Starting Setup Virtual Console... Starting Create list of required st... nodes for the current kernel... Starting Remount Root and Kernel File Systems... Mounting Debug File System... [[0;32m OK [0m] Listening on Journal Socket (/dev/log). [[0;32m OK [0m] Listening on Syslog Socket. [[0;32m OK [0m] Listening on Network Service Netlink Socket. [[0;32m OK [0m] Reached target Remote File Systems. Mounting Huge Pages File System... [[0;32m OK [0m] Reached target Slices. [[0;32m OK [0m] Listening on udev Kernel Socket. [[0;32m OK [0m] Listening on /dev/initctl Compatibility Named Pipe. [ 23.688729] fuse init (API version 7.26) Mounting Temporary Directory... [ 25.911962] EXT4-fs (vda): re-mounted. Opts: (null) [ 26.213314] systemd[1]: Starting Journal Service... Starting Journal Service... [ 27.509560] systemd[1]: Mounted Debug File System. [[0;32m OK [0m] Mounted Debug File System. [ 27.533009] systemd[1]: Mounted Huge Pages File System. [[0;32m OK [0m] Mounted Huge Pages File System. [ 27.554214] systemd[1]: Mounted POSIX Message Queue File System. [[0;32m OK [0m] Mounted POSIX Message Queue File System. [ 27.578116] systemd[1]: Mounted Temporary Directory. [[0;32m OK [0m] Mounted Temporary Directory. [ 27.750206] systemd[1]: Started Load Kernel Modules. [[0;32m OK [0m] Started Load Kernel Modules. [ 27.825215] systemd[1]: Started Setup Virtual Console. [[0;32m OK [0m] Started Setup Virtual Console. [ 27.904890] systemd[1]: Started Create list of required static device nodes for the current kernel. [[0;32m OK [0m] Started Create list of required sta...ce nodes for the current kernel. [ 27.981648] systemd[1]: Started Remount Root and Kernel File Systems. [[0;32m OK [0m] Started Remount Root and Kernel File Systems. [ 28.661509] systemd[1]: Starting Rebuild Hardware Database... Starting Rebuild Hardware Database... Starting Create System Users... Mounting FUSE Control File System... Mounting Configuration File System... Starting Apply Kernel Variables... [ 31.813549] systemd[1]: Mounted FUSE Control File System. [[0;32m OK [0m] Mounted FUSE Control File System. [ 32.032426] systemd[1]: Mounted Configuration File System. [[0;32m OK [0m] Mounted Configuration File System. [ 32.452296] systemd[1]: Started Apply Kernel Variables. [[0;32m OK [0m] Started Apply Kernel Variables. [ 33.718488] systemd[1]: Started Create System Users. [[0;32m OK [0m] Started Create System Users. [ 33.970282] systemd[1]: Starting Create Static Device Nodes in /dev... Starting Create Static Device Nodes in /dev... [ 36.242121] systemd[1]: Started Journal Service. [[0;32m OK [0m] Started Journal Service. Starting Flush Journal to Persistent Storage... [[0;32m OK [0m] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [[0;32m OK [0m] Reached target Local File Systems (Pre). Mounting /var/volatile... [ 39.205557] systemd-journald[1335]: Received request to flush runtime journal from PID 1 [[0;32m OK [0m] Started Flush Journal to Persistent Storage. [[0;32m OK [0m] Mounted /var/volatile. Starting Load/Save Random Seed... [[0;32m OK [0m] Reached target Local File Systems. Starting Rebuild Journal Catalog... Starting Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... [[0;32m OK [0m] Started udev Kernel Device Manager. [[0;32m OK [0m] Started Load/Save Random Seed. [[0;32m OK [0m] Started Rebuild Journal Catalog. [[0;32m OK [0m] Started Create Volatile Files and Directories. Starting Network Time Synchronization... Starting Update UTMP about System Boot/Shutdown... [[0;32m OK [0m] Started Update UTMP about System Boot/Shutdown. [[0;32m OK [0m] Started Network Time Synchronization. [[0;32m OK [0m] Reached target System Time Synchronized. [[0m[0;31m* [0m] (1 of 5) A start job is running for...-ttyAMA0.device (38s / 1min 30s)[K[[0;1;31m*[0m[0;31m* [0m] (1 of 5) A start job is running for...-ttyAMA0.device (38s / 1min 30s)[K[[0;31m*[0;1;31m*[0m[0;31m* [0m] (1 of 5) A start job is running for...-ttyAMA0.device (39s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m[0;31m* [0m] (2 of 5) A start job is running for...-ttyAMA2.device (39s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m[0;31m* [0m] (2 of 5) A start job is running for...-ttyAMA2.device (40s / 1min 30s)[K[[0;32m OK [0m] Started Rebuild Dynamic Linker Cache. [[0;32m OK [0m] Started Rebuild Hardware Database. Starting udev Coldplug all Devices... Starting Update is Completed... [[0;32m OK [0m] Started Update is Completed. [ [0;31m*[0;1;31m*[0m[0;31m*[0m] (2 of 4) A start job is running for...-ttyAMA2.device (48s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m] (3 of 4) A start job is running for...-ttyAMA3.device (49s / 1min 30s)[K[ [0;31m*[0m] (3 of 4) A start job is running for...-ttyAMA3.device (49s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m] (3 of 4) A start job is running for...-ttyAMA3.device (50s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m[0;31m*[0m] (4 of 4) A start job is running for...lug all Devices (50s / no limit)[K[ [0;31m*[0;1;31m*[0m[0;31m* [0m] (4 of 4) A start job is running for...lug all Devices (51s / no limit)[K[ [0;31m*[0;1;31m*[0m[0;31m* [0m] (4 of 4) A start job is running for...lug all Devices (51s / no limit)[K[[0;31m*[0;1;31m*[0m[0;31m* [0m] (1 of 4) A start job is running for...-ttyAMA0.device (52s / 1min 30s)[K[[0;1;31m*[0m[0;31m* [0m] (1 of 4) A start job is running for...-ttyAMA0.device (52s / 1min 30s)[K[[0m[0;31m* [0m] (1 of 4) A start job is running for...-ttyAMA0.device (53s / 1min 30s)[K[[0;1;31m*[0m[0;31m* [0m] (2 of 4) A start job is running for...-ttyAMA2.device (53s / 1min 30s)[K[[0;31m*[0;1;31m*[0m[0;31m* [0m] (2 of 4) A start job is running for...-ttyAMA2.device (54s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m[0;31m* [0m] (2 of 4) A start job is running for...-ttyAMA2.device (54s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m[0;31m* [0m] (3 of 4) A start job is running for...-ttyAMA3.device (55s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m[0;31m*[0m] (3 of 4) A start job is running for...-ttyAMA3.device (55s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m] (3 of 4) A start job is running for...-ttyAMA3.device (56s / 1min 30s)[K[ [0;31m*[0m] (4 of 4) A start job is running for...lug all Devices (56s / no limit)[K[ [0;31m*[0;1;31m*[0m] (4 of 4) A start job is running for...lug all Devices (57s / no limit)[K[ [0;31m*[0;1;31m*[0m[0;31m*[0m] (4 of 4) A start job is running for...lug all Devices (57s / no limit)[K[ [0;31m*[0;1;31m*[0m[0;31m* [0m] (1 of 4) A start job is running for...-ttyAMA0.device (58s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m[0;31m* [0m] (1 of 4) A start job is running for...-ttyAMA0.device (58s / 1min 30s)[K[[0;31m*[0;1;31m*[0m[0;31m* [0m] (1 of 4) A start job is running for...-ttyAMA0.device (59s / 1min 30s)[K[[0;1;31m*[0m[0;31m* [0m] (2 of 4) A start job is running for...-ttyAMA2.device (59s / 1min 30s)[K[[0m[0;31m* [0m] (2 of 4) A start job is running for...ttyAMA2.device (1min / 1min 30s)[K[[0;1;31m*[0m[0;31m* [0m] (2 of 4) A start job is running for...ttyAMA2.device (1min / 1min 30s)[K[[0;31m*[0;1;31m*[0m[0;31m* [0m] (3 of 4) A start job is running for...AMA3.device (1min 2s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m[0;31m* [0m] (3 of 4) A start job is running for...AMA3.device (1min 2s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m[0;31m* [0m] (3 of 4) A start job is running for...AMA3.device (1min 3s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m[0;31m*[0m] (4 of 4) A start job is running for...all Devices (1min 3s / no limit)[K[ [0;31m*[0;1;31m*[0m] (4 of 4) A start job is running for...all Devices (1min 4s / no limit)[K[ [0;31m*[0m] (4 of 4) A start job is running for...all Devices (1min 4s / no limit)[K[ [0;31m*[0;1;31m*[0m] (1 of 4) A start job is running for...AMA0.device (1min 5s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m[0;31m*[0m] (1 of 4) A start job is running for...AMA0.device (1min 5s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m[0;31m* [0m] (1 of 4) A start job is running for...AMA0.device (1min 6s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m[0;31m* [0m] (2 of 4) A start job is running for...AMA2.device (1min 6s / 1min 30s)[K[[0;31m*[0;1;31m*[0m[0;31m* [0m] (2 of 4) A start job is running for...AMA2.device (1min 7s / 1min 30s)[K[[0;1;31m*[0m[0;31m* [0m] (2 of 4) A start job is running for...AMA2.device (1min 7s / 1min 30s)[K[[0m[0;31m* [0m] (3 of 4) A start job is running for...AMA3.device (1min 8s / 1min 30s)[K[[0;1;31m*[0m[0;31m* [0m] (3 of 4) A start job is running for...AMA3.device (1min 8s / 1min 30s)[K[[0;31m*[0;1;31m*[0m[0;31m* [0m] (3 of 4) A start job is running for...AMA3.device (1min 9s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m[0;31m* [0m] (4 of 4) A start job is running for...all Devices (1min 9s / no limit)[K[ [0;31m*[0;1;31m*[0m[0;31m* [0m] (4 of 4) A start job is running for...ll Devices (1min 10s / no limit)[K[ [0;31m*[0;1;31m*[0m[0;31m*[0m] (4 of 4) A start job is running for...ll Devices (1min 10s / no limit)[K[ [0;31m*[0;1;31m*[0m] (1 of 4) A start job is running for...MA0.device (1min 11s / 1min 30s)[K[ [0;31m*[0m] (1 of 4) A start job is running for...MA0.device (1min 11s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m] (1 of 4) A start job is running for...MA0.device (1min 12s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m[0;31m*[0m] (2 of 4) A start job is running for...MA2.device (1min 12s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m[0;31m* [0m] (2 of 4) A start job is running for...MA2.device (1min 13s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m[0;31m* [0m] (2 of 4) A start job is running for...MA2.device (1min 13s / 1min 30s)[K[[0;31m*[0;1;31m*[0m[0;31m* [0m] (3 of 4) A start job is running for...MA3.device (1min 14s / 1min 30s)[K[[0;32m OK [0m] Found device /dev/ttyAMA0. [[0;32m OK [0m] Started udev Coldplug all Devices. [[0;32m OK [0m] Reached target System Initialization. [[0;32m OK [0m] Listening on D-Bus System Message Bus Socket. Starting sshd.socket. [[0;32m OK [0m] Started Daily Cleanup of Temporary Directories. [[0;32m OK [0m] Reached target Timers. [[0;32m OK [0m] Listening on RPCbind Server Activation Socket. [[0;32m OK [0m] Listening on Avahi mDNS/DNS-SD Stack Activation Socket. [ 99.922087] virtio_net virtio0 enp0s1: renamed from eth0 [[0;32m OK [0m] Listening on sshd.socket. [[0;32m OK [0m] Reached target Sockets. [[0;32m OK [0m] Reached target Basic System. [[0;32m OK [0m] Started Kernel Logging Service. Starting Login Service... [[0;32m OK [0m] Started TEE Supplicant. [[0;32m OK [0m] Started Periodic Command Scheduler. Starting Avahi mDNS/DNS-SD Stack... Starting Resize root filesystem to fit available disk space... [[0;32m OK [0m] Started System Logging Service. [[0;32m OK [0m] Started Job spooling tools. [[0;32m OK [0m] Started D-Bus System Message Bus. [[0;32m OK [0m] Started Avahi mDNS/DNS-SD Stack. Starting Network Service... Starting Network Manager... [[0;1;31m TIME [0m] Timed out waiting for device dev-ttyAMA3.device. [[0;1;33mDEPEND[0m] Dependency failed for Serial Getty on ttyAMA3. [[0;1;31m TIME [0m] Timed out waiting for device dev-ttyAMA2.device. [[0;1;33mDEPEND[0m] Dependency failed for Serial Getty on ttyAMA2. [[0;32m OK [0m] Started Network Service. [[0;32m OK [0m] Started Login Service. [[0;32m OK [0m] Started Network Manager. [[0;32m OK [0m] Reached target Network. Starting Network Name Resolution... Starting Permit User Sessions... Starting DNS forwarder and DHCP server... [[0;32m OK [0m] Started Permit User Sessions. [[0;32m OK [0m] Started Network Name Resolution. [[0;32m OK [0m] Started DNS forwarder and DHCP server. [ 137.595973] IPv6: ADDRCONF(NETDEV_UP): enp0s1: link is not ready [[0;32m OK [0m] Started Serial Getty on ttyAMA0. [[0;32m OK [0m] Started Getty on tty1. [[0;32m OK [0m] Reached target Login Prompts. [[0;32m OK [0m] Reached target Multi-User System. Starting Update UTMP about System Runlevel Changes... [[0;32m OK [0m] Started Update UTMP about System Runlevel Changes. Reference-Platform-Build-X11 2.2+linaro hikey ttyAMA0 Matched prompt #5: login: case: kernel-messages
case_id: 10255
definition: lava
duration: 147.42
extra: ...
level: 2.2
namespace: common
result: pass
Sending username root root hikey login: root auto-login-action: Wait for prompt ['root@hikey:~#', 'Login incorrect', 'Login timed out'] (timeout 00:02:29) root [ 168.279354] audit: type=1006 audit(1557911635.374:2): pid=2327 uid=0 old-auid=4294967295 auid=0 tty=(none) old-ses=4294967295 ses=1 res=1 Running login commands su 7[r[999;999H[6nroot@hikey:~# su su end: 2.2 auto-login-action (duration 00:03:08) [common] case: auto-login-action
case_id: 10258
definition: lava
duration: 188.22
extra: ...
level: 2.2
namespace: common
result: pass
start: 2.3 expect-shell-connection (timeout 00:01:51) [common] Forcing a shell prompt, looking for ['root@hikey:~#'] root@hikey:~# expect-shell-connection: Wait for prompt ['root@hikey:~#'] (timeout 00:05:00) Waiting using forced prompt support. 149.97419905662537s timeout end: 2.3 expect-shell-connection (duration 00:00:00) [common] start: 2.4 export-device-env (timeout 00:01:51) [common] end: 2.4 export-device-env (duration 00:00:00) [common] end: 2 boot-image-retry (duration 00:03:09) [common] start: 3 lava-test-retry (timeout 00:05:00) [common] start: 3.1 lava-test-shell (timeout 00:05:00) [common] Using namespace: common # root@hikey:~# # lava-test-shell: Wait for prompt ['root@hikey:~#'] (timeout 00:05:00) # mkdir /lava-462 root@hikey:~# mkdir /lava-462 mkdir /lava-462 mount /dev/disk/by-uuid/8c4e7443-8867-4af1-91ad-7e80732dd001 -t ext2 /lava-462 root@hikey:~# mount /dev/disk/by-uuid/8c4e7443-8867-4af1-91ad-7e80732dd001 -t ext2 /lava-462 mount /dev/disk/by-uuid/8c4e7443-8867-4af1-91ad-7e80732dd001 -t ex t2 /lava-462 ls -la /lava-462/bin/lava-test-runner root@hikey:~# ls -la /lava-462/bin/lava-test-runner ls -la /lava-462/bin/lava-test-runner -rwxr-xr-x 1 root root 1039 May 15 09:10 /lava-462/bin/lava-test-runner Using /lava-462 export SHELL=/bin/sh root@hikey:~# export SHELL=/bin/sh export SHELL=/bin/sh /lava-462/bin/lava-test-runner /lava-462/0 root@hikey:~# /lava-462/bin/lava-test-runner /lava-462/0 Test shell timeout: 10s (minimum of the action and connection timeout) /lava-462/bin/lava-test-runner /lava-462/0 + export TESTRUN_ID=0_smoke-tests + TESTRUN_ID=0_smoke-tests + cd /lava-462/0/tests/0_smoke-tests ++ cat uuid + UUID=462_1.3.3.1 + set +x <LAVA_SIGNAL_STARTRUN 0_smoke-tests 462_1.3.3.1> Received signal: <STARTRUN> 0_smoke-tests 462_1.3.3.1 Starting test lava.0_smoke-tests (462_1.3.3.1) Skipping test definition patterns. + lava-test-case linux-posix-pwd --shell pwd <LAVA_SIGNAL_STARTTC linux-posix-pwd> Received signal: <STARTTC> linux-posix-pwd /lava-462/0/tests/0_smoke-tests <LAVA_SIGNAL_ENDTC linux-posix-pwd> Received signal: <ENDTC> linux-posix-pwd <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-posix-pwd RESULT=pass> Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-pwd RESULT=pass case: linux-posix-pwd
case_id: 10267
definition: 0_smoke-tests
result: pass
+ lava-test-case linux-posix-uname --shell uname -a <LAVA_SIGNAL_STARTTC linux-posix-uname> Received signal: <STARTTC> linux-posix-uname Linux hikey 4.14.13-rc1 #1 SMP PREEMPT Mon Jan 8 14:21:41 UTC 2018 aarch64 aarch64 aarch64 GNU/Linux <LAVA_SIGNAL_ENDTC linux-posix-uname> Received signal: <ENDTC> linux-posix-uname Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-uname RESULT=pass case: linux-posix-uname
case_id: 10268
definition: 0_smoke-tests
result: pass
<LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-posix-uname RESULT=pass> + lava-test-case linux-posix-vmstat --shell vmstat <LAVA_SIGNAL_STARTTC linux-posix-vmstat> Received signal: <STARTTC> linux-posix-vmstat /lava-462/0/../bin/lava-test-case: line 27: vmstat: command not found Received signal: <ENDTC> linux-posix-vmstat <LAVA_SIGNAL_ENDTC linux-posix-vmstat> <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-posix-vmstat RESULT=fail> Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-vmstat RESULT=fail case: linux-posix-vmstat
case_id: 10269
definition: 0_smoke-tests
result: fail
+ lava-test-case linux-posix-ifconfig --shell ifconfig -a <LAVA_SIGNAL_STARTTC linux-posix-ifconfig> Received signal: <STARTTC> linux-posix-ifconfig enp0s1 Link encap:Ethernet HWaddr de:ad:be:ef:32:01 inet addr:10.0.2.15 Bcast:10.0.2.255 Mask:255.255.255.0 inet6 addr: fec0::dcad:beff:feef:3201/64 Scope:Site inet6 addr: fe80::dcad:beff:feef:3201/64 Scope:Link UP BROADCAST RUNNING MULTICAST MTU:1500 Metric:1 RX packets:48 errors:0 dropped:0 overruns:0 frame:0 TX packets:80 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:1000 RX bytes:10945 (10.6 KiB) TX bytes:13192 (12.8 KiB) lo Link encap:Local Loopback inet addr:127.0.0.1 Mask:255.0.0.0 inet6 addr: ::1/128 Scope:Host UP LOOPBACK RUNNING MTU:65536 Metric:1 RX packets:504 errors:0 dropped:0 overruns:0 frame:0 TX packets:504 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:1000 RX bytes:36256 (35.4 KiB) TX bytes:36256 (35.4 KiB) sit0 Link encap:UNSPEC HWaddr 00-00-00-00-3A-30-30-30-00-00-00-00-00-00-00-00 NOARP MTU:1480 Metric:1 RX packets:0 errors:0 dropped:0 overruns:0 frame:0 TX packets:0 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:1000 RX bytes:0 (0.0 B) TX bytes:0 (0.0 B) <LAVA_SIGNAL_ENDTC linux-posix-ifconfig> Received signal: <ENDTC> linux-posix-ifconfig Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-ifconfig RESULT=pass case: linux-posix-ifconfig
case_id: 10270
definition: 0_smoke-tests
result: pass
<LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-posix-ifconfig RESULT=pass> + lava-test-case linux-posix-lscpu --shell lscpu <LAVA_SIGNAL_STARTTC linux-posix-lscpu> Received signal: <STARTTC> linux-posix-lscpu /lava-462/0/../bin/lava-test-case: line 27: lscpu: command not found <LAVA_SIGNAL_ENDTC linux-posix-lscpu> Received signal: <ENDTC> linux-posix-lscpu Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-lscpu RESULT=fail case: linux-posix-lscpu
case_id: 10272
definition: 0_smoke-tests
result: fail
<LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-posix-lscpu RESULT=fail> + lava-test-case linux-posix-lsb_release --shell lsb_release -a Received signal: <STARTTC> linux-posix-lsb_release <LAVA_SIGNAL_STARTTC linux-posix-lsb_release> /lava-462/0/../bin/lava-test-case: line 27: lsb_release: command not found <LAVA_SIGNAL_ENDTC linux-posix-lsb_release> Received signal: <ENDTC> linux-posix-lsb_release <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-posix-lsb_release RESULT=fail> Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-lsb_release RESULT=fail case: linux-posix-lsb_release
case_id: 10273
definition: 0_smoke-tests
result: fail
+ set +x Received signal: <ENDRUN> 0_smoke-tests 462_1.3.3.1 Ending use of test pattern. Ending test lava.0_smoke-tests (462_1.3.3.1), duration 3.29 case: 0_smoke-tests
case_id: 10274
commit_id: 0f8952f18e992371f8686c59b36f44e9c6a94a1a
definition: lava
duration: 3.29
namespace: common
path: lava-test-shell/smoke-tests-basic.yaml
repository: http://git.linaro.org/lava-team/lava-functional-tests.git
result: pass
revision: unspecified
uuid: 462_1.3.3.1
<LAVA_SIGNAL_ENDRUN 0_smoke-tests 462_1.3.3.1> + export TESTRUN_ID=1_singlenode-basic + TESTRUN_ID=1_singlenode-basic + cd /lava-462/0/tests/1_singlenode-basic ++ cat uuid + UUID=462_1.3.3.5 + set +x <LAVA_SIGNAL_STARTRUN 1_singlenode-basic 462_1.3.3.5> Received signal: <STARTRUN> 1_singlenode-basic 462_1.3.3.5 Starting test lava.1_singlenode-basic (462_1.3.3.5) Skipping test definition patterns. + lava-test-case linux-linaro-ubuntu-netstat --shell netstat -an Received signal: <STARTTC> linux-linaro-ubuntu-netstat <LAVA_SIGNAL_STARTTC linux-linaro-ubuntu-netstat> Active Internet connections (servers and established) Proto Recv-Q Send-Q Local Address Foreign Address State tcp 0 0 0.0.0.0:5355 0.0.0.0:* LISTEN tcp 0 0 10.0.2.15:53 0.0.0.0:* LISTEN tcp 0 0 127.0.0.1:53 0.0.0.0:* LISTEN tcp6 0 0 :::5355 :::* LISTEN tcp6 0 0 fec0::dcad:beff:feef:53 :::* LISTEN tcp6 0 0 fe80::dcad:beff:feef:53 :::* LISTEN tcp6 0 0 ::1:53 :::* LISTEN tcp6 0 0 :::22 :::* LISTEN udp 0 0 0.0.0.0:5353 0.0.0.0:* udp 0 0 0.0.0.0:5355 0.0.0.0:* udp 0 0 0.0.0.0:45386 0.0.0.0:* udp 0 0 0.0.0.0:16762 0.0.0.0:* udp 0 0 10.0.2.15:53 0.0.0.0:* udp 0 0 127.0.0.1:53 0.0.0.0:* udp 0 0 0.0.0.0:68 0.0.0.0:* udp6 0 0 :::5353 :::* udp6 0 0 :::5355 :::* udp6 0 0 :::59036 :::* udp6 0 0 fec0::dcad:beff:feef:53 :::* udp6 0 0 :::17461 :::* udp6 0 0 fe80::dcad:beff:feef:53 :::* udp6 0 0 ::1:53 :::* raw6 0 0 :::58 :::* 7 Active UNIX domain sockets (servers and established) Proto RefCnt Flags Type State I-Node Path unix 13 [ ] DGRAM 8477 /run/systemd/journal/dev-log unix 3 [ ] DGRAM 379 /run/systemd/notify unix 2 [ ] DGRAM 381 /run/systemd/cgroups-agent unix 2 [ ACC ] STREAM LISTENING 383 /run/systemd/private unix 2 [ ACC ] SEQPACKET LISTENING 394 /run/udev/control unix 2 [ ACC ] STREAM LISTENING 3469 /var/run/NetworkManager/private-dhcp unix 2 [ ACC ] STREAM LISTENING 397 /run/systemd/journal/stdout unix 9 [ ] DGRAM 400 /run/systemd/journal/socket unix 2 [ ] DGRAM 456 /run/systemd/journal/syslog unix 2 [ ACC ] STREAM LISTENING 987 /var/run/dbus/system_bus_socket unix 2 [ ACC ] STREAM LISTENING 990 /var/run/rpcbind.sock unix 2 [ ] DGRAM 3554 /run/user/0/systemd/notify unix 2 [ ACC ] STREAM LISTENING 3556 /run/user/0/systemd/private unix 2 [ ACC ] STREAM LISTENING 998 /var/run/avahi-daemon/socket unix 2 [ ACC ] STREAM LISTENING 9715 /var/run/NetworkManager/private unix 2 [ ] DGRAM 11265 unix 3 [ ] STREAM CONNECTED 9172 /run/systemd/journal/stdout unix 3 [ ] STREAM CONNECTED 9280 /run/systemd/journal/stdout unix 3 [ ] STREAM CONNECTED 9565 unix 2 [ ] DGRAM 10240 unix 3 [ ] STREAM CONNECTED 3400 /run/systemd/journal/stdout unix 2 [ ] DGRAM 11295 unix 3 [ ] STREAM CONNECTED 9505 /var/run/dbus/system_bus_socket unix 3 [ ] STREAM CONNECTED 9422 unix 3 [ ] STREAM CONNECTED 2727 /run/systemd/journal/stdout unix 2 [ ] DGRAM 8582 unix 3 [ ] DGRAM 8715 unix 2 [ ] DGRAM 8486 unix 3 [ ] STREAM CONNECTED 9138 unix 3 [ ] STREAM CONNECTED 10338 unix 3 [ ] STREAM CONNECTED 9013 unix 3 [ ] STREAM CONNECTED 8961 unix 3 [ ] STREAM CONNECTED 10339 /run/systemd/journal/stdout unix 2 [ ] DGRAM 10571 unix 3 [ ] DGRAM 8718 unix 3 [ ] STREAM CONNECTED 8575 unix 2 [ ] DGRAM 9170 unix 3 [ ] STREAM CONNECTED 8576 /run/systemd/journal/stdout unix 3 [ ] STREAM CONNECTED 10369 /var/run/dbus/system_bus_socket unix 3 [ ] STREAM CONNECTED 10573 /var/run/dbus/system_bus_socket unix 3 [ ] STREAM CONNECTED 9441 unix 3 [ ] STREAM CONNECTED 2733 /run/systemd/journal/stdout unix 3 [ ] STREAM CONNECTED 9580 unix 3 [ ] STREAM CONNECTED 10572 unix 3 [ ] STREAM CONNECTED 9506 /var/run/dbus/system_bus_socket unix 3 [ ] DGRAM 8716 unix 2 [ ] DGRAM 8710 unix 3 [ ] STREAM CONNECTED 10367 unix 3 [ ] STREAM CONNECTED 9502 unix 3 [ ] DGRAM 8646 unix 2 [ ] DGRAM 10625 unix 3 [ ] STREAM CONNECTED 3275 unix 3 [ ] STREAM CONNECTED 9378 unix 3 [ ] STREAM CONNECTED 3383 unix 3 [ ] STREAM CONNECTED 2729 /run/systemd/journal/stdout unix 3 [ ] DGRAM 8717 unix 3 [ ] STREAM CONNECTED 9503 unix 3 [ ] STREAM CONNECTED 8704 /run/systemd/journal/stdout unix 3 [ ] DGRAM 8647 unix 3 [ ] STREAM CONNECTED 9614 /var/run/dbus/system_bus_socket unix 2 [ ] DGRAM 9292 unix 2 [ ] DGRAM 10135 unix 3 [ ] STREAM CONNECTED 9174 /run/systemd/journal/stdout unix 2 [ ] DGRAM 9607 unix 3 [ ] STREAM CONNECTED 9344 unix 2 [ ] DGRAM 9714 unix 3 [ ] STREAM CONNECTED 9278 /run/systemd/journal/stdout unix 3 [ ] STREAM CONNECTED 9718 /var/run/dbus/system_bus_socket unix 2 [ ] DGRAM 9285 unix 3 [ ] STREAM CONNECTED 9717 unix 3 [ ] STREAM CONNECTED 9277 unix 3 [ ] STREAM CONNECTED 10385 /var/run/dbus/system_bus_socket unix 3 [ ] STREAM CONNECTED 9935 unix 3 [ ] STREAM CONNECTED 9374 unix 3 [ ] STREAM CONNECTED 9373 unix 2 [ ] DGRAM 9370 unix 3 [ ] STREAM CONNECTED 9504 /var/run/dbus/system_bus_socket unix 2 [ ] DGRAM 2969 unix 2 [ ] DGRAM 9902 unix 3 [ ] STREAM CONNECTED 2930 /run/systemd/journal/stdout unix 2 [ ] DGRAM 9364 unix 3 [ ] STREAM CONNECTED 9613 unix 2 [ ] DGRAM 9940 unix 3 [ ] STREAM CONNECTED 10707 <LAVA_SIGNAL_ENDTC linux-linaro-ubuntu-netstat> Received signal: <ENDTC> linux-linaro-ubuntu-netstat Received signal: <TESTCASE> TEST_CASE_ID=linux-linaro-ubuntu-netstat RESULT=pass case: linux-linaro-ubuntu-netstat
case_id: 10275
definition: 1_singlenode-basic
result: pass
<LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-linaro-ubuntu-netstat RESULT=pass> + lava-test-case linux-linaro-ubuntu-ifconfig-dump --shell ifconfig -a <LAVA_SIGNAL_STARTTC linux-linaro-ubuntu-ifconfig-dump> Received signal: <STARTTC> linux-linaro-ubuntu-ifconfig-dump enp0s1 Link encap:Ethernet HWaddr de:ad:be:ef:32:01 inet addr:10.0.2.15 Bcast:10.0.2.255 Mask:255.255.255.0 inet6 addr: fec0::dcad:beff:feef:3201/64 Scope:Site inet6 addr: fe80::dcad:beff:feef:3201/64 Scope:Link UP BROADCAST RUNNING MULTICAST MTU:1500 Metric:1 RX packets:49 errors:0 dropped:0 overruns:0 frame:0 TX packets:81 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:1000 RX bytes:11035 (10.7 KiB) TX bytes:13295 (12.9 KiB) lo Link encap:Local Loopback inet addr:127.0.0.1 Mask:255.0.0.0 inet6 addr: ::1/128 Scope:Host UP LOOPBACK RUNNING MTU:65536 Metric:1 RX packets:504 errors:0 dropped:0 overruns:0 frame:0 TX packets:504 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:1000 RX bytes:36256 (35.4 KiB) TX bytes:36256 (35.4 KiB) sit0 Link encap:UNSPEC HWaddr 00-00-00-00-3A-30-30-30-00-00-00-00-00-00-00-00 NOARP MTU:1480 Metric:1 RX packets:0 errors:0 dropped:0 overruns:0 frame:0 TX packets:0 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:1000 RX bytes:0 (0.0 B) TX bytes:0 (0.0 B) Received signal: <ENDTC> linux-linaro-ubuntu-ifconfig-dump <LAVA_SIGNAL_ENDTC linux-linaro-ubuntu-ifconfig-dump> Received signal: <TESTCASE> TEST_CASE_ID=linux-linaro-ubuntu-ifconfig-dump RESULT=pass case: linux-linaro-ubuntu-ifconfig-dump
case_id: 10276
definition: 1_singlenode-basic
result: pass
<LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-linaro-ubuntu-ifconfig-dump RESULT=pass> + lava-test-case linux-linaro-ubuntu-route-dump-a --shell route <LAVA_SIGNAL_STARTTC linux-linaro-ubuntu-route-dump-a> Received signal: <STARTTC> linux-linaro-ubuntu-route-dump-a Kernel IP routing table Destination Gateway Genmask Flags Metric Ref Use Iface default gateway 0.0.0.0 UG 100 0 0 enp0s1 10.0.2.0 * 255.255.255.0 U 100 0 0 enp0s1 Received signal: <ENDTC> linux-linaro-ubuntu-route-dump-a <LAVA_SIGNAL_ENDTC linux-linaro-ubuntu-route-dump-a> <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-linaro-ubuntu-route-dump-a RESULT=pass> Received signal: <TESTCASE> TEST_CASE_ID=linux-linaro-ubuntu-route-dump-a RESULT=pass case: linux-linaro-ubuntu-route-dump-a
case_id: 10277
definition: 1_singlenode-basic
result: pass
+ lava-test-case linux-linaro-ubuntu-route-ifconfig-up-lo --shell ifconfig lo up Received signal: <STARTTC> linux-linaro-ubuntu-route-ifconfig-up-lo <LAVA_SIGNAL_STARTTC linux-linaro-ubuntu-route-ifconfig-up-lo> Received signal: <ENDTC> linux-linaro-ubuntu-route-ifconfig-up-lo <LAVA_SIGNAL_ENDTC linux-linaro-ubuntu-route-ifconfig-up-lo> <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-linaro-ubuntu-route-ifconfig-up-lo RESULT=pass> Received signal: <TESTCASE> TEST_CASE_ID=linux-linaro-ubuntu-route-ifconfig-up-lo RESULT=pass case: linux-linaro-ubuntu-route-ifconfig-up-lo
case_id: 10278
definition: 1_singlenode-basic
result: pass
+ lava-test-case linux-linaro-ubuntu-route-dump-b --shell route <LAVA_SIGNAL_STARTTC linux-linaro-ubuntu-route-dump-b> Received signal: <STARTTC> linux-linaro-ubuntu-route-dump-b Kernel IP routing table Destination Gateway Genmask Flags Metric Ref Use Iface default gateway 0.0.0.0 UG 100 0 0 enp0s1 10.0.2.0 * 255.255.255.0 U 100 0 0 enp0s1 <LAVA_SIGNAL_ENDTC linux-linaro-ubuntu-route-dump-b> Received signal: <ENDTC> linux-linaro-ubuntu-route-dump-b Received signal: <TESTCASE> TEST_CASE_ID=linux-linaro-ubuntu-route-dump-b RESULT=pass case: linux-linaro-ubuntu-route-dump-b
case_id: 10279
definition: 1_singlenode-basic
result: pass
<LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-linaro-ubuntu-route-dump-b RESULT=pass> + lava-test-case ping-test --shell ping -W1 -c1 www.linaro.org <LAVA_SIGNAL_STARTTC ping-test> Received signal: <STARTTC> ping-test PING www.linaro.org (143.204.192.25): 56 data bytes --- www.linaro.org ping statistics --- 1 packets transmitted, 0 packets received, 100% packet loss <LAVA_SIGNAL_ENDTC ping-test> Received signal: <ENDTC> ping-test Received signal: <TESTCASE> TEST_CASE_ID=ping-test RESULT=fail case: ping-test
case_id: 10280
definition: 1_singlenode-basic
result: fail
<LAVA_SIGNAL_TESTCASE TEST_CASE_ID=ping-test RESULT=fail> + set +x Received signal: <ENDRUN> 1_singlenode-basic 462_1.3.3.5 Ending use of test pattern. Ending test lava.1_singlenode-basic (462_1.3.3.5), duration 4.62 case: 1_singlenode-basic
case_id: 10281
commit_id: 0f8952f18e992371f8686c59b36f44e9c6a94a1a
definition: lava
duration: 4.62
namespace: common
path: lava-test-shell/single-node/singlenode01.yaml
repository: http://git.linaro.org/lava-team/lava-functional-tests.git
result: pass
revision: unspecified
uuid: 462_1.3.3.5
<LAVA_SIGNAL_ENDRUN 1_singlenode-basic 462_1.3.3.5> <LAVA_TEST_RUNNER EXIT> ok: lava_test_shell seems to have completed linux-linaro-ubuntu-ifconfig-dump: pass linux-linaro-ubuntu-netstat: pass linux-linaro-ubuntu-route-dump-a: pass linux-linaro-ubuntu-route-dump-b: pass linux-linaro-ubuntu-route-ifconfig-up-lo: pass linux-posix-ifconfig: pass linux-posix-lsb_release: fail linux-posix-lscpu: fail linux-posix-pwd: pass linux-posix-uname: pass linux-posix-vmstat: fail ping-test: fail end: 3.1 lava-test-shell (duration 00:00:13) [common] end: 3 lava-test-retry (duration 00:00:13) [common] start: 4 finalize (timeout 00:00:30) [common] start: 4.1 power-off (timeout 00:00:10) [common] end: 4.1 power-off (duration 00:00:00) [common] start: 4.2 read-feedback (timeout 00:00:30) [common] Listened to connection for namespace 'common' for 1s Finalising connection for namespace 'common' poweroff root@hikey:~# poweroff Disconnecting QemuSession Disconnecting from qemu: end: 4.2 read-feedback (duration 00:00:06) [common] Disconnecting QemuSession Disconnecting from qemu: end: 4 finalize (duration 00:00:06) [common] Cleaning after the job Cleaning up download directory: /var/lib/lava/dispatcher/tmp/lavafed-462/deployimages-0atk5prv/rootfs Cleaning up download directory: /var/lib/lava/dispatcher/tmp/lavafed-462/deployimages-0atk5prv/kernel Root tmp directory removed at /var/lib/lava/dispatcher/tmp/lavafed-462 Job finished correctly case: job
case_id: 10282
definition: lava
result: pass

Top of page

Please read the triage guidelines for help on debugging failures in the test job, test definitions or in individual test cases.

Job Description YAML

Pipeline jobs use the job definition to generate a descriptive YAML file when the pipeline is validated. The file represents the data sent to the dispatcher at the start of the job, including details of the device as well as the job parameters and the details of the parameters sent to each of the deploy, boot and test actions in the job pipeline.

Click on the Device or Job Actions headings below to see some of the information from the description file for this job or download the complete file as YAML using the link above.

device type
hostname
timeouts
  • actions: {'minutes': 20}{'seconds': 45}{'seconds': 10}{'minutes': 10}{'minutes': 2}{'minutes': 3}{'minutes': 2}{'minutes': 3}{'minutes': 3}{'seconds': 30}{'minutes': 3}{'minutes': 3}{'minutes': 2}{'minutes': 3}{'minutes': 10}{'minutes': 5}{'minutes': 2}{'seconds': 30}{'minutes': 5} flash-uboot-umsumount-retrypower-offdd-imageboot-image-retrybootloader-retryapply-overlay-imageuboot-retrylava-test-shellu-boot-interruptbootloader-actionuboot-actionboot-qemu-imagebootloader-commandsnfs-deploydownload-retryauto-login-actionbootloader-interrupthttp-download
  • connections: {'minutes': 10}{'seconds': 30}{'seconds': 10}{'seconds': 30}{'seconds': 30}{'minutes': 2}{'seconds': 30} dd-imageuboot-retrylava-test-shellu-boot-interruptbootloader-commandsauto-login-actionbootloader-interrupt
device action methods
deploy:
  • nfs:
  • image:
    • guest: {'interface': 'virtio', 'driveid': 'lavatest', 'size': 512}
boot:
  • qemu:
    • boot_options: {'mem': '1024M', 'console': 'ttyAMA0 115200', 'root': '/dev/vda2 rw rootwait', 'ip': 'dhcp'}
    • options: ['-cpu cortex-a57', '-machine virt', '-nographic', '-net nic,model=virtio,macaddr=DE:AD:BE:EF:32:01 -net user', '-m 1024', '-monitor none']
    • command: qemu-system-aarch64
  • qemu-nfs:
    • boot_options: {'mem': '1024M', 'console': 'ttyAMA0 115200', 'root': '/dev/vda2 rw rootwait', 'ip': 'dhcp'}
    • options: ['-cpu cortex-a57', '-machine virt', '-nographic', '-net nic,model=virtio,macaddr=DE:AD:BE:EF:32:01 -net user', '-m 1024', '-monitor none']
    • append: {'nfsrootargs': 'nfsroot={NFS_SERVER_IP}:{NFSROOTFS},tcp,hard,intr', 'console': 'ttyAMA0', 'root': '/dev/nfs', 'ipargs': 'ip=dhcp'}
    • command: qemu-system-aarch64
target
...
timeouts
  • job: {'seconds': 900}

  1. to
    tmpfs
    deployment_data
    {'lava_test_shell_file': '~/.bashrc', 'boot_cmds': 'boot_cmds_oe', 'TESTER_PS1': 'linaro-test [rc=$(echo \\$?)]# ', 'TESTER_PS1_PATTERN': 'linaro-test \\[rc=(\\d+)\\]# ', 'line_separator': '\n', 'distro': 'oe', 'TESTER_PS1_INCLUDES_RC': True, 'lava_test_dir': '/lava-%s', 'lava_test_sh_cmd': '/bin/sh', 'lava_test_results_dir': '/lava-%s', 'lava_test_results_part_attr': 'root_part'}
    namespace
    common
    os
    oe
    Timeout
    • 10 minutes
    images
    {'kernel': {'image_arg': '-kernel {kernel} --append "console=ttyAMA0 root=/dev/vda rw"', 'url': 'http://snapshots.linaro.org/openembedded/lkft/morty/hikey/rpb/linux-stable-rc-4.14/64/Image--4.14+git0+ae407d95ee-r0-hikey-20180108141340-64.bin', 'type': 'image'}, 'rootfs': {'image_arg': '-drive format=raw,file={rootfs} -cpu cortex-a57 -machine type=virt -nographic -smp 4 -m 2048', 'apply-overlay': True, 'url': 'http://snapshots.linaro.org/openembedded/lkft/morty/hikey/rpb/linux-stable-rc-4.14/64/rpb-console-image-hikey-20180108141340-64.rootfs.ext4.gz', 'compression': 'gz', 'sparse': False}}

  1. prompts
    ['root@hikey:~#']
    method
    qemu
    auto_login
    {'username': 'root', 'login_prompt': 'login:', 'login_commands': ['su']}
    Timeout
    • 5 minutes
    namespace
    common
    media
    tmpfs

  1. namespace
    common
    stage
    0
    Definitions
    • from git
    • repository http://git.linaro.org/lava-team/lava-functional-tests.git
    • name smoke-tests
    • path lava-test-shell/smoke-tests-basic.yaml
    • from git
    • repository http://git.linaro.org/lava-team/lava-functional-tests.git
    • name singlenode-basic
    • path lava-test-shell/single-node/singlenode01.yaml
    Timeout
    • 5 minutes

Job Timings

This section helps test writers to check for actions with a duration which is much shorter than the requested timeout. Reducing these timeouts will allow failures to be identified more quickly.

The graph only shows actions that are longer than 1 second. The full list is anyway available in the table.

Loading the data...