Skip to content

Build vs image timeout #11620

@xumia

Description

@xumia

Description

The vm has already started successfully, and prompt the "sonic login:" message, but the check_install.py is still waiting for it.

2022-08-04T09:23:42.8905416Z Debian GNU/Linux 11 sonic ttyS0
2022-08-04T09:23:42.8905787Z
2022-08-04T09:23:42.8906200Z [ 23.154924] rc.local[348]: + [ -n ]
2022-08-04T09:23:42.8906808Z sonic [ 23.206462] rc.local[348]: + [ -n x86_64-kvm_x86_64-r0 ]
2022-08-04T09:23:42.8907426Z login: [ 23.265424] rc.local[348]: + platform=x86_64-kvm_x86_64-r0
2022-08-04T09:23:42.8908289Z [ 23.325393] rc.local[348]: + [ -d /host/old_config ]

2022-08-04T09:23:42.8900452Z [   22.587784] rc.local[348]: + [ 0 = 0 ]
2022-08-04T09:23:42.8901296Z [   22.653463] rc.local[348]: + sed -i s|\-\-keep\-baud .* %I| 115200 %I|g /lib/systemd/system/serial-getty@.service
2022-08-04T09:23:42.8901889Z [   22.773574] rc.local[348]: + systemctl daemon-reload
2022-08-04T09:23:42.8903205Z [   22.843848] rc.local[348]: + [ -f /host/image-master-11447.130796-76ae725ca/platform/firsttime ]
2022-08-04T09:23:42.8903918Z [   22.987651] rc.local[348]: + echo First boot detected. Performing first boot tasks...
2022-08-04T09:23:42.8904836Z [   23.073868] rc.local[348]: First boot detected. Performing first boot tasks...
2022-08-04T09:23:42.8905077Z 
2022-08-04T09:23:42.8905164Z 
2022-08-04T09:23:42.8905416Z Debian GNU/Linux 11 sonic ttyS0
2022-08-04T09:23:42.8905787Z 
2022-08-04T09:23:42.8906200Z [   23.154924] rc.local[348]: + [ -n  ]
2022-08-04T09:23:42.8906808Z sonic [   23.206462] rc.local[348]: + [ -n x86_64-kvm_x86_64-r0 ]
2022-08-04T09:23:42.8907426Z login: [   23.265424] rc.local[348]: + platform=x86_64-kvm_x86_64-r0
2022-08-04T09:23:42.8908289Z [   23.325393] rc.local[348]: + [ -d /host/old_config ]
2022-08-04T09:23:42.8909202Z [   23.377605] rc.local[348]: + [ -f /host/minigraph.xml ]
2022-08-04T09:23:42.8909623Z [   23.428046] rc.local[348]: + [ -n  ]
2022-08-04T09:23:42.8910203Z [   23.467036] rc.local[348]: + touch /tmp/pending_config_initialization
2022-08-04T09:23:42.8910591Z [   23.525363] rc.local[348]: + touch /tmp/notify_firstboot_to_platform
2022-08-04T09:23:42.8911368Z [   23.581404] rc.local[348]: + [ ! -d /host/reboot-cause/platform ]
2022-08-04T09:23:42.8911901Z [   23.638691] rc.local[348]: + mkdir -p /host/reboot-cause/platform
2022-08-04T09:23:42.8912624Z [   23.684432] rc.local[348]: + [ -d /host/image-master-11447.130796-76ae725ca/platform/x86_64-kvm_x86_64-r0 ]
2022-08-04T09:23:42.8913034Z [   23.747779] rc.local[348]: + sync
2022-08-04T09:23:42.8913667Z [   23.777573] rc.local[348]: + [ -n x86_64-kvm_x86_64-r0 ]
2022-08-04T09:23:42.8914291Z [   23.821412] rc.local[348]: + [ -n  ]
2022-08-04T09:23:42.8914793Z [   23.854879] rc.local[348]: + mkdir -p /var/platform
2022-08-04T09:23:42.8915449Z [   23.895074] rc.local[348]: + [ -f /etc/default/kdump-tools ]
2022-08-04T09:23:42.8915856Z [   23.937529] rc.local[348]: + firsttime_exit
2022-08-04T09:23:42.8916442Z [   23.969201] rc.local[348]: + rm -rf /host/image-master-11447.130796-76ae725ca/platform/firsttime
2022-08-04T09:23:42.8916822Z [   24.031359] rc.local[348]: + exit 0
2022-08-04T09:23:42.8917155Z Traceback (most recent call last):
2022-08-04T09:23:42.8917458Z   File "/sonic/./check_install.py", line 64, in <module>
2022-08-04T09:23:42.8917770Z     main()
2022-08-04T09:23:42.8918024Z   File "/sonic/./check_install.py", line 41, in main
2022-08-04T09:23:42.8918412Z     i = p.expect([login_prompt, passwd_prompt, cmd_prompt])
2022-08-04T09:23:42.8918982Z   File "/usr/local/lib/python3.9/dist-packages/pexpect/spawnbase.py", line 343, in expect
2022-08-04T09:23:42.8919431Z     return self.expect_list(compiled_pattern_list,
2022-08-04T09:23:42.8919998Z   File "/usr/local/lib/python3.9/dist-packages/pexpect/spawnbase.py", line 372, in expect_list
2022-08-04T09:23:42.8920452Z     return exp.expect_loop(timeout)
2022-08-04T09:23:42.8920988Z   File "/usr/local/lib/python3.9/dist-packages/pexpect/expect.py", line 181, in expect_loop
2022-08-04T09:23:42.8921606Z     return self.timeout(e)
2022-08-04T09:23:42.8922311Z   File "/usr/local/lib/python3.9/dist-packages/pexpect/expect.py", line 144, in timeout
2022-08-04T09:23:42.8922688Z     raise exc
2022-08-04T09:23:42.8922947Z pexpect.exceptions.TIMEOUT: Timeout exceeded.
2022-08-04T09:23:42.8923271Z <pexpect.pty_spawn.spawn object at 0x7f6df3ffeaf0>
2022-08-04T09:23:42.8923618Z command: /usr/bin/telnet
2022-08-04T09:23:42.8924044Z args: [b'/usr/bin/telnet', b'127.0.0.1', b'9000']
2022-08-04T09:23:42.8924772Z buffer (last 100 chars): 'ost/image-master-11447.130796-76ae725ca/platform/firsttime\r\n[   24.031359] rc.local[348]: + exit 0\r\n'
2022-08-04T09:23:42.8925713Z before (last 100 chars): 'ost/image-master-11447.130796-76ae725ca/platform/firsttime\r\n[   24.031359] rc.local[348]: + exit 0\r\n'
2022-08-04T09:23:42.8926342Z after: <class 'pexpect.exceptions.TIMEOUT'>
2022-08-04T09:23:42.8926599Z match: None
2022-08-04T09:23:42.8926866Z match_index: None

Steps to reproduce the issue:

Describe the results you received:

Describe the results you expected:

Output of show version:

(paste your output here)

Output of show techsupport:

(paste your output here or download and attach the file here )

Additional information you deem important (e.g. issue happens only occasionally):

Metadata

Metadata

Assignees

Labels

Triagedthis issue has been triaged

Type

No type

Projects

No projects

Milestone

No milestone

Relationships

None yet

Development

No branches or pull requests

Issue actions