https://ci.suse.de/job/yast-aytests-run/GROUP=1,label=autoyast/205/---------... [...truncated 13894 lines...] 6f:1e:6e:3b:14:ca:a5:0d:81:1c:60:46:2a:7c:fa: 1f:d5 Exponent: 65537 (0x10001) Signature Algorithm: sha1WithRSAEncryption 4b:f6:7c:2a:21:d4:73:44:e6:1a:36:3c:5a:5b:3d:ef:28:84: 29:06:4f:cf:8c:77:5c:70:e3:58:da:f4:b3:3f:a1:ed:9b:57: d3:84:5a:b3:83:30:b3:49:88:2b:f4:95:82:30:c0:89:58:7e: 01:a6:ae:1f:f6:48:b8:d7:e8:72:e0:e0:43:e9:76:41:c6:f7: 69:1b:dd:3e:55:da:ef:d9:c0:c1:2d:6b:35:22:d9:4d:c2:57: 85:46:2f:40:6b:5a:df:7e:52:2d:5d:fa:97:39:50:39:7f:f5: 30:17:53:76:97:66:d9:a3:9b:58:1a:17:ec:61:43:8f:2b:90: 29:97:99:69:c8:85:8a:90:9c:0f:98:1c:ed:12:97:78:08:61: 69:e5:be:60:aa:87:5e:38:52:ec:f7:b2:51:77:ad:da:96:c9: 49:4c:62:5f:1c:94:c1:a8:1f:34:5b:74:41:48:dc:38:dd:2e: 03:b8:fa:98:ea:9f:93:82:06:91:e0:e3:0f:21:d5:44:90:6b: 61:70:06:21:1d:28:1d:5e:a6:ee:cf:3e:1d:b9:0c:fc:d5:af: d1:74:c3:73:f6:98:19:e2:6c:98:09:7f:f2:d0:7d:03:fe:4c: d0:99:34:af:4c:d1:48:c4:58:b8:36:8f:12:07:74:ae:5a:53: b2:82:20:fa [2016-10-10 00:39:02] INFO WEBrick::HTTPServer#start: pid=21280 port=8889 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> Typing:[2]: Typing:[3]: linux Typing:[4]: netdevice=eth0 Typing:[5]: instmode=dvd Typing:[6]: textmode=1 Typing:[7]: insecure=1 Typing:[8]: netsetup=dhcp Typing:[9]: autoyast=http://10.100.24.103:8888/autoinst.xml Typing:[10]: <Enter> Done typing. Hook #after_boot_sequence is not defined Waiting for ssh login on 192.168.122.98 with user vagrant to sshd on port => 22 to work, timeout=10000 sec Hook #before_ssh is not defined ....192.168.122.98 - - [10/Oct/2016:00:40:11 CEST] "GET /autoinst.xml HTTP/1.1" 200 17422 - -> /autoinst.xml 192.168.122.98 - - [10/Oct/2016:00:40:11 CEST] "GET /autoinst.xml HTTP/1.1" 200 17422 - -> /autoinst.xml .192.168.122.98 - - [10/Oct/2016:00:40:14 CEST] "HEAD /static/repos/sles11/repodata/repomd.xml HTTP/1.1" 200 0 - -> /static/repos/sles11/repodata/repomd.xml [2016-10-10 00:40:14] ERROR `/static/repos/sles11/repodata/repomd.xml.asc' not found. 192.168.122.98 - - [10/Oct/2016:00:40:14 CEST] "GET /static/repos/sles11/repodata/repomd.xml.asc HTTP/1.1" 404 332 - -> /static/repos/sles11/repodata/repomd.xml.asc [2016-10-10 00:40:14] ERROR `/static/repos/sles11/repodata/repomd.xml.key' not found. 192.168.122.98 - - [10/Oct/2016:00:40:14 CEST] "GET /static/repos/sles11/repodata/repomd.xml.key HTTP/1.1" 404 332 - -> /static/repos/sles11/repodata/repomd.xml.key 192.168.122.98 - - [10/Oct/2016:00:40:14 CEST] "GET /static/repos/sles11/repodata/repomd.xml HTTP/1.1" 200 1497 - -> /static/repos/sles11/repodata/repomd.xml 192.168.122.98 - - [10/Oct/2016:00:40:14 CEST] "GET /static/repos/sles11/repodata/9009628f0daae9e50f094bcff60ba1e347a8b1b16b213184789fc7b81c816205-primary.xml.gz HTTP/1.1" 200 611 - -> /static/repos/sles11/repodata/9009628f0daae9e50f094bcff60ba1e347a8b1b16b213184789fc7b81c816205-primary.xml.gz [2016-10-10 00:40:14] ERROR `/static/repos/sles11/directory.yast' not found. 192.168.122.98 - - [10/Oct/2016:00:40:14 CEST] "GET /static/repos/sles11/directory.yast HTTP/1.1" 404 323 - -> /static/repos/sles11/directory.yast [2016-10-10 00:40:14] ERROR `/static/repos/sles11/content.asc' not found. 192.168.122.98 - - [10/Oct/2016:00:40:14 CEST] "GET /static/repos/sles11/content.asc HTTP/1.1" 404 320 - -> /static/repos/sles11/content.asc [2016-10-10 00:40:14] ERROR `/static/repos/sles11/content.key' not found. 192.168.122.98 - - [10/Oct/2016:00:40:14 CEST] "GET /static/repos/sles11/content.key HTTP/1.1" 404 320 - -> /static/repos/sles11/content.key [2016-10-10 00:40:14] ERROR `/static/repos/sles11/content' not found. 192.168.122.98 - - [10/Oct/2016:00:40:14 CEST] "GET /static/repos/sles11/content HTTP/1.1" 404 316 - -> /static/repos/sles11/content [2016-10-10 00:40:14] ERROR `/static/repos/sles11/directory.yast' not found. 192.168.122.98 - - [10/Oct/2016:00:40:14 CEST] "GET /static/repos/sles11/directory.yast HTTP/1.1" 404 323 - -> /static/repos/sles11/directory.yast ...............................192.168.122.98 - - [10/Oct/2016:00:42:48 CEST] "GET /static/repos/sles11/dummy-package-1.0-0.noarch.rpm HTTP/1.1" 200 5478 - -> /static/repos/sles11/dummy-package-1.0-0.noarch.rpm .................... Transferring /tmp/.veewee_version20161010-21280-hjmfv4 to .veewee_version .. Waiting for ssh login on 192.168.122.98 with user vagrant to sshd on port => 22 to work, timeout=10000 sec Hook #before_ssh is not defined . Transferring /tmp/.veewee_params20161010-21280-qhj70x to .veewee_params .. Hook #before_postinstall is not defined Waiting for ssh login on 192.168.122.98 with user vagrant to sshd on port => 22 to work, timeout=10000 sec Hook #before_ssh is not defined . Transferring https://ci.suse.de/job/yast-aytests-run/GROUP=1,label=autoyast/ws/aytests-wo... to postinstall.sh .. Waiting for ssh login on 192.168.122.98 with user vagrant to sshd on port => 22 to work, timeout=10000 sec Hook #before_ssh is not defined . Executing command: chmod +x "postinstall.sh" Waiting for ssh login on 192.168.122.98 with user vagrant to sshd on port => 22 to work, timeout=10000 sec Hook #before_ssh is not defined . Executing command: echo 'nots3cr3t'|sudo -S sh './postinstall.sh' We trust you have received the usual lecture from the local System Administrator. It usually boils down to these three things: #1) Respect the privacy of others. #2) Think before you type. #3) With great power comes great responsibility. root's password: % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 102 409 102 409 0 0 508 0 --:--:-- --:--:-- --:--:-- 508 update sudoers ... speed-up remote logins ... Hook #after_postinstall is not defined The box autoyast was built successfully! You can now login to the box with: ssh -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -p 22 -l vagrant 192.168.122.98 [2016-10-10 00:44:51] INFO going to shutdown ... [2016-10-10 00:44:51] INFO going to shutdown ... [2016-10-10 00:44:51] INFO WEBrick::HTTPServer#start done. [2016-10-10 00:44:51] INFO WEBrick::HTTPServer#start done. I, [2016-10-10T00:44:51.295395 #25174] INFO -- : Using definition /usr/lib64/ruby/gems/2.1.0/gems/aytests-1.0.25/share/veewee/upgrade_definition.rb I, [2016-10-10T00:45:10.848094 #25174] INFO -- : Creating kvm image WARNING: Nokogiri was built against LibXML version 2.9.3, but has dynamically loaded 2.9.4 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 [2016-10-10 00:45:14] INFO WEBrick 1.3.1 [2016-10-10 00:45:14] INFO ruby 2.1.3 (2014-09-19) [x86_64-linux-gnu] [2016-10-10 00:45:14] INFO WEBrick::HTTPServer#start: pid=21844 port=8888 Running #after_create hook Hook #after_up is not defined Waiting 10 seconds for the machine to boot Sending keystrokes to VNC port :0 - TCP port: 5900 Skipping webserver as no kickstartfile was specified Typing:[1]: <Esc><Enter> 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]: autoyast=http://10.100.24.103:8888/autoinst.xml Typing:[11]: <Enter> Done typing. Hook #after_boot_sequence is not defined Waiting for ssh login on 192.168.122.98 with user vagrant to sshd on port => 22 to work, timeout=10000 sec Hook #before_ssh is not defined ..........192.168.122.98 - - [10/Oct/2016:00:47:12 CEST] "GET /autoinst.xml HTTP/1.1" 200 7890 - -> /autoinst.xml 192.168.122.98 - - [10/Oct/2016:00:47:12 CEST] "GET /autoinst.xml HTTP/1.1" 200 7890 - -> /autoinst.xml 192.168.122.98 - - [10/Oct/2016:00:47:12 CEST] "HEAD /static/repos/sles12/repodata/repomd.xml HTTP/1.1" 200 0 - -> /static/repos/sles12/repodata/repomd.xml 192.168.122.98 - - [10/Oct/2016:00:47:12 CEST] "HEAD /static/repos/sles12/repodata/repomd.xml HTTP/1.1" 200 0 - -> /static/repos/sles12/repodata/repomd.xml [2016-10-10 00:47:12] ERROR `/static/repos/sles12/repodata/repomd.xml.asc' not found. 192.168.122.98 - - [10/Oct/2016:00:47:12 CEST] "GET /static/repos/sles12/repodata/repomd.xml.asc HTTP/1.1" 404 317 - -> /static/repos/sles12/repodata/repomd.xml.asc [2016-10-10 00:47:12] ERROR `/static/repos/sles12/repodata/repomd.xml.key' not found. 192.168.122.98 - - [10/Oct/2016:00:47:12 CEST] "GET /static/repos/sles12/repodata/repomd.xml.key HTTP/1.1" 404 317 - -> /static/repos/sles12/repodata/repomd.xml.key 192.168.122.98 - - [10/Oct/2016:00:47:12 CEST] "GET /static/repos/sles12/repodata/repomd.xml HTTP/1.1" 200 1497 - -> /static/repos/sles12/repodata/repomd.xml 192.168.122.98 - - [10/Oct/2016:00:47:12 CEST] "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 - - [10/Oct/2016:00:47:12 CEST] "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 - - [10/Oct/2016:00:47:15 CEST] "GET /autoinst.xml HTTP/1.1" 200 7890 - -> /autoinst.xml 192.168.122.98 - - [10/Oct/2016:00:47:15 CEST] "GET /autoinst.xml HTTP/1.1" 200 7890 - -> /autoinst.xml ...192.168.122.98 - - [10/Oct/2016:00:47:33 CEST] "HEAD /static/repos/sles12/repodata/repomd.xml HTTP/1.1" 200 0 - -> /static/repos/sles12/repodata/repomd.xml [2016-10-10 00:47:33] ERROR `/static/repos/sles12/repodata/repomd.xml.asc' not found. 192.168.122.98 - - [10/Oct/2016:00:47:33 CEST] "GET /static/repos/sles12/repodata/repomd.xml.asc HTTP/1.1" 404 317 - -> /static/repos/sles12/repodata/repomd.xml.asc [2016-10-10 00:47:33] ERROR `/static/repos/sles12/repodata/repomd.xml.key' not found. 192.168.122.98 - - [10/Oct/2016:00:47:33 CEST] "GET /static/repos/sles12/repodata/repomd.xml.key HTTP/1.1" 404 317 - -> /static/repos/sles12/repodata/repomd.xml.key 192.168.122.98 - - [10/Oct/2016:00:47:33 CEST] "GET /static/repos/sles12/repodata/repomd.xml HTTP/1.1" 200 1497 - -> /static/repos/sles12/repodata/repomd.xml 192.168.122.98 - - [10/Oct/2016:00:47:33 CEST] "GET /static/repos/sles12/repodata/fa8914c5b7e1745f94044f379a191b0993d2bcdc9385088d679400ee4e58e491-primary.xml.gz HTTP/1.1" 200 659 - -> /static/repos/sles12/repodata/fa8914c5b7e1745f94044f379a191b0993d2bcdc9385088d679400ee4e58e491-primary.xml.gz .[2016-10-10 00:47:33] ERROR `/static/repos/sles12/directory.yast' not found. 192.168.122.98 - - [10/Oct/2016:00:47:33 CEST] "GET /static/repos/sles12/directory.yast HTTP/1.1" 404 308 - -> /static/repos/sles12/directory.yast [2016-10-10 00:47:33] ERROR `/static/repos/sles12/content.asc' not found. 192.168.122.98 - - [10/Oct/2016:00:47:33 CEST] "GET /static/repos/sles12/content.asc HTTP/1.1" 404 305 - -> /static/repos/sles12/content.asc [2016-10-10 00:47:33] ERROR `/static/repos/sles12/content.key' not found. 192.168.122.98 - - [10/Oct/2016:00:47:33 CEST] "GET /static/repos/sles12/content.key HTTP/1.1" 404 305 - -> /static/repos/sles12/content.key [2016-10-10 00:47:33] ERROR `/static/repos/sles12/content' not found. 192.168.122.98 - - [10/Oct/2016:00:47:33 CEST] "GET /static/repos/sles12/content HTTP/1.1" 404 301 - -> /static/repos/sles12/content [2016-10-10 00:47:33] ERROR `/static/repos/sles12/directory.yast' not found. 192.168.122.98 - - [10/Oct/2016:00:47:33 CEST] "GET /static/repos/sles12/directory.yast HTTP/1.1" 404 308 - -> /static/repos/sles12/directory.yast ..........192.168.122.98 - - [10/Oct/2016:00:48:26 CEST] "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 .......................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................Ssh timeout 10000 sec has been reached. [2016-10-10 03:33:03] INFO going to shutdown ... [2016-10-10 03:33:03] INFO WEBrick::HTTPServer#start done. I, [2016-10-10T03:33:03.879423 #25174] INFO -- : Waiting 150 seconds for upgrade process to finish I, [2016-10-10T03:35:33.882587 #25174] INFO -- : Running post-install script /usr/lib64/ruby/gems/2.1.0/gems/net-ssh-simple-1.6.16/lib/net/ssh/simple.rb:632:in `rescue in with_session': Connection refused - connect(2) for 192.168.122.98:22 @ #<Net::SSH::Simple::Result exception=#<Errno::ECONNREFUSED: Connection refused - connect(2) for 192.168.122.98:22> finish_at=2016-10-10 03:35:34 +0200 stderr="" stdout="" success=false> (Net::SSH::Simple::Error) from /usr/lib64/ruby/gems/2.1.0/gems/net-ssh-simple-1.6.16/lib/net/ssh/simple.rb:618:in `with_session' from /usr/lib64/ruby/gems/2.1.0/gems/net-ssh-simple-1.6.16/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.25/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.16/lib/net/ssh/simple.rb:577:in `sync' from /usr/lib64/ruby/gems/2.1.0/gems/aytests-1.0.25/lib/aytests/image_builder.rb:215:in `run_postinstall' from /usr/lib64/ruby/gems/2.1.0/gems/aytests-1.0.25/lib/aytests/image_builder.rb:142:in `upgrade' from /usr/lib64/ruby/gems/2.1.0/gems/aytests-1.0.25/lib/aytests/test_runner.rb:61:in `build' from /usr/lib64/ruby/gems/2.1.0/gems/aytests-1.0.25/lib/aytests/test_runner.rb:39:in `run' from /usr/lib64/ruby/gems/2.1.0/gems/aytests-1.0.25/lib/aytests/cli.rb:55:in `block in test' from /usr/lib64/ruby/gems/2.1.0/gems/aytests-1.0.25/lib/aytests/cli.rb:44:in `each' from /usr/lib64/ruby/gems/2.1.0/gems/aytests-1.0.25/lib/aytests/cli.rb:44:in `test' from /usr/lib64/ruby/gems/2.1.0/gems/thor-0.19.1/lib/thor/command.rb:27:in `run' from /usr/lib64/ruby/gems/2.1.0/gems/thor-0.19.1/lib/thor/invocation.rb:126:in `invoke_command' from /usr/lib64/ruby/gems/2.1.0/gems/thor-0.19.1/lib/thor.rb:359:in `dispatch' from /usr/lib64/ruby/gems/2.1.0/gems/thor-0.19.1/lib/thor/base.rb:440:in `start' from /usr/lib64/ruby/gems/2.1.0/gems/aytests-1.0.25/bin/aytests:9:in `<top (required)>' from /usr/bin/aytests:23:in `load' from /usr/bin/aytests:23:in `<main>' Build step 'Execute shell' marked build as failure