Help
Invalid job definition:

extra keys not allowed @ data['actions[0]']['deploy']['tmpfs']

Description
[lavafed 2019.06.0097.gd4707dda2] qemu - hikey
Device-type
qemu
Submitter
Rémi Duraffort
Created
3 months, 1 week ago
Priority
Medium
Visibility
Public
Required Tags
staging.validation.linaro.org
#770
Complete
Device
staging-qemu01@staging.validation.linaro.org
Started
3 months, 1 week ago
Duration
4 minutes
Results
  
lava-dispatcher, installed at version: 2019.06.0097.gd4707dda2+stretch start: 0 validate Start time: 2019-07-06 09:09:42.523156+00:00 (UTC) 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 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 Using GET because HEAD is not supported properly qemu-system-arm, installed at version: 1:2.8+dfsg-6+deb9u7, host architecture: amd64 validate duration: 1.49 case: validate
case_id: 16227
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/Image--4.14+git0+ae407d95ee-r0-hikey-20180108141340-64.bin saving as /var/lib/lava/dispatcher/tmp/lavafed-770/deployimages-01dv6eim/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 0.70s (27.04MB/s) end: 1.1.1 http-download (duration 00:00:01) [common] case: http-download
case_id: 16228
definition: lava
duration: 0.71
extra: ...
level: 1.1.1
namespace: common
result: pass
end: 1.1 download-retry (duration 00:00:01) [common] start: 1.2 download-retry (timeout 00:09:59) [common] start: 1.2.1 http-download (timeout 00:09:59) [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-770/deployimages-01dv6eim/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 62.88s (5.48MB/s) end: 1.2.1 http-download (duration 00:01:03) [common] case: http-download
case_id: 16228
definition: lava
duration: 62.88
extra: ...
level: 1.2.1
namespace: common
result: pass
end: 1.2 download-retry (duration 00:01:03) [common] start: 1.3 lava-overlay (timeout 00:08:56) [common] [common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/lavafed-770/lava-overlay-7o299e7q makedir: /var/lib/lava/dispatcher/tmp/lavafed-770/lava-overlay-7o299e7q/lava-770/bin makedir: /var/lib/lava/dispatcher/tmp/lavafed-770/lava-overlay-7o299e7q/lava-770/tests makedir: /var/lib/lava/dispatcher/tmp/lavafed-770/lava-overlay-7o299e7q/lava-770/results Creating /var/lib/lava/dispatcher/tmp/lavafed-770/lava-overlay-7o299e7q/lava-770/bin/lava-add-keys Creating /var/lib/lava/dispatcher/tmp/lavafed-770/lava-overlay-7o299e7q/lava-770/bin/lava-add-sources Creating /var/lib/lava/dispatcher/tmp/lavafed-770/lava-overlay-7o299e7q/lava-770/bin/lava-background-process-start Creating /var/lib/lava/dispatcher/tmp/lavafed-770/lava-overlay-7o299e7q/lava-770/bin/lava-background-process-stop Creating /var/lib/lava/dispatcher/tmp/lavafed-770/lava-overlay-7o299e7q/lava-770/bin/lava-common-functions Creating /var/lib/lava/dispatcher/tmp/lavafed-770/lava-overlay-7o299e7q/lava-770/bin/lava-echo-ipv4 Creating /var/lib/lava/dispatcher/tmp/lavafed-770/lava-overlay-7o299e7q/lava-770/bin/lava-install-packages Creating /var/lib/lava/dispatcher/tmp/lavafed-770/lava-overlay-7o299e7q/lava-770/bin/lava-installed-packages Creating /var/lib/lava/dispatcher/tmp/lavafed-770/lava-overlay-7o299e7q/lava-770/bin/lava-os-build Creating /var/lib/lava/dispatcher/tmp/lavafed-770/lava-overlay-7o299e7q/lava-770/bin/lava-probe-channel Creating /var/lib/lava/dispatcher/tmp/lavafed-770/lava-overlay-7o299e7q/lava-770/bin/lava-probe-ip Creating /var/lib/lava/dispatcher/tmp/lavafed-770/lava-overlay-7o299e7q/lava-770/bin/lava-target-ip Creating /var/lib/lava/dispatcher/tmp/lavafed-770/lava-overlay-7o299e7q/lava-770/bin/lava-target-mac Creating /var/lib/lava/dispatcher/tmp/lavafed-770/lava-overlay-7o299e7q/lava-770/bin/lava-target-storage Creating /var/lib/lava/dispatcher/tmp/lavafed-770/lava-overlay-7o299e7q/lava-770/bin/lava-test-case Creating /var/lib/lava/dispatcher/tmp/lavafed-770/lava-overlay-7o299e7q/lava-770/bin/lava-test-event Creating /var/lib/lava/dispatcher/tmp/lavafed-770/lava-overlay-7o299e7q/lava-770/bin/lava-test-feedback Creating /var/lib/lava/dispatcher/tmp/lavafed-770/lava-overlay-7o299e7q/lava-770/bin/lava-test-raise Creating /var/lib/lava/dispatcher/tmp/lavafed-770/lava-overlay-7o299e7q/lava-770/bin/lava-test-reference Creating /var/lib/lava/dispatcher/tmp/lavafed-770/lava-overlay-7o299e7q/lava-770/bin/lava-test-runner Creating /var/lib/lava/dispatcher/tmp/lavafed-770/lava-overlay-7o299e7q/lava-770/bin/lava-test-set Creating /var/lib/lava/dispatcher/tmp/lavafed-770/lava-overlay-7o299e7q/lava-770/bin/lava-test-shell Updating /var/lib/lava/dispatcher/tmp/lavafed-770/lava-overlay-7o299e7q/lava-770/bin/lava-install-packages (oe) Updating /var/lib/lava/dispatcher/tmp/lavafed-770/lava-overlay-7o299e7q/lava-770/bin/lava-installed-packages (oe) Creating /var/lib/lava/dispatcher/tmp/lavafed-770/lava-overlay-7o299e7q/lava-770/environment start: 1.3.1 lava-vland-overlay (timeout 00:08:56) [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:08:56) [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:08:56) [common] Loading test definitions start: 1.3.3.1 git-repo-action (timeout 00:08:56) [common] Using /lava-770 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-770/lava-overlay-7o299e7q/lava-770/0/tests/0_smoke-tests --depth=1' Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/lavafed-770/lava-overlay-7o299e7q/lava-770/0/tests/0_smoke-tests/lava-test-shell/smoke-tests-basic.yaml uuid=770_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: 16288
definition: lava
duration: 0.71
extra: ...
level: 1.3.3.1
namespace: common
result: pass
start: 1.3.3.2 test-overlay (timeout 00:08:56) [common] end: 1.3.3.2 test-overlay (duration 00:00:00) [common] case: test-overlay
case_id: 16289
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:08:56) [common] end: 1.3.3.3 test-install-overlay (duration 00:00:00) [common] case: test-install-overlay
case_id: 16290
definition: lava
duration: 0.05
extra: ...
level: 1.3.3.3
namespace: common
result: pass
start: 1.3.3.4 test-runscript-overlay (timeout 00:08:56) [common] runner path: /var/lib/lava/dispatcher/tmp/lavafed-770/lava-overlay-7o299e7q/lava-770/0/tests/0_smoke-tests test_uuid 770_1.3.3.1 end: 1.3.3.4 test-runscript-overlay (duration 00:00:00) [common] case: test-runscript-overlay
case_id: 16291
definition: lava
duration: 0.02
extra: ...
level: 1.3.3.4
namespace: common
result: pass
start: 1.3.3.5 git-repo-action (timeout 00:08:56) [common] Using /lava-770 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-770/lava-overlay-7o299e7q/lava-770/0/tests/1_singlenode-basic --depth=1' Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/lavafed-770/lava-overlay-7o299e7q/lava-770/0/tests/1_singlenode-basic/lava-test-shell/single-node/singlenode01.yaml uuid=770_1.3.3.5 testdef=None end: 1.3.3.5 git-repo-action (duration 00:00:00) [common] case: git-repo-action
case_id: 16288
definition: lava
duration: 0.19
extra: ...
level: 1.3.3.5
namespace: common
result: pass
start: 1.3.3.6 test-overlay (timeout 00:08:55) [common] end: 1.3.3.6 test-overlay (duration 00:00:00) [common] case: test-overlay
case_id: 16289
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:08:55) [common] end: 1.3.3.7 test-install-overlay (duration 00:00:00) [common] case: test-install-overlay
case_id: 16290
definition: lava
duration: 0.03
extra: ...
level: 1.3.3.7
namespace: common
result: pass
start: 1.3.3.8 test-runscript-overlay (timeout 00:08:55) [common] runner path: /var/lib/lava/dispatcher/tmp/lavafed-770/lava-overlay-7o299e7q/lava-770/0/tests/1_singlenode-basic test_uuid 770_1.3.3.5 end: 1.3.3.8 test-runscript-overlay (duration 00:00:00) [common] case: test-runscript-overlay
case_id: 16291
definition: lava
duration: 0.04
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-770/lava-overlay-7o299e7q/lava-770/0 for stage 0 - 0_smoke-tests - 1_singlenode-basic end: 1.3.3 test-definition (duration 00:00:01) [common] start: 1.3.4 compress-overlay (timeout 00:08:55) [common] end: 1.3.4 compress-overlay (duration 00:00:00) [common] start: 1.3.5 persistent-nfs-overlay (timeout 00:08:55) [common] end: 1.3.5 persistent-nfs-overlay (duration 00:00:00) [common] end: 1.3 lava-overlay (duration 00:00:01) [common] start: 1.4 apply-overlay-guest (timeout 00:08:55) [common] Overlay: /var/lib/lava/dispatcher/tmp/lavafed-770/compress-overlay-wgwe43my/overlay-1.3.4.tar.gz end: 1.4 apply-overlay-guest (duration 00:00:07) [common] case: apply-overlay-guest
case_id: 16296
definition: lava
duration: 6.59
extra: ...
level: 1.4
namespace: common
result: pass
start: 1.5 deploy-device-env (timeout 00:08:49) [common] end: 1.5 deploy-device-env (duration 00:00:00) [common] end: 1 deployimages (duration 00:01:11) [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 -kernel /var/lib/lava/dispatcher/tmp/lavafed-770/deployimages-01dv6eim/kernel/Image--4.14+git0+ae407d95ee-r0-hikey-20180108141340-64.bin --append "console=ttyAMA0 root=/dev/vda rw" -drive format=raw,file=/var/lib/lava/dispatcher/tmp/lavafed-770/deployimages-01dv6eim/rootfs/rpb-console-image-hikey-20180108141340-64.rootfs.ext4 -cpu cortex-a57 -machine type=virt -nographic -smp 4 -m 2048 -drive format=qcow2,file=/var/lib/lava/dispatcher/tmp/lavafed-770/apply-overlay-guest-yxfvmwag/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: 16297
definition: lava
duration: 1.03
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:56) [ 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.000257] sched_clock: 56 bits at 62MHz, resolution 16ns, wraps every 4398046511096ns [ 0.015707] Console: colour dummy device 80x25 [ 0.016751] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar [ 0.016848] ... MAX_LOCKDEP_SUBCLASSES: 8 [ 0.016930] ... MAX_LOCK_DEPTH: 48 [ 0.017009] ... MAX_LOCKDEP_KEYS: 8191 [ 0.017088] ... CLASSHASH_SIZE: 4096 [ 0.017166] ... MAX_LOCKDEP_ENTRIES: 32768 [ 0.017439] ... MAX_LOCKDEP_CHAINS: 65536 [ 0.017517] ... CHAINHASH_SIZE: 32768 [ 0.017595] memory used by lock dependency info: 7391 kB [ 0.017673] per task-struct memory footprint: 1920 bytes [ 0.019583] Calibrating delay loop (skipped), value calculated using timer frequency.. 125.00 BogoMIPS (lpj=250000) [ 0.019932] pid_max: default: 32768 minimum: 301 [ 0.022820] Security Framework initialized [ 0.030332] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes) [ 0.033729] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes) [ 0.034440] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.034671] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.118503] ASID allocator initialised with 65536 entries [ 0.122460] Hierarchical SRCU implementation. [ 0.148752] EFI services will not be available. [ 0.160806] smp: Bringing up secondary CPUs ... [ 0.194444] Detected PIPT I-cache on CPU1 [ 0.196360] CPU1: Booted secondary processor [411fd070] [ 0.241605] Detected PIPT I-cache on CPU2 [ 0.242003] CPU2: Booted secondary processor [411fd070] [ 0.277035] Detected PIPT I-cache on CPU3 [ 0.278019] CPU3: Booted secondary processor [411fd070] [ 0.281496] smp: Brought up 1 node, 4 CPUs [ 0.281770] SMP: Total of 4 processors activated. [ 0.282446] CPU features: detected feature: 32-bit EL0 Support [ 0.309444] CPU: All CPU(s) started at EL1 [ 0.312351] alternatives: patching kernel code [ 0.365967] devtmpfs: initialized [ 0.431234] random: get_random_u32 called from bucket_table_alloc+0x11c/0x270 with crng_init=0 [ 0.505303] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns [ 0.506827] futex hash table entries: 1024 (order: 5, 131072 bytes) [ 0.524634] pinctrl core: initialized pinctrl subsystem [ 0.557920] random: fast init done [ 0.662462] DMI not present or invalid. [ 0.704150] NET: Registered protocol family 16 [ 0.887798] cpuidle: using governor menu [ 0.906962] vdso: 2 pages (1 code @ ffff000008b96000, 1 data @ ffff0000091c5000) [ 0.907437] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. [ 0.954790] DMA: preallocated 256 KiB pool for atomic allocations [ 0.986312] Serial: AMBA PL011 UART driver [ 1.403462] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 39, base_baud = 0) is a PL011 rev1 [ 1.470799] console [ttyAMA0] enabled [ 1.531707] irq: type mismatch, failed to map hwirq-27 for /intc! [ 3.032667] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 3.096345] ACPI: Interpreter disabled. [ 3.166690] vgaarb: loaded [ 3.186173] SCSI subsystem initialized [ 3.239353] usbcore: registered new interface driver usbfs [ 3.251333] usbcore: registered new interface driver hub [ 3.256650] usbcore: registered new device driver usb [ 3.326902] pps_core: LinuxPPS API ver. 1 registered [ 3.327377] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it> [ 3.328528] PTP clock support registered [ 3.340729] EDAC MC: Ver: 3.0.0 [ 3.376598] dmi: Firmware registration failed. [ 3.414779] Advanced Linux Sound Architecture Driver Initialized. [ 3.477223] clocksource: Switched to clocksource arch_sys_counter [ 5.344562] VFS: Disk quotas dquot_6.6.0 [ 5.345948] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 5.354254] pnp: PnP ACPI: disabled [ 5.993414] NET: Registered protocol family 2 [ 6.032840] TCP established hash table entries: 16384 (order: 5, 131072 bytes) [ 6.037438] TCP bind hash table entries: 16384 (order: 8, 1048576 bytes) [ 6.076128] TCP: Hash tables configured (established 16384 bind 16384) [ 6.084279] UDP hash table entries: 1024 (order: 5, 163840 bytes) [ 6.091544] UDP-Lite hash table entries: 1024 (order: 5, 163840 bytes) [ 6.105940] NET: Registered protocol family 1 [ 6.127652] RPC: Registered named UNIX socket transport module. [ 6.128297] RPC: Registered udp transport module. [ 6.128720] RPC: Registered tcp transport module. [ 6.129119] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 6.149945] kvm [1]: HYP mode not available [ 6.378472] audit: initializing netlink subsys (disabled) [ 6.428013] audit: type=2000 audit(5.596:1): state=initialized audit_enabled=0 res=1 [ 6.434224] workingset: timestamp_bits=44 max_order=19 bucket_order=0 [ 7.234323] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ 7.346490] NFS: Registering the id_resolver key type [ 7.355898] Key type id_resolver registered [ 7.356574] Key type id_legacy registered [ 7.357441] nfs4filelayout_init: NFSv4 File Layout Driver Registering... [ 7.377379] 9p: Installing v9fs 9p2000 file system support [ 7.612401] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 246) [ 7.613843] io scheduler noop registered [ 7.632852] io scheduler cfq registered (default) [ 7.633460] io scheduler mq-deadline registered [ 7.634002] io scheduler kyber registered [ 7.664154] test_firmware: interface ready [ 8.221116] pl061_gpio 9030000.pl061: PL061 GPIO chip @0x0000000009030000 registered [ 8.358414] OF: PCI: host bridge /pcie@10000000 ranges: [ 8.365049] OF: PCI: IO 0x3eff0000..0x3effffff -> 0x00000000 [ 8.371614] OF: PCI: MEM 0x10000000..0x3efeffff -> 0x10000000 [ 8.372741] OF: PCI: MEM 0x8000000000..0xffffffffff -> 0x8000000000 [ 8.384837] pci-host-generic 3f000000.pcie: ECAM at [mem 0x3f000000-0x3fffffff] for [bus 00-0f] [ 8.394245] pci-host-generic 3f000000.pcie: PCI host bridge to bus 0000:00 [ 8.401016] pci_bus 0000:00: root bus resource [bus 00-0f] [ 8.401825] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] [ 8.402490] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] [ 8.404721] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] [ 8.487771] pci 0000:00:01.0: BAR 6: assigned [mem 0x10000000-0x1003ffff pref] [ 8.491395] pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] [ 8.496165] pci 0000:00:02.0: BAR 4: assigned [mem 0x8000004000-0x8000007fff 64bit pref] [ 8.501687] pci 0000:00:03.0: BAR 4: assigned [mem 0x8000008000-0x800000bfff 64bit pref] [ 8.503588] pci 0000:00:01.0: BAR 1: assigned [mem 0x10040000-0x10040fff] [ 8.506188] pci 0000:00:02.0: BAR 1: assigned [mem 0x10041000-0x10041fff] [ 8.509104] pci 0000:00:03.0: BAR 1: assigned [mem 0x10042000-0x10042fff] [ 8.509944] pci 0000:00:02.0: BAR 0: assigned [io 0x1000-0x103f] [ 8.512872] pci 0000:00:03.0: BAR 0: assigned [io 0x1040-0x107f] [ 8.513585] pci 0000:00:01.0: BAR 0: assigned [io 0x1080-0x109f] [ 9.130387] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) [ 9.152651] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) [ 9.170139] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) [ 9.722505] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 9.878492] SuperH (H)SCI(F) driver initialized [ 9.936857] msm_serial: driver initialized [ 9.976168] cacheinfo: Unable to detect cache hierarchy for CPU 0 [ 10.484662] loop: module loaded [ 11.129568] libphy: Fixed MDIO Bus: probed [ 11.484678] e1000e: Intel(R) PRO/1000 Network Driver - 3.2.6-k [ 11.485707] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. [ 11.493346] igb: Intel(R) Gigabit Ethernet Network Driver - version 5.4.0-k [ 11.494070] igb: Copyright (c) 2007-2014 Intel Corporation. [ 11.498129] igbvf: Intel(R) Gigabit Virtual Function Network Driver - version 2.4.0-k [ 11.501662] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. [ 11.534127] sky2: driver version 1.30 [ 11.602502] VFIO - User Level meta-driver version: 0.3 [ 11.740312] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver [ 11.741083] ehci-pci: EHCI PCI platform driver [ 11.754447] ehci-platform: EHCI generic platform driver [ 11.770372] ehci-orion: EHCI orion driver [ 11.774427] ehci-exynos: EHCI EXYNOS driver [ 11.778321] ehci-msm: Qualcomm On-Chip EHCI Host Controller [ 11.782222] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver [ 11.784969] ohci-pci: OHCI PCI platform driver [ 11.788217] ohci-platform: OHCI generic platform driver [ 11.792326] ohci-exynos: OHCI EXYNOS driver [ 11.808284] usbcore: registered new interface driver usb-storage [ 11.916488] rtc-pl031 9010000.pl031: rtc core: registered pl031 as rtc0 [ 12.148621] i2c /dev entries driver [ 12.250082] sdhci: Secure Digital Host Controller Interface driver [ 12.252056] sdhci: Copyright(c) Pierre Ossman [ 12.258032] Synopsys Designware Multimedia Card Interface Driver [ 12.285146] sdhci-pltfm: SDHCI platform and OF driver helper [ 12.459562] ledtrig-cpu: registered to indicate activity on CPUs [ 12.806072] usbcore: registered new interface driver usbhid [ 12.808041] usbhid: USB HID core driver [ 13.101139] NET: Registered protocol family 10 [ 13.197807] Segment Routing with IPv6 [ 13.200814] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver [ 13.247784] NET: Registered protocol family 17 [ 13.256473] 9pnet: Installing 9P2000 support [ 13.261911] Key type dns_resolver registered [ 13.388007] registered taskstats version 1 [ 13.461655] input: gpio-keys as /devices/platform/gpio-keys/input/input0 [ 13.497125] rtc-pl031 9010000.pl031: setting system clock to 2019-07-06 09:11:11 UTC (1562404271) [ 13.513502] ALSA device list: [ 13.513960] No soundcards found. [ 13.544907] uart-pl011 9000000.pl011: no DMA platform data [ 13.584461] EXT4-fs (vda): couldn't mount as ext3 due to feature incompatibilities [ 13.765547] EXT4-fs (vda): mounted filesystem with ordered data mode. Opts: (null) [ 13.768264] VFS: Mounted root (ext4 filesystem) on device 254:0. [ 13.819766] devtmpfs: mounted [ 14.099472] Freeing unused kernel memory: 1280K [ 15.232162] 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.246429] systemd[1]: Detected virtualization qemu. [ 15.248683] systemd[1]: Detected architecture arm64. [ 15.250241] systemd[1]: Running with unpopulated /etc. Welcome to [1mReference-Platform-Build-X11 2.2+linaro[0m! [ 15.271624] systemd[1]: Set hostname to <hikey>. [ 15.301377] systemd[1]: Initializing machine ID from random generator. [ 17.190434] systemd[1]: Failed to populate /etc with preset unit settings, ignoring: Too many levels of symbolic links [ 20.285117] systemd[1]: Listening on udev Control Socket. [[0;32m OK [0m] Listening on udev Control Socket. [ 20.304747] systemd[1]: Listening on Journal Socket (/dev/log). [[0;32m OK [0m] Listening on Journal Socket (/dev/log). [ 20.312524] systemd[1]: Reached target Remote File Systems. [[0;32m OK [0m] Reached target Remote File Systems. [ 20.322062] systemd[1]: Listening on Journal Socket. [[0;32m OK [0m] Listening on Journal Socket. [ 20.334065] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe. [[0;32m OK [0m] Listening on /dev/initctl Compatibility Named Pipe. [ 20.341457] systemd[1]: Listening on Syslog Socket. [[0;32m OK [0m] Listening on Syslog Socket. [ 20.357654] systemd[1]: Started Forward Password Requests to Wall Directory Watch. [[0;32m OK [0m] Started Forward Password Requests to Wall Directory Watch. [ 20.429275] systemd[1]: Created slice User and Session Slice. [[0;32m OK [0m] Created slice User and Session Slice. [ 20.436331] systemd[1]: Reached target Swap. [[0;32m OK [0m] Reached target Swap. [ 20.442512] systemd[1]: Listening on udev Kernel Socket. [[0;32m OK [0m] Listening on udev Kernel Socket. [[0;32m OK [0m] Listening on Network Service Netlink Socket. [[0;32m OK [0m] Listening on Journal Audit Socket. [[0;32m OK [0m] Started Dispatch Password Requests to Console Directory Watch. [[0;32m OK [0m] Reached target Paths. [[0;32m OK [0m] Created slice System Slice. Starting Setup Virtual Console... Mounting Debug File System... [[0;32m OK [0m] Created slice system-getty.slice. Mounting Huge Pages File System... [[0;32m OK [0m] Reached target Slices. Mounting POSIX Message Queue File System... [[0;32m OK [0m] Created slice system-serial\x2dgetty.slice. Starting Remount Root and Kernel File Systems... Mounting Temporary Directory... Starting Create list of required st... nodes for the current kernel... [ 24.176205] EXT4-fs (vda): re-mounted. Opts: (null) Starting Load Kernel Modules... Starting Journal Service... [ 25.584861] fuse init (API version 7.26) [ 25.605486] systemd[1]: Mounted Debug File System. [[0;32m OK [0m] Mounted Debug File System. [ 25.668778] systemd[1]: Mounted Huge Pages File System. [[0;32m OK [0m] Mounted Huge Pages File System. [ 25.738160] systemd[1]: Mounted POSIX Message Queue File System. [[0;32m OK [0m] Mounted POSIX Message Queue File System. [ 25.788109] systemd[1]: Mounted Temporary Directory. [[0;32m OK [0m] Mounted Temporary Directory. [ 25.960656] systemd[1]: Started Setup Virtual Console. [[0;32m OK [0m] Started Setup Virtual Console. [ 26.054086] systemd[1]: Started Remount Root and Kernel File Systems. [[0;32m OK [0m] Started Remount Root and Kernel File Systems. [ 26.214236] 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. [ 26.274252] systemd[1]: Started Load Kernel Modules. [[0;32m OK [0m] Started Load Kernel Modules. [ 26.708149] systemd[1]: Starting Apply Kernel Variables... Starting Apply Kernel Variables... [ 26.889349] systemd[1]: Mounting Configuration File System... Mounting Configuration File System... Mounting FUSE Control File System... Starting Create System Users... Starting Rebuild Hardware Database... [[0;32m OK [0m] Mounted Configuration File System. [[0;32m OK [0m] Mounted FUSE Control File System. [[0;32m OK [0m] Started Apply Kernel Variables. [ 30.860878] systemd[1]: Started Create System Users. [[0;32m OK [0m] Started Create System Users. [ 31.148461] systemd[1]: Starting Create Static Device Nodes in /dev... Starting Create Static Device Nodes in /dev... [ 31.580247] 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. [[0;32m OK [0m] Reached target Local File Systems (Pre). [ 34.118038] systemd-journald[1334]: Received request to flush runtime journal from PID 1 Mounting /var/volatile... Starting udev Kernel Device Manager... [[0;32m OK [0m] Mounted /var/volatile. [[0;32m OK [0m] Started Flush Journal to Persistent Storage. Starting Load/Save Random Seed... [[0;32m OK [0m] Reached target Local File Systems. Starting Create Volatile Files and Directories... Starting Rebuild Journal Catalog... Starting Rebuild Dynamic Linker Cache... [[0;32m OK [0m] Started Load/Save Random Seed. [[0;32m OK [0m] Started Create Volatile Files and Directories. Starting Update UTMP about System Boot/Shutdown... Starting Network Time Synchronization... [[0;32m OK [0m] Started udev Kernel Device Manager. [[0;32m OK [0m] Started Rebuild Journal Catalog. [[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. [ 46.059116] hrtimer: interrupt took 11320336 ns [[0;32m OK [0m] Started Rebuild Dynamic Linker Cache. [[0;32m OK [0m] Started Rebuild Hardware Database. Starting Update is Completed... Starting udev Coldplug all Devices... [[0;32m OK [0m] Started Update is Completed. [[0m[0;31m* [0m] (1 of 4) A start job is running for...-ttyAMA2.device (40s / 1min 30s)[K[[0;1;31m*[0m[0;31m* [0m] (1 of 4) A start job is running for...-ttyAMA2.device (41s / 1min 30s)[K[[0;31m*[0;1;31m*[0m[0;31m* [0m] (1 of 4) A start job is running for...-ttyAMA2.device (41s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m[0;31m* [0m] (2 of 4) A start job is running for...lug all Devices (42s / no limit)[K[ [0;31m*[0;1;31m*[0m[0;31m* [0m] (2 of 4) A start job is running for...lug all Devices (42s / no limit)[K[ [0;31m*[0;1;31m*[0m[0;31m*[0m] (2 of 4) A start job is running for...lug all Devices (43s / no limit)[K[ [0;31m*[0;1;31m*[0m] (3 of 4) A start job is running for...-ttyAMA3.device (43s / 1min 30s)[K[ [0;31m*[0m] (3 of 4) A start job is running for...-ttyAMA3.device (44s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m] (3 of 4) A start job is running for...-ttyAMA3.device (45s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m[0;31m*[0m] (4 of 4) A start job is running for...-ttyAMA0.device (45s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m[0;31m* [0m] (4 of 4) A start job is running for...-ttyAMA0.device (46s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m[0;31m* [0m] (4 of 4) A start job is running for...-ttyAMA0.device (47s / 1min 30s)[K[[0;31m*[0;1;31m*[0m[0;31m* [0m] (1 of 4) A start job is running for...-ttyAMA2.device (47s / 1min 30s)[K[[0;1;31m*[0m[0;31m* [0m] (1 of 4) A start job is running for...-ttyAMA2.device (48s / 1min 30s)[K[[0m[0;31m* [0m] (1 of 4) A start job is running for...-ttyAMA2.device (48s / 1min 30s)[K[[0;1;31m*[0m[0;31m* [0m] (2 of 4) A start job is running for...lug all Devices (49s / no limit)[K[[0;31m*[0;1;31m*[0m[0;31m* [0m] (2 of 4) A start job is running for...lug all Devices (49s / no limit)[K[ [0;31m*[0;1;31m*[0m[0;31m* [0m] (2 of 4) A start job is running for...lug all Devices (50s / no limit)[K[ [0;31m*[0;1;31m*[0m[0;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] (3 of 4) A start job is running for...-ttyAMA3.device (51s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m] (3 of 4) A start job is running for...-ttyAMA3.device (51s / 1min 30s)[K[ [0;31m*[0m] (4 of 4) A start job is running for...-ttyAMA0.device (52s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m] (4 of 4) A start job is running for...-ttyAMA0.device (52s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m[0;31m*[0m] (4 of 4) A start job is running for...-ttyAMA0.device (53s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m[0;31m* [0m] (1 of 4) A start job is running for...-ttyAMA2.device (53s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m[0;31m* [0m] (1 of 4) A start job is running for...-ttyAMA2.device (54s / 1min 30s)[K[[0;31m*[0;1;31m*[0m[0;31m* [0m] (1 of 4) A start job is running for...-ttyAMA2.device (54s / 1min 30s)[K[[0;1;31m*[0m[0;31m* [0m] (2 of 4) A start job is running for...lug all Devices (55s / no limit)[K[[0m[0;31m* [0m] (2 of 4) A start job is running for...lug all Devices (55s / no limit)[K[[0;1;31m*[0m[0;31m* [0m] (2 of 4) A start job is running for...lug all Devices (56s / no limit)[K[[0;31m*[0;1;31m*[0m[0;31m* [0m] (3 of 4) A start job is running for...-ttyAMA3.device (56s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m[0;31m* [0m] (3 of 4) A start job is running for...-ttyAMA3.device (56s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m[0;31m* [0m] (3 of 4) A start job is running for...-ttyAMA3.device (57s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m[0;31m*[0m] (4 of 4) A start job is running for...-ttyAMA0.device (57s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m] (4 of 4) A start job is running for...-ttyAMA0.device (58s / 1min 30s)[K[ [0;31m*[0m] (4 of 4) A start job is running for...-ttyAMA0.device (58s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m] (1 of 4) A start job is running for...-ttyAMA2.device (59s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m[0;31m*[0m] (1 of 4) A start job is running for...-ttyAMA2.device (59s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m[0;31m* [0m] (1 of 4) A start job is running for...ttyAMA2.device (1min / 1min 30s)[K[ [0;31m*[0;1;31m*[0m[0;31m* [0m] (2 of 4) A start job is running for...ug all Devices (1min / no limit)[K[[0;31m*[0;1;31m*[0m[0;31m* [0m] (2 of 4) A start job is running for...all Devices (1min 1s / no limit)[K[[0;1;31m*[0m[0;31m* [0m] (2 of 4) A start job is running for...all Devices (1min 1s / no limit)[K[[0m[0;31m* [0m] (3 of 4) A start job is running for...AMA3.device (1min 2s / 1min 30s)[K[[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...AMA0.device (1min 3s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m[0;31m* [0m] (4 of 4) A start job is running for...AMA0.device (1min 4s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m[0;31m*[0m] (4 of 4) A start job is running for...AMA0.device (1min 4s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m] (1 of 4) A start job is running for...AMA2.device (1min 5s / 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 RPCbind Server Activation Socket. [[0;32m OK [0m] Listening on Avahi mDNS/DNS-SD Stack Activation Socket. [[0;32m OK [0m] Listening on D-Bus System Message Bus Socket. Starting sshd.socket. [ 87.149623] virtio_net virtio0 enp0s1: renamed from eth0 [[0;32m OK [0m] Started Daily Cleanup of Temporary Directories. [[0;32m OK [0m] Reached target Timers. [[0;32m OK [0m] Listening on sshd.socket. [[0;32m OK [0m] Reached target Sockets. [[0;32m OK [0m] Reached target Basic System. Starting Resize root filesystem to fit available disk space... [[0;32m OK [0m] Started Kernel Logging Service. [[0;32m OK [0m] Started System Logging Service. Starting Avahi mDNS/DNS-SD Stack... [[0;32m OK [0m] Started Job spooling tools. [[0;32m OK [0m] Started TEE Supplicant. [[0;32m OK [0m] Started Periodic Command Scheduler. [[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... Starting Login Service... [ [0;31m*[0m] (5 of 6) A start job is running for...rk Manager (1min 27s / 2min 52s)[K[ [0;31m*[0;1;31m*[0m] (5 of 6) A start job is running for...rk Manager (1min 27s / 2min 52s)[K[[0;32m OK [0m] Started Network Service. [[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;1;31m TIME [0m] Timed out waiting for device dev-ttyAMA3.device. [[0;1;33mDEPEND[0m] Dependency failed for Serial Getty on ttyAMA3. [[0;32m OK [0m] Started Login Service. [[0;32m OK [0m] Started Network Manager. [[0;32m OK [0m] Reached target Network. Starting Permit User Sessions... Starting DNS forwarder and DHCP server... Starting Network Name Resolution... [[0;32m OK [0m] Started Permit User Sessions. [[0;32m OK [0m] Started Getty on tty1. [[0;32m OK [0m] Started Serial Getty on ttyAMA0. [[0;32m OK [0m] Reached target Login Prompts. [[0;32m OK [0m] Started DNS forwarder and DHCP server. [[0;32m OK [0m] Started Network Name Resolution. [[0;32m OK [0m] Reached target Multi-User System. Starting Update UTMP about System Runlevel Changes... [ 120.963063] IPv6: ADDRCONF(NETDEV_UP): enp0s1: link is not ready [[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: 16330
definition: lava
duration: 126.01
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:50) root [ 140.479737] audit: type=1006 audit(1562404398.472:2): pid=2318 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:02:37) [common] case: auto-login-action
case_id: 16331
definition: lava
duration: 157.10
extra: ...
level: 2.2
namespace: common
result: pass
start: 2.3 expect-shell-connection (timeout 00:02:22) [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.97416925430298s timeout end: 2.3 expect-shell-connection (duration 00:00:00) [common] start: 2.4 export-device-env (timeout 00:02:22) [common] end: 2.4 export-device-env (duration 00:00:00) [common] end: 2 boot-image-retry (duration 00:02:38) [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-770 root@hikey:~# mkdir /lava-770 mkdir /lava-770 mount /dev/disk/by-uuid/6c14d9fc-b0bf-4972-8f61-2fc3a469a253 -t ext2 /lava-770 root@hikey:~# mount /dev/disk/by-uuid/6c14d9fc-b0bf-4972-8f61-2fc3a469a253 -t ext2 /lava-770 mount /dev/disk/by-uuid/6c14d9fc-b0bf-4972-8f61-2fc3a469a253 -t ex t2 /lava-770 ls -la /lava-770/bin/lava-test-runner root@hikey:~# ls -la /lava-770/bin/lava-test-runner ls -la /lava-770/bin/lava-test-runner -rwxr-xr-x 1 root root 1039 Jul 6 09:10 /lava-770/bin/lava-test-runner Using /lava-770 export SHELL=/bin/sh root@hikey:~# export SHELL=/bin/sh export SHELL=/bin/sh . /lava-770/environment root@hikey:~# . /lava-770/environment . /lava-770/environment /lava-770/bin/lava-test-runner /lava-770/0 root@hikey:~# /lava-770/bin/lava-test-runner /lava-770/0 Test shell timeout: 10s (minimum of the action and connection timeout) /lava-770/bin/lava-test-runner /lava-770/0 + export TESTRUN_ID=0_smoke-tests + TESTRUN_ID=0_smoke-tests + cd /lava-770/0/tests/0_smoke-tests ++ cat uuid + UUID=770_1.3.3.1 + set +x <LAVA_SIGNAL_STARTRUN 0_smoke-tests 770_1.3.3.1> Received signal: <STARTRUN> 0_smoke-tests 770_1.3.3.1 Starting test lava.0_smoke-tests (770_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-770/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: 16332
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: 16333
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-770/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: 16334
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:31 errors:0 dropped:0 overruns:0 frame:0 TX packets:60 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:1000 RX bytes:6872 (6.7 KiB) TX bytes:9379 (9.1 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:456 errors:0 dropped:0 overruns:0 frame:0 TX packets:456 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:1000 RX bytes:33056 (32.2 KiB) TX bytes:33056 (32.2 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: 16335
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-770/0/../bin/lava-test-case: line 27: lscpu: command not found <LAVA_SIGNAL_ENDTC linux-posix-lscpu> Received signal: <ENDTC> linux-posix-lscpu <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-posix-lscpu RESULT=fail> Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-lscpu RESULT=fail case: linux-posix-lscpu
case_id: 16336
definition: 0_smoke-tests
result: fail
+ lava-test-case linux-posix-lsb_release --shell lsb_release -a <LAVA_SIGNAL_STARTTC linux-posix-lsb_release> Received signal: <STARTTC> linux-posix-lsb_release /lava-770/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 Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-lsb_release RESULT=fail case: linux-posix-lsb_release
case_id: 16337
definition: 0_smoke-tests
result: fail
<LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-posix-lsb_release RESULT=fail> + set +x Received signal: <ENDRUN> 0_smoke-tests 770_1.3.3.1 Ending use of test pattern. Ending test lava.0_smoke-tests (770_1.3.3.1), duration 2.24 case: 0_smoke-tests
case_id: 16338
commit_id: 0f8952f18e992371f8686c59b36f44e9c6a94a1a
definition: lava
duration: 2.24
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: 770_1.3.3.1
<LAVA_SIGNAL_ENDRUN 0_smoke-tests 770_1.3.3.1> + export TESTRUN_ID=1_singlenode-basic + TESTRUN_ID=1_singlenode-basic + cd /lava-770/0/tests/1_singlenode-basic ++ cat uuid + UUID=770_1.3.3.5 + set +x <LAVA_SIGNAL_STARTRUN 1_singlenode-basic 770_1.3.3.5> Received signal: <STARTRUN> 1_singlenode-basic 770_1.3.3.5 Starting test lava.1_singlenode-basic (770_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:10852 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:* udp 0 0 0.0.0.0:45155 0.0.0.0:* udp6 0 0 :::5353 :::* udp6 0 0 :::5355 :::* udp6 0 0 :::33723 :::* udp6 0 0 :::60398 :::* udp6 0 0 fec0::dcad:beff:feef:53 :::* 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 2 [ ] DGRAM 11556 /run/user/0/systemd/notify unix 2 [ ACC ] STREAM LISTENING 11558 /run/user/0/systemd/private unix 2 [ ACC ] STREAM LISTENING 10281 /var/run/NetworkManager/private unix 2 [ ACC ] STREAM LISTENING 1873 /var/run/rpcbind.sock unix 2 [ ACC ] STREAM LISTENING 11362 /var/run/NetworkManager/private-dhcp unix 2 [ ACC ] STREAM LISTENING 2659 /var/run/avahi-daemon/socket unix 2 [ ACC ] STREAM LISTENING 2662 /var/run/dbus/system_bus_socket unix 3 [ ] DGRAM 478 /run/systemd/notify unix 2 [ ] DGRAM 480 /run/systemd/cgroups-agent unix 2 [ ACC ] STREAM LISTENING 482 /run/systemd/private unix 2 [ ACC ] SEQPACKET LISTENING 489 /run/udev/control unix 13 [ ] DGRAM 493 /run/systemd/journal/dev-log unix 2 [ ACC ] STREAM LISTENING 497 /run/systemd/journal/stdout unix 9 [ ] DGRAM 499 /run/systemd/journal/socket unix 2 [ ] DGRAM 504 /run/systemd/journal/syslog unix 3 [ ] STREAM CONNECTED 10283 unix 3 [ ] STREAM CONNECTED 2955 /var/run/dbus/system_bus_socket unix 3 [ ] DGRAM 697 unix 3 [ ] STREAM CONNECTED 2979 /var/run/dbus/system_bus_socket unix 3 [ ] STREAM CONNECTED 11466 unix 3 [ ] STREAM CONNECTED 10329 unix 3 [ ] STREAM CONNECTED 9643 /run/systemd/journal/stdout unix 3 [ ] STREAM CONNECTED 2803 unix 3 [ ] STREAM CONNECTED 10066 /run/systemd/journal/stdout unix 3 [ ] STREAM CONNECTED 9468 /run/systemd/journal/stdout unix 2 [ ] DGRAM 1024 unix 2 [ ] DGRAM 10266 unix 3 [ ] STREAM CONNECTED 10275 unix 2 [ ] DGRAM 11398 unix 3 [ ] STREAM CONNECTED 2957 /var/run/dbus/system_bus_socket unix 3 [ ] STREAM CONNECTED 11269 unix 2 [ ] DGRAM 10280 unix 3 [ ] STREAM CONNECTED 2859 unix 3 [ ] STREAM CONNECTED 10343 /var/run/dbus/system_bus_socket unix 3 [ ] DGRAM 695 unix 3 [ ] STREAM CONNECTED 10342 unix 3 [ ] STREAM CONNECTED 2954 unix 3 [ ] STREAM CONNECTED 1017 unix 3 [ ] STREAM CONNECTED 2919 unix 3 [ ] DGRAM 694 unix 3 [ ] STREAM CONNECTED 2786 /run/systemd/journal/stdout unix 2 [ ] DGRAM 689 unix 3 [ ] STREAM CONNECTED 9460 /run/systemd/journal/stdout unix 2 [ ] DGRAM 2950 unix 3 [ ] DGRAM 696 unix 3 [ ] STREAM CONNECTED 11273 /var/run/dbus/system_bus_socket unix 2 [ ] DGRAM 11472 unix 2 [ ] DGRAM 10356 unix 3 [ ] STREAM CONNECTED 896 unix 3 [ ] STREAM CONNECTED 2755 /run/systemd/journal/stdout unix 3 [ ] STREAM CONNECTED 2804 unix 3 [ ] STREAM CONNECTED 940 unix 2 [ ] DGRAM 10386 unix 2 [ ] DGRAM 11370 unix 3 [ ] STREAM CONNECTED 2768 /run/systemd/journal/stdout unix 3 [ ] STREAM CONNECTED 9415 unix 3 [ ] STREAM CONNECTED 1703 /run/systemd/journal/stdout unix 3 [ ] DGRAM 8476 unix 3 [ ] STREAM CONNECTED 2806 /var/run/dbus/system_bus_socket unix 3 [ ] STREAM CONNECTED 9407 unix 3 [ ] STREAM CONNECTED 9250 unix 3 [ ] STREAM CONNECTED 9515 unix 2 [ ] DGRAM 1498 unix 3 [ ] STREAM CONNECTED 10265 /run/systemd/journal/stdout unix 3 [ ] STREAM CONNECTED 9406 unix 3 [ ] STREAM CONNECTED 1496 unix 3 [ ] STREAM CONNECTED 625 /run/systemd/journal/stdout unix 2 [ ] DGRAM 10046 unix 2 [ ] DGRAM 8641 unix 3 [ ] STREAM CONNECTED 9402 unix 3 [ ] STREAM CONNECTED 8597 unix 2 [ ] DGRAM 10129 unix 3 [ ] STREAM CONNECTED 2780 /run/systemd/journal/stdout unix 3 [ ] STREAM CONNECTED 2747 /run/systemd/journal/stdout unix 2 [ ] DGRAM 9725 unix 2 [ ] DGRAM 9403 unix 2 [ ] DGRAM 9751 unix 2 [ ] DGRAM 8380 unix 3 [ ] STREAM CONNECTED 11321 /var/run/dbus/system_bus_socket unix 3 [ ] STREAM CONNECTED 1702 unix 3 [ ] STREAM CONNECTED 9733 unix 2 [ ] DGRAM 9259 unix 3 [ ] DGRAM 8477 unix 3 [ ] STREAM CONNECTED 2805 /var/run/dbus/system_bus_socket <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: 16339
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:31 errors:0 dropped:0 overruns:0 frame:0 TX packets:60 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:1000 RX bytes:6872 (6.7 KiB) TX bytes:9379 (9.1 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:456 errors:0 dropped:0 overruns:0 frame:0 TX packets:456 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:1000 RX bytes:33056 (32.2 KiB) TX bytes:33056 (32.2 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-linaro-ubuntu-ifconfig-dump> Received 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: 16340
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 <LAVA_SIGNAL_ENDTC linux-linaro-ubuntu-route-dump-a> Received signal: <ENDTC> linux-linaro-ubuntu-route-dump-a Received signal: <TESTCASE> TEST_CASE_ID=linux-linaro-ubuntu-route-dump-a RESULT=pass case: linux-linaro-ubuntu-route-dump-a
case_id: 16341
definition: 1_singlenode-basic
result: pass
<LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-linaro-ubuntu-route-dump-a RESULT=pass> + lava-test-case linux-linaro-ubuntu-route-ifconfig-up-lo --shell ifconfig lo up <LAVA_SIGNAL_STARTTC linux-linaro-ubuntu-route-ifconfig-up-lo> Received signal: <STARTTC> linux-linaro-ubuntu-route-ifconfig-up-lo <LAVA_SIGNAL_ENDTC linux-linaro-ubuntu-route-ifconfig-up-lo> Received 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: 16342
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 Received signal: <ENDTC> linux-linaro-ubuntu-route-dump-b <LAVA_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: 16343
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 (99.84.8.55): 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 <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=ping-test RESULT=fail> Received signal: <TESTCASE> TEST_CASE_ID=ping-test RESULT=fail case: ping-test
case_id: 16344
definition: 1_singlenode-basic
result: fail
+ set +x <LAVA_SIGNAL_ENDRUN 1_singlenode-basic 770_1.3.3.5> Received signal: <ENDRUN> 1_singlenode-basic 770_1.3.3.5 Ending use of test pattern. Ending test lava.1_singlenode-basic (770_1.3.3.5), duration 4.03 case: 1_singlenode-basic
case_id: 16345
commit_id: 0f8952f18e992371f8686c59b36f44e9c6a94a1a
definition: lava
duration: 4.03
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: 770_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:12) [common] end: 3 lava-test-retry (duration 00:00:12) [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-770/deployimages-01dv6eim/kernel Cleaning up download directory: /var/lib/lava/dispatcher/tmp/lavafed-770/deployimages-01dv6eim/rootfs Root tmp directory removed at /var/lib/lava/dispatcher/tmp/lavafed-770 Job finished correctly case: job
case_id: 16346
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 Metadata, 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.

features.3.type
device
slave.version
2019.06.0097.gd4707dda2
features.3.name
test.definition.inline
features.0.description
Deploy to tmpfs
features.0.action
deploy
features.0.type
device
job.url
https://federation.lavasoftware.org/lava/scheduler/job/{id}
features.2.description
Test definition from git
features.1.description
Boot qemu
job.name
qemu - hikey
features.2.type
device
features.1.name
boot.qemu
slave.arch
amd64
features.2.name
test.definition.git
features.0.name
deploy.tmpfs
device.name
staging-qemu01
features.3.action
test
features.1.type
device
features.1.action
boot
job.type
test
features.2.action
test
device.type
qemu
features.3.description
Inline test definition
lab.name
staging.validation.linaro.org
device type
hostname
timeouts
  • connections: {'minutes': 2}{'seconds': 30}{'seconds': 10}{'seconds': 30}{'seconds': 30}{'minutes': 10}{'seconds': 30} auto-login-actionu-boot-interruptlava-test-shellbootloader-interruptbootloader-commandsdd-imageuboot-retry
  • actions: {'minutes': 2}{'minutes': 3}{'seconds': 30}{'minutes': 3}{'seconds': 10}{'seconds': 45}{'minutes': 5}{'minutes': 2}{'minutes': 2}{'minutes': 10}{'minutes': 3}{'minutes': 3}{'minutes': 3}{'minutes': 20}{'minutes': 5}{'minutes': 3}{'seconds': 30}{'minutes': 10}{'minutes': 2} auto-login-actionlava-test-shellbootloader-interruptbootloader-commandspower-offumount-retryhttp-downloadapply-overlay-imageboot-qemu-imagedd-imagebootloader-actionuboot-retryuboot-actionflash-uboot-umsdownload-retrybootloader-retryu-boot-interruptnfs-deployboot-image-retry
device action methods
boot:
  • qemu-nfs:
    • append: {'console': 'ttyAMA0', 'nfsrootargs': 'nfsroot={NFS_SERVER_IP}:{NFSROOTFS},tcp,hard,intr', 'root': '/dev/nfs', 'ipargs': 'ip=dhcp'}
    • command: qemu-system-aarch64
    • boot_options: {'console': 'ttyAMA0 115200', 'mem': '1024M', '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']
  • qemu:
    • 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
    • boot_options: {'console': 'ttyAMA0 115200', 'mem': '1024M', 'root': '/dev/vda2 rw rootwait', 'ip': 'dhcp'}
deploy:
  • nfs:
  • image:
    • guest: {'driveid': 'lavatest', 'interface': 'virtio', 'size': 512}
target
...
timeouts
  • job: {'seconds': 900}

  1. os
    oe
    to
    tmpfs
    namespace
    common
    Timeout
    • 10 minutes
    deployment_data
    {'line_separator': '\n', 'lava_test_dir': '/lava-%s', 'lava_test_sh_cmd': '/bin/sh', 'distro': 'oe', 'TESTER_PS1': 'linaro-test [rc=$(echo \\$?)]# ', 'lava_test_shell_file': '~/.bashrc', 'TESTER_PS1_PATTERN': 'linaro-test \\[rc=(\\d+)\\]# ', 'lava_test_results_part_attr': 'root_part', 'TESTER_PS1_INCLUDES_RC': True, 'lava_test_results_dir': '/lava-%s', 'boot_cmds': 'boot_cmds_oe'}
    images
    {'rootfs': {'compression': 'gz', 'image_arg': '-drive format=raw,file={rootfs} -cpu cortex-a57 -machine type=virt -nographic -smp 4 -m 2048', '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', 'apply-overlay': True, 'sparse': False}, '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'}}

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

  1. stage
    0
    namespace
    common
    Definitions
    • repository http://git.linaro.org/lava-team/lava-functional-tests.git
    • from git
    • name smoke-tests
    • path lava-test-shell/smoke-tests-basic.yaml
    • repository http://git.linaro.org/lava-team/lava-functional-tests.git
    • from 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...