Help
Invalid job definition:

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

Description
[lavafed 2019.09.0089.gf1de999c2] qemu - hikey
Device-type
qemu
Submitter
Rémi Duraffort
Created
6 days, 15 hours ago
Priority
Medium
Visibility
Public
Required Tags
staging.validation.linaro.org
#1219
Complete
Device
staging-qemu01@staging.validation.linaro.org
Started
6 days, 15 hours ago
Duration
4 minutes
Results
  
lava-dispatcher, installed at version: 2019.09.0089.gf1de999c2+stretch start: 0 validate Start time: 2019-10-09 09:09:25.810261+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+deb9u8, host architecture: amd64 validate duration: 5.55 case: validate
case_id: 24605
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-1219/deployimages-f_vgu7ny/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 38.64s (8.91MB/s) end: 1.1.1 http-download (duration 00:00:39) [common] case: http-download
case_id: 24637
definition: lava
duration: 38.64
extra: ...
level: 1.1.1
namespace: common
result: pass
end: 1.1 download-retry (duration 00:00:39) [common] start: 1.2 download-retry (timeout 00:09:21) [common] start: 1.2.1 http-download (timeout 00:09:21) [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-1219/deployimages-f_vgu7ny/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 8.25s (2.31MB/s) end: 1.2.1 http-download (duration 00:00:08) [common] case: http-download
case_id: 24637
definition: lava
duration: 8.25
extra: ...
level: 1.2.1
namespace: common
result: pass
end: 1.2 download-retry (duration 00:00:08) [common] start: 1.3 lava-overlay (timeout 00:09:13) [common] [common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/lavafed-1219/lava-overlay-pqix_enz makedir: /var/lib/lava/dispatcher/tmp/lavafed-1219/lava-overlay-pqix_enz/lava-1219/bin makedir: /var/lib/lava/dispatcher/tmp/lavafed-1219/lava-overlay-pqix_enz/lava-1219/tests makedir: /var/lib/lava/dispatcher/tmp/lavafed-1219/lava-overlay-pqix_enz/lava-1219/results Creating /var/lib/lava/dispatcher/tmp/lavafed-1219/lava-overlay-pqix_enz/lava-1219/bin/lava-add-keys Creating /var/lib/lava/dispatcher/tmp/lavafed-1219/lava-overlay-pqix_enz/lava-1219/bin/lava-add-sources Creating /var/lib/lava/dispatcher/tmp/lavafed-1219/lava-overlay-pqix_enz/lava-1219/bin/lava-background-process-start Creating /var/lib/lava/dispatcher/tmp/lavafed-1219/lava-overlay-pqix_enz/lava-1219/bin/lava-background-process-stop Creating /var/lib/lava/dispatcher/tmp/lavafed-1219/lava-overlay-pqix_enz/lava-1219/bin/lava-common-functions Creating /var/lib/lava/dispatcher/tmp/lavafed-1219/lava-overlay-pqix_enz/lava-1219/bin/lava-echo-ipv4 Creating /var/lib/lava/dispatcher/tmp/lavafed-1219/lava-overlay-pqix_enz/lava-1219/bin/lava-install-packages Creating /var/lib/lava/dispatcher/tmp/lavafed-1219/lava-overlay-pqix_enz/lava-1219/bin/lava-installed-packages Creating /var/lib/lava/dispatcher/tmp/lavafed-1219/lava-overlay-pqix_enz/lava-1219/bin/lava-os-build Creating /var/lib/lava/dispatcher/tmp/lavafed-1219/lava-overlay-pqix_enz/lava-1219/bin/lava-probe-channel Creating /var/lib/lava/dispatcher/tmp/lavafed-1219/lava-overlay-pqix_enz/lava-1219/bin/lava-probe-ip Creating /var/lib/lava/dispatcher/tmp/lavafed-1219/lava-overlay-pqix_enz/lava-1219/bin/lava-target-ip Creating /var/lib/lava/dispatcher/tmp/lavafed-1219/lava-overlay-pqix_enz/lava-1219/bin/lava-target-mac Creating /var/lib/lava/dispatcher/tmp/lavafed-1219/lava-overlay-pqix_enz/lava-1219/bin/lava-target-storage Creating /var/lib/lava/dispatcher/tmp/lavafed-1219/lava-overlay-pqix_enz/lava-1219/bin/lava-test-case Creating /var/lib/lava/dispatcher/tmp/lavafed-1219/lava-overlay-pqix_enz/lava-1219/bin/lava-test-event Creating /var/lib/lava/dispatcher/tmp/lavafed-1219/lava-overlay-pqix_enz/lava-1219/bin/lava-test-feedback Creating /var/lib/lava/dispatcher/tmp/lavafed-1219/lava-overlay-pqix_enz/lava-1219/bin/lava-test-raise Creating /var/lib/lava/dispatcher/tmp/lavafed-1219/lava-overlay-pqix_enz/lava-1219/bin/lava-test-reference Creating /var/lib/lava/dispatcher/tmp/lavafed-1219/lava-overlay-pqix_enz/lava-1219/bin/lava-test-runner Creating /var/lib/lava/dispatcher/tmp/lavafed-1219/lava-overlay-pqix_enz/lava-1219/bin/lava-test-set Creating /var/lib/lava/dispatcher/tmp/lavafed-1219/lava-overlay-pqix_enz/lava-1219/bin/lava-test-shell Updating /var/lib/lava/dispatcher/tmp/lavafed-1219/lava-overlay-pqix_enz/lava-1219/bin/lava-install-packages (oe) Updating /var/lib/lava/dispatcher/tmp/lavafed-1219/lava-overlay-pqix_enz/lava-1219/bin/lava-installed-packages (oe) Creating /var/lib/lava/dispatcher/tmp/lavafed-1219/lava-overlay-pqix_enz/lava-1219/environment start: 1.3.1 lava-vland-overlay (timeout 00:09:13) [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:13) [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:13) [common] Loading test definitions start: 1.3.3.1 git-repo-action (timeout 00:09:13) [common] Using /lava-1219 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-1219/lava-overlay-pqix_enz/lava-1219/0/tests/0_smoke-tests --depth=1' Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/lavafed-1219/lava-overlay-pqix_enz/lava-1219/0/tests/0_smoke-tests/lava-test-shell/smoke-tests-basic.yaml uuid=1219_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: 24639
definition: lava
duration: 1.13
extra: ...
level: 1.3.3.1
namespace: common
result: pass
start: 1.3.3.2 test-overlay (timeout 00:09:12) [common] end: 1.3.3.2 test-overlay (duration 00:00:00) [common] case: test-overlay
case_id: 24640
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:12) [common] end: 1.3.3.3 test-install-overlay (duration 00:00:00) [common] case: test-install-overlay
case_id: 24641
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:09:12) [common] runner path: /var/lib/lava/dispatcher/tmp/lavafed-1219/lava-overlay-pqix_enz/lava-1219/0/tests/0_smoke-tests test_uuid 1219_1.3.3.1 end: 1.3.3.4 test-runscript-overlay (duration 00:00:00) [common] case: test-runscript-overlay
case_id: 24642
definition: lava
duration: 0.06
extra: ...
level: 1.3.3.4
namespace: common
result: pass
start: 1.3.3.5 git-repo-action (timeout 00:09:12) [common] Using /lava-1219 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-1219/lava-overlay-pqix_enz/lava-1219/0/tests/1_singlenode-basic --depth=1' Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/lavafed-1219/lava-overlay-pqix_enz/lava-1219/0/tests/1_singlenode-basic/lava-test-shell/single-node/singlenode01.yaml uuid=1219_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: 24639
definition: lava
duration: 1.14
extra: ...
level: 1.3.3.5
namespace: common
result: pass
start: 1.3.3.6 test-overlay (timeout 00:09:11) [common] end: 1.3.3.6 test-overlay (duration 00:00:00) [common] case: test-overlay
case_id: 24640
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:11) [common] end: 1.3.3.7 test-install-overlay (duration 00:00:00) [common] case: test-install-overlay
case_id: 24641
definition: lava
duration: 0.06
extra: ...
level: 1.3.3.7
namespace: common
result: pass
start: 1.3.3.8 test-runscript-overlay (timeout 00:09:11) [common] runner path: /var/lib/lava/dispatcher/tmp/lavafed-1219/lava-overlay-pqix_enz/lava-1219/0/tests/1_singlenode-basic test_uuid 1219_1.3.3.5 end: 1.3.3.8 test-runscript-overlay (duration 00:00:00) [common] case: test-runscript-overlay
case_id: 24642
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-1219/lava-overlay-pqix_enz/lava-1219/0 for stage 0 - 0_smoke-tests - 1_singlenode-basic end: 1.3.3 test-definition (duration 00:00:03) [common] start: 1.3.4 compress-overlay (timeout 00:09:11) [common] end: 1.3.4 compress-overlay (duration 00:00:00) [common] start: 1.3.5 persistent-nfs-overlay (timeout 00:09:10) [common] end: 1.3.5 persistent-nfs-overlay (duration 00:00:00) [common] end: 1.3 lava-overlay (duration 00:00:03) [common] start: 1.4 apply-overlay-guest (timeout 00:09:10) [common] Overlay: /var/lib/lava/dispatcher/tmp/lavafed-1219/compress-overlay-er3mdixb/overlay-1.3.4.tar.gz end: 1.4 apply-overlay-guest (duration 00:00:05) [common] case: apply-overlay-guest
case_id: 24648
definition: lava
duration: 5.21
extra: ...
level: 1.4
namespace: common
result: pass
start: 1.5 deploy-device-env (timeout 00:09:05) [common] end: 1.5 deploy-device-env (duration 00:00:00) [common] end: 1 deployimages (duration 00:00:55) [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-1219/deployimages-f_vgu7ny/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-1219/deployimages-f_vgu7ny/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-1219/apply-overlay-guest-4k47iyn1/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: 24649
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: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.000253] sched_clock: 56 bits at 62MHz, resolution 16ns, wraps every 4398046511096ns [ 0.015661] Console: colour dummy device 80x25 [ 0.016696] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar [ 0.016790] ... MAX_LOCKDEP_SUBCLASSES: 8 [ 0.016871] ... MAX_LOCK_DEPTH: 48 [ 0.016948] ... MAX_LOCKDEP_KEYS: 8191 [ 0.017026] ... CLASSHASH_SIZE: 4096 [ 0.017102] ... MAX_LOCKDEP_ENTRIES: 32768 [ 0.017179] ... MAX_LOCKDEP_CHAINS: 65536 [ 0.017255] ... CHAINHASH_SIZE: 32768 [ 0.017332] memory used by lock dependency info: 7391 kB [ 0.017409] per task-struct memory footprint: 1920 bytes [ 0.019306] Calibrating delay loop (skipped), value calculated using timer frequency.. 125.00 BogoMIPS (lpj=250000) [ 0.019647] pid_max: default: 32768 minimum: 301 [ 0.022629] Security Framework initialized [ 0.030119] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes) [ 0.033494] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes) [ 0.034216] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.034444] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.119703] ASID allocator initialised with 65536 entries [ 0.124041] Hierarchical SRCU implementation. [ 0.150471] EFI services will not be available. [ 0.162276] smp: Bringing up secondary CPUs ... [ 0.195884] Detected PIPT I-cache on CPU1 [ 0.197856] CPU1: Booted secondary processor [411fd070] [ 0.238569] Detected PIPT I-cache on CPU2 [ 0.238946] CPU2: Booted secondary processor [411fd070] [ 0.273313] Detected PIPT I-cache on CPU3 [ 0.274303] CPU3: Booted secondary processor [411fd070] [ 0.277741] smp: Brought up 1 node, 4 CPUs [ 0.278025] SMP: Total of 4 processors activated. [ 0.278654] CPU features: detected feature: 32-bit EL0 Support [ 0.305353] CPU: All CPU(s) started at EL1 [ 0.308553] alternatives: patching kernel code [ 0.363563] devtmpfs: initialized [ 0.437346] random: get_random_u32 called from bucket_table_alloc+0x11c/0x270 with crng_init=0 [ 0.535435] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns [ 0.536874] futex hash table entries: 1024 (order: 5, 131072 bytes) [ 0.553134] pinctrl core: initialized pinctrl subsystem [ 0.610044] random: fast init done [ 0.656843] DMI not present or invalid. [ 0.722170] NET: Registered protocol family 16 [ 0.871096] cpuidle: using governor menu [ 0.880670] vdso: 2 pages (1 code @ ffff000008b96000, 1 data @ ffff0000091c5000) [ 0.881121] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. [ 0.931156] DMA: preallocated 256 KiB pool for atomic allocations [ 0.972658] Serial: AMBA PL011 UART driver [ 1.368058] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 39, base_baud = 0) is a PL011 rev1 [ 1.433712] console [ttyAMA0] enabled [ 1.502489] irq: type mismatch, failed to map hwirq-27 for /intc! [ 2.935736] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 3.016313] ACPI: Interpreter disabled. [ 3.107453] vgaarb: loaded [ 3.126714] SCSI subsystem initialized [ 3.164032] usbcore: registered new interface driver usbfs [ 3.168245] usbcore: registered new interface driver hub [ 3.189249] usbcore: registered new device driver usb [ 3.266070] pps_core: LinuxPPS API ver. 1 registered [ 3.266658] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it> [ 3.272406] PTP clock support registered [ 3.289102] EDAC MC: Ver: 3.0.0 [ 3.315081] dmi: Firmware registration failed. [ 3.365962] Advanced Linux Sound Architecture Driver Initialized. [ 3.454544] clocksource: Switched to clocksource arch_sys_counter [ 5.021779] VFS: Disk quotas dquot_6.6.0 [ 5.024745] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 5.037441] pnp: PnP ACPI: disabled [ 5.930075] NET: Registered protocol family 2 [ 5.982028] TCP established hash table entries: 16384 (order: 5, 131072 bytes) [ 5.988513] TCP bind hash table entries: 16384 (order: 8, 1048576 bytes) [ 6.034833] TCP: Hash tables configured (established 16384 bind 16384) [ 6.040847] UDP hash table entries: 1024 (order: 5, 163840 bytes) [ 6.046478] UDP-Lite hash table entries: 1024 (order: 5, 163840 bytes) [ 6.058863] NET: Registered protocol family 1 [ 6.080086] RPC: Registered named UNIX socket transport module. [ 6.080731] RPC: Registered udp transport module. [ 6.081154] RPC: Registered tcp transport module. [ 6.081554] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 6.110157] kvm [1]: HYP mode not available [ 6.365239] audit: initializing netlink subsys (disabled) [ 6.387972] audit: type=2000 audit(5.568:1): state=initialized audit_enabled=0 res=1 [ 6.403793] workingset: timestamp_bits=44 max_order=19 bucket_order=0 [ 7.070646] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ 7.132719] NFS: Registering the id_resolver key type [ 7.136462] Key type id_resolver registered [ 7.136999] Key type id_legacy registered [ 7.137668] nfs4filelayout_init: NFSv4 File Layout Driver Registering... [ 7.150038] 9p: Installing v9fs 9p2000 file system support [ 7.301398] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 246) [ 7.302509] io scheduler noop registered [ 7.309833] io scheduler cfq registered (default) [ 7.310328] io scheduler mq-deadline registered [ 7.310751] io scheduler kyber registered [ 7.321102] test_firmware: interface ready [ 7.482002] pl061_gpio 9030000.pl061: PL061 GPIO chip @0x0000000009030000 registered [ 7.785851] OF: PCI: host bridge /pcie@10000000 ranges: [ 7.786976] OF: PCI: IO 0x3eff0000..0x3effffff -> 0x00000000 [ 7.794666] OF: PCI: MEM 0x10000000..0x3efeffff -> 0x10000000 [ 7.797081] OF: PCI: MEM 0x8000000000..0xffffffffff -> 0x8000000000 [ 7.813636] pci-host-generic 3f000000.pcie: ECAM at [mem 0x3f000000-0x3fffffff] for [bus 00-0f] [ 7.820318] pci-host-generic 3f000000.pcie: PCI host bridge to bus 0000:00 [ 7.824220] pci_bus 0000:00: root bus resource [bus 00-0f] [ 7.824839] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] [ 7.825356] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] [ 7.825898] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] [ 7.872604] pci 0000:00:01.0: BAR 6: assigned [mem 0x10000000-0x1003ffff pref] [ 7.877942] pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] [ 7.884838] pci 0000:00:02.0: BAR 4: assigned [mem 0x8000004000-0x8000007fff 64bit pref] [ 7.887791] pci 0000:00:03.0: BAR 4: assigned [mem 0x8000008000-0x800000bfff 64bit pref] [ 7.889758] pci 0000:00:01.0: BAR 1: assigned [mem 0x10040000-0x10040fff] [ 7.890928] pci 0000:00:02.0: BAR 1: assigned [mem 0x10041000-0x10041fff] [ 7.893734] pci 0000:00:03.0: BAR 1: assigned [mem 0x10042000-0x10042fff] [ 7.894365] pci 0000:00:02.0: BAR 0: assigned [io 0x1000-0x103f] [ 7.894915] pci 0000:00:03.0: BAR 0: assigned [io 0x1040-0x107f] [ 7.897670] pci 0000:00:01.0: BAR 0: assigned [io 0x1080-0x109f] [ 8.393065] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) [ 8.414957] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) [ 8.438503] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) [ 9.172726] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 9.272970] SuperH (H)SCI(F) driver initialized [ 9.330413] msm_serial: driver initialized [ 9.370416] cacheinfo: Unable to detect cache hierarchy for CPU 0 [ 9.704138] loop: module loaded [ 10.190545] libphy: Fixed MDIO Bus: probed [ 10.706338] e1000e: Intel(R) PRO/1000 Network Driver - 3.2.6-k [ 10.706874] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. [ 10.709549] igb: Intel(R) Gigabit Ethernet Network Driver - version 5.4.0-k [ 10.710129] igb: Copyright (c) 2007-2014 Intel Corporation. [ 10.712977] igbvf: Intel(R) Gigabit Virtual Function Network Driver - version 2.4.0-k [ 10.713588] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. [ 10.722915] sky2: driver version 1.30 [ 10.744704] VFIO - User Level meta-driver version: 0.3 [ 10.922495] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver [ 10.924294] ehci-pci: EHCI PCI platform driver [ 10.925840] ehci-platform: EHCI generic platform driver [ 10.929932] ehci-orion: EHCI orion driver [ 10.933255] ehci-exynos: EHCI EXYNOS driver [ 10.937067] ehci-msm: Qualcomm On-Chip EHCI Host Controller [ 10.940481] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver [ 10.941362] ohci-pci: OHCI PCI platform driver [ 10.942906] ohci-platform: OHCI generic platform driver [ 10.946921] ohci-exynos: OHCI EXYNOS driver [ 10.961734] usbcore: registered new interface driver usb-storage [ 11.166697] rtc-pl031 9010000.pl031: rtc core: registered pl031 as rtc0 [ 11.290267] i2c /dev entries driver [ 11.378436] sdhci: Secure Digital Host Controller Interface driver [ 11.378989] sdhci: Copyright(c) Pierre Ossman [ 11.385836] Synopsys Designware Multimedia Card Interface Driver [ 11.406315] sdhci-pltfm: SDHCI platform and OF driver helper [ 11.562859] ledtrig-cpu: registered to indicate activity on CPUs [ 11.842599] usbcore: registered new interface driver usbhid [ 11.845611] usbhid: USB HID core driver [ 12.034854] NET: Registered protocol family 10 [ 12.150279] Segment Routing with IPv6 [ 12.152935] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver [ 12.189175] NET: Registered protocol family 17 [ 12.197357] 9pnet: Installing 9P2000 support [ 12.205184] Key type dns_resolver registered [ 12.305573] registered taskstats version 1 [ 12.361431] input: gpio-keys as /devices/platform/gpio-keys/input/input0 [ 12.389771] rtc-pl031 9010000.pl031: setting system clock to 2019-10-09 09:10:40 UTC (1570612240) [ 12.406536] ALSA device list: [ 12.406929] No soundcards found. [ 12.433698] uart-pl011 9000000.pl011: no DMA platform data [ 12.470935] EXT4-fs (vda): couldn't mount as ext3 due to feature incompatibilities [ 12.627270] EXT4-fs (vda): mounted filesystem with ordered data mode. Opts: (null) [ 12.629684] VFS: Mounted root (ext4 filesystem) on device 254:0. [ 12.669945] devtmpfs: mounted [ 12.924042] Freeing unused kernel memory: 1280K [ 14.121400] 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) [ 14.135944] systemd[1]: Detected virtualization qemu. [ 14.137271] systemd[1]: Detected architecture arm64. [ 14.139111] systemd[1]: Running with unpopulated /etc. Welcome to [1mReference-Platform-Build-X11 2.2+linaro[0m! [ 14.161081] systemd[1]: Set hostname to <hikey>. [ 14.192967] systemd[1]: Initializing machine ID from random generator. [ 15.945427] systemd[1]: Failed to populate /etc with preset unit settings, ignoring: Too many levels of symbolic links [ 18.672577] systemd[1]: Reached target Swap. [[0;32m OK [0m] Reached target Swap. [ 18.689966] systemd[1]: Listening on Syslog Socket. [[0;32m OK [0m] Listening on Syslog Socket. [ 18.698112] systemd[1]: Listening on Journal Socket (/dev/log). [[0;32m OK [0m] Listening on Journal Socket (/dev/log). [[0;32m OK [0m] Listening on udev Kernel Socket. [[0;32m OK [0m] Created slice User and Session Slice. [[0;32m OK [0m] Listening on udev Control Socket. [[0;32m OK [0m] Started Dispatch Password Requests to Console Directory Watch. [[0;32m OK [0m] Listening on Journal Socket. [[0;32m OK [0m] Reached target Remote File Systems. [[0;32m OK [0m] Listening on Network Service Netlink Socket. [[0;32m OK [0m] Listening on /dev/initctl Compatibility Named Pipe. [[0;32m OK [0m] Listening on Journal Audit Socket. [[0;32m OK [0m] Started Forward Password Requests to Wall Directory Watch. [[0;32m OK [0m] Reached target Paths. [[0;32m OK [0m] Created slice System Slice. Mounting Huge Pages File System... Starting Journal Service... [ 19.297465] systemd[1]: Starting Load Kernel Modules... Starting Load Kernel Modules... [ 19.502696] systemd[1]: Mounting Temporary Directory... Mounting Temporary Directory... [ 19.721334] systemd[1]: Starting Setup Virtual Console... Starting Setup Virtual Console... [ 19.737426] systemd[1]: Reached target Slices. [[0;32m OK [0m] Reached target Slices. [ 20.239061] systemd[1]: Mounting Debug File System... Mounting Debug File System... [ 20.358539] systemd[1]: Created slice system-serial\x2dgetty.slice. [[0;32m OK [0m] Created slice system-serial\x2dgetty.slice. [ 20.810449] systemd[1]: Mounting POSIX Message Queue File System... Mounting POSIX Message Queue File System... [ 21.070379] systemd[1]: Created slice system-getty.slice. [[0;32m OK [0m] Created slice system-getty.slice. [ 21.709563] fuse init (API version 7.26) [ 23.148789] systemd[1]: Starting Remount Root and Kernel File Systems... Starting Remount Root and Kernel File Systems... [ 23.960789] systemd[1]: Starting Create list of required static device nodes for the current kernel... Starting Create list of required st... nodes for the current kernel... [ 25.048449] systemd[1]: Mounted Debug File System. [[0;32m OK [0m] Mounted Debug File System. [ 25.064889] systemd[1]: Mounted Huge Pages File System. [[0;32m OK [0m] Mounted Huge Pages File System. [ 25.080904] systemd[1]: Mounted POSIX Message Queue File System. [[0;32m OK [0m] Mounted POSIX Message Queue File System. [ 25.096834] systemd[1]: Mounted Temporary Directory. [[0;32m OK [0m] Mounted Temporary Directory. [ 25.290413] systemd[1]: Started Load Kernel Modules. [[0;32m OK [0m] Started Load Kernel Modules. [ 25.426947] systemd[1]: Started Setup Virtual Console. [[0;32m OK [0m] Started Setup Virtual Console. [ 25.824349] EXT4-fs (vda): re-mounted. Opts: (null) [ 25.973202] systemd[1]: Mounting Configuration File System... Mounting Configuration File System... [ 26.052662] systemd[1]: Mounting FUSE Control File System... Mounting FUSE Control File System... [ 26.229196] systemd[1]: Starting Apply Kernel Variables... Starting Apply Kernel Variables... [ 27.122376] systemd[1]: Started Apply Kernel Variables. [[0;32m OK [0m] Started Apply Kernel Variables. [[0;32m OK [0m] Started Remount Root and Kernel File Systems. [[0;32m OK [0m] Started Create list of required sta...ce nodes for the current kernel. [[0;32m OK [0m] Mounted Configuration File System. [[0;32m OK [0m] Mounted FUSE Control File System. [[0;32m OK [0m] Started Journal Service. Starting Flush Journal to Persistent Storage... Starting Create System Users... Starting Rebuild Hardware Database... [ 31.153249] systemd-journald[1315]: Received request to flush runtime journal from PID 1 [[0;32m OK [0m] Started Flush Journal to Persistent Storage. [[0;32m OK [0m] Started Create System Users. Starting Create Static Device Nodes in /dev... [[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... [[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 Create Volatile Files and Directories... Starting Rebuild Dynamic Linker Cache... [[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 Update UTMP about System Boot/Shutdown... Starting Network Time Synchronization... [[0;32m OK [0m] Started udev Kernel Device Manager. [[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. [[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. [[0m[0;31m* [0m] (1 of 4) A start job is running for...-ttyAMA3.device (46s / 1min 30s)[K[[0;1;31m*[0m[0;31m* [0m] (1 of 4) A start job is running for...-ttyAMA3.device (46s / 1min 30s)[K[[0;31m*[0;1;31m*[0m[0;31m* [0m] (1 of 4) A start job is running for...-ttyAMA3.device (47s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m[0;31m* [0m] (2 of 4) A start job is running for...-ttyAMA0.device (47s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m[0;31m* [0m] (2 of 4) A start job is running for...-ttyAMA0.device (48s / 1min 30s)[ 67.193929] hrtimer: interrupt took 10825392 ns [K[ [0;31m*[0;1;31m*[0m[0;31m*[0m] (2 of 4) A start job is running for...-ttyAMA0.device (48s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m] (3 of 4) A start job is running for...lug all Devices (49s / no limit)[K[ [0;31m*[0m] (3 of 4) A start job is running for...lug all Devices (49s / no limit)[K[ [0;31m*[0;1;31m*[0m] (3 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...-ttyAMA2.device (50s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m[0;31m* [0m] (4 of 4) A start job is running for...-ttyAMA2.device (51s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m[0;31m* [0m] (4 of 4) A start job is running for...-ttyAMA2.device (51s / 1min 30s)[K[[0;31m*[0;1;31m*[0m[0;31m* [0m] (1 of 4) A start job is running for...-ttyAMA3.device (52s / 1min 30s)[K[[0;1;31m*[0m[0;31m* [0m] (1 of 4) A start job is running for...-ttyAMA3.device (52s / 1min 30s)[K[[0m[0;31m* [0m] (1 of 4) A start job is running for...-ttyAMA3.device (53s / 1min 30s)[K[[0;1;31m*[0m[0;31m* [0m] (2 of 4) A start job is running for...-ttyAMA0.device (53s / 1min 30s)[K[[0;31m*[0;1;31m*[0m[0;31m* [0m] (2 of 4) A start job is running for...-ttyAMA0.device (54s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m[0;31m* [0m] (2 of 4) A start job is running for...-ttyAMA0.device (55s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m[0;31m* [0m] (3 of 4) A start job is running for...lug all Devices (55s / no limit)[K[ [0;31m*[0;1;31m*[0m[0;31m*[0m] (3 of 4) A start job is running for...lug all Devices (55s / no limit)[K[ [0;31m*[0;1;31m*[0m] (3 of 4) A start job is running for...lug all Devices (56s / no limit)[K[ [0;31m*[0m] (4 of 4) A start job is running for...-ttyAMA2.device (57s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m] (4 of 4) A start job is running for...-ttyAMA2.device (57s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m[0;31m*[0m] (4 of 4) A start job is running for...-ttyAMA2.device (58s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m[0;31m* [0m] (1 of 4) A start job is running for...-ttyAMA3.device (59s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m[0;31m* [0m] (1 of 4) A start job is running for...-ttyAMA3.device (59s / 1min 30s)[K[[0;31m*[0;1;31m*[0m[0;31m* [0m] (1 of 4) A start job is running for...ttyAMA3.device (1min / 1min 30s)[K[[0;1;31m*[0m[0;31m* [0m] (2 of 4) A start job is running for...ttyAMA0.device (1min / 1min 30s)[K[[0m[0;31m* [0m] (2 of 4) A start job is running for...AMA0.device (1min 1s / 1min 30s)[K[[0;1;31m*[0m[0;31m* [0m] (2 of 4) A start job is running for...AMA0.device (1min 1s / 1min 30s)[K[[0;31m*[0;1;31m*[0m[0;31m* [0m] (3 of 4) A start job is running for...all Devices (1min 2s / no limit)[K[ [0;31m*[0;1;31m*[0m[0;31m* [0m] (3 of 4) A start job is running for...all Devices (1min 2s / no limit)[K[ [0;31m*[0;1;31m*[0m[0;31m* [0m] (3 of 4) A start job is running for...all Devices (1min 3s / no limit)[K[ [0;31m*[0;1;31m*[0m[0;31m*[0m] (4 of 4) A start job is running for...AMA2.device (1min 3s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m] (4 of 4) A start job is running for...AMA2.device (1min 4s / 1min 30s)[K[ [0;31m*[0m] (4 of 4) A start job is running for...AMA2.device (1min 4s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m] (1 of 4) A start job is running for...AMA3.device (1min 5s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m[0;31m*[0m] (1 of 4) A start job is running for...AMA3.device (1min 5s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m[0;31m* [0m] (1 of 4) A start job is running for...AMA3.device (1min 6s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m[0;31m* [0m] (2 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...AMA0.device (1min 6s / 1min 30s)[K[[0;1;31m*[0m[0;31m* [0m] (2 of 4) A start job is running for...AMA0.device (1min 7s / 1min 30s)[K[[0m[0;31m* [0m] (3 of 4) A start job is running for...all Devices (1min 7s / no limit)[K[[0;1;31m*[0m[0;31m* [0m] (3 of 4) A start job is running for...all Devices (1min 8s / no limit)[K[[0;31m*[0;1;31m*[0m[0;31m* [0m] (3 of 4) A start job is running for...all Devices (1min 8s / no limit)[K[ [0;31m*[0;1;31m*[0m[0;31m* [0m] (4 of 4) A start job is running for...AMA2.device (1min 9s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m[0;31m* [0m] (4 of 4) A start job is running for...AMA2.device (1min 9s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m[0;31m*[0m] (4 of 4) A start job is running for...MA2.device (1min 10s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m] (1 of 4) A start job is running for...MA3.device (1min 10s / 1min 30s)[K[ [0;31m*[0m] (1 of 4) A start job is running for...MA3.device (1min 11s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m] (1 of 4) A start job is running for...MA3.device (1min 11s / 1min 30s)[K[ [0;31m*[0;1;31m*[0m[0;31m*[0m] (2 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...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...MA0.device (1min 13s / 1min 30s)[K[[0;31m*[0;1;31m*[0m[0;31m* [0m] (3 of 4) A start job is running for...ll Devices (1min 13s / no limit)[K[[0;1;31m*[0m[0;31m* [0m] (3 of 4) A start job is running for...ll Devices (1min 14s / no limit)[K[[0;32m OK [0m] Found device /dev/ttyAMA0. [ 94.635060] virtio_net virtio0 enp0s1: renamed from eth0 [[0m[0;31m* [0m] (1 of 3) A start job is running for...MA3.device (1min 20s / 1min 30s)[K[[0;1;31m*[0m[0;31m* [0m] (2 of 3) A start job is running for...ll Devices (1min 21s / no limit)[K[[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] Listening on RPCbind Server Activation Socket. [[0;32m OK [0m] Started Daily Cleanup of Temporary Directories. [[0;32m OK [0m] Reached target Timers. [[0;32m OK [0m] Listening on Avahi mDNS/DNS-SD Stack Activation Socket. [[0;32m OK [0m] Listening on sshd.socket. [[0;32m OK [0m] Reached target Sockets. [[0;32m OK [0m] Reached target Basic System. Starting Avahi mDNS/DNS-SD Stack... [[0;32m OK [0m] Started TEE Supplicant. [[0;32m OK [0m] Started System Logging Service. Starting Login Service... [[0;32m OK [0m] Started Kernel Logging Service. [[0;32m OK [0m] Started Periodic Command Scheduler. Starting Resize root filesystem to fit available disk space... [[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 Manager... Starting Network Service... [[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 DNS forwarder and DHCP server... Starting Network Name Resolution... Starting Permit User Sessions... [[0;32m OK [0m] Started Permit User Sessions. [[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] 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... [[0;32m OK [0m] Started Update UTMP about System Runlevel Changes. [ 135.805375] IPv6: ADDRCONF(NETDEV_UP): enp0s1: link is not ready Reference-Platform-Build-X11 2.2+linaro hikey ttyAMA0 Matched prompt #5: login: case: kernel-messages
case_id: 24682
definition: lava
duration: 138.59
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:38) root [ 160.128793] audit: type=1006 audit(1570612388.136:2): pid=2338 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:56) [common] case: auto-login-action
case_id: 24694
definition: lava
duration: 176.35
extra: ...
level: 2.2
namespace: common
result: pass
start: 2.3 expect-shell-connection (timeout 00:02:03) [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.97406649589539s timeout end: 2.3 expect-shell-connection (duration 00:00:00) [common] start: 2.4 export-device-env (timeout 00:02:03) [common] end: 2.4 export-device-env (duration 00:00:00) [common] end: 2 boot-image-retry (duration 00:02:57) [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-1219 root@hikey:~# mkdir /lava-1219 mkdir /lava-1219 mount /dev/disk/by-uuid/3ce3b044-618c-4bec-82fa-a609e756b626 -t ext2 /lava-1219 root@hikey:~# mount /dev/disk/by-uuid/3ce3b044-618c-4bec-82fa-a609e756b626 -t ext2 /lava-1219 mount /dev/disk/by-uuid/3ce3b044-618c-4bec-82fa-a609e756b626 -t ex t2 /lava-1219 ls -la /lava-1219/bin/lava-test-runner root@hikey:~# ls -la /lava-1219/bin/lava-test-runner ls -la /lava-1219/bin/lava-test-runner -rwxr-xr-x 1 root root 1039 Oct 9 09:10 /lava-1219/bin/lava-test-runner Using /lava-1219 export SHELL=/bin/sh root@hikey:~# export SHELL=/bin/sh export SHELL=/bin/sh . /lava-1219/environment root@hikey:~# . /lava-1219/environment . /lava-1219/environment /lava-1219/bin/lava-test-runner /lava-1219/0 root@hikey:~# /lava-1219/bin/lava-test-runner /lava-1219/0 Test shell timeout: 10s (minimum of the action and connection timeout) /lava-1219/bin/lava-test-runner /lava-1219/0 + export TESTRUN_ID=0_smoke-tests + TESTRUN_ID=0_smoke-tests + cd /lava-1219/0/tests/0_smoke-tests ++ cat uuid + UUID=1219_1.3.3.1 + set +x Received signal: <STARTRUN> 0_smoke-tests 1219_1.3.3.1 Starting test lava.0_smoke-tests (1219_1.3.3.1) Skipping test definition patterns. <LAVA_SIGNAL_STARTRUN 0_smoke-tests 1219_1.3.3.1> + lava-test-case linux-posix-pwd --shell pwd <LAVA_SIGNAL_STARTTC linux-posix-pwd> Received signal: <STARTTC> linux-posix-pwd /lava-1219/0/tests/0_smoke-tests <LAVA_SIGNAL_ENDTC linux-posix-pwd> Received signal: <ENDTC> linux-posix-pwd Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-pwd RESULT=pass case: linux-posix-pwd
case_id: 24695
definition: 0_smoke-tests
result: pass
<LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-posix-pwd 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 Received signal: <ENDTC> linux-posix-uname <LAVA_SIGNAL_ENDTC linux-posix-uname> <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-posix-uname RESULT=pass> Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-uname RESULT=pass case: linux-posix-uname
case_id: 24696
definition: 0_smoke-tests
result: pass
+ lava-test-case linux-posix-vmstat --shell vmstat Received signal: <STARTTC> linux-posix-vmstat <LAVA_SIGNAL_STARTTC linux-posix-vmstat> /lava-1219/0/../bin/lava-test-case: line 27: vmstat: command not found Received signal: <ENDTC> linux-posix-vmstat <LAVA_SIGNAL_ENDTC linux-posix-vmstat> Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-vmstat RESULT=fail case: linux-posix-vmstat
case_id: 24697
definition: 0_smoke-tests
result: fail
<LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-posix-vmstat 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:22 errors:0 dropped:0 overruns:0 frame:0 TX packets:52 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:1000 RX bytes:5793 (5.6 KiB) TX bytes:8906 (8.6 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:568 errors:0 dropped:0 overruns:0 frame:0 TX packets:568 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:1000 RX bytes:42016 (41.0 KiB) TX bytes:42016 (41.0 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: 24698
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-1219/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: 24699
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-1219/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: 24700
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 1219_1.3.3.1 Ending use of test pattern. Ending test lava.0_smoke-tests (1219_1.3.3.1), duration 2.58 case: 0_smoke-tests
case_id: 24701
commit_id: 0f8952f18e992371f8686c59b36f44e9c6a94a1a
definition: lava
duration: 2.58
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: 1219_1.3.3.1
<LAVA_SIGNAL_ENDRUN 0_smoke-tests 1219_1.3.3.1> + export TESTRUN_ID=1_singlenode-basic + TESTRUN_ID=1_singlenode-basic + cd /lava-1219/0/tests/1_singlenode-basic ++ cat uuid + UUID=1219_1.3.3.5 + set +x Received signal: <STARTRUN> 1_singlenode-basic 1219_1.3.3.5 Starting test lava.1_singlenode-basic (1219_1.3.3.5) Skipping test definition patterns. <LAVA_SIGNAL_STARTRUN 1_singlenode-basic 1219_1.3.3.5> + 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:43220 0.0.0.0:* 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:47737 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 :::57510 :::* udp6 0 0 :::5353 :::* udp6 0 0 :::5355 :::* udp6 0 0 :::46009 :::* 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 3 [ ] DGRAM 8470 /run/systemd/notify unix 2 [ ] DGRAM 8472 /run/systemd/cgroups-agent unix 2 [ ACC ] STREAM LISTENING 8474 /run/systemd/private unix 2 [ ACC ] STREAM LISTENING 2843 /var/run/avahi-daemon/socket unix 2 [ ACC ] STREAM LISTENING 10337 /var/run/NetworkManager/private unix 2 [ ] DGRAM 9844 /run/user/0/systemd/notify unix 2 [ ACC ] STREAM LISTENING 9846 /run/user/0/systemd/private unix 2 [ ] DGRAM 3214 /run/systemd/journal/syslog unix 13 [ ] DGRAM 3217 /run/systemd/journal/dev-log unix 2 [ ACC ] SEQPACKET LISTENING 3221 /run/udev/control unix 2 [ ACC ] STREAM LISTENING 3224 /run/systemd/journal/stdout unix 9 [ ] DGRAM 3226 /run/systemd/journal/socket unix 2 [ ACC ] STREAM LISTENING 1436 /var/run/rpcbind.sock unix 2 [ ACC ] STREAM LISTENING 9198 /var/run/dbus/system_bus_socket unix 2 [ ACC ] STREAM LISTENING 4092 /var/run/NetworkManager/private-dhcp unix 3 [ ] STREAM CONNECTED 9282 /run/systemd/journal/stdout unix 2 [ ] DGRAM 3885 unix 3 [ ] STREAM CONNECTED 3986 /var/run/dbus/system_bus_socket unix 3 [ ] DGRAM 9068 unix 3 [ ] DGRAM 9065 unix 3 [ ] STREAM CONNECTED 3985 unix 3 [ ] STREAM CONNECTED 3499 /run/systemd/journal/stdout unix 3 [ ] STREAM CONNECTED 9038 unix 2 [ ] DGRAM 9059 unix 3 [ ] DGRAM 9066 unix 3 [ ] DGRAM 9067 unix 3 [ ] DGRAM 8984 unix 2 [ ] DGRAM 11301 unix 3 [ ] STREAM CONNECTED 9281 unix 3 [ ] DGRAM 8983 unix 3 [ ] STREAM CONNECTED 10298 /var/run/dbus/system_bus_socket unix 2 [ ] DGRAM 9412 unix 2 [ ] DGRAM 10336 unix 3 [ ] STREAM CONNECTED 9614 /run/systemd/journal/stdout unix 3 [ ] STREAM CONNECTED 3799 unix 3 [ ] STREAM CONNECTED 2952 /run/systemd/journal/stdout unix 3 [ ] STREAM CONNECTED 2941 /run/systemd/journal/stdout unix 3 [ ] STREAM CONNECTED 9827 unix 3 [ ] STREAM CONNECTED 10339 unix 3 [ ] STREAM CONNECTED 2940 unix 3 [ ] STREAM CONNECTED 9414 /run/systemd/journal/stdout unix 3 [ ] STREAM CONNECTED 10300 /var/run/dbus/system_bus_socket unix 2 [ ] DGRAM 3277 unix 3 [ ] STREAM CONNECTED 9828 /run/systemd/journal/stdout unix 3 [ ] STREAM CONNECTED 9424 unix 3 [ ] STREAM CONNECTED 2544 unix 3 [ ] STREAM CONNECTED 10834 /var/run/dbus/system_bus_socket unix 3 [ ] STREAM CONNECTED 9610 unix 3 [ ] STREAM CONNECTED 10299 /var/run/dbus/system_bus_socket unix 3 [ ] STREAM CONNECTED 9377 unix 3 [ ] STREAM CONNECTED 10340 /var/run/dbus/system_bus_socket unix 3 [ ] STREAM CONNECTED 10254 unix 3 [ ] STREAM CONNECTED 10464 /run/systemd/journal/stdout unix 3 [ ] STREAM CONNECTED 2879 /run/systemd/journal/stdout unix 2 [ ] DGRAM 10912 unix 2 [ ] DGRAM 10571 unix 3 [ ] STREAM CONNECTED 10411 unix 3 [ ] STREAM CONNECTED 10277 unix 2 [ ] DGRAM 9382 unix 2 [ ] DGRAM 9733 unix 3 [ ] STREAM CONNECTED 3754 unix 3 [ ] STREAM CONNECTED 10475 /var/run/dbus/system_bus_socket unix 3 [ ] STREAM CONNECTED 9622 unix 2 [ ] DGRAM 9715 unix 3 [ ] STREAM CONNECTED 3742 unix 3 [ ] STREAM CONNECTED 10313 unix 2 [ ] DGRAM 10906 unix 3 [ ] STREAM CONNECTED 10474 unix 2 [ ] DGRAM 9616 unix 2 [ ] DGRAM 3413 unix 3 [ ] STREAM CONNECTED 9611 /run/systemd/journal/stdout unix 2 [ ] DGRAM 10832 unix 3 [ ] STREAM CONNECTED 10297 unix 2 [ ] DGRAM 9439 unix 3 [ ] STREAM CONNECTED 10296 unix 2 [ ] DGRAM 3807 unix 3 [ ] STREAM CONNECTED 8884 /run/systemd/journal/stdout unix 3 [ ] STREAM CONNECTED 3851 unix 3 [ ] STREAM CONNECTED 3743 unix 2 [ ] DGRAM 9861 unix 2 [ ] DGRAM 10564 unix 3 [ ] STREAM CONNECTED 3860 /run/systemd/journal/stdout unix 3 [ ] STREAM CONNECTED 9623 /var/run/dbus/system_bus_socket unix 3 [ ] STREAM CONNECTED 10833 <LAVA_SIGNAL_ENDTC linux-linaro-ubuntu-netstat> Received signal: <ENDTC> linux-linaro-ubuntu-netstat <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-linaro-ubuntu-netstat RESULT=pass> Received signal: <TESTCASE> TEST_CASE_ID=linux-linaro-ubuntu-netstat RESULT=pass case: linux-linaro-ubuntu-netstat
case_id: 24702
definition: 1_singlenode-basic
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:27 errors:0 dropped:0 overruns:0 frame:0 TX packets:57 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:1000 RX bytes:6207 (6.0 KiB) TX bytes:9292 (9.0 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:568 errors:0 dropped:0 overruns:0 frame:0 TX packets:568 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:1000 RX bytes:42016 (41.0 KiB) TX bytes:42016 (41.0 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: 24703
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: 24704
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: 24705
definition: 1_singlenode-basic
result: pass
+ lava-test-case linux-linaro-ubuntu-route-dump-b --shell route Received signal: <STARTTC> linux-linaro-ubuntu-route-dump-b <LAVA_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: 24706
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 Received signal: <STARTTC> ping-test <LAVA_SIGNAL_STARTTC ping-test> PING www.linaro.org (13.224.227.53): 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: 24707
definition: 1_singlenode-basic
result: fail
+ set +x Received signal: <ENDRUN> 1_singlenode-basic 1219_1.3.3.5 Ending use of test pattern. Ending test lava.1_singlenode-basic (1219_1.3.3.5), duration 4.33 case: 1_singlenode-basic
case_id: 24708
commit_id: 0f8952f18e992371f8686c59b36f44e9c6a94a1a
definition: lava
duration: 4.33
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: 1219_1.3.3.5
<LAVA_SIGNAL_ENDRUN 1_singlenode-basic 1219_1.3.3.5> 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:11) [common] end: 3 lava-test-retry (duration 00:00:11) [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-1219/deployimages-f_vgu7ny/rootfs Cleaning up download directory: /var/lib/lava/dispatcher/tmp/lavafed-1219/deployimages-f_vgu7ny/kernel Root tmp directory removed at /var/lib/lava/dispatcher/tmp/lavafed-1219 Job finished correctly case: job
case_id: 24709
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.09.0089.gf1de999c2
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...