<?xml version="1.0" encoding="UTF-8"?>
<robot generator="Robot 7.4.2 (Python 3.11.10 on linux)" generated="2026-05-01T00:46:20.876807" rpa="false" schemaversion="5">
<suite id="s1" name="openflowplugin-cbench.txt" source="/w/workspace/openflowplugin-csit-1node-cbench-only-vanadium/test/csit/suites/openflowplugin/Performance/010_Cbench.robot">
<kw name="Cbench Suite Setup" type="SETUP">
<kw name="Wait Until Keyword Succeeds" owner="BuiltIn">
<kw name="Issue_Command_On_Karaf_Console" owner="KarafKeywords">
<if>
<branch type="IF" condition="'${IS_KARAF_APPL}' == 'False'">
<return>
<value>Not A Karaf App</value>
<status status="NOT RUN" start="2026-05-01T00:46:21.450973" elapsed="0.000023"/>
</return>
<status status="NOT RUN" start="2026-05-01T00:46:21.450835" elapsed="0.000209"/>
</branch>
<status status="PASS" start="2026-05-01T00:46:21.450804" elapsed="0.000266"/>
</if>
<kw name="Open Connection" owner="SSHLibrary">
<arg>${controller}</arg>
<arg>port=${karaf_port}</arg>
<arg>prompt=${KARAF_PROMPT_LOGIN}</arg>
<arg>timeout=${timeout}</arg>
<doc>Opens a new SSH connection to the given ``host`` and ``port``.</doc>
<status status="PASS" start="2026-05-01T00:46:21.451246" elapsed="0.000526"/>
</kw>
<kw name="Login" owner="SSHLibrary">
<msg time="2026-05-01T00:46:21.452249" level="INFO">Logging into '10.30.171.63:8101' as 'karaf'.</msg>
<msg time="2026-05-01T00:46:22.142745" level="INFO">Read output: [33m                                                                                           
[33m    ________                       ________                .__  .__       .__     __       
[33m    \_____  \ ______   ____   ____ \______ \ _____  ___.__.|  | |__| ____ |  |___/  |_     
[33m     /   |   \\____ \_/ __ \ /    \ |    |  \\__  \&lt;   |  ||  | |  |/ ___\|  |  \   __\    
[33m    /    |    \  |_&gt; &gt;  ___/|   |  \|    `   \/ __ \\___  ||  |_|  / /_/  &gt;   Y  \  |      
[33m    \_______  /   __/ \___  &gt;___|  /_______  (____  / ____||____/__\___  /|___|  /__|      
[33m            \/|__|        \/     \/        \/     \/\/            /_____/      \/          
[33m                                                                                           

Hit '[1m&lt;tab&gt;[0m' for a list of available commands
and '[1m[cmd] --help[0m' for help on a specific command.
Hit '[1m&lt;ctrl-d&gt;[0m' or type '[1msystem:shutdown[0m' or '[1mlogout[0m' to shutdown OpenDaylight.

[?1h=[90m~[0m                                                                                [?2004hopendaylight-user</msg>
<arg>${KARAF_USER}</arg>
<arg>${KARAF_PASSWORD}</arg>
<arg>loglevel=${loglevel}</arg>
<doc>Logs into the SSH server with the given ``username`` and ``password``.</doc>
<status status="PASS" start="2026-05-01T00:46:21.451951" elapsed="0.690986"/>
</kw>
<kw name="Write" owner="SSHLibrary">
<msg time="2026-05-01T00:46:22.191779" level="INFO">@root&gt;log:set ERROR</msg>
<arg>${cmd}</arg>
<doc>Writes the given ``text`` on the remote machine and appends a newline.</doc>
<status status="PASS" start="2026-05-01T00:46:22.143228" elapsed="0.048721"/>
</kw>
<kw name="Read Until Regexp" owner="SSHLibrary">
<msg time="2026-05-01T00:46:22.193859" level="INFO">[?1l&gt;[?2004l[?1h=[90m~[0m                                                                                [?2004hopendaylight-user@root&gt;</msg>
<msg time="2026-05-01T00:46:22.193972" level="INFO">${output} = [?1l&gt;[?2004l[?1h=[90m~[0m                                                                                [?2004hopendaylight-user@root&gt;</msg>
<var>${output}</var>
<arg>${KARAF_PROMPT}</arg>
<doc>Consumes and returns the server output until a match to ``regexp`` is found.</doc>
<status status="PASS" start="2026-05-01T00:46:22.192241" elapsed="0.001761"/>
</kw>
<kw name="Write Bare" owner="SSHLibrary">
<arg>logout\n</arg>
<doc>Writes the given ``text`` on the remote machine without appending a newline.</doc>
<status status="PASS" start="2026-05-01T00:46:22.194164" elapsed="0.000283"/>
</kw>
<kw name="Close Connection" owner="SSHLibrary">
<doc>Closes the current connection.</doc>
<status status="PASS" start="2026-05-01T00:46:22.194608" elapsed="0.000229"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-05-01T00:46:22.195329" level="INFO">[?1l&gt;[?2004l[?1h=[90m~[0m                                                                                [?2004hopendaylight-user@root&gt;</msg>
<arg>${output}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-05-01T00:46:22.194994" elapsed="0.000389"/>
</kw>
<return>
<value>${output}</value>
<status status="PASS" start="2026-05-01T00:46:22.195436" elapsed="0.000054"/>
</return>
<arg>log:set ${log_level}</arg>
<doc>Will execute the given ${cmd} by ssh'ing to the karaf console running on 10.30.171.63
Note that this keyword will open&amp;close new SSH connection, without switching back to previously current session.</doc>
<status status="PASS" start="2026-05-01T00:46:21.450432" elapsed="0.745195"/>
</kw>
<arg>3x</arg>
<arg>1s</arg>
<arg>KarafKeywords.Issue Command On Karaf Console</arg>
<arg>log:set ${log_level}</arg>
<doc>Runs the specified keyword and retries if it fails.</doc>
<status status="PASS" start="2026-05-01T00:46:21.446798" elapsed="0.748918"/>
</kw>
<kw name="Append To File" owner="OperatingSystem">
<msg time="2026-05-01T00:46:22.196329" level="INFO" html="true">Appended to file '&lt;a href="file:///w/workspace/openflowplugin-csit-1node-cbench-only-vanadium/latency.csv"&gt;/w/workspace/openflowplugin-csit-1node-cbench-only-vanadium/latency.csv&lt;/a&gt;'.</msg>
<arg>${latency_results_file}</arg>
<arg>LATENCY_MIN,LATENCY_MAX,LATENCY_AVERAGE\n</arg>
<doc>Appends the given content to the specified file.</doc>
<status status="PASS" start="2026-05-01T00:46:22.195900" elapsed="0.000488"/>
</kw>
<kw name="Append To File" owner="OperatingSystem">
<msg time="2026-05-01T00:46:22.196817" level="INFO" html="true">Appended to file '&lt;a href="file:///w/workspace/openflowplugin-csit-1node-cbench-only-vanadium/throughput.csv"&gt;/w/workspace/openflowplugin-csit-1node-cbench-only-vanadium/throughput.csv&lt;/a&gt;'.</msg>
<arg>${throughput_results_file}</arg>
<arg>THROUGHPUT_MIN,THROUGHPUT_MAX,THROUGHPUT_AVERAGE\n</arg>
<doc>Appends the given content to the specified file.</doc>
<status status="PASS" start="2026-05-01T00:46:22.196543" elapsed="0.000331"/>
</kw>
<kw name="Evaluate" owner="BuiltIn">
<msg time="2026-05-01T00:46:22.197466" level="INFO">${duration_in_ms} = 60000</msg>
<var>${duration_in_ms}</var>
<arg>${duration_in_secs} * 1000</arg>
<doc>Evaluates the given expression in Python and returns the result.</doc>
<status status="PASS" start="2026-05-01T00:46:22.197032" elapsed="0.000464"/>
</kw>
<kw name="Set Suite Variable" owner="BuiltIn">
<msg time="2026-05-01T00:46:22.198031" level="INFO">${duration_in_ms} = 60000</msg>
<arg>${duration_in_ms}</arg>
<doc>Makes a variable available everywhere within the scope of the current suite.</doc>
<status status="PASS" start="2026-05-01T00:46:22.197686" elapsed="0.000397"/>
</kw>
<kw name="Evaluate" owner="BuiltIn">
<msg time="2026-05-01T00:46:22.198699" level="INFO">${test_timeout} = 900.0</msg>
<var>${test_timeout}</var>
<arg>(${loops} * ${duration_in_secs}) * 1.5</arg>
<doc>Evaluates the given expression in Python and returns the result.</doc>
<status status="PASS" start="2026-05-01T00:46:22.198280" elapsed="0.000448"/>
</kw>
<kw name="Set Suite Variable" owner="BuiltIn">
<msg time="2026-05-01T00:46:22.199212" level="INFO">${test_timeout} = 900.0</msg>
<arg>${test_timeout}</arg>
<doc>Makes a variable available everywhere within the scope of the current suite.</doc>
<status status="PASS" start="2026-05-01T00:46:22.198892" elapsed="0.000361"/>
</kw>
<if>
<branch type="IF" condition="&quot;${cbench_system}&quot; == &quot;localhost&quot;">
<kw name="File Should Exist" owner="OperatingSystem">
<arg>${cbench_executable}</arg>
<doc>Fails unless the given ``path`` points to an existing file.</doc>
<status status="NOT RUN" start="2026-05-01T00:46:22.199606" elapsed="0.000055"/>
</kw>
<status status="NOT RUN" start="2026-05-01T00:46:22.199344" elapsed="0.000361"/>
</branch>
<branch type="ELSE">
<kw name="Verify File Exists On Remote System" owner="Utils">
<kw name="Open Connection" owner="SSHLibrary">
<msg time="2026-05-01T00:46:22.204518" level="INFO">${conn_id} = 2</msg>
<var>${conn_id}</var>
<arg>${system}</arg>
<arg>prompt=${prompt}</arg>
<arg>timeout=${prompt_timeout}</arg>
<doc>Opens a new SSH connection to the given ``host`` and ``port``.</doc>
<status status="PASS" start="2026-05-01T00:46:22.204085" elapsed="0.000462"/>
</kw>
<kw name="Flexible_SSH_Login" owner="SSHKeywords">
<kw name="Get Length" owner="BuiltIn">
<msg time="2026-05-01T00:46:22.205613" level="INFO">Length is 0.</msg>
<msg time="2026-05-01T00:46:22.205713" level="INFO">${pwd_length} = 0</msg>
<var>${pwd_length}</var>
<arg>${password}</arg>
<doc>Returns and logs the length of the given item as an integer.</doc>
<status status="PASS" start="2026-05-01T00:46:22.205140" elapsed="0.000598"/>
</kw>
<kw name="Run Keyword And Return If" owner="BuiltIn">
<arg>${pwd_length} &gt; 0</arg>
<arg>SSHLibrary.Login</arg>
<arg>${user}</arg>
<arg>${password}</arg>
<arg>delay=${delay}</arg>
<doc>Runs the specified keyword and returns from the enclosing user keyword.</doc>
<status status="PASS" start="2026-05-01T00:46:22.205885" elapsed="0.000364"/>
</kw>
<kw name="Run Keyword And Return" owner="BuiltIn">
<kw name="Login With Public Key" owner="SSHLibrary">
<msg time="2026-05-01T00:46:22.207325" level="INFO">Logging into '10.30.171.37:22' as 'jenkins'.</msg>
<msg time="2026-05-01T00:46:22.903656" level="INFO">Read output: Welcome to Ubuntu 22.04.5 LTS (GNU/Linux 5.15.0-153-generic x86_64)

 * Documentation:  https://help.ubuntu.com
 * Management:     https://landscape.canonical.com
 * Support:        https://ubuntu.com/pro

 System information as of Fri May  1 00:46:22 UTC 2026

  System load:  0.08               Processes:             110
  Usage of /:   19.3% of 38.58GB   Users logged in:       0
  Memory usage: 4%                 IPv4 address for ens3: 10.30.171.37
  Swap usage:   0%


Expanded Security Maintenance for Applications is not enabled.

0 updates can be applied immediately.

9 additional security updates can be applied with ESM Apps.
Learn more about enabling ESM Apps service at https://ubuntu.com/esm


The list of available updates is more than a week old.
To check for new updates run: sudo apt update
New release '24.04.4 LTS' available.
Run 'do-release-upgrade' to upgrade to it.


[?2004h[jenkins@releng-63164-215-1-mininet-ovs-217-0 ~]&gt;</msg>
<arg>${user}</arg>
<arg>${USER_HOME}/.ssh/${SSH_KEY}</arg>
<arg>${KEYFILE_PASS}</arg>
<arg>delay=${delay}</arg>
<doc>Logs into the SSH server using key-based authentication.</doc>
<status status="PASS" start="2026-05-01T00:46:22.206773" elapsed="0.697042"/>
</kw>
<msg time="2026-05-01T00:46:22.903909" level="INFO">Returning from the enclosing user keyword.</msg>
<arg>SSHLibrary.Login With Public Key</arg>
<arg>${user}</arg>
<arg>${USER_HOME}/.ssh/${SSH_KEY}</arg>
<arg>${KEYFILE_PASS}</arg>
<arg>delay=${delay}</arg>
<doc>Runs the specified keyword and returns from the enclosing user keyword.</doc>
<status status="PASS" start="2026-05-01T00:46:22.206405" elapsed="0.697621"/>
</kw>
<arg>${user}</arg>
<arg>${password}</arg>
<doc>On active SSH session: if given non-empty password, do Login, else do Login With Public Key.</doc>
<status status="PASS" start="2026-05-01T00:46:22.204789" elapsed="0.699362"/>
</kw>
<kw name="File Should Exist" owner="SSHLibrary">
<msg time="2026-05-01T00:46:22.911743" level="INFO">[chan 1] Opened sftp connection (server version 3)</msg>
<arg>${file}</arg>
<doc>Fails if the given ``path`` does NOT point to an existing file.</doc>
<status status="PASS" start="2026-05-01T00:46:22.904369" elapsed="0.009195"/>
</kw>
<kw name="Close Connection" owner="SSHLibrary">
<doc>Closes the current connection.</doc>
<status status="PASS" start="2026-05-01T00:46:22.914001" elapsed="0.000606"/>
</kw>
<arg>${cbench_system}</arg>
<arg>${cbench_executable}</arg>
<doc>Will create connection with public key and will PASS if the given ${file} exists,
otherwise will FAIL</doc>
<status status="PASS" start="2026-05-01T00:46:22.203486" elapsed="0.711295"/>
</kw>
<status status="PASS" start="2026-05-01T00:46:22.199985" elapsed="0.714881"/>
</branch>
<status status="PASS" start="2026-05-01T00:46:22.199315" elapsed="0.715619"/>
</if>
<kw name="Should Be True" owner="BuiltIn">
<arg>${loops} &gt;= 2</arg>
<arg>If number of loops is less than 2, cbench will not run</arg>
<doc>Fails if the given condition is not true.</doc>
<status status="PASS" start="2026-05-01T00:46:22.915279" elapsed="0.001130"/>
</kw>
<kw name="Verify_Feature_Is_Installed" owner="KarafKeywords">
<if>
<branch type="IF" condition="'${IS_KARAF_APPL}' == 'False'">
<return>
<value>Not A Karaf App</value>
<status status="NOT RUN" start="2026-05-01T00:46:22.923030" elapsed="0.000026"/>
</return>
<status status="NOT RUN" start="2026-05-01T00:46:22.922857" elapsed="0.000248"/>
</branch>
<status status="PASS" start="2026-05-01T00:46:22.922822" elapsed="0.000317"/>
</if>
<kw name="Issue_Command_On_Karaf_Console" owner="KarafKeywords">
<if>
<branch type="IF" condition="'${IS_KARAF_APPL}' == 'False'">
<return>
<value>Not A Karaf App</value>
<status status="NOT RUN" start="2026-05-01T00:46:22.927409" elapsed="0.000014"/>
</return>
<status status="NOT RUN" start="2026-05-01T00:46:22.927288" elapsed="0.000168"/>
</branch>
<status status="PASS" start="2026-05-01T00:46:22.927261" elapsed="0.000218"/>
</if>
<kw name="Open Connection" owner="SSHLibrary">
<arg>${controller}</arg>
<arg>port=${karaf_port}</arg>
<arg>prompt=${KARAF_PROMPT_LOGIN}</arg>
<arg>timeout=${timeout}</arg>
<doc>Opens a new SSH connection to the given ``host`` and ``port``.</doc>
<status status="PASS" start="2026-05-01T00:46:22.927613" elapsed="0.000475"/>
</kw>
<kw name="Login" owner="SSHLibrary">
<msg time="2026-05-01T00:46:22.928498" level="INFO">Logging into '10.30.171.63:8101' as 'karaf'.</msg>
<msg time="2026-05-01T00:46:23.103289" level="INFO">Read output: [33m                                                                                           
[33m    ________                       ________                .__  .__       .__     __       
[33m    \_____  \ ______   ____   ____ \______ \ _____  ___.__.|  | |__| ____ |  |___/  |_     
[33m     /   |   \\____ \_/ __ \ /    \ |    |  \\__  \&lt;   |  ||  | |  |/ ___\|  |  \   __\    
[33m    /    |    \  |_&gt; &gt;  ___/|   |  \|    `   \/ __ \\___  ||  |_|  / /_/  &gt;   Y  \  |      
[33m    \_______  /   __/ \___  &gt;___|  /_______  (____  / ____||____/__\___  /|___|  /__|      
[33m            \/|__|        \/     \/        \/     \/\/            /_____/      \/          
[33m                                                                                           

Hit '[1m&lt;tab&gt;[0m' for a list of available commands
and '[1m[cmd] --help[0m' for help on a specific command.
Hit '[1m&lt;ctrl-d&gt;[0m' or type '[1msystem:shutdown[0m' or '[1mlogout[0m' to shutdown OpenDaylight.

[?1h=[90m~[0m                                                                                [?2004hopendaylight-user</msg>
<arg>${KARAF_USER}</arg>
<arg>${KARAF_PASSWORD}</arg>
<arg>loglevel=${loglevel}</arg>
<doc>Logs into the SSH server with the given ``username`` and ``password``.</doc>
<status status="PASS" start="2026-05-01T00:46:22.928237" elapsed="0.175263"/>
</kw>
<kw name="Write" owner="SSHLibrary">
<msg time="2026-05-01T00:46:23.199311" level="INFO">@root&gt;feature:list -i | 0[C 0g[Krep odl-openflowplugin-drop-test</msg>
<arg>${cmd}</arg>
<doc>Writes the given ``text`` on the remote machine and appends a newline.</doc>
<status status="PASS" start="2026-05-01T00:46:23.103855" elapsed="0.095607"/>
</kw>
<kw name="Read Until Regexp" owner="SSHLibrary">
<msg time="2026-05-01T00:46:23.239187" level="INFO">[?1l&gt;[?2004l[31;1modl-openflowplugin-drop-test[0m                                    x 0.21.3           x x        x Started x [31;1modl-openflowplugin-drop-test[0m                                    x OpenDaylight :: Openflow Plugin :: Drop Test
[?1h=[90m~[0m                                                                                [?2004hopendaylight-user@root&gt;</msg>
<msg time="2026-05-01T00:46:23.239553" level="INFO">${output} = [?1l&gt;[?2004l[31;1modl-openflowplugin-drop-test[0m                                    x 0.21.3           x x        x Started x [31;1modl-openflowplugin-drop-test[0m                     ...</msg>
<var>${output}</var>
<arg>${KARAF_PROMPT}</arg>
<doc>Consumes and returns the server output until a match to ``regexp`` is found.</doc>
<status status="PASS" start="2026-05-01T00:46:23.199737" elapsed="0.039857"/>
</kw>
<kw name="Write Bare" owner="SSHLibrary">
<arg>logout\n</arg>
<doc>Writes the given ``text`` on the remote machine without appending a newline.</doc>
<status status="PASS" start="2026-05-01T00:46:23.239882" elapsed="0.000383"/>
</kw>
<kw name="Close Connection" owner="SSHLibrary">
<doc>Closes the current connection.</doc>
<status status="PASS" start="2026-05-01T00:46:23.240422" elapsed="0.000260"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-05-01T00:46:23.241160" level="INFO">[?1l&gt;[?2004l[31;1modl-openflowplugin-drop-test[0m                                    x 0.21.3           x x        x Started x [31;1modl-openflowplugin-drop-test[0m                                    x OpenDaylight :: Openflow Plugin :: Drop Test
[?1h=[90m~[0m                                                                                [?2004hopendaylight-user@root&gt;</msg>
<arg>${output}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-05-01T00:46:23.240826" elapsed="0.000390"/>
</kw>
<return>
<value>${output}</value>
<status status="PASS" start="2026-05-01T00:46:23.241268" elapsed="0.000055"/>
</return>
<msg time="2026-05-01T00:46:23.241476" level="INFO">${output} = [?1l&gt;[?2004l[31;1modl-openflowplugin-drop-test[0m                                    x 0.21.3           x x        x Started x [31;1modl-openflowplugin-drop-test[0m                     ...</msg>
<var>${output}</var>
<arg>feature:list -i | grep ${feature_name}</arg>
<arg>${controller}</arg>
<arg>${karaf_port}</arg>
<doc>Will execute the given ${cmd} by ssh'ing to the karaf console running on 10.30.171.63
Note that this keyword will open&amp;close new SSH connection, without switching back to previously current session.</doc>
<status status="PASS" start="2026-05-01T00:46:22.926852" elapsed="0.314656"/>
</kw>
<kw name="Should Contain" owner="BuiltIn">
<arg>${output}</arg>
<arg>${feature_name}</arg>
<doc>Fails if ``container`` does not contain ``item`` one or more times.</doc>
<status status="PASS" start="2026-05-01T00:46:23.241679" elapsed="0.000555"/>
</kw>
<return>
<value>${output}</value>
<status status="PASS" start="2026-05-01T00:46:23.242289" elapsed="0.000032"/>
</return>
<arg>odl-openflowplugin-drop-test</arg>
<doc>Will Succeed if the given ${feature_name} is found in the output of "feature:list -i"</doc>
<status status="PASS" start="2026-05-01T00:46:22.922399" elapsed="0.320030"/>
</kw>
<kw name="Issue_Command_On_Karaf_Console" owner="KarafKeywords">
<if>
<branch type="IF" condition="'${IS_KARAF_APPL}' == 'False'">
<return>
<value>Not A Karaf App</value>
<status status="NOT RUN" start="2026-05-01T00:46:23.246360" elapsed="0.000016"/>
</return>
<status status="NOT RUN" start="2026-05-01T00:46:23.246200" elapsed="0.000208"/>
</branch>
<status status="PASS" start="2026-05-01T00:46:23.246175" elapsed="0.000266"/>
</if>
<kw name="Open Connection" owner="SSHLibrary">
<arg>${controller}</arg>
<arg>port=${karaf_port}</arg>
<arg>prompt=${KARAF_PROMPT_LOGIN}</arg>
<arg>timeout=${timeout}</arg>
<doc>Opens a new SSH connection to the given ``host`` and ``port``.</doc>
<status status="PASS" start="2026-05-01T00:46:23.246586" elapsed="0.000673"/>
</kw>
<kw name="Login" owner="SSHLibrary">
<msg time="2026-05-01T00:46:23.247712" level="INFO">Logging into '10.30.171.63:8101' as 'karaf'.</msg>
<msg time="2026-05-01T00:46:23.434684" level="INFO">Read output: [33m                                                                                           
[33m    ________                       ________                .__  .__       .__     __       
[33m    \_____  \ ______   ____   ____ \______ \ _____  ___.__.|  | |__| ____ |  |___/  |_     
[33m     /   |   \\____ \_/ __ \ /    \ |    |  \\__  \&lt;   |  ||  | |  |/ ___\|  |  \   __\    
[33m    /    |    \  |_&gt; &gt;  ___/|   |  \|    `   \/ __ \\___  ||  |_|  / /_/  &gt;   Y  \  |      
[33m    \_______  /   __/ \___  &gt;___|  /_______  (____  / ____||____/__\___  /|___|  /__|      
[33m            \/|__|        \/     \/        \/     \/\/            /_____/      \/          
[33m                                                                                           

Hit '[1m&lt;tab&gt;[0m' for a list of available commands
and '[1m[cmd] --help[0m' for help on a specific command.
Hit '[1m&lt;ctrl-d&gt;[0m' or type '[1msystem:shutdown[0m' or '[1mlogout[0m' to shutdown OpenDaylight.

[?1h=[90m~[0m                                                                                [?2004hopendaylight-user</msg>
<arg>${KARAF_USER}</arg>
<arg>${KARAF_PASSWORD}</arg>
<arg>loglevel=${loglevel}</arg>
<doc>Logs into the SSH server with the given ``username`` and ``password``.</doc>
<status status="PASS" start="2026-05-01T00:46:23.247416" elapsed="0.187461"/>
</kw>
<kw name="Write" owner="SSHLibrary">
<msg time="2026-05-01T00:46:23.458518" level="INFO">@root&gt;dropallpacketsrpc on</msg>
<arg>${cmd}</arg>
<doc>Writes the given ``text`` on the remote machine and appends a newline.</doc>
<status status="PASS" start="2026-05-01T00:46:23.435229" elapsed="0.023380"/>
</kw>
<kw name="Read Until Regexp" owner="SSHLibrary">
<msg time="2026-05-01T00:46:23.460548" level="INFO">[?1l&gt;[?2004lDropAllFlows transitions to on
[?1h=[90m~[0m                                                                                [?2004hopendaylight-user@root&gt;</msg>
<msg time="2026-05-01T00:46:23.460688" level="INFO">${output} = [?1l&gt;[?2004lDropAllFlows transitions to on
[?1h=[90m~[0m                                                                                [?2004hopendaylight-user@root&gt;</msg>
<var>${output}</var>
<arg>${KARAF_PROMPT}</arg>
<doc>Consumes and returns the server output until a match to ``regexp`` is found.</doc>
<status status="PASS" start="2026-05-01T00:46:23.458824" elapsed="0.001896"/>
</kw>
<kw name="Write Bare" owner="SSHLibrary">
<arg>logout\n</arg>
<doc>Writes the given ``text`` on the remote machine without appending a newline.</doc>
<status status="PASS" start="2026-05-01T00:46:23.460868" elapsed="0.000220"/>
</kw>
<kw name="Close Connection" owner="SSHLibrary">
<doc>Closes the current connection.</doc>
<status status="PASS" start="2026-05-01T00:46:23.461235" elapsed="0.000218"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-05-01T00:46:23.461938" level="INFO">[?1l&gt;[?2004lDropAllFlows transitions to on
[?1h=[90m~[0m                                                                                [?2004hopendaylight-user@root&gt;</msg>
<arg>${output}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-05-01T00:46:23.461593" elapsed="0.000401"/>
</kw>
<return>
<value>${output}</value>
<status status="PASS" start="2026-05-01T00:46:23.462047" elapsed="0.000055"/>
</return>
<arg>dropallpacketsrpc on</arg>
<doc>Will execute the given ${cmd} by ssh'ing to the karaf console running on 10.30.171.63
Note that this keyword will open&amp;close new SSH connection, without switching back to previously current session.</doc>
<status status="PASS" start="2026-05-01T00:46:23.245850" elapsed="0.216373"/>
</kw>
<status status="PASS" start="2026-05-01T00:46:21.445022" elapsed="2.017293"/>
</kw>
<test id="s1-t1" name="Cbench Latency Test" line="37">
<kw name="Log" owner="BuiltIn">
<msg time="2026-05-01T00:46:23.465130" level="INFO">Cbench tests using 10 iterations of 60 second tests. Switch Count: 16. Unique MACS to cycle: 100</msg>
<arg>Cbench tests using ${loops} iterations of ${duration_in_secs} second tests. Switch Count: ${switch_count}. Unique MACS to cycle: ${num_of_unique_macs}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-05-01T00:46:23.463307" elapsed="0.001902"/>
</kw>
<kw name="Run Cbench And Log Results">
<kw name="Set Suite Variable" owner="BuiltIn">
<msg time="2026-05-01T00:46:23.466779" level="INFO">${output_filename} = latency.csv</msg>
<arg>${output_filename}</arg>
<doc>Makes a variable available everywhere within the scope of the current suite.</doc>
<status status="PASS" start="2026-05-01T00:46:23.466405" elapsed="0.000418"/>
</kw>
<if>
<branch type="IF" condition="&quot;${cbench_system}&quot; == &quot;localhost&quot;">
<kw name="Run" owner="OperatingSystem">
<var>${output}</var>
<arg>${cbench_executable} -c ${ODL_SYSTEM_IP} ${cbench_args}</arg>
<doc>_This keyword is considered deprecated. Use the
[http://robotframework.org/robotframework/latest/libraries/Process.html|
Process] library instead._</doc>
<status status="NOT RUN" start="2026-05-01T00:46:23.467165" elapsed="0.000021"/>
</kw>
<status status="NOT RUN" start="2026-05-01T00:46:23.466910" elapsed="0.000314"/>
</branch>
<branch type="ELSE">
<kw name="Run Command On Remote System" owner="Utils">
<if>
<branch type="IF" condition="&quot;${return_stdout}&quot;!=&quot;True&quot; and &quot;${return_stderr}&quot;!=&quot;True&quot;">
<kw name="Fail" owner="BuiltIn">
<arg>At least one of {return_stdout} or {return_stderr} args should be set to True</arg>
<doc>Fails the test with the given message and optionally alters its tags.</doc>
<status status="NOT RUN" start="2026-05-01T00:46:23.470345" elapsed="0.000022"/>
</kw>
<status status="NOT RUN" start="2026-05-01T00:46:23.470088" elapsed="0.000313"/>
</branch>
<status status="PASS" start="2026-05-01T00:46:23.470068" elapsed="0.000360"/>
</if>
<kw name="Get Connection" owner="SSHLibrary">
<msg time="2026-05-01T00:46:23.470822" level="INFO">index=None
host=None
alias=None
port=22
timeout=3 seconds
newline=

prompt=None
term_type=vt100
width=80
height=24
path_separator=/
encoding=utf8
escape_ansi=False
encoding_errors=strict</msg>
<msg time="2026-05-01T00:46:23.470981" level="INFO">${current_ssh_connection} = index=None
host=None
alias=None
port=22
timeout=3 seconds
newline=

prompt=None
term_type=vt100
width=80
height=24
path_separator=/
encoding=utf8
escape_ansi=False
encoding_errors=strict</msg>
<var>${current_ssh_connection}</var>
<doc>Returns information about the connection.</doc>
<status status="PASS" start="2026-05-01T00:46:23.470567" elapsed="0.000442"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-05-01T00:46:23.471589" level="INFO">Attempting to execute command "/usr/local/bin/cbench -c 10.30.171.63 -m 60000 -M 100 -S 16 -l 10 -D 5000 -p 6633" on remote system "10.30.171.37" by user "jenkins" with keyfile pass "any" and prompt "&gt;" and password ""</msg>
<arg>Attempting to execute command "${cmd}" on remote system "${system}" by user "${user}" with keyfile pass "${keyfile_pass}" and prompt "${prompt}" and password "${password}"</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-05-01T00:46:23.471148" elapsed="0.000517"/>
</kw>
<kw name="Open Connection" owner="SSHLibrary">
<msg time="2026-05-01T00:46:23.472293" level="INFO">${conn_id} = 5</msg>
<var>${conn_id}</var>
<arg>${system}</arg>
<arg>prompt=${prompt}</arg>
<arg>timeout=${prompt_timeout}</arg>
<doc>Opens a new SSH connection to the given ``host`` and ``port``.</doc>
<status status="PASS" start="2026-05-01T00:46:23.471810" elapsed="0.000510"/>
</kw>
<kw name="Flexible_SSH_Login" owner="SSHKeywords">
<kw name="Get Length" owner="BuiltIn">
<msg time="2026-05-01T00:46:23.473270" level="INFO">Length is 0.</msg>
<msg time="2026-05-01T00:46:23.473363" level="INFO">${pwd_length} = 0</msg>
<var>${pwd_length}</var>
<arg>${password}</arg>
<doc>Returns and logs the length of the given item as an integer.</doc>
<status status="PASS" start="2026-05-01T00:46:23.472937" elapsed="0.000451"/>
</kw>
<kw name="Run Keyword And Return If" owner="BuiltIn">
<arg>${pwd_length} &gt; 0</arg>
<arg>SSHLibrary.Login</arg>
<arg>${user}</arg>
<arg>${password}</arg>
<arg>delay=${delay}</arg>
<doc>Runs the specified keyword and returns from the enclosing user keyword.</doc>
<status status="PASS" start="2026-05-01T00:46:23.473537" elapsed="0.000359"/>
</kw>
<kw name="Run Keyword And Return" owner="BuiltIn">
<kw name="Login With Public Key" owner="SSHLibrary">
<msg time="2026-05-01T00:46:23.474793" level="INFO">Logging into '10.30.171.37:22' as 'jenkins'.</msg>
<msg time="2026-05-01T00:46:23.805056" level="INFO">Read output: Welcome to Ubuntu 22.04.5 LTS (GNU/Linux 5.15.0-153-generic x86_64)

 * Documentation:  https://help.ubuntu.com
 * Management:     https://landscape.canonical.com
 * Support:        https://ubuntu.com/pro

 System information as of Fri May  1 00:46:22 UTC 2026

  System load:  0.08               Processes:             110
  Usage of /:   19.3% of 38.58GB   Users logged in:       0
  Memory usage: 4%                 IPv4 address for ens3: 10.30.171.37
  Swap usage:   0%


Expanded Security Maintenance for Applications is not enabled.

0 updates can be applied immediately.

9 additional security updates can be applied with ESM Apps.
Learn more about enabling ESM Apps service at https://ubuntu.com/esm


The list of available updates is more than a week old.
To check for new updates run: sudo apt update
New release '24.04.4 LTS' available.
Run 'do-release-upgrade' to upgrade to it.


Last login: Fri May  1 00:46:22 2026 from 10.30.171.253
[?2004h[jenkins@releng-63164-215-1-mininet-ovs-217-0 ~]&gt;</msg>
<arg>${user}</arg>
<arg>${USER_HOME}/.ssh/${SSH_KEY}</arg>
<arg>${KEYFILE_PASS}</arg>
<arg>delay=${delay}</arg>
<doc>Logs into the SSH server using key-based authentication.</doc>
<status status="PASS" start="2026-05-01T00:46:23.474405" elapsed="0.330831"/>
</kw>
<msg time="2026-05-01T00:46:23.805324" level="INFO">Returning from the enclosing user keyword.</msg>
<arg>SSHLibrary.Login With Public Key</arg>
<arg>${user}</arg>
<arg>${USER_HOME}/.ssh/${SSH_KEY}</arg>
<arg>${KEYFILE_PASS}</arg>
<arg>delay=${delay}</arg>
<doc>Runs the specified keyword and returns from the enclosing user keyword.</doc>
<status status="PASS" start="2026-05-01T00:46:23.474053" elapsed="0.331359"/>
</kw>
<arg>${user}</arg>
<arg>${password}</arg>
<doc>On active SSH session: if given non-empty password, do Login, else do Login With Public Key.</doc>
<status status="PASS" start="2026-05-01T00:46:23.472528" elapsed="0.333006"/>
</kw>
<kw name="Execute Command" owner="SSHLibrary">
<msg time="2026-05-01T00:46:23.806337" level="INFO">Executing command '/usr/local/bin/cbench -c 10.30.171.63 -m 60000 -M 100 -S 16 -l 10 -D 5000 -p 6633'.</msg>
<msg time="2026-05-01T00:56:29.927370" level="INFO">Command exited with return code 0.</msg>
<msg time="2026-05-01T00:56:29.928040" level="INFO">${stdout} = </msg>
<msg time="2026-05-01T00:56:29.928151" level="INFO">${stderr} = cbench: controller benchmarking tool
   running in mode 'latency'
   connecting to controller at 10.30.171.63:6633 
   faking 16 switches with 1 threads :: 10 tests each; 60000 ms per test
   with 100...</msg>
<var>${stdout}</var>
<var>${stderr}</var>
<arg>${cmd}</arg>
<arg>return_stderr=True</arg>
<doc>Executes ``command`` on the remote machine and returns its outputs.</doc>
<status status="PASS" start="2026-05-01T00:46:23.805776" elapsed="606.122435"/>
</kw>
<kw name="Close Connection" owner="SSHLibrary">
<doc>Closes the current connection.</doc>
<status status="PASS" start="2026-05-01T00:56:29.928627" elapsed="0.000798"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-05-01T00:56:29.930594" level="INFO">cbench: controller benchmarking tool
   running in mode 'latency'
   connecting to controller at 10.30.171.63:6633 
   faking 16 switches with 1 threads :: 10 tests each; 60000 ms per test
   with 100 unique source MACs per switch
   learning destination mac addresses before the test
   starting test with 5000 ms delay after features_reply
   ignoring first 1 "warmup" and last 0 "cooldown" loops
   debugging info is off
Master Thread: Creating thread-0! 
Thread-0: All switches initialized. Running at node 10.30.171.63. So far 1 cbench instances have initialized their switches.  Waiting for other cbench-instances
Thread-0: Total switches from ALL cbench-instances initialized.
00:47:29.013 16  switches: flows:  107339 108568 108292 108755 109430 107352 105041 107486 105095 103593 104538 103211 105569 104990 103963 104944  total = 28.302767 per ms 
00:48:29.113 16  switches: flows:  112480 113086 114933 115606 113754 113510 110338 112970 109797 107598 108196 110373 107983 107898 108977 107832  total = 29.539520 per ms 
00:49:29.213 16  switches: flows:  118613 122222 117154 119987 117154 118035 115444 118394 116483 115111 110503 115390 112101 114245 115679 112444  total = 30.930982 per ms 
00:50:29.314 16  switches: flows:  117177 119451 117959 116901 115918 116688 116308 116750 113685 111612 112727 111838 111310 113008 113364 110601  total = 30.537215 per ms 
00:51:29.414 16  switches: flows:  120891 124582 121684 122845 122030 120219 121546 119973 118785 116748 115587 118748 115884 117308 117977 115436  total = 31.784255 per ms 
00:52:29.514 16  switches: flows:  119354 121570 119294 120026 118330 118415 114745 117972 115120 114304 113745 114199 114099 115106 114174 114660  total = 31.033419 per ms 
00:53:29.615 16  switches: flows:  117667 118370 118598 118333 115458 120341 115601 116542 116095 112489 112057 113636 111661 113825 115283 111894  total = 30.746115 per ms 
00:54:29.715 16  switches: flows:  120445 120915 120697 119830 118486 122091 116947 120148 117846 118614 114567 117739 114473 115887 119513 115087  total = 31.502107 per ms 
00:55:29.815 16  switches: flows:  113828 114965 113675 113160 115237 116020 111055 113248 112341 110367 109379 110821 108039 108839 110296 110426  total = 29.811852 per ms 
00:56:29.916 16  switches: flows:  120071 121987 118149 119848 122609 121769 119063 117705 116212 117453 116413 118971 114284 113396 114246 115559  total = 31.409766 per ms 
RESULT: 16 switches 9 tests min/max/avg/stdev = 29539.52/31784.25/30810.58/10294.73 responses/s</msg>
<arg>${stderr}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-05-01T00:56:29.929822" elapsed="0.001108"/>
</kw>
<if>
<branch type="IF" condition="&quot;${return_stdout}&quot;!=&quot;True&quot;">
<return>
<value>${stderr}</value>
<status status="PASS" start="2026-05-01T00:56:29.931451" elapsed="0.000092"/>
</return>
<status status="PASS" start="2026-05-01T00:56:29.931122" elapsed="0.000506"/>
</branch>
<status status="PASS" start="2026-05-01T00:56:29.931065" elapsed="0.000654"/>
</if>
<if>
<branch type="IF" condition="&quot;${return_stderr}&quot;!=&quot;True&quot;">
<return>
<value>${stdout}</value>
<status status="NOT RUN" start="2026-05-01T00:56:29.931944" elapsed="0.000029"/>
</return>
<status status="NOT RUN" start="2026-05-01T00:56:29.931858" elapsed="0.000178"/>
</branch>
<status status="NOT RUN" start="2026-05-01T00:56:29.931821" elapsed="0.000265"/>
</if>
<return>
<value>${stdout}</value>
<value>${stderr}</value>
<status status="NOT RUN" start="2026-05-01T00:56:29.932161" elapsed="0.000031"/>
</return>
<kw name="Restore_Current_Ssh_Connection_From_Index" owner="SSHKeywords" type="TEARDOWN">
<kw name="Run Keyword And Return If" owner="BuiltIn">
<arg>${connection_index} is not None</arg>
<arg>SSHLibrary.Switch Connection</arg>
<arg>${connection_index}</arg>
<doc>Runs the specified keyword and returns from the enclosing user keyword.</doc>
<status status="PASS" start="2026-05-01T00:56:29.937372" elapsed="0.000481"/>
</kw>
<kw name="Open Connection" owner="SSHLibrary">
<arg>127.0.0.1</arg>
<doc>Opens a new SSH connection to the given ``host`` and ``port``.</doc>
<status status="PASS" start="2026-05-01T00:56:29.938061" elapsed="0.000233"/>
</kw>
<kw name="Close Connection" owner="SSHLibrary">
<doc>Closes the current connection.</doc>
<status status="PASS" start="2026-05-01T00:56:29.938478" elapsed="0.000141"/>
</kw>
<arg>${current_ssh_connection.index}</arg>
<doc>Restore active SSH connection in SSHLibrary to given index.</doc>
<status status="PASS" start="2026-05-01T00:56:29.933050" elapsed="0.005666"/>
</kw>
<msg time="2026-05-01T00:56:29.938896" level="INFO">${output} = cbench: controller benchmarking tool
   running in mode 'latency'
   connecting to controller at 10.30.171.63:6633 
   faking 16 switches with 1 threads :: 10 tests each; 60000 ms per test
   with 100...</msg>
<var>${output}</var>
<arg>${cbench_system}</arg>
<arg>${cbench_executable} -c ${ODL_SYSTEM_IP} ${cbench_args}</arg>
<arg>prompt_timeout=${test_timeout}</arg>
<arg>return_stdout=False</arg>
<arg>return_stderr=True</arg>
<doc>Reduces the common work of running a command on a remote system to a single higher level
robot keyword, taking care to log in with a public key and. The command given is written and the return value
depends on the passed argument values of return_stdout (default: True) and return_stderr (default: False).
At least one should be True, or the keyword will exit and FAIL. If both are True, the resulting return value
will be a two element list containing both. Otherwise the resulting return value is a string.
No test conditions are checked.</doc>
<status status="PASS" start="2026-05-01T00:46:23.467596" elapsed="606.471339"/>
</kw>
<status status="PASS" start="2026-05-01T00:46:23.467257" elapsed="606.471757"/>
</branch>
<status status="PASS" start="2026-05-01T00:46:23.466883" elapsed="606.472165"/>
</if>
<kw name="Log" owner="BuiltIn">
<msg time="2026-05-01T00:56:29.939694" level="INFO">cbench: controller benchmarking tool
   running in mode 'latency'
   connecting to controller at 10.30.171.63:6633 
   faking 16 switches with 1 threads :: 10 tests each; 60000 ms per test
   with 100 unique source MACs per switch
   learning destination mac addresses before the test
   starting test with 5000 ms delay after features_reply
   ignoring first 1 "warmup" and last 0 "cooldown" loops
   debugging info is off
Master Thread: Creating thread-0! 
Thread-0: All switches initialized. Running at node 10.30.171.63. So far 1 cbench instances have initialized their switches.  Waiting for other cbench-instances
Thread-0: Total switches from ALL cbench-instances initialized.
00:47:29.013 16  switches: flows:  107339 108568 108292 108755 109430 107352 105041 107486 105095 103593 104538 103211 105569 104990 103963 104944  total = 28.302767 per ms 
00:48:29.113 16  switches: flows:  112480 113086 114933 115606 113754 113510 110338 112970 109797 107598 108196 110373 107983 107898 108977 107832  total = 29.539520 per ms 
00:49:29.213 16  switches: flows:  118613 122222 117154 119987 117154 118035 115444 118394 116483 115111 110503 115390 112101 114245 115679 112444  total = 30.930982 per ms 
00:50:29.314 16  switches: flows:  117177 119451 117959 116901 115918 116688 116308 116750 113685 111612 112727 111838 111310 113008 113364 110601  total = 30.537215 per ms 
00:51:29.414 16  switches: flows:  120891 124582 121684 122845 122030 120219 121546 119973 118785 116748 115587 118748 115884 117308 117977 115436  total = 31.784255 per ms 
00:52:29.514 16  switches: flows:  119354 121570 119294 120026 118330 118415 114745 117972 115120 114304 113745 114199 114099 115106 114174 114660  total = 31.033419 per ms 
00:53:29.615 16  switches: flows:  117667 118370 118598 118333 115458 120341 115601 116542 116095 112489 112057 113636 111661 113825 115283 111894  total = 30.746115 per ms 
00:54:29.715 16  switches: flows:  120445 120915 120697 119830 118486 122091 116947 120148 117846 118614 114567 117739 114473 115887 119513 115087  total = 31.502107 per ms 
00:55:29.815 16  switches: flows:  113828 114965 113675 113160 115237 116020 111055 113248 112341 110367 109379 110821 108039 108839 110296 110426  total = 29.811852 per ms 
00:56:29.916 16  switches: flows:  120071 121987 118149 119848 122609 121769 119063 117705 116212 117453 116413 118971 114284 113396 114246 115559  total = 31.409766 per ms 
RESULT: 16 switches 9 tests min/max/avg/stdev = 29539.52/31784.25/30810.58/10294.73 responses/s</msg>
<arg>${output}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-05-01T00:56:29.939247" elapsed="0.000552"/>
</kw>
<kw name="Should Contain" owner="BuiltIn">
<arg>${output}</arg>
<arg>RESULT</arg>
<doc>Fails if ``container`` does not contain ``item`` one or more times.</doc>
<status status="PASS" start="2026-05-01T00:56:29.940008" elapsed="0.000503"/>
</kw>
<kw name="Get Lines Containing String" owner="String">
<msg time="2026-05-01T00:56:29.941074" level="INFO">1 out of 23 lines matched.</msg>
<msg time="2026-05-01T00:56:29.941203" level="INFO">${result_line} = RESULT: 16 switches 9 tests min/max/avg/stdev = 29539.52/31784.25/30810.58/10294.73 responses/s</msg>
<var>${result_line}</var>
<arg>${output}</arg>
<arg>RESULT</arg>
<doc>Returns lines of the given ``string`` that contain the ``pattern``.</doc>
<status status="PASS" start="2026-05-01T00:56:29.940755" elapsed="0.000484"/>
</kw>
<kw name="Split String" owner="String">
<msg time="2026-05-01T00:56:29.941840" level="INFO">@{results_list} = [ RESULT: | 16 | switches | 9 | tests | min/max/avg/stdev | = | 29539.52/31784.25/30810.58/10294.73 | responses/s ]</msg>
<var>@{results_list}</var>
<arg>${result_line}</arg>
<doc>Splits the ``string`` using ``separator`` as a delimiter string.</doc>
<status status="PASS" start="2026-05-01T00:56:29.941458" elapsed="0.000421"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-05-01T00:56:29.945251" level="INFO">min/max/avg/stdev</msg>
<arg>${results_list[5]}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-05-01T00:56:29.942084" elapsed="0.003248"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-05-01T00:56:29.948573" level="INFO">29539.52/31784.25/30810.58/10294.73</msg>
<arg>${results_list[7]}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-05-01T00:56:29.945537" elapsed="0.003112"/>
</kw>
<kw name="Split String" owner="String">
<msg time="2026-05-01T00:56:29.951019" level="INFO">@{result_name_list} = [ min | max | avg | stdev ]</msg>
<var>@{result_name_list}</var>
<arg>${results_list[5]}</arg>
<arg>/</arg>
<doc>Splits the ``string`` using ``separator`` as a delimiter string.</doc>
<status status="PASS" start="2026-05-01T00:56:29.948803" elapsed="0.002244"/>
</kw>
<kw name="Split String" owner="String">
<msg time="2026-05-01T00:56:29.953479" level="INFO">@{result_value_list} = [ 29539.52 | 31784.25 | 30810.58 | 10294.73 ]</msg>
<var>@{result_value_list}</var>
<arg>${results_list[7]}</arg>
<arg>/</arg>
<doc>Splits the ``string`` using ``separator`` as a delimiter string.</doc>
<status status="PASS" start="2026-05-01T00:56:29.951234" elapsed="0.002273"/>
</kw>
<kw name="Get Length" owner="BuiltIn">
<msg time="2026-05-01T00:56:29.953989" level="INFO">Length is 4.</msg>
<msg time="2026-05-01T00:56:29.954079" level="INFO">${num_stats} = 4</msg>
<var>${num_stats}</var>
<arg>${result_name_list}</arg>
<doc>Returns and logs the length of the given item as an integer.</doc>
<status status="PASS" start="2026-05-01T00:56:29.953677" elapsed="0.000427"/>
</kw>
<for flavor="IN RANGE">
<iter>
<kw name="Log" owner="BuiltIn">
<msg time="2026-05-01T00:56:29.959281" level="INFO">min :: 29539.52</msg>
<arg>${result_name_list[${i}]} :: ${result_value_list[${i}]}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-05-01T00:56:29.954956" elapsed="0.004383"/>
</kw>
<var name="${i}">0</var>
<status status="PASS" start="2026-05-01T00:56:29.954822" elapsed="0.004553"/>
</iter>
<iter>
<kw name="Log" owner="BuiltIn">
<msg time="2026-05-01T00:56:29.964077" level="INFO">max :: 31784.25</msg>
<arg>${result_name_list[${i}]} :: ${result_value_list[${i}]}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-05-01T00:56:29.959667" elapsed="0.004468"/>
</kw>
<var name="${i}">1</var>
<status status="PASS" start="2026-05-01T00:56:29.959521" elapsed="0.004649"/>
</iter>
<iter>
<kw name="Log" owner="BuiltIn">
<msg time="2026-05-01T00:56:29.968765" level="INFO">avg :: 30810.58</msg>
<arg>${result_name_list[${i}]} :: ${result_value_list[${i}]}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-05-01T00:56:29.964390" elapsed="0.004437"/>
</kw>
<var name="${i}">2</var>
<status status="PASS" start="2026-05-01T00:56:29.964260" elapsed="0.004602"/>
</iter>
<iter>
<kw name="Log" owner="BuiltIn">
<msg time="2026-05-01T00:56:29.973747" level="INFO">stdev :: 10294.73</msg>
<arg>${result_name_list[${i}]} :: ${result_value_list[${i}]}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-05-01T00:56:29.969083" elapsed="0.004722"/>
</kw>
<var name="${i}">3</var>
<status status="PASS" start="2026-05-01T00:56:29.968955" elapsed="0.004885"/>
</iter>
<var>${i}</var>
<value>0</value>
<value>${num_stats}</value>
<status status="PASS" start="2026-05-01T00:56:29.954621" elapsed="0.019253"/>
</for>
<kw name="Set Variable" owner="BuiltIn">
<msg time="2026-05-01T00:56:29.977845" level="INFO">${min} = 29539.52</msg>
<var>${min}</var>
<arg>${result_value_list[${0}]}</arg>
<doc>Returns the given values which can then be assigned to a variables.</doc>
<status status="PASS" start="2026-05-01T00:56:29.974036" elapsed="0.003837"/>
</kw>
<kw name="Set Variable" owner="BuiltIn">
<msg time="2026-05-01T00:56:29.981849" level="INFO">${max} = 31784.25</msg>
<var>${max}</var>
<arg>${result_value_list[${1}]}</arg>
<doc>Returns the given values which can then be assigned to a variables.</doc>
<status status="PASS" start="2026-05-01T00:56:29.978030" elapsed="0.003847"/>
</kw>
<kw name="Set Variable" owner="BuiltIn">
<msg time="2026-05-01T00:56:29.985983" level="INFO">${average} = 30810.58</msg>
<var>${average}</var>
<arg>${result_value_list[${2}]}</arg>
<doc>Returns the given values which can then be assigned to a variables.</doc>
<status status="PASS" start="2026-05-01T00:56:29.982034" elapsed="0.003976"/>
</kw>
<kw name="Set Variable" owner="BuiltIn">
<msg time="2026-05-01T00:56:29.989917" level="INFO">${stdev} = 10294.73</msg>
<var>${stdev}</var>
<arg>${result_value_list[${3}]}</arg>
<doc>Returns the given values which can then be assigned to a variables.</doc>
<status status="PASS" start="2026-05-01T00:56:29.986164" elapsed="0.003780"/>
</kw>
<kw name="Get Time" owner="BuiltIn">
<msg time="2026-05-01T00:56:29.990464" level="INFO">${date} = 2026-05-01 00:56:29</msg>
<var>${date}</var>
<arg>d,m,s</arg>
<doc>Returns the given time in the requested format.</doc>
<status status="PASS" start="2026-05-01T00:56:29.990105" elapsed="0.000385"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-05-01T00:56:29.991065" level="INFO">CBench Result: 2026-05-01 00:56:29,-m 60000 -M 100 -S 16 -l 10 -D 5000 -p 6633,29539.52,31784.25,30810.58,10294.73</msg>
<arg>CBench Result: ${date},${cbench_args},${min},${max},${average},${stdev}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-05-01T00:56:29.990650" elapsed="0.000471"/>
</kw>
<kw name="Log Results And Determine Status">
<kw name="Append To File" owner="OperatingSystem">
<msg time="2026-05-01T00:56:29.992303" level="INFO" html="true">Appended to file '&lt;a href="file:///w/workspace/openflowplugin-csit-1node-cbench-only-vanadium/latency.csv"&gt;/w/workspace/openflowplugin-csit-1node-cbench-only-vanadium/latency.csv&lt;/a&gt;'.</msg>
<arg>${output_file}</arg>
<arg>${min},${max},${average}\n</arg>
<doc>Appends the given content to the specified file.</doc>
<status status="PASS" start="2026-05-01T00:56:29.991852" elapsed="0.000519"/>
</kw>
<kw name="Should Be True" owner="BuiltIn">
<arg>${average} &gt; ${threshold}</arg>
<arg>${average} flow_mods per/sec did not exceed threshold of ${threshold}</arg>
<doc>Fails if the given condition is not true.</doc>
<status status="PASS" start="2026-05-01T00:56:29.992524" elapsed="0.000485"/>
</kw>
<arg>${min}</arg>
<arg>${max}</arg>
<arg>${average}</arg>
<arg>${average_threshold}</arg>
<arg>${output_filename}</arg>
<status status="PASS" start="2026-05-01T00:56:29.991284" elapsed="0.001792"/>
</kw>
<arg>-m ${duration_in_ms} -M ${num_of_unique_macs} -S ${switch_count} -l ${loops} -D ${start_delay} -p ${of_port}</arg>
<arg>${latency_threshold}</arg>
<arg>${latency_results_file}</arg>
<status status="PASS" start="2026-05-01T00:46:23.465723" elapsed="606.527415"/>
</kw>
<kw name="Sleep" owner="BuiltIn">
<msg time="2026-05-01T00:56:29.993607" level="INFO">Slept 0 seconds.</msg>
<arg>${test_delay}</arg>
<doc>Pauses the test executed for the given time.</doc>
<status status="PASS" start="2026-05-01T00:56:29.993288" elapsed="0.000392"/>
</kw>
<kw name="Log Results As Zero If Cbench Timed Out" type="TEARDOWN">
<kw name="Run Keyword If Timeout Occurred" owner="BuiltIn">
<arg>Log Results And Determine Status</arg>
<arg>0</arg>
<arg>0</arg>
<arg>0</arg>
<arg>0</arg>
<arg>${output_filename}</arg>
<doc>Runs the given keyword if either a test or a keyword timeout has occurred.</doc>
<status status="PASS" start="2026-05-01T00:56:29.994201" elapsed="0.000269"/>
</kw>
<status status="PASS" start="2026-05-01T00:56:29.993894" elapsed="0.000648"/>
</kw>
<doc>cbench executed in default latency mode. Test parameters have defaults, but can be overridden
on the pybot command line</doc>
<tag>cbench</tag>
<tag>latency</tag>
<timeout value="15 minutes"/>
<status status="PASS" start="2026-05-01T00:46:23.462474" elapsed="606.532105"/>
</test>
<test id="s1-t2" name="Cbench Throughput Test" line="51">
<kw name="Log" owner="BuiltIn">
<msg time="2026-05-01T00:56:29.996103" level="INFO">Cbench tests using 10 iterations of 60 second tests. Switch Count: 16. Unique MACS to cycle: 100</msg>
<arg>Cbench tests using ${loops} iterations of ${duration_in_secs} second tests. Switch Count: ${switch_count}. Unique MACS to cycle: ${num_of_unique_macs}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-05-01T00:56:29.995579" elapsed="0.000582"/>
</kw>
<kw name="Run Cbench And Log Results">
<kw name="Set Suite Variable" owner="BuiltIn">
<msg time="2026-05-01T00:56:29.997442" level="INFO">${output_filename} = throughput.csv</msg>
<arg>${output_filename}</arg>
<doc>Makes a variable available everywhere within the scope of the current suite.</doc>
<status status="PASS" start="2026-05-01T00:56:29.997104" elapsed="0.000382"/>
</kw>
<if>
<branch type="IF" condition="&quot;${cbench_system}&quot; == &quot;localhost&quot;">
<kw name="Run" owner="OperatingSystem">
<var>${output}</var>
<arg>${cbench_executable} -c ${ODL_SYSTEM_IP} ${cbench_args}</arg>
<doc>_This keyword is considered deprecated. Use the
[http://robotframework.org/robotframework/latest/libraries/Process.html|
Process] library instead._</doc>
<status status="NOT RUN" start="2026-05-01T00:56:29.997846" elapsed="0.000022"/>
</kw>
<status status="NOT RUN" start="2026-05-01T00:56:29.997603" elapsed="0.000303"/>
</branch>
<branch type="ELSE">
<kw name="Run Command On Remote System" owner="Utils">
<if>
<branch type="IF" condition="&quot;${return_stdout}&quot;!=&quot;True&quot; and &quot;${return_stderr}&quot;!=&quot;True&quot;">
<kw name="Fail" owner="BuiltIn">
<arg>At least one of {return_stdout} or {return_stderr} args should be set to True</arg>
<doc>Fails the test with the given message and optionally alters its tags.</doc>
<status status="NOT RUN" start="2026-05-01T00:56:30.000894" elapsed="0.000021"/>
</kw>
<status status="NOT RUN" start="2026-05-01T00:56:30.000628" elapsed="0.000322"/>
</branch>
<status status="PASS" start="2026-05-01T00:56:30.000608" elapsed="0.000367"/>
</if>
<kw name="Get Connection" owner="SSHLibrary">
<msg time="2026-05-01T00:56:30.001320" level="INFO">index=None
host=None
alias=None
port=22
timeout=3 seconds
newline=

prompt=None
term_type=vt100
width=80
height=24
path_separator=/
encoding=utf8
escape_ansi=False
encoding_errors=strict</msg>
<msg time="2026-05-01T00:56:30.001440" level="INFO">${current_ssh_connection} = index=None
host=None
alias=None
port=22
timeout=3 seconds
newline=

prompt=None
term_type=vt100
width=80
height=24
path_separator=/
encoding=utf8
escape_ansi=False
encoding_errors=strict</msg>
<var>${current_ssh_connection}</var>
<doc>Returns information about the connection.</doc>
<status status="PASS" start="2026-05-01T00:56:30.001109" elapsed="0.000357"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-05-01T00:56:30.002036" level="INFO">Attempting to execute command "/usr/local/bin/cbench -c 10.30.171.63 -t -m 60000 -M 100 -S 16 -l 10 -D 5000 -p 6633" on remote system "10.30.171.37" by user "jenkins" with keyfile pass "any" and prompt "&gt;" and password ""</msg>
<arg>Attempting to execute command "${cmd}" on remote system "${system}" by user "${user}" with keyfile pass "${keyfile_pass}" and prompt "${prompt}" and password "${password}"</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-05-01T00:56:30.001601" elapsed="0.000492"/>
</kw>
<kw name="Open Connection" owner="SSHLibrary">
<msg time="2026-05-01T00:56:30.002644" level="INFO">${conn_id} = 7</msg>
<var>${conn_id}</var>
<arg>${system}</arg>
<arg>prompt=${prompt}</arg>
<arg>timeout=${prompt_timeout}</arg>
<doc>Opens a new SSH connection to the given ``host`` and ``port``.</doc>
<status status="PASS" start="2026-05-01T00:56:30.002233" elapsed="0.000439"/>
</kw>
<kw name="Flexible_SSH_Login" owner="SSHKeywords">
<kw name="Get Length" owner="BuiltIn">
<msg time="2026-05-01T00:56:30.003594" level="INFO">Length is 0.</msg>
<msg time="2026-05-01T00:56:30.003700" level="INFO">${pwd_length} = 0</msg>
<var>${pwd_length}</var>
<arg>${password}</arg>
<doc>Returns and logs the length of the given item as an integer.</doc>
<status status="PASS" start="2026-05-01T00:56:30.003253" elapsed="0.000472"/>
</kw>
<kw name="Run Keyword And Return If" owner="BuiltIn">
<arg>${pwd_length} &gt; 0</arg>
<arg>SSHLibrary.Login</arg>
<arg>${user}</arg>
<arg>${password}</arg>
<arg>delay=${delay}</arg>
<doc>Runs the specified keyword and returns from the enclosing user keyword.</doc>
<status status="PASS" start="2026-05-01T00:56:30.003867" elapsed="0.000324"/>
</kw>
<kw name="Run Keyword And Return" owner="BuiltIn">
<kw name="Login With Public Key" owner="SSHLibrary">
<msg time="2026-05-01T00:56:30.005078" level="INFO">Logging into '10.30.171.37:22' as 'jenkins'.</msg>
<msg time="2026-05-01T00:56:30.595921" level="INFO">Read output: Welcome to Ubuntu 22.04.5 LTS (GNU/Linux 5.15.0-153-generic x86_64)

 * Documentation:  https://help.ubuntu.com
 * Management:     https://landscape.canonical.com
 * Support:        https://ubuntu.com/pro

 System information as of Fri May  1 00:56:30 UTC 2026

  System load:  1.0                Processes:             104
  Usage of /:   19.5% of 38.58GB   Users logged in:       0
  Memory usage: 4%                 IPv4 address for ens3: 10.30.171.37
  Swap usage:   0%


Expanded Security Maintenance for Applications is not enabled.

0 updates can be applied immediately.

9 additional security updates can be applied with ESM Apps.
Learn more about enabling ESM Apps service at https://ubuntu.com/esm


The list of available updates is more than a week old.
To check for new updates run: sudo apt update
New release '24.04.4 LTS' available.
Run 'do-release-upgrade' to upgrade to it.


Last login: Fri May  1 00:46:23 2026 from 10.30.171.253
[?2004h[jenkins@releng-63164-215-1-mininet-ovs-217-0 ~]&gt;</msg>
<arg>${user}</arg>
<arg>${USER_HOME}/.ssh/${SSH_KEY}</arg>
<arg>${KEYFILE_PASS}</arg>
<arg>delay=${delay}</arg>
<doc>Logs into the SSH server using key-based authentication.</doc>
<status status="PASS" start="2026-05-01T00:56:30.004697" elapsed="0.591438"/>
</kw>
<msg time="2026-05-01T00:56:30.596246" level="INFO">Returning from the enclosing user keyword.</msg>
<arg>SSHLibrary.Login With Public Key</arg>
<arg>${user}</arg>
<arg>${USER_HOME}/.ssh/${SSH_KEY}</arg>
<arg>${KEYFILE_PASS}</arg>
<arg>delay=${delay}</arg>
<doc>Runs the specified keyword and returns from the enclosing user keyword.</doc>
<status status="PASS" start="2026-05-01T00:56:30.004342" elapsed="0.591997"/>
</kw>
<arg>${user}</arg>
<arg>${password}</arg>
<doc>On active SSH session: if given non-empty password, do Login, else do Login With Public Key.</doc>
<status status="PASS" start="2026-05-01T00:56:30.002875" elapsed="0.593585"/>
</kw>
<kw name="Execute Command" owner="SSHLibrary">
<msg time="2026-05-01T00:56:30.597008" level="INFO">Executing command '/usr/local/bin/cbench -c 10.30.171.63 -t -m 60000 -M 100 -S 16 -l 10 -D 5000 -p 6633'.</msg>
<msg time="2026-05-01T01:06:37.090365" level="INFO">Command exited with return code 0.</msg>
<msg time="2026-05-01T01:06:37.090892" level="INFO">${stdout} = </msg>
<msg time="2026-05-01T01:06:37.091014" level="INFO">${stderr} = cbench: controller benchmarking tool
   running in mode 'throughput'
   connecting to controller at 10.30.171.63:6633 
   faking 16 switches with 1 threads :: 10 tests each; 60000 ms per test
   with ...</msg>
<var>${stdout}</var>
<var>${stderr}</var>
<arg>${cmd}</arg>
<arg>return_stderr=True</arg>
<doc>Executes ``command`` on the remote machine and returns its outputs.</doc>
<status status="PASS" start="2026-05-01T00:56:30.596704" elapsed="606.494380"/>
</kw>
<kw name="Close Connection" owner="SSHLibrary">
<doc>Closes the current connection.</doc>
<status status="PASS" start="2026-05-01T01:06:37.091509" elapsed="0.000637"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-05-01T01:06:37.092968" level="INFO">cbench: controller benchmarking tool
   running in mode 'throughput'
   connecting to controller at 10.30.171.63:6633 
   faking 16 switches with 1 threads :: 10 tests each; 60000 ms per test
   with 100 unique source MACs per switch
   learning destination mac addresses before the test
   starting test with 5000 ms delay after features_reply
   ignoring first 1 "warmup" and last 0 "cooldown" loops
   debugging info is off
Master Thread: Creating thread-0! 
Thread-0: All switches initialized. Running at node 10.30.171.63. So far 1 cbench instances have initialized their switches.  Waiting for other cbench-instances
Thread-0: Total switches from ALL cbench-instances initialized.
00:57:36.156 16  switches: flows:  519939 530281 517070 511935 519211 505546 503880 505577 509479 517338 492906 515669 507594 516947 510361 514635  total = 136.639467 per ms 
00:58:36.259 16  switches: flows:  567397 580100 577421 571429 564843 567527 576118 577113 575657 555136 574855 581969 579906 575098 581237 571425  total = 152.692288 per ms 
00:59:36.365 16  switches: flows:  552484 556473 563704 568111 560853 556406 564797 565789 557412 567550 563984 560518 558424 554377 554234 563189  total = 149.208149 per ms 
01:00:36.467 16  switches: flows:  591277 579432 576674 590560 580469 594793 585122 582670 578683 590363 601654 575363 584923 579404 589428 580422  total = 155.757739 per ms 
01:01:36.567 16  switches: flows:  593614 603819 586828 592844 594923 598264 596202 592901 591445 592759 606126 590653 588681 597772 598283 599281  total = 158.473842 per ms 
01:02:36.669 16  switches: flows:  594545 589409 583764 589248 575561 591532 589559 590904 583708 592177 590377 587631 598151 583785 589163 594472  total = 156.802916 per ms 
01:03:36.771 16  switches: flows:  567978 578016 580899 574572 570438 576069 560462 576060 578323 569762 566109 573981 572765 571741 576420 566114  total = 152.404832 per ms 
01:04:36.873 16  switches: flows:  566563 565647 565522 575408 564834 572073 575731 571338 561003 564163 573796 568342 564712 568606 571496 568758  total = 151.376115 per ms 
01:05:36.983 16  switches: flows:  584180 584223 586193 588064 581915 571495 587042 585390 585688 583447 577790 590647 586570 583069 591954 586310  total = 155.616716 per ms 
01:06:37.084 16  switches: flows:  600892 604920 602938 607687 604399 601234 605591 609873 605220 611758 606002 607692 603811 608187 605143 606929  total = 161.265065 per ms 
RESULT: 16 switches 9 tests min/max/avg/stdev = 149208.15/161265.06/154844.18/51737.33 responses/s</msg>
<arg>${stderr}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-05-01T01:06:37.092405" elapsed="0.000774"/>
</kw>
<if>
<branch type="IF" condition="&quot;${return_stdout}&quot;!=&quot;True&quot;">
<return>
<value>${stderr}</value>
<status status="PASS" start="2026-05-01T01:06:37.093523" elapsed="0.000158"/>
</return>
<status status="PASS" start="2026-05-01T01:06:37.093305" elapsed="0.000442"/>
</branch>
<status status="PASS" start="2026-05-01T01:06:37.093268" elapsed="0.000518"/>
</if>
<if>
<branch type="IF" condition="&quot;${return_stderr}&quot;!=&quot;True&quot;">
<return>
<value>${stdout}</value>
<status status="NOT RUN" start="2026-05-01T01:06:37.093936" elapsed="0.000019"/>
</return>
<status status="NOT RUN" start="2026-05-01T01:06:37.093878" elapsed="0.000118"/>
</branch>
<status status="NOT RUN" start="2026-05-01T01:06:37.093852" elapsed="0.000177"/>
</if>
<return>
<value>${stdout}</value>
<value>${stderr}</value>
<status status="NOT RUN" start="2026-05-01T01:06:37.094077" elapsed="0.000019"/>
</return>
<kw name="Restore_Current_Ssh_Connection_From_Index" owner="SSHKeywords" type="TEARDOWN">
<kw name="Run Keyword And Return If" owner="BuiltIn">
<arg>${connection_index} is not None</arg>
<arg>SSHLibrary.Switch Connection</arg>
<arg>${connection_index}</arg>
<doc>Runs the specified keyword and returns from the enclosing user keyword.</doc>
<status status="PASS" start="2026-05-01T01:06:37.097690" elapsed="0.000478"/>
</kw>
<kw name="Open Connection" owner="SSHLibrary">
<arg>127.0.0.1</arg>
<doc>Opens a new SSH connection to the given ``host`` and ``port``.</doc>
<status status="PASS" start="2026-05-01T01:06:37.098382" elapsed="0.000237"/>
</kw>
<kw name="Close Connection" owner="SSHLibrary">
<doc>Closes the current connection.</doc>
<status status="PASS" start="2026-05-01T01:06:37.098835" elapsed="0.000152"/>
</kw>
<arg>${current_ssh_connection.index}</arg>
<doc>Restore active SSH connection in SSHLibrary to given index.</doc>
<status status="PASS" start="2026-05-01T01:06:37.094537" elapsed="0.004528"/>
</kw>
<msg time="2026-05-01T01:06:37.099249" level="INFO">${output} = cbench: controller benchmarking tool
   running in mode 'throughput'
   connecting to controller at 10.30.171.63:6633 
   faking 16 switches with 1 threads :: 10 tests each; 60000 ms per test
   with ...</msg>
<var>${output}</var>
<arg>${cbench_system}</arg>
<arg>${cbench_executable} -c ${ODL_SYSTEM_IP} ${cbench_args}</arg>
<arg>prompt_timeout=${test_timeout}</arg>
<arg>return_stdout=False</arg>
<arg>return_stderr=True</arg>
<doc>Reduces the common work of running a command on a remote system to a single higher level
robot keyword, taking care to log in with a public key and. The command given is written and the return value
depends on the passed argument values of return_stdout (default: True) and return_stderr (default: False).
At least one should be True, or the keyword will exit and FAIL. If both are True, the resulting return value
will be a two element list containing both. Otherwise the resulting return value is a string.
No test conditions are checked.</doc>
<status status="PASS" start="2026-05-01T00:56:29.998260" elapsed="607.101029"/>
</kw>
<status status="PASS" start="2026-05-01T00:56:29.997932" elapsed="607.101429"/>
</branch>
<status status="PASS" start="2026-05-01T00:56:29.997580" elapsed="607.101816"/>
</if>
<kw name="Log" owner="BuiltIn">
<msg time="2026-05-01T01:06:37.100047" level="INFO">cbench: controller benchmarking tool
   running in mode 'throughput'
   connecting to controller at 10.30.171.63:6633 
   faking 16 switches with 1 threads :: 10 tests each; 60000 ms per test
   with 100 unique source MACs per switch
   learning destination mac addresses before the test
   starting test with 5000 ms delay after features_reply
   ignoring first 1 "warmup" and last 0 "cooldown" loops
   debugging info is off
Master Thread: Creating thread-0! 
Thread-0: All switches initialized. Running at node 10.30.171.63. So far 1 cbench instances have initialized their switches.  Waiting for other cbench-instances
Thread-0: Total switches from ALL cbench-instances initialized.
00:57:36.156 16  switches: flows:  519939 530281 517070 511935 519211 505546 503880 505577 509479 517338 492906 515669 507594 516947 510361 514635  total = 136.639467 per ms 
00:58:36.259 16  switches: flows:  567397 580100 577421 571429 564843 567527 576118 577113 575657 555136 574855 581969 579906 575098 581237 571425  total = 152.692288 per ms 
00:59:36.365 16  switches: flows:  552484 556473 563704 568111 560853 556406 564797 565789 557412 567550 563984 560518 558424 554377 554234 563189  total = 149.208149 per ms 
01:00:36.467 16  switches: flows:  591277 579432 576674 590560 580469 594793 585122 582670 578683 590363 601654 575363 584923 579404 589428 580422  total = 155.757739 per ms 
01:01:36.567 16  switches: flows:  593614 603819 586828 592844 594923 598264 596202 592901 591445 592759 606126 590653 588681 597772 598283 599281  total = 158.473842 per ms 
01:02:36.669 16  switches: flows:  594545 589409 583764 589248 575561 591532 589559 590904 583708 592177 590377 587631 598151 583785 589163 594472  total = 156.802916 per ms 
01:03:36.771 16  switches: flows:  567978 578016 580899 574572 570438 576069 560462 576060 578323 569762 566109 573981 572765 571741 576420 566114  total = 152.404832 per ms 
01:04:36.873 16  switches: flows:  566563 565647 565522 575408 564834 572073 575731 571338 561003 564163 573796 568342 564712 568606 571496 568758  total = 151.376115 per ms 
01:05:36.983 16  switches: flows:  584180 584223 586193 588064 581915 571495 587042 585390 585688 583447 577790 590647 586570 583069 591954 586310  total = 155.616716 per ms 
01:06:37.084 16  switches: flows:  600892 604920 602938 607687 604399 601234 605591 609873 605220 611758 606002 607692 603811 608187 605143 606929  total = 161.265065 per ms 
RESULT: 16 switches 9 tests min/max/avg/stdev = 149208.15/161265.06/154844.18/51737.33 responses/s</msg>
<arg>${output}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-05-01T01:06:37.099604" elapsed="0.000553"/>
</kw>
<kw name="Should Contain" owner="BuiltIn">
<arg>${output}</arg>
<arg>RESULT</arg>
<doc>Fails if ``container`` does not contain ``item`` one or more times.</doc>
<status status="PASS" start="2026-05-01T01:06:37.100370" elapsed="0.000545"/>
</kw>
<kw name="Get Lines Containing String" owner="String">
<msg time="2026-05-01T01:06:37.101455" level="INFO">1 out of 23 lines matched.</msg>
<msg time="2026-05-01T01:06:37.101590" level="INFO">${result_line} = RESULT: 16 switches 9 tests min/max/avg/stdev = 149208.15/161265.06/154844.18/51737.33 responses/s</msg>
<var>${result_line}</var>
<arg>${output}</arg>
<arg>RESULT</arg>
<doc>Returns lines of the given ``string`` that contain the ``pattern``.</doc>
<status status="PASS" start="2026-05-01T01:06:37.101145" elapsed="0.000550"/>
</kw>
<kw name="Split String" owner="String">
<msg time="2026-05-01T01:06:37.102295" level="INFO">@{results_list} = [ RESULT: | 16 | switches | 9 | tests | min/max/avg/stdev | = | 149208.15/161265.06/154844.18/51737.33 | responses/s ]</msg>
<var>@{results_list}</var>
<arg>${result_line}</arg>
<doc>Splits the ``string`` using ``separator`` as a delimiter string.</doc>
<status status="PASS" start="2026-05-01T01:06:37.101924" elapsed="0.000410"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-05-01T01:06:37.104891" level="INFO">min/max/avg/stdev</msg>
<arg>${results_list[5]}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-05-01T01:06:37.102541" elapsed="0.002410"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-05-01T01:06:37.107369" level="INFO">149208.15/161265.06/154844.18/51737.33</msg>
<arg>${results_list[7]}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-05-01T01:06:37.105111" elapsed="0.002318"/>
</kw>
<kw name="Split String" owner="String">
<msg time="2026-05-01T01:06:37.109936" level="INFO">@{result_name_list} = [ min | max | avg | stdev ]</msg>
<var>@{result_name_list}</var>
<arg>${results_list[5]}</arg>
<arg>/</arg>
<doc>Splits the ``string`` using ``separator`` as a delimiter string.</doc>
<status status="PASS" start="2026-05-01T01:06:37.107583" elapsed="0.002389"/>
</kw>
<kw name="Split String" owner="String">
<msg time="2026-05-01T01:06:37.112909" level="INFO">@{result_value_list} = [ 149208.15 | 161265.06 | 154844.18 | 51737.33 ]</msg>
<var>@{result_value_list}</var>
<arg>${results_list[7]}</arg>
<arg>/</arg>
<doc>Splits the ``string`` using ``separator`` as a delimiter string.</doc>
<status status="PASS" start="2026-05-01T01:06:37.110219" elapsed="0.002718"/>
</kw>
<kw name="Get Length" owner="BuiltIn">
<msg time="2026-05-01T01:06:37.113417" level="INFO">Length is 4.</msg>
<msg time="2026-05-01T01:06:37.113508" level="INFO">${num_stats} = 4</msg>
<var>${num_stats}</var>
<arg>${result_name_list}</arg>
<doc>Returns and logs the length of the given item as an integer.</doc>
<status status="PASS" start="2026-05-01T01:06:37.113099" elapsed="0.000434"/>
</kw>
<for flavor="IN RANGE">
<iter>
<kw name="Log" owner="BuiltIn">
<msg time="2026-05-01T01:06:37.118421" level="INFO">min :: 149208.15</msg>
<arg>${result_name_list[${i}]} :: ${result_value_list[${i}]}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-05-01T01:06:37.113991" elapsed="0.004503"/>
</kw>
<var name="${i}">0</var>
<status status="PASS" start="2026-05-01T01:06:37.113857" elapsed="0.004681"/>
</iter>
<iter>
<kw name="Log" owner="BuiltIn">
<msg time="2026-05-01T01:06:37.123588" level="INFO">max :: 161265.06</msg>
<arg>${result_name_list[${i}]} :: ${result_value_list[${i}]}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-05-01T01:06:37.118804" elapsed="0.004861"/>
</kw>
<var name="${i}">1</var>
<status status="PASS" start="2026-05-01T01:06:37.118656" elapsed="0.005048"/>
</iter>
<iter>
<kw name="Log" owner="BuiltIn">
<msg time="2026-05-01T01:06:37.128527" level="INFO">avg :: 154844.18</msg>
<arg>${result_name_list[${i}]} :: ${result_value_list[${i}]}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-05-01T01:06:37.123927" elapsed="0.004684"/>
</kw>
<var name="${i}">2</var>
<status status="PASS" start="2026-05-01T01:06:37.123795" elapsed="0.004871"/>
</iter>
<iter>
<kw name="Log" owner="BuiltIn">
<msg time="2026-05-01T01:06:37.133592" level="INFO">stdev :: 51737.33</msg>
<arg>${result_name_list[${i}]} :: ${result_value_list[${i}]}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-05-01T01:06:37.128902" elapsed="0.004814"/>
</kw>
<var name="${i}">3</var>
<status status="PASS" start="2026-05-01T01:06:37.128770" elapsed="0.004985"/>
</iter>
<var>${i}</var>
<value>0</value>
<value>${num_stats}</value>
<status status="PASS" start="2026-05-01T01:06:37.113629" elapsed="0.020164"/>
</for>
<kw name="Set Variable" owner="BuiltIn">
<msg time="2026-05-01T01:06:37.137790" level="INFO">${min} = 149208.15</msg>
<var>${min}</var>
<arg>${result_value_list[${0}]}</arg>
<doc>Returns the given values which can then be assigned to a variables.</doc>
<status status="PASS" start="2026-05-01T01:06:37.133964" elapsed="0.003855"/>
</kw>
<kw name="Set Variable" owner="BuiltIn">
<msg time="2026-05-01T01:06:37.141818" level="INFO">${max} = 161265.06</msg>
<var>${max}</var>
<arg>${result_value_list[${1}]}</arg>
<doc>Returns the given values which can then be assigned to a variables.</doc>
<status status="PASS" start="2026-05-01T01:06:37.137980" elapsed="0.003866"/>
</kw>
<kw name="Set Variable" owner="BuiltIn">
<msg time="2026-05-01T01:06:37.145814" level="INFO">${average} = 154844.18</msg>
<var>${average}</var>
<arg>${result_value_list[${2}]}</arg>
<doc>Returns the given values which can then be assigned to a variables.</doc>
<status status="PASS" start="2026-05-01T01:06:37.142002" elapsed="0.003840"/>
</kw>
<kw name="Set Variable" owner="BuiltIn">
<msg time="2026-05-01T01:06:37.149918" level="INFO">${stdev} = 51737.33</msg>
<var>${stdev}</var>
<arg>${result_value_list[${3}]}</arg>
<doc>Returns the given values which can then be assigned to a variables.</doc>
<status status="PASS" start="2026-05-01T01:06:37.145999" elapsed="0.003951"/>
</kw>
<kw name="Get Time" owner="BuiltIn">
<msg time="2026-05-01T01:06:37.150478" level="INFO">${date} = 2026-05-01 01:06:37</msg>
<var>${date}</var>
<arg>d,m,s</arg>
<doc>Returns the given time in the requested format.</doc>
<status status="PASS" start="2026-05-01T01:06:37.150114" elapsed="0.000391"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-05-01T01:06:37.151089" level="INFO">CBench Result: 2026-05-01 01:06:37,-t -m 60000 -M 100 -S 16 -l 10 -D 5000 -p 6633,149208.15,161265.06,154844.18,51737.33</msg>
<arg>CBench Result: ${date},${cbench_args},${min},${max},${average},${stdev}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-05-01T01:06:37.150676" elapsed="0.000472"/>
</kw>
<kw name="Log Results And Determine Status">
<kw name="Append To File" owner="OperatingSystem">
<msg time="2026-05-01T01:06:37.152253" level="INFO" html="true">Appended to file '&lt;a href="file:///w/workspace/openflowplugin-csit-1node-cbench-only-vanadium/throughput.csv"&gt;/w/workspace/openflowplugin-csit-1node-cbench-only-vanadium/throughput.csv&lt;/a&gt;'.</msg>
<arg>${output_file}</arg>
<arg>${min},${max},${average}\n</arg>
<doc>Appends the given content to the specified file.</doc>
<status status="PASS" start="2026-05-01T01:06:37.151841" elapsed="0.000480"/>
</kw>
<kw name="Should Be True" owner="BuiltIn">
<arg>${average} &gt; ${threshold}</arg>
<arg>${average} flow_mods per/sec did not exceed threshold of ${threshold}</arg>
<doc>Fails if the given condition is not true.</doc>
<status status="PASS" start="2026-05-01T01:06:37.152474" elapsed="0.000484"/>
</kw>
<arg>${min}</arg>
<arg>${max}</arg>
<arg>${average}</arg>
<arg>${average_threshold}</arg>
<arg>${output_filename}</arg>
<status status="PASS" start="2026-05-01T01:06:37.151293" elapsed="0.001731"/>
</kw>
<arg>-t -m ${duration_in_ms} -M ${num_of_unique_macs} -S ${switch_count} -l ${loops} -D ${start_delay} -p ${of_port}</arg>
<arg>${throughput_threshold}</arg>
<arg>${throughput_results_file}</arg>
<status status="PASS" start="2026-05-01T00:56:29.996486" elapsed="607.156600"/>
</kw>
<kw name="Sleep" owner="BuiltIn">
<msg time="2026-05-01T01:06:37.153556" level="INFO">Slept 0 seconds.</msg>
<arg>${test_delay}</arg>
<doc>Pauses the test executed for the given time.</doc>
<status status="PASS" start="2026-05-01T01:06:37.153236" elapsed="0.000376"/>
</kw>
<kw name="Log Results As Zero If Cbench Timed Out" type="TEARDOWN">
<kw name="Run Keyword If Timeout Occurred" owner="BuiltIn">
<arg>Log Results And Determine Status</arg>
<arg>0</arg>
<arg>0</arg>
<arg>0</arg>
<arg>0</arg>
<arg>${output_filename}</arg>
<doc>Runs the given keyword if either a test or a keyword timeout has occurred.</doc>
<status status="PASS" start="2026-05-01T01:06:37.154165" elapsed="0.000283"/>
</kw>
<status status="PASS" start="2026-05-01T01:06:37.153861" elapsed="0.000659"/>
</kw>
<doc>cbench executed in throughput mode (-t). Test parameters have defaults, but can be overridden
on the pybot command line</doc>
<tag>cbench</tag>
<tag>throughput</tag>
<timeout value="15 minutes"/>
<status status="PASS" start="2026-05-01T00:56:29.994988" elapsed="607.159575"/>
</test>
<test id="s1-t3" name="Rerun Latency Test To Check Bug 6176" line="65">
<kw name="Log" owner="BuiltIn">
<msg time="2026-05-01T01:06:37.155951" level="INFO">Cbench tests using 10 iterations of 60 second tests. Switch Count: 16. Unique MACS to cycle: 100</msg>
<arg>Cbench tests using ${loops} iterations of ${duration_in_secs} second tests. Switch Count: ${switch_count}. Unique MACS to cycle: ${num_of_unique_macs}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-05-01T01:06:37.155436" elapsed="0.000575"/>
</kw>
<kw name="Run Cbench And Log Results">
<kw name="Set Suite Variable" owner="BuiltIn">
<msg time="2026-05-01T01:06:37.157270" level="INFO">${output_filename} = bug.csv</msg>
<arg>${output_filename}</arg>
<doc>Makes a variable available everywhere within the scope of the current suite.</doc>
<status status="PASS" start="2026-05-01T01:06:37.156937" elapsed="0.000376"/>
</kw>
<if>
<branch type="IF" condition="&quot;${cbench_system}&quot; == &quot;localhost&quot;">
<kw name="Run" owner="OperatingSystem">
<var>${output}</var>
<arg>${cbench_executable} -c ${ODL_SYSTEM_IP} ${cbench_args}</arg>
<doc>_This keyword is considered deprecated. Use the
[http://robotframework.org/robotframework/latest/libraries/Process.html|
Process] library instead._</doc>
<status status="NOT RUN" start="2026-05-01T01:06:37.157714" elapsed="0.000026"/>
</kw>
<status status="NOT RUN" start="2026-05-01T01:06:37.157431" elapsed="0.000349"/>
</branch>
<branch type="ELSE">
<kw name="Run Command On Remote System" owner="Utils">
<if>
<branch type="IF" condition="&quot;${return_stdout}&quot;!=&quot;True&quot; and &quot;${return_stderr}&quot;!=&quot;True&quot;">
<kw name="Fail" owner="BuiltIn">
<arg>At least one of {return_stdout} or {return_stderr} args should be set to True</arg>
<doc>Fails the test with the given message and optionally alters its tags.</doc>
<status status="NOT RUN" start="2026-05-01T01:06:37.160730" elapsed="0.000023"/>
</kw>
<status status="NOT RUN" start="2026-05-01T01:06:37.160446" elapsed="0.000343"/>
</branch>
<status status="PASS" start="2026-05-01T01:06:37.160426" elapsed="0.000388"/>
</if>
<kw name="Get Connection" owner="SSHLibrary">
<msg time="2026-05-01T01:06:37.161150" level="INFO">index=None
host=None
alias=None
port=22
timeout=3 seconds
newline=

prompt=None
term_type=vt100
width=80
height=24
path_separator=/
encoding=utf8
escape_ansi=False
encoding_errors=strict</msg>
<msg time="2026-05-01T01:06:37.161281" level="INFO">${current_ssh_connection} = index=None
host=None
alias=None
port=22
timeout=3 seconds
newline=

prompt=None
term_type=vt100
width=80
height=24
path_separator=/
encoding=utf8
escape_ansi=False
encoding_errors=strict</msg>
<var>${current_ssh_connection}</var>
<doc>Returns information about the connection.</doc>
<status status="PASS" start="2026-05-01T01:06:37.160951" elapsed="0.000359"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-05-01T01:06:37.161964" level="INFO">Attempting to execute command "/usr/local/bin/cbench -c 10.30.171.63 -m 60000 -M 100 -S 16 -l 10 -D 5000 -p 6633" on remote system "10.30.171.37" by user "jenkins" with keyfile pass "any" and prompt "&gt;" and password ""</msg>
<arg>Attempting to execute command "${cmd}" on remote system "${system}" by user "${user}" with keyfile pass "${keyfile_pass}" and prompt "${prompt}" and password "${password}"</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-05-01T01:06:37.161450" elapsed="0.000584"/>
</kw>
<kw name="Open Connection" owner="SSHLibrary">
<msg time="2026-05-01T01:06:37.162604" level="INFO">${conn_id} = 9</msg>
<var>${conn_id}</var>
<arg>${system}</arg>
<arg>prompt=${prompt}</arg>
<arg>timeout=${prompt_timeout}</arg>
<doc>Opens a new SSH connection to the given ``host`` and ``port``.</doc>
<status status="PASS" start="2026-05-01T01:06:37.162183" elapsed="0.000463"/>
</kw>
<kw name="Flexible_SSH_Login" owner="SSHKeywords">
<kw name="Get Length" owner="BuiltIn">
<msg time="2026-05-01T01:06:37.163777" level="INFO">Length is 0.</msg>
<msg time="2026-05-01T01:06:37.163876" level="INFO">${pwd_length} = 0</msg>
<var>${pwd_length}</var>
<arg>${password}</arg>
<doc>Returns and logs the length of the given item as an integer.</doc>
<status status="PASS" start="2026-05-01T01:06:37.163279" elapsed="0.000623"/>
</kw>
<kw name="Run Keyword And Return If" owner="BuiltIn">
<arg>${pwd_length} &gt; 0</arg>
<arg>SSHLibrary.Login</arg>
<arg>${user}</arg>
<arg>${password}</arg>
<arg>delay=${delay}</arg>
<doc>Runs the specified keyword and returns from the enclosing user keyword.</doc>
<status status="PASS" start="2026-05-01T01:06:37.164048" elapsed="0.000332"/>
</kw>
<kw name="Run Keyword And Return" owner="BuiltIn">
<kw name="Login With Public Key" owner="SSHLibrary">
<msg time="2026-05-01T01:06:37.165263" level="INFO">Logging into '10.30.171.37:22' as 'jenkins'.</msg>
<msg time="2026-05-01T01:06:37.762174" level="INFO">Read output: Welcome to Ubuntu 22.04.5 LTS (GNU/Linux 5.15.0-153-generic x86_64)

 * Documentation:  https://help.ubuntu.com
 * Management:     https://landscape.canonical.com
 * Support:        https://ubuntu.com/pro

 System information as of Fri May  1 01:06:37 UTC 2026

  System load:  0.2                Processes:             105
  Usage of /:   19.5% of 38.58GB   Users logged in:       0
  Memory usage: 4%                 IPv4 address for ens3: 10.30.171.37
  Swap usage:   0%


Expanded Security Maintenance for Applications is not enabled.

0 updates can be applied immediately.

9 additional security updates can be applied with ESM Apps.
Learn more about enabling ESM Apps service at https://ubuntu.com/esm


The list of available updates is more than a week old.
To check for new updates run: sudo apt update
New release '24.04.4 LTS' available.
Run 'do-release-upgrade' to upgrade to it.


Last login: Fri May  1 00:56:30 2026 from 10.30.171.253
[?2004h[jenkins@releng-63164-215-1-mininet-ovs-217-0 ~]&gt;</msg>
<arg>${user}</arg>
<arg>${USER_HOME}/.ssh/${SSH_KEY}</arg>
<arg>${KEYFILE_PASS}</arg>
<arg>delay=${delay}</arg>
<doc>Logs into the SSH server using key-based authentication.</doc>
<status status="PASS" start="2026-05-01T01:06:37.164903" elapsed="0.597483"/>
</kw>
<msg time="2026-05-01T01:06:37.762490" level="INFO">Returning from the enclosing user keyword.</msg>
<arg>SSHLibrary.Login With Public Key</arg>
<arg>${user}</arg>
<arg>${USER_HOME}/.ssh/${SSH_KEY}</arg>
<arg>${KEYFILE_PASS}</arg>
<arg>delay=${delay}</arg>
<doc>Runs the specified keyword and returns from the enclosing user keyword.</doc>
<status status="PASS" start="2026-05-01T01:06:37.164535" elapsed="0.598042"/>
</kw>
<arg>${user}</arg>
<arg>${password}</arg>
<doc>On active SSH session: if given non-empty password, do Login, else do Login With Public Key.</doc>
<status status="PASS" start="2026-05-01T01:06:37.162886" elapsed="0.599830"/>
</kw>
<kw name="Execute Command" owner="SSHLibrary">
<msg time="2026-05-01T01:06:37.763272" level="INFO">Executing command '/usr/local/bin/cbench -c 10.30.171.63 -m 60000 -M 100 -S 16 -l 10 -D 5000 -p 6633'.</msg>
<msg time="2026-05-01T01:16:43.794861" level="INFO">Command exited with return code 0.</msg>
<msg time="2026-05-01T01:16:43.795512" level="INFO">${stdout} = </msg>
<msg time="2026-05-01T01:16:43.795622" level="INFO">${stderr} = cbench: controller benchmarking tool
   running in mode 'latency'
   connecting to controller at 10.30.171.63:6633 
   faking 16 switches with 1 threads :: 10 tests each; 60000 ms per test
   with 100...</msg>
<var>${stdout}</var>
<var>${stderr}</var>
<arg>${cmd}</arg>
<arg>return_stderr=True</arg>
<doc>Executes ``command`` on the remote machine and returns its outputs.</doc>
<status status="PASS" start="2026-05-01T01:06:37.762948" elapsed="606.032770"/>
</kw>
<kw name="Close Connection" owner="SSHLibrary">
<doc>Closes the current connection.</doc>
<status status="PASS" start="2026-05-01T01:16:43.796218" elapsed="0.000901"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-05-01T01:16:43.798495" level="INFO">cbench: controller benchmarking tool
   running in mode 'latency'
   connecting to controller at 10.30.171.63:6633 
   faking 16 switches with 1 threads :: 10 tests each; 60000 ms per test
   with 100 unique source MACs per switch
   learning destination mac addresses before the test
   starting test with 5000 ms delay after features_reply
   ignoring first 1 "warmup" and last 0 "cooldown" loops
   debugging info is off
Master Thread: Creating thread-0! 
Thread-0: All switches initialized. Running at node 10.30.171.63. So far 1 cbench instances have initialized their switches.  Waiting for other cbench-instances
Thread-0: Total switches from ALL cbench-instances initialized.
01:07:42.888 16  switches: flows:  113524 112783 112831 112367 111477 111778 112295 112698 110587 109676 107290 111803 108663 109523 109133 110776  total = 29.620067 per ms 
01:08:42.989 16  switches: flows:  112612 112996 112721 112643 112499 110842 110492 110524 108350 109729 108418 109131 108092 109591 110865 108748  total = 29.421746 per ms 
01:09:43.089 16  switches: flows:  123978 123804 120107 119944 121874 120189 123682 123041 118770 116690 118815 119294 120049 121328 119157 118147  total = 32.094209 per ms 
01:10:43.190 16  switches: flows:  121851 123244 119514 119252 119140 117359 120808 119902 115385 114634 116532 120233 118168 118821 116925 114985  total = 31.559785 per ms 
01:11:43.290 16  switches: flows:  116997 115311 115395 116153 115766 113707 116288 115896 114426 110762 110753 115583 112124 110783 113337 112450  total = 30.378142 per ms 
01:12:43.390 16  switches: flows:  120775 122140 122974 117850 118568 120237 121258 120007 117367 115248 116730 120902 116816 115158 119261 116407  total = 31.642116 per ms 
01:13:43.490 16  switches: flows:  124762 122291 121615 119133 119706 123647 119161 122489 118248 117287 115324 121362 117545 117969 118990 117983  total = 31.905277 per ms 
01:14:43.591 16  switches: flows:  122612 120766 119217 118256 119065 121820 119014 120901 118433 115891 113888 121144 117123 116479 119995 115638  total = 31.617948 per ms 
01:15:43.691 16  switches: flows:  121856 121730 119478 119289 117095 119393 118677 119264 118215 114331 113766 118944 115958 115421 117220 114374  total = 31.364491 per ms 
01:16:43.791 16  switches: flows:  118832 120416 120556 117394 116974 118112 117195 118671 118099 113908 115858 119191 116013 115442 117996 114699  total = 31.270374 per ms 
RESULT: 16 switches 9 tests min/max/avg/stdev = 29421.75/32094.21/31250.45/10446.87 responses/s</msg>
<arg>${stderr}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-05-01T01:16:43.797507" elapsed="0.001355"/>
</kw>
<if>
<branch type="IF" condition="&quot;${return_stdout}&quot;!=&quot;True&quot;">
<return>
<value>${stderr}</value>
<status status="PASS" start="2026-05-01T01:16:43.799267" elapsed="0.000048"/>
</return>
<status status="PASS" start="2026-05-01T01:16:43.799061" elapsed="0.000306"/>
</branch>
<status status="PASS" start="2026-05-01T01:16:43.799016" elapsed="0.000388"/>
</if>
<if>
<branch type="IF" condition="&quot;${return_stderr}&quot;!=&quot;True&quot;">
<return>
<value>${stdout}</value>
<status status="NOT RUN" start="2026-05-01T01:16:43.799541" elapsed="0.000018"/>
</return>
<status status="NOT RUN" start="2026-05-01T01:16:43.799487" elapsed="0.000112"/>
</branch>
<status status="NOT RUN" start="2026-05-01T01:16:43.799463" elapsed="0.000167"/>
</if>
<return>
<value>${stdout}</value>
<value>${stderr}</value>
<status status="NOT RUN" start="2026-05-01T01:16:43.799729" elapsed="0.000034"/>
</return>
<kw name="Restore_Current_Ssh_Connection_From_Index" owner="SSHKeywords" type="TEARDOWN">
<kw name="Run Keyword And Return If" owner="BuiltIn">
<arg>${connection_index} is not None</arg>
<arg>SSHLibrary.Switch Connection</arg>
<arg>${connection_index}</arg>
<doc>Runs the specified keyword and returns from the enclosing user keyword.</doc>
<status status="PASS" start="2026-05-01T01:16:43.803505" elapsed="0.000557"/>
</kw>
<kw name="Open Connection" owner="SSHLibrary">
<arg>127.0.0.1</arg>
<doc>Opens a new SSH connection to the given ``host`` and ``port``.</doc>
<status status="PASS" start="2026-05-01T01:16:43.804346" elapsed="0.000282"/>
</kw>
<kw name="Close Connection" owner="SSHLibrary">
<doc>Closes the current connection.</doc>
<status status="PASS" start="2026-05-01T01:16:43.804853" elapsed="0.000149"/>
</kw>
<arg>${current_ssh_connection.index}</arg>
<doc>Restore active SSH connection in SSHLibrary to given index.</doc>
<status status="PASS" start="2026-05-01T01:16:43.800286" elapsed="0.004796"/>
</kw>
<msg time="2026-05-01T01:16:43.805272" level="INFO">${output} = cbench: controller benchmarking tool
   running in mode 'latency'
   connecting to controller at 10.30.171.63:6633 
   faking 16 switches with 1 threads :: 10 tests each; 60000 ms per test
   with 100...</msg>
<var>${output}</var>
<arg>${cbench_system}</arg>
<arg>${cbench_executable} -c ${ODL_SYSTEM_IP} ${cbench_args}</arg>
<arg>prompt_timeout=${test_timeout}</arg>
<arg>return_stdout=False</arg>
<arg>return_stderr=True</arg>
<doc>Reduces the common work of running a command on a remote system to a single higher level
robot keyword, taking care to log in with a public key and. The command given is written and the return value
depends on the passed argument values of return_stdout (default: True) and return_stderr (default: False).
At least one should be True, or the keyword will exit and FAIL. If both are True, the resulting return value
will be a two element list containing both. Otherwise the resulting return value is a string.
No test conditions are checked.</doc>
<status status="PASS" start="2026-05-01T01:06:37.158134" elapsed="606.647178"/>
</kw>
<status status="PASS" start="2026-05-01T01:06:37.157808" elapsed="606.647588"/>
</branch>
<status status="PASS" start="2026-05-01T01:06:37.157408" elapsed="606.648024"/>
</if>
<kw name="Log" owner="BuiltIn">
<msg time="2026-05-01T01:16:43.806116" level="INFO">cbench: controller benchmarking tool
   running in mode 'latency'
   connecting to controller at 10.30.171.63:6633 
   faking 16 switches with 1 threads :: 10 tests each; 60000 ms per test
   with 100 unique source MACs per switch
   learning destination mac addresses before the test
   starting test with 5000 ms delay after features_reply
   ignoring first 1 "warmup" and last 0 "cooldown" loops
   debugging info is off
Master Thread: Creating thread-0! 
Thread-0: All switches initialized. Running at node 10.30.171.63. So far 1 cbench instances have initialized their switches.  Waiting for other cbench-instances
Thread-0: Total switches from ALL cbench-instances initialized.
01:07:42.888 16  switches: flows:  113524 112783 112831 112367 111477 111778 112295 112698 110587 109676 107290 111803 108663 109523 109133 110776  total = 29.620067 per ms 
01:08:42.989 16  switches: flows:  112612 112996 112721 112643 112499 110842 110492 110524 108350 109729 108418 109131 108092 109591 110865 108748  total = 29.421746 per ms 
01:09:43.089 16  switches: flows:  123978 123804 120107 119944 121874 120189 123682 123041 118770 116690 118815 119294 120049 121328 119157 118147  total = 32.094209 per ms 
01:10:43.190 16  switches: flows:  121851 123244 119514 119252 119140 117359 120808 119902 115385 114634 116532 120233 118168 118821 116925 114985  total = 31.559785 per ms 
01:11:43.290 16  switches: flows:  116997 115311 115395 116153 115766 113707 116288 115896 114426 110762 110753 115583 112124 110783 113337 112450  total = 30.378142 per ms 
01:12:43.390 16  switches: flows:  120775 122140 122974 117850 118568 120237 121258 120007 117367 115248 116730 120902 116816 115158 119261 116407  total = 31.642116 per ms 
01:13:43.490 16  switches: flows:  124762 122291 121615 119133 119706 123647 119161 122489 118248 117287 115324 121362 117545 117969 118990 117983  total = 31.905277 per ms 
01:14:43.591 16  switches: flows:  122612 120766 119217 118256 119065 121820 119014 120901 118433 115891 113888 121144 117123 116479 119995 115638  total = 31.617948 per ms 
01:15:43.691 16  switches: flows:  121856 121730 119478 119289 117095 119393 118677 119264 118215 114331 113766 118944 115958 115421 117220 114374  total = 31.364491 per ms 
01:16:43.791 16  switches: flows:  118832 120416 120556 117394 116974 118112 117195 118671 118099 113908 115858 119191 116013 115442 117996 114699  total = 31.270374 per ms 
RESULT: 16 switches 9 tests min/max/avg/stdev = 29421.75/32094.21/31250.45/10446.87 responses/s</msg>
<arg>${output}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-05-01T01:16:43.805674" elapsed="0.000590"/>
</kw>
<kw name="Should Contain" owner="BuiltIn">
<arg>${output}</arg>
<arg>RESULT</arg>
<doc>Fails if ``container`` does not contain ``item`` one or more times.</doc>
<status status="PASS" start="2026-05-01T01:16:43.806490" elapsed="0.000608"/>
</kw>
<kw name="Get Lines Containing String" owner="String">
<msg time="2026-05-01T01:16:43.807507" level="INFO">1 out of 23 lines matched.</msg>
<msg time="2026-05-01T01:16:43.807602" level="INFO">${result_line} = RESULT: 16 switches 9 tests min/max/avg/stdev = 29421.75/32094.21/31250.45/10446.87 responses/s</msg>
<var>${result_line}</var>
<arg>${output}</arg>
<arg>RESULT</arg>
<doc>Returns lines of the given ``string`` that contain the ``pattern``.</doc>
<status status="PASS" start="2026-05-01T01:16:43.807274" elapsed="0.000354"/>
</kw>
<kw name="Split String" owner="String">
<msg time="2026-05-01T01:16:43.808060" level="INFO">@{results_list} = [ RESULT: | 16 | switches | 9 | tests | min/max/avg/stdev | = | 29421.75/32094.21/31250.45/10446.87 | responses/s ]</msg>
<var>@{results_list}</var>
<arg>${result_line}</arg>
<doc>Splits the ``string`` using ``separator`` as a delimiter string.</doc>
<status status="PASS" start="2026-05-01T01:16:43.807801" elapsed="0.000287"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-05-01T01:16:43.810516" level="INFO">min/max/avg/stdev</msg>
<arg>${results_list[5]}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-05-01T01:16:43.808235" elapsed="0.002340"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-05-01T01:16:43.813161" level="INFO">29421.75/32094.21/31250.45/10446.87</msg>
<arg>${results_list[7]}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-05-01T01:16:43.810740" elapsed="0.002479"/>
</kw>
<kw name="Split String" owner="String">
<msg time="2026-05-01T01:16:43.816095" level="INFO">@{result_name_list} = [ min | max | avg | stdev ]</msg>
<var>@{result_name_list}</var>
<arg>${results_list[5]}</arg>
<arg>/</arg>
<doc>Splits the ``string`` using ``separator`` as a delimiter string.</doc>
<status status="PASS" start="2026-05-01T01:16:43.813373" elapsed="0.002760"/>
</kw>
<kw name="Split String" owner="String">
<msg time="2026-05-01T01:16:43.819065" level="INFO">@{result_value_list} = [ 29421.75 | 32094.21 | 31250.45 | 10446.87 ]</msg>
<var>@{result_value_list}</var>
<arg>${results_list[7]}</arg>
<arg>/</arg>
<doc>Splits the ``string`` using ``separator`` as a delimiter string.</doc>
<status status="PASS" start="2026-05-01T01:16:43.816424" elapsed="0.002670"/>
</kw>
<kw name="Get Length" owner="BuiltIn">
<msg time="2026-05-01T01:16:43.819611" level="INFO">Length is 4.</msg>
<msg time="2026-05-01T01:16:43.819737" level="INFO">${num_stats} = 4</msg>
<var>${num_stats}</var>
<arg>${result_name_list}</arg>
<doc>Returns and logs the length of the given item as an integer.</doc>
<status status="PASS" start="2026-05-01T01:16:43.819261" elapsed="0.000508"/>
</kw>
<for flavor="IN RANGE">
<iter>
<kw name="Log" owner="BuiltIn">
<msg time="2026-05-01T01:16:43.824892" level="INFO">min :: 29421.75</msg>
<arg>${result_name_list[${i}]} :: ${result_value_list[${i}]}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-05-01T01:16:43.820206" elapsed="0.004750"/>
</kw>
<var name="${i}">0</var>
<status status="PASS" start="2026-05-01T01:16:43.820072" elapsed="0.004922"/>
</iter>
<iter>
<kw name="Log" owner="BuiltIn">
<msg time="2026-05-01T01:16:43.829732" level="INFO">max :: 32094.21</msg>
<arg>${result_name_list[${i}]} :: ${result_value_list[${i}]}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-05-01T01:16:43.825221" elapsed="0.004572"/>
</kw>
<var name="${i}">1</var>
<status status="PASS" start="2026-05-01T01:16:43.825088" elapsed="0.004740"/>
</iter>
<iter>
<kw name="Log" owner="BuiltIn">
<msg time="2026-05-01T01:16:43.835129" level="INFO">avg :: 31250.45</msg>
<arg>${result_name_list[${i}]} :: ${result_value_list[${i}]}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-05-01T01:16:43.830077" elapsed="0.005115"/>
</kw>
<var name="${i}">2</var>
<status status="PASS" start="2026-05-01T01:16:43.829919" elapsed="0.005311"/>
</iter>
<iter>
<kw name="Log" owner="BuiltIn">
<msg time="2026-05-01T01:16:43.839995" level="INFO">stdev :: 10446.87</msg>
<arg>${result_name_list[${i}]} :: ${result_value_list[${i}]}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-05-01T01:16:43.835458" elapsed="0.004598"/>
</kw>
<var name="${i}">3</var>
<status status="PASS" start="2026-05-01T01:16:43.835322" elapsed="0.004770"/>
</iter>
<var>${i}</var>
<value>0</value>
<value>${num_stats}</value>
<status status="PASS" start="2026-05-01T01:16:43.819871" elapsed="0.020252"/>
</for>
<kw name="Set Variable" owner="BuiltIn">
<msg time="2026-05-01T01:16:43.844096" level="INFO">${min} = 29421.75</msg>
<var>${min}</var>
<arg>${result_value_list[${0}]}</arg>
<doc>Returns the given values which can then be assigned to a variables.</doc>
<status status="PASS" start="2026-05-01T01:16:43.840293" elapsed="0.003832"/>
</kw>
<kw name="Set Variable" owner="BuiltIn">
<msg time="2026-05-01T01:16:43.848240" level="INFO">${max} = 32094.21</msg>
<var>${max}</var>
<arg>${result_value_list[${1}]}</arg>
<doc>Returns the given values which can then be assigned to a variables.</doc>
<status status="PASS" start="2026-05-01T01:16:43.844285" elapsed="0.003990"/>
</kw>
<kw name="Set Variable" owner="BuiltIn">
<msg time="2026-05-01T01:16:43.852553" level="INFO">${average} = 31250.45</msg>
<var>${average}</var>
<arg>${result_value_list[${2}]}</arg>
<doc>Returns the given values which can then be assigned to a variables.</doc>
<status status="PASS" start="2026-05-01T01:16:43.848451" elapsed="0.004137"/>
</kw>
<kw name="Set Variable" owner="BuiltIn">
<msg time="2026-05-01T01:16:43.856782" level="INFO">${stdev} = 10446.87</msg>
<var>${stdev}</var>
<arg>${result_value_list[${3}]}</arg>
<doc>Returns the given values which can then be assigned to a variables.</doc>
<status status="PASS" start="2026-05-01T01:16:43.852801" elapsed="0.004013"/>
</kw>
<kw name="Get Time" owner="BuiltIn">
<msg time="2026-05-01T01:16:43.857378" level="INFO">${date} = 2026-05-01 01:16:43</msg>
<var>${date}</var>
<arg>d,m,s</arg>
<doc>Returns the given time in the requested format.</doc>
<status status="PASS" start="2026-05-01T01:16:43.856988" elapsed="0.000417"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-05-01T01:16:43.857998" level="INFO">CBench Result: 2026-05-01 01:16:43,-m 60000 -M 100 -S 16 -l 10 -D 5000 -p 6633,29421.75,32094.21,31250.45,10446.87</msg>
<arg>CBench Result: ${date},${cbench_args},${min},${max},${average},${stdev}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-05-01T01:16:43.857558" elapsed="0.000500"/>
</kw>
<kw name="Log Results And Determine Status">
<kw name="Append To File" owner="OperatingSystem">
<msg time="2026-05-01T01:16:43.859435" level="INFO" html="true">Appended to file '&lt;a href="file:///w/workspace/openflowplugin-csit-1node-cbench-only-vanadium/bug.csv"&gt;/w/workspace/openflowplugin-csit-1node-cbench-only-vanadium/bug.csv&lt;/a&gt;'.</msg>
<arg>${output_file}</arg>
<arg>${min},${max},${average}\n</arg>
<doc>Appends the given content to the specified file.</doc>
<status status="PASS" start="2026-05-01T01:16:43.858806" elapsed="0.000715"/>
</kw>
<kw name="Should Be True" owner="BuiltIn">
<arg>${average} &gt; ${threshold}</arg>
<arg>${average} flow_mods per/sec did not exceed threshold of ${threshold}</arg>
<doc>Fails if the given condition is not true.</doc>
<status status="PASS" start="2026-05-01T01:16:43.859732" elapsed="0.000630"/>
</kw>
<arg>${min}</arg>
<arg>${max}</arg>
<arg>${average}</arg>
<arg>${average_threshold}</arg>
<arg>${output_filename}</arg>
<status status="PASS" start="2026-05-01T01:16:43.858231" elapsed="0.002211"/>
</kw>
<arg>-m ${duration_in_ms} -M ${num_of_unique_macs} -S ${switch_count} -l ${loops} -D ${start_delay} -p ${of_port}</arg>
<arg>${latency_threshold}</arg>
<arg>bug.csv</arg>
<status status="PASS" start="2026-05-01T01:06:37.156342" elapsed="606.704177"/>
</kw>
<kw name="Sleep" owner="BuiltIn">
<msg time="2026-05-01T01:16:43.861116" level="INFO">Slept 0 seconds.</msg>
<arg>${test_delay}</arg>
<doc>Pauses the test executed for the given time.</doc>
<status status="PASS" start="2026-05-01T01:16:43.860724" elapsed="0.000464"/>
</kw>
<kw name="Report Failure Due To Bug" owner="Utils" type="TEARDOWN">
<kw name="Evaluate" owner="BuiltIn">
<msg time="2026-05-01T01:16:43.862974" level="INFO">${test_skipped} = False</msg>
<var>${test_skipped}</var>
<arg>len(re.findall('SKIPPED', """${TEST_MESSAGE}""")) &gt; 0</arg>
<arg>modules=re</arg>
<doc>Evaluates the given expression in Python and returns the result.</doc>
<status status="PASS" start="2026-05-01T01:16:43.862102" elapsed="0.000909"/>
</kw>
<if>
<branch type="IF" condition="('${TEST_STATUS}' != 'FAIL') or ${test_skipped}">
<return>
<status status="PASS" start="2026-05-01T01:16:43.863297" elapsed="0.000046"/>
</return>
<status status="PASS" start="2026-05-01T01:16:43.863112" elapsed="0.000272"/>
</branch>
<status status="PASS" start="2026-05-01T01:16:43.863085" elapsed="0.000332"/>
</if>
<kw name="Comment" owner="BuiltIn">
<arg>Jira tickets are {PROJECT}-{NUMBER} while Bugzilla tickets are {NUMBER}</arg>
<doc>Displays the given messages in the log file as keyword arguments.</doc>
<status status="NOT RUN" start="2026-05-01T01:16:43.863599" elapsed="0.000027"/>
</kw>
<kw name="Run Keyword And Return Status" owner="BuiltIn">
<var>${match}</var>
<arg>Should Contain</arg>
<arg>${number}</arg>
<arg>-</arg>
<doc>Runs the specified keyword and returns the status as a Boolean value.</doc>
<status status="NOT RUN" start="2026-05-01T01:16:43.863821" elapsed="0.000026"/>
</kw>
<kw name="Set Variable If" owner="BuiltIn">
<var>${bug_url}</var>
<arg>${match}</arg>
<arg>https://jira.opendaylight.org/browse/${number}</arg>
<arg>https://bugs.opendaylight.org/show_bug.cgi?id=${number}</arg>
<doc>Sets variable based on the given condition.</doc>
<status status="NOT RUN" start="2026-05-01T01:16:43.864038" elapsed="0.000026"/>
</kw>
<kw name="Set Variable" owner="BuiltIn">
<var>${msg}</var>
<arg>This test fails due to ${bug_url}</arg>
<doc>Returns the given values which can then be assigned to a variables.</doc>
<status status="NOT RUN" start="2026-05-01T01:16:43.864309" elapsed="0.000026"/>
</kw>
<kw name="Evaluate" owner="BuiltIn">
<var>${newline}</var>
<arg>chr(10)</arg>
<doc>Evaluates the given expression in Python and returns the result.</doc>
<status status="NOT RUN" start="2026-05-01T01:16:43.864503" elapsed="0.000026"/>
</kw>
<kw name="Set Test Message" owner="BuiltIn">
<arg>${msg}${newline}${newline}${TEST_MESSAGE}</arg>
<doc>Sets message for the current test case.</doc>
<status status="NOT RUN" start="2026-05-01T01:16:43.864705" elapsed="0.000027"/>
</kw>
<kw name="Log" owner="BuiltIn">
<arg>${msg}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="NOT RUN" start="2026-05-01T01:16:43.864899" elapsed="0.000025"/>
</kw>
<if>
<branch type="IF" condition="&quot;${include_bug_in_tags}&quot;==&quot;True&quot;">
<kw name="Set Tags" owner="BuiltIn">
<arg>${bug_url}</arg>
<doc>Adds given ``tags`` for the current test or all tests in a suite.</doc>
<status status="NOT RUN" start="2026-05-01T01:16:43.865179" elapsed="0.000025"/>
</kw>
<status status="NOT RUN" start="2026-05-01T01:16:43.865005" elapsed="0.000238"/>
</branch>
<status status="NOT RUN" start="2026-05-01T01:16:43.864983" elapsed="0.000292"/>
</if>
<arg>6176</arg>
<doc>Report that a test failed due to a known Bugzilla bug whose
number is provided as an argument.
Not FAILED (incl. SKIPPED) test are not reported.
This keyword must be used in the [Teardown] setting of the affected test
or as the first line of the test if FastFail module is not being
used. It reports the URL of the bug on console and also puts it
into the Robot log file.</doc>
<status status="PASS" start="2026-05-01T01:16:43.861671" elapsed="0.003665"/>
</kw>
<doc>cbench executed in latency mode to see if controller is healthy</doc>
<tag>cbench</tag>
<tag>latency</tag>
<timeout value="15 minutes"/>
<status status="PASS" start="2026-05-01T01:06:37.154895" elapsed="606.710490"/>
</test>
<doc>Cbench Latency and Throughput tests can be run from an external
cbench.
If cbench is run with a medium number of switches or higher (e.g. 32+)
the normal openflow operations seem to break.
BUG: https://bugs.opendaylight.org/show_bug.cgi?id=2897</doc>
<status status="PASS" start="2026-05-01T00:46:20.878380" elapsed="1822.987983"/>
</suite>
<statistics>
<total>
<stat pass="3" fail="0" skip="0">All Tests</stat>
</total>
<tag>
<stat pass="3" fail="0" skip="0">cbench</stat>
<stat pass="2" fail="0" skip="0">latency</stat>
<stat pass="1" fail="0" skip="0">throughput</stat>
</tag>
<suite>
<stat name="openflowplugin-cbench.txt" id="s1" pass="3" fail="0" skip="0">openflowplugin-cbench.txt</stat>
</suite>
</statistics>
<errors>
</errors>
</robot>
