search

HCK-CI / AutoHCK-Installer

BSD 2-Clause "Simplified" License
2 stars 2 forks source link

Issues & help for installation on f36 #4

Closed elmarco closed 11 months ago

elmarco commented 2 years ago

QEMU compilation failed. I had to add:

-    ./configure --disable-docs --target-list=x86_64-softmmu --enable-virtfs
+    ./configure --disable-docs --target-list=x86_64-softmmu --enable-virtfs --disable-werror

And now I have the following error:

[...]
Installed:
  dhcp-server-12:4.4.3-2.fc36.x86_64                                                                                                                                                                               

Complete!
Created symlink /etc/systemd/system/multi-user.target.wants/dhcpd.service → /usr/lib/systemd/system/dhcpd.service.
INFO:  Running config stage 
INFO:  Generating DHCP server configuration 
INFO:  Restarting DHCP server 
INFO:  Running test stage 
INFO:  Checking bridge br_autohck configuration 
INFO:  Generating image for testing DHCP configuration 
+++ dirname /root/hck/src/DHCPServerSetup.git/test/build-img.sh
++ cd /root/hck/src/DHCPServerSetup.git/test
++ pwd
+ work_dir=/root/hck/src/DHCPServerSetup.git/test
+ docker run --rm --mount type=bind,src=/root/hck/src/DHCPServerSetup.git/test,dst=/data archlinux bash /data/build-internal.sh
Emulate Docker CLI using podman. Create /etc/containers/nodocker to quiet msg.
Resolved "archlinux" as an alias (/etc/containers/registries.conf.d/000-shortnames.conf)
Trying to pull docker.io/archlinux/archlinux:latest...
Getting image source signatures
Copying blob ad581b309533 done  
Copying blob 7b71c00503e3 done  
Copying config 02bec35956 done  
Writing manifest to image destination
Storing signatures
bash: /data/build-internal.sh: Permission denied
FATAL:  Failed to generate image for testing DHCP configuration

thanks for the help!

kostyanf14 commented 2 years ago

Issue was closed automatically due to PR merge. Reopen to check that everything work as expected

elmarco commented 2 years ago

There are more issues on f36:

Unknown ruby interpreter version (do not know how to handle): >=2.6.5p114.
Searching for binary rubies, this might take some time.
No binary rubies available for: fedora/36/x86_64/ruby-3.0.0.
Continuing with compilation. Please read 'rvm help mount' to get more information on binary rubies.
Checking requirements for fedora.
Installing requirements for fedora.
Installing required packages: libyaml-devel....
Requirements installation successful.
Installing Ruby from source to: /usr/local/rvm/rubies/ruby-3.0.0, this may take a while depending on your cpu(s)...
ruby-3.0.0 - #downloading ruby-3.0.0, this may take a while depending on your connection...
  % Total    % Received % Xferd  Average Speed   Time    Time     Time  Current
                                 Dload  Upload   Total   Spent    Left  Speed
100 18.6M  100 18.6M    0     0   695k      0  0:00:27  0:00:27 --:--:--  817k
ruby-3.0.0 - #extracting ruby-3.0.0 to /usr/local/rvm/src/ruby-3.0.0.....
ruby-3.0.0 - #configuring.........................................................................
ruby-3.0.0 - #post-configuration..
ruby-3.0.0 - #compiling...........................................................................................................................................................................................-
Error running '__rvm_make -j8',
please read /usr/local/rvm/log/1652429291_ruby-3.0.0/make.log

There has been an error while running make. Halting the installation.
 /usr/local/rvm/log/1652429291_ruby-3.0.0/make.log:

ossl_pkey_rsa.c: In function ‘Init_ossl_rsa’:
ossl_pkey_rsa.c:885:58: error: ‘RSA_SSLV23_PADDING’ undeclared (first use in this function); did you mean ‘RSA_NO_PADDING’?
  885 | #define DefRSAConst(x) rb_define_const(cRSA, #x, INT2NUM(RSA_##x))
      |                                                          ^~~~
kostyanf14 commented 2 years ago

Hi @elmarco,

Thanks for reporting these issues. About the ruby issue. The problem occurs because Fedora 36 provides openssl3. This is a very old bug in ruby: https://bugs.ruby-lang.org/issues/18658 (2 month), https://github.com/ruby/openssl/issues/369 (2 years).

I found a workaround in rvm repository https://github.com/rvm/rvm/issues/5209#issuecomment-1114159447 I will check if it works for Fedora as well as for Ubuntu and then commits it.

kostyanf14 commented 2 years ago

Hi @elmarco,

I pushed all changes for Fedora 36. Please check if it works for you or not?

elmarco commented 2 years ago

I pushed all changes for Fedora 36. Please check if it works for you or not?

install.sh successfully. How do you run the tests? :)

kostyanf14 commented 2 years ago

Before running the test you should generate Windows images (wiki https://github.com/HCK-CI/AutoHCK/wiki/Image-Installation). For example for Windows Server 2019 (platform - Win2019x64):

  1. Download Windows Server 2019 ISO and put it in the ISO folder
  2. Add information (iso name, license keys) about iso to lib/engines/hckinstall/iso.json or override.json
  3. run ruby ./bin/auto_hck install -p Win2019x64 for image preparation

Then you can run tests. Command examples: https://github.com/HCK-CI/AutoHCK/wiki#examples

elmarco commented 2 years ago

ok.. hopefully I did right, now I get:

[root@localhost AutoHCK.git]# ruby bin/auto_hck install -p Win2019x64
Warning: no such file to load -- net/https. Make sure openssl is installed if you want ssl support
/usr/local/rvm/rubies/ruby-3.0.4/lib/ruby/3.0.0/net/https.rb:23:in `require': cannot load such file -- openssl (LoadError)
Did you mean?  open3
    from /usr/local/rvm/rubies/ruby-3.0.4/lib/ruby/3.0.0/net/https.rb:23:in `<top (required)>'
    from /usr/local/rvm/gems/ruby-3.0.4/gems/net-ping-2.0.8/lib/net/ping/http.rb:3:in `require'
    from /usr/local/rvm/gems/ruby-3.0.4/gems/net-ping-2.0.8/lib/net/ping/http.rb:3:in `<top (required)>'
    from /usr/local/rvm/gems/ruby-3.0.4/gems/net-ping-2.0.8/lib/net/ping.rb:11:in `require_relative'
    from /usr/local/rvm/gems/ruby-3.0.4/gems/net-ping-2.0.8/lib/net/ping.rb:11:in `<top (required)>'
    from /root/hck/src/AutoHCK.git/lib/setupmanagers/hckstudio.rb:3:in `require'
    from /root/hck/src/AutoHCK.git/lib/setupmanagers/hckstudio.rb:3:in `<top (required)>'
    from /root/hck/src/AutoHCK.git/lib/engines/hcktest/hcktest.rb:3:in `require'
    from /root/hck/src/AutoHCK.git/lib/engines/hcktest/hcktest.rb:3:in `<top (required)>'
    from /root/hck/src/AutoHCK.git/lib/engines/engine.rb:5:in `require'
    from /root/hck/src/AutoHCK.git/lib/engines/engine.rb:5:in `<top (required)>'
    from /root/hck/src/AutoHCK.git/lib/project.rb:6:in `require'
    from /root/hck/src/AutoHCK.git/lib/project.rb:6:in `<top (required)>'
    from bin/auto_hck:9:in `require'
    from bin/auto_hck:9:in `<main>'

[root@localhost AutoHCK.git]# curl -Lks 'https://git.io/rg-ssl' | ruby
Here's your Ruby and OpenSSL environment:

Ruby:           3.0.4p208 (2022-04-12 revision 3fa771ddedac25560be57f4055f1767e6c810f58) [x86_64-linux]
RubyGems:       3.3.15
Bundler:        2.2.15
Compiled with:  OpenSSL 3.0.3 3 May 2022
Loaded version: OpenSSL 3.0.3 3 May 2022
SSL_CERT_FILE:  /etc/pki/tls/cert.pem
SSL_CERT_DIR:   /etc/pki/tls/certs

With that out of the way, let's see if you can connect to rubygems.org...

Bundler connection to rubygems.org:       success ✅
RubyGems connection to rubygems.org:      success ✅
Ruby net/http connection to rubygems.org: success ✅

Hooray! This Ruby can connect to rubygems.org. You are all set to use Bundler and RubyGems. 👌

any idea?

kostyanf14 commented 2 years ago

Hi @elmarco,

Please pull the new installer, update your setup and try again.

elmarco commented 2 years ago

Hi @kostyanf14

How can I troubleshoot this:

[root@localhost AutoHCK.git]# ruby bin/auto_hck install -p Win2019x64
I, [2022-08-10T20:13:54.532308 #82388]  INFO -- : Workspace init path: /root/hck/workspace/hckinstall/qemuhck
I, [2022-08-10T20:13:54.543376 #82388]  INFO -- : Assigned ID: 2
I, [2022-08-10T20:13:54.544344 #82388]  INFO -- : Workspace moved to: /root/hck/workspace/hckinstall/qemuhck/Win2019x64/2022_08_10_20_13_54
I, [2022-08-10T20:13:54.544638 #82388]  INFO -- : Loading platform: Win2019x64
I, [2022-08-10T20:13:54.544954 #82388]  INFO -- : Loading studio platform for kit: HLK1809server
I, [2022-08-10T20:13:54.545168 #82388]  INFO -- : Loading ISO for platform: Win2019x64
I, [2022-08-10T20:13:54.545405 #82388]  INFO -- : Loading ISO for platform: Win2019x64
I, [2022-08-10T20:13:54.545618 #82388]  INFO -- : Loading kit by name: HLK1809server
I, [2022-08-10T20:13:54.546092 #82388]  INFO -- : 17763.1.180914-1434.rs5_release_amd64fre_SERVER-FOD-PACKAGES_OEM_amd64fre_MULTI.iso already exist, download skipped
I, [2022-08-10T20:13:54.546476 #82388]  INFO -- : hlk1809_grfx_fod.zip already exist, download skipped
I, [2022-08-10T20:13:54.546825 #82388]  INFO -- : WDTFNetData.dll already exist, download skipped
I, [2022-08-10T20:13:54.547174 #82388]  INFO -- : winfsp-1.6.20027.msi already exist, download skipped
I, [2022-08-10T20:13:54.593657 #82388]  INFO -- : Initializing result uploaders
I, [2022-08-10T20:13:54.593880 #82388]  INFO -- : Loading Dropbox token from the local file
I, [2022-08-10T20:13:54.593970 #82388]  INFO -- : Dropbox token missing
I, [2022-08-10T20:13:54.594034 #82388]  INFO -- : dropbox connection failed, (ignoring)
I, [2022-08-10T20:13:54.594320 #82388]  INFO -- : Loading machine: pc
I, [2022-08-10T20:13:54.594466 #82388]  INFO -- : Loading FW: bios
I, [2022-08-10T20:13:54.594564 #82388]  INFO -- : Initiating qemu-monitor session for QemuMachine0002_CL00
I, [2022-08-10T20:13:54.594918 #82388]  INFO -- : Loading machine: pc
I, [2022-08-10T20:13:54.595054 #82388]  INFO -- : Loading FW: bios
I, [2022-08-10T20:13:54.595139 #82388]  INFO -- : Initiating qemu-monitor session for QemuMachine0002_CL01
I, [2022-08-10T20:13:54.595464 #82388]  INFO -- : Loading machine: pc
I, [2022-08-10T20:13:54.595587 #82388]  INFO -- : Loading FW: bios
I, [2022-08-10T20:13:54.595670 #82388]  INFO -- : Initiating qemu-monitor session for QemuMachine0002_CL02
I, [2022-08-10T20:13:54.595876 #82388]  INFO -- : Starting download https://go.microsoft.com/fwlink/?linkid=2026646 to /root/hck/src/HLK-Setup-Scripts.git/Kits/HLK1809serverSetup.exe
I, [2022-08-10T20:13:58.692976 #82388]  INFO -- : Downloaded 72.0 KiB (5%)
I, [2022-08-10T20:13:58.895746 #82388]  INFO -- : Downloaded 136.0 KiB (10%)
I, [2022-08-10T20:13:59.172776 #82388]  INFO -- : Downloaded 208.0 KiB (16%)
I, [2022-08-10T20:13:59.337550 #82388]  INFO -- : Downloaded 280.0 KiB (21%)
I, [2022-08-10T20:13:59.423877 #82388]  INFO -- : Downloaded 336.0 KiB (26%)
I, [2022-08-10T20:13:59.511722 #82388]  INFO -- : Downloaded 400.0 KiB (31%)
I, [2022-08-10T20:13:59.601883 #82388]  INFO -- : Downloaded 472.0 KiB (36%)
I, [2022-08-10T20:13:59.660094 #82388]  INFO -- : Downloaded 528.0 KiB (41%)
I, [2022-08-10T20:13:59.716940 #82388]  INFO -- : Downloaded 592.0 KiB (46%)
I, [2022-08-10T20:13:59.769331 #82388]  INFO -- : Downloaded 664.0 KiB (51%)
I, [2022-08-10T20:13:59.808643 #82388]  INFO -- : Downloaded 720.0 KiB (56%)
I, [2022-08-10T20:13:59.847507 #82388]  INFO -- : Downloaded 784.0 KiB (61%)
I, [2022-08-10T20:13:59.887427 #82388]  INFO -- : Downloaded 856.0 KiB (66%)
I, [2022-08-10T20:13:59.916791 #82388]  INFO -- : Downloaded 912.0 KiB (71%)
I, [2022-08-10T20:13:59.949328 #82388]  INFO -- : Downloaded 976.0 KiB (76%)
I, [2022-08-10T20:13:59.983344 #82388]  INFO -- : Downloaded 1.02 MiB (81%)
I, [2022-08-10T20:14:00.009897 #82388]  INFO -- : Downloaded 1.08 MiB (86%)
I, [2022-08-10T20:14:00.033881 #82388]  INFO -- : Downloaded 1.14 MiB (91%)
I, [2022-08-10T20:14:00.065191 #82388]  INFO -- : Downloaded 1.21 MiB (96%)
I, [2022-08-10T20:14:00.078381 #82388]  INFO -- : Downloaded 1.25 MiB (100%)
I, [2022-08-10T20:14:00.079702 #82388]  INFO -- : HCKInstall: Studio image exist, installation skipped
I, [2022-08-10T20:14:00.080551 #82388]  INFO -- : Run command: mkisofs -iso-level 4 -l -R -udf -D -allow-limited-size -exclude=Kits -o /root/hck/workspace/hckinstall/qemuhck/Win2019x64/2022_08_10_20_13_54/setup-client.iso /root/hck/src/HLK-Setup-Scripts.git
W, [2022-08-10T20:14:00.845932 #82388]  WARN -- : Error dump:
   -- Warning: Creating ISO-9660:1999 (version 2) filesystem.
   -- Warning: ISO-9660 filenames longer than 31 may cause buffer overflows in the OS.
   --   2.59% done, estimate finish Wed Aug 10 20:14:00 2022
   --   5.16% done, estimate finish Wed Aug 10 20:14:00 2022
   --   7.74% done, estimate finish Wed Aug 10 20:14:00 2022
   --  10.32% done, estimate finish Wed Aug 10 20:14:00 2022
   --  12.90% done, estimate finish Wed Aug 10 20:14:00 2022
   --  15.47% done, estimate finish Wed Aug 10 20:14:00 2022
   --  18.06% done, estimate finish Wed Aug 10 20:14:00 2022
   --  20.63% done, estimate finish Wed Aug 10 20:14:00 2022
   --  23.21% done, estimate finish Wed Aug 10 20:14:00 2022
   --  25.79% done, estimate finish Wed Aug 10 20:14:00 2022
   --  28.37% done, estimate finish Wed Aug 10 20:14:00 2022
   --  30.94% done, estimate finish Wed Aug 10 20:14:00 2022
   --  33.53% done, estimate finish Wed Aug 10 20:14:00 2022
   --  36.10% done, estimate finish Wed Aug 10 20:14:00 2022
   --  38.68% done, estimate finish Wed Aug 10 20:14:00 2022
   --  41.26% done, estimate finish Wed Aug 10 20:14:00 2022
   --  43.84% done, estimate finish Wed Aug 10 20:14:00 2022
   --  46.41% done, estimate finish Wed Aug 10 20:14:00 2022
   --  48.99% done, estimate finish Wed Aug 10 20:14:00 2022
   --  51.57% done, estimate finish Wed Aug 10 20:14:00 2022
   --  54.15% done, estimate finish Wed Aug 10 20:14:00 2022
   --  56.73% done, estimate finish Wed Aug 10 20:14:00 2022
   --  59.31% done, estimate finish Wed Aug 10 20:14:00 2022
   --  61.88% done, estimate finish Wed Aug 10 20:14:00 2022
   --  64.46% done, estimate finish Wed Aug 10 20:14:00 2022
   --  67.04% done, estimate finish Wed Aug 10 20:14:00 2022
   --  69.62% done, estimate finish Wed Aug 10 20:14:00 2022
   --  72.20% done, estimate finish Wed Aug 10 20:14:00 2022
   --  74.78% done, estimate finish Wed Aug 10 20:14:00 2022
   --  77.35% done, estimate finish Wed Aug 10 20:14:00 2022
   --  79.93% done, estimate finish Wed Aug 10 20:14:00 2022
   --  82.51% done, estimate finish Wed Aug 10 20:14:00 2022
   --  85.09% done, estimate finish Wed Aug 10 20:14:00 2022
   --  87.67% done, estimate finish Wed Aug 10 20:14:00 2022
   --  90.25% done, estimate finish Wed Aug 10 20:14:00 2022
   --  92.82% done, estimate finish Wed Aug 10 20:14:00 2022
   --  95.40% done, estimate finish Wed Aug 10 20:14:00 2022
   --  97.98% done, estimate finish Wed Aug 10 20:14:00 2022
   -- Total translation table size: 0
   -- Total rockridge attributes bytes: 10557
   -- Total directory bytes: 49152
   -- Path table size(bytes): 356
   -- Max brk space used 2b000
   -- 193928 extents written (378 MB)
I, [2022-08-10T20:14:00.846201 #82388]  INFO -- : Creating QemuMachine0002_CL00 snapshot file
I, [2022-08-10T20:14:00.846306 #82388]  INFO -- : Run command: /root/hck/src/qemu.git/build/qemu-img create -f qcow2 -F qcow2 -b /root/hck/img/HLK1809.qcow2 /root/hck/workspace/hckinstall/qemuhck/Win2019x64/2022_08_10_20_13_54/HLK1809-snapshot.qcow2
I, [2022-08-10T20:14:00.898215 #82388]  INFO -- : Info dump:
   -- Formatting '/root/hck/workspace/hckinstall/qemuhck/Win2019x64/2022_08_10_20_13_54/HLK1809-snapshot.qcow2', fmt=qcow2 cluster_size=65536 extended_l2=off compression_type=zlib size=161061273600 backing_file=/root/hck/img/HLK1809.qcow2 backing_fmt=qcow2 lazy_refcounts=off refcount_bits=16
I, [2022-08-10T20:14:00.899171 #82388]  INFO -- : Creating QemuMachine0002_CL00 snapshot file
I, [2022-08-10T20:14:00.899468 #82388]  INFO -- : Run command: /root/hck/src/qemu.git/build/qemu-img create -f qcow2 -F qcow2 -b /root/hck/img/HLK1809.qcow2 /root/hck/workspace/hckinstall/qemuhck/Win2019x64/2022_08_10_20_13_54/HLK1809-snapshot.qcow2
I, [2022-08-10T20:14:00.991103 #82388]  INFO -- : Info dump:
   -- Formatting '/root/hck/workspace/hckinstall/qemuhck/Win2019x64/2022_08_10_20_13_54/HLK1809-snapshot.qcow2', fmt=qcow2 cluster_size=65536 extended_l2=off compression_type=zlib size=161061273600 backing_file=/root/hck/img/HLK1809.qcow2 backing_fmt=qcow2 lazy_refcounts=off refcount_bits=16
I, [2022-08-10T20:14:00.991459 #82388]  INFO -- : Loading device: ide-storage
I, [2022-08-10T20:14:00.991746 #82388]  INFO -- : Loading device: vga-std
I, [2022-08-10T20:14:00.992051 #82388]  INFO -- : Loading device: e1000e
I, [2022-08-10T20:14:00.992943 #82388]  INFO -- : Disabling bridge-netfilter
I, [2022-08-10T20:14:00.993124 #82388]  INFO -- : Loading device: e1000e
I, [2022-08-10T20:14:00.994028 #82388]  INFO -- : Run command: brctl show br_ctrl_0002 1>/dev/null 2>&1 || brctl addbr br_ctrl_0002
I, [2022-08-10T20:14:01.009879 #82388]  INFO -- : Run command: ifconfig br_ctrl_0002 up
I, [2022-08-10T20:14:01.021735 #82388]  INFO -- : Run command: sysctl net.bridge.bridge-nf-call-arptables=0
I, [2022-08-10T20:14:01.028019 #82388]  INFO -- : Info dump:
   -- net.bridge.bridge-nf-call-arptables = 0
I, [2022-08-10T20:14:01.028407 #82388]  INFO -- : Run command: sysctl net.bridge.bridge-nf-call-ip6tables=0
I, [2022-08-10T20:14:01.035705 #82388]  INFO -- : Info dump:
   -- net.bridge.bridge-nf-call-ip6tables = 0
I, [2022-08-10T20:14:01.036154 #82388]  INFO -- : Run command: sysctl net.bridge.bridge-nf-call-iptables=0
I, [2022-08-10T20:14:01.040190 #82388]  INFO -- : Info dump:
   -- net.bridge.bridge-nf-call-iptables = 0
I, [2022-08-10T20:14:01.040576 #82388]  INFO -- : Setup configuration:
   Setup ID ................... 0002
   Machine type ............... pc
   QEMU binary ................ /root/hck/src/qemu.git/build/x86_64-softmmu/qemu-system-x86_64
   FW type .................... bios
   Test devices ............... ide-storage
   VM ID ...................... 00
   VM image ................... /root/hck/workspace/hckinstall/qemuhck/Win2019x64/2022_08_10_20_13_54/HLK1809-snapshot.qcow2
   VM VCPUs ................... 2
   VM Memory .................. 4G
   VM display port ............ Vnc 4/5904
   VM monitor port ............ Telnet 10003
I, [2022-08-10T20:14:01.041203 #82388]  INFO -- : Run command: /root/hck/src/qemu.git/build/x86_64-softmmu/qemu-system-x86_64 -enable-kvm -machine pc  -m 4G -smp 2,cores=2  -cpu qemu64,+x2apic,+fsgsbase,model=13 -boot order=cd,menu=on  -nodefaults -no-user-config -usb -device usb-tablet -vnc :4  -global kvm-pit.lost_tick_policy=discard -rtc base=localtime,clock=host,driftfix=slew  -global PIIX4_PM.disable_s3=0 -global PIIX4_PM.disable_s4=0  -monitor telnet::10003,server,nowait -monitor vc -pidfile /tmp/QemuMachine0002_CL0020220810-82388-n0nuad -uuid cdef127c-8795-4e67-95da-8dd0a8000200 -netdev tap,id=cs_r0002_c00,vhost=on,script=/root/hck/workspace/hckinstall/qemuhck/Win2019x64/2022_08_10_20_13_54/control_ifup_0002.sh,downscript=no -device e1000e,netdev=cs_r0002_c00,mac=56:00:02:00:cc:cc,addr=03,bus=pci.0,id=cs_r0002_c00 -netdev tap,id=ws_r0002_c00,vhost=on,script=/root/hck/workspace/hckinstall/qemuhck/Win2019x64/2022_08_10_20_13_54/world_ifup_0002.sh,downscript=no -device e1000e,netdev=ws_r0002_c00,mac=56:00:02:00:dd:dd,bus=pci.0,id=ws_r0002_c00 -drive file=/root/hck/workspace/hckinstall/qemuhck/Win2019x64/2022_08_10_20_13_54/HLK1809-snapshot.qcow2,if=none,id=ide_0002_00 -device ide-hd,drive=ide_0002_00,serial=0010002 -vga std -name QemuMachine0002_CL00
I, [2022-08-10T20:14:06.046731 #82388]  INFO -- : QemuMachine0002_CL00 started with PID 82415
I, [2022-08-10T20:14:06.047165 #82388]  INFO -- : Run command: /root/hck/src/qemu.git/build/qemu-img create -f qcow2 /root/hck/img/HLK1809-C1-Win2019x64.qcow2 150G
I, [2022-08-10T20:14:06.085248 #82388]  INFO -- : Info dump:
   -- Formatting '/root/hck/img/HLK1809-C1-Win2019x64.qcow2', fmt=qcow2 cluster_size=65536 extended_l2=off compression_type=zlib size=161061273600 lazy_refcounts=off refcount_bits=16
I, [2022-08-10T20:14:06.085564 #82388]  INFO -- : Loading device: ide-storage
I, [2022-08-10T20:14:06.085732 #82388]  INFO -- : Loading device: vga-std
I, [2022-08-10T20:14:06.085918 #82388]  INFO -- : Loading device: e1000e
I, [2022-08-10T20:14:06.086557 #82388]  INFO -- : Run command: brctl show br_ctrl_0002 1>/dev/null 2>&1 || brctl addbr br_ctrl_0002
I, [2022-08-10T20:14:06.093306 #82388]  INFO -- : Run command: ifconfig br_ctrl_0002 up
I, [2022-08-10T20:14:06.100186 #82388]  INFO -- : Setup configuration:
   Setup ID ................... 0002
   Machine type ............... pc
   QEMU binary ................ /root/hck/src/qemu.git/build/x86_64-softmmu/qemu-system-x86_64
   FW type .................... bios
   Test devices ............... ide-storage
   VM ID ...................... 01
   VM image ................... /root/hck/img/HLK1809-C1-Win2019x64.qcow2
   VM VCPUs ................... 2
   VM Memory .................. 2G
   VM display port ............ Vnc 5/5905
   VM monitor port ............ Telnet 10004
I, [2022-08-10T20:14:06.100721 #82388]  INFO -- : Run command: /root/hck/src/qemu.git/build/x86_64-softmmu/qemu-system-x86_64 -enable-kvm -machine pc  -m 2G -smp 2,cores=2  -cpu qemu64,+x2apic,+fsgsbase,model=13 -boot order=cd,menu=on  -nodefaults -no-user-config -usb -device usb-tablet -vnc :5  -global kvm-pit.lost_tick_policy=discard -rtc base=localtime,clock=host,driftfix=slew  -global PIIX4_PM.disable_s3=0 -global PIIX4_PM.disable_s4=0  -monitor telnet::10004,server,nowait -monitor vc -pidfile /tmp/QemuMachine0002_CL0120220810-82388-1s9ec3 -uuid cdef127c-8795-4e67-95da-8dd0a8000201 -netdev tap,id=cs_r0002_c01,vhost=on,script=/root/hck/workspace/hckinstall/qemuhck/Win2019x64/2022_08_10_20_13_54/control_ifup_0002.sh,downscript=no -device e1000e,netdev=cs_r0002_c01,mac=56:00:02:01:cc:cc,addr=03,bus=pci.0,id=cs_r0002_c01 -drive file=/root/hck/img/HLK1809-C1-Win2019x64.qcow2,if=none,id=ide_0002_01 -device ide-hd,drive=ide_0002_01,serial=0110002 -vga std -drive file=/root/hck/workspace/hckinstall/qemuhck/Win2019x64/2022_08_10_20_13_54/setup-client.iso,media=cdrom,readonly=on -drive file=/root/hck/iso/en-us_windows_server_2019_updated_jul_2021_x64_dvd_01319f3c.iso,media=cdrom,readonly=on -name QemuMachine0002_CL01
I, [2022-08-10T20:14:11.105872 #82388]  INFO -- : QemuMachine0002_CL01 started with PID 82519
I, [2022-08-10T20:14:11.106104 #82388]  INFO -- : Run command: /root/hck/src/qemu.git/build/qemu-img create -f qcow2 /root/hck/img/HLK1809-C2-Win2019x64.qcow2 150G
I, [2022-08-10T20:14:11.139906 #82388]  INFO -- : Info dump:
   -- Formatting '/root/hck/img/HLK1809-C2-Win2019x64.qcow2', fmt=qcow2 cluster_size=65536 extended_l2=off compression_type=zlib size=161061273600 lazy_refcounts=off refcount_bits=16
I, [2022-08-10T20:14:11.140314 #82388]  INFO -- : Loading device: ide-storage
I, [2022-08-10T20:14:11.140543 #82388]  INFO -- : Loading device: vga-std
I, [2022-08-10T20:14:11.140712 #82388]  INFO -- : Loading device: e1000e
I, [2022-08-10T20:14:11.141364 #82388]  INFO -- : Run command: brctl show br_ctrl_0002 1>/dev/null 2>&1 || brctl addbr br_ctrl_0002
I, [2022-08-10T20:14:11.147434 #82388]  INFO -- : Run command: ifconfig br_ctrl_0002 up
I, [2022-08-10T20:14:11.152307 #82388]  INFO -- : Setup configuration:
   Setup ID ................... 0002
   Machine type ............... pc
   QEMU binary ................ /root/hck/src/qemu.git/build/x86_64-softmmu/qemu-system-x86_64
   FW type .................... bios
   Test devices ............... ide-storage
   VM ID ...................... 02
   VM image ................... /root/hck/img/HLK1809-C2-Win2019x64.qcow2
   VM VCPUs ................... 2
   VM Memory .................. 2G
   VM display port ............ Vnc 6/5906
   VM monitor port ............ Telnet 10005
I, [2022-08-10T20:14:11.152809 #82388]  INFO -- : Run command: /root/hck/src/qemu.git/build/x86_64-softmmu/qemu-system-x86_64 -enable-kvm -machine pc  -m 2G -smp 2,cores=2  -cpu qemu64,+x2apic,+fsgsbase,model=13 -boot order=cd,menu=on  -nodefaults -no-user-config -usb -device usb-tablet -vnc :6  -global kvm-pit.lost_tick_policy=discard -rtc base=localtime,clock=host,driftfix=slew  -global PIIX4_PM.disable_s3=0 -global PIIX4_PM.disable_s4=0  -monitor telnet::10005,server,nowait -monitor vc -pidfile /tmp/QemuMachine0002_CL0220220810-82388-9yk6bk -uuid cdef127c-8795-4e67-95da-8dd0a8000202 -netdev tap,id=cs_r0002_c02,vhost=on,script=/root/hck/workspace/hckinstall/qemuhck/Win2019x64/2022_08_10_20_13_54/control_ifup_0002.sh,downscript=no -device e1000e,netdev=cs_r0002_c02,mac=56:00:02:02:cc:cc,addr=03,bus=pci.0,id=cs_r0002_c02 -drive file=/root/hck/img/HLK1809-C2-Win2019x64.qcow2,if=none,id=ide_0002_02 -device ide-hd,drive=ide_0002_02,serial=0210002 -vga std -drive file=/root/hck/workspace/hckinstall/qemuhck/Win2019x64/2022_08_10_20_13_54/setup-client.iso,media=cdrom,readonly=on -drive file=/root/hck/iso/en-us_windows_server_2019_updated_jul_2021_x64_dvd_01319f3c.iso,media=cdrom,readonly=on -name QemuMachine0002_CL02
I, [2022-08-10T20:14:16.157726 #82388]  INFO -- : QemuMachine0002_CL02 started with PID 82568
I, [2022-08-10T20:14:16.157949 #82388]  INFO -- : Waiting for CL1 installation finished
F, [2022-08-10T21:14:16.186780 #82388] FATAL -- : (Timeout::Error) execution expired
   -- /root/hck/src/AutoHCK.git/lib/engines/hckinstall/hckinstall.rb:216:in `sleep'
   -- /root/hck/src/AutoHCK.git/lib/engines/hckinstall/hckinstall.rb:216:in `block (2 levels) in install_clients'
   -- /usr/local/rvm/rubies/ruby-3.0.4/lib/ruby/3.0.0/timeout.rb:112:in `timeout'
   -- /root/hck/src/AutoHCK.git/lib/engines/hckinstall/hckinstall.rb:214:in `block in install_clients'
   -- /root/hck/src/AutoHCK.git/lib/engines/hckinstall/hckinstall.rb:213:in `each'
   -- /root/hck/src/AutoHCK.git/lib/engines/hckinstall/hckinstall.rb:213:in `install_clients'
   -- /root/hck/src/AutoHCK.git/lib/engines/hckinstall/hckinstall.rb:319:in `run'
   -- /root/hck/src/AutoHCK.git/lib/engines/engine.rb:58:in `run'
   -- /root/hck/src/AutoHCK.git/lib/project.rb:68:in `run'
   -- bin/auto_hck:29:in `<module:AutoHCK>'
   -- bin/auto_hck:13:in `<main>'
I, [2022-08-10T21:14:16.188554 #82388]  INFO -- : Sending powerdown signal to QemuMachine0002_CL01 via qemu-monitor
I, [2022-08-10T21:14:46.231812 #82388]  INFO -- : QemuMachine0002_CL01 is not alive
I, [2022-08-10T21:14:46.232333 #82388]  INFO -- : Run command: ifconfig br_ctrl_0002 down
I, [2022-08-10T21:14:46.243576 #82388]  INFO -- : Command finished with code: 0
I, [2022-08-10T21:14:46.243969 #82388]  INFO -- : Run command: brctl delbr br_ctrl_0002
I, [2022-08-10T21:14:46.296341 #82388]  INFO -- : Command finished with code: 0
I, [2022-08-10T21:14:46.297368 #82388]  INFO -- : Sending powerdown signal to QemuMachine0002_CL02 via qemu-monitor
I, [2022-08-10T21:15:16.329949 #82388]  INFO -- : Sending powerdown signal to QemuMachine0002_CL02 via qemu-monitor
I, [2022-08-10T21:15:46.360600 #82388]  INFO -- : Sending powerdown signal to QemuMachine0002_CL02 via qemu-monitor
I, [2022-08-10T21:16:16.392333 #82388]  INFO -- : QemuMachine0002_CL02 soft abort failed, hard aborting...
I, [2022-08-10T21:16:16.392509 #82388]  INFO -- : Sending quit signal to QemuMachine0002_CL02 via qemu-monitor
I, [2022-08-10T21:16:46.411948 #82388]  INFO -- : QemuMachine0002_CL02 is not alive
I, [2022-08-10T21:16:46.412605 #82388]  INFO -- : Run command: ifconfig br_ctrl_0002 down
W, [2022-08-10T21:16:46.447931 #82388]  WARN -- : Error dump:
   -- br_ctrl_0002: ERROR while getting interface flags: No such device
I, [2022-08-10T21:16:46.448128 #82388]  INFO -- : Command finished with code: 255
I, [2022-08-10T21:16:46.448267 #82388]  INFO -- : Run command: brctl delbr br_ctrl_0002
W, [2022-08-10T21:16:46.451691 #82388]  WARN -- : Error dump:
   -- bridge br_ctrl_0002 doesn't exist; can't delete it
I, [2022-08-10T21:16:46.451822 #82388]  INFO -- : Command finished with code: 1
I, [2022-08-10T21:16:46.452085 #82388]  INFO -- : Aborting HLK Studio
I, [2022-08-10T21:16:46.452299 #82388]  INFO -- : Aborting studio VM
I, [2022-08-10T21:16:46.452392 #82388]  INFO -- : Sending powerdown signal to QemuMachine0002_CL00 via qemu-monitor
I, [2022-08-10T21:17:16.478898 #82388]  INFO -- : Sending powerdown signal to QemuMachine0002_CL00 via qemu-monitor
I, [2022-08-10T21:17:46.486536 #82388]  INFO -- : Sending powerdown signal to QemuMachine0002_CL00 via qemu-monitor
I, [2022-08-10T21:18:16.503126 #82388]  INFO -- : QemuMachine0002_CL00 soft abort failed, hard aborting...
I, [2022-08-10T21:18:16.503576 #82388]  INFO -- : Sending quit signal to QemuMachine0002_CL00 via qemu-monitor
I, [2022-08-10T21:18:46.514907 #82388]  INFO -- : QemuMachine0002_CL00 is not alive
I, [2022-08-10T21:18:46.515353 #82388]  INFO -- : Run command: ifconfig br_ctrl_0002 down
W, [2022-08-10T21:18:46.527600 #82388]  WARN -- : Error dump:
   -- br_ctrl_0002: ERROR while getting interface flags: No such device
I, [2022-08-10T21:18:46.527938 #82388]  INFO -- : Command finished with code: 255
I, [2022-08-10T21:18:46.528105 #82388]  INFO -- : Run command: brctl delbr br_ctrl_0002
W, [2022-08-10T21:18:46.532329 #82388]  WARN -- : Error dump:
   -- bridge br_ctrl_0002 doesn't exist; can't delete it
I, [2022-08-10T21:18:46.532525 #82388]  INFO -- : Command finished with code: 1
I, [2022-08-10T21:18:46.532977 #82388]  INFO -- : Aborting HLKClient CL1
I, [2022-08-10T21:18:46.533113 #82388]  INFO -- : Aborting client CL1 VM
I, [2022-08-10T21:18:46.533200 #82388]  INFO -- : QemuMachine0002_CL01 is not alive
I, [2022-08-10T21:18:46.533268 #82388]  INFO -- : Run command: ifconfig br_ctrl_0002 down
W, [2022-08-10T21:18:46.541062 #82388]  WARN -- : Error dump:
   -- br_ctrl_0002: ERROR while getting interface flags: No such device
I, [2022-08-10T21:18:46.541260 #82388]  INFO -- : Command finished with code: 255
I, [2022-08-10T21:18:46.541397 #82388]  INFO -- : Run command: brctl delbr br_ctrl_0002
W, [2022-08-10T21:18:46.544549 #82388]  WARN -- : Error dump:
   -- bridge br_ctrl_0002 doesn't exist; can't delete it
I, [2022-08-10T21:18:46.544692 #82388]  INFO -- : Command finished with code: 1
I, [2022-08-10T21:18:46.544834 #82388]  INFO -- : Aborting HLKClient CL2
I, [2022-08-10T21:18:46.544922 #82388]  INFO -- : Aborting client CL2 VM
I, [2022-08-10T21:18:46.545018 #82388]  INFO -- : QemuMachine0002_CL02 is not alive
I, [2022-08-10T21:18:46.545146 #82388]  INFO -- : Run command: ifconfig br_ctrl_0002 down
W, [2022-08-10T21:18:46.553520 #82388]  WARN -- : Error dump:
   -- br_ctrl_0002: ERROR while getting interface flags: No such device
I, [2022-08-10T21:18:46.553703 #82388]  INFO -- : Command finished with code: 255
I, [2022-08-10T21:18:46.553847 #82388]  INFO -- : Run command: brctl delbr br_ctrl_0002
W, [2022-08-10T21:18:46.557570 #82388]  WARN -- : Error dump:
   -- bridge br_ctrl_0002 doesn't exist; can't delete it
I, [2022-08-10T21:18:46.557734 #82388]  INFO -- : Command finished with code: 1
I, [2022-08-10T21:18:46.558772 #82388]  INFO -- : Aborting HLK Studio
I, [2022-08-10T21:18:46.558874 #82388]  INFO -- : Aborting studio VM
I, [2022-08-10T21:18:46.558969 #82388]  INFO -- : QemuMachine0002_CL00 is not alive
I, [2022-08-10T21:18:46.559056 #82388]  INFO -- : Run command: ifconfig br_ctrl_0002 down
W, [2022-08-10T21:18:46.566677 #82388]  WARN -- : Error dump:
   -- br_ctrl_0002: ERROR while getting interface flags: No such device
I, [2022-08-10T21:18:46.566893 #82388]  INFO -- : Command finished with code: 255
I, [2022-08-10T21:18:46.567085 #82388]  INFO -- : Run command: brctl delbr br_ctrl_0002
W, [2022-08-10T21:18:46.571179 #82388]  WARN -- : Error dump:
   -- bridge br_ctrl_0002 doesn't exist; can't delete it
I, [2022-08-10T21:18:46.571386 #82388]  INFO -- : Command finished with code: 1
I, [2022-08-10T21:18:46.572079 #82388]  INFO -- : Releasing ID: 2
[root@localhost AutoHCK.git]# ls /root/hck/img/
HLK1809-C1-Win2019x64.qcow2  HLK1809-C2-Win2019x64.qcow2  HLK1809.qcow2
[root@localhost AutoHCK.git]# ls /root/hck/img/ -l
total 15014348
-rw-r--r--. 1 root root 7749369856 Aug 10 21:14 HLK1809-C1-Win2019x64.qcow2
-rw-r--r--. 1 root root 7625244672 Aug 10 21:16 HLK1809-C2-Win2019x64.qcow2
-rw-r--r--. 1 root root     199008 Aug 10 20:09 HLK1809.qcow2
kostyanf14 commented 2 years ago

Hi @elmarco,

The main error is:

I, [2022-08-10T20:14:16.157949 #82388]  INFO -- : Waiting for CL1 installation finished
F, [2022-08-10T21:14:16.186780 #82388] FATAL -- : (Timeout::Error) execution expired

There are 2 possible reasons:

  1. STUDIO Image is corrupted and a Client can't install the HLK component.
  2. You /root/hck/img/ storage is slow and 1 hour (default timeout) is not enough for installation (client_install_timeout value in lib/engines/hckinstall/hckinstall.json).

During installation, you can connect to clients/studio via VNC and look at what happens with guests. If everything is OK you always should see the PowerShell terminal. If you see a clean desktop - some error happened. All logs of HLK-Setup-Scripts are available in the administrator temp directory (PowerShell path - $env:TEMP\install-$stage-$time.log)

kostyanf14 commented 11 months ago

The original issue was fixed. If there are any other problems please open a new issue.