See https://ci.suse.de/job/yast-aytests-run/GROUP=1,label=autoyast/340/display/r...
------------------------------------------
[...truncated 242.44 KB...]
3145728K ........ ........ ........ ........ 96% 3.37M 31s
3178496K ........ ........ ........ ........ 97% 3.17M 23s
3211264K ........ ........ ........ ........ 98% 3.55M 15s
3244032K ........ ........ ........ ........ 99% 3.61M 7s
3276800K ........ ........ ........ .... 100% 3.78M=13m39s
2017-02-09 14:28:54 (3.94 MB/s) - ‘dist.suse.de/install/SLES-11-SP4-GM/SLES-11-SP4-DVD-x86_64-GM-DVD1.iso’ saved [3384803328/3384803328]
--2017-02-09 14:28:54-- http://dist.suse.de/install/SLES-11-SP4-GM/drpmsync/
Reusing existing connection to dist.suse.de:80.
HTTP request sent, awaiting response... 200 OK
Length: 717 [text/html]
Saving to: ‘dist.suse.de/install/SLES-11-SP4-GM/drpmsync/index.html.tmp’
0K 100% 167M=0s
2017-02-09 14:28:54 (167 MB/s) - ‘dist.suse.de/install/SLES-11-SP4-GM/drpmsync/index.html.tmp’ saved [717/717]
Removing dist.suse.de/install/SLES-11-SP4-GM/drpmsync/index.html.tmp since it should be rejected.
FINISHED --2017-02-09 14:28:54--
Total wall clock time: 13m 39s
Downloaded: 7 files, 3.2G in 13m 39s (3.94 MB/s)
I, [2017-02-09T14:28:55.100656 #1273] INFO -- : Using definition /usr/lib64/ruby/gems/2.1.0/gems/aytests-1.0.32/share/veewee/install_definition.rb
I, [2017-02-09T14:28:55.102003 #1273] INFO -- : Creating kvm image
Building Box autoyast with Definition autoyast:
- debug : false
- cwd : https://ci.suse.de/job/yast-aytests-run/GROUP=1,label=autoyast/ws/aytests-wo...
- force : true
- nogui : false
- auto : true
- checksum : false
- postinstall_include : []
- postinstall_exclude : []
- skip_to_postinstall : false
- use_emulation : false
- network_name : default
The isofile testing.iso already exists.
Running #before_create hook
[2017-02-09 14:28:56] INFO WEBrick 1.3.1
[2017-02-09 14:28:56] INFO ruby 2.1.2 (2014-05-08) [x86_64-linux-gnu]
[2017-02-09 14:28:56] INFO WEBrick::HTTPServer#start: pid=27603 port=8888
Running #after_create hook
[2017-02-09 14:28:56] INFO WEBrick 1.3.1
[2017-02-09 14:28:56] INFO ruby 2.1.2 (2014-05-08) [x86_64-linux-gnu]
[2017-02-09 14:28:56] INFO
Certificate:
Data:
Version: 3 (0x2)
Serial Number: 9206 (0x23f6)
Signature Algorithm: sha1WithRSAEncryption
Issuer: C=DE, ST=Bavaria, L=Nuremberg, O=YaST testing, CN=aytests
Validity
Not Before: Feb 9 13:28:56 2017 GMT
Not After : Feb 9 14:28:56 2017 GMT
Subject: CN=10.100.24.103
Subject Public Key Info:
Public Key Algorithm: rsaEncryption
Public-Key: (2048 bit)
Modulus:
00:d9:51:13:55:97:45:52:6a:92:01:d4:75:56:a8:
f9:96:da:18:cc:78:05:41:09:df:98:42:ba:b6:e8:
56:1d:2e:14:10:4d:16:31:59:c6:01:3c:9f:51:60:
2c:0b:84:54:3d:9f:e3:52:12:5e:d7:cb:b6:cb:cb:
56:d1:6f:a5:b5:7d:86:1f:a3:5b:78:6d:3c:26:db:
d8:19:98:92:f3:80:54:08:a7:b9:67:08:11:b3:81:
64:6e:44:5e:85:2c:82:d6:c9:3c:9b:ee:3f:94:68:
07:0f:50:f8:14:c7:f2:a0:53:09:33:81:e6:2c:60:
83:1a:ab:19:36:9d:2b:7f:50:d0:c5:e2:59:3c:48:
18:1e:c1:d6:97:e8:be:a0:b3:83:ce:f5:6c:aa:b9:
8a:82:16:7a:83:8a:b3:51:46:2f:f5:df:a9:74:e8:
ba:30:6b:70:24:55:26:72:63:56:99:e7:a0:7a:7a:
a8:9a:08:45:3c:8a:70:4f:23:dd:88:8f:13:bc:05:
1f:50:ac:ab:2e:b6:06:7d:cd:0d:62:ef:8e:50:4f:
be:d8:3c:01:1b:33:50:01:13:ff:30:57:34:51:7c:
0e:f0:75:6a:1c:20:aa:dc:6f:e7:07:b4:9c:41:a1:
58:34:50:0e:2d:5a:7e:f1:05:a2:9f:8e:44:4e:4d:
0a:9b
Exponent: 65537 (0x10001)
Signature Algorithm: sha1WithRSAEncryption
4c:08:06:06:b3:ba:c2:75:b0:e0:e5:23:2b:6e:7e:f3:fb:2b:
97:59:61:10:1c:8b:ae:d0:9f:f1:a3:6d:a6:0e:93:05:c9:de:
db:7b:99:ad:75:56:1f:c2:84:26:42:df:f0:f7:4c:4a:a1:c5:
59:85:95:e0:75:5d:cf:d0:b7:0c:e6:27:59:98:62:b6:43:1f:
d2:7e:eb:58:70:ca:fe:bf:bb:8e:34:cb:42:89:d4:2c:00:5d:
c4:7a:78:65:27:e9:3c:3f:89:57:f5:66:56:2f:2e:1e:8a:a3:
18:65:33:54:7b:f3:69:ce:15:e5:0b:91:17:38:1a:b8:4d:06:
4b:0c:a5:25:18:7b:b3:b8:19:86:47:3b:94:3b:f5:a9:dd:e9:
20:f4:c2:5f:20:4a:05:d3:69:cf:85:52:b2:ed:f4:2e:8f:9d:
d9:38:c8:54:f9:3a:7f:a4:29:aa:32:9f:ad:58:9b:bd:c1:1f:
46:f0:2b:45:1c:68:23:54:da:3c:e7:8d:21:bf:d1:b8:d3:c2:
e0:38:25:5a:81:da:d2:66:d2:01:c1:21:29:b8:10:cd:68:98:
e8:de:d3:03:88:02:be:3e:43:d1:de:46:a0:9a:c3:0b:af:9b:
6a:c3:38:c9:17:2b:93:b8:ff:a3:94:e2:92:06:87:24:c8:4c:
3e:46:fc:5c
[2017-02-09 14:28:56] INFO WEBrick::HTTPServer#start: pid=27603 port=8889
Libvirt Error! Make sure your user has permissions to run anything.
[2017-02-09 14:28:58] INFO going to shutdown ...
[2017-02-09 14:28:58] INFO going to shutdown ...
[2017-02-09 14:28:58] INFO WEBrick::HTTPServer#start done.
[2017-02-09 14:28:58] INFO WEBrick::HTTPServer#start done.
/home/jenkins/.gem/ruby/2.1.0/gems/fog-1.29.0/lib/fog/libvirt/requests/compute/vm_action.rb:7:in `create': Call to virDomainCreateWithFlags failed: internal error: process exited while connecting to monitor: 2017-02-09T13:28:57.977350Z qemu-system-x86_64: -drive file=https://ci.suse.de/job/yast-aytests-run/GROUP=1,label=autoyast/ws/aytests-wo...: Could not refresh total sector count: File too large (Libvirt::Error)
from /home/jenkins/.gem/ruby/2.1.0/gems/fog-1.29.0/lib/fog/libvirt/requests/compute/vm_action.rb:7:in `vm_action'
from /home/jenkins/.gem/ruby/2.1.0/gems/fog-1.29.0/lib/fog/libvirt/models/compute/server.rb:69:in `start'
from /home/jenkins/.gem/ruby/2.1.0/gems/veewee-0.4.5.1/lib/veewee/provider/kvm/box/up.rb:8:in `up'
from /home/jenkins/.gem/ruby/2.1.0/gems/veewee-0.4.5.1/lib/veewee/provider/core/box/build.rb:50:in `kickstart'
from /home/jenkins/.gem/ruby/2.1.0/gems/veewee-0.4.5.1/lib/veewee/provider/core/box/build.rb:113:in `build'
from /home/jenkins/.gem/ruby/2.1.0/gems/veewee-0.4.5.1/lib/veewee/provider/kvm/box/build.rb:6:in `build'
from /home/jenkins/.gem/ruby/2.1.0/gems/veewee-0.4.5.1/lib/veewee/command/kvm.rb:25:in `build'
from /usr/lib64/ruby/gems/2.1.0/gems/thor-0.19.4/lib/thor/command.rb:27:in `run'
from /usr/lib64/ruby/gems/2.1.0/gems/thor-0.19.4/lib/thor/invocation.rb:126:in `invoke_command'
from /usr/lib64/ruby/gems/2.1.0/gems/thor-0.19.4/lib/thor.rb:369:in `dispatch'
from /usr/lib64/ruby/gems/2.1.0/gems/thor-0.19.4/lib/thor/invocation.rb:115:in `invoke'
from /usr/lib64/ruby/gems/2.1.0/gems/thor-0.19.4/lib/thor.rb:242:in `block in subcommand'
from /usr/lib64/ruby/gems/2.1.0/gems/thor-0.19.4/lib/thor/command.rb:27:in `run'
from /usr/lib64/ruby/gems/2.1.0/gems/thor-0.19.4/lib/thor/invocation.rb:126:in `invoke_command'
from /usr/lib64/ruby/gems/2.1.0/gems/thor-0.19.4/lib/thor.rb:369:in `dispatch'
from /usr/lib64/ruby/gems/2.1.0/gems/thor-0.19.4/lib/thor/base.rb:444:in `start'
from /home/jenkins/.gem/ruby/2.1.0/gems/veewee-0.4.5.1/bin/veewee:24:in `'
from /home/jenkins/.gem/ruby/2.1.0/bin/veewee:23:in `load'
from /home/jenkins/.gem/ruby/2.1.0/bin/veewee:23:in `<main>'
I, [2017-02-09T14:28:58.104723 #1273] INFO -- : Using definition /usr/lib64/ruby/gems/2.1.0/gems/aytests-1.0.32/share/veewee/upgrade_definition.rb
I, [2017-02-09T14:28:58.702652 #1273] INFO -- : Creating kvm image
Building Box autoyast with Definition autoyast:
- debug : false
- cwd : https://ci.suse.de/job/yast-aytests-run/GROUP=1,label=autoyast/ws/aytests-wo...
- force : true
- nogui : false
- auto : true
- checksum : false
- postinstall_include : []
- postinstall_exclude : []
- skip_to_postinstall : false
- use_emulation : false
- network_name : default
The isofile testing.iso already exists.
Running #before_create hook
[2017-02-09 14:28:59] INFO WEBrick 1.3.1
[2017-02-09 14:28:59] INFO ruby 2.1.2 (2014-05-08) [x86_64-linux-gnu]
Running #after_create hook
[2017-02-09 14:28:59] INFO WEBrick::HTTPServer#start: pid=27671 port=8888
Hook #after_up is not defined
Waiting 10 seconds for the machine to boot
Skipping webserver as no kickstartfile was specified
Sending keystrokes to VNC port :0 - TCP port: 5900
Typing:[1]: <Esc><Enter>
Waiting for ssh login on 192.168.122.98 with user vagrant to sshd on port => 22 to work, timeout=3600 sec
Hook #before_ssh is not defined
.Typing:[2]:
Typing:[3]: linux
Typing:[4]: netdevice=eth0
Typing:[5]: instmode=dvd
Typing:[6]: textmode=1
Typing:[7]: insecure=1
.Typing:[8]: autoupgrade=1
Typing:[9]: netsetup=dhcp
Typing:[10]: SetHostname=0
Typing:[11]: autoyast=http://10.100.24.103:8888/autoinst.xml
.Typing:[12]: <Enter>
Done typing.
Hook #after_boot_sequence is not defined
..............192.168.122.98 - - [09/Feb/2017:14:30:48 CET] "GET /autoinst.xml HTTP/1.1" 200 7577
- -> /autoinst.xml
192.168.122.98 - - [09/Feb/2017:14:30:48 CET] "GET /autoinst.xml HTTP/1.1" 200 7577
- -> /autoinst.xml
192.168.122.98 - - [09/Feb/2017:14:30:49 CET] "HEAD /static/repos/sles12/repodata/repomd.xml HTTP/1.1" 200 0
- -> /static/repos/sles12/repodata/repomd.xml
192.168.122.98 - - [09/Feb/2017:14:30:49 CET] "HEAD /static/repos/sles12/repodata/repomd.xml HTTP/1.1" 200 0
- -> /static/repos/sles12/repodata/repomd.xml
[2017-02-09 14:30:49] ERROR `/static/repos/sles12/repodata/repomd.xml.asc' not found.
192.168.122.98 - - [09/Feb/2017:14:30:49 CET] "GET /static/repos/sles12/repodata/repomd.xml.asc HTTP/1.1" 404 317
- -> /static/repos/sles12/repodata/repomd.xml.asc
[2017-02-09 14:30:49] ERROR `/static/repos/sles12/repodata/repomd.xml.key' not found.
192.168.122.98 - - [09/Feb/2017:14:30:49 CET] "GET /static/repos/sles12/repodata/repomd.xml.key HTTP/1.1" 404 317
- -> /static/repos/sles12/repodata/repomd.xml.key
192.168.122.98 - - [09/Feb/2017:14:30:49 CET] "GET /static/repos/sles12/repodata/repomd.xml HTTP/1.1" 200 1497
- -> /static/repos/sles12/repodata/repomd.xml
192.168.122.98 - - [09/Feb/2017:14:30:49 CET] "GET /static/repos/sles12/repodata/fa8914c5b7e1745f94044f379a191b0993d2bcdc9385088d679400ee4e58e491-primary.xml.gz HTTP/1.1" 200 659
- -> /static/repos/sles12/repodata/fa8914c5b7e1745f94044f379a191b0993d2bcdc9385088d679400ee4e58e491-primary.xml.gz
192.168.122.98 - - [09/Feb/2017:14:30:49 CET] "GET /static/repos/sles12/dummy-package-1.1-2.1.noarch.rpm HTTP/1.1" 200 2066
- -> /static/repos/sles12/dummy-package-1.1-2.1.noarch.rpm
.192.168.122.98 - - [09/Feb/2017:14:30:52 CET] "GET /autoinst.xml HTTP/1.1" 200 7577
- -> /autoinst.xml
192.168.122.98 - - [09/Feb/2017:14:30:52 CET] "GET /autoinst.xml HTTP/1.1" 200 7577
- -> /autoinst.xml
...192.168.122.98 - - [09/Feb/2017:14:31:09 CET] "HEAD /static/repos/sles12/repodata/repomd.xml HTTP/1.1" 200 0
- -> /static/repos/sles12/repodata/repomd.xml
[2017-02-09 14:31:09] ERROR `/static/repos/sles12/repodata/repomd.xml.asc' not found.
192.168.122.98 - - [09/Feb/2017:14:31:09 CET] "GET /static/repos/sles12/repodata/repomd.xml.asc HTTP/1.1" 404 317
- -> /static/repos/sles12/repodata/repomd.xml.asc
[2017-02-09 14:31:09] ERROR `/static/repos/sles12/repodata/repomd.xml.key' not found.
192.168.122.98 - - [09/Feb/2017:14:31:09 CET] "GET /static/repos/sles12/repodata/repomd.xml.key HTTP/1.1" 404 317
- -> /static/repos/sles12/repodata/repomd.xml.key
192.168.122.98 - - [09/Feb/2017:14:31:09 CET] "GET /static/repos/sles12/repodata/repomd.xml HTTP/1.1" 200 1497
- -> /static/repos/sles12/repodata/repomd.xml
192.168.122.98 - - [09/Feb/2017:14:31:09 CET] "GET /static/repos/sles12/repodata/fa8914c5b7e1745f94044f379a191b0993d2bcdc9385088d679400ee4e58e491-primary.xml.gz HTTP/1.1" 200 659
- -> /static/repos/sles12/repodata/fa8914c5b7e1745f94044f379a191b0993d2bcdc9385088d679400ee4e58e491-primary.xml.gz
[2017-02-09 14:31:09] ERROR `/static/repos/sles12/directory.yast' not found.
192.168.122.98 - - [09/Feb/2017:14:31:09 CET] "GET /static/repos/sles12/directory.yast HTTP/1.1" 404 308
- -> /static/repos/sles12/directory.yast
[2017-02-09 14:31:09] ERROR `/static/repos/sles12/content.asc' not found.
192.168.122.98 - - [09/Feb/2017:14:31:09 CET] "GET /static/repos/sles12/content.asc HTTP/1.1" 404 305
- -> /static/repos/sles12/content.asc
[2017-02-09 14:31:09] ERROR `/static/repos/sles12/content.key' not found.
192.168.122.98 - - [09/Feb/2017:14:31:09 CET] "GET /static/repos/sles12/content.key HTTP/1.1" 404 305
- -> /static/repos/sles12/content.key
[2017-02-09 14:31:09] ERROR `/static/repos/sles12/content' not found.
192.168.122.98 - - [09/Feb/2017:14:31:09 CET] "GET /static/repos/sles12/content HTTP/1.1" 404 301
- -> /static/repos/sles12/content
[2017-02-09 14:31:09] ERROR `/static/repos/sles12/directory.yast' not found.
192.168.122.98 - - [09/Feb/2017:14:31:09 CET] "GET /static/repos/sles12/directory.yast HTTP/1.1" 404 308
- -> /static/repos/sles12/directory.yast
........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................Ssh timeout 3600 sec has been reached.
[2017-02-09 15:29:11] INFO going to shutdown ...
[2017-02-09 15:29:11] INFO WEBrick::HTTPServer#start done.
I, [2017-02-09T15:29:11.317761 #1273] INFO -- : Waiting 150 seconds for upgrade process to finish
I, [2017-02-09T15:31:41.321241 #1273] INFO -- : Running post-install script
/usr/lib64/ruby/gems/2.1.0/gems/net-ssh-simple-1.6.17/lib/net/ssh/simple.rb:632:in `rescue in with_session': No route to host - connect(2) for 192.168.122.98:22 @ # finish_at=2017-02-09 15:31:59 +0100 stderr="" stdout="" success=false> (Net::SSH::Simple::Error)
from /usr/lib64/ruby/gems/2.1.0/gems/net-ssh-simple-1.6.17/lib/net/ssh/simple.rb:618:in `with_session'
from /usr/lib64/ruby/gems/2.1.0/gems/net-ssh-simple-1.6.17/lib/net/ssh/simple.rb:490:in `ssh'
from /usr/lib64/ruby/gems/2.1.0/gems/blockenspiel-0.5.0/lib/blockenspiel/impl.rb:517:in `block in _target_dispatch'
from /usr/lib64/ruby/gems/2.1.0/gems/blockenspiel-0.5.0/lib/blockenspiel/impl.rb:513:in `reverse_each'
from /usr/lib64/ruby/gems/2.1.0/gems/blockenspiel-0.5.0/lib/blockenspiel/impl.rb:513:in `_target_dispatch'
from (eval):1:in `ssh'
from /usr/lib64/ruby/gems/2.1.0/gems/aytests-1.0.32/lib/aytests/image_builder.rb:218:in `block in run_postinstall'
from /usr/lib64/ruby/gems/2.1.0/gems/blockenspiel-0.5.0/lib/blockenspiel/impl.rb:489:in `instance_eval'
from /usr/lib64/ruby/gems/2.1.0/gems/blockenspiel-0.5.0/lib/blockenspiel/impl.rb:489:in `_execute_dsl'
from /usr/lib64/ruby/gems/2.1.0/gems/blockenspiel-0.5.0/lib/blockenspiel/impl.rb:382:in `_invoke_with_block'
from /usr/lib64/ruby/gems/2.1.0/gems/blockenspiel-0.5.0/lib/blockenspiel/impl.rb:309:in `invoke'
from /usr/lib64/ruby/gems/2.1.0/gems/net-ssh-simple-1.6.17/lib/net/ssh/simple.rb:577:in `sync'
from /usr/lib64/ruby/gems/2.1.0/gems/aytests-1.0.32/lib/aytests/image_builder.rb:215:in `run_postinstall'
from /usr/lib64/ruby/gems/2.1.0/gems/aytests-1.0.32/lib/aytests/image_builder.rb:142:in `upgrade'
from /usr/lib64/ruby/gems/2.1.0/gems/aytests-1.0.32/lib/aytests/test_runner.rb:61:in `build'
from /usr/lib64/ruby/gems/2.1.0/gems/aytests-1.0.32/lib/aytests/test_runner.rb:39:in `run'
from /usr/lib64/ruby/gems/2.1.0/gems/aytests-1.0.32/lib/aytests/cli.rb:55:in `block in test'
from /usr/lib64/ruby/gems/2.1.0/gems/aytests-1.0.32/lib/aytests/cli.rb:44:in `each'
from /usr/lib64/ruby/gems/2.1.0/gems/aytests-1.0.32/lib/aytests/cli.rb:44:in `test'
from /usr/lib64/ruby/gems/2.1.0/gems/thor-0.19.4/lib/thor/command.rb:27:in `run'
from /usr/lib64/ruby/gems/2.1.0/gems/thor-0.19.4/lib/thor/invocation.rb:126:in `invoke_command'
from /usr/lib64/ruby/gems/2.1.0/gems/thor-0.19.4/lib/thor.rb:369:in `dispatch'
from /usr/lib64/ruby/gems/2.1.0/gems/thor-0.19.4/lib/thor/base.rb:444:in `start'
from /usr/lib64/ruby/gems/2.1.0/gems/aytests-1.0.32/bin/aytests:9:in `'
from /usr/bin/aytests:23:in `load'
from /usr/bin/aytests:23:in `<main>'
Build step 'Execute shell' marked build as failure