Girish Shilamkar created CLOUDSTACK-4637:
--------------------------------------------
Summary: test_egress_fw_rules.py fails with Script result is
['send: spawn id exp3 not open' ....'] is not matching with ['100']
Key: CLOUDSTACK-4637
URL: https://issues.apache.org/jira/browse/CLOUDSTACK-4637
Project: CloudStack
Issue Type: Bug
Security Level: Public (Anyone can view this level - this is the default.)
Components: Automation
Reporter: Girish Shilamkar
Assignee: Girish Shilamkar
Priority: Critical
Still fails
Script result is ['send: spawn id exp3 not open', ' while executing', '"send
"ssh -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o
LogLevel=quiet root@10.1.1.183 ping -c 1 www.google.com; exit $?', '""', '
(file "/tmp/expect_script.exp" line 4)'] is not matching with ['100']
-------------------- >> begin captured logging << --------------------
testclient.testcase.TestEgressFWRules: DEBUG: Creating network with network
offering: 05d985b1-ee1c-4b04-9cee-d6c97e43032d
testclient.testcase.TestEgressFWRules: DEBUG: Created network with ID:
02187a7a-9afe-45ff-b9c3-069b00c731fc
testclient.testcase.TestEgressFWRules: DEBUG: Deploying instance in the
account: test-B0F0Z8
testclient.testcase.TestEgressFWRules: DEBUG: Deployed instance in account:
test-B0F0Z8
testclient.testcase.TestEgressFWRules: DEBUG: Creating Egress FW rule for
networkid=02187a7a-9afe-45ff-b9c3-069b00c731fc networkname=Test Network
testclient.testcase.TestEgressFWRules: DEBUG: Created
rule=fe84b110-8042-47d3-8b72-917607ab8811
testclient.testcase.TestEgressFWRules: DEBUG: expect_script>>
#!/usr/bin/expect
spawn ssh -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o
LogLevel=quiet -i /root/.ssh/id_rsa.cloud -p 3922 root@169.254.2.102
expect "root@r-588-QA:~#"
send "ssh -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o
LogLevel=quiet root@10.1.1.183 ping -c 1 www.google.com; exit $?
"
expect "root@10.1.1.183's password: "
send "password
"
interact
<<expect_script
paramiko.transport: DEBUG: starting thread (client mode): 0x204143d0L
paramiko.transport: INFO: Connected (version 2.0, client OpenSSH_5.3)
paramiko.transport: DEBUG: kex algos:['diffie-hellman-group-exchange-sha256',
'diffie-hellman-group-exchange-sha1', 'diffie-hellman-group14-sha1',
'diffie-hellman-group1-sha1'] server key:['ssh-rsa', 'ssh-dss'] client
encrypt:['aes128-ctr', 'aes192-ctr', 'aes256-ctr', 'arcfour256', 'arcfour128',
'aes128-cbc', '3des-cbc', 'blowfish-cbc', 'cast128-cbc', 'aes192-cbc',
'aes256-cbc', 'arcfour', 'rijndael-...@lysator.liu.se'] server
encrypt:['aes128-ctr', 'aes192-ctr', 'aes256-ctr', 'arcfour256', 'arcfour128',
'aes128-cbc', '3des-cbc', 'blowfish-cbc', 'cast128-cbc', 'aes192-cbc',
'aes256-cbc', 'arcfour', 'rijndael-...@lysator.liu.se'] client mac:['hmac-md5',
'hmac-sha1', 'umac...@openssh.com', 'hmac-ripemd160',
'hmac-ripemd...@openssh.com', 'hmac-sha1-96', 'hmac-md5-96'] server
mac:['hmac-md5', 'hmac-sha1', 'umac...@openssh.com', 'hmac-ripemd160',
'hmac-ripemd...@openssh.com', 'hmac-sha1-96', 'hmac-md5-96'] client
compress:['none', 'z...@openssh.com'] server compress:['none',
'z...@openssh.com'] client lang:[''] server lang:[''] kex follows?False
paramiko.transport: DEBUG: Ciphers agreed: local=aes128-ctr, remote=aes128-ctr
paramiko.transport: DEBUG: using kex diffie-hellman-group1-sha1; server key
type ssh-rsa; cipher: local aes128-ctr, remote aes128-ctr; mac: local
hmac-sha1, remote hmac-sha1; compression: local none, remote none
paramiko.transport: DEBUG: Switch to new keys ...
paramiko.transport: DEBUG: Adding ssh-rsa host key for 10.223.50.66:
41aef5f507e1ddd99077b11f4bf26553
paramiko.transport: DEBUG: Trying discovered key
76be480fa6b8ad3b78082d6d19e4ee44 in /root/.ssh/id_rsa
paramiko.transport: DEBUG: userauth is OK
paramiko.transport: INFO: Authentication (publickey) failed.
paramiko.transport: DEBUG: userauth is OK
paramiko.transport: INFO: Authentication (password) successful!
sshClient: DEBUG: SSH connect: root@10.223.50.66 with passwd password
testclient.testcase.TestEgressFWRules: DEBUG: SSH client to : 10.223.50.66
obtained
paramiko.transport: DEBUG: starting thread (client mode): 0x204b6b90L
paramiko.transport: INFO: Connected (version 2.0, client OpenSSH_5.3)
paramiko.transport: DEBUG: kex algos:['diffie-hellman-group-exchange-sha256',
'diffie-hellman-group-exchange-sha1', 'diffie-hellman-group14-sha1',
'diffie-hellman-group1-sha1'] server key:['ssh-rsa', 'ssh-dss'] client
encrypt:['aes128-ctr', 'aes192-ctr', 'aes256-ctr', 'arcfour256', 'arcfour128',
'aes128-cbc', '3des-cbc', 'blowfish-cbc', 'cast128-cbc', 'aes192-cbc',
'aes256-cbc', 'arcfour', 'rijndael-...@lysator.liu.se'] server
encrypt:['aes128-ctr', 'aes192-ctr', 'aes256-ctr', 'arcfour256', 'arcfour128',
'aes128-cbc', '3des-cbc', 'blowfish-cbc', 'cast128-cbc', 'aes192-cbc',
'aes256-cbc', 'arcfour', 'rijndael-...@lysator.liu.se'] client mac:['hmac-md5',
'hmac-sha1', 'umac...@openssh.com', 'hmac-ripemd160',
'hmac-ripemd...@openssh.com', 'hmac-sha1-96', 'hmac-md5-96'] server
mac:['hmac-md5', 'hmac-sha1', 'umac...@openssh.com', 'hmac-ripemd160',
'hmac-ripemd...@openssh.com', 'hmac-sha1-96', 'hmac-md5-96'] client
compress:['none', 'z...@openssh.com'] server compress:['none',
'z...@openssh.com'] client lang:[''] server lang:[''] kex follows?False
paramiko.transport: DEBUG: Ciphers agreed: local=aes128-ctr, remote=aes128-ctr
paramiko.transport: DEBUG: using kex diffie-hellman-group1-sha1; server key
type ssh-rsa; cipher: local aes128-ctr, remote aes128-ctr; mac: local
hmac-sha1, remote hmac-sha1; compression: local none, remote none
paramiko.transport: DEBUG: Switch to new keys ...
paramiko.transport: DEBUG: Attempting password auth...
paramiko.transport: DEBUG: userauth is OK
paramiko.transport: INFO: Authentication (password) successful!
paramiko.transport: DEBUG: [chan 1] Max packet in: 34816 bytes
paramiko.transport: DEBUG: [chan 1] Max packet out: 32768 bytes
paramiko.transport: INFO: Secsh channel 1 opened.
paramiko.transport: DEBUG: [chan 1] Sesch channel 1 request ok
paramiko.transport.sftp: INFO: [chan 1] Opened sftp connection (server version
3)
paramiko.transport.sftp: DEBUG: [chan 1] open('/tmp/expect_script.exp', 'wb')
paramiko.transport.sftp: DEBUG: [chan 1] open('/tmp/expect_script.exp', 'wb')
-> 00000000
paramiko.transport.sftp: DEBUG: [chan 1] close(00000000)
paramiko.transport.sftp: DEBUG: [chan 1] stat('/tmp/expect_script.exp')
paramiko.transport: DEBUG: [chan 1] Max packet in: 34816 bytes
paramiko.transport: DEBUG: [chan 1] Max packet out: 32768 bytes
paramiko.transport: INFO: Secsh channel 1 opened.
paramiko.transport: DEBUG: [chan 1] Sesch channel 1 request ok
paramiko.transport: DEBUG: [chan 1] EOF received (1)
paramiko.transport: DEBUG: [chan 1] EOF sent (1)
sshClient: DEBUG:
{Cmd: chmod +x /tmp/expect_script.exp via Host: 10.223.50.66}
{returns: []}
testclient.testcase.TestEgressFWRules: DEBUG: /tmp/expect_script.exp | grep -oP
'\d+(?=% packet loss)'
testclient.testcase.TestEgressFWRules: DEBUG: sleep 30 seconds for egress rule
to affect on Router.
paramiko.transport: DEBUG: [chan 2] Max packet in: 34816 bytes
paramiko.transport: DEBUG: [chan 2] Max packet out: 32768 bytes
paramiko.transport: INFO: Secsh channel 2 opened.
paramiko.transport: DEBUG: [chan 2] Sesch channel 2 request ok
paramiko.transport: DEBUG: [chan 2] EOF received (2)
paramiko.transport: DEBUG: [chan 2] EOF sent (2)
sshClient: DEBUG:
{Cmd: /tmp/expect_script.exp | grep -oP '\d+(?=% packet loss)' via Host:
10.223.50.66}
{returns: ['send: spawn id exp3 not open', ' while executing', '"send "ssh -o
UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=quiet
root@10.1.1.183 ping -c 1 www.google.com; exit $?', '""', ' (file
"/tmp/expect_script.exp" line 4)']}
testclient.testcase.TestEgressFWRules: DEBUG: Result is=['send: spawn id exp3
not open', ' while executing', '"send "ssh -o UserKnownHostsFile=/dev/null -o
StrictHostKeyChecking=no -o LogLevel=quiet root@10.1.1.183 ping -c 1
www.google.com; exit $?', '""', ' (file "/tmp/expect_script.exp" line 4)']
paramiko.transport: DEBUG: [chan 3] Max packet in: 34816 bytes
paramiko.transport: DEBUG: [chan 3] Max packet out: 32768 bytes
paramiko.transport: INFO: Secsh channel 3 opened.
paramiko.transport: DEBUG: [chan 3] Sesch channel 3 request ok
paramiko.transport: DEBUG: [chan 3] EOF received (3)
paramiko.transport: DEBUG: [chan 3] EOF sent (3)
sshClient: DEBUG:
{Cmd: rm -rf /tmp/expect_script.exp via Host: 10.223.50.66}
{returns: []}
testclient.testcase.TestEgressFWRules: DEBUG: Error=Script result is ['send:
spawn id exp3 not open', ' while executing', '"send "ssh -o
UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=quiet
root@10.1.1.183 ping -c 1 www.google.com; exit $?', '""', ' (file
"/tmp/expect_script.exp" line 4)'] is not matching with ['100']
testclient.testcase.TestEgressFWRules: DEBUG: Test <function test_09_egress_fr9
at 0x2023c5f0> Failed due to Exception=Script result is ['send: spawn id exp3
not open', ' while executing', '"send "ssh -o UserKnownHostsFile=/dev/null -o
StrictHostKeyChecking=no -o LogLevel=quiet root@10.1.1.183 ping -c 1
www.google.com; exit $?', '""', ' (file "/tmp/expect_script.exp" line 4)'] is
not matching with ['100']
paramiko.transport: DEBUG: EOF in transport thread
--------------------- >> end captured logging << ---------------------
Stacktrace
File "/usr/local/lib/python2.7/unittest/case.py", line 318, in run
testMethod()
File
"/Repo_30X/ipcl/cloudstack/test/integration/component/test_egress_fw_rules.py",
line 51, in test_wrap_exception_log
raise e
Script result is ['send: spawn id exp3 not open', ' while executing', '"send
"ssh -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o
LogLevel=quiet root@10.1.1.183 ping -c 1 www.google.com; exit $?', '""', '
(file "/tmp/expect_script.exp" line 4)'] is not matching with ['100']
--
This message is automatically generated by JIRA.
If you think it was sent incorrectly, please contact your JIRA administrators
For more information on JIRA, see: http://www.atlassian.com/software/jira
