Project

General

Profile

os-autoinst_job1.txt

Logs from one of first fails, on Tumbleweed - syrianidou_sofia, 2020-01-17 13:15

 
1
[2020-01-09T10:27:05.603 CET] [debug] <<< testapi::assert_screen(mustmatch="inst-bootmenu", timeout=30)
2
[2020-01-09T10:27:06.746 CET] [debug] no match: 29.0s, best candidate: bootloader_uefi-poo58244-20191016 (0.00)
3
[2020-01-09T10:27:07.677 CET] [debug] no match: 28.0s, best candidate: bootloader_uefi-poo58244-20191016 (0.00)
4
[2020-01-09T10:27:08.679 CET] [debug] no match: 27.0s, best candidate: bootloader_uefi-poo58244-20191016 (0.00)
5
[2020-01-09T10:27:09.588 CET] [debug] no change: 26.0s
6
[2020-01-09T10:27:11.125 CET] [debug] >>> testapi::_handle_found_needle: found bootmenu-sles15-20191205, similarity 1.00 @ 0/0
7
[2020-01-09T10:27:11.126 CET] [debug] /var/lib/openqa/share/tests/sle/tests/installation/bootloader_start.pm:69 called bootloader::run
8
[2020-01-09T10:27:11.126 CET] [debug] <<< testapi::check_screen(mustmatch="inst-oninstallation", timeout=5)
9
[2020-01-09T10:27:11.619 CET] [debug] no match: 4.5s, best candidate: inst-oninstallation-20191205 (0.66)
10
[2020-01-09T10:27:12.591 CET] [debug] no change: 3.5s
11
[2020-01-09T10:27:13.592 CET] [debug] no change: 2.5s
12
[2020-01-09T10:27:14.593 CET] [debug] no change: 1.5s
13
[2020-01-09T10:27:15.790 CET] [debug] no match: 0.5s, best candidate: inst-oninstallation-20191205 (0.66)
14
[2020-01-09T10:27:16.789 CET] [debug] no match: -0.5s, best candidate: inst-oninstallation-20191205 (0.66)
15
[2020-01-09T10:27:16.921 CET] [debug] >>> testapi::_check_backend_response: match=inst-oninstallation timed out after 5 (check_screen)
16
[2020-01-09T10:27:16.936 CET] [debug] /var/lib/openqa/share/tests/sle/tests/installation/bootloader_start.pm:69 called bootloader::run
17
[2020-01-09T10:27:16.936 CET] [debug] <<< testapi::wait_screen_change(timeout=10, similarity_level=50)
18
[2020-01-09T10:27:16.937 CET] [debug] /var/lib/openqa/share/tests/sle/tests/installation/bootloader_start.pm:69 called bootloader::run
19
[2020-01-09T10:27:16.937 CET] [debug] <<< testapi::send_key(key="down", do_wait=0, wait_screen_change=0)
20
[2020-01-09T10:27:17.210 CET] [debug] waiting for screen change: 0 25.3560340852889
21
[2020-01-09T10:27:17.210 CET] [debug] >>> testapi::wait_screen_change: screen change seen at 0
22
[2020-01-09T10:27:17.210 CET] [debug] /var/lib/openqa/share/tests/sle/tests/installation/bootloader_start.pm:69 called bootloader::run
23
[2020-01-09T10:27:17.210 CET] [debug] <<< testapi::check_screen(mustmatch="inst-oninstallation", timeout=5)
24
[2020-01-09T10:27:17.668 CET] [debug] >>> testapi::_handle_found_needle: found inst-oninstallation-20191205, similarity 1.00 @ 244/190
25
Use of uninitialized value in split at /var/lib/openqa/share/tests/sle/lib/bootloader_setup.pm line 293.
26
[2020-01-09T10:27:17.669 CET] [debug] /var/lib/openqa/share/tests/sle/tests/installation/bootloader_start.pm:69 called bootloader::run
27
[2020-01-09T10:27:17.669 CET] [debug] <<< testapi::type_string(string=" Y2DEBUG=1 vga=791 video=1024x768 plymouth.ignore-serial-consoles console=ttyS0 console=tty linuxrc.log=/dev/ttyS0 linuxrc.core=/dev/ttyS0 linuxrc.debug=4,trace reboot_timeout=0 ", max_interval=4, wait_screen_changes=0, wait_still_screen=0, timeout=30, similarity_level=47)
28
[2020-01-09T10:27:22.458 CET] [debug] signalhandler got TERM
29
[2020-01-09T10:27:22.458 CET] [debug] autotest received signal TERM, saving results of current test before exiting
30
[2020-01-09T10:27:22.459 CET] [debug] terminating command server 2352 because test execution ended
31
[2020-01-09T10:27:22.459 CET] [debug] isotovideo: informing websocket clients before stopping command server: http://127.0.0.1:20043/RlJeOlgNiPjzpfBK/broadcast
32
[2020-01-09T10:27:22.459 CET] [debug] backend got TERM
33
[2020-01-09T10:27:22.460 CET] [info] ::: OpenQA::Qemu::Proc::save_state: Saving QEMU state to qemu_state.json
34
[2020-01-09T10:27:22.462 CET] [debug] isotovideo: unable to inform websocket clients about stopping command server: Connection refused at /usr/bin/isotovideo line 175.
35

    
36
[2020-01-09T10:27:22.463 CET] [debug] commands process exited: 0
37
[2020-01-09T10:27:22.463 CET] [debug] done with command server
38
[2020-01-09T10:27:22.463 CET] [debug] stopping autotest process 2355
39
[2020-01-09T10:27:22.469 CET] [debug] [autotest] process exited: 1
40
[2020-01-09T10:27:22.469 CET] [debug] done with autotest process
41
[2020-01-09T10:27:22.469 CET] [debug] isotovideo failed
42
[2020-01-09T10:27:22.470 CET] [debug] stopping backend process 2363
43
[2020-01-09T10:27:23.0466 CET] [info] [pid:1567] Isotovideo exit status: 0
44
[2020-01-09T10:27:23.0475 CET] [info] [pid:1567] +++ worker notes +++
45
[2020-01-09T10:27:23.0475 CET] [info] [pid:1567] End time: 2020-01-09 09:27:23
46
[2020-01-09T10:27:23.0475 CET] [info] [pid:1567] Result: api-failure