Attachment 752177 Details for Bug 1072008

Run with unit test: prove --verbose t/99-full-stack.t of os-autoinst

os-autoinst.qemu-bug.txt (text/plain), 7.30 KB, created by Santiago Zarate on 2017-12-08 19:44:20 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Santiago Zarate

Created: 2017-12-08 19:44:20 UTC

Size: 7.30 KB

patch

obsolete

>[Fri Dec  8 20:20:03 2017] [10515:debug] starting: /usr/bin/qemu-system-i386 -serial file:serial0 -soundhw ac97 -vga cirrus -m 1024 -netdev user,id=qanet0 -device virtio-net,netdev=qanet0,mac=52:54:00:12:34:56 -device ide-drive,drive=hd1,serial=1 -drive file=raid/l1,cach
>e=unsafe,if=none,id=hd1,format=qcow2 -drive media=cdrom,if=none,id=cd0,format=raw,file=/home/foursixnine/Projects/suse.com/github.com/os-autoinst/os-autoinst/t/data//Core-7.2.iso -device ide-cd,drive=cd0 -boot once=d,menu=on,splash-time=5000 -smp 1 -no-shutdown -vnc :90,
>share=force-shared -qmp unix:qmp_socket,server,nowait -monitor unix:hmp_socket,server,nowait -S -monitor telnet:127.0.0.1:15222,server,nowait
>
>...
>...
>
>[Fri Dec  8 20:20:55 2017] [10501:debug] If save_memory_dump is called multiple times with the same '$filename', it will be rewritten.
>[Fri Dec  8 20:20:55 2017] [10501:debug] Trying to save machine state
>[Fri Dec  8 20:20:55 2017] [10507:debug] Migrating the machine.       
>[Fri Dec  8 20:20:55 2017] [10507:debug] COMMAND {"arguments":{"value":120},"execute":"migrate_set_downtime"}
>[Fri Dec  8 20:20:55 2017] [10507:debug] EVENT {"data":{"path":"/machine/peripheral-anon/device[0]/virtio-backend"},"timestamp":{"microseconds":398547,"seconds":1512760821},"event":"NIC_RX_FILTER_CHANGED"}
>[Fri Dec  8 20:20:55 2017] [10507:debug] COMMAND {"execute":"migrate","arguments":{"uri":"exec:gzip -c > ulogs/mysnapshot-vm-memory-dump.gz"}}         
>[Fri Dec  8 20:20:56 2017] [10507:debug] Querying Migration:  
>[Fri Dec  8 20:20:56 2017] [10507:debug] COMMAND {"execute":"query-migrate"}                                                                                                               
>[Fri Dec  8 20:20:56 2017] [10507:debug] EVENT {"event":"STOP","timestamp":{"microseconds":740910,"seconds":1512760855}}
>[Fri Dec  8 20:20:58 2017] [10507:debug] Migrating total bytes:         1078796288
>[Fri Dec  8 20:20:58 2017] [10507:debug] Migrating remaining bytes:     0
>[Fri Dec  8 20:20:58 2017] [10507:debug] $VAR1 = {       
>          'return' => {                                                                      
>                        'total-time' => 3354,
>                        'downtime' => 3220,
>                        'status' => 'completed',
>                        'ram' => {
>                                   'remaining' => 0,
>                                   'dirty-sync-count' => 3,
>                                   'page-size' => 4096,
>                                   'mbps' => '262.8361',
>                                   'normal-bytes' => 107675648,
>                                   'dirty-pages-rate' => 0,
>                                   'skipped' => 0,
>                                   'transferred' => 110019931,
>                                   'postcopy-requests' => 0,
>                                   'total' => 1078796288,
>                                   'normal' => 26288,
>                                   'duplicate' => 237090
>                                 },
>                        'setup-time' => 5
>                      }
>        };
>[Fri Dec  8 20:20:58 2017] [10507:debug] $VAR1 = {
>          'total-time' => 3354,
>          'downtime' => 3220,
>          'status' => 'completed',
>          'ram' => {
>                     'remaining' => 0,
>                     'dirty-sync-count' => 3,
>                     'page-size' => 4096,
>                     'mbps' => '262.8361',
>                     'normal-bytes' => 107675648,
>                     'dirty-pages-rate' => 0,
>                     'skipped' => 0,
>                     'transferred' => 110019931,
>                     'postcopy-requests' => 0,
>                     'total' => 1078796288,
>                     'normal' => 26288,
>                     'duplicate' => 237090
>                   },
>          'setup-time' => 5
>        };
>
>[Fri Dec  8 20:20:58 2017] [10507:debug] Migration completed.
>DIE Can't call method "handle_qmp_command" on unblessed reference at /home/foursixnine/Projects/suse.com/github.com/os-autoinst/os-autoinst/backend/qemu.pm line 960.
>
> at /home/foursixnine/Projects/suse.com/github.com/os-autoinst/os-autoinst/backend/baseclass.pm line 80.
>        backend::baseclass::die_handler("Can't call method \"handle_qmp_command\" on unblessed reference"...) called at /home/foursixnine/Projects/suse.com/github.com/os-autoinst/os-autoinst/backend/qemu.pm line 960
>        backend::qemu::cont_vm() called at /home/foursixnine/Projects/suse.com/github.com/os-autoinst/os-autoinst/backend/qemu.pm line 254
>        backend::qemu::save_memory_dump(backend::qemu=HASH(0x55f57b77d600), HASH(0x55f57ca93090)) called at /home/foursixnine/Projects/suse.com/github.com/os-autoinst/os-autoinst/backend/baseclass.pm line 75
>        backend::baseclass::handle_command(backend::qemu=HASH(0x55f57b77d600), HASH(0x55f57b98ac40)) called at /home/foursixnine/Projects/suse.com/github.com/os-autoinst/os-autoinst/backend/baseclass.pm line 436
>        backend::baseclass::check_socket(backend::qemu=HASH(0x55f57b77d600), IO::Handle=GLOB(0x55f57c792ce8)) called at /home/foursixnine/Projects/suse.com/github.com/os-autoinst/os-autoinst/backend/qemu.pm line 942
>        backend::qemu::check_socket(backend::qemu=HASH(0x55f57b77d600), IO::Handle=GLOB(0x55f57c792ce8), 0) called at /home/foursixnine/Projects/suse.com/github.com/os-autoinst/os-autoinst/backend/baseclass.pm line 208
>        eval {...} called at /home/foursixnine/Projects/suse.com/github.com/os-autoinst/os-autoinst/backend/baseclass.pm line 156
>        backend::baseclass::run_capture_loop(backend::qemu=HASH(0x55f57b77d600)) called at /home/foursixnine/Projects/suse.com/github.com/os-autoinst/os-autoinst/backend/baseclass.pm line 129
>        backend::baseclass::run(backend::qemu=HASH(0x55f57b77d600), 9, 12) called at /home/foursixnine/Projects/suse.com/github.com/os-autoinst/os-autoinst/backend/driver.pm line 85
>        backend::driver::start(backend::driver=HASH(0x55f57a560160)) called at /home/foursixnine/Projects/suse.com/github.com/os-autoinst/os-autoinst/backend/driver.pm line 48
>        backend::driver::new("backend::driver", "qemu") called at /home/foursixnine/Projects/suse.com/github.com/os-autoinst/os-autoinst/isotovideo line 211
>        main::init_backend() called at /home/foursixnine/Projects/suse.com/github.com/os-autoinst/os-autoinst/isotovideo line 280
>[Fri Dec  8 20:20:58 2017] [10507:debug] waitpid for 10515 returned 0
>[Fri Dec  8 20:20:58 2017] [10507:debug] sending TERM to qemu pid: 10515
>[Fri Dec  8 20:20:59 2017] [10507:debug] waitpid for 10515 returned 10515
>last frame
>[Fri Dec  8 20:20:59 2017] [10507:debug] QEMU: qemu-system-i386: terminating on signal 15 from pid 10507 (/home/foursixnine/Projects/suse.com/github.com/os-autoinst/os-autoinst/isotovideo: backen)
>[Fri Dec  8 20:20:59 2017] [10507:debug] sending magic and exit
>[Fri Dec  8 20:20:59 2017] [10495:debug] received magic close
>[Fri Dec  8 20:20:59 2017] [10495:debug] THERE IS NOTHING TO READ 11 8 7
>[Fri Dec  8 20:20:59 2017] [10495:debug] awaiting death of commands process
>[Fri Dec  8 20:20:59 2017] [10495:debug] commands process exited: 10500
>[Fri Dec  8 20:20:59 2017] [10495:debug] awaiting death of testpid 10501
>[Fri Dec  8 20:20:59 2017] [10495:debug] test process exited: 10501
>[Fri Dec  8 20:20:59 2017] [10495:debug] isotovideo failed
>[Fri Dec  8 20:20:59 2017] [10495:debug] killing backend process 10507
>[Fri Dec  8 20:20:59 2017] [10495:debug] done with backend process

Actions: View

Attachments on bug 1072008: 752177