Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

First time run: Error starting kubernetes (v0.4.1) #562

Closed
ahmetb opened this issue Aug 31, 2021 · 27 comments
Closed

First time run: Error starting kubernetes (v0.4.1) #562

ahmetb opened this issue Aug 31, 2021 · 27 comments
Labels
kind/bug Something isn't working
Milestone

Comments

@ahmetb
Copy link

ahmetb commented Aug 31, 2021

There was an unknown error starting Kubernetes: Error: /Applications/Rancher Desktop.app/Contents/Resources/resources/darwin/lima/bin/limactl exited with code 1

Happened in v0.4.1

image

@mooneye14
Copy link

Getting the same

@jandubois
Copy link
Member

Could you please verify that this is not a duplicate of #535:

$ id
uid=501(jan) gid=20(staff) groups=20(staff), [...]

If your uid is larger than 256000 (which can happen when the ids are assigned by a directory service), then you are hitting a known issue that has already been fixed for the next release.

If this is not the problem, then please attach the lime log files:

$ ls ~/Library/State/rancher-desktop/lima/rancher-desktop/*.log
/Users/jan/Library/State/rancher-desktop/lima/rancher-desktop/ha.stderr.log
/Users/jan/Library/State/rancher-desktop/lima/rancher-desktop/ha.stdout.log
/Users/jan/Library/State/rancher-desktop/lima/rancher-desktop/serial.log

@mpptx
Copy link

mpptx commented Sep 1, 2021

Same issue. My uid is 501.

~/Library/State/rancher-desktop/lima/rancher-desktop/ha.stderr.log:

{"level":"info","msg":"Starting QEMU (hint: to watch the boot progress, see \"/Users/user/Library/State/rancher-desktop/lima/rancher-desktop/serial.log\")","time":"2021-09-01T15:49:39+02:00"}
{"level":"debug","msg":"qCmd.Args: [/Applications/Rancher Desktop.app/Contents/Resources/resources/darwin/lima/bin/qemu-system-x86_64 -cpu Haswell-v4 -machine q35,accel=hvf -smp 2,sockets=1,cores=2,threads=1 -m 4096 -boot order=d,splash-time=0,menu=on -drive file=/Users/user/Library/State/rancher-desktop/lima/rancher-desktop/basedisk,media=cdrom,readonly=on -drive file=/Users/user/Library/State/rancher-desktop/lima/rancher-desktop/diffdisk,if=virtio -cdrom /Users/user/Library/State/rancher-desktop/lima/rancher-desktop/cidata.iso -netdev user,id=net0,net=192.168.5.0/24,dhcpstart=192.168.5.15,hostfwd=tcp:127.0.0.1:62191-:22 -device virtio-net-pci,netdev=net0,mac=52:55:55:fa:8e:a5 -device virtio-rng-pci -display none -device virtio-vga -device virtio-keyboard-pci -device virtio-mouse-pci -parallel none -chardev socket,id=char-serial,path=/Users/user/Library/State/rancher-desktop/lima/rancher-desktop/serial.sock,server,nowait,logfile=/Users/user/Library/State/rancher-desktop/lima/rancher-desktop/serial.log -serial chardev:char-serial -chardev socket,id=char-qmp,path=/Users/user/Library/State/rancher-desktop/lima/rancher-desktop/qmp.sock,server,nowait -qmp chardev:char-qmp -name lima-rancher-desktop -pidfile /Users/user/Library/State/rancher-desktop/lima/rancher-desktop/qemu.pid]","time":"2021-09-01T15:49:39+02:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-09-01T15:49:39+02:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-09-01T15:49:39+02:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"ssh: connect to host 127.0.0.1 port 62191: Connection refused\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"ssh: connect to host 127.0.0.1 port 62191: Connection refused\\r\\n\": exit status 255","time":"2021-09-01T15:49:39+02:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: -chardev socket,id=char-serial,path=/Users/user/Library/State/rancher-desktop/lima/rancher-desktop/serial.sock,server,nowait,logfile=/Users/user/Library/State/rancher-desktop/lima/rancher-desktop/serial.log: warning: short-form boolean option 'server' deprecated","time":"2021-09-01T15:49:41+02:00"}
{"level":"debug","msg":"qemu[stderr]: Please use server=on instead","time":"2021-09-01T15:49:41+02:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: -chardev socket,id=char-serial,path=/Users/user/Library/State/rancher-desktop/lima/rancher-desktop/serial.sock,server,nowait,logfile=/Users/user/Library/State/rancher-desktop/lima/rancher-desktop/serial.log: warning: short-form boolean option 'nowait' deprecated","time":"2021-09-01T15:49:41+02:00"}
{"level":"debug","msg":"qemu[stderr]: Please use wait=off instead","time":"2021-09-01T15:49:41+02:00"}
{"error":"signal: abort trap","level":"info","msg":"QEMU has exited","time":"2021-09-01T15:49:41+02:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: -chardev socket,id=char-qmp,path=/Users/user/Library/State/rancher-desktop/lima/rancher-desktop/qmp.sock,server,nowait: warning: short-form boolean option 'server' deprecated","time":"2021-09-01T15:49:41+02:00"}
{"level":"debug","msg":"qemu[stderr]: Please use server=on instead","time":"2021-09-01T15:49:41+02:00"}
signal: abort trap

~/Library/State/rancher-desktop/lima/rancher-desktop/ha.stdout.log:

{"time":"2021-09-01T15:49:39.703442+02:00","status":{"sshLocalPort":62191}}
{"time":"2021-09-01T15:49:41.557688+02:00","status":{"exiting":true}}

~/Library/State/rancher-desktop/lima/rancher-desktop/serial.log:

(file is empty)

@mooneye14
Copy link

mooneye14 commented Sep 1, 2021

My uid is 503 and this is on a Macbook 13" 2019 Core i5:

~/Library/State/rancher-desktop/lima/rancher-desktop/ha.stderr.log
{"level":"info","msg":"Starting QEMU (hint: to watch the boot progress, see \"/Users/user/Library/State/rancher-desktop/lima/rancher-desktop/serial.log\")","time":"2021-08-31T16:12:25-05:00"}
{"level":"debug","msg":"qCmd.Args: [/Applications/Rancher Desktop.app/Contents/Resources/resources/darwin/lima/bin/qemu-system-x86_64 -cpu Haswell-v4 -machine q35,accel=hvf -smp 4,sockets=1,cores=4,threads=1 -m 6144 -boot order=d,splash-time=0,menu=on -drive file=/Users/user/Library/State/rancher-desktop/lima/rancher-desktop/basedisk,media=cdrom,readonly=on -drive file=/Users/user/Library/State/rancher-desktop/lima/rancher-desktop/diffdisk,if=virtio -cdrom /Users/user/Library/State/rancher-desktop/lima/rancher-desktop/cidata.iso -netdev user,id=net0,net=192.168.5.0/24,dhcpstart=192.168.5.15,hostfwd=tcp:127.0.0.1:57605-:22 -device virtio-net-pci,netdev=net0,mac=52:55:55:7c:ed:15 -device virtio-rng-pci -display none -device virtio-vga -device virtio-keyboard-pci -device virtio-mouse-pci -parallel none -chardev socket,id=char-serial,path=/Users/user/Library/State/rancher-desktop/lima/rancher-desktop/serial.sock,server,nowait,logfile=/Users/user/Library/State/rancher-desktop/lima/rancher-desktop/serial.log -serial chardev:char-serial -chardev socket,id=char-qmp,path=/Users/user/Library/State/rancher-desktop/lima/rancher-desktop/qmp.sock,server,nowait -qmp chardev:char-qmp -name lima-rancher-desktop -pidfile /Users/user/Library/State/rancher-desktop/lima/rancher-desktop/qemu.pid]","time":"2021-08-31T16:12:25-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:12:25-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:12:25-05:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: -chardev socket,id=char-serial,path=/Users/user/Library/State/rancher-desktop/lima/rancher-desktop/serial.sock,server,nowait,logfile=/Users/user/Library/State/rancher-desktop/lima/rancher-desktop/serial.log: warning: short-form boolean option 'server' deprecated","time":"2021-08-31T16:12:25-05:00"}
{"level":"debug","msg":"qemu[stderr]: Please use server=on instead","time":"2021-08-31T16:12:25-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"ssh: connect to host 127.0.0.1 port 57605: Connection refused\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"ssh: connect to host 127.0.0.1 port 57605: Connection refused\\r\\n\": exit status 255","time":"2021-08-31T16:12:25-05:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: -chardev socket,id=char-serial,path=/Users/user/Library/State/rancher-desktop/lima/rancher-desktop/serial.sock,server,nowait,logfile=/Users/user/Library/State/rancher-desktop/lima/rancher-desktop/serial.log: warning: short-form boolean option 'nowait' deprecated","time":"2021-08-31T16:12:25-05:00"}
{"level":"debug","msg":"qemu[stderr]: Please use wait=off instead","time":"2021-08-31T16:12:25-05:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: -chardev socket,id=char-qmp,path=/Users/user/Library/State/rancher-desktop/lima/rancher-desktop/qmp.sock,server,nowait: warning: short-form boolean option 'server' deprecated","time":"2021-08-31T16:12:25-05:00"}
{"level":"debug","msg":"qemu[stderr]: Please use server=on instead","time":"2021-08-31T16:12:25-05:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: -chardev socket,id=char-qmp,path=/Users/user/Library/State/rancher-desktop/lima/rancher-desktop/qmp.sock,server,nowait: warning: short-form boolean option 'nowait' deprecated","time":"2021-08-31T16:12:25-05:00"}
{"level":"debug","msg":"qemu[stderr]: Please use wait=off instead","time":"2021-08-31T16:12:25-05:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: warning: host doesn't support requested feature: CPUID.01H:ECX.x2apic [bit 21]","time":"2021-08-31T16:12:25-05:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: warning: host doesn't support requested feature: CPUID.01H:ECX.tsc-deadline [bit 24]","time":"2021-08-31T16:12:25-05:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: warning: host doesn't support requested feature: CPUID.07H:EBX.invpcid [bit 10]","time":"2021-08-31T16:12:25-05:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: warning: host doesn't support requested feature: CPUID.07H:EDX.spec-ctrl [bit 26]","time":"2021-08-31T16:12:25-05:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: warning: host doesn't support requested feature: CPUID.80000001H:EDX.rdtscp [bit 27]","time":"2021-08-31T16:12:25-05:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: warning: host doesn't support requested feature: CPUID.01H:ECX.x2apic [bit 21]","time":"2021-08-31T16:12:25-05:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: warning: host doesn't support requested feature: CPUID.01H:ECX.tsc-deadline [bit 24]","time":"2021-08-31T16:12:25-05:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: warning: host doesn't support requested feature: CPUID.07H:EBX.invpcid [bit 10]","time":"2021-08-31T16:12:25-05:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: warning: host doesn't support requested feature: CPUID.07H:EDX.spec-ctrl [bit 26]","time":"2021-08-31T16:12:25-05:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: warning: host doesn't support requested feature: CPUID.80000001H:EDX.rdtscp [bit 27]","time":"2021-08-31T16:12:25-05:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: warning: host doesn't support requested feature: CPUID.01H:ECX.x2apic [bit 21]","time":"2021-08-31T16:12:25-05:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: warning: host doesn't support requested feature: CPUID.01H:ECX.tsc-deadline [bit 24]","time":"2021-08-31T16:12:25-05:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: warning: host doesn't support requested feature: CPUID.07H:EBX.invpcid [bit 10]","time":"2021-08-31T16:12:25-05:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: warning: host doesn't support requested feature: CPUID.07H:EDX.spec-ctrl [bit 26]","time":"2021-08-31T16:12:25-05:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: warning: host doesn't support requested feature: CPUID.80000001H:EDX.rdtscp [bit 27]","time":"2021-08-31T16:12:25-05:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: warning: host doesn't support requested feature: CPUID.01H:ECX.x2apic [bit 21]","time":"2021-08-31T16:12:25-05:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: warning: host doesn't support requested feature: CPUID.01H:ECX.tsc-deadline [bit 24]","time":"2021-08-31T16:12:25-05:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: warning: host doesn't support requested feature: CPUID.07H:EBX.invpcid [bit 10]","time":"2021-08-31T16:12:25-05:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: warning: host doesn't support requested feature: CPUID.07H:EDX.spec-ctrl [bit 26]","time":"2021-08-31T16:12:25-05:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: warning: host doesn't support requested feature: CPUID.80000001H:EDX.rdtscp [bit 27]","time":"2021-08-31T16:12:25-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:12:35-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:12:35-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"kex_exchange_identification: read: Connection reset by peer\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"kex_exchange_identification: read: Connection reset by peer\\r\\n\": exit status 255","time":"2021-08-31T16:12:35-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:12:45-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:12:45-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:12:45-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:12:55-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:12:55-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:12:55-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:13:05-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:13:05-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:13:05-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:13:15-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:13:15-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:13:15-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:13:25-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:13:25-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:13:26-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:13:36-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:13:36-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:13:36-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:13:46-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:13:46-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:13:46-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:13:56-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:13:56-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:13:56-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:14:06-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:14:06-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:14:06-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:14:16-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:14:16-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:14:16-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:14:26-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:14:26-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:14:26-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:14:37-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:14:37-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:14:37-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:14:47-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:14:47-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:14:47-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:14:57-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:14:57-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:14:57-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:15:07-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:15:07-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:15:07-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:15:17-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:15:17-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:15:17-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:15:27-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:15:27-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:15:27-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:15:37-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:15:37-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:15:38-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:15:48-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:15:48-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:15:48-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:15:58-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:15:58-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:15:58-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:16:08-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:16:08-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:16:08-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:16:18-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:16:18-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:16:18-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:16:28-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:16:28-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:16:28-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:16:38-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:16:38-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:16:38-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:16:48-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:16:48-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:16:49-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:16:59-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:16:59-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:16:59-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:17:09-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:17:09-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:17:09-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:17:19-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:17:19-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:17:19-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:17:29-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:17:29-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:17:29-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:17:39-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:17:39-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:17:39-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:17:49-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:17:49-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:17:50-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:18:00-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:18:00-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:18:00-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:18:10-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:18:10-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:18:10-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:18:20-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:18:20-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:18:20-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:18:30-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:18:30-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:18:30-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:18:40-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:18:40-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:18:40-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:18:50-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:18:50-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:18:50-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:19:00-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:19:00-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:19:01-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:19:11-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:19:11-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:19:11-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:19:21-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:19:21-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:19:21-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:19:31-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:19:31-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:19:31-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:19:41-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:19:41-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:19:41-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:19:51-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:19:51-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:19:51-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:20:01-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:20:01-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:20:02-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:20:12-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:20:12-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:20:12-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:20:22-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:20:22-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:20:22-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:20:32-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:20:32-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:20:32-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:20:42-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:20:42-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:20:42-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:20:52-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:20:52-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:20:52-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:21:02-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:21:02-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:21:02-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:21:12-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:21:12-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:21:13-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:21:23-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:21:23-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:21:23-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:21:33-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:21:33-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:21:33-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:21:43-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:21:43-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:21:43-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:21:53-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:21:53-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:21:53-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:22:03-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:22:03-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:22:03-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:22:13-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:22:13-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:22:13-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-08-31T16:22:23-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-08-31T16:22:23-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:22:24-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 2 of 4: \"sshfs binary to be installed\"","time":"2021-08-31T16:22:24-05:00"}
{"level":"debug","msg":"executing script \"sshfs binary to be installed\"","time":"2021-08-31T16:22:24-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"sshfs binary to be installed\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:22:24-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 2 of 4: \"sshfs binary to be installed\"","time":"2021-08-31T16:22:34-05:00"}
{"level":"debug","msg":"executing script \"sshfs binary to be installed\"","time":"2021-08-31T16:22:34-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"sshfs binary to be installed\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:22:34-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 2 of 4: \"sshfs binary to be installed\"","time":"2021-08-31T16:22:44-05:00"}
{"level":"debug","msg":"executing script \"sshfs binary to be installed\"","time":"2021-08-31T16:22:44-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"sshfs binary to be installed\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:22:44-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 2 of 4: \"sshfs binary to be installed\"","time":"2021-08-31T16:22:54-05:00"}
{"level":"debug","msg":"executing script \"sshfs binary to be installed\"","time":"2021-08-31T16:22:54-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"sshfs binary to be installed\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:22:54-05:00"}
{"level":"info","msg":"Received SIGINT, shutting down the host agent","time":"2021-08-31T16:23:00-05:00"}
{"level":"info","msg":"Shutting down the host agent","time":"2021-08-31T16:23:00-05:00"}
{"level":"debug","msg":"shutting down the SSH master","time":"2021-08-31T16:23:00-05:00"}
{"error":"failed to execute `ssh -O exit -p 57605 127.0.0.1`, out=\"Control socket connect(/Users/user/Library/State/rancher-desktop/lima/rancher-desktop/ssh.sock): No such file or directory\\r\\n\": exit status 255","level":"warning","msg":"failed to exit SSH master","time":"2021-08-31T16:23:00-05:00"}
{"level":"info","msg":"Shutting down QEMU with ACPI","time":"2021-08-31T16:23:00-05:00"}
{"level":"info","msg":"Sending QMP system_powerdown command","time":"2021-08-31T16:23:00-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 2 of 4: \"sshfs binary to be installed\"","time":"2021-08-31T16:23:04-05:00"}
{"level":"debug","msg":"executing script \"sshfs binary to be installed\"","time":"2021-08-31T16:23:04-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"sshfs binary to be installed\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:23:04-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 2 of 4: \"sshfs binary to be installed\"","time":"2021-08-31T16:23:14-05:00"}
{"level":"debug","msg":"executing script \"sshfs binary to be installed\"","time":"2021-08-31T16:23:14-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"sshfs binary to be installed\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:23:14-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 2 of 4: \"sshfs binary to be installed\"","time":"2021-08-31T16:23:24-05:00"}
{"level":"debug","msg":"executing script \"sshfs binary to be installed\"","time":"2021-08-31T16:23:24-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"sshfs binary to be installed\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:23:24-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 2 of 4: \"sshfs binary to be installed\"","time":"2021-08-31T16:23:34-05:00"}
{"level":"debug","msg":"executing script \"sshfs binary to be installed\"","time":"2021-08-31T16:23:34-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"sshfs binary to be installed\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:23:35-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 2 of 4: \"sshfs binary to be installed\"","time":"2021-08-31T16:23:45-05:00"}
{"level":"debug","msg":"executing script \"sshfs binary to be installed\"","time":"2021-08-31T16:23:45-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"sshfs binary to be installed\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:23:45-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 2 of 4: \"sshfs binary to be installed\"","time":"2021-08-31T16:23:55-05:00"}
{"level":"debug","msg":"executing script \"sshfs binary to be installed\"","time":"2021-08-31T16:23:55-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"sshfs binary to be installed\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:23:55-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 2 of 4: \"sshfs binary to be installed\"","time":"2021-08-31T16:24:05-05:00"}
{"level":"debug","msg":"executing script \"sshfs binary to be installed\"","time":"2021-08-31T16:24:05-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"sshfs binary to be installed\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:24:05-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 2 of 4: \"sshfs binary to be installed\"","time":"2021-08-31T16:24:15-05:00"}
{"level":"debug","msg":"executing script \"sshfs binary to be installed\"","time":"2021-08-31T16:24:15-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"sshfs binary to be installed\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:24:15-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 2 of 4: \"sshfs binary to be installed\"","time":"2021-08-31T16:24:25-05:00"}
{"level":"debug","msg":"executing script \"sshfs binary to be installed\"","time":"2021-08-31T16:24:25-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"sshfs binary to be installed\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:24:25-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 2 of 4: \"sshfs binary to be installed\"","time":"2021-08-31T16:24:35-05:00"}
{"level":"debug","msg":"executing script \"sshfs binary to be installed\"","time":"2021-08-31T16:24:35-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"sshfs binary to be installed\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:24:36-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 2 of 4: \"sshfs binary to be installed\"","time":"2021-08-31T16:24:46-05:00"}
{"level":"debug","msg":"executing script \"sshfs binary to be installed\"","time":"2021-08-31T16:24:46-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"sshfs binary to be installed\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:24:46-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 2 of 4: \"sshfs binary to be installed\"","time":"2021-08-31T16:24:56-05:00"}
{"level":"debug","msg":"executing script \"sshfs binary to be installed\"","time":"2021-08-31T16:24:56-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"sshfs binary to be installed\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:24:56-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 2 of 4: \"sshfs binary to be installed\"","time":"2021-08-31T16:25:06-05:00"}
{"level":"debug","msg":"executing script \"sshfs binary to be installed\"","time":"2021-08-31T16:25:06-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"sshfs binary to be installed\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:25:06-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 2 of 4: \"sshfs binary to be installed\"","time":"2021-08-31T16:25:16-05:00"}
{"level":"debug","msg":"executing script \"sshfs binary to be installed\"","time":"2021-08-31T16:25:16-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"sshfs binary to be installed\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:25:16-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 2 of 4: \"sshfs binary to be installed\"","time":"2021-08-31T16:25:26-05:00"}
{"level":"debug","msg":"executing script \"sshfs binary to be installed\"","time":"2021-08-31T16:25:26-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"sshfs binary to be installed\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:25:26-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 2 of 4: \"sshfs binary to be installed\"","time":"2021-08-31T16:25:36-05:00"}
{"level":"debug","msg":"executing script \"sshfs binary to be installed\"","time":"2021-08-31T16:25:36-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"sshfs binary to be installed\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:25:36-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 2 of 4: \"sshfs binary to be installed\"","time":"2021-08-31T16:25:47-05:00"}
{"level":"debug","msg":"executing script \"sshfs binary to be installed\"","time":"2021-08-31T16:25:47-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"sshfs binary to be installed\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:25:47-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 2 of 4: \"sshfs binary to be installed\"","time":"2021-08-31T16:25:57-05:00"}
{"level":"debug","msg":"executing script \"sshfs binary to be installed\"","time":"2021-08-31T16:25:57-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"sshfs binary to be installed\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-08-31T16:25:57-05:00"}
{"level":"warning","msg":"QEMU did not exit in 3m0s, forcibly killing QEMU","time":"2021-08-31T16:26:00-05:00"}
{"error":"signal: killed","level":"info","msg":"QEMU has exited, after killing forcibly","time":"2021-08-31T16:26:00-05:00"}
signal: killed
~/Library/State/rancher-desktop/lima/rancher-desktop/ha.stdout.log
{"time":"2021-08-31T16:12:25.085109-05:00","status":{"sshLocalPort":57605}}
{"time":"2021-08-31T16:26:00.97573-05:00","status":{"exiting":true}}
~/Library/State/rancher-desktop/lima/rancher-desktop/serial.log
ISOLINUX 6.04 6.04-pre1  Copyright (C) 1994-2015 H. Peter Anvin et al
boot:
Loading /boot/vmlinuz-virt... ok
Loading /boot/initramfs-virt...ok
[    0.000000] Linux version 5.10.38-0-virt (buildozer@build-3-13-x86_64) (gcc (Alpine 10.2.1_pre1) 10.2.1 20201203, GNU ld (GNU Binutils) 2.35.2) #1-Alpine SMP Fri, 21 May 2021 11:41:13 UTC
[    0.000000] Command line: BOOT_IMAGE=/boot/vmlinuz-virt modules=loop,squashfs,sd-mod,usb-storage console=tty0 console=ttyS0,115200 initrd=/boot/initramfs-virt
[    0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'
[    0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'
[    0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'
[    0.000000] x86/fpu: xstate_offset[2]:  576, xstate_sizes[2]:  256
[    0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format.
[    0.000000] BIOS-provided physical RAM map:
[    0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable
[    0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved
[    0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000007ffdbfff] usable
[    0.000000] BIOS-e820: [mem 0x000000007ffdc000-0x000000007fffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
[    0.000000] BIOS-e820: [mem 0x0000000100000000-0x00000001ffffffff] usable
[    0.000000] NX (Execute Disable) protection: active
[    0.000000] SMBIOS 2.8 present.
[    0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.14.0-0-g155821a1990b-prebuilt.qemu.org 04/01/2014
[    0.000000] last_pfn = 0x200000 max_arch_pfn = 0x400000000
[    0.000000] Disabled
[    0.000000] x86/PAT: MTRRs disabled, skipping PAT initialization too.
[    0.000000] CPU MTRRs all blank - virtualized system.
[    0.000000] x86/PAT: Configuration [0-7]: WB  WT  UC- UC  WB  WT  UC- UC
[    0.000000] last_pfn = 0x7ffdc max_arch_pfn = 0x400000000
[    0.000000] RAMDISK: [mem 0x7fa34000-0x7ffdbfff]
[    0.000000] ACPI: Early table checksum verification disabled
[    0.000000] ACPI: RSDP 0x00000000000F5890 000014 (v00 BOCHS )
[    0.000000] ACPI: RSDT 0x000000007FFE2234 000038 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
[    0.000000] ACPI: FACP 0x000000007FFE2014 0000F4 (v03 BOCHS  BXPC     00000001 BXPC 00000001)
[    0.000000] ACPI: DSDT 0x000000007FFE0040 001FD4 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
[    0.000000] ACPI: FACS 0x000000007FFE0000 000040
[    0.000000] ACPI: APIC 0x000000007FFE2108 000090 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
[    0.000000] ACPI: HPET 0x000000007FFE2198 000038 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
[    0.000000] ACPI: MCFG 0x000000007FFE21D0 00003C (v01 BOCHS  BXPC     00000001 BXPC 00000001)
[    0.000000] ACPI: WAET 0x000000007FFE220C 000028 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
[    0.000000] ACPI: Reserving FACP table memory at [mem 0x7ffe2014-0x7ffe2107]
[    0.000000] ACPI: Reserving DSDT table memory at [mem 0x7ffe0040-0x7ffe2013]
[    0.000000] ACPI: Reserving FACS table memory at [mem 0x7ffe0000-0x7ffe003f]
[    0.000000] ACPI: Reserving APIC table memory at [mem 0x7ffe2108-0x7ffe2197]
[    0.000000] ACPI: Reserving HPET table memory at [mem 0x7ffe2198-0x7ffe21cf]
[    0.000000] ACPI: Reserving MCFG table memory at [mem 0x7ffe21d0-0x7ffe220b]
[    0.000000] ACPI: Reserving WAET table memory at [mem 0x7ffe220c-0x7ffe2233]
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
[    0.000000]   DMA32    [mem 0x0000000001000000-0x00000000ffffffff]
[    0.000000]   Normal   [mem 0x0000000100000000-0x00000001ffffffff]
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000001000-0x000000000009efff]
[    0.000000]   node   0: [mem 0x0000000000100000-0x000000007ffdbfff]
[    0.000000]   node   0: [mem 0x0000000100000000-0x00000001ffffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x00000001ffffffff]
[    0.000000]   DMA zone: 28770 pages in unavailable ranges
[    0.000000]   DMA32 zone: 36 pages in unavailable ranges
[    0.000000] ACPI: PM-Timer IO Port: 0x608
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
[    0.000000] IOAPIC[0]: apic_id 0, version 32, address 0xfec00000, GSI 0-23
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
[    0.000000] Using ACPI (MADT) for SMP configuration information
[    0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000
[    0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs
[    0.000000] [mem 0xc0000000-0xfed1bfff] available for PCI devices
[    0.000000] Booting paravirtualized kernel on bare hardware
[    0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.000000] setup_percpu: NR_CPUS:256 nr_cpumask_bits:256 nr_cpu_ids:4 nr_node_ids:1
[    0.000000] percpu: Embedded 53 pages/cpu s179736 r8192 d29160 u524288
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 1548133
[    0.000000] Kernel command line: BOOT_IMAGE=/boot/vmlinuz-virt modules=loop,squashfs,sd-mod,usb-storage console=tty0 console=ttyS0,115200 initrd=/boot/initramfs-virt
[    0.000000] printk: log_buf_len individual max cpu contribution: 4096 bytes
[    0.000000] printk: log_buf_len total cpu_extra contributions: 12288 bytes
[    0.000000] printk: log_buf_len min size: 16384 bytes
[    0.000000] printk: log_buf_len: 32768 bytes
[    0.000000] printk: early log buf free: 9888(60%)
[    0.000000] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, linear)
[    0.000000] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)
[    0.000000] mem auto-init: stack:off, heap alloc:on, heap free:off
[    0.000000] Memory: 6084636K/6290920K available (10246K kernel code, 1130K rwdata, 2820K rodata, 1184K init, 900K bss, 206024K reserved, 0K cma-reserved)
[    0.000000] random: get_random_u64 called from cache_random_seq_create+0x84/0x170 with crng_init=0
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] Kernel/User page tables isolation: enabled
[    0.000000] rcu: Hierarchical RCU implementation.
[    0.000000] rcu: 	RCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=4.
[    0.000000] 	Tracing variant of Tasks RCU enabled.
[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies.
[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4
[    0.000000] NR_IRQS: 16640, nr_irqs: 456, preallocated irqs: 16
[    0.000000] random: crng done (trusting CPU's manufacturer)
[    0.000000] Console: colour VGA+ 80x25
[    0.000000] printk: console [tty0] enabled
[    0.000000] printk: console [ttyS0] enabled
[    0.000000] ACPI: Core revision 20200925
[    0.000000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
[    0.010000] APIC: Switch to symmetric I/O mode setup
[    0.010000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[    0.080000] tsc: Unable to calibrate against PIT
[    0.090000] tsc: using HPET reference calibration
[    0.090000] tsc: Detected 2399.383 MHz processor
[    0.000007] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x2295f0b5f49, max_idle_ns: 440795258281 ns
[    0.004379] Calibrating delay loop (skipped), value calculated using timer frequency.. 4798.76 BogoMIPS (lpj=23993830)
[    0.014380] pid_max: default: 32768 minimum: 301
[    0.016665] LSM: Security Framework initializing
[    0.024413] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, linear)
[    0.028454] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, linear)
[    0.033171] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[    0.034380] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[    0.037155] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization
[    0.040969] Spectre V2 : Mitigation: Full generic retpoline
[    0.044387] Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch
[    0.048058] Speculative Store Bypass: Vulnerable
[    0.050138] SRBDS: Unknown: Dependent on hypervisor status
[    0.052489] MDS: Vulnerable: Clear CPU buffers attempted, no microcode
[    0.054464] Freeing SMP alternatives memory: 28K
[    0.181378] smpboot: CPU0: Intel Core Processor (Haswell, no TSX, IBRS) (family: 0x6, model: 0x3c, stepping: 0x1)
[    0.184374] Performance Events: unsupported p6 CPU model 60 no PMU driver, software events only.
[    0.184374] rcu: Hierarchical SRCU implementation.
[    0.184374] NMI watchdog: Perf NMI watchdog permanently disabled
[    0.184808] smp: Bringing up secondary CPUs ...
[    0.186998] x86: Booting SMP configuration:
[    0.189104] .... node  #0, CPUs:      #1 #2 #3
[    0.204699] smp: Brought up 1 node, 4 CPUs
[    0.208733] smpboot: Max logical packages: 1
[    0.211001] ----------------
[    0.212616] | NMI testsuite:
[    0.214381] --------------------
[    0.216049]   remote IPI:  ok  |
[    0.217946]    local IPI:  ok  |
[    0.219323] --------------------
[    0.221439] Good, all   2 testcases passed! |
[    0.223267] ---------------------------------
[    0.224380] smpboot: Total of 4 processors activated (19195.06 BogoMIPS)
[    0.227725] devtmpfs: initialized
[    0.227725] x86/mm: Memory block size: 128MB
[    0.235133] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.239181] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
[    0.244678] NET: Registered protocol family 16
[    0.246946] audit: initializing netlink subsys (disabled)
[    0.249150] audit: type=2000 audit(1630444348.330:1): state=initialized audit_enabled=0 res=1
[    0.249150] thermal_sys: Registered thermal governor 'step_wise'
[    0.254534] cpuidle: using governor ladder
[    0.259008] cpuidle: using governor menu
[    0.260952] ACPI: bus type PCI registered
[    0.262815] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
[    0.265056] PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xb0000000-0xbfffffff] (base 0xb0000000)
[    0.269521] PCI: MMCONFIG at [mem 0xb0000000-0xbfffffff] reserved in E820
[    0.274411] PCI: Using configuration type 1 for base access
[    0.282162] Kprobes globally optimized
[    0.287176] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[    0.304622] ACPI: Added _OSI(Module Device)
[    0.306351] ACPI: Added _OSI(Processor Device)
[    0.308638] ACPI: Added _OSI(3.0 _SCP Extensions)
[    0.314400] ACPI: Added _OSI(Processor Aggregator Device)
[    0.317428] ACPI: Added _OSI(Linux-Dell-Video)
[    0.319792] ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio)
[    0.322476] ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics)
[    0.325104] ACPI: 1 ACPI AML tables successfully acquired and loaded
[    0.328969] ACPI: Interpreter enabled
[    0.330836] ACPI: (supports S0 S3 S5)
[    0.332638] ACPI: Using IOAPIC for interrupt routing
[    0.334461] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[    0.338892] ACPI: Enabled 1 GPEs in block 00 to 3F
[    0.347903] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[    0.350498] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]
[    0.354067] acpi PNP0A08:00: _OSC: platform does not support [LTR]
[    0.354458] acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME PCIeCapability]
[    0.358044] PCI host bridge to bus 0000:00
[    0.360134] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
[    0.364382] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
[    0.367619] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[    0.372147] pci_bus 0000:00: root bus resource [mem 0x80000000-0xafffffff window]
[    0.374381] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]
[    0.377443] pci_bus 0000:00: root bus resource [mem 0x200000000-0x9ffffffff window]
[    0.384381] pci_bus 0000:00: root bus resource [bus 00-ff]
[    0.387449] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000
[    0.391268] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000
[    0.395694] pci 0000:00:01.0: reg 0x10: [io  0xc0c0-0xc0df]
[    0.399194] pci 0000:00:01.0: reg 0x14: [mem 0xfebd0000-0xfebd0fff]
[    0.405005] pci 0000:00:01.0: reg 0x20: [mem 0xfe800000-0xfe803fff 64bit pref]
[    0.410305] pci 0000:00:01.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref]
[    0.415911] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00
[    0.419165] pci 0000:00:02.0: reg 0x10: [io  0xc0e0-0xc0ff]
[    0.425114] pci 0000:00:02.0: reg 0x20: [mem 0xfe804000-0xfe807fff 64bit pref]
[    0.431101] pci 0000:00:03.0: [1af4:1050] type 00 class 0x030000
[    0.435233] pci 0000:00:03.0: reg 0x10: [mem 0xfe000000-0xfe7fffff pref]
[    0.440268] pci 0000:00:03.0: reg 0x18: [mem 0xfe808000-0xfe80bfff 64bit pref]
[    0.444806] pci 0000:00:03.0: reg 0x20: [mem 0xfebd1000-0xfebd1fff]
[    0.448960] pci 0000:00:03.0: reg 0x30: [mem 0xfebc0000-0xfebcffff pref]
[    0.453057] pci 0000:00:04.0: [1af4:1052] type 00 class 0x090000
[    0.456306] pci 0000:00:04.0: reg 0x14: [mem 0xfebd2000-0xfebd2fff]
[    0.460801] pci 0000:00:04.0: reg 0x20: [mem 0xfe80c000-0xfe80ffff 64bit pref]
[    0.466280] pci 0000:00:05.0: [1af4:1052] type 00 class 0x090200
[    0.470745] pci 0000:00:05.0: reg 0x14: [mem 0xfebd3000-0xfebd3fff]
[    0.476432] pci 0000:00:05.0: reg 0x20: [mem 0xfe810000-0xfe813fff 64bit pref]
[    0.483009] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000
[    0.485550] pci 0000:00:06.0: reg 0x10: [io  0xc000-0xc07f]
[    0.488800] pci 0000:00:06.0: reg 0x14: [mem 0xfebd4000-0xfebd4fff]
[    0.496451] pci 0000:00:06.0: reg 0x20: [mem 0xfe814000-0xfe817fff 64bit pref]
[    0.504628] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100
[    0.507946] pci 0000:00:1f.0: quirk: [io  0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO
[    0.512145] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601
[    0.517574] pci 0000:00:1f.2: reg 0x20: [io  0xc100-0xc11f]
[    0.521160] pci 0000:00:1f.2: reg 0x24: [mem 0xfebd5000-0xfebd5fff]
[    0.525625] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500
[    0.529671] pci 0000:00:1f.3: reg 0x20: [io  0x0700-0x073f]
[    0.537130] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
[    0.539510] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
[    0.542018] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
[    0.544485] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
[    0.546990] ACPI: PCI Interrupt Link [LNKE] (IRQs 5 *10 11)
[    0.549549] ACPI: PCI Interrupt Link [LNKF] (IRQs 5 *10 11)
[    0.552041] ACPI: PCI Interrupt Link [LNKG] (IRQs 5 10 *11)
[    0.554471] ACPI: PCI Interrupt Link [LNKH] (IRQs 5 10 *11)
[    0.556762] ACPI: PCI Interrupt Link [GSIA] (IRQs *16)
[    0.559041] ACPI: PCI Interrupt Link [GSIB] (IRQs *17)
[    0.561522] ACPI: PCI Interrupt Link [GSIC] (IRQs *18)
[    0.563954] ACPI: PCI Interrupt Link [GSID] (IRQs *19)
[    0.564397] ACPI: PCI Interrupt Link [GSIE] (IRQs *20)
[    0.566540] ACPI: PCI Interrupt Link [GSIF] (IRQs *21)
[    0.569173] ACPI: PCI Interrupt Link [GSIG] (IRQs *22)
[    0.574397] ACPI: PCI Interrupt Link [GSIH] (IRQs *23)
[    0.577385] iommu: Default domain type: Translated
[    0.579652] SCSI subsystem initialized
[    0.581472] pps_core: LinuxPPS API ver. 1 registered
[    0.584428] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <[email protected]>
[    0.588177] PTP clock support registered
[    0.589934] PCI: Using ACPI for IRQ routing
[    0.704394] clocksource: Switched to clocksource tsc-early
[    0.727879] VFS: Disk quotas dquot_6.6.0
[    0.730307] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    0.733705] pnp: PnP ACPI init
[    0.735885] system 00:04: [mem 0xb0000000-0xbfffffff window] has been reserved
[    0.740557] pnp: PnP ACPI: found 5 devices
[    0.770097] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[    0.774257] NET: Registered protocol family 2
[    0.778934] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, linear)
[    0.782787] tcp_listen_portaddr_hash hash table entries: 4096 (order: 4, 65536 bytes, linear)
[    0.787249] TCP established hash table entries: 65536 (order: 7, 524288 bytes, linear)
[    0.792790] TCP bind hash table entries: 65536 (order: 8, 1048576 bytes, linear)
[    0.796370] TCP: Hash tables configured (established 65536 bind 65536)
[    0.799823] UDP hash table entries: 4096 (order: 5, 131072 bytes, linear)
[    0.803067] UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes, linear)
[    0.806881] NET: Registered protocol family 1
[    0.808882] NET: Registered protocol family 44
[    0.810742] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]
[    0.813555] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]
[    0.816213] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
[    0.819152] pci_bus 0000:00: resource 7 [mem 0x80000000-0xafffffff window]
[    0.823350] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window]
[    0.826406] pci_bus 0000:00: resource 9 [mem 0x200000000-0x9ffffffff window]
[    0.829345] pci 0000:00:03.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
[    0.833364] PCI: CLS 0 bytes, default 64
[    0.835534] Unpacking initramfs...
[    0.945038] Freeing initrd memory: 5792K
[    0.947069] PCI-DMA: Using software bounce buffering for IO (SWIOTLB)
[    0.950451] software IO TLB: mapped [mem 0x000000007ba34000-0x000000007fa34000] (64MB)
[    0.955330] Initialise system trusted keyrings
[    0.957773] workingset: timestamp_bits=46 max_order=21 bucket_order=0
[    0.961141] Key type asymmetric registered
[    0.963054] Asymmetric key parser 'x509' registered
[    0.965276] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250)
[    0.969263] io scheduler mq-deadline registered
[    0.971521] io scheduler kyber registered
[    0.974826] ERST DBG: ERST support is disabled.
[    0.978482] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[    0.982199] 00:02: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
[    0.987826] VMware PVSCSI driver - version 1.0.7.0-k
[    0.990490] PCI Interrupt Link [GSIA] enabled at IRQ 16
[    0.994061] ahci 0000:00:1f.2: AHCI 0001.0000 32 slots 6 ports 1.5 Gbps 0x3f impl SATA mode
[    0.998847] ahci 0000:00:1f.2: flags: 64bit ncq only
[    1.004509] scsi host0: ahci
[    1.006851] scsi host1: ahci
[    1.008987] scsi host2: ahci
[    1.011084] scsi host3: ahci
[    1.014147] scsi host4: ahci
[    1.016554] scsi host5: ahci
[    1.018291] ata1: SATA max UDMA/133 abar m4096@0xfebd5000 port 0xfebd5100 irq 24
[    1.021427] ata2: SATA max UDMA/133 abar m4096@0xfebd5000 port 0xfebd5180 irq 24
[    1.025070] ata3: SATA max UDMA/133 abar m4096@0xfebd5000 port 0xfebd5200 irq 24
[    1.028465] ata4: SATA max UDMA/133 abar m4096@0xfebd5000 port 0xfebd5280 irq 24
[    1.031579] ata5: SATA max UDMA/133 abar m4096@0xfebd5000 port 0xfebd5300 irq 24
[    1.034956] ata6: SATA max UDMA/133 abar m4096@0xfebd5000 port 0xfebd5380 irq 24
[    1.039457] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
[    1.047632] serio: i8042 KBD port at 0x60,0x64 irq 1
[    1.049626] serio: i8042 AUX port at 0x60,0x64 irq 12
[    1.052014] rtc_cmos 00:03: RTC can wake from S4
[    1.057222] rtc_cmos 00:03: registered as rtc0
[    1.059853] rtc_cmos 00:03: setting system clock to 2021-08-31T21:12:29 UTC (1630444349)
[    1.063523] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0
[    1.063526] rtc_cmos 00:03: alarms up to one day, y3k, 242 bytes nvram, hpet irqs
[    1.071993] intel_pstate: CPU model not supported
[    1.074530] gre: GRE over IPv4 demultiplexor driver
[    1.080941] Key type dns_resolver registered
[    1.083067] IPI shorthand broadcast: enabled
[    1.084887] sched_clock: Marking stable (1166522254, -85625484)->(1076913286, 3983484)
[    1.089283] registered taskstats version 1
[    1.091354] Loading compiled-in X.509 certificates
[    1.096474] Loaded X.509 cert 'Build time autogenerated kernel key: cf89a92e98f767fa823b6e024af04575c3b49d79'
[    1.102231] Key type ._fscrypt registered
[    1.104633] Key type .fscrypt registered
[    1.106826] Key type fscrypt-provisioning registered
[    1.368941] ata2: SATA link down (SStatus 0 SControl 300)
[    1.373241] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
[    1.377521] ata6: SATA link down (SStatus 0 SControl 300)
[    1.380947] ata5: SATA link down (SStatus 0 SControl 300)
[    1.384011] ata4: SATA link down (SStatus 0 SControl 300)
[    1.387337] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
[    1.390549] ata1.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100
[    1.393499] ata1.00: applying bridge limits
[    1.395222] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100
[    1.399131] ata3.00: applying bridge limits
[    1.402159] ata1.00: configured for UDMA/100
[    1.404521] ata3.00: configured for UDMA/100
[    1.405307] scsi 0:0:0:0: CD-ROM            QEMU     QEMU DVD-ROM     2.5+ PQ: 0 ANSI: 5
[    1.411387] scsi 2:0:0:0: CD-ROM            QEMU     QEMU DVD-ROM     2.5+ PQ: 0 ANSI: 5
[    1.416613] Freeing unused kernel image (initmem) memory: 1184K
[    1.467087] Write protecting the kernel read-only data: 16384k
[    1.471308] Freeing unused kernel image (text/rodata gap) memory: 2040K
[    1.475061] Freeing unused kernel image (rodata/data gap) memory: 1276K
[    1.478516] rodata_test: all tests were successful
[    1.480599] Run /init as init process
[    1.491531] Alpine Init 3.5.0-r0
Alpine Init 3.5.0-r0
[    1.493799] Loading boot drivers...
 * Loading boot drivers: [    1.848184] loop: module loaded
[    1.853644] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    1.870324] ACPI: bus type USB registered
[    1.872218] usbcore: registered new interface driver usbfs
[    1.874968] usbcore: registered new interface driver hub
[    1.877352] usbcore: registered new device driver usb
[    1.882203] usbcore: registered new interface driver usb-storage
[    1.886048] Loading boot drivers: ok.
ok.
[    1.889030] Mounting boot media...
 * Mounting boot media: [    1.950544] PCI Interrupt Link [GSIF] enabled at IRQ 21
[    1.957229] PCI Interrupt Link [GSIG] enabled at IRQ 22
[    1.961662] PCI Interrupt Link [GSIH] enabled at IRQ 23
[    1.967664] PCI Interrupt Link [GSIE] enabled at IRQ 20
[    1.994341] sr 0:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray
[    1.999143] cdrom: Uniform CD-ROM driver Revision: 3.20
[    2.003645] tsc: Refined TSC clocksource calibration: 2399.950 MHz
[    2.008418] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x2298086d749, max_idle_ns: 440795294037 ns
[    2.014740] clocksource: Switched to clocksource tsc
[    2.117839] sr 2:0:0:0: [sr1] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray
[    2.253032] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:04.0/virtio3/input/input3
[    2.262358] input: QEMU Virtio Mouse as /devices/pci0000:00/0000:00:05.0/virtio4/input/input4
[    2.297620] virtio_blk virtio5: [vda] 209715200 512-byte logical blocks (107 GB/100 GiB)
[    2.301833] vda: detected capacity change from 0 to 107374182400
[    2.526957] Mounting boot media: ok.
ok.
[    2.530339] Loading user settings from /media/sr0/alpine.apkovl.tar.gz...
 * Loading user settings from /media/sr0/alpine.apkovl.tar.gz: [    2.544583] Loading user settings from /media/sr0/alpine.apkovl.tar.gz: ok.
ok.
[    2.562734] Installing packages to root filesystem...
 * Installing packages to root filesystem: (1/62) Installing musl (1.2.2-r1)
(2/62) Installing busybox (1.32.1-r6)
Executing busybox-1.32.1-r6.post-install
(3/62) Installing alpine-baselayout (3.2.0-r8)
Executing alpine-baselayout-3.2.0-r8.pre-install
Executing alpine-baselayout-3.2.0-r8.post-install
(4/62) Installing ifupdown-ng (0.11.2-r0)
(5/62) Installing openrc (0.42.1-r20)
Executing openrc-0.42.1-r20.post-install
(6/62) Installing alpine-conf (3.11.0-r2)
(7/62) Installing libcrypto1.1 (1.1.1k-r0)
(8/62) Installing libssl1.1 (1.1.1k-r0)
(9/62) Installing ca-certificates-bundle (20191127-r5)
(10/62) Installing libtls-standalone (2.9.1-r1)
(11/62) Installing ssl_client (1.32.1-r6)
(12/62) Installing zlib (1.2.11-r3)
(13/62) Installing apk-tools (2.12.5-r0)
(14/62) Installing busybox-suid (1.32.1-r6)
(15/62) Installing busybox-initscripts (3.2-r2)
Executing busybox-initscripts-3.2-r2.post-install
(16/62) Installing scanelf (1.2.8-r0)
(17/62) Installing musl-utils (1.2.2-r1)
(18/62) Installing libc-utils (0.7.2-r3)
(19/62) Installing alpine-keys (2.2-r0)
(20/62) Installing alpine-base (3.13.5-r0)
(21/62) Installing ca-certificates (20191127-r5)
(22/62) Installing libblkid (2.36.1-r1)
(23/62) Installing libcom_err (1.45.7-r0)
(24/62) Installing e2fsprogs-libs (1.45.7-r0)
(25/62) Installing libuuid (2.36.1-r1)
(26/62) Installing e2fsprogs (1.45.7-r0)
(27/62) Installing libmount (2.36.1-r1)
(28/62) Installing libsmartcols (2.36.1-r1)
(29/62) Installing lsblk (2.36.1-r1)
(30/62) Installing openssh-keygen (8.4_p1-r3)
(31/62) Installing ncurses-terminfo-base (6.2_p20210109-r0)
(32/62) Installing ncurses-libs (6.2_p20210109-r0)
(33/62) Installing libedit (20191231.3.1-r1)
(34/62) Installing openssh-client (8.4_p1-r3)
(35/62) Installing openssh-sftp-server (8.4_p1-r3)
(36/62) Installing openssh-server-common (8.4_p1-r3)
(37/62) Installing openssh-server (8.4_p1-r3)
(38/62) Installing openssh (8.4_p1-r3)
(39/62) Installing openssl (1.1.1k-r0)
(40/62) Installing qemu-aarch64 (5.2.0-r3)
(41/62) Installing libfdisk (2.36.1-r1)
(42/62) Installing sfdisk (2.36.1-r1)
(43/62) Installing linux-pam (1.5.1-r0)
(44/62) Installing shadow (4.8.1-r0)
(45/62) Installing fuse-common (3.10.2-r0)
(46/62) Installing fuse-openrc (3.10.2-r0)
(47/62) Installing fuse3-libs (3.10.2-r0)
(48/62) Installing fuse3 (3.10.2-r0)
(49/62) Installing libffi (3.3-r2)
(50/62) Installing libintl (0.20.2-r2)
(51/62) Installing pcre (8.44-r0)
(52/62) Installing glib (2.66.8-r0)
(53/62) Installing sshfs (3.7.1-r0)
(54/62) Installing sudo (1.9.5p2-r0)
(55/62) Installing udev-init-scripts (34-r0)
Executing udev-init-scripts-34-r0.post-install
(56/62) Installing udev-init-scripts-openrc (34-r0)
(57/62) Installing eudev-libs (3.2.9-r3)
(58/62) Installing xz-libs (5.2.5-r0)
(59/62) Installing zstd-libs (1.4.9-r0)
(60/62) Installing kmod-libs (28-r0)
(61/62) Installing eudev (3.2.9-r3)
(62/62) Installing eudev-openrc (3.2.9-r3)
Executing busybox-1.32.1-r6.trigger
Executing ca-certificates-20191127-r5.trigger
Executing eudev-3.2.9-r3.trigger
OK: 33 MiB in 62 packages
[    3.159278] Installing packages to root filesystem: ok.
ok.


   OpenRC 0.42.1.cd6f22142c is starting up Linux 5.10.38-0-virt (x86_64)

 * /proc is already mounted
 * Mounting /run ... * /run/openrc: creating directory
 * /run/lock: creating directory
 * /run/lock: correcting owner
 * Caching service dependencies ... [ ok ]
 * Remounting devtmpfs on /dev ... [ ok ]
 * Mounting /dev/mqueue ... [ ok ]
 * Mounting modloop  ... * Verifying modloop
 [ ok ]
 * Mounting security filesystem ... [ ok ]
 * Mounting debug filesystem ... [ ok ]
 * Mounting persistent storage (pstore) filesystem ... [ ok ]
 * Starting udev ... [ ok ]
 * Generating a rule to create a /dev/root symlink ... [ ok ]
 * Populating /dev with existing devices through uevents ... [ ok ]
 * Loading modules ... [ ok ]
 * Setting system clock using the hardware clock [UTC] ... [ ok ]
 * Checking local filesystems  ... [ ok ]
 * Remounting filesystems ... [ ok ]
 * Mounting local filesystems ... [ ok ]
 * Configuring kernel parameters ... [ ok ]
 * Migrating /var/lock to /run/lock ... [ ok ]
 * Creating user login records ... [ ok ]
 * Wiping /tmp directory ... [ ok ]
 * Setting hostname ... [ ok ]
 * Starting busybox syslog ... [ ok ]
 * Waiting for uevents to be processed ... [ ok ]
 * Starting networking ... *   lo ... [ ok ]
 *   eth0 ...udhcpc: started, v1.32.1
udhcpc: sending discover
udhcpc: sending select for 192.168.5.15
udhcpc: lease of 192.168.5.15 obtained, lease time 86400
 [ ok ]
 [ ok ]
 * Registering QEMU binaries in binfmt misc ... [ ok ]
ssh-keygen: generating new host keys: RSA DSA ECDSA ED25519
 * Starting sshd ... [ ok ]

Welcome to Alpine Linux 3.13
Kernel 5.10.38-0-virt on an x86_64 (/dev/ttyS0)

lima-rancher-desktop login: ⏎

@willie
Copy link

willie commented Sep 1, 2021

Same error and user id as @mooneye14

@jandubois
Copy link
Member

jandubois commented Sep 1, 2021

@mpptx This looks similar to #532, which we couldn't figure out because the error suddenly went away.

There are several instructions to run lima and qemu on their own, and to try different CPU settings. Could you see if any of that applies to your setup?

@mooneye14 You logs look different, and I'm still working through them, but there is most likely no point in performing the steps from #532. I'll update if I have further ideas...

@jandubois
Copy link
Member

@mooneye14 Your logs very much look like the error in #535, but since your uid is less than 256000, the error must be something different.

For the following check you need the socat utility; if you are using brew then you can install it via brew install socat.

Your VM seems to be running, and the SSH daemon is listening on the port, but rejects your key. Since we can't connect via ssh we need to establish a connection via (virtual) serial port. You will not get a prompt when socat connects, but the terminal is waiting for you to enter a username. Just type root↩. Then you have a shell prompt. Ignore the incorrect control sequences. Run cat /mnt/lima-cidata/user-data and share the output. On my (working) setup it looks like this:

$ socat stdio ~/Library/State/rancher-desktop/lima/rancher-desktop/serial.sock
root
root
Welcome to Alpine!

The Alpine Wiki contains a large amount of how-to guides and general
information about administrating Alpine systems.
See <http://wiki.alpinelinux.org/>.

You can setup the system with the command: setup-alpine

You may change this message by editing /etc/motd.
lima-rancher-desktop:~# ^[[60;25Rcat /mnt/lima-cidata/user-data
cat /mnt/lima-cidata/user-data
#cloud-config
# vim:syntax=yaml

growpart:
  mode: auto
  devices: ['/']

users:
  - name: "jan"
    uid: "501"
    homedir: "/home/jan.linux"
    shell: /bin/bash
    sudo: ALL=(ALL) NOPASSWD:ALL
    lock_passwd: true
    ssh-authorized-keys:
      - ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFGNCQ9U3k8ErTkhLHJP+8bCgvi56GJshb6q0cdhWKHw jan@mactop
[...]

The ssh-authorized-keys value should be this public key from your host:

$ cat ~/Library/State/rancher-desktop/lima/_config/user.pub
ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFGNCQ9U3k8ErTkhLHJP+8bCgvi56GJshb6q0cdhWKHw jan@mactop

Since it is a public key, it is safe to share it. If you have concerns, you can delete the key files on the host and Rancher Desktop will generate a new one on the next start.

While you are still connected via socat you can also run cat /home/*/.ssh/authorized_keys. It should print the same key value:

lima-rancher-desktop:~# ^[[60;25Rcat /home/*/.ssh/authorized_keys
cat /home/*/.ssh/authorized_keys
ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFGNCQ9U3k8ErTkhLHJP+8bCgvi56GJshb6q0cdhWKHw jan@mactop

Finally, please capture the output of cat /var/log/lima-init.log as well, just in case the user-data doesn't have enough information to show what went wrong.

@gaktive gaktive added the kind/bug Something isn't working label Sep 2, 2021
@gaktive gaktive added this to the v0.5.0 milestone Sep 2, 2021
@spurin
Copy link

spurin commented Sep 3, 2021

To add to the thread, I'm also seeing this on a recently fresh/new version of Big Sur on Mac M1. Please let me know if you'd like any logs or further steps carried out for troubleshooting purposes. My id is 502, here's the log output -

~/Library/State/rancher-desktop/lima/rancher-desktop/ha.stderr.log:

james@MacMini ~ % cat ~/Library/State/rancher-desktop/lima/rancher-desktop/ha.stderr.log
{"level":"info","msg":"Starting QEMU (hint: to watch the boot progress, see \"/Users/james/Library/State/rancher-desktop/lima/rancher-desktop/serial.log\")","time":"2021-09-03T11:35:47+01:00"}
{"level":"debug","msg":"qCmd.Args: [/Applications/Rancher Desktop.app/Contents/Resources/resources/darwin/lima/bin/qemu-system-x86_64 -cpu Haswell-v4 -machine q35,accel=hvf -smp 2,sockets=1,cores=2,threads=1 -m 4096 -boot order=d,splash-time=0,menu=on -drive file=/Users/james/Library/State/rancher-desktop/lima/rancher-desktop/basedisk,media=cdrom,readonly=on -drive file=/Users/james/Library/State/rancher-desktop/lima/rancher-desktop/diffdisk,if=virtio -cdrom /Users/james/Library/State/rancher-desktop/lima/rancher-desktop/cidata.iso -netdev user,id=net0,net=192.168.5.0/24,dhcpstart=192.168.5.15,hostfwd=tcp:127.0.0.1:61857-:22 -device virtio-net-pci,netdev=net0,mac=52:55:55:32:51:6c -device virtio-rng-pci -display none -device virtio-vga -device virtio-keyboard-pci -device virtio-mouse-pci -parallel none -chardev socket,id=char-serial,path=/Users/james/Library/State/rancher-desktop/lima/rancher-desktop/serial.sock,server,nowait,logfile=/Users/james/Library/State/rancher-desktop/lima/rancher-desktop/serial.log -serial chardev:char-serial -chardev socket,id=char-qmp,path=/Users/james/Library/State/rancher-desktop/lima/rancher-desktop/qmp.sock,server,nowait -qmp chardev:char-qmp -name lima-rancher-desktop -pidfile /Users/james/Library/State/rancher-desktop/lima/rancher-desktop/qemu.pid]","time":"2021-09-03T11:35:47+01:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-09-03T11:35:47+01:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-09-03T11:35:47+01:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"ssh: connect to host 127.0.0.1 port 61857: Connection refused\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"ssh: connect to host 127.0.0.1 port 61857: Connection refused\\r\\n\": exit status 255","time":"2021-09-03T11:35:48+01:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: -chardev socket,id=char-serial,path=/Users/james/Library/State/rancher-desktop/lima/rancher-desktop/serial.sock,server,nowait,logfile=/Users/james/Library/State/rancher-desktop/lima/rancher-desktop/serial.log: warning: short-form boolean option 'server' deprecated","time":"2021-09-03T11:35:48+01:00"}
{"level":"debug","msg":"qemu[stderr]: Please use server=on instead","time":"2021-09-03T11:35:48+01:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: -chardev socket,id=char-serial,path=/Users/james/Library/State/rancher-desktop/lima/rancher-desktop/serial.sock,server,nowait,logfile=/Users/james/Library/State/rancher-desktop/lima/rancher-desktop/serial.log: warning: short-form boolean option 'nowait' deprecated","time":"2021-09-03T11:35:48+01:00"}
{"level":"debug","msg":"qemu[stderr]: Please use wait=off instead","time":"2021-09-03T11:35:48+01:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: -chardev socket,id=char-qmp,path=/Users/james/Library/State/rancher-desktop/lima/rancher-desktop/qmp.sock,server,nowait: warning: short-form boolean option 'server' deprecated","time":"2021-09-03T11:35:48+01:00"}
{"level":"debug","msg":"qemu[stderr]: Please use server=on instead","time":"2021-09-03T11:35:48+01:00"}
{"error":"signal: abort trap","level":"info","msg":"QEMU has exited","time":"2021-09-03T11:35:48+01:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: -chardev socket,id=char-qmp,path=/Users/james/Library/State/rancher-desktop/lima/rancher-desktop/qmp.sock,server,nowait: warning: short-form boolean option 'nowait' deprecated","time":"2021-09-03T11:35:48+01:00"}
{"level":"debug","msg":"qemu[stderr]: Please use wait=off instead","time":"2021-09-03T11:35:48+01:00"}
signal: abort trap

~/Library/State/rancher-desktop/lima/rancher-desktop/ha.stdout.log

james@MacMini ~ % cat ~/Library/State/rancher-desktop/lima/rancher-desktop/ha.stdout.log
{"time":"2021-09-03T11:35:47.932889+01:00","status":{"sshLocalPort":61857}}
{"time":"2021-09-03T11:35:48.215792+01:00","status":{"exiting":true}}

~/Library/State/rancher-desktop/lima/rancher-desktop/serial.log (is empty)

@pulberg
Copy link

pulberg commented Sep 3, 2021

2019 Intel Core i9 MBP

lima-rancher-desktop:~# ^[[60;25Rcat /home/*/.ssh/authorized_keys
cat /home/*/.ssh/authorized_keys
cat: can't open '/home/*/.ssh/authorized_keys': No such file or directory
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: warning: host doesn't support requested feature: CPUID.01H:ECX.x2apic [bit 21]","time":"2021-09-03T15:16:50-05:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: warning: host doesn't support requested feature: CPUID.01H:ECX.tsc-deadline [bit 24]","time":"2021-09-03T15:16:50-05:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: warning: host doesn't support requested feature: CPUID.07H:EBX.invpcid [bit 10]","time":"2021-09-03T15:16:50-05:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: warning: host doesn't support requested feature: CPUID.07H:EDX.spec-ctrl [bit 26]","time":"2021-09-03T15:16:50-05:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: warning: host doesn't support requested feature: CPUID.80000001H:EDX.rdtscp [bit 27]","time":"2021-09-03T15:16:50-05:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: warning: host doesn't support requested feature: CPUID.01H:ECX.x2apic [bit 21]","time":"2021-09-03T15:16:50-05:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: warning: host doesn't support requested feature: CPUID.01H:ECX.tsc-deadline [bit 24]","time":"2021-09-03T15:16:50-05:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: warning: host doesn't support requested feature: CPUID.07H:EBX.invpcid [bit 10]","time":"2021-09-03T15:16:50-05:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: warning: host doesn't support requested feature: CPUID.07H:EDX.spec-ctrl [bit 26]","time":"2021-09-03T15:16:50-05:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: warning: host doesn't support requested feature: CPUID.80000001H:EDX.rdtscp [bit 27]","time":"2021-09-03T15:16:50-05:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: warning: host doesn't support requested feature: CPUID.01H:ECX.x2apic [bit 21]","time":"2021-09-03T15:16:50-05:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: warning: host doesn't support requested feature: CPUID.01H:ECX.tsc-deadline [bit 24]","time":"2021-09-03T15:16:51-05:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: warning: host doesn't support requested feature: CPUID.07H:EBX.invpcid [bit 10]","time":"2021-09-03T15:16:51-05:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: warning: host doesn't support requested feature: CPUID.07H:EDX.spec-ctrl [bit 26]","time":"2021-09-03T15:16:51-05:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: warning: host doesn't support requested feature: CPUID.80000001H:EDX.rdtscp [bit 27]","time":"2021-09-03T15:16:51-05:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: warning: host doesn't support requested feature: CPUID.01H:ECX.x2apic [bit 21]","time":"2021-09-03T15:16:51-05:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: warning: host doesn't support requested feature: CPUID.01H:ECX.tsc-deadline [bit 24]","time":"2021-09-03T15:16:51-05:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: warning: host doesn't support requested feature: CPUID.07H:EBX.invpcid [bit 10]","time":"2021-09-03T15:16:51-05:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: warning: host doesn't support requested feature: CPUID.07H:EDX.spec-ctrl [bit 26]","time":"2021-09-03T15:16:51-05:00"}
{"level":"debug","msg":"qemu[stderr]: qemu-system-x86_64: warning: host doesn't support requested feature: CPUID.80000001H:EDX.rdtscp [bit 27]","time":"2021-09-03T15:16:51-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-09-03T15:17:00-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-09-03T15:17:00-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"kex_exchange_identification: Connection closed by remote host\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"kex_exchange_identification: Connection closed by remote host\\r\\n\": exit status 255","time":"2021-09-03T15:17:00-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-09-03T15:17:10-05:00"}
{"level":"debug","msg":"executing script \"ssh\"","time":"2021-09-03T15:17:10-05:00"}
{"level":"debug","msg":"stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\", err=failed to execute script \"ssh\": stdout=\"\", stderr=\"[email protected]: Permission denied (publickey,password,keyboard-interactive).\\r\\n\": exit status 255","time":"2021-09-03T15:17:10-05:00"}
{"level":"info","msg":"Waiting for the essential requirement 1 of 4: \"ssh\"","time":"2021-09-03T15:17:20-05:00"}

@jandubois
Copy link
Member

@pulberg Thanks! That shows that lima-init has failed somehow. I really need the content of /mnt/lima-cidata/user-data to see what might have happened (and ideally the content of /var/log/lima-init.log as well).

@jandubois
Copy link
Member

@spurin Your failure looks similar to #532.

There are several instructions to run lima and qemu on their own, and to try different CPU settings in the discussion of #532. Could you see if any of that applies to your setup?

@pulberg
Copy link

pulberg commented Sep 3, 2021

I have applied the updated lima .iso as well

lima-rancher-desktop:~# ^[[60;25Rcat /mnt/lima-cidata/user-data
cat /mnt/lima-cidata/user-data
#cloud-config
# vim:syntax=yaml

growpart:
  mode: auto
  devices: ['/']

users:
  - name: "pulberg"
    uid: "740863103"
    homedir: "/home/pulberg.linux"
    shell: /bin/bash
    sudo: ALL=(ALL) NOPASSWD:ALL
    lock_passwd: true
    ssh-authorized-keys:
      - ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAINRgZTKM8VhSKhVyn9mtjUjJuYHGXM5++TibniGv/cF9 pulberg@z204333

write_files:
 - content: |
      #!/bin/sh
      set -eux
      LIMA_CIDATA_MNT="/mnt/lima-cidata"
      LIMA_CIDATA_DEV="/dev/disk/by-label/cidata"
      mkdir -p -m 700 "${LIMA_CIDATA_MNT}"
      mount -o ro,mode=0700,dmode=0700,overriderockperm,exec,uid=0 "${LIMA_CIDATA_DEV}" "${LIMA_CIDATA_MNT}"
      export LIMA_CIDATA_MNT
      exec "${LIMA_CIDATA_MNT}"/boot.sh
   owner: root:root
   path: /var/lib/cloud/scripts/per-boot/00-lima.boot.sh
   permissions: '0755'
lima-rancher-desktop:~# ^[[60;25Rcat /var/log/lima-init.log
cat /var/log/lima-init.log
+ ln -s /var/log/lima-init.log /var/log/cloud-init-output.log
+ LIMA_CIDATA_MNT=/mnt/lima-cidata
+ LIMA_CIDATA_DEV=/dev/disk/by-label/cidata
+ mkdir -p -m 700 /mnt/lima-cidata
+ mount -o 'ro,mode=0700,dmode=0700,overriderockperm,exec,uid=0' /dev/disk/by-label/cidata /mnt/lima-cidata
+ export LIMA_CIDATA_MNT
+ . /mnt/lima-cidata/lima.env
+ LIMA_CIDATA_USER=pulberg
+ LIMA_CIDATA_UID=740863103
+ LIMA_CIDATA_MOUNTS=1
+ LIMA_CIDATA_MOUNTS_0_MOUNTPOINT=/Users/pulberg/Library/Caches/rancher-desktop/k3s
+ LIMA_CIDATA_CONTAINERD_USER=
+ LIMA_CIDATA_CONTAINERD_SYSTEM=
+ LIMA_CIDATA_SLIRP_GATEWAY=192.168.5.2
+ awk '/^local-hostname:/ {print $2}' /mnt/lima-cidata/meta-data
+ LIMA_CIDATA_HOSTNAME=lima-rancher-desktop
+ hostname lima-rancher-desktop
+ LIMA_CIDATA_HOMEDIR=/home/pulberg.linux
+ adduser -h /home/pulberg.linux -u 740863103 -D pulberg
adduser: number 740863103 is not in 0..256000 range

@jandubois
Copy link
Member

@pulberg adduser: number 740863103 is not in 0..256000 range shows that you are still running the old ISO.

Try selecting "Reset Kubernetes" on the "Kubernetes Settings" page. That should delete the old VM and create a new one using the new ISO.

@pulberg
Copy link

pulberg commented Sep 3, 2021

I also found that it looks like there is a typo in the code looking for the alpine image name -

time="2021-09-03T16:28:51-05:00" level=info msg="Attempting to download the image from \"/Applications/Rancher Desktop.app/Contents/Resources/resources/darwin/alpline-lima-v0.1.0-std-3.13.5.iso

alpline should be alpine

After resetting kubernetes i get the error pretty much instantly now -

There was an unknown error starting Kubernetes: Error: /Applications/Rancher Desktop.app/Contents/Resources/resources/darwin/lima/bin/limactl exited with code 1

There is also no serial.sock or any log files now, just cidata.iso and lima.yaml

@jandubois
Copy link
Member

I also found that it looks like there is a typo in the code looking for the alpine image name -

Yes, there is, but it is inconsequential. The downloaded ISO is renamed to that name during packaging, and will be loaded from that name at runtime. The name can really be anything.

After resetting kubernetes i get the error pretty much instantly now -
[...]
There is also no serial.sock or any log files now, just cidata.iso and lima.yaml

That's not what I expected. Let's start over: Make sure RD is not running, and then delete the whole State directory (it will be recreated automatically when RD runs):

rm -rf ~/Library/State/rancher-desktop

Then run RD again, and see if it now works. If it doesn't, and there are no logs in ~/Library/State/rancher-desktop/lima/rancher-desktop/ then look for the logs in ~/Library/State/rancher-desktop/logs/.

@pulberg
Copy link

pulberg commented Sep 3, 2021

ok, reset everything, RD running now, mostly...it's been in "Waiting for image manager to be ready" for about 15min now

@jandubois
Copy link
Member

@pulberg Can you run kim image ls from the commandline?

Maybe try kim builder install --force to see if that fixes things. It would be weird though, as this should be a fresh install after you deleted everything.

@pulberg
Copy link

pulberg commented Sep 3, 2021

➜ kim image ls
WARN[0000] Cannot find available builder daemon, attempting automatic installation...
INFO[0000] Applying node-role `builder` to `lima-rancher-desktop`
INFO[0000] Asserting namespace `kube-image`
INFO[0000] Asserting TLS secrets
INFO[0000] Asserting service/endpoints
INFO[0000] Installing builder daemon
WARN[0000] builder already installed
Error: unknown service port

trying the force option

@mook-as
Copy link
Contributor

mook-as commented Sep 3, 2021

On a mac, you need to user kim builder install --force --endpoint-addr=127.0.0.1 instead. (It's fine to run that if you've already run the other one.)

@pulberg
Copy link

pulberg commented Sep 5, 2021

kim builder install --force
Error: Get "https://127.0.0.1:6443/api/v1/nodes": dial tcp 127.0.0.1:6443: connect: connection refused

@vivanov83
Copy link

kim builder install --force --endpoint-addr=127.0.0.1

Im getting this output. Is trying to connect to AWS even the command is specifying localhost.

kim builder install --force --endpoint-addr=127.0.0.1
Error: Get "https://***********************.ca-central-1.eks.amazonaws.com/api/v1/nodes": dial tcp ************:443: i/o timeout

adduser: number 1575537784 is not in 0..256000 range
I tried Reset Kubernetes many times, without any success still stuck in the middle of nowhere :(

@larssb
Copy link

larssb commented Sep 7, 2021

Having the same err. on MacOS v12.0 Beta of Monterey. I'm seeing the following in the lima.log:

time="2021-09-07T09:32:08+02:00" level=fatal msg="open /Users/lars/Library/State/rancher-desktop/lima: no such file or directory"
Could not parse lima status, assuming machine is unavailable.
time="2021-09-07T09:32:09+02:00" level=fatal msg="open /Users/lars/Library/State/rancher-desktop/lima: no such file or directory"
Could not parse lima status, assuming machine is unavailable.
time="2021-09-07T09:32:13+02:00" level=info msg="Terminal is not available, proceeding without opening an editor"
time="2021-09-07T09:32:13+02:00" level=info msg="Attempting to download the image from \"/Applications/Rancher Desktop.app/Contents/Resources/resources/darwin/alpline-lima-v0.1.0-std-3.13.5.iso\""
time="2021-09-07T09:32:13+02:00" level=info msg="Downloaded image from \"/Applications/Rancher Desktop.app/Contents/Resources/resources/darwin/alpline-lima-v0.1.0-std-3.13.5.iso\""
time="2021-09-07T09:32:13+02:00" level=fatal msg="failed to run [qemu-img create -f qcow2 /Users/lars/Library/State/rancher-desktop/lima/rancher-desktop/diffdisk 107374182400]: \"dyld[74677]: Library not loaded: @executable_path/../opt/glib/lib/libglib-2.0.0.dylib\\n  Referenced from: /Applications/Rancher Desktop.app/Contents/Resources/resources/darwin/lima/Cellar/qemu/6.0.0/bin/qemu-img\\n  Reason: tried: '/Applications/Rancher Desktop.app/Contents/Resources/resources/darwin/lima/Cellar/qemu/6.0.0/bin/../opt/glib/lib/libglib-2.0.0.dylib' (no such file), '/usr/lib/libglib-2.0.0.dylib' (no such file)\\n\": signal: abort trap"
Error starting lima: Error: /Applications/Rancher Desktop.app/Contents/Resources/resources/darwin/lima/bin/limactl exited with code 1
    at ChildProcess.<anonymous> (/Applications/Rancher Desktop.app/Contents/Resources/app.asar/dist/app/background.js:1:3150)
    at ChildProcess.emit (events.js:315:20)
    at Process.ChildProcess._handle.onexit (internal/child_process.js:277:12)

What can one do? And thank you

@jandubois
Copy link
Member

@larssb Your error seems to be a duplicate of #518. It will be fixed in the next release, but there is also a workaround documented in #518 (comment).

@mooneye14
Copy link

mooneye14 commented Sep 7, 2021

cat /var/log/lima-init.log
Was able to connect and verify that the ssh keys are the same as well.

lima-rancher-desktop:~#

lima-rancher-desktop:~# ^[[57;25Rcat /mnt/lima-cidata/user-data
cat /mnt/lima-cidata/user-data
#cloud-config
# vim:syntax=yaml

growpart:
  mode: auto
  devices: ['/']

users:
  - name: "userFirst.userLast"
    uid: "503"
    homedir: "/home/userFirst.userLast.linux"
    shell: /bin/bash
    sudo: ALL=(ALL) NOPASSWD:ALL
    lock_passwd: true
    ssh-authorized-keys:
      - ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDTJQtO9lsLzHY4YIoX2ngP+I45wB9yTsaHuVcQiD13B userFirst.userLast@Work-0ZELVDL

write_files:
 - content: |
      #!/bin/sh
      set -eux
      LIMA_CIDATA_MNT="/mnt/lima-cidata"
      LIMA_CIDATA_DEV="/dev/disk/by-label/cidata"
      mkdir -p -m 700 "${LIMA_CIDATA_MNT}"
      mount -o ro,mode=0700,dmode=0700,overriderockperm,exec,uid=0 "${LIMA_CIDATA_DEV}" "${LIMA_CIDATA_MNT}"
      export LIMA_CIDATA_MNT
      exec "${LIMA_CIDATA_MNT}"/boot.sh
   owner: root:root
   path: /var/lib/cloud/scripts/per-boot/00-lima.boot.sh
   permissions: '0755'
lima-rancher-desktop:~# ^[[57;25Rcat /home/*/.ssh/authorized_keys
cat /home/*/.ssh/authorized_keys
ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDTJQtO9lsLzHY4YIoX2ngP+I45wB9yTsaHuVcQiD13B userFirst.userLast@Work-0ZELVDL

lima-rancher-desktop:~# ^[[57;25Rcat /var/log/lima-init.log
cat /var/log/lima-init.log
+ ln -s /var/log/lima-init.log /var/log/cloud-init-output.log
+ LIMA_CIDATA_MNT=/mnt/lima-cidata
+ LIMA_CIDATA_DEV=/dev/disk/by-label/cidata
+ mkdir -p -m 700 /mnt/lima-cidata
+ mount -o 'ro,mode=0700,dmode=0700,overriderockperm,exec,uid=0' /dev/disk/by-label/cidata /mnt/lima-cidata
+ export LIMA_CIDATA_MNT
+ . /mnt/lima-cidata/lima.env
+ LIMA_CIDATA_USER=userFirst.userLast
+ LIMA_CIDATA_UID=503
+ LIMA_CIDATA_MOUNTS=1
+ LIMA_CIDATA_MOUNTS_0_MOUNTPOINT=/Users/userFirst.userLast/Library/Caches/rancher-desktop/k3s
+ LIMA_CIDATA_CONTAINERD_USER=
+ LIMA_CIDATA_CONTAINERD_SYSTEM=
+ LIMA_CIDATA_SLIRP_GATEWAY=192.168.5.2
+ awk '/^local-hostname:/ {print $2}' /mnt/lima-cidata/meta-data
+ LIMA_CIDATA_HOSTNAME=lima-rancher-desktop
+ hostname lima-rancher-desktop
+ LIMA_CIDATA_HOMEDIR=/home/userFirst.userLast.linux
+ adduser -h /home/userFirst.userLast.linux -u 503 -D userFirst.userLast
+ echo 'userFirst.userLast ALL=(ALL) NOPASSWD:ALL'
+ LIMA_CIDATA_SSHDIR=/home/userFirst.userLast.linux/.ssh
+ mkdir -p -m 700 /home/userFirst.userLast.linux/.ssh
+ awk '/ssh-authorized-keys/ {flag=1; next} /^ *$/ {flag=0} flag {sub(/^ +- /, ""); print $0}' /mnt/lima-cidata/user-data
+ chown -R userFirst.userLast:userFirst.userLast /home/userFirst.userLast.linux/.ssh
chown: unknown user/group userFirst.userLast:userFirst.userLast
lima-rancher-desktop:~# ^[[57;25R

(edited by mook-as: formatting changes only.)

@jandubois
Copy link
Member

Thanks @mooneye14, the limia-init.log shows the problem: chown does not support user or group names that include a ..

This will be fixed by a new ISO image via lima-vm/alpine-lima#13

@jandubois
Copy link
Member

jandubois commented Sep 7, 2021

This issue is getting too crowded, with so many people reporting the same symptoms, but different underlying causes.

We know about 3 issues:

These issues have been already fixed for the next release.

I've created separate issues for any problems related to kim builder mentioned in this issue (#591, #592).

And there is one problem left where qemu exits with "signal: abort trap" (in ha.stderr.log). We don't know yet what is causing this (and why it sometimes seems to fix itself). There are instructions in #532 to collect further information.

I'm going to close this issue now; if you still get the "limactl exited with code 1" error, and it is not covered by any of the scenarios listed above, please open a new separate issue. This includes any further instances of #532.

@vivanov83
Copy link

kim builder install --force
Error: Get "https://127.0.0.1:6443/api/v1/nodes": dial tcp 127.0.0.1:6443: connect: connection refused

I'm getting the same :(

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
kind/bug Something isn't working
Projects
None yet
Development

No branches or pull requests

12 participants